builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-029 starttime: 1471407633.28 results: success (0) buildid: 20160816195215 builduid: ca1ea87d9d7c4cd6bf1c919e3fcdf45e revision: b18d0dc89c1bcde24ba63b77f239f16fdf9a5620 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 21:20:33.284166) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 21:20:33.284666) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 21:20:33.285004) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 21:20:33.304244) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 21:20:33.304648) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-16 21:20:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2016-08-16 21:20:33 (46.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.076178 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 21:20:33.394820) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 21:20:33.395138) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015078 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 21:20:33.445305) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-16 21:20:33.445663) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev b18d0dc89c1bcde24ba63b77f239f16fdf9a5620 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev b18d0dc89c1bcde24ba63b77f239f16fdf9a5620 --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=9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-16 21:20:33,482 truncating revision to first 12 chars 2016-08-16 21:20:33,482 Setting DEBUG logging. 2016-08-16 21:20:33,483 attempt 1/10 2016-08-16 21:20:33,483 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/b18d0dc89c1b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-16 21:20:34,815 unpacking tar archive at: autoland-b18d0dc89c1b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.681492 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-16 21:20:35.141324) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 21:20:35.141681) ========= script_repo_revision: b18d0dc89c1bcde24ba63b77f239f16fdf9a5620 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 21:20:35.142069) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 21:20:35.142345) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 21:20:35.151120) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-08-16 21:20:35.151590) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-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', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-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=9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:20:35 INFO - MultiFileLogger online at 20160816 21:20:35 in /builds/slave/test 21:20:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 21:20:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:20:35 INFO - {'append_to_log': False, 21:20:35 INFO - 'base_work_dir': '/builds/slave/test', 21:20:35 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 21:20:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:20:35 INFO - 'branch': 'Autoland-Non-PGO', 21:20:35 INFO - 'buildbot_json_path': 'buildprops.json', 21:20:35 INFO - 'config_files': ('talos/linux_config.py',), 21:20:35 INFO - 'default_actions': ('clobber', 21:20:35 INFO - 'read-buildbot-config', 21:20:35 INFO - 'download-and-extract', 21:20:35 INFO - 'populate-webroot', 21:20:35 INFO - 'create-virtualenv', 21:20:35 INFO - 'install', 21:20:35 INFO - 'run-tests'), 21:20:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:20:35 INFO - 'download_minidump_stackwalk': True, 21:20:35 INFO - 'download_symbols': 'ondemand', 21:20:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:20:35 INFO - 'tooltool.py': '/tools/tooltool.py', 21:20:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:20:35 INFO - '/tools/misc-python/virtualenv.py')}, 21:20:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:20:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:20:35 INFO - 'installer_path': 'installer.exe', 21:20:35 INFO - 'log_level': 'info', 21:20:35 INFO - 'log_name': 'talos', 21:20:35 INFO - 'log_to_console': True, 21:20:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:20:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:20:35 INFO - 'opt_config_files': (), 21:20:35 INFO - 'pip_index': False, 21:20:35 INFO - 'sps_profile': False, 21:20:35 INFO - 'sps_profile_interval': 0, 21:20:35 INFO - 'suite': 'dromaeojs', 21:20:35 INFO - 'system_bits': '32', 21:20:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:20:35 INFO - 'title': 'talos-linux64-ix-029', 21:20:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:20:35 INFO - 'use_talos_json': True, 21:20:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:20:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:20:35 INFO - 'work_dir': 'build'} 21:20:35 INFO - [mozharness: 2016-08-17 04:20:35.283819Z] Running clobber step. 21:20:35 INFO - Running pre-action listener: _resource_record_pre_action 21:20:35 INFO - Running main action method: clobber 21:20:35 INFO - rmtree: /builds/slave/test/build 21:20:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:20:35 INFO - Running post-action listener: _resource_record_post_action 21:20:35 INFO - [mozharness: 2016-08-17 04:20:35.744410Z] Finished clobber step (success) 21:20:35 INFO - [mozharness: 2016-08-17 04:20:35.744511Z] Running read-buildbot-config step. 21:20:35 INFO - Running pre-action listener: _resource_record_pre_action 21:20:35 INFO - Running main action method: read_buildbot_config 21:20:35 INFO - Using buildbot properties: 21:20:35 INFO - { 21:20:35 INFO - "project": "", 21:20:35 INFO - "product": "firefox", 21:20:35 INFO - "script_repo_revision": "production", 21:20:35 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 21:20:35 INFO - "repository": "", 21:20:35 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 21:20:35 INFO - "buildid": "20160816195215", 21:20:35 INFO - "pgo_build": "False", 21:20:35 INFO - "basedir": "/builds/slave/test", 21:20:35 INFO - "buildnumber": 292, 21:20:35 INFO - "slavename": "talos-linux64-ix-029", 21:20:35 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:20:35 INFO - "platform": "ubuntu64_hw", 21:20:35 INFO - "scheduler": "tests-autoland-linux64-talos-7-3600", 21:20:35 INFO - "branch": "autoland", 21:20:35 INFO - "repo_path": "integration/autoland", 21:20:35 INFO - "revision": "b18d0dc89c1bcde24ba63b77f239f16fdf9a5620", 21:20:35 INFO - "stage_platform": "linux64", 21:20:35 INFO - "builduid": "ca1ea87d9d7c4cd6bf1c919e3fcdf45e", 21:20:35 INFO - "slavebuilddir": "test" 21:20:35 INFO - } 21:20:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 21:20:35 INFO - Running post-action listener: _resource_record_post_action 21:20:35 INFO - [mozharness: 2016-08-17 04:20:35.746953Z] Finished read-buildbot-config step (success) 21:20:35 INFO - [mozharness: 2016-08-17 04:20:35.747042Z] Running download-and-extract step. 21:20:35 INFO - Running pre-action listener: _resource_record_pre_action 21:20:35 INFO - Running main action method: download_and_extract 21:20:35 INFO - mkdir: /builds/slave/test/build/tests 21:20:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:20:35 INFO - trying https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 21:20:35 INFO - Downloading https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/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 21:20:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/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 21:20:36 INFO - Downloaded 1361 bytes. 21:20:36 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 21:20:36 INFO - Using the following test package requirements: 21:20:36 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 21:20:36 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:20:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:20:36 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:20:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:20:36 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:20:36 INFO - u'jsshell-linux-x86_64.zip'], 21:20:36 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:20:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:20:36 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 21:20:36 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:20:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:20:36 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:20:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:20:36 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:20:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:20:36 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:20:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:20:36 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:20:36 INFO - trying https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 21:20:36 INFO - Downloading https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/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 21:20:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/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 21:20:37 INFO - Downloaded 20269136 bytes. 21:20:37 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 21:20:41 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 21:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:20:41 INFO - trying https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 21:20:41 INFO - Downloading https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/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 21:20:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/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 21:20:42 INFO - Downloaded 20269136 bytes. 21:20:42 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 21:20:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:20:46 INFO - trying https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 21:20:46 INFO - Downloading https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/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 21:20:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/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 21:20:47 INFO - Downloaded 14413719 bytes. 21:20:47 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 21:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:20:47 INFO - trying https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 21:20:47 INFO - Downloading https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/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 21:20:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/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 21:20:49 INFO - Downloaded 55672401 bytes. 21:20:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 21:20:49 INFO - mkdir: /builds/slave/test/properties 21:20:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:20:49 INFO - Writing to file /builds/slave/test/properties/build_url 21:20:49 INFO - Contents: 21:20:49 INFO - build_url:https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 21:20:49 INFO - Running post-action listener: _resource_record_post_action 21:20:49 INFO - Running post-action listener: set_extra_try_arguments 21:20:49 INFO - [mozharness: 2016-08-17 04:20:49.676431Z] Finished download-and-extract step (success) 21:20:49 INFO - [mozharness: 2016-08-17 04:20:49.676655Z] Running populate-webroot step. 21:20:49 INFO - Running pre-action listener: _resource_record_pre_action 21:20:49 INFO - Running main action method: populate_webroot 21:20:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:20:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:20:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:20:49 INFO - '--tppagecycles', 21:20:49 INFO - '7'], 21:20:49 INFO - 'tests': ['tsvgm']}}, 21:20:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:20:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:20:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:20:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:20:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:20:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:20:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:20:49 INFO - 'talos_options': ['--disable-e10s'], 21:20:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:20:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:20:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:20:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:20:49 INFO - 'talos_options': ['--disable-e10s'], 21:20:49 INFO - 'tests': ['damp', 'tps']}, 21:20:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:20:49 INFO - 'tests': ['damp', 'tps']}, 21:20:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:20:49 INFO - 'tests': ['dromaeo_dom']}, 21:20:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:20:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:20:49 INFO - 'tests': ['basic_compositor_video']}, 21:20:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:20:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:20:49 INFO - 'tests': ['a11yr', 21:20:49 INFO - 'ts_paint', 21:20:49 INFO - 'tpaint', 21:20:49 INFO - 'sessionrestore', 21:20:49 INFO - 'sessionrestore_no_auto_restore', 21:20:49 INFO - 'tabpaint']}, 21:20:49 INFO - 'other-e10s': {'tests': ['a11yr', 21:20:49 INFO - 'ts_paint', 21:20:49 INFO - 'tpaint', 21:20:49 INFO - 'sessionrestore', 21:20:49 INFO - 'sessionrestore_no_auto_restore', 21:20:49 INFO - 'tabpaint']}, 21:20:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:20:49 INFO - 'ts_paint', 21:20:49 INFO - 'tpaint', 21:20:49 INFO - 'sessionrestore', 21:20:49 INFO - 'sessionrestore_no_auto_restore', 21:20:49 INFO - 'tabpaint']}, 21:20:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:20:49 INFO - 'ts_paint', 21:20:49 INFO - 'tpaint', 21:20:49 INFO - 'sessionrestore', 21:20:49 INFO - 'sessionrestore_no_auto_restore', 21:20:49 INFO - 'tabpaint']}, 21:20:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:20:49 INFO - 'tests': ['a11yr', 21:20:49 INFO - 'ts_paint', 21:20:49 INFO - 'tpaint', 21:20:49 INFO - 'sessionrestore', 21:20:49 INFO - 'sessionrestore_no_auto_restore', 21:20:49 INFO - 'tabpaint']}, 21:20:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:20:49 INFO - 'tests': ['a11yr', 21:20:49 INFO - 'ts_paint', 21:20:49 INFO - 'tpaint', 21:20:49 INFO - 'sessionrestore', 21:20:49 INFO - 'sessionrestore_no_auto_restore', 21:20:49 INFO - 'tabpaint']}, 21:20:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:20:49 INFO - 'tests': ['tsvgx', 21:20:49 INFO - 'tsvgr_opacity', 21:20:49 INFO - 'tart', 21:20:49 INFO - 'tscrollx', 21:20:49 INFO - 'cart']}, 21:20:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:20:49 INFO - 'tsvgr_opacity', 21:20:49 INFO - 'tart', 21:20:49 INFO - 'tscrollx', 21:20:49 INFO - 'cart']}, 21:20:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:20:49 INFO - 'talos_options': ['--disable-e10s'], 21:20:49 INFO - 'tests': ['tp5o']}, 21:20:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:20:49 INFO - 'tests': ['tp5o']}, 21:20:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:20:49 INFO - 'talos_options': ['--disable-e10s', 21:20:49 INFO - '--xperf_path', 21:20:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:20:49 INFO - 'tests': ['tp5n']}, 21:20:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:20:49 INFO - 'talos_options': ['--xperf_path', 21:20:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:20:49 INFO - 'tests': ['tp5n']}}, 21:20:49 INFO - 'talos.zip': {'path': '', 21:20:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:20:49 INFO - Running post-action listener: _resource_record_post_action 21:20:49 INFO - [mozharness: 2016-08-17 04:20:49.692851Z] Finished populate-webroot step (success) 21:20:49 INFO - [mozharness: 2016-08-17 04:20:49.692939Z] Running create-virtualenv step. 21:20:49 INFO - Running pre-action listener: _resource_record_pre_action 21:20:49 INFO - Running main action method: create_virtualenv 21:20:49 INFO - Creating virtualenv /builds/slave/test/build/venv 21:20:49 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:20:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:20:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:20:49 INFO - Using real prefix '/usr' 21:20:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:20:50 INFO - Installing distribute.............................................................................................................................................................................................done. 21:20:52 INFO - Installing pip.................done. 21:20:52 INFO - Return code: 0 21:20:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:20: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')]} 21:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:20:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:20: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')]} 21:20:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:20:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:20:52 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 0x7f2737279f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd5090>, '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 0x2cd89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8e40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:20:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:20:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:20:52 INFO - Using env: {'DISPLAY': ':0', 21:20:52 INFO - 'HOME': '/home/cltbld', 21:20:52 INFO - 'LANG': 'en_US.UTF-8', 21:20:52 INFO - 'LANGUAGE': 'en_US:en', 21:20:52 INFO - 'LOGNAME': 'cltbld', 21:20:52 INFO - 'MAIL': '/var/mail/cltbld', 21:20:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:20:52 INFO - 'MOZ_NO_REMOTE': '1', 21:20:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:20:52 INFO - 'NO_EM_RESTART': '1', 21:20:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:20:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:20:52 INFO - 'PWD': '/builds/slave/test', 21:20:52 INFO - 'SHELL': '/bin/bash', 21:20:52 INFO - 'SHLVL': '1', 21:20:52 INFO - 'TERM': 'linux', 21:20:52 INFO - 'TMOUT': '86400', 21:20:52 INFO - 'USER': 'cltbld', 21:20:52 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 21:20:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:20:52 INFO - '_': '/tools/buildbot/bin/python'} 21:20:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:20:52 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 21:20:52 INFO - Cleaning up... 21:20:52 INFO - Return code: 0 21:20:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:20: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')]} 21:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:20:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:20: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')]} 21:20:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:20:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:20:52 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 0x7f2737279f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd5090>, '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 0x2cd89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8e40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:20:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:20:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:20:52 INFO - Using env: {'DISPLAY': ':0', 21:20:52 INFO - 'HOME': '/home/cltbld', 21:20:52 INFO - 'LANG': 'en_US.UTF-8', 21:20:52 INFO - 'LANGUAGE': 'en_US:en', 21:20:52 INFO - 'LOGNAME': 'cltbld', 21:20:52 INFO - 'MAIL': '/var/mail/cltbld', 21:20:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:20:52 INFO - 'MOZ_NO_REMOTE': '1', 21:20:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:20:52 INFO - 'NO_EM_RESTART': '1', 21:20:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:20:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:20:52 INFO - 'PWD': '/builds/slave/test', 21:20:52 INFO - 'SHELL': '/bin/bash', 21:20:52 INFO - 'SHLVL': '1', 21:20:52 INFO - 'TERM': 'linux', 21:20:52 INFO - 'TMOUT': '86400', 21:20:52 INFO - 'USER': 'cltbld', 21:20:52 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 21:20:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:20:52 INFO - '_': '/tools/buildbot/bin/python'} 21:20:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:20:52 INFO - Downloading/unpacking psutil>=3.1.1 21:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:20:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:20:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:20:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:20:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:20:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:20:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:20:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:20:54 INFO - Installing collected packages: psutil 21:20:54 INFO - Running setup.py install for psutil 21:20:54 INFO - building 'psutil._psutil_linux' extension 21:20:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 21:20:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 21:20:54 INFO - building 'psutil._psutil_posix' extension 21:20:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 21:20:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 21:20:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:20:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:20:55 INFO - Successfully installed psutil 21:20:55 INFO - Cleaning up... 21:20:55 INFO - Return code: 0 21:20:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:20:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:20:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:20:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:20:55 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 0x7f2737279f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd5090>, '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 0x2cd89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8e40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:20:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:20:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:20:55 INFO - Using env: {'DISPLAY': ':0', 21:20:55 INFO - 'HOME': '/home/cltbld', 21:20:55 INFO - 'LANG': 'en_US.UTF-8', 21:20:55 INFO - 'LANGUAGE': 'en_US:en', 21:20:55 INFO - 'LOGNAME': 'cltbld', 21:20:55 INFO - 'MAIL': '/var/mail/cltbld', 21:20:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:20:55 INFO - 'MOZ_NO_REMOTE': '1', 21:20:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:20:55 INFO - 'NO_EM_RESTART': '1', 21:20:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:20:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:20:55 INFO - 'PWD': '/builds/slave/test', 21:20:55 INFO - 'SHELL': '/bin/bash', 21:20:55 INFO - 'SHLVL': '1', 21:20:55 INFO - 'TERM': 'linux', 21:20:55 INFO - 'TMOUT': '86400', 21:20:55 INFO - 'USER': 'cltbld', 21:20:55 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 21:20:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:20:55 INFO - '_': '/tools/buildbot/bin/python'} 21:20:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:20:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:20:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:20:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:20:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:20:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:20:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:20:57 INFO - Installing collected packages: mozsystemmonitor 21:20:57 INFO - Running setup.py install for mozsystemmonitor 21:20:57 INFO - Successfully installed mozsystemmonitor 21:20:57 INFO - Cleaning up... 21:20:57 INFO - Return code: 0 21:20:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:20:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:20:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:20:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:20:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:20:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:20:57 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 0x7f2737279f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd5090>, '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 0x2cd89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8e40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:20:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:20:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:20:57 INFO - Using env: {'DISPLAY': ':0', 21:20:57 INFO - 'HOME': '/home/cltbld', 21:20:57 INFO - 'LANG': 'en_US.UTF-8', 21:20:57 INFO - 'LANGUAGE': 'en_US:en', 21:20:57 INFO - 'LOGNAME': 'cltbld', 21:20:57 INFO - 'MAIL': '/var/mail/cltbld', 21:20:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:20:57 INFO - 'MOZ_NO_REMOTE': '1', 21:20:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:20:57 INFO - 'NO_EM_RESTART': '1', 21:20:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:20:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:20:57 INFO - 'PWD': '/builds/slave/test', 21:20:57 INFO - 'SHELL': '/bin/bash', 21:20:57 INFO - 'SHLVL': '1', 21:20:57 INFO - 'TERM': 'linux', 21:20:57 INFO - 'TMOUT': '86400', 21:20:57 INFO - 'USER': 'cltbld', 21:20:57 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 21:20:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:20:57 INFO - '_': '/tools/buildbot/bin/python'} 21:20:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:20:57 INFO - Downloading/unpacking jsonschema==2.5.1 21:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:20:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:20:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:20:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:20:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:20:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:20:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:20:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:20:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:20:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:20:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:20:59 INFO - Downloading functools32-3.2.3-2.tar.gz 21:20:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:20:59 INFO - warning: no files found matching '*.txt' 21:20:59 INFO - no previously-included directories found matching 'build' 21:20:59 INFO - no previously-included directories found matching 'dist' 21:20:59 INFO - no previously-included directories found matching '.git*' 21:20:59 INFO - Installing collected packages: jsonschema, functools32 21:20:59 INFO - Running setup.py install for functools32 21:21:00 INFO - warning: no files found matching '*.txt' 21:21:00 INFO - no previously-included directories found matching 'build' 21:21:00 INFO - no previously-included directories found matching 'dist' 21:21:00 INFO - no previously-included directories found matching '.git*' 21:21:00 INFO - Successfully installed jsonschema functools32 21:21:00 INFO - Cleaning up... 21:21:00 INFO - Return code: 0 21:21:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:21:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:21:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:21:00 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 0x7f2737279f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd5090>, '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 0x2cd89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8e40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:21:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:21:00 INFO - Using env: {'DISPLAY': ':0', 21:21:00 INFO - 'HOME': '/home/cltbld', 21:21:00 INFO - 'LANG': 'en_US.UTF-8', 21:21:00 INFO - 'LANGUAGE': 'en_US:en', 21:21:00 INFO - 'LOGNAME': 'cltbld', 21:21:00 INFO - 'MAIL': '/var/mail/cltbld', 21:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:21:00 INFO - 'MOZ_NO_REMOTE': '1', 21:21:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:21:00 INFO - 'NO_EM_RESTART': '1', 21:21:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:21:00 INFO - 'PWD': '/builds/slave/test', 21:21:00 INFO - 'SHELL': '/bin/bash', 21:21:00 INFO - 'SHLVL': '1', 21:21:00 INFO - 'TERM': 'linux', 21:21:00 INFO - 'TMOUT': '86400', 21:21:00 INFO - 'USER': 'cltbld', 21:21:00 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 21:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:21:00 INFO - '_': '/tools/buildbot/bin/python'} 21:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:21:00 INFO - Cleaning up... 21:21:00 INFO - Return code: 0 21:21:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:21:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:21:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:21:00 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 0x7f2737279f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd5090>, '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 0x2cd89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8e40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:21:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:21:00 INFO - Using env: {'DISPLAY': ':0', 21:21:00 INFO - 'HOME': '/home/cltbld', 21:21:00 INFO - 'LANG': 'en_US.UTF-8', 21:21:00 INFO - 'LANGUAGE': 'en_US:en', 21:21:00 INFO - 'LOGNAME': 'cltbld', 21:21:00 INFO - 'MAIL': '/var/mail/cltbld', 21:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:21:00 INFO - 'MOZ_NO_REMOTE': '1', 21:21:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:21:00 INFO - 'NO_EM_RESTART': '1', 21:21:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:21:00 INFO - 'PWD': '/builds/slave/test', 21:21:00 INFO - 'SHELL': '/bin/bash', 21:21:00 INFO - 'SHLVL': '1', 21:21:00 INFO - 'TERM': 'linux', 21:21:00 INFO - 'TMOUT': '86400', 21:21:00 INFO - 'USER': 'cltbld', 21:21:00 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 21:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:21:00 INFO - '_': '/tools/buildbot/bin/python'} 21:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:21:00 INFO - Downloading/unpacking blobuploader==1.2.4 21:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:21:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:21:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:21:02 INFO - Downloading blobuploader-1.2.4.tar.gz 21:21:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:21:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:21:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:21:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:21:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:21:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:21:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:21:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:21:03 INFO - Downloading docopt-0.6.1.tar.gz 21:21:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:21:03 INFO - Installing collected packages: blobuploader, requests, docopt 21:21:03 INFO - Running setup.py install for blobuploader 21:21:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:21:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:21:03 INFO - Running setup.py install for requests 21:21:03 INFO - Running setup.py install for docopt 21:21:03 INFO - Successfully installed blobuploader requests docopt 21:21:03 INFO - Cleaning up... 21:21:03 INFO - Return code: 0 21:21:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:21: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')]} 21:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:21:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:21: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')]} 21:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:21:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:21:03 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 0x7f2737279f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd5090>, '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 0x2cd89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8e40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:21:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:21:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:21:03 INFO - Using env: {'DISPLAY': ':0', 21:21:03 INFO - 'HOME': '/home/cltbld', 21:21:03 INFO - 'LANG': 'en_US.UTF-8', 21:21:03 INFO - 'LANGUAGE': 'en_US:en', 21:21:03 INFO - 'LOGNAME': 'cltbld', 21:21:03 INFO - 'MAIL': '/var/mail/cltbld', 21:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:21:03 INFO - 'MOZ_NO_REMOTE': '1', 21:21:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:21:03 INFO - 'NO_EM_RESTART': '1', 21:21:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:21:03 INFO - 'PWD': '/builds/slave/test', 21:21:03 INFO - 'SHELL': '/bin/bash', 21:21:03 INFO - 'SHLVL': '1', 21:21:03 INFO - 'TERM': 'linux', 21:21:03 INFO - 'TMOUT': '86400', 21:21:03 INFO - 'USER': 'cltbld', 21:21:03 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 21:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:21:03 INFO - '_': '/tools/buildbot/bin/python'} 21:21:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:21:04 INFO - Running setup.py (path:/tmp/pip-JwUVjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:21:04 INFO - Running setup.py (path:/tmp/pip-u1b1HM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:21:04 INFO - Running setup.py (path:/tmp/pip-TI97b3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:21:04 INFO - Running setup.py (path:/tmp/pip-7OFuK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:21:04 INFO - Running setup.py (path:/tmp/pip-jnsFRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:21:04 INFO - Running setup.py (path:/tmp/pip-odztkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:21:04 INFO - Running setup.py (path:/tmp/pip-oxFdFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:21:04 INFO - Running setup.py (path:/tmp/pip-HOnUTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:21:04 INFO - Running setup.py (path:/tmp/pip-JvHq8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:21:04 INFO - Running setup.py (path:/tmp/pip-hwJlak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:21:04 INFO - Running setup.py (path:/tmp/pip-lnRbWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:21:04 INFO - Running setup.py (path:/tmp/pip-ejqa2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:21:04 INFO - Running setup.py (path:/tmp/pip-WTrgUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:21:04 INFO - Running setup.py (path:/tmp/pip-itz3uw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:21:05 INFO - Running setup.py (path:/tmp/pip-FnmNwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:21:05 INFO - Running setup.py (path:/tmp/pip-nUQ5L2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:21:05 INFO - Running setup.py (path:/tmp/pip-27jBvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:21:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:21:05 INFO - Running setup.py install for manifestparser 21:21:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:21:05 INFO - Running setup.py install for mozcrash 21:21:05 INFO - Running setup.py install for mozdebug 21:21:05 INFO - Running setup.py install for mozdevice 21:21:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:21:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:21:05 INFO - Running setup.py install for mozfile 21:21:05 INFO - Running setup.py install for mozhttpd 21:21:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:21:05 INFO - Running setup.py install for mozinfo 21:21:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:21:05 INFO - Running setup.py install for mozInstall 21:21:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:21:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:21:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:21:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:21:06 INFO - Running setup.py install for mozleak 21:21:06 INFO - Running setup.py install for mozlog 21:21:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:21:06 INFO - Running setup.py install for moznetwork 21:21:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:21:06 INFO - Running setup.py install for mozprocess 21:21:06 INFO - Running setup.py install for mozprofile 21:21:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:21:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:21:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:21:06 INFO - Running setup.py install for mozrunner 21:21:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:21:06 INFO - Running setup.py install for mozscreenshot 21:21:06 INFO - Running setup.py install for moztest 21:21:06 INFO - Running setup.py install for mozversion 21:21:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:21:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:21:07 INFO - Cleaning up... 21:21:07 INFO - Return code: 0 21:21:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:21:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:21:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:21:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:21:07 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 0x7f2737279f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd5090>, '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 0x2cd89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8e40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:21:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:21:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:21:07 INFO - Using env: {'DISPLAY': ':0', 21:21:07 INFO - 'HOME': '/home/cltbld', 21:21:07 INFO - 'LANG': 'en_US.UTF-8', 21:21:07 INFO - 'LANGUAGE': 'en_US:en', 21:21:07 INFO - 'LOGNAME': 'cltbld', 21:21:07 INFO - 'MAIL': '/var/mail/cltbld', 21:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:21:07 INFO - 'MOZ_NO_REMOTE': '1', 21:21:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:21:07 INFO - 'NO_EM_RESTART': '1', 21:21:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:21:07 INFO - 'PWD': '/builds/slave/test', 21:21:07 INFO - 'SHELL': '/bin/bash', 21:21:07 INFO - 'SHLVL': '1', 21:21:07 INFO - 'TERM': 'linux', 21:21:07 INFO - 'TMOUT': '86400', 21:21:07 INFO - 'USER': 'cltbld', 21:21:07 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 21:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:21:07 INFO - '_': '/tools/buildbot/bin/python'} 21:21:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:21:07 INFO - Running setup.py (path:/tmp/pip-8gfzVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:21:07 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 21:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:21:07 INFO - Running setup.py (path:/tmp/pip-wooV_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:21:07 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)) 21:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:21:07 INFO - Running setup.py (path:/tmp/pip-X8y52m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:21:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:21:07 INFO - Running setup.py (path:/tmp/pip-IKxqEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:21:07 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)) 21:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:21:07 INFO - Running setup.py (path:/tmp/pip-9kUscf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:21:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 21:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:21:07 INFO - Running setup.py (path:/tmp/pip-yApN6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:21:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:21:07 INFO - Running setup.py (path:/tmp/pip-fjD6ML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:21:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:21:07 INFO - Running setup.py (path:/tmp/pip-T6Fm0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:21:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:21:07 INFO - Running setup.py (path:/tmp/pip-Noa3ND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:21:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 21:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:21:07 INFO - Running setup.py (path:/tmp/pip-Mx8Jx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:21:07 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)) 21:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:21:07 INFO - Running setup.py (path:/tmp/pip-8BGvcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 21:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:21:08 INFO - Running setup.py (path:/tmp/pip-tKbV58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:21:08 INFO - Running setup.py (path:/tmp/pip-EBKS6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 21:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:21:08 INFO - Running setup.py (path:/tmp/pip-Jlz0BZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:21:08 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)) 21:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:21:08 INFO - Running setup.py (path:/tmp/pip-QPpiKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:21:08 INFO - Running setup.py (path:/tmp/pip-zD7CkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:21:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:21:08 INFO - Running setup.py (path:/tmp/pip-5qalPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:21:08 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)) 21:21:08 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)) 21:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:21:08 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)) 21:21:08 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)) 21:21:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:21:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:21:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:21:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:21:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:21:10 INFO - Downloading blessings-1.6.tar.gz 21:21:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:21:10 INFO - Installing collected packages: blessings 21:21:10 INFO - Running setup.py install for blessings 21:21:10 INFO - Successfully installed blessings 21:21:10 INFO - Cleaning up... 21:21:10 INFO - Return code: 0 21:21:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:21:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:21:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:21:10 INFO - Reading from file tmpfile_stdout 21:21:10 INFO - Current package versions: 21:21:10 INFO - argparse == 1.2.1 21:21:10 INFO - blessings == 1.6 21:21:10 INFO - blobuploader == 1.2.4 21:21:10 INFO - docopt == 0.6.1 21:21:10 INFO - functools32 == 3.2.3-2 21:21:10 INFO - jsonschema == 2.5.1 21:21:10 INFO - manifestparser == 1.1 21:21:10 INFO - mozInstall == 1.12 21:21:10 INFO - mozcrash == 0.17 21:21:10 INFO - mozdebug == 0.1 21:21:10 INFO - mozdevice == 0.48 21:21:10 INFO - mozfile == 1.2 21:21:10 INFO - mozhttpd == 0.7 21:21:10 INFO - mozinfo == 0.9 21:21:10 INFO - mozleak == 0.1 21:21:10 INFO - mozlog == 3.3 21:21:10 INFO - moznetwork == 0.27 21:21:10 INFO - mozprocess == 0.23 21:21:10 INFO - mozprofile == 0.28 21:21:10 INFO - mozrunner == 6.12 21:21:10 INFO - mozscreenshot == 0.1 21:21:10 INFO - mozsystemmonitor == 0.3 21:21:10 INFO - moztest == 0.7 21:21:10 INFO - mozversion == 1.4 21:21:10 INFO - psutil == 3.1.1 21:21:10 INFO - requests == 1.2.3 21:21:10 INFO - wsgiref == 0.1.2 21:21:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:21: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')]} 21:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:21:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:21: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')]} 21:21:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:21:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:21:10 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 0x7f2737279f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd5090>, '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 0x2cd89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8e40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:21:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 21:21:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:21:10 INFO - Using env: {'DISPLAY': ':0', 21:21:10 INFO - 'HOME': '/home/cltbld', 21:21:10 INFO - 'LANG': 'en_US.UTF-8', 21:21:10 INFO - 'LANGUAGE': 'en_US:en', 21:21:10 INFO - 'LOGNAME': 'cltbld', 21:21:10 INFO - 'MAIL': '/var/mail/cltbld', 21:21:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:21:10 INFO - 'MOZ_NO_REMOTE': '1', 21:21:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:21:10 INFO - 'NO_EM_RESTART': '1', 21:21:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:21:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:21:10 INFO - 'PWD': '/builds/slave/test', 21:21:10 INFO - 'SHELL': '/bin/bash', 21:21:10 INFO - 'SHLVL': '1', 21:21:10 INFO - 'TERM': 'linux', 21:21:10 INFO - 'TMOUT': '86400', 21:21:10 INFO - 'USER': 'cltbld', 21:21:10 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 21:21:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:21:10 INFO - '_': '/tools/buildbot/bin/python'} 21:21:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 21:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 21:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 21:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 21:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 21:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 21:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 21:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:21:11 INFO - Cleaning up... 21:21:11 INFO - Return code: 0 21:21:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:21:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:21:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:21:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:21:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:21:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:21:11 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 0x7f2737279f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cdb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd5090>, '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 0x2cd89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8e40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:21:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:21:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:21:11 INFO - Using env: {'DISPLAY': ':0', 21:21:11 INFO - 'HOME': '/home/cltbld', 21:21:11 INFO - 'LANG': 'en_US.UTF-8', 21:21:11 INFO - 'LANGUAGE': 'en_US:en', 21:21:11 INFO - 'LOGNAME': 'cltbld', 21:21:11 INFO - 'MAIL': '/var/mail/cltbld', 21:21:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:21:11 INFO - 'MOZ_NO_REMOTE': '1', 21:21:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:21:11 INFO - 'NO_EM_RESTART': '1', 21:21:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:21:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:21:11 INFO - 'PWD': '/builds/slave/test', 21:21:11 INFO - 'SHELL': '/bin/bash', 21:21:11 INFO - 'SHLVL': '1', 21:21:11 INFO - 'TERM': 'linux', 21:21:11 INFO - 'TMOUT': '86400', 21:21:11 INFO - 'USER': 'cltbld', 21:21:11 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 21:21:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:21:11 INFO - '_': '/tools/buildbot/bin/python'} 21:21:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:21:11 INFO - Cleaning up... 21:21:11 INFO - Return code: 0 21:21:11 INFO - Running post-action listener: _resource_record_post_action 21:21:11 INFO - Running post-action listener: _start_resource_monitoring 21:21:11 INFO - Starting resource monitoring. 21:21:11 INFO - [mozharness: 2016-08-17 04:21:11.312753Z] Finished create-virtualenv step (success) 21:21:11 INFO - [mozharness: 2016-08-17 04:21:11.313267Z] Running install step. 21:21:11 INFO - Running pre-action listener: _resource_record_pre_action 21:21:11 INFO - Running main action method: install 21:21:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:21:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:21:11 INFO - Reading from file tmpfile_stdout 21:21:11 INFO - Detecting whether we're running mozinstall >=1.0... 21:21:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:21:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:21:11 INFO - Reading from file tmpfile_stdout 21:21:11 INFO - Output received: 21:21:11 INFO - Usage: mozinstall [options] installer 21:21:11 INFO - Options: 21:21:11 INFO - -h, --help show this help message and exit 21:21:11 INFO - -d DEST, --destination=DEST 21:21:11 INFO - Directory to install application into. [default: 21:21:11 INFO - "/builds/slave/test"] 21:21:11 INFO - --app=APP Application being installed. [default: firefox] 21:21:11 INFO - mkdir: /builds/slave/test/build/application 21:21:11 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'] 21:21:11 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 21:21:27 INFO - Reading from file tmpfile_stdout 21:21:27 INFO - Output received: 21:21:27 INFO - /builds/slave/test/build/application/firefox/firefox 21:21:27 INFO - Running post-action listener: _resource_record_post_action 21:21:27 INFO - [mozharness: 2016-08-17 04:21:27.647044Z] Finished install step (success) 21:21:27 INFO - [mozharness: 2016-08-17 04:21:27.647293Z] Running run-tests step. 21:21:27 INFO - Running pre-action listener: _resource_record_pre_action 21:21:27 INFO - Running main action method: run_tests 21:21:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:21:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:21:27 INFO - Python 2.7.3 21:21:27 INFO - Return code: 0 21:21:27 INFO - grabbing minidump binary from tooltool 21:21:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:21:27 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 0x2cd5090>, '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 0x2cd89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:21:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:21:27 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 21:21:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:21:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:21:27 INFO - Return code: 0 21:21:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:21:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:21:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:21:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:21:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:21:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:21:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:21:27 INFO - Using env: {'DISPLAY': ':0', 21:21:27 INFO - 'HOME': '/home/cltbld', 21:21:27 INFO - 'LANG': 'en_US.UTF-8', 21:21:27 INFO - 'LANGUAGE': 'en_US:en', 21:21:27 INFO - 'LOGNAME': 'cltbld', 21:21:27 INFO - 'MAIL': '/var/mail/cltbld', 21:21:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:21:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:21:27 INFO - 'MOZ_NO_REMOTE': '1', 21:21:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:21:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:21:27 INFO - 'NO_EM_RESTART': '1', 21:21:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:21:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:21:27 INFO - 'PWD': '/builds/slave/test', 21:21:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:21:27 INFO - 'SHELL': '/bin/bash', 21:21:27 INFO - 'SHLVL': '1', 21:21:27 INFO - 'TERM': 'linux', 21:21:27 INFO - 'TMOUT': '86400', 21:21:27 INFO - 'USER': 'cltbld', 21:21:27 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468', 21:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:21:27 INFO - '_': '/tools/buildbot/bin/python'} 21:21:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:21:27 INFO - mozversion application_buildid: 20160816195215 21:21:27 INFO - mozversion application_changeset: b18d0dc89c1bcde24ba63b77f239f16fdf9a5620 21:21:27 INFO - mozversion application_display_name: Nightly 21:21:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:21:27 INFO - mozversion application_name: Firefox 21:21:27 INFO - mozversion application_remotingname: firefox 21:21:27 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:21:27 INFO - mozversion application_vendor: Mozilla 21:21:27 INFO - mozversion application_version: 51.0a1 21:21:27 INFO - mozversion platform_buildid: 20160816195215 21:21:27 INFO - mozversion platform_changeset: b18d0dc89c1bcde24ba63b77f239f16fdf9a5620 21:21:27 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:21:27 INFO - mozversion platform_version: 51.0a1 21:21:27 INFO - using testdate: 1471407687 21:21:27 INFO - actual date: 1471407687 21:21:27 INFO - starting webserver on 'localhost:47009' 21:21:27 INFO - SUITE-START | Running 2 tests 21:21:27 INFO - TEST-START | dromaeo_css 21:21:27 INFO - operating with platform_type : linux_ 21:21:27 INFO - Initialising browser for dromaeo_css test... 21:21:27 INFO - TEST-INFO | started process 25485 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3neJeG/profile http://localhost:47009/getInfo.html) 21:21:32 INFO - PROCESS | 25485 | __metrics Screen width/height:1600/1200 21:21:32 INFO - PROCESS | 25485 | colorDepth:24 21:21:32 INFO - PROCESS | 25485 | Browser inner width/height: 1024/697 21:21:32 INFO - PROCESS | 25485 | __metrics 21:21:33 INFO - PROCESS | 25485 | 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" 21:21:33 INFO - PROCESS | 25485 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:21:34 INFO - TEST-INFO | 25485: exit 0 21:21:34 INFO - Browser initialized. 21:21:34 INFO - Running cycle 1/1 for dromaeo_css test... 21:21:34 INFO - TEST-INFO | started process 25563 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3neJeG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:21:46 INFO - PROCESS | 25563 | RSS: Main: 157126656 21:21:46 INFO - PROCESS | 25563 | 21:22:37 INFO - PROCESS | 25563 | 1471407757698 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] 21:24:49 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:47009/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47009/tests/dromaeo/cssquery-ext.html) 21:24:51 INFO - PROCESS | 25563 | RSS: Main: 276471808 21:24:51 INFO - PROCESS | 25563 | 21:27:54 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:47009/tests/dromaeo/cssquery-ext.html (next: http://localhost:47009/tests/dromaeo/cssquery-jquery.html) 21:27:54 INFO - PROCESS | 25563 | RSS: Main: 250671104 21:27:54 INFO - PROCESS | 25563 | 21:30:56 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:47009/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47009/tests/dromaeo/cssquery-mootools.html) 21:30:57 INFO - PROCESS | 25563 | RSS: Main: 230494208 21:30:57 INFO - PROCESS | 25563 | 21:33:59 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:47009/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47009/tests/dromaeo/cssquery-prototype.html) 21:34:00 INFO - PROCESS | 25563 | RSS: Main: 188219392 21:34:00 INFO - PROCESS | 25563 | 21:37:03 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:47009/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47009/tests/dromaeo/cssquery-yui.html) 21:37:04 INFO - PROCESS | 25563 | RSS: Main: 187809792 21:37:04 INFO - PROCESS | 25563 | 21:40:07 INFO - PROCESS | 25563 | Cycle 1(1): loaded http://localhost:47009/tests/dromaeo/cssquery-yui.html (next: http://localhost:47009/tests/dromaeo/cssquery-dojo.html) 21:40:07 INFO - PROCESS | 25563 | RSS: Main: 276541440 21:40:07 INFO - PROCESS | 25563 | 21:40:07 INFO - PROCESS | 25563 | __start_tp_report 21:40:07 INFO - PROCESS | 25563 | _x_x_mozilla_page_load 21:40:07 INFO - PROCESS | 25563 | _x_x_mozilla_page_load_details 21:40:07 INFO - PROCESS | 25563 | |i|pagename|runs| 21:40:07 INFO - PROCESS | 25563 | |0;dojo.html;2574.909878154265;2578.097665816964;2600.3759097816524;2614.986925065374;2624.37015116372;4501.504834468205;4591.517890621479;4600.102979918917;4637.304404339347;4657.278121891112;4522.29904364824;4540.97729511352;4566.086782643468;4600.309953506967;4631.467381251157;4164;4204.957950420502;4210.515790684065;4216.789160541976;4220.746755194683;4232;4234.407182994383;4248.107897341562;4276.037891474424;4282.31482962726;4244.97877510612;4252.14957008598;4255.5957184067465;4269.146170765844;4275.572442755731;4143.97928010361;4168.0205151789305;4198.391233271159;4213.073123912734;4267.850625228133;4146.3365861462025;4181.811818468146;4201.663866890642;4224.239636865363;4235.174141042498;4664.300354946785;4668.906621867578;4676.415448068991;4676.649251306166;4677.415323084614;4824.179889418772;4841.515848415165;4994.600431965434;4998.40019197695;5000.524950129732;4894.608431325485;4903.092927808367;4935.185694360426;4951.925721114186;4972.5276098770555;4628.791704373311;4652.837150699743;4653.208954477747;4670.95329046712;4672.065586882637;4939.3084968104495;4970.15507363749;5077.365329333833;5077.568406685399;5081.415637201695;4773.04539092176;4857.1742803723455;4871.244957031666;4874.975625121851;4878.463369029404;4903.41159060915;4907.509249075135;4934.1611925972675;4938.456769755324;4951.678140920829;4761.071591039714;4806.519348065235;4854.514548545118;4900.926486102711;4960.255961605716;4828.179209534387;4853.611711063107;4869.1722407190155;4937.21004639256;4986.775595098229;4305.590968857953;4318.179545886272;4319.827206911688;4320.481542214891;4356.390105385249;3793.317202903504;3798.487204227449;3798.639129282769;3815.2178803345246;3816.6183381661613;4172.958270417257;4265.957340426556;4281.464816897887;4290.849820256276;4353.194658988034;4942.061008408391;4943.134951383494;4943.703377797344;4947.876303092451;4996.800127994912;4311.849085282063;4319.892002699958;4323.956760432356;4327.437433133672;4364.388985542027;4427.977860110679;4497.347884556721;4531.750753708512;4550.431196100488;4600.125976064537;4708.6468514860835;4721.268203428543;4726.19654658702;4726.6455015874635;4785.162596545661;4955.55400013986;4983.875403114806;4990.750462476934;4992.276119962513;5039.0929632666475;4601.4938356781395;4669.0428462165855;4682.836100736458;4715.764211789466;4776.8566942991765;4869;4904.92642610354;4907.190313598217;4908.067467181224;4920.852374428773;4973.303737476684;4974.900501990012;4981.850544483671;4995.125852975642;5022.096022715946;4699.271612900006;4773.761311934459;4824.589909857701;4846.854594362175;4916.70499770015;4850.7817148227705;4880.194767863405;4881.9511804881495;4883.68256063369;4927.039227350632;4804.039192161652;4822.9758851205515;4851.733154676526;4858.854234372974;4867.780949857194;4740.407449068866;4795.184818580919;4802.855914322576;4843.491433399442;4866.732329721899;4706.223473126895;4737.170995075779;4752.572268495852;4761.857144285677;4766.332713420194 21:40:07 INFO - PROCESS | 25563 | |1;ext.html;553.9058360078786;568.2839622076183;570.3156212544944;575.0081110085102;579.8637320229748;29124.27189320268;40145;41312.79343603285;41418.37872431908;45400.54599454004;40563.59436405635;41055.794721026425;41083;41106.58893411065;41118.38322425166;7426.071741032371;7627.923720762805;7769.728059517918;7777.805554861117;7778.988731464901;7400.962995185017;7680;7682.193369696185;9727.221822254203;9957.502124893763;10046.34698744583;10102.242331825106;10109.494525273743;10113.292069555135;10115;9959.302848800551;9988.95005524975;9994.150497207746;10023.148032417253;10034;9860;9931.404115753077;9932.652357167537;9933.701988940344;9934.701958941243;39663.60336396628;39940.60059399398;40003.7999810002;40060.79969600133;40069.79965100185;2852.229897927548;2861.756750676195;2901.3036871150985;2977.1034044601856;3217.3082787200865;29381.26546836325;30065.699343006727;30162.69837301621;30290.697093029008;30338.54492182619;3529.7737808085913;3534.628863969272;3570.285942811448;3609.2961872434885;3658.506101676293;180031;181223;182001.0899945505;182442;182568;53648;53818.46181538134;53966.73016634892;54045.72977135089;54116.917661647334;32568.18579535483;33372;56325.43674563284;56389.15416268677;56503.15245271324;10762.3542587445;10889.346639201673;10895.346279223271;10897.618583349546;10903.890961090447;8260.710875119343;8268;8268.793280168044;8268.917310826815;8284.130166332568;15980.840191597936;17685.823141768677;18082.276708931495;18144.45566633003;18696;6652.534322597372;6775;6974.721011159497;7059.505834591632;7148.714051437988;795.5107608820533;856.5117882806741;938.1416004355938;963.7012526116927;1002.5187909803335;27522.587161192598;28541.286467838054;28813.42373152525;29192.708072919424;29426.264343391587;950.7908260182749;969.9515024248759;972.3436680240809;991.3308516751164;996.4917891875195;14811.925940370229;15336.463223787114;15400.30698618565;15534.634823109836;15585.532434026998;86432.56783716167;87245.1275487237;87550;87639.56180219058;87810;49773.75113124411;50042.49957500451;50050.24924626134;50138;50181.74909125504;1081.5998080710187;1084.8535447714457;1135.0238794636605;1179.33367647279;1184.3782014442486;26460;28837.567436488887;30246.697533025268;30798.538021929242;30926.381472369976;43809.56190438055;44633.33050004317;44729.55270447384;44790.3281450785;44798.552014479435;335559;337807;340272;341182;341755;38171.42742858804;39143.10711161105;39793;39912.80043599763;41644.375334369404;40590.79704601458;40617;40734.59265407308;40953.79523102365;42247.788761056;34573.65426345704;34666.65333346735;34879.82560087183;35268.29463410769;35801.46297805587;34742.82628586841;34788.82605586956;34891.47662785111;35232;35341.29317413689;32646;32803.375729942025;32804.179895502806;32969.835150824096;39330.410043848795;29285.70714292915;31120.221994449414;34776.30447391089;34911;35071;25256.873715631304;25980.740192598587;26168.34579135537;26174.34564135912;26453.866227438724 21:40:07 INFO - PROCESS | 25563 | |2;jquery.html;344.5176752546436;360.19676122247387;361.5300109857186;363.55283001907645;380.220587505336;12729;15970.041797492158;16336.346546138155;16356;17547.94712317262;14667.633309167277;15370.615734606641;15425.997310174831;15447;15565.066096034247;4667.346571479996;4923.769057735566;5006.324146240257;5123.615728820335;5143.614228932826;5023.648344615878;5060.418051924039;5085.1863701807715;5097.7196254206;5101.081805275049;4941.160002799515;5066.037452883958;5069.036882992238;5071.416787069479;5175.171972484403;5344.812931547397;5362.839114826562;5368.1679339702405;5371.221172929942;5379.139337705968;5107.13178759608;5221.973890130563;5234.895302093975;5247.1866860636665;5292.364916210079;11895.881041189565;12110.757784844254;12117.757644847143;12130.818037729441;16640.583985400342;1791.9014454205014;1815.7911840138422;1863.0684657671165;1869.0281053852057;1873.653374125791;8707.869381959275;8757.562121893943;8820.117988201193;9251.90748092524;9497.810043799154;1690.2478397113289;1726.231826837058;1743.6425532765752;1784.5538615346163;1809.6742586334456;68065;68311.65844170797;69516.30483695149;70878;72299;9458.668946586837;9719.368241064309;9878.259130565093;9935.254855885838;10120.443375614412;13634.863651363557;13654.863451365558;13659.385327660279;13662.453501859814;13673.658158546115;6198.194234749544;6387.872242555123;6395.232572091379;6397.040443933354;6430.678466076677;5585.525230355384;5617.326500234793;5790.565707571949;5800.216970708901;5813.680247586339;4193;4229.619334259932;4243.469566304212;4439.911201775946;4462.478699534752;1712.212382304154;1766.5142085926216;1767.381416504239;1818.563334965389;1840.9539761506069;768.2317682317682;791.2087912087912;803.4697099914146;843.5655637346771;871.5206636349982;16224.188790560425;16707.582310442336;16993.91503042477;17504.64990700179;17634.735478967825;815.8327542853701;845.9280961118374;861.8233262181237;899.0515006667897;900.0729248873671;9251.213646839959;9260.583273752698;9334.859977100348;9993.700188994342;10076.899231007743;354618;354949;355011;355341;395633;79677.60161199157;81326;82559.5872020636;84134;87886.56056719675;891.2379915172435;901.5176880368971;936.5410948635256;940.4592359393239;944.8157609266265;14861.121472087529;15219.315130818919;16225.43210987657;16402.015879047296;16550;37312;38153;38599;38973.610263897;39037.510686271446;46889.76555117339;47203;48589;51126.48873511217;52287.47712522826;7219.6751146199595;7483.176850546544;7648.196939321291;7678.308952194329;7801.2198780121535;10909.290896091728;12238.388080595756;12324.383780811102;12642.747145056863;12795.552155674506;10537.420441875463;10600.770167002485;10746.355218686904;10798.460076996276;10898.509567069343;5829.53363730892;6381.266154392304;10350.327228730108;10533.36799792015;10656.34826601878;10358.689239322832;10415.84376234342;10541.525631346454;10631.255812093079;11013.174011948975;7167.668137264787;7394.408447324308;7469.439792015729;7493.475456717977;7790.805229869298;8616.913830861611;10048.69853904384;10242.846357304497;10411;10540.104091152338 21:40:07 INFO - PROCESS | 25563 | |3;mootools.html;1252.0171665242785;1334.55959533354;1382.5092092307232;1413.7667284897993;1663.6672665466901;6549.377809108139;7379.335859772619;7654.88517672234;7662.118856331516;7912.92087079129;7815.570143642111;7867.859160421735;7872.606369681522;7872.9606351968305;7876.251756083163;2143.153454385517;2167.1548096242477;2169.3600387885613;2170.131947221116;2171.815395691368;2016.294296996047;2068.606964676714;2080.6878968154747;2086.3636590839787;2090.7177531033267;2099.517111064456;2141.9785802142014;2142.4215461825297;2147.9892600537055;2151.752548456932;2069.6998935154393;2083.958320833575;2091.44576687178;2092.2886218685567;2094.570613024334;2020.3736841579157;2078.740157480315;2083.4687154775434;2085.1450905128977;2087.165133946434;7594.620268986529;7804.843903121962;7885.8028549286155;7913.525188488709;7949.7217597383815;4168.187203495319;4326.134773045372;4332;4337.349397590355;4339.891502712458;2684.9060282890005;2808.7191280871953;2861.241770930705;2861.399106187703;2862.613547171126;898.5507246376811;901.6753968571309;919.1543779722605;928.4058202750246;934.8831396075491;10554.997275258838;10658.307210031324;10665.626703065356;10673.893261067367;10677.519511622073;3756.098536351256;3828.157805282833;3891.0661441253897;3955.841766329372;3982;8365.456245344154;8374.665013399395;8380.16198380157;8383.329333653415;8389.119142490074;4178.268802959531;4199.832006719758;4201.285781417167;4204.957950420518;4211.052513184509;3417.5557177567252;3730.3844865597416;3886.825092870771;3903.8048097595574;3904.2386734587053;3316.9834150829092;3426.0920855973186;3451.2062225688233;3461.532693086401;3560.964390356115;969.7769513012047;1006.6979906028308;1010.9747256318651;1019.0370100255187;1022.2997246885907;762.6034462079688;783.925527074927;808.7614153824636;810.1250649298743;815.657423881971;4722.1972264714395;4726.291056341576;4816.277558366203;4951.653384263067;5115.97442012795;381.26796550622316;395.8317714971126;883.5979629268796;969.3844408800321;990.2375171118342;3315.5026745988293;3464.2898205867737;3507.7895326280504;3537.080359106599;3582.6775590196485;9358.391704539183;9722.173615242642;9735.172510336559;9743.61025558984;9744.31789774723;9527.714168574954;9612.951935240279;9614.230861531201;9616.134547890584;9622.182114520205;6943.826404339932;7195.784126476214;7204.351608355273;7375.045612912582;7384.335409813035;5187.792488300426;5308.336457942757;5315.0964336063735;5341.145416733304;5508.118701007819;8555.700550480953;8650;8760.211580957744;8769.166929141722;8914.064023277462;8677.1756683115;9089;9114.316426267924;9120.908790912006;9198.448093114435;5935.287765467999;6501.869962600627;6702.932970670426;6772.11962444879;6813.693383797642;7431.256874312742;7766.029246344207;7770.961145194238;7773.766786996399;7776.105747839071;6612.50406219545;6625.271220165874;6728.529002969745;6787.728490860311;6901.24086350491;6438.581492202992;6568.474522038321;6705.597664139972;6742.292059333896;6850.177978642595;5859.44335288147;6156.846078847885;6172.22847144107;6178.69106544662;6184.5980011299125;4548.977255113703;4565.06416184688;4567.748773817472;4587.8623641290815;4593.242115050979;4493.77531123436;4548.272276435754;4598.149342371672;4609.723416595015;4671.065786842577 21:40:07 INFO - PROCESS | 25563 | |4;prototype.html;299.29665286576545;302.6564848896502;305.625608629429;313.2888343460344;313.89798315547455;4774.856754297368;4782.665213435061;4799.328094066834;4838.153323168449;4864.100141473826;4685.83599574015;4701.694389864657;4702.929456058153;4703.717776933387;4792.9281060784115;3596.856125754973;3706.870259540917;3712.6844218241345;3744.120131769032;3771.9622803771886;3761.755485893422;3762.457392469088;3774.9433758493647;3775.2826962876966;3778.641029102245;3729.3287208302495;3730.4404339349044;3733.8903519511523;3738.1962877981227;3792.089898424372;3571.910702232452;3626.165981824195;3738.4579236010763;3755.8497660093562;3781.3949768037255;3315.900522984314;3321.684439978198;3323.6177839548277;3336.249343897628;3341.6992470677515;4614.976925115387;4617.660878345276;4625.444946606429;4627.537246275379;4684.672072954894;4111.465509483778;4241.660667146652;4251.298535741599;4254.340577210537;4254.957450425487;4859.913954638794;4901.436334821506;4924.655274130812;4987.127252730757;5012.874678133076;3656.872009479682;3673.9265214695556;3677.485152078711;3681.668649821529;3685.299793039341;4971.602271818246;5136.9743151284;5212.50481204285;5231.921521177185;5242.23987521811;3650.4159334506353;3675.5038069860225;3694.0949467380665;3713.3475603356246;3729.9627003729615;3756.192418629953;3763.1532905096133;3769.246150769802;3771.792551409644;3821.3885778275344;4206.3595197872855;4216.683748718859;4229.851955181553;4236.597523235288;4238.470191226097;4794.904101917942;4896.094222568836;4901.411830580353;4920.163572192736;4936.358273424432;1297.6172029251393;1311.6065180445828;1313.0677219174302;1313.750387426405;1313.7766579681477;817.5176645779019;844.5312851367427;845.9830803383898;850.171083193881;870.3776799588325;4339.370791235253;4436.646822719102;4443.866943866962;4445.133199026159;4445.844395446144;5036.571891389273;5054.241863720471;5101.591872650179;5104.1578139606545;5115.897682046412;4382.83854778484;4410.3825464435;4431.7340959542535;4463.888402789891;4468.039371535136;2578.510083084208;2695.029789275859;2716.2122984334715;2724.523208438515;2728.7816974641983;4844.733539655283;4890.315355850184;4891.217405215149;4908.852734417973;4909.239067944474;5025.447200807908;5183.740812959337;5191.844244672666;5296.861174847426;5313.973430132824;4876.5854902332985;4880.243562247857;4886.902261954741;4887.804487820447;4889.894916727832;5134.255532947776;5137.486251374833;5139.588832893434;5143.382794064736;5233.162693968947;4880.560234730692;5021.573166280907;5022.472640372855;5028.636540825806;5044.8234311798915;4859.514048595112;4867.269909513601;4888.755562221803;4893.9021219576125;4934.802607895644;3746.756460830037;3752.1932784451474;3753;3760.8307626156343;3811.0662887591607;4821.7348045856415;5077.974610126926;5081.669691470042;5083.771230294572;5091.974540127276;4952;4961.355023846877;4977.527134922177;4979.178435558092;4983.177775666974;4971.975140124277;4981.334985782589;5053.469385714594;5062.088824011713;5066.214736715815;4779;4913.803447862046;4937.35814344133;4969.751512424436;4990;4800.591949684316;4910.140725373117;5014.674046186986;5041.269015992734;5053.620978426559;4863.781129849094;4863.805447782049;4874.5147219738055;4877.439094504178;4886.242632391985 21:40:07 INFO - PROCESS | 25563 | |5;yui.html;508.63124234929677;522.6106550619788;537.9919301210482;538.3055857943253;615.1295916278466;12653.304068276253;13756.174629522224;13851.168929864216;13867.791983120249;13873.861261387383;11814.527418903233;12245.571405000826;12431.378431078456;13169.934150329234;13313.93343033286;632.3392055302197;764.4075841223055;783.5103060587134;785.5522352259214;787.6376866641352;757.1103952855373;767.6890859202032;776.192759530088;787.9999999999972;789.4828887078972;822.251750906675;822.5393779483478;823.1315961660446;823.1932705948175;826.6858593734372;659.5020759326726;814.9225823546813;814.9918500814975;816.1512027491402;816.6814942172557;579.8550362409397;585.5169485174748;591.0130082761798;601.5909181756404;601.7322291580249;13144.60566183016;13168.53910113151;13172.407241674147;13198;13225.140365876283;11764;12235.571754988621;12279.2018518796;12297.508099676003;12729.490820367175;11905.464254108585;12081.57714479989;12098.092643051717;12178.269303841798;12803.231806091664;4046.736962097454;4076.5515793262716;4109.506859176918;4247.511536173318;4255.2978758504805;64088;64213.357866421204;64651.67674161646;64736.086876090216;64923;10069.144122749502;10078.697639070839;10472.947635261775;10666.093382062612;10852.24034317606;19197.136128874234;19257.614847702967;19277.132529036226;19348.32280870163;19419.51451213731;6493.51298652603;6600.86798264032;6633.6019838809825;6659.4006539411685;6776.796696099026;3645.289168612095;3680.5399325084363;3733.216024634857;3740.214554943438;3775.5469343678933;2687.6909155447374;2791.5393959996372;2792.6928037915814;2794.105886116464;2795.077624383948;492.5271739130475;502.10625087344584;504.13540777566647;509.64579617166453;515.7730598536707;397.07679644825896;449.6155786802302;513.9408967968657;517.7256054291232;523.9292695486137;7137;7140.678669459887;7210.639468026683;7388.759016164189;7670.386369090459;416.6978940268342;434.82987281226247;495.5366732105493;506.38980029065624;515.4587682932905;3349.2129349602824;3387.7827897465586;3435.2957643682985;3454.6199918008997;3473.3501586746092;56107.719461402434;58624;71202.93195602071;109315.45342273237;110454.44772776245;51767.48232517702;51793.22310165275;73566;92660.53669731609;94397;77986.6100669493;78810;79306;80079.59960200162;85206.57396712976;6665.66671666405;6817.386435220854;6863;6979.6859141337745;7022.964885175541;30374.54438183385;33227.50158747572;38085.4287185698;38191.61808381881;38746.80626596849;59091;59605.40394596172;59780;60256;60998;10428.478576071015;10521.736956576147;10524.105451036441;10552.894471055499;10916.508757106109;12359.876401236232;12655.367231638565;12655.746885062063;12828.29444380568;12828.679283017998;10996.725081873017;11033.337999720043;11303.43482825839;11530.94234528822;11768.882311176778;10611.681649550526;10702;10754.462276886281;10763.51564179598;11101.38942358178;10332.690019299433;10899.673009809401;11101.5559377624;11161.274517156358;11356.318620882774;9025.368224224241;9225.400348977295;9286.489243091695;9447.480388578695;9491.952540237255;8793.780155496164;8950.23922966555;9181.724548263563;9254.444733316022;9435.198008169382 21:40:07 INFO - PROCESS | 25563 | __end_tp_report 21:40:07 INFO - PROCESS | 25563 | __start_cc_report 21:40:07 INFO - PROCESS | 25563 | _x_x_mozilla_cycle_collect,1242 21:40:07 INFO - PROCESS | 25563 | __end_cc_report 21:40:07 INFO - PROCESS | 25563 | __startTimestamp1471408807567__endTimestamp 21:40:07 INFO - PROCESS | 25563 | 21:40:07 INFO - PROCESS | 25563 | ------- Summary: start ------- 21:40:07 INFO - PROCESS | 25563 | Number of tests: 6 21:40:07 INFO - PROCESS | 25563 | 21:40:07 INFO - PROCESS | 25563 | [#0] dojo.html Cycles:165 Average:4582.99 Median:4721.27 stddev:468.31 (9.9%) stddev-sans-first:442.45 21:40:07 INFO - PROCESS | 25563 | Values: 2574.9 2578.1 2600.4 2615.0 2624.4 4501.5 4591.5 4600.1 4637.3 4657.3 4522.3 4541.0 4566.1 4600.3 4631.5 4164.0 4205.0 4210.5 4216.8 4220.7 4232.0 4234.4 4248.1 4276.0 4282.3 4245.0 4252.1 4255.6 4269.1 4275.6 4144.0 4168.0 4198.4 4213.1 4267.9 4146.3 4181.8 4201.7 4224.2 4235.2 4664.3 4668.9 4676.4 4676.6 4677.4 4824.2 4841.5 4994.6 4998.4 5000.5 4894.6 4903.1 4935.2 4951.9 4972.5 4628.8 4652.8 4653.2 4671.0 4672.1 4939.3 4970.2 5077.4 5077.6 5081.4 4773.0 4857.2 4871.2 4875.0 4878.5 4903.4 4907.5 4934.2 4938.5 4951.7 4761.1 4806.5 4854.5 4900.9 4960.3 4828.2 4853.6 4869.2 4937.2 4986.8 4305.6 4318.2 4319.8 4320.5 4356.4 3793.3 3798.5 3798.6 3815.2 3816.6 4173.0 4266.0 4281.5 4290.8 4353.2 4942.1 4943.1 4943.7 4947.9 4996.8 4311.8 4319.9 4324.0 4327.4 4364.4 4428.0 4497.3 4531.8 4550.4 4600.1 4708.6 4721.3 4726.2 4726.6 4785.2 4955.6 4983.9 4990.8 4992.3 5039.1 4601.5 4669.0 4682.8 4715.8 4776.9 4869.0 4904.9 4907.2 4908.1 4920.9 4973.3 4974.9 4981.9 4995.1 5022.1 4699.3 4773.8 4824.6 4846.9 4916.7 4850.8 4880.2 4882.0 4883.7 4927.0 4804.0 4823.0 4851.7 4858.9 4867.8 4740.4 4795.2 4802.9 4843.5 4866.7 4706.2 4737.2 4752.6 4761.9 4766.3 21:40:07 INFO - PROCESS | 25563 | 21:40:07 INFO - PROCESS | 25563 | [#1] ext.html Cycles:180 Average:38106.52 Median:38657.27 stddev:60397.82 (156.2%) stddev-sans-first:60501.44 21:40:07 INFO - PROCESS | 25563 | Values: 553.9 568.3 570.3 575.0 579.9 29124.3 40145.0 41312.8 41418.4 45400.5 40563.6 41055.8 41083.0 41106.6 41118.4 7426.1 7627.9 7769.7 7777.8 7779.0 7401.0 7680.0 7682.2 9727.2 9957.5 10046.3 10102.2 10109.5 10113.3 10115.0 9959.3 9989.0 9994.2 10023.1 10034.0 9860.0 9931.4 9932.7 9933.7 9934.7 39663.6 39940.6 40003.8 40060.8 40069.8 2852.2 2861.8 2901.3 2977.1 3217.3 29381.3 30065.7 30162.7 30290.7 30338.5 3529.8 3534.6 3570.3 3609.3 3658.5 180031.0 181223.0 182001.1 182442.0 182568.0 53648.0 53818.5 53966.7 54045.7 54116.9 32568.2 33372.0 56325.4 56389.2 56503.2 10762.4 10889.3 10895.3 10897.6 10903.9 8260.7 8268.0 8268.8 8268.9 8284.1 15980.8 17685.8 18082.3 18144.5 18696.0 6652.5 6775.0 6974.7 7059.5 7148.7 795.5 856.5 938.1 963.7 1002.5 27522.6 28541.3 28813.4 29192.7 29426.3 950.8 970.0 972.3 991.3 996.5 14811.9 15336.5 15400.3 15534.6 15585.5 86432.6 87245.1 87550.0 87639.6 87810.0 49773.8 50042.5 50050.2 50138.0 50181.7 1081.6 1084.9 1135.0 1179.3 1184.4 26460.0 28837.6 30246.7 30798.5 30926.4 43809.6 44633.3 44729.6 44790.3 44798.6 335559.0 337807.0 340272.0 341182.0 341755.0 38171.4 39143.1 39793.0 39912.8 41644.4 40590.8 40617.0 40734.6 40953.8 42247.8 34573.7 34666.7 34879.8 35268.3 35801.5 34742.8 34788.8 34891.5 35232.0 35341.3 32646.0 32803.4 32804.2 32969.8 39330.4 29285.7 31120.2 34776.3 34911.0 35071.0 25256.9 25980.7 26168.3 26174.3 26453.9 21:40:07 INFO - PROCESS | 25563 | 21:40:07 INFO - PROCESS | 25563 | [#2] jquery.html Cycles:180 Average:23342.99 Median:47046.38 stddev:60434.90 (128.5%) stddev-sans-first:60579.76 21:40:07 INFO - PROCESS | 25563 | Values: 344.5 360.2 361.5 363.6 380.2 12729.0 15970.0 16336.3 16356.0 17547.9 14667.6 15370.6 15426.0 15447.0 15565.1 4667.3 4923.8 5006.3 5123.6 5143.6 5023.6 5060.4 5085.2 5097.7 5101.1 4941.2 5066.0 5069.0 5071.4 5175.2 5344.8 5362.8 5368.2 5371.2 5379.1 5107.1 5222.0 5234.9 5247.2 5292.4 11895.9 12110.8 12117.8 12130.8 16640.6 1791.9 1815.8 1863.1 1869.0 1873.7 8707.9 8757.6 8820.1 9251.9 9497.8 1690.2 1726.2 1743.6 1784.6 1809.7 68065.0 68311.7 69516.3 70878.0 72299.0 9458.7 9719.4 9878.3 9935.3 10120.4 13634.9 13654.9 13659.4 13662.5 13673.7 6198.2 6387.9 6395.2 6397.0 6430.7 5585.5 5617.3 5790.6 5800.2 5813.7 4193.0 4229.6 4243.5 4439.9 4462.5 1712.2 1766.5 1767.4 1818.6 1841.0 768.2 791.2 803.5 843.6 871.5 16224.2 16707.6 16993.9 17504.6 17634.7 815.8 845.9 861.8 899.1 900.1 9251.2 9260.6 9334.9 9993.7 10076.9 354618.0 354949.0 355011.0 355341.0 395633.0 79677.6 81326.0 82559.6 84134.0 87886.6 891.2 901.5 936.5 940.5 944.8 14861.1 15219.3 16225.4 16402.0 16550.0 37312.0 38153.0 38599.0 38973.6 39037.5 46889.8 47203.0 48589.0 51126.5 52287.5 7219.7 7483.2 7648.2 7678.3 7801.2 10909.3 12238.4 12324.4 12642.7 12795.6 10537.4 10600.8 10746.4 10798.5 10898.5 5829.5 6381.3 10350.3 10533.4 10656.3 10358.7 10415.8 10541.5 10631.3 11013.2 7167.7 7394.4 7469.4 7493.5 7790.8 8616.9 10048.7 10242.8 10411.0 10540.1 21:40:07 INFO - PROCESS | 25563 | 21:40:07 INFO - PROCESS | 25563 | [#3] mootools.html Cycles:180 Average:5021.50 Median:5151.88 stddev:2885.49 (56.0%) stddev-sans-first:2879.68 21:40:07 INFO - PROCESS | 25563 | Values: 1252.0 1334.6 1382.5 1413.8 1663.7 6549.4 7379.3 7654.9 7662.1 7912.9 7815.6 7867.9 7872.6 7873.0 7876.3 2143.2 2167.2 2169.4 2170.1 2171.8 2016.3 2068.6 2080.7 2086.4 2090.7 2099.5 2142.0 2142.4 2148.0 2151.8 2069.7 2084.0 2091.4 2092.3 2094.6 2020.4 2078.7 2083.5 2085.1 2087.2 7594.6 7804.8 7885.8 7913.5 7949.7 4168.2 4326.1 4332.0 4337.3 4339.9 2684.9 2808.7 2861.2 2861.4 2862.6 898.6 901.7 919.2 928.4 934.9 10555.0 10658.3 10665.6 10673.9 10677.5 3756.1 3828.2 3891.1 3955.8 3982.0 8365.5 8374.7 8380.2 8383.3 8389.1 4178.3 4199.8 4201.3 4205.0 4211.1 3417.6 3730.4 3886.8 3903.8 3904.2 3317.0 3426.1 3451.2 3461.5 3561.0 969.8 1006.7 1011.0 1019.0 1022.3 762.6 783.9 808.8 810.1 815.7 4722.2 4726.3 4816.3 4951.7 5116.0 381.3 395.8 883.6 969.4 990.2 3315.5 3464.3 3507.8 3537.1 3582.7 9358.4 9722.2 9735.2 9743.6 9744.3 9527.7 9613.0 9614.2 9616.1 9622.2 6943.8 7195.8 7204.4 7375.0 7384.3 5187.8 5308.3 5315.1 5341.1 5508.1 8555.7 8650.0 8760.2 8769.2 8914.1 8677.2 9089.0 9114.3 9120.9 9198.4 5935.3 6501.9 6702.9 6772.1 6813.7 7431.3 7766.0 7771.0 7773.8 7776.1 6612.5 6625.3 6728.5 6787.7 6901.2 6438.6 6568.5 6705.6 6742.3 6850.2 5859.4 6156.8 6172.2 6178.7 6184.6 4549.0 4565.1 4567.7 4587.9 4593.2 4493.8 4548.3 4598.1 4609.7 4671.1 21:40:07 INFO - PROCESS | 25563 | 21:40:07 INFO - PROCESS | 25563 | [#4] prototype.html Cycles:180 Average:4156.78 Median:4780.83 stddev:1190.85 (24.9%) stddev-sans-first:1158.46 21:40:07 INFO - PROCESS | 25563 | Values: 299.3 302.7 305.6 313.3 313.9 4774.9 4782.7 4799.3 4838.2 4864.1 4685.8 4701.7 4702.9 4703.7 4792.9 3596.9 3706.9 3712.7 3744.1 3772.0 3761.8 3762.5 3774.9 3775.3 3778.6 3729.3 3730.4 3733.9 3738.2 3792.1 3571.9 3626.2 3738.5 3755.8 3781.4 3315.9 3321.7 3323.6 3336.2 3341.7 4615.0 4617.7 4625.4 4627.5 4684.7 4111.5 4241.7 4251.3 4254.3 4255.0 4859.9 4901.4 4924.7 4987.1 5012.9 3656.9 3673.9 3677.5 3681.7 3685.3 4971.6 5137.0 5212.5 5231.9 5242.2 3650.4 3675.5 3694.1 3713.3 3730.0 3756.2 3763.2 3769.2 3771.8 3821.4 4206.4 4216.7 4229.9 4236.6 4238.5 4794.9 4896.1 4901.4 4920.2 4936.4 1297.6 1311.6 1313.1 1313.8 1313.8 817.5 844.5 846.0 850.2 870.4 4339.4 4436.6 4443.9 4445.1 4445.8 5036.6 5054.2 5101.6 5104.2 5115.9 4382.8 4410.4 4431.7 4463.9 4468.0 2578.5 2695.0 2716.2 2724.5 2728.8 4844.7 4890.3 4891.2 4908.9 4909.2 5025.4 5183.7 5191.8 5296.9 5314.0 4876.6 4880.2 4886.9 4887.8 4889.9 5134.3 5137.5 5139.6 5143.4 5233.2 4880.6 5021.6 5022.5 5028.6 5044.8 4859.5 4867.3 4888.8 4893.9 4934.8 3746.8 3752.2 3753.0 3760.8 3811.1 4821.7 5078.0 5081.7 5083.8 5092.0 4952.0 4961.4 4977.5 4979.2 4983.2 4972.0 4981.3 5053.5 5062.1 5066.2 4779.0 4913.8 4937.4 4969.8 4990.0 4800.6 4910.1 5014.7 5041.3 5053.6 4863.8 4863.8 4874.5 4877.4 4886.2 21:40:07 INFO - PROCESS | 25563 | 21:40:07 INFO - PROCESS | 25563 | [#5] yui.html Cycles:180 Average:16760.60 Median:471.07 stddev:23931.46 (5080.2%) stddev-sans-first:23967.48 21:40:07 INFO - PROCESS | 25563 | Values: 508.6 522.6 538.0 538.3 615.1 12653.3 13756.2 13851.2 13867.8 13873.9 11814.5 12245.6 12431.4 13169.9 13313.9 632.3 764.4 783.5 785.6 787.6 757.1 767.7 776.2 788.0 789.5 822.3 822.5 823.1 823.2 826.7 659.5 814.9 815.0 816.2 816.7 579.9 585.5 591.0 601.6 601.7 13144.6 13168.5 13172.4 13198.0 13225.1 11764.0 12235.6 12279.2 12297.5 12729.5 11905.5 12081.6 12098.1 12178.3 12803.2 4046.7 4076.6 4109.5 4247.5 4255.3 64088.0 64213.4 64651.7 64736.1 64923.0 10069.1 10078.7 10472.9 10666.1 10852.2 19197.1 19257.6 19277.1 19348.3 19419.5 6493.5 6600.9 6633.6 6659.4 6776.8 3645.3 3680.5 3733.2 3740.2 3775.5 2687.7 2791.5 2792.7 2794.1 2795.1 492.5 502.1 504.1 509.6 515.8 397.1 449.6 513.9 517.7 523.9 7137.0 7140.7 7210.6 7388.8 7670.4 416.7 434.8 495.5 506.4 515.5 3349.2 3387.8 3435.3 3454.6 3473.4 56107.7 58624.0 71202.9 109315.5 110454.4 51767.5 51793.2 73566.0 92660.5 94397.0 77986.6 78810.0 79306.0 80079.6 85206.6 6665.7 6817.4 6863.0 6979.7 7023.0 30374.5 33227.5 38085.4 38191.6 38746.8 59091.0 59605.4 59780.0 60256.0 60998.0 10428.5 10521.7 10524.1 10552.9 10916.5 12359.9 12655.4 12655.7 12828.3 12828.7 10996.7 11033.3 11303.4 11530.9 11768.9 10611.7 10702.0 10754.5 10763.5 11101.4 10332.7 10899.7 11101.6 11161.3 11356.3 9025.4 9225.4 9286.5 9447.5 9492.0 8793.8 8950.2 9181.7 9254.4 9435.2 21:40:07 INFO - PROCESS | 25563 | -------- Summary: end -------- 21:40:07 INFO - PROCESS | 25563 | 21:40:08 INFO - TEST-INFO | 25563: exit 0 21:40:08 INFO - TEST-OK | dromaeo_css | took 1120511ms 21:40:08 INFO - TEST-START | kraken 21:40:08 INFO - operating with platform_type : linux_ 21:40:08 INFO - Initialising browser for kraken test... 21:40:08 INFO - TEST-INFO | started process 25865 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWWpFAD/profile http://localhost:47009/getInfo.html) 21:40:12 INFO - PROCESS | 25865 | __metrics Screen width/height:1600/1200 21:40:12 INFO - PROCESS | 25865 | colorDepth:24 21:40:12 INFO - PROCESS | 25865 | Browser inner width/height: 1024/697 21:40:12 INFO - PROCESS | 25865 | __metrics 21:40:13 INFO - PROCESS | 25865 | 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" 21:40:13 INFO - PROCESS | 25865 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:40:14 INFO - TEST-INFO | 25865: exit 0 21:40:14 INFO - Browser initialized. 21:40:14 INFO - Running cycle 1/1 for kraken test... 21:40:14 INFO - TEST-INFO | started process 25945 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWWpFAD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:40:26 INFO - PROCESS | 25945 | RSS: Main: 157401088 21:40:26 INFO - PROCESS | 25945 | 21:40:29 INFO - PROCESS | 25945 | Cycle 1(1): loaded http://localhost:47009/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47009/tests/kraken/driver.html?testName=audio-beat-detection) 21:40:30 INFO - PROCESS | 25945 | RSS: Main: 205283328 21:40:30 INFO - PROCESS | 25945 | 21:40:32 INFO - PROCESS | 25945 | Cycle 1(1): loaded http://localhost:47009/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47009/tests/kraken/driver.html?testName=audio-dft) 21:40:33 INFO - PROCESS | 25945 | RSS: Main: 196599808 21:40:33 INFO - PROCESS | 25945 | 21:40:36 INFO - PROCESS | 25945 | Cycle 1(1): loaded http://localhost:47009/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47009/tests/kraken/driver.html?testName=audio-fft) 21:40:37 INFO - PROCESS | 25945 | RSS: Main: 276963328 21:40:37 INFO - PROCESS | 25945 | 21:40:39 INFO - PROCESS | 25945 | Cycle 1(1): loaded http://localhost:47009/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47009/tests/kraken/driver.html?testName=audio-oscillator) 21:40:40 INFO - PROCESS | 25945 | RSS: Main: 187301888 21:40:40 INFO - PROCESS | 25945 | 21:40:42 INFO - PROCESS | 25945 | Cycle 1(1): loaded http://localhost:47009/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47009/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:40:43 INFO - PROCESS | 25945 | RSS: Main: 191807488 21:40:43 INFO - PROCESS | 25945 | 21:40:46 INFO - PROCESS | 25945 | Cycle 1(1): loaded http://localhost:47009/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47009/tests/kraken/driver.html?testName=imaging-darkroom) 21:40:47 INFO - PROCESS | 25945 | RSS: Main: 228339712 21:40:47 INFO - PROCESS | 25945 | 21:40:50 INFO - PROCESS | 25945 | Cycle 1(1): loaded http://localhost:47009/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47009/tests/kraken/driver.html?testName=imaging-desaturate) 21:40:51 INFO - PROCESS | 25945 | RSS: Main: 221659136 21:40:51 INFO - PROCESS | 25945 | 21:40:54 INFO - PROCESS | 25945 | Cycle 1(1): loaded http://localhost:47009/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47009/tests/kraken/driver.html?testName=json-parse-financial) 21:40:54 INFO - PROCESS | 25945 | RSS: Main: 225525760 21:40:54 INFO - PROCESS | 25945 | 21:40:56 INFO - PROCESS | 25945 | Cycle 1(1): loaded http://localhost:47009/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47009/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:40:56 INFO - PROCESS | 25945 | RSS: Main: 189403136 21:40:56 INFO - PROCESS | 25945 | 21:40:58 INFO - PROCESS | 25945 | Cycle 1(1): loaded http://localhost:47009/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47009/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:40:59 INFO - PROCESS | 25945 | RSS: Main: 196329472 21:40:59 INFO - PROCESS | 25945 | 21:41:01 INFO - PROCESS | 25945 | Cycle 1(1): loaded http://localhost:47009/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47009/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:41:02 INFO - PROCESS | 25945 | RSS: Main: 192942080 21:41:02 INFO - PROCESS | 25945 | 21:41:05 INFO - PROCESS | 25945 | Cycle 1(1): loaded http://localhost:47009/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47009/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:41:05 INFO - PROCESS | 25945 | RSS: Main: 188678144 21:41:05 INFO - PROCESS | 25945 | 21:41:08 INFO - PROCESS | 25945 | Cycle 1(1): loaded http://localhost:47009/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47009/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:41:09 INFO - PROCESS | 25945 | RSS: Main: 191709184 21:41:09 INFO - PROCESS | 25945 | 21:41:11 INFO - PROCESS | 25945 | Cycle 1(1): loaded http://localhost:47009/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47009/tests/kraken/driver.html?testName=ai-astar) 21:41:11 INFO - PROCESS | 25945 | RSS: Main: 242888704 21:41:11 INFO - PROCESS | 25945 | 21:41:11 INFO - PROCESS | 25945 | __start_tp_report 21:41:11 INFO - PROCESS | 25945 | _x_x_mozilla_page_load 21:41:11 INFO - PROCESS | 25945 | _x_x_mozilla_page_load_details 21:41:11 INFO - PROCESS | 25945 | |i|pagename|runs| 21:41:11 INFO - PROCESS | 25945 | |0;ai-astar;105;115;114;115;110;114;115;115;115;116 21:41:11 INFO - PROCESS | 25945 | |1;audio-beat-detection;151;123;123;188;127;126;149;122;164;128 21:41:11 INFO - PROCESS | 25945 | |2;audio-dft;160;161;161;160;161;163;161;163;161;159 21:41:11 INFO - PROCESS | 25945 | |3;audio-fft;77;77;75;107;77;77;76;77;130;75 21:41:11 INFO - PROCESS | 25945 | |4;audio-oscillator;86;87;133;87;87;87;120;86;86;127 21:41:11 INFO - PROCESS | 25945 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 21:41:11 INFO - PROCESS | 25945 | |6;imaging-darkroom;147;146;146;146;146;146;145;145;146;147 21:41:11 INFO - PROCESS | 25945 | |7;imaging-desaturate;95;96;95;94;95;95;95;95;95;95 21:41:11 INFO - PROCESS | 25945 | |8;json-parse-financial;64;63;65;63;63;66;64;64;66;64 21:41:11 INFO - PROCESS | 25945 | |9;json-stringify-tinderbox;65;66;65;66;66;66;66;66;69;65 21:41:11 INFO - PROCESS | 25945 | |10;stanford-crypto-aes;84;83;81;82;82;82;82;91;82;85 21:41:11 INFO - PROCESS | 25945 | |11;stanford-crypto-ccm;138;144;143;131;134;141;133;154;147;147 21:41:11 INFO - PROCESS | 25945 | |12;stanford-crypto-pbkdf2;175;170;173;170;170;175;177;173;171;169 21:41:11 INFO - PROCESS | 25945 | |13;stanford-crypto-sha256-iterative;67;78;69;69;70;68;67;72;68;66 21:41:11 INFO - PROCESS | 25945 | __end_tp_report 21:41:11 INFO - PROCESS | 25945 | __start_cc_report 21:41:11 INFO - PROCESS | 25945 | _x_x_mozilla_cycle_collect,1952 21:41:11 INFO - PROCESS | 25945 | __end_cc_report 21:41:11 INFO - PROCESS | 25945 | __startTimestamp1471408871378__endTimestamp 21:41:11 INFO - PROCESS | 25945 | 21:41:11 INFO - PROCESS | 25945 | ------- Summary: start ------- 21:41:11 INFO - PROCESS | 25945 | Number of tests: 14 21:41:11 INFO - PROCESS | 25945 | 21:41:11 INFO - PROCESS | 25945 | [#0] ai-astar Cycles:10 Average:113.40 Median:115.00 stddev:3.37 (2.9%) stddev-sans-first:1.73 21:41:11 INFO - PROCESS | 25945 | Values: 105.0 115.0 114.0 115.0 110.0 114.0 115.0 115.0 115.0 116.0 21:41:11 INFO - PROCESS | 25945 | 21:41:11 INFO - PROCESS | 25945 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:138.50 stddev:22.34 (16.1%) stddev-sans-first:23.35 21:41:11 INFO - PROCESS | 25945 | Values: 151.0 123.0 123.0 188.0 127.0 126.0 149.0 122.0 164.0 128.0 21:41:11 INFO - PROCESS | 25945 | 21:41:11 INFO - PROCESS | 25945 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.25 (0.8%) stddev-sans-first:1.27 21:41:11 INFO - PROCESS | 25945 | Values: 160.0 161.0 161.0 160.0 161.0 163.0 161.0 163.0 161.0 159.0 21:41:11 INFO - PROCESS | 25945 | 21:41:11 INFO - PROCESS | 25945 | [#3] audio-fft Cycles:10 Average:84.80 Median:77.00 stddev:18.59 (24.1%) stddev-sans-first:19.50 21:41:11 INFO - PROCESS | 25945 | Values: 77.0 77.0 75.0 107.0 77.0 77.0 76.0 77.0 130.0 75.0 21:41:11 INFO - PROCESS | 25945 | 21:41:11 INFO - PROCESS | 25945 | [#4] audio-oscillator Cycles:10 Average:98.60 Median:86.50 stddev:19.61 (22.7%) stddev-sans-first:20.27 21:41:11 INFO - PROCESS | 25945 | Values: 86.0 87.0 133.0 87.0 87.0 87.0 120.0 86.0 86.0 127.0 21:41:11 INFO - PROCESS | 25945 | 21:41:11 INFO - PROCESS | 25945 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 21:41:11 INFO - PROCESS | 25945 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 21:41:11 INFO - PROCESS | 25945 | 21:41:11 INFO - PROCESS | 25945 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.60 21:41:11 INFO - PROCESS | 25945 | Values: 147.0 146.0 146.0 146.0 146.0 146.0 145.0 145.0 146.0 147.0 21:41:11 INFO - PROCESS | 25945 | 21:41:11 INFO - PROCESS | 25945 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 21:41:11 INFO - PROCESS | 25945 | Values: 95.0 96.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 21:41:11 INFO - PROCESS | 25945 | 21:41:11 INFO - PROCESS | 25945 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:64.00 stddev:1.14 (1.8%) stddev-sans-first:1.20 21:41:11 INFO - PROCESS | 25945 | Values: 64.0 63.0 65.0 63.0 63.0 66.0 64.0 64.0 66.0 64.0 21:41:11 INFO - PROCESS | 25945 | 21:41:11 INFO - PROCESS | 25945 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:66.00 stddev:1.15 (1.7%) stddev-sans-first:1.17 21:41:11 INFO - PROCESS | 25945 | Values: 65.0 66.0 65.0 66.0 66.0 66.0 66.0 66.0 69.0 65.0 21:41:11 INFO - PROCESS | 25945 | 21:41:11 INFO - PROCESS | 25945 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:82.50 stddev:2.91 (3.5%) stddev-sans-first:3.08 21:41:11 INFO - PROCESS | 25945 | Values: 84.0 83.0 81.0 82.0 82.0 82.0 82.0 91.0 82.0 85.0 21:41:11 INFO - PROCESS | 25945 | 21:41:11 INFO - PROCESS | 25945 | [#11] stanford-crypto-ccm Cycles:10 Average:141.20 Median:143.50 stddev:7.27 (5.1%) stddev-sans-first:7.62 21:41:11 INFO - PROCESS | 25945 | Values: 138.0 144.0 143.0 131.0 134.0 141.0 133.0 154.0 147.0 147.0 21:41:11 INFO - PROCESS | 25945 | 21:41:11 INFO - PROCESS | 25945 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.30 Median:173.00 stddev:2.71 (1.6%) stddev-sans-first:2.69 21:41:11 INFO - PROCESS | 25945 | Values: 175.0 170.0 173.0 170.0 170.0 175.0 177.0 173.0 171.0 169.0 21:41:11 INFO - PROCESS | 25945 | 21:41:11 INFO - PROCESS | 25945 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:69.00 stddev:3.47 (5.0%) stddev-sans-first:3.57 21:41:11 INFO - PROCESS | 25945 | Values: 67.0 78.0 69.0 69.0 70.0 68.0 67.0 72.0 68.0 66.0 21:41:11 INFO - PROCESS | 25945 | -------- Summary: end -------- 21:41:11 INFO - PROCESS | 25945 | 21:41:12 INFO - TEST-INFO | 25945: exit 0 21:41:12 INFO - TEST-OK | kraken | took 64138ms 21:41:12 INFO - SUITE-END | took 1184s 21:41:13 INFO - Completed test suite (00:19:45) 21:41:13 INFO - javascript benchmark 21:41:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2574.909878154265, 2578.097665816964, 2600.3759097816524, 2614.986925065374, 2624.37015116372, 4501.504834468205, 4591.517890621479, 4600.102979918917, 4637.304404339347, 4657.278121891112, 4522.29904364824, 4540.97729511352, 4566.086782643468, 4600.309953506967, 4631.467381251157, 4164.0, 4204.957950420502, 4210.515790684065, 4216.789160541976, 4220.746755194683, 4232.0, 4234.407182994383, 4248.107897341562, 4276.037891474424, 4282.31482962726, 4244.97877510612, 4252.14957008598, 4255.5957184067465, 4269.146170765844, 4275.572442755731, 4143.97928010361, 4168.0205151789305, 4198.391233271159, 4213.073123912734, 4267.850625228133, 4146.3365861462025, 4181.811818468146, 4201.663866890642, 4224.239636865363, 4235.174141042498, 4664.300354946785, 4668.906621867578, 4676.415448068991, 4676.649251306166, 4677.415323084614, 4824.179889418772, 4841.515848415165, 4994.600431965434, 4998.40019197695, 5000.524950129732, 4894.608431325485, 4903.092927808367, 4935.185694360426, 4951.925721114186, 4972.5276098770555, 4628.791704373311, 4652.837150699743, 4653.208954477747, 4670.95329046712, 4672.065586882637, 4939.3084968104495, 4970.15507363749, 5077.365329333833, 5077.568406685399, 5081.415637201695, 4773.04539092176, 4857.1742803723455, 4871.244957031666, 4874.975625121851, 4878.463369029404, 4903.41159060915, 4907.509249075135, 4934.1611925972675, 4938.456769755324, 4951.678140920829, 4761.071591039714, 4806.519348065235, 4854.514548545118, 4900.926486102711, 4960.255961605716, 4828.179209534387, 4853.611711063107, 4869.1722407190155, 4937.21004639256, 4986.775595098229, 4305.590968857953, 4318.179545886272, 4319.827206911688, 4320.481542214891, 4356.390105385249, 3793.317202903504, 3798.487204227449, 3798.639129282769, 3815.2178803345246, 3816.6183381661613, 4172.958270417257, 4265.957340426556, 4281.464816897887, 4290.849820256276, 4353.194658988034, 4942.061008408391, 4943.134951383494, 4943.703377797344, 4947.876303092451, 4996.800127994912, 4311.849085282063, 4319.892002699958, 4323.956760432356, 4327.437433133672, 4364.388985542027, 4427.977860110679, 4497.347884556721, 4531.750753708512, 4550.431196100488, 4600.125976064537, 4708.6468514860835, 4721.268203428543, 4726.19654658702, 4726.6455015874635, 4785.162596545661, 4955.55400013986, 4983.875403114806, 4990.750462476934, 4992.276119962513, 5039.0929632666475, 4601.4938356781395, 4669.0428462165855, 4682.836100736458, 4715.764211789466, 4776.8566942991765, 4869.0, 4904.92642610354, 4907.190313598217, 4908.067467181224, 4920.852374428773, 4973.303737476684, 4974.900501990012, 4981.850544483671, 4995.125852975642, 5022.096022715946, 4699.271612900006, 4773.761311934459, 4824.589909857701, 4846.854594362175, 4916.70499770015, 4850.7817148227705, 4880.194767863405, 4881.9511804881495, 4883.68256063369, 4927.039227350632, 4804.039192161652, 4822.9758851205515, 4851.733154676526, 4858.854234372974, 4867.780949857194, 4740.407449068866, 4795.184818580919, 4802.855914322576, 4843.491433399442, 4866.732329721899, 4706.223473126895, 4737.170995075779, 4752.572268495852, 4761.857144285677, 4766.332713420194], "value": 4553.689034883324, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.9058360078786, 568.2839622076183, 570.3156212544944, 575.0081110085102, 579.8637320229748, 29124.27189320268, 40145.0, 41312.79343603285, 41418.37872431908, 45400.54599454004, 40563.59436405635, 41055.794721026425, 41083.0, 41106.58893411065, 41118.38322425166, 7426.071741032371, 7627.923720762805, 7769.728059517918, 7777.805554861117, 7778.988731464901, 7400.962995185017, 7680.0, 7682.193369696185, 9727.221822254203, 9957.502124893763, 10046.34698744583, 10102.242331825106, 10109.494525273743, 10113.292069555135, 10115.0, 9959.302848800551, 9988.95005524975, 9994.150497207746, 10023.148032417253, 10034.0, 9860.0, 9931.404115753077, 9932.652357167537, 9933.701988940344, 9934.701958941243, 39663.60336396628, 39940.60059399398, 40003.7999810002, 40060.79969600133, 40069.79965100185, 2852.229897927548, 2861.756750676195, 2901.3036871150985, 2977.1034044601856, 3217.3082787200865, 29381.26546836325, 30065.699343006727, 30162.69837301621, 30290.697093029008, 30338.54492182619, 3529.7737808085913, 3534.628863969272, 3570.285942811448, 3609.2961872434885, 3658.506101676293, 180031.0, 181223.0, 182001.0899945505, 182442.0, 182568.0, 53648.0, 53818.46181538134, 53966.73016634892, 54045.72977135089, 54116.917661647334, 32568.18579535483, 33372.0, 56325.43674563284, 56389.15416268677, 56503.15245271324, 10762.3542587445, 10889.346639201673, 10895.346279223271, 10897.618583349546, 10903.890961090447, 8260.710875119343, 8268.0, 8268.793280168044, 8268.917310826815, 8284.130166332568, 15980.840191597936, 17685.823141768677, 18082.276708931495, 18144.45566633003, 18696.0, 6652.534322597372, 6775.0, 6974.721011159497, 7059.505834591632, 7148.714051437988, 795.5107608820533, 856.5117882806741, 938.1416004355938, 963.7012526116927, 1002.5187909803335, 27522.587161192598, 28541.286467838054, 28813.42373152525, 29192.708072919424, 29426.264343391587, 950.7908260182749, 969.9515024248759, 972.3436680240809, 991.3308516751164, 996.4917891875195, 14811.925940370229, 15336.463223787114, 15400.30698618565, 15534.634823109836, 15585.532434026998, 86432.56783716167, 87245.1275487237, 87550.0, 87639.56180219058, 87810.0, 49773.75113124411, 50042.49957500451, 50050.24924626134, 50138.0, 50181.74909125504, 1081.5998080710187, 1084.8535447714457, 1135.0238794636605, 1179.33367647279, 1184.3782014442486, 26460.0, 28837.567436488887, 30246.697533025268, 30798.538021929242, 30926.381472369976, 43809.56190438055, 44633.33050004317, 44729.55270447384, 44790.3281450785, 44798.552014479435, 335559.0, 337807.0, 340272.0, 341182.0, 341755.0, 38171.42742858804, 39143.10711161105, 39793.0, 39912.80043599763, 41644.375334369404, 40590.79704601458, 40617.0, 40734.59265407308, 40953.79523102365, 42247.788761056, 34573.65426345704, 34666.65333346735, 34879.82560087183, 35268.29463410769, 35801.46297805587, 34742.82628586841, 34788.82605586956, 34891.47662785111, 35232.0, 35341.29317413689, 32646.0, 32803.375729942025, 32804.179895502806, 32969.835150824096, 39330.410043848795, 29285.70714292915, 31120.221994449414, 34776.30447391089, 34911.0, 35071.0, 25256.873715631304, 25980.740192598587, 26168.34579135537, 26174.34564135912, 26453.866227438724], "value": 16999.40276645022, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.5176752546436, 360.19676122247387, 361.5300109857186, 363.55283001907645, 380.220587505336, 12729.0, 15970.041797492158, 16336.346546138155, 16356.0, 17547.94712317262, 14667.633309167277, 15370.615734606641, 15425.997310174831, 15447.0, 15565.066096034247, 4667.346571479996, 4923.769057735566, 5006.324146240257, 5123.615728820335, 5143.614228932826, 5023.648344615878, 5060.418051924039, 5085.1863701807715, 5097.7196254206, 5101.081805275049, 4941.160002799515, 5066.037452883958, 5069.036882992238, 5071.416787069479, 5175.171972484403, 5344.812931547397, 5362.839114826562, 5368.1679339702405, 5371.221172929942, 5379.139337705968, 5107.13178759608, 5221.973890130563, 5234.895302093975, 5247.1866860636665, 5292.364916210079, 11895.881041189565, 12110.757784844254, 12117.757644847143, 12130.818037729441, 16640.583985400342, 1791.9014454205014, 1815.7911840138422, 1863.0684657671165, 1869.0281053852057, 1873.653374125791, 8707.869381959275, 8757.562121893943, 8820.117988201193, 9251.90748092524, 9497.810043799154, 1690.2478397113289, 1726.231826837058, 1743.6425532765752, 1784.5538615346163, 1809.6742586334456, 68065.0, 68311.65844170797, 69516.30483695149, 70878.0, 72299.0, 9458.668946586837, 9719.368241064309, 9878.259130565093, 9935.254855885838, 10120.443375614412, 13634.863651363557, 13654.863451365558, 13659.385327660279, 13662.453501859814, 13673.658158546115, 6198.194234749544, 6387.872242555123, 6395.232572091379, 6397.040443933354, 6430.678466076677, 5585.525230355384, 5617.326500234793, 5790.565707571949, 5800.216970708901, 5813.680247586339, 4193.0, 4229.619334259932, 4243.469566304212, 4439.911201775946, 4462.478699534752, 1712.212382304154, 1766.5142085926216, 1767.381416504239, 1818.563334965389, 1840.9539761506069, 768.2317682317682, 791.2087912087912, 803.4697099914146, 843.5655637346771, 871.5206636349982, 16224.188790560425, 16707.582310442336, 16993.91503042477, 17504.64990700179, 17634.735478967825, 815.8327542853701, 845.9280961118374, 861.8233262181237, 899.0515006667897, 900.0729248873671, 9251.213646839959, 9260.583273752698, 9334.859977100348, 9993.700188994342, 10076.899231007743, 354618.0, 354949.0, 355011.0, 355341.0, 395633.0, 79677.60161199157, 81326.0, 82559.5872020636, 84134.0, 87886.56056719675, 891.2379915172435, 901.5176880368971, 936.5410948635256, 940.4592359393239, 944.8157609266265, 14861.121472087529, 15219.315130818919, 16225.43210987657, 16402.015879047296, 16550.0, 37312.0, 38153.0, 38599.0, 38973.610263897, 39037.510686271446, 46889.76555117339, 47203.0, 48589.0, 51126.48873511217, 52287.47712522826, 7219.6751146199595, 7483.176850546544, 7648.196939321291, 7678.308952194329, 7801.2198780121535, 10909.290896091728, 12238.388080595756, 12324.383780811102, 12642.747145056863, 12795.552155674506, 10537.420441875463, 10600.770167002485, 10746.355218686904, 10798.460076996276, 10898.509567069343, 5829.53363730892, 6381.266154392304, 10350.327228730108, 10533.36799792015, 10656.34826601878, 10358.689239322832, 10415.84376234342, 10541.525631346454, 10631.255812093079, 11013.174011948975, 7167.668137264787, 7394.408447324308, 7469.439792015729, 7493.475456717977, 7790.805229869298, 8616.913830861611, 10048.69853904384, 10242.846357304497, 10411.0, 10540.104091152338], "value": 7896.858200329575, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1252.0171665242785, 1334.55959533354, 1382.5092092307232, 1413.7667284897993, 1663.6672665466901, 6549.377809108139, 7379.335859772619, 7654.88517672234, 7662.118856331516, 7912.92087079129, 7815.570143642111, 7867.859160421735, 7872.606369681522, 7872.9606351968305, 7876.251756083163, 2143.153454385517, 2167.1548096242477, 2169.3600387885613, 2170.131947221116, 2171.815395691368, 2016.294296996047, 2068.606964676714, 2080.6878968154747, 2086.3636590839787, 2090.7177531033267, 2099.517111064456, 2141.9785802142014, 2142.4215461825297, 2147.9892600537055, 2151.752548456932, 2069.6998935154393, 2083.958320833575, 2091.44576687178, 2092.2886218685567, 2094.570613024334, 2020.3736841579157, 2078.740157480315, 2083.4687154775434, 2085.1450905128977, 2087.165133946434, 7594.620268986529, 7804.843903121962, 7885.8028549286155, 7913.525188488709, 7949.7217597383815, 4168.187203495319, 4326.134773045372, 4332.0, 4337.349397590355, 4339.891502712458, 2684.9060282890005, 2808.7191280871953, 2861.241770930705, 2861.399106187703, 2862.613547171126, 898.5507246376811, 901.6753968571309, 919.1543779722605, 928.4058202750246, 934.8831396075491, 10554.997275258838, 10658.307210031324, 10665.626703065356, 10673.893261067367, 10677.519511622073, 3756.098536351256, 3828.157805282833, 3891.0661441253897, 3955.841766329372, 3982.0, 8365.456245344154, 8374.665013399395, 8380.16198380157, 8383.329333653415, 8389.119142490074, 4178.268802959531, 4199.832006719758, 4201.285781417167, 4204.957950420518, 4211.052513184509, 3417.5557177567252, 3730.3844865597416, 3886.825092870771, 3903.8048097595574, 3904.2386734587053, 3316.9834150829092, 3426.0920855973186, 3451.2062225688233, 3461.532693086401, 3560.964390356115, 969.7769513012047, 1006.6979906028308, 1010.9747256318651, 1019.0370100255187, 1022.2997246885907, 762.6034462079688, 783.925527074927, 808.7614153824636, 810.1250649298743, 815.657423881971, 4722.1972264714395, 4726.291056341576, 4816.277558366203, 4951.653384263067, 5115.97442012795, 381.26796550622316, 395.8317714971126, 883.5979629268796, 969.3844408800321, 990.2375171118342, 3315.5026745988293, 3464.2898205867737, 3507.7895326280504, 3537.080359106599, 3582.6775590196485, 9358.391704539183, 9722.173615242642, 9735.172510336559, 9743.61025558984, 9744.31789774723, 9527.714168574954, 9612.951935240279, 9614.230861531201, 9616.134547890584, 9622.182114520205, 6943.826404339932, 7195.784126476214, 7204.351608355273, 7375.045612912582, 7384.335409813035, 5187.792488300426, 5308.336457942757, 5315.0964336063735, 5341.145416733304, 5508.118701007819, 8555.700550480953, 8650.0, 8760.211580957744, 8769.166929141722, 8914.064023277462, 8677.1756683115, 9089.0, 9114.316426267924, 9120.908790912006, 9198.448093114435, 5935.287765467999, 6501.869962600627, 6702.932970670426, 6772.11962444879, 6813.693383797642, 7431.256874312742, 7766.029246344207, 7770.961145194238, 7773.766786996399, 7776.105747839071, 6612.50406219545, 6625.271220165874, 6728.529002969745, 6787.728490860311, 6901.24086350491, 6438.581492202992, 6568.474522038321, 6705.597664139972, 6742.292059333896, 6850.177978642595, 5859.44335288147, 6156.846078847885, 6172.22847144107, 6178.69106544662, 6184.5980011299125, 4548.977255113703, 4565.06416184688, 4567.748773817472, 4587.8623641290815, 4593.242115050979, 4493.77531123436, 4548.272276435754, 4598.149342371672, 4609.723416595015, 4671.065786842577], "value": 3994.358462999609, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.29665286576545, 302.6564848896502, 305.625608629429, 313.2888343460344, 313.89798315547455, 4774.856754297368, 4782.665213435061, 4799.328094066834, 4838.153323168449, 4864.100141473826, 4685.83599574015, 4701.694389864657, 4702.929456058153, 4703.717776933387, 4792.9281060784115, 3596.856125754973, 3706.870259540917, 3712.6844218241345, 3744.120131769032, 3771.9622803771886, 3761.755485893422, 3762.457392469088, 3774.9433758493647, 3775.2826962876966, 3778.641029102245, 3729.3287208302495, 3730.4404339349044, 3733.8903519511523, 3738.1962877981227, 3792.089898424372, 3571.910702232452, 3626.165981824195, 3738.4579236010763, 3755.8497660093562, 3781.3949768037255, 3315.900522984314, 3321.684439978198, 3323.6177839548277, 3336.249343897628, 3341.6992470677515, 4614.976925115387, 4617.660878345276, 4625.444946606429, 4627.537246275379, 4684.672072954894, 4111.465509483778, 4241.660667146652, 4251.298535741599, 4254.340577210537, 4254.957450425487, 4859.913954638794, 4901.436334821506, 4924.655274130812, 4987.127252730757, 5012.874678133076, 3656.872009479682, 3673.9265214695556, 3677.485152078711, 3681.668649821529, 3685.299793039341, 4971.602271818246, 5136.9743151284, 5212.50481204285, 5231.921521177185, 5242.23987521811, 3650.4159334506353, 3675.5038069860225, 3694.0949467380665, 3713.3475603356246, 3729.9627003729615, 3756.192418629953, 3763.1532905096133, 3769.246150769802, 3771.792551409644, 3821.3885778275344, 4206.3595197872855, 4216.683748718859, 4229.851955181553, 4236.597523235288, 4238.470191226097, 4794.904101917942, 4896.094222568836, 4901.411830580353, 4920.163572192736, 4936.358273424432, 1297.6172029251393, 1311.6065180445828, 1313.0677219174302, 1313.750387426405, 1313.7766579681477, 817.5176645779019, 844.5312851367427, 845.9830803383898, 850.171083193881, 870.3776799588325, 4339.370791235253, 4436.646822719102, 4443.866943866962, 4445.133199026159, 4445.844395446144, 5036.571891389273, 5054.241863720471, 5101.591872650179, 5104.1578139606545, 5115.897682046412, 4382.83854778484, 4410.3825464435, 4431.7340959542535, 4463.888402789891, 4468.039371535136, 2578.510083084208, 2695.029789275859, 2716.2122984334715, 2724.523208438515, 2728.7816974641983, 4844.733539655283, 4890.315355850184, 4891.217405215149, 4908.852734417973, 4909.239067944474, 5025.447200807908, 5183.740812959337, 5191.844244672666, 5296.861174847426, 5313.973430132824, 4876.5854902332985, 4880.243562247857, 4886.902261954741, 4887.804487820447, 4889.894916727832, 5134.255532947776, 5137.486251374833, 5139.588832893434, 5143.382794064736, 5233.162693968947, 4880.560234730692, 5021.573166280907, 5022.472640372855, 5028.636540825806, 5044.8234311798915, 4859.514048595112, 4867.269909513601, 4888.755562221803, 4893.9021219576125, 4934.802607895644, 3746.756460830037, 3752.1932784451474, 3753.0, 3760.8307626156343, 3811.0662887591607, 4821.7348045856415, 5077.974610126926, 5081.669691470042, 5083.771230294572, 5091.974540127276, 4952.0, 4961.355023846877, 4977.527134922177, 4979.178435558092, 4983.177775666974, 4971.975140124277, 4981.334985782589, 5053.469385714594, 5062.088824011713, 5066.214736715815, 4779.0, 4913.803447862046, 4937.35814344133, 4969.751512424436, 4990.0, 4800.591949684316, 4910.140725373117, 5014.674046186986, 5041.269015992734, 5053.620978426559, 4863.781129849094, 4863.805447782049, 4874.5147219738055, 4877.439094504178, 4886.242632391985], "value": 3780.8362426636863, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.63124234929677, 522.6106550619788, 537.9919301210482, 538.3055857943253, 615.1295916278466, 12653.304068276253, 13756.174629522224, 13851.168929864216, 13867.791983120249, 13873.861261387383, 11814.527418903233, 12245.571405000826, 12431.378431078456, 13169.934150329234, 13313.93343033286, 632.3392055302197, 764.4075841223055, 783.5103060587134, 785.5522352259214, 787.6376866641352, 757.1103952855373, 767.6890859202032, 776.192759530088, 787.9999999999972, 789.4828887078972, 822.251750906675, 822.5393779483478, 823.1315961660446, 823.1932705948175, 826.6858593734372, 659.5020759326726, 814.9225823546813, 814.9918500814975, 816.1512027491402, 816.6814942172557, 579.8550362409397, 585.5169485174748, 591.0130082761798, 601.5909181756404, 601.7322291580249, 13144.60566183016, 13168.53910113151, 13172.407241674147, 13198.0, 13225.140365876283, 11764.0, 12235.571754988621, 12279.2018518796, 12297.508099676003, 12729.490820367175, 11905.464254108585, 12081.57714479989, 12098.092643051717, 12178.269303841798, 12803.231806091664, 4046.736962097454, 4076.5515793262716, 4109.506859176918, 4247.511536173318, 4255.2978758504805, 64088.0, 64213.357866421204, 64651.67674161646, 64736.086876090216, 64923.0, 10069.144122749502, 10078.697639070839, 10472.947635261775, 10666.093382062612, 10852.24034317606, 19197.136128874234, 19257.614847702967, 19277.132529036226, 19348.32280870163, 19419.51451213731, 6493.51298652603, 6600.86798264032, 6633.6019838809825, 6659.4006539411685, 6776.796696099026, 3645.289168612095, 3680.5399325084363, 3733.216024634857, 3740.214554943438, 3775.5469343678933, 2687.6909155447374, 2791.5393959996372, 2792.6928037915814, 2794.105886116464, 2795.077624383948, 492.5271739130475, 502.10625087344584, 504.13540777566647, 509.64579617166453, 515.7730598536707, 397.07679644825896, 449.6155786802302, 513.9408967968657, 517.7256054291232, 523.9292695486137, 7137.0, 7140.678669459887, 7210.639468026683, 7388.759016164189, 7670.386369090459, 416.6978940268342, 434.82987281226247, 495.5366732105493, 506.38980029065624, 515.4587682932905, 3349.2129349602824, 3387.7827897465586, 3435.2957643682985, 3454.6199918008997, 3473.3501586746092, 56107.719461402434, 58624.0, 71202.93195602071, 109315.45342273237, 110454.44772776245, 51767.48232517702, 51793.22310165275, 73566.0, 92660.53669731609, 94397.0, 77986.6100669493, 78810.0, 79306.0, 80079.59960200162, 85206.57396712976, 6665.66671666405, 6817.386435220854, 6863.0, 6979.6859141337745, 7022.964885175541, 30374.54438183385, 33227.50158747572, 38085.4287185698, 38191.61808381881, 38746.80626596849, 59091.0, 59605.40394596172, 59780.0, 60256.0, 60998.0, 10428.478576071015, 10521.736956576147, 10524.105451036441, 10552.894471055499, 10916.508757106109, 12359.876401236232, 12655.367231638565, 12655.746885062063, 12828.29444380568, 12828.679283017998, 10996.725081873017, 11033.337999720043, 11303.43482825839, 11530.94234528822, 11768.882311176778, 10611.681649550526, 10702.0, 10754.462276886281, 10763.51564179598, 11101.38942358178, 10332.690019299433, 10899.673009809401, 11101.5559377624, 11161.274517156358, 11356.318620882774, 9025.368224224241, 9225.400348977295, 9286.489243091695, 9447.480388578695, 9491.952540237255, 8793.780155496164, 8950.23922966555, 9181.724548263563, 9254.444733316022, 9435.198008169382], "value": 6256.9001312234095, "unit": "score"}], "name": "dromaeo_css", "value": 6217.501288586915, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 114.0, 115.0, 110.0, 114.0, 115.0, 115.0, 115.0, 116.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 123.0, 123.0, 188.0, 127.0, 126.0, 149.0, 122.0, 164.0, 128.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 160.0, 161.0, 163.0, 161.0, 163.0, 161.0, 159.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 75.0, 107.0, 77.0, 77.0, 76.0, 77.0, 130.0, 75.0], "value": 84.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 133.0, 87.0, 87.0, 87.0, 120.0, 86.0, 86.0, 127.0], "value": 98.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 147.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 65.0, 63.0, 63.0, 66.0, 64.0, 64.0, 66.0, 64.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 66.0, 66.0, 66.0, 66.0, 66.0, 69.0, 65.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 81.0, 82.0, 82.0, 82.0, 82.0, 91.0, 82.0, 85.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 144.0, 143.0, 131.0, 134.0, 141.0, 133.0, 154.0, 147.0, 147.0], "value": 141.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 170.0, 173.0, 170.0, 170.0, 175.0, 177.0, 173.0, 171.0, 169.0], "value": 172.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 78.0, 69.0, 69.0, 70.0, 68.0, 67.0, 72.0, 68.0, 66.0], "value": 69.4, "unit": "score"}], "name": "kraken", "value": 1552.4, "alertThreshold": 2.0}]} 21:41:13 INFO - Return code: 0 21:41:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:41:13 INFO - # TBPL SUCCESS # 21:41:13 INFO - Running post-action listener: _resource_record_post_action 21:41:13 INFO - [mozharness: 2016-08-17 04:41:13.179425Z] Finished run-tests step (success) 21:41:13 INFO - Running post-run listener: _resource_record_post_run 21:41:13 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 0; Write bytes: 406339584; Read time: 0; Write time: 330116 21:41:13 INFO - TinderboxPrint: CPU usage
13.0% 21:41:13 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 21:41:13 INFO - TinderboxPrint: I/O write bytes / time
406,339,584 / 330,116 21:41:13 INFO - TinderboxPrint: CPU idle
8,342.1 (87.0%) 21:41:13 INFO - TinderboxPrint: CPU user
1,198.7 (12.5%) 21:41:13 INFO - TinderboxPrint: Swap in / out
0 / 0 21:41:13 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 113479680; Read time: 0; Write time: 91836 21:41:13 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 291684352; Read time: 0; Write time: 238256 21:41:13 INFO - Running post-run listener: _upload_blobber_files 21:41:13 INFO - Blob upload gear active. 21:41:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:41:13 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 21:41:13 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:41:13 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:41:14 INFO - (blobuploader) - INFO - Open directory for files ... 21:41:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:41:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:41:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:41:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:41:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:41:19 INFO - (blobuploader) - INFO - Done attempting. 21:41:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:41:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:41:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:41:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:41:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:41:20 INFO - (blobuploader) - INFO - Done attempting. 21:41:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:41:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:41:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:41:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:41:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:41:21 INFO - (blobuploader) - INFO - Done attempting. 21:41:21 INFO - (blobuploader) - INFO - Iteration through files over. 21:41:21 INFO - Return code: 0 21:41:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:41:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:41:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b6e8ee19df40f2c0518aef112e5c5ab6796bf3754d21cd4f62ab055a4cbd61c3b5ab8b44aeaa6928ae233d5481881ed9d58aadb9892c72b811eaffe774ff5a34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/03a975f90ebf5e660fcb740fa1b34c0f9bdd664f5e00834fdba05e80b50cd70378abca41157ca976f6a2953f1951f6559b56873ad0b9461f2ab711a216130eaf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/dc3d0185438b66782d4ce2022b52c32b3a61fffecaaa709f1ee10fb9e96cf5ce2191c407cfdf6e3d3b2f510f9af615b784c92f7fd0bc7d0e329159e93eb4abc0"} 21:41:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:41:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:41:21 INFO - Contents: 21:41:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b6e8ee19df40f2c0518aef112e5c5ab6796bf3754d21cd4f62ab055a4cbd61c3b5ab8b44aeaa6928ae233d5481881ed9d58aadb9892c72b811eaffe774ff5a34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/03a975f90ebf5e660fcb740fa1b34c0f9bdd664f5e00834fdba05e80b50cd70378abca41157ca976f6a2953f1951f6559b56873ad0b9461f2ab711a216130eaf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/dc3d0185438b66782d4ce2022b52c32b3a61fffecaaa709f1ee10fb9e96cf5ce2191c407cfdf6e3d3b2f510f9af615b784c92f7fd0bc7d0e329159e93eb4abc0"} 21:41:21 INFO - Running post-run listener: copy_logs_to_upload_dir 21:41:21 INFO - Copying logs to upload dir... 21:41:21 INFO - mkdir: /builds/slave/test/build/upload/logs 21:41:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.044648 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-08-16 21:41:21.224309) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 21:41:21.230136) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b6e8ee19df40f2c0518aef112e5c5ab6796bf3754d21cd4f62ab055a4cbd61c3b5ab8b44aeaa6928ae233d5481881ed9d58aadb9892c72b811eaffe774ff5a34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/03a975f90ebf5e660fcb740fa1b34c0f9bdd664f5e00834fdba05e80b50cd70378abca41157ca976f6a2953f1951f6559b56873ad0b9461f2ab711a216130eaf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/dc3d0185438b66782d4ce2022b52c32b3a61fffecaaa709f1ee10fb9e96cf5ce2191c407cfdf6e3d3b2f510f9af615b784c92f7fd0bc7d0e329159e93eb4abc0"} build_url:https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011196 build_url: 'https://queue.taskcluster.net/v1/task/OvJhCNJWQ8KZMX2r2GOVww/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/Autoland-Non-PGO/sha512/b6e8ee19df40f2c0518aef112e5c5ab6796bf3754d21cd4f62ab055a4cbd61c3b5ab8b44aeaa6928ae233d5481881ed9d58aadb9892c72b811eaffe774ff5a34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/03a975f90ebf5e660fcb740fa1b34c0f9bdd664f5e00834fdba05e80b50cd70378abca41157ca976f6a2953f1951f6559b56873ad0b9461f2ab711a216130eaf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/dc3d0185438b66782d4ce2022b52c32b3a61fffecaaa709f1ee10fb9e96cf5ce2191c407cfdf6e3d3b2f510f9af615b784c92f7fd0bc7d0e329159e93eb4abc0"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 21:41:21.279588) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 21:41:21.279921) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1471407632.558008-979981468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004495 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 21:41:21.361121) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 21:41:21.361658) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 21:41:21.362154) ========= ========= Total master_lag: 0.21 =========