builder: graphics_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-059 starttime: 1483119188.76 results: success (0) buildid: 20161230071820 builduid: ff12ce52b68c4a1ca923c8c44b76beee revision: 6b5d5c4d2ed94d33f518ca0fbf101da315e2bf87 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 09:33:08.756424) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 09:33:08.756925) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 09:33:08.757203) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 09:33:08.772078) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 09:33:08.772392) ========= 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=3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-30 09:33:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2016-12-30 09:33:09 (222 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131964 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 09:33:08.918184) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 09:33:08.918498) ========= 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=3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014903 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 09:33:08.969434) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-30 09:33:08.969808) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 6b5d5c4d2ed94d33f518ca0fbf101da315e2bf87 --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 6b5d5c4d2ed94d33f518ca0fbf101da315e2bf87 --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=3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-30 09:33:09,878 truncating revision to first 12 chars 2016-12-30 09:33:09,878 Setting DEBUG logging. 2016-12-30 09:33:09,878 attempt 1/10 2016-12-30 09:33:09,878 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/6b5d5c4d2ed9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-30 09:33:10,709 unpacking tar archive at: graphics-6b5d5c4d2ed9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.137558 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-30 09:33:10.121108) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 09:33:10.121466) ========= script_repo_revision: 6b5d5c4d2ed94d33f518ca0fbf101da315e2bf87 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 09:33:10.121923) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 09:33:10.122238) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 09:33:10.131294) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-12-30 09:33:10.131682) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:33:11 INFO - MultiFileLogger online at 20161230 09:33:11 in /builds/slave/test 09:33:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 09:33:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:33:11 INFO - {'append_to_log': False, 09:33:11 INFO - 'base_work_dir': '/builds/slave/test', 09:33:11 INFO - 'blob_upload_branch': 'Graphics', 09:33:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:33:11 INFO - 'branch': 'Graphics', 09:33:11 INFO - 'buildbot_json_path': 'buildprops.json', 09:33:11 INFO - 'config_files': ('talos/linux_config.py',), 09:33:11 INFO - 'default_actions': ('clobber', 09:33:11 INFO - 'read-buildbot-config', 09:33:11 INFO - 'download-and-extract', 09:33:11 INFO - 'populate-webroot', 09:33:11 INFO - 'create-virtualenv', 09:33:11 INFO - 'install', 09:33:11 INFO - 'run-tests'), 09:33:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:33:11 INFO - 'download_minidump_stackwalk': True, 09:33:11 INFO - 'download_symbols': 'ondemand', 09:33:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:33:11 INFO - 'tooltool.py': '/tools/tooltool.py', 09:33:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:33:11 INFO - '/tools/misc-python/virtualenv.py')}, 09:33:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:33:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:33:11 INFO - 'installer_path': 'installer.exe', 09:33:11 INFO - 'log_level': 'info', 09:33:11 INFO - 'log_name': 'talos', 09:33:11 INFO - 'log_to_console': True, 09:33:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:33:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:33:11 INFO - 'opt_config_files': (), 09:33:11 INFO - 'pip_index': False, 09:33:11 INFO - 'sps_profile': False, 09:33:11 INFO - 'sps_profile_interval': 0, 09:33:11 INFO - 'suite': 'dromaeojs-e10s', 09:33:11 INFO - 'system_bits': '32', 09:33:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:33:11 INFO - 'title': 'talos-linux64-ix-059', 09:33:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:33:11 INFO - 'use_talos_json': True, 09:33:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:33:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:33:11 INFO - 'work_dir': 'build'} 09:33:11 INFO - [mozharness: 2016-12-30 17:33:11.134311Z] Running clobber step. 09:33:11 INFO - Running pre-action listener: _resource_record_pre_action 09:33:11 INFO - Running main action method: clobber 09:33:11 INFO - rmtree: /builds/slave/test/build 09:33:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:33:11 INFO - Running post-action listener: _resource_record_post_action 09:33:11 INFO - [mozharness: 2016-12-30 17:33:11.610771Z] Finished clobber step (success) 09:33:11 INFO - [mozharness: 2016-12-30 17:33:11.610871Z] Running read-buildbot-config step. 09:33:11 INFO - Running pre-action listener: _resource_record_pre_action 09:33:11 INFO - Running main action method: read_buildbot_config 09:33:11 INFO - Using buildbot properties: 09:33:11 INFO - { 09:33:11 INFO - "project": "", 09:33:11 INFO - "product": "firefox", 09:33:11 INFO - "script_repo_revision": "default", 09:33:11 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs-e10s", 09:33:11 INFO - "repository": "", 09:33:11 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs-e10s", 09:33:11 INFO - "buildid": "20161230071820", 09:33:11 INFO - "pgo_build": "False", 09:33:11 INFO - "basedir": "/builds/slave/test", 09:33:11 INFO - "buildnumber": 43, 09:33:11 INFO - "slavename": "talos-linux64-ix-059", 09:33:11 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:33:11 INFO - "platform": "ubuntu64_hw", 09:33:11 INFO - "scheduler": "tests-graphics-linux64-talos-8-3600", 09:33:11 INFO - "branch": "graphics", 09:33:11 INFO - "repo_path": "projects/graphics", 09:33:11 INFO - "revision": "6b5d5c4d2ed94d33f518ca0fbf101da315e2bf87", 09:33:11 INFO - "stage_platform": "linux64", 09:33:11 INFO - "builduid": "ff12ce52b68c4a1ca923c8c44b76beee", 09:33:11 INFO - "slavebuilddir": "test" 09:33:11 INFO - } 09:33:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:33:11 INFO - Running post-action listener: _resource_record_post_action 09:33:11 INFO - [mozharness: 2016-12-30 17:33:11.613316Z] Finished read-buildbot-config step (success) 09:33:11 INFO - [mozharness: 2016-12-30 17:33:11.613404Z] Running download-and-extract step. 09:33:11 INFO - Running pre-action listener: _resource_record_pre_action 09:33:11 INFO - Running main action method: download_and_extract 09:33:11 INFO - mkdir: /builds/slave/test/build/tests 09:33:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:11 INFO - trying https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:33:11 INFO - Downloading https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/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 09:33:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/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 09:33:12 INFO - Downloaded 1361 bytes. 09:33:12 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:33:12 INFO - Using the following test package requirements: 09:33:12 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:33:12 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:33:12 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:33:12 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:12 INFO - u'jsshell-linux-x86_64.zip'], 09:33:12 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:33:12 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:33:12 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:33:12 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:33:12 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:33:12 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:12 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:33:12 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:33:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:33:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:33:12 INFO - Fetch https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:33:12 INFO - Expected file size: 20246119 09:33:12 INFO - Obtained file size: 20246119 09:33:16 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 09:33:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:33:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:33:16 INFO - Fetch https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:33:17 INFO - Expected file size: 20246119 09:33:17 INFO - Obtained file size: 20246119 09:33:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:33:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:33:22 INFO - Fetch https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:33:22 INFO - Expected file size: 14409776 09:33:22 INFO - Obtained file size: 14409776 09:33:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:23 INFO - trying https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:33:23 INFO - Downloading https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/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 09:33:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/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 09:33:24 INFO - Downloaded 55929561 bytes. 09:33:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:33:24 INFO - mkdir: /builds/slave/test/properties 09:33:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:33:24 INFO - Writing to file /builds/slave/test/properties/build_url 09:33:24 INFO - Contents: 09:33:24 INFO - build_url:https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:33:24 INFO - Running post-action listener: _resource_record_post_action 09:33:24 INFO - Running post-action listener: set_extra_try_arguments 09:33:24 INFO - [mozharness: 2016-12-30 17:33:24.936337Z] Finished download-and-extract step (success) 09:33:24 INFO - [mozharness: 2016-12-30 17:33:24.936563Z] Running populate-webroot step. 09:33:24 INFO - Running pre-action listener: _resource_record_pre_action 09:33:24 INFO - Running main action method: populate_webroot 09:33:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:33:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:33:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:33:24 INFO - '--tppagecycles', 09:33:24 INFO - '7'], 09:33:24 INFO - 'tests': ['tsvgm']}}, 09:33:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:33:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:33:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:33:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:33:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:33:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:33:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:33:24 INFO - 'talos_options': ['--disable-e10s'], 09:33:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:33:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:33:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:33:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:33:24 INFO - 'talos_options': ['--disable-e10s'], 09:33:24 INFO - 'tests': ['damp', 'tps']}, 09:33:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:33:24 INFO - 'tests': ['damp', 'tps']}, 09:33:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:33:24 INFO - 'tests': ['dromaeo_dom']}, 09:33:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:33:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:33:24 INFO - 'tests': ['basic_compositor_video']}, 09:33:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:33:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:33:24 INFO - 'tests': ['a11yr', 09:33:24 INFO - 'ts_paint', 09:33:24 INFO - 'tpaint', 09:33:24 INFO - 'sessionrestore', 09:33:24 INFO - 'sessionrestore_no_auto_restore', 09:33:24 INFO - 'tabpaint']}, 09:33:24 INFO - 'other-e10s': {'tests': ['a11yr', 09:33:24 INFO - 'ts_paint', 09:33:24 INFO - 'tpaint', 09:33:24 INFO - 'sessionrestore', 09:33:24 INFO - 'sessionrestore_no_auto_restore', 09:33:24 INFO - 'tabpaint']}, 09:33:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:33:24 INFO - 'ts_paint', 09:33:24 INFO - 'tpaint', 09:33:24 INFO - 'sessionrestore', 09:33:24 INFO - 'sessionrestore_no_auto_restore', 09:33:24 INFO - 'tabpaint']}, 09:33:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:33:24 INFO - 'ts_paint', 09:33:24 INFO - 'tpaint', 09:33:24 INFO - 'sessionrestore', 09:33:24 INFO - 'sessionrestore_no_auto_restore', 09:33:24 INFO - 'tabpaint']}, 09:33:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:33:24 INFO - 'tests': ['a11yr', 09:33:24 INFO - 'ts_paint', 09:33:24 INFO - 'tpaint', 09:33:24 INFO - 'sessionrestore', 09:33:24 INFO - 'sessionrestore_no_auto_restore', 09:33:24 INFO - 'tabpaint']}, 09:33:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:33:24 INFO - 'tests': ['a11yr', 09:33:24 INFO - 'ts_paint', 09:33:24 INFO - 'tpaint', 09:33:24 INFO - 'sessionrestore', 09:33:24 INFO - 'sessionrestore_no_auto_restore', 09:33:24 INFO - 'tabpaint']}, 09:33:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:33:24 INFO - 'tests': ['tsvgx', 09:33:24 INFO - 'tsvgr_opacity', 09:33:24 INFO - 'tart', 09:33:24 INFO - 'tscrollx', 09:33:24 INFO - 'cart', 09:33:24 INFO - 'tsvg_static']}, 09:33:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:33:24 INFO - 'tsvgr_opacity', 09:33:24 INFO - 'tart', 09:33:24 INFO - 'tscrollx', 09:33:24 INFO - 'cart', 09:33:24 INFO - 'tsvg_static']}, 09:33:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:33:24 INFO - 'talos_options': ['--disable-e10s'], 09:33:24 INFO - 'tests': ['tp5o']}, 09:33:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:33:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:33:24 INFO - 'talos_options': ['--disable-e10s', 09:33:24 INFO - '--xperf_path', 09:33:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:33:24 INFO - 'tests': ['tp5n']}, 09:33:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:33:24 INFO - 'talos_options': ['--xperf_path', 09:33:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:33:24 INFO - 'tests': ['tp5n']}}, 09:33:24 INFO - 'talos.zip': {'path': '', 09:33:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:33:24 INFO - Running post-action listener: _resource_record_post_action 09:33:24 INFO - [mozharness: 2016-12-30 17:33:24.951805Z] Finished populate-webroot step (success) 09:33:24 INFO - [mozharness: 2016-12-30 17:33:24.951918Z] Running create-virtualenv step. 09:33:24 INFO - Running pre-action listener: _resource_record_pre_action 09:33:24 INFO - Running main action method: create_virtualenv 09:33:24 INFO - Creating virtualenv /builds/slave/test/build/venv 09:33:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:33:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:33:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:33:25 INFO - Using real prefix '/usr' 09:33:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:33:25 INFO - Installing distribute.............................................................................................................................................................................................done. 09:33:27 INFO - Installing pip.................done. 09:33:27 INFO - Return code: 0 09:33:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:33:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:33:27 INFO - Reading from file tmpfile_stdout 09:33:27 INFO - Output received: 09:33:27 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:33:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:33:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:27 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 0x273c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2908a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aadc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab3f0>, '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 0x2aabcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aac1a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:33:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:33:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:33:27 INFO - Using env: {'DISPLAY': ':0', 09:33:27 INFO - 'HOME': '/home/cltbld', 09:33:27 INFO - 'LANG': 'en_US.UTF-8', 09:33:27 INFO - 'LANGUAGE': 'en_US:en', 09:33:27 INFO - 'LOGNAME': 'cltbld', 09:33:27 INFO - 'MAIL': '/var/mail/cltbld', 09:33:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:27 INFO - 'MOZ_NO_REMOTE': '1', 09:33:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:27 INFO - 'NO_EM_RESTART': '1', 09:33:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:27 INFO - 'PWD': '/builds/slave/test', 09:33:27 INFO - 'SHELL': '/bin/bash', 09:33:27 INFO - 'SHLVL': '1', 09:33:27 INFO - 'TERM': 'linux', 09:33:27 INFO - 'TMOUT': '86400', 09:33:27 INFO - 'USER': 'cltbld', 09:33:27 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 09:33:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:27 INFO - '_': '/tools/buildbot/bin/python'} 09:33:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:33:27 INFO - Cleaning up... 09:33:27 INFO - Return code: 0 09:33:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:33:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:27 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 0x273c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2908a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aadc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab3f0>, '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 0x2aabcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aac1a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:33:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:33:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:33:27 INFO - Using env: {'DISPLAY': ':0', 09:33:27 INFO - 'HOME': '/home/cltbld', 09:33:27 INFO - 'LANG': 'en_US.UTF-8', 09:33:27 INFO - 'LANGUAGE': 'en_US:en', 09:33:27 INFO - 'LOGNAME': 'cltbld', 09:33:27 INFO - 'MAIL': '/var/mail/cltbld', 09:33:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:27 INFO - 'MOZ_NO_REMOTE': '1', 09:33:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:27 INFO - 'NO_EM_RESTART': '1', 09:33:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:27 INFO - 'PWD': '/builds/slave/test', 09:33:27 INFO - 'SHELL': '/bin/bash', 09:33:27 INFO - 'SHLVL': '1', 09:33:27 INFO - 'TERM': 'linux', 09:33:27 INFO - 'TMOUT': '86400', 09:33:27 INFO - 'USER': 'cltbld', 09:33:27 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 09:33:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:27 INFO - '_': '/tools/buildbot/bin/python'} 09:33:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:28 INFO - Downloading/unpacking psutil>=3.1.1 09:33:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:33:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:33:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:33:30 INFO - Installing collected packages: psutil 09:33:30 INFO - Running setup.py install for psutil 09:33:30 INFO - building 'psutil._psutil_linux' extension 09:33:30 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:33:30 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:33:30 INFO - building 'psutil._psutil_posix' extension 09:33:30 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:33:30 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:33:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:33:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:33:30 INFO - Successfully installed psutil 09:33:30 INFO - Cleaning up... 09:33:30 INFO - Return code: 0 09:33:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:30 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 0x273c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2908a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aadc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab3f0>, '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 0x2aabcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aac1a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:33:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:33:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:33:30 INFO - Using env: {'DISPLAY': ':0', 09:33:30 INFO - 'HOME': '/home/cltbld', 09:33:30 INFO - 'LANG': 'en_US.UTF-8', 09:33:30 INFO - 'LANGUAGE': 'en_US:en', 09:33:30 INFO - 'LOGNAME': 'cltbld', 09:33:30 INFO - 'MAIL': '/var/mail/cltbld', 09:33:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:30 INFO - 'MOZ_NO_REMOTE': '1', 09:33:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:30 INFO - 'NO_EM_RESTART': '1', 09:33:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:30 INFO - 'PWD': '/builds/slave/test', 09:33:30 INFO - 'SHELL': '/bin/bash', 09:33:30 INFO - 'SHLVL': '1', 09:33:30 INFO - 'TERM': 'linux', 09:33:30 INFO - 'TMOUT': '86400', 09:33:30 INFO - 'USER': 'cltbld', 09:33:30 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 09:33:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:30 INFO - '_': '/tools/buildbot/bin/python'} 09:33:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:33: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 09:33: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 09:33: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 09:33: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 09:33: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 09:33: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 09:33:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:33:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:33:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:33:33 INFO - Installing collected packages: mozsystemmonitor 09:33:33 INFO - Running setup.py install for mozsystemmonitor 09:33:33 INFO - Successfully installed mozsystemmonitor 09:33:33 INFO - Cleaning up... 09:33:33 INFO - Return code: 0 09:33:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:33: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')]} 09:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33: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')]} 09:33:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2908a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aadc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab3f0>, '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 0x2aabcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aac1a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:33: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==2.5.1'] in /builds/slave/test/build 09:33: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==2.5.1 09:33:33 INFO - Using env: {'DISPLAY': ':0', 09:33:33 INFO - 'HOME': '/home/cltbld', 09:33:33 INFO - 'LANG': 'en_US.UTF-8', 09:33:33 INFO - 'LANGUAGE': 'en_US:en', 09:33:33 INFO - 'LOGNAME': 'cltbld', 09:33:33 INFO - 'MAIL': '/var/mail/cltbld', 09:33:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:33 INFO - 'MOZ_NO_REMOTE': '1', 09:33:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:33 INFO - 'NO_EM_RESTART': '1', 09:33:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:33 INFO - 'PWD': '/builds/slave/test', 09:33:33 INFO - 'SHELL': '/bin/bash', 09:33:33 INFO - 'SHLVL': '1', 09:33:33 INFO - 'TERM': 'linux', 09:33:33 INFO - 'TMOUT': '86400', 09:33:33 INFO - 'USER': 'cltbld', 09:33:33 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 09:33:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:33 INFO - '_': '/tools/buildbot/bin/python'} 09:33:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:33 INFO - Downloading/unpacking jsonschema==2.5.1 09:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:33:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:35 INFO - Downloading functools32-3.2.3-2.tar.gz 09:33:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:33:35 INFO - warning: no files found matching '*.txt' 09:33:35 INFO - no previously-included directories found matching 'build' 09:33:35 INFO - no previously-included directories found matching 'dist' 09:33:35 INFO - no previously-included directories found matching '.git*' 09:33:35 INFO - Installing collected packages: jsonschema, functools32 09:33:35 INFO - Running setup.py install for functools32 09:33:35 INFO - warning: no files found matching '*.txt' 09:33:35 INFO - no previously-included directories found matching 'build' 09:33:35 INFO - no previously-included directories found matching 'dist' 09:33:35 INFO - no previously-included directories found matching '.git*' 09:33:35 INFO - Successfully installed jsonschema functools32 09:33:35 INFO - Cleaning up... 09:33:35 INFO - Return code: 0 09:33:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:33:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:35 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 0x273c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2908a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aadc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab3f0>, '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 0x2aabcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aac1a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:33:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:33:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:33:36 INFO - Using env: {'DISPLAY': ':0', 09:33:36 INFO - 'HOME': '/home/cltbld', 09:33:36 INFO - 'LANG': 'en_US.UTF-8', 09:33:36 INFO - 'LANGUAGE': 'en_US:en', 09:33:36 INFO - 'LOGNAME': 'cltbld', 09:33:36 INFO - 'MAIL': '/var/mail/cltbld', 09:33:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:36 INFO - 'MOZ_NO_REMOTE': '1', 09:33:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:36 INFO - 'NO_EM_RESTART': '1', 09:33:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:36 INFO - 'PWD': '/builds/slave/test', 09:33:36 INFO - 'SHELL': '/bin/bash', 09:33:36 INFO - 'SHLVL': '1', 09:33:36 INFO - 'TERM': 'linux', 09:33:36 INFO - 'TMOUT': '86400', 09:33:36 INFO - 'USER': 'cltbld', 09:33:36 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 09:33:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:36 INFO - '_': '/tools/buildbot/bin/python'} 09:33:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:33:36 INFO - Cleaning up... 09:33:36 INFO - Return code: 0 09:33:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:33:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x273c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2908a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aadc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab3f0>, '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 0x2aabcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aac1a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:33:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:33:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:33:36 INFO - Using env: {'DISPLAY': ':0', 09:33:36 INFO - 'HOME': '/home/cltbld', 09:33:36 INFO - 'LANG': 'en_US.UTF-8', 09:33:36 INFO - 'LANGUAGE': 'en_US:en', 09:33:36 INFO - 'LOGNAME': 'cltbld', 09:33:36 INFO - 'MAIL': '/var/mail/cltbld', 09:33:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:36 INFO - 'MOZ_NO_REMOTE': '1', 09:33:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:36 INFO - 'NO_EM_RESTART': '1', 09:33:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:36 INFO - 'PWD': '/builds/slave/test', 09:33:36 INFO - 'SHELL': '/bin/bash', 09:33:36 INFO - 'SHLVL': '1', 09:33:36 INFO - 'TERM': 'linux', 09:33:36 INFO - 'TMOUT': '86400', 09:33:36 INFO - 'USER': 'cltbld', 09:33:36 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 09:33:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:36 INFO - '_': '/tools/buildbot/bin/python'} 09:33:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:36 INFO - Downloading/unpacking blobuploader==1.2.4 09:33:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:38 INFO - Downloading blobuploader-1.2.4.tar.gz 09:33:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:33:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:33:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:39 INFO - Downloading docopt-0.6.1.tar.gz 09:33:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:33:39 INFO - Installing collected packages: blobuploader, requests, docopt 09:33:39 INFO - Running setup.py install for blobuploader 09:33:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:33:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:33:39 INFO - Running setup.py install for requests 09:33:39 INFO - Running setup.py install for docopt 09:33:39 INFO - Successfully installed blobuploader requests docopt 09:33:39 INFO - Cleaning up... 09:33:39 INFO - Return code: 0 09:33:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:39 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 0x273c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2908a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aadc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab3f0>, '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 0x2aabcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aac1a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:33:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:33:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:33:39 INFO - Using env: {'DISPLAY': ':0', 09:33:39 INFO - 'HOME': '/home/cltbld', 09:33:39 INFO - 'LANG': 'en_US.UTF-8', 09:33:39 INFO - 'LANGUAGE': 'en_US:en', 09:33:39 INFO - 'LOGNAME': 'cltbld', 09:33:39 INFO - 'MAIL': '/var/mail/cltbld', 09:33:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:39 INFO - 'MOZ_NO_REMOTE': '1', 09:33:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:39 INFO - 'NO_EM_RESTART': '1', 09:33:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:39 INFO - 'PWD': '/builds/slave/test', 09:33:39 INFO - 'SHELL': '/bin/bash', 09:33:39 INFO - 'SHLVL': '1', 09:33:39 INFO - 'TERM': 'linux', 09:33:39 INFO - 'TMOUT': '86400', 09:33:39 INFO - 'USER': 'cltbld', 09:33:39 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 09:33:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:39 INFO - '_': '/tools/buildbot/bin/python'} 09:33:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:33:40 INFO - Running setup.py (path:/tmp/pip-TVrnFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:33:40 INFO - Running setup.py (path:/tmp/pip-ZuKnI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:33:40 INFO - Running setup.py (path:/tmp/pip-RD8qo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:33:40 INFO - Running setup.py (path:/tmp/pip-zLNJC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:33:40 INFO - Running setup.py (path:/tmp/pip-MaZ5mz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:33:40 INFO - Running setup.py (path:/tmp/pip-ou8RB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:33:40 INFO - Running setup.py (path:/tmp/pip-wNSXzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:33:40 INFO - Running setup.py (path:/tmp/pip-l7w1SV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:33:40 INFO - Running setup.py (path:/tmp/pip-wdVJmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:33:40 INFO - Running setup.py (path:/tmp/pip-k6DXiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:33:40 INFO - Running setup.py (path:/tmp/pip-GU23M1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:33:40 INFO - Running setup.py (path:/tmp/pip-Txgqao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:33:40 INFO - Running setup.py (path:/tmp/pip-0Nn_jI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:33:41 INFO - Running setup.py (path:/tmp/pip-SKbg43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:41 INFO - Running setup.py (path:/tmp/pip-BdCZop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:33:41 INFO - Running setup.py (path:/tmp/pip-HiFtDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:33:41 INFO - Running setup.py (path:/tmp/pip-hJpyyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:33:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:33:41 INFO - Running setup.py install for manifestparser 09:33:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:33:41 INFO - Running setup.py install for mozcrash 09:33:41 INFO - Running setup.py install for mozdebug 09:33:41 INFO - Running setup.py install for mozdevice 09:33:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:33:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:33:41 INFO - Running setup.py install for mozfile 09:33:41 INFO - Running setup.py install for mozhttpd 09:33:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:33:41 INFO - Running setup.py install for mozinfo 09:33:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:33:42 INFO - Running setup.py install for mozInstall 09:33:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:33:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:33:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:33:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:33:42 INFO - Running setup.py install for mozleak 09:33:42 INFO - Running setup.py install for mozlog 09:33:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:33:42 INFO - Running setup.py install for moznetwork 09:33:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:33:42 INFO - Running setup.py install for mozprocess 09:33:42 INFO - Running setup.py install for mozprofile 09:33:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:33:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:33:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:33:42 INFO - Running setup.py install for mozrunner 09:33:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:33:42 INFO - Running setup.py install for mozscreenshot 09:33:42 INFO - Running setup.py install for moztest 09:33:42 INFO - Running setup.py install for mozversion 09:33:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:33:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:33:43 INFO - Cleaning up... 09:33:43 INFO - Return code: 0 09:33:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:43 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 0x273c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2908a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aadc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab3f0>, '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 0x2aabcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aac1a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:33:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:33:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:33:43 INFO - Using env: {'DISPLAY': ':0', 09:33:43 INFO - 'HOME': '/home/cltbld', 09:33:43 INFO - 'LANG': 'en_US.UTF-8', 09:33:43 INFO - 'LANGUAGE': 'en_US:en', 09:33:43 INFO - 'LOGNAME': 'cltbld', 09:33:43 INFO - 'MAIL': '/var/mail/cltbld', 09:33:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:43 INFO - 'MOZ_NO_REMOTE': '1', 09:33:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:43 INFO - 'NO_EM_RESTART': '1', 09:33:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:43 INFO - 'PWD': '/builds/slave/test', 09:33:43 INFO - 'SHELL': '/bin/bash', 09:33:43 INFO - 'SHLVL': '1', 09:33:43 INFO - 'TERM': 'linux', 09:33:43 INFO - 'TMOUT': '86400', 09:33:43 INFO - 'USER': 'cltbld', 09:33:43 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 09:33:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:43 INFO - '_': '/tools/buildbot/bin/python'} 09:33:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:33:43 INFO - Running setup.py (path:/tmp/pip-okK6kz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:33:43 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:33:43 INFO - Running setup.py (path:/tmp/pip-vjLpe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:33:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:33:43 INFO - Running setup.py (path:/tmp/pip-OXvHOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:33:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:33:43 INFO - Running setup.py (path:/tmp/pip-Es2dMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:33:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:33:43 INFO - Running setup.py (path:/tmp/pip-Qui9Q5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:33:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:33:43 INFO - Running setup.py (path:/tmp/pip-fi0ADd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:33:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:33:43 INFO - Running setup.py (path:/tmp/pip-urKwNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:33:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:33:43 INFO - Running setup.py (path:/tmp/pip-4pM4Q1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:33:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:33:43 INFO - Running setup.py (path:/tmp/pip-wX0NY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:33:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:33:43 INFO - Running setup.py (path:/tmp/pip-koKzEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:33:44 INFO - Running setup.py (path:/tmp/pip-0uq11F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:33:44 INFO - Running setup.py (path:/tmp/pip-zBlQGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:33:44 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)) 09:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:33:44 INFO - Running setup.py (path:/tmp/pip-o4F44T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:33:44 INFO - Running setup.py (path:/tmp/pip-C2NtKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:44 INFO - Running setup.py (path:/tmp/pip-g2uz8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:33:44 INFO - Running setup.py (path:/tmp/pip-kIqxIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:33:44 INFO - Running setup.py (path:/tmp/pip-RD2z6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:33:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:33:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:46 INFO - Downloading blessings-1.6.tar.gz 09:33:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:33:46 INFO - Installing collected packages: blessings 09:33:46 INFO - Running setup.py install for blessings 09:33:46 INFO - Successfully installed blessings 09:33:46 INFO - Cleaning up... 09:33:46 INFO - Return code: 0 09:33:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:33:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:33:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:33:47 INFO - Reading from file tmpfile_stdout 09:33:47 INFO - Current package versions: 09:33:47 INFO - argparse == 1.2.1 09:33:47 INFO - blessings == 1.6 09:33:47 INFO - blobuploader == 1.2.4 09:33:47 INFO - docopt == 0.6.1 09:33:47 INFO - functools32 == 3.2.3-2 09:33:47 INFO - jsonschema == 2.5.1 09:33:47 INFO - manifestparser == 1.1 09:33:47 INFO - mozInstall == 1.12 09:33:47 INFO - mozcrash == 1.0 09:33:47 INFO - mozdebug == 0.1 09:33:47 INFO - mozdevice == 0.49 09:33:47 INFO - mozfile == 1.2 09:33:47 INFO - mozhttpd == 0.7 09:33:47 INFO - mozinfo == 0.9 09:33:47 INFO - mozleak == 0.1 09:33:47 INFO - mozlog == 3.4 09:33:47 INFO - moznetwork == 0.27 09:33:47 INFO - mozprocess == 0.23 09:33:47 INFO - mozprofile == 0.28 09:33:47 INFO - mozrunner == 6.13 09:33:47 INFO - mozscreenshot == 0.1 09:33:47 INFO - mozsystemmonitor == 0.3 09:33:47 INFO - moztest == 0.8 09:33:47 INFO - mozversion == 1.4 09:33:47 INFO - psutil == 3.1.1 09:33:47 INFO - requests == 1.2.3 09:33:47 INFO - wsgiref == 0.1.2 09:33:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:47 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 0x273c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2908a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aadc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab3f0>, '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 0x2aabcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aac1a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:33:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:33:47 INFO - Using env: {'DISPLAY': ':0', 09:33:47 INFO - 'HOME': '/home/cltbld', 09:33:47 INFO - 'LANG': 'en_US.UTF-8', 09:33:47 INFO - 'LANGUAGE': 'en_US:en', 09:33:47 INFO - 'LOGNAME': 'cltbld', 09:33:47 INFO - 'MAIL': '/var/mail/cltbld', 09:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:47 INFO - 'MOZ_NO_REMOTE': '1', 09:33:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:47 INFO - 'NO_EM_RESTART': '1', 09:33:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:47 INFO - 'PWD': '/builds/slave/test', 09:33:47 INFO - 'SHELL': '/bin/bash', 09:33:47 INFO - 'SHLVL': '1', 09:33:47 INFO - 'TERM': 'linux', 09:33:47 INFO - 'TMOUT': '86400', 09:33:47 INFO - 'USER': 'cltbld', 09:33:47 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 09:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:47 INFO - '_': '/tools/buildbot/bin/python'} 09:33:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:33:47 INFO - Cleaning up... 09:33:47 INFO - Return code: 0 09:33:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:47 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 0x273c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2908a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aadc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aab3f0>, '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 0x2aabcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aac1a0>, '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': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:33:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:33:47 INFO - Using env: {'DISPLAY': ':0', 09:33:47 INFO - 'HOME': '/home/cltbld', 09:33:47 INFO - 'LANG': 'en_US.UTF-8', 09:33:47 INFO - 'LANGUAGE': 'en_US:en', 09:33:47 INFO - 'LOGNAME': 'cltbld', 09:33:47 INFO - 'MAIL': '/var/mail/cltbld', 09:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:47 INFO - 'MOZ_NO_REMOTE': '1', 09:33:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:47 INFO - 'NO_EM_RESTART': '1', 09:33:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:47 INFO - 'PWD': '/builds/slave/test', 09:33:47 INFO - 'SHELL': '/bin/bash', 09:33:47 INFO - 'SHLVL': '1', 09:33:47 INFO - 'TERM': 'linux', 09:33:47 INFO - 'TMOUT': '86400', 09:33:47 INFO - 'USER': 'cltbld', 09:33:47 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 09:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:47 INFO - '_': '/tools/buildbot/bin/python'} 09:33:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:33:47 INFO - Cleaning up... 09:33:47 INFO - Return code: 0 09:33:47 INFO - Running post-action listener: _resource_record_post_action 09:33:47 INFO - Running post-action listener: _start_resource_monitoring 09:33:47 INFO - Starting resource monitoring. 09:33:47 INFO - [mozharness: 2016-12-30 17:33:47.545655Z] Finished create-virtualenv step (success) 09:33:47 INFO - [mozharness: 2016-12-30 17:33:47.546160Z] Running install step. 09:33:47 INFO - Running pre-action listener: _resource_record_pre_action 09:33:47 INFO - Running main action method: install 09:33:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:33:47 INFO - Reading from file tmpfile_stdout 09:33:47 INFO - Detecting whether we're running mozinstall >=1.0... 09:33:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:33:47 INFO - Reading from file tmpfile_stdout 09:33:47 INFO - Output received: 09:33:47 INFO - Usage: mozinstall [options] installer 09:33:47 INFO - Options: 09:33:47 INFO - -h, --help show this help message and exit 09:33:47 INFO - -d DEST, --destination=DEST 09:33:47 INFO - Directory to install application into. [default: 09:33:47 INFO - "/builds/slave/test"] 09:33:47 INFO - --app=APP Application being installed. [default: firefox] 09:33:47 INFO - mkdir: /builds/slave/test/build/application 09:33:47 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'] 09:33:47 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 09:34:03 INFO - Reading from file tmpfile_stdout 09:34:03 INFO - Output received: 09:34:03 INFO - /builds/slave/test/build/application/firefox/firefox 09:34:03 INFO - Running post-action listener: _resource_record_post_action 09:34:03 INFO - [mozharness: 2016-12-30 17:34:03.961422Z] Finished install step (success) 09:34:03 INFO - [mozharness: 2016-12-30 17:34:03.961668Z] Running run-tests step. 09:34:03 INFO - Running pre-action listener: _resource_record_pre_action 09:34:03 INFO - Running main action method: run_tests 09:34:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:34:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:34:03 INFO - Python 2.7.3 09:34:03 INFO - Return code: 0 09:34:03 INFO - grabbing minidump binary from tooltool 09:34:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:03 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 0x2aab3f0>, '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 0x2aabcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aac1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:34:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:34:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:34:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:34:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:34:04 INFO - Return code: 0 09:34:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:34:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:34:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:34:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:34:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:34:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:34:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:34:04 INFO - Using env: {'DISPLAY': ':0', 09:34:04 INFO - 'HOME': '/home/cltbld', 09:34:04 INFO - 'LANG': 'en_US.UTF-8', 09:34:04 INFO - 'LANGUAGE': 'en_US:en', 09:34:04 INFO - 'LOGNAME': 'cltbld', 09:34:04 INFO - 'MAIL': '/var/mail/cltbld', 09:34:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:34:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:34:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:04 INFO - 'MOZ_NO_REMOTE': '1', 09:34:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:34:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:04 INFO - 'NO_EM_RESTART': '1', 09:34:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:04 INFO - 'PWD': '/builds/slave/test', 09:34:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:34:04 INFO - 'SHELL': '/bin/bash', 09:34:04 INFO - 'SHLVL': '1', 09:34:04 INFO - 'TERM': 'linux', 09:34:04 INFO - 'TMOUT': '86400', 09:34:04 INFO - 'USER': 'cltbld', 09:34:04 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262', 09:34:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:04 INFO - '_': '/tools/buildbot/bin/python'} 09:34:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:34:04 INFO - mozversion application_buildid: 20161230071820 09:34:04 INFO - mozversion application_changeset: 6b5d5c4d2ed94d33f518ca0fbf101da315e2bf87 09:34:04 INFO - mozversion application_display_name: Nightly 09:34:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:34:04 INFO - mozversion application_name: Firefox 09:34:04 INFO - mozversion application_remotingname: firefox 09:34:04 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 09:34:04 INFO - mozversion application_vendor: Mozilla 09:34:04 INFO - mozversion application_version: 53.0a1 09:34:04 INFO - mozversion platform_buildid: 20161230071820 09:34:04 INFO - mozversion platform_changeset: 6b5d5c4d2ed94d33f518ca0fbf101da315e2bf87 09:34:04 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 09:34:04 INFO - mozversion platform_version: 53.0a1 09:34:04 INFO - using testdate: 1483119244 09:34:04 INFO - actual date: 1483119244 09:34:04 INFO - starting webserver on 'localhost:48370' 09:34:04 INFO - SUITE-START | Running 2 tests 09:34:04 INFO - TEST-START | dromaeo_css 09:34:04 INFO - operating with platform_type : linux_ 09:34:04 INFO - Initialising browser for dromaeo_css test... 09:34:04 INFO - TEST-INFO | started process 24938 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI7h99O/profile http://localhost:48370/getInfo.html) 09:34:04 INFO - PROCESS | 24938 | 1483119244748 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 09:34:08 INFO - PROCESS | 24938 | __metrics Screen width/height:1600/1200 09:34:08 INFO - PROCESS | 24938 | colorDepth:24 09:34:08 INFO - PROCESS | 24938 | Browser inner width/height: 1024/697 09:34:08 INFO - PROCESS | 24938 | __metrics 09:34:09 ERROR - PROCESS | 24938 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 09:34:10 INFO - TEST-INFO | 24938: exit 0 09:34:10 INFO - Browser initialized. 09:34:10 INFO - Running cycle 1/1 for dromaeo_css test... 09:34:10 INFO - TEST-INFO | started process 25058 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI7h99O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:34:22 INFO - PROCESS | 25058 | RSS: Main: 157691904 09:34:22 INFO - PROCESS | 25058 | 09:37:26 INFO - PROCESS | 25058 | Cycle 1(1): loaded http://localhost:48370/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48370/tests/dromaeo/cssquery-ext.html) 09:37:27 INFO - PROCESS | 25058 | RSS: Main: 167444480 09:37:27 INFO - PROCESS | 25058 | 09:39:10 INFO - PROCESS | 25058 | ************************* 09:39:10 INFO - PROCESS | 25058 | A coding exception was thrown and uncaught in a Task. 09:39:10 INFO - PROCESS | 25058 | 09:39:10 INFO - PROCESS | 25058 | Full message: ReferenceError: fetch is not defined 09:39:10 INFO - PROCESS | 25058 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 09:39:10 INFO - PROCESS | 25058 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 09:39:10 INFO - PROCESS | 25058 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 09:39:10 INFO - PROCESS | 25058 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:39:10 INFO - PROCESS | 25058 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:39:10 INFO - PROCESS | 25058 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:39:10 INFO - PROCESS | 25058 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 09:39:10 INFO - PROCESS | 25058 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:39:10 INFO - PROCESS | 25058 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:39:10 INFO - PROCESS | 25058 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:39:10 INFO - PROCESS | 25058 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 09:39:10 INFO - PROCESS | 25058 | 09:39:10 INFO - PROCESS | 25058 | ************************* 09:40:30 INFO - PROCESS | 25058 | Cycle 1(1): loaded http://localhost:48370/tests/dromaeo/cssquery-ext.html (next: http://localhost:48370/tests/dromaeo/cssquery-jquery.html) 09:40:30 INFO - PROCESS | 25058 | RSS: Main: 162992128 09:40:30 INFO - PROCESS | 25058 | 09:43:32 INFO - PROCESS | 25058 | Cycle 1(1): loaded http://localhost:48370/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48370/tests/dromaeo/cssquery-mootools.html) 09:43:33 INFO - PROCESS | 25058 | RSS: Main: 168476672 09:43:33 INFO - PROCESS | 25058 | 09:46:35 INFO - PROCESS | 25058 | Cycle 1(1): loaded http://localhost:48370/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48370/tests/dromaeo/cssquery-prototype.html) 09:46:36 INFO - PROCESS | 25058 | RSS: Main: 162766848 09:46:36 INFO - PROCESS | 25058 | 09:49:39 INFO - PROCESS | 25058 | Cycle 1(1): loaded http://localhost:48370/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48370/tests/dromaeo/cssquery-yui.html) 09:49:39 INFO - PROCESS | 25058 | RSS: Main: 167567360 09:49:39 INFO - PROCESS | 25058 | 09:52:42 INFO - PROCESS | 25058 | Cycle 1(1): loaded http://localhost:48370/tests/dromaeo/cssquery-yui.html (next: http://localhost:48370/tests/dromaeo/cssquery-dojo.html) 09:52:42 INFO - PROCESS | 25058 | RSS: Main: 166440960 09:52:42 INFO - PROCESS | 25058 | 09:52:42 INFO - PROCESS | 25058 | __start_tp_report 09:52:42 INFO - PROCESS | 25058 | _x_x_mozilla_page_load 09:52:42 INFO - PROCESS | 25058 | _x_x_mozilla_page_load_details 09:52:42 INFO - PROCESS | 25058 | |i|pagename|runs| 09:52:42 INFO - PROCESS | 25058 | |0;dojo.html;2605.92182234533;2623.5277650023;2631.5789473684213;2655.588383800511;2668.0528412413605;4721.315409265663;4923.113839508887;4944.3819522559725;4976.7013979161275;4985.7008579485255;4728.881777955544;4846.68496547725;4881.1213981483315;4887.535684110004;4937.037277730835;4407.757573333465;4471;4481.462224533045;4544.84093056743;4601.447826260854;4374.34384842273;4491.820327186909;4522.253828118356;4532.546745325474;4562;4368.781560921973;4482.574155455227;4510.007798284372;4562.201614717428;4575.954240457603;4382.627476664456;4410.1179764047;4436.046250056221;4457.3091170868565;4516.593506584423;4435.711678740904;4441.133978874121;4442.822287108512;4457.66567507435;4479.820807167709;4846.515348465161;4875.68308059975;4888.169011268057;4894.1679914414635;4896.853094407174;5167.819126330561;5206.661566998133;5208.713520756357;5209.244659524347;5248.291480650101;5297.205419187114;5297.920531192035;5299.284596579451;5300.602454815904;5335.51980321769;4914.754262286871;4923.950760492385;4936.111499929975;4939.8271060513425;4977.527134922213;5231.973840130851;5271.1302635064785;5273.34083239595;5332.626716129834;5410.107332290128;5116.283720279164;5152.92270615941;5163;5200.037992971313;5247.947520524822;5093.923591146136;5162.303089082926;5253.080710875582;5287.471252874759;5289.70906600133;5170.689758614495;5193.350831146106;5214.530692237717;5225.346831646044;5235.973820130875;5098.97450512745;5172.301739265151;5243.737813109406;5247.291615631917;5249.895002100013;4422.601965823027;4436.134953684065;4442.044960333544;4471.418715566955;4518.977405112953;3837.9616203837604;3838.980805095957;3853.537575490959;3871.922561548753;3889.649931506178;4395.274779661384;4550.13547425988;4560.338750881104;4560.863174104782;4613.30800379939;5100;5269.051570717308;5297.311349524614;5312.5218730314455;5372.086745253238;4553.385292985405;4563.224251877189;4569.840055598038;4580.610648094949;4602.125596136723;4762.095201911626;4765.547273009059;4773.809047638056;4783.091212669582;4824.445188803263;4760.143174228603;4804.399450068741;4811.109944660249;4846.36997190363;4853.635977301646;5254.448282930237;5291.894162116813;5297.920531192112;5303;5357.973210133924;4830.203016502378;4862.440819305825;4864.6594738369395;4871.634627402888;4892.290617860462;5022.472640372708;5070.366204224472;5105.2086926526445;5141.254518094781;5196.610254230872;5265.420803711512;5274.446183150714;5279.155335146512;5312.707801070978;5326.280952071421;5006.224035274538;5052.823151189691;5105.2086926526445;5114.232865070269;5156.767945442389;4997.775100120431;5021.046001259749;5046.116929537389;5068.417132029864;5129.333186685707;4938.925916111333;4941.258811178352;4980.601551875914;4987.80048798044;5024.874378140576;5104.617153713561;5117.692938423706;5118.181091025418;5118.436971933159;5134.743262836769;4853.80584776605;4877.6341774367775;4973.751312434436;5004.524570165829;5026.170681837602 09:52:42 INFO - PROCESS | 25058 | |1;ext.html;498.29740066506224;499.2227669496592;500.4370083655887;511.3097318619862;522.9032628963643;38285.61714382855;40614;40687.593124068786;44286.55713442864;44322.33516497247;39999.80000099995;40471.392929106085;40490;40501.39247911284;40513.59486405135;7551.773446796591;7720;7738.961305193466;9578.808423831519;9912.851307230398;9681.661141860037;9694.854577181313;9758.707238782812;9772.609095636202;9782.804343913118;9672.516374181298;9732.805343893118;9776.462294573796;9824.21406287499;9832.410055396664;9694.612215511372;9708.077732615426;9733.270004749602;9741.415515069119;9780.168685661727;9610.231181505464;9660.90339096607;9661.855072173923;9676.56455459506;9680.27397945157;34027.48958765621;34725.30549388998;34777.478337824956;34864;34971.30057398838;3575.856965721368;3686.5391826021746;3710.8330125144435;3725.217704282104;3735.9066023349633;27303.45393092127;27581;29154;29912.55131173034;30585.38829223403;3310.8841190558214;3479.425894727367;3486.564179477565;3521.5069890215136;3521.6302288259876;237222;240248;240790;243469.99999999822;243874;56827;57157.85421842784;57563.71218143882;57895;57940.42059579435;60450;60640;60781;60799.69600152059;61047;13772.449102036007;14283.143011419348;14301.78547321791;14397.92801036009;14428.567142985727;9584.424934503952;9899.406035637885;10452.843207351896;10733.195010374253;10761.031507164393;19010.42968710941;19187.90406047961;19231.903840480707;19239.13423895928;19280.807191927903;6920.9653951729915;7261;7371.299726525972;7400.296971787671;7626.656800443993;764.6903004283274;776.3323541754087;781.7928249014016;791.5053091817614;798.3652995868183;25321.240362789147;26402.73597264041;26792.33019174536;26913.192604221906;27256.182314530597;909.7862002429424;949.4920217683645;952.1478276942237;966.6375109334;969.8157350103457;13667.589972300648;14272.714545709028;14341.713165736835;14571.708565828623;14607.488737894122;351999;354822;356346;356820;357325;50486.24270635944;50605.74697126491;50683;50772.492275077515;50922;1176.9941150294364;1177.670252329349;1230.3356187658744;1230.9015278777676;1241;25350.74649253484;25622.359441014123;25950.313229640014;26171.476570468865;26262.73737262603;41182.588174119075;41908;41944.1611167781;42003.57996420119;42064;318335;318764;319077;319371;320782;23603.64594531049;24133.034678612657;24268.75731242665;25274.11540596144;25396.492070158863;37344.81327593345;37550;37603.43594846025;38081.80959095187;38274.425883612326;30392.848035759678;31877.840610796797;32515.837420812746;32631.836840815646;33312;32689.673103268662;32910.34179316448;33171;33620;34035.82982085074;29674.851625741732;29968.85015574908;30073.248168795955;30134.54798177985;30279;24999.375015624755;26612.7338726618;28190.295242619097;28657.713422866338;29946.850265748533;22423.551528969656;23019.309420717404;23074.192403265806;23105.191318304453;23306 09:52:42 INFO - PROCESS | 25058 | |2;jquery.html;366.6040676069843;369.4034134872182;373.25349301397205;377.8262594208645;388.8172558897319;15613.68772624547;16297.185140742975;16664.083475408865;16761.329546818142;16813;15024.549263522085;15106.471273505429;15366.923165384185;15616.84383156168;15690.76463853043;4824.541668541483;5044.243363495469;5119.872003199913;5123.590112790967;5126.666766660155;3851.345271303871;4063.5326937402133;4995.525425084611;5097.617678674096;5730.1118326659225;5947.583669143161;6050.818475445743;6079.027355623101;6080;6080.817575472743;5744.195812586241;5954.07711804671;6002.249718785152;6002.939970600303;6003.579749417542;5214.32213812202;5252.606054545924;5257.316548848664;5257.86855328616;5258.395284542288;19792.208311667517;20323.18707251708;20404.387868363974;20512;20567.89716051425;2321.0831721469926;2358.0803486640225;2358.3868194269444;2392.6411038344213;2413.734489206186;12433.129680922339;12504.937475312656;12608.432620532007;12653;12993.090483666147;1659.9751003734953;1688.8057873344599;1699.8629783346912;1704.8295170482977;1728.3432295727669;63914.68042659803;64133.358666412736;64563.67718161379;64745.67627161881;65782.3421765781;9482.336236463381;9541.856872146924;9570.856437153448;9876.160526355197;9892.406455612543;12806.67983300425;12852.871471285354;12875.806862897063;12878.613641590768;12944.546940857024;6634.568753031038;6741.932580674229;6825.453963682893;6901;6946.965265173743;6287.559870808999;6288.748450061947;6495.772647957298;6502.512311576651;6508.381703738138;5871.005787048636;5934.1692163097205;5962.34414214436;6052.697365131726;6200.3179650238435;1588.1265304082708;1686.6710991356813;1736.6873962686839;1755.7454169145658;1781.2251670523365;765.4679997401863;802.9598520073973;814.1004190369674;814.8714031067015;815.8939337886037;15524.84475155234;15747.685046299239;15915.283812228477;15973.680526389408;16140.75788863168;805.6997272786228;806.467731297341;816.1797393619374;819.2954059508818;834.7370578267826;9022.548872556346;9366.859497107549;9393.295502837178;9429.245660347156;9779.902200978042;317841;318070;319786;320815;325100;61564.38435615676;65417.672911635134;65956.6702166486;66199.66900165468;66217.66891165513;907.804821963281;919.5080631862055;939.3143005605946;941;942.783159873233;13990.720185596434;14391.568252952427;14466.927665362027;14651.70696586041;15161.772573411186;31792.979666931606;32663.18342041468;33156.33687326192;33617.15957101092;33721.83139084289;39938.60061399465;40708.38937415882;41477.37783933183;42612;42818.5718142827;7482.70069197226;7538.133114691881;7610.467267291458;7614.961925190339;7712;10944.78110437771;11742.354170520703;11798.764024719629;11842.940785296018;12534.87465125337;10372.17022638172;10721.892781072089;10723.088537474401;10832.945835271088;11148.331100134017;10499.63251286232;10812;10833.404162771123;10931;10991.450427478754;10709.303895246783;10729.195310351599;10920.126389888632;11276.32342059479;11363;7598.696052158073;7696.15342312334;7841.490303130278;7874.488158269694;7889.0138732658415;10485.318454300446;10634;10870.565177392815;11077.778444431227;11136.554537818396 09:52:42 INFO - PROCESS | 25058 | |3;mootools.html;1307.8757518035789;1312.540610786225;1320.4124164746686;1329.900257480689;1342.9798553021706;6582.17722784652;6849.931500684992;7028.472864535155;7363.190049094595;7373.225811289811;6904.171499420064;7176.282371762821;7185.604791736452;7281.017062696535;7324.63376831159;2090.1639344262303;2134.018351558285;2134.3596920923737;2136.1455417832835;2137.027652418146;2117.692934524493;2124.2352753008904;2168.6530155175174;2172.4677454023786;2195.1987524553583;2099.149844313056;2113.5455876986443;2123.437289118385;2131;2139.5506943541873;2025.4531276555394;2051.7025031370445;2051.8050785175383;2054.578811343686;2056.393363957636;2097.6014557234075;2124.2671278408925;2126.797954194349;2138.4119367173994;2147.516808718026;7374.151972523121;7409.222031686649;7429.925700742978;7434.516756410816;7450.664720087609;4304.418903448026;4491.797869095899;4499.775011249424;4500.032493014018;4520.638348932111;2727.386338073937;2885.451764164823;2928.6046383738135;2936.6329208848892;2956.320046389321;1133.5012594458412;1174.3364998775664;1186.2348785033605;1187.091874715843;1213.6541085790595;9516.762080947963;9589.18491928187;9589.952050239774;9646.035396460298;9649.807003859883;3669.6697297243377;3847.1920896611464;3867.941980870289;3921.215756848642;3943.4873466449176;7722.189170137055;7785.922140778633;7789.337906277917;7789.3768498519985;7823.882641760378;4372.23485889968;4372.519022907478;4374.03771170342;4374.278244089754;4374.431323927933;3900.8829735107993;3959.425883246913;4089.959100408958;4090.1819636072905;4099.3031184698675;3152.684731526829;3161.65221825599;3256.690614391629;3268.248302890301;3285.572875526214;960.4429430930044;991.1366630734174;998.1814911772423;1008.4050410257983;1021.1524434719314;721.7041013184675;733.5268751655152;751.8984937033431;759.3052357093309;764.0831002796666;4387.937669295426;4541.682082254277;4571.405717256802;4681.485036645966;4726.409198850144;821.219841150909;833.4624167411986;836.6527890925198;848.1899785704637;862.1206369503071;3290.341931613639;3319.6223567219818;3327.434336162858;3363.4618461046603;3412.3857705613227;8565.743027709179;8685.565721713889;8751.956240218886;8760.693375731818;8782.780430489289;8732.78168045804;8734.126587341216;8739.257163141205;8752.081031491602;8773.649054037767;6487.902681459782;6767.221769496472;6804.795856124324;6808.931910680929;6905.205901321412;4697.718136911796;4864.708117512961;5002.824901128443;5016.824411145592;5169.948300516947;7887.605619718876;7988.3609311253795;8014.639341229776;8027.5584842834205;8041.11547729761;7999.200079991954;8066.556339401389;8080.9191908080165;8093.3929955252415;8155.184481551797;6394.808155755335;6445.70994305248;6500.609963402022;6522.21733391996;6600.042993765973;6831.931680683129;6973.372396484341;7022.824429389306;7049.224585295511;7061.505694601534;6303.936960630335;6431.163948686641;6433.5496515243485;6590.505712071518;6627.602343859384;6284.591501552384;6284.811455656338;6322.083297921868;6333.904991425225;6357.427831495188;5397.217403476552;5438.320209973754;5454.018276710231;5478.780848766005;5490.6980116094;3925.666318362971;3961.485006949078;3995.760254384746;4031.536373317106;4117.505899292104;4076.571959944282;4089.7341672792363;4090.5909409058854;4136.648384887319;4162.334026555736 09:52:42 INFO - PROCESS | 25058 | |4;prototype.html;345.1509287153602;353.56864625157306;360.1716053077921;361.76123758319517;364.1569765991729;4871.318015477836;4993.525615066563;5042.168042273025;5110.284560161571;5119.820806271777;4864.927026094611;4908.631852611051;4979.161877729695;4984.750762461871;5019.4941517544685;4016.2569924563977;4037.2934736421157;4037.7173597848164;4040.373742069969;4044.939325910114;3924.174594292105;4040.131371755072;4048.4657183954014;4064.471618689581;4068.169323804285;3952.308346039446;3983.4704962348974;3998.580149084348;4003.1393250451147;4008.2384346974127;3942.2706799242233;3987.4616926714957;3991.760494370318;3995.8801235962824;3997.960020399788;3596.892093237207;3601.4957905893198;3626.1659818241687;3631.8184090795353;3633.182533929871;4678.8128474861305;4876.829310974099;4890.5109489051165;4916.58209052231;4917;4513.864584062483;4539.296409056601;4572.794224259883;4592.540745925414;4599.655025873073;5301.496357845998;5314.627976041678;5325.4142044375085;5331.853651464954;5333.306670132897;3840.5007349044445;3892.1198313248274;3915.490986171808;3924.646781789653;3939.783311917872;5454.154606036071;5495.835124946258;5496.972515137439;5526.58550608706;5527.281453411071;4043.4945631796027;4051.4327994080854;4056.3307054336296;4072.490938632671;4084;4041.413994970713;4115.73247738896;4144.461220041375;4146.564610715892;4160.313548264502;4546.568076032771;4549.678734523816;4551.4765801932535;4555.40779698637;4595.172868883633;5266.47335266478;5282.656627319212;5298.668585822226;5304.416514183436;5315;1354.5733094075335;1364.6793003644136;1374.17549470317;1375;1387.341013018808;964.6880242869692;973.933542770665;994.6618149829093;1006.642641862143;1014.9035841595037;4411.6544453941215;4430;4441.533638967861;4449.399331090326;4449.822007119679;5131.384233891882;5180.4560521145495;5199;5208.7916483341;5231.319928409359;4303.160883627663;4432.180046691373;4467.5822995408535;4477.171723231213;4479.238529450001;2750.188694335136;2766.847823369734;2773.459175460806;2774.8900439824233;2783.6520434945633;4686;4780.713157210509;4785.827472269316;4790.329353890458;4821.566059054632;5132.358455193101;5291.365036195682;5307.310049693592;5321.494458026481;5350.3312085989255;4798.488476130077;4829.6860704054125;4836.637252206169;4837.710356957303;4839.443464001614;5013.724245166551;5172.818951336685;5229.398619158694;5235.764390602356;5251.448597897166;5006.874828129326;5103.260027296095;5122.974385128051;5132.255822905732;5270.841874743764;4969;4973.254011898244;4979.975100124476;4985.501449855131;4986.7257300848805;3686.189038411545;3706.9814650926573;3714.4799728037556;3720.2373513429143;3722.2555488901785;5254.527092561688;5359.222912677717;5450.645708028966;5464.022107951256;5470.316210473691;5225.843224703265;5231.869203269796;5235.581153507786;5237.904838064805;5262.947370526245;5213.530782229466;5240.270710665395;5245.921311180412;5273.6572122811895;5292.010014341885;4964.230544265644;5138.203578445347;5140.922886156636;5146;5164.922526162036;5210.8957820844125;5221.242919776687;5222.542443146845;5245.475452454724;5281.227355638321;5164.715940623151;5164.948350516447;5174.327337446108;5177.430482646982;5183.974080129575 09:52:42 INFO - PROCESS | 25058 | |5;yui.html;679.6024325769424;679.9524033317666;739.1979702023305;746.8730315846307;768.06679883941;13191.538296159637;13524.053316267866;13773.311334433289;13847.238401887906;13867.653308667288;13967.580972570839;14680.486182983597;14696.118232906034;15210.087394756294;15225.923870380633;818.9426740128179;840.2060053249681;843.1189407069627;843.161054750521;843.1821133500525;784.5606460382176;812.7236739508566;816.0533780814256;817.5707753429438;818.7011740714656;849.5157760076759;856.1053698884649;856.2336708645789;859.7291853066296;866.7919699272161;850.2645211891731;851.2764150472111;858.3991206155654;858.5621333120091;863.9179277968583;634.6160572853404;634.7492740367575;635.0664523150961;635.1742734445203;635.2123367024856;13640.454381824715;13972.161670299814;14065.437382504688;14087.647808804759;14381.640458988504;12397.566085187065;12644.367781610883;12933.224006559636;13308.733825323536;13413.128146670435;10539.473026348653;12075.698107547381;12189.214325858036;12216.450259738333;12637.557685480964;3304.867805287786;3306.6528014558608;3333.3166700826273;3372.3255348930065;3386.8137252451106;60943;61682.69158654223;61687.383126169065;61991;63033.68483157555;10147.492625368703;10292.588296468208;10318.1745460363;10361.533730982124;10396.064354208158;18574.81425185758;18647.160877760532;18686.53283667919;18894.3386981455;18898.433047008613;6161.537884658668;6208.255009398901;6242.531810114168;6267.341929097471;6438.581492202992;3350.782199157047;3406.0803583032443;3417.0773891049275;3458.5849698036395;3500.334936362083;2933.5453004784295;2982.8657710403086;3016.75865930725;3023.319753055589;3055.618047744032;485.4150748348232;488.68479830509835;493.50156342094743;493.77533222383727;501.75664802570725;534.6070638081007;553.7646500237383;558.6229295225706;559.3232189051212;559.5663360895257;7927.801804954807;8204.753857384288;8264.586770661443;8340.87488687658;8374.2881855043;609.6403122157948;624.7672022050552;628.9654069026247;631.387554072558;639.3510586754448;3601.5858176309534;3793.772373657534;3859.6333348331864;3894.9805250973563;3973.6423721864594;63840.68079659665;65019.34980650227;65514.53495638244;65548.34451655518;67028.66485667638;55369;56722.71638641863;57061.42938570644;58206.7089664549;59213.11180332299;50192;50319.24521132186;50423.243651345256;50544.49455505471;50612.493875060776;7314.724742411215;7504.2120577340775;7524.661390237343;7529.058867641545;7628.084629844454;35868.64131358653;37855;37905.43141852819;38056.42915356327;38667.613323866404;42579.57420425756;48124.518754812;48148.27777583409;48198.759006204746;48561.757191213816;9615.615375384907;9715.562799674173;9728.951355243178;9882;10068.24488163376;10689.732756681145;10746.946265268623;10860;10940.23418360739;10984.560617575207;10391.792164156523;10578.312409693346;10581.576736930436;10640.680779576625;10667.093297069836;9261.53692315368;9344.205742511962;9398.107179817905;9406.576704048472;9444.433333999981;8994.145556172152;9062.275017998676;9833.311668183158;9852;9882.160016398686;8292.751217463485;8404.243618074403;8534.78663033429;8766.123387661182;9044.547772611475;7915.960420198093;8141.14517975604;8302.294304984143;8331.16688331162;8562.700305489278 09:52:42 INFO - PROCESS | 25058 | __end_tp_report 09:52:42 INFO - PROCESS | 25058 | __start_cc_report 09:52:42 INFO - PROCESS | 25058 | _x_x_mozilla_cycle_collect,354 09:52:42 INFO - PROCESS | 25058 | __end_cc_report 09:52:42 INFO - PROCESS | 25058 | __startTimestamp1483120362833__endTimestamp 09:52:42 INFO - PROCESS | 25058 | 09:52:42 INFO - PROCESS | 25058 | ------- Summary: start ------- 09:52:42 INFO - PROCESS | 25058 | Number of tests: 6 09:52:42 INFO - PROCESS | 25058 | 09:52:42 INFO - PROCESS | 25058 | [#0] dojo.html Cycles:165 Average:4826.41 Median:4938.93 stddev:519.69 (10.5%) stddev-sans-first:491.23 09:52:42 INFO - PROCESS | 25058 | Values: 2605.9 2623.5 2631.6 2655.6 2668.1 4721.3 4923.1 4944.4 4976.7 4985.7 4728.9 4846.7 4881.1 4887.5 4937.0 4407.8 4471.0 4481.5 4544.8 4601.4 4374.3 4491.8 4522.3 4532.5 4562.0 4368.8 4482.6 4510.0 4562.2 4576.0 4382.6 4410.1 4436.0 4457.3 4516.6 4435.7 4441.1 4442.8 4457.7 4479.8 4846.5 4875.7 4888.2 4894.2 4896.9 5167.8 5206.7 5208.7 5209.2 5248.3 5297.2 5297.9 5299.3 5300.6 5335.5 4914.8 4924.0 4936.1 4939.8 4977.5 5232.0 5271.1 5273.3 5332.6 5410.1 5116.3 5152.9 5163.0 5200.0 5247.9 5093.9 5162.3 5253.1 5287.5 5289.7 5170.7 5193.4 5214.5 5225.3 5236.0 5099.0 5172.3 5243.7 5247.3 5249.9 4422.6 4436.1 4442.0 4471.4 4519.0 3838.0 3839.0 3853.5 3871.9 3889.6 4395.3 4550.1 4560.3 4560.9 4613.3 5100.0 5269.1 5297.3 5312.5 5372.1 4553.4 4563.2 4569.8 4580.6 4602.1 4762.1 4765.5 4773.8 4783.1 4824.4 4760.1 4804.4 4811.1 4846.4 4853.6 5254.4 5291.9 5297.9 5303.0 5358.0 4830.2 4862.4 4864.7 4871.6 4892.3 5022.5 5070.4 5105.2 5141.3 5196.6 5265.4 5274.4 5279.2 5312.7 5326.3 5006.2 5052.8 5105.2 5114.2 5156.8 4997.8 5021.0 5046.1 5068.4 5129.3 4938.9 4941.3 4980.6 4987.8 5024.9 5104.6 5117.7 5118.2 5118.4 5134.7 4853.8 4877.6 4973.8 5004.5 5026.2 09:52:42 INFO - PROCESS | 25058 | 09:52:42 INFO - PROCESS | 25058 | [#1] ext.html Cycles:180 Average:46009.88 Median:356583.00 stddev:81210.91 (22.8%) stddev-sans-first:81366.84 09:52:42 INFO - PROCESS | 25058 | Values: 498.3 499.2 500.4 511.3 522.9 38285.6 40614.0 40687.6 44286.6 44322.3 39999.8 40471.4 40490.0 40501.4 40513.6 7551.8 7720.0 7739.0 9578.8 9912.9 9681.7 9694.9 9758.7 9772.6 9782.8 9672.5 9732.8 9776.5 9824.2 9832.4 9694.6 9708.1 9733.3 9741.4 9780.2 9610.2 9660.9 9661.9 9676.6 9680.3 34027.5 34725.3 34777.5 34864.0 34971.3 3575.9 3686.5 3710.8 3725.2 3735.9 27303.5 27581.0 29154.0 29912.6 30585.4 3310.9 3479.4 3486.6 3521.5 3521.6 237222.0 240248.0 240790.0 243470.0 243874.0 56827.0 57157.9 57563.7 57895.0 57940.4 60450.0 60640.0 60781.0 60799.7 61047.0 13772.4 14283.1 14301.8 14397.9 14428.6 9584.4 9899.4 10452.8 10733.2 10761.0 19010.4 19187.9 19231.9 19239.1 19280.8 6921.0 7261.0 7371.3 7400.3 7626.7 764.7 776.3 781.8 791.5 798.4 25321.2 26402.7 26792.3 26913.2 27256.2 909.8 949.5 952.1 966.6 969.8 13667.6 14272.7 14341.7 14571.7 14607.5 351999.0 354822.0 356346.0 356820.0 357325.0 50486.2 50605.7 50683.0 50772.5 50922.0 1177.0 1177.7 1230.3 1230.9 1241.0 25350.7 25622.4 25950.3 26171.5 26262.7 41182.6 41908.0 41944.2 42003.6 42064.0 318335.0 318764.0 319077.0 319371.0 320782.0 23603.6 24133.0 24268.8 25274.1 25396.5 37344.8 37550.0 37603.4 38081.8 38274.4 30392.8 31877.8 32515.8 32631.8 33312.0 32689.7 32910.3 33171.0 33620.0 34035.8 29674.9 29968.9 30073.2 30134.5 30279.0 24999.4 26612.7 28190.3 28657.7 29946.9 22423.6 23019.3 23074.2 23105.2 23306.0 09:52:42 INFO - PROCESS | 25058 | 09:52:42 INFO - PROCESS | 25058 | [#2] jquery.html Cycles:180 Average:21586.90 Median:20163.71 stddev:52843.37 (262.1%) stddev-sans-first:52967.59 09:52:42 INFO - PROCESS | 25058 | Values: 366.6 369.4 373.3 377.8 388.8 15613.7 16297.2 16664.1 16761.3 16813.0 15024.5 15106.5 15366.9 15616.8 15690.8 4824.5 5044.2 5119.9 5123.6 5126.7 3851.3 4063.5 4995.5 5097.6 5730.1 5947.6 6050.8 6079.0 6080.0 6080.8 5744.2 5954.1 6002.2 6002.9 6003.6 5214.3 5252.6 5257.3 5257.9 5258.4 19792.2 20323.2 20404.4 20512.0 20567.9 2321.1 2358.1 2358.4 2392.6 2413.7 12433.1 12504.9 12608.4 12653.0 12993.1 1660.0 1688.8 1699.9 1704.8 1728.3 63914.7 64133.4 64563.7 64745.7 65782.3 9482.3 9541.9 9570.9 9876.2 9892.4 12806.7 12852.9 12875.8 12878.6 12944.5 6634.6 6741.9 6825.5 6901.0 6947.0 6287.6 6288.7 6495.8 6502.5 6508.4 5871.0 5934.2 5962.3 6052.7 6200.3 1588.1 1686.7 1736.7 1755.7 1781.2 765.5 803.0 814.1 814.9 815.9 15524.8 15747.7 15915.3 15973.7 16140.8 805.7 806.5 816.2 819.3 834.7 9022.5 9366.9 9393.3 9429.2 9779.9 317841.0 318070.0 319786.0 320815.0 325100.0 61564.4 65417.7 65956.7 66199.7 66217.7 907.8 919.5 939.3 941.0 942.8 13990.7 14391.6 14466.9 14651.7 15161.8 31793.0 32663.2 33156.3 33617.2 33721.8 39938.6 40708.4 41477.4 42612.0 42818.6 7482.7 7538.1 7610.5 7615.0 7712.0 10944.8 11742.4 11798.8 11842.9 12534.9 10372.2 10721.9 10723.1 10832.9 11148.3 10499.6 10812.0 10833.4 10931.0 10991.5 10709.3 10729.2 10920.1 11276.3 11363.0 7598.7 7696.2 7841.5 7874.5 7889.0 10485.3 10634.0 10870.6 11077.8 11136.6 09:52:42 INFO - PROCESS | 25058 | 09:52:42 INFO - PROCESS | 25058 | [#3] mootools.html Cycles:180 Average:4736.19 Median:5093.39 stddev:2575.18 (50.6%) stddev-sans-first:2569.52 09:52:42 INFO - PROCESS | 25058 | Values: 1307.9 1312.5 1320.4 1329.9 1343.0 6582.2 6849.9 7028.5 7363.2 7373.2 6904.2 7176.3 7185.6 7281.0 7324.6 2090.2 2134.0 2134.4 2136.1 2137.0 2117.7 2124.2 2168.7 2172.5 2195.2 2099.1 2113.5 2123.4 2131.0 2139.6 2025.5 2051.7 2051.8 2054.6 2056.4 2097.6 2124.3 2126.8 2138.4 2147.5 7374.2 7409.2 7429.9 7434.5 7450.7 4304.4 4491.8 4499.8 4500.0 4520.6 2727.4 2885.5 2928.6 2936.6 2956.3 1133.5 1174.3 1186.2 1187.1 1213.7 9516.8 9589.2 9590.0 9646.0 9649.8 3669.7 3847.2 3867.9 3921.2 3943.5 7722.2 7785.9 7789.3 7789.4 7823.9 4372.2 4372.5 4374.0 4374.3 4374.4 3900.9 3959.4 4090.0 4090.2 4099.3 3152.7 3161.7 3256.7 3268.2 3285.6 960.4 991.1 998.2 1008.4 1021.2 721.7 733.5 751.9 759.3 764.1 4387.9 4541.7 4571.4 4681.5 4726.4 821.2 833.5 836.7 848.2 862.1 3290.3 3319.6 3327.4 3363.5 3412.4 8565.7 8685.6 8752.0 8760.7 8782.8 8732.8 8734.1 8739.3 8752.1 8773.6 6487.9 6767.2 6804.8 6808.9 6905.2 4697.7 4864.7 5002.8 5016.8 5169.9 7887.6 7988.4 8014.6 8027.6 8041.1 7999.2 8066.6 8080.9 8093.4 8155.2 6394.8 6445.7 6500.6 6522.2 6600.0 6831.9 6973.4 7022.8 7049.2 7061.5 6303.9 6431.2 6433.5 6590.5 6627.6 6284.6 6284.8 6322.1 6333.9 6357.4 5397.2 5438.3 5454.0 5478.8 5490.7 3925.7 3961.5 3995.8 4031.5 4117.5 4076.6 4089.7 4090.6 4136.6 4162.3 09:52:42 INFO - PROCESS | 25058 | 09:52:42 INFO - PROCESS | 25058 | [#4] prototype.html Cycles:180 Average:4351.42 Median:4837.17 stddev:1218.73 (25.2%) stddev-sans-first:1184.47 09:52:42 INFO - PROCESS | 25058 | Values: 345.2 353.6 360.2 361.8 364.2 4871.3 4993.5 5042.2 5110.3 5119.8 4864.9 4908.6 4979.2 4984.8 5019.5 4016.3 4037.3 4037.7 4040.4 4044.9 3924.2 4040.1 4048.5 4064.5 4068.2 3952.3 3983.5 3998.6 4003.1 4008.2 3942.3 3987.5 3991.8 3995.9 3998.0 3596.9 3601.5 3626.2 3631.8 3633.2 4678.8 4876.8 4890.5 4916.6 4917.0 4513.9 4539.3 4572.8 4592.5 4599.7 5301.5 5314.6 5325.4 5331.9 5333.3 3840.5 3892.1 3915.5 3924.6 3939.8 5454.2 5495.8 5497.0 5526.6 5527.3 4043.5 4051.4 4056.3 4072.5 4084.0 4041.4 4115.7 4144.5 4146.6 4160.3 4546.6 4549.7 4551.5 4555.4 4595.2 5266.5 5282.7 5298.7 5304.4 5315.0 1354.6 1364.7 1374.2 1375.0 1387.3 964.7 973.9 994.7 1006.6 1014.9 4411.7 4430.0 4441.5 4449.4 4449.8 5131.4 5180.5 5199.0 5208.8 5231.3 4303.2 4432.2 4467.6 4477.2 4479.2 2750.2 2766.8 2773.5 2774.9 2783.7 4686.0 4780.7 4785.8 4790.3 4821.6 5132.4 5291.4 5307.3 5321.5 5350.3 4798.5 4829.7 4836.6 4837.7 4839.4 5013.7 5172.8 5229.4 5235.8 5251.4 5006.9 5103.3 5123.0 5132.3 5270.8 4969.0 4973.3 4980.0 4985.5 4986.7 3686.2 3707.0 3714.5 3720.2 3722.3 5254.5 5359.2 5450.6 5464.0 5470.3 5225.8 5231.9 5235.6 5237.9 5262.9 5213.5 5240.3 5245.9 5273.7 5292.0 4964.2 5138.2 5140.9 5146.0 5164.9 5210.9 5221.2 5222.5 5245.5 5281.2 5164.7 5164.9 5174.3 5177.4 5184.0 09:52:42 INFO - PROCESS | 25058 | 09:52:42 INFO - PROCESS | 25058 | [#5] yui.html Cycles:180 Average:14585.30 Median:559.44 stddev:18423.02 (3293.1%) stddev-sans-first:18445.11 09:52:42 INFO - PROCESS | 25058 | Values: 679.6 680.0 739.2 746.9 768.1 13191.5 13524.1 13773.3 13847.2 13867.7 13967.6 14680.5 14696.1 15210.1 15225.9 818.9 840.2 843.1 843.2 843.2 784.6 812.7 816.1 817.6 818.7 849.5 856.1 856.2 859.7 866.8 850.3 851.3 858.4 858.6 863.9 634.6 634.7 635.1 635.2 635.2 13640.5 13972.2 14065.4 14087.6 14381.6 12397.6 12644.4 12933.2 13308.7 13413.1 10539.5 12075.7 12189.2 12216.5 12637.6 3304.9 3306.7 3333.3 3372.3 3386.8 60943.0 61682.7 61687.4 61991.0 63033.7 10147.5 10292.6 10318.2 10361.5 10396.1 18574.8 18647.2 18686.5 18894.3 18898.4 6161.5 6208.3 6242.5 6267.3 6438.6 3350.8 3406.1 3417.1 3458.6 3500.3 2933.5 2982.9 3016.8 3023.3 3055.6 485.4 488.7 493.5 493.8 501.8 534.6 553.8 558.6 559.3 559.6 7927.8 8204.8 8264.6 8340.9 8374.3 609.6 624.8 629.0 631.4 639.4 3601.6 3793.8 3859.6 3895.0 3973.6 63840.7 65019.3 65514.5 65548.3 67028.7 55369.0 56722.7 57061.4 58206.7 59213.1 50192.0 50319.2 50423.2 50544.5 50612.5 7314.7 7504.2 7524.7 7529.1 7628.1 35868.6 37855.0 37905.4 38056.4 38667.6 42579.6 48124.5 48148.3 48198.8 48561.8 9615.6 9715.6 9729.0 9882.0 10068.2 10689.7 10746.9 10860.0 10940.2 10984.6 10391.8 10578.3 10581.6 10640.7 10667.1 9261.5 9344.2 9398.1 9406.6 9444.4 8994.1 9062.3 9833.3 9852.0 9882.2 8292.8 8404.2 8534.8 8766.1 9044.5 7916.0 8141.1 8302.3 8331.2 8562.7 09:52:42 INFO - PROCESS | 25058 | -------- Summary: end -------- 09:52:42 INFO - PROCESS | 25058 | 09:52:43 INFO - TEST-INFO | 25058: exit 0 09:52:43 INFO - TEST-OK | dromaeo_css | took 1119503ms 09:52:43 INFO - TEST-START | kraken 09:52:43 INFO - operating with platform_type : linux_ 09:52:43 INFO - Initialising browser for kraken test... 09:52:43 INFO - TEST-INFO | started process 25408 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZT6usc/profile http://localhost:48370/getInfo.html) 09:52:44 INFO - PROCESS | 25408 | 1483120364247 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 09:52:48 INFO - PROCESS | 25408 | __metrics Screen width/height:1600/1200 09:52:48 INFO - PROCESS | 25408 | colorDepth:24 09:52:48 INFO - PROCESS | 25408 | Browser inner width/height: 1024/697 09:52:48 INFO - PROCESS | 25408 | __metrics 09:52:48 ERROR - PROCESS | 25408 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 09:52:49 INFO - TEST-INFO | 25408: exit 0 09:52:49 INFO - Browser initialized. 09:52:49 INFO - Running cycle 1/1 for kraken test... 09:52:49 INFO - TEST-INFO | started process 25529 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZT6usc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:53:01 INFO - PROCESS | 25529 | RSS: Main: 158466048 09:53:01 INFO - PROCESS | 25529 | 09:53:04 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:48370/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48370/tests/kraken/driver.html?testName=audio-beat-detection) 09:53:05 INFO - PROCESS | 25529 | RSS: Main: 163921920 09:53:05 INFO - PROCESS | 25529 | 09:53:08 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:48370/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48370/tests/kraken/driver.html?testName=audio-dft) 09:53:08 INFO - PROCESS | 25529 | RSS: Main: 163090432 09:53:08 INFO - PROCESS | 25529 | 09:53:11 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:48370/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48370/tests/kraken/driver.html?testName=audio-fft) 09:53:12 INFO - PROCESS | 25529 | RSS: Main: 159621120 09:53:12 INFO - PROCESS | 25529 | 09:53:14 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:48370/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48370/tests/kraken/driver.html?testName=audio-oscillator) 09:53:15 INFO - PROCESS | 25529 | RSS: Main: 159424512 09:53:15 INFO - PROCESS | 25529 | 09:53:17 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:48370/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48370/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:53:18 INFO - PROCESS | 25529 | RSS: Main: 160845824 09:53:18 INFO - PROCESS | 25529 | 09:53:21 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:48370/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48370/tests/kraken/driver.html?testName=imaging-darkroom) 09:53:21 INFO - PROCESS | 25529 | RSS: Main: 164651008 09:53:21 INFO - PROCESS | 25529 | 09:53:25 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:48370/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48370/tests/kraken/driver.html?testName=imaging-desaturate) 09:53:26 INFO - PROCESS | 25529 | RSS: Main: 161488896 09:53:26 INFO - PROCESS | 25529 | 09:53:29 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:48370/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48370/tests/kraken/driver.html?testName=json-parse-financial) 09:53:29 INFO - PROCESS | 25529 | RSS: Main: 162992128 09:53:29 INFO - PROCESS | 25529 | 09:53:31 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:48370/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48370/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:53:32 INFO - PROCESS | 25529 | RSS: Main: 162938880 09:53:32 INFO - PROCESS | 25529 | 09:53:34 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:48370/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48370/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:53:34 INFO - PROCESS | 25529 | RSS: Main: 162828288 09:53:34 INFO - PROCESS | 25529 | 09:53:36 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:48370/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48370/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:53:37 INFO - PROCESS | 25529 | RSS: Main: 163315712 09:53:37 INFO - PROCESS | 25529 | 09:53:40 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:48370/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48370/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:53:41 INFO - PROCESS | 25529 | RSS: Main: 164495360 09:53:41 INFO - PROCESS | 25529 | 09:53:44 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:48370/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48370/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:53:44 INFO - PROCESS | 25529 | RSS: Main: 163971072 09:53:44 INFO - PROCESS | 25529 | 09:53:46 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:48370/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48370/tests/kraken/driver.html?testName=ai-astar) 09:53:46 INFO - PROCESS | 25529 | RSS: Main: 165658624 09:53:46 INFO - PROCESS | 25529 | 09:53:46 INFO - PROCESS | 25529 | __start_tp_report 09:53:46 INFO - PROCESS | 25529 | _x_x_mozilla_page_load 09:53:46 INFO - PROCESS | 25529 | _x_x_mozilla_page_load_details 09:53:46 INFO - PROCESS | 25529 | |i|pagename|runs| 09:53:46 INFO - PROCESS | 25529 | |0;ai-astar;109;119;118;117;115;115;117;117;116;116 09:53:46 INFO - PROCESS | 25529 | |1;audio-beat-detection;161;129;130;197;131;145;128;129;153;128 09:53:46 INFO - PROCESS | 25529 | |2;audio-dft;168;166;170;163;163;164;166;163;161;169 09:53:46 INFO - PROCESS | 25529 | |3;audio-fft;77;76;76;102;78;82;77;76;94;77 09:53:46 INFO - PROCESS | 25529 | |4;audio-oscillator;95;85;110;86;87;90;104;86;85;109 09:53:46 INFO - PROCESS | 25529 | |5;imaging-gaussian-blur;118;118;119;118;123;118;118;119;118;119 09:53:46 INFO - PROCESS | 25529 | |6;imaging-darkroom;151;151;151;151;151;151;151;151;153;150 09:53:46 INFO - PROCESS | 25529 | |7;imaging-desaturate;96;96;96;97;96;97;97;96;96;97 09:53:46 INFO - PROCESS | 25529 | |8;json-parse-financial;72;73;71;70;72;74;74;72;72;72 09:53:46 INFO - PROCESS | 25529 | |9;json-stringify-tinderbox;68;67;68;68;69;69;69;68;69;69 09:53:46 INFO - PROCESS | 25529 | |10;stanford-crypto-aes;86;86;87;84;86;86;90;86;86;84 09:53:46 INFO - PROCESS | 25529 | |11;stanford-crypto-ccm;154;138;136;146;139;136;138;138;189;136 09:53:46 INFO - PROCESS | 25529 | |12;stanford-crypto-pbkdf2;186;177;182;176;179;182;181;185;178;176 09:53:46 INFO - PROCESS | 25529 | |13;stanford-crypto-sha256-iterative;72;71;71;69;70;69;69;70;69;68 09:53:46 INFO - PROCESS | 25529 | __end_tp_report 09:53:46 INFO - PROCESS | 25529 | __start_cc_report 09:53:46 INFO - PROCESS | 25529 | _x_x_mozilla_cycle_collect,801 09:53:46 INFO - PROCESS | 25529 | __end_cc_report 09:53:46 INFO - PROCESS | 25529 | __startTimestamp1483120426796__endTimestamp 09:53:46 INFO - PROCESS | 25529 | 09:53:46 INFO - PROCESS | 25529 | ------- Summary: start ------- 09:53:46 INFO - PROCESS | 25529 | Number of tests: 14 09:53:46 INFO - PROCESS | 25529 | 09:53:46 INFO - PROCESS | 25529 | [#0] ai-astar Cycles:10 Average:115.90 Median:117.00 stddev:2.73 (2.3%) stddev-sans-first:1.32 09:53:46 INFO - PROCESS | 25529 | Values: 109.0 119.0 118.0 117.0 115.0 115.0 117.0 117.0 116.0 116.0 09:53:46 INFO - PROCESS | 25529 | 09:53:46 INFO - PROCESS | 25529 | [#1] audio-beat-detection Cycles:10 Average:143.10 Median:138.00 stddev:22.36 (16.2%) stddev-sans-first:22.76 09:53:46 INFO - PROCESS | 25529 | Values: 161.0 129.0 130.0 197.0 131.0 145.0 128.0 129.0 153.0 128.0 09:53:46 INFO - PROCESS | 25529 | 09:53:46 INFO - PROCESS | 25529 | [#2] audio-dft Cycles:10 Average:165.30 Median:166.00 stddev:2.98 (1.8%) stddev-sans-first:3.00 09:53:46 INFO - PROCESS | 25529 | Values: 168.0 166.0 170.0 163.0 163.0 164.0 166.0 163.0 161.0 169.0 09:53:46 INFO - PROCESS | 25529 | 09:53:46 INFO - PROCESS | 25529 | [#3] audio-fft Cycles:10 Average:81.50 Median:77.00 stddev:9.07 (11.8%) stddev-sans-first:9.47 09:53:46 INFO - PROCESS | 25529 | Values: 77.0 76.0 76.0 102.0 78.0 82.0 77.0 76.0 94.0 77.0 09:53:46 INFO - PROCESS | 25529 | 09:53:46 INFO - PROCESS | 25529 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:86.00 stddev:10.20 (11.9%) stddev-sans-first:10.81 09:53:46 INFO - PROCESS | 25529 | Values: 95.0 85.0 110.0 86.0 87.0 90.0 104.0 86.0 85.0 109.0 09:53:46 INFO - PROCESS | 25529 | 09:53:46 INFO - PROCESS | 25529 | [#5] imaging-gaussian-blur Cycles:10 Average:118.80 Median:118.50 stddev:1.55 (1.3%) stddev-sans-first:1.62 09:53:46 INFO - PROCESS | 25529 | Values: 118.0 118.0 119.0 118.0 123.0 118.0 118.0 119.0 118.0 119.0 09:53:46 INFO - PROCESS | 25529 | 09:53:46 INFO - PROCESS | 25529 | [#6] imaging-darkroom Cycles:10 Average:151.10 Median:151.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 09:53:46 INFO - PROCESS | 25529 | Values: 151.0 151.0 151.0 151.0 151.0 151.0 151.0 151.0 153.0 150.0 09:53:46 INFO - PROCESS | 25529 | 09:53:46 INFO - PROCESS | 25529 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 09:53:46 INFO - PROCESS | 25529 | Values: 96.0 96.0 96.0 97.0 96.0 97.0 97.0 96.0 96.0 97.0 09:53:46 INFO - PROCESS | 25529 | 09:53:46 INFO - PROCESS | 25529 | [#8] json-parse-financial Cycles:10 Average:72.20 Median:72.00 stddev:1.23 (1.7%) stddev-sans-first:1.30 09:53:46 INFO - PROCESS | 25529 | Values: 72.0 73.0 71.0 70.0 72.0 74.0 74.0 72.0 72.0 72.0 09:53:46 INFO - PROCESS | 25529 | 09:53:46 INFO - PROCESS | 25529 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:69.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 09:53:46 INFO - PROCESS | 25529 | Values: 68.0 67.0 68.0 68.0 69.0 69.0 69.0 68.0 69.0 69.0 09:53:46 INFO - PROCESS | 25529 | 09:53:46 INFO - PROCESS | 25529 | [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:86.00 stddev:1.66 (1.9%) stddev-sans-first:1.76 09:53:46 INFO - PROCESS | 25529 | Values: 86.0 86.0 87.0 84.0 86.0 86.0 90.0 86.0 86.0 84.0 09:53:46 INFO - PROCESS | 25529 | 09:53:46 INFO - PROCESS | 25529 | [#11] stanford-crypto-ccm Cycles:10 Average:145.00 Median:138.50 stddev:16.48 (11.9%) stddev-sans-first:17.15 09:53:46 INFO - PROCESS | 25529 | Values: 154.0 138.0 136.0 146.0 139.0 136.0 138.0 138.0 189.0 136.0 09:53:46 INFO - PROCESS | 25529 | 09:53:46 INFO - PROCESS | 25529 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.20 Median:181.50 stddev:3.58 (2.0%) stddev-sans-first:3.13 09:53:46 INFO - PROCESS | 25529 | Values: 186.0 177.0 182.0 176.0 179.0 182.0 181.0 185.0 178.0 176.0 09:53:46 INFO - PROCESS | 25529 | 09:53:46 INFO - PROCESS | 25529 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:70.00 stddev:1.23 (1.8%) stddev-sans-first:1.01 09:53:46 INFO - PROCESS | 25529 | Values: 72.0 71.0 71.0 69.0 70.0 69.0 69.0 70.0 69.0 68.0 09:53:46 INFO - PROCESS | 25529 | -------- Summary: end -------- 09:53:46 INFO - PROCESS | 25529 | 09:53:47 INFO - TEST-INFO | 25529: exit 0 09:53:47 INFO - TEST-OK | kraken | took 64198ms 09:53:47 INFO - SUITE-END | took 1183s 09:53:47 INFO - Completed test suite (00:19:43) 09:53:47 INFO - javascript benchmark 09:53:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6334.287196522715, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2605.92182234533, 2623.5277650023, 2631.5789473684213, 2655.588383800511, 2668.0528412413605, 4721.315409265663, 4923.113839508887, 4944.3819522559725, 4976.7013979161275, 4985.7008579485255, 4728.881777955544, 4846.68496547725, 4881.1213981483315, 4887.535684110004, 4937.037277730835, 4407.757573333465, 4471.0, 4481.462224533045, 4544.84093056743, 4601.447826260854, 4374.34384842273, 4491.820327186909, 4522.253828118356, 4532.546745325474, 4562.0, 4368.781560921973, 4482.574155455227, 4510.007798284372, 4562.201614717428, 4575.954240457603, 4382.627476664456, 4410.1179764047, 4436.046250056221, 4457.3091170868565, 4516.593506584423, 4435.711678740904, 4441.133978874121, 4442.822287108512, 4457.66567507435, 4479.820807167709, 4846.515348465161, 4875.68308059975, 4888.169011268057, 4894.1679914414635, 4896.853094407174, 5167.819126330561, 5206.661566998133, 5208.713520756357, 5209.244659524347, 5248.291480650101, 5297.205419187114, 5297.920531192035, 5299.284596579451, 5300.602454815904, 5335.51980321769, 4914.754262286871, 4923.950760492385, 4936.111499929975, 4939.8271060513425, 4977.527134922213, 5231.973840130851, 5271.1302635064785, 5273.34083239595, 5332.626716129834, 5410.107332290128, 5116.283720279164, 5152.92270615941, 5163.0, 5200.037992971313, 5247.947520524822, 5093.923591146136, 5162.303089082926, 5253.080710875582, 5287.471252874759, 5289.70906600133, 5170.689758614495, 5193.350831146106, 5214.530692237717, 5225.346831646044, 5235.973820130875, 5098.97450512745, 5172.301739265151, 5243.737813109406, 5247.291615631917, 5249.895002100013, 4422.601965823027, 4436.134953684065, 4442.044960333544, 4471.418715566955, 4518.977405112953, 3837.9616203837604, 3838.980805095957, 3853.537575490959, 3871.922561548753, 3889.649931506178, 4395.274779661384, 4550.13547425988, 4560.338750881104, 4560.863174104782, 4613.30800379939, 5100.0, 5269.051570717308, 5297.311349524614, 5312.5218730314455, 5372.086745253238, 4553.385292985405, 4563.224251877189, 4569.840055598038, 4580.610648094949, 4602.125596136723, 4762.095201911626, 4765.547273009059, 4773.809047638056, 4783.091212669582, 4824.445188803263, 4760.143174228603, 4804.399450068741, 4811.109944660249, 4846.36997190363, 4853.635977301646, 5254.448282930237, 5291.894162116813, 5297.920531192112, 5303.0, 5357.973210133924, 4830.203016502378, 4862.440819305825, 4864.6594738369395, 4871.634627402888, 4892.290617860462, 5022.472640372708, 5070.366204224472, 5105.2086926526445, 5141.254518094781, 5196.610254230872, 5265.420803711512, 5274.446183150714, 5279.155335146512, 5312.707801070978, 5326.280952071421, 5006.224035274538, 5052.823151189691, 5105.2086926526445, 5114.232865070269, 5156.767945442389, 4997.775100120431, 5021.046001259749, 5046.116929537389, 5068.417132029864, 5129.333186685707, 4938.925916111333, 4941.258811178352, 4980.601551875914, 4987.80048798044, 5024.874378140576, 5104.617153713561, 5117.692938423706, 5118.181091025418, 5118.436971933159, 5134.743262836769, 4853.80584776605, 4877.6341774367775, 4973.751312434436, 5004.524570165829, 5026.170681837602], "value": 4791.731512251556, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.29740066506224, 499.2227669496592, 500.4370083655887, 511.3097318619862, 522.9032628963643, 38285.61714382855, 40614.0, 40687.593124068786, 44286.55713442864, 44322.33516497247, 39999.80000099995, 40471.392929106085, 40490.0, 40501.39247911284, 40513.59486405135, 7551.773446796591, 7720.0, 7738.961305193466, 9578.808423831519, 9912.851307230398, 9681.661141860037, 9694.854577181313, 9758.707238782812, 9772.609095636202, 9782.804343913118, 9672.516374181298, 9732.805343893118, 9776.462294573796, 9824.21406287499, 9832.410055396664, 9694.612215511372, 9708.077732615426, 9733.270004749602, 9741.415515069119, 9780.168685661727, 9610.231181505464, 9660.90339096607, 9661.855072173923, 9676.56455459506, 9680.27397945157, 34027.48958765621, 34725.30549388998, 34777.478337824956, 34864.0, 34971.30057398838, 3575.856965721368, 3686.5391826021746, 3710.8330125144435, 3725.217704282104, 3735.9066023349633, 27303.45393092127, 27581.0, 29154.0, 29912.55131173034, 30585.38829223403, 3310.8841190558214, 3479.425894727367, 3486.564179477565, 3521.5069890215136, 3521.6302288259876, 237222.0, 240248.0, 240790.0, 243469.99999999822, 243874.0, 56827.0, 57157.85421842784, 57563.71218143882, 57895.0, 57940.42059579435, 60450.0, 60640.0, 60781.0, 60799.69600152059, 61047.0, 13772.449102036007, 14283.143011419348, 14301.78547321791, 14397.92801036009, 14428.567142985727, 9584.424934503952, 9899.406035637885, 10452.843207351896, 10733.195010374253, 10761.031507164393, 19010.42968710941, 19187.90406047961, 19231.903840480707, 19239.13423895928, 19280.807191927903, 6920.9653951729915, 7261.0, 7371.299726525972, 7400.296971787671, 7626.656800443993, 764.6903004283274, 776.3323541754087, 781.7928249014016, 791.5053091817614, 798.3652995868183, 25321.240362789147, 26402.73597264041, 26792.33019174536, 26913.192604221906, 27256.182314530597, 909.7862002429424, 949.4920217683645, 952.1478276942237, 966.6375109334, 969.8157350103457, 13667.589972300648, 14272.714545709028, 14341.713165736835, 14571.708565828623, 14607.488737894122, 351999.0, 354822.0, 356346.0, 356820.0, 357325.0, 50486.24270635944, 50605.74697126491, 50683.0, 50772.492275077515, 50922.0, 1176.9941150294364, 1177.670252329349, 1230.3356187658744, 1230.9015278777676, 1241.0, 25350.74649253484, 25622.359441014123, 25950.313229640014, 26171.476570468865, 26262.73737262603, 41182.588174119075, 41908.0, 41944.1611167781, 42003.57996420119, 42064.0, 318335.0, 318764.0, 319077.0, 319371.0, 320782.0, 23603.64594531049, 24133.034678612657, 24268.75731242665, 25274.11540596144, 25396.492070158863, 37344.81327593345, 37550.0, 37603.43594846025, 38081.80959095187, 38274.425883612326, 30392.848035759678, 31877.840610796797, 32515.837420812746, 32631.836840815646, 33312.0, 32689.673103268662, 32910.34179316448, 33171.0, 33620.0, 34035.82982085074, 29674.851625741732, 29968.85015574908, 30073.248168795955, 30134.54798177985, 30279.0, 24999.375015624755, 26612.7338726618, 28190.295242619097, 28657.713422866338, 29946.850265748533, 22423.551528969656, 23019.309420717404, 23074.192403265806, 23105.191318304453, 23306.0], "value": 17516.029793178317, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.6040676069843, 369.4034134872182, 373.25349301397205, 377.8262594208645, 388.8172558897319, 15613.68772624547, 16297.185140742975, 16664.083475408865, 16761.329546818142, 16813.0, 15024.549263522085, 15106.471273505429, 15366.923165384185, 15616.84383156168, 15690.76463853043, 4824.541668541483, 5044.243363495469, 5119.872003199913, 5123.590112790967, 5126.666766660155, 3851.345271303871, 4063.5326937402133, 4995.525425084611, 5097.617678674096, 5730.1118326659225, 5947.583669143161, 6050.818475445743, 6079.027355623101, 6080.0, 6080.817575472743, 5744.195812586241, 5954.07711804671, 6002.249718785152, 6002.939970600303, 6003.579749417542, 5214.32213812202, 5252.606054545924, 5257.316548848664, 5257.86855328616, 5258.395284542288, 19792.208311667517, 20323.18707251708, 20404.387868363974, 20512.0, 20567.89716051425, 2321.0831721469926, 2358.0803486640225, 2358.3868194269444, 2392.6411038344213, 2413.734489206186, 12433.129680922339, 12504.937475312656, 12608.432620532007, 12653.0, 12993.090483666147, 1659.9751003734953, 1688.8057873344599, 1699.8629783346912, 1704.8295170482977, 1728.3432295727669, 63914.68042659803, 64133.358666412736, 64563.67718161379, 64745.67627161881, 65782.3421765781, 9482.336236463381, 9541.856872146924, 9570.856437153448, 9876.160526355197, 9892.406455612543, 12806.67983300425, 12852.871471285354, 12875.806862897063, 12878.613641590768, 12944.546940857024, 6634.568753031038, 6741.932580674229, 6825.453963682893, 6901.0, 6946.965265173743, 6287.559870808999, 6288.748450061947, 6495.772647957298, 6502.512311576651, 6508.381703738138, 5871.005787048636, 5934.1692163097205, 5962.34414214436, 6052.697365131726, 6200.3179650238435, 1588.1265304082708, 1686.6710991356813, 1736.6873962686839, 1755.7454169145658, 1781.2251670523365, 765.4679997401863, 802.9598520073973, 814.1004190369674, 814.8714031067015, 815.8939337886037, 15524.84475155234, 15747.685046299239, 15915.283812228477, 15973.680526389408, 16140.75788863168, 805.6997272786228, 806.467731297341, 816.1797393619374, 819.2954059508818, 834.7370578267826, 9022.548872556346, 9366.859497107549, 9393.295502837178, 9429.245660347156, 9779.902200978042, 317841.0, 318070.0, 319786.0, 320815.0, 325100.0, 61564.38435615676, 65417.672911635134, 65956.6702166486, 66199.66900165468, 66217.66891165513, 907.804821963281, 919.5080631862055, 939.3143005605946, 941.0, 942.783159873233, 13990.720185596434, 14391.568252952427, 14466.927665362027, 14651.70696586041, 15161.772573411186, 31792.979666931606, 32663.18342041468, 33156.33687326192, 33617.15957101092, 33721.83139084289, 39938.60061399465, 40708.38937415882, 41477.37783933183, 42612.0, 42818.5718142827, 7482.70069197226, 7538.133114691881, 7610.467267291458, 7614.961925190339, 7712.0, 10944.78110437771, 11742.354170520703, 11798.764024719629, 11842.940785296018, 12534.87465125337, 10372.17022638172, 10721.892781072089, 10723.088537474401, 10832.945835271088, 11148.331100134017, 10499.63251286232, 10812.0, 10833.404162771123, 10931.0, 10991.450427478754, 10709.303895246783, 10729.195310351599, 10920.126389888632, 11276.32342059479, 11363.0, 7598.696052158073, 7696.15342312334, 7841.490303130278, 7874.488158269694, 7889.0138732658415, 10485.318454300446, 10634.0, 10870.565177392815, 11077.778444431227, 11136.554537818396], "value": 8131.3111051106525, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1307.8757518035789, 1312.540610786225, 1320.4124164746686, 1329.900257480689, 1342.9798553021706, 6582.17722784652, 6849.931500684992, 7028.472864535155, 7363.190049094595, 7373.225811289811, 6904.171499420064, 7176.282371762821, 7185.604791736452, 7281.017062696535, 7324.63376831159, 2090.1639344262303, 2134.018351558285, 2134.3596920923737, 2136.1455417832835, 2137.027652418146, 2117.692934524493, 2124.2352753008904, 2168.6530155175174, 2172.4677454023786, 2195.1987524553583, 2099.149844313056, 2113.5455876986443, 2123.437289118385, 2131.0, 2139.5506943541873, 2025.4531276555394, 2051.7025031370445, 2051.8050785175383, 2054.578811343686, 2056.393363957636, 2097.6014557234075, 2124.2671278408925, 2126.797954194349, 2138.4119367173994, 2147.516808718026, 7374.151972523121, 7409.222031686649, 7429.925700742978, 7434.516756410816, 7450.664720087609, 4304.418903448026, 4491.797869095899, 4499.775011249424, 4500.032493014018, 4520.638348932111, 2727.386338073937, 2885.451764164823, 2928.6046383738135, 2936.6329208848892, 2956.320046389321, 1133.5012594458412, 1174.3364998775664, 1186.2348785033605, 1187.091874715843, 1213.6541085790595, 9516.762080947963, 9589.18491928187, 9589.952050239774, 9646.035396460298, 9649.807003859883, 3669.6697297243377, 3847.1920896611464, 3867.941980870289, 3921.215756848642, 3943.4873466449176, 7722.189170137055, 7785.922140778633, 7789.337906277917, 7789.3768498519985, 7823.882641760378, 4372.23485889968, 4372.519022907478, 4374.03771170342, 4374.278244089754, 4374.431323927933, 3900.8829735107993, 3959.425883246913, 4089.959100408958, 4090.1819636072905, 4099.3031184698675, 3152.684731526829, 3161.65221825599, 3256.690614391629, 3268.248302890301, 3285.572875526214, 960.4429430930044, 991.1366630734174, 998.1814911772423, 1008.4050410257983, 1021.1524434719314, 721.7041013184675, 733.5268751655152, 751.8984937033431, 759.3052357093309, 764.0831002796666, 4387.937669295426, 4541.682082254277, 4571.405717256802, 4681.485036645966, 4726.409198850144, 821.219841150909, 833.4624167411986, 836.6527890925198, 848.1899785704637, 862.1206369503071, 3290.341931613639, 3319.6223567219818, 3327.434336162858, 3363.4618461046603, 3412.3857705613227, 8565.743027709179, 8685.565721713889, 8751.956240218886, 8760.693375731818, 8782.780430489289, 8732.78168045804, 8734.126587341216, 8739.257163141205, 8752.081031491602, 8773.649054037767, 6487.902681459782, 6767.221769496472, 6804.795856124324, 6808.931910680929, 6905.205901321412, 4697.718136911796, 4864.708117512961, 5002.824901128443, 5016.824411145592, 5169.948300516947, 7887.605619718876, 7988.3609311253795, 8014.639341229776, 8027.5584842834205, 8041.11547729761, 7999.200079991954, 8066.556339401389, 8080.9191908080165, 8093.3929955252415, 8155.184481551797, 6394.808155755335, 6445.70994305248, 6500.609963402022, 6522.21733391996, 6600.042993765973, 6831.931680683129, 6973.372396484341, 7022.824429389306, 7049.224585295511, 7061.505694601534, 6303.936960630335, 6431.163948686641, 6433.5496515243485, 6590.505712071518, 6627.602343859384, 6284.591501552384, 6284.811455656338, 6322.083297921868, 6333.904991425225, 6357.427831495188, 5397.217403476552, 5438.320209973754, 5454.018276710231, 5478.780848766005, 5490.6980116094, 3925.666318362971, 3961.485006949078, 3995.760254384746, 4031.536373317106, 4117.505899292104, 4076.571959944282, 4089.7341672792363, 4090.5909409058854, 4136.648384887319, 4162.334026555736], "value": 3864.8412921511176, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.1509287153602, 353.56864625157306, 360.1716053077921, 361.76123758319517, 364.1569765991729, 4871.318015477836, 4993.525615066563, 5042.168042273025, 5110.284560161571, 5119.820806271777, 4864.927026094611, 4908.631852611051, 4979.161877729695, 4984.750762461871, 5019.4941517544685, 4016.2569924563977, 4037.2934736421157, 4037.7173597848164, 4040.373742069969, 4044.939325910114, 3924.174594292105, 4040.131371755072, 4048.4657183954014, 4064.471618689581, 4068.169323804285, 3952.308346039446, 3983.4704962348974, 3998.580149084348, 4003.1393250451147, 4008.2384346974127, 3942.2706799242233, 3987.4616926714957, 3991.760494370318, 3995.8801235962824, 3997.960020399788, 3596.892093237207, 3601.4957905893198, 3626.1659818241687, 3631.8184090795353, 3633.182533929871, 4678.8128474861305, 4876.829310974099, 4890.5109489051165, 4916.58209052231, 4917.0, 4513.864584062483, 4539.296409056601, 4572.794224259883, 4592.540745925414, 4599.655025873073, 5301.496357845998, 5314.627976041678, 5325.4142044375085, 5331.853651464954, 5333.306670132897, 3840.5007349044445, 3892.1198313248274, 3915.490986171808, 3924.646781789653, 3939.783311917872, 5454.154606036071, 5495.835124946258, 5496.972515137439, 5526.58550608706, 5527.281453411071, 4043.4945631796027, 4051.4327994080854, 4056.3307054336296, 4072.490938632671, 4084.0, 4041.413994970713, 4115.73247738896, 4144.461220041375, 4146.564610715892, 4160.313548264502, 4546.568076032771, 4549.678734523816, 4551.4765801932535, 4555.40779698637, 4595.172868883633, 5266.47335266478, 5282.656627319212, 5298.668585822226, 5304.416514183436, 5315.0, 1354.5733094075335, 1364.6793003644136, 1374.17549470317, 1375.0, 1387.341013018808, 964.6880242869692, 973.933542770665, 994.6618149829093, 1006.642641862143, 1014.9035841595037, 4411.6544453941215, 4430.0, 4441.533638967861, 4449.399331090326, 4449.822007119679, 5131.384233891882, 5180.4560521145495, 5199.0, 5208.7916483341, 5231.319928409359, 4303.160883627663, 4432.180046691373, 4467.5822995408535, 4477.171723231213, 4479.238529450001, 2750.188694335136, 2766.847823369734, 2773.459175460806, 2774.8900439824233, 2783.6520434945633, 4686.0, 4780.713157210509, 4785.827472269316, 4790.329353890458, 4821.566059054632, 5132.358455193101, 5291.365036195682, 5307.310049693592, 5321.494458026481, 5350.3312085989255, 4798.488476130077, 4829.6860704054125, 4836.637252206169, 4837.710356957303, 4839.443464001614, 5013.724245166551, 5172.818951336685, 5229.398619158694, 5235.764390602356, 5251.448597897166, 5006.874828129326, 5103.260027296095, 5122.974385128051, 5132.255822905732, 5270.841874743764, 4969.0, 4973.254011898244, 4979.975100124476, 4985.501449855131, 4986.7257300848805, 3686.189038411545, 3706.9814650926573, 3714.4799728037556, 3720.2373513429143, 3722.2555488901785, 5254.527092561688, 5359.222912677717, 5450.645708028966, 5464.022107951256, 5470.316210473691, 5225.843224703265, 5231.869203269796, 5235.581153507786, 5237.904838064805, 5262.947370526245, 5213.530782229466, 5240.270710665395, 5245.921311180412, 5273.6572122811895, 5292.010014341885, 4964.230544265644, 5138.203578445347, 5140.922886156636, 5146.0, 5164.922526162036, 5210.8957820844125, 5221.242919776687, 5222.542443146845, 5245.475452454724, 5281.227355638321, 5164.715940623151, 5164.948350516447, 5174.327337446108, 5177.430482646982, 5183.974080129575], "value": 3982.094426943612, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.6024325769424, 679.9524033317666, 739.1979702023305, 746.8730315846307, 768.06679883941, 13191.538296159637, 13524.053316267866, 13773.311334433289, 13847.238401887906, 13867.653308667288, 13967.580972570839, 14680.486182983597, 14696.118232906034, 15210.087394756294, 15225.923870380633, 818.9426740128179, 840.2060053249681, 843.1189407069627, 843.161054750521, 843.1821133500525, 784.5606460382176, 812.7236739508566, 816.0533780814256, 817.5707753429438, 818.7011740714656, 849.5157760076759, 856.1053698884649, 856.2336708645789, 859.7291853066296, 866.7919699272161, 850.2645211891731, 851.2764150472111, 858.3991206155654, 858.5621333120091, 863.9179277968583, 634.6160572853404, 634.7492740367575, 635.0664523150961, 635.1742734445203, 635.2123367024856, 13640.454381824715, 13972.161670299814, 14065.437382504688, 14087.647808804759, 14381.640458988504, 12397.566085187065, 12644.367781610883, 12933.224006559636, 13308.733825323536, 13413.128146670435, 10539.473026348653, 12075.698107547381, 12189.214325858036, 12216.450259738333, 12637.557685480964, 3304.867805287786, 3306.6528014558608, 3333.3166700826273, 3372.3255348930065, 3386.8137252451106, 60943.0, 61682.69158654223, 61687.383126169065, 61991.0, 63033.68483157555, 10147.492625368703, 10292.588296468208, 10318.1745460363, 10361.533730982124, 10396.064354208158, 18574.81425185758, 18647.160877760532, 18686.53283667919, 18894.3386981455, 18898.433047008613, 6161.537884658668, 6208.255009398901, 6242.531810114168, 6267.341929097471, 6438.581492202992, 3350.782199157047, 3406.0803583032443, 3417.0773891049275, 3458.5849698036395, 3500.334936362083, 2933.5453004784295, 2982.8657710403086, 3016.75865930725, 3023.319753055589, 3055.618047744032, 485.4150748348232, 488.68479830509835, 493.50156342094743, 493.77533222383727, 501.75664802570725, 534.6070638081007, 553.7646500237383, 558.6229295225706, 559.3232189051212, 559.5663360895257, 7927.801804954807, 8204.753857384288, 8264.586770661443, 8340.87488687658, 8374.2881855043, 609.6403122157948, 624.7672022050552, 628.9654069026247, 631.387554072558, 639.3510586754448, 3601.5858176309534, 3793.772373657534, 3859.6333348331864, 3894.9805250973563, 3973.6423721864594, 63840.68079659665, 65019.34980650227, 65514.53495638244, 65548.34451655518, 67028.66485667638, 55369.0, 56722.71638641863, 57061.42938570644, 58206.7089664549, 59213.11180332299, 50192.0, 50319.24521132186, 50423.243651345256, 50544.49455505471, 50612.493875060776, 7314.724742411215, 7504.2120577340775, 7524.661390237343, 7529.058867641545, 7628.084629844454, 35868.64131358653, 37855.0, 37905.43141852819, 38056.42915356327, 38667.613323866404, 42579.57420425756, 48124.518754812, 48148.27777583409, 48198.759006204746, 48561.757191213816, 9615.615375384907, 9715.562799674173, 9728.951355243178, 9882.0, 10068.24488163376, 10689.732756681145, 10746.946265268623, 10860.0, 10940.23418360739, 10984.560617575207, 10391.792164156523, 10578.312409693346, 10581.576736930436, 10640.680779576625, 10667.093297069836, 9261.53692315368, 9344.205742511962, 9398.107179817905, 9406.576704048472, 9444.433333999981, 8994.145556172152, 9062.275017998676, 9833.311668183158, 9852.0, 9882.160016398686, 8292.751217463485, 8404.243618074403, 8534.78663033429, 8766.123387661182, 9044.547772611475, 7915.960420198093, 8141.14517975604, 8302.294304984143, 8331.16688331162, 8562.700305489278], "value": 6149.007823780408, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1587.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 119.0, 118.0, 117.0, 115.0, 115.0, 117.0, 117.0, 116.0, 116.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 129.0, 130.0, 197.0, 131.0, 145.0, 128.0, 129.0, 153.0, 128.0], "value": 143.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 170.0, 163.0, 163.0, 164.0, 166.0, 163.0, 161.0, 169.0], "value": 165.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 102.0, 78.0, 82.0, 77.0, 76.0, 94.0, 77.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 85.0, 110.0, 86.0, 87.0, 90.0, 104.0, 86.0, 85.0, 109.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 119.0, 118.0, 123.0, 118.0, 118.0, 119.0, 118.0, 119.0], "value": 118.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 151.0, 151.0, 151.0, 151.0, 151.0, 151.0, 153.0, 150.0], "value": 151.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 97.0, 96.0, 97.0, 97.0, 96.0, 96.0, 97.0], "value": 96.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 71.0, 70.0, 72.0, 74.0, 74.0, 72.0, 72.0, 72.0], "value": 72.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 68.0, 69.0, 69.0, 69.0, 68.0, 69.0, 69.0], "value": 68.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 84.0, 86.0, 86.0, 90.0, 86.0, 86.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 138.0, 136.0, 146.0, 139.0, 136.0, 138.0, 138.0, 189.0, 136.0], "value": 145.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 177.0, 182.0, 176.0, 179.0, 182.0, 181.0, 185.0, 178.0, 176.0], "value": 180.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 71.0, 69.0, 70.0, 69.0, 69.0, 70.0, 69.0, 68.0], "value": 69.8, "unit": "score"}]}]} 09:53:48 INFO - Return code: 0 09:53:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:53:48 ERROR - # TBPL SUCCESS # 09:53:48 INFO - Running post-action listener: _resource_record_post_action 09:53:48 INFO - [mozharness: 2016-12-30 17:53:48.108379Z] Finished run-tests step (success) 09:53:48 INFO - Running post-run listener: _resource_record_post_run 09:53:48 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 8192; Write bytes: 388419584; Read time: 24; Write time: 289780 09:53:48 INFO - TinderboxPrint: CPU usage
13.5% 09:53:48 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 24 09:53:48 INFO - TinderboxPrint: I/O write bytes / time
388,419,584 / 289,780 09:53:48 INFO - TinderboxPrint: CPU idle
8,280.4 (86.5%) 09:53:48 INFO - TinderboxPrint: CPU user
1,234.6 (12.9%) 09:53:48 INFO - TinderboxPrint: Swap in / out
0 / 0 09:53:48 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 100179968; Read time: 0; Write time: 108856 09:53:48 INFO - run-tests - Wall time: 1184s; CPU: 14.0%; Read bytes: 4096; Write bytes: 288239616; Read time: 16; Write time: 180924 09:53:48 INFO - Running post-run listener: _upload_blobber_files 09:53:48 INFO - Blob upload gear active. 09:53:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:53:48 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:53:48 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'] 09:53:48 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 09:53:49 INFO - (blobuploader) - INFO - Open directory for files ... 09:53:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:53:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:53:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:49 INFO - (blobuploader) - INFO - Done attempting. 09:53:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:53:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:53:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:53 INFO - (blobuploader) - INFO - Done attempting. 09:53:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:53:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:53:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:54 INFO - (blobuploader) - INFO - Done attempting. 09:53:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:53:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:53:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:55 INFO - (blobuploader) - INFO - Done attempting. 09:53:55 INFO - (blobuploader) - INFO - Iteration through files over. 09:53:55 INFO - Return code: 0 09:53:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:53:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:53:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/aaa83a3e53715ae148cdc7817c6149afabc168efc03412a3cf63654cfedad6da9f99953454faf352b61419f90e3bfbb6ee9c017ee07d7dc0626480572425f1ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6198b36be604feecc1f868ed715b9d9d56f5c678b78c54c33ea2ec3fdabaa57199debf75bbb5ae16f13ce90980243d08cbeeaa0e70f8607b349ab6194c6936e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/36b6aa495ef665ac2c626607c5ae9d1451d1c09a2f0ade0d962f382737187bd6f338cc31daf468e7f2445930b120971597ddcaf2be2cc02df44cd9d70422611b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/37ba77d3d388b11e7166e0d1c0cd4531ea3a1df50e8684495780dfa52139dd90a584dc410003a4f6a25b54bc28d2746e5e256ff6758abaac3f91633231283656"} 09:53:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:53:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:53:55 INFO - Contents: 09:53:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/aaa83a3e53715ae148cdc7817c6149afabc168efc03412a3cf63654cfedad6da9f99953454faf352b61419f90e3bfbb6ee9c017ee07d7dc0626480572425f1ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6198b36be604feecc1f868ed715b9d9d56f5c678b78c54c33ea2ec3fdabaa57199debf75bbb5ae16f13ce90980243d08cbeeaa0e70f8607b349ab6194c6936e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/36b6aa495ef665ac2c626607c5ae9d1451d1c09a2f0ade0d962f382737187bd6f338cc31daf468e7f2445930b120971597ddcaf2be2cc02df44cd9d70422611b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/37ba77d3d388b11e7166e0d1c0cd4531ea3a1df50e8684495780dfa52139dd90a584dc410003a4f6a25b54bc28d2746e5e256ff6758abaac3f91633231283656"} 09:53:55 INFO - Running post-run listener: copy_logs_to_upload_dir 09:53:55 INFO - Copying logs to upload dir... 09:53:55 INFO - mkdir: /builds/slave/test/build/upload/logs 09:53:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.067566 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-12-30 09:53:54.193100) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 09:53:54.196953) ========= 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=3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/aaa83a3e53715ae148cdc7817c6149afabc168efc03412a3cf63654cfedad6da9f99953454faf352b61419f90e3bfbb6ee9c017ee07d7dc0626480572425f1ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6198b36be604feecc1f868ed715b9d9d56f5c678b78c54c33ea2ec3fdabaa57199debf75bbb5ae16f13ce90980243d08cbeeaa0e70f8607b349ab6194c6936e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/36b6aa495ef665ac2c626607c5ae9d1451d1c09a2f0ade0d962f382737187bd6f338cc31daf468e7f2445930b120971597ddcaf2be2cc02df44cd9d70422611b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/37ba77d3d388b11e7166e0d1c0cd4531ea3a1df50e8684495780dfa52139dd90a584dc410003a4f6a25b54bc28d2746e5e256ff6758abaac3f91633231283656"} build_url:https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.012020 build_url: 'https://queue.taskcluster.net/v1/task/Q7C6--BGQ4Wly1E16anvyg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/aaa83a3e53715ae148cdc7817c6149afabc168efc03412a3cf63654cfedad6da9f99953454faf352b61419f90e3bfbb6ee9c017ee07d7dc0626480572425f1ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6198b36be604feecc1f868ed715b9d9d56f5c678b78c54c33ea2ec3fdabaa57199debf75bbb5ae16f13ce90980243d08cbeeaa0e70f8607b349ab6194c6936e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/36b6aa495ef665ac2c626607c5ae9d1451d1c09a2f0ade0d962f382737187bd6f338cc31daf468e7f2445930b120971597ddcaf2be2cc02df44cd9d70422611b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/37ba77d3d388b11e7166e0d1c0cd4531ea3a1df50e8684495780dfa52139dd90a584dc410003a4f6a25b54bc28d2746e5e256ff6758abaac3f91633231283656"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 09:53:54.222751) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 09:53:54.223068) ========= 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=3a3620fb28aea1604ab609ef000001d2-1483119188.990110-2145246262 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004553 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 09:53:54.308408) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 09:53:54.308729) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 09:53:54.309029) ========= ========= Total master_lag: 0.15 =========