builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-052 starttime: 1474907588.07 results: success (0) buildid: 20160926063005 builduid: 1b1228bc0c924add8393338bb48cd06b revision: 3c4ca5845f2c12974cad7617efe5f12137603721 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-26 09:33:08.074954) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-26 09:33:08.075424) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-26 09:33:08.076294) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-26 09:33:08.091504) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 09:33:08.091880) ========= 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=826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-26 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.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 22.3M=0.001s 2016-09-26 09:33:09 (22.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.141649 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 09:33:08.248046) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 09:33:08.248389) ========= 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=826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017250 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 09:33:08.299082) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 09:33:08.299384) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 3c4ca5845f2c12974cad7617efe5f12137603721 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 3c4ca5845f2c12974cad7617efe5f12137603721 --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=826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-26 09:33:09,764 truncating revision to first 12 chars 2016-09-26 09:33:09,764 Setting DEBUG logging. 2016-09-26 09:33:09,764 attempt 1/10 2016-09-26 09:33:09,764 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/3c4ca5845f2c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-26 09:33:10,074 unpacking tar archive at: autoland-3c4ca5845f2c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.647768 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 09:33:08.984626) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-26 09:33:08.984905) ========= script_repo_revision: 3c4ca5845f2c12974cad7617efe5f12137603721 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-26 09:33:08.985247) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-26 09:33:08.985503) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-26 09:33:08.997509) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-09-26 09:33:08.997947) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] 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=826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:33:10 INFO - MultiFileLogger online at 20160926 09:33:10 in /builds/slave/test 09:33:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 09:33:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:33:10 INFO - {'append_to_log': False, 09:33:10 INFO - 'base_work_dir': '/builds/slave/test', 09:33:10 INFO - 'blob_upload_branch': 'Autoland', 09:33:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:33:10 INFO - 'branch': 'Autoland', 09:33:10 INFO - 'buildbot_json_path': 'buildprops.json', 09:33:10 INFO - 'config_files': ('talos/linux_config.py',), 09:33:10 INFO - 'default_actions': ('clobber', 09:33:10 INFO - 'read-buildbot-config', 09:33:10 INFO - 'download-and-extract', 09:33:10 INFO - 'populate-webroot', 09:33:10 INFO - 'create-virtualenv', 09:33:10 INFO - 'install', 09:33:10 INFO - 'run-tests'), 09:33:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:33:10 INFO - 'download_minidump_stackwalk': True, 09:33:10 INFO - 'download_symbols': 'ondemand', 09:33:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:33:10 INFO - 'tooltool.py': '/tools/tooltool.py', 09:33:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:33:10 INFO - '/tools/misc-python/virtualenv.py')}, 09:33:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:33:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:33:10 INFO - 'installer_path': 'installer.exe', 09:33:10 INFO - 'log_level': 'info', 09:33:10 INFO - 'log_name': 'talos', 09:33:10 INFO - 'log_to_console': True, 09:33:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:33:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:33:10 INFO - 'opt_config_files': (), 09:33:10 INFO - 'pip_index': False, 09:33:10 INFO - 'sps_profile': False, 09:33:10 INFO - 'sps_profile_interval': 0, 09:33:10 INFO - 'suite': 'dromaeojs', 09:33:10 INFO - 'system_bits': '32', 09:33:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:33:10 INFO - 'title': 'talos-linux64-ix-052', 09:33:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:33:10 INFO - 'use_talos_json': True, 09:33:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:33:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:33:10 INFO - 'work_dir': 'build'} 09:33:10 INFO - [mozharness: 2016-09-26 16:33:10.560677Z] Running clobber step. 09:33:10 INFO - Running pre-action listener: _resource_record_pre_action 09:33:10 INFO - Running main action method: clobber 09:33:10 INFO - rmtree: /builds/slave/test/build 09:33:10 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-09-26 16:33:11.537686Z] Finished clobber step (success) 09:33:11 INFO - [mozharness: 2016-09-26 16:33:11.537784Z] 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": "production", 09:33:11 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 09:33:11 INFO - "repository": "", 09:33:11 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 09:33:11 INFO - "buildid": "20160926063005", 09:33:11 INFO - "pgo_build": "True", 09:33:11 INFO - "basedir": "/builds/slave/test", 09:33:11 INFO - "buildnumber": 259, 09:33:11 INFO - "slavename": "talos-linux64-ix-052", 09:33:11 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:33:11 INFO - "platform": "ubuntu64_hw", 09:33:11 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 09:33:11 INFO - "branch": "autoland", 09:33:11 INFO - "repo_path": "integration/autoland", 09:33:11 INFO - "revision": "3c4ca5845f2c12974cad7617efe5f12137603721", 09:33:11 INFO - "stage_platform": "linux64-pgo", 09:33:11 INFO - "builduid": "1b1228bc0c924add8393338bb48cd06b", 09:33:11 INFO - "slavebuilddir": "test" 09:33:11 INFO - } 09:33:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.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-09-26 16:33:11.540197Z] Finished read-buildbot-config step (success) 09:33:11 INFO - [mozharness: 2016-09-26 16:33:11.540285Z] 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/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:33:11 INFO - Downloading https://queue.taskcluster.net/v1/task/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.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/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.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-52.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-52.0a1.en-US.linux-x86_64.common.tests.zip'], 09:33:12 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:33:12 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:33:12 INFO - u'jittest': [u'firefox-52.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-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:33:12 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 09:33:12 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:33:12 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:33:12 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:33:12 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:12 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:33:12 INFO - Downloading packages: [u'firefox-52.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/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.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/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:33:12 INFO - Fetch https://queue.taskcluster.net/v1/task/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:33:12 INFO - Expected file size: 21981525 09:33:17 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:33:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 09:33:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:33:17 INFO - Fetch https://queue.taskcluster.net/v1/task/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:33:17 INFO - Expected file size: 21981525 09:33:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.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/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:33:22 INFO - Fetch https://queue.taskcluster.net/v1/task/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:33:23 INFO - Expected file size: 14414057 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/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:33:23 INFO - Downloading https://queue.taskcluster.net/v1/task/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.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/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:33:24 INFO - Downloaded 59696383 bytes. 09:33:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.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/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:33:25 INFO - Running post-action listener: _resource_record_post_action 09:33:25 INFO - Running post-action listener: set_extra_try_arguments 09:33:25 INFO - [mozharness: 2016-09-26 16:33:25.547688Z] Finished download-and-extract step (success) 09:33:25 INFO - [mozharness: 2016-09-26 16:33:25.547913Z] Running populate-webroot step. 09:33:25 INFO - Running pre-action listener: _resource_record_pre_action 09:33:25 INFO - Running main action method: populate_webroot 09:33:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:33:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:33:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:33:25 INFO - '--tppagecycles', 09:33:25 INFO - '7'], 09:33:25 INFO - 'tests': ['tsvgm']}}, 09:33:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:33:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:33:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:33:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:33:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:33:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:33:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:25 INFO - 'talos_options': ['--disable-e10s'], 09:33:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:33:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:33:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:25 INFO - 'talos_options': ['--disable-e10s'], 09:33:25 INFO - 'tests': ['damp', 'tps']}, 09:33:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:25 INFO - 'tests': ['damp', 'tps']}, 09:33:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:33:25 INFO - 'tests': ['dromaeo_dom']}, 09:33:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:33:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:33:25 INFO - 'tests': ['basic_compositor_video']}, 09:33:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:33:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:33:25 INFO - 'tests': ['a11yr', 09:33:25 INFO - 'ts_paint', 09:33:25 INFO - 'tpaint', 09:33:25 INFO - 'sessionrestore', 09:33:25 INFO - 'sessionrestore_no_auto_restore', 09:33:25 INFO - 'tabpaint']}, 09:33:25 INFO - 'other-e10s': {'tests': ['a11yr', 09:33:25 INFO - 'ts_paint', 09:33:25 INFO - 'tpaint', 09:33:25 INFO - 'sessionrestore', 09:33:25 INFO - 'sessionrestore_no_auto_restore', 09:33:25 INFO - 'tabpaint']}, 09:33:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:33:25 INFO - 'ts_paint', 09:33:25 INFO - 'tpaint', 09:33:25 INFO - 'sessionrestore', 09:33:25 INFO - 'sessionrestore_no_auto_restore', 09:33:25 INFO - 'tabpaint']}, 09:33:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:33:25 INFO - 'ts_paint', 09:33:25 INFO - 'tpaint', 09:33:25 INFO - 'sessionrestore', 09:33:25 INFO - 'sessionrestore_no_auto_restore', 09:33:25 INFO - 'tabpaint']}, 09:33:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:33:25 INFO - 'tests': ['a11yr', 09:33:25 INFO - 'ts_paint', 09:33:25 INFO - 'tpaint', 09:33:25 INFO - 'sessionrestore', 09:33:25 INFO - 'sessionrestore_no_auto_restore', 09:33:25 INFO - 'tabpaint']}, 09:33:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:33:25 INFO - 'tests': ['a11yr', 09:33:25 INFO - 'ts_paint', 09:33:25 INFO - 'tpaint', 09:33:25 INFO - 'sessionrestore', 09:33:25 INFO - 'sessionrestore_no_auto_restore', 09:33:25 INFO - 'tabpaint']}, 09:33:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:33:25 INFO - 'tests': ['tsvgx', 09:33:25 INFO - 'tsvgr_opacity', 09:33:25 INFO - 'tart', 09:33:25 INFO - 'tscrollx', 09:33:25 INFO - 'cart']}, 09:33:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:33:25 INFO - 'tsvgr_opacity', 09:33:25 INFO - 'tart', 09:33:25 INFO - 'tscrollx', 09:33:25 INFO - 'cart']}, 09:33:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:25 INFO - 'talos_options': ['--disable-e10s'], 09:33:25 INFO - 'tests': ['tp5o']}, 09:33:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:25 INFO - 'tests': ['tp5o']}, 09:33:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:25 INFO - 'talos_options': ['--disable-e10s', 09:33:25 INFO - '--xperf_path', 09:33:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:33:25 INFO - 'tests': ['tp5n']}, 09:33:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:25 INFO - 'talos_options': ['--xperf_path', 09:33:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:33:25 INFO - 'tests': ['tp5n']}}, 09:33:25 INFO - 'talos.zip': {'path': '', 09:33:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:33:25 INFO - Running post-action listener: _resource_record_post_action 09:33:25 INFO - [mozharness: 2016-09-26 16:33:25.565745Z] Finished populate-webroot step (success) 09:33:25 INFO - [mozharness: 2016-09-26 16:33:25.565834Z] Running create-virtualenv step. 09:33:25 INFO - Running pre-action listener: _resource_record_pre_action 09:33:25 INFO - Running main action method: create_virtualenv 09:33:25 INFO - Creating virtualenv /builds/slave/test/build/venv 09:33:25 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:25 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:26 INFO - Installing distribute.............................................................................................................................................................................................done. 09:33:28 INFO - Installing pip.................done. 09:33:28 INFO - Return code: 0 09:33:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:33:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:33:28 INFO - Reading from file tmpfile_stdout 09:33:28 INFO - Output received: 09:33:28 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:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:33:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdf9783f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21dc930>, '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 0x21dd830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ddcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', '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:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:33:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:33:28 INFO - Using env: {'DISPLAY': ':0', 09:33:28 INFO - 'HOME': '/home/cltbld', 09:33:28 INFO - 'LANG': 'en_US.UTF-8', 09:33:28 INFO - 'LANGUAGE': 'en_US:en', 09:33:28 INFO - 'LOGNAME': 'cltbld', 09:33:28 INFO - 'MAIL': '/var/mail/cltbld', 09:33:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:28 INFO - 'MOZ_NO_REMOTE': '1', 09:33:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:28 INFO - 'NO_EM_RESTART': '1', 09:33:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:28 INFO - 'PWD': '/builds/slave/test', 09:33:28 INFO - 'SHELL': '/bin/bash', 09:33:28 INFO - 'SHLVL': '1', 09:33:28 INFO - 'TERM': 'linux', 09:33:28 INFO - 'TMOUT': '86400', 09:33:28 INFO - 'USER': 'cltbld', 09:33:28 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', 09:33:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:28 INFO - '_': '/tools/buildbot/bin/python'} 09:33:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:28 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:28 INFO - Cleaning up... 09:33:28 INFO - Return code: 0 09:33:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:33:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ffdf9783f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21dc930>, '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 0x21dd830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ddcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', '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:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:33:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:33:28 INFO - Using env: {'DISPLAY': ':0', 09:33:28 INFO - 'HOME': '/home/cltbld', 09:33:28 INFO - 'LANG': 'en_US.UTF-8', 09:33:28 INFO - 'LANGUAGE': 'en_US:en', 09:33:28 INFO - 'LOGNAME': 'cltbld', 09:33:28 INFO - 'MAIL': '/var/mail/cltbld', 09:33:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:28 INFO - 'MOZ_NO_REMOTE': '1', 09:33:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:28 INFO - 'NO_EM_RESTART': '1', 09:33:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:28 INFO - 'PWD': '/builds/slave/test', 09:33:28 INFO - 'SHELL': '/bin/bash', 09:33:28 INFO - 'SHLVL': '1', 09:33:28 INFO - 'TERM': 'linux', 09:33:28 INFO - 'TMOUT': '86400', 09:33:28 INFO - 'USER': 'cltbld', 09:33:28 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', 09:33:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:28 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:31 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:31 INFO - building 'psutil._psutil_posix' extension 09:33:31 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:31 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:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:33:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:33:31 INFO - Successfully installed psutil 09:33:31 INFO - Cleaning up... 09:33:31 INFO - Return code: 0 09:33:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:33:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdf9783f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21dc930>, '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 0x21dd830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ddcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', '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:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:33:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:33:31 INFO - Using env: {'DISPLAY': ':0', 09:33:31 INFO - 'HOME': '/home/cltbld', 09:33:31 INFO - 'LANG': 'en_US.UTF-8', 09:33:31 INFO - 'LANGUAGE': 'en_US:en', 09:33:31 INFO - 'LOGNAME': 'cltbld', 09:33:31 INFO - 'MAIL': '/var/mail/cltbld', 09:33:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:31 INFO - 'MOZ_NO_REMOTE': '1', 09:33:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:31 INFO - 'NO_EM_RESTART': '1', 09:33:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:31 INFO - 'PWD': '/builds/slave/test', 09:33:31 INFO - 'SHELL': '/bin/bash', 09:33:31 INFO - 'SHLVL': '1', 09:33:31 INFO - 'TERM': 'linux', 09:33:31 INFO - 'TMOUT': '86400', 09:33:31 INFO - 'USER': 'cltbld', 09:33:31 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', 09:33:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:31 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 0x7ffdf9783f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21dc930>, '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 0x21dd830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ddcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', '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': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', 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:36 INFO - Running setup.py install for functools32 09:33:36 INFO - warning: no files found matching '*.txt' 09:33:36 INFO - no previously-included directories found matching 'build' 09:33:36 INFO - no previously-included directories found matching 'dist' 09:33:36 INFO - no previously-included directories found matching '.git*' 09:33:36 INFO - Successfully installed jsonschema functools32 09:33:36 INFO - Cleaning up... 09:33:36 INFO - Return code: 0 09:33:36 INFO - Installing functools32==3.2.3-2 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', '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 0x7ffdf9783f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21dc930>, '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 0x21dd830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ddcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', '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', 'functools32==3.2.3-2'] 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 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': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', 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 0x7ffdf9783f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21dc930>, '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 0x21dd830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ddcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', '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': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', 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 0x7ffdf9783f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21dc930>, '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 0x21dd830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ddcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', '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': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', 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-BdqQFG-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-8c0zeH-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-7pHTIJ-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-Zfs39d-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-9g6J39-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-aRw7ED-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-sRdhHk-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-fsWET9-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-k1WqzU-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-IpnaEc-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-NFOBV9-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-qnu1fS-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-6LgYR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:33:41 INFO - Running setup.py (path:/tmp/pip-j2ifwa-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-_VC9ym-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-A1Jz2m-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-CI3vhz-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:42 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:43 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 0x7ffdf9783f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21dc930>, '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 0x21dd830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ddcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', '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': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', 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-iyO743-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-w9wpAh-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==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:33:43 INFO - Running setup.py (path:/tmp/pip-fYfEYs-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-OoQBsG-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.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:33:43 INFO - Running setup.py (path:/tmp/pip-Er_Trb-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-S3qKLx-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-fHXInp-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-s8NDFK-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-wpi8IK-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:44 INFO - Running setup.py (path:/tmp/pip-sJfsBm-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-PcxaFg-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-zYCej6-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-M1sShy-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-eGVdas-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.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:44 INFO - Running setup.py (path:/tmp/pip-6AgpWg-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-6NGdYY-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.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:33:44 INFO - Running setup.py (path:/tmp/pip-AbqZ6X-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==0.17->-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==0.17->-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.48->-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.48->-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:46 INFO - Reading from file tmpfile_stdout 09:33:46 INFO - Current package versions: 09:33:46 INFO - argparse == 1.2.1 09:33:46 INFO - blessings == 1.6 09:33:46 INFO - blobuploader == 1.2.4 09:33:46 INFO - docopt == 0.6.1 09:33:46 INFO - functools32 == 3.2.3-2 09:33:46 INFO - jsonschema == 2.5.1 09:33:46 INFO - manifestparser == 1.1 09:33:46 INFO - mozInstall == 1.12 09:33:46 INFO - mozcrash == 0.17 09:33:46 INFO - mozdebug == 0.1 09:33:46 INFO - mozdevice == 0.48 09:33:46 INFO - mozfile == 1.2 09:33:46 INFO - mozhttpd == 0.7 09:33:46 INFO - mozinfo == 0.9 09:33:46 INFO - mozleak == 0.1 09:33:46 INFO - mozlog == 3.4 09:33:46 INFO - moznetwork == 0.27 09:33:46 INFO - mozprocess == 0.23 09:33:46 INFO - mozprofile == 0.28 09:33:46 INFO - mozrunner == 6.12 09:33:46 INFO - mozscreenshot == 0.1 09:33:46 INFO - mozsystemmonitor == 0.3 09:33:46 INFO - moztest == 0.7 09:33:46 INFO - mozversion == 1.4 09:33:46 INFO - psutil == 3.1.1 09:33:46 INFO - requests == 1.2.3 09:33:46 INFO - wsgiref == 0.1.2 09:33:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:46 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 0x7ffdf9783f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21dc930>, '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 0x21dd830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ddcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', '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:46 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:46 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:46 INFO - Using env: {'DISPLAY': ':0', 09:33:46 INFO - 'HOME': '/home/cltbld', 09:33:46 INFO - 'LANG': 'en_US.UTF-8', 09:33:46 INFO - 'LANGUAGE': 'en_US:en', 09:33:46 INFO - 'LOGNAME': 'cltbld', 09:33:46 INFO - 'MAIL': '/var/mail/cltbld', 09:33:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:46 INFO - 'MOZ_NO_REMOTE': '1', 09:33:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:46 INFO - 'NO_EM_RESTART': '1', 09:33:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:46 INFO - 'PWD': '/builds/slave/test', 09:33:46 INFO - 'SHELL': '/bin/bash', 09:33:46 INFO - 'SHLVL': '1', 09:33:46 INFO - 'TERM': 'linux', 09:33:46 INFO - 'TMOUT': '86400', 09:33:46 INFO - 'USER': 'cltbld', 09:33:46 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', 09:33:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:46 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 0x7ffdf9783f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21df790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21dc930>, '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 0x21dd830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ddcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', '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': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', 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-09-26 16:33:47.455069Z] Finished create-virtualenv step (success) 09:33:47 INFO - [mozharness: 2016-09-26 16:33:47.455666Z] 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-52.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-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:34:04 INFO - Reading from file tmpfile_stdout 09:34:04 INFO - Output received: 09:34:04 INFO - /builds/slave/test/build/application/firefox/firefox 09:34:04 INFO - Running post-action listener: _resource_record_post_action 09:34:04 INFO - [mozharness: 2016-09-26 16:34:04.761284Z] Finished install step (success) 09:34:04 INFO - [mozharness: 2016-09-26 16:34:04.761529Z] Running run-tests step. 09:34:04 INFO - Running pre-action listener: _resource_record_pre_action 09:34:04 INFO - Running main action method: run_tests 09:34:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:34:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:34:04 INFO - Python 2.7.3 09:34:04 INFO - Return code: 0 09:34:04 INFO - grabbing minidump binary from tooltool 09:34:04 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:04 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 0x21dc930>, '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 0x21dd830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ddcc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:34:04 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:04 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:04 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', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:34:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-052 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 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': '826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557', 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', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:34:05 INFO - mozversion application_buildid: 20160926063005 09:34:05 INFO - mozversion application_changeset: 3c4ca5845f2c12974cad7617efe5f12137603721 09:34:05 INFO - mozversion application_display_name: Nightly 09:34:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:34:05 INFO - mozversion application_name: Firefox 09:34:05 INFO - mozversion application_remotingname: firefox 09:34:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:34:05 INFO - mozversion application_vendor: Mozilla 09:34:05 INFO - mozversion application_version: 52.0a1 09:34:05 INFO - mozversion platform_buildid: 20160926063005 09:34:05 INFO - mozversion platform_changeset: 3c4ca5845f2c12974cad7617efe5f12137603721 09:34:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:34:05 INFO - mozversion platform_version: 52.0a1 09:34:05 INFO - using testdate: 1474907645 09:34:05 INFO - actual date: 1474907645 09:34:05 INFO - starting webserver on 'localhost:34221' 09:34:05 INFO - SUITE-START | Running 2 tests 09:34:05 INFO - TEST-START | dromaeo_css 09:34:05 INFO - operating with platform_type : linux_ 09:34:05 INFO - Initialising browser for dromaeo_css test... 09:34:05 INFO - TEST-INFO | started process 25636 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppttAzv/profile http://localhost:34221/getInfo.html) 09:34:09 INFO - PROCESS | 25636 | __metrics Screen width/height:1600/1200 09:34:09 INFO - PROCESS | 25636 | colorDepth:24 09:34:09 INFO - PROCESS | 25636 | Browser inner width/height: 1024/697 09:34:09 INFO - PROCESS | 25636 | __metrics 09:34:09 INFO - PROCESS | 25636 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:34:09 INFO - PROCESS | 25636 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:34:10 INFO - PROCESS | 25636 | console.error: PushService: 09:34:10 INFO - PROCESS | 25636 | stateChangeProcessEnqueue: Error transitioning state 09:34:10 INFO - PROCESS | 25636 | UnknownError 09:34:10 INFO - TEST-INFO | 25636: 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 25718 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppttAzv/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 | 25718 | RSS: Main: 177602560 09:34:22 INFO - PROCESS | 25718 | 09:35:14 INFO - PROCESS | 25718 | 1474907714220 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:37:23 INFO - PROCESS | 25718 | Cycle 1(1): loaded http://localhost:34221/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34221/tests/dromaeo/cssquery-ext.html) 09:37:25 INFO - PROCESS | 25718 | RSS: Main: 327057408 09:37:25 INFO - PROCESS | 25718 | 09:40:27 INFO - PROCESS | 25718 | Cycle 1(1): loaded http://localhost:34221/tests/dromaeo/cssquery-ext.html (next: http://localhost:34221/tests/dromaeo/cssquery-jquery.html) 09:40:28 INFO - PROCESS | 25718 | RSS: Main: 282730496 09:40:28 INFO - PROCESS | 25718 | 09:43:30 INFO - PROCESS | 25718 | Cycle 1(1): loaded http://localhost:34221/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34221/tests/dromaeo/cssquery-mootools.html) 09:43:30 INFO - PROCESS | 25718 | RSS: Main: 248573952 09:43:30 INFO - PROCESS | 25718 | 09:46:32 INFO - PROCESS | 25718 | Cycle 1(1): loaded http://localhost:34221/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34221/tests/dromaeo/cssquery-prototype.html) 09:46:33 INFO - PROCESS | 25718 | RSS: Main: 241909760 09:46:33 INFO - PROCESS | 25718 | 09:49:36 INFO - PROCESS | 25718 | Cycle 1(1): loaded http://localhost:34221/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34221/tests/dromaeo/cssquery-yui.html) 09:49:36 INFO - PROCESS | 25718 | RSS: Main: 218218496 09:49:36 INFO - PROCESS | 25718 | 09:52:40 INFO - PROCESS | 25718 | Cycle 1(1): loaded http://localhost:34221/tests/dromaeo/cssquery-yui.html (next: http://localhost:34221/tests/dromaeo/cssquery-dojo.html) 09:52:40 INFO - PROCESS | 25718 | RSS: Main: 306057216 09:52:40 INFO - PROCESS | 25718 | 09:52:40 INFO - PROCESS | 25718 | __start_tp_report 09:52:40 INFO - PROCESS | 25718 | _x_x_mozilla_page_load 09:52:40 INFO - PROCESS | 25718 | _x_x_mozilla_page_load_details 09:52:40 INFO - PROCESS | 25718 | |i|pagename|runs| 09:52:40 INFO - PROCESS | 25718 | |0;dojo.html;3003.354278830052;3024.6219222597174;3030.6514750803663;3073.692630736928;3077.2922227887584;5275.340582427197;5297.735113244342;5343.839684809457;5374.247605335257;5396.163594642837;5345.49217824307;5374.032674118657;5384.273123128386;5417.295751552302;5490.396056433789;4654.501775936421;4878.926816097762;4898.510148985109;4906.969536397361;4924;5015.724135172564;5020.824271150511;5024.572911302544;5031;5037.269595908591;4789.664723469358;4806.975965120152;4837.975810120962;4935.654504184727;4961.131801934665;4794.113089078532;4838.927416088762;4846.0550192712435;4850.83022094225;4920.286558449041;4792;4792.424909010941;4806.735629540374;4815.759212039384;4849.1513985052825;5239.345081864767;5275.340582427197;5281.36623605166;5287.788488460457;5342.839714808562;5654.151877218409;5670.461306175948;5729.312482502085;5731.541476681897;5821.3014438267255;5725.370209276976;5734.684592347419;5739.885202295973;5742.454466825645;5755.0504166812425;5341.519263266286;5346.117890548055;5364.919526207071;5366.812161574326;5429.511343979061;5788.21859049023;5839.182514447895;5859.619124756877;5865.501432378211;5908.675022873783;5646.85882852932;5689.203511508392;5691.9715401423555;5698.943010569924;5757.539396848242;5734.541236701054;5771.451712087345;5791.652500849962;5793.391693872167;5801.361850196475;5552.972235138798;5618.494335509824;5672.4894759471845;5729.598928075078;5755.482006619341;5766.88466230673;5771.682557459296;5781.046127389018;5831.329397119301;5863.794767183128;4431.911361772811;4456.197884380777;4463.062756821104;4501.774911254424;4519.683622146484;3904.3557812960544;3914.6085391460624;3925.7055720821054;3955.4660120883887;4019.879403617896;5431.592630552725;5453.15476101205;5455.509004189563;5506.587005974568;5527.309086364205;5677.943220567824;5707.200991861143;5730.799422020293;5762.827115186551;5765.04876695349;5324.334458192726;5328.3605967284175;5409.729513524387;5419.891602167934;5482.23248745176;4724.598409135262;4823.1800593899125;4869.245266983623;4878.609711223094;4938.728369939688;5721;5747.5689323300085;5753.51095156908;5755.165501002331;5761.222234998306;5559.694216818114;5563.833085007456;5579.414161513145;5582.5291015819;5651.011073062115;5781.682007489629;5869.295684517885;5901.616394934315;5914.615549989237;5959.73181206838;5600.775968961196;5635.267415235993;5641.689707065987;5647.915281270866;5661.603687741818;5684.118961560965;5740.425957404226;5750.223719797774;5752.7698892043845;5758.856028599319;5438.184272359178;5491.066518691752;5533.667979921218;5573.052581061311;5583.413741557078;5597.608167428241;5600.971995139998;5602.047651899268;5603.859903502445;5649.265595472727;5537.335519737658;5541.33503979525;5542.8337149885565;5543.6396634218645;5544.112941929272;5478.479544443271;5527.806526771544;5607.74765135578;5608.559390425591;5650.067738823048;5528.143137813645;5549.889002219852;5552.972235138798;5631.943680563142;5636.5208957237455 09:52:40 INFO - PROCESS | 25718 | |1;ext.html;577.2265164679332;602.6981719485908;607.3471018654946;616.1127975714967;629.4177885455955;31531.21171970699;32234.194145146383;34266;34281.48577771335;43056.3541546877;39695.20609587814;44254.11491770171;44258;44266.77866610671;44274.114517709626;8881.333899957497;9037.412568183046;9037.864432033492;9038.86441703375;9043.050479699636;8905.910940890606;8970.820583588324;8990.820183596325;8996.595153218088;8997.550122493849;9003.774905627379;9030.187283144516;9054.728358149234;9079.364444488889;9083.273338132967;8936.687215947442;8950.552472376356;8954.37319387643;8989.05614910438;8995.730128096167;8772.956135219347;8790.120987901159;8813.691520796805;8814.86777698335;8823;42044.36933446001;42443.787781061204;42982.35526467074;43110.78444607788;43136;3358.781679190845;3360.5799275090612;3389.8983030509125;3584.605693373727;3600.1899572596217;31493.48052155822;31626.841865790753;31782;31927.6807231927;31931;3805.3721136012523;3928.46965659638;3944.112574670705;4027.154297597508;4042.191561687674;329386;331571;331762;332358;333274;56241;56460.71769641207;56530.717346413;56621;56993.71503142541;61027.38972610217;61082;61131;61197.69401152966;61325.38674613197;15519.224038798015;15766.605834854221;15769.526914192593;15770.684586308209;15779.763303550455;11097;11114.388708620942;11122.943320384551;11131.332120072822;11141.72145696364;15964.36142554308;16864.156792160342;16891.662166756596;16897.324107035827;17099.145042747812;5315.574754019669;6148.385161483905;6215.352931473163;6286.811395658137;6300.30696623371;898.4025622960788;958.3387462650749;1058.2909450668071;1072.4691277817374;1079.7084787107594;31081;31992.520112198337;32233.35533289321;32332;32532.512012319836;1090.9563617455372;1098.9010989011028;1101.983470247947;1118.3569447567681;1157.0338767129542;16105.758413623806;16781.328746849988;16783.580410489834;16859.831401685828;16960;504989;515418;515593;516038;516259;55784.48969796369;55871.72064139653;55873.720631397395;56074.43925560692;56091.71954140203;1202.963911082669;1230.7600017996406;1284.1717092475487;1299.123091912955;1311.8819306262483;28031.859840700665;31079;32284.35431291408;32519.837400812845;33386.16534586558;50921;56147.71926140343;56252.71873640606;56309;56333.71833140808;471491;476123;476237;476346;477488;27002.864985674947;27795;27878;28139.57790633101;29748.405031899674;46240.768796155804;46602.76698616621;47202.26975294365;47350.526494734615;47413;37037.44443833399;41398.37902431526;41949.580504194564;42089.5254612322;42588.148237035704;39326;39976.40035399413;39995.80002099971;40204;41461.58538414577;39688.40467393049;40055.79972100121;40462.797686011385;40566;40658.18683626252;34022.14944626404;36266;36466.45300320444;37058;37152.62847371492;27433.725662743116;27456.45087098287;27528.174154775388;27759.722402775715;27808.86095569509 09:52:40 INFO - PROCESS | 25718 | |2;jquery.html;373.66930266713985;375.59811002227593;397.1164159744568;400.89977505623597;412.7564736805284;13701.451941922323;15323.38706451742;17740.82259177408;18909.810901890978;19439.805601943975;18063.458096257134;18262.269509219648;18274.08629568523;18772.436826895217;18780.71828922567;5511.58663100267;5709.143628455723;5750.971245143789;5752.482276595106;5758.157389635318;5395.946040539584;5638.718064096779;5658.745356458949;5664.037113690682;5666.263385759846;5626.54987600993;5634.661920284776;5635;5635.520980716624;5639.323281206241;5544.251526043993;5806.941930580703;5814.244148260741;5841.474267315962;5845.269341332333;5430.755615997289;5555.194496797982;5623.240862483594;5627.887442251173;5627.915581266284;12733.554325598652;12747.808782868264;12913.354332283443;12917.483300667962;12931.349073439922;2024.44327809852;2092.184048221195;2191.802737753594;2435.366804630809;2540.352210186409;9084.636614535411;9703.12671859536;9830.557624906896;11671.949524542832;12441.564545240964;1721.8622510199154;1725.8187890271465;1796.3173993882242;1797.703378942486;1862.2178684952353;65996.34003659998;77009.22990770076;83694;87046.56476717576;88577.55711221466;9643.662471813454;9743.366681165702;10090.09189172978;10241.692749217535;10613.416262105658;14585.635359116108;14594.854051459562;14599.029131067382;14603.415863365346;14612.196329201995;7209.0988626421695;7435.293647103516;7474.925250747532;7550.169481357047;7693.846123077507;7012.333828286304;7122.501424900311;7245.311695388929;7276.490645654859;7413.666385012687;4178.415021896937;4285.343087285841;4325.783710814447;4565.56627120423;4612.723236605814;1915.0711904726197;1924.70167124096;1927.9174205382042;1987.2810019333172;1987.6012019446907;825.6944930375679;948.9715308540755;959.6928982725461;960.644561512245;1064.8509208710786;17418;18495.44513664592;18547.35084272071;18638.44084677435;18642.720359194624;944.2776276148752;944.8110377924443;957.5690939077303;977.6333874796951;979.9412035277907;9763.07250811186;10054.69835904924;10281.588736450607;10310.484475776182;10346.741331466623;468437;468820;469744;482896;488151;56200;87938;89582;92734;94492.52753736325;1004.7689031522791;1025.3642741500319;1048.3238311289329;1063.6649455421518;1063.7393838509615;15954.840451595335;16283.511494655178;16404.589885252964;16579.419720309732;16782.83217167813;37102.814485927396;40905.79547102245;40925;41754.79122604368;42720.57279427166;52310.73844630752;53893;53900.46099539111;56907.14639280497;58494;5009.849704508871;5756.9061878243;7468.253174682488;7579.54522728638;7793.06483222017;11134.387608681276;11302.660920172408;11305.15211359135;11441.199116061796;11556.537738490366;9627.326087173831;9705.223582113555;9854;10082.949585252027;10460.37237765706;10012.148967337858;10013.749656258651;10108.696739097839;10131.442770647685;10204.540795664065;5573.219749235192;5966.612170208922;9349.813003740022;10425.739356516147;10463.162946964072;6768.4585233182215;7559.659815308434;7668.271514206142;7738.574378409242;7984.560849153352;8141.837163256583;9548.570314335733;9633.9036609633;9794.314397992071;9964.50177491137 09:52:40 INFO - PROCESS | 25718 | |3;mootools.html;1338.4311667541306;1439.5465428390057;1492.4776328285102;1624.1310898669208;1802.3781795280631;7920.960395198023;8578.828423431536;8867.334949878768;8937.776555586113;9128.178463938244;8948.8657670135;8965.103489651032;9020.458772473656;9042.864357034634;9050.140236677522;2248.123231939545;2271.341311019802;2272.1138755885218;2276.430892276931;2280.680704701343;2253.4141123307895;2279.361778701966;2305.8270629702756;2311.2141871763597;2316.1546035696933;2300.148944890393;2325.6744055832196;2325.9302220933314;2330.860148391102;2331.9533609327805;2193.7696541863033;2196.5936301784222;2197.48359135603;2200.2629119244993;2203.4160947348964;2206.4042708468623;2233.6872837802557;2235.3629215673454;2235.7540670526228;2244.5510897820336;8956.641734330618;8958.955205223998;9054.366194366397;9060.81878362429;9068.591913363915;4932.8520144395725;5090.032893750175;5093.108705976476;5134;5204.375474943031;3120.7659425543175;3270.8201048942296;3340.849661765202;3347.815870127142;3355.916102097467;1108.5031833319704;1151.694800877768;1153.319541470528;1161.1291531351487;1164.8776878427727;12464.875351246463;12469.314187719672;12471.251724896536;12479.376031198404;12560.120791544505;4325.199838029975;4424.225760491901;4561.977190114028;4565.269556870885;4632.258838585877;9892.505374731234;9899;9901.356411833207;9919.057689519628;9921.50392480373;5140.794368225229;5142.51146141116;5145.716985565755;5147.794088236504;5152.793888244503;4309.784510774449;4424.60178583929;4663.976680116645;4801.855944321676;4806.2310030395665;3749.9687585914094;3785.2240290740333;3826.2921359548573;3864.246471937945;3884.5821275234903;1154.440096553171;1161.895429411357;1169.023865072657;1179.2099293473393;1180.2800291821982;878.0297770963122;922.4327038871046;964.864918911353;990.2028866762181;1009.3237530854345;5852.6195797273895;6061.484773794189;6107.786227482017;6129.325774164839;6291;440.90740944402035;456.4568163885033;461.5107985535343;463.28191303479474;464.846600621794;4171.728837625545;4302.139572085595;4316.69783115179;4321.935170972438;4327.956720432755;10987.835182472269;11207.047400971009;11233.38216398106;11257.099432045417;11335.263207891461;10428.113610343205;10950;11157.72105697364;11173;11188.272762270426;8121.4721043132;8270.710525131712;8339.45793523419;8343.8331233375;8344.08215096339;6147.508199344131;6202.689865506797;6227.844303892439;6402.327755585597;6471.967640161769;9953.353032052893;9999.450030248407;10100.242481813746;10150.13723833453;10277.640282590073;9967.10296073357;10110.848337275094;10373.636922707668;10469.68590942273;10588;7579.393648508217;7688.308052275322;7824.491408058458;7828.882566761389;7894.605269736376;7857.096433910174;8090;8327.6668933242;8341.916580834113;8508.617112230088;7535.472516923763;7553.5845528494265;7762.904015915558;8028.197180281925;8033.277005069572;7388.076490438695;7398.556086634819;7413.369863561442;7434.293742094493;7686.039245094364;6581.539292249497;6791.286914874065;6791.524593278423;6797.9320206799275;6804.727810887707;5189.92215116766;5211.791528338824;5228.215767634885;5264.31563896691;5525.751341189576;5195.76619052136;5276.182191760283;5297.125974214366;5316.6012549058205;5558.583106266965 09:52:40 INFO - PROCESS | 25718 | |4;prototype.html;319.64359738891136;320.69533942754373;330.2684553713525;332.7903420844867;332.8202770503928;5081;5210.374755029397;5272.3145991021165;5282.418933917266;5293.285406470125;5021.2970184174155;5128.768985491304;5132.794688212476;5156.3296771419755;5160.174372100455;4082.7142100052934;4088.48725971391;4091.9181616367805;4100.63094321511;4102.138550904314;3808.352580061396;3856.9421458678144;4101.220768054075;4102.487189101363;4102.5897410258885;4017.27689015977;4023.3964905264297;4028.6374226319626;4038.5153781546255;4063.4717486726686;4037.253108174997;4041.2725709372303;4044.3326851069537;4049.3925911113274;4059.8985025374454;3598.704466392097;3604.3151801157696;3606.6934310583633;3611.7291203159866;3632.3280193164087;4947.381577302837;5036.924446133311;5076.974615126937;5082.440931497532;5093.79624815007;4653.395058642389;4663.60359369454;4667.6265898728025;4669.603083737887;4682.157211701892;5397.757100930428;5399.730013499348;5401.540869026137;5404.297441332641;5441.700706461143;3797.810109494514;3935.5474120476224;3951.6487689824794;3958.841646334143;3964.4647972523917;5645.5483561314995;5649.4633009864;5650.6892120933335;5654.858628534278;5660.811229641739;4049.372347286175;4076.8381011411607;4091.263572556909;4095.9385609215888;4104.138130992465;3972.880813575597;4090.652294554996;4098.077932465171;4103.712740108223;4155.688323375698;4615.699979501322;4619.353290539327;4623.953760462419;4626.7223966562115;4628.745419001987;5449.730161902906;5460.645058071292;5465.262189604432;5467.289252397163;5468.671879687232;1323;1375.24361601119;1386.7642500774894;1409.6616811965055;1424.2522675595187;868.2272777228272;883.7304622090196;891.9375643705008;898.3172788680649;901.9897714559735;4691.671582989158;4695.347128872177;4731.384919960452;4740;4767.69010014348;4905.288733133676;4943.28322393251;4946.788036930903;4951.653384263067;4960.305557221992;4665.323528088408;4683.367745354401;4688.437387513521;4704.717716936994;4764.4520880099235;2772.695003549608;2982.910512684623;2997.4754417976765;3001.8198908065588;3002.384511175204;5823.79616713413;5825.465922323802;5844.561657875676;5847.94152058474;5878.118282257696;5521.641093328921;5639.577031722637;5679.886402271932;5781.4507621775865;5807.825765226966;5716.885662286731;5721.141828725725;5789.681567513827;5808.593398462067;5827.533797296206;5593.188987596857;5634.661920284796;5651;5666.376698563073;5680.602357834912;5396.784128634968;5533.446655334596;5569.164625306236;5570.275864137636;5606.831795046155;5678.290213723285;5788.118861369988;5800.477956983892;5854.824355269348;5858.765649373977;3778.319902417591;3987.302222111177;3990.4014397840556;3995.380715989026;4028.0735430851655;5205.401378841481;5521.730002099459;5572.247746554163;5585.938671652373;5591.245181900392;5430;5430.6198566101575;5445.128779395278;5460.071787796005;5494.587905906993;5211.5049070338255;5405.5135037846785;5410.565245639399;5488.862778430571;5504.669719816824;5358.302771087937;5360.16917377807;5360.812371566976;5368.2752828367675;5384.623076384615;5458.6724796512335;5462.399136095106;5475.342958844964;5491.203775452457;5517.586181036519;5188.896222075462;5305.257263982969;5329.920051199313;5341.78632854697;5346.385165705994 09:52:40 INFO - PROCESS | 25718 | |5;yui.html;747.5626758346367;759.7530802489192;766.5668897073153;824.4229039672229;836.899572051354;14910.776338354934;15438.84561154388;15453.536393908173;15458.149801760885;15458.690826183498;15219.315130819112;15334.616634584114;15371.615709607237;15376.923115384407;15377.84622153778;643.2377632505472;673.4040374268758;778.0546635837457;795;796.8326651403212;758.404652347907;787.4881327137377;788.9566073865964;791.8178818871663;792.3067316098413;832;833.2667252817512;835.1648351648352;835.9331253499736;836.5399030533213;680.4284401103097;832.0930186097153;832.2426591801432;832.2883934236262;833.995830020852;515.4896652314219;588.2676068295;609.6433586351945;611.6330201878882;613.6686189457732;14193.929030354886;14210.786838197435;14224.85775142256;14225.07537010091;14320.498782542665;13222;13507.189568625914;13711.382987765575;13788.517401890986;14273.357698903574;10617.78764424715;10950.452477376179;11268.830967535494;11421.942890285578;11422.942885285604;3582.802945837978;3629.891103266906;3664.468652045465;3691.53855768029;3808.0479880029993;72923;73107.63446182734;76066;76412;76586.70239892756;10466.424346661006;10548.630797922036;10558.472076396303;10601;11156.553737850396;21490.677639835416;21806.781932180475;21863.672044919338;21903.780962190172;21906.12375504962;7964.601769911481;8003.279704826594;8549.743507694779;8554.700585479603;8554.871676924851;4462;4497.595216430537;4498.100379924028;4504.662150338672;4563.566461186182;3211.1169428406906;3248.8538015789345;3270.2151483643756;3275.132089996153;3277.901662950115;610.9633421994695;612.653850574424;617.1421723803916;625.8904691678938;632.0235236559527;542.6418563747986;568.9800856969986;615.3262177915176;661.503872095928;689.6689588997309;7782.844343113106;8080.676772929134;8351.3736469765;8663.220310172115;9102.908970910206;683.4498228925762;684.637142314574;705.9788206353818;729.5221629832469;737.9040724705861;4145.626893579592;4360.258756011485;4587.9082418351445;4673.1354699380045;4715.740634265148;107788;108155;112785.43607281911;114791.42604286925;115367.42316288364;56851.71574142103;58394.708026460445;63969;92083;95212.04787952032;77646;77792.61103694445;78226.6088669553;88294.55852720696;92198;8347.582620869054;9095.317851161322;9226.215771659485;9229.723108306762;9248.398854074463;42871.7856410716;52740.47259527355;53481.197782034076;54099;56480;46119.538804611526;61256;79277.60361198157;79396;81214.59392702999;12958.546450874173;13137.802932956205;13242.933785331012;13358.530561638017;13730.244836534086;13297.933510332387;13327.933360333136;13528.93235533816;14009.789853152008;14089.718205636034;12163.75672486563;12191;12277.815832762695;12844.935775321064;13057.869421306046;11575.826362604399;12308.692282693004;12514.687132821387;12573.994080473723;13035.934820325838;12265.754684906431;12276.938615306866;12616.17994830333;12702.936485317514;13062.869371306166;10394.220433467672;10572.41851698164;10799.298045627009;10886.67339979802;10954.56181752721;10649.254552181274;10938;11171.944140279571;11186.496607652512;11227.66317010491 09:52:40 INFO - PROCESS | 25718 | __end_tp_report 09:52:40 INFO - PROCESS | 25718 | __start_cc_report 09:52:40 INFO - PROCESS | 25718 | _x_x_mozilla_cycle_collect,1180 09:52:40 INFO - PROCESS | 25718 | __end_cc_report 09:52:40 INFO - PROCESS | 25718 | __startTimestamp1474908760308__endTimestamp 09:52:40 INFO - PROCESS | 25718 | 09:52:40 INFO - PROCESS | 25718 | ------- Summary: start ------- 09:52:40 INFO - PROCESS | 25718 | Number of tests: 6 09:52:40 INFO - PROCESS | 25718 | 09:52:40 INFO - PROCESS | 25718 | [#0] dojo.html Cycles:165 Average:5314.79 Median:5541.34 stddev:598.97 (10.8%) stddev-sans-first:572.71 09:52:40 INFO - PROCESS | 25718 | Values: 3003.4 3024.6 3030.7 3073.7 3077.3 5275.3 5297.7 5343.8 5374.2 5396.2 5345.5 5374.0 5384.3 5417.3 5490.4 4654.5 4878.9 4898.5 4907.0 4924.0 5015.7 5020.8 5024.6 5031.0 5037.3 4789.7 4807.0 4838.0 4935.7 4961.1 4794.1 4838.9 4846.1 4850.8 4920.3 4792.0 4792.4 4806.7 4815.8 4849.2 5239.3 5275.3 5281.4 5287.8 5342.8 5654.2 5670.5 5729.3 5731.5 5821.3 5725.4 5734.7 5739.9 5742.5 5755.1 5341.5 5346.1 5364.9 5366.8 5429.5 5788.2 5839.2 5859.6 5865.5 5908.7 5646.9 5689.2 5692.0 5698.9 5757.5 5734.5 5771.5 5791.7 5793.4 5801.4 5553.0 5618.5 5672.5 5729.6 5755.5 5766.9 5771.7 5781.0 5831.3 5863.8 4431.9 4456.2 4463.1 4501.8 4519.7 3904.4 3914.6 3925.7 3955.5 4019.9 5431.6 5453.2 5455.5 5506.6 5527.3 5677.9 5707.2 5730.8 5762.8 5765.0 5324.3 5328.4 5409.7 5419.9 5482.2 4724.6 4823.2 4869.2 4878.6 4938.7 5721.0 5747.6 5753.5 5755.2 5761.2 5559.7 5563.8 5579.4 5582.5 5651.0 5781.7 5869.3 5901.6 5914.6 5959.7 5600.8 5635.3 5641.7 5647.9 5661.6 5684.1 5740.4 5750.2 5752.8 5758.9 5438.2 5491.1 5533.7 5573.1 5583.4 5597.6 5601.0 5602.0 5603.9 5649.3 5537.3 5541.3 5542.8 5543.6 5544.1 5478.5 5527.8 5607.7 5608.6 5650.1 5528.1 5549.9 5553.0 5631.9 5636.5 09:52:40 INFO - PROCESS | 25718 | 09:52:40 INFO - PROCESS | 25718 | [#1] ext.html Cycles:180 Average:59695.40 Median:40514.40 stddev:118677.99 (292.9%) stddev-sans-first:118927.91 09:52:40 INFO - PROCESS | 25718 | Values: 577.2 602.7 607.3 616.1 629.4 31531.2 32234.2 34266.0 34281.5 43056.4 39695.2 44254.1 44258.0 44266.8 44274.1 8881.3 9037.4 9037.9 9038.9 9043.1 8905.9 8970.8 8990.8 8996.6 8997.6 9003.8 9030.2 9054.7 9079.4 9083.3 8936.7 8950.6 8954.4 8989.1 8995.7 8773.0 8790.1 8813.7 8814.9 8823.0 42044.4 42443.8 42982.4 43110.8 43136.0 3358.8 3360.6 3389.9 3584.6 3600.2 31493.5 31626.8 31782.0 31927.7 31931.0 3805.4 3928.5 3944.1 4027.2 4042.2 329386.0 331571.0 331762.0 332358.0 333274.0 56241.0 56460.7 56530.7 56621.0 56993.7 61027.4 61082.0 61131.0 61197.7 61325.4 15519.2 15766.6 15769.5 15770.7 15779.8 11097.0 11114.4 11122.9 11131.3 11141.7 15964.4 16864.2 16891.7 16897.3 17099.1 5315.6 6148.4 6215.4 6286.8 6300.3 898.4 958.3 1058.3 1072.5 1079.7 31081.0 31992.5 32233.4 32332.0 32532.5 1091.0 1098.9 1102.0 1118.4 1157.0 16105.8 16781.3 16783.6 16859.8 16960.0 504989.0 515418.0 515593.0 516038.0 516259.0 55784.5 55871.7 55873.7 56074.4 56091.7 1203.0 1230.8 1284.2 1299.1 1311.9 28031.9 31079.0 32284.4 32519.8 33386.2 50921.0 56147.7 56252.7 56309.0 56333.7 471491.0 476123.0 476237.0 476346.0 477488.0 27002.9 27795.0 27878.0 28139.6 29748.4 46240.8 46602.8 47202.3 47350.5 47413.0 37037.4 41398.4 41949.6 42089.5 42588.1 39326.0 39976.4 39995.8 40204.0 41461.6 39688.4 40055.8 40462.8 40566.0 40658.2 34022.1 36266.0 36466.5 37058.0 37152.6 27433.7 27456.5 27528.2 27759.7 27808.9 09:52:40 INFO - PROCESS | 25718 | 09:52:40 INFO - PROCESS | 25718 | [#2] jquery.html Cycles:180 Average:27349.40 Median:469282.00 stddev:78518.41 (16.7%) stddev-sans-first:78712.55 09:52:40 INFO - PROCESS | 25718 | Values: 373.7 375.6 397.1 400.9 412.8 13701.5 15323.4 17740.8 18909.8 19439.8 18063.5 18262.3 18274.1 18772.4 18780.7 5511.6 5709.1 5751.0 5752.5 5758.2 5395.9 5638.7 5658.7 5664.0 5666.3 5626.5 5634.7 5635.0 5635.5 5639.3 5544.3 5806.9 5814.2 5841.5 5845.3 5430.8 5555.2 5623.2 5627.9 5627.9 12733.6 12747.8 12913.4 12917.5 12931.3 2024.4 2092.2 2191.8 2435.4 2540.4 9084.6 9703.1 9830.6 11671.9 12441.6 1721.9 1725.8 1796.3 1797.7 1862.2 65996.3 77009.2 83694.0 87046.6 88577.6 9643.7 9743.4 10090.1 10241.7 10613.4 14585.6 14594.9 14599.0 14603.4 14612.2 7209.1 7435.3 7474.9 7550.2 7693.8 7012.3 7122.5 7245.3 7276.5 7413.7 4178.4 4285.3 4325.8 4565.6 4612.7 1915.1 1924.7 1927.9 1987.3 1987.6 825.7 949.0 959.7 960.6 1064.9 17418.0 18495.4 18547.4 18638.4 18642.7 944.3 944.8 957.6 977.6 979.9 9763.1 10054.7 10281.6 10310.5 10346.7 468437.0 468820.0 469744.0 482896.0 488151.0 56200.0 87938.0 89582.0 92734.0 94492.5 1004.8 1025.4 1048.3 1063.7 1063.7 15954.8 16283.5 16404.6 16579.4 16782.8 37102.8 40905.8 40925.0 41754.8 42720.6 52310.7 53893.0 53900.5 56907.1 58494.0 5009.8 5756.9 7468.3 7579.5 7793.1 11134.4 11302.7 11305.2 11441.2 11556.5 9627.3 9705.2 9854.0 10082.9 10460.4 10012.1 10013.7 10108.7 10131.4 10204.5 5573.2 5966.6 9349.8 10425.7 10463.2 6768.5 7559.7 7668.3 7738.6 7984.6 8141.8 9548.6 9633.9 9794.3 9964.5 09:52:40 INFO - PROCESS | 25718 | 09:52:40 INFO - PROCESS | 25718 | [#3] mootools.html Cycles:180 Average:5785.35 Median:4732.92 stddev:3353.95 (70.9%) stddev-sans-first:3346.71 09:52:40 INFO - PROCESS | 25718 | Values: 1338.4 1439.5 1492.5 1624.1 1802.4 7921.0 8578.8 8867.3 8937.8 9128.2 8948.9 8965.1 9020.5 9042.9 9050.1 2248.1 2271.3 2272.1 2276.4 2280.7 2253.4 2279.4 2305.8 2311.2 2316.2 2300.1 2325.7 2325.9 2330.9 2332.0 2193.8 2196.6 2197.5 2200.3 2203.4 2206.4 2233.7 2235.4 2235.8 2244.6 8956.6 8959.0 9054.4 9060.8 9068.6 4932.9 5090.0 5093.1 5134.0 5204.4 3120.8 3270.8 3340.8 3347.8 3355.9 1108.5 1151.7 1153.3 1161.1 1164.9 12464.9 12469.3 12471.3 12479.4 12560.1 4325.2 4424.2 4562.0 4565.3 4632.3 9892.5 9899.0 9901.4 9919.1 9921.5 5140.8 5142.5 5145.7 5147.8 5152.8 4309.8 4424.6 4664.0 4801.9 4806.2 3750.0 3785.2 3826.3 3864.2 3884.6 1154.4 1161.9 1169.0 1179.2 1180.3 878.0 922.4 964.9 990.2 1009.3 5852.6 6061.5 6107.8 6129.3 6291.0 440.9 456.5 461.5 463.3 464.8 4171.7 4302.1 4316.7 4321.9 4328.0 10987.8 11207.0 11233.4 11257.1 11335.3 10428.1 10950.0 11157.7 11173.0 11188.3 8121.5 8270.7 8339.5 8343.8 8344.1 6147.5 6202.7 6227.8 6402.3 6472.0 9953.4 9999.5 10100.2 10150.1 10277.6 9967.1 10110.8 10373.6 10469.7 10588.0 7579.4 7688.3 7824.5 7828.9 7894.6 7857.1 8090.0 8327.7 8341.9 8508.6 7535.5 7553.6 7762.9 8028.2 8033.3 7388.1 7398.6 7413.4 7434.3 7686.0 6581.5 6791.3 6791.5 6797.9 6804.7 5189.9 5211.8 5228.2 5264.3 5525.8 5195.8 5276.2 5297.1 5316.6 5558.6 09:52:40 INFO - PROCESS | 25718 | 09:52:40 INFO - PROCESS | 25718 | [#4] prototype.html Cycles:180 Average:4548.15 Median:5088.12 stddev:1334.95 (26.2%) stddev-sans-first:1300.42 09:52:40 INFO - PROCESS | 25718 | Values: 319.6 320.7 330.3 332.8 332.8 5081.0 5210.4 5272.3 5282.4 5293.3 5021.3 5128.8 5132.8 5156.3 5160.2 4082.7 4088.5 4091.9 4100.6 4102.1 3808.4 3856.9 4101.2 4102.5 4102.6 4017.3 4023.4 4028.6 4038.5 4063.5 4037.3 4041.3 4044.3 4049.4 4059.9 3598.7 3604.3 3606.7 3611.7 3632.3 4947.4 5036.9 5077.0 5082.4 5093.8 4653.4 4663.6 4667.6 4669.6 4682.2 5397.8 5399.7 5401.5 5404.3 5441.7 3797.8 3935.5 3951.6 3958.8 3964.5 5645.5 5649.5 5650.7 5654.9 5660.8 4049.4 4076.8 4091.3 4095.9 4104.1 3972.9 4090.7 4098.1 4103.7 4155.7 4615.7 4619.4 4624.0 4626.7 4628.7 5449.7 5460.6 5465.3 5467.3 5468.7 1323.0 1375.2 1386.8 1409.7 1424.3 868.2 883.7 891.9 898.3 902.0 4691.7 4695.3 4731.4 4740.0 4767.7 4905.3 4943.3 4946.8 4951.7 4960.3 4665.3 4683.4 4688.4 4704.7 4764.5 2772.7 2982.9 2997.5 3001.8 3002.4 5823.8 5825.5 5844.6 5847.9 5878.1 5521.6 5639.6 5679.9 5781.5 5807.8 5716.9 5721.1 5789.7 5808.6 5827.5 5593.2 5634.7 5651.0 5666.4 5680.6 5396.8 5533.4 5569.2 5570.3 5606.8 5678.3 5788.1 5800.5 5854.8 5858.8 3778.3 3987.3 3990.4 3995.4 4028.1 5205.4 5521.7 5572.2 5585.9 5591.2 5430.0 5430.6 5445.1 5460.1 5494.6 5211.5 5405.5 5410.6 5488.9 5504.7 5358.3 5360.2 5360.8 5368.3 5384.6 5458.7 5462.4 5475.3 5491.2 5517.6 5188.9 5305.3 5329.9 5341.8 5346.4 09:52:40 INFO - PROCESS | 25718 | 09:52:40 INFO - PROCESS | 25718 | [#5] yui.html Cycles:180 Average:19468.82 Median:4501.38 stddev:27821.85 (618.1%) stddev-sans-first:27864.38 09:52:40 INFO - PROCESS | 25718 | Values: 747.6 759.8 766.6 824.4 836.9 14910.8 15438.8 15453.5 15458.1 15458.7 15219.3 15334.6 15371.6 15376.9 15377.8 643.2 673.4 778.1 795.0 796.8 758.4 787.5 789.0 791.8 792.3 832.0 833.3 835.2 835.9 836.5 680.4 832.1 832.2 832.3 834.0 515.5 588.3 609.6 611.6 613.7 14193.9 14210.8 14224.9 14225.1 14320.5 13222.0 13507.2 13711.4 13788.5 14273.4 10617.8 10950.5 11268.8 11421.9 11422.9 3582.8 3629.9 3664.5 3691.5 3808.0 72923.0 73107.6 76066.0 76412.0 76586.7 10466.4 10548.6 10558.5 10601.0 11156.6 21490.7 21806.8 21863.7 21903.8 21906.1 7964.6 8003.3 8549.7 8554.7 8554.9 4462.0 4497.6 4498.1 4504.7 4563.6 3211.1 3248.9 3270.2 3275.1 3277.9 611.0 612.7 617.1 625.9 632.0 542.6 569.0 615.3 661.5 689.7 7782.8 8080.7 8351.4 8663.2 9102.9 683.4 684.6 706.0 729.5 737.9 4145.6 4360.3 4587.9 4673.1 4715.7 107788.0 108155.0 112785.4 114791.4 115367.4 56851.7 58394.7 63969.0 92083.0 95212.0 77646.0 77792.6 78226.6 88294.6 92198.0 8347.6 9095.3 9226.2 9229.7 9248.4 42871.8 52740.5 53481.2 54099.0 56480.0 46119.5 61256.0 79277.6 79396.0 81214.6 12958.5 13137.8 13242.9 13358.5 13730.2 13297.9 13327.9 13528.9 14009.8 14089.7 12163.8 12191.0 12277.8 12844.9 13057.9 11575.8 12308.7 12514.7 12574.0 13035.9 12265.8 12276.9 12616.2 12702.9 13062.9 10394.2 10572.4 10799.3 10886.7 10954.6 10649.3 10938.0 11171.9 11186.5 11227.7 09:52:40 INFO - PROCESS | 25718 | -------- Summary: end -------- 09:52:40 INFO - PROCESS | 25718 | 09:52:41 INFO - TEST-INFO | 25718: exit 0 09:52:41 INFO - TEST-OK | dromaeo_css | took 1116245ms 09:52:41 INFO - TEST-START | kraken 09:52:41 INFO - operating with platform_type : linux_ 09:52:41 INFO - Initialising browser for kraken test... 09:52:41 INFO - TEST-INFO | started process 26023 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpj5GBXM/profile http://localhost:34221/getInfo.html) 09:52:45 INFO - PROCESS | 26023 | __metrics Screen width/height:1600/1200 09:52:45 INFO - PROCESS | 26023 | colorDepth:24 09:52:45 INFO - PROCESS | 26023 | Browser inner width/height: 1024/697 09:52:45 INFO - PROCESS | 26023 | __metrics 09:52:45 INFO - PROCESS | 26023 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:52:45 INFO - PROCESS | 26023 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:52:46 INFO - PROCESS | 26023 | console.error: PushService: 09:52:46 INFO - PROCESS | 26023 | stateChangeProcessEnqueue: Error transitioning state 09:52:46 INFO - PROCESS | 26023 | UnknownError 09:52:46 INFO - TEST-INFO | 26023: exit 0 09:52:46 INFO - Browser initialized. 09:52:46 INFO - Running cycle 1/1 for kraken test... 09:52:46 INFO - TEST-INFO | started process 26107 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpj5GBXM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:52:58 INFO - PROCESS | 26107 | RSS: Main: 173277184 09:52:58 INFO - PROCESS | 26107 | 09:53:01 INFO - PROCESS | 26107 | Cycle 1(1): loaded http://localhost:34221/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34221/tests/kraken/driver.html?testName=audio-beat-detection) 09:53:02 INFO - PROCESS | 26107 | RSS: Main: 233029632 09:53:02 INFO - PROCESS | 26107 | 09:53:04 INFO - PROCESS | 26107 | Cycle 1(1): loaded http://localhost:34221/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34221/tests/kraken/driver.html?testName=audio-dft) 09:53:05 INFO - PROCESS | 26107 | RSS: Main: 217387008 09:53:05 INFO - PROCESS | 26107 | 09:53:08 INFO - PROCESS | 26107 | Cycle 1(1): loaded http://localhost:34221/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34221/tests/kraken/driver.html?testName=audio-fft) 09:53:09 INFO - PROCESS | 26107 | RSS: Main: 298737664 09:53:09 INFO - PROCESS | 26107 | 09:53:11 INFO - PROCESS | 26107 | Cycle 1(1): loaded http://localhost:34221/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34221/tests/kraken/driver.html?testName=audio-oscillator) 09:53:11 INFO - PROCESS | 26107 | RSS: Main: 208343040 09:53:11 INFO - PROCESS | 26107 | 09:53:14 INFO - PROCESS | 26107 | Cycle 1(1): loaded http://localhost:34221/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34221/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:53:14 INFO - PROCESS | 26107 | RSS: Main: 212566016 09:53:14 INFO - PROCESS | 26107 | 09:53:17 INFO - PROCESS | 26107 | Cycle 1(1): loaded http://localhost:34221/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34221/tests/kraken/driver.html?testName=imaging-darkroom) 09:53:18 INFO - PROCESS | 26107 | RSS: Main: 249073664 09:53:18 INFO - PROCESS | 26107 | 09:53:21 INFO - PROCESS | 26107 | Cycle 1(1): loaded http://localhost:34221/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34221/tests/kraken/driver.html?testName=imaging-desaturate) 09:53:22 INFO - PROCESS | 26107 | RSS: Main: 248037376 09:53:22 INFO - PROCESS | 26107 | 09:53:24 INFO - PROCESS | 26107 | Cycle 1(1): loaded http://localhost:34221/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34221/tests/kraken/driver.html?testName=json-parse-financial) 09:53:25 INFO - PROCESS | 26107 | RSS: Main: 248381440 09:53:25 INFO - PROCESS | 26107 | 09:53:27 INFO - PROCESS | 26107 | Cycle 1(1): loaded http://localhost:34221/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34221/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:53:27 INFO - PROCESS | 26107 | RSS: Main: 209920000 09:53:27 INFO - PROCESS | 26107 | 09:53:29 INFO - PROCESS | 26107 | Cycle 1(1): loaded http://localhost:34221/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34221/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:53:29 INFO - PROCESS | 26107 | RSS: Main: 216981504 09:53:29 INFO - PROCESS | 26107 | 09:53:32 INFO - PROCESS | 26107 | Cycle 1(1): loaded http://localhost:34221/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34221/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:53:32 INFO - PROCESS | 26107 | RSS: Main: 222375936 09:53:32 INFO - PROCESS | 26107 | 09:53:35 INFO - PROCESS | 26107 | Cycle 1(1): loaded http://localhost:34221/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:53:35 INFO - PROCESS | 26107 | RSS: Main: 223162368 09:53:35 INFO - PROCESS | 26107 | 09:53:38 INFO - PROCESS | 26107 | Cycle 1(1): loaded http://localhost:34221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:53:39 INFO - PROCESS | 26107 | RSS: Main: 221884416 09:53:39 INFO - PROCESS | 26107 | 09:53:40 INFO - PROCESS | 26107 | Cycle 1(1): loaded http://localhost:34221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34221/tests/kraken/driver.html?testName=ai-astar) 09:53:41 INFO - PROCESS | 26107 | RSS: Main: 272842752 09:53:41 INFO - PROCESS | 26107 | 09:53:41 INFO - PROCESS | 26107 | __start_tp_report 09:53:41 INFO - PROCESS | 26107 | _x_x_mozilla_page_load 09:53:41 INFO - PROCESS | 26107 | _x_x_mozilla_page_load_details 09:53:41 INFO - PROCESS | 26107 | |i|pagename|runs| 09:53:41 INFO - PROCESS | 26107 | |0;ai-astar;109;112;111;110;110;111;112;112;111;112 09:53:41 INFO - PROCESS | 26107 | |1;audio-beat-detection;147;120;121;160;119;119;118;122;153;122 09:53:41 INFO - PROCESS | 26107 | |2;audio-dft;159;160;160;160;162;160;161;160;160;162 09:53:41 INFO - PROCESS | 26107 | |3;audio-fft;75;76;75;101;76;77;76;77;97;75 09:53:41 INFO - PROCESS | 26107 | |4;audio-oscillator;83;81;116;82;83;82;100;81;82;120 09:53:41 INFO - PROCESS | 26107 | |5;imaging-gaussian-blur;117;116;116;116;117;117;116;116;116;117 09:53:41 INFO - PROCESS | 26107 | |6;imaging-darkroom;146;145;146;146;146;145;146;145;145;145 09:53:41 INFO - PROCESS | 26107 | |7;imaging-desaturate;95;95;95;95;95;95;95;96;95;96 09:53:41 INFO - PROCESS | 26107 | |8;json-parse-financial;54;51;51;52;52;52;55;53;53;52 09:53:41 INFO - PROCESS | 26107 | |9;json-stringify-tinderbox;62;62;60;61;62;61;60;61;61;61 09:53:41 INFO - PROCESS | 26107 | |10;stanford-crypto-aes;79;79;78;79;79;79;79;77;78;78 09:53:41 INFO - PROCESS | 26107 | |11;stanford-crypto-ccm;124;129;123;121;123;122;128;132;164;133 09:53:41 INFO - PROCESS | 26107 | |12;stanford-crypto-pbkdf2;165;166;164;163;165;165;163;162;164;163 09:53:41 INFO - PROCESS | 26107 | |13;stanford-crypto-sha256-iterative;68;65;64;64;64;65;70;64;64;62 09:53:41 INFO - PROCESS | 26107 | __end_tp_report 09:53:41 INFO - PROCESS | 26107 | __start_cc_report 09:53:41 INFO - PROCESS | 26107 | _x_x_mozilla_cycle_collect,1603 09:53:41 INFO - PROCESS | 26107 | __end_cc_report 09:53:41 INFO - PROCESS | 26107 | __startTimestamp1474908821224__endTimestamp 09:53:41 INFO - PROCESS | 26107 | 09:53:41 INFO - PROCESS | 26107 | ------- Summary: start ------- 09:53:41 INFO - PROCESS | 26107 | Number of tests: 14 09:53:41 INFO - PROCESS | 26107 | 09:53:41 INFO - PROCESS | 26107 | [#0] ai-astar Cycles:10 Average:111.00 Median:111.50 stddev:1.05 (0.9%) stddev-sans-first:0.83 09:53:41 INFO - PROCESS | 26107 | Values: 109.0 112.0 111.0 110.0 110.0 111.0 112.0 112.0 111.0 112.0 09:53:41 INFO - PROCESS | 26107 | 09:53:41 INFO - PROCESS | 26107 | [#1] audio-beat-detection Cycles:10 Average:130.10 Median:122.00 stddev:16.37 (13.4%) stddev-sans-first:16.18 09:53:41 INFO - PROCESS | 26107 | Values: 147.0 120.0 121.0 160.0 119.0 119.0 118.0 122.0 153.0 122.0 09:53:41 INFO - PROCESS | 26107 | 09:53:41 INFO - PROCESS | 26107 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.00 stddev:0.97 (0.6%) stddev-sans-first:0.88 09:53:41 INFO - PROCESS | 26107 | Values: 159.0 160.0 160.0 160.0 162.0 160.0 161.0 160.0 160.0 162.0 09:53:41 INFO - PROCESS | 26107 | 09:53:41 INFO - PROCESS | 26107 | [#3] audio-fft Cycles:10 Average:80.50 Median:76.00 stddev:9.82 (12.9%) stddev-sans-first:10.22 09:53:41 INFO - PROCESS | 26107 | Values: 75.0 76.0 75.0 101.0 76.0 77.0 76.0 77.0 97.0 75.0 09:53:41 INFO - PROCESS | 26107 | 09:53:41 INFO - PROCESS | 26107 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:82.00 stddev:15.34 (18.7%) stddev-sans-first:16.00 09:53:41 INFO - PROCESS | 26107 | Values: 83.0 81.0 116.0 82.0 83.0 82.0 100.0 81.0 82.0 120.0 09:53:41 INFO - PROCESS | 26107 | 09:53:41 INFO - PROCESS | 26107 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 09:53:41 INFO - PROCESS | 26107 | Values: 117.0 116.0 116.0 116.0 117.0 117.0 116.0 116.0 116.0 117.0 09:53:41 INFO - PROCESS | 26107 | 09:53:41 INFO - PROCESS | 26107 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 09:53:41 INFO - PROCESS | 26107 | Values: 146.0 145.0 146.0 146.0 146.0 145.0 146.0 145.0 145.0 145.0 09:53:41 INFO - PROCESS | 26107 | 09:53:41 INFO - PROCESS | 26107 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:53:41 INFO - PROCESS | 26107 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 96.0 09:53:41 INFO - PROCESS | 26107 | 09:53:41 INFO - PROCESS | 26107 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:52.50 stddev:1.27 (2.4%) stddev-sans-first:1.22 09:53:41 INFO - PROCESS | 26107 | Values: 54.0 51.0 51.0 52.0 52.0 52.0 55.0 53.0 53.0 52.0 09:53:41 INFO - PROCESS | 26107 | 09:53:41 INFO - PROCESS | 26107 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:61.00 stddev:0.74 (1.2%) stddev-sans-first:0.71 09:53:41 INFO - PROCESS | 26107 | Values: 62.0 62.0 60.0 61.0 62.0 61.0 60.0 61.0 61.0 61.0 09:53:41 INFO - PROCESS | 26107 | 09:53:41 INFO - PROCESS | 26107 | [#10] stanford-crypto-aes Cycles:10 Average:78.50 Median:79.00 stddev:0.71 (0.9%) stddev-sans-first:0.73 09:53:41 INFO - PROCESS | 26107 | Values: 79.0 79.0 78.0 79.0 79.0 79.0 79.0 77.0 78.0 78.0 09:53:41 INFO - PROCESS | 26107 | 09:53:41 INFO - PROCESS | 26107 | [#11] stanford-crypto-ccm Cycles:10 Average:129.90 Median:128.50 stddev:12.71 (9.9%) stddev-sans-first:13.30 09:53:41 INFO - PROCESS | 26107 | Values: 124.0 129.0 123.0 121.0 123.0 122.0 128.0 132.0 164.0 133.0 09:53:41 INFO - PROCESS | 26107 | 09:53:41 INFO - PROCESS | 26107 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.00 Median:164.50 stddev:1.25 (0.8%) stddev-sans-first:1.27 09:53:41 INFO - PROCESS | 26107 | Values: 165.0 166.0 164.0 163.0 165.0 165.0 163.0 162.0 164.0 163.0 09:53:41 INFO - PROCESS | 26107 | 09:53:41 INFO - PROCESS | 26107 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:64.50 stddev:2.31 (3.6%) stddev-sans-first:2.18 09:53:41 INFO - PROCESS | 26107 | Values: 68.0 65.0 64.0 64.0 64.0 65.0 70.0 64.0 64.0 62.0 09:53:41 INFO - PROCESS | 26107 | -------- Summary: end -------- 09:53:41 INFO - PROCESS | 26107 | 09:53:42 INFO - TEST-INFO | 26107: exit 0 09:53:42 INFO - TEST-OK | kraken | took 61103ms 09:53:42 INFO - SUITE-END | took 1177s 09:53:42 INFO - Completed test suite (00:19:37) 09:53:42 INFO - javascript benchmark 09:53:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3003.354278830052, 3024.6219222597174, 3030.6514750803663, 3073.692630736928, 3077.2922227887584, 5275.340582427197, 5297.735113244342, 5343.839684809457, 5374.247605335257, 5396.163594642837, 5345.49217824307, 5374.032674118657, 5384.273123128386, 5417.295751552302, 5490.396056433789, 4654.501775936421, 4878.926816097762, 4898.510148985109, 4906.969536397361, 4924.0, 5015.724135172564, 5020.824271150511, 5024.572911302544, 5031.0, 5037.269595908591, 4789.664723469358, 4806.975965120152, 4837.975810120962, 4935.654504184727, 4961.131801934665, 4794.113089078532, 4838.927416088762, 4846.0550192712435, 4850.83022094225, 4920.286558449041, 4792.0, 4792.424909010941, 4806.735629540374, 4815.759212039384, 4849.1513985052825, 5239.345081864767, 5275.340582427197, 5281.36623605166, 5287.788488460457, 5342.839714808562, 5654.151877218409, 5670.461306175948, 5729.312482502085, 5731.541476681897, 5821.3014438267255, 5725.370209276976, 5734.684592347419, 5739.885202295973, 5742.454466825645, 5755.0504166812425, 5341.519263266286, 5346.117890548055, 5364.919526207071, 5366.812161574326, 5429.511343979061, 5788.21859049023, 5839.182514447895, 5859.619124756877, 5865.501432378211, 5908.675022873783, 5646.85882852932, 5689.203511508392, 5691.9715401423555, 5698.943010569924, 5757.539396848242, 5734.541236701054, 5771.451712087345, 5791.652500849962, 5793.391693872167, 5801.361850196475, 5552.972235138798, 5618.494335509824, 5672.4894759471845, 5729.598928075078, 5755.482006619341, 5766.88466230673, 5771.682557459296, 5781.046127389018, 5831.329397119301, 5863.794767183128, 4431.911361772811, 4456.197884380777, 4463.062756821104, 4501.774911254424, 4519.683622146484, 3904.3557812960544, 3914.6085391460624, 3925.7055720821054, 3955.4660120883887, 4019.879403617896, 5431.592630552725, 5453.15476101205, 5455.509004189563, 5506.587005974568, 5527.309086364205, 5677.943220567824, 5707.200991861143, 5730.799422020293, 5762.827115186551, 5765.04876695349, 5324.334458192726, 5328.3605967284175, 5409.729513524387, 5419.891602167934, 5482.23248745176, 4724.598409135262, 4823.1800593899125, 4869.245266983623, 4878.609711223094, 4938.728369939688, 5721.0, 5747.5689323300085, 5753.51095156908, 5755.165501002331, 5761.222234998306, 5559.694216818114, 5563.833085007456, 5579.414161513145, 5582.5291015819, 5651.011073062115, 5781.682007489629, 5869.295684517885, 5901.616394934315, 5914.615549989237, 5959.73181206838, 5600.775968961196, 5635.267415235993, 5641.689707065987, 5647.915281270866, 5661.603687741818, 5684.118961560965, 5740.425957404226, 5750.223719797774, 5752.7698892043845, 5758.856028599319, 5438.184272359178, 5491.066518691752, 5533.667979921218, 5573.052581061311, 5583.413741557078, 5597.608167428241, 5600.971995139998, 5602.047651899268, 5603.859903502445, 5649.265595472727, 5537.335519737658, 5541.33503979525, 5542.8337149885565, 5543.6396634218645, 5544.112941929272, 5478.479544443271, 5527.806526771544, 5607.74765135578, 5608.559390425591, 5650.067738823048, 5528.143137813645, 5549.889002219852, 5552.972235138798, 5631.943680563142, 5636.5208957237455], "value": 5274.127235003738, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.2265164679332, 602.6981719485908, 607.3471018654946, 616.1127975714967, 629.4177885455955, 31531.21171970699, 32234.194145146383, 34266.0, 34281.48577771335, 43056.3541546877, 39695.20609587814, 44254.11491770171, 44258.0, 44266.77866610671, 44274.114517709626, 8881.333899957497, 9037.412568183046, 9037.864432033492, 9038.86441703375, 9043.050479699636, 8905.910940890606, 8970.820583588324, 8990.820183596325, 8996.595153218088, 8997.550122493849, 9003.774905627379, 9030.187283144516, 9054.728358149234, 9079.364444488889, 9083.273338132967, 8936.687215947442, 8950.552472376356, 8954.37319387643, 8989.05614910438, 8995.730128096167, 8772.956135219347, 8790.120987901159, 8813.691520796805, 8814.86777698335, 8823.0, 42044.36933446001, 42443.787781061204, 42982.35526467074, 43110.78444607788, 43136.0, 3358.781679190845, 3360.5799275090612, 3389.8983030509125, 3584.605693373727, 3600.1899572596217, 31493.48052155822, 31626.841865790753, 31782.0, 31927.6807231927, 31931.0, 3805.3721136012523, 3928.46965659638, 3944.112574670705, 4027.154297597508, 4042.191561687674, 329386.0, 331571.0, 331762.0, 332358.0, 333274.0, 56241.0, 56460.71769641207, 56530.717346413, 56621.0, 56993.71503142541, 61027.38972610217, 61082.0, 61131.0, 61197.69401152966, 61325.38674613197, 15519.224038798015, 15766.605834854221, 15769.526914192593, 15770.684586308209, 15779.763303550455, 11097.0, 11114.388708620942, 11122.943320384551, 11131.332120072822, 11141.72145696364, 15964.36142554308, 16864.156792160342, 16891.662166756596, 16897.324107035827, 17099.145042747812, 5315.574754019669, 6148.385161483905, 6215.352931473163, 6286.811395658137, 6300.30696623371, 898.4025622960788, 958.3387462650749, 1058.2909450668071, 1072.4691277817374, 1079.7084787107594, 31081.0, 31992.520112198337, 32233.35533289321, 32332.0, 32532.512012319836, 1090.9563617455372, 1098.9010989011028, 1101.983470247947, 1118.3569447567681, 1157.0338767129542, 16105.758413623806, 16781.328746849988, 16783.580410489834, 16859.831401685828, 16960.0, 504989.0, 515418.0, 515593.0, 516038.0, 516259.0, 55784.48969796369, 55871.72064139653, 55873.720631397395, 56074.43925560692, 56091.71954140203, 1202.963911082669, 1230.7600017996406, 1284.1717092475487, 1299.123091912955, 1311.8819306262483, 28031.859840700665, 31079.0, 32284.35431291408, 32519.837400812845, 33386.16534586558, 50921.0, 56147.71926140343, 56252.71873640606, 56309.0, 56333.71833140808, 471491.0, 476123.0, 476237.0, 476346.0, 477488.0, 27002.864985674947, 27795.0, 27878.0, 28139.57790633101, 29748.405031899674, 46240.768796155804, 46602.76698616621, 47202.26975294365, 47350.526494734615, 47413.0, 37037.44443833399, 41398.37902431526, 41949.580504194564, 42089.5254612322, 42588.148237035704, 39326.0, 39976.40035399413, 39995.80002099971, 40204.0, 41461.58538414577, 39688.40467393049, 40055.79972100121, 40462.797686011385, 40566.0, 40658.18683626252, 34022.14944626404, 36266.0, 36466.45300320444, 37058.0, 37152.62847371492, 27433.725662743116, 27456.45087098287, 27528.174154775388, 27759.722402775715, 27808.86095569509], "value": 19579.851834493667, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.66930266713985, 375.59811002227593, 397.1164159744568, 400.89977505623597, 412.7564736805284, 13701.451941922323, 15323.38706451742, 17740.82259177408, 18909.810901890978, 19439.805601943975, 18063.458096257134, 18262.269509219648, 18274.08629568523, 18772.436826895217, 18780.71828922567, 5511.58663100267, 5709.143628455723, 5750.971245143789, 5752.482276595106, 5758.157389635318, 5395.946040539584, 5638.718064096779, 5658.745356458949, 5664.037113690682, 5666.263385759846, 5626.54987600993, 5634.661920284776, 5635.0, 5635.520980716624, 5639.323281206241, 5544.251526043993, 5806.941930580703, 5814.244148260741, 5841.474267315962, 5845.269341332333, 5430.755615997289, 5555.194496797982, 5623.240862483594, 5627.887442251173, 5627.915581266284, 12733.554325598652, 12747.808782868264, 12913.354332283443, 12917.483300667962, 12931.349073439922, 2024.44327809852, 2092.184048221195, 2191.802737753594, 2435.366804630809, 2540.352210186409, 9084.636614535411, 9703.12671859536, 9830.557624906896, 11671.949524542832, 12441.564545240964, 1721.8622510199154, 1725.8187890271465, 1796.3173993882242, 1797.703378942486, 1862.2178684952353, 65996.34003659998, 77009.22990770076, 83694.0, 87046.56476717576, 88577.55711221466, 9643.662471813454, 9743.366681165702, 10090.09189172978, 10241.692749217535, 10613.416262105658, 14585.635359116108, 14594.854051459562, 14599.029131067382, 14603.415863365346, 14612.196329201995, 7209.0988626421695, 7435.293647103516, 7474.925250747532, 7550.169481357047, 7693.846123077507, 7012.333828286304, 7122.501424900311, 7245.311695388929, 7276.490645654859, 7413.666385012687, 4178.415021896937, 4285.343087285841, 4325.783710814447, 4565.56627120423, 4612.723236605814, 1915.0711904726197, 1924.70167124096, 1927.9174205382042, 1987.2810019333172, 1987.6012019446907, 825.6944930375679, 948.9715308540755, 959.6928982725461, 960.644561512245, 1064.8509208710786, 17418.0, 18495.44513664592, 18547.35084272071, 18638.44084677435, 18642.720359194624, 944.2776276148752, 944.8110377924443, 957.5690939077303, 977.6333874796951, 979.9412035277907, 9763.07250811186, 10054.69835904924, 10281.588736450607, 10310.484475776182, 10346.741331466623, 468437.0, 468820.0, 469744.0, 482896.0, 488151.0, 56200.0, 87938.0, 89582.0, 92734.0, 94492.52753736325, 1004.7689031522791, 1025.3642741500319, 1048.3238311289329, 1063.6649455421518, 1063.7393838509615, 15954.840451595335, 16283.511494655178, 16404.589885252964, 16579.419720309732, 16782.83217167813, 37102.814485927396, 40905.79547102245, 40925.0, 41754.79122604368, 42720.57279427166, 52310.73844630752, 53893.0, 53900.46099539111, 56907.14639280497, 58494.0, 5009.849704508871, 5756.9061878243, 7468.253174682488, 7579.54522728638, 7793.06483222017, 11134.387608681276, 11302.660920172408, 11305.15211359135, 11441.199116061796, 11556.537738490366, 9627.326087173831, 9705.223582113555, 9854.0, 10082.949585252027, 10460.37237765706, 10012.148967337858, 10013.749656258651, 10108.696739097839, 10131.442770647685, 10204.540795664065, 5573.219749235192, 5966.612170208922, 9349.813003740022, 10425.739356516147, 10463.162946964072, 6768.4585233182215, 7559.659815308434, 7668.271514206142, 7738.574378409242, 7984.560849153352, 8141.837163256583, 9548.570314335733, 9633.9036609633, 9794.314397992071, 9964.50177491137], "value": 8469.112929800387, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1338.4311667541306, 1439.5465428390057, 1492.4776328285102, 1624.1310898669208, 1802.3781795280631, 7920.960395198023, 8578.828423431536, 8867.334949878768, 8937.776555586113, 9128.178463938244, 8948.8657670135, 8965.103489651032, 9020.458772473656, 9042.864357034634, 9050.140236677522, 2248.123231939545, 2271.341311019802, 2272.1138755885218, 2276.430892276931, 2280.680704701343, 2253.4141123307895, 2279.361778701966, 2305.8270629702756, 2311.2141871763597, 2316.1546035696933, 2300.148944890393, 2325.6744055832196, 2325.9302220933314, 2330.860148391102, 2331.9533609327805, 2193.7696541863033, 2196.5936301784222, 2197.48359135603, 2200.2629119244993, 2203.4160947348964, 2206.4042708468623, 2233.6872837802557, 2235.3629215673454, 2235.7540670526228, 2244.5510897820336, 8956.641734330618, 8958.955205223998, 9054.366194366397, 9060.81878362429, 9068.591913363915, 4932.8520144395725, 5090.032893750175, 5093.108705976476, 5134.0, 5204.375474943031, 3120.7659425543175, 3270.8201048942296, 3340.849661765202, 3347.815870127142, 3355.916102097467, 1108.5031833319704, 1151.694800877768, 1153.319541470528, 1161.1291531351487, 1164.8776878427727, 12464.875351246463, 12469.314187719672, 12471.251724896536, 12479.376031198404, 12560.120791544505, 4325.199838029975, 4424.225760491901, 4561.977190114028, 4565.269556870885, 4632.258838585877, 9892.505374731234, 9899.0, 9901.356411833207, 9919.057689519628, 9921.50392480373, 5140.794368225229, 5142.51146141116, 5145.716985565755, 5147.794088236504, 5152.793888244503, 4309.784510774449, 4424.60178583929, 4663.976680116645, 4801.855944321676, 4806.2310030395665, 3749.9687585914094, 3785.2240290740333, 3826.2921359548573, 3864.246471937945, 3884.5821275234903, 1154.440096553171, 1161.895429411357, 1169.023865072657, 1179.2099293473393, 1180.2800291821982, 878.0297770963122, 922.4327038871046, 964.864918911353, 990.2028866762181, 1009.3237530854345, 5852.6195797273895, 6061.484773794189, 6107.786227482017, 6129.325774164839, 6291.0, 440.90740944402035, 456.4568163885033, 461.5107985535343, 463.28191303479474, 464.846600621794, 4171.728837625545, 4302.139572085595, 4316.69783115179, 4321.935170972438, 4327.956720432755, 10987.835182472269, 11207.047400971009, 11233.38216398106, 11257.099432045417, 11335.263207891461, 10428.113610343205, 10950.0, 11157.72105697364, 11173.0, 11188.272762270426, 8121.4721043132, 8270.710525131712, 8339.45793523419, 8343.8331233375, 8344.08215096339, 6147.508199344131, 6202.689865506797, 6227.844303892439, 6402.327755585597, 6471.967640161769, 9953.353032052893, 9999.450030248407, 10100.242481813746, 10150.13723833453, 10277.640282590073, 9967.10296073357, 10110.848337275094, 10373.636922707668, 10469.68590942273, 10588.0, 7579.393648508217, 7688.308052275322, 7824.491408058458, 7828.882566761389, 7894.605269736376, 7857.096433910174, 8090.0, 8327.6668933242, 8341.916580834113, 8508.617112230088, 7535.472516923763, 7553.5845528494265, 7762.904015915558, 8028.197180281925, 8033.277005069572, 7388.076490438695, 7398.556086634819, 7413.369863561442, 7434.293742094493, 7686.039245094364, 6581.539292249497, 6791.286914874065, 6791.524593278423, 6797.9320206799275, 6804.727810887707, 5189.92215116766, 5211.791528338824, 5228.215767634885, 5264.31563896691, 5525.751341189576, 5195.76619052136, 5276.182191760283, 5297.125974214366, 5316.6012549058205, 5558.583106266965], "value": 4522.306628208332, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.64359738891136, 320.69533942754373, 330.2684553713525, 332.7903420844867, 332.8202770503928, 5081.0, 5210.374755029397, 5272.3145991021165, 5282.418933917266, 5293.285406470125, 5021.2970184174155, 5128.768985491304, 5132.794688212476, 5156.3296771419755, 5160.174372100455, 4082.7142100052934, 4088.48725971391, 4091.9181616367805, 4100.63094321511, 4102.138550904314, 3808.352580061396, 3856.9421458678144, 4101.220768054075, 4102.487189101363, 4102.5897410258885, 4017.27689015977, 4023.3964905264297, 4028.6374226319626, 4038.5153781546255, 4063.4717486726686, 4037.253108174997, 4041.2725709372303, 4044.3326851069537, 4049.3925911113274, 4059.8985025374454, 3598.704466392097, 3604.3151801157696, 3606.6934310583633, 3611.7291203159866, 3632.3280193164087, 4947.381577302837, 5036.924446133311, 5076.974615126937, 5082.440931497532, 5093.79624815007, 4653.395058642389, 4663.60359369454, 4667.6265898728025, 4669.603083737887, 4682.157211701892, 5397.757100930428, 5399.730013499348, 5401.540869026137, 5404.297441332641, 5441.700706461143, 3797.810109494514, 3935.5474120476224, 3951.6487689824794, 3958.841646334143, 3964.4647972523917, 5645.5483561314995, 5649.4633009864, 5650.6892120933335, 5654.858628534278, 5660.811229641739, 4049.372347286175, 4076.8381011411607, 4091.263572556909, 4095.9385609215888, 4104.138130992465, 3972.880813575597, 4090.652294554996, 4098.077932465171, 4103.712740108223, 4155.688323375698, 4615.699979501322, 4619.353290539327, 4623.953760462419, 4626.7223966562115, 4628.745419001987, 5449.730161902906, 5460.645058071292, 5465.262189604432, 5467.289252397163, 5468.671879687232, 1323.0, 1375.24361601119, 1386.7642500774894, 1409.6616811965055, 1424.2522675595187, 868.2272777228272, 883.7304622090196, 891.9375643705008, 898.3172788680649, 901.9897714559735, 4691.671582989158, 4695.347128872177, 4731.384919960452, 4740.0, 4767.69010014348, 4905.288733133676, 4943.28322393251, 4946.788036930903, 4951.653384263067, 4960.305557221992, 4665.323528088408, 4683.367745354401, 4688.437387513521, 4704.717716936994, 4764.4520880099235, 2772.695003549608, 2982.910512684623, 2997.4754417976765, 3001.8198908065588, 3002.384511175204, 5823.79616713413, 5825.465922323802, 5844.561657875676, 5847.94152058474, 5878.118282257696, 5521.641093328921, 5639.577031722637, 5679.886402271932, 5781.4507621775865, 5807.825765226966, 5716.885662286731, 5721.141828725725, 5789.681567513827, 5808.593398462067, 5827.533797296206, 5593.188987596857, 5634.661920284796, 5651.0, 5666.376698563073, 5680.602357834912, 5396.784128634968, 5533.446655334596, 5569.164625306236, 5570.275864137636, 5606.831795046155, 5678.290213723285, 5788.118861369988, 5800.477956983892, 5854.824355269348, 5858.765649373977, 3778.319902417591, 3987.302222111177, 3990.4014397840556, 3995.380715989026, 4028.0735430851655, 5205.401378841481, 5521.730002099459, 5572.247746554163, 5585.938671652373, 5591.245181900392, 5430.0, 5430.6198566101575, 5445.128779395278, 5460.071787796005, 5494.587905906993, 5211.5049070338255, 5405.5135037846785, 5410.565245639399, 5488.862778430571, 5504.669719816824, 5358.302771087937, 5360.16917377807, 5360.812371566976, 5368.2752828367675, 5384.623076384615, 5458.6724796512335, 5462.399136095106, 5475.342958844964, 5491.203775452457, 5517.586181036519, 5188.896222075462, 5305.257263982969, 5329.920051199313, 5341.78632854697, 5346.385165705994], "value": 4122.02054387142, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.5626758346367, 759.7530802489192, 766.5668897073153, 824.4229039672229, 836.899572051354, 14910.776338354934, 15438.84561154388, 15453.536393908173, 15458.149801760885, 15458.690826183498, 15219.315130819112, 15334.616634584114, 15371.615709607237, 15376.923115384407, 15377.84622153778, 643.2377632505472, 673.4040374268758, 778.0546635837457, 795.0, 796.8326651403212, 758.404652347907, 787.4881327137377, 788.9566073865964, 791.8178818871663, 792.3067316098413, 832.0, 833.2667252817512, 835.1648351648352, 835.9331253499736, 836.5399030533213, 680.4284401103097, 832.0930186097153, 832.2426591801432, 832.2883934236262, 833.995830020852, 515.4896652314219, 588.2676068295, 609.6433586351945, 611.6330201878882, 613.6686189457732, 14193.929030354886, 14210.786838197435, 14224.85775142256, 14225.07537010091, 14320.498782542665, 13222.0, 13507.189568625914, 13711.382987765575, 13788.517401890986, 14273.357698903574, 10617.78764424715, 10950.452477376179, 11268.830967535494, 11421.942890285578, 11422.942885285604, 3582.802945837978, 3629.891103266906, 3664.468652045465, 3691.53855768029, 3808.0479880029993, 72923.0, 73107.63446182734, 76066.0, 76412.0, 76586.70239892756, 10466.424346661006, 10548.630797922036, 10558.472076396303, 10601.0, 11156.553737850396, 21490.677639835416, 21806.781932180475, 21863.672044919338, 21903.780962190172, 21906.12375504962, 7964.601769911481, 8003.279704826594, 8549.743507694779, 8554.700585479603, 8554.871676924851, 4462.0, 4497.595216430537, 4498.100379924028, 4504.662150338672, 4563.566461186182, 3211.1169428406906, 3248.8538015789345, 3270.2151483643756, 3275.132089996153, 3277.901662950115, 610.9633421994695, 612.653850574424, 617.1421723803916, 625.8904691678938, 632.0235236559527, 542.6418563747986, 568.9800856969986, 615.3262177915176, 661.503872095928, 689.6689588997309, 7782.844343113106, 8080.676772929134, 8351.3736469765, 8663.220310172115, 9102.908970910206, 683.4498228925762, 684.637142314574, 705.9788206353818, 729.5221629832469, 737.9040724705861, 4145.626893579592, 4360.258756011485, 4587.9082418351445, 4673.1354699380045, 4715.740634265148, 107788.0, 108155.0, 112785.43607281911, 114791.42604286925, 115367.42316288364, 56851.71574142103, 58394.708026460445, 63969.0, 92083.0, 95212.04787952032, 77646.0, 77792.61103694445, 78226.6088669553, 88294.55852720696, 92198.0, 8347.582620869054, 9095.317851161322, 9226.215771659485, 9229.723108306762, 9248.398854074463, 42871.7856410716, 52740.47259527355, 53481.197782034076, 54099.0, 56480.0, 46119.538804611526, 61256.0, 79277.60361198157, 79396.0, 81214.59392702999, 12958.546450874173, 13137.802932956205, 13242.933785331012, 13358.530561638017, 13730.244836534086, 13297.933510332387, 13327.933360333136, 13528.93235533816, 14009.789853152008, 14089.718205636034, 12163.75672486563, 12191.0, 12277.815832762695, 12844.935775321064, 13057.869421306046, 11575.826362604399, 12308.692282693004, 12514.687132821387, 12573.994080473723, 13035.934820325838, 12265.754684906431, 12276.938615306866, 12616.17994830333, 12702.936485317514, 13062.869371306166, 10394.220433467672, 10572.41851698164, 10799.298045627009, 10886.67339979802, 10954.56181752721, 10649.254552181274, 10938.0, 11171.944140279571, 11186.496607652512, 11227.66317010491], "value": 7186.50812220202, "unit": "score"}], "name": "dromaeo_css", "value": 6995.281353794055, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 112.0, 111.0, 110.0, 110.0, 111.0, 112.0, 112.0, 111.0, 112.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 120.0, 121.0, 160.0, 119.0, 119.0, 118.0, 122.0, 153.0, 122.0], "value": 130.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 160.0, 160.0, 162.0, 160.0, 161.0, 160.0, 160.0, 162.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 101.0, 76.0, 77.0, 76.0, 77.0, 97.0, 75.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 116.0, 82.0, 83.0, 82.0, 100.0, 81.0, 82.0, 120.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 51.0, 52.0, 52.0, 52.0, 55.0, 53.0, 53.0, 52.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 60.0, 61.0, 62.0, 61.0, 60.0, 61.0, 61.0, 61.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 79.0, 79.0, 79.0, 79.0, 77.0, 78.0, 78.0], "value": 78.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 129.0, 123.0, 121.0, 123.0, 122.0, 128.0, 132.0, 164.0, 133.0], "value": 129.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 164.0, 163.0, 165.0, 165.0, 163.0, 162.0, 164.0, 163.0], "value": 164.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 64.0, 64.0, 64.0, 65.0, 70.0, 64.0, 64.0, 62.0], "value": 65.0, "unit": "score"}], "name": "kraken", "value": 1481.1000000000001, "alertThreshold": 2.0}]} 09:53:42 INFO - Return code: 0 09:53:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:53:42 INFO - # TBPL SUCCESS # 09:53:42 INFO - Running post-action listener: _resource_record_post_action 09:53:42 INFO - [mozharness: 2016-09-26 16:53:42.998338Z] Finished run-tests step (success) 09:53:42 INFO - Running post-run listener: _resource_record_post_run 09:53:43 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 392896512; Read time: 12; Write time: 260928 09:53:43 INFO - TinderboxPrint: CPU usage
13.1% 09:53:43 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 09:53:43 INFO - TinderboxPrint: I/O write bytes / time
392,896,512 / 260,928 09:53:43 INFO - TinderboxPrint: CPU idle
8,289.2 (86.9%) 09:53:43 INFO - TinderboxPrint: CPU user
1,197.9 (12.6%) 09:53:43 INFO - TinderboxPrint: Swap in / out
0 / 0 09:53:43 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103075840; Read time: 0; Write time: 66816 09:53:43 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 288931840; Read time: 0; Write time: 194092 09:53:43 INFO - Running post-run listener: _upload_blobber_files 09:53:43 INFO - Blob upload gear active. 09:53:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:53:43 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:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:53:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:53:44 INFO - (blobuploader) - INFO - Open directory for files ... 09:53:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:53:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:53:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:44 INFO - (blobuploader) - INFO - Done attempting. 09:53:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:53:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:53:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:48 INFO - (blobuploader) - INFO - Done attempting. 09:53:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:53:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.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 - Iteration through files over. 09:53:49 INFO - Return code: 0 09:53:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:53:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:53:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bd4104c27a0d26efe0c2034184fced01f072231fc37c44328c332bc25bf0c487a116d17327045939997ebc0c5f60db44ae050511ba45d45464ef623a77a5159b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a7028811826ac326992961580e60f1ef7643dc89b5ee5eb95763391283bc0cdbff4f76e1522f8abd8111bf593f88b0eb0da3123dd2a6091df65789c6c12209ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/784281f2deddb6da8bb312601ab56389ba81765b81df05975fc9caccdfdfa01a88619e556cfb51d54f7caff5dacc534f8a33c628bb3c5f3a3c9656cc787f0a49"} 09:53:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:53:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:53:49 INFO - Contents: 09:53:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bd4104c27a0d26efe0c2034184fced01f072231fc37c44328c332bc25bf0c487a116d17327045939997ebc0c5f60db44ae050511ba45d45464ef623a77a5159b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a7028811826ac326992961580e60f1ef7643dc89b5ee5eb95763391283bc0cdbff4f76e1522f8abd8111bf593f88b0eb0da3123dd2a6091df65789c6c12209ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/784281f2deddb6da8bb312601ab56389ba81765b81df05975fc9caccdfdfa01a88619e556cfb51d54f7caff5dacc534f8a33c628bb3c5f3a3c9656cc787f0a49"} 09:53:49 INFO - Running post-run listener: copy_logs_to_upload_dir 09:53:49 INFO - Copying logs to upload dir... 09:53:49 INFO - mkdir: /builds/slave/test/build/upload/logs 09:53:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.857171 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-09-26 09:53:47.873396) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-26 09:53:47.876648) ========= 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=826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bd4104c27a0d26efe0c2034184fced01f072231fc37c44328c332bc25bf0c487a116d17327045939997ebc0c5f60db44ae050511ba45d45464ef623a77a5159b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a7028811826ac326992961580e60f1ef7643dc89b5ee5eb95763391283bc0cdbff4f76e1522f8abd8111bf593f88b0eb0da3123dd2a6091df65789c6c12209ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/784281f2deddb6da8bb312601ab56389ba81765b81df05975fc9caccdfdfa01a88619e556cfb51d54f7caff5dacc534f8a33c628bb3c5f3a3c9656cc787f0a49"} build_url:https://queue.taskcluster.net/v1/task/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011316 build_url: 'https://queue.taskcluster.net/v1/task/DUMtH1FdRpqnXqdSz_gOmw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bd4104c27a0d26efe0c2034184fced01f072231fc37c44328c332bc25bf0c487a116d17327045939997ebc0c5f60db44ae050511ba45d45464ef623a77a5159b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a7028811826ac326992961580e60f1ef7643dc89b5ee5eb95763391283bc0cdbff4f76e1522f8abd8111bf593f88b0eb0da3123dd2a6091df65789c6c12209ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/784281f2deddb6da8bb312601ab56389ba81765b81df05975fc9caccdfdfa01a88619e556cfb51d54f7caff5dacc534f8a33c628bb3c5f3a3c9656cc787f0a49"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-26 09:53:47.901912) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 09:53:47.902233) ========= 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=826874ccc97bd7bf541b4875000001d5-1474902515.290001-2140224557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004477 ========= master_lag: 0.19 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 09:53:48.097101) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-26 09:53:48.097871) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-26 09:53:48.098640) ========= ========= Total master_lag: 0.31 =========