builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-019 starttime: 1481329432.7 results: success (0) buildid: 20161209153551 builduid: 792a89b870244446b6140db22a522e73 revision: 73bdd29461aef3297b099ba25e8b317a18a0a4b9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-09 16:23:52.697961) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-09 16:23:52.699075) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-09 16:23:52.699548) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-09 16:23:52.736763) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 16:23:52.737071) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-09 16:23:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.6M=0s 2016-12-09 16:23:53 (69.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.153407 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 16:23:52.905729) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 16:23:52.906043) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014731 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 16:23:52.957636) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-09 16:23:52.958001) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 73bdd29461aef3297b099ba25e8b317a18a0a4b9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 73bdd29461aef3297b099ba25e8b317a18a0a4b9 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-09 16:23:53,339 truncating revision to first 12 chars 2016-12-09 16:23:53,339 Setting DEBUG logging. 2016-12-09 16:23:53,340 attempt 1/10 2016-12-09 16:23:53,340 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/73bdd29461ae?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-09 16:23:54,206 unpacking tar archive at: graphics-73bdd29461ae/testing/mozharness/ program finished with exit code 0 elapsedTime=1.173783 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-09 16:23:54.145629) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-09 16:23:54.145952) ========= script_repo_revision: 73bdd29461aef3297b099ba25e8b317a18a0a4b9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-09 16:23:54.146354) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-09 16:23:54.146635) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-09 16:23:54.155625) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-12-09 16:23:54.155986) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:23:54 INFO - MultiFileLogger online at 20161209 16:23:54 in /builds/slave/test 16:23:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 16:23:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:23:54 INFO - {'append_to_log': False, 16:23:54 INFO - 'base_work_dir': '/builds/slave/test', 16:23:54 INFO - 'blob_upload_branch': 'Graphics', 16:23:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:23:54 INFO - 'branch': 'Graphics', 16:23:54 INFO - 'buildbot_json_path': 'buildprops.json', 16:23:54 INFO - 'config_files': ('talos/linux_config.py',), 16:23:54 INFO - 'default_actions': ('clobber', 16:23:54 INFO - 'read-buildbot-config', 16:23:54 INFO - 'download-and-extract', 16:23:54 INFO - 'populate-webroot', 16:23:54 INFO - 'create-virtualenv', 16:23:54 INFO - 'install', 16:23:54 INFO - 'run-tests'), 16:23:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:23:54 INFO - 'download_minidump_stackwalk': True, 16:23:54 INFO - 'download_symbols': 'ondemand', 16:23:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:23:54 INFO - 'tooltool.py': '/tools/tooltool.py', 16:23:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:23:54 INFO - '/tools/misc-python/virtualenv.py')}, 16:23:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:23:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:23:54 INFO - 'installer_path': 'installer.exe', 16:23:54 INFO - 'log_level': 'info', 16:23:54 INFO - 'log_name': 'talos', 16:23:54 INFO - 'log_to_console': True, 16:23:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:23:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:23:54 INFO - 'opt_config_files': (), 16:23:54 INFO - 'pip_index': False, 16:23:54 INFO - 'sps_profile': False, 16:23:54 INFO - 'sps_profile_interval': 0, 16:23:54 INFO - 'suite': 'dromaeojs', 16:23:54 INFO - 'system_bits': '32', 16:23:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:23:54 INFO - 'title': 'talos-linux64-ix-019', 16:23:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:23:54 INFO - 'use_talos_json': True, 16:23:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:23:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:23:54 INFO - 'work_dir': 'build'} 16:23:54 INFO - [mozharness: 2016-12-10 00:23:54.642091Z] Running clobber step. 16:23:54 INFO - Running pre-action listener: _resource_record_pre_action 16:23:54 INFO - Running main action method: clobber 16:23:54 INFO - rmtree: /builds/slave/test/build 16:23:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:23:55 INFO - Running post-action listener: _resource_record_post_action 16:23:55 INFO - [mozharness: 2016-12-10 00:23:55.110054Z] Finished clobber step (success) 16:23:55 INFO - [mozharness: 2016-12-10 00:23:55.110153Z] Running read-buildbot-config step. 16:23:55 INFO - Running pre-action listener: _resource_record_pre_action 16:23:55 INFO - Running main action method: read_buildbot_config 16:23:55 INFO - Using buildbot properties: 16:23:55 INFO - { 16:23:55 INFO - "project": "", 16:23:55 INFO - "product": "firefox", 16:23:55 INFO - "script_repo_revision": "default", 16:23:55 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 16:23:55 INFO - "repository": "", 16:23:55 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 16:23:55 INFO - "buildid": "20161209153551", 16:23:55 INFO - "pgo_build": "False", 16:23:55 INFO - "basedir": "/builds/slave/test", 16:23:55 INFO - "buildnumber": 25, 16:23:55 INFO - "slavename": "talos-linux64-ix-019", 16:23:55 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:23:55 INFO - "platform": "ubuntu64_hw", 16:23:55 INFO - "scheduler": "tests-graphics-linux64-talos", 16:23:55 INFO - "branch": "graphics", 16:23:55 INFO - "repo_path": "projects/graphics", 16:23:55 INFO - "revision": "73bdd29461aef3297b099ba25e8b317a18a0a4b9", 16:23:55 INFO - "stage_platform": "linux64", 16:23:55 INFO - "builduid": "792a89b870244446b6140db22a522e73", 16:23:55 INFO - "slavebuilddir": "test" 16:23:55 INFO - } 16:23:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 16:23:55 INFO - Running post-action listener: _resource_record_post_action 16:23:55 INFO - [mozharness: 2016-12-10 00:23:55.112541Z] Finished read-buildbot-config step (success) 16:23:55 INFO - [mozharness: 2016-12-10 00:23:55.112628Z] Running download-and-extract step. 16:23:55 INFO - Running pre-action listener: _resource_record_pre_action 16:23:55 INFO - Running main action method: download_and_extract 16:23:55 INFO - mkdir: /builds/slave/test/build/tests 16:23: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')]} 16:23:55 INFO - trying https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 16:23:55 INFO - Downloading https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 16:23:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:23:55 INFO - Downloaded 1361 bytes. 16:23:55 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 16:23:55 INFO - Using the following test package requirements: 16:23:55 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 16:23:55 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:23:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:23:55 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:23:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:23:55 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:23:55 INFO - u'jsshell-linux-x86_64.zip'], 16:23:55 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:23:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:23:55 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 16:23:55 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:23:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:23:55 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:23:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:23:55 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:23:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:23:55 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:23:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:23:55 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:23:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 16:23:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:23:55 INFO - Fetch https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 16:23:57 INFO - Expected file size: 20222327 16:23:57 INFO - Obtained file size: 20222327 16:24:01 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:24:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 16:24:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:24:01 INFO - Fetch https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 16:24:02 INFO - Expected file size: 20222327 16:24:02 INFO - Obtained file size: 20222327 16:24:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 16:24:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:24:06 INFO - Fetch https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 16:24:08 INFO - Expected file size: 14409734 16:24:08 INFO - Obtained file size: 14409734 16:24:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:08 INFO - trying https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 16:24:08 INFO - Downloading https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 16:24:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:24:10 INFO - Downloaded 55781760 bytes. 16:24:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 16:24:10 INFO - mkdir: /builds/slave/test/properties 16:24:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:24:10 INFO - Writing to file /builds/slave/test/properties/build_url 16:24:10 INFO - Contents: 16:24:10 INFO - build_url:https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 16:24:11 INFO - Running post-action listener: _resource_record_post_action 16:24:11 INFO - Running post-action listener: set_extra_try_arguments 16:24:11 INFO - [mozharness: 2016-12-10 00:24:11.462611Z] Finished download-and-extract step (success) 16:24:11 INFO - [mozharness: 2016-12-10 00:24:11.462836Z] Running populate-webroot step. 16:24:11 INFO - Running pre-action listener: _resource_record_pre_action 16:24:11 INFO - Running main action method: populate_webroot 16:24:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:24:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:24:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:24:11 INFO - '--tppagecycles', 16:24:11 INFO - '7'], 16:24:11 INFO - 'tests': ['tsvgm']}}, 16:24:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:24:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:24:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:24:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:24:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:24:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:24:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:24:11 INFO - 'talos_options': ['--disable-e10s'], 16:24:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:24:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:24:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:24:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:24:11 INFO - 'talos_options': ['--disable-e10s'], 16:24:11 INFO - 'tests': ['damp', 'tps']}, 16:24:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:24:11 INFO - 'tests': ['damp', 'tps']}, 16:24:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:24:11 INFO - 'tests': ['dromaeo_dom']}, 16:24:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:24:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:24:11 INFO - 'tests': ['basic_compositor_video']}, 16:24:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:24:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:24:11 INFO - 'tests': ['a11yr', 16:24:11 INFO - 'ts_paint', 16:24:11 INFO - 'tpaint', 16:24:11 INFO - 'sessionrestore', 16:24:11 INFO - 'sessionrestore_no_auto_restore', 16:24:11 INFO - 'tabpaint']}, 16:24:11 INFO - 'other-e10s': {'tests': ['a11yr', 16:24:11 INFO - 'ts_paint', 16:24:11 INFO - 'tpaint', 16:24:11 INFO - 'sessionrestore', 16:24:11 INFO - 'sessionrestore_no_auto_restore', 16:24:11 INFO - 'tabpaint']}, 16:24:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:24:11 INFO - 'ts_paint', 16:24:11 INFO - 'tpaint', 16:24:11 INFO - 'sessionrestore', 16:24:11 INFO - 'sessionrestore_no_auto_restore', 16:24:11 INFO - 'tabpaint']}, 16:24:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:24:11 INFO - 'ts_paint', 16:24:11 INFO - 'tpaint', 16:24:11 INFO - 'sessionrestore', 16:24:11 INFO - 'sessionrestore_no_auto_restore', 16:24:11 INFO - 'tabpaint']}, 16:24:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:24:11 INFO - 'tests': ['a11yr', 16:24:11 INFO - 'ts_paint', 16:24:11 INFO - 'tpaint', 16:24:11 INFO - 'sessionrestore', 16:24:11 INFO - 'sessionrestore_no_auto_restore', 16:24:11 INFO - 'tabpaint']}, 16:24:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:24:11 INFO - 'tests': ['a11yr', 16:24:11 INFO - 'ts_paint', 16:24:11 INFO - 'tpaint', 16:24:11 INFO - 'sessionrestore', 16:24:11 INFO - 'sessionrestore_no_auto_restore', 16:24:11 INFO - 'tabpaint']}, 16:24:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:24:11 INFO - 'tests': ['tsvgx', 16:24:11 INFO - 'tsvgr_opacity', 16:24:11 INFO - 'tart', 16:24:11 INFO - 'tscrollx', 16:24:11 INFO - 'cart', 16:24:11 INFO - 'tsvg_static']}, 16:24:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:24:11 INFO - 'tsvgr_opacity', 16:24:11 INFO - 'tart', 16:24:11 INFO - 'tscrollx', 16:24:11 INFO - 'cart', 16:24:11 INFO - 'tsvg_static']}, 16:24:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:24:11 INFO - 'talos_options': ['--disable-e10s'], 16:24:11 INFO - 'tests': ['tp5o']}, 16:24:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:24:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:24:11 INFO - 'talos_options': ['--disable-e10s', 16:24:11 INFO - '--xperf_path', 16:24:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:24:11 INFO - 'tests': ['tp5n']}, 16:24:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:24:11 INFO - 'talos_options': ['--xperf_path', 16:24:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:24:11 INFO - 'tests': ['tp5n']}}, 16:24:11 INFO - 'talos.zip': {'path': '', 16:24:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:24:11 INFO - Running post-action listener: _resource_record_post_action 16:24:11 INFO - [mozharness: 2016-12-10 00:24:11.482315Z] Finished populate-webroot step (success) 16:24:11 INFO - [mozharness: 2016-12-10 00:24:11.482402Z] Running create-virtualenv step. 16:24:11 INFO - Running pre-action listener: _resource_record_pre_action 16:24:11 INFO - Running main action method: create_virtualenv 16:24:11 INFO - Creating virtualenv /builds/slave/test/build/venv 16:24:11 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:24:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:24:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:24:11 INFO - Using real prefix '/usr' 16:24:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:24:12 INFO - Installing distribute.............................................................................................................................................................................................done. 16:24:14 INFO - Installing pip.................done. 16:24:14 INFO - Return code: 0 16:24:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:24:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:24:14 INFO - Reading from file tmpfile_stdout 16:24:14 INFO - Output received: 16:24:14 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 16:24:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:24:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:14 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 0x7fd32287ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a73120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b03f20>, '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 0x2a70c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a70f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:24:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:24:14 INFO - Using env: {'DISPLAY': ':0', 16:24:14 INFO - 'HOME': '/home/cltbld', 16:24:14 INFO - 'LANG': 'en_US.UTF-8', 16:24:14 INFO - 'LANGUAGE': 'en_US:en', 16:24:14 INFO - 'LOGNAME': 'cltbld', 16:24:14 INFO - 'MAIL': '/var/mail/cltbld', 16:24:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:14 INFO - 'MOZ_NO_REMOTE': '1', 16:24:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:14 INFO - 'NO_EM_RESTART': '1', 16:24:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:14 INFO - 'PWD': '/builds/slave/test', 16:24:14 INFO - 'SHELL': '/bin/bash', 16:24:14 INFO - 'SHLVL': '1', 16:24:14 INFO - 'TERM': 'linux', 16:24:14 INFO - 'TMOUT': '86400', 16:24:14 INFO - 'USER': 'cltbld', 16:24:14 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 16:24:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:14 INFO - '_': '/tools/buildbot/bin/python'} 16:24:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:14 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:24:14 INFO - Cleaning up... 16:24:14 INFO - Return code: 0 16:24:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:24:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:14 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 0x7fd32287ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a73120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b03f20>, '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 0x2a70c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a70f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:24:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:24:14 INFO - Using env: {'DISPLAY': ':0', 16:24:14 INFO - 'HOME': '/home/cltbld', 16:24:14 INFO - 'LANG': 'en_US.UTF-8', 16:24:14 INFO - 'LANGUAGE': 'en_US:en', 16:24:14 INFO - 'LOGNAME': 'cltbld', 16:24:14 INFO - 'MAIL': '/var/mail/cltbld', 16:24:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:14 INFO - 'MOZ_NO_REMOTE': '1', 16:24:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:14 INFO - 'NO_EM_RESTART': '1', 16:24:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:14 INFO - 'PWD': '/builds/slave/test', 16:24:14 INFO - 'SHELL': '/bin/bash', 16:24:14 INFO - 'SHLVL': '1', 16:24:14 INFO - 'TERM': 'linux', 16:24:14 INFO - 'TMOUT': '86400', 16:24:14 INFO - 'USER': 'cltbld', 16:24:14 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 16:24:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:14 INFO - '_': '/tools/buildbot/bin/python'} 16:24:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:14 INFO - Downloading/unpacking psutil>=3.1.1 16:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:24:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:24:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:24:16 INFO - Installing collected packages: psutil 16:24:16 INFO - Running setup.py install for psutil 16:24:16 INFO - building 'psutil._psutil_linux' extension 16:24:16 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:24:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:24:17 INFO - building 'psutil._psutil_posix' extension 16:24:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:24:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:24:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:24:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:24:17 INFO - Successfully installed psutil 16:24:17 INFO - Cleaning up... 16:24:17 INFO - Return code: 0 16:24:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd32287ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a73120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b03f20>, '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 0x2a70c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a70f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:24:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:24:17 INFO - Using env: {'DISPLAY': ':0', 16:24:17 INFO - 'HOME': '/home/cltbld', 16:24:17 INFO - 'LANG': 'en_US.UTF-8', 16:24:17 INFO - 'LANGUAGE': 'en_US:en', 16:24:17 INFO - 'LOGNAME': 'cltbld', 16:24:17 INFO - 'MAIL': '/var/mail/cltbld', 16:24:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:17 INFO - 'MOZ_NO_REMOTE': '1', 16:24:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:17 INFO - 'NO_EM_RESTART': '1', 16:24:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:17 INFO - 'PWD': '/builds/slave/test', 16:24:17 INFO - 'SHELL': '/bin/bash', 16:24:17 INFO - 'SHLVL': '1', 16:24:17 INFO - 'TERM': 'linux', 16:24:17 INFO - 'TMOUT': '86400', 16:24:17 INFO - 'USER': 'cltbld', 16:24:17 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 16:24:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:17 INFO - '_': '/tools/buildbot/bin/python'} 16:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:24:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:24:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:24:19 INFO - Installing collected packages: mozsystemmonitor 16:24:19 INFO - Running setup.py install for mozsystemmonitor 16:24:19 INFO - Successfully installed mozsystemmonitor 16:24:19 INFO - Cleaning up... 16:24:19 INFO - Return code: 0 16:24:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:24:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:19 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 0x7fd32287ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a73120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b03f20>, '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 0x2a70c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a70f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:24:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:24:19 INFO - Using env: {'DISPLAY': ':0', 16:24:19 INFO - 'HOME': '/home/cltbld', 16:24:19 INFO - 'LANG': 'en_US.UTF-8', 16:24:19 INFO - 'LANGUAGE': 'en_US:en', 16:24:19 INFO - 'LOGNAME': 'cltbld', 16:24:19 INFO - 'MAIL': '/var/mail/cltbld', 16:24:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:19 INFO - 'MOZ_NO_REMOTE': '1', 16:24:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:19 INFO - 'NO_EM_RESTART': '1', 16:24:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:19 INFO - 'PWD': '/builds/slave/test', 16:24:19 INFO - 'SHELL': '/bin/bash', 16:24:19 INFO - 'SHLVL': '1', 16:24:19 INFO - 'TERM': 'linux', 16:24:19 INFO - 'TMOUT': '86400', 16:24:19 INFO - 'USER': 'cltbld', 16:24:19 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 16:24:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:19 INFO - '_': '/tools/buildbot/bin/python'} 16:24:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:19 INFO - Downloading/unpacking jsonschema==2.5.1 16:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:24:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:24:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:22 INFO - Downloading functools32-3.2.3-2.tar.gz 16:24:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:24:22 INFO - warning: no files found matching '*.txt' 16:24:22 INFO - no previously-included directories found matching 'build' 16:24:22 INFO - no previously-included directories found matching 'dist' 16:24:22 INFO - no previously-included directories found matching '.git*' 16:24:22 INFO - Installing collected packages: jsonschema, functools32 16:24:22 INFO - Running setup.py install for functools32 16:24:22 INFO - warning: no files found matching '*.txt' 16:24:22 INFO - no previously-included directories found matching 'build' 16:24:22 INFO - no previously-included directories found matching 'dist' 16:24:22 INFO - no previously-included directories found matching '.git*' 16:24:22 INFO - Successfully installed jsonschema functools32 16:24:22 INFO - Cleaning up... 16:24:22 INFO - Return code: 0 16:24:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:24:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fd32287ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a73120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b03f20>, '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 0x2a70c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a70f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:24:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:24:22 INFO - Using env: {'DISPLAY': ':0', 16:24:22 INFO - 'HOME': '/home/cltbld', 16:24:22 INFO - 'LANG': 'en_US.UTF-8', 16:24:22 INFO - 'LANGUAGE': 'en_US:en', 16:24:22 INFO - 'LOGNAME': 'cltbld', 16:24:22 INFO - 'MAIL': '/var/mail/cltbld', 16:24:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:22 INFO - 'MOZ_NO_REMOTE': '1', 16:24:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:22 INFO - 'NO_EM_RESTART': '1', 16:24:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:22 INFO - 'PWD': '/builds/slave/test', 16:24:22 INFO - 'SHELL': '/bin/bash', 16:24:22 INFO - 'SHLVL': '1', 16:24:22 INFO - 'TERM': 'linux', 16:24:22 INFO - 'TMOUT': '86400', 16:24:22 INFO - 'USER': 'cltbld', 16:24:22 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 16:24:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:22 INFO - '_': '/tools/buildbot/bin/python'} 16:24:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:24:22 INFO - Cleaning up... 16:24:22 INFO - Return code: 0 16:24:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:24:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fd32287ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a73120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b03f20>, '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 0x2a70c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a70f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:24:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:24:22 INFO - Using env: {'DISPLAY': ':0', 16:24:22 INFO - 'HOME': '/home/cltbld', 16:24:22 INFO - 'LANG': 'en_US.UTF-8', 16:24:22 INFO - 'LANGUAGE': 'en_US:en', 16:24:22 INFO - 'LOGNAME': 'cltbld', 16:24:22 INFO - 'MAIL': '/var/mail/cltbld', 16:24:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:22 INFO - 'MOZ_NO_REMOTE': '1', 16:24:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:22 INFO - 'NO_EM_RESTART': '1', 16:24:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:22 INFO - 'PWD': '/builds/slave/test', 16:24:22 INFO - 'SHELL': '/bin/bash', 16:24:22 INFO - 'SHLVL': '1', 16:24:22 INFO - 'TERM': 'linux', 16:24:22 INFO - 'TMOUT': '86400', 16:24:22 INFO - 'USER': 'cltbld', 16:24:22 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 16:24:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:22 INFO - '_': '/tools/buildbot/bin/python'} 16:24:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:22 INFO - Downloading/unpacking blobuploader==1.2.4 16:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:24 INFO - Downloading blobuploader-1.2.4.tar.gz 16:24:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:24:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:24:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:25 INFO - Downloading docopt-0.6.1.tar.gz 16:24:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:24:25 INFO - Installing collected packages: blobuploader, requests, docopt 16:24:25 INFO - Running setup.py install for blobuploader 16:24:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:24:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:24:25 INFO - Running setup.py install for requests 16:24:26 INFO - Running setup.py install for docopt 16:24:26 INFO - Successfully installed blobuploader requests docopt 16:24:26 INFO - Cleaning up... 16:24:26 INFO - Return code: 0 16:24:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:26 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 0x7fd32287ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a73120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b03f20>, '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 0x2a70c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a70f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:24:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:24:26 INFO - Using env: {'DISPLAY': ':0', 16:24:26 INFO - 'HOME': '/home/cltbld', 16:24:26 INFO - 'LANG': 'en_US.UTF-8', 16:24:26 INFO - 'LANGUAGE': 'en_US:en', 16:24:26 INFO - 'LOGNAME': 'cltbld', 16:24:26 INFO - 'MAIL': '/var/mail/cltbld', 16:24:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:26 INFO - 'MOZ_NO_REMOTE': '1', 16:24:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:26 INFO - 'NO_EM_RESTART': '1', 16:24:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:26 INFO - 'PWD': '/builds/slave/test', 16:24:26 INFO - 'SHELL': '/bin/bash', 16:24:26 INFO - 'SHLVL': '1', 16:24:26 INFO - 'TERM': 'linux', 16:24:26 INFO - 'TMOUT': '86400', 16:24:26 INFO - 'USER': 'cltbld', 16:24:26 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 16:24:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:26 INFO - '_': '/tools/buildbot/bin/python'} 16:24:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:24:26 INFO - Running setup.py (path:/tmp/pip-J5qwps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:24:26 INFO - Running setup.py (path:/tmp/pip-fWtXJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:24:26 INFO - Running setup.py (path:/tmp/pip-LAsCX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:24:26 INFO - Running setup.py (path:/tmp/pip-770ynX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:24:26 INFO - Running setup.py (path:/tmp/pip-M3TT_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:24:26 INFO - Running setup.py (path:/tmp/pip-rvIWgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:24:27 INFO - Running setup.py (path:/tmp/pip-SbrYQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:24:27 INFO - Running setup.py (path:/tmp/pip-M7ZILS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:24:27 INFO - Running setup.py (path:/tmp/pip-TgD7e9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:24:27 INFO - Running setup.py (path:/tmp/pip-IBRl9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:24:27 INFO - Running setup.py (path:/tmp/pip-VhamXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:24:27 INFO - Running setup.py (path:/tmp/pip-RslGx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:24:27 INFO - Running setup.py (path:/tmp/pip-r2K9Bn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:24:27 INFO - Running setup.py (path:/tmp/pip-sOaUbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:24:27 INFO - Running setup.py (path:/tmp/pip-Y2Qv83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:24:27 INFO - Running setup.py (path:/tmp/pip-7qxJS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:24:27 INFO - Running setup.py (path:/tmp/pip-bUS4rb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:24:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:24:27 INFO - Running setup.py install for manifestparser 16:24:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:24:27 INFO - Running setup.py install for mozcrash 16:24:28 INFO - Running setup.py install for mozdebug 16:24:28 INFO - Running setup.py install for mozdevice 16:24:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:24:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:24:28 INFO - Running setup.py install for mozfile 16:24:28 INFO - Running setup.py install for mozhttpd 16:24:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:24:28 INFO - Running setup.py install for mozinfo 16:24:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:24:28 INFO - Running setup.py install for mozInstall 16:24:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:24:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:24:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:24:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:24:28 INFO - Running setup.py install for mozleak 16:24:28 INFO - Running setup.py install for mozlog 16:24:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:24:28 INFO - Running setup.py install for moznetwork 16:24:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:24:28 INFO - Running setup.py install for mozprocess 16:24:29 INFO - Running setup.py install for mozprofile 16:24:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:24:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:24:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:24:29 INFO - Running setup.py install for mozrunner 16:24:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:24:29 INFO - Running setup.py install for mozscreenshot 16:24:29 INFO - Running setup.py install for moztest 16:24:29 INFO - Running setup.py install for mozversion 16:24:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:24:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:24:29 INFO - Cleaning up... 16:24:29 INFO - Return code: 0 16:24:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:29 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 0x7fd32287ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a73120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b03f20>, '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 0x2a70c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a70f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:24:29 INFO - Using env: {'DISPLAY': ':0', 16:24:29 INFO - 'HOME': '/home/cltbld', 16:24:29 INFO - 'LANG': 'en_US.UTF-8', 16:24:29 INFO - 'LANGUAGE': 'en_US:en', 16:24:29 INFO - 'LOGNAME': 'cltbld', 16:24:29 INFO - 'MAIL': '/var/mail/cltbld', 16:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:29 INFO - 'MOZ_NO_REMOTE': '1', 16:24:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:29 INFO - 'NO_EM_RESTART': '1', 16:24:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:29 INFO - 'PWD': '/builds/slave/test', 16:24:29 INFO - 'SHELL': '/bin/bash', 16:24:29 INFO - 'SHLVL': '1', 16:24:29 INFO - 'TERM': 'linux', 16:24:29 INFO - 'TMOUT': '86400', 16:24:29 INFO - 'USER': 'cltbld', 16:24:29 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 16:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:29 INFO - '_': '/tools/buildbot/bin/python'} 16:24:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:24:29 INFO - Running setup.py (path:/tmp/pip-piiGl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:24:29 INFO - Running setup.py (path:/tmp/pip-paEVU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:24:30 INFO - Running setup.py (path:/tmp/pip-qDAfaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:24:30 INFO - Running setup.py (path:/tmp/pip-zWGjeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:24:30 INFO - Running setup.py (path:/tmp/pip-5hfdfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:24:30 INFO - Running setup.py (path:/tmp/pip-7W4NSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:24:30 INFO - Running setup.py (path:/tmp/pip-ZyQlYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:24:30 INFO - Running setup.py (path:/tmp/pip-XG15k5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:24:30 INFO - Running setup.py (path:/tmp/pip-vLs3Y_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:24:30 INFO - Running setup.py (path:/tmp/pip-Viq6kI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:24:30 INFO - Running setup.py (path:/tmp/pip-hJf8V3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:24:30 INFO - Running setup.py (path:/tmp/pip-CahhuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:24:30 INFO - Running setup.py (path:/tmp/pip-LqlGHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:24:30 INFO - Running setup.py (path:/tmp/pip-VpCazl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:24:30 INFO - Running setup.py (path:/tmp/pip-V5lzgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:24:30 INFO - Running setup.py (path:/tmp/pip-xuCBNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:24:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:24:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:24:31 INFO - Running setup.py (path:/tmp/pip-CqNVJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:24:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:24:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:24:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:24:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:24:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:24:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:24:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:33 INFO - Downloading blessings-1.6.tar.gz 16:24:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:24:33 INFO - Installing collected packages: blessings 16:24:33 INFO - Running setup.py install for blessings 16:24:33 INFO - Successfully installed blessings 16:24:33 INFO - Cleaning up... 16:24:33 INFO - Return code: 0 16:24:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:24:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:24:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:24:33 INFO - Reading from file tmpfile_stdout 16:24:33 INFO - Current package versions: 16:24:33 INFO - argparse == 1.2.1 16:24:33 INFO - blessings == 1.6 16:24:33 INFO - blobuploader == 1.2.4 16:24:33 INFO - docopt == 0.6.1 16:24:33 INFO - functools32 == 3.2.3-2 16:24:33 INFO - jsonschema == 2.5.1 16:24:33 INFO - manifestparser == 1.1 16:24:33 INFO - mozInstall == 1.12 16:24:33 INFO - mozcrash == 1.0 16:24:33 INFO - mozdebug == 0.1 16:24:33 INFO - mozdevice == 0.49 16:24:33 INFO - mozfile == 1.2 16:24:33 INFO - mozhttpd == 0.7 16:24:33 INFO - mozinfo == 0.9 16:24:33 INFO - mozleak == 0.1 16:24:33 INFO - mozlog == 3.4 16:24:33 INFO - moznetwork == 0.27 16:24:33 INFO - mozprocess == 0.23 16:24:33 INFO - mozprofile == 0.28 16:24:33 INFO - mozrunner == 6.13 16:24:33 INFO - mozscreenshot == 0.1 16:24:33 INFO - mozsystemmonitor == 0.3 16:24:33 INFO - moztest == 0.7 16:24:33 INFO - mozversion == 1.4 16:24:33 INFO - psutil == 3.1.1 16:24:33 INFO - requests == 1.2.3 16:24:33 INFO - wsgiref == 0.1.2 16:24:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:24:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:33 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 0x7fd32287ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a73120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b03f20>, '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 0x2a70c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a70f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:24:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:24:33 INFO - Using env: {'DISPLAY': ':0', 16:24:33 INFO - 'HOME': '/home/cltbld', 16:24:33 INFO - 'LANG': 'en_US.UTF-8', 16:24:33 INFO - 'LANGUAGE': 'en_US:en', 16:24:33 INFO - 'LOGNAME': 'cltbld', 16:24:33 INFO - 'MAIL': '/var/mail/cltbld', 16:24:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:33 INFO - 'MOZ_NO_REMOTE': '1', 16:24:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:33 INFO - 'NO_EM_RESTART': '1', 16:24:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:33 INFO - 'PWD': '/builds/slave/test', 16:24:33 INFO - 'SHELL': '/bin/bash', 16:24:33 INFO - 'SHLVL': '1', 16:24:33 INFO - 'TERM': 'linux', 16:24:33 INFO - 'TMOUT': '86400', 16:24:33 INFO - 'USER': 'cltbld', 16:24:33 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 16:24:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:33 INFO - '_': '/tools/buildbot/bin/python'} 16:24:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:24:33 INFO - Cleaning up... 16:24:33 INFO - Return code: 0 16:24:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:24:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:24:33 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 0x7fd32287ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a73120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b03f20>, '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 0x2a70c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a70f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:24:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:24:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:24:33 INFO - Using env: {'DISPLAY': ':0', 16:24:33 INFO - 'HOME': '/home/cltbld', 16:24:33 INFO - 'LANG': 'en_US.UTF-8', 16:24:33 INFO - 'LANGUAGE': 'en_US:en', 16:24:33 INFO - 'LOGNAME': 'cltbld', 16:24:33 INFO - 'MAIL': '/var/mail/cltbld', 16:24:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:33 INFO - 'MOZ_NO_REMOTE': '1', 16:24:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:33 INFO - 'NO_EM_RESTART': '1', 16:24:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:33 INFO - 'PWD': '/builds/slave/test', 16:24:33 INFO - 'SHELL': '/bin/bash', 16:24:33 INFO - 'SHLVL': '1', 16:24:33 INFO - 'TERM': 'linux', 16:24:33 INFO - 'TMOUT': '86400', 16:24:33 INFO - 'USER': 'cltbld', 16:24:33 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 16:24:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:33 INFO - '_': '/tools/buildbot/bin/python'} 16:24:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:24:34 INFO - Cleaning up... 16:24:34 INFO - Return code: 0 16:24:34 INFO - Running post-action listener: _resource_record_post_action 16:24:34 INFO - Running post-action listener: _start_resource_monitoring 16:24:34 INFO - Starting resource monitoring. 16:24:34 INFO - [mozharness: 2016-12-10 00:24:34.064578Z] Finished create-virtualenv step (success) 16:24:34 INFO - [mozharness: 2016-12-10 00:24:34.065094Z] Running install step. 16:24:34 INFO - Running pre-action listener: _resource_record_pre_action 16:24:34 INFO - Running main action method: install 16:24:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:24:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:24:34 INFO - Reading from file tmpfile_stdout 16:24:34 INFO - Detecting whether we're running mozinstall >=1.0... 16:24:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:24:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:24:34 INFO - Reading from file tmpfile_stdout 16:24:34 INFO - Output received: 16:24:34 INFO - Usage: mozinstall [options] installer 16:24:34 INFO - Options: 16:24:34 INFO - -h, --help show this help message and exit 16:24:34 INFO - -d DEST, --destination=DEST 16:24:34 INFO - Directory to install application into. [default: 16:24:34 INFO - "/builds/slave/test"] 16:24:34 INFO - --app=APP Application being installed. [default: firefox] 16:24:34 INFO - mkdir: /builds/slave/test/build/application 16:24:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:24:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:24:50 INFO - Reading from file tmpfile_stdout 16:24:50 INFO - Output received: 16:24:50 INFO - /builds/slave/test/build/application/firefox/firefox 16:24:50 INFO - Running post-action listener: _resource_record_post_action 16:24:50 INFO - [mozharness: 2016-12-10 00:24:50.420575Z] Finished install step (success) 16:24:50 INFO - [mozharness: 2016-12-10 00:24:50.420816Z] Running run-tests step. 16:24:50 INFO - Running pre-action listener: _resource_record_pre_action 16:24:50 INFO - Running main action method: run_tests 16:24:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:24:50 INFO - Python 2.7.3 16:24:50 INFO - Return code: 0 16:24:50 INFO - grabbing minidump binary from tooltool 16:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:50 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 0x2b03f20>, '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 0x2a70c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a70f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:24:50 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:24:50 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:24:50 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:24:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:24:50 INFO - Return code: 0 16:24:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:24:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:24:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:24:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:24:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:24:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-019 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:24:50 INFO - Using env: {'DISPLAY': ':0', 16:24:50 INFO - 'HOME': '/home/cltbld', 16:24:50 INFO - 'LANG': 'en_US.UTF-8', 16:24:50 INFO - 'LANGUAGE': 'en_US:en', 16:24:50 INFO - 'LOGNAME': 'cltbld', 16:24:50 INFO - 'MAIL': '/var/mail/cltbld', 16:24:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:24:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:50 INFO - 'MOZ_NO_REMOTE': '1', 16:24:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:24:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:24:50 INFO - 'NO_EM_RESTART': '1', 16:24:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:50 INFO - 'PWD': '/builds/slave/test', 16:24:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:24:50 INFO - 'SHELL': '/bin/bash', 16:24:50 INFO - 'SHLVL': '1', 16:24:50 INFO - 'TERM': 'linux', 16:24:50 INFO - 'TMOUT': '86400', 16:24:50 INFO - 'USER': 'cltbld', 16:24:50 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911', 16:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:50 INFO - '_': '/tools/buildbot/bin/python'} 16:24:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:24:50 INFO - mozversion application_buildid: 20161209153551 16:24:50 INFO - mozversion application_changeset: 73bdd29461aef3297b099ba25e8b317a18a0a4b9 16:24:50 INFO - mozversion application_display_name: Nightly 16:24:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:24:50 INFO - mozversion application_name: Firefox 16:24:50 INFO - mozversion application_remotingname: firefox 16:24:50 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 16:24:50 INFO - mozversion application_vendor: Mozilla 16:24:50 INFO - mozversion application_version: 53.0a1 16:24:50 INFO - mozversion platform_buildid: 20161209153551 16:24:50 INFO - mozversion platform_changeset: 73bdd29461aef3297b099ba25e8b317a18a0a4b9 16:24:50 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 16:24:50 INFO - mozversion platform_version: 53.0a1 16:24:50 INFO - using testdate: 1481329490 16:24:50 INFO - actual date: 1481329490 16:24:50 INFO - starting webserver on 'localhost:53455' 16:24:50 INFO - SUITE-START | Running 2 tests 16:24:50 INFO - TEST-START | dromaeo_css 16:24:50 INFO - operating with platform_type : linux_ 16:24:50 INFO - Initialising browser for dromaeo_css test... 16:24:50 INFO - TEST-INFO | started process 26686 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCxxwyq/profile http://localhost:53455/getInfo.html) 16:24:55 INFO - PROCESS | 26686 | __metrics Screen width/height:1600/1200 16:24:55 INFO - PROCESS | 26686 | colorDepth:24 16:24:55 INFO - PROCESS | 26686 | Browser inner width/height: 1024/697 16:24:55 INFO - PROCESS | 26686 | __metrics 16:24:55 INFO - PROCESS | 26686 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:24:55 INFO - PROCESS | 26686 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:24:56 INFO - PROCESS | 26686 | console.error: 16:24:56 INFO - PROCESS | 26686 | Message: Error: Connection closed before committing the transaction. 16:24:56 INFO - PROCESS | 26686 | Stack: 16:24:56 INFO - PROCESS | 26686 | executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 16:24:56 INFO - PROCESS | 26686 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 16:24:56 INFO - PROCESS | 26686 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 16:24:56 INFO - PROCESS | 26686 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 16:24:56 INFO - PROCESS | 26686 | completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 16:24:56 INFO - PROCESS | 26686 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 16:24:56 INFO - PROCESS | 26686 | observe@resource://gre/modules/AsyncShutdown.jsm:550:9 16:24:56 INFO - PROCESS | 26686 | addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 16:24:56 INFO - PROCESS | 26686 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 16:24:56 INFO - PROCESS | 26686 | moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 16:24:56 INFO - PROCESS | 26686 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 16:24:56 INFO - PROCESS | 26686 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 16:24:56 INFO - PROCESS | 26686 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 16:24:56 INFO - PROCESS | 26686 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 16:24:56 INFO - PROCESS | 26686 | 16:24:56 INFO - PROCESS | 26686 | console.error: PushService: 16:24:56 INFO - PROCESS | 26686 | stateChangeProcessEnqueue: Error transitioning state 16:24:56 INFO - PROCESS | 26686 | UnknownError 16:24:57 INFO - TEST-INFO | 26686: exit 0 16:24:57 INFO - Browser initialized. 16:24:57 INFO - Running cycle 1/1 for dromaeo_css test... 16:24:57 INFO - TEST-INFO | started process 26769 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCxxwyq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:25:09 INFO - PROCESS | 26769 | RSS: Main: 173850624 16:25:09 INFO - PROCESS | 26769 | 16:26:00 INFO - PROCESS | 26769 | 1481329560902 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:28:15 INFO - PROCESS | 26769 | Cycle 1(1): loaded http://localhost:53455/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53455/tests/dromaeo/cssquery-ext.html) 16:28:16 INFO - PROCESS | 26769 | RSS: Main: 316600320 16:28:16 INFO - PROCESS | 26769 | 16:31:19 INFO - PROCESS | 26769 | Cycle 1(1): loaded http://localhost:53455/tests/dromaeo/cssquery-ext.html (next: http://localhost:53455/tests/dromaeo/cssquery-jquery.html) 16:31:20 INFO - PROCESS | 26769 | RSS: Main: 274165760 16:31:20 INFO - PROCESS | 26769 | 16:34:22 INFO - PROCESS | 26769 | Cycle 1(1): loaded http://localhost:53455/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53455/tests/dromaeo/cssquery-mootools.html) 16:34:23 INFO - PROCESS | 26769 | RSS: Main: 220680192 16:34:23 INFO - PROCESS | 26769 | 16:37:25 INFO - PROCESS | 26769 | Cycle 1(1): loaded http://localhost:53455/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53455/tests/dromaeo/cssquery-prototype.html) 16:37:26 INFO - PROCESS | 26769 | RSS: Main: 255537152 16:37:26 INFO - PROCESS | 26769 | 16:40:29 INFO - PROCESS | 26769 | Cycle 1(1): loaded http://localhost:53455/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53455/tests/dromaeo/cssquery-yui.html) 16:40:30 INFO - PROCESS | 26769 | RSS: Main: 219717632 16:40:30 INFO - PROCESS | 26769 | 16:43:33 INFO - PROCESS | 26769 | Cycle 1(1): loaded http://localhost:53455/tests/dromaeo/cssquery-yui.html (next: http://localhost:53455/tests/dromaeo/cssquery-dojo.html) 16:43:33 INFO - PROCESS | 26769 | RSS: Main: 314114048 16:43:33 INFO - PROCESS | 26769 | 16:43:33 INFO - PROCESS | 26769 | __start_tp_report 16:43:33 INFO - PROCESS | 26769 | _x_x_mozilla_page_load 16:43:33 INFO - PROCESS | 26769 | _x_x_mozilla_page_load_details 16:43:33 INFO - PROCESS | 26769 | |i|pagename|runs| 16:43:33 INFO - PROCESS | 26769 | |0;dojo.html;2558.501092287004;2577.896884124635;2599.6230546570746;2621.1088230001797;2622.2919811650845;4532.886677833056;4590.196715574778;4634.675572709907;4657.37125488059;4680.882977925554;4621.768911554425;4627.953720462794;4636.420447444069;4647.093816705744;4746.264329028997;4264.211120942636;4301.698881078326;4306.633936115434;4310.245707001278;4313.2020576193345;4313.849015284466;4333.674974376919;4353.08585197109;4360.694751367406;4406.096750166208;4391.6486681065435;4416.072624748791;4436.068425630621;4455.242608756519;4457.487388950263;4213.915721685549;4238.21593005295;4246.532881383046;4352.216601011816;4354.216241076605;4272.636825869805;4276.935845962313;4279.122779830127;4280.6789490788005;4287.656987440997;4618.722876627413;4637.5826175644015;4644.373009643688;4646.883827904296;4651.790669419885;5028.195488721787;5046.09170349337;5057.165567681328;5067.34124563808;5073.670211436245;5054.469280725544;5066.493350664941;5070.08738427083;5070.2394640803805;5107.284980102789;4714.929276060793;4718.504557021498;4719.457262414832;4724.43306803179;4727.456342520585;4978.502149784992;5032.496750324938;5084.339035925306;5084.669496482791;5163.3545806774155;4893.143699852511;4897.510248975074;4921.704697718148;4968.627352948543;4970.179920313108;5012.24816277554;5013.49865013503;5014;5018.573421259234;5068.898622027613;4789.976050119797;4848.5636292733825;4989.3264409305;4991.625628077836;5037.244413338029;4877.341558889575;4878.8780280492565;4882.6826256293225;4886.364772579542;4888.951110488921;4176.164767046603;4270.295401258758;4273.893152671208;4274.551172126944;4274.957250427518;3682.1531047858607;3685.1339935115225;3713.6100709425664;3719.516462859811;3732.2722069196248;4174.874753757392;4192.182524407772;4195.223883581547;4220.978895105505;4231.386448964944;4818.831340903052;4823.589994850407;4871.878203044881;4875.448916871268;4924.507549245047;4173.165366926627;4225.070484493406;4246.235677578065;4293.785310734451;4305.547917468621;4577.267637178035;4580.770961451914;4582.908341833145;4615.699979501389;4627.120847039052;4697.436307642968;4699.060187962353;4706.082313948816;4712.269598212282;4742.004179122423;4731.668783185281;4801.231802911518;4802.135615589227;4953.826616068421;5024.271480635361;4646.349511068521;4705.411823522059;4728.243481043017;4741.122892264942;4752.073345697556;4961.404631444249;4963.305137280856;4967.05625931072;4974.825881094144;4983.651144419856;5045.596352291735;5046.646734728681;5052.72210028452;5054.494550544916;5101.9744901276745;4723.669343145947;4774.069056533943;4785;4836.081144582518;4860.076585448754;4885.8045678172475;4901.191303434894;4910.975445122752;4913.719494032878;4935.827246046371;4849.199882019569;4853.878653033702;4878.146324393146;4911.803527858989;4916.410030796327;4713.481517033055;4791.1854984652;4866.318715379921;4889.144399730104;4948.257761335684;4829.323894654822;4835.081334546425;4862.562369386772;4869.0261947611325;4875.317455556154 16:43:33 INFO - PROCESS | 26769 | |1;ext.html;509.2971699055304;518.3857129301778;537.8951104534617;554.6893739505875;565.138164299443;27635.309117272078;28326.85836570817;36948.81525592371;38377.42433863494;38538;38918;39200.41199382012;39290.21419571607;39296.60703392965;39308.41037384442;7197.604131772763;7412.258774122599;7413.25867413257;7414.369778568828;7419.406447484215;7370.299821516947;7392.926070739278;7570.394368450511;9417.81164376712;9663.468509231989;9700.417974921493;9773;9773.511324433786;9779.168770654504;9784.755381115458;9433.198178154866;9485.668001619979;9488.193503552206;9510.28672849539;9512.191463725558;9527.52362381885;9599.040095990415;9621.470819104947;9623.615055397846;9626.374285671409;35657.643423565954;35810;35929.64070359289;35952.460713089065;35958.280834383426;2965.569992351097;3218.227625369918;3233.3856567252146;3634.4184930411006;3667.7982710950887;26973.190804275902;27586.862065689544;27680.446391072066;27727.445451091065;27998.44003119926;3411.6076651185185;3472.392331342005;3521.9647803521893;3542;3588.3002814451193;236372;236916.52333906622;237090.8145459279;237274;238427;48201;48499.272510912364;48544;48792.756036219595;48902.266466003035;45583.77208114004;45687;50597.747011264706;50944.23583646248;51285.743571281906;14156.221407822668;14533.709325813425;14544.345504452325;14550.563483095524;14553.054051486832;10174.186065114773;11209.607663731731;11270;11292.830607540893;11296.41850140981;12247.693807654701;14254.572362829133;14286;14495;15171.317290721723;5920.733566989496;5984.700764961734;5988.820335389945;6032.306284777219;6111.51107911366;751.5340488896919;880.1242763450408;927.1074248345207;931.9841372903568;961.8797650293714;26033.609495857578;26489.735102649112;26624.60063099055;26872.193834185007;27146.592801108;829.3531045784298;879.8372301124313;880.225401646547;969.2730452160879;1009.9597414662909;13742.450301988009;14100.929495352662;14455.421783128768;14511.1293322401;14520.419183232552;346677;349213;350626;351031;351703;47096.7645161772;47450.28824567634;47507.28739068917;47560.286595701094;47626.5237347629;1080.2330345454816;1080.3355936099242;1133.2350663302238;1133.9886601133883;1156.0000599481557;24525.38686532851;27669.723302766713;27720;28475.288117796394;28514.002009929554;34091.48862767011;34331.656683433845;34402.82798585991;34428.655713442546;34430.48354274738;267184;267388;267449;267483;267882;33407.49888751622;34702.30595388028;34713.30573388569;34733.47899781556;34782.65217347896;38886.222275554894;38900.80549597347;38925;38951;39475.80262098671;33661.49507757337;33806.661933380354;33965.83017084899;34217.31565368629;34694;32495.35009299848;32768.344633107685;32811.50782738213;33017.17457063593;33616.663833362334;29711.85144074352;33452.665473344954;33770.155746106546;33880;34142;23989.640155397334;25153.87423062873;27089.86455067712;27790.583141252493;28126;22031;22113.78862113776;22351.105955761588;22392.440188995406;22433.77566224317 16:43:33 INFO - PROCESS | 26769 | |2;jquery.html;339.0726363396113;352.3340885725978;355.20257022983395;359.35854499717993;374.20294772135367;12413.131080824345;15263.694726105485;15537.92231038846;16698.7495187572;16849.494515164533;16315.98412682546;16362.7545586816;16412.75380869285;16517.91741041293;16668.33326666935;4904.215325547913;4920.729359885205;5010.574101201402;5044.470330615288;5142.254373115896;3487.267673788495;4918.409790825106;5014.874628134307;5017.899642007158;5019.498050194988;3664.963350366489;5580.302462192226;5849.239598852143;5890.499307558863;5976.312724036748;5704.971475142598;5854.414558544154;5962.075878238858;5972.910406343908;5973.671448070376;5170.276161337416;5171.758777893316;5173.430922598511;5177.689338639656;5180.171172612401;19145.425637230906;19617.117229724696;19630.41108766725;19665.508362290915;19776.20895164192;1801.6666916620336;1918.1560113550133;2054.342610364684;2149.140343862452;2445.730969593343;6213.161223234851;6276;6282.120503129566;10805.351678899213;12155.237490563906;1714.7599336092958;1717.802452717941;1773.5034190426702;1798.2087881332172;1826.2877477783675;61654.383456165764;63470.68264658647;63756;64998.67500662467;65082.67458662677;8916.955415223012;8943.150400712053;9117.589708463134;9260.814783704289;9391.201747851372;12169.2698438094;12220.3278819664;12253.387330633434;12283.201591896677;12293.692657683629;7110.936502479927;7204.379851100891;7275.745348912868;7416.22129676387;7470.70117195317;6969.0940177776565;7031.7890463286185;7066.434685225169;7186.687772371082;7380.5940673263485;5787.479126878601;5861.443162899518;5958.582899197101;5976.61152025117;6081.391860813883;1663.2182874049176;1693.5342780735396;1743.2504023269862;1750.1861634340094;1755.736639504059;784.8430313937235;793.2345286798268;794.8330850521339;821.5481485183126;909.3452714045876;15412.768808467881;16075.276612552463;16232.513024609281;16359.509214723577;16770.161491925355;794.7575989323312;825.0882774534053;840.6007146605314;856.9657213711382;910.899801021887;9012.8648070279;9314.208292295096;9619.134277915022;9744.658936937172;9865.309428339948;314083;318847;333701;336793;340239;65161.348386516474;65667.34332656613;65962.67018664876;66544.33455665382;67287;885.406777459104;897.0580890065536;919.6091661044163;923.6490133749267;932.1703683721494;14124.293785310489;14312.141271523325;14320.283985800877;14631.268436577915;15165.01427407215;33488.66511334953;33656.83171584126;33662.3267534643;33989.98030059102;34109.82945085259;42786.35820462633;42792;42951.785241073594;42991.57008430001;44470;6575.243846957661;6651.567648102858;6749.831254218684;7151.141862976476;7540.472166948261;10671.46642667879;11334.433278335886;11714;11716.297022178696;12010.459529321377;9749.268804839523;9766.80466390654;10003.34978226413;10298.845517317395;10480.58077676915;9753.756156096155;10143.08712215904;10222.693319200436;10379.06588407047;10394.636187733695;9582.425054496753;9601.807963840824;9764.755881102745;9827.705168844946;10816.67549973502;6409.316841407246;6493.837654058686;6526.543141980016;6657.1345725055435;6743.629100399525;7421.777346679608;9021.50381728985;9044.728658140266;9069.727908162766;9358.11097945694 16:43:33 INFO - PROCESS | 26769 | |3;mootools.html;1239.696274412769;1310.6395741171177;1346.5421756602752;1420.8223972003493;1633.5834362237629;6353.777617783373;6857.862842743142;7107.50742344107;7113.217546069928;7285.854282914352;7160.713571457136;7211.459140564465;7213.495055346128;7215.7835264942005;7219.63901804909;1784.6698360803293;1851.2687488442057;2060.2583070094793;2097.4127244371603;2101.4011006863047;2019.8384129269696;2103.274370342237;2119.0782009825775;2130.3289463818924;2131.467133216696;2044.100595737878;2057.0331943986303;2060.7733149353558;2061.5052387426986;2081.8022287882627;2025.3721346382667;2047.7645070816898;2066.772655007948;2075.885826279554;2082.9583408331823;2032.3394896658644;2035.9491012724652;2052.9486762830898;2061.0210150178696;2067.059487932987;7053.858922821566;7153.320434558708;7161.92838071623;7179.389751871097;7238.167610724781;4148.377743338493;4256.808443620013;4260.105377870651;4271.039016221356;4276.444062271916;2555.4062626434184;2636.8813403396894;2724.6457960465013;2729.2221716810614;2732.6584176977876;827.437342607027;853.1255463150259;871.8212766382876;874.903760586335;876.6055275126155;9450.763730906714;9491.952540237324;9492.667756628554;9503.524823758784;9515.952420237854;3590.335787879251;3798.8100594970692;3810.3141434541494;3827.7129215308964;3857.6528112470014;8161.918380816116;8168.42821002536;8170.060443049007;8180.304674102768;8186.467879587808;4291.785410729513;4330.891727706832;4334.41485399467;4335.501417337046;4341.869743907688;3528.8412021459094;3653.8355773990234;3811.256804923069;3951.545572259227;3959.861404850874;3343.4483310254022;3347.2301370684877;3469.392856250146;3475.721942244614;3482.5820901491984;1099.4997276239292;1099.631623406152;1100.4332768624165;1112.6161474291357;1137.0221609415896;708.3624737736079;847.2163248994656;848.6138806842872;857.5969294431607;863.3481721300378;4833.637477189154;4833.830815921426;4868.172410690191;4943.184374578226;5005.1241032819835;381.4126245581828;392.2762503181625;392.4427313215241;395.5905637665003;397.2690249940124;3375.932481350359;3387.779794313362;3393.541871847269;3425.1779572902324;3458.152752575635;7717.536947783151;7893.605319733991;7941.801454963556;7995;8014.358851291882;7929.841403171904;7948.244916732901;7957.880631790527;7961.402894782915;7973.561454120079;6321.462675672619;6341.714622841983;6384.872302554016;6385.7126429309865;6421.1652485177565;4623.445186577632;4680.227762419163;4686.1799185143145;4771.618270538418;4866.756662166807;7203.495755297288;7396.4082873368925;7456.701731930879;7464.029676142067;7470.92529074724;7455.291747283999;7466.664000120117;7471.476996610185;7497.812554685958;7511;5694.17434471991;5907.734151963258;6062.757489700539;6402.583832050902;6416.967915160394;6454.19322584677;6664.500162487736;6759.087523184504;6932.7226910923;6944.2708515607155;5970.253718285215;6006.159137720635;6107;6189.319174890668;6221.09794079865;6020.066889632114;6021.608595441282;6048.183495228264;6081.969590152021;6117.480014198843;5823.79616713413;5915.674637894957;5922.111683247547;5975.611585246945;5979.730912108878;4226.6618670506905;4260.723053001545;4268.466441694788;4312.892177695583;4411.779411029372;4156.937645935373;4237.724547904377;4272.700910936204;4292.527821939647;4385.912281754283 16:43:33 INFO - PROCESS | 26769 | |4;prototype.html;314.10793346894815;323.77335864894565;328.9210589458531;334.1762555301183;340.7989286321071;4604.838830640928;4759.357486739289;4775.90448191036;4829.613630909527;4857.198562237227;4580.885477863055;4682.695624784386;4719.050593379138;4736.621070314367;4768.23708206687;3844.9615503845025;3846.4230365445196;3846.8653597124107;3853.537575490945;3868.3423817951016;3681.7606855554304;3764;3856.8842934712;3879.243547508237;3881.3207688654375;3700;3805.6574908258253;3823.1971286029966;3824.808759562011;3841.500604921356;3777.886663400075;3816.923661526768;3824.158685089276;3829.1958688675413;3830.2996387546714;3446.758726889119;3449.724022078228;3454.619991800925;3458.291050334675;3469.6988629264024;4571.2000399929875;4659.813607455698;4676.859694209179;4692.648051396159;4711.293306004092;4358.5205627380965;4363.912721745579;4366.650667946588;4366.890827729332;4387.868363949055;5000.849804544975;5011.799528018875;5012.1980483122525;5023.773930173151;5026.5727413169925;3308.5368048473233;3503.3343664703625;3773.339665558516;3775.5658099318657;3777.9622203777885;5180.197069454241;5193.610479214074;5199.220116982483;5206.505381988705;5223.242629818655;3502.7022703070015;3539.4867744177463;3541.11472131967;3551.5560554930635;3561.1631266652316;3595.9032495088727;3601.94597081044;3607.549056367954;3615;3616.31290054894;3966.2464131814863;4128.699459670194;4137.027798467418;4150.792460376969;4156.168766246763;4962.352670786969;4963.5036496350795;4965.255211718199;4967.7019378837385;4975.4278258000795;1165.2542372881362;1184.5321098166175;1190.2798806721808;1216.2459275249403;1228.3121451986915;815.6288888555692;835.3692961813791;837.9162083791693;843.9493630382196;851.0978362935308;4273.764942928169;4282.5075116361395;4309.461317335333;4336.9783151084675;4349.716833534115;4842.975785121052;4962.478939711351;4983.750812459362;4988.376452943382;5014.17266151081;4227.196832601796;4250.277452833026;4271.807768650418;4273.252180868336;4291.74249545022;2643.9074632387774;2646.2193652872447;2649.4038841260563;2673.42521357909;2675.505031569166;4644.187267228289;4685.672002959828;4720.645951553579;4730.290456431494;4734.834280800155;4968.90062198754;5300.1254792958725;5315.176147697113;5322.893542129135;5334.306540149756;4800.975995120002;4846.588040016638;4860.343853579863;4865.95134048655;4882.4385195701525;4978.601711863114;5197.24639927216;5200.609954253522;5217.713025783618;5226.242194881797;5070.73231692077;5073;5086.669366491166;5097.954919241472;5117.2579975902545;4801.591864691401;4816.686915350491;4849.927251091307;4877.146499362669;4880.828601135773;3629.6370362963494;3640.3265395901844;3642.2351306225987;3645.507856439453;3663.900829751011;4958.975205123951;5158.277841102324;5174.662342788955;5179.352580927384;5189.636725429184;5000.974995125147;5007.649464537448;5012.348394708664;5015.974920125522;5043.949560504495;4824.107540105092;5000.999800040079;5019.3976722793495;5025.346704928336;5037.934446876763;4719.244920812654;4726.19654658702;4798.712077275374;4883.682560633548;4914.778834952507;4999.775145031507;5004.874878128076;5010.298558201927;5032.672876263031;5057.949420505895;4905.313256144214;4910.263460480814;4935.864751107337;4941.407031156285;4954.529319714621 16:43:33 INFO - PROCESS | 26769 | |5;yui.html;599.9460048595625;639.4468784501405;663.1365861927982;677.0724107972079;685.6160550091946;12142.210756300845;13435.126716763416;13497;13523.323833808296;13586.18482891026;11389.772204555904;11552.53789848405;11563.132765042634;12893.419796109174;12895.677608059803;765.4718244411366;780.4458834227677;781.9765407037798;781.9921800782005;782.3662833105175;628.4877824572982;648.1444493268887;766.8657984852656;789.4000559574736;792.8057625881638;834.7913021744564;836.5608055770739;836.9079401265856;837.0624900111883;837.4221786967004;815.6533473273894;820.8768684697284;827.3711978896022;827.4414770030205;828.3662997806682;474.7460108841753;485.226064427238;574.9683767392792;596.0582279997593;602.4367216652444;12651.873481265162;12696.619101426879;12702.237865728086;12715.364231788373;12721.173123746927;11810.23233489829;12116.939415302955;12361.876381236163;12467.438965246585;12670.239785612892;9769.413835169842;9959.253056020754;10361.119304859096;10375.636852710119;10650.680479585624;4050.5544390117066;4095;4106.466159399259;4139.068709540359;4188.937165942483;58456.41543584595;60014;60941;61929.071063934076;62473;9934.701958941243;10053.19574434058;10092.04125608066;10092.14216791581;10164.237682173867;17753;17816.91091544534;17981.280748770165;18138.909305453653;18153.636927261647;6560.44236239925;6574.408303252731;6644.60132392058;6734.8989765153565;6750.628715420601;3420.6237313895454;3481.860725570949;3494.266204097168;3505.1938054247667;3608.585012723518;2722.659667541557;2793.7904657150793;2805.2005178524023;2826.9293267668063;2829.773618110546;467.2034181720159;467.7941705649503;468.2938395649027;469.0220889445476;474.5776259129378;437.3287004448157;441.2322558747756;486.34586481182754;516.2694786876527;530.1835173832333;7023.403010744042;7264.818379540554;8359.373047021498;8403.663853445794;8697.304215662733;453.7617750680919;539.5090467674397;568.9516391106803;599.8183578350643;613.7698363113832;3439.174598096489;3601.819909004539;3602.8018458985007;3723.069232691827;3790.7346485746284;58872.705636471546;59882.70058649766;59987.70006150028;61643;61691.383086168564;46361.20484939383;52902.735486323094;53110.20334694983;54311.72844135754;54627;47050.76474617605;47460.5253947463;47992;48707.51292487171;49340.50659493359;6939.514234003571;7090.290970902868;7296.671649775667;7404.888926665996;7505.362044226302;32100;32879;32891.50662740109;33530.32939341248;34290.657093428745;42370;45229.09541809211;45347.77326113348;47592.2861157076;47598.76200618975;9579.808403831745;9834.311598188058;9941.552630131517;9945.717913088809;10050.396976181453;11161.55353785872;11257.662270131908;11375.43122843838;11684.357360345262;12056.336901470504;9901.801963960825;9988.550515226687;9990.200783937413;10118.443485608068;10382.948085259526;9805.55874985613;9861.013898610081;10024.198064154703;10894.400807955322;11098.61154859576;9538;9674.322797404115;9769.902300976899;10037;10296.382217067;8823.514706691194;9257.537123143682;9365.531723413938;9379.577918993798;9413.81172376535;8500.277476414574;8772.342074344322;8782.209601135928;8797;9153.63385464574 16:43:33 INFO - PROCESS | 26769 | __end_tp_report 16:43:33 INFO - PROCESS | 26769 | __start_cc_report 16:43:33 INFO - PROCESS | 26769 | _x_x_mozilla_cycle_collect,1416 16:43:33 INFO - PROCESS | 26769 | __end_cc_report 16:43:33 INFO - PROCESS | 26769 | __startTimestamp1481330613673__endTimestamp 16:43:33 INFO - PROCESS | 26769 | 16:43:33 INFO - PROCESS | 26769 | ------- Summary: start ------- 16:43:33 INFO - PROCESS | 26769 | Number of tests: 6 16:43:33 INFO - PROCESS | 26769 | 16:43:33 INFO - PROCESS | 26769 | [#0] dojo.html Cycles:165 Average:4615.66 Median:4728.24 stddev:482.88 (10.2%) stddev-sans-first:456.59 16:43:33 INFO - PROCESS | 26769 | Values: 2558.5 2577.9 2599.6 2621.1 2622.3 4532.9 4590.2 4634.7 4657.4 4680.9 4621.8 4628.0 4636.4 4647.1 4746.3 4264.2 4301.7 4306.6 4310.2 4313.2 4313.8 4333.7 4353.1 4360.7 4406.1 4391.6 4416.1 4436.1 4455.2 4457.5 4213.9 4238.2 4246.5 4352.2 4354.2 4272.6 4276.9 4279.1 4280.7 4287.7 4618.7 4637.6 4644.4 4646.9 4651.8 5028.2 5046.1 5057.2 5067.3 5073.7 5054.5 5066.5 5070.1 5070.2 5107.3 4714.9 4718.5 4719.5 4724.4 4727.5 4978.5 5032.5 5084.3 5084.7 5163.4 4893.1 4897.5 4921.7 4968.6 4970.2 5012.2 5013.5 5014.0 5018.6 5068.9 4790.0 4848.6 4989.3 4991.6 5037.2 4877.3 4878.9 4882.7 4886.4 4889.0 4176.2 4270.3 4273.9 4274.6 4275.0 3682.2 3685.1 3713.6 3719.5 3732.3 4174.9 4192.2 4195.2 4221.0 4231.4 4818.8 4823.6 4871.9 4875.4 4924.5 4173.2 4225.1 4246.2 4293.8 4305.5 4577.3 4580.8 4582.9 4615.7 4627.1 4697.4 4699.1 4706.1 4712.3 4742.0 4731.7 4801.2 4802.1 4953.8 5024.3 4646.3 4705.4 4728.2 4741.1 4752.1 4961.4 4963.3 4967.1 4974.8 4983.7 5045.6 5046.6 5052.7 5054.5 5102.0 4723.7 4774.1 4785.0 4836.1 4860.1 4885.8 4901.2 4911.0 4913.7 4935.8 4849.2 4853.9 4878.1 4911.8 4916.4 4713.5 4791.2 4866.3 4889.1 4948.3 4829.3 4835.1 4862.6 4869.0 4875.3 16:43:33 INFO - PROCESS | 26769 | 16:43:33 INFO - PROCESS | 26769 | [#1] ext.html Cycles:180 Average:43368.26 Median:19092.85 stddev:75737.52 (396.7%) stddev-sans-first:75881.62 16:43:33 INFO - PROCESS | 26769 | Values: 509.3 518.4 537.9 554.7 565.1 27635.3 28326.9 36948.8 38377.4 38538.0 38918.0 39200.4 39290.2 39296.6 39308.4 7197.6 7412.3 7413.3 7414.4 7419.4 7370.3 7392.9 7570.4 9417.8 9663.5 9700.4 9773.0 9773.5 9779.2 9784.8 9433.2 9485.7 9488.2 9510.3 9512.2 9527.5 9599.0 9621.5 9623.6 9626.4 35657.6 35810.0 35929.6 35952.5 35958.3 2965.6 3218.2 3233.4 3634.4 3667.8 26973.2 27586.9 27680.4 27727.4 27998.4 3411.6 3472.4 3522.0 3542.0 3588.3 236372.0 236916.5 237090.8 237274.0 238427.0 48201.0 48499.3 48544.0 48792.8 48902.3 45583.8 45687.0 50597.7 50944.2 51285.7 14156.2 14533.7 14544.3 14550.6 14553.1 10174.2 11209.6 11270.0 11292.8 11296.4 12247.7 14254.6 14286.0 14495.0 15171.3 5920.7 5984.7 5988.8 6032.3 6111.5 751.5 880.1 927.1 932.0 961.9 26033.6 26489.7 26624.6 26872.2 27146.6 829.4 879.8 880.2 969.3 1010.0 13742.5 14100.9 14455.4 14511.1 14520.4 346677.0 349213.0 350626.0 351031.0 351703.0 47096.8 47450.3 47507.3 47560.3 47626.5 1080.2 1080.3 1133.2 1134.0 1156.0 24525.4 27669.7 27720.0 28475.3 28514.0 34091.5 34331.7 34402.8 34428.7 34430.5 267184.0 267388.0 267449.0 267483.0 267882.0 33407.5 34702.3 34713.3 34733.5 34782.7 38886.2 38900.8 38925.0 38951.0 39475.8 33661.5 33806.7 33965.8 34217.3 34694.0 32495.4 32768.3 32811.5 33017.2 33616.7 29711.9 33452.7 33770.2 33880.0 34142.0 23989.6 25153.9 27089.9 27790.6 28126.0 22031.0 22113.8 22351.1 22392.4 22433.8 16:43:33 INFO - PROCESS | 26769 | 16:43:33 INFO - PROCESS | 26769 | [#2] jquery.html Cycles:180 Average:21551.37 Median:5156.27 stddev:54301.87 (1053.1%) stddev-sans-first:54430.85 16:43:33 INFO - PROCESS | 26769 | Values: 339.1 352.3 355.2 359.4 374.2 12413.1 15263.7 15537.9 16698.7 16849.5 16316.0 16362.8 16412.8 16517.9 16668.3 4904.2 4920.7 5010.6 5044.5 5142.3 3487.3 4918.4 5014.9 5017.9 5019.5 3665.0 5580.3 5849.2 5890.5 5976.3 5705.0 5854.4 5962.1 5972.9 5973.7 5170.3 5171.8 5173.4 5177.7 5180.2 19145.4 19617.1 19630.4 19665.5 19776.2 1801.7 1918.2 2054.3 2149.1 2445.7 6213.2 6276.0 6282.1 10805.4 12155.2 1714.8 1717.8 1773.5 1798.2 1826.3 61654.4 63470.7 63756.0 64998.7 65082.7 8917.0 8943.2 9117.6 9260.8 9391.2 12169.3 12220.3 12253.4 12283.2 12293.7 7110.9 7204.4 7275.7 7416.2 7470.7 6969.1 7031.8 7066.4 7186.7 7380.6 5787.5 5861.4 5958.6 5976.6 6081.4 1663.2 1693.5 1743.3 1750.2 1755.7 784.8 793.2 794.8 821.5 909.3 15412.8 16075.3 16232.5 16359.5 16770.2 794.8 825.1 840.6 857.0 910.9 9012.9 9314.2 9619.1 9744.7 9865.3 314083.0 318847.0 333701.0 336793.0 340239.0 65161.3 65667.3 65962.7 66544.3 67287.0 885.4 897.1 919.6 923.6 932.2 14124.3 14312.1 14320.3 14631.3 15165.0 33488.7 33656.8 33662.3 33990.0 34109.8 42786.4 42792.0 42951.8 42991.6 44470.0 6575.2 6651.6 6749.8 7151.1 7540.5 10671.5 11334.4 11714.0 11716.3 12010.5 9749.3 9766.8 10003.3 10298.8 10480.6 9753.8 10143.1 10222.7 10379.1 10394.6 9582.4 9601.8 9764.8 9827.7 10816.7 6409.3 6493.8 6526.5 6657.1 6743.6 7421.8 9021.5 9044.7 9069.7 9358.1 16:43:33 INFO - PROCESS | 26769 | 16:43:33 INFO - PROCESS | 26769 | [#3] mootools.html Cycles:180 Average:4601.80 Median:4850.29 stddev:2477.06 (51.1%) stddev-sans-first:2471.12 16:43:33 INFO - PROCESS | 26769 | Values: 1239.7 1310.6 1346.5 1420.8 1633.6 6353.8 6857.9 7107.5 7113.2 7285.9 7160.7 7211.5 7213.5 7215.8 7219.6 1784.7 1851.3 2060.3 2097.4 2101.4 2019.8 2103.3 2119.1 2130.3 2131.5 2044.1 2057.0 2060.8 2061.5 2081.8 2025.4 2047.8 2066.8 2075.9 2083.0 2032.3 2035.9 2052.9 2061.0 2067.1 7053.9 7153.3 7161.9 7179.4 7238.2 4148.4 4256.8 4260.1 4271.0 4276.4 2555.4 2636.9 2724.6 2729.2 2732.7 827.4 853.1 871.8 874.9 876.6 9450.8 9492.0 9492.7 9503.5 9516.0 3590.3 3798.8 3810.3 3827.7 3857.7 8161.9 8168.4 8170.1 8180.3 8186.5 4291.8 4330.9 4334.4 4335.5 4341.9 3528.8 3653.8 3811.3 3951.5 3959.9 3343.4 3347.2 3469.4 3475.7 3482.6 1099.5 1099.6 1100.4 1112.6 1137.0 708.4 847.2 848.6 857.6 863.3 4833.6 4833.8 4868.2 4943.2 5005.1 381.4 392.3 392.4 395.6 397.3 3375.9 3387.8 3393.5 3425.2 3458.2 7717.5 7893.6 7941.8 7995.0 8014.4 7929.8 7948.2 7957.9 7961.4 7973.6 6321.5 6341.7 6384.9 6385.7 6421.2 4623.4 4680.2 4686.2 4771.6 4866.8 7203.5 7396.4 7456.7 7464.0 7470.9 7455.3 7466.7 7471.5 7497.8 7511.0 5694.2 5907.7 6062.8 6402.6 6417.0 6454.2 6664.5 6759.1 6932.7 6944.3 5970.3 6006.2 6107.0 6189.3 6221.1 6020.1 6021.6 6048.2 6082.0 6117.5 5823.8 5915.7 5922.1 5975.6 5979.7 4226.7 4260.7 4268.5 4312.9 4411.8 4156.9 4237.7 4272.7 4292.5 4385.9 16:43:33 INFO - PROCESS | 26769 | 16:43:33 INFO - PROCESS | 26769 | [#4] prototype.html Cycles:180 Average:4146.66 Median:4719.15 stddev:1200.28 (25.4%) stddev-sans-first:1168.66 16:43:33 INFO - PROCESS | 26769 | Values: 314.1 323.8 328.9 334.2 340.8 4604.8 4759.4 4775.9 4829.6 4857.2 4580.9 4682.7 4719.1 4736.6 4768.2 3845.0 3846.4 3846.9 3853.5 3868.3 3681.8 3764.0 3856.9 3879.2 3881.3 3700.0 3805.7 3823.2 3824.8 3841.5 3777.9 3816.9 3824.2 3829.2 3830.3 3446.8 3449.7 3454.6 3458.3 3469.7 4571.2 4659.8 4676.9 4692.6 4711.3 4358.5 4363.9 4366.7 4366.9 4387.9 5000.8 5011.8 5012.2 5023.8 5026.6 3308.5 3503.3 3773.3 3775.6 3778.0 5180.2 5193.6 5199.2 5206.5 5223.2 3502.7 3539.5 3541.1 3551.6 3561.2 3595.9 3601.9 3607.5 3615.0 3616.3 3966.2 4128.7 4137.0 4150.8 4156.2 4962.4 4963.5 4965.3 4967.7 4975.4 1165.3 1184.5 1190.3 1216.2 1228.3 815.6 835.4 837.9 843.9 851.1 4273.8 4282.5 4309.5 4337.0 4349.7 4843.0 4962.5 4983.8 4988.4 5014.2 4227.2 4250.3 4271.8 4273.3 4291.7 2643.9 2646.2 2649.4 2673.4 2675.5 4644.2 4685.7 4720.6 4730.3 4734.8 4968.9 5300.1 5315.2 5322.9 5334.3 4801.0 4846.6 4860.3 4866.0 4882.4 4978.6 5197.2 5200.6 5217.7 5226.2 5070.7 5073.0 5086.7 5098.0 5117.3 4801.6 4816.7 4849.9 4877.1 4880.8 3629.6 3640.3 3642.2 3645.5 3663.9 4959.0 5158.3 5174.7 5179.4 5189.6 5001.0 5007.6 5012.3 5016.0 5043.9 4824.1 5001.0 5019.4 5025.3 5037.9 4719.2 4726.2 4798.7 4883.7 4914.8 4999.8 5004.9 5010.3 5032.7 5057.9 4905.3 4910.3 4935.9 4941.4 4954.5 16:43:33 INFO - PROCESS | 26769 | 16:43:33 INFO - PROCESS | 26769 | [#5] yui.html Cycles:180 Average:13897.87 Median:49024.01 stddev:17295.00 (35.3%) stddev-sans-first:17314.69 16:43:33 INFO - PROCESS | 26769 | Values: 599.9 639.4 663.1 677.1 685.6 12142.2 13435.1 13497.0 13523.3 13586.2 11389.8 11552.5 11563.1 12893.4 12895.7 765.5 780.4 782.0 782.0 782.4 628.5 648.1 766.9 789.4 792.8 834.8 836.6 836.9 837.1 837.4 815.7 820.9 827.4 827.4 828.4 474.7 485.2 575.0 596.1 602.4 12651.9 12696.6 12702.2 12715.4 12721.2 11810.2 12116.9 12361.9 12467.4 12670.2 9769.4 9959.3 10361.1 10375.6 10650.7 4050.6 4095.0 4106.5 4139.1 4188.9 58456.4 60014.0 60941.0 61929.1 62473.0 9934.7 10053.2 10092.0 10092.1 10164.2 17753.0 17816.9 17981.3 18138.9 18153.6 6560.4 6574.4 6644.6 6734.9 6750.6 3420.6 3481.9 3494.3 3505.2 3608.6 2722.7 2793.8 2805.2 2826.9 2829.8 467.2 467.8 468.3 469.0 474.6 437.3 441.2 486.3 516.3 530.2 7023.4 7264.8 8359.4 8403.7 8697.3 453.8 539.5 569.0 599.8 613.8 3439.2 3601.8 3602.8 3723.1 3790.7 58872.7 59882.7 59987.7 61643.0 61691.4 46361.2 52902.7 53110.2 54311.7 54627.0 47050.8 47460.5 47992.0 48707.5 49340.5 6939.5 7090.3 7296.7 7404.9 7505.4 32100.0 32879.0 32891.5 33530.3 34290.7 42370.0 45229.1 45347.8 47592.3 47598.8 9579.8 9834.3 9941.6 9945.7 10050.4 11161.6 11257.7 11375.4 11684.4 12056.3 9901.8 9988.6 9990.2 10118.4 10382.9 9805.6 9861.0 10024.2 10894.4 11098.6 9538.0 9674.3 9769.9 10037.0 10296.4 8823.5 9257.5 9365.5 9379.6 9413.8 8500.3 8772.3 8782.2 8797.0 9153.6 16:43:33 INFO - PROCESS | 26769 | -------- Summary: end -------- 16:43:33 INFO - PROCESS | 26769 | 16:43:34 INFO - TEST-INFO | 26769: exit 0 16:43:34 INFO - TEST-OK | dromaeo_css | took 1124019ms 16:43:34 INFO - TEST-START | kraken 16:43:34 INFO - operating with platform_type : linux_ 16:43:34 INFO - Initialising browser for kraken test... 16:43:34 INFO - TEST-INFO | started process 27070 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3Pgjmc/profile http://localhost:53455/getInfo.html) 16:43:39 INFO - PROCESS | 27070 | __metrics Screen width/height:1600/1200 16:43:39 INFO - PROCESS | 27070 | colorDepth:24 16:43:39 INFO - PROCESS | 27070 | Browser inner width/height: 1024/697 16:43:39 INFO - PROCESS | 27070 | __metrics 16:43:40 INFO - PROCESS | 27070 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:43:40 INFO - PROCESS | 27070 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:43:40 INFO - PROCESS | 27070 | console.error: PushService: 16:43:40 INFO - PROCESS | 27070 | stateChangeProcessEnqueue: Error transitioning state 16:43:40 INFO - PROCESS | 27070 | UnknownError 16:43:41 INFO - TEST-INFO | 27070: exit 0 16:43:41 INFO - Browser initialized. 16:43:41 INFO - Running cycle 1/1 for kraken test... 16:43:41 INFO - TEST-INFO | started process 27156 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3Pgjmc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:43:53 INFO - PROCESS | 27156 | RSS: Main: 173371392 16:43:53 INFO - PROCESS | 27156 | 16:43:56 INFO - PROCESS | 27156 | Cycle 1(1): loaded http://localhost:53455/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53455/tests/kraken/driver.html?testName=audio-beat-detection) 16:43:57 INFO - PROCESS | 27156 | RSS: Main: 235405312 16:43:57 INFO - PROCESS | 27156 | 16:44:00 INFO - PROCESS | 27156 | Cycle 1(1): loaded http://localhost:53455/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53455/tests/kraken/driver.html?testName=audio-dft) 16:44:00 INFO - PROCESS | 27156 | RSS: Main: 218349568 16:44:00 INFO - PROCESS | 27156 | 16:44:04 INFO - PROCESS | 27156 | Cycle 1(1): loaded http://localhost:53455/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53455/tests/kraken/driver.html?testName=audio-fft) 16:44:04 INFO - PROCESS | 27156 | RSS: Main: 301162496 16:44:04 INFO - PROCESS | 27156 | 16:44:06 INFO - PROCESS | 27156 | Cycle 1(1): loaded http://localhost:53455/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53455/tests/kraken/driver.html?testName=audio-oscillator) 16:44:07 INFO - PROCESS | 27156 | RSS: Main: 211816448 16:44:07 INFO - PROCESS | 27156 | 16:44:09 INFO - PROCESS | 27156 | Cycle 1(1): loaded http://localhost:53455/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53455/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:44:10 INFO - PROCESS | 27156 | RSS: Main: 214310912 16:44:10 INFO - PROCESS | 27156 | 16:44:13 INFO - PROCESS | 27156 | Cycle 1(1): loaded http://localhost:53455/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53455/tests/kraken/driver.html?testName=imaging-darkroom) 16:44:14 INFO - PROCESS | 27156 | RSS: Main: 254300160 16:44:14 INFO - PROCESS | 27156 | 16:44:17 INFO - PROCESS | 27156 | Cycle 1(1): loaded http://localhost:53455/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53455/tests/kraken/driver.html?testName=imaging-desaturate) 16:44:18 INFO - PROCESS | 27156 | RSS: Main: 253915136 16:44:18 INFO - PROCESS | 27156 | 16:44:21 INFO - PROCESS | 27156 | Cycle 1(1): loaded http://localhost:53455/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53455/tests/kraken/driver.html?testName=json-parse-financial) 16:44:21 INFO - PROCESS | 27156 | RSS: Main: 252936192 16:44:21 INFO - PROCESS | 27156 | 16:44:23 INFO - PROCESS | 27156 | Cycle 1(1): loaded http://localhost:53455/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53455/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:44:24 INFO - PROCESS | 27156 | RSS: Main: 215072768 16:44:24 INFO - PROCESS | 27156 | 16:44:26 INFO - PROCESS | 27156 | Cycle 1(1): loaded http://localhost:53455/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53455/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:44:26 INFO - PROCESS | 27156 | RSS: Main: 222543872 16:44:26 INFO - PROCESS | 27156 | 16:44:28 INFO - PROCESS | 27156 | Cycle 1(1): loaded http://localhost:53455/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53455/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:44:29 INFO - PROCESS | 27156 | RSS: Main: 226787328 16:44:29 INFO - PROCESS | 27156 | 16:44:32 INFO - PROCESS | 27156 | Cycle 1(1): loaded http://localhost:53455/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53455/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:44:33 INFO - PROCESS | 27156 | RSS: Main: 225529856 16:44:33 INFO - PROCESS | 27156 | 16:44:36 INFO - PROCESS | 27156 | Cycle 1(1): loaded http://localhost:53455/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53455/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:44:36 INFO - PROCESS | 27156 | RSS: Main: 228360192 16:44:36 INFO - PROCESS | 27156 | 16:44:38 INFO - PROCESS | 27156 | Cycle 1(1): loaded http://localhost:53455/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53455/tests/kraken/driver.html?testName=ai-astar) 16:44:38 INFO - PROCESS | 27156 | RSS: Main: 281964544 16:44:38 INFO - PROCESS | 27156 | 16:44:38 INFO - PROCESS | 27156 | __start_tp_report 16:44:38 INFO - PROCESS | 27156 | _x_x_mozilla_page_load 16:44:38 INFO - PROCESS | 27156 | _x_x_mozilla_page_load_details 16:44:38 INFO - PROCESS | 27156 | |i|pagename|runs| 16:44:38 INFO - PROCESS | 27156 | |0;ai-astar;114;114;114;114;114;113;114;114;115;115 16:44:38 INFO - PROCESS | 27156 | |1;audio-beat-detection;152;132;129;165;136;130;139;129;163;137 16:44:38 INFO - PROCESS | 27156 | |2;audio-dft;162;163;163;162;162;160;162;161;160;161 16:44:38 INFO - PROCESS | 27156 | |3;audio-fft;75;75;76;106;77;77;89;80;93;76 16:44:38 INFO - PROCESS | 27156 | |4;audio-oscillator;84;85;110;85;85;84;104;85;86;106 16:44:38 INFO - PROCESS | 27156 | |5;imaging-gaussian-blur;128;128;127;128;128;127;128;128;127;127 16:44:38 INFO - PROCESS | 27156 | |6;imaging-darkroom;147;146;146;147;148;146;146;147;148;147 16:44:38 INFO - PROCESS | 27156 | |7;imaging-desaturate;96;96;95;95;96;95;96;95;95;95 16:44:38 INFO - PROCESS | 27156 | |8;json-parse-financial;66;65;73;64;65;66;65;65;66;65 16:44:38 INFO - PROCESS | 27156 | |9;json-stringify-tinderbox;65;66;66;66;65;66;66;66;66;66 16:44:38 INFO - PROCESS | 27156 | |10;stanford-crypto-aes;99;87;93;89;95;90;90;94;132;90 16:44:38 INFO - PROCESS | 27156 | |11;stanford-crypto-ccm;143;142;142;150;144;141;149;141;158;151 16:44:38 INFO - PROCESS | 27156 | |12;stanford-crypto-pbkdf2;183;180;182;185;182;181;180;189;180;179 16:44:38 INFO - PROCESS | 27156 | |13;stanford-crypto-sha256-iterative;74;70;70;69;69;69;68;68;69;67 16:44:38 INFO - PROCESS | 27156 | __end_tp_report 16:44:38 INFO - PROCESS | 27156 | __start_cc_report 16:44:38 INFO - PROCESS | 27156 | _x_x_mozilla_cycle_collect,1968 16:44:38 INFO - PROCESS | 27156 | __end_cc_report 16:44:38 INFO - PROCESS | 27156 | __startTimestamp1481330678936__endTimestamp 16:44:38 INFO - PROCESS | 27156 | 16:44:38 INFO - PROCESS | 27156 | ------- Summary: start ------- 16:44:38 INFO - PROCESS | 27156 | Number of tests: 14 16:44:38 INFO - PROCESS | 27156 | 16:44:38 INFO - PROCESS | 27156 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 16:44:38 INFO - PROCESS | 27156 | Values: 114.0 114.0 114.0 114.0 114.0 113.0 114.0 114.0 115.0 115.0 16:44:38 INFO - PROCESS | 27156 | 16:44:38 INFO - PROCESS | 27156 | [#1] audio-beat-detection Cycles:10 Average:141.20 Median:138.00 stddev:13.81 (10.0%) stddev-sans-first:14.08 16:44:38 INFO - PROCESS | 27156 | Values: 152.0 132.0 129.0 165.0 136.0 130.0 139.0 129.0 163.0 137.0 16:44:38 INFO - PROCESS | 27156 | 16:44:38 INFO - PROCESS | 27156 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:1.07 (0.7%) stddev-sans-first:1.13 16:44:38 INFO - PROCESS | 27156 | Values: 162.0 163.0 163.0 162.0 162.0 160.0 162.0 161.0 160.0 161.0 16:44:38 INFO - PROCESS | 27156 | 16:44:38 INFO - PROCESS | 27156 | [#3] audio-fft Cycles:10 Average:82.40 Median:77.00 stddev:10.37 (13.5%) stddev-sans-first:10.65 16:44:38 INFO - PROCESS | 27156 | Values: 75.0 75.0 76.0 106.0 77.0 77.0 89.0 80.0 93.0 76.0 16:44:38 INFO - PROCESS | 27156 | 16:44:38 INFO - PROCESS | 27156 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:85.00 stddev:10.65 (12.5%) stddev-sans-first:10.95 16:44:38 INFO - PROCESS | 27156 | Values: 84.0 85.0 110.0 85.0 85.0 84.0 104.0 85.0 86.0 106.0 16:44:38 INFO - PROCESS | 27156 | 16:44:38 INFO - PROCESS | 27156 | [#5] imaging-gaussian-blur Cycles:10 Average:127.60 Median:128.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 16:44:38 INFO - PROCESS | 27156 | Values: 128.0 128.0 127.0 128.0 128.0 127.0 128.0 128.0 127.0 127.0 16:44:38 INFO - PROCESS | 27156 | 16:44:38 INFO - PROCESS | 27156 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 16:44:38 INFO - PROCESS | 27156 | Values: 147.0 146.0 146.0 147.0 148.0 146.0 146.0 147.0 148.0 147.0 16:44:38 INFO - PROCESS | 27156 | 16:44:38 INFO - PROCESS | 27156 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 16:44:38 INFO - PROCESS | 27156 | Values: 96.0 96.0 95.0 95.0 96.0 95.0 96.0 95.0 95.0 95.0 16:44:38 INFO - PROCESS | 27156 | 16:44:38 INFO - PROCESS | 27156 | [#8] json-parse-financial Cycles:10 Average:66.00 Median:65.50 stddev:2.54 (3.9%) stddev-sans-first:2.69 16:44:38 INFO - PROCESS | 27156 | Values: 66.0 65.0 73.0 64.0 65.0 66.0 65.0 65.0 66.0 65.0 16:44:38 INFO - PROCESS | 27156 | 16:44:38 INFO - PROCESS | 27156 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.42 (0.6%) stddev-sans-first:0.33 16:44:38 INFO - PROCESS | 27156 | Values: 65.0 66.0 66.0 66.0 65.0 66.0 66.0 66.0 66.0 66.0 16:44:38 INFO - PROCESS | 27156 | 16:44:38 INFO - PROCESS | 27156 | [#10] stanford-crypto-aes Cycles:10 Average:95.90 Median:91.50 stddev:13.15 (14.4%) stddev-sans-first:13.90 16:44:38 INFO - PROCESS | 27156 | Values: 99.0 87.0 93.0 89.0 95.0 90.0 90.0 94.0 132.0 90.0 16:44:38 INFO - PROCESS | 27156 | 16:44:38 INFO - PROCESS | 27156 | [#11] stanford-crypto-ccm Cycles:10 Average:146.10 Median:146.50 stddev:5.67 (3.9%) stddev-sans-first:5.90 16:44:38 INFO - PROCESS | 27156 | Values: 143.0 142.0 142.0 150.0 144.0 141.0 149.0 141.0 158.0 151.0 16:44:38 INFO - PROCESS | 27156 | 16:44:38 INFO - PROCESS | 27156 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.10 Median:182.00 stddev:3.00 (1.6%) stddev-sans-first:3.16 16:44:38 INFO - PROCESS | 27156 | Values: 183.0 180.0 182.0 185.0 182.0 181.0 180.0 189.0 180.0 179.0 16:44:38 INFO - PROCESS | 27156 | 16:44:38 INFO - PROCESS | 27156 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:69.00 stddev:1.89 (2.7%) stddev-sans-first:0.97 16:44:38 INFO - PROCESS | 27156 | Values: 74.0 70.0 70.0 69.0 69.0 69.0 68.0 68.0 69.0 67.0 16:44:38 INFO - PROCESS | 27156 | -------- Summary: end -------- 16:44:38 INFO - PROCESS | 27156 | 16:44:40 INFO - TEST-INFO | 27156: exit 0 16:44:40 INFO - TEST-OK | kraken | took 65623ms 16:44:40 INFO - SUITE-END | took 1189s 16:44:40 INFO - Completed test suite (00:19:49) 16:44:40 INFO - javascript benchmark 16:44:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2558.501092287004, 2577.896884124635, 2599.6230546570746, 2621.1088230001797, 2622.2919811650845, 4532.886677833056, 4590.196715574778, 4634.675572709907, 4657.37125488059, 4680.882977925554, 4621.768911554425, 4627.953720462794, 4636.420447444069, 4647.093816705744, 4746.264329028997, 4264.211120942636, 4301.698881078326, 4306.633936115434, 4310.245707001278, 4313.2020576193345, 4313.849015284466, 4333.674974376919, 4353.08585197109, 4360.694751367406, 4406.096750166208, 4391.6486681065435, 4416.072624748791, 4436.068425630621, 4455.242608756519, 4457.487388950263, 4213.915721685549, 4238.21593005295, 4246.532881383046, 4352.216601011816, 4354.216241076605, 4272.636825869805, 4276.935845962313, 4279.122779830127, 4280.6789490788005, 4287.656987440997, 4618.722876627413, 4637.5826175644015, 4644.373009643688, 4646.883827904296, 4651.790669419885, 5028.195488721787, 5046.09170349337, 5057.165567681328, 5067.34124563808, 5073.670211436245, 5054.469280725544, 5066.493350664941, 5070.08738427083, 5070.2394640803805, 5107.284980102789, 4714.929276060793, 4718.504557021498, 4719.457262414832, 4724.43306803179, 4727.456342520585, 4978.502149784992, 5032.496750324938, 5084.339035925306, 5084.669496482791, 5163.3545806774155, 4893.143699852511, 4897.510248975074, 4921.704697718148, 4968.627352948543, 4970.179920313108, 5012.24816277554, 5013.49865013503, 5014.0, 5018.573421259234, 5068.898622027613, 4789.976050119797, 4848.5636292733825, 4989.3264409305, 4991.625628077836, 5037.244413338029, 4877.341558889575, 4878.8780280492565, 4882.6826256293225, 4886.364772579542, 4888.951110488921, 4176.164767046603, 4270.295401258758, 4273.893152671208, 4274.551172126944, 4274.957250427518, 3682.1531047858607, 3685.1339935115225, 3713.6100709425664, 3719.516462859811, 3732.2722069196248, 4174.874753757392, 4192.182524407772, 4195.223883581547, 4220.978895105505, 4231.386448964944, 4818.831340903052, 4823.589994850407, 4871.878203044881, 4875.448916871268, 4924.507549245047, 4173.165366926627, 4225.070484493406, 4246.235677578065, 4293.785310734451, 4305.547917468621, 4577.267637178035, 4580.770961451914, 4582.908341833145, 4615.699979501389, 4627.120847039052, 4697.436307642968, 4699.060187962353, 4706.082313948816, 4712.269598212282, 4742.004179122423, 4731.668783185281, 4801.231802911518, 4802.135615589227, 4953.826616068421, 5024.271480635361, 4646.349511068521, 4705.411823522059, 4728.243481043017, 4741.122892264942, 4752.073345697556, 4961.404631444249, 4963.305137280856, 4967.05625931072, 4974.825881094144, 4983.651144419856, 5045.596352291735, 5046.646734728681, 5052.72210028452, 5054.494550544916, 5101.9744901276745, 4723.669343145947, 4774.069056533943, 4785.0, 4836.081144582518, 4860.076585448754, 4885.8045678172475, 4901.191303434894, 4910.975445122752, 4913.719494032878, 4935.827246046371, 4849.199882019569, 4853.878653033702, 4878.146324393146, 4911.803527858989, 4916.410030796327, 4713.481517033055, 4791.1854984652, 4866.318715379921, 4889.144399730104, 4948.257761335684, 4829.323894654822, 4835.081334546425, 4862.562369386772, 4869.0261947611325, 4875.317455556154], "value": 4584.683629501932, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.2971699055304, 518.3857129301778, 537.8951104534617, 554.6893739505875, 565.138164299443, 27635.309117272078, 28326.85836570817, 36948.81525592371, 38377.42433863494, 38538.0, 38918.0, 39200.41199382012, 39290.21419571607, 39296.60703392965, 39308.41037384442, 7197.604131772763, 7412.258774122599, 7413.25867413257, 7414.369778568828, 7419.406447484215, 7370.299821516947, 7392.926070739278, 7570.394368450511, 9417.81164376712, 9663.468509231989, 9700.417974921493, 9773.0, 9773.511324433786, 9779.168770654504, 9784.755381115458, 9433.198178154866, 9485.668001619979, 9488.193503552206, 9510.28672849539, 9512.191463725558, 9527.52362381885, 9599.040095990415, 9621.470819104947, 9623.615055397846, 9626.374285671409, 35657.643423565954, 35810.0, 35929.64070359289, 35952.460713089065, 35958.280834383426, 2965.569992351097, 3218.227625369918, 3233.3856567252146, 3634.4184930411006, 3667.7982710950887, 26973.190804275902, 27586.862065689544, 27680.446391072066, 27727.445451091065, 27998.44003119926, 3411.6076651185185, 3472.392331342005, 3521.9647803521893, 3542.0, 3588.3002814451193, 236372.0, 236916.52333906622, 237090.8145459279, 237274.0, 238427.0, 48201.0, 48499.272510912364, 48544.0, 48792.756036219595, 48902.266466003035, 45583.77208114004, 45687.0, 50597.747011264706, 50944.23583646248, 51285.743571281906, 14156.221407822668, 14533.709325813425, 14544.345504452325, 14550.563483095524, 14553.054051486832, 10174.186065114773, 11209.607663731731, 11270.0, 11292.830607540893, 11296.41850140981, 12247.693807654701, 14254.572362829133, 14286.0, 14495.0, 15171.317290721723, 5920.733566989496, 5984.700764961734, 5988.820335389945, 6032.306284777219, 6111.51107911366, 751.5340488896919, 880.1242763450408, 927.1074248345207, 931.9841372903568, 961.8797650293714, 26033.609495857578, 26489.735102649112, 26624.60063099055, 26872.193834185007, 27146.592801108, 829.3531045784298, 879.8372301124313, 880.225401646547, 969.2730452160879, 1009.9597414662909, 13742.450301988009, 14100.929495352662, 14455.421783128768, 14511.1293322401, 14520.419183232552, 346677.0, 349213.0, 350626.0, 351031.0, 351703.0, 47096.7645161772, 47450.28824567634, 47507.28739068917, 47560.286595701094, 47626.5237347629, 1080.2330345454816, 1080.3355936099242, 1133.2350663302238, 1133.9886601133883, 1156.0000599481557, 24525.38686532851, 27669.723302766713, 27720.0, 28475.288117796394, 28514.002009929554, 34091.48862767011, 34331.656683433845, 34402.82798585991, 34428.655713442546, 34430.48354274738, 267184.0, 267388.0, 267449.0, 267483.0, 267882.0, 33407.49888751622, 34702.30595388028, 34713.30573388569, 34733.47899781556, 34782.65217347896, 38886.222275554894, 38900.80549597347, 38925.0, 38951.0, 39475.80262098671, 33661.49507757337, 33806.661933380354, 33965.83017084899, 34217.31565368629, 34694.0, 32495.35009299848, 32768.344633107685, 32811.50782738213, 33017.17457063593, 33616.663833362334, 29711.85144074352, 33452.665473344954, 33770.155746106546, 33880.0, 34142.0, 23989.640155397334, 25153.87423062873, 27089.86455067712, 27790.583141252493, 28126.0, 22031.0, 22113.78862113776, 22351.105955761588, 22392.440188995406, 22433.77566224317], "value": 16856.02495112289, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.0726363396113, 352.3340885725978, 355.20257022983395, 359.35854499717993, 374.20294772135367, 12413.131080824345, 15263.694726105485, 15537.92231038846, 16698.7495187572, 16849.494515164533, 16315.98412682546, 16362.7545586816, 16412.75380869285, 16517.91741041293, 16668.33326666935, 4904.215325547913, 4920.729359885205, 5010.574101201402, 5044.470330615288, 5142.254373115896, 3487.267673788495, 4918.409790825106, 5014.874628134307, 5017.899642007158, 5019.498050194988, 3664.963350366489, 5580.302462192226, 5849.239598852143, 5890.499307558863, 5976.312724036748, 5704.971475142598, 5854.414558544154, 5962.075878238858, 5972.910406343908, 5973.671448070376, 5170.276161337416, 5171.758777893316, 5173.430922598511, 5177.689338639656, 5180.171172612401, 19145.425637230906, 19617.117229724696, 19630.41108766725, 19665.508362290915, 19776.20895164192, 1801.6666916620336, 1918.1560113550133, 2054.342610364684, 2149.140343862452, 2445.730969593343, 6213.161223234851, 6276.0, 6282.120503129566, 10805.351678899213, 12155.237490563906, 1714.7599336092958, 1717.802452717941, 1773.5034190426702, 1798.2087881332172, 1826.2877477783675, 61654.383456165764, 63470.68264658647, 63756.0, 64998.67500662467, 65082.67458662677, 8916.955415223012, 8943.150400712053, 9117.589708463134, 9260.814783704289, 9391.201747851372, 12169.2698438094, 12220.3278819664, 12253.387330633434, 12283.201591896677, 12293.692657683629, 7110.936502479927, 7204.379851100891, 7275.745348912868, 7416.22129676387, 7470.70117195317, 6969.0940177776565, 7031.7890463286185, 7066.434685225169, 7186.687772371082, 7380.5940673263485, 5787.479126878601, 5861.443162899518, 5958.582899197101, 5976.61152025117, 6081.391860813883, 1663.2182874049176, 1693.5342780735396, 1743.2504023269862, 1750.1861634340094, 1755.736639504059, 784.8430313937235, 793.2345286798268, 794.8330850521339, 821.5481485183126, 909.3452714045876, 15412.768808467881, 16075.276612552463, 16232.513024609281, 16359.509214723577, 16770.161491925355, 794.7575989323312, 825.0882774534053, 840.6007146605314, 856.9657213711382, 910.899801021887, 9012.8648070279, 9314.208292295096, 9619.134277915022, 9744.658936937172, 9865.309428339948, 314083.0, 318847.0, 333701.0, 336793.0, 340239.0, 65161.348386516474, 65667.34332656613, 65962.67018664876, 66544.33455665382, 67287.0, 885.406777459104, 897.0580890065536, 919.6091661044163, 923.6490133749267, 932.1703683721494, 14124.293785310489, 14312.141271523325, 14320.283985800877, 14631.268436577915, 15165.01427407215, 33488.66511334953, 33656.83171584126, 33662.3267534643, 33989.98030059102, 34109.82945085259, 42786.35820462633, 42792.0, 42951.785241073594, 42991.57008430001, 44470.0, 6575.243846957661, 6651.567648102858, 6749.831254218684, 7151.141862976476, 7540.472166948261, 10671.46642667879, 11334.433278335886, 11714.0, 11716.297022178696, 12010.459529321377, 9749.268804839523, 9766.80466390654, 10003.34978226413, 10298.845517317395, 10480.58077676915, 9753.756156096155, 10143.08712215904, 10222.693319200436, 10379.06588407047, 10394.636187733695, 9582.425054496753, 9601.807963840824, 9764.755881102745, 9827.705168844946, 10816.67549973502, 6409.316841407246, 6493.837654058686, 6526.543141980016, 6657.1345725055435, 6743.629100399525, 7421.777346679608, 9021.50381728985, 9044.728658140266, 9069.727908162766, 9358.11097945694], "value": 7851.576582160629, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1239.696274412769, 1310.6395741171177, 1346.5421756602752, 1420.8223972003493, 1633.5834362237629, 6353.777617783373, 6857.862842743142, 7107.50742344107, 7113.217546069928, 7285.854282914352, 7160.713571457136, 7211.459140564465, 7213.495055346128, 7215.7835264942005, 7219.63901804909, 1784.6698360803293, 1851.2687488442057, 2060.2583070094793, 2097.4127244371603, 2101.4011006863047, 2019.8384129269696, 2103.274370342237, 2119.0782009825775, 2130.3289463818924, 2131.467133216696, 2044.100595737878, 2057.0331943986303, 2060.7733149353558, 2061.5052387426986, 2081.8022287882627, 2025.3721346382667, 2047.7645070816898, 2066.772655007948, 2075.885826279554, 2082.9583408331823, 2032.3394896658644, 2035.9491012724652, 2052.9486762830898, 2061.0210150178696, 2067.059487932987, 7053.858922821566, 7153.320434558708, 7161.92838071623, 7179.389751871097, 7238.167610724781, 4148.377743338493, 4256.808443620013, 4260.105377870651, 4271.039016221356, 4276.444062271916, 2555.4062626434184, 2636.8813403396894, 2724.6457960465013, 2729.2221716810614, 2732.6584176977876, 827.437342607027, 853.1255463150259, 871.8212766382876, 874.903760586335, 876.6055275126155, 9450.763730906714, 9491.952540237324, 9492.667756628554, 9503.524823758784, 9515.952420237854, 3590.335787879251, 3798.8100594970692, 3810.3141434541494, 3827.7129215308964, 3857.6528112470014, 8161.918380816116, 8168.42821002536, 8170.060443049007, 8180.304674102768, 8186.467879587808, 4291.785410729513, 4330.891727706832, 4334.41485399467, 4335.501417337046, 4341.869743907688, 3528.8412021459094, 3653.8355773990234, 3811.256804923069, 3951.545572259227, 3959.861404850874, 3343.4483310254022, 3347.2301370684877, 3469.392856250146, 3475.721942244614, 3482.5820901491984, 1099.4997276239292, 1099.631623406152, 1100.4332768624165, 1112.6161474291357, 1137.0221609415896, 708.3624737736079, 847.2163248994656, 848.6138806842872, 857.5969294431607, 863.3481721300378, 4833.637477189154, 4833.830815921426, 4868.172410690191, 4943.184374578226, 5005.1241032819835, 381.4126245581828, 392.2762503181625, 392.4427313215241, 395.5905637665003, 397.2690249940124, 3375.932481350359, 3387.779794313362, 3393.541871847269, 3425.1779572902324, 3458.152752575635, 7717.536947783151, 7893.605319733991, 7941.801454963556, 7995.0, 8014.358851291882, 7929.841403171904, 7948.244916732901, 7957.880631790527, 7961.402894782915, 7973.561454120079, 6321.462675672619, 6341.714622841983, 6384.872302554016, 6385.7126429309865, 6421.1652485177565, 4623.445186577632, 4680.227762419163, 4686.1799185143145, 4771.618270538418, 4866.756662166807, 7203.495755297288, 7396.4082873368925, 7456.701731930879, 7464.029676142067, 7470.92529074724, 7455.291747283999, 7466.664000120117, 7471.476996610185, 7497.812554685958, 7511.0, 5694.17434471991, 5907.734151963258, 6062.757489700539, 6402.583832050902, 6416.967915160394, 6454.19322584677, 6664.500162487736, 6759.087523184504, 6932.7226910923, 6944.2708515607155, 5970.253718285215, 6006.159137720635, 6107.0, 6189.319174890668, 6221.09794079865, 6020.066889632114, 6021.608595441282, 6048.183495228264, 6081.969590152021, 6117.480014198843, 5823.79616713413, 5915.674637894957, 5922.111683247547, 5975.611585246945, 5979.730912108878, 4226.6618670506905, 4260.723053001545, 4268.466441694788, 4312.892177695583, 4411.779411029372, 4156.937645935373, 4237.724547904377, 4272.700910936204, 4292.527821939647, 4385.912281754283], "value": 3705.456002879471, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.10793346894815, 323.77335864894565, 328.9210589458531, 334.1762555301183, 340.7989286321071, 4604.838830640928, 4759.357486739289, 4775.90448191036, 4829.613630909527, 4857.198562237227, 4580.885477863055, 4682.695624784386, 4719.050593379138, 4736.621070314367, 4768.23708206687, 3844.9615503845025, 3846.4230365445196, 3846.8653597124107, 3853.537575490945, 3868.3423817951016, 3681.7606855554304, 3764.0, 3856.8842934712, 3879.243547508237, 3881.3207688654375, 3700.0, 3805.6574908258253, 3823.1971286029966, 3824.808759562011, 3841.500604921356, 3777.886663400075, 3816.923661526768, 3824.158685089276, 3829.1958688675413, 3830.2996387546714, 3446.758726889119, 3449.724022078228, 3454.619991800925, 3458.291050334675, 3469.6988629264024, 4571.2000399929875, 4659.813607455698, 4676.859694209179, 4692.648051396159, 4711.293306004092, 4358.5205627380965, 4363.912721745579, 4366.650667946588, 4366.890827729332, 4387.868363949055, 5000.849804544975, 5011.799528018875, 5012.1980483122525, 5023.773930173151, 5026.5727413169925, 3308.5368048473233, 3503.3343664703625, 3773.339665558516, 3775.5658099318657, 3777.9622203777885, 5180.197069454241, 5193.610479214074, 5199.220116982483, 5206.505381988705, 5223.242629818655, 3502.7022703070015, 3539.4867744177463, 3541.11472131967, 3551.5560554930635, 3561.1631266652316, 3595.9032495088727, 3601.94597081044, 3607.549056367954, 3615.0, 3616.31290054894, 3966.2464131814863, 4128.699459670194, 4137.027798467418, 4150.792460376969, 4156.168766246763, 4962.352670786969, 4963.5036496350795, 4965.255211718199, 4967.7019378837385, 4975.4278258000795, 1165.2542372881362, 1184.5321098166175, 1190.2798806721808, 1216.2459275249403, 1228.3121451986915, 815.6288888555692, 835.3692961813791, 837.9162083791693, 843.9493630382196, 851.0978362935308, 4273.764942928169, 4282.5075116361395, 4309.461317335333, 4336.9783151084675, 4349.716833534115, 4842.975785121052, 4962.478939711351, 4983.750812459362, 4988.376452943382, 5014.17266151081, 4227.196832601796, 4250.277452833026, 4271.807768650418, 4273.252180868336, 4291.74249545022, 2643.9074632387774, 2646.2193652872447, 2649.4038841260563, 2673.42521357909, 2675.505031569166, 4644.187267228289, 4685.672002959828, 4720.645951553579, 4730.290456431494, 4734.834280800155, 4968.90062198754, 5300.1254792958725, 5315.176147697113, 5322.893542129135, 5334.306540149756, 4800.975995120002, 4846.588040016638, 4860.343853579863, 4865.95134048655, 4882.4385195701525, 4978.601711863114, 5197.24639927216, 5200.609954253522, 5217.713025783618, 5226.242194881797, 5070.73231692077, 5073.0, 5086.669366491166, 5097.954919241472, 5117.2579975902545, 4801.591864691401, 4816.686915350491, 4849.927251091307, 4877.146499362669, 4880.828601135773, 3629.6370362963494, 3640.3265395901844, 3642.2351306225987, 3645.507856439453, 3663.900829751011, 4958.975205123951, 5158.277841102324, 5174.662342788955, 5179.352580927384, 5189.636725429184, 5000.974995125147, 5007.649464537448, 5012.348394708664, 5015.974920125522, 5043.949560504495, 4824.107540105092, 5000.999800040079, 5019.3976722793495, 5025.346704928336, 5037.934446876763, 4719.244920812654, 4726.19654658702, 4798.712077275374, 4883.682560633548, 4914.778834952507, 4999.775145031507, 5004.874878128076, 5010.298558201927, 5032.672876263031, 5057.949420505895, 4905.313256144214, 4910.263460480814, 4935.864751107337, 4941.407031156285, 4954.529319714621], "value": 3770.0072703683804, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.9460048595625, 639.4468784501405, 663.1365861927982, 677.0724107972079, 685.6160550091946, 12142.210756300845, 13435.126716763416, 13497.0, 13523.323833808296, 13586.18482891026, 11389.772204555904, 11552.53789848405, 11563.132765042634, 12893.419796109174, 12895.677608059803, 765.4718244411366, 780.4458834227677, 781.9765407037798, 781.9921800782005, 782.3662833105175, 628.4877824572982, 648.1444493268887, 766.8657984852656, 789.4000559574736, 792.8057625881638, 834.7913021744564, 836.5608055770739, 836.9079401265856, 837.0624900111883, 837.4221786967004, 815.6533473273894, 820.8768684697284, 827.3711978896022, 827.4414770030205, 828.3662997806682, 474.7460108841753, 485.226064427238, 574.9683767392792, 596.0582279997593, 602.4367216652444, 12651.873481265162, 12696.619101426879, 12702.237865728086, 12715.364231788373, 12721.173123746927, 11810.23233489829, 12116.939415302955, 12361.876381236163, 12467.438965246585, 12670.239785612892, 9769.413835169842, 9959.253056020754, 10361.119304859096, 10375.636852710119, 10650.680479585624, 4050.5544390117066, 4095.0, 4106.466159399259, 4139.068709540359, 4188.937165942483, 58456.41543584595, 60014.0, 60941.0, 61929.071063934076, 62473.0, 9934.701958941243, 10053.19574434058, 10092.04125608066, 10092.14216791581, 10164.237682173867, 17753.0, 17816.91091544534, 17981.280748770165, 18138.909305453653, 18153.636927261647, 6560.44236239925, 6574.408303252731, 6644.60132392058, 6734.8989765153565, 6750.628715420601, 3420.6237313895454, 3481.860725570949, 3494.266204097168, 3505.1938054247667, 3608.585012723518, 2722.659667541557, 2793.7904657150793, 2805.2005178524023, 2826.9293267668063, 2829.773618110546, 467.2034181720159, 467.7941705649503, 468.2938395649027, 469.0220889445476, 474.5776259129378, 437.3287004448157, 441.2322558747756, 486.34586481182754, 516.2694786876527, 530.1835173832333, 7023.403010744042, 7264.818379540554, 8359.373047021498, 8403.663853445794, 8697.304215662733, 453.7617750680919, 539.5090467674397, 568.9516391106803, 599.8183578350643, 613.7698363113832, 3439.174598096489, 3601.819909004539, 3602.8018458985007, 3723.069232691827, 3790.7346485746284, 58872.705636471546, 59882.70058649766, 59987.70006150028, 61643.0, 61691.383086168564, 46361.20484939383, 52902.735486323094, 53110.20334694983, 54311.72844135754, 54627.0, 47050.76474617605, 47460.5253947463, 47992.0, 48707.51292487171, 49340.50659493359, 6939.514234003571, 7090.290970902868, 7296.671649775667, 7404.888926665996, 7505.362044226302, 32100.0, 32879.0, 32891.50662740109, 33530.32939341248, 34290.657093428745, 42370.0, 45229.09541809211, 45347.77326113348, 47592.2861157076, 47598.76200618975, 9579.808403831745, 9834.311598188058, 9941.552630131517, 9945.717913088809, 10050.396976181453, 11161.55353785872, 11257.662270131908, 11375.43122843838, 11684.357360345262, 12056.336901470504, 9901.801963960825, 9988.550515226687, 9990.200783937413, 10118.443485608068, 10382.948085259526, 9805.55874985613, 9861.013898610081, 10024.198064154703, 10894.400807955322, 11098.61154859576, 9538.0, 9674.322797404115, 9769.902300976899, 10037.0, 10296.382217067, 8823.514706691194, 9257.537123143682, 9365.531723413938, 9379.577918993798, 9413.81172376535, 8500.277476414574, 8772.342074344322, 8782.209601135928, 8797.0, 9153.63385464574], "value": 5911.594483312555, "unit": "score"}], "name": "dromaeo_css", "value": 6071.934336365115, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 115.0, 115.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 132.0, 129.0, 165.0, 136.0, 130.0, 139.0, 129.0, 163.0, 137.0], "value": 141.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 163.0, 162.0, 162.0, 160.0, 162.0, 161.0, 160.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 106.0, 77.0, 77.0, 89.0, 80.0, 93.0, 76.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 110.0, 85.0, 85.0, 84.0, 104.0, 85.0, 86.0, 106.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 128.0, 127.0, 128.0, 128.0, 127.0, 128.0, 128.0, 127.0, 127.0], "value": 127.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 147.0, 148.0, 146.0, 146.0, 147.0, 148.0, 147.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 73.0, 64.0, 65.0, 66.0, 65.0, 65.0, 66.0, 65.0], "value": 66.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 66.0, 65.0, 66.0, 66.0, 66.0, 66.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 87.0, 93.0, 89.0, 95.0, 90.0, 90.0, 94.0, 132.0, 90.0], "value": 95.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 142.0, 142.0, 150.0, 144.0, 141.0, 149.0, 141.0, 158.0, 151.0], "value": 146.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 180.0, 182.0, 185.0, 182.0, 181.0, 180.0, 189.0, 180.0, 179.0], "value": 182.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 70.0, 70.0, 69.0, 69.0, 69.0, 68.0, 68.0, 69.0, 67.0], "value": 69.3, "unit": "score"}], "name": "kraken", "value": 1585.6999999999998, "alertThreshold": 2.0}]} 16:44:40 INFO - Return code: 0 16:44:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:44:40 INFO - # TBPL SUCCESS # 16:44:40 INFO - Running post-action listener: _resource_record_post_action 16:44:40 INFO - [mozharness: 2016-12-10 00:44:40.720783Z] Finished run-tests step (success) 16:44:40 INFO - Running post-run listener: _resource_record_post_run 16:44:40 INFO - Total resource usage - Wall time: 1206s; CPU: 13.0%; Read bytes: 4096; Write bytes: 389144576; Read time: 36; Write time: 368044 16:44:40 INFO - TinderboxPrint: CPU usage
13.2% 16:44:40 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 36 16:44:40 INFO - TinderboxPrint: I/O write bytes / time
389,144,576 / 368,044 16:44:40 INFO - TinderboxPrint: CPU idle
8,356.9 (86.8%) 16:44:40 INFO - TinderboxPrint: CPU user
1,214.0 (12.6%) 16:44:40 INFO - TinderboxPrint: Swap in / out
0 / 0 16:44:40 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99536896; Read time: 0; Write time: 137216 16:44:40 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 289193984; Read time: 0; Write time: 230800 16:44:41 INFO - Running post-run listener: _upload_blobber_files 16:44:41 INFO - Blob upload gear active. 16:44:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:44:41 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:44:41 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:44:41 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:44:41 INFO - (blobuploader) - INFO - Open directory for files ... 16:44:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:44:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:44:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:44:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:44:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:44:43 INFO - (blobuploader) - INFO - Done attempting. 16:44:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:44:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:44:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:44:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:44:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:44:44 INFO - (blobuploader) - INFO - Done attempting. 16:44:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:44:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:44:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:44:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:44:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:44:47 INFO - (blobuploader) - INFO - Done attempting. 16:44:47 INFO - (blobuploader) - INFO - Iteration through files over. 16:44:47 INFO - Return code: 0 16:44:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:44:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:44:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/149afefac33c7c998f7ce35839b3871ed13dd755323c362944e2595086537ec0c6288995a0a1e1ec864c17e8606b0b5d1d41320a051bfe1bde84c1224f67f645", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/96d3b6645c145bb57274bf4d0c6b0e63f7ec2f6df4a44b0cc6f2e5b5ec2c18efbdc1c9905afe61c65c4116eba000b088d792652c10a4c0d8937ebed41aaa2a14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/637e1e382aa9d072f353c3a9cd86b8e1c6f6caaa8a2645e32a0278883fcdd7dbed405537e690cb06a00b0b335fe02a275702e4b6b2699400b4aa3565b0854edd"} 16:44:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:44:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:44:47 INFO - Contents: 16:44:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/149afefac33c7c998f7ce35839b3871ed13dd755323c362944e2595086537ec0c6288995a0a1e1ec864c17e8606b0b5d1d41320a051bfe1bde84c1224f67f645", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/96d3b6645c145bb57274bf4d0c6b0e63f7ec2f6df4a44b0cc6f2e5b5ec2c18efbdc1c9905afe61c65c4116eba000b088d792652c10a4c0d8937ebed41aaa2a14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/637e1e382aa9d072f353c3a9cd86b8e1c6f6caaa8a2645e32a0278883fcdd7dbed405537e690cb06a00b0b335fe02a275702e4b6b2699400b4aa3565b0854edd"} 16:44:47 INFO - Running post-run listener: copy_logs_to_upload_dir 16:44:47 INFO - Copying logs to upload dir... 16:44:47 INFO - mkdir: /builds/slave/test/build/upload/logs 16:44:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.933922 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-12-09 16:44:47.104678) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-09 16:44:47.108060) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/149afefac33c7c998f7ce35839b3871ed13dd755323c362944e2595086537ec0c6288995a0a1e1ec864c17e8606b0b5d1d41320a051bfe1bde84c1224f67f645", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/96d3b6645c145bb57274bf4d0c6b0e63f7ec2f6df4a44b0cc6f2e5b5ec2c18efbdc1c9905afe61c65c4116eba000b088d792652c10a4c0d8937ebed41aaa2a14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/637e1e382aa9d072f353c3a9cd86b8e1c6f6caaa8a2645e32a0278883fcdd7dbed405537e690cb06a00b0b335fe02a275702e4b6b2699400b4aa3565b0854edd"} build_url:https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011475 build_url: 'https://queue.taskcluster.net/v1/task/LwR9DTO2Sei2OvCM_mlwHg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/149afefac33c7c998f7ce35839b3871ed13dd755323c362944e2595086537ec0c6288995a0a1e1ec864c17e8606b0b5d1d41320a051bfe1bde84c1224f67f645", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/96d3b6645c145bb57274bf4d0c6b0e63f7ec2f6df4a44b0cc6f2e5b5ec2c18efbdc1c9905afe61c65c4116eba000b088d792652c10a4c0d8937ebed41aaa2a14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/637e1e382aa9d072f353c3a9cd86b8e1c6f6caaa8a2645e32a0278883fcdd7dbed405537e690cb06a00b0b335fe02a275702e4b6b2699400b4aa3565b0854edd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-09 16:44:47.134609) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 16:44:47.134990) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=78329bfc0fd5309db0a6e0ac000001b4-1481329260.576351-487417911 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004404 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 16:44:47.218673) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-09 16:44:47.219029) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-09 16:44:47.219347) ========= ========= Total master_lag: 0.18 =========