builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-060 starttime: 1475168146.68 results: success (0) buildid: 20160929071320 builduid: 8fae0d82656646d19535a15ba08811dc revision: 0e12aae055677772cabc476634a4100410035375 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 09:55:46.684866) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 09:55:46.685326) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 09:55:46.685608) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 09:55:46.701011) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 09:55:46.701339) ========= 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=337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-29 09:55:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2016-09-29 09:55:47 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094227 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 09:55:46.808956) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 09:55:46.809284) ========= 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=337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016290 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 09:55:46.859083) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 09:55:46.859462) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0e12aae055677772cabc476634a4100410035375 --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-aurora --rev 0e12aae055677772cabc476634a4100410035375 --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=337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-29 09:55:47,402 truncating revision to first 12 chars 2016-09-29 09:55:47,402 Setting DEBUG logging. 2016-09-29 09:55:47,402 attempt 1/10 2016-09-29 09:55:47,402 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0e12aae05567?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-29 09:55:47,779 unpacking tar archive at: mozilla-aurora-0e12aae05567/testing/mozharness/ program finished with exit code 0 elapsedTime=0.723405 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 09:55:47.596119) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 09:55:47.596452) ========= script_repo_revision: 0e12aae055677772cabc476634a4100410035375 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 09:55:47.596828) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 09:55:47.597092) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 09:55:47.606014) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-09-29 09:55:47.606334) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:55:48 INFO - MultiFileLogger online at 20160929 09:55:48 in /builds/slave/test 09:55:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:55:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:55:48 INFO - {'append_to_log': False, 09:55:48 INFO - 'base_work_dir': '/builds/slave/test', 09:55:48 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:55:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:55:48 INFO - 'branch': 'Mozilla-Aurora', 09:55:48 INFO - 'buildbot_json_path': 'buildprops.json', 09:55:48 INFO - 'config_files': ('talos/linux_config.py',), 09:55:48 INFO - 'default_actions': ('clobber', 09:55:48 INFO - 'read-buildbot-config', 09:55:48 INFO - 'download-and-extract', 09:55:48 INFO - 'populate-webroot', 09:55:48 INFO - 'create-virtualenv', 09:55:48 INFO - 'install', 09:55:48 INFO - 'run-tests'), 09:55:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:55:48 INFO - 'download_minidump_stackwalk': True, 09:55:48 INFO - 'download_symbols': 'ondemand', 09:55:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:55:48 INFO - 'tooltool.py': '/tools/tooltool.py', 09:55:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:55:48 INFO - '/tools/misc-python/virtualenv.py')}, 09:55:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:55:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:55:48 INFO - 'installer_path': 'installer.exe', 09:55:48 INFO - 'log_level': 'info', 09:55:48 INFO - 'log_name': 'talos', 09:55:48 INFO - 'log_to_console': True, 09:55:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:55:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:55:48 INFO - 'opt_config_files': (), 09:55:48 INFO - 'pip_index': False, 09:55:48 INFO - 'sps_profile': False, 09:55:48 INFO - 'sps_profile_interval': 0, 09:55:48 INFO - 'suite': 'dromaeojs', 09:55:48 INFO - 'system_bits': '32', 09:55:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:55:48 INFO - 'title': 'talos-linux64-ix-060', 09:55:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:55:48 INFO - 'use_talos_json': True, 09:55:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:55:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:55:48 INFO - 'work_dir': 'build'} 09:55:48 INFO - [mozharness: 2016-09-29 16:55:48.240414Z] Running clobber step. 09:55:48 INFO - Running pre-action listener: _resource_record_pre_action 09:55:48 INFO - Running main action method: clobber 09:55:48 INFO - rmtree: /builds/slave/test/build 09:55:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:55:48 INFO - Running post-action listener: _resource_record_post_action 09:55:48 INFO - [mozharness: 2016-09-29 16:55:48.728881Z] Finished clobber step (success) 09:55:48 INFO - [mozharness: 2016-09-29 16:55:48.728985Z] Running read-buildbot-config step. 09:55:48 INFO - Running pre-action listener: _resource_record_pre_action 09:55:48 INFO - Running main action method: read_buildbot_config 09:55:48 INFO - Using buildbot properties: 09:55:48 INFO - { 09:55:48 INFO - "project": "", 09:55:48 INFO - "product": "firefox", 09:55:48 INFO - "script_repo_revision": "production", 09:55:48 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:55:48 INFO - "repository": "", 09:55:48 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:55:48 INFO - "buildid": "20160929071320", 09:55:48 INFO - "pgo_build": "True", 09:55:48 INFO - "basedir": "/builds/slave/test", 09:55:48 INFO - "buildnumber": 492, 09:55:48 INFO - "slavename": "talos-linux64-ix-060", 09:55:48 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:55:48 INFO - "platform": "ubuntu64_hw", 09:55:48 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:55:48 INFO - "branch": "mozilla-aurora", 09:55:48 INFO - "repo_path": "releases/mozilla-aurora", 09:55:48 INFO - "revision": "0e12aae055677772cabc476634a4100410035375", 09:55:48 INFO - "stage_platform": "linux64-pgo", 09:55:48 INFO - "builduid": "8fae0d82656646d19535a15ba08811dc", 09:55:48 INFO - "slavebuilddir": "test" 09:55:48 INFO - } 09:55:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 09:55:48 INFO - Running post-action listener: _resource_record_post_action 09:55:48 INFO - [mozharness: 2016-09-29 16:55:48.731448Z] Finished read-buildbot-config step (success) 09:55:48 INFO - [mozharness: 2016-09-29 16:55:48.731556Z] Running download-and-extract step. 09:55:48 INFO - Running pre-action listener: _resource_record_pre_action 09:55:48 INFO - Running main action method: download_and_extract 09:55:48 INFO - mkdir: /builds/slave/test/build/tests 09:55:48 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:55:48 INFO - trying https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:55:48 INFO - Downloading https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:55:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:55:49 INFO - Downloaded 1361 bytes. 09:55:49 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:55:49 INFO - Using the following test package requirements: 09:55:49 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:55:49 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:55:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:55:49 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:55:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:55:49 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:55:49 INFO - u'jsshell-linux-x86_64.zip'], 09:55:49 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:55:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:55:49 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:55:49 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:55:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:55:49 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:55:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:55:49 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:55:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:55:49 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:55:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:55:49 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:55:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:55:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:55:49 INFO - Fetch https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:55:49 INFO - Expected file size: 21901785 09:55:54 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:55:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:55:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:55:54 INFO - Fetch https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:55:54 INFO - Expected file size: 21901785 09:55:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 09:55:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:55:59 INFO - Fetch https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 09:55:59 INFO - Expected file size: 14414059 09:56:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:00 INFO - trying https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:56:00 INFO - Downloading https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:56:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:56:01 INFO - Downloaded 63965784 bytes. 09:56:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:56:01 INFO - mkdir: /builds/slave/test/properties 09:56:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:56:01 INFO - Writing to file /builds/slave/test/properties/build_url 09:56:01 INFO - Contents: 09:56:01 INFO - build_url:https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:56:02 INFO - Running post-action listener: _resource_record_post_action 09:56:02 INFO - Running post-action listener: set_extra_try_arguments 09:56:02 INFO - [mozharness: 2016-09-29 16:56:02.340643Z] Finished download-and-extract step (success) 09:56:02 INFO - [mozharness: 2016-09-29 16:56:02.340873Z] Running populate-webroot step. 09:56:02 INFO - Running pre-action listener: _resource_record_pre_action 09:56:02 INFO - Running main action method: populate_webroot 09:56:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:56:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:56:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:56:02 INFO - '--tppagecycles', 09:56:02 INFO - '7'], 09:56:02 INFO - 'tests': ['tsvgm']}}, 09:56:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:56:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:56:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:56:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:56:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:56:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:56:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:02 INFO - 'talos_options': ['--disable-e10s'], 09:56:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:56:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:56:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:02 INFO - 'talos_options': ['--disable-e10s'], 09:56:02 INFO - 'tests': ['damp', 'tps']}, 09:56:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:02 INFO - 'tests': ['damp', 'tps']}, 09:56:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:56:02 INFO - 'tests': ['dromaeo_dom']}, 09:56:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:56:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:56:02 INFO - 'tests': ['basic_compositor_video']}, 09:56:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:56:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:56:02 INFO - 'tests': ['a11yr', 09:56:02 INFO - 'ts_paint', 09:56:02 INFO - 'tpaint', 09:56:02 INFO - 'sessionrestore', 09:56:02 INFO - 'sessionrestore_no_auto_restore', 09:56:02 INFO - 'tabpaint']}, 09:56:02 INFO - 'other-e10s': {'tests': ['a11yr', 09:56:02 INFO - 'ts_paint', 09:56:02 INFO - 'tpaint', 09:56:02 INFO - 'sessionrestore', 09:56:02 INFO - 'sessionrestore_no_auto_restore', 09:56:02 INFO - 'tabpaint']}, 09:56:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:56:02 INFO - 'ts_paint', 09:56:02 INFO - 'tpaint', 09:56:02 INFO - 'sessionrestore', 09:56:02 INFO - 'sessionrestore_no_auto_restore', 09:56:02 INFO - 'tabpaint']}, 09:56:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:56:02 INFO - 'ts_paint', 09:56:02 INFO - 'tpaint', 09:56:02 INFO - 'sessionrestore', 09:56:02 INFO - 'sessionrestore_no_auto_restore', 09:56:02 INFO - 'tabpaint']}, 09:56:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:56:02 INFO - 'tests': ['a11yr', 09:56:02 INFO - 'ts_paint', 09:56:02 INFO - 'tpaint', 09:56:02 INFO - 'sessionrestore', 09:56:02 INFO - 'sessionrestore_no_auto_restore', 09:56:02 INFO - 'tabpaint']}, 09:56:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:56:02 INFO - 'tests': ['a11yr', 09:56:02 INFO - 'ts_paint', 09:56:02 INFO - 'tpaint', 09:56:02 INFO - 'sessionrestore', 09:56:02 INFO - 'sessionrestore_no_auto_restore', 09:56:02 INFO - 'tabpaint']}, 09:56:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:56:02 INFO - 'tests': ['tsvgx', 09:56:02 INFO - 'tsvgr_opacity', 09:56:02 INFO - 'tart', 09:56:02 INFO - 'tscrollx', 09:56:02 INFO - 'cart']}, 09:56:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:56:02 INFO - 'tsvgr_opacity', 09:56:02 INFO - 'tart', 09:56:02 INFO - 'tscrollx', 09:56:02 INFO - 'cart']}, 09:56:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:02 INFO - 'talos_options': ['--disable-e10s'], 09:56:02 INFO - 'tests': ['tp5o']}, 09:56:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:02 INFO - 'tests': ['tp5o']}, 09:56:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:02 INFO - 'talos_options': ['--disable-e10s', 09:56:02 INFO - '--xperf_path', 09:56:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:56:02 INFO - 'tests': ['tp5n']}, 09:56:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:02 INFO - 'talos_options': ['--xperf_path', 09:56:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:56:02 INFO - 'tests': ['tp5n']}}, 09:56:02 INFO - 'talos.zip': {'path': '', 09:56:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:56:02 INFO - Running post-action listener: _resource_record_post_action 09:56:02 INFO - [mozharness: 2016-09-29 16:56:02.361138Z] Finished populate-webroot step (success) 09:56:02 INFO - [mozharness: 2016-09-29 16:56:02.361228Z] Running create-virtualenv step. 09:56:02 INFO - Running pre-action listener: _resource_record_pre_action 09:56:02 INFO - Running main action method: create_virtualenv 09:56:02 INFO - Creating virtualenv /builds/slave/test/build/venv 09:56:02 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:56:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:56:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:56:02 INFO - Using real prefix '/usr' 09:56:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:56:02 INFO - Installing distribute.............................................................................................................................................................................................done. 09:56:04 INFO - Installing pip.................done. 09:56:04 INFO - Return code: 0 09:56:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:56: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:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56: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:56:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:04 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 0x7f501f533f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e25ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e22f60>, '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 0x1e23d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e241d0>, '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': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', '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:56:04 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:56:04 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:56:04 INFO - Using env: {'DISPLAY': ':0', 09:56:04 INFO - 'HOME': '/home/cltbld', 09:56:04 INFO - 'LANG': 'en_US.UTF-8', 09:56:04 INFO - 'LANGUAGE': 'en_US:en', 09:56:04 INFO - 'LOGNAME': 'cltbld', 09:56:04 INFO - 'MAIL': '/var/mail/cltbld', 09:56:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:04 INFO - 'MOZ_NO_REMOTE': '1', 09:56:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:04 INFO - 'NO_EM_RESTART': '1', 09:56:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:04 INFO - 'PWD': '/builds/slave/test', 09:56:04 INFO - 'SHELL': '/bin/bash', 09:56:04 INFO - 'SHLVL': '1', 09:56:04 INFO - 'TERM': 'linux', 09:56:04 INFO - 'TMOUT': '86400', 09:56:04 INFO - 'USER': 'cltbld', 09:56:04 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', 09:56:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:04 INFO - '_': '/tools/buildbot/bin/python'} 09:56:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:05 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:56:05 INFO - Cleaning up... 09:56:05 INFO - Return code: 0 09:56:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:56:05 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:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:05 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:56:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:05 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 0x7f501f533f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e25ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e22f60>, '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 0x1e23d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e241d0>, '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': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', '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:56:05 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:56:05 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:56:05 INFO - Using env: {'DISPLAY': ':0', 09:56:05 INFO - 'HOME': '/home/cltbld', 09:56:05 INFO - 'LANG': 'en_US.UTF-8', 09:56:05 INFO - 'LANGUAGE': 'en_US:en', 09:56:05 INFO - 'LOGNAME': 'cltbld', 09:56:05 INFO - 'MAIL': '/var/mail/cltbld', 09:56:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:05 INFO - 'MOZ_NO_REMOTE': '1', 09:56:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:05 INFO - 'NO_EM_RESTART': '1', 09:56:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:05 INFO - 'PWD': '/builds/slave/test', 09:56:05 INFO - 'SHELL': '/bin/bash', 09:56:05 INFO - 'SHLVL': '1', 09:56:05 INFO - 'TERM': 'linux', 09:56:05 INFO - 'TMOUT': '86400', 09:56:05 INFO - 'USER': 'cltbld', 09:56:05 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', 09:56:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:05 INFO - '_': '/tools/buildbot/bin/python'} 09:56:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:05 INFO - Downloading/unpacking psutil>=3.1.1 09:56:05 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:56:05 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:56:05 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:56:05 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:56:05 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:56:05 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:56:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:56:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:56:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:56:07 INFO - Installing collected packages: psutil 09:56:07 INFO - Running setup.py install for psutil 09:56:07 INFO - building 'psutil._psutil_linux' extension 09:56:07 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:56:07 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:56:07 INFO - building 'psutil._psutil_posix' extension 09:56:07 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:56:07 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:56:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:56:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:56:07 INFO - Successfully installed psutil 09:56:07 INFO - Cleaning up... 09:56:07 INFO - Return code: 0 09:56:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:07 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 0x7f501f533f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e25ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e22f60>, '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 0x1e23d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e241d0>, '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': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', '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:56:07 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:56:07 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:56:07 INFO - Using env: {'DISPLAY': ':0', 09:56:07 INFO - 'HOME': '/home/cltbld', 09:56:07 INFO - 'LANG': 'en_US.UTF-8', 09:56:07 INFO - 'LANGUAGE': 'en_US:en', 09:56:07 INFO - 'LOGNAME': 'cltbld', 09:56:07 INFO - 'MAIL': '/var/mail/cltbld', 09:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:07 INFO - 'MOZ_NO_REMOTE': '1', 09:56:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:07 INFO - 'NO_EM_RESTART': '1', 09:56:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:07 INFO - 'PWD': '/builds/slave/test', 09:56:07 INFO - 'SHELL': '/bin/bash', 09:56:07 INFO - 'SHLVL': '1', 09:56:07 INFO - 'TERM': 'linux', 09:56:07 INFO - 'TMOUT': '86400', 09:56:07 INFO - 'USER': 'cltbld', 09:56:07 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', 09:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:07 INFO - '_': '/tools/buildbot/bin/python'} 09:56:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:56:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:56:10 INFO - Installing collected packages: mozsystemmonitor 09:56:10 INFO - Running setup.py install for mozsystemmonitor 09:56:10 INFO - Successfully installed mozsystemmonitor 09:56:10 INFO - Cleaning up... 09:56:10 INFO - Return code: 0 09:56:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:10 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 0x7f501f533f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e25ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e22f60>, '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 0x1e23d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e241d0>, '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': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', '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:56:10 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:56:10 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:56:10 INFO - Using env: {'DISPLAY': ':0', 09:56:10 INFO - 'HOME': '/home/cltbld', 09:56:10 INFO - 'LANG': 'en_US.UTF-8', 09:56:10 INFO - 'LANGUAGE': 'en_US:en', 09:56:10 INFO - 'LOGNAME': 'cltbld', 09:56:10 INFO - 'MAIL': '/var/mail/cltbld', 09:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:10 INFO - 'MOZ_NO_REMOTE': '1', 09:56:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:10 INFO - 'NO_EM_RESTART': '1', 09:56:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:10 INFO - 'PWD': '/builds/slave/test', 09:56:10 INFO - 'SHELL': '/bin/bash', 09:56:10 INFO - 'SHLVL': '1', 09:56:10 INFO - 'TERM': 'linux', 09:56:10 INFO - 'TMOUT': '86400', 09:56:10 INFO - 'USER': 'cltbld', 09:56:10 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', 09:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:10 INFO - '_': '/tools/buildbot/bin/python'} 09:56:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:10 INFO - Downloading/unpacking jsonschema==2.5.1 09:56:10 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:56:10 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:56:10 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:56:10 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:56:10 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:56:10 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:56:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:56:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:56:12 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:56:12 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:56:12 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:56:12 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:56:12 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:56:12 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:56:12 INFO - Downloading functools32-3.2.3-2.tar.gz 09:56:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:56:12 INFO - warning: no files found matching '*.txt' 09:56:12 INFO - no previously-included directories found matching 'build' 09:56:12 INFO - no previously-included directories found matching 'dist' 09:56:12 INFO - no previously-included directories found matching '.git*' 09:56:12 INFO - Installing collected packages: jsonschema, functools32 09:56:12 INFO - Running setup.py install for functools32 09:56:12 INFO - warning: no files found matching '*.txt' 09:56:12 INFO - no previously-included directories found matching 'build' 09:56:12 INFO - no previously-included directories found matching 'dist' 09:56:12 INFO - no previously-included directories found matching '.git*' 09:56:12 INFO - Successfully installed jsonschema functools32 09:56:12 INFO - Cleaning up... 09:56:12 INFO - Return code: 0 09:56:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:56:12 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:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:12 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:56:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:12 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 0x7f501f533f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e25ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e22f60>, '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 0x1e23d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e241d0>, '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': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', '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:56:12 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:56:12 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:56:12 INFO - Using env: {'DISPLAY': ':0', 09:56:12 INFO - 'HOME': '/home/cltbld', 09:56:12 INFO - 'LANG': 'en_US.UTF-8', 09:56:12 INFO - 'LANGUAGE': 'en_US:en', 09:56:12 INFO - 'LOGNAME': 'cltbld', 09:56:12 INFO - 'MAIL': '/var/mail/cltbld', 09:56:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:12 INFO - 'MOZ_NO_REMOTE': '1', 09:56:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:12 INFO - 'NO_EM_RESTART': '1', 09:56:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:12 INFO - 'PWD': '/builds/slave/test', 09:56:12 INFO - 'SHELL': '/bin/bash', 09:56:12 INFO - 'SHLVL': '1', 09:56:12 INFO - 'TERM': 'linux', 09:56:12 INFO - 'TMOUT': '86400', 09:56:12 INFO - 'USER': 'cltbld', 09:56:12 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', 09:56:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:12 INFO - '_': '/tools/buildbot/bin/python'} 09:56:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:56:13 INFO - Cleaning up... 09:56:13 INFO - Return code: 0 09:56:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:56:13 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:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:13 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:56:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:13 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 0x7f501f533f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e25ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e22f60>, '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 0x1e23d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e241d0>, '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': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', '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:56:13 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:56:13 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:56:13 INFO - Using env: {'DISPLAY': ':0', 09:56:13 INFO - 'HOME': '/home/cltbld', 09:56:13 INFO - 'LANG': 'en_US.UTF-8', 09:56:13 INFO - 'LANGUAGE': 'en_US:en', 09:56:13 INFO - 'LOGNAME': 'cltbld', 09:56:13 INFO - 'MAIL': '/var/mail/cltbld', 09:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:13 INFO - 'MOZ_NO_REMOTE': '1', 09:56:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:13 INFO - 'NO_EM_RESTART': '1', 09:56:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:13 INFO - 'PWD': '/builds/slave/test', 09:56:13 INFO - 'SHELL': '/bin/bash', 09:56:13 INFO - 'SHLVL': '1', 09:56:13 INFO - 'TERM': 'linux', 09:56:13 INFO - 'TMOUT': '86400', 09:56:13 INFO - 'USER': 'cltbld', 09:56:13 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', 09:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:13 INFO - '_': '/tools/buildbot/bin/python'} 09:56:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:13 INFO - Downloading/unpacking blobuploader==1.2.4 09:56:13 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:56:13 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:56:13 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:56:13 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:56:13 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:56:13 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:56:15 INFO - Downloading blobuploader-1.2.4.tar.gz 09:56:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:56:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:56:15 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:56:15 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:56:15 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:56:15 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:56:15 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:56:15 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:56:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:56:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:56:15 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:56:15 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:56:15 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:56:15 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:56:15 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:56:15 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:56:15 INFO - Downloading docopt-0.6.1.tar.gz 09:56:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:56:15 INFO - Installing collected packages: blobuploader, requests, docopt 09:56:15 INFO - Running setup.py install for blobuploader 09:56:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:56:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:56:16 INFO - Running setup.py install for requests 09:56:16 INFO - Running setup.py install for docopt 09:56:16 INFO - Successfully installed blobuploader requests docopt 09:56:16 INFO - Cleaning up... 09:56:16 INFO - Return code: 0 09:56:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56: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:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56: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:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:16 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 0x7f501f533f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e25ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e22f60>, '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 0x1e23d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e241d0>, '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': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', '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:56:16 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:56:16 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:56:16 INFO - Using env: {'DISPLAY': ':0', 09:56:16 INFO - 'HOME': '/home/cltbld', 09:56:16 INFO - 'LANG': 'en_US.UTF-8', 09:56:16 INFO - 'LANGUAGE': 'en_US:en', 09:56:16 INFO - 'LOGNAME': 'cltbld', 09:56:16 INFO - 'MAIL': '/var/mail/cltbld', 09:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:16 INFO - 'MOZ_NO_REMOTE': '1', 09:56:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:16 INFO - 'NO_EM_RESTART': '1', 09:56:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:16 INFO - 'PWD': '/builds/slave/test', 09:56:16 INFO - 'SHELL': '/bin/bash', 09:56:16 INFO - 'SHLVL': '1', 09:56:16 INFO - 'TERM': 'linux', 09:56:16 INFO - 'TMOUT': '86400', 09:56:16 INFO - 'USER': 'cltbld', 09:56:16 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', 09:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:16 INFO - '_': '/tools/buildbot/bin/python'} 09:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:56:16 INFO - Running setup.py (path:/tmp/pip-t3S7hm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:56:16 INFO - Running setup.py (path:/tmp/pip-upLQSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:56:16 INFO - Running setup.py (path:/tmp/pip-MTjcud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:56:16 INFO - Running setup.py (path:/tmp/pip-oyYPd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:56:17 INFO - Running setup.py (path:/tmp/pip-8gBKy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:56:17 INFO - Running setup.py (path:/tmp/pip-eZY7Wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:56:17 INFO - Running setup.py (path:/tmp/pip-Di_mz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:56:17 INFO - Running setup.py (path:/tmp/pip-oPurtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:56:17 INFO - Running setup.py (path:/tmp/pip-E0v7EZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:56:17 INFO - Running setup.py (path:/tmp/pip-uwAHj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:56:17 INFO - Running setup.py (path:/tmp/pip-ulGhi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:56:17 INFO - Running setup.py (path:/tmp/pip-RrTJdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:56:17 INFO - Running setup.py (path:/tmp/pip-eKjY_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:56:17 INFO - Running setup.py (path:/tmp/pip-71bYLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:17 INFO - Running setup.py (path:/tmp/pip-WKQj0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:56:17 INFO - Running setup.py (path:/tmp/pip-L0OOlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:56:17 INFO - Running setup.py (path:/tmp/pip-St5jl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:56:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:56:17 INFO - Running setup.py install for manifestparser 09:56:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:56:18 INFO - Running setup.py install for mozcrash 09:56:18 INFO - Running setup.py install for mozdebug 09:56:18 INFO - Running setup.py install for mozdevice 09:56:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:56:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:56:18 INFO - Running setup.py install for mozfile 09:56:18 INFO - Running setup.py install for mozhttpd 09:56:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:56:18 INFO - Running setup.py install for mozinfo 09:56:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:56:18 INFO - Running setup.py install for mozInstall 09:56:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:56:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:56:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:56:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:56:18 INFO - Running setup.py install for mozleak 09:56:18 INFO - Running setup.py install for mozlog 09:56:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:56:19 INFO - Running setup.py install for moznetwork 09:56:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:56:19 INFO - Running setup.py install for mozprocess 09:56:19 INFO - Running setup.py install for mozprofile 09:56:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:56:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:56:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:56:19 INFO - Running setup.py install for mozrunner 09:56:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:56:19 INFO - Running setup.py install for mozscreenshot 09:56:19 INFO - Running setup.py install for moztest 09:56:19 INFO - Running setup.py install for mozversion 09:56:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:56:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:56:19 INFO - Cleaning up... 09:56:19 INFO - Return code: 0 09:56:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:19 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 0x7f501f533f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e25ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e22f60>, '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 0x1e23d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e241d0>, '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': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', '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:56:19 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:56:19 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:56:19 INFO - Using env: {'DISPLAY': ':0', 09:56:19 INFO - 'HOME': '/home/cltbld', 09:56:19 INFO - 'LANG': 'en_US.UTF-8', 09:56:19 INFO - 'LANGUAGE': 'en_US:en', 09:56:19 INFO - 'LOGNAME': 'cltbld', 09:56:19 INFO - 'MAIL': '/var/mail/cltbld', 09:56:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:19 INFO - 'MOZ_NO_REMOTE': '1', 09:56:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:19 INFO - 'NO_EM_RESTART': '1', 09:56:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:19 INFO - 'PWD': '/builds/slave/test', 09:56:19 INFO - 'SHELL': '/bin/bash', 09:56:19 INFO - 'SHLVL': '1', 09:56:19 INFO - 'TERM': 'linux', 09:56:19 INFO - 'TMOUT': '86400', 09:56:19 INFO - 'USER': 'cltbld', 09:56:19 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', 09:56:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:19 INFO - '_': '/tools/buildbot/bin/python'} 09:56:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:56:20 INFO - Running setup.py (path:/tmp/pip-gDYfpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:56:20 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:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:56:20 INFO - Running setup.py (path:/tmp/pip-Hi8tH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:56:20 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:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:56:20 INFO - Running setup.py (path:/tmp/pip-7b27rk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:56:20 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:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:56:20 INFO - Running setup.py (path:/tmp/pip-c2IEXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:56:20 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:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:56:20 INFO - Running setup.py (path:/tmp/pip-8cWKD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:56:20 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:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:56:20 INFO - Running setup.py (path:/tmp/pip-3hmF2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:56:20 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:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:56:20 INFO - Running setup.py (path:/tmp/pip-WD76A4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:56:20 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:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:56:20 INFO - Running setup.py (path:/tmp/pip-_UHuN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:56:20 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:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:56:20 INFO - Running setup.py (path:/tmp/pip-rM412g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:56:20 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:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:56:20 INFO - Running setup.py (path:/tmp/pip-ZK7l9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:56:20 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:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:56:20 INFO - Running setup.py (path:/tmp/pip-Zf3Ndl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:56:20 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:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:56:20 INFO - Running setup.py (path:/tmp/pip-cIvs92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:56:20 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:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:56:20 INFO - Running setup.py (path:/tmp/pip-JrNFZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:56:20 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:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:56:20 INFO - Running setup.py (path:/tmp/pip-sEu_iR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:56:21 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:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:21 INFO - Running setup.py (path:/tmp/pip-RsE955-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:21 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:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:56:21 INFO - Running setup.py (path:/tmp/pip-DihoD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:56:21 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:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:56:21 INFO - Running setup.py (path:/tmp/pip-GAuJ77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:56:21 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:56:21 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:56:21 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:56:21 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:56:21 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:56:21 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:56:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:56: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:56: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:56: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:56: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:56: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:56: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:56:23 INFO - Downloading blessings-1.6.tar.gz 09:56:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:56:23 INFO - Installing collected packages: blessings 09:56:23 INFO - Running setup.py install for blessings 09:56:23 INFO - Successfully installed blessings 09:56:23 INFO - Cleaning up... 09:56:23 INFO - Return code: 0 09:56:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:56:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:56:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:56:23 INFO - Reading from file tmpfile_stdout 09:56:23 INFO - Current package versions: 09:56:23 INFO - argparse == 1.2.1 09:56:23 INFO - blessings == 1.6 09:56:23 INFO - blobuploader == 1.2.4 09:56:23 INFO - docopt == 0.6.1 09:56:23 INFO - functools32 == 3.2.3-2 09:56:23 INFO - jsonschema == 2.5.1 09:56:23 INFO - manifestparser == 1.1 09:56:23 INFO - mozInstall == 1.12 09:56:23 INFO - mozcrash == 0.17 09:56:23 INFO - mozdebug == 0.1 09:56:23 INFO - mozdevice == 0.48 09:56:23 INFO - mozfile == 1.2 09:56:23 INFO - mozhttpd == 0.7 09:56:23 INFO - mozinfo == 0.9 09:56:23 INFO - mozleak == 0.1 09:56:23 INFO - mozlog == 3.4 09:56:23 INFO - moznetwork == 0.27 09:56:23 INFO - mozprocess == 0.23 09:56:23 INFO - mozprofile == 0.28 09:56:23 INFO - mozrunner == 6.12 09:56:23 INFO - mozscreenshot == 0.1 09:56:23 INFO - mozsystemmonitor == 0.3 09:56:23 INFO - moztest == 0.7 09:56:23 INFO - mozversion == 1.4 09:56:23 INFO - psutil == 3.1.1 09:56:23 INFO - requests == 1.2.3 09:56:23 INFO - wsgiref == 0.1.2 09:56:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:23 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 0x7f501f533f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e25ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e22f60>, '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 0x1e23d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e241d0>, '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': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', '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:56:23 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:56:23 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:56:23 INFO - Using env: {'DISPLAY': ':0', 09:56:23 INFO - 'HOME': '/home/cltbld', 09:56:23 INFO - 'LANG': 'en_US.UTF-8', 09:56:23 INFO - 'LANGUAGE': 'en_US:en', 09:56:23 INFO - 'LOGNAME': 'cltbld', 09:56:23 INFO - 'MAIL': '/var/mail/cltbld', 09:56:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:23 INFO - 'MOZ_NO_REMOTE': '1', 09:56:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:23 INFO - 'NO_EM_RESTART': '1', 09:56:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:23 INFO - 'PWD': '/builds/slave/test', 09:56:23 INFO - 'SHELL': '/bin/bash', 09:56:23 INFO - 'SHLVL': '1', 09:56:23 INFO - 'TERM': 'linux', 09:56:23 INFO - 'TMOUT': '86400', 09:56:23 INFO - 'USER': 'cltbld', 09:56:23 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', 09:56:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:23 INFO - '_': '/tools/buildbot/bin/python'} 09:56:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:23 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:56:23 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:56:23 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:56:23 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:56:23 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:56:23 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:56:23 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:56:23 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:56:23 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:56:23 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:56:23 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:56:23 INFO - Cleaning up... 09:56:23 INFO - Return code: 0 09:56:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:56:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:23 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 0x7f501f533f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e25ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e22f60>, '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 0x1e23d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e241d0>, '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': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', '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:56:23 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:56:23 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:56:23 INFO - Using env: {'DISPLAY': ':0', 09:56:23 INFO - 'HOME': '/home/cltbld', 09:56:23 INFO - 'LANG': 'en_US.UTF-8', 09:56:23 INFO - 'LANGUAGE': 'en_US:en', 09:56:23 INFO - 'LOGNAME': 'cltbld', 09:56:23 INFO - 'MAIL': '/var/mail/cltbld', 09:56:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:23 INFO - 'MOZ_NO_REMOTE': '1', 09:56:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:23 INFO - 'NO_EM_RESTART': '1', 09:56:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:23 INFO - 'PWD': '/builds/slave/test', 09:56:23 INFO - 'SHELL': '/bin/bash', 09:56:23 INFO - 'SHLVL': '1', 09:56:23 INFO - 'TERM': 'linux', 09:56:23 INFO - 'TMOUT': '86400', 09:56:23 INFO - 'USER': 'cltbld', 09:56:23 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', 09:56:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:23 INFO - '_': '/tools/buildbot/bin/python'} 09:56:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:56:24 INFO - Cleaning up... 09:56:24 INFO - Return code: 0 09:56:24 INFO - Running post-action listener: _resource_record_post_action 09:56:24 INFO - Running post-action listener: _start_resource_monitoring 09:56:24 INFO - Starting resource monitoring. 09:56:24 INFO - [mozharness: 2016-09-29 16:56:24.100224Z] Finished create-virtualenv step (success) 09:56:24 INFO - [mozharness: 2016-09-29 16:56:24.100725Z] Running install step. 09:56:24 INFO - Running pre-action listener: _resource_record_pre_action 09:56:24 INFO - Running main action method: install 09:56:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:56:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:56:24 INFO - Reading from file tmpfile_stdout 09:56:24 INFO - Detecting whether we're running mozinstall >=1.0... 09:56:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:56:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:56:24 INFO - Reading from file tmpfile_stdout 09:56:24 INFO - Output received: 09:56:24 INFO - Usage: mozinstall [options] installer 09:56:24 INFO - Options: 09:56:24 INFO - -h, --help show this help message and exit 09:56:24 INFO - -d DEST, --destination=DEST 09:56:24 INFO - Directory to install application into. [default: 09:56:24 INFO - "/builds/slave/test"] 09:56:24 INFO - --app=APP Application being installed. [default: firefox] 09:56:24 INFO - mkdir: /builds/slave/test/build/application 09:56:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:56:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:56:40 INFO - Reading from file tmpfile_stdout 09:56:40 INFO - Output received: 09:56:40 INFO - /builds/slave/test/build/application/firefox/firefox 09:56:40 INFO - Running post-action listener: _resource_record_post_action 09:56:40 INFO - [mozharness: 2016-09-29 16:56:40.368296Z] Finished install step (success) 09:56:40 INFO - [mozharness: 2016-09-29 16:56:40.368544Z] Running run-tests step. 09:56:40 INFO - Running pre-action listener: _resource_record_pre_action 09:56:40 INFO - Running main action method: run_tests 09:56:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:56:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:56:40 INFO - Python 2.7.3 09:56:40 INFO - Return code: 0 09:56:40 INFO - grabbing minidump binary from tooltool 09:56: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:56:40 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 0x1e22f60>, '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 0x1e23d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e241d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:56:40 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:56:40 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:56:40 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:56:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:56:40 INFO - Return code: 0 09:56:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:56:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:56:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:56:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:56:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:56:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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:56:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-060 --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:56:40 INFO - Using env: {'DISPLAY': ':0', 09:56:40 INFO - 'HOME': '/home/cltbld', 09:56:40 INFO - 'LANG': 'en_US.UTF-8', 09:56:40 INFO - 'LANGUAGE': 'en_US:en', 09:56:40 INFO - 'LOGNAME': 'cltbld', 09:56:40 INFO - 'MAIL': '/var/mail/cltbld', 09:56:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:56:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:56:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:40 INFO - 'MOZ_NO_REMOTE': '1', 09:56:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:56:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:40 INFO - 'NO_EM_RESTART': '1', 09:56:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:40 INFO - 'PWD': '/builds/slave/test', 09:56:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:56:40 INFO - 'SHELL': '/bin/bash', 09:56:40 INFO - 'SHLVL': '1', 09:56:40 INFO - 'TERM': 'linux', 09:56:40 INFO - 'TMOUT': '86400', 09:56:40 INFO - 'USER': 'cltbld', 09:56:40 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117', 09:56:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:40 INFO - '_': '/tools/buildbot/bin/python'} 09:56:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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:56:40 INFO - mozversion application_buildid: 20160929071320 09:56:40 INFO - mozversion application_changeset: 0e12aae055677772cabc476634a4100410035375 09:56:40 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:56:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:56:40 INFO - mozversion application_name: Firefox 09:56:40 INFO - mozversion application_remotingname: firefox-dev 09:56:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:56:40 INFO - mozversion application_vendor: Mozilla 09:56:40 INFO - mozversion application_version: 51.0a2 09:56:40 INFO - mozversion platform_buildid: 20160929071320 09:56:40 INFO - mozversion platform_changeset: 0e12aae055677772cabc476634a4100410035375 09:56:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:56:40 INFO - mozversion platform_version: 51.0a2 09:56:40 INFO - using testdate: 1475168200 09:56:40 INFO - actual date: 1475168200 09:56:40 INFO - starting webserver on 'localhost:58704' 09:56:40 INFO - SUITE-START | Running 2 tests 09:56:40 INFO - TEST-START | dromaeo_css 09:56:40 INFO - operating with platform_type : linux_ 09:56:40 INFO - Initialising browser for dromaeo_css test... 09:56:40 INFO - TEST-INFO | started process 6429 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplWwUJO/profile http://localhost:58704/getInfo.html) 09:56:44 INFO - PROCESS | 6429 | __metrics Screen width/height:1600/1200 09:56:44 INFO - PROCESS | 6429 | colorDepth:24 09:56:44 INFO - PROCESS | 6429 | Browser inner width/height: 1024/697 09:56:44 INFO - PROCESS | 6429 | __metrics 09:56:45 INFO - PROCESS | 6429 | 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:56:45 INFO - PROCESS | 6429 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:56:45 INFO - PROCESS | 6429 | console.error: PushService: 09:56:45 INFO - PROCESS | 6429 | stateChangeProcessEnqueue: Error transitioning state 09:56:45 INFO - PROCESS | 6429 | UnknownError 09:56:46 INFO - TEST-INFO | 6429: exit 0 09:56:46 INFO - Browser initialized. 09:56:46 INFO - Running cycle 1/1 for dromaeo_css test... 09:56:46 INFO - TEST-INFO | started process 6519 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplWwUJO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:56:58 INFO - PROCESS | 6519 | RSS: Main: 144691200 09:56:58 INFO - PROCESS | 6519 | 09:57:49 INFO - PROCESS | 6519 | 1475168269129 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:59:58 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:58704/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58704/tests/dromaeo/cssquery-ext.html) 09:59:59 INFO - PROCESS | 6519 | RSS: Main: 248377344 09:59:59 INFO - PROCESS | 6519 | 10:03:01 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:58704/tests/dromaeo/cssquery-ext.html (next: http://localhost:58704/tests/dromaeo/cssquery-jquery.html) 10:03:02 INFO - PROCESS | 6519 | RSS: Main: 234725376 10:03:02 INFO - PROCESS | 6519 | 10:06:04 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:58704/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58704/tests/dromaeo/cssquery-mootools.html) 10:06:04 INFO - PROCESS | 6519 | RSS: Main: 223956992 10:06:04 INFO - PROCESS | 6519 | 10:09:06 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:58704/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58704/tests/dromaeo/cssquery-prototype.html) 10:09:07 INFO - PROCESS | 6519 | RSS: Main: 173400064 10:09:07 INFO - PROCESS | 6519 | 10:12:10 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:58704/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58704/tests/dromaeo/cssquery-yui.html) 10:12:10 INFO - PROCESS | 6519 | RSS: Main: 176078848 10:12:10 INFO - PROCESS | 6519 | 10:15:13 INFO - PROCESS | 6519 | Cycle 1(1): loaded http://localhost:58704/tests/dromaeo/cssquery-yui.html (next: http://localhost:58704/tests/dromaeo/cssquery-dojo.html) 10:15:13 INFO - PROCESS | 6519 | RSS: Main: 265932800 10:15:13 INFO - PROCESS | 6519 | 10:15:13 INFO - PROCESS | 6519 | __start_tp_report 10:15:13 INFO - PROCESS | 6519 | _x_x_mozilla_page_load 10:15:13 INFO - PROCESS | 6519 | _x_x_mozilla_page_load_details 10:15:13 INFO - PROCESS | 6519 | |i|pagename|runs| 10:15:13 INFO - PROCESS | 6519 | |0;dojo.html;3090.5982222311095;3101.085179871938;3116.6571677115526;3144.7955882867595;3164.493681011039;5645.096784514479;5753.942460575393;5785.942140578593;5792.507636850867;5802.680852553108;5739.196512488255;5803.6227645203035;5817.854553636162;5818.767249310034;5826.271716035496;5163.122269214243;5214.8696282592855;5239.266502689627;5268.631195816294;5288;5057.772400241998;5291.603129765283;5305.469453054702;5306.310179676656;5337.412884582693;5032.069067222557;5033.320501732274;5034.84895453137;5062.190049592066;5066.442691303953;5101.20931255656;5126.410462796789;5128.153854613985;5170.767315470813;5178.223266510016;5055.4186268579215;5075;5145.176771716508;5151.665141765773;5188.299579556749;5475.616706830523;5484.232207490954;5527.53015993637;5584.1902924076185;5591.888162236733;6049.455549000611;6061.090836374579;6061.272647282354;6063.363346848561;6072.51419886408;5824.3302020267365;6068.726907289139;6072.5445591580365;6101.237345331833;6106.145139680449;5503.091989821714;5619.325680918275;5716.79991200306;5726.3700992890745;5804.2744656917885;6297.653629050446;6320.683965801783;6326.335734747877;6334.619922804554;6335.556511044275;6049.274087109575;6061.424164704435;6063.9090413643835;6064.151018857364;6068.544859135583;5869.794557190563;5904.4095590441475;5909.96575599277;5952.464278214895;5954.4045595440975;5877.764889404376;5914.6746928918465;5918.260217472816;5924.7926322578505;5945.4649081582;5937.2578427696535;5952.791652292236;5968.1644569760265;6022.427869352405;6022.5784195105925;4477.7984990675495;4500.909981800346;4539.954600453953;4559.703619264737;4592.586667199901;4052.0477687743355;4058.6144316289906;4062.7968601569805;4075.1238483726142;4091.91816163681;5706.343770466367;5708.20085188074;5714.114312281603;5715.028445164332;5748.856278593069;5874.353821079678;5894.056950887837;6049.485793707584;6084.30030546484;6089.54328425361;5811.157382179645;5817.069268916953;5818.476337129594;5822.0684690449325;5829.446202610745;5274.736263186825;5283.762230699628;5284.207368894619;5287.735613219401;5292.259083728281;5982.790602328898;6020.548458865515;6163.8150855474405;6172.9074063889975;6365.809025729235;6131.713108457195;6144.525313924715;6147.44672979434;6148.1699970503405;6152.630842149485;6245.687715614292;6297.842553936188;6346.428821406095;6349.015902535114;6350.618962862059;5766.94233057664;5802.47777700009;6048.334683184758;6073.058675905242;6081.087836824512;6010.519158467399;6091.573589848668;6091.969540152271;6160.10678451631;6171.47542458896;6003.519718422428;6005.159277701213;6007.54943379257;6020;6035.6076855004285;5713.143028545752;5937.614055086406;5988.580799344004;5997.880042399215;5998.160257564032;5671.432856714296;5853.09277062056;5859.707014649336;5864.706764661835;5941.31674857397;6011.72947217385;6024.367441418588;6029.909551356645;6034.034554471264;6034.306054803754;5593.440655934374;5619.8595035124445;5718.828435146952;5865.090910908816;5888.205092312654 10:15:13 INFO - PROCESS | 6519 | |1;ext.html;556.0150263559093;595.4373117404053;610.1579819848607;617.7652492053022;650.2099948562495;34331.31337373254;46976.29535556965;48217.758911205485;48432.99999999991;51734.223986640136;49443.25835112467;49799.25301120486;49933;50062.74968625152;50104.74947625257;8410.453320534145;9280.16478516935;9440.433573985583;9445.622175112987;9451.858222126673;8352.955880514935;10871.347719136877;11156.386398748065;11161.82800805916;11515.712107197303;11258.324500529954;11316.943415282913;11374.886251137506;11403.942980285086;11407.201495895291;11234.269772464846;11275.703294121158;11325.716857078556;11355.829662555068;11359.034482068993;10934.125269978382;11002.78969313375;11006.944965275123;11009.119270458345;11020.283681560673;46177.769111154565;46556;46571.76714116408;46690.06619867618;46788.06423871538;3752.887413377603;3812.942805857914;3841.519810023747;3843.4619153318276;3873.12854607711;35098.824505877565;35189;35431.822840885885;35797.46303805445;37036.25927481435;4336.197803406349;4376.102898905717;4377.102693947733;4445.422095127613;4502.234620114588;371570;372546;378735;381131;382006;66469.6676516624;66819.33180668228;66864.33135668679;66874.66562667156;66943.66528167328;40695.38956915648;41338.79330603328;41370;43887;43889.34165987513;19997.800021999883;20302.08640611176;20309.390718278475;20310.796892031187;20322.89838550798;13878.236696981561;14274.714505710035;15115.697686046218;15316;15519.767203491956;20889.89555052215;21126.366209013755;21179;21252.36242912715;21310;7381.778546643609;7512.173660897297;7664;7675.692972281158;7831.334336581341;978.5596481583316;1003.0721582536125;1041.1774697988656;1080.1682704317632;1110.9722256943642;32000.03999880007;34315.48526772101;34608;34712.30575388478;34753.478697819555;1136.142212629476;1149.454009345571;1186.5313201285617;1195.2708847602955;1216.9878301217052;17376.65246695059;18285.63428731418;18372.724409133873;18505;18629.813701862808;581807;584482;586497;586672;587621;64391.356086438536;64856.67571662112;64947;64969.67515162394;65051;1326.316946772413;1337.839459264914;1356.7354365898555;1430.8497607751244;1483.9332230049674;31167.376652467276;35565.288694225455;36336.4549531752;36436.45345319875;36531.086722832144;54855.45144548663;55376;55457.44542554523;55527.72236138793;55597;413803;414121;415195;415976;420592;30359.392812144077;31612.841935790173;31655;33114.172145695586;33130.337393252485;51983.740081300864;52029.7398513005;52186;52371;53530;43604;44158;44494.76846783356;45399.77300113478;46475.76762116168;43639;43980;45122.45724370013;45549.31676024928;45662.77168614136;44823.327650084626;45275.547244528454;45855.77072114618;45865.312020319056;46157.538424615326;35343.11642208965;38800.80599596984;39333;39488.80255598704;39721;29859.104226873227;29907.700922991364;30247.88388779547;30313.545296820124;30577 10:15:13 INFO - PROCESS | 6519 | |2;jquery.html;388.7686826338328;407.6066595735115;414.33912908910287;414.65376410697064;432.403283468813;14629.707405851877;16444;20299.594008119828;21141;21205.363839084814;20226;20363.694544581842;20660.586788264227;20864.582708345824;20891.059902304394;5801.738921748521;5871.8532036699;5918.940810591882;5919.49684276837;5957.85105372367;4363.258246098171;5895.469407753301;5966.850828729295;5974.372690867462;5976.4919981801595;4485.842995495158;6465.773697920574;6495.317991610884;6622.53642245043;6631.038499417582;6447.83880402989;6588.110605068302;6611;6614.50391220658;6633.568818026861;5868.9413105868825;5874.295084589834;5881.088431293157;5882.794102206402;5886.6173698709445;24748.257552273462;24858.627120593206;25368.492630147295;25556.23331300064;25557.48885022308;2604.414006848463;2691.1657386210336;2830.0236418435607;2964.3626620276527;3003.57949887016;15516.767248491282;15583.376664933388;15804.209789510478;15831.762523562156;15891.28489217988;1982.5539253667953;1997.4507010572208;2009.1059478532059;2027.472857057161;2116.5237821490196;78659;79594;81240;86927;93009.99999999932;11508.19442639024;11529.48117334722;11892.762144757056;11969.102317326235;11976.88023119775;17134.571635709206;17137.228824702917;17142.657146856993;17156.22796974139;17165.055921924166;9137.588808503633;9315.953420232856;9386.296027797942;9396.812063758687;9433.952830235805;8907;8932.732018039469;8980.595873185723;9162;9185.63257469707;5033.270175824485;5110.105731496973;5142.665726727751;5143.331366922276;5189.143791274473;2024.210557882427;2065.783092775267;2081.689324849416;2103.516191276015;2112.6830975353514;934.761635782871;998.7403275148368;1012.8328825743818;1045.6026709850207;1068;19153.233870645017;19885.204591816164;20305.898470507553;20345;20573.89713051425;1018.4398580780542;1035.6582327831948;1055.0820785916303;1062.8565143705655;1082.7617924056694;9917.603295868084;11209.383483908301;11374.374409407563;11399.829002564968;11419;521579;528136;538963;550477;550938;92501;95250.04749952412;95256;96276;96757.51621241849;989.5992123190119;1020.3826684855555;1028.84052971791;1041.9218558608134;1047.984280235797;17535.736963945295;17638.823611763717;17857.374991875222;18086.90956545209;18223.99768012772;44514;44669.10661786681;45210.77394613006;45605;45848.54151458442;56965.14552281636;60634.39365606287;60707.39292607194;61701.07448388188;62070.68964655148;7932.405069619917;8366.656413411278;8409;8666.263367613572;9007.504587247764;10159.593616255266;13606.183628982293;13709.862901370858;13729.773503226934;14245.572632821031;12218.75562488763;12225.471816022997;12245.326507041842;12274.263544187377;12426.62720118398;11842.111841612086;11933.820992684943;11961.700957476132;11996.820047699466;12435.626931192079;12058.216215945935;12501.93656076784;12513.311767852856;12944.8058279124;13081.672958176121;8373.288270497076;8489.702860399857;8566.18621230982;8645.092265312052;8695.739127826175;10839.67480975572;11938;12198.734347650237;12361.320127393079;12457.937710311391 10:15:13 INFO - PROCESS | 6519 | |3;mootools.html;1434.476416108121;1538.1693885301934;1563.5153102538213;1758.7713597232357;1855.3320804510383;8357.91642083579;9352.859707104399;9758.902410975888;9807.264455165865;9817.852732209023;9602.615895364177;9688.85466717998;9702.66040688576;9705.272104592148;9709.660161894335;2533.556627590169;2552.885120169597;2561.679790026247;2565.730598287181;2566.5765148750434;2518.068314723555;2593.688757349121;2598.883050262743;2600.5839065749483;2602.778763805079;2506.8245222834407;2523.4196134888984;2541.313845261778;2548.120898290087;2548.439343344461;2408.5784987627185;2429.0890915906534;2432.5499782540287;2470.1354525915876;2480.503899220163;2460.4341001569737;2492.239866840613;2511.660925775015;2515.8113141514277;2536.9492610147877;9214.861777073349;9384.62461501546;9485.66800161991;9511.143997040299;9514.762130946712;5648.124540696199;5903.085021821625;5914.763409463616;5922.230110085704;5938.851528711773;3688.704903607705;3716.758410703296;3725.18046029873;3779.0930176757656;3787.9810600947094;1267.4739982907083;1283.5507572349695;1305.9869401306055;1312.3241530611742;1326.4031185966353;13319.933400332937;13429;13489.595312140651;13529.661758456019;13564.864351356458;4800.639952003614;4927.113119638428;4946.604271658259;5069.188929771292;5095.898082038412;10592.947035264928;10597.15222782191;10598.735031624272;10613.469326533643;10623.946880265548;5827.155062515912;5831.562632802472;5832.154337621021;5838.795642152504;5841.795537156264;4625.098105869323;4800.255960326155;4894.583960363409;5455.936092461931;5485.725713714299;4175.97912010438;4195.139996300751;4287.314029755286;4305.397244385726;4312.676549258818;1252.0546987024738;1258.213616489694;1271.2817258249245;1274.5666473398903;1284.3642397013539;1037.86507753993;1042.332906939544;1043.3739756146251;1050.3330385205297;1053.536443964653;6247.656753797955;6414.61512309263;6510.739570417225;6557.082008518812;6606.669666516655;484.4549881383444;510.47166183000417;516;523.5288240583429;529.6821906855932;4268.722533035343;4332.696711230246;4352.455943007124;4359.542248063971;4361.498427680794;11690;11926.701832454084;11989.820152697717;12011.279323240633;12017.819732704016;11395.715107122389;11860.406979650983;11982.16124871268;12001.579944701894;12005.819912701316;8606;9004.459732416077;9042.728718138467;9057.456552606865;9057.501837399006;6582.868342633216;6613.43785778195;6626.304238054935;6713.160854893138;6736.157980252468;10698.839517407401;11019;11088.223824332543;11155;11230.887691123311;10037.548310325907;10350.741231469274;11488.368139752394;11530.884691152982;11561.306321620394;8131.918680813353;8280.089190188954;8335.541545215072;8399.496030238206;8567.228949394335;9183;9337.766555836159;9346.971833098218;9354.71935841926;9371.484568348806;8235.29999950011;8251.257386835214;8309.626066826886;8448.366372521963;8607.612657430305;8242.464239824392;8248.13394593559;8281.917180828115;8361;8503.659853605786;7371.557706537625;7409.777706668809;7469.514481558681;7484;7507.136679281734;5379.6620595910435;5426.864328391822;5544.168374743659;5561.110222364402;5595.5523558116065;5431.701256430934;5603.131514615241;5607.831765047055;5618.831435056954;5668.093105103164 10:15:13 INFO - PROCESS | 6519 | |4;prototype.html;338.6241272187871;347.2811280649058;351.725653989888;354.04231553647384;359.1524003352089;5561.249231353766;5592.315688469537;5622.212890195366;5626.099824028156;5687.345955215141;5569.275994120769;5580.330360356753;5593.804216852411;5604.607677462579;5611.2144299798065;3820.789856557888;4160.209560183571;4187.623113919746;4248.830046798124;4360.433143691315;4322.891927701817;4327.0480494291205;4334.84828031019;4337.54455782143;4371.322445021026;4319;4323.697341186118;4325.589069038452;4335.544767799383;4336.371226172203;4044.705694177864;4085.571015043437;4316.3741257517795;4319.257271632505;4320.351947207912;3861.9765762073075;3874.1283211277237;3879.650831425185;3884.3396622574232;3892.3966785148345;5388.137897936351;5391.245225668409;5406.783728650849;5410.864728381783;5459.781608735646;4802.967362017148;4910.116179087763;4942.479335538865;5030.37120359955;5035.672681275705;5710.2862142232225;5731;5733.082706766939;5733.942660573382;5737.254156959611;4247.7451352918615;4254.87235382939;4257.40396344512;4261.914761704779;4277.893052673677;5811.476967072941;5937.970310148422;5942.792002279943;5999.310079340888;6014.278286605591;4348.434703488526;4348.847790327387;4361.236783562864;4364.803583838735;4377.759223242689;4422.314541246112;4431.604044725901;4431.8448854289945;4433.268510695763;4446.577575130357;4861.270809378621;4945.134601444733;4973.875653108629;4995.750212489361;4997.5002499749735;5794.391588883191;5799.9130013049835;5812.767489300465;5814.418558144152;5815.156802263648;1519.468186134844;1535.8540938610813;1537.1392020468572;1537.6847746212;1539.9846001540066;937.6624415210518;941.5782168924973;943.8867335919733;955.7180631713661;992.399598243067;4948.925766113512;4951.306817045616;4963;4972.825951091694;4973;5235.921461178085;5236.884053987871;5256.605754568346;5297.65565238266;5300.655457395257;4590.311453281967;4939.2838038484215;4957.429895562056;5019.171836646912;5041.571466425395;2767.2113447667316;2774.557230240264;2794.2455537005303;2805.452936677328;2811.9297017574313;5379.78480860769;5401.270828438189;5418.4039755626845;5421.674699518042;5422.267993820863;5104.948950510522;5300.70846103468;5347.791325204846;5348.085293515043;5777.306723193244;5948.702564871739;5991.460768530853;6174.320824709368;6182.6599537025895;6187.659678717714;5984.371640977634;5988.461038506555;5990.400959904149;6023.939760602513;6024.849378765566;5764.711764411679;5793.391693872083;5807.593468457168;5889.764409423575;5918.224532640104;6144.293406258159;6153.323134455296;6155.384461553988;6166.383361663798;6175.84560385994;4053.7973101345406;4343.34849772524;4346.304591265382;4346.6088052075465;4353.804078816397;5797.188393624981;5894.2611929480045;5899.410058994066;5900.970495147497;5905.932923476748;5691.922448306421;5741.913871292018;5764.337101233412;5768.192453056491;5800.187973683604;5588.38527761954;5683.60214784961;5728.198052272768;5731.799387021434;5751.367349591458;5702.258706368146;5703.686297253525;5703.714814259188;5711.001149597608;5718.685472299064;5556.605932751412;5739.196512488172;5754.3382511010095;5770.682612456355;5794.159846822272;5557.527610153021;5592.832215033555;5612.607117501736;5625.184348269396;5629.509703983556 10:15:13 INFO - PROCESS | 6519 | |5;yui.html;817.407379649754;849.1593322610613;877.5831480046977;897.0446474504652;900.3427497926517;16243;17114.144292785357;17284;17305.22126504307;17373.565660858483;15187.392504299842;15280.923595382008;15300.234988250599;15776.132312722795;15889.841101588981;863.956802159891;880.3089574683875;882.0385779500343;883.0904168706243;883.4301875290433;729.5075823818928;752.7854561449988;852.7015544559416;875.8467268228067;881.22012019363;937.7186843946789;940.4028441939383;940.5767404667893;940.7695114696875;941.4163218804316;934.8784657994452;935.4808081514757;935.7005758157393;936.6347124621335;937.0114529171722;555.4695266020981;570.7203470299564;648.6626953983949;661.1603263854926;668.8428219368444;16286.755698664529;16358.672826543403;16361.754573681284;16372.263248153862;16399.098049607266;14969;15418;15607.45373911919;15789.842101578952;15861.52415427539;13437.798433023512;13602.387892544859;13771.380287887068;13811.585652430444;14062.578122656336;4692.296155576691;4704.858854234379;4709.481956984763;4773;4807.591354734852;81006.59496702538;81463;82095.5895220526;84054.57972710159;84912.99999999939;11583.131265154978;11622.88377116218;11722.120840936965;11839.467223974942;11929.403529823474;23588.17441389543;23594.17420390278;23605.7639423607;23655.408614784767;23661.881690591435;9108.544572771334;9343.859842102374;9420.105090016437;9421.76445588852;9467.857982130274;4927.113119638428;5038.571721403649;5100.1074811907765;5222.764975576109;5230.8169214077325;3891.96108038916;4112.485939257593;4122.154958233555;4167.270727622715;4170.416141740159;676.5669971218424;698.6402002968475;707.1761397971409;709.1667290933133;718.2063819479505;759.7720683794839;792.2473650032492;799.5522507395877;820.1019883227856;822.9094799572042;10574.206934479946;10879.259318509;10895.83656245157;11083;11130.165237607212;780.3756994404454;780.605794073989;822.0751654388813;826.3348004856028;836.2640876029177;5068.4424713281505;5228.581713462914;5310.981826905751;5313.54834839043;5346.385165705916;97683;99462.50268748609;100988.49505752571;115865;118382;100404.49797750964;103378;103682;104150;105668;86587.56706216554;89781.55109224543;91831.54084229536;93211.53394232986;97736.0226397727;10538;10783.719613355868;10834.891651083388;11112.611058612909;11194.608188713355;50456.74771626118;51511.48488515067;51688;51949.7402512985;53507.732461337444;75635.62182189054;76108.61945690236;80356;82120.58939705264;83431;14022.439102436196;14086.295685215493;14105.858941410455;14304;14384;16150.757738634165;16666.16669166561;16685.332586696397;16871.91564042172;17166.742498862277;12374.505019799108;14524.564263072125;14852.034617749812;14980.625484362978;15100.39598416051;14331.426742930165;15583.76624350613;15600.609984750472;16010.9199454002;16101.275442604876;14636.853631463975;14796.852031479548;14828.703425931637;15944.681106378039;15950.963187392783;12409.254765685157;12611.49554017829;12916.547920822726;13082.542111026069;13185.670358241121;12861.807072894102;12865.2924089176;13114.540991065267;13211.273379964194;13355.53255636048 10:15:13 INFO - PROCESS | 6519 | __end_tp_report 10:15:13 INFO - PROCESS | 6519 | __start_cc_report 10:15:13 INFO - PROCESS | 6519 | _x_x_mozilla_cycle_collect,813 10:15:13 INFO - PROCESS | 6519 | __end_cc_report 10:15:13 INFO - PROCESS | 6519 | __startTimestamp1475169313521__endTimestamp 10:15:13 INFO - PROCESS | 6519 | 10:15:13 INFO - PROCESS | 6519 | ------- Summary: start ------- 10:15:13 INFO - PROCESS | 6519 | Number of tests: 6 10:15:13 INFO - PROCESS | 6519 | 10:15:13 INFO - PROCESS | 6519 | [#0] dojo.html Cycles:165 Average:5624.78 Median:5829.45 stddev:667.82 (11.5%) stddev-sans-first:639.60 10:15:13 INFO - PROCESS | 6519 | Values: 3090.6 3101.1 3116.7 3144.8 3164.5 5645.1 5753.9 5785.9 5792.5 5802.7 5739.2 5803.6 5817.9 5818.8 5826.3 5163.1 5214.9 5239.3 5268.6 5288.0 5057.8 5291.6 5305.5 5306.3 5337.4 5032.1 5033.3 5034.8 5062.2 5066.4 5101.2 5126.4 5128.2 5170.8 5178.2 5055.4 5075.0 5145.2 5151.7 5188.3 5475.6 5484.2 5527.5 5584.2 5591.9 6049.5 6061.1 6061.3 6063.4 6072.5 5824.3 6068.7 6072.5 6101.2 6106.1 5503.1 5619.3 5716.8 5726.4 5804.3 6297.7 6320.7 6326.3 6334.6 6335.6 6049.3 6061.4 6063.9 6064.2 6068.5 5869.8 5904.4 5910.0 5952.5 5954.4 5877.8 5914.7 5918.3 5924.8 5945.5 5937.3 5952.8 5968.2 6022.4 6022.6 4477.8 4500.9 4540.0 4559.7 4592.6 4052.0 4058.6 4062.8 4075.1 4091.9 5706.3 5708.2 5714.1 5715.0 5748.9 5874.4 5894.1 6049.5 6084.3 6089.5 5811.2 5817.1 5818.5 5822.1 5829.4 5274.7 5283.8 5284.2 5287.7 5292.3 5982.8 6020.5 6163.8 6172.9 6365.8 6131.7 6144.5 6147.4 6148.2 6152.6 6245.7 6297.8 6346.4 6349.0 6350.6 5766.9 5802.5 6048.3 6073.1 6081.1 6010.5 6091.6 6092.0 6160.1 6171.5 6003.5 6005.2 6007.5 6020.0 6035.6 5713.1 5937.6 5988.6 5997.9 5998.2 5671.4 5853.1 5859.7 5864.7 5941.3 6011.7 6024.4 6029.9 6034.0 6034.3 5593.4 5619.9 5718.8 5865.1 5888.2 10:15:13 INFO - PROCESS | 6519 | 10:15:13 INFO - PROCESS | 6519 | [#1] ext.html Cycles:180 Average:63651.66 Median:39066.90 stddev:124006.44 (317.4%) stddev-sans-first:124263.82 10:15:13 INFO - PROCESS | 6519 | Values: 556.0 595.4 610.2 617.8 650.2 34331.3 46976.3 48217.8 48433.0 51734.2 49443.3 49799.3 49933.0 50062.7 50104.7 8410.5 9280.2 9440.4 9445.6 9451.9 8353.0 10871.3 11156.4 11161.8 11515.7 11258.3 11316.9 11374.9 11403.9 11407.2 11234.3 11275.7 11325.7 11355.8 11359.0 10934.1 11002.8 11006.9 11009.1 11020.3 46177.8 46556.0 46571.8 46690.1 46788.1 3752.9 3812.9 3841.5 3843.5 3873.1 35098.8 35189.0 35431.8 35797.5 37036.3 4336.2 4376.1 4377.1 4445.4 4502.2 371570.0 372546.0 378735.0 381131.0 382006.0 66469.7 66819.3 66864.3 66874.7 66943.7 40695.4 41338.8 41370.0 43887.0 43889.3 19997.8 20302.1 20309.4 20310.8 20322.9 13878.2 14274.7 15115.7 15316.0 15519.8 20889.9 21126.4 21179.0 21252.4 21310.0 7381.8 7512.2 7664.0 7675.7 7831.3 978.6 1003.1 1041.2 1080.2 1111.0 32000.0 34315.5 34608.0 34712.3 34753.5 1136.1 1149.5 1186.5 1195.3 1217.0 17376.7 18285.6 18372.7 18505.0 18629.8 581807.0 584482.0 586497.0 586672.0 587621.0 64391.4 64856.7 64947.0 64969.7 65051.0 1326.3 1337.8 1356.7 1430.8 1483.9 31167.4 35565.3 36336.5 36436.5 36531.1 54855.5 55376.0 55457.4 55527.7 55597.0 413803.0 414121.0 415195.0 415976.0 420592.0 30359.4 31612.8 31655.0 33114.2 33130.3 51983.7 52029.7 52186.0 52371.0 53530.0 43604.0 44158.0 44494.8 45399.8 46475.8 43639.0 43980.0 45122.5 45549.3 45662.8 44823.3 45275.5 45855.8 45865.3 46157.5 35343.1 38800.8 39333.0 39488.8 39721.0 29859.1 29907.7 30247.9 30313.5 30577.0 10:15:13 INFO - PROCESS | 6519 | 10:15:13 INFO - PROCESS | 6519 | [#2] jquery.html Cycles:180 Average:31220.76 Median:14080.95 stddev:88531.93 (628.7%) stddev-sans-first:88750.02 10:15:13 INFO - PROCESS | 6519 | Values: 388.8 407.6 414.3 414.7 432.4 14629.7 16444.0 20299.6 21141.0 21205.4 20226.0 20363.7 20660.6 20864.6 20891.1 5801.7 5871.9 5918.9 5919.5 5957.9 4363.3 5895.5 5966.9 5974.4 5976.5 4485.8 6465.8 6495.3 6622.5 6631.0 6447.8 6588.1 6611.0 6614.5 6633.6 5868.9 5874.3 5881.1 5882.8 5886.6 24748.3 24858.6 25368.5 25556.2 25557.5 2604.4 2691.2 2830.0 2964.4 3003.6 15516.8 15583.4 15804.2 15831.8 15891.3 1982.6 1997.5 2009.1 2027.5 2116.5 78659.0 79594.0 81240.0 86927.0 93010.0 11508.2 11529.5 11892.8 11969.1 11976.9 17134.6 17137.2 17142.7 17156.2 17165.1 9137.6 9316.0 9386.3 9396.8 9434.0 8907.0 8932.7 8980.6 9162.0 9185.6 5033.3 5110.1 5142.7 5143.3 5189.1 2024.2 2065.8 2081.7 2103.5 2112.7 934.8 998.7 1012.8 1045.6 1068.0 19153.2 19885.2 20305.9 20345.0 20573.9 1018.4 1035.7 1055.1 1062.9 1082.8 9917.6 11209.4 11374.4 11399.8 11419.0 521579.0 528136.0 538963.0 550477.0 550938.0 92501.0 95250.0 95256.0 96276.0 96757.5 989.6 1020.4 1028.8 1041.9 1048.0 17535.7 17638.8 17857.4 18086.9 18224.0 44514.0 44669.1 45210.8 45605.0 45848.5 56965.1 60634.4 60707.4 61701.1 62070.7 7932.4 8366.7 8409.0 8666.3 9007.5 10159.6 13606.2 13709.9 13729.8 14245.6 12218.8 12225.5 12245.3 12274.3 12426.6 11842.1 11933.8 11961.7 11996.8 12435.6 12058.2 12501.9 12513.3 12944.8 13081.7 8373.3 8489.7 8566.2 8645.1 8695.7 10839.7 11938.0 12198.7 12361.3 12457.9 10:15:13 INFO - PROCESS | 6519 | 10:15:13 INFO - PROCESS | 6519 | [#3] mootools.html Cycles:180 Average:6272.21 Median:4712.68 stddev:3596.69 (76.3%) stddev-sans-first:3588.41 10:15:13 INFO - PROCESS | 6519 | Values: 1434.5 1538.2 1563.5 1758.8 1855.3 8357.9 9352.9 9758.9 9807.3 9817.9 9602.6 9688.9 9702.7 9705.3 9709.7 2533.6 2552.9 2561.7 2565.7 2566.6 2518.1 2593.7 2598.9 2600.6 2602.8 2506.8 2523.4 2541.3 2548.1 2548.4 2408.6 2429.1 2432.5 2470.1 2480.5 2460.4 2492.2 2511.7 2515.8 2536.9 9214.9 9384.6 9485.7 9511.1 9514.8 5648.1 5903.1 5914.8 5922.2 5938.9 3688.7 3716.8 3725.2 3779.1 3788.0 1267.5 1283.6 1306.0 1312.3 1326.4 13319.9 13429.0 13489.6 13529.7 13564.9 4800.6 4927.1 4946.6 5069.2 5095.9 10592.9 10597.2 10598.7 10613.5 10623.9 5827.2 5831.6 5832.2 5838.8 5841.8 4625.1 4800.3 4894.6 5455.9 5485.7 4176.0 4195.1 4287.3 4305.4 4312.7 1252.1 1258.2 1271.3 1274.6 1284.4 1037.9 1042.3 1043.4 1050.3 1053.5 6247.7 6414.6 6510.7 6557.1 6606.7 484.5 510.5 516.0 523.5 529.7 4268.7 4332.7 4352.5 4359.5 4361.5 11690.0 11926.7 11989.8 12011.3 12017.8 11395.7 11860.4 11982.2 12001.6 12005.8 8606.0 9004.5 9042.7 9057.5 9057.5 6582.9 6613.4 6626.3 6713.2 6736.2 10698.8 11019.0 11088.2 11155.0 11230.9 10037.5 10350.7 11488.4 11530.9 11561.3 8131.9 8280.1 8335.5 8399.5 8567.2 9183.0 9337.8 9347.0 9354.7 9371.5 8235.3 8251.3 8309.6 8448.4 8607.6 8242.5 8248.1 8281.9 8361.0 8503.7 7371.6 7409.8 7469.5 7484.0 7507.1 5379.7 5426.9 5544.2 5561.1 5595.6 5431.7 5603.1 5607.8 5618.8 5668.1 10:15:13 INFO - PROCESS | 6519 | 10:15:13 INFO - PROCESS | 6519 | [#4] prototype.html Cycles:180 Average:4785.15 Median:5389.69 stddev:1393.24 (25.9%) stddev-sans-first:1356.58 10:15:13 INFO - PROCESS | 6519 | Values: 338.6 347.3 351.7 354.0 359.2 5561.2 5592.3 5622.2 5626.1 5687.3 5569.3 5580.3 5593.8 5604.6 5611.2 3820.8 4160.2 4187.6 4248.8 4360.4 4322.9 4327.0 4334.8 4337.5 4371.3 4319.0 4323.7 4325.6 4335.5 4336.4 4044.7 4085.6 4316.4 4319.3 4320.4 3862.0 3874.1 3879.7 3884.3 3892.4 5388.1 5391.2 5406.8 5410.9 5459.8 4803.0 4910.1 4942.5 5030.4 5035.7 5710.3 5731.0 5733.1 5733.9 5737.3 4247.7 4254.9 4257.4 4261.9 4277.9 5811.5 5938.0 5942.8 5999.3 6014.3 4348.4 4348.8 4361.2 4364.8 4377.8 4422.3 4431.6 4431.8 4433.3 4446.6 4861.3 4945.1 4973.9 4995.8 4997.5 5794.4 5799.9 5812.8 5814.4 5815.2 1519.5 1535.9 1537.1 1537.7 1540.0 937.7 941.6 943.9 955.7 992.4 4948.9 4951.3 4963.0 4972.8 4973.0 5235.9 5236.9 5256.6 5297.7 5300.7 4590.3 4939.3 4957.4 5019.2 5041.6 2767.2 2774.6 2794.2 2805.5 2811.9 5379.8 5401.3 5418.4 5421.7 5422.3 5104.9 5300.7 5347.8 5348.1 5777.3 5948.7 5991.5 6174.3 6182.7 6187.7 5984.4 5988.5 5990.4 6023.9 6024.8 5764.7 5793.4 5807.6 5889.8 5918.2 6144.3 6153.3 6155.4 6166.4 6175.8 4053.8 4343.3 4346.3 4346.6 4353.8 5797.2 5894.3 5899.4 5901.0 5905.9 5691.9 5741.9 5764.3 5768.2 5800.2 5588.4 5683.6 5728.2 5731.8 5751.4 5702.3 5703.7 5703.7 5711.0 5718.7 5556.6 5739.2 5754.3 5770.7 5794.2 5557.5 5592.8 5612.6 5625.2 5629.5 10:15:13 INFO - PROCESS | 6519 | 10:15:13 INFO - PROCESS | 6519 | [#5] yui.html Cycles:180 Average:21916.90 Median:4144.71 stddev:30354.20 (732.4%) stddev-sans-first:30398.01 10:15:13 INFO - PROCESS | 6519 | Values: 817.4 849.2 877.6 897.0 900.3 16243.0 17114.1 17284.0 17305.2 17373.6 15187.4 15280.9 15300.2 15776.1 15889.8 864.0 880.3 882.0 883.1 883.4 729.5 752.8 852.7 875.8 881.2 937.7 940.4 940.6 940.8 941.4 934.9 935.5 935.7 936.6 937.0 555.5 570.7 648.7 661.2 668.8 16286.8 16358.7 16361.8 16372.3 16399.1 14969.0 15418.0 15607.5 15789.8 15861.5 13437.8 13602.4 13771.4 13811.6 14062.6 4692.3 4704.9 4709.5 4773.0 4807.6 81006.6 81463.0 82095.6 84054.6 84913.0 11583.1 11622.9 11722.1 11839.5 11929.4 23588.2 23594.2 23605.8 23655.4 23661.9 9108.5 9343.9 9420.1 9421.8 9467.9 4927.1 5038.6 5100.1 5222.8 5230.8 3892.0 4112.5 4122.2 4167.3 4170.4 676.6 698.6 707.2 709.2 718.2 759.8 792.2 799.6 820.1 822.9 10574.2 10879.3 10895.8 11083.0 11130.2 780.4 780.6 822.1 826.3 836.3 5068.4 5228.6 5311.0 5313.5 5346.4 97683.0 99462.5 100988.5 115865.0 118382.0 100404.5 103378.0 103682.0 104150.0 105668.0 86587.6 89781.6 91831.5 93211.5 97736.0 10538.0 10783.7 10834.9 11112.6 11194.6 50456.7 51511.5 51688.0 51949.7 53507.7 75635.6 76108.6 80356.0 82120.6 83431.0 14022.4 14086.3 14105.9 14304.0 14384.0 16150.8 16666.2 16685.3 16871.9 17166.7 12374.5 14524.6 14852.0 14980.6 15100.4 14331.4 15583.8 15600.6 16010.9 16101.3 14636.9 14796.9 14828.7 15944.7 15951.0 12409.3 12611.5 12916.5 13082.5 13185.7 12861.8 12865.3 13114.5 13211.3 13355.5 10:15:13 INFO - PROCESS | 6519 | -------- Summary: end -------- 10:15:13 INFO - PROCESS | 6519 | 10:15:14 INFO - TEST-INFO | 6519: exit 0 10:15:14 INFO - TEST-OK | dromaeo_css | took 1113605ms 10:15:14 INFO - TEST-START | kraken 10:15:14 INFO - operating with platform_type : linux_ 10:15:14 INFO - Initialising browser for kraken test... 10:15:14 INFO - TEST-INFO | started process 6843 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6oL9FG/profile http://localhost:58704/getInfo.html) 10:15:18 INFO - PROCESS | 6843 | __metrics Screen width/height:1600/1200 10:15:18 INFO - PROCESS | 6843 | colorDepth:24 10:15:18 INFO - PROCESS | 6843 | Browser inner width/height: 1024/697 10:15:18 INFO - PROCESS | 6843 | __metrics 10:15:18 INFO - PROCESS | 6843 | 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:15:18 INFO - PROCESS | 6843 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:15:19 INFO - PROCESS | 6843 | console.error: PushService: 10:15:19 INFO - PROCESS | 6843 | stateChangeProcessEnqueue: Error transitioning state 10:15:19 INFO - PROCESS | 6843 | UnknownError 10:15:19 INFO - TEST-INFO | 6843: exit 0 10:15:19 INFO - Browser initialized. 10:15:19 INFO - Running cycle 1/1 for kraken test... 10:15:19 INFO - TEST-INFO | started process 6932 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6oL9FG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:15:31 INFO - PROCESS | 6932 | RSS: Main: 144379904 10:15:31 INFO - PROCESS | 6932 | 10:15:34 INFO - PROCESS | 6932 | Cycle 1(1): loaded http://localhost:58704/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58704/tests/kraken/driver.html?testName=audio-beat-detection) 10:15:35 INFO - PROCESS | 6932 | RSS: Main: 185929728 10:15:35 INFO - PROCESS | 6932 | 10:15:37 INFO - PROCESS | 6932 | Cycle 1(1): loaded http://localhost:58704/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58704/tests/kraken/driver.html?testName=audio-dft) 10:15:38 INFO - PROCESS | 6932 | RSS: Main: 175579136 10:15:38 INFO - PROCESS | 6932 | 10:15:41 INFO - PROCESS | 6932 | Cycle 1(1): loaded http://localhost:58704/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58704/tests/kraken/driver.html?testName=audio-fft) 10:15:41 INFO - PROCESS | 6932 | RSS: Main: 267698176 10:15:41 INFO - PROCESS | 6932 | 10:15:43 INFO - PROCESS | 6932 | Cycle 1(1): loaded http://localhost:58704/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58704/tests/kraken/driver.html?testName=audio-oscillator) 10:15:44 INFO - PROCESS | 6932 | RSS: Main: 173092864 10:15:44 INFO - PROCESS | 6932 | 10:15:46 INFO - PROCESS | 6932 | Cycle 1(1): loaded http://localhost:58704/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58704/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:15:47 INFO - PROCESS | 6932 | RSS: Main: 174444544 10:15:47 INFO - PROCESS | 6932 | 10:15:49 INFO - PROCESS | 6932 | Cycle 1(1): loaded http://localhost:58704/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58704/tests/kraken/driver.html?testName=imaging-darkroom) 10:15:50 INFO - PROCESS | 6932 | RSS: Main: 210690048 10:15:50 INFO - PROCESS | 6932 | 10:15:53 INFO - PROCESS | 6932 | Cycle 1(1): loaded http://localhost:58704/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58704/tests/kraken/driver.html?testName=imaging-desaturate) 10:15:54 INFO - PROCESS | 6932 | RSS: Main: 209846272 10:15:54 INFO - PROCESS | 6932 | 10:15:57 INFO - PROCESS | 6932 | Cycle 1(1): loaded http://localhost:58704/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58704/tests/kraken/driver.html?testName=json-parse-financial) 10:15:57 INFO - PROCESS | 6932 | RSS: Main: 209481728 10:15:57 INFO - PROCESS | 6932 | 10:15:59 INFO - PROCESS | 6932 | Cycle 1(1): loaded http://localhost:58704/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58704/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:15:59 INFO - PROCESS | 6932 | RSS: Main: 171446272 10:15:59 INFO - PROCESS | 6932 | 10:16:01 INFO - PROCESS | 6932 | Cycle 1(1): loaded http://localhost:58704/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58704/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:16:02 INFO - PROCESS | 6932 | RSS: Main: 179642368 10:16:02 INFO - PROCESS | 6932 | 10:16:04 INFO - PROCESS | 6932 | Cycle 1(1): loaded http://localhost:58704/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58704/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:16:04 INFO - PROCESS | 6932 | RSS: Main: 183824384 10:16:04 INFO - PROCESS | 6932 | 10:16:07 INFO - PROCESS | 6932 | Cycle 1(1): loaded http://localhost:58704/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58704/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:16:08 INFO - PROCESS | 6932 | RSS: Main: 183422976 10:16:08 INFO - PROCESS | 6932 | 10:16:10 INFO - PROCESS | 6932 | Cycle 1(1): loaded http://localhost:58704/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58704/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:16:11 INFO - PROCESS | 6932 | RSS: Main: 182693888 10:16:11 INFO - PROCESS | 6932 | 10:16:12 INFO - PROCESS | 6932 | Cycle 1(1): loaded http://localhost:58704/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58704/tests/kraken/driver.html?testName=ai-astar) 10:16:13 INFO - PROCESS | 6932 | RSS: Main: 235134976 10:16:13 INFO - PROCESS | 6932 | 10:16:13 INFO - PROCESS | 6932 | __start_tp_report 10:16:13 INFO - PROCESS | 6932 | _x_x_mozilla_page_load 10:16:13 INFO - PROCESS | 6932 | _x_x_mozilla_page_load_details 10:16:13 INFO - PROCESS | 6932 | |i|pagename|runs| 10:16:13 INFO - PROCESS | 6932 | |0;ai-astar;108;112;113;111;111;111;110;112;110;111 10:16:13 INFO - PROCESS | 6932 | |1;audio-beat-detection;145;119;119;154;119;121;117;117;155;116 10:16:13 INFO - PROCESS | 6932 | |2;audio-dft;162;160;162;162;161;162;160;161;161;161 10:16:13 INFO - PROCESS | 6932 | |3;audio-fft;74;75;76;111;76;76;75;74;96;76 10:16:13 INFO - PROCESS | 6932 | |4;audio-oscillator;82;82;130;82;82;81;112;82;82;118 10:16:13 INFO - PROCESS | 6932 | |5;imaging-gaussian-blur;117;117;117;117;117;118;117;117;117;116 10:16:13 INFO - PROCESS | 6932 | |6;imaging-darkroom;146;146;147;146;145;145;146;146;146;146 10:16:13 INFO - PROCESS | 6932 | |7;imaging-desaturate;95;95;95;95;94;95;95;94;94;96 10:16:13 INFO - PROCESS | 6932 | |8;json-parse-financial;49;48;49;49;49;49;50;49;49;50 10:16:13 INFO - PROCESS | 6932 | |9;json-stringify-tinderbox;59;59;59;59;60;59;58;58;59;61 10:16:13 INFO - PROCESS | 6932 | |10;stanford-crypto-aes;74;75;75;83;74;79;73;81;74;74 10:16:13 INFO - PROCESS | 6932 | |11;stanford-crypto-ccm;124;121;128;117;119;120;128;131;164;118 10:16:13 INFO - PROCESS | 6932 | |12;stanford-crypto-pbkdf2;155;157;157;159;157;155;155;158;155;154 10:16:13 INFO - PROCESS | 6932 | |13;stanford-crypto-sha256-iterative;64;62;66;62;63;63;62;68;61;61 10:16:13 INFO - PROCESS | 6932 | __end_tp_report 10:16:13 INFO - PROCESS | 6932 | __start_cc_report 10:16:13 INFO - PROCESS | 6932 | _x_x_mozilla_cycle_collect,1601 10:16:13 INFO - PROCESS | 6932 | __end_cc_report 10:16:13 INFO - PROCESS | 6932 | __startTimestamp1475169373225__endTimestamp 10:16:13 INFO - PROCESS | 6932 | 10:16:13 INFO - PROCESS | 6932 | ------- Summary: start ------- 10:16:13 INFO - PROCESS | 6932 | Number of tests: 14 10:16:13 INFO - PROCESS | 6932 | 10:16:13 INFO - PROCESS | 6932 | [#0] ai-astar Cycles:10 Average:110.90 Median:111.00 stddev:1.37 (1.2%) stddev-sans-first:0.97 10:16:13 INFO - PROCESS | 6932 | Values: 108.0 112.0 113.0 111.0 111.0 111.0 110.0 112.0 110.0 111.0 10:16:13 INFO - PROCESS | 6932 | 10:16:13 INFO - PROCESS | 6932 | [#1] audio-beat-detection Cycles:10 Average:128.20 Median:120.00 stddev:16.23 (13.5%) stddev-sans-first:16.04 10:16:13 INFO - PROCESS | 6932 | Values: 145.0 119.0 119.0 154.0 119.0 121.0 117.0 117.0 155.0 116.0 10:16:13 INFO - PROCESS | 6932 | 10:16:13 INFO - PROCESS | 6932 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:0.79 (0.5%) stddev-sans-first:0.78 10:16:13 INFO - PROCESS | 6932 | Values: 162.0 160.0 162.0 162.0 161.0 162.0 160.0 161.0 161.0 161.0 10:16:13 INFO - PROCESS | 6932 | 10:16:13 INFO - PROCESS | 6932 | [#3] audio-fft Cycles:10 Average:80.90 Median:76.00 stddev:12.45 (16.4%) stddev-sans-first:12.95 10:16:13 INFO - PROCESS | 6932 | Values: 74.0 75.0 76.0 111.0 76.0 76.0 75.0 74.0 96.0 76.0 10:16:13 INFO - PROCESS | 6932 | 10:16:13 INFO - PROCESS | 6932 | [#4] audio-oscillator Cycles:10 Average:93.30 Median:82.00 stddev:18.93 (23.1%) stddev-sans-first:19.63 10:16:13 INFO - PROCESS | 6932 | Values: 82.0 82.0 130.0 82.0 82.0 81.0 112.0 82.0 82.0 118.0 10:16:13 INFO - PROCESS | 6932 | 10:16:13 INFO - PROCESS | 6932 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 10:16:13 INFO - PROCESS | 6932 | Values: 117.0 117.0 117.0 117.0 117.0 118.0 117.0 117.0 117.0 116.0 10:16:13 INFO - PROCESS | 6932 | 10:16:13 INFO - PROCESS | 6932 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 10:16:13 INFO - PROCESS | 6932 | Values: 146.0 146.0 147.0 146.0 145.0 145.0 146.0 146.0 146.0 146.0 10:16:13 INFO - PROCESS | 6932 | 10:16:13 INFO - PROCESS | 6932 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 10:16:13 INFO - PROCESS | 6932 | Values: 95.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 94.0 96.0 10:16:13 INFO - PROCESS | 6932 | 10:16:13 INFO - PROCESS | 6932 | [#8] json-parse-financial Cycles:10 Average:49.10 Median:49.00 stddev:0.57 (1.2%) stddev-sans-first:0.60 10:16:13 INFO - PROCESS | 6932 | Values: 49.0 48.0 49.0 49.0 49.0 49.0 50.0 49.0 49.0 50.0 10:16:13 INFO - PROCESS | 6932 | 10:16:13 INFO - PROCESS | 6932 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.00 stddev:0.88 (1.5%) stddev-sans-first:0.93 10:16:13 INFO - PROCESS | 6932 | Values: 59.0 59.0 59.0 59.0 60.0 59.0 58.0 58.0 59.0 61.0 10:16:13 INFO - PROCESS | 6932 | 10:16:13 INFO - PROCESS | 6932 | [#10] stanford-crypto-aes Cycles:10 Average:76.20 Median:75.00 stddev:3.49 (4.7%) stddev-sans-first:3.61 10:16:13 INFO - PROCESS | 6932 | Values: 74.0 75.0 75.0 83.0 74.0 79.0 73.0 81.0 74.0 74.0 10:16:13 INFO - PROCESS | 6932 | 10:16:13 INFO - PROCESS | 6932 | [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:126.00 stddev:13.85 (11.0%) stddev-sans-first:14.65 10:16:13 INFO - PROCESS | 6932 | Values: 124.0 121.0 128.0 117.0 119.0 120.0 128.0 131.0 164.0 118.0 10:16:13 INFO - PROCESS | 6932 | 10:16:13 INFO - PROCESS | 6932 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.20 Median:157.00 stddev:1.62 (1.0%) stddev-sans-first:1.66 10:16:13 INFO - PROCESS | 6932 | Values: 155.0 157.0 157.0 159.0 157.0 155.0 155.0 158.0 155.0 154.0 10:16:13 INFO - PROCESS | 6932 | 10:16:13 INFO - PROCESS | 6932 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.00 stddev:2.25 (3.6%) stddev-sans-first:2.37 10:16:13 INFO - PROCESS | 6932 | Values: 64.0 62.0 66.0 62.0 63.0 63.0 62.0 68.0 61.0 61.0 10:16:13 INFO - PROCESS | 6932 | -------- Summary: end -------- 10:16:13 INFO - PROCESS | 6932 | 10:16:14 INFO - TEST-INFO | 6932: exit 0 10:16:14 INFO - TEST-OK | kraken | took 59927ms 10:16:14 INFO - SUITE-END | took 1173s 10:16:14 INFO - Completed test suite (00:19:33) 10:16:14 INFO - javascript benchmark 10:16:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3090.5982222311095, 3101.085179871938, 3116.6571677115526, 3144.7955882867595, 3164.493681011039, 5645.096784514479, 5753.942460575393, 5785.942140578593, 5792.507636850867, 5802.680852553108, 5739.196512488255, 5803.6227645203035, 5817.854553636162, 5818.767249310034, 5826.271716035496, 5163.122269214243, 5214.8696282592855, 5239.266502689627, 5268.631195816294, 5288.0, 5057.772400241998, 5291.603129765283, 5305.469453054702, 5306.310179676656, 5337.412884582693, 5032.069067222557, 5033.320501732274, 5034.84895453137, 5062.190049592066, 5066.442691303953, 5101.20931255656, 5126.410462796789, 5128.153854613985, 5170.767315470813, 5178.223266510016, 5055.4186268579215, 5075.0, 5145.176771716508, 5151.665141765773, 5188.299579556749, 5475.616706830523, 5484.232207490954, 5527.53015993637, 5584.1902924076185, 5591.888162236733, 6049.455549000611, 6061.090836374579, 6061.272647282354, 6063.363346848561, 6072.51419886408, 5824.3302020267365, 6068.726907289139, 6072.5445591580365, 6101.237345331833, 6106.145139680449, 5503.091989821714, 5619.325680918275, 5716.79991200306, 5726.3700992890745, 5804.2744656917885, 6297.653629050446, 6320.683965801783, 6326.335734747877, 6334.619922804554, 6335.556511044275, 6049.274087109575, 6061.424164704435, 6063.9090413643835, 6064.151018857364, 6068.544859135583, 5869.794557190563, 5904.4095590441475, 5909.96575599277, 5952.464278214895, 5954.4045595440975, 5877.764889404376, 5914.6746928918465, 5918.260217472816, 5924.7926322578505, 5945.4649081582, 5937.2578427696535, 5952.791652292236, 5968.1644569760265, 6022.427869352405, 6022.5784195105925, 4477.7984990675495, 4500.909981800346, 4539.954600453953, 4559.703619264737, 4592.586667199901, 4052.0477687743355, 4058.6144316289906, 4062.7968601569805, 4075.1238483726142, 4091.91816163681, 5706.343770466367, 5708.20085188074, 5714.114312281603, 5715.028445164332, 5748.856278593069, 5874.353821079678, 5894.056950887837, 6049.485793707584, 6084.30030546484, 6089.54328425361, 5811.157382179645, 5817.069268916953, 5818.476337129594, 5822.0684690449325, 5829.446202610745, 5274.736263186825, 5283.762230699628, 5284.207368894619, 5287.735613219401, 5292.259083728281, 5982.790602328898, 6020.548458865515, 6163.8150855474405, 6172.9074063889975, 6365.809025729235, 6131.713108457195, 6144.525313924715, 6147.44672979434, 6148.1699970503405, 6152.630842149485, 6245.687715614292, 6297.842553936188, 6346.428821406095, 6349.015902535114, 6350.618962862059, 5766.94233057664, 5802.47777700009, 6048.334683184758, 6073.058675905242, 6081.087836824512, 6010.519158467399, 6091.573589848668, 6091.969540152271, 6160.10678451631, 6171.47542458896, 6003.519718422428, 6005.159277701213, 6007.54943379257, 6020.0, 6035.6076855004285, 5713.143028545752, 5937.614055086406, 5988.580799344004, 5997.880042399215, 5998.160257564032, 5671.432856714296, 5853.09277062056, 5859.707014649336, 5864.706764661835, 5941.31674857397, 6011.72947217385, 6024.367441418588, 6029.909551356645, 6034.034554471264, 6034.306054803754, 5593.440655934374, 5619.8595035124445, 5718.828435146952, 5865.090910908816, 5888.205092312654], "value": 5576.630528097694, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.0150263559093, 595.4373117404053, 610.1579819848607, 617.7652492053022, 650.2099948562495, 34331.31337373254, 46976.29535556965, 48217.758911205485, 48432.99999999991, 51734.223986640136, 49443.25835112467, 49799.25301120486, 49933.0, 50062.74968625152, 50104.74947625257, 8410.453320534145, 9280.16478516935, 9440.433573985583, 9445.622175112987, 9451.858222126673, 8352.955880514935, 10871.347719136877, 11156.386398748065, 11161.82800805916, 11515.712107197303, 11258.324500529954, 11316.943415282913, 11374.886251137506, 11403.942980285086, 11407.201495895291, 11234.269772464846, 11275.703294121158, 11325.716857078556, 11355.829662555068, 11359.034482068993, 10934.125269978382, 11002.78969313375, 11006.944965275123, 11009.119270458345, 11020.283681560673, 46177.769111154565, 46556.0, 46571.76714116408, 46690.06619867618, 46788.06423871538, 3752.887413377603, 3812.942805857914, 3841.519810023747, 3843.4619153318276, 3873.12854607711, 35098.824505877565, 35189.0, 35431.822840885885, 35797.46303805445, 37036.25927481435, 4336.197803406349, 4376.102898905717, 4377.102693947733, 4445.422095127613, 4502.234620114588, 371570.0, 372546.0, 378735.0, 381131.0, 382006.0, 66469.6676516624, 66819.33180668228, 66864.33135668679, 66874.66562667156, 66943.66528167328, 40695.38956915648, 41338.79330603328, 41370.0, 43887.0, 43889.34165987513, 19997.800021999883, 20302.08640611176, 20309.390718278475, 20310.796892031187, 20322.89838550798, 13878.236696981561, 14274.714505710035, 15115.697686046218, 15316.0, 15519.767203491956, 20889.89555052215, 21126.366209013755, 21179.0, 21252.36242912715, 21310.0, 7381.778546643609, 7512.173660897297, 7664.0, 7675.692972281158, 7831.334336581341, 978.5596481583316, 1003.0721582536125, 1041.1774697988656, 1080.1682704317632, 1110.9722256943642, 32000.03999880007, 34315.48526772101, 34608.0, 34712.30575388478, 34753.478697819555, 1136.142212629476, 1149.454009345571, 1186.5313201285617, 1195.2708847602955, 1216.9878301217052, 17376.65246695059, 18285.63428731418, 18372.724409133873, 18505.0, 18629.813701862808, 581807.0, 584482.0, 586497.0, 586672.0, 587621.0, 64391.356086438536, 64856.67571662112, 64947.0, 64969.67515162394, 65051.0, 1326.316946772413, 1337.839459264914, 1356.7354365898555, 1430.8497607751244, 1483.9332230049674, 31167.376652467276, 35565.288694225455, 36336.4549531752, 36436.45345319875, 36531.086722832144, 54855.45144548663, 55376.0, 55457.44542554523, 55527.72236138793, 55597.0, 413803.0, 414121.0, 415195.0, 415976.0, 420592.0, 30359.392812144077, 31612.841935790173, 31655.0, 33114.172145695586, 33130.337393252485, 51983.740081300864, 52029.7398513005, 52186.0, 52371.0, 53530.0, 43604.0, 44158.0, 44494.76846783356, 45399.77300113478, 46475.76762116168, 43639.0, 43980.0, 45122.45724370013, 45549.31676024928, 45662.77168614136, 44823.327650084626, 45275.547244528454, 45855.77072114618, 45865.312020319056, 46157.538424615326, 35343.11642208965, 38800.80599596984, 39333.0, 39488.80255598704, 39721.0, 29859.104226873227, 29907.700922991364, 30247.88388779547, 30313.545296820124, 30577.0], "value": 21831.10712143799, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.7686826338328, 407.6066595735115, 414.33912908910287, 414.65376410697064, 432.403283468813, 14629.707405851877, 16444.0, 20299.594008119828, 21141.0, 21205.363839084814, 20226.0, 20363.694544581842, 20660.586788264227, 20864.582708345824, 20891.059902304394, 5801.738921748521, 5871.8532036699, 5918.940810591882, 5919.49684276837, 5957.85105372367, 4363.258246098171, 5895.469407753301, 5966.850828729295, 5974.372690867462, 5976.4919981801595, 4485.842995495158, 6465.773697920574, 6495.317991610884, 6622.53642245043, 6631.038499417582, 6447.83880402989, 6588.110605068302, 6611.0, 6614.50391220658, 6633.568818026861, 5868.9413105868825, 5874.295084589834, 5881.088431293157, 5882.794102206402, 5886.6173698709445, 24748.257552273462, 24858.627120593206, 25368.492630147295, 25556.23331300064, 25557.48885022308, 2604.414006848463, 2691.1657386210336, 2830.0236418435607, 2964.3626620276527, 3003.57949887016, 15516.767248491282, 15583.376664933388, 15804.209789510478, 15831.762523562156, 15891.28489217988, 1982.5539253667953, 1997.4507010572208, 2009.1059478532059, 2027.472857057161, 2116.5237821490196, 78659.0, 79594.0, 81240.0, 86927.0, 93009.99999999932, 11508.19442639024, 11529.48117334722, 11892.762144757056, 11969.102317326235, 11976.88023119775, 17134.571635709206, 17137.228824702917, 17142.657146856993, 17156.22796974139, 17165.055921924166, 9137.588808503633, 9315.953420232856, 9386.296027797942, 9396.812063758687, 9433.952830235805, 8907.0, 8932.732018039469, 8980.595873185723, 9162.0, 9185.63257469707, 5033.270175824485, 5110.105731496973, 5142.665726727751, 5143.331366922276, 5189.143791274473, 2024.210557882427, 2065.783092775267, 2081.689324849416, 2103.516191276015, 2112.6830975353514, 934.761635782871, 998.7403275148368, 1012.8328825743818, 1045.6026709850207, 1068.0, 19153.233870645017, 19885.204591816164, 20305.898470507553, 20345.0, 20573.89713051425, 1018.4398580780542, 1035.6582327831948, 1055.0820785916303, 1062.8565143705655, 1082.7617924056694, 9917.603295868084, 11209.383483908301, 11374.374409407563, 11399.829002564968, 11419.0, 521579.0, 528136.0, 538963.0, 550477.0, 550938.0, 92501.0, 95250.04749952412, 95256.0, 96276.0, 96757.51621241849, 989.5992123190119, 1020.3826684855555, 1028.84052971791, 1041.9218558608134, 1047.984280235797, 17535.736963945295, 17638.823611763717, 17857.374991875222, 18086.90956545209, 18223.99768012772, 44514.0, 44669.10661786681, 45210.77394613006, 45605.0, 45848.54151458442, 56965.14552281636, 60634.39365606287, 60707.39292607194, 61701.07448388188, 62070.68964655148, 7932.405069619917, 8366.656413411278, 8409.0, 8666.263367613572, 9007.504587247764, 10159.593616255266, 13606.183628982293, 13709.862901370858, 13729.773503226934, 14245.572632821031, 12218.75562488763, 12225.471816022997, 12245.326507041842, 12274.263544187377, 12426.62720118398, 11842.111841612086, 11933.820992684943, 11961.700957476132, 11996.820047699466, 12435.626931192079, 12058.216215945935, 12501.93656076784, 12513.311767852856, 12944.8058279124, 13081.672958176121, 8373.288270497076, 8489.702860399857, 8566.18621230982, 8645.092265312052, 8695.739127826175, 10839.67480975572, 11938.0, 12198.734347650237, 12361.320127393079, 12457.937710311391], "value": 9837.99917780254, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1434.476416108121, 1538.1693885301934, 1563.5153102538213, 1758.7713597232357, 1855.3320804510383, 8357.91642083579, 9352.859707104399, 9758.902410975888, 9807.264455165865, 9817.852732209023, 9602.615895364177, 9688.85466717998, 9702.66040688576, 9705.272104592148, 9709.660161894335, 2533.556627590169, 2552.885120169597, 2561.679790026247, 2565.730598287181, 2566.5765148750434, 2518.068314723555, 2593.688757349121, 2598.883050262743, 2600.5839065749483, 2602.778763805079, 2506.8245222834407, 2523.4196134888984, 2541.313845261778, 2548.120898290087, 2548.439343344461, 2408.5784987627185, 2429.0890915906534, 2432.5499782540287, 2470.1354525915876, 2480.503899220163, 2460.4341001569737, 2492.239866840613, 2511.660925775015, 2515.8113141514277, 2536.9492610147877, 9214.861777073349, 9384.62461501546, 9485.66800161991, 9511.143997040299, 9514.762130946712, 5648.124540696199, 5903.085021821625, 5914.763409463616, 5922.230110085704, 5938.851528711773, 3688.704903607705, 3716.758410703296, 3725.18046029873, 3779.0930176757656, 3787.9810600947094, 1267.4739982907083, 1283.5507572349695, 1305.9869401306055, 1312.3241530611742, 1326.4031185966353, 13319.933400332937, 13429.0, 13489.595312140651, 13529.661758456019, 13564.864351356458, 4800.639952003614, 4927.113119638428, 4946.604271658259, 5069.188929771292, 5095.898082038412, 10592.947035264928, 10597.15222782191, 10598.735031624272, 10613.469326533643, 10623.946880265548, 5827.155062515912, 5831.562632802472, 5832.154337621021, 5838.795642152504, 5841.795537156264, 4625.098105869323, 4800.255960326155, 4894.583960363409, 5455.936092461931, 5485.725713714299, 4175.97912010438, 4195.139996300751, 4287.314029755286, 4305.397244385726, 4312.676549258818, 1252.0546987024738, 1258.213616489694, 1271.2817258249245, 1274.5666473398903, 1284.3642397013539, 1037.86507753993, 1042.332906939544, 1043.3739756146251, 1050.3330385205297, 1053.536443964653, 6247.656753797955, 6414.61512309263, 6510.739570417225, 6557.082008518812, 6606.669666516655, 484.4549881383444, 510.47166183000417, 516.0, 523.5288240583429, 529.6821906855932, 4268.722533035343, 4332.696711230246, 4352.455943007124, 4359.542248063971, 4361.498427680794, 11690.0, 11926.701832454084, 11989.820152697717, 12011.279323240633, 12017.819732704016, 11395.715107122389, 11860.406979650983, 11982.16124871268, 12001.579944701894, 12005.819912701316, 8606.0, 9004.459732416077, 9042.728718138467, 9057.456552606865, 9057.501837399006, 6582.868342633216, 6613.43785778195, 6626.304238054935, 6713.160854893138, 6736.157980252468, 10698.839517407401, 11019.0, 11088.223824332543, 11155.0, 11230.887691123311, 10037.548310325907, 10350.741231469274, 11488.368139752394, 11530.884691152982, 11561.306321620394, 8131.918680813353, 8280.089190188954, 8335.541545215072, 8399.496030238206, 8567.228949394335, 9183.0, 9337.766555836159, 9346.971833098218, 9354.71935841926, 9371.484568348806, 8235.29999950011, 8251.257386835214, 8309.626066826886, 8448.366372521963, 8607.612657430305, 8242.464239824392, 8248.13394593559, 8281.917180828115, 8361.0, 8503.659853605786, 7371.557706537625, 7409.777706668809, 7469.514481558681, 7484.0, 7507.136679281734, 5379.6620595910435, 5426.864328391822, 5544.168374743659, 5561.110222364402, 5595.5523558116065, 5431.701256430934, 5603.131514615241, 5607.831765047055, 5618.831435056954, 5668.093105103164], "value": 4930.83185892967, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.6241272187871, 347.2811280649058, 351.725653989888, 354.04231553647384, 359.1524003352089, 5561.249231353766, 5592.315688469537, 5622.212890195366, 5626.099824028156, 5687.345955215141, 5569.275994120769, 5580.330360356753, 5593.804216852411, 5604.607677462579, 5611.2144299798065, 3820.789856557888, 4160.209560183571, 4187.623113919746, 4248.830046798124, 4360.433143691315, 4322.891927701817, 4327.0480494291205, 4334.84828031019, 4337.54455782143, 4371.322445021026, 4319.0, 4323.697341186118, 4325.589069038452, 4335.544767799383, 4336.371226172203, 4044.705694177864, 4085.571015043437, 4316.3741257517795, 4319.257271632505, 4320.351947207912, 3861.9765762073075, 3874.1283211277237, 3879.650831425185, 3884.3396622574232, 3892.3966785148345, 5388.137897936351, 5391.245225668409, 5406.783728650849, 5410.864728381783, 5459.781608735646, 4802.967362017148, 4910.116179087763, 4942.479335538865, 5030.37120359955, 5035.672681275705, 5710.2862142232225, 5731.0, 5733.082706766939, 5733.942660573382, 5737.254156959611, 4247.7451352918615, 4254.87235382939, 4257.40396344512, 4261.914761704779, 4277.893052673677, 5811.476967072941, 5937.970310148422, 5942.792002279943, 5999.310079340888, 6014.278286605591, 4348.434703488526, 4348.847790327387, 4361.236783562864, 4364.803583838735, 4377.759223242689, 4422.314541246112, 4431.604044725901, 4431.8448854289945, 4433.268510695763, 4446.577575130357, 4861.270809378621, 4945.134601444733, 4973.875653108629, 4995.750212489361, 4997.5002499749735, 5794.391588883191, 5799.9130013049835, 5812.767489300465, 5814.418558144152, 5815.156802263648, 1519.468186134844, 1535.8540938610813, 1537.1392020468572, 1537.6847746212, 1539.9846001540066, 937.6624415210518, 941.5782168924973, 943.8867335919733, 955.7180631713661, 992.399598243067, 4948.925766113512, 4951.306817045616, 4963.0, 4972.825951091694, 4973.0, 5235.921461178085, 5236.884053987871, 5256.605754568346, 5297.65565238266, 5300.655457395257, 4590.311453281967, 4939.2838038484215, 4957.429895562056, 5019.171836646912, 5041.571466425395, 2767.2113447667316, 2774.557230240264, 2794.2455537005303, 2805.452936677328, 2811.9297017574313, 5379.78480860769, 5401.270828438189, 5418.4039755626845, 5421.674699518042, 5422.267993820863, 5104.948950510522, 5300.70846103468, 5347.791325204846, 5348.085293515043, 5777.306723193244, 5948.702564871739, 5991.460768530853, 6174.320824709368, 6182.6599537025895, 6187.659678717714, 5984.371640977634, 5988.461038506555, 5990.400959904149, 6023.939760602513, 6024.849378765566, 5764.711764411679, 5793.391693872083, 5807.593468457168, 5889.764409423575, 5918.224532640104, 6144.293406258159, 6153.323134455296, 6155.384461553988, 6166.383361663798, 6175.84560385994, 4053.7973101345406, 4343.34849772524, 4346.304591265382, 4346.6088052075465, 4353.804078816397, 5797.188393624981, 5894.2611929480045, 5899.410058994066, 5900.970495147497, 5905.932923476748, 5691.922448306421, 5741.913871292018, 5764.337101233412, 5768.192453056491, 5800.187973683604, 5588.38527761954, 5683.60214784961, 5728.198052272768, 5731.799387021434, 5751.367349591458, 5702.258706368146, 5703.686297253525, 5703.714814259188, 5711.001149597608, 5718.685472299064, 5556.605932751412, 5739.196512488172, 5754.3382511010095, 5770.682612456355, 5794.159846822272, 5557.527610153021, 5592.832215033555, 5612.607117501736, 5625.184348269396, 5629.509703983556], "value": 4344.05440126594, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [817.407379649754, 849.1593322610613, 877.5831480046977, 897.0446474504652, 900.3427497926517, 16243.0, 17114.144292785357, 17284.0, 17305.22126504307, 17373.565660858483, 15187.392504299842, 15280.923595382008, 15300.234988250599, 15776.132312722795, 15889.841101588981, 863.956802159891, 880.3089574683875, 882.0385779500343, 883.0904168706243, 883.4301875290433, 729.5075823818928, 752.7854561449988, 852.7015544559416, 875.8467268228067, 881.22012019363, 937.7186843946789, 940.4028441939383, 940.5767404667893, 940.7695114696875, 941.4163218804316, 934.8784657994452, 935.4808081514757, 935.7005758157393, 936.6347124621335, 937.0114529171722, 555.4695266020981, 570.7203470299564, 648.6626953983949, 661.1603263854926, 668.8428219368444, 16286.755698664529, 16358.672826543403, 16361.754573681284, 16372.263248153862, 16399.098049607266, 14969.0, 15418.0, 15607.45373911919, 15789.842101578952, 15861.52415427539, 13437.798433023512, 13602.387892544859, 13771.380287887068, 13811.585652430444, 14062.578122656336, 4692.296155576691, 4704.858854234379, 4709.481956984763, 4773.0, 4807.591354734852, 81006.59496702538, 81463.0, 82095.5895220526, 84054.57972710159, 84912.99999999939, 11583.131265154978, 11622.88377116218, 11722.120840936965, 11839.467223974942, 11929.403529823474, 23588.17441389543, 23594.17420390278, 23605.7639423607, 23655.408614784767, 23661.881690591435, 9108.544572771334, 9343.859842102374, 9420.105090016437, 9421.76445588852, 9467.857982130274, 4927.113119638428, 5038.571721403649, 5100.1074811907765, 5222.764975576109, 5230.8169214077325, 3891.96108038916, 4112.485939257593, 4122.154958233555, 4167.270727622715, 4170.416141740159, 676.5669971218424, 698.6402002968475, 707.1761397971409, 709.1667290933133, 718.2063819479505, 759.7720683794839, 792.2473650032492, 799.5522507395877, 820.1019883227856, 822.9094799572042, 10574.206934479946, 10879.259318509, 10895.83656245157, 11083.0, 11130.165237607212, 780.3756994404454, 780.605794073989, 822.0751654388813, 826.3348004856028, 836.2640876029177, 5068.4424713281505, 5228.581713462914, 5310.981826905751, 5313.54834839043, 5346.385165705916, 97683.0, 99462.50268748609, 100988.49505752571, 115865.0, 118382.0, 100404.49797750964, 103378.0, 103682.0, 104150.0, 105668.0, 86587.56706216554, 89781.55109224543, 91831.54084229536, 93211.53394232986, 97736.0226397727, 10538.0, 10783.719613355868, 10834.891651083388, 11112.611058612909, 11194.608188713355, 50456.74771626118, 51511.48488515067, 51688.0, 51949.7402512985, 53507.732461337444, 75635.62182189054, 76108.61945690236, 80356.0, 82120.58939705264, 83431.0, 14022.439102436196, 14086.295685215493, 14105.858941410455, 14304.0, 14384.0, 16150.757738634165, 16666.16669166561, 16685.332586696397, 16871.91564042172, 17166.742498862277, 12374.505019799108, 14524.564263072125, 14852.034617749812, 14980.625484362978, 15100.39598416051, 14331.426742930165, 15583.76624350613, 15600.609984750472, 16010.9199454002, 16101.275442604876, 14636.853631463975, 14796.852031479548, 14828.703425931637, 15944.681106378039, 15950.963187392783, 12409.254765685157, 12611.49554017829, 12916.547920822726, 13082.542111026069, 13185.670358241121, 12861.807072894102, 12865.2924089176, 13114.540991065267, 13211.273379964194, 13355.53255636048], "value": 8270.898460963514, "unit": "score"}], "name": "dromaeo_css", "value": 7723.152374390604, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 112.0, 113.0, 111.0, 111.0, 111.0, 110.0, 112.0, 110.0, 111.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 119.0, 119.0, 154.0, 119.0, 121.0, 117.0, 117.0, 155.0, 116.0], "value": 128.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 162.0, 162.0, 161.0, 162.0, 160.0, 161.0, 161.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 111.0, 76.0, 76.0, 75.0, 74.0, 96.0, 76.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 130.0, 82.0, 82.0, 81.0, 112.0, 82.0, 82.0, 118.0], "value": 93.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 116.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 94.0, 96.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 49.0, 49.0, 49.0, 49.0, 50.0, 49.0, 49.0, 50.0], "value": 49.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 59.0, 60.0, 59.0, 58.0, 58.0, 59.0, 61.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 83.0, 74.0, 79.0, 73.0, 81.0, 74.0, 74.0], "value": 76.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 121.0, 128.0, 117.0, 119.0, 120.0, 128.0, 131.0, 164.0, 118.0], "value": 127.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 157.0, 157.0, 159.0, 157.0, 155.0, 155.0, 158.0, 155.0, 154.0], "value": 156.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 66.0, 62.0, 63.0, 63.0, 62.0, 68.0, 61.0, 61.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1463.0, "alertThreshold": 2.0}]} 10:16:14 INFO - Return code: 0 10:16:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:16:14 INFO - # TBPL SUCCESS # 10:16:14 INFO - Running post-action listener: _resource_record_post_action 10:16:14 INFO - [mozharness: 2016-09-29 17:16:14.570993Z] Finished run-tests step (success) 10:16:14 INFO - Running post-run listener: _resource_record_post_run 10:16:14 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 1994752; Write bytes: 368152576; Read time: 52; Write time: 282612 10:16:14 INFO - TinderboxPrint: CPU usage
13.0% 10:16:14 INFO - TinderboxPrint: I/O read bytes / time
1,994,752 / 52 10:16:14 INFO - TinderboxPrint: I/O write bytes / time
368,152,576 / 282,612 10:16:14 INFO - TinderboxPrint: CPU idle
8,264.5 (87.0%) 10:16:14 INFO - TinderboxPrint: CPU user
1,188.2 (12.5%) 10:16:14 INFO - TinderboxPrint: Swap in / out
0 / 0 10:16:14 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 118980608; Read time: 0; Write time: 77880 10:16:14 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 262144; Write bytes: 249171968; Read time: 8; Write time: 204732 10:16:15 INFO - Running post-run listener: _upload_blobber_files 10:16:15 INFO - Blob upload gear active. 10:16:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:16:15 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:16:15 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:16:15 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:16:15 INFO - (blobuploader) - INFO - Open directory for files ... 10:16:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:16:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:16:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:16:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:16:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:16:16 INFO - (blobuploader) - INFO - Done attempting. 10:16:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:16:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:16:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:16:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:16:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:16:17 INFO - (blobuploader) - INFO - Done attempting. 10:16:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:16:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:16:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:16:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:16:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:16:21 INFO - (blobuploader) - INFO - Done attempting. 10:16:21 INFO - (blobuploader) - INFO - Iteration through files over. 10:16:21 INFO - Return code: 0 10:16:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:16:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:16:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c24515ba57b0636276c678ed3869e690508572b96a4c28fa14fb000af60dbdc9e602e64304b3a3a0906b434fd5417403d73774297b0ad972b3ce4a35723e9d19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1fa3509055b9ca48947454b06f601c60f3685f9d29315e1d8760439fa0232b0836a8214e1f7a8fcec5d05714e6bd85a2f842c1556f22e0385360e65ffae6221", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d96dd9b08a2f4682f3383e7ad75a2ef1a88298c4508f2e82dcdc5cc5fea052f62bbc233a1aaf43e4f78cb2f3cae727dc53537aadffe5ffe26ce3462f9c2f9563"} 10:16:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:16:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:16:21 INFO - Contents: 10:16:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c24515ba57b0636276c678ed3869e690508572b96a4c28fa14fb000af60dbdc9e602e64304b3a3a0906b434fd5417403d73774297b0ad972b3ce4a35723e9d19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1fa3509055b9ca48947454b06f601c60f3685f9d29315e1d8760439fa0232b0836a8214e1f7a8fcec5d05714e6bd85a2f842c1556f22e0385360e65ffae6221", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d96dd9b08a2f4682f3383e7ad75a2ef1a88298c4508f2e82dcdc5cc5fea052f62bbc233a1aaf43e4f78cb2f3cae727dc53537aadffe5ffe26ce3462f9c2f9563"} 10:16:21 INFO - Running post-run listener: copy_logs_to_upload_dir 10:16:21 INFO - Copying logs to upload dir... 10:16:21 INFO - mkdir: /builds/slave/test/build/upload/logs 10:16:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.694131 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-09-29 10:16:21.319758) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 10:16:21.323724) ========= 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=337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117 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-Aurora/sha512/c24515ba57b0636276c678ed3869e690508572b96a4c28fa14fb000af60dbdc9e602e64304b3a3a0906b434fd5417403d73774297b0ad972b3ce4a35723e9d19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1fa3509055b9ca48947454b06f601c60f3685f9d29315e1d8760439fa0232b0836a8214e1f7a8fcec5d05714e6bd85a2f842c1556f22e0385360e65ffae6221", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d96dd9b08a2f4682f3383e7ad75a2ef1a88298c4508f2e82dcdc5cc5fea052f62bbc233a1aaf43e4f78cb2f3cae727dc53537aadffe5ffe26ce3462f9c2f9563"} build_url:https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011190 build_url: 'https://queue.taskcluster.net/v1/task/SM6EKkRNTn6uialJQfnz8A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c24515ba57b0636276c678ed3869e690508572b96a4c28fa14fb000af60dbdc9e602e64304b3a3a0906b434fd5417403d73774297b0ad972b3ce4a35723e9d19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e1fa3509055b9ca48947454b06f601c60f3685f9d29315e1d8760439fa0232b0836a8214e1f7a8fcec5d05714e6bd85a2f842c1556f22e0385360e65ffae6221", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d96dd9b08a2f4682f3383e7ad75a2ef1a88298c4508f2e82dcdc5cc5fea052f62bbc233a1aaf43e4f78cb2f3cae727dc53537aadffe5ffe26ce3462f9c2f9563"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 10:16:21.352438) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 10:16:21.352784) ========= 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=337fe3f4b1febe37b85fccc40000019e-1475168146.428272-1713585117 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004406 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 10:16:21.438132) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 10:16:21.438473) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 10:16:21.438783) ========= ========= Total master_lag: 0.18 =========