builder: larch_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-051 starttime: 1472512490.88 results: success (0) buildid: 20160829145731 builduid: abd1db8157124b098f748bfcd580f142 revision: e980aee95cb2ab7503daa5575dac1e649fbac4d0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 16:14:50.880036) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 16:14:50.880658) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 16:14:50.881287) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 16:14:50.924907) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:14:50.925179) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-29 16:14:50-- 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% 37.9M=0s 2016-08-29 16:14:51 (37.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114974 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:14:51.136322) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:14:51.136595) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016411 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:14:51.177822) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:14:51.178176) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev e980aee95cb2ab7503daa5575dac1e649fbac4d0 --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 e980aee95cb2ab7503daa5575dac1e649fbac4d0 --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=5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-29 16:14:51,261 truncating revision to first 12 chars 2016-08-29 16:14:51,262 Setting DEBUG logging. 2016-08-29 16:14:51,262 attempt 1/10 2016-08-29 16:14:51,262 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/e980aee95cb2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 16:14:51,550 unpacking tar archive at: larch-e980aee95cb2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.639945 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:14:51.838069) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 16:14:51.838376) ========= script_repo_revision: e980aee95cb2ab7503daa5575dac1e649fbac4d0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 16:14:51.838808) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 16:14:51.839139) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 16:14:51.852302) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-08-29 16:14:51.852906) ========= /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=5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:14:52 INFO - MultiFileLogger online at 20160829 16:14:52 in /builds/slave/test 16:14:52 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 16:14:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:14:52 INFO - {'append_to_log': False, 16:14:52 INFO - 'base_work_dir': '/builds/slave/test', 16:14:52 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 16:14:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:14:52 INFO - 'branch': 'Larch-Non-PGO', 16:14:52 INFO - 'buildbot_json_path': 'buildprops.json', 16:14:52 INFO - 'config_files': ('talos/linux_config.py',), 16:14:52 INFO - 'default_actions': ('clobber', 16:14:52 INFO - 'read-buildbot-config', 16:14:52 INFO - 'download-and-extract', 16:14:52 INFO - 'populate-webroot', 16:14:52 INFO - 'create-virtualenv', 16:14:52 INFO - 'install', 16:14:52 INFO - 'run-tests'), 16:14:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:14:52 INFO - 'download_minidump_stackwalk': True, 16:14:52 INFO - 'download_symbols': 'ondemand', 16:14:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:14:52 INFO - 'tooltool.py': '/tools/tooltool.py', 16:14:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:14:52 INFO - '/tools/misc-python/virtualenv.py')}, 16:14:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:14:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:14:52 INFO - 'installer_path': 'installer.exe', 16:14:52 INFO - 'log_level': 'info', 16:14:52 INFO - 'log_name': 'talos', 16:14:52 INFO - 'log_to_console': True, 16:14:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:14:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:14:52 INFO - 'opt_config_files': (), 16:14:52 INFO - 'pip_index': False, 16:14:52 INFO - 'sps_profile': False, 16:14:52 INFO - 'sps_profile_interval': 0, 16:14:52 INFO - 'suite': 'dromaeojs', 16:14:52 INFO - 'system_bits': '32', 16:14:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:14:52 INFO - 'title': 'talos-linux64-ix-051', 16:14:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:14:52 INFO - 'use_talos_json': True, 16:14:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:14:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:14:52 INFO - 'work_dir': 'build'} 16:14:52 INFO - [mozharness: 2016-08-29 23:14:52.032238Z] Running clobber step. 16:14:52 INFO - Running pre-action listener: _resource_record_pre_action 16:14:52 INFO - Running main action method: clobber 16:14:52 INFO - rmtree: /builds/slave/test/build 16:14:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:14:52 INFO - Running post-action listener: _resource_record_post_action 16:14:52 INFO - [mozharness: 2016-08-29 23:14:52.489102Z] Finished clobber step (success) 16:14:52 INFO - [mozharness: 2016-08-29 23:14:52.489205Z] Running read-buildbot-config step. 16:14:52 INFO - Running pre-action listener: _resource_record_pre_action 16:14:52 INFO - Running main action method: read_buildbot_config 16:14:52 INFO - Using buildbot properties: 16:14:52 INFO - { 16:14:52 INFO - "project": "", 16:14:52 INFO - "product": "firefox", 16:14:52 INFO - "script_repo_revision": "production", 16:14:52 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 16:14:52 INFO - "repository": "", 16:14:52 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs", 16:14:52 INFO - "buildid": "20160829145731", 16:14:52 INFO - "pgo_build": "False", 16:14:52 INFO - "basedir": "/builds/slave/test", 16:14:52 INFO - "buildnumber": 3, 16:14:52 INFO - "slavename": "talos-linux64-ix-051", 16:14:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:14:52 INFO - "platform": "ubuntu64_hw", 16:14:52 INFO - "scheduler": "tests-larch-linux64-talos", 16:14:52 INFO - "branch": "larch", 16:14:52 INFO - "repo_path": "projects/larch", 16:14:52 INFO - "revision": "e980aee95cb2ab7503daa5575dac1e649fbac4d0", 16:14:52 INFO - "stage_platform": "linux64", 16:14:52 INFO - "builduid": "abd1db8157124b098f748bfcd580f142", 16:14:52 INFO - "slavebuilddir": "test" 16:14:52 INFO - } 16:14:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 16:14:52 INFO - Running post-action listener: _resource_record_post_action 16:14:52 INFO - [mozharness: 2016-08-29 23:14:52.491657Z] Finished read-buildbot-config step (success) 16:14:52 INFO - [mozharness: 2016-08-29 23:14:52.491748Z] Running download-and-extract step. 16:14:52 INFO - Running pre-action listener: _resource_record_pre_action 16:14:52 INFO - Running main action method: download_and_extract 16:14:52 INFO - mkdir: /builds/slave/test/build/tests 16:14:52 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')]} 16:14:52 INFO - trying https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:14:52 INFO - Downloading https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/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 16:14:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/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 16:14:53 INFO - Downloaded 1361 bytes. 16:14:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:14:53 INFO - Using the following test package requirements: 16:14:53 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:14:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:14:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:14:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:14:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:14:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:14:53 INFO - u'jsshell-linux-x86_64.zip'], 16:14:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:14:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:14:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:14:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:14:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:14:53 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:14:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:14:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:14:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:14:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:14:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:14:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:53 INFO - trying https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:14:53 INFO - Downloading https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/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 16:14:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/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 16:14:53 INFO - Downloaded 20305117 bytes. 16:14:53 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 16:14:58 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 16:14:58 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')]} 16:14:58 INFO - trying https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:14:58 INFO - Downloading https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/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 16:14:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/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 16:14:58 INFO - Downloaded 20305117 bytes. 16:14:58 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 16:15:03 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')]} 16:15:03 INFO - trying https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:15:03 INFO - Downloading https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/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 16:15:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/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 16:15:05 INFO - Downloaded 14413998 bytes. 16:15:05 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 16:15:05 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')]} 16:15:05 INFO - trying https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:15:05 INFO - Downloading https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/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 16:15:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/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 16:15:06 INFO - Downloaded 55887010 bytes. 16:15:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:15:06 INFO - mkdir: /builds/slave/test/properties 16:15:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:15:06 INFO - Writing to file /builds/slave/test/properties/build_url 16:15:06 INFO - Contents: 16:15:06 INFO - build_url:https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:15:07 INFO - Running post-action listener: _resource_record_post_action 16:15:07 INFO - Running post-action listener: set_extra_try_arguments 16:15:07 INFO - [mozharness: 2016-08-29 23:15:07.218974Z] Finished download-and-extract step (success) 16:15:07 INFO - [mozharness: 2016-08-29 23:15:07.219201Z] Running populate-webroot step. 16:15:07 INFO - Running pre-action listener: _resource_record_pre_action 16:15:07 INFO - Running main action method: populate_webroot 16:15:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:15:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:15:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:15:07 INFO - '--tppagecycles', 16:15:07 INFO - '7'], 16:15:07 INFO - 'tests': ['tsvgm']}}, 16:15:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:15:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:15:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:15:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:15:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:15:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:15:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:15:07 INFO - 'talos_options': ['--disable-e10s'], 16:15:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:15:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:15:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:15:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:15:07 INFO - 'talos_options': ['--disable-e10s'], 16:15:07 INFO - 'tests': ['damp', 'tps']}, 16:15:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:15:07 INFO - 'tests': ['damp', 'tps']}, 16:15:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:15:07 INFO - 'tests': ['dromaeo_dom']}, 16:15:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:15:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:15:07 INFO - 'tests': ['basic_compositor_video']}, 16:15:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:15:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:15:07 INFO - 'tests': ['a11yr', 16:15:07 INFO - 'ts_paint', 16:15:07 INFO - 'tpaint', 16:15:07 INFO - 'sessionrestore', 16:15:07 INFO - 'sessionrestore_no_auto_restore', 16:15:07 INFO - 'tabpaint']}, 16:15:07 INFO - 'other-e10s': {'tests': ['a11yr', 16:15:07 INFO - 'ts_paint', 16:15:07 INFO - 'tpaint', 16:15:07 INFO - 'sessionrestore', 16:15:07 INFO - 'sessionrestore_no_auto_restore', 16:15:07 INFO - 'tabpaint']}, 16:15:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:15:07 INFO - 'ts_paint', 16:15:07 INFO - 'tpaint', 16:15:07 INFO - 'sessionrestore', 16:15:07 INFO - 'sessionrestore_no_auto_restore', 16:15:07 INFO - 'tabpaint']}, 16:15:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:15:07 INFO - 'ts_paint', 16:15:07 INFO - 'tpaint', 16:15:07 INFO - 'sessionrestore', 16:15:07 INFO - 'sessionrestore_no_auto_restore', 16:15:07 INFO - 'tabpaint']}, 16:15:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:15:07 INFO - 'tests': ['a11yr', 16:15:07 INFO - 'ts_paint', 16:15:07 INFO - 'tpaint', 16:15:07 INFO - 'sessionrestore', 16:15:07 INFO - 'sessionrestore_no_auto_restore', 16:15:07 INFO - 'tabpaint']}, 16:15:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:15:07 INFO - 'tests': ['a11yr', 16:15:07 INFO - 'ts_paint', 16:15:07 INFO - 'tpaint', 16:15:07 INFO - 'sessionrestore', 16:15:07 INFO - 'sessionrestore_no_auto_restore', 16:15:07 INFO - 'tabpaint']}, 16:15:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:15:07 INFO - 'tests': ['tsvgx', 16:15:07 INFO - 'tsvgr_opacity', 16:15:07 INFO - 'tart', 16:15:07 INFO - 'tscrollx', 16:15:07 INFO - 'cart']}, 16:15:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:15:07 INFO - 'tsvgr_opacity', 16:15:07 INFO - 'tart', 16:15:07 INFO - 'tscrollx', 16:15:07 INFO - 'cart']}, 16:15:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:15:07 INFO - 'talos_options': ['--disable-e10s'], 16:15:07 INFO - 'tests': ['tp5o']}, 16:15:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:15:07 INFO - 'tests': ['tp5o']}, 16:15:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:15:07 INFO - 'talos_options': ['--disable-e10s', 16:15:07 INFO - '--xperf_path', 16:15:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:15:07 INFO - 'tests': ['tp5n']}, 16:15:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:15:07 INFO - 'talos_options': ['--xperf_path', 16:15:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:15:07 INFO - 'tests': ['tp5n']}}, 16:15:07 INFO - 'talos.zip': {'path': '', 16:15:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:15:07 INFO - Running post-action listener: _resource_record_post_action 16:15:07 INFO - [mozharness: 2016-08-29 23:15:07.241572Z] Finished populate-webroot step (success) 16:15:07 INFO - [mozharness: 2016-08-29 23:15:07.241662Z] Running create-virtualenv step. 16:15:07 INFO - Running pre-action listener: _resource_record_pre_action 16:15:07 INFO - Running main action method: create_virtualenv 16:15:07 INFO - Creating virtualenv /builds/slave/test/build/venv 16:15:07 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 16:15:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:15:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:15:07 INFO - Using real prefix '/usr' 16:15:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:15:07 INFO - Installing distribute.............................................................................................................................................................................................done. 16:15:09 INFO - Installing pip.................done. 16:15:09 INFO - Return code: 0 16:15:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:15:09 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')]} 16:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:09 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')]} 16:15:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:09 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 0x7f804dba9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1157420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e5d20>, '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 0x11e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11e70b0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', '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 16:15:09 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 16:15:09 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 16:15:09 INFO - Using env: {'DISPLAY': ':0', 16:15:09 INFO - 'HOME': '/home/cltbld', 16:15:09 INFO - 'LANG': 'en_US.UTF-8', 16:15:09 INFO - 'LANGUAGE': 'en_US:en', 16:15:09 INFO - 'LOGNAME': 'cltbld', 16:15:09 INFO - 'MAIL': '/var/mail/cltbld', 16:15:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:09 INFO - 'MOZ_NO_REMOTE': '1', 16:15:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:09 INFO - 'NO_EM_RESTART': '1', 16:15:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:09 INFO - 'PWD': '/builds/slave/test', 16:15:09 INFO - 'SHELL': '/bin/bash', 16:15:09 INFO - 'SHLVL': '1', 16:15:09 INFO - 'TERM': 'linux', 16:15:09 INFO - 'TMOUT': '86400', 16:15:09 INFO - 'USER': 'cltbld', 16:15:09 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', 16:15:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:09 INFO - '_': '/tools/buildbot/bin/python'} 16:15:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:10 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 16:15:10 INFO - Cleaning up... 16:15:10 INFO - Return code: 0 16:15:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:15:10 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')]} 16:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:10 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')]} 16:15:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:10 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 0x7f804dba9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1157420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e5d20>, '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 0x11e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11e70b0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', '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 16:15:10 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 16:15:10 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 16:15:10 INFO - Using env: {'DISPLAY': ':0', 16:15:10 INFO - 'HOME': '/home/cltbld', 16:15:10 INFO - 'LANG': 'en_US.UTF-8', 16:15:10 INFO - 'LANGUAGE': 'en_US:en', 16:15:10 INFO - 'LOGNAME': 'cltbld', 16:15:10 INFO - 'MAIL': '/var/mail/cltbld', 16:15:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:10 INFO - 'MOZ_NO_REMOTE': '1', 16:15:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:10 INFO - 'NO_EM_RESTART': '1', 16:15:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:10 INFO - 'PWD': '/builds/slave/test', 16:15:10 INFO - 'SHELL': '/bin/bash', 16:15:10 INFO - 'SHLVL': '1', 16:15:10 INFO - 'TERM': 'linux', 16:15:10 INFO - 'TMOUT': '86400', 16:15:10 INFO - 'USER': 'cltbld', 16:15:10 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', 16:15:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:10 INFO - '_': '/tools/buildbot/bin/python'} 16:15:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:10 INFO - Downloading/unpacking psutil>=3.1.1 16:15:10 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 16:15:10 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 16:15:10 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 16:15:10 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 16:15:10 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 16:15:10 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 16:15:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:15:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:15:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:15:12 INFO - Installing collected packages: psutil 16:15:12 INFO - Running setup.py install for psutil 16:15:12 INFO - building 'psutil._psutil_linux' extension 16:15:12 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 16:15:12 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 16:15:12 INFO - building 'psutil._psutil_posix' extension 16:15:12 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 16:15:12 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 16:15:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:15:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:15:12 INFO - Successfully installed psutil 16:15:12 INFO - Cleaning up... 16:15:12 INFO - Return code: 0 16:15:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:15:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:12 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 0x7f804dba9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1157420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e5d20>, '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 0x11e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11e70b0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', '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 16:15:12 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 16:15:12 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 16:15:12 INFO - Using env: {'DISPLAY': ':0', 16:15:12 INFO - 'HOME': '/home/cltbld', 16:15:12 INFO - 'LANG': 'en_US.UTF-8', 16:15:12 INFO - 'LANGUAGE': 'en_US:en', 16:15:12 INFO - 'LOGNAME': 'cltbld', 16:15:12 INFO - 'MAIL': '/var/mail/cltbld', 16:15:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:12 INFO - 'MOZ_NO_REMOTE': '1', 16:15:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:12 INFO - 'NO_EM_RESTART': '1', 16:15:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:12 INFO - 'PWD': '/builds/slave/test', 16:15:12 INFO - 'SHELL': '/bin/bash', 16:15:12 INFO - 'SHLVL': '1', 16:15:12 INFO - 'TERM': 'linux', 16:15:12 INFO - 'TMOUT': '86400', 16:15:12 INFO - 'USER': 'cltbld', 16:15:12 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', 16:15:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:12 INFO - '_': '/tools/buildbot/bin/python'} 16:15:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:15:13 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 16:15:13 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 16:15:13 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 16:15:13 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 16:15:13 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 16:15:13 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 16:15:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:15:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:15:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:15:14 INFO - Installing collected packages: mozsystemmonitor 16:15:14 INFO - Running setup.py install for mozsystemmonitor 16:15:15 INFO - Successfully installed mozsystemmonitor 16:15:15 INFO - Cleaning up... 16:15:15 INFO - Return code: 0 16:15:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:15: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')]} 16:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15: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')]} 16:15:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:15 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 0x7f804dba9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1157420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e5d20>, '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 0x11e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11e70b0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', '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 16:15:15 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 16:15:15 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 16:15:15 INFO - Using env: {'DISPLAY': ':0', 16:15:15 INFO - 'HOME': '/home/cltbld', 16:15:15 INFO - 'LANG': 'en_US.UTF-8', 16:15:15 INFO - 'LANGUAGE': 'en_US:en', 16:15:15 INFO - 'LOGNAME': 'cltbld', 16:15:15 INFO - 'MAIL': '/var/mail/cltbld', 16:15:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:15 INFO - 'MOZ_NO_REMOTE': '1', 16:15:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:15 INFO - 'NO_EM_RESTART': '1', 16:15:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:15 INFO - 'PWD': '/builds/slave/test', 16:15:15 INFO - 'SHELL': '/bin/bash', 16:15:15 INFO - 'SHLVL': '1', 16:15:15 INFO - 'TERM': 'linux', 16:15:15 INFO - 'TMOUT': '86400', 16:15:15 INFO - 'USER': 'cltbld', 16:15:15 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', 16:15:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:15 INFO - '_': '/tools/buildbot/bin/python'} 16:15:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:15 INFO - Downloading/unpacking jsonschema==2.5.1 16:15:15 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 16:15:15 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 16:15:15 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 16:15:15 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 16:15:15 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 16:15:15 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 16:15:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:15:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:17 INFO - Downloading functools32-3.2.3-2.tar.gz 16:15:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:15:17 INFO - warning: no files found matching '*.txt' 16:15:17 INFO - no previously-included directories found matching 'build' 16:15:17 INFO - no previously-included directories found matching 'dist' 16:15:17 INFO - no previously-included directories found matching '.git*' 16:15:17 INFO - Installing collected packages: jsonschema, functools32 16:15:17 INFO - Running setup.py install for functools32 16:15:17 INFO - warning: no files found matching '*.txt' 16:15:17 INFO - no previously-included directories found matching 'build' 16:15:17 INFO - no previously-included directories found matching 'dist' 16:15:17 INFO - no previously-included directories found matching '.git*' 16:15:17 INFO - Successfully installed jsonschema functools32 16:15:17 INFO - Cleaning up... 16:15:17 INFO - Return code: 0 16:15:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f804dba9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1157420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e5d20>, '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 0x11e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11e70b0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', '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 16:15:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:15:17 INFO - Using env: {'DISPLAY': ':0', 16:15:17 INFO - 'HOME': '/home/cltbld', 16:15:17 INFO - 'LANG': 'en_US.UTF-8', 16:15:17 INFO - 'LANGUAGE': 'en_US:en', 16:15:17 INFO - 'LOGNAME': 'cltbld', 16:15:17 INFO - 'MAIL': '/var/mail/cltbld', 16:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:17 INFO - 'MOZ_NO_REMOTE': '1', 16:15:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:17 INFO - 'NO_EM_RESTART': '1', 16:15:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:17 INFO - 'PWD': '/builds/slave/test', 16:15:17 INFO - 'SHELL': '/bin/bash', 16:15:17 INFO - 'SHLVL': '1', 16:15:17 INFO - 'TERM': 'linux', 16:15:17 INFO - 'TMOUT': '86400', 16:15:17 INFO - 'USER': 'cltbld', 16:15:17 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', 16:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:17 INFO - '_': '/tools/buildbot/bin/python'} 16:15:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:15:17 INFO - Cleaning up... 16:15:17 INFO - Return code: 0 16:15:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f804dba9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1157420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e5d20>, '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 0x11e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11e70b0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', '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 16:15:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:15:17 INFO - Using env: {'DISPLAY': ':0', 16:15:17 INFO - 'HOME': '/home/cltbld', 16:15:17 INFO - 'LANG': 'en_US.UTF-8', 16:15:17 INFO - 'LANGUAGE': 'en_US:en', 16:15:17 INFO - 'LOGNAME': 'cltbld', 16:15:17 INFO - 'MAIL': '/var/mail/cltbld', 16:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:17 INFO - 'MOZ_NO_REMOTE': '1', 16:15:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:17 INFO - 'NO_EM_RESTART': '1', 16:15:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:17 INFO - 'PWD': '/builds/slave/test', 16:15:17 INFO - 'SHELL': '/bin/bash', 16:15:17 INFO - 'SHLVL': '1', 16:15:17 INFO - 'TERM': 'linux', 16:15:17 INFO - 'TMOUT': '86400', 16:15:17 INFO - 'USER': 'cltbld', 16:15:17 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', 16:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:17 INFO - '_': '/tools/buildbot/bin/python'} 16:15:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:18 INFO - Downloading/unpacking blobuploader==1.2.4 16:15:18 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 16:15:18 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 16:15:18 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 16:15:18 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 16:15:18 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 16:15:18 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 16:15:19 INFO - Downloading blobuploader-1.2.4.tar.gz 16:15:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:15:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:15:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:20 INFO - Downloading docopt-0.6.1.tar.gz 16:15:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:15:20 INFO - Installing collected packages: blobuploader, requests, docopt 16:15:20 INFO - Running setup.py install for blobuploader 16:15:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:15:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:15:20 INFO - Running setup.py install for requests 16:15:21 INFO - Running setup.py install for docopt 16:15:21 INFO - Successfully installed blobuploader requests docopt 16:15:21 INFO - Cleaning up... 16:15:21 INFO - Return code: 0 16:15:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:21 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 0x7f804dba9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1157420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e5d20>, '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 0x11e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11e70b0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', '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 16:15:21 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 16:15:21 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 16:15:21 INFO - Using env: {'DISPLAY': ':0', 16:15:21 INFO - 'HOME': '/home/cltbld', 16:15:21 INFO - 'LANG': 'en_US.UTF-8', 16:15:21 INFO - 'LANGUAGE': 'en_US:en', 16:15:21 INFO - 'LOGNAME': 'cltbld', 16:15:21 INFO - 'MAIL': '/var/mail/cltbld', 16:15:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:21 INFO - 'MOZ_NO_REMOTE': '1', 16:15:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:21 INFO - 'NO_EM_RESTART': '1', 16:15:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:21 INFO - 'PWD': '/builds/slave/test', 16:15:21 INFO - 'SHELL': '/bin/bash', 16:15:21 INFO - 'SHLVL': '1', 16:15:21 INFO - 'TERM': 'linux', 16:15:21 INFO - 'TMOUT': '86400', 16:15:21 INFO - 'USER': 'cltbld', 16:15:21 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', 16:15:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:21 INFO - '_': '/tools/buildbot/bin/python'} 16:15:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:15:21 INFO - Running setup.py (path:/tmp/pip-JdrtPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:15:21 INFO - Running setup.py (path:/tmp/pip-kaS8cU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:15:21 INFO - Running setup.py (path:/tmp/pip-PFqpBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:15:21 INFO - Running setup.py (path:/tmp/pip-LE45PI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:15:21 INFO - Running setup.py (path:/tmp/pip-b7KnyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:15:21 INFO - Running setup.py (path:/tmp/pip-aFmQqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:15:22 INFO - Running setup.py (path:/tmp/pip-ZBP7VS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:15:22 INFO - Running setup.py (path:/tmp/pip-B0l7cZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:15:22 INFO - Running setup.py (path:/tmp/pip-iWjJQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:15:22 INFO - Running setup.py (path:/tmp/pip-uf27Hw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:15:22 INFO - Running setup.py (path:/tmp/pip-EoISep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:15:22 INFO - Running setup.py (path:/tmp/pip-k3VZ3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:15:22 INFO - Running setup.py (path:/tmp/pip-1539Jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:15:22 INFO - Running setup.py (path:/tmp/pip-b7CkDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:22 INFO - Running setup.py (path:/tmp/pip-a8JVOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:15:22 INFO - Running setup.py (path:/tmp/pip-Kyj4fO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:15:22 INFO - Running setup.py (path:/tmp/pip-q7e71Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:15:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:15:22 INFO - Running setup.py install for manifestparser 16:15:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:15:22 INFO - Running setup.py install for mozcrash 16:15:22 INFO - Running setup.py install for mozdebug 16:15:23 INFO - Running setup.py install for mozdevice 16:15:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:15:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:15:23 INFO - Running setup.py install for mozfile 16:15:23 INFO - Running setup.py install for mozhttpd 16:15:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:15:23 INFO - Running setup.py install for mozinfo 16:15:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:15:23 INFO - Running setup.py install for mozInstall 16:15:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:15:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:15:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:15:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:15:23 INFO - Running setup.py install for mozleak 16:15:23 INFO - Running setup.py install for mozlog 16:15:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:15:23 INFO - Running setup.py install for moznetwork 16:15:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:15:23 INFO - Running setup.py install for mozprocess 16:15:24 INFO - Running setup.py install for mozprofile 16:15:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:15:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:15:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:15:24 INFO - Running setup.py install for mozrunner 16:15:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:15:24 INFO - Running setup.py install for mozscreenshot 16:15:24 INFO - Running setup.py install for moztest 16:15:24 INFO - Running setup.py install for mozversion 16:15:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:15:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:15:24 INFO - Cleaning up... 16:15:24 INFO - Return code: 0 16:15:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:24 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 0x7f804dba9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1157420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e5d20>, '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 0x11e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11e70b0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', '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 16:15:24 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 16:15:24 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 16:15:24 INFO - Using env: {'DISPLAY': ':0', 16:15:24 INFO - 'HOME': '/home/cltbld', 16:15:24 INFO - 'LANG': 'en_US.UTF-8', 16:15:24 INFO - 'LANGUAGE': 'en_US:en', 16:15:24 INFO - 'LOGNAME': 'cltbld', 16:15:24 INFO - 'MAIL': '/var/mail/cltbld', 16:15:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:24 INFO - 'MOZ_NO_REMOTE': '1', 16:15:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:24 INFO - 'NO_EM_RESTART': '1', 16:15:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:24 INFO - 'PWD': '/builds/slave/test', 16:15:24 INFO - 'SHELL': '/bin/bash', 16:15:24 INFO - 'SHLVL': '1', 16:15:24 INFO - 'TERM': 'linux', 16:15:24 INFO - 'TMOUT': '86400', 16:15:24 INFO - 'USER': 'cltbld', 16:15:24 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', 16:15:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:24 INFO - '_': '/tools/buildbot/bin/python'} 16:15:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:15:24 INFO - Running setup.py (path:/tmp/pip-Mr5_e8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:15:24 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)) 16:15:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:15:24 INFO - Running setup.py (path:/tmp/pip-GnpHWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:15:24 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)) 16:15:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:15:24 INFO - Running setup.py (path:/tmp/pip-PVFtZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:15:25 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)) 16:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:15:25 INFO - Running setup.py (path:/tmp/pip-rjKrXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:15:25 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)) 16:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:15:25 INFO - Running setup.py (path:/tmp/pip-5INy9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:15:25 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)) 16:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:15:25 INFO - Running setup.py (path:/tmp/pip-TPlerr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:15:25 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)) 16:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:15:25 INFO - Running setup.py (path:/tmp/pip-xU4TAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:15:25 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)) 16:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:15:25 INFO - Running setup.py (path:/tmp/pip-GqEa3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:15:25 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)) 16:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:15:25 INFO - Running setup.py (path:/tmp/pip-ilOfNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:15:25 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)) 16:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:15:25 INFO - Running setup.py (path:/tmp/pip-0ovGHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:15:25 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)) 16:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:15:25 INFO - Running setup.py (path:/tmp/pip-t4KSjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:15:25 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)) 16:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:15:25 INFO - Running setup.py (path:/tmp/pip-21xcPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:15:25 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)) 16:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:15:25 INFO - Running setup.py (path:/tmp/pip-9tDSZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:15:25 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)) 16:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:15:25 INFO - Running setup.py (path:/tmp/pip-wx6d2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:15:25 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)) 16:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:25 INFO - Running setup.py (path:/tmp/pip-MmDkkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:25 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)) 16:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:15:25 INFO - Running setup.py (path:/tmp/pip-bswy3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:15:25 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)) 16:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:15:25 INFO - Running setup.py (path:/tmp/pip-kPXWVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:15:26 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)) 16:15:26 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)) 16:15:26 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)) 16:15:26 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)) 16:15:26 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)) 16:15:26 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)) 16:15:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:27 INFO - Downloading blessings-1.6.tar.gz 16:15:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:15:28 INFO - Installing collected packages: blessings 16:15:28 INFO - Running setup.py install for blessings 16:15:28 INFO - Successfully installed blessings 16:15:28 INFO - Cleaning up... 16:15:28 INFO - Return code: 0 16:15:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:15:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:15:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:15:28 INFO - Reading from file tmpfile_stdout 16:15:28 INFO - Current package versions: 16:15:28 INFO - argparse == 1.2.1 16:15:28 INFO - blessings == 1.6 16:15:28 INFO - blobuploader == 1.2.4 16:15:28 INFO - docopt == 0.6.1 16:15:28 INFO - functools32 == 3.2.3-2 16:15:28 INFO - jsonschema == 2.5.1 16:15:28 INFO - manifestparser == 1.1 16:15:28 INFO - mozInstall == 1.12 16:15:28 INFO - mozcrash == 0.17 16:15:28 INFO - mozdebug == 0.1 16:15:28 INFO - mozdevice == 0.48 16:15:28 INFO - mozfile == 1.2 16:15:28 INFO - mozhttpd == 0.7 16:15:28 INFO - mozinfo == 0.9 16:15:28 INFO - mozleak == 0.1 16:15:28 INFO - mozlog == 3.3 16:15:28 INFO - moznetwork == 0.27 16:15:28 INFO - mozprocess == 0.23 16:15:28 INFO - mozprofile == 0.28 16:15:28 INFO - mozrunner == 6.12 16:15:28 INFO - mozscreenshot == 0.1 16:15:28 INFO - mozsystemmonitor == 0.3 16:15:28 INFO - moztest == 0.7 16:15:28 INFO - mozversion == 1.4 16:15:28 INFO - psutil == 3.1.1 16:15:28 INFO - requests == 1.2.3 16:15:28 INFO - wsgiref == 0.1.2 16:15:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:28 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 0x7f804dba9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1157420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e5d20>, '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 0x11e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11e70b0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', '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 16:15:28 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 16:15:28 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 16:15:28 INFO - Using env: {'DISPLAY': ':0', 16:15:28 INFO - 'HOME': '/home/cltbld', 16:15:28 INFO - 'LANG': 'en_US.UTF-8', 16:15:28 INFO - 'LANGUAGE': 'en_US:en', 16:15:28 INFO - 'LOGNAME': 'cltbld', 16:15:28 INFO - 'MAIL': '/var/mail/cltbld', 16:15:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:28 INFO - 'MOZ_NO_REMOTE': '1', 16:15:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:28 INFO - 'NO_EM_RESTART': '1', 16:15:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:28 INFO - 'PWD': '/builds/slave/test', 16:15:28 INFO - 'SHELL': '/bin/bash', 16:15:28 INFO - 'SHLVL': '1', 16:15:28 INFO - 'TERM': 'linux', 16:15:28 INFO - 'TMOUT': '86400', 16:15:28 INFO - 'USER': 'cltbld', 16:15:28 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', 16:15:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:28 INFO - '_': '/tools/buildbot/bin/python'} 16:15:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:28 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)) 16:15:28 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)) 16:15:28 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)) 16:15:28 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)) 16:15:28 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)) 16:15:28 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)) 16:15:28 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)) 16:15:28 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)) 16:15:28 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)) 16:15:28 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)) 16:15:28 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)) 16:15:28 INFO - Cleaning up... 16:15:28 INFO - Return code: 0 16:15:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:15:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f804dba9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1157420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e5d20>, '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 0x11e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11e70b0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', '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 16:15:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:15:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:15:28 INFO - Using env: {'DISPLAY': ':0', 16:15:28 INFO - 'HOME': '/home/cltbld', 16:15:28 INFO - 'LANG': 'en_US.UTF-8', 16:15:28 INFO - 'LANGUAGE': 'en_US:en', 16:15:28 INFO - 'LOGNAME': 'cltbld', 16:15:28 INFO - 'MAIL': '/var/mail/cltbld', 16:15:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:28 INFO - 'MOZ_NO_REMOTE': '1', 16:15:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:28 INFO - 'NO_EM_RESTART': '1', 16:15:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:28 INFO - 'PWD': '/builds/slave/test', 16:15:28 INFO - 'SHELL': '/bin/bash', 16:15:28 INFO - 'SHLVL': '1', 16:15:28 INFO - 'TERM': 'linux', 16:15:28 INFO - 'TMOUT': '86400', 16:15:28 INFO - 'USER': 'cltbld', 16:15:28 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', 16:15:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:28 INFO - '_': '/tools/buildbot/bin/python'} 16:15:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:15:28 INFO - Cleaning up... 16:15:28 INFO - Return code: 0 16:15:28 INFO - Running post-action listener: _resource_record_post_action 16:15:28 INFO - Running post-action listener: _start_resource_monitoring 16:15:28 INFO - Starting resource monitoring. 16:15:28 INFO - [mozharness: 2016-08-29 23:15:28.886182Z] Finished create-virtualenv step (success) 16:15:28 INFO - [mozharness: 2016-08-29 23:15:28.886654Z] Running install step. 16:15:28 INFO - Running pre-action listener: _resource_record_pre_action 16:15:28 INFO - Running main action method: install 16:15:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:15:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:15:29 INFO - Reading from file tmpfile_stdout 16:15:29 INFO - Detecting whether we're running mozinstall >=1.0... 16:15:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:15:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:15:29 INFO - Reading from file tmpfile_stdout 16:15:29 INFO - Output received: 16:15:29 INFO - Usage: mozinstall [options] installer 16:15:29 INFO - Options: 16:15:29 INFO - -h, --help show this help message and exit 16:15:29 INFO - -d DEST, --destination=DEST 16:15:29 INFO - Directory to install application into. [default: 16:15:29 INFO - "/builds/slave/test"] 16:15:29 INFO - --app=APP Application being installed. [default: firefox] 16:15:29 INFO - mkdir: /builds/slave/test/build/application 16:15:29 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'] 16:15:29 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 16:15:45 INFO - Reading from file tmpfile_stdout 16:15:45 INFO - Output received: 16:15:45 INFO - /builds/slave/test/build/application/firefox/firefox 16:15:45 INFO - Running post-action listener: _resource_record_post_action 16:15:45 INFO - [mozharness: 2016-08-29 23:15:45.969324Z] Finished install step (success) 16:15:45 INFO - [mozharness: 2016-08-29 23:15:45.969646Z] Running run-tests step. 16:15:45 INFO - Running pre-action listener: _resource_record_pre_action 16:15:45 INFO - Running main action method: run_tests 16:15:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:15:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:15:45 INFO - Python 2.7.3 16:15:45 INFO - Return code: 0 16:15:45 INFO - grabbing minidump binary from tooltool 16:15:45 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')]} 16:15:45 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 0x11e5d20>, '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 0x11e6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11e70b0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:15:45 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 16:15:45 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 16:15:45 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 16:15:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:15:46 INFO - Return code: 0 16:15:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:15:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:15:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:15:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:15:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:15:46 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/b1oLzUEXR9GfgN1WyRY1ig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 16:15:46 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/b1oLzUEXR9GfgN1WyRY1ig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 16:15:46 INFO - Using env: {'DISPLAY': ':0', 16:15:46 INFO - 'HOME': '/home/cltbld', 16:15:46 INFO - 'LANG': 'en_US.UTF-8', 16:15:46 INFO - 'LANGUAGE': 'en_US:en', 16:15:46 INFO - 'LOGNAME': 'cltbld', 16:15:46 INFO - 'MAIL': '/var/mail/cltbld', 16:15:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:15:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:15:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:46 INFO - 'MOZ_NO_REMOTE': '1', 16:15:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:15:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:46 INFO - 'NO_EM_RESTART': '1', 16:15:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:46 INFO - 'PWD': '/builds/slave/test', 16:15:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:15:46 INFO - 'SHELL': '/bin/bash', 16:15:46 INFO - 'SHLVL': '1', 16:15:46 INFO - 'TERM': 'linux', 16:15:46 INFO - 'TMOUT': '86400', 16:15:46 INFO - 'USER': 'cltbld', 16:15:46 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971', 16:15:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:46 INFO - '_': '/tools/buildbot/bin/python'} 16:15:46 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/b1oLzUEXR9GfgN1WyRY1ig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 16:15:46 INFO - mozversion application_buildid: 20160829145731 16:15:46 INFO - mozversion application_changeset: e980aee95cb2ab7503daa5575dac1e649fbac4d0 16:15:46 INFO - mozversion application_display_name: Nightly 16:15:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:15:46 INFO - mozversion application_name: Firefox 16:15:46 INFO - mozversion application_remotingname: firefox 16:15:46 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 16:15:46 INFO - mozversion application_vendor: Mozilla 16:15:46 INFO - mozversion application_version: 51.0a1 16:15:46 INFO - mozversion platform_buildid: 20160829145731 16:15:46 INFO - mozversion platform_changeset: e980aee95cb2ab7503daa5575dac1e649fbac4d0 16:15:46 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 16:15:46 INFO - mozversion platform_version: 51.0a1 16:15:46 INFO - using testdate: 1472512546 16:15:46 INFO - actual date: 1472512546 16:15:46 INFO - starting webserver on 'localhost:39996' 16:15:46 INFO - SUITE-START | Running 2 tests 16:15:46 INFO - TEST-START | dromaeo_css 16:15:46 INFO - operating with platform_type : linux_ 16:15:46 INFO - Initialising browser for dromaeo_css test... 16:15:46 INFO - TEST-INFO | started process 15392 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu0cZcZ/profile http://localhost:39996/getInfo.html) 16:15:50 INFO - PROCESS | 15392 | __metrics Screen width/height:1600/1200 16:15:50 INFO - PROCESS | 15392 | colorDepth:24 16:15:50 INFO - PROCESS | 15392 | Browser inner width/height: 1024/697 16:15:50 INFO - PROCESS | 15392 | __metrics 16:15:51 INFO - PROCESS | 15392 | 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" 16:15:51 INFO - PROCESS | 15392 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:15:51 INFO - PROCESS | 15392 | console.error: PushService: 16:15:51 INFO - PROCESS | 15392 | stateChangeProcessEnqueue: Error transitioning state 16:15:51 INFO - PROCESS | 15392 | UnknownError 16:15:52 INFO - TEST-INFO | 15392: exit 0 16:15:52 INFO - Browser initialized. 16:15:52 INFO - Running cycle 1/1 for dromaeo_css test... 16:15:52 INFO - TEST-INFO | started process 15473 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu0cZcZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:16:04 INFO - PROCESS | 15473 | RSS: Main: 192086016 16:16:04 INFO - PROCESS | 15473 | 16:16:55 INFO - PROCESS | 15473 | 1472512615738 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] 16:19:09 INFO - PROCESS | 15473 | Cycle 1(1): loaded http://localhost:39996/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39996/tests/dromaeo/cssquery-ext.html) 16:19:10 INFO - PROCESS | 15473 | RSS: Main: 270585856 16:19:10 INFO - PROCESS | 15473 | 16:22:13 INFO - PROCESS | 15473 | Cycle 1(1): loaded http://localhost:39996/tests/dromaeo/cssquery-ext.html (next: http://localhost:39996/tests/dromaeo/cssquery-jquery.html) 16:22:13 INFO - PROCESS | 15473 | RSS: Main: 268120064 16:22:13 INFO - PROCESS | 15473 | 16:25:16 INFO - PROCESS | 15473 | Cycle 1(1): loaded http://localhost:39996/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39996/tests/dromaeo/cssquery-mootools.html) 16:25:16 INFO - PROCESS | 15473 | RSS: Main: 220274688 16:25:16 INFO - PROCESS | 15473 | 16:28:18 INFO - PROCESS | 15473 | Cycle 1(1): loaded http://localhost:39996/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39996/tests/dromaeo/cssquery-prototype.html) 16:28:19 INFO - PROCESS | 15473 | RSS: Main: 218509312 16:28:19 INFO - PROCESS | 15473 | 16:31:22 INFO - PROCESS | 15473 | Cycle 1(1): loaded http://localhost:39996/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39996/tests/dromaeo/cssquery-yui.html) 16:31:23 INFO - PROCESS | 15473 | RSS: Main: 221503488 16:31:23 INFO - PROCESS | 15473 | 16:34:26 INFO - PROCESS | 15473 | Cycle 1(1): loaded http://localhost:39996/tests/dromaeo/cssquery-yui.html (next: http://localhost:39996/tests/dromaeo/cssquery-dojo.html) 16:34:26 INFO - PROCESS | 15473 | RSS: Main: 309821440 16:34:26 INFO - PROCESS | 15473 | 16:34:26 INFO - PROCESS | 15473 | __start_tp_report 16:34:26 INFO - PROCESS | 15473 | _x_x_mozilla_page_load 16:34:26 INFO - PROCESS | 15473 | _x_x_mozilla_page_load_details 16:34:26 INFO - PROCESS | 15473 | |i|pagename|runs| 16:34:26 INFO - PROCESS | 15473 | |0;dojo.html;2615.6468876701647;2638.670166229221;2646.6427032350625;2693.9057133000338;2705.012670375312;4525.592696657301;4630.62954963603;4691.788869500872;4783.952160478394;4840.951590484098;4646.837360692376;4658.557437043475;4660.533946605337;4678.695884767496;4731.337612734212;4248.553901840309;4259.701820872525;4330.653547716191;4398.582134697199;4510.97744511277;4442.733435993835;4482.305242687388;4505.459344878619;4546.772661366935;4548.317752337143;4266.1467706458525;4302.655787537021;4321.092570560186;4359.258925982591;4363;4334.068175342285;4342.522322544518;4343.478782546114;4348.347747837818;4354.325079612665;4248.235317642823;4261.637760790337;4266.06146647737;4271.61555460007;4315.654747620214;4646;4710.764461776932;4711.316859055452;4721.905561888743;4764.7617619119255;4909.631777616657;4953.257011448276;5013.598912087061;5020.623453240985;5031.094403007457;5014.047331007134;5033.018561380532;5034.874128146789;5040.14317566011;5048.217526283432;4646.395968524104;4720.811167553328;4729.243321068612;4745.050989802054;4786.521347865186;5069.163588008011;5159.638825282269;5176.145935920531;5194.376674798973;5264.6051546134195;4990.525900039491;4992.500749925051;5026.472220416877;5057.241413787961;5057.418396884332;5066.632009357527;5068.873278168076;5075.65103777064;5076.923846142311;5136.203888397305;4853.830115945925;4894.926576101361;5016.749162541932;5017.949820501748;5022.397312322545;4871.244957031666;4899.1426500362295;4907.9263811042865;4956.479569645207;4967.081089998362;4290.013296941721;4291.420658211102;4291.69958102936;4343.19650864591;4343.283358245917;3749.9437508437395;3769.7738135711943;3780.3762379207674;3783.697304215656;3819.255245227154;4365;4367.978160109243;4371.759553224603;4378.78106094694;4440.533743956903;5037.11850426174;5053.444121146671;5072.087024335655;5075.8223462179385;5145;4296.89257768552;4389.813878664991;4391.14372697321;4408.933865992013;4448.110377924363;4527.683062185615;4581.65637577183;4587.977060114678;4602.378678878308;4667.299905014275;4694.647901407408;4697.835575754969;4731.976340118277;4737.360456338351;4793.0413917216;4936.308916751729;5009.098362294676;5054.974725126351;5058.011077093758;5121.9743901281745;4720.976395118002;4726.645501587326;4749.2876068589985;4762.380890484214;4826.0830442215865;4978.726170060681;4984.551390374883;4987.526185012418;4995.925061124159;5052.444231134646;4923.212286034217;5065.113605119163;5080.187170052774;5085.618578606545;5151.562117219928;4931.06309801151;4980.501949805135;4981.3026176334615;4983.5763960063805;5035.546800787946;4972.950270497248;4980;4981.726005069756;4985.152524070989;5040.798368065236;4799.832005879777;4887.0714564232685;4899.510048995215;4902;4948.752562371939;4959.256111583291;5005.0740612985255;5035.697858128524;5040.4455509894615;5060.443351231435;4872.342233798392;4878.048780487771;4885.560299573055;4888.266759986031;4951.702897826142 16:34:26 INFO - PROCESS | 15473 | |1;ext.html;563.5210071439277;564.7035306464106;574.3481148896002;579.0387955993051;582.490320969152;26725.797339119737;36391.27217455649;37357.813210933906;37438.43842342367;42044.94887627811;37363.43954840673;37691;37720.43419348705;37728.62271377286;37731.62268377315;7648.847023059536;7793.103793063813;7805;7806.1413244543055;7807.531548107103;7240.094988126484;9690.37012594179;9920.355176913477;9951.402915825074;10281.074703276703;10029.69910902674;10119.544620492095;10132.392056476598;10134.189264858831;10151.23865710071;10000.899991000106;10084.49577521121;10084.899151008469;10089.848652270186;10094.343867648578;9852.014798520162;9940.403575785476;9954.900450995543;9955.900440995641;9956.203503719686;35190.64809351899;35408.29183416343;35443.11392215215;35450.46824297638;35541.82229088838;2900.7824413168887;2991.4316279906748;2997.2057404787747;3183.602049743782;3545.4504551794257;26870.5969410459;27082.187534374;27553.311167220778;27683.86158069217;27997.860010699816;3152.6059242594674;3216.6140063192565;3309.652486488908;3321.7508686848582;3362.4788157835574;236922;238566;239269.99999999825;239715;239771;29667.25831854178;48158;48565;48613.513864861605;48708;29748.55377169344;31469.213269668442;31481.8425907869;31524.36951261008;31526.369472610877;12589.685257868443;12737.808932866015;12783.296918669563;12847.614571562868;12937.353132343533;9273;9489.90510094904;10640.042396184226;10861.83707244392;10891.618793342195;7155.105611798525;7216;7406;7611.90672565049;7644.770656880302;2962.674105848355;2984.985075074654;3026.7881248312406;3046.5887105240954;3054.740347070524;847.8304339132075;851.6721062391024;862.9180227878342;875.6803766625136;896.8296023755465;25339.493210135694;26081.217563473125;26429.735702643113;26850.5972410414;26898.5965210522;901.5627420700955;925.6482536636036;927.1794461901131;935.9953200234091;966.9564869580886;13862.514811981533;13900.582982510541;14287.57137285883;14735.410583576537;14819.925900370428;362963;365215;365847;366884;367088;44974;45506.08987820225;45511.317330240076;45528.772356138004;45544;1042.8487869258915;1128.424503503203;1135.250734515216;1154.4054811772112;1197.7305106351;25231.62152567676;27699.44601108007;27785.30536736517;27975;28538.42923141484;38763;39241.60758392494;39243.2151356977;39266.411003835536;39276.803615981735;296523;296627;296682;297214;297432;23946.64080038766;24058.75941240635;24641.383965401008;24877.875610622556;25776.871115644302;39466.802665986484;39761.24655692112;39809.80095099506;39875.60124398719;40632.390514142906;33469.33061338808;33925.83037084799;33993.150171245914;34026.319473610885;34288.31423371568;33018;33229.50155747617;33493.54895061286;33908.66091339153;34213.657863421045;33746.66253337435;34122.48816267708;34281.657183427844;34545.30909381748;34654;28336;30110;30893.1464112154;31104.84447577838;31116.066518004496;24199.758002420454;24525.38686532851;24586.139485117936;24616.384590385383;24885.377865553506 16:34:26 INFO - PROCESS | 15473 | |2;jquery.html;341.77784440113936;347.7617831785227;354.19597513644027;360.96751292383664;385.7569731069426;12482.625521234366;15844.841551584495;16082.83917160828;17010.914945425255;18124.546886327847;15853.286602102904;16093.436729714462;16288.10415427154;16528.917355413207;16566.83433165671;4906.533879281472;5125.487451254863;5218.13900706383;5435.972820135894;5504.146857237135;3393.1347506385837;3560.608333083359;5436.972815135919;5470.124780035195;5476.069068258385;3548.1661809474754;3558.1282585766526;4416.447944006999;6068.362821903703;6082.878342433149;6136.96931515344;6146.66193359365;6146.907796383058;6148.940105064808;6151.969240153815;5260.9736951315;5398.082326004589;5422;5429.782808687648;5434.809781657623;11117.388543630097;11311.490982905789;12164.72270411603;12277.508899644003;12320.630381088582;1786.1872847854195;1808.3941879470394;1862.087577087231;1964.6758284882976;2345.2846881701075;8491.575421228914;9012.233960113399;9167.954160229156;9225.67710130149;9467.479288639122;1664.642101948087;1745.8690598205058;1757.578181236507;1770.9468715938542;1789.91945362459;64696;64813;68142.65928670325;69057.96413053758;69228;9777.951110244545;9823.508824558743;9934.403935763878;10146.797064058677;10208.285420020527;13562.05065645415;13655;13660.521881734092;13666.863331366758;13668.17990920528;6608.372204640552;6646.501512386591;6915.412189963909;6956.965215173892;7023.508354415142;6231.719572619243;6267.874642507216;6410.166678331881;6413.775517856946;6422.325655806167;4089.8159582818844;4141.378793180987;4261.424707664488;4290.377895205178;4373.956260437419;1705.4372057221087;1729.4175829116218;1734.5230061732873;1758.4373000639673;1791.784985801712;808.6563210635456;814.6415577146037;817.4686453804956;818.3003531980071;935.9812803743887;15112.471063512725;16132.274047667883;16634.417795377103;16719.6656066878;17313.56716082108;820.274057459141;821.5793573225226;835.5529791561486;843.6667516331015;856.8243510080418;8687.913120868838;9353.11145441182;9489.1934185595;9578.281628877861;9702.078302561387;367348;370332;380603;383353;383449.7447614864;45646.771766140955;74356;74993.62503187449;75308;75932.24067759252;844.2444012608681;859.5530324231364;861.1990848510819;862.7282406042077;866.2853146154448;14589.562313130624;14757;14886.478973235884;14902.776458352917;15506.379744810081;36105.81947090353;36785.26429471449;36974.63025369712;37165;37635.43546846745;49325.75337123292;49908.750456247486;50264.74867625785;52177;52261;7373.410127189919;7467.290607392289;7616.69533218665;7632.427567932316;7698;9472.384295020802;11011.28426652265;12545.498180072695;12545.499628068435;12635.684107897008;10557.68326950162;10563.52464139131;10604;10681.46592670379;11111.222214444912;10572.471376431302;10802.945985270024;10866.348019118877;10898.891011090105;11075.778484430428;10461.790764184825;11233.157513186381;11321.490532926204;11374;11429.371384573928;7715.228477152465;7729.111152217342;7855.175206603224;7944.880826787718;7960.4825686330205;8471.364647651573;10166;10485.108765754996;10543.789124217627;10865.945670271598 16:34:26 INFO - PROCESS | 15473 | |3;mootools.html;1236.6351926181776;1330.720548684776;1367.0840536840317;1407.3526177958138;1663.0437498438394;6562.835929101775;7633.961830190849;7857.214278572147;7868;8114.472559283642;7980.840383192332;8033.598320083987;8039.995000624922;8051.3558915286785;8055.113937466874;2173.4783651923544;2201.152556265833;2203.0526873444414;2203.526241858;2204;2132.264368792764;2172.142003908455;2189.5292512109895;2197.8791166485835;2198.2196320306302;2142.839287053477;2160.2331172433796;2180.923667671632;2193.5174261662487;2193.5503221839563;2102.9474263143393;2107.7049213110176;2110.947226319339;2115.7355330583678;2118.491562025118;2097.6014557234075;2141.3468891987936;2148.269588339972;2152.7847215278503;2153.2033147735283;7703.422243331773;7850.175731548163;7944.761657150295;7951.244631759973;7979.521228726237;4156.397322388267;4310.870673879788;4315.331123675835;4316.266234740102;4327.956720432819;2748.876300566479;2839.403725217707;2886;2887.8556072196307;2888.436754832808;893.1113542025661;898.6270697660528;917.1699611851285;924.3021518753366;935.8175155844613;10696.037356637862;10725.463726813707;10736.838947415796;10752.892471075189;10773.084287835465;3741.6258374162367;3950.3876899080688;3951.2295102454746;4061.5532291447917;4062.756234625932;8479.915200848036;8496.235338819411;8507;8516.659333626585;8518.148185181431;4397.978010109929;4403.031333106712;4403.207422663952;4404.185225733249;4415.050764085737;3659.396199627032;3956.4263181838473;4082.040720430696;4102.794860256975;4111.1366613011605;3367.2760356523463;3382;3537.646235376442;3546.131197856542;3556.05764472415;1036.3419228789774;1040.2354269611828;1040.3549799124592;1066.8826429092794;1068.1134658233648;684.6029303004345;787.3779714025856;799.6921185343684;804.8631732605471;815.2825513548158;5015.799368025311;5058.873528161826;5142.61430392722;5172.612054095883;5435.076037073707;852.6376290076817;868.8566386546275;879.2877769007125;911.0889110889111;921.8016578448124;3171.254755132542;3366.1584603849037;3416.367971925202;3469.8091604961464;3497.8251087445524;9573.090556397132;9926.65256716026;10074.294799364121;10089.394636321844;10091.798164036678;9677.854832177523;10037.598496060076;10045.547950342252;10057.849132263022;10059.497025148714;7151.106111736033;7469.290417410337;7474.551526908403;7478.5138965967035;7479.700811967569;5008.098542262282;5039.773210205476;5039.778537047718;5056.873578160428;5288.68267903927;9006.774830629287;9121.589528471368;9123.452592844451;9133.45199288045;9184.58669359867;8845.24815390699;9005.23455506263;9006.279497640042;9072;9124.855658920016;6757.797266082025;7189.676464559003;7275.781726548212;7673.462857599914;7677.193894640982;7331.560106393634;7511.399088072832;7575.659095340833;7580.4693671444675;7587.886181707168;6712.2952090031795;6833.931660683528;6904.447644188353;6928.272531384132;7073.3987611053635;6763.188417389944;6771.3905748482875;6840.41856442208;6846.760363387258;6893.862122757617;5930.733117009831;6085.482733967662;6125.019996800491;6132.202813634199;6145.477634400947;4316.978415107905;4420.270655341833;4434.600885920283;4527.411436513232;4536.138133754576;4496.640268778555;4515.661325400673;4608.700434471749;4616.1229366420275;4778.880527986828 16:34:26 INFO - PROCESS | 15473 | |4;prototype.html;285.31524341580206;296.8753123688051;302.5899905627874;303.21618615877964;304.5766384725231;4501.594856462918;4695.2957056441555;4703.365045718827;4719.6460265480055;4722.551357621025;4557.977210113953;4653.69750966187;4690.319903613974;4695.506971767966;4704.858854234379;3745.382011968022;3752.836620647608;3773.622637736218;3776.603456637048;3782.2246439479977;3553.662402071799;3601.603823579404;3737.6075512071247;3742.5321834770652;3745.962540374602;3695.907602309937;3721.7022638189014;3730.794806285653;3745.45690874823;3749.9062523436996;3730.291244663505;3733.7386382953205;3734.2158146789207;3735.794531300777;3739.9626003739886;3323.93352132956;3331.267121233325;3336.5662463879785;3340.5490258814993;3343.331333733263;4447.911041779179;4585.862424127282;4596.655250856199;4612.285095810154;4647.930281045787;4275.615194632498;4284.957150428519;4285.35719642053;4297.527272000078;4311.245532031882;4910.656254062217;4935.35840340757;4940.827071052496;4957.82647607332;4961.131801934647;3422.19578399076;3619.1314084619767;3627.963720362789;3631.6731494165388;3647.908802279964;5139.897202055975;5141.768620412053;5155.226715992631;5156.381234251876;5164.4952198183855;3717.182219911615;3721.9627803721614;3724.255148970217;3726.70186385094;3731.626837316301;3786.3373909565717;3794.0135564753364;3803.972927309611;3806.1436176859984;3811.3901775715754;4174.3112386456505;4231.513375961742;4259.765712885821;4293.957060429419;4299.505556860939;4886.389201349832;4899.975500122477;4946.307516947631;4955.182394904872;4963.875903102379;1238.5107882386587;1270.1490001698885;1297.1568480487758;1301.9349032548334;1315.6381994951464;893.2228960804142;908.418612088264;926.9201380391768;931.0828833598899;942.7878727286443;4413.337999300066;4417.690761646718;4420.138073075719;4428.4243048403505;4443.355713421536;5095.235714642759;5099.14447909172;5107.157319042316;5110.28456016158;5116.2069879168785;4346.9347959780625;4377.277749171415;4384.56154384559;4407.4050003249795;4418.447694038246;2273.5111950930627;2388.2654360756837;2393.700787401575;2400.291913885408;2441.5605191065424;4263.893402664896;4282.721623094551;4293.742375457483;4309.556298639991;4325.718828276152;4555.567221113989;4565.223911934979;4613.907721845544;4635.490096089427;4656.509916826651;4101.692373072032;4287.335463003239;4299.78501074945;4317.633001194934;4343.239933011773;4546.136234115507;4555;4564.908701826012;4568.223402021597;4571.634269258385;4392.6485881130075;4394.857337092443;4431.578999994996;4445.599896009375;4477.910441791211;4086.59134086589;4240.724352917051;4245.150969806113;4255.5106162790435;4291.914161716811;3384.390809654286;3395.541601883714;3398.184435735356;3426.794392336468;3433.1588760753775;4523.5250298718165;4542.750148741851;4547.953970586717;4554.544545545419;4943.184374578154;4781.760911954458;4904.798324410543;4942.827001054946;4955.5044495550155;4996.850094497171;4949.82675606352;4964.851054468228;4986.905499690066;4996.100701873553;5008.348914641073;4760.190767569452;4927.507249275044;4939.185034469272;4956.256561515802;4958.7024778513405;5020.422651395136;5026.195808670595;5031.723255220852;5036.521530454601;5038.1435156022835;4842.15262329098;4847.248676455155;4851.611871050237;4878.926816097833;4879.487653796442 16:34:26 INFO - PROCESS | 15473 | |5;yui.html;590.9674869058207;600.5615900392713;656.2485953583144;703.5848849178983;726.3390314813519;11328.376939268348;11892.7621447571;12212.755744885097;12228.266304021754;12233.694157646063;12518;12543.059270554722;12600.495980160806;12604.243745375285;12604.558840440586;600.9669468179248;738.6639079218955;757.6476938223725;759.2521366454066;759.8366351234483;728.1407938632411;750.7972847331217;753.2956685499076;754.6000619671582;756.1417790807445;786.48092259109;786.9409794265424;788.5978151142901;788.7121200761709;789.2344425906862;628;799.2686691677109;799.940004499659;800.5116878703986;801.5551368990238;564.0523919814709;579.5711173731451;579.6782785554016;580.8170426315739;581.4505292498591;12071.57749478764;12084.456199471046;12127.818082728767;12145.696357591043;12183.695407614792;10496.737581560523;11024.44877756109;11038.889611103867;11149.442527873574;11158.386288754115;11123.27698699582;11125.109991200685;11303.378314192716;11381.658550243506;11496.482658280398;3989.3218152914073;3999.5800440953863;4000.5599384067727;4109.97945010276;4129.97935010326;64399;64432.355676443105;64596.3540364595;64646.35353646451;64686.676566616865;9832.950835245778;9856.605735770489;9866.703998880044;9937.304388692866;9999.550020249106;19400.223991040482;19424.22303107888;19442;19480.51298717515;19498.415047548595;6449.774257900951;6541.0515475255825;6591.604503729695;6626.9337306627285;6742.157230346207;3691.8338674759702;3695.2424752925585;3763.6074652378247;3778.6599206071587;3780.527434070741;2713.8778754956074;2788.8745006474755;2822.4778415993105;2828.420173864353;2828.872700728431;462.0620141113545;470.32273526122276;473.22863732117116;490.2670507591174;499.1389852504401;401.26167851154014;407.997960010204;433.82213292549903;440.61666350275465;472.5180316077582;6879.931200687929;7229.963850180716;7232.674529646073;7278.381337586258;7535.321821036133;510.29052673543333;510.49206039990446;514.0772313696923;519.5142541723501;532.475511621053;3290.917727056795;3349.2129349602824;3463.3419650266874;3511.3855075361707;3588.1747198144053;94733.05266947381;100528;101386;101685;102289;50756.49243507592;58682;82753.75869361965;88516;92152.53923730338;70791;79147;79231;80558;80587.59706201432;6612.404883560503;6711.9328806713265;6732.966335168293;6785.1518560179975;7125.5368401053765;45054.54945450504;45192.187523432614;46900.29649555191;47342.52657473381;47661.52338476571;59605;60875;64785.67607161934;68445.65777171082;68463.65768171127;8783.956080219814;8957.865632015655;9154.725358239264;9395.906040939502;9628.085331893459;9238.769030774285;9339.953300233456;9772.853407198756;9900.158486528439;10498.632547860789;9353.158215760614;9532;9558.426494410358;9631.807363852824;10119.645812396531;9755.9024409755;9768.120869121813;9834.901650983398;10181.949090254502;10424;10599.417032063311;11038.724031899266;11256.606018789631;11785.351805650429;11918.761624767283;9893.915242126184;10128.746781330527;10285.845712314172;10413.687589372332;10612.310199836986;9545.761355965878;9756.951215244162;9886.30795844284;9987.500624968578;10020.949895250476 16:34:26 INFO - PROCESS | 15473 | __end_tp_report 16:34:26 INFO - PROCESS | 15473 | __start_cc_report 16:34:26 INFO - PROCESS | 15473 | _x_x_mozilla_cycle_collect,1127 16:34:26 INFO - PROCESS | 15473 | __end_cc_report 16:34:26 INFO - PROCESS | 15473 | __startTimestamp1472513666603__endTimestamp 16:34:26 INFO - PROCESS | 15473 | 16:34:26 INFO - PROCESS | 15473 | ------- Summary: start ------- 16:34:26 INFO - PROCESS | 15473 | Number of tests: 6 16:34:26 INFO - PROCESS | 15473 | 16:34:26 INFO - PROCESS | 15473 | [#0] dojo.html Cycles:165 Average:4677.01 Median:4793.04 stddev:482.68 (10.1%) stddev-sans-first:456.27 16:34:26 INFO - PROCESS | 15473 | Values: 2615.6 2638.7 2646.6 2693.9 2705.0 4525.6 4630.6 4691.8 4784.0 4841.0 4646.8 4658.6 4660.5 4678.7 4731.3 4248.6 4259.7 4330.7 4398.6 4511.0 4442.7 4482.3 4505.5 4546.8 4548.3 4266.1 4302.7 4321.1 4359.3 4363.0 4334.1 4342.5 4343.5 4348.3 4354.3 4248.2 4261.6 4266.1 4271.6 4315.7 4646.0 4710.8 4711.3 4721.9 4764.8 4909.6 4953.3 5013.6 5020.6 5031.1 5014.0 5033.0 5034.9 5040.1 5048.2 4646.4 4720.8 4729.2 4745.1 4786.5 5069.2 5159.6 5176.1 5194.4 5264.6 4990.5 4992.5 5026.5 5057.2 5057.4 5066.6 5068.9 5075.7 5076.9 5136.2 4853.8 4894.9 5016.7 5017.9 5022.4 4871.2 4899.1 4907.9 4956.5 4967.1 4290.0 4291.4 4291.7 4343.2 4343.3 3749.9 3769.8 3780.4 3783.7 3819.3 4365.0 4368.0 4371.8 4378.8 4440.5 5037.1 5053.4 5072.1 5075.8 5145.0 4296.9 4389.8 4391.1 4408.9 4448.1 4527.7 4581.7 4588.0 4602.4 4667.3 4694.6 4697.8 4732.0 4737.4 4793.0 4936.3 5009.1 5055.0 5058.0 5122.0 4721.0 4726.6 4749.3 4762.4 4826.1 4978.7 4984.6 4987.5 4995.9 5052.4 4923.2 5065.1 5080.2 5085.6 5151.6 4931.1 4980.5 4981.3 4983.6 5035.5 4973.0 4980.0 4981.7 4985.2 5040.8 4799.8 4887.1 4899.5 4902.0 4948.8 4959.3 5005.1 5035.7 5040.4 5060.4 4872.3 4878.0 4885.6 4888.3 4951.7 16:34:26 INFO - PROCESS | 15473 | 16:34:26 INFO - PROCESS | 15473 | [#1] ext.html Cycles:180 Average:43840.04 Median:33349.42 stddev:80272.30 (240.7%) stddev-sans-first:80431.73 16:34:26 INFO - PROCESS | 15473 | Values: 563.5 564.7 574.3 579.0 582.5 26725.8 36391.3 37357.8 37438.4 42044.9 37363.4 37691.0 37720.4 37728.6 37731.6 7648.8 7793.1 7805.0 7806.1 7807.5 7240.1 9690.4 9920.4 9951.4 10281.1 10029.7 10119.5 10132.4 10134.2 10151.2 10000.9 10084.5 10084.9 10089.8 10094.3 9852.0 9940.4 9954.9 9955.9 9956.2 35190.6 35408.3 35443.1 35450.5 35541.8 2900.8 2991.4 2997.2 3183.6 3545.5 26870.6 27082.2 27553.3 27683.9 27997.9 3152.6 3216.6 3309.7 3321.8 3362.5 236922.0 238566.0 239270.0 239715.0 239771.0 29667.3 48158.0 48565.0 48613.5 48708.0 29748.6 31469.2 31481.8 31524.4 31526.4 12589.7 12737.8 12783.3 12847.6 12937.4 9273.0 9489.9 10640.0 10861.8 10891.6 7155.1 7216.0 7406.0 7611.9 7644.8 2962.7 2985.0 3026.8 3046.6 3054.7 847.8 851.7 862.9 875.7 896.8 25339.5 26081.2 26429.7 26850.6 26898.6 901.6 925.6 927.2 936.0 967.0 13862.5 13900.6 14287.6 14735.4 14819.9 362963.0 365215.0 365847.0 366884.0 367088.0 44974.0 45506.1 45511.3 45528.8 45544.0 1042.8 1128.4 1135.3 1154.4 1197.7 25231.6 27699.4 27785.3 27975.0 28538.4 38763.0 39241.6 39243.2 39266.4 39276.8 296523.0 296627.0 296682.0 297214.0 297432.0 23946.6 24058.8 24641.4 24877.9 25776.9 39466.8 39761.2 39809.8 39875.6 40632.4 33469.3 33925.8 33993.2 34026.3 34288.3 33018.0 33229.5 33493.5 33908.7 34213.7 33746.7 34122.5 34281.7 34545.3 34654.0 28336.0 30110.0 30893.1 31104.8 31116.1 24199.8 24525.4 24586.1 24616.4 24885.4 16:34:26 INFO - PROCESS | 15473 | 16:34:26 INFO - PROCESS | 15473 | [#2] jquery.html Cycles:180 Average:23342.55 Median:4201.40 stddev:62211.12 (1480.7%) stddev-sans-first:62361.67 16:34:26 INFO - PROCESS | 15473 | Values: 341.8 347.8 354.2 361.0 385.8 12482.6 15844.8 16082.8 17010.9 18124.5 15853.3 16093.4 16288.1 16528.9 16566.8 4906.5 5125.5 5218.1 5436.0 5504.1 3393.1 3560.6 5437.0 5470.1 5476.1 3548.2 3558.1 4416.4 6068.4 6082.9 6137.0 6146.7 6146.9 6148.9 6152.0 5261.0 5398.1 5422.0 5429.8 5434.8 11117.4 11311.5 12164.7 12277.5 12320.6 1786.2 1808.4 1862.1 1964.7 2345.3 8491.6 9012.2 9168.0 9225.7 9467.5 1664.6 1745.9 1757.6 1770.9 1789.9 64696.0 64813.0 68142.7 69058.0 69228.0 9778.0 9823.5 9934.4 10146.8 10208.3 13562.1 13655.0 13660.5 13666.9 13668.2 6608.4 6646.5 6915.4 6957.0 7023.5 6231.7 6267.9 6410.2 6413.8 6422.3 4089.8 4141.4 4261.4 4290.4 4374.0 1705.4 1729.4 1734.5 1758.4 1791.8 808.7 814.6 817.5 818.3 936.0 15112.5 16132.3 16634.4 16719.7 17313.6 820.3 821.6 835.6 843.7 856.8 8687.9 9353.1 9489.2 9578.3 9702.1 367348.0 370332.0 380603.0 383353.0 383449.7 45646.8 74356.0 74993.6 75308.0 75932.2 844.2 859.6 861.2 862.7 866.3 14589.6 14757.0 14886.5 14902.8 15506.4 36105.8 36785.3 36974.6 37165.0 37635.4 49325.8 49908.8 50264.7 52177.0 52261.0 7373.4 7467.3 7616.7 7632.4 7698.0 9472.4 11011.3 12545.5 12545.5 12635.7 10557.7 10563.5 10604.0 10681.5 11111.2 10572.5 10802.9 10866.3 10898.9 11075.8 10461.8 11233.2 11321.5 11374.0 11429.4 7715.2 7729.1 7855.2 7944.9 7960.5 8471.4 10166.0 10485.1 10543.8 10865.9 16:34:26 INFO - PROCESS | 15473 | 16:34:26 INFO - PROCESS | 15473 | [#3] mootools.html Cycles:180 Average:5120.19 Median:4697.50 stddev:2946.97 (62.7%) stddev-sans-first:2940.79 16:34:26 INFO - PROCESS | 15473 | Values: 1236.6 1330.7 1367.1 1407.4 1663.0 6562.8 7634.0 7857.2 7868.0 8114.5 7980.8 8033.6 8040.0 8051.4 8055.1 2173.5 2201.2 2203.1 2203.5 2204.0 2132.3 2172.1 2189.5 2197.9 2198.2 2142.8 2160.2 2180.9 2193.5 2193.6 2102.9 2107.7 2110.9 2115.7 2118.5 2097.6 2141.3 2148.3 2152.8 2153.2 7703.4 7850.2 7944.8 7951.2 7979.5 4156.4 4310.9 4315.3 4316.3 4328.0 2748.9 2839.4 2886.0 2887.9 2888.4 893.1 898.6 917.2 924.3 935.8 10696.0 10725.5 10736.8 10752.9 10773.1 3741.6 3950.4 3951.2 4061.6 4062.8 8479.9 8496.2 8507.0 8516.7 8518.1 4398.0 4403.0 4403.2 4404.2 4415.1 3659.4 3956.4 4082.0 4102.8 4111.1 3367.3 3382.0 3537.6 3546.1 3556.1 1036.3 1040.2 1040.4 1066.9 1068.1 684.6 787.4 799.7 804.9 815.3 5015.8 5058.9 5142.6 5172.6 5435.1 852.6 868.9 879.3 911.1 921.8 3171.3 3366.2 3416.4 3469.8 3497.8 9573.1 9926.7 10074.3 10089.4 10091.8 9677.9 10037.6 10045.5 10057.8 10059.5 7151.1 7469.3 7474.6 7478.5 7479.7 5008.1 5039.8 5039.8 5056.9 5288.7 9006.8 9121.6 9123.5 9133.5 9184.6 8845.2 9005.2 9006.3 9072.0 9124.9 6757.8 7189.7 7275.8 7673.5 7677.2 7331.6 7511.4 7575.7 7580.5 7587.9 6712.3 6833.9 6904.4 6928.3 7073.4 6763.2 6771.4 6840.4 6846.8 6893.9 5930.7 6085.5 6125.0 6132.2 6145.5 4317.0 4420.3 4434.6 4527.4 4536.1 4496.6 4515.7 4608.7 4616.1 4778.9 16:34:26 INFO - PROCESS | 15473 | 16:34:26 INFO - PROCESS | 15473 | [#4] prototype.html Cycles:180 Average:4020.53 Median:4410.37 stddev:1140.02 (25.8%) stddev-sans-first:1108.21 16:34:26 INFO - PROCESS | 15473 | Values: 285.3 296.9 302.6 303.2 304.6 4501.6 4695.3 4703.4 4719.6 4722.6 4558.0 4653.7 4690.3 4695.5 4704.9 3745.4 3752.8 3773.6 3776.6 3782.2 3553.7 3601.6 3737.6 3742.5 3746.0 3695.9 3721.7 3730.8 3745.5 3749.9 3730.3 3733.7 3734.2 3735.8 3740.0 3323.9 3331.3 3336.6 3340.5 3343.3 4447.9 4585.9 4596.7 4612.3 4647.9 4275.6 4285.0 4285.4 4297.5 4311.2 4910.7 4935.4 4940.8 4957.8 4961.1 3422.2 3619.1 3628.0 3631.7 3647.9 5139.9 5141.8 5155.2 5156.4 5164.5 3717.2 3722.0 3724.3 3726.7 3731.6 3786.3 3794.0 3804.0 3806.1 3811.4 4174.3 4231.5 4259.8 4294.0 4299.5 4886.4 4900.0 4946.3 4955.2 4963.9 1238.5 1270.1 1297.2 1301.9 1315.6 893.2 908.4 926.9 931.1 942.8 4413.3 4417.7 4420.1 4428.4 4443.4 5095.2 5099.1 5107.2 5110.3 5116.2 4346.9 4377.3 4384.6 4407.4 4418.4 2273.5 2388.3 2393.7 2400.3 2441.6 4263.9 4282.7 4293.7 4309.6 4325.7 4555.6 4565.2 4613.9 4635.5 4656.5 4101.7 4287.3 4299.8 4317.6 4343.2 4546.1 4555.0 4564.9 4568.2 4571.6 4392.6 4394.9 4431.6 4445.6 4477.9 4086.6 4240.7 4245.2 4255.5 4291.9 3384.4 3395.5 3398.2 3426.8 3433.2 4523.5 4542.8 4548.0 4554.5 4943.2 4781.8 4904.8 4942.8 4955.5 4996.9 4949.8 4964.9 4986.9 4996.1 5008.3 4760.2 4927.5 4939.2 4956.3 4958.7 5020.4 5026.2 5031.7 5036.5 5038.1 4842.2 4847.2 4851.6 4878.9 4879.5 16:34:26 INFO - PROCESS | 15473 | 16:34:26 INFO - PROCESS | 15473 | [#5] yui.html Cycles:180 Average:17410.51 Median:579.62 stddev:25690.34 (4432.2%) stddev-sans-first:25731.36 16:34:26 INFO - PROCESS | 15473 | Values: 591.0 600.6 656.2 703.6 726.3 11328.4 11892.8 12212.8 12228.3 12233.7 12518.0 12543.1 12600.5 12604.2 12604.6 601.0 738.7 757.6 759.3 759.8 728.1 750.8 753.3 754.6 756.1 786.5 786.9 788.6 788.7 789.2 628.0 799.3 799.9 800.5 801.6 564.1 579.6 579.7 580.8 581.5 12071.6 12084.5 12127.8 12145.7 12183.7 10496.7 11024.4 11038.9 11149.4 11158.4 11123.3 11125.1 11303.4 11381.7 11496.5 3989.3 3999.6 4000.6 4110.0 4130.0 64399.0 64432.4 64596.4 64646.4 64686.7 9833.0 9856.6 9866.7 9937.3 9999.6 19400.2 19424.2 19442.0 19480.5 19498.4 6449.8 6541.1 6591.6 6626.9 6742.2 3691.8 3695.2 3763.6 3778.7 3780.5 2713.9 2788.9 2822.5 2828.4 2828.9 462.1 470.3 473.2 490.3 499.1 401.3 408.0 433.8 440.6 472.5 6879.9 7230.0 7232.7 7278.4 7535.3 510.3 510.5 514.1 519.5 532.5 3290.9 3349.2 3463.3 3511.4 3588.2 94733.1 100528.0 101386.0 101685.0 102289.0 50756.5 58682.0 82753.8 88516.0 92152.5 70791.0 79147.0 79231.0 80558.0 80587.6 6612.4 6711.9 6733.0 6785.2 7125.5 45054.5 45192.2 46900.3 47342.5 47661.5 59605.0 60875.0 64785.7 68445.7 68463.7 8784.0 8957.9 9154.7 9395.9 9628.1 9238.8 9340.0 9772.9 9900.2 10498.6 9353.2 9532.0 9558.4 9631.8 10119.6 9755.9 9768.1 9834.9 10181.9 10424.0 10599.4 11038.7 11256.6 11785.4 11918.8 9893.9 10128.7 10285.8 10413.7 10612.3 9545.8 9757.0 9886.3 9987.5 10020.9 16:34:26 INFO - PROCESS | 15473 | -------- Summary: end -------- 16:34:26 INFO - PROCESS | 15473 | 16:34:27 INFO - TEST-INFO | 15473: exit 0 16:34:27 INFO - TEST-OK | dromaeo_css | took 1121411ms 16:34:27 INFO - TEST-START | kraken 16:34:27 INFO - operating with platform_type : linux_ 16:34:27 INFO - Initialising browser for kraken test... 16:34:27 INFO - TEST-INFO | started process 15782 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW5CzkL/profile http://localhost:39996/getInfo.html) 16:34:32 INFO - PROCESS | 15782 | __metrics Screen width/height:1600/1200 16:34:32 INFO - PROCESS | 15782 | colorDepth:24 16:34:32 INFO - PROCESS | 15782 | Browser inner width/height: 1024/697 16:34:32 INFO - PROCESS | 15782 | __metrics 16:34:32 INFO - PROCESS | 15782 | 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" 16:34:32 INFO - PROCESS | 15782 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:34:33 INFO - PROCESS | 15782 | console.error: PushService: 16:34:33 INFO - PROCESS | 15782 | stateChangeProcessEnqueue: Error transitioning state 16:34:33 INFO - PROCESS | 15782 | UnknownError 16:34:33 INFO - TEST-INFO | 15782: exit 0 16:34:33 INFO - Browser initialized. 16:34:33 INFO - Running cycle 1/1 for kraken test... 16:34:33 INFO - TEST-INFO | started process 15862 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW5CzkL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:34:46 INFO - PROCESS | 15862 | RSS: Main: 192180224 16:34:46 INFO - PROCESS | 15862 | 16:34:48 INFO - PROCESS | 15862 | Cycle 1(1): loaded http://localhost:39996/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39996/tests/kraken/driver.html?testName=audio-beat-detection) 16:34:49 INFO - PROCESS | 15862 | RSS: Main: 236617728 16:34:49 INFO - PROCESS | 15862 | 16:34:52 INFO - PROCESS | 15862 | Cycle 1(1): loaded http://localhost:39996/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39996/tests/kraken/driver.html?testName=audio-dft) 16:34:53 INFO - PROCESS | 15862 | RSS: Main: 227258368 16:34:53 INFO - PROCESS | 15862 | 16:34:56 INFO - PROCESS | 15862 | Cycle 1(1): loaded http://localhost:39996/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39996/tests/kraken/driver.html?testName=audio-fft) 16:34:57 INFO - PROCESS | 15862 | RSS: Main: 304697344 16:34:57 INFO - PROCESS | 15862 | 16:34:59 INFO - PROCESS | 15862 | Cycle 1(1): loaded http://localhost:39996/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39996/tests/kraken/driver.html?testName=audio-oscillator) 16:34:59 INFO - PROCESS | 15862 | RSS: Main: 215203840 16:34:59 INFO - PROCESS | 15862 | 16:35:01 INFO - PROCESS | 15862 | Cycle 1(1): loaded http://localhost:39996/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39996/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:35:02 INFO - PROCESS | 15862 | RSS: Main: 216903680 16:35:02 INFO - PROCESS | 15862 | 16:35:05 INFO - PROCESS | 15862 | Cycle 1(1): loaded http://localhost:39996/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39996/tests/kraken/driver.html?testName=imaging-darkroom) 16:35:06 INFO - PROCESS | 15862 | RSS: Main: 254795776 16:35:06 INFO - PROCESS | 15862 | 16:35:09 INFO - PROCESS | 15862 | Cycle 1(1): loaded http://localhost:39996/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39996/tests/kraken/driver.html?testName=imaging-desaturate) 16:35:10 INFO - PROCESS | 15862 | RSS: Main: 249270272 16:35:10 INFO - PROCESS | 15862 | 16:35:13 INFO - PROCESS | 15862 | Cycle 1(1): loaded http://localhost:39996/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39996/tests/kraken/driver.html?testName=json-parse-financial) 16:35:13 INFO - PROCESS | 15862 | RSS: Main: 252551168 16:35:13 INFO - PROCESS | 15862 | 16:35:15 INFO - PROCESS | 15862 | Cycle 1(1): loaded http://localhost:39996/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39996/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:35:16 INFO - PROCESS | 15862 | RSS: Main: 215941120 16:35:16 INFO - PROCESS | 15862 | 16:35:18 INFO - PROCESS | 15862 | Cycle 1(1): loaded http://localhost:39996/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39996/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:35:18 INFO - PROCESS | 15862 | RSS: Main: 223272960 16:35:18 INFO - PROCESS | 15862 | 16:35:21 INFO - PROCESS | 15862 | Cycle 1(1): loaded http://localhost:39996/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39996/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:35:21 INFO - PROCESS | 15862 | RSS: Main: 224362496 16:35:21 INFO - PROCESS | 15862 | 16:35:24 INFO - PROCESS | 15862 | Cycle 1(1): loaded http://localhost:39996/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39996/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:35:25 INFO - PROCESS | 15862 | RSS: Main: 225415168 16:35:25 INFO - PROCESS | 15862 | 16:35:28 INFO - PROCESS | 15862 | Cycle 1(1): loaded http://localhost:39996/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39996/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:35:28 INFO - PROCESS | 15862 | RSS: Main: 222720000 16:35:28 INFO - PROCESS | 15862 | 16:35:30 INFO - PROCESS | 15862 | Cycle 1(1): loaded http://localhost:39996/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39996/tests/kraken/driver.html?testName=ai-astar) 16:35:30 INFO - PROCESS | 15862 | RSS: Main: 273666048 16:35:30 INFO - PROCESS | 15862 | 16:35:30 INFO - PROCESS | 15862 | __start_tp_report 16:35:30 INFO - PROCESS | 15862 | _x_x_mozilla_page_load 16:35:30 INFO - PROCESS | 15862 | _x_x_mozilla_page_load_details 16:35:30 INFO - PROCESS | 15862 | |i|pagename|runs| 16:35:30 INFO - PROCESS | 15862 | |0;ai-astar;113;114;116;114;115;116;114;106;115;116 16:35:30 INFO - PROCESS | 15862 | |1;audio-beat-detection;167;130;151;189;125;145;152;125;179;125 16:35:30 INFO - PROCESS | 15862 | |2;audio-dft;161;161;161;161;160;161;161;162;161;160 16:35:30 INFO - PROCESS | 15862 | |3;audio-fft;76;76;77;156;78;78;78;77;157;78 16:35:30 INFO - PROCESS | 15862 | |4;audio-oscillator;83;84;143;84;84;83;139;83;83;136 16:35:30 INFO - PROCESS | 15862 | |5;imaging-gaussian-blur;117;118;118;118;119;117;118;118;117;118 16:35:30 INFO - PROCESS | 15862 | |6;imaging-darkroom;147;148;147;148;147;148;147;147;148;147 16:35:30 INFO - PROCESS | 15862 | |7;imaging-desaturate;94;94;95;96;95;95;96;94;95;96 16:35:30 INFO - PROCESS | 15862 | |8;json-parse-financial;64;64;65;68;66;67;64;64;65;64 16:35:30 INFO - PROCESS | 15862 | |9;json-stringify-tinderbox;66;66;65;65;66;65;65;65;66;66 16:35:30 INFO - PROCESS | 15862 | |10;stanford-crypto-aes;83;90;83;85;88;82;83;82;81;87 16:35:30 INFO - PROCESS | 15862 | |11;stanford-crypto-ccm;142;147;128;133;134;148;143;134;169;135 16:35:30 INFO - PROCESS | 15862 | |12;stanford-crypto-pbkdf2;176;176;171;180;174;175;174;172;173;172 16:35:30 INFO - PROCESS | 15862 | |13;stanford-crypto-sha256-iterative;73;68;69;69;70;72;68;69;70;70 16:35:30 INFO - PROCESS | 15862 | __end_tp_report 16:35:30 INFO - PROCESS | 15862 | __start_cc_report 16:35:30 INFO - PROCESS | 15862 | _x_x_mozilla_cycle_collect,1971 16:35:30 INFO - PROCESS | 15862 | __end_cc_report 16:35:30 INFO - PROCESS | 15862 | __startTimestamp1472513730816__endTimestamp 16:35:30 INFO - PROCESS | 15862 | 16:35:30 INFO - PROCESS | 15862 | ------- Summary: start ------- 16:35:30 INFO - PROCESS | 15862 | Number of tests: 14 16:35:30 INFO - PROCESS | 15862 | 16:35:30 INFO - PROCESS | 15862 | [#0] ai-astar Cycles:10 Average:113.90 Median:115.00 stddev:2.96 (2.6%) stddev-sans-first:3.12 16:35:30 INFO - PROCESS | 15862 | Values: 113.0 114.0 116.0 114.0 115.0 116.0 114.0 106.0 115.0 116.0 16:35:30 INFO - PROCESS | 15862 | 16:35:30 INFO - PROCESS | 15862 | [#1] audio-beat-detection Cycles:10 Average:148.80 Median:151.50 stddev:23.43 (15.5%) stddev-sans-first:23.91 16:35:30 INFO - PROCESS | 15862 | Values: 167.0 130.0 151.0 189.0 125.0 145.0 152.0 125.0 179.0 125.0 16:35:30 INFO - PROCESS | 15862 | 16:35:30 INFO - PROCESS | 15862 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 16:35:30 INFO - PROCESS | 15862 | Values: 161.0 161.0 161.0 161.0 160.0 161.0 161.0 162.0 161.0 160.0 16:35:30 INFO - PROCESS | 15862 | 16:35:30 INFO - PROCESS | 15862 | [#3] audio-fft Cycles:10 Average:93.10 Median:77.50 stddev:33.42 (43.1%) stddev-sans-first:34.87 16:35:30 INFO - PROCESS | 15862 | Values: 76.0 76.0 77.0 156.0 78.0 78.0 78.0 77.0 157.0 78.0 16:35:30 INFO - PROCESS | 15862 | 16:35:30 INFO - PROCESS | 15862 | [#4] audio-oscillator Cycles:10 Average:100.20 Median:83.00 stddev:27.06 (32.6%) stddev-sans-first:27.98 16:35:30 INFO - PROCESS | 15862 | Values: 83.0 84.0 143.0 84.0 84.0 83.0 139.0 83.0 83.0 136.0 16:35:30 INFO - PROCESS | 15862 | 16:35:30 INFO - PROCESS | 15862 | [#5] imaging-gaussian-blur Cycles:10 Average:117.80 Median:118.00 stddev:0.63 (0.5%) stddev-sans-first:0.60 16:35:30 INFO - PROCESS | 15862 | Values: 117.0 118.0 118.0 118.0 119.0 117.0 118.0 118.0 117.0 118.0 16:35:30 INFO - PROCESS | 15862 | 16:35:30 INFO - PROCESS | 15862 | [#6] imaging-darkroom Cycles:10 Average:147.40 Median:147.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 16:35:30 INFO - PROCESS | 15862 | Values: 147.0 148.0 147.0 148.0 147.0 148.0 147.0 147.0 148.0 147.0 16:35:30 INFO - PROCESS | 15862 | 16:35:30 INFO - PROCESS | 15862 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.82 (0.9%) stddev-sans-first:0.78 16:35:30 INFO - PROCESS | 15862 | Values: 94.0 94.0 95.0 96.0 95.0 95.0 96.0 94.0 95.0 96.0 16:35:30 INFO - PROCESS | 15862 | 16:35:30 INFO - PROCESS | 15862 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:65.00 stddev:1.45 (2.2%) stddev-sans-first:1.48 16:35:30 INFO - PROCESS | 15862 | Values: 64.0 64.0 65.0 68.0 66.0 67.0 64.0 64.0 65.0 64.0 16:35:30 INFO - PROCESS | 15862 | 16:35:30 INFO - PROCESS | 15862 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:66.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 16:35:30 INFO - PROCESS | 15862 | Values: 66.0 66.0 65.0 65.0 66.0 65.0 65.0 65.0 66.0 66.0 16:35:30 INFO - PROCESS | 15862 | 16:35:30 INFO - PROCESS | 15862 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:84.00 stddev:2.99 (3.6%) stddev-sans-first:3.13 16:35:30 INFO - PROCESS | 15862 | Values: 83.0 90.0 83.0 85.0 88.0 82.0 83.0 82.0 81.0 87.0 16:35:30 INFO - PROCESS | 15862 | 16:35:30 INFO - PROCESS | 15862 | [#11] stanford-crypto-ccm Cycles:10 Average:141.30 Median:142.50 stddev:11.74 (8.2%) stddev-sans-first:12.45 16:35:30 INFO - PROCESS | 15862 | Values: 142.0 147.0 128.0 133.0 134.0 148.0 143.0 134.0 169.0 135.0 16:35:30 INFO - PROCESS | 15862 | 16:35:30 INFO - PROCESS | 15862 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.30 Median:174.50 stddev:2.63 (1.5%) stddev-sans-first:2.71 16:35:30 INFO - PROCESS | 15862 | Values: 176.0 176.0 171.0 180.0 174.0 175.0 174.0 172.0 173.0 172.0 16:35:30 INFO - PROCESS | 15862 | 16:35:30 INFO - PROCESS | 15862 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:70.00 stddev:1.62 (2.3%) stddev-sans-first:1.24 16:35:30 INFO - PROCESS | 15862 | Values: 73.0 68.0 69.0 69.0 70.0 72.0 68.0 69.0 70.0 70.0 16:35:30 INFO - PROCESS | 15862 | -------- Summary: end -------- 16:35:30 INFO - PROCESS | 15862 | 16:35:32 INFO - TEST-INFO | 15862: exit 0 16:35:32 INFO - TEST-OK | kraken | took 64449ms 16:35:32 INFO - SUITE-END | took 1185s 16:35:32 INFO - Completed test suite (00:19:46) 16:35:32 INFO - javascript benchmark 16:35:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2615.6468876701647, 2638.670166229221, 2646.6427032350625, 2693.9057133000338, 2705.012670375312, 4525.592696657301, 4630.62954963603, 4691.788869500872, 4783.952160478394, 4840.951590484098, 4646.837360692376, 4658.557437043475, 4660.533946605337, 4678.695884767496, 4731.337612734212, 4248.553901840309, 4259.701820872525, 4330.653547716191, 4398.582134697199, 4510.97744511277, 4442.733435993835, 4482.305242687388, 4505.459344878619, 4546.772661366935, 4548.317752337143, 4266.1467706458525, 4302.655787537021, 4321.092570560186, 4359.258925982591, 4363.0, 4334.068175342285, 4342.522322544518, 4343.478782546114, 4348.347747837818, 4354.325079612665, 4248.235317642823, 4261.637760790337, 4266.06146647737, 4271.61555460007, 4315.654747620214, 4646.0, 4710.764461776932, 4711.316859055452, 4721.905561888743, 4764.7617619119255, 4909.631777616657, 4953.257011448276, 5013.598912087061, 5020.623453240985, 5031.094403007457, 5014.047331007134, 5033.018561380532, 5034.874128146789, 5040.14317566011, 5048.217526283432, 4646.395968524104, 4720.811167553328, 4729.243321068612, 4745.050989802054, 4786.521347865186, 5069.163588008011, 5159.638825282269, 5176.145935920531, 5194.376674798973, 5264.6051546134195, 4990.525900039491, 4992.500749925051, 5026.472220416877, 5057.241413787961, 5057.418396884332, 5066.632009357527, 5068.873278168076, 5075.65103777064, 5076.923846142311, 5136.203888397305, 4853.830115945925, 4894.926576101361, 5016.749162541932, 5017.949820501748, 5022.397312322545, 4871.244957031666, 4899.1426500362295, 4907.9263811042865, 4956.479569645207, 4967.081089998362, 4290.013296941721, 4291.420658211102, 4291.69958102936, 4343.19650864591, 4343.283358245917, 3749.9437508437395, 3769.7738135711943, 3780.3762379207674, 3783.697304215656, 3819.255245227154, 4365.0, 4367.978160109243, 4371.759553224603, 4378.78106094694, 4440.533743956903, 5037.11850426174, 5053.444121146671, 5072.087024335655, 5075.8223462179385, 5145.0, 4296.89257768552, 4389.813878664991, 4391.14372697321, 4408.933865992013, 4448.110377924363, 4527.683062185615, 4581.65637577183, 4587.977060114678, 4602.378678878308, 4667.299905014275, 4694.647901407408, 4697.835575754969, 4731.976340118277, 4737.360456338351, 4793.0413917216, 4936.308916751729, 5009.098362294676, 5054.974725126351, 5058.011077093758, 5121.9743901281745, 4720.976395118002, 4726.645501587326, 4749.2876068589985, 4762.380890484214, 4826.0830442215865, 4978.726170060681, 4984.551390374883, 4987.526185012418, 4995.925061124159, 5052.444231134646, 4923.212286034217, 5065.113605119163, 5080.187170052774, 5085.618578606545, 5151.562117219928, 4931.06309801151, 4980.501949805135, 4981.3026176334615, 4983.5763960063805, 5035.546800787946, 4972.950270497248, 4980.0, 4981.726005069756, 4985.152524070989, 5040.798368065236, 4799.832005879777, 4887.0714564232685, 4899.510048995215, 4902.0, 4948.752562371939, 4959.256111583291, 5005.0740612985255, 5035.697858128524, 5040.4455509894615, 5060.443351231435, 4872.342233798392, 4878.048780487771, 4885.560299573055, 4888.266759986031, 4951.702897826142], "value": 4646.673185704064, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.5210071439277, 564.7035306464106, 574.3481148896002, 579.0387955993051, 582.490320969152, 26725.797339119737, 36391.27217455649, 37357.813210933906, 37438.43842342367, 42044.94887627811, 37363.43954840673, 37691.0, 37720.43419348705, 37728.62271377286, 37731.62268377315, 7648.847023059536, 7793.103793063813, 7805.0, 7806.1413244543055, 7807.531548107103, 7240.094988126484, 9690.37012594179, 9920.355176913477, 9951.402915825074, 10281.074703276703, 10029.69910902674, 10119.544620492095, 10132.392056476598, 10134.189264858831, 10151.23865710071, 10000.899991000106, 10084.49577521121, 10084.899151008469, 10089.848652270186, 10094.343867648578, 9852.014798520162, 9940.403575785476, 9954.900450995543, 9955.900440995641, 9956.203503719686, 35190.64809351899, 35408.29183416343, 35443.11392215215, 35450.46824297638, 35541.82229088838, 2900.7824413168887, 2991.4316279906748, 2997.2057404787747, 3183.602049743782, 3545.4504551794257, 26870.5969410459, 27082.187534374, 27553.311167220778, 27683.86158069217, 27997.860010699816, 3152.6059242594674, 3216.6140063192565, 3309.652486488908, 3321.7508686848582, 3362.4788157835574, 236922.0, 238566.0, 239269.99999999825, 239715.0, 239771.0, 29667.25831854178, 48158.0, 48565.0, 48613.513864861605, 48708.0, 29748.55377169344, 31469.213269668442, 31481.8425907869, 31524.36951261008, 31526.369472610877, 12589.685257868443, 12737.808932866015, 12783.296918669563, 12847.614571562868, 12937.353132343533, 9273.0, 9489.90510094904, 10640.042396184226, 10861.83707244392, 10891.618793342195, 7155.105611798525, 7216.0, 7406.0, 7611.90672565049, 7644.770656880302, 2962.674105848355, 2984.985075074654, 3026.7881248312406, 3046.5887105240954, 3054.740347070524, 847.8304339132075, 851.6721062391024, 862.9180227878342, 875.6803766625136, 896.8296023755465, 25339.493210135694, 26081.217563473125, 26429.735702643113, 26850.5972410414, 26898.5965210522, 901.5627420700955, 925.6482536636036, 927.1794461901131, 935.9953200234091, 966.9564869580886, 13862.514811981533, 13900.582982510541, 14287.57137285883, 14735.410583576537, 14819.925900370428, 362963.0, 365215.0, 365847.0, 366884.0, 367088.0, 44974.0, 45506.08987820225, 45511.317330240076, 45528.772356138004, 45544.0, 1042.8487869258915, 1128.424503503203, 1135.250734515216, 1154.4054811772112, 1197.7305106351, 25231.62152567676, 27699.44601108007, 27785.30536736517, 27975.0, 28538.42923141484, 38763.0, 39241.60758392494, 39243.2151356977, 39266.411003835536, 39276.803615981735, 296523.0, 296627.0, 296682.0, 297214.0, 297432.0, 23946.64080038766, 24058.75941240635, 24641.383965401008, 24877.875610622556, 25776.871115644302, 39466.802665986484, 39761.24655692112, 39809.80095099506, 39875.60124398719, 40632.390514142906, 33469.33061338808, 33925.83037084799, 33993.150171245914, 34026.319473610885, 34288.31423371568, 33018.0, 33229.50155747617, 33493.54895061286, 33908.66091339153, 34213.657863421045, 33746.66253337435, 34122.48816267708, 34281.657183427844, 34545.30909381748, 34654.0, 28336.0, 30110.0, 30893.1464112154, 31104.84447577838, 31116.066518004496, 24199.758002420454, 24525.38686532851, 24586.139485117936, 24616.384590385383, 24885.377865553506], "value": 16118.918764289927, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.77784440113936, 347.7617831785227, 354.19597513644027, 360.96751292383664, 385.7569731069426, 12482.625521234366, 15844.841551584495, 16082.83917160828, 17010.914945425255, 18124.546886327847, 15853.286602102904, 16093.436729714462, 16288.10415427154, 16528.917355413207, 16566.83433165671, 4906.533879281472, 5125.487451254863, 5218.13900706383, 5435.972820135894, 5504.146857237135, 3393.1347506385837, 3560.608333083359, 5436.972815135919, 5470.124780035195, 5476.069068258385, 3548.1661809474754, 3558.1282585766526, 4416.447944006999, 6068.362821903703, 6082.878342433149, 6136.96931515344, 6146.66193359365, 6146.907796383058, 6148.940105064808, 6151.969240153815, 5260.9736951315, 5398.082326004589, 5422.0, 5429.782808687648, 5434.809781657623, 11117.388543630097, 11311.490982905789, 12164.72270411603, 12277.508899644003, 12320.630381088582, 1786.1872847854195, 1808.3941879470394, 1862.087577087231, 1964.6758284882976, 2345.2846881701075, 8491.575421228914, 9012.233960113399, 9167.954160229156, 9225.67710130149, 9467.479288639122, 1664.642101948087, 1745.8690598205058, 1757.578181236507, 1770.9468715938542, 1789.91945362459, 64696.0, 64813.0, 68142.65928670325, 69057.96413053758, 69228.0, 9777.951110244545, 9823.508824558743, 9934.403935763878, 10146.797064058677, 10208.285420020527, 13562.05065645415, 13655.0, 13660.521881734092, 13666.863331366758, 13668.17990920528, 6608.372204640552, 6646.501512386591, 6915.412189963909, 6956.965215173892, 7023.508354415142, 6231.719572619243, 6267.874642507216, 6410.166678331881, 6413.775517856946, 6422.325655806167, 4089.8159582818844, 4141.378793180987, 4261.424707664488, 4290.377895205178, 4373.956260437419, 1705.4372057221087, 1729.4175829116218, 1734.5230061732873, 1758.4373000639673, 1791.784985801712, 808.6563210635456, 814.6415577146037, 817.4686453804956, 818.3003531980071, 935.9812803743887, 15112.471063512725, 16132.274047667883, 16634.417795377103, 16719.6656066878, 17313.56716082108, 820.274057459141, 821.5793573225226, 835.5529791561486, 843.6667516331015, 856.8243510080418, 8687.913120868838, 9353.11145441182, 9489.1934185595, 9578.281628877861, 9702.078302561387, 367348.0, 370332.0, 380603.0, 383353.0, 383449.7447614864, 45646.771766140955, 74356.0, 74993.62503187449, 75308.0, 75932.24067759252, 844.2444012608681, 859.5530324231364, 861.1990848510819, 862.7282406042077, 866.2853146154448, 14589.562313130624, 14757.0, 14886.478973235884, 14902.776458352917, 15506.379744810081, 36105.81947090353, 36785.26429471449, 36974.63025369712, 37165.0, 37635.43546846745, 49325.75337123292, 49908.750456247486, 50264.74867625785, 52177.0, 52261.0, 7373.410127189919, 7467.290607392289, 7616.69533218665, 7632.427567932316, 7698.0, 9472.384295020802, 11011.28426652265, 12545.498180072695, 12545.499628068435, 12635.684107897008, 10557.68326950162, 10563.52464139131, 10604.0, 10681.46592670379, 11111.222214444912, 10572.471376431302, 10802.945985270024, 10866.348019118877, 10898.891011090105, 11075.778484430428, 10461.790764184825, 11233.157513186381, 11321.490532926204, 11374.0, 11429.371384573928, 7715.228477152465, 7729.111152217342, 7855.175206603224, 7944.880826787718, 7960.4825686330205, 8471.364647651573, 10166.0, 10485.108765754996, 10543.789124217627, 10865.945670271598], "value": 7927.47835440704, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1236.6351926181776, 1330.720548684776, 1367.0840536840317, 1407.3526177958138, 1663.0437498438394, 6562.835929101775, 7633.961830190849, 7857.214278572147, 7868.0, 8114.472559283642, 7980.840383192332, 8033.598320083987, 8039.995000624922, 8051.3558915286785, 8055.113937466874, 2173.4783651923544, 2201.152556265833, 2203.0526873444414, 2203.526241858, 2204.0, 2132.264368792764, 2172.142003908455, 2189.5292512109895, 2197.8791166485835, 2198.2196320306302, 2142.839287053477, 2160.2331172433796, 2180.923667671632, 2193.5174261662487, 2193.5503221839563, 2102.9474263143393, 2107.7049213110176, 2110.947226319339, 2115.7355330583678, 2118.491562025118, 2097.6014557234075, 2141.3468891987936, 2148.269588339972, 2152.7847215278503, 2153.2033147735283, 7703.422243331773, 7850.175731548163, 7944.761657150295, 7951.244631759973, 7979.521228726237, 4156.397322388267, 4310.870673879788, 4315.331123675835, 4316.266234740102, 4327.956720432819, 2748.876300566479, 2839.403725217707, 2886.0, 2887.8556072196307, 2888.436754832808, 893.1113542025661, 898.6270697660528, 917.1699611851285, 924.3021518753366, 935.8175155844613, 10696.037356637862, 10725.463726813707, 10736.838947415796, 10752.892471075189, 10773.084287835465, 3741.6258374162367, 3950.3876899080688, 3951.2295102454746, 4061.5532291447917, 4062.756234625932, 8479.915200848036, 8496.235338819411, 8507.0, 8516.659333626585, 8518.148185181431, 4397.978010109929, 4403.031333106712, 4403.207422663952, 4404.185225733249, 4415.050764085737, 3659.396199627032, 3956.4263181838473, 4082.040720430696, 4102.794860256975, 4111.1366613011605, 3367.2760356523463, 3382.0, 3537.646235376442, 3546.131197856542, 3556.05764472415, 1036.3419228789774, 1040.2354269611828, 1040.3549799124592, 1066.8826429092794, 1068.1134658233648, 684.6029303004345, 787.3779714025856, 799.6921185343684, 804.8631732605471, 815.2825513548158, 5015.799368025311, 5058.873528161826, 5142.61430392722, 5172.612054095883, 5435.076037073707, 852.6376290076817, 868.8566386546275, 879.2877769007125, 911.0889110889111, 921.8016578448124, 3171.254755132542, 3366.1584603849037, 3416.367971925202, 3469.8091604961464, 3497.8251087445524, 9573.090556397132, 9926.65256716026, 10074.294799364121, 10089.394636321844, 10091.798164036678, 9677.854832177523, 10037.598496060076, 10045.547950342252, 10057.849132263022, 10059.497025148714, 7151.106111736033, 7469.290417410337, 7474.551526908403, 7478.5138965967035, 7479.700811967569, 5008.098542262282, 5039.773210205476, 5039.778537047718, 5056.873578160428, 5288.68267903927, 9006.774830629287, 9121.589528471368, 9123.452592844451, 9133.45199288045, 9184.58669359867, 8845.24815390699, 9005.23455506263, 9006.279497640042, 9072.0, 9124.855658920016, 6757.797266082025, 7189.676464559003, 7275.781726548212, 7673.462857599914, 7677.193894640982, 7331.560106393634, 7511.399088072832, 7575.659095340833, 7580.4693671444675, 7587.886181707168, 6712.2952090031795, 6833.931660683528, 6904.447644188353, 6928.272531384132, 7073.3987611053635, 6763.188417389944, 6771.3905748482875, 6840.41856442208, 6846.760363387258, 6893.862122757617, 5930.733117009831, 6085.482733967662, 6125.019996800491, 6132.202813634199, 6145.477634400947, 4316.978415107905, 4420.270655341833, 4434.600885920283, 4527.411436513232, 4536.138133754576, 4496.640268778555, 4515.661325400673, 4608.700434471749, 4616.1229366420275, 4778.880527986828], "value": 4081.9888559769106, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.31524341580206, 296.8753123688051, 302.5899905627874, 303.21618615877964, 304.5766384725231, 4501.594856462918, 4695.2957056441555, 4703.365045718827, 4719.6460265480055, 4722.551357621025, 4557.977210113953, 4653.69750966187, 4690.319903613974, 4695.506971767966, 4704.858854234379, 3745.382011968022, 3752.836620647608, 3773.622637736218, 3776.603456637048, 3782.2246439479977, 3553.662402071799, 3601.603823579404, 3737.6075512071247, 3742.5321834770652, 3745.962540374602, 3695.907602309937, 3721.7022638189014, 3730.794806285653, 3745.45690874823, 3749.9062523436996, 3730.291244663505, 3733.7386382953205, 3734.2158146789207, 3735.794531300777, 3739.9626003739886, 3323.93352132956, 3331.267121233325, 3336.5662463879785, 3340.5490258814993, 3343.331333733263, 4447.911041779179, 4585.862424127282, 4596.655250856199, 4612.285095810154, 4647.930281045787, 4275.615194632498, 4284.957150428519, 4285.35719642053, 4297.527272000078, 4311.245532031882, 4910.656254062217, 4935.35840340757, 4940.827071052496, 4957.82647607332, 4961.131801934647, 3422.19578399076, 3619.1314084619767, 3627.963720362789, 3631.6731494165388, 3647.908802279964, 5139.897202055975, 5141.768620412053, 5155.226715992631, 5156.381234251876, 5164.4952198183855, 3717.182219911615, 3721.9627803721614, 3724.255148970217, 3726.70186385094, 3731.626837316301, 3786.3373909565717, 3794.0135564753364, 3803.972927309611, 3806.1436176859984, 3811.3901775715754, 4174.3112386456505, 4231.513375961742, 4259.765712885821, 4293.957060429419, 4299.505556860939, 4886.389201349832, 4899.975500122477, 4946.307516947631, 4955.182394904872, 4963.875903102379, 1238.5107882386587, 1270.1490001698885, 1297.1568480487758, 1301.9349032548334, 1315.6381994951464, 893.2228960804142, 908.418612088264, 926.9201380391768, 931.0828833598899, 942.7878727286443, 4413.337999300066, 4417.690761646718, 4420.138073075719, 4428.4243048403505, 4443.355713421536, 5095.235714642759, 5099.14447909172, 5107.157319042316, 5110.28456016158, 5116.2069879168785, 4346.9347959780625, 4377.277749171415, 4384.56154384559, 4407.4050003249795, 4418.447694038246, 2273.5111950930627, 2388.2654360756837, 2393.700787401575, 2400.291913885408, 2441.5605191065424, 4263.893402664896, 4282.721623094551, 4293.742375457483, 4309.556298639991, 4325.718828276152, 4555.567221113989, 4565.223911934979, 4613.907721845544, 4635.490096089427, 4656.509916826651, 4101.692373072032, 4287.335463003239, 4299.78501074945, 4317.633001194934, 4343.239933011773, 4546.136234115507, 4555.0, 4564.908701826012, 4568.223402021597, 4571.634269258385, 4392.6485881130075, 4394.857337092443, 4431.578999994996, 4445.599896009375, 4477.910441791211, 4086.59134086589, 4240.724352917051, 4245.150969806113, 4255.5106162790435, 4291.914161716811, 3384.390809654286, 3395.541601883714, 3398.184435735356, 3426.794392336468, 3433.1588760753775, 4523.5250298718165, 4542.750148741851, 4547.953970586717, 4554.544545545419, 4943.184374578154, 4781.760911954458, 4904.798324410543, 4942.827001054946, 4955.5044495550155, 4996.850094497171, 4949.82675606352, 4964.851054468228, 4986.905499690066, 4996.100701873553, 5008.348914641073, 4760.190767569452, 4927.507249275044, 4939.185034469272, 4956.256561515802, 4958.7024778513405, 5020.422651395136, 5026.195808670595, 5031.723255220852, 5036.521530454601, 5038.1435156022835, 4842.15262329098, 4847.248676455155, 4851.611871050237, 4878.926816097833, 4879.487653796442], "value": 3667.4704439160323, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.9674869058207, 600.5615900392713, 656.2485953583144, 703.5848849178983, 726.3390314813519, 11328.376939268348, 11892.7621447571, 12212.755744885097, 12228.266304021754, 12233.694157646063, 12518.0, 12543.059270554722, 12600.495980160806, 12604.243745375285, 12604.558840440586, 600.9669468179248, 738.6639079218955, 757.6476938223725, 759.2521366454066, 759.8366351234483, 728.1407938632411, 750.7972847331217, 753.2956685499076, 754.6000619671582, 756.1417790807445, 786.48092259109, 786.9409794265424, 788.5978151142901, 788.7121200761709, 789.2344425906862, 628.0, 799.2686691677109, 799.940004499659, 800.5116878703986, 801.5551368990238, 564.0523919814709, 579.5711173731451, 579.6782785554016, 580.8170426315739, 581.4505292498591, 12071.57749478764, 12084.456199471046, 12127.818082728767, 12145.696357591043, 12183.695407614792, 10496.737581560523, 11024.44877756109, 11038.889611103867, 11149.442527873574, 11158.386288754115, 11123.27698699582, 11125.109991200685, 11303.378314192716, 11381.658550243506, 11496.482658280398, 3989.3218152914073, 3999.5800440953863, 4000.5599384067727, 4109.97945010276, 4129.97935010326, 64399.0, 64432.355676443105, 64596.3540364595, 64646.35353646451, 64686.676566616865, 9832.950835245778, 9856.605735770489, 9866.703998880044, 9937.304388692866, 9999.550020249106, 19400.223991040482, 19424.22303107888, 19442.0, 19480.51298717515, 19498.415047548595, 6449.774257900951, 6541.0515475255825, 6591.604503729695, 6626.9337306627285, 6742.157230346207, 3691.8338674759702, 3695.2424752925585, 3763.6074652378247, 3778.6599206071587, 3780.527434070741, 2713.8778754956074, 2788.8745006474755, 2822.4778415993105, 2828.420173864353, 2828.872700728431, 462.0620141113545, 470.32273526122276, 473.22863732117116, 490.2670507591174, 499.1389852504401, 401.26167851154014, 407.997960010204, 433.82213292549903, 440.61666350275465, 472.5180316077582, 6879.931200687929, 7229.963850180716, 7232.674529646073, 7278.381337586258, 7535.321821036133, 510.29052673543333, 510.49206039990446, 514.0772313696923, 519.5142541723501, 532.475511621053, 3290.917727056795, 3349.2129349602824, 3463.3419650266874, 3511.3855075361707, 3588.1747198144053, 94733.05266947381, 100528.0, 101386.0, 101685.0, 102289.0, 50756.49243507592, 58682.0, 82753.75869361965, 88516.0, 92152.53923730338, 70791.0, 79147.0, 79231.0, 80558.0, 80587.59706201432, 6612.404883560503, 6711.9328806713265, 6732.966335168293, 6785.1518560179975, 7125.5368401053765, 45054.54945450504, 45192.187523432614, 46900.29649555191, 47342.52657473381, 47661.52338476571, 59605.0, 60875.0, 64785.67607161934, 68445.65777171082, 68463.65768171127, 8783.956080219814, 8957.865632015655, 9154.725358239264, 9395.906040939502, 9628.085331893459, 9238.769030774285, 9339.953300233456, 9772.853407198756, 9900.158486528439, 10498.632547860789, 9353.158215760614, 9532.0, 9558.426494410358, 9631.807363852824, 10119.645812396531, 9755.9024409755, 9768.120869121813, 9834.901650983398, 10181.949090254502, 10424.0, 10599.417032063311, 11038.724031899266, 11256.606018789631, 11785.351805650429, 11918.761624767283, 9893.915242126184, 10128.746781330527, 10285.845712314172, 10413.687589372332, 10612.310199836986, 9545.761355965878, 9756.951215244162, 9886.30795844284, 9987.500624968578, 10020.949895250476], "value": 6183.337220151595, "unit": "score"}], "name": "dromaeo_css", "value": 6166.238960009614, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 116.0, 114.0, 115.0, 116.0, 114.0, 106.0, 115.0, 116.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 130.0, 151.0, 189.0, 125.0, 145.0, 152.0, 125.0, 179.0, 125.0], "value": 148.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 160.0, 161.0, 161.0, 162.0, 161.0, 160.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 156.0, 78.0, 78.0, 78.0, 77.0, 157.0, 78.0], "value": 93.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 143.0, 84.0, 84.0, 83.0, 139.0, 83.0, 83.0, 136.0], "value": 100.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 118.0, 119.0, 117.0, 118.0, 118.0, 117.0, 118.0], "value": 117.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 148.0, 147.0, 148.0, 147.0, 147.0, 148.0, 147.0], "value": 147.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 96.0, 95.0, 95.0, 96.0, 94.0, 95.0, 96.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 68.0, 66.0, 67.0, 64.0, 64.0, 65.0, 64.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 65.0, 66.0, 65.0, 65.0, 65.0, 66.0, 66.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 90.0, 83.0, 85.0, 88.0, 82.0, 83.0, 82.0, 81.0, 87.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 147.0, 128.0, 133.0, 134.0, 148.0, 143.0, 134.0, 169.0, 135.0], "value": 141.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 176.0, 171.0, 180.0, 174.0, 175.0, 174.0, 172.0, 173.0, 172.0], "value": 174.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 68.0, 69.0, 69.0, 70.0, 72.0, 68.0, 69.0, 70.0, 70.0], "value": 69.8, "unit": "score"}], "name": "kraken", "value": 1577.5, "alertThreshold": 2.0}]} 16:35:32 INFO - Return code: 0 16:35:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:35:32 INFO - # TBPL SUCCESS # 16:35:32 INFO - Running post-action listener: _resource_record_post_action 16:35:32 INFO - [mozharness: 2016-08-29 23:35:32.609665Z] Finished run-tests step (success) 16:35:32 INFO - Running post-run listener: _resource_record_post_run 16:35:32 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 407994368; Read time: 16; Write time: 274936 16:35:32 INFO - TinderboxPrint: CPU usage
13.1% 16:35:32 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 16:35:32 INFO - TinderboxPrint: I/O write bytes / time
407,994,368 / 274,936 16:35:32 INFO - TinderboxPrint: CPU idle
8,342.3 (86.9%) 16:35:32 INFO - TinderboxPrint: CPU user
1,206.1 (12.6%) 16:35:32 INFO - TinderboxPrint: Swap in / out
0 / 0 16:35:32 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 113655808; Read time: 0; Write time: 69568 16:35:32 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 293036032; Read time: 0; Write time: 205228 16:35:33 INFO - Running post-run listener: _upload_blobber_files 16:35:33 INFO - Blob upload gear active. 16:35:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:35:33 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 16:35:33 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'] 16:35:33 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 16:35:33 INFO - (blobuploader) - INFO - Open directory for files ... 16:35:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:35:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:35:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:35:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:35:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:35:34 INFO - (blobuploader) - INFO - Done attempting. 16:35:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:35:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:35:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:35:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:35:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:35:35 INFO - (blobuploader) - INFO - Done attempting. 16:35:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:35:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:35:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:35:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:35:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:35:38 INFO - (blobuploader) - INFO - Done attempting. 16:35:38 INFO - (blobuploader) - INFO - Iteration through files over. 16:35:38 INFO - Return code: 0 16:35:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:35:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:35:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/d44271949649613c4be875a38873592edc2c3cf6775eb670cb7948aa11818782c32b2456af0938099ef622a98e9c3f2013894646291b3ff2291535122d60f874", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/c78058f88694c7ec2b17a35e76ac33e35acd6638cf46975ebfa2d64b2b38a7ad925b6cc91e585311525fe328ca0f7ee70f2ddb3c1deeb7c193dbbb8aa57ccd28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/1c1263a53ec024362951330ea4b31fd1e8eb123e8f02d7a91b710f0acd8453b241f1a6b95c2465702b73a4d5bdeb92ca0130d31ac289ead76795cd81e8674df3"} 16:35:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:35:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:35:38 INFO - Contents: 16:35:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/d44271949649613c4be875a38873592edc2c3cf6775eb670cb7948aa11818782c32b2456af0938099ef622a98e9c3f2013894646291b3ff2291535122d60f874", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/c78058f88694c7ec2b17a35e76ac33e35acd6638cf46975ebfa2d64b2b38a7ad925b6cc91e585311525fe328ca0f7ee70f2ddb3c1deeb7c193dbbb8aa57ccd28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/1c1263a53ec024362951330ea4b31fd1e8eb123e8f02d7a91b710f0acd8453b241f1a6b95c2465702b73a4d5bdeb92ca0130d31ac289ead76795cd81e8674df3"} 16:35:38 INFO - Running post-run listener: copy_logs_to_upload_dir 16:35:38 INFO - Copying logs to upload dir... 16:35:38 INFO - mkdir: /builds/slave/test/build/upload/logs 16:35:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.790637 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-08-29 16:35:38.668899) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 16:35:38.672431) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971 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/d44271949649613c4be875a38873592edc2c3cf6775eb670cb7948aa11818782c32b2456af0938099ef622a98e9c3f2013894646291b3ff2291535122d60f874", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/c78058f88694c7ec2b17a35e76ac33e35acd6638cf46975ebfa2d64b2b38a7ad925b6cc91e585311525fe328ca0f7ee70f2ddb3c1deeb7c193dbbb8aa57ccd28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/1c1263a53ec024362951330ea4b31fd1e8eb123e8f02d7a91b710f0acd8453b241f1a6b95c2465702b73a4d5bdeb92ca0130d31ac289ead76795cd81e8674df3"} build_url:https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011201 build_url: 'https://queue.taskcluster.net/v1/task/b1oLzUEXR9GfgN1WyRY1ig/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/d44271949649613c4be875a38873592edc2c3cf6775eb670cb7948aa11818782c32b2456af0938099ef622a98e9c3f2013894646291b3ff2291535122d60f874", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/c78058f88694c7ec2b17a35e76ac33e35acd6638cf46975ebfa2d64b2b38a7ad925b6cc91e585311525fe328ca0f7ee70f2ddb3c1deeb7c193dbbb8aa57ccd28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/1c1263a53ec024362951330ea4b31fd1e8eb123e8f02d7a91b710f0acd8453b241f1a6b95c2465702b73a4d5bdeb92ca0130d31ac289ead76795cd81e8674df3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 16:35:38.700013) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:35:38.700692) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1472512418.262387-573023971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004457 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:35:38.783042) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 16:35:38.783410) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 16:35:38.783726) ========= ========= Total master_lag: 0.26 =========