builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-065 starttime: 1480614662.03 results: success (0) buildid: 20161201065444 builduid: 20fde412ae8a45eaa3b5cd58528a8d64 revision: ed3d26ab8b35982ad915254c68087e97b2d9b792 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-01 09:51:02.034320) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-01 09:51:02.035003) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-01 09:51:02.038444) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-01 09:51:02.052963) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 09:51:02.053309) ========= 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=523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-01 09:51:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.2M=0s 2016-12-01 09:51:02 (35.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.397093 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 09:51:02.507777) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 09:51:02.508121) ========= 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=523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018596 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 09:51:02.558051) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 09:51:02.558420) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ed3d26ab8b35982ad915254c68087e97b2d9b792 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ed3d26ab8b35982ad915254c68087e97b2d9b792 --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=523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-01 09:51:03,071 truncating revision to first 12 chars 2016-12-01 09:51:03,071 Setting DEBUG logging. 2016-12-01 09:51:03,071 attempt 1/10 2016-12-01 09:51:03,072 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ed3d26ab8b35?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-01 09:51:03,399 unpacking tar archive at: mozilla-beta-ed3d26ab8b35/testing/mozharness/ program finished with exit code 0 elapsedTime=0.656868 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 09:51:03.228456) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-01 09:51:03.228769) ========= script_repo_revision: ed3d26ab8b35982ad915254c68087e97b2d9b792 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-01 09:51:03.229176) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-01 09:51:03.229463) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-01 09:51:03.238920) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-12-01 09:51:03.239323) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:51:03 INFO - MultiFileLogger online at 20161201 09:51:03 in /builds/slave/test 09:51:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:51:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:51:03 INFO - {'append_to_log': False, 09:51:03 INFO - 'base_work_dir': '/builds/slave/test', 09:51:03 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:51:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:51:03 INFO - 'branch': 'Mozilla-Beta', 09:51:03 INFO - 'buildbot_json_path': 'buildprops.json', 09:51:03 INFO - 'config_files': ('talos/linux_config.py',), 09:51:03 INFO - 'default_actions': ('clobber', 09:51:03 INFO - 'read-buildbot-config', 09:51:03 INFO - 'download-and-extract', 09:51:03 INFO - 'populate-webroot', 09:51:03 INFO - 'create-virtualenv', 09:51:03 INFO - 'install', 09:51:03 INFO - 'run-tests'), 09:51:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:51:03 INFO - 'download_minidump_stackwalk': True, 09:51:03 INFO - 'download_symbols': 'ondemand', 09:51:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:51:03 INFO - 'tooltool.py': '/tools/tooltool.py', 09:51:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:51:03 INFO - '/tools/misc-python/virtualenv.py')}, 09:51:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:51:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:51:03 INFO - 'installer_path': 'installer.exe', 09:51:03 INFO - 'log_level': 'info', 09:51:03 INFO - 'log_name': 'talos', 09:51:03 INFO - 'log_to_console': True, 09:51:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:51:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:51:03 INFO - 'opt_config_files': (), 09:51:03 INFO - 'pip_index': False, 09:51:03 INFO - 'sps_profile': False, 09:51:03 INFO - 'sps_profile_interval': 0, 09:51:03 INFO - 'suite': 'dromaeojs', 09:51:03 INFO - 'system_bits': '32', 09:51:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:51:03 INFO - 'title': 'talos-linux64-ix-065', 09:51:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:51:03 INFO - 'use_talos_json': True, 09:51:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:51:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:51:03 INFO - 'work_dir': 'build'} 09:51:03 INFO - [mozharness: 2016-12-01 17:51:03.852471Z] Running clobber step. 09:51:03 INFO - Running pre-action listener: _resource_record_pre_action 09:51:03 INFO - Running main action method: clobber 09:51:03 INFO - rmtree: /builds/slave/test/build 09:51:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:51:04 INFO - Running post-action listener: _resource_record_post_action 09:51:04 INFO - [mozharness: 2016-12-01 17:51:04.830269Z] Finished clobber step (success) 09:51:04 INFO - [mozharness: 2016-12-01 17:51:04.830368Z] Running read-buildbot-config step. 09:51:04 INFO - Running pre-action listener: _resource_record_pre_action 09:51:04 INFO - Running main action method: read_buildbot_config 09:51:04 INFO - Using buildbot properties: 09:51:04 INFO - { 09:51:04 INFO - "project": "", 09:51:04 INFO - "product": "firefox", 09:51:04 INFO - "script_repo_revision": "production", 09:51:04 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:51:04 INFO - "repository": "", 09:51:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 09:51:04 INFO - "buildid": "20161201065444", 09:51:04 INFO - "pgo_build": "True", 09:51:04 INFO - "basedir": "/builds/slave/test", 09:51:04 INFO - "buildnumber": 20, 09:51:04 INFO - "slavename": "talos-linux64-ix-065", 09:51:04 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:51:04 INFO - "platform": "ubuntu64_hw", 09:51:04 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 09:51:04 INFO - "branch": "mozilla-beta", 09:51:04 INFO - "repo_path": "releases/mozilla-beta", 09:51:04 INFO - "revision": "ed3d26ab8b35982ad915254c68087e97b2d9b792", 09:51:04 INFO - "stage_platform": "linux64-pgo", 09:51:04 INFO - "builduid": "20fde412ae8a45eaa3b5cd58528a8d64", 09:51:04 INFO - "slavebuilddir": "test" 09:51:04 INFO - } 09:51:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2. 09:51:04 INFO - Running post-action listener: _resource_record_post_action 09:51:04 INFO - [mozharness: 2016-12-01 17:51:04.832793Z] Finished read-buildbot-config step (success) 09:51:04 INFO - [mozharness: 2016-12-01 17:51:04.832882Z] Running download-and-extract step. 09:51:04 INFO - Running pre-action listener: _resource_record_pre_action 09:51:04 INFO - Running main action method: download_and_extract 09:51:04 INFO - mkdir: /builds/slave/test/build/tests 09:51: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:51:04 INFO - trying https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 09:51:04 INFO - Downloading https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 09:51:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:51:05 INFO - Downloaded 1327 bytes. 09:51:05 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 09:51:05 INFO - Using the following test package requirements: 09:51:05 INFO - {u'common': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 09:51:05 INFO - u'cppunittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:51:05 INFO - u'firefox-51.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:51:05 INFO - u'gtest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:51:05 INFO - u'firefox-51.0.en-US.linux-x86_64.gtest.tests.zip'], 09:51:05 INFO - u'jittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:51:05 INFO - u'jsshell-linux-x86_64.zip'], 09:51:05 INFO - u'mochitest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:51:05 INFO - u'firefox-51.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:51:05 INFO - u'mozbase': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 09:51:05 INFO - u'reftest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:51:05 INFO - u'firefox-51.0.en-US.linux-x86_64.reftest.tests.zip'], 09:51:05 INFO - u'talos': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:51:05 INFO - u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'], 09:51:05 INFO - u'web-platform': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:51:05 INFO - u'firefox-51.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:51:05 INFO - u'xpcshell': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:51:05 INFO - u'firefox-51.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:51:05 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:51:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 09:51:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:51:05 INFO - Fetch https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 09:51:05 INFO - Expected file size: 21964820 09:51:10 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:51:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 09:51:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:51:10 INFO - Fetch https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 09:51:10 INFO - Expected file size: 21964820 09:51:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip 09:51:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:51:15 INFO - Fetch https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip into memory 09:51:16 INFO - Expected file size: 14414089 09:51:16 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:51:16 INFO - trying https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 09:51:16 INFO - Downloading https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 09:51:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:51:17 INFO - Downloaded 56977180 bytes. 09:51:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 09:51:17 INFO - mkdir: /builds/slave/test/properties 09:51:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:51:17 INFO - Writing to file /builds/slave/test/properties/build_url 09:51:17 INFO - Contents: 09:51:17 INFO - build_url:https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 09:51:18 INFO - Running post-action listener: _resource_record_post_action 09:51:18 INFO - Running post-action listener: set_extra_try_arguments 09:51:18 INFO - [mozharness: 2016-12-01 17:51:18.496526Z] Finished download-and-extract step (success) 09:51:18 INFO - [mozharness: 2016-12-01 17:51:18.496754Z] Running populate-webroot step. 09:51:18 INFO - Running pre-action listener: _resource_record_pre_action 09:51:18 INFO - Running main action method: populate_webroot 09:51:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:51:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:51:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:51:18 INFO - '--tppagecycles', 09:51:18 INFO - '7'], 09:51:18 INFO - 'tests': ['tsvgm']}}, 09:51:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:51:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:51:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:51:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:51:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:51:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:51:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:18 INFO - 'talos_options': ['--disable-e10s'], 09:51:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:51:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:51:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:18 INFO - 'talos_options': ['--disable-e10s'], 09:51:18 INFO - 'tests': ['damp', 'tps']}, 09:51:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:18 INFO - 'tests': ['damp', 'tps']}, 09:51:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:51:18 INFO - 'tests': ['dromaeo_dom']}, 09:51:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:51:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:51:18 INFO - 'tests': ['basic_compositor_video']}, 09:51:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:51:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:51:18 INFO - 'tests': ['a11yr', 09:51:18 INFO - 'ts_paint', 09:51:18 INFO - 'tpaint', 09:51:18 INFO - 'sessionrestore', 09:51:18 INFO - 'sessionrestore_no_auto_restore', 09:51:18 INFO - 'tabpaint']}, 09:51:18 INFO - 'other-e10s': {'tests': ['a11yr', 09:51:18 INFO - 'ts_paint', 09:51:18 INFO - 'tpaint', 09:51:18 INFO - 'sessionrestore', 09:51:18 INFO - 'sessionrestore_no_auto_restore', 09:51:18 INFO - 'tabpaint']}, 09:51:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:51:18 INFO - 'ts_paint', 09:51:18 INFO - 'tpaint', 09:51:18 INFO - 'sessionrestore', 09:51:18 INFO - 'sessionrestore_no_auto_restore', 09:51:18 INFO - 'tabpaint']}, 09:51:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:51:18 INFO - 'ts_paint', 09:51:18 INFO - 'tpaint', 09:51:18 INFO - 'sessionrestore', 09:51:18 INFO - 'sessionrestore_no_auto_restore', 09:51:18 INFO - 'tabpaint']}, 09:51:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:51:18 INFO - 'tests': ['a11yr', 09:51:18 INFO - 'ts_paint', 09:51:18 INFO - 'tpaint', 09:51:18 INFO - 'sessionrestore', 09:51:18 INFO - 'sessionrestore_no_auto_restore', 09:51:18 INFO - 'tabpaint']}, 09:51:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:51:18 INFO - 'tests': ['a11yr', 09:51:18 INFO - 'ts_paint', 09:51:18 INFO - 'tpaint', 09:51:18 INFO - 'sessionrestore', 09:51:18 INFO - 'sessionrestore_no_auto_restore', 09:51:18 INFO - 'tabpaint']}, 09:51:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:51:18 INFO - 'tests': ['tsvgx', 09:51:18 INFO - 'tsvgr_opacity', 09:51:18 INFO - 'tart', 09:51:18 INFO - 'tscrollx', 09:51:18 INFO - 'cart']}, 09:51:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:51:18 INFO - 'tsvgr_opacity', 09:51:18 INFO - 'tart', 09:51:18 INFO - 'tscrollx', 09:51:18 INFO - 'cart']}, 09:51:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:18 INFO - 'talos_options': ['--disable-e10s'], 09:51:18 INFO - 'tests': ['tp5o']}, 09:51:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:18 INFO - 'tests': ['tp5o']}, 09:51:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:18 INFO - 'talos_options': ['--disable-e10s', 09:51:18 INFO - '--xperf_path', 09:51:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:51:18 INFO - 'tests': ['tp5n']}, 09:51:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:51:18 INFO - 'talos_options': ['--xperf_path', 09:51:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:51:18 INFO - 'tests': ['tp5n']}}, 09:51:18 INFO - 'talos.zip': {'path': '', 09:51:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:51:18 INFO - Running post-action listener: _resource_record_post_action 09:51:18 INFO - [mozharness: 2016-12-01 17:51:18.514385Z] Finished populate-webroot step (success) 09:51:18 INFO - [mozharness: 2016-12-01 17:51:18.514478Z] Running create-virtualenv step. 09:51:18 INFO - Running pre-action listener: _resource_record_pre_action 09:51:18 INFO - Running main action method: create_virtualenv 09:51:18 INFO - Creating virtualenv /builds/slave/test/build/venv 09:51:18 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:51:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:51:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:51:18 INFO - Using real prefix '/usr' 09:51:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:51:19 INFO - Installing distribute.............................................................................................................................................................................................done. 09:51:21 INFO - Installing pip.................done. 09:51:21 INFO - Return code: 0 09:51:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:51:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:21 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 0x7f9db6d65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2899ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2896f60>, '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 0x2897d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28981d0>, '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': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', '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:51:21 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:51:21 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:51:21 INFO - Using env: {'DISPLAY': ':0', 09:51:21 INFO - 'HOME': '/home/cltbld', 09:51:21 INFO - 'LANG': 'en_US.UTF-8', 09:51:21 INFO - 'LANGUAGE': 'en_US:en', 09:51:21 INFO - 'LOGNAME': 'cltbld', 09:51:21 INFO - 'MAIL': '/var/mail/cltbld', 09:51:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:21 INFO - 'MOZ_NO_REMOTE': '1', 09:51:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:21 INFO - 'NO_EM_RESTART': '1', 09:51:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:21 INFO - 'PWD': '/builds/slave/test', 09:51:21 INFO - 'SHELL': '/bin/bash', 09:51:21 INFO - 'SHLVL': '1', 09:51:21 INFO - 'TERM': 'linux', 09:51:21 INFO - 'TMOUT': '86400', 09:51:21 INFO - 'USER': 'cltbld', 09:51:21 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', 09:51:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:21 INFO - '_': '/tools/buildbot/bin/python'} 09:51:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:21 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:51:21 INFO - Cleaning up... 09:51:21 INFO - Return code: 0 09:51:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:51:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:21 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 0x7f9db6d65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2899ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2896f60>, '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 0x2897d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28981d0>, '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': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', '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:51:21 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:51:21 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:51:21 INFO - Using env: {'DISPLAY': ':0', 09:51:21 INFO - 'HOME': '/home/cltbld', 09:51:21 INFO - 'LANG': 'en_US.UTF-8', 09:51:21 INFO - 'LANGUAGE': 'en_US:en', 09:51:21 INFO - 'LOGNAME': 'cltbld', 09:51:21 INFO - 'MAIL': '/var/mail/cltbld', 09:51:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:21 INFO - 'MOZ_NO_REMOTE': '1', 09:51:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:21 INFO - 'NO_EM_RESTART': '1', 09:51:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:21 INFO - 'PWD': '/builds/slave/test', 09:51:21 INFO - 'SHELL': '/bin/bash', 09:51:21 INFO - 'SHLVL': '1', 09:51:21 INFO - 'TERM': 'linux', 09:51:21 INFO - 'TMOUT': '86400', 09:51:21 INFO - 'USER': 'cltbld', 09:51:21 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', 09:51:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:21 INFO - '_': '/tools/buildbot/bin/python'} 09:51:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:21 INFO - Downloading/unpacking psutil>=3.1.1 09:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:51:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:51:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:51:23 INFO - Installing collected packages: psutil 09:51:23 INFO - Running setup.py install for psutil 09:51:23 INFO - building 'psutil._psutil_linux' extension 09:51:23 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:51:23 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:51:23 INFO - building 'psutil._psutil_posix' extension 09:51:23 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:51:24 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:51:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:51:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:51:24 INFO - Successfully installed psutil 09:51:24 INFO - Cleaning up... 09:51:24 INFO - Return code: 0 09:51:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:24 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 0x7f9db6d65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2899ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2896f60>, '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 0x2897d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28981d0>, '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': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', '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:51:24 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:51:24 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:51:24 INFO - Using env: {'DISPLAY': ':0', 09:51:24 INFO - 'HOME': '/home/cltbld', 09:51:24 INFO - 'LANG': 'en_US.UTF-8', 09:51:24 INFO - 'LANGUAGE': 'en_US:en', 09:51:24 INFO - 'LOGNAME': 'cltbld', 09:51:24 INFO - 'MAIL': '/var/mail/cltbld', 09:51:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:24 INFO - 'MOZ_NO_REMOTE': '1', 09:51:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:24 INFO - 'NO_EM_RESTART': '1', 09:51:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:24 INFO - 'PWD': '/builds/slave/test', 09:51:24 INFO - 'SHELL': '/bin/bash', 09:51:24 INFO - 'SHLVL': '1', 09:51:24 INFO - 'TERM': 'linux', 09:51:24 INFO - 'TMOUT': '86400', 09:51:24 INFO - 'USER': 'cltbld', 09:51:24 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', 09:51:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:24 INFO - '_': '/tools/buildbot/bin/python'} 09:51:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:51:24 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:51:24 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:51:24 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:51:24 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:51:24 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:51:24 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:51:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:51:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:51:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:51:26 INFO - Installing collected packages: mozsystemmonitor 09:51:26 INFO - Running setup.py install for mozsystemmonitor 09:51:26 INFO - Successfully installed mozsystemmonitor 09:51:26 INFO - Cleaning up... 09:51:26 INFO - Return code: 0 09:51:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:51:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:26 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 0x7f9db6d65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2899ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2896f60>, '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 0x2897d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28981d0>, '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': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', '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:51:26 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:51:26 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:51:26 INFO - Using env: {'DISPLAY': ':0', 09:51:26 INFO - 'HOME': '/home/cltbld', 09:51:26 INFO - 'LANG': 'en_US.UTF-8', 09:51:26 INFO - 'LANGUAGE': 'en_US:en', 09:51:26 INFO - 'LOGNAME': 'cltbld', 09:51:26 INFO - 'MAIL': '/var/mail/cltbld', 09:51:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:26 INFO - 'MOZ_NO_REMOTE': '1', 09:51:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:26 INFO - 'NO_EM_RESTART': '1', 09:51:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:26 INFO - 'PWD': '/builds/slave/test', 09:51:26 INFO - 'SHELL': '/bin/bash', 09:51:26 INFO - 'SHLVL': '1', 09:51:26 INFO - 'TERM': 'linux', 09:51:26 INFO - 'TMOUT': '86400', 09:51:26 INFO - 'USER': 'cltbld', 09:51:26 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', 09:51:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:26 INFO - '_': '/tools/buildbot/bin/python'} 09:51:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:26 INFO - Downloading/unpacking jsonschema==2.5.1 09:51:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:51:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:51: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:51: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:51: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:51: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:51: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:51: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:51:29 INFO - Downloading functools32-3.2.3-2.tar.gz 09:51:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:51:29 INFO - warning: no files found matching '*.txt' 09:51:29 INFO - no previously-included directories found matching 'build' 09:51:29 INFO - no previously-included directories found matching 'dist' 09:51:29 INFO - no previously-included directories found matching '.git*' 09:51:29 INFO - Installing collected packages: jsonschema, functools32 09:51:29 INFO - Running setup.py install for functools32 09:51:29 INFO - warning: no files found matching '*.txt' 09:51:29 INFO - no previously-included directories found matching 'build' 09:51:29 INFO - no previously-included directories found matching 'dist' 09:51:29 INFO - no previously-included directories found matching '.git*' 09:51:29 INFO - Successfully installed jsonschema functools32 09:51:29 INFO - Cleaning up... 09:51:29 INFO - Return code: 0 09:51:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:29 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 0x7f9db6d65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2899ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2896f60>, '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 0x2897d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28981d0>, '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': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', '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:51:29 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:51:29 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:51:29 INFO - Using env: {'DISPLAY': ':0', 09:51:29 INFO - 'HOME': '/home/cltbld', 09:51:29 INFO - 'LANG': 'en_US.UTF-8', 09:51:29 INFO - 'LANGUAGE': 'en_US:en', 09:51:29 INFO - 'LOGNAME': 'cltbld', 09:51:29 INFO - 'MAIL': '/var/mail/cltbld', 09:51:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:29 INFO - 'MOZ_NO_REMOTE': '1', 09:51:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:29 INFO - 'NO_EM_RESTART': '1', 09:51:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:29 INFO - 'PWD': '/builds/slave/test', 09:51:29 INFO - 'SHELL': '/bin/bash', 09:51:29 INFO - 'SHLVL': '1', 09:51:29 INFO - 'TERM': 'linux', 09:51:29 INFO - 'TMOUT': '86400', 09:51:29 INFO - 'USER': 'cltbld', 09:51:29 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', 09:51:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:29 INFO - '_': '/tools/buildbot/bin/python'} 09:51:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:51:29 INFO - Cleaning up... 09:51:29 INFO - Return code: 0 09:51:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:29 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 0x7f9db6d65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2899ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2896f60>, '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 0x2897d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28981d0>, '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': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', '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:51:29 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:51:29 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:51:29 INFO - Using env: {'DISPLAY': ':0', 09:51:29 INFO - 'HOME': '/home/cltbld', 09:51:29 INFO - 'LANG': 'en_US.UTF-8', 09:51:29 INFO - 'LANGUAGE': 'en_US:en', 09:51:29 INFO - 'LOGNAME': 'cltbld', 09:51:29 INFO - 'MAIL': '/var/mail/cltbld', 09:51:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:29 INFO - 'MOZ_NO_REMOTE': '1', 09:51:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:29 INFO - 'NO_EM_RESTART': '1', 09:51:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:29 INFO - 'PWD': '/builds/slave/test', 09:51:29 INFO - 'SHELL': '/bin/bash', 09:51:29 INFO - 'SHLVL': '1', 09:51:29 INFO - 'TERM': 'linux', 09:51:29 INFO - 'TMOUT': '86400', 09:51:29 INFO - 'USER': 'cltbld', 09:51:29 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', 09:51:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:29 INFO - '_': '/tools/buildbot/bin/python'} 09:51:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:29 INFO - Downloading/unpacking blobuploader==1.2.4 09:51:29 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:51:29 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:51:29 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:51:29 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:51:29 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:51:29 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:51:31 INFO - Downloading blobuploader-1.2.4.tar.gz 09:51:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:51:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:51: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:51: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:51: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:51: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:51: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:51: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:51:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:51:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:51:32 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:51:32 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:51:32 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:51:32 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:51:32 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:51:32 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:51:32 INFO - Downloading docopt-0.6.1.tar.gz 09:51:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:51:32 INFO - Installing collected packages: blobuploader, requests, docopt 09:51:32 INFO - Running setup.py install for blobuploader 09:51:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:51:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:51:32 INFO - Running setup.py install for requests 09:51:33 INFO - Running setup.py install for docopt 09:51:33 INFO - Successfully installed blobuploader requests docopt 09:51:33 INFO - Cleaning up... 09:51:33 INFO - Return code: 0 09:51:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:51: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:51:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51: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:51:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:33 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 0x7f9db6d65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2899ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2896f60>, '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 0x2897d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28981d0>, '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': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', '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:51:33 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:51:33 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:51:33 INFO - Using env: {'DISPLAY': ':0', 09:51:33 INFO - 'HOME': '/home/cltbld', 09:51:33 INFO - 'LANG': 'en_US.UTF-8', 09:51:33 INFO - 'LANGUAGE': 'en_US:en', 09:51:33 INFO - 'LOGNAME': 'cltbld', 09:51:33 INFO - 'MAIL': '/var/mail/cltbld', 09:51:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:33 INFO - 'MOZ_NO_REMOTE': '1', 09:51:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:33 INFO - 'NO_EM_RESTART': '1', 09:51:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:33 INFO - 'PWD': '/builds/slave/test', 09:51:33 INFO - 'SHELL': '/bin/bash', 09:51:33 INFO - 'SHLVL': '1', 09:51:33 INFO - 'TERM': 'linux', 09:51:33 INFO - 'TMOUT': '86400', 09:51:33 INFO - 'USER': 'cltbld', 09:51:33 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', 09:51:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:33 INFO - '_': '/tools/buildbot/bin/python'} 09:51:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:51:33 INFO - Running setup.py (path:/tmp/pip-M5uVar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:51:33 INFO - Running setup.py (path:/tmp/pip-f4doVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:51:33 INFO - Running setup.py (path:/tmp/pip-o58Crc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:51:33 INFO - Running setup.py (path:/tmp/pip-nRyRE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:51:33 INFO - Running setup.py (path:/tmp/pip-cJ7fEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:51:33 INFO - Running setup.py (path:/tmp/pip-cbTRJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:51:33 INFO - Running setup.py (path:/tmp/pip-Z2orPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:51:34 INFO - Running setup.py (path:/tmp/pip-HT44iW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:51:34 INFO - Running setup.py (path:/tmp/pip-VdBmeS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:51:34 INFO - Running setup.py (path:/tmp/pip-v6KzmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:51:34 INFO - Running setup.py (path:/tmp/pip-UpK7NH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:51:34 INFO - Running setup.py (path:/tmp/pip-c9Lu74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:51:34 INFO - Running setup.py (path:/tmp/pip-6UWjD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:51:34 INFO - Running setup.py (path:/tmp/pip-M2qYj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:51:34 INFO - Running setup.py (path:/tmp/pip-gpnL1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:51:34 INFO - Running setup.py (path:/tmp/pip-c651Xo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:51:34 INFO - Running setup.py (path:/tmp/pip-y3KpMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:51:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:51:34 INFO - Running setup.py install for manifestparser 09:51:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:51:34 INFO - Running setup.py install for mozcrash 09:51:34 INFO - Running setup.py install for mozdebug 09:51:35 INFO - Running setup.py install for mozdevice 09:51:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:51:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:51:35 INFO - Running setup.py install for mozfile 09:51:35 INFO - Running setup.py install for mozhttpd 09:51:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:51:35 INFO - Running setup.py install for mozinfo 09:51:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:51:35 INFO - Running setup.py install for mozInstall 09:51:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:51:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:51:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:51:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:51:35 INFO - Running setup.py install for mozleak 09:51:35 INFO - Running setup.py install for mozlog 09:51:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:51:35 INFO - Running setup.py install for moznetwork 09:51:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:51:35 INFO - Running setup.py install for mozprocess 09:51:35 INFO - Running setup.py install for mozprofile 09:51:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:51:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:51:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:51:36 INFO - Running setup.py install for mozrunner 09:51:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:51:36 INFO - Running setup.py install for mozscreenshot 09:51:36 INFO - Running setup.py install for moztest 09:51:36 INFO - Running setup.py install for mozversion 09:51:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:51:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:51:36 INFO - Cleaning up... 09:51:36 INFO - Return code: 0 09:51:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:51: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:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51: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:51:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:36 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 0x7f9db6d65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2899ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2896f60>, '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 0x2897d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28981d0>, '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': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', '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:51:36 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:51:36 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:51:36 INFO - Using env: {'DISPLAY': ':0', 09:51:36 INFO - 'HOME': '/home/cltbld', 09:51:36 INFO - 'LANG': 'en_US.UTF-8', 09:51:36 INFO - 'LANGUAGE': 'en_US:en', 09:51:36 INFO - 'LOGNAME': 'cltbld', 09:51:36 INFO - 'MAIL': '/var/mail/cltbld', 09:51:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:36 INFO - 'MOZ_NO_REMOTE': '1', 09:51:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:36 INFO - 'NO_EM_RESTART': '1', 09:51:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:36 INFO - 'PWD': '/builds/slave/test', 09:51:36 INFO - 'SHELL': '/bin/bash', 09:51:36 INFO - 'SHLVL': '1', 09:51:36 INFO - 'TERM': 'linux', 09:51:36 INFO - 'TMOUT': '86400', 09:51:36 INFO - 'USER': 'cltbld', 09:51:36 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', 09:51:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:36 INFO - '_': '/tools/buildbot/bin/python'} 09:51:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:51:36 INFO - Running setup.py (path:/tmp/pip-6KSitR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:51:36 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:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:51:36 INFO - Running setup.py (path:/tmp/pip-aBPTar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:51:36 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:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:51:36 INFO - Running setup.py (path:/tmp/pip-B86TMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:51:36 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:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:51:36 INFO - Running setup.py (path:/tmp/pip-YBKz7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:51:37 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:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:51:37 INFO - Running setup.py (path:/tmp/pip-6_90a_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:51:37 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:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:51:37 INFO - Running setup.py (path:/tmp/pip-EizvUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:51:37 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:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:51:37 INFO - Running setup.py (path:/tmp/pip-4tWmVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:51:37 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:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:51:37 INFO - Running setup.py (path:/tmp/pip-cLyB02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:51:37 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:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:51:37 INFO - Running setup.py (path:/tmp/pip-3Fpb6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:51:37 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:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:51:37 INFO - Running setup.py (path:/tmp/pip-rVgdYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:51:37 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:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:51:37 INFO - Running setup.py (path:/tmp/pip-qyI7mQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:51:37 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:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:51:37 INFO - Running setup.py (path:/tmp/pip-V7394y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:51:37 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:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:51:37 INFO - Running setup.py (path:/tmp/pip-9VwybL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:51:37 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:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:51:37 INFO - Running setup.py (path:/tmp/pip-PL3Vm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:51:37 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:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:51:37 INFO - Running setup.py (path:/tmp/pip-CJTA6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:51:37 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:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:51:37 INFO - Running setup.py (path:/tmp/pip-FBUPSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:51:37 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:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:51:37 INFO - Running setup.py (path:/tmp/pip-oS38ke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:51:37 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:51:37 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:51:37 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:51:37 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:51:37 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:51:37 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:51:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:51:37 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:51:37 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:51:37 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:51:37 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:51:37 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:51:37 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:51:39 INFO - Downloading blessings-1.6.tar.gz 09:51:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:51:40 INFO - Installing collected packages: blessings 09:51:40 INFO - Running setup.py install for blessings 09:51:40 INFO - Successfully installed blessings 09:51:40 INFO - Cleaning up... 09:51:40 INFO - Return code: 0 09:51:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:51:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:51:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:51:40 INFO - Reading from file tmpfile_stdout 09:51:40 INFO - Current package versions: 09:51:40 INFO - argparse == 1.2.1 09:51:40 INFO - blessings == 1.6 09:51:40 INFO - blobuploader == 1.2.4 09:51:40 INFO - docopt == 0.6.1 09:51:40 INFO - functools32 == 3.2.3-2 09:51:40 INFO - jsonschema == 2.5.1 09:51:40 INFO - manifestparser == 1.1 09:51:40 INFO - mozInstall == 1.12 09:51:40 INFO - mozcrash == 0.17 09:51:40 INFO - mozdebug == 0.1 09:51:40 INFO - mozdevice == 0.48 09:51:40 INFO - mozfile == 1.2 09:51:40 INFO - mozhttpd == 0.7 09:51:40 INFO - mozinfo == 0.9 09:51:40 INFO - mozleak == 0.1 09:51:40 INFO - mozlog == 3.4 09:51:40 INFO - moznetwork == 0.27 09:51:40 INFO - mozprocess == 0.23 09:51:40 INFO - mozprofile == 0.28 09:51:40 INFO - mozrunner == 6.12 09:51:40 INFO - mozscreenshot == 0.1 09:51:40 INFO - mozsystemmonitor == 0.3 09:51:40 INFO - moztest == 0.7 09:51:40 INFO - mozversion == 1.4 09:51:40 INFO - psutil == 3.1.1 09:51:40 INFO - requests == 1.2.3 09:51:40 INFO - wsgiref == 0.1.2 09:51:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:51:40 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:51:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:40 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:51:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:40 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 0x7f9db6d65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2899ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2896f60>, '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 0x2897d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28981d0>, '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': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', '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:51:40 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:51:40 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:51:40 INFO - Using env: {'DISPLAY': ':0', 09:51:40 INFO - 'HOME': '/home/cltbld', 09:51:40 INFO - 'LANG': 'en_US.UTF-8', 09:51:40 INFO - 'LANGUAGE': 'en_US:en', 09:51:40 INFO - 'LOGNAME': 'cltbld', 09:51:40 INFO - 'MAIL': '/var/mail/cltbld', 09:51:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:40 INFO - 'MOZ_NO_REMOTE': '1', 09:51:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:40 INFO - 'NO_EM_RESTART': '1', 09:51:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:40 INFO - 'PWD': '/builds/slave/test', 09:51:40 INFO - 'SHELL': '/bin/bash', 09:51:40 INFO - 'SHLVL': '1', 09:51:40 INFO - 'TERM': 'linux', 09:51:40 INFO - 'TMOUT': '86400', 09:51:40 INFO - 'USER': 'cltbld', 09:51:40 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', 09:51:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:40 INFO - '_': '/tools/buildbot/bin/python'} 09:51:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:40 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:51:40 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:51:40 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:51:40 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:51:40 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:51:40 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:51:40 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:51:40 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:51:40 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:51:40 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:51:40 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:51:40 INFO - Cleaning up... 09:51:40 INFO - Return code: 0 09:51:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:51:40 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:51:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:40 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:51:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:40 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 0x7f9db6d65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2899ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2896f60>, '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 0x2897d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28981d0>, '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': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', '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:51:40 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:51:40 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:51:40 INFO - Using env: {'DISPLAY': ':0', 09:51:40 INFO - 'HOME': '/home/cltbld', 09:51:40 INFO - 'LANG': 'en_US.UTF-8', 09:51:40 INFO - 'LANGUAGE': 'en_US:en', 09:51:40 INFO - 'LOGNAME': 'cltbld', 09:51:40 INFO - 'MAIL': '/var/mail/cltbld', 09:51:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:40 INFO - 'MOZ_NO_REMOTE': '1', 09:51:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:40 INFO - 'NO_EM_RESTART': '1', 09:51:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:40 INFO - 'PWD': '/builds/slave/test', 09:51:40 INFO - 'SHELL': '/bin/bash', 09:51:40 INFO - 'SHLVL': '1', 09:51:40 INFO - 'TERM': 'linux', 09:51:40 INFO - 'TMOUT': '86400', 09:51:40 INFO - 'USER': 'cltbld', 09:51:40 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', 09:51:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:40 INFO - '_': '/tools/buildbot/bin/python'} 09:51:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:51:40 INFO - Cleaning up... 09:51:40 INFO - Return code: 0 09:51:40 INFO - Running post-action listener: _resource_record_post_action 09:51:40 INFO - Running post-action listener: _start_resource_monitoring 09:51:40 INFO - Starting resource monitoring. 09:51:40 INFO - [mozharness: 2016-12-01 17:51:40.887523Z] Finished create-virtualenv step (success) 09:51:40 INFO - [mozharness: 2016-12-01 17:51:40.887998Z] Running install step. 09:51:40 INFO - Running pre-action listener: _resource_record_pre_action 09:51:40 INFO - Running main action method: install 09:51:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:51:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:51:41 INFO - Reading from file tmpfile_stdout 09:51:41 INFO - Detecting whether we're running mozinstall >=1.0... 09:51:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:51:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:51:41 INFO - Reading from file tmpfile_stdout 09:51:41 INFO - Output received: 09:51:41 INFO - Usage: mozinstall [options] installer 09:51:41 INFO - Options: 09:51:41 INFO - -h, --help show this help message and exit 09:51:41 INFO - -d DEST, --destination=DEST 09:51:41 INFO - Directory to install application into. [default: 09:51:41 INFO - "/builds/slave/test"] 09:51:41 INFO - --app=APP Application being installed. [default: firefox] 09:51:41 INFO - mkdir: /builds/slave/test/build/application 09:51:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:51:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:51:54 INFO - Reading from file tmpfile_stdout 09:51:54 INFO - Output received: 09:51:54 INFO - /builds/slave/test/build/application/firefox/firefox 09:51:54 INFO - Running post-action listener: _resource_record_post_action 09:51:54 INFO - [mozharness: 2016-12-01 17:51:54.701069Z] Finished install step (success) 09:51:54 INFO - [mozharness: 2016-12-01 17:51:54.701315Z] Running run-tests step. 09:51:54 INFO - Running pre-action listener: _resource_record_pre_action 09:51:54 INFO - Running main action method: run_tests 09:51:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:51:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:51:54 INFO - Python 2.7.3 09:51:54 INFO - Return code: 0 09:51:54 INFO - grabbing minidump binary from tooltool 09:51:54 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:51:54 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 0x2896f60>, '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 0x2897d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28981d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:51:54 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:51:54 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:51:54 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:51:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:51:54 INFO - Return code: 0 09:51:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:51:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:51:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:51:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:51:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:51:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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:51:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --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:51:54 INFO - Using env: {'DISPLAY': ':0', 09:51:54 INFO - 'HOME': '/home/cltbld', 09:51:54 INFO - 'LANG': 'en_US.UTF-8', 09:51:54 INFO - 'LANGUAGE': 'en_US:en', 09:51:54 INFO - 'LOGNAME': 'cltbld', 09:51:54 INFO - 'MAIL': '/var/mail/cltbld', 09:51:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:51:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:51:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:54 INFO - 'MOZ_NO_REMOTE': '1', 09:51:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:51:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:54 INFO - 'NO_EM_RESTART': '1', 09:51:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:54 INFO - 'PWD': '/builds/slave/test', 09:51:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:51:54 INFO - 'SHELL': '/bin/bash', 09:51:54 INFO - 'SHLVL': '1', 09:51:54 INFO - 'TERM': 'linux', 09:51:54 INFO - 'TMOUT': '86400', 09:51:54 INFO - 'USER': 'cltbld', 09:51:54 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727', 09:51:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:54 INFO - '_': '/tools/buildbot/bin/python'} 09:51:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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:51:55 INFO - mozversion application_buildid: 20161201065444 09:51:55 INFO - mozversion application_changeset: ed3d26ab8b35982ad915254c68087e97b2d9b792 09:51:55 INFO - mozversion application_display_name: Firefox 09:51:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:51:55 INFO - mozversion application_name: Firefox 09:51:55 INFO - mozversion application_remotingname: firefox 09:51:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:51:55 INFO - mozversion application_vendor: Mozilla 09:51:55 INFO - mozversion application_version: 51.0 09:51:55 INFO - mozversion platform_buildid: 20161201065444 09:51:55 INFO - mozversion platform_changeset: ed3d26ab8b35982ad915254c68087e97b2d9b792 09:51:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:51:55 INFO - mozversion platform_version: 51.0 09:51:55 INFO - using testdate: 1480614715 09:51:55 INFO - actual date: 1480614715 09:51:55 INFO - starting webserver on 'localhost:56057' 09:51:55 INFO - SUITE-START | Running 2 tests 09:51:55 INFO - TEST-START | dromaeo_css 09:51:55 INFO - operating with platform_type : linux_ 09:51:55 INFO - Initialising browser for dromaeo_css test... 09:51:55 INFO - TEST-INFO | started process 22911 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjJDOgl/profile http://localhost:56057/getInfo.html) 09:51:58 INFO - PROCESS | 22911 | __metrics Screen width/height:1600/1200 09:51:58 INFO - PROCESS | 22911 | colorDepth:24 09:51:58 INFO - PROCESS | 22911 | Browser inner width/height: 1024/697 09:51:58 INFO - PROCESS | 22911 | __metrics 09:51:59 INFO - PROCESS | 22911 | 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:51:59 INFO - PROCESS | 22911 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:51:59 INFO - PROCESS | 22911 | console.error: PushService: 09:51:59 INFO - PROCESS | 22911 | stateChangeProcessEnqueue: Error transitioning state 09:51:59 INFO - PROCESS | 22911 | UnknownError 09:52:00 INFO - TEST-INFO | 22911: exit 0 09:52:00 INFO - Browser initialized. 09:52:00 INFO - Running cycle 1/1 for dromaeo_css test... 09:52:00 INFO - TEST-INFO | started process 23009 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjJDOgl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:52:12 INFO - PROCESS | 23009 | RSS: Main: 143499264 09:52:12 INFO - PROCESS | 23009 | 09:53:03 INFO - PROCESS | 23009 | 1480614783187 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:55:12 INFO - PROCESS | 23009 | Cycle 1(1): loaded http://localhost:56057/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56057/tests/dromaeo/cssquery-ext.html) 09:55:13 INFO - PROCESS | 23009 | RSS: Main: 250675200 09:55:13 INFO - PROCESS | 23009 | 09:58:15 INFO - PROCESS | 23009 | Cycle 1(1): loaded http://localhost:56057/tests/dromaeo/cssquery-ext.html (next: http://localhost:56057/tests/dromaeo/cssquery-jquery.html) 09:58:16 INFO - PROCESS | 23009 | RSS: Main: 235016192 09:58:16 INFO - PROCESS | 23009 | 10:01:18 INFO - PROCESS | 23009 | Cycle 1(1): loaded http://localhost:56057/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56057/tests/dromaeo/cssquery-mootools.html) 10:01:18 INFO - PROCESS | 23009 | RSS: Main: 174481408 10:01:18 INFO - PROCESS | 23009 | 10:04:20 INFO - PROCESS | 23009 | Cycle 1(1): loaded http://localhost:56057/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56057/tests/dromaeo/cssquery-prototype.html) 10:04:21 INFO - PROCESS | 23009 | RSS: Main: 174739456 10:04:21 INFO - PROCESS | 23009 | 10:07:23 INFO - PROCESS | 23009 | Cycle 1(1): loaded http://localhost:56057/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56057/tests/dromaeo/cssquery-yui.html) 10:07:24 INFO - PROCESS | 23009 | RSS: Main: 175124480 10:07:24 INFO - PROCESS | 23009 | 10:10:26 INFO - PROCESS | 23009 | Cycle 1(1): loaded http://localhost:56057/tests/dromaeo/cssquery-yui.html (next: http://localhost:56057/tests/dromaeo/cssquery-dojo.html) 10:10:27 INFO - PROCESS | 23009 | RSS: Main: 262610944 10:10:27 INFO - PROCESS | 23009 | 10:10:27 INFO - PROCESS | 23009 | __start_tp_report 10:10:27 INFO - PROCESS | 23009 | _x_x_mozilla_page_load 10:10:27 INFO - PROCESS | 23009 | _x_x_mozilla_page_load_details 10:10:27 INFO - PROCESS | 23009 | |i|pagename|runs| 10:10:27 INFO - PROCESS | 23009 | |0;dojo.html;3112.750979921606;3145.465270903946;3172.2228054126736;3187.139472342469;3212.132724164476;5510.641808282464;5603.271574695284;5605.383407825136;5606.411326810682;5625.718714064291;5565.359983601887;5588.30146231721;5599.720013999294;5600.4959553640165;5619.157126431027;5090;5093.439721630618;5126.436092029892;5143.125668636322;5169.250458683488;5044.697318160904;5045.571126454256;5117.641765076446;5171.793128274883;5190.377154741436;5048.596112311025;5064.063148317573;5102.719350435725;5120.539151476366;5121.1038068338075;5123.129068058439;5141.35733033369;5199.844004679866;5208.817691380784;5229.738513074331;4858.1012512685265;5100.234964755279;5119.795208191668;5164.122099243138;5180.222966555009;5437.510624043855;5504.697241651707;5508.6970216638065;5534.059209934321;5578.776848926038;5936.436038576328;5945.048792193272;6034.487068599175;6043.214382130339;6054.8183554493435;5910.202122713422;6099.359567245419;6104.267487901436;6116.3577824328695;6119.204503414572;5551.2228288039705;5584.692841893694;5610.999999999959;5622.971885140548;5650.74571644277;6185.165002724664;6191.040388739752;6191.721372538246;6196.8760624787255;6199.690015499297;5684.261046063985;5745.253117094751;5921.319048309413;5937.70311484424;5939.970300148471;5884.9705751470965;5966.463018328284;5971.611845230046;6015.939840601625;6052.425019623129;5966.642001479839;5969.761209551656;5975.52195824333;5978.820635380858;5981.790637327673;5727.656340619576;5947.37552556984;6005.939940600538;6018.187544681412;6033.7586496540525;4520.4575450946095;4594.563516465931;4655.650826188049;4659.091477161988;4728.432588089382;4131.111810960658;4141.772202528829;4145.523264824524;4150.584941505886;4181.979090104591;5818.592698511148;5853.795117170878;5854.853628659318;5865.266841644794;5896.292444906553;5917.52659787216;5958.582899197015;6000.069989151689;6003.519718422515;6015.669138197353;5794.62334948227;5796.884062318814;5807.332156802022;5807.709614519342;5832.62087964281;5150.175971844562;5151.124308867426;5156.123459011977;5156.381234251914;5188.844334669966;5996.790112346047;6025;6027.638341699337;6058.57589968698;6231.657258850807;5921.052631578927;5925;5925.348211696796;5936.22829032223;5967.7911273106965;6096.939030609815;6097.146399504161;6108.267007959073;6111.083337499411;6121.418465245794;5665.830025099253;5682.261306030355;5708.714564271853;5763.9135412968;5771.480566749013;5907.17299578068;5935.881282374242;5953.166556681981;5973.880522389615;5986.4013598639785;5780.682062486603;5821.476067153976;5829.300483941955;5831.79588714393;5832.12518122285;5743.1385292206505;5749.367569567428;5807.883842323215;5808.157817116579;5815.069588865761;5796.913046304225;5846.649201047951;5862.062070068769;5922.230110085834;5939.524838012862;5947.821565353046;5962.284525856925;5964.135200395832;5966.761329546943;5975.223220981245;5610.887782244414;5669.149627555899;5680.829575112753;5796.188533605383;5813.76744930198 10:10:27 INFO - PROCESS | 23009 | |1;ext.html;588.2264821759386;599.7391134856338;608.3186830749559;630.3003665930818;637.9728636894602;33783.15542111448;44885;45780.54219457805;45856.54143458564;45890.54109458905;47599.28601070987;48167.75916120423;48169.51830481694;48227.51772482274;48435.75782121093;8516.318694504456;9300.162985331335;9446.716598502057;9449.527523623825;9453.243740500744;8462.619182136788;10933.890661093406;11172.217944743872;11179.776404471906;11530.308181509094;11411.54353825846;11463.140264480156;11474.139439542027;11480.598179063734;11484.36835974021;11266.929641684068;11328.660140195767;11330.546778128864;11333.15001374892;11350.772984540263;11066.667999959933;11127.666170014912;11133.165012624004;11138.832917506244;11152.721181970435;44523.55476445227;44649.77675111636;44678;44730.105397892185;44861.55138448606;3822.847086116552;3923.7253392262555;4018.0758425561994;4038;4209.600966989473;33571.664283357095;33605.66394336074;33610.66389336124;33658;33730.49404258938;4543.5456454354635;4582.495925448198;4597.471290801567;4708;4712.95287047132;252106;253378;254219;256528;257306;61408.692956534935;61937.69031154906;61939.6903015482;61988.69005654943;62024.37975620276;40039;40765;40775.79612101921;42691.57308426876;64952.350476494634;17383.56541086483;17398.913005434893;17451;17459.21433535494;17500.38748643817;13210.207387556777;13427.3957671905;14095.36570854314;14348.282585870666;14518.782218266735;19351.90324048371;20181.495462613257;20351.287704930255;20407.79592204059;20584.691229631568;7243.167035790846;7253.56478611285;7304.96347518259;7361.8159546011775;7654.961725191339;1035.9223058270659;1049.3389164826262;1072.9217136777488;1131.1233793809863;1144.221929088228;33737;33876.661233387844;34844.477332840026;35531.82234088865;35682.464763028576;1130.0564029035684;1142.9997504367357;1144.8626164860104;1190.2620375367328;1200.6938230751102;17664.116794160498;17749.201285942163;17803.821961780475;17866.374676886247;17884;544826;547749;554382;555521;558812;58795.706021470476;59100.04499774994;59160.11259831106;59239.40760592339;59241;1308.941097650608;1380.0891411668251;1392.192528333564;1421.5024741340656;1454.7745099509593;30110.917627570205;34516;34597;34859;34983;50905.74547127241;51725;51821.48178518166;51828;51829.7408512955;420906;424661;425017;425055;425575;26785;27615.58576621312;30262.54606180953;30286.242843929078;30356.24109397283;48676;49140.262896057306;49315;49764.502354975986;50771.74614126905;42039.3694094595;42210.78894605507;42904.06343605324;43268.78365608152;43395.13209735851;42321.365179522945;42525.36211956885;42879.785601071795;43401;44072;41507.88690848138;41706.582934171485;42295.5770442304;42401.36397954095;42658.36012459754;34229.828850855585;36956.63043369532;37215.62784372122;37825.62174378221;38175;28159.57760633551;28257.204704380147;28309.433811323248;28314.716852831207;28340 10:10:27 INFO - PROCESS | 23009 | |2;jquery.html;424.8767857321377;425.6615990287721;433.81621790247414;438.59210933831525;441.5474139007519;18194.636107277845;18693.62612747744;20092.598148037032;20125.396238112862;20225.292114775984;19268.903655481703;19382.806171938275;19788.60422791543;20042.69935950958;20190.69713954288;5844.357120716729;6077.848053798668;6105.786297479589;6119.5716299858805;6138.324784273726;5708.885822283572;5967.731452084645;5981.28224613047;6063.272407311107;6071.028635418334;6079.6960151992225;6099.603525770833;6109.3890610939;6115.29674087479;6116.877662446748;5898.262717160355;5963.224780778514;6013.6993150342305;6022.09668549721;6101.054336577838;5838.328592211865;5843.123531470271;5846.035404158309;5846.239988801429;5853.385394533555;22454.663180052314;23062.308130756104;23096.307110786704;23972.880135599386;24218.757812421827;2505.1232068775785;2535.657686212356;2550.732173121814;2725.5775354820034;2785.8467784271857;11124.110071194285;11224.270422422516;11396.601118960796;11824.586139485076;14730.852691473056;1881.7177423386602;1900.4963684623585;1902.3627084926566;1974.7630284365825;1978.0505357428372;61524.75002144694;67706.66146669236;70997;86118.56940715319;88935.55532222362;11139.498722557504;11465.656030319104;11930.22453540517;12071.456784444721;12249.387530623433;16986.06576638272;17004.744928826076;17028.148592570324;17038.74441883373;17045.23296451663;8342.83314333722;8772.78068048304;9001.594928228245;9101.772455688528;9119.498427586546;8044.718434854752;8701.738947831574;8739.30085593151;8907.10928907117;8922.197002269826;5089.465606111379;5147.1507201312115;5179.870503237374;5247.553957913544;5285.048691235615;2063.607914496241;2090.7491101067967;2100.317396846031;2107.79975902289;2122.5224324527167;890.7817584691792;1006.33079002464;1051.0712841935135;1069.5935544493043;1100.5542755184163;18709.719354209697;19027.90486047561;20074.39776806698;20172.19311227564;20179.697304540445;1019.5463018956493;1056.7569459024467;1078.660222030058;1100.1858624617726;1123.550579768083;10570;11214.66356009321;11460.140489463323;11538.307701537935;11602.883971160349;364073;366650;366735;467287;471724;82303;96290.51854740681;97006;98231.50884245533;98993.50503247582;942.5569982108398;966.8114717629994;1033.0960409641564;1092.7978324010085;1098.6154845803906;17742.379016734354;17883.463496095137;17963.820361796214;18050.819491804912;18483.075846207907;41001.384979225935;41247.58752412438;41490.170196596504;41828.790856045525;42308.36537451879;53994.730026349614;54034.18948715694;54532.18201726898;55594.444055558924;56877.431225688866;8938.86591701112;9118.133777291147;9169.174774270348;9180.908190918273;9189.172974432333;13424.664383390493;13457.528986485426;13782.793258100935;13813.585592432242;13932.442702291795;12058.276503409823;12145.635630931087;12184.512619495476;12325.137240393087;12375.62873113808;10951.726206844893;11594.536218551164;11954.70113247141;12299.631011069681;12350.876491235333;11181.211006238962;11671.416429178338;12197.573084941985;12244.510219591117;12424.06819488524;7929.762107136795;8359.791005224917;9102.817943641223;9119.042500537347;9226.215771659485;10287.485625718833;11876.76246475083;11963.940180299043;12039.819402709141;12280.38598070075 10:10:27 INFO - PROCESS | 23009 | |3;mootools.html;1503.5414198669405;1567.6707891342817;1724.8533874620657;1806.6386722655473;1854.6754317994348;8697.826043479134;9331.206847417969;9335.62657493701;9339.299552533561;9353.298502612315;7884.684612615489;8114.91885081149;8122.309603683694;8331.458455200407;9128.726138215865;2405.566997940374;2489.676342075528;2490.5018996200743;2490.7260201377835;2495.43852633157;2520.9747902521017;2526.2421273617933;2541.834780739255;2552.4767422678306;2560.0143944581378;2516.3331717094984;2523.5457617628817;2538.4669219463935;2541.606051062088;2544.0841297133015;2485.254423672909;2488.8008959283216;2491.4643351679383;2495.3886297857052;2507.1601013660447;2482.094035676983;2485.8135639826905;2490.0786708917817;2490.9003639854386;2491.6013437849856;9074.18332350085;9242.953785231099;9275.768105797342;9277.211437027861;9284.535773211312;5414.3232095988005;5502.5597952163735;5535.975844468786;5547.223388725582;5548.805791797267;3418.8803391881165;3460.4290292101773;3600.0999750062483;3618.0773902654655;3632.291703117893;1220.200768496636;1254.9686257843537;1262.3688155922039;1274.8151518029927;1275.7384736129072;12467.189632673848;12490.250584964931;12491.75016499674;12508.562200322946;12534.87465125337;4873.95126048735;4904.337914381585;5045.369328833895;5124.15451450514;5160.329157209614;10600.04599586041;10614.469276536141;10664.146868250522;10667.093297069681;10671.626493072705;5528.419515950848;5551.028570000234;5552.972235138879;5554.750036248379;5555.166724991283;4974.527419895104;5020.523050310215;5020.573251273682;5027.120253955616;5040.420351659606;4352.69531132824;4359.738415695068;4360.65114790816;4437.711548749321;4451.132029254264;1385.681293302546;1410.605030591436;1415.1367665723901;1424.309210033133;1436.4182506085053;854.9316054715608;963.1957315641378;1023.8003589300015;1044.6604853422668;1045.2056437107701;5896.3219229788265;6078.331383547807;6124.663143527059;6294.559380843297;6335.208098987627;488.35537091039487;492.75608573755505;495.1804763117047;520.5185203686575;524.0279281931961;4106.7125301228625;4147.681854516375;4149.439825623563;4205.76868272248;4218.282891908383;10989;11141.721456963478;11270.718232044264;11284.32294062359;11378.03286720638;11247.212695111428;11261.774764504828;11262.943685281685;11270.154738394653;11286.266392684449;8278.958605206935;8361.122082181284;8411.621477033548;8450.112738162527;8463.49219046859;5986.2517185351835;6095.725692343944;6176.66028368444;6177.320494745484;6256.436920676979;10245.385276883411;10265.948670256601;10349.27555071168;10363.274570780275;10447.94776026115;10474.790504189721;10524.263301568817;10619.787604248026;10677.039066484054;10760.46197690128;7982.361411087215;8159;8166.060902995994;8167.79580510492;8173;8739.868901966604;8783.5169065702;8799.604017819085;8876.068012858557;8957.14907083826;7979;8038.437309388287;8054.23484768946;8074.83850323002;8082.919170808452;7881.684732610861;7908.762737117896;7945.721899733481;7982.521048737094;8100.35197184212;7314.926850731425;7404.555726656418;7456.888146677696;7478.70085196586;7479.326860582573;5229.346331708536;5247.816326428557;5265.6050796189675;5337.706426146599;5426.186072089218;5166.870828229325;5175.275461435477;5243.108671525773;5304.443033481429;5342.75957581902 10:10:27 INFO - PROCESS | 23009 | |4;prototype.html;332.94007078725826;340.8363985287063;349.8985294264663;349.9125218695326;368.9409694448887;5509.972450137749;5530.9999999999945;5541.556675465963;5560.471755183251;5562.499375056249;5498.697571633558;5502.532284755791;5504.422035686255;5521.503064724174;5522.723863806814;3848.780302742894;4117.547069822324;4197.601227883346;4198.706090573661;4257.467816522934;4258.510271318807;4294.398784170218;4300.3764454154125;4301.612854843063;4323.891902702426;4289.549597292286;4291.871243862689;4296.9140617187795;4311.590398912098;4327.63215126713;4070.979645101785;4259.999999999969;4316.395704601358;4321.373400856858;4324.351347297899;3768.246350729865;3779.527559055118;3796.2976849282973;3800.90497737556;3831.17629709611;5278.366596008465;5307.097793375136;5314.521693047605;5338.306020217386;5372.516473517402;4805.471398146201;4921.71140419703;4924.187509061001;4928.433230178503;4928.43323017854;5660.320761508604;5690.687012214326;5695.487406133468;5697.202391665171;5733.365990692674;4170.374443833419;4176.66346769034;4180.352045432963;4189.49726032875;4189.685773566995;5743.569232307552;5948.483136800175;5955.188679245243;5978.282606087254;5984.551158663117;4262.25410553152;4264.933766558361;4269.402283680287;4278.507971583308;4278.828846846153;4326.783660816946;4329.350597410414;4358.280883654225;4373.387725718402;4384.583464570861;4845.491223421561;4939.876503087452;4952.2819191217795;4952.603791696655;4971.1797553404;5723.255976723;5731.369549349568;5735.741891614887;5740.42595740431;5751.568632352591;1450.9564713058626;1483.68842543065;1489.5456885649764;1498.1535432480614;1502.1963249661378;918.4718786697675;925.8657494663236;933.5052422216236;954.8472244440856;978.4178413843752;4955.331030310934;4985.280078372955;5014.796448852249;5020.723860187724;5028.899422011539;5214.947850521522;5228.640553456053;5236.6857988520815;5258.474152584711;5262.657927234794;4985.401751789808;5046.44489106198;5047.9495205048215;5313.787448502017;5720.2849643794525;3125.6874312569016;3132.7337176340266;3143.2141964508874;3151.779375443697;3170.3659268146002;6131.938680613226;6144.692765361803;6148.385161483816;6158.784442544489;6179.69101544921;5612.887742245132;5980.342162362137;6037.396260374016;6090.147379366892;6098.786542470993;5898.962034552209;5975.342712301644;6040.60736052164;6165.167702360213;6171.321154673072;5921.85195370102;5948.762049517971;5954.46409823118;5977.372375900642;5987;5881.235439392852;5888.234529511136;5896.380880007538;5896.55775816824;5902.2622172228475;6129.877402452015;6148.631082135086;6151.72317245716;6154.1999540059505;6171;4239.406483092308;4263.808128634281;4277.443932288783;4280.636145927631;4295.37717031035;5709.714514274353;5919.881602367843;5948.553858460719;5975;5993.61041532299;5788.421157884178;5805.068440437552;5807.186993820953;5815.680137592473;5817.243758311392;5607.579431542569;5732.283464566929;5816.970915145397;5819.418058194146;5834.3080506652595;5718.227349521661;5735.283089613798;5737.684427356536;5751.9424805751405;5754.424557544379;5787.739551720267;5800.59395842287;5821.38875418075;5827.4172582741385;5840.977828880014;5425.294711687456;5639.295088113986;5654.088144605614;5675.432456754292;5677.1768093627015 10:10:27 INFO - PROCESS | 23009 | |5;yui.html;733.1385621894276;786.543804593336;792.8493586218619;793.6388943030921;824.1428913929514;14488.492902748407;15267.312970916308;15302.54092377229;15306.158161301124;15321.080735155898;14230.07504512206;14388.424463021467;14400.639984000405;14646.780298295535;14678.779818302733;699.5452955578863;715.4276578737016;805.3637626275236;810.0481933727875;826.9090400055989;835.1982097186677;835.3526017336583;835.7534527314457;837.5937670229933;838.0864857305536;899.244634507014;900.1268769293774;901.5942825728416;902.1474706402453;902.4179404284233;889.7553172877446;891.6789955615973;891.9108089191061;892.0232345581578;894.029977474441;655.5378458186967;656.2715385921623;657.8223879552494;657.8717150155721;658.0984051848998;14948;14951.177685227309;14978;14995.400183992628;14999.250037498081;16003.91998040014;16173.110478923654;16249.675006499921;16252.918735406365;16513.504594862174;11651.533938642444;11769.58806441779;11882.643520694393;11932.522699092113;12393.132480726354;3848.3457812171996;3884.281407939512;3891.5524714657895;3904;3991.960080399188;81439.18560814376;81678.59160704218;81743.59128204321;82461;82801.58599207025;11797.410129493663;11958;12289.93855030737;12365.319907405179;12375.195612285353;20695.27566535164;20801.895990520254;20826.583468330547;20858.687119693215;20871.791282087288;8940.32947528938;9022.233110185576;9152.130547597968;9240.722778316662;9257.166854983085;4669.719816811003;4873.853784386403;4908.386451693538;4973.52751488608;4998.325226094504;3564.144605294711;3701.056230661164;3703.5926048134684;3705.3330400527625;3705.796181210005;636.5003472274243;640.5964242527178;645.6578013652771;653.9019147127874;655.5378458186967;680.2755065854948;747.1781040855125;749.8950146979428;789.9763007109796;802.2659266770956;9793.608255669693;9801.754956126153;10357.223208259411;10621.468926553642;10991.175661825395;726.6512074204305;760.9162992070868;793.3732351442296;796.5817945578617;831.501099340391;4991.7004979700605;5216.713080780593;5269.288646032813;5269.288646032813;5323.387810401853;73593;73854.26145738474;73953;82337;120719;108385;111258;113279;114168.42915785534;117374;60045;74811.62594186995;94890.52554737183;97641;99418.99999999856;9337.299702522474;9552.42685438876;9660.227181825578;9870.161036311994;9890.653827116017;56202.1569676447;56365.71817140888;57226.7138664304;57525;57787.1331930013;76504;82842;86302.56848715716;86931;89236.5538172305;11978.520859165536;12124.939375303067;12154;12275.140740148105;12880;13172.538961136313;13216.801747973595;13398.488944340288;13521.797173042216;13555.59333220005;12070.155089143676;12082.33547154915;12171.634850954486;12274.877251227374;14136.50522231717;14238.715225695221;14264.358103885143;14631.92684036573;15147.091174529563;15340;13699.178049317074;14231.50189743354;14727.631809204855;14806.481773137888;14860.62848428798;12094.274343539415;12175.634730958085;12989.350532473149;13016.804747928978;13373.933130334286;11800.081917263797;12019;12271;12314.261144331369;12404.689882753004 10:10:27 INFO - PROCESS | 23009 | __end_tp_report 10:10:27 INFO - PROCESS | 23009 | __start_cc_report 10:10:27 INFO - PROCESS | 23009 | _x_x_mozilla_cycle_collect,898 10:10:27 INFO - PROCESS | 23009 | __end_cc_report 10:10:27 INFO - PROCESS | 23009 | __startTimestamp1480615827043__endTimestamp 10:10:27 INFO - PROCESS | 23009 | 10:10:27 INFO - PROCESS | 23009 | ------- Summary: start ------- 10:10:27 INFO - PROCESS | 23009 | Number of tests: 6 10:10:27 INFO - PROCESS | 23009 | 10:10:27 INFO - PROCESS | 23009 | [#0] dojo.html Cycles:165 Average:5563.48 Median:5796.91 stddev:626.04 (10.8%) stddev-sans-first:597.71 10:10:27 INFO - PROCESS | 23009 | Values: 3112.8 3145.5 3172.2 3187.1 3212.1 5510.6 5603.3 5605.4 5606.4 5625.7 5565.4 5588.3 5599.7 5600.5 5619.2 5090.0 5093.4 5126.4 5143.1 5169.3 5044.7 5045.6 5117.6 5171.8 5190.4 5048.6 5064.1 5102.7 5120.5 5121.1 5123.1 5141.4 5199.8 5208.8 5229.7 4858.1 5100.2 5119.8 5164.1 5180.2 5437.5 5504.7 5508.7 5534.1 5578.8 5936.4 5945.0 6034.5 6043.2 6054.8 5910.2 6099.4 6104.3 6116.4 6119.2 5551.2 5584.7 5611.0 5623.0 5650.7 6185.2 6191.0 6191.7 6196.9 6199.7 5684.3 5745.3 5921.3 5937.7 5940.0 5885.0 5966.5 5971.6 6015.9 6052.4 5966.6 5969.8 5975.5 5978.8 5981.8 5727.7 5947.4 6005.9 6018.2 6033.8 4520.5 4594.6 4655.7 4659.1 4728.4 4131.1 4141.8 4145.5 4150.6 4182.0 5818.6 5853.8 5854.9 5865.3 5896.3 5917.5 5958.6 6000.1 6003.5 6015.7 5794.6 5796.9 5807.3 5807.7 5832.6 5150.2 5151.1 5156.1 5156.4 5188.8 5996.8 6025.0 6027.6 6058.6 6231.7 5921.1 5925.0 5925.3 5936.2 5967.8 6096.9 6097.1 6108.3 6111.1 6121.4 5665.8 5682.3 5708.7 5763.9 5771.5 5907.2 5935.9 5953.2 5973.9 5986.4 5780.7 5821.5 5829.3 5831.8 5832.1 5743.1 5749.4 5807.9 5808.2 5815.1 5796.9 5846.6 5862.1 5922.2 5939.5 5947.8 5962.3 5964.1 5966.8 5975.2 5610.9 5669.1 5680.8 5796.2 5813.8 10:10:27 INFO - PROCESS | 23009 | 10:10:27 INFO - PROCESS | 23009 | [#1] ext.html Cycles:180 Average:58414.29 Median:3873.29 stddev:113567.02 (2932.1%) stddev-sans-first:113802.62 10:10:27 INFO - PROCESS | 23009 | Values: 588.2 599.7 608.3 630.3 638.0 33783.2 44885.0 45780.5 45856.5 45890.5 47599.3 48167.8 48169.5 48227.5 48435.8 8516.3 9300.2 9446.7 9449.5 9453.2 8462.6 10933.9 11172.2 11179.8 11530.3 11411.5 11463.1 11474.1 11480.6 11484.4 11266.9 11328.7 11330.5 11333.2 11350.8 11066.7 11127.7 11133.2 11138.8 11152.7 44523.6 44649.8 44678.0 44730.1 44861.6 3822.8 3923.7 4018.1 4038.0 4209.6 33571.7 33605.7 33610.7 33658.0 33730.5 4543.5 4582.5 4597.5 4708.0 4713.0 252106.0 253378.0 254219.0 256528.0 257306.0 61408.7 61937.7 61939.7 61988.7 62024.4 40039.0 40765.0 40775.8 42691.6 64952.4 17383.6 17398.9 17451.0 17459.2 17500.4 13210.2 13427.4 14095.4 14348.3 14518.8 19351.9 20181.5 20351.3 20407.8 20584.7 7243.2 7253.6 7305.0 7361.8 7655.0 1035.9 1049.3 1072.9 1131.1 1144.2 33737.0 33876.7 34844.5 35531.8 35682.5 1130.1 1143.0 1144.9 1190.3 1200.7 17664.1 17749.2 17803.8 17866.4 17884.0 544826.0 547749.0 554382.0 555521.0 558812.0 58795.7 59100.0 59160.1 59239.4 59241.0 1308.9 1380.1 1392.2 1421.5 1454.8 30110.9 34516.0 34597.0 34859.0 34983.0 50905.7 51725.0 51821.5 51828.0 51829.7 420906.0 424661.0 425017.0 425055.0 425575.0 26785.0 27615.6 30262.5 30286.2 30356.2 48676.0 49140.3 49315.0 49764.5 50771.7 42039.4 42210.8 42904.1 43268.8 43395.1 42321.4 42525.4 42879.8 43401.0 44072.0 41507.9 41706.6 42295.6 42401.4 42658.4 34229.8 36956.6 37215.6 37825.6 38175.0 28159.6 28257.2 28309.4 28314.7 28340.0 10:10:27 INFO - PROCESS | 23009 | 10:10:27 INFO - PROCESS | 23009 | [#2] jquery.html Cycles:180 Average:26887.94 Median:24095.82 stddev:68085.03 (282.6%) stddev-sans-first:68247.03 10:10:27 INFO - PROCESS | 23009 | Values: 424.9 425.7 433.8 438.6 441.5 18194.6 18693.6 20092.6 20125.4 20225.3 19268.9 19382.8 19788.6 20042.7 20190.7 5844.4 6077.8 6105.8 6119.6 6138.3 5708.9 5967.7 5981.3 6063.3 6071.0 6079.7 6099.6 6109.4 6115.3 6116.9 5898.3 5963.2 6013.7 6022.1 6101.1 5838.3 5843.1 5846.0 5846.2 5853.4 22454.7 23062.3 23096.3 23972.9 24218.8 2505.1 2535.7 2550.7 2725.6 2785.8 11124.1 11224.3 11396.6 11824.6 14730.9 1881.7 1900.5 1902.4 1974.8 1978.1 61524.8 67706.7 70997.0 86118.6 88935.6 11139.5 11465.7 11930.2 12071.5 12249.4 16986.1 17004.7 17028.1 17038.7 17045.2 8342.8 8772.8 9001.6 9101.8 9119.5 8044.7 8701.7 8739.3 8907.1 8922.2 5089.5 5147.2 5179.9 5247.6 5285.0 2063.6 2090.7 2100.3 2107.8 2122.5 890.8 1006.3 1051.1 1069.6 1100.6 18709.7 19027.9 20074.4 20172.2 20179.7 1019.5 1056.8 1078.7 1100.2 1123.6 10570.0 11214.7 11460.1 11538.3 11602.9 364073.0 366650.0 366735.0 467287.0 471724.0 82303.0 96290.5 97006.0 98231.5 98993.5 942.6 966.8 1033.1 1092.8 1098.6 17742.4 17883.5 17963.8 18050.8 18483.1 41001.4 41247.6 41490.2 41828.8 42308.4 53994.7 54034.2 54532.2 55594.4 56877.4 8938.9 9118.1 9169.2 9180.9 9189.2 13424.7 13457.5 13782.8 13813.6 13932.4 12058.3 12145.6 12184.5 12325.1 12375.6 10951.7 11594.5 11954.7 12299.6 12350.9 11181.2 11671.4 12197.6 12244.5 12424.1 7929.8 8359.8 9102.8 9119.0 9226.2 10287.5 11876.8 11963.9 12039.8 12280.4 10:10:27 INFO - PROCESS | 23009 | 10:10:27 INFO - PROCESS | 23009 | [#3] mootools.html Cycles:180 Average:6014.76 Median:2698.55 stddev:3373.97 (125.0%) stddev-sans-first:3366.40 10:10:27 INFO - PROCESS | 23009 | Values: 1503.5 1567.7 1724.9 1806.6 1854.7 8697.8 9331.2 9335.6 9339.3 9353.3 7884.7 8114.9 8122.3 8331.5 9128.7 2405.6 2489.7 2490.5 2490.7 2495.4 2521.0 2526.2 2541.8 2552.5 2560.0 2516.3 2523.5 2538.5 2541.6 2544.1 2485.3 2488.8 2491.5 2495.4 2507.2 2482.1 2485.8 2490.1 2490.9 2491.6 9074.2 9243.0 9275.8 9277.2 9284.5 5414.3 5502.6 5536.0 5547.2 5548.8 3418.9 3460.4 3600.1 3618.1 3632.3 1220.2 1255.0 1262.4 1274.8 1275.7 12467.2 12490.3 12491.8 12508.6 12534.9 4874.0 4904.3 5045.4 5124.2 5160.3 10600.0 10614.5 10664.1 10667.1 10671.6 5528.4 5551.0 5553.0 5554.8 5555.2 4974.5 5020.5 5020.6 5027.1 5040.4 4352.7 4359.7 4360.7 4437.7 4451.1 1385.7 1410.6 1415.1 1424.3 1436.4 854.9 963.2 1023.8 1044.7 1045.2 5896.3 6078.3 6124.7 6294.6 6335.2 488.4 492.8 495.2 520.5 524.0 4106.7 4147.7 4149.4 4205.8 4218.3 10989.0 11141.7 11270.7 11284.3 11378.0 11247.2 11261.8 11262.9 11270.2 11286.3 8279.0 8361.1 8411.6 8450.1 8463.5 5986.3 6095.7 6176.7 6177.3 6256.4 10245.4 10265.9 10349.3 10363.3 10447.9 10474.8 10524.3 10619.8 10677.0 10760.5 7982.4 8159.0 8166.1 8167.8 8173.0 8739.9 8783.5 8799.6 8876.1 8957.1 7979.0 8038.4 8054.2 8074.8 8082.9 7881.7 7908.8 7945.7 7982.5 8100.4 7314.9 7404.6 7456.9 7478.7 7479.3 5229.3 5247.8 5265.6 5337.7 5426.2 5166.9 5175.3 5243.1 5304.4 5342.8 10:10:27 INFO - PROCESS | 23009 | 10:10:27 INFO - PROCESS | 23009 | [#4] prototype.html Cycles:180 Average:4822.69 Median:5500.61 stddev:1413.89 (25.7%) stddev-sans-first:1377.11 10:10:27 INFO - PROCESS | 23009 | Values: 332.9 340.8 349.9 349.9 368.9 5510.0 5531.0 5541.6 5560.5 5562.5 5498.7 5502.5 5504.4 5521.5 5522.7 3848.8 4117.5 4197.6 4198.7 4257.5 4258.5 4294.4 4300.4 4301.6 4323.9 4289.5 4291.9 4296.9 4311.6 4327.6 4071.0 4260.0 4316.4 4321.4 4324.4 3768.2 3779.5 3796.3 3800.9 3831.2 5278.4 5307.1 5314.5 5338.3 5372.5 4805.5 4921.7 4924.2 4928.4 4928.4 5660.3 5690.7 5695.5 5697.2 5733.4 4170.4 4176.7 4180.4 4189.5 4189.7 5743.6 5948.5 5955.2 5978.3 5984.6 4262.3 4264.9 4269.4 4278.5 4278.8 4326.8 4329.4 4358.3 4373.4 4384.6 4845.5 4939.9 4952.3 4952.6 4971.2 5723.3 5731.4 5735.7 5740.4 5751.6 1451.0 1483.7 1489.5 1498.2 1502.2 918.5 925.9 933.5 954.8 978.4 4955.3 4985.3 5014.8 5020.7 5028.9 5214.9 5228.6 5236.7 5258.5 5262.7 4985.4 5046.4 5047.9 5313.8 5720.3 3125.7 3132.7 3143.2 3151.8 3170.4 6131.9 6144.7 6148.4 6158.8 6179.7 5612.9 5980.3 6037.4 6090.1 6098.8 5899.0 5975.3 6040.6 6165.2 6171.3 5921.9 5948.8 5954.5 5977.4 5987.0 5881.2 5888.2 5896.4 5896.6 5902.3 6129.9 6148.6 6151.7 6154.2 6171.0 4239.4 4263.8 4277.4 4280.6 4295.4 5709.7 5919.9 5948.6 5975.0 5993.6 5788.4 5805.1 5807.2 5815.7 5817.2 5607.6 5732.3 5817.0 5819.4 5834.3 5718.2 5735.3 5737.7 5751.9 5754.4 5787.7 5800.6 5821.4 5827.4 5841.0 5425.3 5639.3 5654.1 5675.4 5677.2 10:10:27 INFO - PROCESS | 23009 | 10:10:27 INFO - PROCESS | 23009 | [#5] yui.html Cycles:180 Average:21065.41 Median:4891.12 stddev:29991.59 (613.2%) stddev-sans-first:30036.86 10:10:27 INFO - PROCESS | 23009 | Values: 733.1 786.5 792.8 793.6 824.1 14488.5 15267.3 15302.5 15306.2 15321.1 14230.1 14388.4 14400.6 14646.8 14678.8 699.5 715.4 805.4 810.0 826.9 835.2 835.4 835.8 837.6 838.1 899.2 900.1 901.6 902.1 902.4 889.8 891.7 891.9 892.0 894.0 655.5 656.3 657.8 657.9 658.1 14948.0 14951.2 14978.0 14995.4 14999.3 16003.9 16173.1 16249.7 16252.9 16513.5 11651.5 11769.6 11882.6 11932.5 12393.1 3848.3 3884.3 3891.6 3904.0 3992.0 81439.2 81678.6 81743.6 82461.0 82801.6 11797.4 11958.0 12289.9 12365.3 12375.2 20695.3 20801.9 20826.6 20858.7 20871.8 8940.3 9022.2 9152.1 9240.7 9257.2 4669.7 4873.9 4908.4 4973.5 4998.3 3564.1 3701.1 3703.6 3705.3 3705.8 636.5 640.6 645.7 653.9 655.5 680.3 747.2 749.9 790.0 802.3 9793.6 9801.8 10357.2 10621.5 10991.2 726.7 760.9 793.4 796.6 831.5 4991.7 5216.7 5269.3 5269.3 5323.4 73593.0 73854.3 73953.0 82337.0 120719.0 108385.0 111258.0 113279.0 114168.4 117374.0 60045.0 74811.6 94890.5 97641.0 99419.0 9337.3 9552.4 9660.2 9870.2 9890.7 56202.2 56365.7 57226.7 57525.0 57787.1 76504.0 82842.0 86302.6 86931.0 89236.6 11978.5 12124.9 12154.0 12275.1 12880.0 13172.5 13216.8 13398.5 13521.8 13555.6 12070.2 12082.3 12171.6 12274.9 14136.5 14238.7 14264.4 14631.9 15147.1 15340.0 13699.2 14231.5 14727.6 14806.5 14860.6 12094.3 12175.6 12989.4 13016.8 13373.9 11800.1 12019.0 12271.0 12314.3 12404.7 10:10:27 INFO - PROCESS | 23009 | -------- Summary: end -------- 10:10:27 INFO - PROCESS | 23009 | 10:10:27 INFO - TEST-INFO | 23009: exit 0 10:10:27 INFO - TEST-OK | dromaeo_css | took 1112808ms 10:10:27 INFO - TEST-START | kraken 10:10:27 INFO - operating with platform_type : linux_ 10:10:27 INFO - Initialising browser for kraken test... 10:10:27 INFO - TEST-INFO | started process 23318 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ0YjWe/profile http://localhost:56057/getInfo.html) 10:10:31 INFO - PROCESS | 23318 | __metrics Screen width/height:1600/1200 10:10:31 INFO - PROCESS | 23318 | colorDepth:24 10:10:31 INFO - PROCESS | 23318 | Browser inner width/height: 1024/697 10:10:31 INFO - PROCESS | 23318 | __metrics 10:10:31 INFO - PROCESS | 23318 | 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" 10:10:31 INFO - PROCESS | 23318 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:10:32 INFO - PROCESS | 23318 | console.error: PushService: 10:10:32 INFO - PROCESS | 23318 | stateChangeProcessEnqueue: Error transitioning state 10:10:32 INFO - PROCESS | 23318 | UnknownError 10:10:32 INFO - TEST-INFO | 23318: exit 0 10:10:32 INFO - Browser initialized. 10:10:32 INFO - Running cycle 1/1 for kraken test... 10:10:32 INFO - TEST-INFO | started process 23409 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ0YjWe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:10:44 INFO - PROCESS | 23409 | RSS: Main: 143499264 10:10:44 INFO - PROCESS | 23409 | 10:10:47 INFO - PROCESS | 23409 | Cycle 1(1): loaded http://localhost:56057/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56057/tests/kraken/driver.html?testName=audio-beat-detection) 10:10:48 INFO - PROCESS | 23409 | RSS: Main: 186544128 10:10:48 INFO - PROCESS | 23409 | 10:10:50 INFO - PROCESS | 23409 | Cycle 1(1): loaded http://localhost:56057/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56057/tests/kraken/driver.html?testName=audio-dft) 10:10:51 INFO - PROCESS | 23409 | RSS: Main: 174456832 10:10:51 INFO - PROCESS | 23409 | 10:10:54 INFO - PROCESS | 23409 | Cycle 1(1): loaded http://localhost:56057/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56057/tests/kraken/driver.html?testName=audio-fft) 10:10:54 INFO - PROCESS | 23409 | RSS: Main: 266231808 10:10:54 INFO - PROCESS | 23409 | 10:10:56 INFO - PROCESS | 23409 | Cycle 1(1): loaded http://localhost:56057/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56057/tests/kraken/driver.html?testName=audio-oscillator) 10:10:57 INFO - PROCESS | 23409 | RSS: Main: 172011520 10:10:57 INFO - PROCESS | 23409 | 10:10:59 INFO - PROCESS | 23409 | Cycle 1(1): loaded http://localhost:56057/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56057/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:10:59 INFO - PROCESS | 23409 | RSS: Main: 173826048 10:10:59 INFO - PROCESS | 23409 | 10:11:02 INFO - PROCESS | 23409 | Cycle 1(1): loaded http://localhost:56057/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56057/tests/kraken/driver.html?testName=imaging-darkroom) 10:11:03 INFO - PROCESS | 23409 | RSS: Main: 209485824 10:11:03 INFO - PROCESS | 23409 | 10:11:06 INFO - PROCESS | 23409 | Cycle 1(1): loaded http://localhost:56057/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56057/tests/kraken/driver.html?testName=imaging-desaturate) 10:11:07 INFO - PROCESS | 23409 | RSS: Main: 209195008 10:11:07 INFO - PROCESS | 23409 | 10:11:10 INFO - PROCESS | 23409 | Cycle 1(1): loaded http://localhost:56057/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56057/tests/kraken/driver.html?testName=json-parse-financial) 10:11:10 INFO - PROCESS | 23409 | RSS: Main: 208797696 10:11:10 INFO - PROCESS | 23409 | 10:11:12 INFO - PROCESS | 23409 | Cycle 1(1): loaded http://localhost:56057/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56057/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:11:12 INFO - PROCESS | 23409 | RSS: Main: 170090496 10:11:12 INFO - PROCESS | 23409 | 10:11:14 INFO - PROCESS | 23409 | Cycle 1(1): loaded http://localhost:56057/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56057/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:11:15 INFO - PROCESS | 23409 | RSS: Main: 176943104 10:11:15 INFO - PROCESS | 23409 | 10:11:17 INFO - PROCESS | 23409 | Cycle 1(1): loaded http://localhost:56057/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56057/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:11:17 INFO - PROCESS | 23409 | RSS: Main: 187166720 10:11:17 INFO - PROCESS | 23409 | 10:11:20 INFO - PROCESS | 23409 | Cycle 1(1): loaded http://localhost:56057/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56057/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:11:20 INFO - PROCESS | 23409 | RSS: Main: 195039232 10:11:20 INFO - PROCESS | 23409 | 10:11:23 INFO - PROCESS | 23409 | Cycle 1(1): loaded http://localhost:56057/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56057/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:11:24 INFO - PROCESS | 23409 | RSS: Main: 192294912 10:11:24 INFO - PROCESS | 23409 | 10:11:25 INFO - PROCESS | 23409 | Cycle 1(1): loaded http://localhost:56057/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56057/tests/kraken/driver.html?testName=ai-astar) 10:11:26 INFO - PROCESS | 23409 | RSS: Main: 244404224 10:11:26 INFO - PROCESS | 23409 | 10:11:26 INFO - PROCESS | 23409 | __start_tp_report 10:11:26 INFO - PROCESS | 23409 | _x_x_mozilla_page_load 10:11:26 INFO - PROCESS | 23409 | _x_x_mozilla_page_load_details 10:11:26 INFO - PROCESS | 23409 | |i|pagename|runs| 10:11:26 INFO - PROCESS | 23409 | |0;ai-astar;112;111;109;109;110;110;110;110;110;110 10:11:26 INFO - PROCESS | 23409 | |1;audio-beat-detection;148;117;116;162;121;116;117;116;151;126 10:11:26 INFO - PROCESS | 23409 | |2;audio-dft;159;161;160;160;162;161;161;160;161;159 10:11:26 INFO - PROCESS | 23409 | |3;audio-fft;75;77;76;105;76;76;78;75;97;74 10:11:26 INFO - PROCESS | 23409 | |4;audio-oscillator;81;81;124;82;81;80;105;81;81;110 10:11:26 INFO - PROCESS | 23409 | |5;imaging-gaussian-blur;117;116;117;117;116;116;116;117;117;117 10:11:26 INFO - PROCESS | 23409 | |6;imaging-darkroom;147;147;145;146;147;147;145;146;145;146 10:11:26 INFO - PROCESS | 23409 | |7;imaging-desaturate;95;96;95;95;95;95;95;95;95;94 10:11:26 INFO - PROCESS | 23409 | |8;json-parse-financial;49;50;49;49;50;58;49;49;49;50 10:11:26 INFO - PROCESS | 23409 | |9;json-stringify-tinderbox;58;58;59;60;58;58;58;58;57;58 10:11:26 INFO - PROCESS | 23409 | |10;stanford-crypto-aes;76;80;74;73;73;77;77;77;73;75 10:11:26 INFO - PROCESS | 23409 | |11;stanford-crypto-ccm;124;122;123;124;121;123;127;127;169;118 10:11:26 INFO - PROCESS | 23409 | |12;stanford-crypto-pbkdf2;150;153;152;154;154;150;152;151;155;161 10:11:26 INFO - PROCESS | 23409 | |13;stanford-crypto-sha256-iterative;62;62;65;63;62;61;64;62;61;60 10:11:26 INFO - PROCESS | 23409 | __end_tp_report 10:11:26 INFO - PROCESS | 23409 | __start_cc_report 10:11:26 INFO - PROCESS | 23409 | _x_x_mozilla_cycle_collect,1598 10:11:26 INFO - PROCESS | 23409 | __end_cc_report 10:11:26 INFO - PROCESS | 23409 | __startTimestamp1480615886035__endTimestamp 10:11:26 INFO - PROCESS | 23409 | 10:11:26 INFO - PROCESS | 23409 | ------- Summary: start ------- 10:11:26 INFO - PROCESS | 23409 | Number of tests: 14 10:11:26 INFO - PROCESS | 23409 | 10:11:26 INFO - PROCESS | 23409 | [#0] ai-astar Cycles:10 Average:110.10 Median:110.00 stddev:0.88 (0.8%) stddev-sans-first:0.60 10:11:26 INFO - PROCESS | 23409 | Values: 112.0 111.0 109.0 109.0 110.0 110.0 110.0 110.0 110.0 110.0 10:11:26 INFO - PROCESS | 23409 | 10:11:26 INFO - PROCESS | 23409 | [#1] audio-beat-detection Cycles:10 Average:129.00 Median:123.50 stddev:17.64 (14.3%) stddev-sans-first:17.32 10:11:26 INFO - PROCESS | 23409 | Values: 148.0 117.0 116.0 162.0 121.0 116.0 117.0 116.0 151.0 126.0 10:11:26 INFO - PROCESS | 23409 | 10:11:26 INFO - PROCESS | 23409 | [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:0.88 10:11:26 INFO - PROCESS | 23409 | Values: 159.0 161.0 160.0 160.0 162.0 161.0 161.0 160.0 161.0 159.0 10:11:26 INFO - PROCESS | 23409 | 10:11:26 INFO - PROCESS | 23409 | [#3] audio-fft Cycles:10 Average:80.90 Median:76.00 stddev:10.82 (14.2%) stddev-sans-first:11.26 10:11:26 INFO - PROCESS | 23409 | Values: 75.0 77.0 76.0 105.0 76.0 76.0 78.0 75.0 97.0 74.0 10:11:26 INFO - PROCESS | 23409 | 10:11:26 INFO - PROCESS | 23409 | [#4] audio-oscillator Cycles:10 Average:90.60 Median:81.00 stddev:16.15 (19.9%) stddev-sans-first:16.75 10:11:26 INFO - PROCESS | 23409 | Values: 81.0 81.0 124.0 82.0 81.0 80.0 105.0 81.0 81.0 110.0 10:11:26 INFO - PROCESS | 23409 | 10:11:26 INFO - PROCESS | 23409 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 10:11:26 INFO - PROCESS | 23409 | Values: 117.0 116.0 117.0 117.0 116.0 116.0 116.0 117.0 117.0 117.0 10:11:26 INFO - PROCESS | 23409 | 10:11:26 INFO - PROCESS | 23409 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.50 stddev:0.88 (0.6%) stddev-sans-first:0.87 10:11:26 INFO - PROCESS | 23409 | Values: 147.0 147.0 145.0 146.0 147.0 147.0 145.0 146.0 145.0 146.0 10:11:26 INFO - PROCESS | 23409 | 10:11:26 INFO - PROCESS | 23409 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 10:11:26 INFO - PROCESS | 23409 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 10:11:26 INFO - PROCESS | 23409 | 10:11:26 INFO - PROCESS | 23409 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:49.50 stddev:2.78 (5.6%) stddev-sans-first:2.92 10:11:26 INFO - PROCESS | 23409 | Values: 49.0 50.0 49.0 49.0 50.0 58.0 49.0 49.0 49.0 50.0 10:11:26 INFO - PROCESS | 23409 | 10:11:26 INFO - PROCESS | 23409 | [#9] json-stringify-tinderbox Cycles:10 Average:58.20 Median:58.00 stddev:0.79 (1.4%) stddev-sans-first:0.83 10:11:26 INFO - PROCESS | 23409 | Values: 58.0 58.0 59.0 60.0 58.0 58.0 58.0 58.0 57.0 58.0 10:11:26 INFO - PROCESS | 23409 | 10:11:26 INFO - PROCESS | 23409 | [#10] stanford-crypto-aes Cycles:10 Average:75.50 Median:76.50 stddev:2.32 (3.0%) stddev-sans-first:2.46 10:11:26 INFO - PROCESS | 23409 | Values: 76.0 80.0 74.0 73.0 73.0 77.0 77.0 77.0 73.0 75.0 10:11:26 INFO - PROCESS | 23409 | 10:11:26 INFO - PROCESS | 23409 | [#11] stanford-crypto-ccm Cycles:10 Average:127.80 Median:124.00 stddev:14.72 (11.9%) stddev-sans-first:15.55 10:11:26 INFO - PROCESS | 23409 | Values: 124.0 122.0 123.0 124.0 121.0 123.0 127.0 127.0 169.0 118.0 10:11:26 INFO - PROCESS | 23409 | 10:11:26 INFO - PROCESS | 23409 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.20 Median:153.50 stddev:3.22 (2.1%) stddev-sans-first:3.21 10:11:26 INFO - PROCESS | 23409 | Values: 150.0 153.0 152.0 154.0 154.0 150.0 152.0 151.0 155.0 161.0 10:11:26 INFO - PROCESS | 23409 | 10:11:26 INFO - PROCESS | 23409 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.00 stddev:1.48 (2.4%) stddev-sans-first:1.56 10:11:26 INFO - PROCESS | 23409 | Values: 62.0 62.0 65.0 63.0 62.0 61.0 64.0 62.0 61.0 60.0 10:11:26 INFO - PROCESS | 23409 | -------- Summary: end -------- 10:11:26 INFO - PROCESS | 23409 | 10:11:26 INFO - TEST-INFO | 23409: exit 0 10:11:27 INFO - TEST-OK | kraken | took 59193ms 10:11:27 INFO - SUITE-END | took 1172s 10:11:27 INFO - Completed test suite (00:19:32) 10:11:27 INFO - javascript benchmark 10:11:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3112.750979921606, 3145.465270903946, 3172.2228054126736, 3187.139472342469, 3212.132724164476, 5510.641808282464, 5603.271574695284, 5605.383407825136, 5606.411326810682, 5625.718714064291, 5565.359983601887, 5588.30146231721, 5599.720013999294, 5600.4959553640165, 5619.157126431027, 5090.0, 5093.439721630618, 5126.436092029892, 5143.125668636322, 5169.250458683488, 5044.697318160904, 5045.571126454256, 5117.641765076446, 5171.793128274883, 5190.377154741436, 5048.596112311025, 5064.063148317573, 5102.719350435725, 5120.539151476366, 5121.1038068338075, 5123.129068058439, 5141.35733033369, 5199.844004679866, 5208.817691380784, 5229.738513074331, 4858.1012512685265, 5100.234964755279, 5119.795208191668, 5164.122099243138, 5180.222966555009, 5437.510624043855, 5504.697241651707, 5508.6970216638065, 5534.059209934321, 5578.776848926038, 5936.436038576328, 5945.048792193272, 6034.487068599175, 6043.214382130339, 6054.8183554493435, 5910.202122713422, 6099.359567245419, 6104.267487901436, 6116.3577824328695, 6119.204503414572, 5551.2228288039705, 5584.692841893694, 5610.999999999959, 5622.971885140548, 5650.74571644277, 6185.165002724664, 6191.040388739752, 6191.721372538246, 6196.8760624787255, 6199.690015499297, 5684.261046063985, 5745.253117094751, 5921.319048309413, 5937.70311484424, 5939.970300148471, 5884.9705751470965, 5966.463018328284, 5971.611845230046, 6015.939840601625, 6052.425019623129, 5966.642001479839, 5969.761209551656, 5975.52195824333, 5978.820635380858, 5981.790637327673, 5727.656340619576, 5947.37552556984, 6005.939940600538, 6018.187544681412, 6033.7586496540525, 4520.4575450946095, 4594.563516465931, 4655.650826188049, 4659.091477161988, 4728.432588089382, 4131.111810960658, 4141.772202528829, 4145.523264824524, 4150.584941505886, 4181.979090104591, 5818.592698511148, 5853.795117170878, 5854.853628659318, 5865.266841644794, 5896.292444906553, 5917.52659787216, 5958.582899197015, 6000.069989151689, 6003.519718422515, 6015.669138197353, 5794.62334948227, 5796.884062318814, 5807.332156802022, 5807.709614519342, 5832.62087964281, 5150.175971844562, 5151.124308867426, 5156.123459011977, 5156.381234251914, 5188.844334669966, 5996.790112346047, 6025.0, 6027.638341699337, 6058.57589968698, 6231.657258850807, 5921.052631578927, 5925.0, 5925.348211696796, 5936.22829032223, 5967.7911273106965, 6096.939030609815, 6097.146399504161, 6108.267007959073, 6111.083337499411, 6121.418465245794, 5665.830025099253, 5682.261306030355, 5708.714564271853, 5763.9135412968, 5771.480566749013, 5907.17299578068, 5935.881282374242, 5953.166556681981, 5973.880522389615, 5986.4013598639785, 5780.682062486603, 5821.476067153976, 5829.300483941955, 5831.79588714393, 5832.12518122285, 5743.1385292206505, 5749.367569567428, 5807.883842323215, 5808.157817116579, 5815.069588865761, 5796.913046304225, 5846.649201047951, 5862.062070068769, 5922.230110085834, 5939.524838012862, 5947.821565353046, 5962.284525856925, 5964.135200395832, 5966.761329546943, 5975.223220981245, 5610.887782244414, 5669.149627555899, 5680.829575112753, 5796.188533605383, 5813.76744930198], "value": 5520.935635976925, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.2264821759386, 599.7391134856338, 608.3186830749559, 630.3003665930818, 637.9728636894602, 33783.15542111448, 44885.0, 45780.54219457805, 45856.54143458564, 45890.54109458905, 47599.28601070987, 48167.75916120423, 48169.51830481694, 48227.51772482274, 48435.75782121093, 8516.318694504456, 9300.162985331335, 9446.716598502057, 9449.527523623825, 9453.243740500744, 8462.619182136788, 10933.890661093406, 11172.217944743872, 11179.776404471906, 11530.308181509094, 11411.54353825846, 11463.140264480156, 11474.139439542027, 11480.598179063734, 11484.36835974021, 11266.929641684068, 11328.660140195767, 11330.546778128864, 11333.15001374892, 11350.772984540263, 11066.667999959933, 11127.666170014912, 11133.165012624004, 11138.832917506244, 11152.721181970435, 44523.55476445227, 44649.77675111636, 44678.0, 44730.105397892185, 44861.55138448606, 3822.847086116552, 3923.7253392262555, 4018.0758425561994, 4038.0, 4209.600966989473, 33571.664283357095, 33605.66394336074, 33610.66389336124, 33658.0, 33730.49404258938, 4543.5456454354635, 4582.495925448198, 4597.471290801567, 4708.0, 4712.95287047132, 252106.0, 253378.0, 254219.0, 256528.0, 257306.0, 61408.692956534935, 61937.69031154906, 61939.6903015482, 61988.69005654943, 62024.37975620276, 40039.0, 40765.0, 40775.79612101921, 42691.57308426876, 64952.350476494634, 17383.56541086483, 17398.913005434893, 17451.0, 17459.21433535494, 17500.38748643817, 13210.207387556777, 13427.3957671905, 14095.36570854314, 14348.282585870666, 14518.782218266735, 19351.90324048371, 20181.495462613257, 20351.287704930255, 20407.79592204059, 20584.691229631568, 7243.167035790846, 7253.56478611285, 7304.96347518259, 7361.8159546011775, 7654.961725191339, 1035.9223058270659, 1049.3389164826262, 1072.9217136777488, 1131.1233793809863, 1144.221929088228, 33737.0, 33876.661233387844, 34844.477332840026, 35531.82234088865, 35682.464763028576, 1130.0564029035684, 1142.9997504367357, 1144.8626164860104, 1190.2620375367328, 1200.6938230751102, 17664.116794160498, 17749.201285942163, 17803.821961780475, 17866.374676886247, 17884.0, 544826.0, 547749.0, 554382.0, 555521.0, 558812.0, 58795.706021470476, 59100.04499774994, 59160.11259831106, 59239.40760592339, 59241.0, 1308.941097650608, 1380.0891411668251, 1392.192528333564, 1421.5024741340656, 1454.7745099509593, 30110.917627570205, 34516.0, 34597.0, 34859.0, 34983.0, 50905.74547127241, 51725.0, 51821.48178518166, 51828.0, 51829.7408512955, 420906.0, 424661.0, 425017.0, 425055.0, 425575.0, 26785.0, 27615.58576621312, 30262.54606180953, 30286.242843929078, 30356.24109397283, 48676.0, 49140.262896057306, 49315.0, 49764.502354975986, 50771.74614126905, 42039.3694094595, 42210.78894605507, 42904.06343605324, 43268.78365608152, 43395.13209735851, 42321.365179522945, 42525.36211956885, 42879.785601071795, 43401.0, 44072.0, 41507.88690848138, 41706.582934171485, 42295.5770442304, 42401.36397954095, 42658.36012459754, 34229.828850855585, 36956.63043369532, 37215.62784372122, 37825.62174378221, 38175.0, 28159.57760633551, 28257.204704380147, 28309.433811323248, 28314.716852831207, 28340.0], "value": 21007.21670442842, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.8767857321377, 425.6615990287721, 433.81621790247414, 438.59210933831525, 441.5474139007519, 18194.636107277845, 18693.62612747744, 20092.598148037032, 20125.396238112862, 20225.292114775984, 19268.903655481703, 19382.806171938275, 19788.60422791543, 20042.69935950958, 20190.69713954288, 5844.357120716729, 6077.848053798668, 6105.786297479589, 6119.5716299858805, 6138.324784273726, 5708.885822283572, 5967.731452084645, 5981.28224613047, 6063.272407311107, 6071.028635418334, 6079.6960151992225, 6099.603525770833, 6109.3890610939, 6115.29674087479, 6116.877662446748, 5898.262717160355, 5963.224780778514, 6013.6993150342305, 6022.09668549721, 6101.054336577838, 5838.328592211865, 5843.123531470271, 5846.035404158309, 5846.239988801429, 5853.385394533555, 22454.663180052314, 23062.308130756104, 23096.307110786704, 23972.880135599386, 24218.757812421827, 2505.1232068775785, 2535.657686212356, 2550.732173121814, 2725.5775354820034, 2785.8467784271857, 11124.110071194285, 11224.270422422516, 11396.601118960796, 11824.586139485076, 14730.852691473056, 1881.7177423386602, 1900.4963684623585, 1902.3627084926566, 1974.7630284365825, 1978.0505357428372, 61524.75002144694, 67706.66146669236, 70997.0, 86118.56940715319, 88935.55532222362, 11139.498722557504, 11465.656030319104, 11930.22453540517, 12071.456784444721, 12249.387530623433, 16986.06576638272, 17004.744928826076, 17028.148592570324, 17038.74441883373, 17045.23296451663, 8342.83314333722, 8772.78068048304, 9001.594928228245, 9101.772455688528, 9119.498427586546, 8044.718434854752, 8701.738947831574, 8739.30085593151, 8907.10928907117, 8922.197002269826, 5089.465606111379, 5147.1507201312115, 5179.870503237374, 5247.553957913544, 5285.048691235615, 2063.607914496241, 2090.7491101067967, 2100.317396846031, 2107.79975902289, 2122.5224324527167, 890.7817584691792, 1006.33079002464, 1051.0712841935135, 1069.5935544493043, 1100.5542755184163, 18709.719354209697, 19027.90486047561, 20074.39776806698, 20172.19311227564, 20179.697304540445, 1019.5463018956493, 1056.7569459024467, 1078.660222030058, 1100.1858624617726, 1123.550579768083, 10570.0, 11214.66356009321, 11460.140489463323, 11538.307701537935, 11602.883971160349, 364073.0, 366650.0, 366735.0, 467287.0, 471724.0, 82303.0, 96290.51854740681, 97006.0, 98231.50884245533, 98993.50503247582, 942.5569982108398, 966.8114717629994, 1033.0960409641564, 1092.7978324010085, 1098.6154845803906, 17742.379016734354, 17883.463496095137, 17963.820361796214, 18050.819491804912, 18483.075846207907, 41001.384979225935, 41247.58752412438, 41490.170196596504, 41828.790856045525, 42308.36537451879, 53994.730026349614, 54034.18948715694, 54532.18201726898, 55594.444055558924, 56877.431225688866, 8938.86591701112, 9118.133777291147, 9169.174774270348, 9180.908190918273, 9189.172974432333, 13424.664383390493, 13457.528986485426, 13782.793258100935, 13813.585592432242, 13932.442702291795, 12058.276503409823, 12145.635630931087, 12184.512619495476, 12325.137240393087, 12375.62873113808, 10951.726206844893, 11594.536218551164, 11954.70113247141, 12299.631011069681, 12350.876491235333, 11181.211006238962, 11671.416429178338, 12197.573084941985, 12244.510219591117, 12424.06819488524, 7929.762107136795, 8359.791005224917, 9102.817943641223, 9119.042500537347, 9226.215771659485, 10287.485625718833, 11876.76246475083, 11963.940180299043, 12039.819402709141, 12280.38598070075], "value": 9565.848042774252, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1503.5414198669405, 1567.6707891342817, 1724.8533874620657, 1806.6386722655473, 1854.6754317994348, 8697.826043479134, 9331.206847417969, 9335.62657493701, 9339.299552533561, 9353.298502612315, 7884.684612615489, 8114.91885081149, 8122.309603683694, 8331.458455200407, 9128.726138215865, 2405.566997940374, 2489.676342075528, 2490.5018996200743, 2490.7260201377835, 2495.43852633157, 2520.9747902521017, 2526.2421273617933, 2541.834780739255, 2552.4767422678306, 2560.0143944581378, 2516.3331717094984, 2523.5457617628817, 2538.4669219463935, 2541.606051062088, 2544.0841297133015, 2485.254423672909, 2488.8008959283216, 2491.4643351679383, 2495.3886297857052, 2507.1601013660447, 2482.094035676983, 2485.8135639826905, 2490.0786708917817, 2490.9003639854386, 2491.6013437849856, 9074.18332350085, 9242.953785231099, 9275.768105797342, 9277.211437027861, 9284.535773211312, 5414.3232095988005, 5502.5597952163735, 5535.975844468786, 5547.223388725582, 5548.805791797267, 3418.8803391881165, 3460.4290292101773, 3600.0999750062483, 3618.0773902654655, 3632.291703117893, 1220.200768496636, 1254.9686257843537, 1262.3688155922039, 1274.8151518029927, 1275.7384736129072, 12467.189632673848, 12490.250584964931, 12491.75016499674, 12508.562200322946, 12534.87465125337, 4873.95126048735, 4904.337914381585, 5045.369328833895, 5124.15451450514, 5160.329157209614, 10600.04599586041, 10614.469276536141, 10664.146868250522, 10667.093297069681, 10671.626493072705, 5528.419515950848, 5551.028570000234, 5552.972235138879, 5554.750036248379, 5555.166724991283, 4974.527419895104, 5020.523050310215, 5020.573251273682, 5027.120253955616, 5040.420351659606, 4352.69531132824, 4359.738415695068, 4360.65114790816, 4437.711548749321, 4451.132029254264, 1385.681293302546, 1410.605030591436, 1415.1367665723901, 1424.309210033133, 1436.4182506085053, 854.9316054715608, 963.1957315641378, 1023.8003589300015, 1044.6604853422668, 1045.2056437107701, 5896.3219229788265, 6078.331383547807, 6124.663143527059, 6294.559380843297, 6335.208098987627, 488.35537091039487, 492.75608573755505, 495.1804763117047, 520.5185203686575, 524.0279281931961, 4106.7125301228625, 4147.681854516375, 4149.439825623563, 4205.76868272248, 4218.282891908383, 10989.0, 11141.721456963478, 11270.718232044264, 11284.32294062359, 11378.03286720638, 11247.212695111428, 11261.774764504828, 11262.943685281685, 11270.154738394653, 11286.266392684449, 8278.958605206935, 8361.122082181284, 8411.621477033548, 8450.112738162527, 8463.49219046859, 5986.2517185351835, 6095.725692343944, 6176.66028368444, 6177.320494745484, 6256.436920676979, 10245.385276883411, 10265.948670256601, 10349.27555071168, 10363.274570780275, 10447.94776026115, 10474.790504189721, 10524.263301568817, 10619.787604248026, 10677.039066484054, 10760.46197690128, 7982.361411087215, 8159.0, 8166.060902995994, 8167.79580510492, 8173.0, 8739.868901966604, 8783.5169065702, 8799.604017819085, 8876.068012858557, 8957.14907083826, 7979.0, 8038.437309388287, 8054.23484768946, 8074.83850323002, 8082.919170808452, 7881.684732610861, 7908.762737117896, 7945.721899733481, 7982.521048737094, 8100.35197184212, 7314.926850731425, 7404.555726656418, 7456.888146677696, 7478.70085196586, 7479.326860582573, 5229.346331708536, 5247.816326428557, 5265.6050796189675, 5337.706426146599, 5426.186072089218, 5166.870828229325, 5175.275461435477, 5243.108671525773, 5304.443033481429, 5342.75957581902], "value": 4781.155870442977, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.94007078725826, 340.8363985287063, 349.8985294264663, 349.9125218695326, 368.9409694448887, 5509.972450137749, 5530.9999999999945, 5541.556675465963, 5560.471755183251, 5562.499375056249, 5498.697571633558, 5502.532284755791, 5504.422035686255, 5521.503064724174, 5522.723863806814, 3848.780302742894, 4117.547069822324, 4197.601227883346, 4198.706090573661, 4257.467816522934, 4258.510271318807, 4294.398784170218, 4300.3764454154125, 4301.612854843063, 4323.891902702426, 4289.549597292286, 4291.871243862689, 4296.9140617187795, 4311.590398912098, 4327.63215126713, 4070.979645101785, 4259.999999999969, 4316.395704601358, 4321.373400856858, 4324.351347297899, 3768.246350729865, 3779.527559055118, 3796.2976849282973, 3800.90497737556, 3831.17629709611, 5278.366596008465, 5307.097793375136, 5314.521693047605, 5338.306020217386, 5372.516473517402, 4805.471398146201, 4921.71140419703, 4924.187509061001, 4928.433230178503, 4928.43323017854, 5660.320761508604, 5690.687012214326, 5695.487406133468, 5697.202391665171, 5733.365990692674, 4170.374443833419, 4176.66346769034, 4180.352045432963, 4189.49726032875, 4189.685773566995, 5743.569232307552, 5948.483136800175, 5955.188679245243, 5978.282606087254, 5984.551158663117, 4262.25410553152, 4264.933766558361, 4269.402283680287, 4278.507971583308, 4278.828846846153, 4326.783660816946, 4329.350597410414, 4358.280883654225, 4373.387725718402, 4384.583464570861, 4845.491223421561, 4939.876503087452, 4952.2819191217795, 4952.603791696655, 4971.1797553404, 5723.255976723, 5731.369549349568, 5735.741891614887, 5740.42595740431, 5751.568632352591, 1450.9564713058626, 1483.68842543065, 1489.5456885649764, 1498.1535432480614, 1502.1963249661378, 918.4718786697675, 925.8657494663236, 933.5052422216236, 954.8472244440856, 978.4178413843752, 4955.331030310934, 4985.280078372955, 5014.796448852249, 5020.723860187724, 5028.899422011539, 5214.947850521522, 5228.640553456053, 5236.6857988520815, 5258.474152584711, 5262.657927234794, 4985.401751789808, 5046.44489106198, 5047.9495205048215, 5313.787448502017, 5720.2849643794525, 3125.6874312569016, 3132.7337176340266, 3143.2141964508874, 3151.779375443697, 3170.3659268146002, 6131.938680613226, 6144.692765361803, 6148.385161483816, 6158.784442544489, 6179.69101544921, 5612.887742245132, 5980.342162362137, 6037.396260374016, 6090.147379366892, 6098.786542470993, 5898.962034552209, 5975.342712301644, 6040.60736052164, 6165.167702360213, 6171.321154673072, 5921.85195370102, 5948.762049517971, 5954.46409823118, 5977.372375900642, 5987.0, 5881.235439392852, 5888.234529511136, 5896.380880007538, 5896.55775816824, 5902.2622172228475, 6129.877402452015, 6148.631082135086, 6151.72317245716, 6154.1999540059505, 6171.0, 4239.406483092308, 4263.808128634281, 4277.443932288783, 4280.636145927631, 4295.37717031035, 5709.714514274353, 5919.881602367843, 5948.553858460719, 5975.0, 5993.61041532299, 5788.421157884178, 5805.068440437552, 5807.186993820953, 5815.680137592473, 5817.243758311392, 5607.579431542569, 5732.283464566929, 5816.970915145397, 5819.418058194146, 5834.3080506652595, 5718.227349521661, 5735.283089613798, 5737.684427356536, 5751.9424805751405, 5754.424557544379, 5787.739551720267, 5800.59395842287, 5821.38875418075, 5827.4172582741385, 5840.977828880014, 5425.294711687456, 5639.295088113986, 5654.088144605614, 5675.432456754292, 5677.1768093627015], "value": 4372.331082392094, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [733.1385621894276, 786.543804593336, 792.8493586218619, 793.6388943030921, 824.1428913929514, 14488.492902748407, 15267.312970916308, 15302.54092377229, 15306.158161301124, 15321.080735155898, 14230.07504512206, 14388.424463021467, 14400.639984000405, 14646.780298295535, 14678.779818302733, 699.5452955578863, 715.4276578737016, 805.3637626275236, 810.0481933727875, 826.9090400055989, 835.1982097186677, 835.3526017336583, 835.7534527314457, 837.5937670229933, 838.0864857305536, 899.244634507014, 900.1268769293774, 901.5942825728416, 902.1474706402453, 902.4179404284233, 889.7553172877446, 891.6789955615973, 891.9108089191061, 892.0232345581578, 894.029977474441, 655.5378458186967, 656.2715385921623, 657.8223879552494, 657.8717150155721, 658.0984051848998, 14948.0, 14951.177685227309, 14978.0, 14995.400183992628, 14999.250037498081, 16003.91998040014, 16173.110478923654, 16249.675006499921, 16252.918735406365, 16513.504594862174, 11651.533938642444, 11769.58806441779, 11882.643520694393, 11932.522699092113, 12393.132480726354, 3848.3457812171996, 3884.281407939512, 3891.5524714657895, 3904.0, 3991.960080399188, 81439.18560814376, 81678.59160704218, 81743.59128204321, 82461.0, 82801.58599207025, 11797.410129493663, 11958.0, 12289.93855030737, 12365.319907405179, 12375.195612285353, 20695.27566535164, 20801.895990520254, 20826.583468330547, 20858.687119693215, 20871.791282087288, 8940.32947528938, 9022.233110185576, 9152.130547597968, 9240.722778316662, 9257.166854983085, 4669.719816811003, 4873.853784386403, 4908.386451693538, 4973.52751488608, 4998.325226094504, 3564.144605294711, 3701.056230661164, 3703.5926048134684, 3705.3330400527625, 3705.796181210005, 636.5003472274243, 640.5964242527178, 645.6578013652771, 653.9019147127874, 655.5378458186967, 680.2755065854948, 747.1781040855125, 749.8950146979428, 789.9763007109796, 802.2659266770956, 9793.608255669693, 9801.754956126153, 10357.223208259411, 10621.468926553642, 10991.175661825395, 726.6512074204305, 760.9162992070868, 793.3732351442296, 796.5817945578617, 831.501099340391, 4991.7004979700605, 5216.713080780593, 5269.288646032813, 5269.288646032813, 5323.387810401853, 73593.0, 73854.26145738474, 73953.0, 82337.0, 120719.0, 108385.0, 111258.0, 113279.0, 114168.42915785534, 117374.0, 60045.0, 74811.62594186995, 94890.52554737183, 97641.0, 99418.99999999856, 9337.299702522474, 9552.42685438876, 9660.227181825578, 9870.161036311994, 9890.653827116017, 56202.1569676447, 56365.71817140888, 57226.7138664304, 57525.0, 57787.1331930013, 76504.0, 82842.0, 86302.56848715716, 86931.0, 89236.5538172305, 11978.520859165536, 12124.939375303067, 12154.0, 12275.140740148105, 12880.0, 13172.538961136313, 13216.801747973595, 13398.488944340288, 13521.797173042216, 13555.59333220005, 12070.155089143676, 12082.33547154915, 12171.634850954486, 12274.877251227374, 14136.50522231717, 14238.715225695221, 14264.358103885143, 14631.92684036573, 15147.091174529563, 15340.0, 13699.178049317074, 14231.50189743354, 14727.631809204855, 14806.481773137888, 14860.62848428798, 12094.274343539415, 12175.634730958085, 12989.350532473149, 13016.804747928978, 13373.933130334286, 11800.081917263797, 12019.0, 12271.0, 12314.261144331369, 12404.689882753004], "value": 7783.137914269874, "unit": "score"}], "name": "dromaeo_css", "value": 7517.813891202871, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 109.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 117.0, 116.0, 162.0, 121.0, 116.0, 117.0, 116.0, 151.0, 126.0], "value": 129.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 160.0, 160.0, 162.0, 161.0, 161.0, 160.0, 161.0, 159.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 105.0, 76.0, 76.0, 78.0, 75.0, 97.0, 74.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 124.0, 82.0, 81.0, 80.0, 105.0, 81.0, 81.0, 110.0], "value": 90.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 145.0, 146.0, 147.0, 147.0, 145.0, 146.0, 145.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 49.0, 49.0, 50.0, 58.0, 49.0, 49.0, 49.0, 50.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 59.0, 60.0, 58.0, 58.0, 58.0, 58.0, 57.0, 58.0], "value": 58.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 80.0, 74.0, 73.0, 73.0, 77.0, 77.0, 77.0, 73.0, 75.0], "value": 75.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 122.0, 123.0, 124.0, 121.0, 123.0, 127.0, 127.0, 169.0, 118.0], "value": 127.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 153.0, 152.0, 154.0, 154.0, 150.0, 152.0, 151.0, 155.0, 161.0], "value": 153.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 65.0, 63.0, 62.0, 61.0, 64.0, 62.0, 61.0, 60.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1455.8000000000002, "alertThreshold": 2.0}]} 10:11:27 INFO - Return code: 0 10:11:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:11:27 INFO - # TBPL SUCCESS # 10:11:27 INFO - Running post-action listener: _resource_record_post_action 10:11:27 INFO - [mozharness: 2016-12-01 18:11:27.362951Z] Finished run-tests step (success) 10:11:27 INFO - Running post-run listener: _resource_record_post_run 10:11:27 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 320655360; Read time: 56; Write time: 162780 10:11:27 INFO - TinderboxPrint: CPU usage
12.9% 10:11:27 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 56 10:11:27 INFO - TinderboxPrint: I/O write bytes / time
320,655,360 / 162,780 10:11:27 INFO - TinderboxPrint: CPU idle
8,244.6 (87.1%) 10:11:27 INFO - TinderboxPrint: CPU user
1,181.7 (12.5%) 10:11:27 INFO - TinderboxPrint: Swap in / out
0 / 0 10:11:27 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 100343808; Read time: 0; Write time: 55032 10:11:27 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 220311552; Read time: 8; Write time: 107748 10:11:28 INFO - Running post-run listener: _upload_blobber_files 10:11:28 INFO - Blob upload gear active. 10:11:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:11:28 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 10:11:28 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:11:28 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:11:28 INFO - (blobuploader) - INFO - Open directory for files ... 10:11:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:11:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:11:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:11:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:11:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:11:32 INFO - (blobuploader) - INFO - Done attempting. 10:11:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:11:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:11:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:11:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:11:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:11:33 INFO - (blobuploader) - INFO - Done attempting. 10:11:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:11:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:11:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:11:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:11:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:11:34 INFO - (blobuploader) - INFO - Done attempting. 10:11:34 INFO - (blobuploader) - INFO - Iteration through files over. 10:11:34 INFO - Return code: 0 10:11:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:11:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:11:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/004346b562c0cf60db2864a020b509026dbc39ebbb826342a6f05dfdc26c598c46a7220a63979717c6f6beadb6eedeb468d69c4b8378dafe152f5d971a1aa5af", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ce21d15729a7e2bbfd79eb87e129203bc88960a3a0534b8df4e7d3db0eee9292a9bbb7f1e83820461a6b606e4815cd1c273e36dc155314b4fe3030d76da976c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1807f48a8def91a36bedb7625faef4802459730a20fc8439715886599da4f04718cc02a0058c76a8462fee6ca147843fbfed278ac7bd2358b28d1a4775a93a8a"} 10:11:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:11:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:11:34 INFO - Contents: 10:11:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/004346b562c0cf60db2864a020b509026dbc39ebbb826342a6f05dfdc26c598c46a7220a63979717c6f6beadb6eedeb468d69c4b8378dafe152f5d971a1aa5af", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ce21d15729a7e2bbfd79eb87e129203bc88960a3a0534b8df4e7d3db0eee9292a9bbb7f1e83820461a6b606e4815cd1c273e36dc155314b4fe3030d76da976c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1807f48a8def91a36bedb7625faef4802459730a20fc8439715886599da4f04718cc02a0058c76a8462fee6ca147843fbfed278ac7bd2358b28d1a4775a93a8a"} 10:11:34 INFO - Running post-run listener: copy_logs_to_upload_dir 10:11:34 INFO - Copying logs to upload dir... 10:11:34 INFO - mkdir: /builds/slave/test/build/upload/logs 10:11:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.535202 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-12-01 10:11:33.777663) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-01 10:11:33.781742) ========= 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=523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/004346b562c0cf60db2864a020b509026dbc39ebbb826342a6f05dfdc26c598c46a7220a63979717c6f6beadb6eedeb468d69c4b8378dafe152f5d971a1aa5af", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ce21d15729a7e2bbfd79eb87e129203bc88960a3a0534b8df4e7d3db0eee9292a9bbb7f1e83820461a6b606e4815cd1c273e36dc155314b4fe3030d76da976c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1807f48a8def91a36bedb7625faef4802459730a20fc8439715886599da4f04718cc02a0058c76a8462fee6ca147843fbfed278ac7bd2358b28d1a4775a93a8a"} build_url:https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011306 build_url: 'https://queue.taskcluster.net/v1/task/PNZKfvJDTy-cLDxNZqA5hQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/004346b562c0cf60db2864a020b509026dbc39ebbb826342a6f05dfdc26c598c46a7220a63979717c6f6beadb6eedeb468d69c4b8378dafe152f5d971a1aa5af", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ce21d15729a7e2bbfd79eb87e129203bc88960a3a0534b8df4e7d3db0eee9292a9bbb7f1e83820461a6b606e4815cd1c273e36dc155314b4fe3030d76da976c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1807f48a8def91a36bedb7625faef4802459730a20fc8439715886599da4f04718cc02a0058c76a8462fee6ca147843fbfed278ac7bd2358b28d1a4775a93a8a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-01 10:11:33.808817) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 10:11:33.809244) ========= 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=523ca41ca1e0623de333ad52000001bf-1480614089.219309-956852727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004475 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 10:11:33.891587) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-01 10:11:33.891969) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-01 10:11:33.892298) ========= ========= Total master_lag: 0.20 =========