builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-032 starttime: 1486255129.29 results: success (0) revision: 45d7372ceb6695e42915f11a4d30fce43cb59841 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-04 16:38:49.294716) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-04 16:38:49.295251) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-04 16:38:49.295532) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-04 16:38:49.312702) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 16:38:49.313020) ========= 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=745a4373a2a16df560f216e000000264-1486245375.767225-598311644 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-04 16:38:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.8M=0s 2017-02-04 16:38:50 (42.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.107726 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 16:38:49.435879) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 16:38:49.436182) ========= 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=745a4373a2a16df560f216e000000264-1486245375.767225-598311644 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016894 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 16:38:49.485087) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 16:38:49.485408) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 45d7372ceb6695e42915f11a4d30fce43cb59841 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 45d7372ceb6695e42915f11a4d30fce43cb59841 --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=745a4373a2a16df560f216e000000264-1486245375.767225-598311644 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-04 16:38:50,240 truncating revision to first 12 chars 2017-02-04 16:38:50,240 Setting DEBUG logging. 2017-02-04 16:38:50,240 attempt 1/10 2017-02-04 16:38:50,240 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/45d7372ceb66?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-04 16:38:50,513 unpacking tar archive at: mozilla-aurora-45d7372ceb66/testing/mozharness/ program finished with exit code 0 elapsedTime=0.541416 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 16:38:50.039808) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-04 16:38:50.040147) ========= script_repo_revision: 45d7372ceb6695e42915f11a4d30fce43cb59841 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-04 16:38:50.040526) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-04 16:38:50.040803) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-04 16:38:50.048998) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-02-04 16:38:50.049324) ========= /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=745a4373a2a16df560f216e000000264-1486245375.767225-598311644 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:38:50 INFO - MultiFileLogger online at 20170204 16:38:50 in /builds/slave/test 16:38:50 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 16:38:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:38:50 INFO - {'append_to_log': False, 16:38:50 INFO - 'base_work_dir': '/builds/slave/test', 16:38:50 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:38:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:38:50 INFO - 'branch': 'Mozilla-Aurora', 16:38:50 INFO - 'buildbot_json_path': 'buildprops.json', 16:38:50 INFO - 'config_files': ('talos/linux_config.py',), 16:38:50 INFO - 'default_actions': ('clobber', 16:38:50 INFO - 'read-buildbot-config', 16:38:50 INFO - 'download-and-extract', 16:38:50 INFO - 'populate-webroot', 16:38:50 INFO - 'create-virtualenv', 16:38:50 INFO - 'install', 16:38:50 INFO - 'run-tests'), 16:38:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:38:50 INFO - 'download_minidump_stackwalk': True, 16:38:50 INFO - 'download_symbols': 'ondemand', 16:38:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:38:50 INFO - 'tooltool.py': '/tools/tooltool.py', 16:38:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:38:50 INFO - '/tools/misc-python/virtualenv.py')}, 16:38:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:38:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:38:50 INFO - 'installer_path': 'installer.exe', 16:38:50 INFO - 'log_level': 'info', 16:38:50 INFO - 'log_name': 'talos', 16:38:50 INFO - 'log_to_console': True, 16:38:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:38:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:38:50 INFO - 'opt_config_files': (), 16:38:50 INFO - 'pip_index': False, 16:38:50 INFO - 'sps_profile': False, 16:38:50 INFO - 'sps_profile_interval': 0, 16:38:50 INFO - 'suite': 'dromaeojs', 16:38:50 INFO - 'system_bits': '32', 16:38:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:38:50 INFO - 'title': 'talos-linux64-ix-032', 16:38:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:38:50 INFO - 'use_talos_json': True, 16:38:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:38:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:38:50 INFO - 'work_dir': 'build'} 16:38:50 INFO - [mozharness: 2017-02-05 00:38:50.897717Z] Running clobber step. 16:38:50 INFO - Running pre-action listener: _resource_record_pre_action 16:38:50 INFO - Running main action method: clobber 16:38:50 INFO - rmtree: /builds/slave/test/build 16:38:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:38:51 INFO - Running post-action listener: _resource_record_post_action 16:38:51 INFO - [mozharness: 2017-02-05 00:38:51.384577Z] Finished clobber step (success) 16:38:51 INFO - [mozharness: 2017-02-05 00:38:51.384679Z] Running read-buildbot-config step. 16:38:51 INFO - Running pre-action listener: _resource_record_pre_action 16:38:51 INFO - Running main action method: read_buildbot_config 16:38:51 INFO - Using buildbot properties: 16:38:51 INFO - { 16:38:51 INFO - "project": "", 16:38:51 INFO - "product": "firefox", 16:38:51 INFO - "who": "philringnalda@gmail.com", 16:38:51 INFO - "installer_path": "public/build/target.tar.bz2", 16:38:51 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 16:38:51 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 16:38:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 16:38:51 INFO - "stage_platform": "linux64-pgo", 16:38:51 INFO - "basedir": "/builds/slave/test", 16:38:51 INFO - "buildnumber": 1040, 16:38:51 INFO - "platform": "ubuntu64_hw", 16:38:51 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:38:51 INFO - "slavebuilddir": "test", 16:38:51 INFO - "taskId": "MCQz6vD-TeiTqLh2fncvww", 16:38:51 INFO - "branch": "mozilla-aurora", 16:38:51 INFO - "script_repo_revision": "production", 16:38:51 INFO - "revision": "45d7372ceb6695e42915f11a4d30fce43cb59841", 16:38:51 INFO - "slavename": "talos-linux64-ix-032", 16:38:51 INFO - "repo_path": "releases/mozilla-aurora" 16:38:51 INFO - } 16:38:51 INFO - Finding installer, test and symbols from parent task. 16:38:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MCQz6vD-TeiTqLh2fncvww'}, attempt #1 16:38:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MCQz6vD-TeiTqLh2fncvww'}, attempt #1 16:38:52 INFO - Task dependencies: aKgd_HSBQauTGNYTd51wCA 16:38:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA'}, attempt #1 16:38:52 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.tar.bz2 16:38:52 INFO - Set test_url: https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/test_packages.json 16:38:52 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.crashreporter-symbols.zip 16:38:52 INFO - Running post-action listener: _resource_record_post_action 16:38:52 INFO - [mozharness: 2017-02-05 00:38:52.759749Z] Finished read-buildbot-config step (success) 16:38:52 INFO - [mozharness: 2017-02-05 00:38:52.759965Z] Running download-and-extract step. 16:38:52 INFO - Running pre-action listener: _resource_record_pre_action 16:38:52 INFO - Running main action method: download_and_extract 16:38:52 INFO - mkdir: /builds/slave/test/build/tests 16:38:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:38:52 INFO - trying https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.test_packages.json 16:38:52 INFO - Downloading https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:38:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:38:53 INFO - Downloaded 896 bytes. 16:38:53 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:38:53 INFO - Using the following test package requirements: 16:38:53 INFO - {u'common': [u'target.common.tests.zip'], 16:38:53 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:38:53 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:38:53 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:38:53 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:38:53 INFO - u'mozbase': [u'target.common.tests.zip'], 16:38:53 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:38:53 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:38:53 INFO - u'web-platform': [u'target.common.tests.zip', 16:38:53 INFO - u'target.web-platform.tests.zip'], 16:38:53 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:38:53 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:38:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.common.tests.zip 16:38:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:38:53 INFO - Fetch https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.common.tests.zip into memory 16:38:54 INFO - Expected file size: 21855124 16:38:54 INFO - Obtained file size: 21855124 16:38:58 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:38:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.common.tests.zip 16:38:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:38:58 INFO - Fetch https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.common.tests.zip into memory 16:38:59 INFO - Expected file size: 21855124 16:38:59 INFO - Obtained file size: 21855124 16:39:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.talos.tests.zip 16:39:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:39:04 INFO - Fetch https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.talos.tests.zip into memory 16:39:05 INFO - Expected file size: 14403485 16:39:05 INFO - Obtained file size: 14403485 16:39: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')]} 16:39:05 INFO - trying https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.tar.bz2 16:39:05 INFO - Downloading https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:39:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:39:08 INFO - Downloaded 66140991 bytes. 16:39:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.tar.bz2 16:39:08 INFO - mkdir: /builds/slave/test/properties 16:39:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:39:08 INFO - Writing to file /builds/slave/test/properties/build_url 16:39:08 INFO - Contents: 16:39:08 INFO - build_url:https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.tar.bz2 16:39:08 INFO - Running post-action listener: _resource_record_post_action 16:39:08 INFO - Running post-action listener: set_extra_try_arguments 16:39:08 INFO - [mozharness: 2017-02-05 00:39:08.299126Z] Finished download-and-extract step (success) 16:39:08 INFO - [mozharness: 2017-02-05 00:39:08.299299Z] Running populate-webroot step. 16:39:08 INFO - Running pre-action listener: _resource_record_pre_action 16:39:08 INFO - Running main action method: populate_webroot 16:39:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:39:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:39:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:39:08 INFO - '--tppagecycles', 16:39:08 INFO - '7'], 16:39:08 INFO - 'tests': ['tsvgm']}}, 16:39:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:39:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:39:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:39:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:39:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:39:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:39:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:39:08 INFO - 'talos_options': ['--disable-e10s'], 16:39:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:39:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:39:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:39:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:39:08 INFO - 'talos_options': ['--disable-e10s'], 16:39:08 INFO - 'tests': ['damp', 'tps']}, 16:39:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:39:08 INFO - 'tests': ['damp', 'tps']}, 16:39:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:39:08 INFO - 'tests': ['dromaeo_dom']}, 16:39:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:39:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:39:08 INFO - 'tests': ['basic_compositor_video']}, 16:39:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:39:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:39:08 INFO - 'tests': ['a11yr', 16:39:08 INFO - 'ts_paint', 16:39:08 INFO - 'tpaint', 16:39:08 INFO - 'sessionrestore', 16:39:08 INFO - 'sessionrestore_no_auto_restore', 16:39:08 INFO - 'tabpaint']}, 16:39:08 INFO - 'other-e10s': {'tests': ['a11yr', 16:39:08 INFO - 'ts_paint', 16:39:08 INFO - 'tpaint', 16:39:08 INFO - 'sessionrestore', 16:39:08 INFO - 'sessionrestore_no_auto_restore', 16:39:08 INFO - 'tabpaint']}, 16:39:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:39:08 INFO - 'ts_paint', 16:39:08 INFO - 'tpaint', 16:39:08 INFO - 'sessionrestore', 16:39:08 INFO - 'sessionrestore_no_auto_restore', 16:39:08 INFO - 'tabpaint']}, 16:39:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:39:08 INFO - 'ts_paint', 16:39:08 INFO - 'tpaint', 16:39:08 INFO - 'sessionrestore', 16:39:08 INFO - 'sessionrestore_no_auto_restore', 16:39:08 INFO - 'tabpaint']}, 16:39:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:39:08 INFO - 'tests': ['a11yr', 16:39:08 INFO - 'ts_paint', 16:39:08 INFO - 'tpaint', 16:39:08 INFO - 'sessionrestore', 16:39:08 INFO - 'sessionrestore_no_auto_restore', 16:39:08 INFO - 'tabpaint']}, 16:39:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:39:08 INFO - 'tests': ['a11yr', 16:39:08 INFO - 'ts_paint', 16:39:08 INFO - 'tpaint', 16:39:08 INFO - 'sessionrestore', 16:39:08 INFO - 'sessionrestore_no_auto_restore', 16:39:08 INFO - 'tabpaint']}, 16:39:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:39:08 INFO - 'tests': ['tsvgx', 16:39:08 INFO - 'tsvgr_opacity', 16:39:08 INFO - 'tart', 16:39:08 INFO - 'tscrollx', 16:39:08 INFO - 'cart', 16:39:08 INFO - 'tsvg_static']}, 16:39:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:39:08 INFO - 'tsvgr_opacity', 16:39:08 INFO - 'tart', 16:39:08 INFO - 'tscrollx', 16:39:08 INFO - 'cart', 16:39:08 INFO - 'tsvg_static']}, 16:39:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:39:08 INFO - 'talos_options': ['--disable-e10s'], 16:39:08 INFO - 'tests': ['tp5o']}, 16:39:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:39:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:39:08 INFO - 'talos_options': ['--disable-e10s', 16:39:08 INFO - '--xperf_path', 16:39:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:39:08 INFO - 'tests': ['tp5n']}, 16:39:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:39:08 INFO - 'talos_options': ['--xperf_path', 16:39:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:39:08 INFO - 'tests': ['tp5n']}}, 16:39:08 INFO - 'talos.zip': {'path': '', 16:39:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:39:08 INFO - Running post-action listener: _resource_record_post_action 16:39:08 INFO - [mozharness: 2017-02-05 00:39:08.315408Z] Finished populate-webroot step (success) 16:39:08 INFO - [mozharness: 2017-02-05 00:39:08.315499Z] Running create-virtualenv step. 16:39:08 INFO - Running pre-action listener: _resource_record_pre_action 16:39:08 INFO - Running main action method: create_virtualenv 16:39:08 INFO - Creating virtualenv /builds/slave/test/build/venv 16:39:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:39:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:39:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:39:08 INFO - Using real prefix '/usr' 16:39:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:39:08 INFO - Installing distribute.............................................................................................................................................................................................done. 16:39:10 INFO - Installing pip.................done. 16:39:10 INFO - Return code: 0 16:39:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:39:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:39:11 INFO - Reading from file tmpfile_stdout 16:39:11 INFO - Output received: 16:39:11 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 16:39:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:11 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 0x15d31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1945630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce850>, '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 0x19436d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1943b60>, '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': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:39:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:39:11 INFO - Using env: {'DISPLAY': ':0', 16:39:11 INFO - 'HOME': '/home/cltbld', 16:39:11 INFO - 'LANG': 'en_US.UTF-8', 16:39:11 INFO - 'LANGUAGE': 'en_US:en', 16:39:11 INFO - 'LOGNAME': 'cltbld', 16:39:11 INFO - 'MAIL': '/var/mail/cltbld', 16:39:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:11 INFO - 'MOZ_NO_REMOTE': '1', 16:39:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:11 INFO - 'NO_EM_RESTART': '1', 16:39:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:11 INFO - 'PWD': '/builds/slave/test', 16:39:11 INFO - 'SHELL': '/bin/bash', 16:39:11 INFO - 'SHLVL': '1', 16:39:11 INFO - 'TERM': 'linux', 16:39:11 INFO - 'TMOUT': '86400', 16:39:11 INFO - 'USER': 'cltbld', 16:39:11 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 16:39:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:11 INFO - '_': '/tools/buildbot/bin/python'} 16:39:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:11 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:39:11 INFO - Cleaning up... 16:39:11 INFO - Return code: 0 16:39:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:11 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 0x15d31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1945630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce850>, '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 0x19436d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1943b60>, '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': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:39:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:39:11 INFO - Using env: {'DISPLAY': ':0', 16:39:11 INFO - 'HOME': '/home/cltbld', 16:39:11 INFO - 'LANG': 'en_US.UTF-8', 16:39:11 INFO - 'LANGUAGE': 'en_US:en', 16:39:11 INFO - 'LOGNAME': 'cltbld', 16:39:11 INFO - 'MAIL': '/var/mail/cltbld', 16:39:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:11 INFO - 'MOZ_NO_REMOTE': '1', 16:39:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:11 INFO - 'NO_EM_RESTART': '1', 16:39:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:11 INFO - 'PWD': '/builds/slave/test', 16:39:11 INFO - 'SHELL': '/bin/bash', 16:39:11 INFO - 'SHLVL': '1', 16:39:11 INFO - 'TERM': 'linux', 16:39:11 INFO - 'TMOUT': '86400', 16:39:11 INFO - 'USER': 'cltbld', 16:39:11 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 16:39:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:11 INFO - '_': '/tools/buildbot/bin/python'} 16:39:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:11 INFO - Downloading/unpacking psutil>=3.1.1 16:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:39:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:39:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:39:13 INFO - Installing collected packages: psutil 16:39:13 INFO - Running setup.py install for psutil 16:39:13 INFO - building 'psutil._psutil_linux' extension 16:39:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:39:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:39:13 INFO - building 'psutil._psutil_posix' extension 16:39:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:39:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:39:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:39:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:39:14 INFO - Successfully installed psutil 16:39:14 INFO - Cleaning up... 16:39:14 INFO - Return code: 0 16:39:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:39:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1945630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce850>, '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 0x19436d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1943b60>, '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': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:39:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:39:14 INFO - Using env: {'DISPLAY': ':0', 16:39:14 INFO - 'HOME': '/home/cltbld', 16:39:14 INFO - 'LANG': 'en_US.UTF-8', 16:39:14 INFO - 'LANGUAGE': 'en_US:en', 16:39:14 INFO - 'LOGNAME': 'cltbld', 16:39:14 INFO - 'MAIL': '/var/mail/cltbld', 16:39:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:14 INFO - 'MOZ_NO_REMOTE': '1', 16:39:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:14 INFO - 'NO_EM_RESTART': '1', 16:39:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:14 INFO - 'PWD': '/builds/slave/test', 16:39:14 INFO - 'SHELL': '/bin/bash', 16:39:14 INFO - 'SHLVL': '1', 16:39:14 INFO - 'TERM': 'linux', 16:39:14 INFO - 'TMOUT': '86400', 16:39:14 INFO - 'USER': 'cltbld', 16:39:14 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 16:39:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:14 INFO - '_': '/tools/buildbot/bin/python'} 16:39:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:39:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:39:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:39:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:39:16 INFO - Installing collected packages: mozsystemmonitor 16:39:16 INFO - Running setup.py install for mozsystemmonitor 16:39:16 INFO - Successfully installed mozsystemmonitor 16:39:16 INFO - Cleaning up... 16:39:16 INFO - Return code: 0 16:39:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:39: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')]} 16:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39: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')]} 16:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:16 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 0x15d31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1945630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce850>, '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 0x19436d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1943b60>, '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': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:39:16 INFO - Using env: {'DISPLAY': ':0', 16:39:16 INFO - 'HOME': '/home/cltbld', 16:39:16 INFO - 'LANG': 'en_US.UTF-8', 16:39:16 INFO - 'LANGUAGE': 'en_US:en', 16:39:16 INFO - 'LOGNAME': 'cltbld', 16:39:16 INFO - 'MAIL': '/var/mail/cltbld', 16:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:16 INFO - 'MOZ_NO_REMOTE': '1', 16:39:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:16 INFO - 'NO_EM_RESTART': '1', 16:39:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:16 INFO - 'PWD': '/builds/slave/test', 16:39:16 INFO - 'SHELL': '/bin/bash', 16:39:16 INFO - 'SHLVL': '1', 16:39:16 INFO - 'TERM': 'linux', 16:39:16 INFO - 'TMOUT': '86400', 16:39:16 INFO - 'USER': 'cltbld', 16:39:16 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 16:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:16 INFO - '_': '/tools/buildbot/bin/python'} 16:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:16 INFO - Downloading/unpacking jsonschema==2.5.1 16:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:39:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:39:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:19 INFO - Downloading functools32-3.2.3-2.tar.gz 16:39:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:39:19 INFO - warning: no files found matching '*.txt' 16:39:19 INFO - no previously-included directories found matching 'build' 16:39:19 INFO - no previously-included directories found matching 'dist' 16:39:19 INFO - no previously-included directories found matching '.git*' 16:39:19 INFO - Installing collected packages: jsonschema, functools32 16:39:19 INFO - Running setup.py install for functools32 16:39:19 INFO - warning: no files found matching '*.txt' 16:39:19 INFO - no previously-included directories found matching 'build' 16:39:19 INFO - no previously-included directories found matching 'dist' 16:39:19 INFO - no previously-included directories found matching '.git*' 16:39:19 INFO - Successfully installed jsonschema functools32 16:39:19 INFO - Cleaning up... 16:39:19 INFO - Return code: 0 16:39:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x15d31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1945630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce850>, '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 0x19436d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1943b60>, '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': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:39:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:39:19 INFO - Using env: {'DISPLAY': ':0', 16:39:19 INFO - 'HOME': '/home/cltbld', 16:39:19 INFO - 'LANG': 'en_US.UTF-8', 16:39:19 INFO - 'LANGUAGE': 'en_US:en', 16:39:19 INFO - 'LOGNAME': 'cltbld', 16:39:19 INFO - 'MAIL': '/var/mail/cltbld', 16:39:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:19 INFO - 'MOZ_NO_REMOTE': '1', 16:39:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:19 INFO - 'NO_EM_RESTART': '1', 16:39:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:19 INFO - 'PWD': '/builds/slave/test', 16:39:19 INFO - 'SHELL': '/bin/bash', 16:39:19 INFO - 'SHLVL': '1', 16:39:19 INFO - 'TERM': 'linux', 16:39:19 INFO - 'TMOUT': '86400', 16:39:19 INFO - 'USER': 'cltbld', 16:39:19 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 16:39:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:19 INFO - '_': '/tools/buildbot/bin/python'} 16:39:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:39:19 INFO - Cleaning up... 16:39:19 INFO - Return code: 0 16:39:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x15d31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1945630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce850>, '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 0x19436d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1943b60>, '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': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:39:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:39:19 INFO - Using env: {'DISPLAY': ':0', 16:39:19 INFO - 'HOME': '/home/cltbld', 16:39:19 INFO - 'LANG': 'en_US.UTF-8', 16:39:19 INFO - 'LANGUAGE': 'en_US:en', 16:39:19 INFO - 'LOGNAME': 'cltbld', 16:39:19 INFO - 'MAIL': '/var/mail/cltbld', 16:39:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:19 INFO - 'MOZ_NO_REMOTE': '1', 16:39:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:19 INFO - 'NO_EM_RESTART': '1', 16:39:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:19 INFO - 'PWD': '/builds/slave/test', 16:39:19 INFO - 'SHELL': '/bin/bash', 16:39:19 INFO - 'SHLVL': '1', 16:39:19 INFO - 'TERM': 'linux', 16:39:19 INFO - 'TMOUT': '86400', 16:39:19 INFO - 'USER': 'cltbld', 16:39:19 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 16:39:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:19 INFO - '_': '/tools/buildbot/bin/python'} 16:39:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:19 INFO - Downloading/unpacking blobuploader==1.2.4 16:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:21 INFO - Downloading blobuploader-1.2.4.tar.gz 16:39:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:39:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:39:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:39:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:22 INFO - Downloading docopt-0.6.1.tar.gz 16:39:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:39:22 INFO - Installing collected packages: blobuploader, requests, docopt 16:39:22 INFO - Running setup.py install for blobuploader 16:39:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:39:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:39:22 INFO - Running setup.py install for requests 16:39:23 INFO - Running setup.py install for docopt 16:39:23 INFO - Successfully installed blobuploader requests docopt 16:39:23 INFO - Cleaning up... 16:39:23 INFO - Return code: 0 16:39:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:39: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')]} 16:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39: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')]} 16:39:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:23 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 0x15d31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1945630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce850>, '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 0x19436d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1943b60>, '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': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:39:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:39:23 INFO - Using env: {'DISPLAY': ':0', 16:39:23 INFO - 'HOME': '/home/cltbld', 16:39:23 INFO - 'LANG': 'en_US.UTF-8', 16:39:23 INFO - 'LANGUAGE': 'en_US:en', 16:39:23 INFO - 'LOGNAME': 'cltbld', 16:39:23 INFO - 'MAIL': '/var/mail/cltbld', 16:39:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:23 INFO - 'MOZ_NO_REMOTE': '1', 16:39:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:23 INFO - 'NO_EM_RESTART': '1', 16:39:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:23 INFO - 'PWD': '/builds/slave/test', 16:39:23 INFO - 'SHELL': '/bin/bash', 16:39:23 INFO - 'SHLVL': '1', 16:39:23 INFO - 'TERM': 'linux', 16:39:23 INFO - 'TMOUT': '86400', 16:39:23 INFO - 'USER': 'cltbld', 16:39:23 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 16:39:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:23 INFO - '_': '/tools/buildbot/bin/python'} 16:39:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:39:23 INFO - Running setup.py (path:/tmp/pip-K0dVzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:39:23 INFO - Running setup.py (path:/tmp/pip-DDtKd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:39:23 INFO - Running setup.py (path:/tmp/pip-3_i3Kk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:39:23 INFO - Running setup.py (path:/tmp/pip-VOy_tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:39:23 INFO - Running setup.py (path:/tmp/pip-ciC6Nf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:39:23 INFO - Running setup.py (path:/tmp/pip-rlG6A2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:39:23 INFO - Running setup.py (path:/tmp/pip-qx0M7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:39:23 INFO - Running setup.py (path:/tmp/pip-h1lMvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:39:23 INFO - Running setup.py (path:/tmp/pip-96QSW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:39:24 INFO - Running setup.py (path:/tmp/pip-zetLvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:39:24 INFO - Running setup.py (path:/tmp/pip-EURMvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:39:24 INFO - Running setup.py (path:/tmp/pip-KeqnU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:39:24 INFO - Running setup.py (path:/tmp/pip-cfli1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:39:24 INFO - Running setup.py (path:/tmp/pip-GRDKQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:39:24 INFO - Running setup.py (path:/tmp/pip-FShuuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:39:24 INFO - Running setup.py (path:/tmp/pip-oMjZKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:39:24 INFO - Running setup.py (path:/tmp/pip-rBfbG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:39:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:39:24 INFO - Running setup.py install for manifestparser 16:39:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:39:24 INFO - Running setup.py install for mozcrash 16:39:24 INFO - Running setup.py install for mozdebug 16:39:24 INFO - Running setup.py install for mozdevice 16:39:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:39:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:39:25 INFO - Running setup.py install for mozfile 16:39:25 INFO - Running setup.py install for mozhttpd 16:39:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:39:25 INFO - Running setup.py install for mozinfo 16:39:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:39:25 INFO - Running setup.py install for mozInstall 16:39:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:39:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:39:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:39:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:39:25 INFO - Running setup.py install for mozleak 16:39:25 INFO - Running setup.py install for mozlog 16:39:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:39:25 INFO - Running setup.py install for moznetwork 16:39:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:39:25 INFO - Running setup.py install for mozprocess 16:39:25 INFO - Running setup.py install for mozprofile 16:39:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:39:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:39:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:39:25 INFO - Running setup.py install for mozrunner 16:39:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:39:26 INFO - Running setup.py install for mozscreenshot 16:39:26 INFO - Running setup.py install for moztest 16:39:26 INFO - Running setup.py install for mozversion 16:39:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:39:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:39:26 INFO - Cleaning up... 16:39:26 INFO - Return code: 0 16:39:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:39:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:26 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 0x15d31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1945630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce850>, '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 0x19436d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1943b60>, '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': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:39:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:39:26 INFO - Using env: {'DISPLAY': ':0', 16:39:26 INFO - 'HOME': '/home/cltbld', 16:39:26 INFO - 'LANG': 'en_US.UTF-8', 16:39:26 INFO - 'LANGUAGE': 'en_US:en', 16:39:26 INFO - 'LOGNAME': 'cltbld', 16:39:26 INFO - 'MAIL': '/var/mail/cltbld', 16:39:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:26 INFO - 'MOZ_NO_REMOTE': '1', 16:39:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:26 INFO - 'NO_EM_RESTART': '1', 16:39:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:26 INFO - 'PWD': '/builds/slave/test', 16:39:26 INFO - 'SHELL': '/bin/bash', 16:39:26 INFO - 'SHLVL': '1', 16:39:26 INFO - 'TERM': 'linux', 16:39:26 INFO - 'TMOUT': '86400', 16:39:26 INFO - 'USER': 'cltbld', 16:39:26 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 16:39:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:26 INFO - '_': '/tools/buildbot/bin/python'} 16:39:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:39:26 INFO - Running setup.py (path:/tmp/pip-JIbjBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:39:26 INFO - Running setup.py (path:/tmp/pip-P4rLUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:39:26 INFO - Running setup.py (path:/tmp/pip-g2ImK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:39:26 INFO - Running setup.py (path:/tmp/pip-ZiM9i5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:39:26 INFO - Running setup.py (path:/tmp/pip-mAGJGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:39:27 INFO - Running setup.py (path:/tmp/pip-N7KxAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:39:27 INFO - Running setup.py (path:/tmp/pip-4SFw8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:39:27 INFO - Running setup.py (path:/tmp/pip-kozfBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:39:27 INFO - Running setup.py (path:/tmp/pip-f_mkLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:39:27 INFO - Running setup.py (path:/tmp/pip-qD18lU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:39:27 INFO - Running setup.py (path:/tmp/pip-8wnX5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:39:27 INFO - Running setup.py (path:/tmp/pip-NyZhkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:39:27 INFO - Running setup.py (path:/tmp/pip-92gpkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:39:27 INFO - Running setup.py (path:/tmp/pip-jpbiE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:39:27 INFO - Running setup.py (path:/tmp/pip-LJ6Ncf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:39:27 INFO - Running setup.py (path:/tmp/pip-35leDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:39:27 INFO - Running setup.py (path:/tmp/pip-zQE0mY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:39:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:39:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:39:29 INFO - Downloading blessings-1.6.tar.gz 16:39:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:39:29 INFO - Installing collected packages: blessings 16:39:29 INFO - Running setup.py install for blessings 16:39:30 INFO - Successfully installed blessings 16:39:30 INFO - Cleaning up... 16:39:30 INFO - Return code: 0 16:39:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:39:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:39:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:39:30 INFO - Reading from file tmpfile_stdout 16:39:30 INFO - Current package versions: 16:39:30 INFO - argparse == 1.2.1 16:39:30 INFO - blessings == 1.6 16:39:30 INFO - blobuploader == 1.2.4 16:39:30 INFO - docopt == 0.6.1 16:39:30 INFO - functools32 == 3.2.3-2 16:39:30 INFO - jsonschema == 2.5.1 16:39:30 INFO - manifestparser == 1.1 16:39:30 INFO - mozInstall == 1.12 16:39:30 INFO - mozcrash == 1.0 16:39:30 INFO - mozdebug == 0.1 16:39:30 INFO - mozdevice == 0.49 16:39:30 INFO - mozfile == 1.2 16:39:30 INFO - mozhttpd == 0.7 16:39:30 INFO - mozinfo == 0.9 16:39:30 INFO - mozleak == 0.1 16:39:30 INFO - mozlog == 3.4 16:39:30 INFO - moznetwork == 0.27 16:39:30 INFO - mozprocess == 0.24 16:39:30 INFO - mozprofile == 0.28 16:39:30 INFO - mozrunner == 6.13 16:39:30 INFO - mozscreenshot == 0.1 16:39:30 INFO - mozsystemmonitor == 0.3 16:39:30 INFO - moztest == 0.8 16:39:30 INFO - mozversion == 1.4 16:39:30 INFO - psutil == 3.1.1 16:39:30 INFO - requests == 1.2.3 16:39:30 INFO - wsgiref == 0.1.2 16:39:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:39:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:30 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 0x15d31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1945630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce850>, '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 0x19436d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1943b60>, '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': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:39:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:39:30 INFO - Using env: {'DISPLAY': ':0', 16:39:30 INFO - 'HOME': '/home/cltbld', 16:39:30 INFO - 'LANG': 'en_US.UTF-8', 16:39:30 INFO - 'LANGUAGE': 'en_US:en', 16:39:30 INFO - 'LOGNAME': 'cltbld', 16:39:30 INFO - 'MAIL': '/var/mail/cltbld', 16:39:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:30 INFO - 'MOZ_NO_REMOTE': '1', 16:39:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:30 INFO - 'NO_EM_RESTART': '1', 16:39:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:30 INFO - 'PWD': '/builds/slave/test', 16:39:30 INFO - 'SHELL': '/bin/bash', 16:39:30 INFO - 'SHLVL': '1', 16:39:30 INFO - 'TERM': 'linux', 16:39:30 INFO - 'TMOUT': '86400', 16:39:30 INFO - 'USER': 'cltbld', 16:39:30 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 16:39:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:30 INFO - '_': '/tools/buildbot/bin/python'} 16:39:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:39:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:39:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:39:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:39:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:39:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:39:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:39:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:39:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:39:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:39:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:39:30 INFO - Cleaning up... 16:39:30 INFO - Return code: 0 16:39:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:39:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:39:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:39:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:39:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1945630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce850>, '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 0x19436d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1943b60>, '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': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:39:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:39:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:39:30 INFO - Using env: {'DISPLAY': ':0', 16:39:30 INFO - 'HOME': '/home/cltbld', 16:39:30 INFO - 'LANG': 'en_US.UTF-8', 16:39:30 INFO - 'LANGUAGE': 'en_US:en', 16:39:30 INFO - 'LOGNAME': 'cltbld', 16:39:30 INFO - 'MAIL': '/var/mail/cltbld', 16:39:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:30 INFO - 'MOZ_NO_REMOTE': '1', 16:39:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:30 INFO - 'NO_EM_RESTART': '1', 16:39:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:30 INFO - 'PWD': '/builds/slave/test', 16:39:30 INFO - 'SHELL': '/bin/bash', 16:39:30 INFO - 'SHLVL': '1', 16:39:30 INFO - 'TERM': 'linux', 16:39:30 INFO - 'TMOUT': '86400', 16:39:30 INFO - 'USER': 'cltbld', 16:39:30 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 16:39:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:30 INFO - '_': '/tools/buildbot/bin/python'} 16:39:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:39:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:39:30 INFO - Cleaning up... 16:39:30 INFO - Return code: 0 16:39:30 INFO - Running post-action listener: _resource_record_post_action 16:39:30 INFO - Running post-action listener: _start_resource_monitoring 16:39:30 INFO - Starting resource monitoring. 16:39:30 INFO - [mozharness: 2017-02-05 00:39:30.886929Z] Finished create-virtualenv step (success) 16:39:30 INFO - [mozharness: 2017-02-05 00:39:30.887384Z] Running install step. 16:39:30 INFO - Running pre-action listener: _resource_record_pre_action 16:39:30 INFO - Running main action method: install 16:39:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:39:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:39:31 INFO - Reading from file tmpfile_stdout 16:39:31 INFO - Detecting whether we're running mozinstall >=1.0... 16:39:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:39:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:39:31 INFO - Reading from file tmpfile_stdout 16:39:31 INFO - Output received: 16:39:31 INFO - Usage: mozinstall [options] installer 16:39:31 INFO - Options: 16:39:31 INFO - -h, --help show this help message and exit 16:39:31 INFO - -d DEST, --destination=DEST 16:39:31 INFO - Directory to install application into. [default: 16:39:31 INFO - "/builds/slave/test"] 16:39:31 INFO - --app=APP Application being installed. [default: firefox] 16:39:31 INFO - mkdir: /builds/slave/test/build/application 16:39:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:39:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:39:47 INFO - Reading from file tmpfile_stdout 16:39:47 INFO - Output received: 16:39:47 INFO - /builds/slave/test/build/application/firefox/firefox 16:39:47 INFO - Running post-action listener: _resource_record_post_action 16:39:47 INFO - [mozharness: 2017-02-05 00:39:47.615590Z] Finished install step (success) 16:39:47 INFO - [mozharness: 2017-02-05 00:39:47.615865Z] Running run-tests step. 16:39:47 INFO - Running pre-action listener: _resource_record_pre_action 16:39:47 INFO - Running main action method: run_tests 16:39:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:39:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:39:47 INFO - Python 2.7.3 16:39:47 INFO - Return code: 0 16:39:47 INFO - grabbing minidump binary from tooltool 16:39:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:47 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 0x19ce850>, '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 0x19436d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1943b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:39:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:39:47 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:39:47 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:39:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:39:47 INFO - Return code: 0 16:39:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:39:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:39:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:39:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:39:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:39:47 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/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:39:47 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/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-032 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:39:47 INFO - Using env: {'DISPLAY': ':0', 16:39:47 INFO - 'HOME': '/home/cltbld', 16:39:47 INFO - 'LANG': 'en_US.UTF-8', 16:39:47 INFO - 'LANGUAGE': 'en_US:en', 16:39:47 INFO - 'LOGNAME': 'cltbld', 16:39:47 INFO - 'MAIL': '/var/mail/cltbld', 16:39:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:39:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:39:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:47 INFO - 'MOZ_NO_REMOTE': '1', 16:39:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:39:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:39:47 INFO - 'NO_EM_RESTART': '1', 16:39:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:39:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:47 INFO - 'PWD': '/builds/slave/test', 16:39:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:39:47 INFO - 'SHELL': '/bin/bash', 16:39:47 INFO - 'SHLVL': '1', 16:39:47 INFO - 'TERM': 'linux', 16:39:47 INFO - 'TMOUT': '86400', 16:39:47 INFO - 'USER': 'cltbld', 16:39:47 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1486245375.767225-598311644', 16:39:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:47 INFO - '_': '/tools/buildbot/bin/python'} 16:39:47 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/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:39:47 INFO - mozversion application_buildid: 20170204233700 16:39:47 INFO - mozversion application_changeset: 45d7372ceb6695e42915f11a4d30fce43cb59841 16:39:47 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:39:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:39:47 INFO - mozversion application_name: Firefox 16:39:47 INFO - mozversion application_remotingname: firefox-dev 16:39:47 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:39:47 INFO - mozversion application_vendor: Mozilla 16:39:47 INFO - mozversion application_version: 53.0a2 16:39:47 INFO - mozversion platform_buildid: 20170204233700 16:39:47 INFO - mozversion platform_changeset: 45d7372ceb6695e42915f11a4d30fce43cb59841 16:39:47 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:39:47 INFO - mozversion platform_version: 53.0a2 16:39:47 INFO - using testdate: 1486255187 16:39:47 INFO - actual date: 1486255187 16:39:47 INFO - starting webserver on 'localhost:52856' 16:39:47 INFO - SUITE-START | Running 2 tests 16:39:47 INFO - TEST-START | dromaeo_css 16:39:47 INFO - operating with platform_type : linux_ 16:39:47 INFO - Initialising browser for dromaeo_css test... 16:39:47 INFO - TEST-INFO | started process 9363 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmph60Xbx/profile http://localhost:52856/getInfo.html) 16:39:52 INFO - PROCESS | 9363 | __metrics Screen width/height:1600/1200 16:39:52 INFO - PROCESS | 9363 | colorDepth:24 16:39:52 INFO - PROCESS | 9363 | Browser inner width/height: 1024/697 16:39:52 INFO - PROCESS | 9363 | __metrics 16:39:53 INFO - PROCESS | 9363 | 1486255193033 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 16:39:53 INFO - PROCESS | 9363 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:39:53 INFO - PROCESS | 9363 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:39:53 INFO - PROCESS | 9363 | console.error: PushService: 16:39:53 INFO - PROCESS | 9363 | stateChangeProcessEnqueue: Error transitioning state 16:39:53 INFO - PROCESS | 9363 | UnknownError 16:39:54 INFO - TEST-INFO | 9363: exit 0 16:39:54 INFO - Browser initialized. 16:39:54 INFO - Running cycle 1/1 for dromaeo_css test... 16:39:54 INFO - TEST-INFO | started process 9456 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmph60Xbx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:40:06 INFO - PROCESS | 9456 | RSS: Main: 138293248 16:40:06 INFO - PROCESS | 9456 | 16:43:06 INFO - PROCESS | 9456 | Cycle 1(1): loaded http://localhost:52856/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52856/tests/dromaeo/cssquery-ext.html) 16:43:08 INFO - PROCESS | 9456 | RSS: Main: 234434560 16:43:08 INFO - PROCESS | 9456 | 16:44:56 INFO - PROCESS | 9456 | ************************* 16:44:56 INFO - PROCESS | 9456 | A coding exception was thrown and uncaught in a Task. 16:44:56 INFO - PROCESS | 9456 | 16:44:56 ERROR - PROCESS | 9456 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:44:56 INFO - PROCESS | 9456 | Full stack: 16:44:56 INFO - PROCESS | 9456 | ************************* 16:46:10 INFO - PROCESS | 9456 | Cycle 1(1): loaded http://localhost:52856/tests/dromaeo/cssquery-ext.html (next: http://localhost:52856/tests/dromaeo/cssquery-jquery.html) 16:46:10 INFO - PROCESS | 9456 | RSS: Main: 229408768 16:46:10 INFO - PROCESS | 9456 | 16:49:12 INFO - PROCESS | 9456 | Cycle 1(1): loaded http://localhost:52856/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52856/tests/dromaeo/cssquery-mootools.html) 16:49:13 INFO - PROCESS | 9456 | RSS: Main: 173780992 16:49:13 INFO - PROCESS | 9456 | 16:52:15 INFO - PROCESS | 9456 | Cycle 1(1): loaded http://localhost:52856/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52856/tests/dromaeo/cssquery-prototype.html) 16:52:15 INFO - PROCESS | 9456 | RSS: Main: 229543936 16:52:15 INFO - PROCESS | 9456 | 16:55:18 INFO - PROCESS | 9456 | Cycle 1(1): loaded http://localhost:52856/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52856/tests/dromaeo/cssquery-yui.html) 16:55:19 INFO - PROCESS | 9456 | RSS: Main: 178032640 16:55:19 INFO - PROCESS | 9456 | 16:58:21 INFO - PROCESS | 9456 | Cycle 1(1): loaded http://localhost:52856/tests/dromaeo/cssquery-yui.html (next: http://localhost:52856/tests/dromaeo/cssquery-dojo.html) 16:58:21 INFO - PROCESS | 9456 | RSS: Main: 266858496 16:58:21 INFO - PROCESS | 9456 | 16:58:21 INFO - PROCESS | 9456 | __start_tp_report 16:58:21 INFO - PROCESS | 9456 | _x_x_mozilla_page_load 16:58:21 INFO - PROCESS | 9456 | _x_x_mozilla_page_load_details 16:58:21 INFO - PROCESS | 9456 | |i|pagename|runs| 16:58:21 INFO - PROCESS | 9456 | |0;dojo.html;3269.918252043699;3297.406466835969;3308.858443836876;3310.8675652973884;3357.244619960509;5358.169483730018;5450.3459584849825;5451.536619387357;5458.4541545845395;5465.781368745255;5434.510894019538;5467.289252397193;5467.890642187163;5468.9179662305;5471.671699698021;4960.379952505928;4973.303737476756;5032.194848824189;5053.620978426614;5058.038972595195;4844.733539655317;4863.14894893394;5014.423341315741;5049.141645920203;5049.2173713074535;4926.285688575154;4954.925676114861;4962.3548938638105;5017.322661440695;5041.243813427996;5055.494450554952;5057.0897238497055;5060.1650727630085;5061.215511595708;5087.084324821531;4855.858873164803;4981.028699403618;4992.700437973734;5008.524190201925;5051.823186188502;5266.92099618506;5273.367195936474;5326.44072372403;5331.893362132736;5396.43337449566;5752;5756.942430575725;5762.250907382056;5763.596548241625;5767.653940763567;5796.710164491801;5837.474627283523;5849.239598852165;5871.2073870027425;5873.060310350324;5287.4976877196605;5314.362276526842;5324.760385782649;5348.759305831247;5411.56707463402;5850.0932355484965;5859.44335288147;5861.88276234473;5865.736041878125;5888.146218798251;5599.776008959596;5642.915356269659;5648.830535083954;5650.5479561634975;5656.377798442168;5696.629719068247;5731.455511726379;5741;5749.88500229993;5758.136279558016;5727.3699893011735;5733.569982251348;5790.913136302959;5818.359980402152;5818.505427038749;5804.100364443518;5806.622569532967;5807.593468457168;5811.0992796115825;5811.94188058114;4371.5628437156665;4441.222786012435;4442.68901176921;4443.244648409778;4470.508244093147;3877.476540666974;3936.7637941723588;3951.209758048402;3973.443717879499;4031;5663.801766938137;5675.77296908119;5677.545796336283;5677.943220567824;5678.290213723285;5732.226149469769;5798.362180160263;5802.709864506758;5876.706164691748;5913.497352725067;5550.666959982414;5569.693666848281;5589.133684278943;5684.9431505684415;5694.373618901917;5025;5027.195648696191;5030.798768049237;5032.043911656773;5034.723090230073;6099.451049405486;6113.8165855023535;6117.355800906391;6123.203983482119;6140.473573783631;5677.801276955287;5716.885662286814;5768.192453056491;5794.1308803679785;5796.101604251347;6002.429769171922;6057.878842423215;6069.605475644069;6073.453389194994;6090.482309003784;5708;5751.051076572486;5752.395998420273;5755.769769209185;5760.222369980167;5664.546836253007;5708.600397972102;5770.480656740913;5811.738471768866;5852.7658893645485;5749.655020698771;5753.309602847517;5757.510611597893;5776.220210271559;5795.536357091339;5750.166225897138;5755.65466072037;5757.942420575908;5758.3090029196765;5763;5561.972190139023;5575.972120139373;5593.608447408805;5722.685252311163;5725.971370143123;5677.347105082803;5678.801241956512;5753.654780713171;5757.366689664217;5761.539076873756;5619.297587801525;5621.52217061538;5628.634138780966;5631.493165615114;5635.295588051494 16:58:21 INFO - PROCESS | 9456 | |1;ext.html;589.9144624029516;615.8675884684793;653.875763604915;658.4271683635235;663.6515829189672;45942.54057459424;47475.999999999956;47479.525204747944;53719.19421208675;53866.46133538664;50847.49152508474;51542;51566;51576.742116289366;51739;8809.691660791874;9298.349115561914;9521.285903557226;9528.904710952871;9536.761580960461;8703.042665306812;11150.944245278763;11399.3730344831;11430.142739294544;11863;11683.766324673501;11733.237339572901;11743.177977541576;11743.941280293586;11751.471183796706;11260.04289635382;11289.209755317132;11311;11326.546938122465;11327.037201837853;11472.196946213768;11552.249103808226;11562.653120406401;11564.074874010059;11567.710807229803;47270;47640.761796191146;47708.52291477076;47765.761171194266;47800.521994779956;4363.432753742025;4416.447944006999;4439.75581343026;4496.595306422405;4547;34259.31481370359;34339.14152146191;34593.13517162066;35142;35986;4498.79755411004;4502.729836209838;4533.750643714594;4555.772211389417;4571.451425828888;337559;338080;338901;339078;340129;71578.64210678914;71941;71951;72001.6399917997;72160.63919680368;73793.6310318445;73802.63098684579;73869.6306518464;74072;74103;20448.795512044988;21073.683894741593;21096.261630842848;21096.683549746765;21113.26103586367;14678.41286348534;14843.257837108102;15720.371185152695;15888.364465421484;15965.042097474188;19721.704174437396;21067;21249.68125478119;21269.36191914245;21323;7448.180700122982;7619.180833154296;7622.352100071556;7640.503367281109;7690;1005.6882366466418;1012.7620009297809;1018.4653057145058;1019.7399663085865;1030.3096925060227;34305;34469;34744.47883281753;34976.82511587426;35103.4734478983;1098.9998203269972;1165.1901928159848;1174.301290732013;1198.7362780188344;1206.252123683322;18263.269469221083;18290.17694203764;18323.450296491126;18380.632387352445;18456.723149152775;566855;569063;569711;570465;571263;70526.64736676283;70697.93953090708;70934;70978;71053.644731776;1536.8232653244797;1548.3729089718681;1575.2911189964561;1588.4043483693615;1589.4516391844795;32311.51532727058;34052;34078.82960585181;34102.317953641286;34147.14632134217;59791.40208598032;60448;60508.69745651243;60513.69743151256;60562;423685;423764;425223;426228;426329;40961.590384095776;41145.38281925833;45980.080398392514;49430;49801.750991244815;48984;54741.726291368286;55965.72017139888;56155;56788.148177778196;45170.322445164005;46088.308675370565;46172.769136155446;47306.52693473021;47914.5208547924;45001;46177;46323.768381157875;46452;47922.5207747918;46080.769596151804;46560.53439465562;46623.76688116673;46784.298235527174;46909.2963605553;38980;40609.1878162441;40906.38640420337;44264.55735442733;44947.550524494334;30870.69129308678;30974.690253097182;31081.222969425944;31492.370152596362;31669.841650791597 16:58:21 INFO - PROCESS | 9456 | |2;jquery.html;425.1093958157659;433.57199681782026;439.77571438566343;445.39203986558346;456.1879853860128;20621.793782062174;21785.564288714217;22263;22386.552268954612;22739.658905116437;21725.23961661342;22447.775522244774;22646.094156233732;22730.204442844464;23040.42398940023;5487.874985627956;5866.941330586704;5981.28224613047;6023.30731965823;6067.453929146376;5879.970600146993;5938.8218353449465;5983.491403230731;5993.101034844786;5994.070919007539;5773.769049238025;5862.413758624146;5913.704314784265;5921.911171332434;5957.434043765836;5861.970690146543;5898.911516327258;5923.170756094129;5928.051511758098;5940.198073260098;5308.814191503279;5402.972985135049;5601.271834661508;5638.182463542805;5741.425857414267;14831.925840370837;14896;14949.327280272306;15046.924765376103;15066.924665376713;2731.617573539706;2731.740484653955;2738.52075886719;2752.862356882148;2754.311422144464;10985;10989.945050274777;11098.112151027899;11469.827952580717;11528.077753779677;1947.5228569000544;1992.7409436773128;1995.281698588507;1997;2008.8694234874686;89999.55000224957;91559.54220228923;93833.53083234541;94173;95309.52345238299;12025.579104731292;12239.326837023871;12305.50777968889;12382.442790074469;12591.811122833165;17635.382761603283;17644.382446614305;17689.823101768816;17695;17699.557511062325;9337.299702522203;9410.576524056434;9470.621175152917;9507.049295070437;9603.279754018477;9052.683156089637;9189.402688825205;9198.908010919939;9215.815683686289;9456.007119252517;5277.577793776489;5277.973610131925;5300.734963251822;5317.1226747586215;5383.838484845461;2040.9387718368473;2062.9277975270793;2090.153487837428;2141.1542440736134;2151.9462013449474;912.3066469483108;931.3201363005044;931.8928323242778;933.7618907178626;939.2204470289644;19915.60168796616;20387.388378348674;21135.78864211369;21511.67732484014;22545.43636409103;1040.323789536792;1049.2026060194307;1064.8030114428855;1076.822324316479;1084.1543595995136;10735.731606709895;11348.319100853974;11396.088312934946;11405.942970285261;11435.94282028601;752570;763649.1796394626;766467;767376;775781;82429;85142.14857851467;88460.5576972111;114139;115833;1082.723905404117;1091.2252300866314;1120.3725913488472;1126.7014241226082;1128.6501184632791;18995;19692.21231150738;19714.60570788605;19773.802261977195;19893.303734369227;47232;47586.52413475821;48029.279560807314;48358;48848.75575622099;65338.673306633165;65908.6704566474;65967.6701616508;66347.66826165839;66457;9048.230900373543;9094;9198.126178013077;9228.077192280718;9473.810523789347;10206.489675516046;13053.086283960032;13422.194668319931;13618.18290902549;14123.64690882736;10598.15214782831;11654.650360489199;11716.355600441717;11753.236039657395;12027.338496382434;10893.782124357627;11383.54465821358;11478.196526243417;11667.124965627441;12032.338221397908;7952.801179970547;10901.454927253448;11581.7104572383;11594.362310073027;11678.591249306233;8839.734807955514;8848;8933.73198804011;8988.370814043215;9069.591868366071;10835.945820270848;11218;11686.357250350971;11766.176367654181;12150.696232594255 16:58:21 INFO - PROCESS | 9456 | |3;mootools.html;1498.9100653960763;1514.9697006059873;1515.1666583379147;1520.5514373259891;1531.471642283412;7763.340116090132;8214.958925205381;8216.13730558291;8216.84964105025;8219.342452603792;7162.1405431348185;7372.815679608012;7387.817949128141;7579.4694371394025;8091.069527004396;2293.128611127769;2385.666006759055;2390.8685022323766;2400.1719406804623;2409.1567951217107;2428.2229686500327;2448.6571879936823;2469.64190192422;2472.24596498068;2474.307193985687;2387.1048356866177;2387.140629373424;2409.987950060256;2417.9516409671796;2420.007796803311;2309.815214782813;2310.8151347892126;2327.3832434404894;2334.4397344637337;2339.894704738291;2301.0910690277246;2381.868997205153;2385.0340612051973;2385.33210701004;2385.773351531619;8136.145704700981;8162.224588664068;8169.877451838227;8170.101288858221;8189.549574773385;5038.823641172578;5161;5164.819231326923;5174;5176.0683077046115;2981.757120740782;3175.857086431116;3182.5067114597273;3199.6160460744627;3207.6471588125287;1127.8421021057059;1149.4769879704797;1178.1635039122234;1195.48594104535;1197.9760404791855;10531.73670658232;10657.84013239802;10674.573017079307;10687.465626718633;10698;4320.783960801948;4485.663575231871;4543.568361005699;4548.476925153584;4552.772361381918;9448.480333581581;9450.385724927857;9470.810583788285;9473.47895865734;9478.857817132748;5208.817691380784;5213.244079608436;5216.921746173811;5223.216517522402;5223.712695801767;4718.976405118021;4790.784414701347;4802.783874725646;4844.8304309349005;4888.364512613338;3825.2349530094093;3872.0900588362274;3905.5508616509464;3934.09515811365;3945.940810887839;1284.537566476068;1293.9417726202344;1296.786030305008;1308.738252349534;1313.6124843171287;983.3362480325751;987.6444479987199;994.3139233928711;998.2164001258977;1019.8470229465639;5552.666839989613;5624.521915637217;5625.353084395347;5640.85897852557;5720.513756330675;652.2857471069171;652.9347065293528;655.7180412422704;665.3246954341347;674.844785699292;3765.6987441005203;3785.488958990556;3788.3749181385315;3830.3296923038356;3871.322518559241;9286.814263714688;9653.758656033655;9656.420614763138;9665.516724163763;9665.855012174823;9480.762980925532;9570.760730981781;9629.229661627052;9633.9036609633;9647.228221742243;7394.26057394267;7576.24237576238;7608.277213664693;7696.15342312334;7709.036370453693;5729.369769325454;5856.94143058564;5925.288965324189;6039.154518367344;6043.667598282137;8747.956260218658;8956.776080598038;9007.729768106967;9117.635294588408;9121.908780912107;8212.30195433371;8608.182222688834;8926.419782714103;9063.184313411824;9072.95463522678;6624.735010599715;6986.091808064919;7049.929500704928;7149.82125446868;7309.086364204474;7635.694572217049;7759.30166285037;7804.180561041236;7857.685692572233;7895.052593688794;6696.698648560729;6772.288909664611;6779.559328643622;6792.93207067923;6826.8634627308165;6630.668466576555;6658.1344425224415;6679.365460281266;6690.330966903271;6728.461723062045;6007.8197654070445;6016.939830601638;6027.035674292092;6095.116208149882;6112.113743507255;4746.857594272177;4747.810087596457;4857.611391088775;4866.197077482176;4869.586085182799;4787.114383839001;4841.830535931225;4971.527704868032;4977.253411988231;5028.421731500925 16:58:21 INFO - PROCESS | 9456 | |4;prototype.html;374.52061361457345;379.83477187423455;380.9276237514872;383.5627384781348;386.37793153023637;5225.817096401622;5304.310439642846;5328.893422131555;5330.626856120073;5336;5212.556932660719;5225.928684619654;5235.58115350772;5266.868328291795;5267.23625074364;3717.7769333840056;3939.9409008864745;4138.544760076389;4211.452511173543;4223.598758117974;4158.542560318362;4162.646175075122;4170.749755014694;4184.581541845821;4206.221848957937;4120.196561670476;4131.727549847572;4140.461739973799;4182.6862985276075;4189.533663217864;4019.77891215983;4116.938245926283;4190.28765109932;4194.832206711743;4202.8022013726095;3658.304922064826;3717.423799311111;3752.5496940367057;3755.586885442599;3764.5858955514873;5067.163917953533;5103.438621751641;5104.25988231708;5107.901801112742;5114.28400023997;4627.537246275379;4721.3862197914395;4755.643326750507;4760.214564596838;4769.976150119227;5429.837104886859;5473;5483.75323110461;5484;5536.750846211889;4098.959010409888;4108.569344597265;4131.111810960658;4135.31767258402;4147.834086636531;5430.642339415146;5538.584606154554;5571.4428557144365;5676.460736230051;5693.601447898649;4195.139996300751;4209.34755112958;4222.451081359403;4223.070924396628;4231.492220933507;4229.555896630871;4255.723377980421;4267.402563641031;4288.656907447396;4307.6769242306955;4421.447319085114;4565.539027511231;4737.431508218967;4780.49804770501;4795.136875362398;5493.395726470085;5500.3949565547755;5515.503604675519;5578.2748242728185;5597.188407680864;1358.9796153057712;1372.848986611472;1384.515419603151;1396.015808854755;1401.8317801863639;910.006666893753;937.3579098317674;947.1806887042659;950.5104870991445;952.7427594549433;5204;5206.817761378334;5210.947890521122;5245.213218017328;5245.449227831099;5408.621396502207;5453.973044960086;5455.672659640434;5465.726713664301;5484.287042684426;5059.848204553869;5152.252923326172;5179.81870634526;5224.190250511177;5286.841394758163;2861.1988643179943;2882.466743652389;2893.8119022263486;2900.3329234276234;2903.6225290712073;5668;5671.914921276184;5674.2339784129435;5693.505766934928;5716.857078573069;5401.756920938567;5488.694748205763;5555.694436805934;5560.86097847549;5571.275734154534;5624.493795558419;5625.409332020243;5638;5662.37713851476;5664.801731939362;5266.368035835724;5422.752766863565;5456.753231827057;5487.670739755627;5510.008198524144;5381.8923621526565;5383.946160538345;5386.841829006626;5397.595180361567;5452.972735136299;5526.005319042611;5535.640183388067;5717.1995920570325;5752.971235143965;5791.594588378773;4035.596440355941;4051.5948405159247;4052.979735101306;4053.858114965962;4063.817128229296;5533.253010843646;5659.660420374791;5665.263515742927;5668.291463567054;5673.4114447954635;5464.1257398816;5477.685355514727;5478.452154784649;5479.561635069105;5510.889782204414;5458.454154584669;5460.31746031746;5461.836144915659;5473.658953556404;5475.4250803665045;5208.5312321891215;5418.674879507243;5426.67439953604;5431.131019036935;5489.945100548944;5460.972695136499;5469.808556700656;5470.699111548903;5493.775021116454;5506.724663766876;5341.5459685927135;5351.705656188947;5362.168863826113;5385.919211211756;5415.241866138665 16:58:21 INFO - PROCESS | 9456 | |5;yui.html;827.3794654009492;852.2500199824154;852.9488230706158;928.7678080479878;935.602368993178;14890.92554537227;16570.83429165708;16723.414680486185;16765.497035088967;16765.832341676578;16171.191440427961;16541.172941352943;16821.57946051346;16833.747493787603;16849.91575042126;701.340739704676;724.5652608434949;831.0733532111682;846.1622993236682;848.6308455821707;811.2414892075899;828.4159667434475;830.7383174300077;834.2366734437982;837.811492414208;851.3231980575457;851.902031266403;852.4501696405814;854.4360721923532;855.589317127779;740.6333864736989;759.8062494063978;848.0798333807795;850.808568072185;850.9574521273912;621.4438077920281;636.2206297285788;636.2937139774846;636.5416899832113;641.5829710688043;16740.41408550707;16959.745603815954;16967.06681132537;16991.065491397967;17016.319347226097;15119;15449.382024718998;15814.68370632581;15890.046597204204;16039.679206415807;13445.462181512728;13542.187468751907;13656.93171534136;13697.383617737225;13773.724525509533;4679.859604211879;4699.812007519695;4700.29495575663;4753.809847606091;4773.498782627808;77218.61390693067;77318;77405.61297193535;77978.99999999943;79373.20626793716;11643.650690479299;11735.88264117365;11926.821097683542;11927.499900521207;11968.88031119695;23235.53528929412;23539.529209415716;23654.763452365256;23845.761542384702;24117;8132.959335203404;8193.426460147732;8241.093479717227;8290.08809031006;8450.830983380298;4399.6040356368085;4480.283154695233;4527.298268768346;4566.58900698932;4630.814767409266;3659.1766852457986;3744.9812750936617;3770.849166033328;3778.2065766189407;3784.016155799512;614.1402037148029;621.5431657731564;624.6408315218767;631.4758750237293;631.7504585688628;620.1194304088206;627.3945642455053;631.4916492223712;637.171676820132;669.9296573859772;9908.355956862924;10681.198910081775;10846.240763146505;10851.348919064882;10855.620053298097;784.3607459920261;786.4337676872757;805.769905729863;809.3039985612369;828.5235989306173;5267.91747823668;5337.706426146521;5349.598780091509;5387.8114266001285;5425.728713564306;82983.5850820742;84307.5784621073;84849.57575212084;85129;86510;71184.64407677928;72078.63960680163;73139.63430182815;73470;75226;62471;62509.374906251265;63457.365426345146;64019;65238.34761652323;10355.43045132525;10362.067413932782;10406.323588966692;10458.633947812094;10469.738256543647;37202.627973719915;37309.25381492409;37979.24041519099;38216.235675286895;46458;52592.737036314575;54035;54203.45796541984;54977;56952.43047569637;14414.207218603078;14525.346359414063;14574.781378279122;14667.559973200821;15522.922385388001;16425;16641.50075497737;16784.58038549046;17792.733109003115;17837.73243401376;14670.706585868436;15295;15308.464203752816;15336.386544538094;16257;14105;14106.717865642835;14198.077124986843;14409;14535.636609084857;13770;14008.369623367176;14246.572602821932;14279.57161285163;14410.495632652806;12059.683587013642;12177.939110304393;12216.572419965258;12251.571195008488;12688.936555317165;11222.943885280521;11284.333343288412;11501.597444089417;11534.192606517463;11539.8269025963 16:58:21 INFO - PROCESS | 9456 | __end_tp_report 16:58:21 INFO - PROCESS | 9456 | __start_cc_report 16:58:21 INFO - PROCESS | 9456 | _x_x_mozilla_cycle_collect,1124 16:58:21 INFO - PROCESS | 9456 | __end_cc_report 16:58:21 INFO - PROCESS | 9456 | __startTimestamp1486256301857__endTimestamp 16:58:21 INFO - PROCESS | 9456 | 16:58:21 INFO - PROCESS | 9456 | ------- Summary: start ------- 16:58:21 INFO - PROCESS | 9456 | Number of tests: 6 16:58:21 INFO - PROCESS | 9456 | 16:58:21 INFO - PROCESS | 9456 | [#0] dojo.html Cycles:165 Average:5417.62 Median:5664.55 stddev:592.73 (10.5%) stddev-sans-first:570.10 16:58:21 INFO - PROCESS | 9456 | Values: 3269.9 3297.4 3308.9 3310.9 3357.2 5358.2 5450.3 5451.5 5458.5 5465.8 5434.5 5467.3 5467.9 5468.9 5471.7 4960.4 4973.3 5032.2 5053.6 5058.0 4844.7 4863.1 5014.4 5049.1 5049.2 4926.3 4954.9 4962.4 5017.3 5041.2 5055.5 5057.1 5060.2 5061.2 5087.1 4855.9 4981.0 4992.7 5008.5 5051.8 5266.9 5273.4 5326.4 5331.9 5396.4 5752.0 5756.9 5762.3 5763.6 5767.7 5796.7 5837.5 5849.2 5871.2 5873.1 5287.5 5314.4 5324.8 5348.8 5411.6 5850.1 5859.4 5861.9 5865.7 5888.1 5599.8 5642.9 5648.8 5650.5 5656.4 5696.6 5731.5 5741.0 5749.9 5758.1 5727.4 5733.6 5790.9 5818.4 5818.5 5804.1 5806.6 5807.6 5811.1 5811.9 4371.6 4441.2 4442.7 4443.2 4470.5 3877.5 3936.8 3951.2 3973.4 4031.0 5663.8 5675.8 5677.5 5677.9 5678.3 5732.2 5798.4 5802.7 5876.7 5913.5 5550.7 5569.7 5589.1 5684.9 5694.4 5025.0 5027.2 5030.8 5032.0 5034.7 6099.5 6113.8 6117.4 6123.2 6140.5 5677.8 5716.9 5768.2 5794.1 5796.1 6002.4 6057.9 6069.6 6073.5 6090.5 5708.0 5751.1 5752.4 5755.8 5760.2 5664.5 5708.6 5770.5 5811.7 5852.8 5749.7 5753.3 5757.5 5776.2 5795.5 5750.2 5755.7 5757.9 5758.3 5763.0 5562.0 5576.0 5593.6 5722.7 5726.0 5677.3 5678.8 5753.7 5757.4 5761.5 5619.3 5621.5 5628.6 5631.5 5635.3 16:58:21 INFO - PROCESS | 9456 | 16:58:21 INFO - PROCESS | 9456 | [#1] ext.html Cycles:180 Average:64626.13 Median:24340.50 stddev:120064.42 (493.3%) stddev-sans-first:120304.96 16:58:21 INFO - PROCESS | 9456 | Values: 589.9 615.9 653.9 658.4 663.7 45942.5 47476.0 47479.5 53719.2 53866.5 50847.5 51542.0 51566.0 51576.7 51739.0 8809.7 9298.3 9521.3 9528.9 9536.8 8703.0 11150.9 11399.4 11430.1 11863.0 11683.8 11733.2 11743.2 11743.9 11751.5 11260.0 11289.2 11311.0 11326.5 11327.0 11472.2 11552.2 11562.7 11564.1 11567.7 47270.0 47640.8 47708.5 47765.8 47800.5 4363.4 4416.4 4439.8 4496.6 4547.0 34259.3 34339.1 34593.1 35142.0 35986.0 4498.8 4502.7 4533.8 4555.8 4571.5 337559.0 338080.0 338901.0 339078.0 340129.0 71578.6 71941.0 71951.0 72001.6 72160.6 73793.6 73802.6 73869.6 74072.0 74103.0 20448.8 21073.7 21096.3 21096.7 21113.3 14678.4 14843.3 15720.4 15888.4 15965.0 19721.7 21067.0 21249.7 21269.4 21323.0 7448.2 7619.2 7622.4 7640.5 7690.0 1005.7 1012.8 1018.5 1019.7 1030.3 34305.0 34469.0 34744.5 34976.8 35103.5 1099.0 1165.2 1174.3 1198.7 1206.3 18263.3 18290.2 18323.5 18380.6 18456.7 566855.0 569063.0 569711.0 570465.0 571263.0 70526.6 70697.9 70934.0 70978.0 71053.6 1536.8 1548.4 1575.3 1588.4 1589.5 32311.5 34052.0 34078.8 34102.3 34147.1 59791.4 60448.0 60508.7 60513.7 60562.0 423685.0 423764.0 425223.0 426228.0 426329.0 40961.6 41145.4 45980.1 49430.0 49801.8 48984.0 54741.7 55965.7 56155.0 56788.1 45170.3 46088.3 46172.8 47306.5 47914.5 45001.0 46177.0 46323.8 46452.0 47922.5 46080.8 46560.5 46623.8 46784.3 46909.3 38980.0 40609.2 40906.4 44264.6 44947.6 30870.7 30974.7 31081.2 31492.4 31669.8 16:58:21 INFO - PROCESS | 9456 | 16:58:21 INFO - PROCESS | 9456 | [#2] jquery.html Cycles:180 Average:37829.43 Median:12886.02 stddev:125403.98 (973.2%) stddev-sans-first:125724.32 16:58:21 INFO - PROCESS | 9456 | Values: 425.1 433.6 439.8 445.4 456.2 20621.8 21785.6 22263.0 22386.6 22739.7 21725.2 22447.8 22646.1 22730.2 23040.4 5487.9 5866.9 5981.3 6023.3 6067.5 5880.0 5938.8 5983.5 5993.1 5994.1 5773.8 5862.4 5913.7 5921.9 5957.4 5862.0 5898.9 5923.2 5928.1 5940.2 5308.8 5403.0 5601.3 5638.2 5741.4 14831.9 14896.0 14949.3 15046.9 15066.9 2731.6 2731.7 2738.5 2752.9 2754.3 10985.0 10989.9 11098.1 11469.8 11528.1 1947.5 1992.7 1995.3 1997.0 2008.9 89999.6 91559.5 93833.5 94173.0 95309.5 12025.6 12239.3 12305.5 12382.4 12591.8 17635.4 17644.4 17689.8 17695.0 17699.6 9337.3 9410.6 9470.6 9507.0 9603.3 9052.7 9189.4 9198.9 9215.8 9456.0 5277.6 5278.0 5300.7 5317.1 5383.8 2040.9 2062.9 2090.2 2141.2 2151.9 912.3 931.3 931.9 933.8 939.2 19915.6 20387.4 21135.8 21511.7 22545.4 1040.3 1049.2 1064.8 1076.8 1084.2 10735.7 11348.3 11396.1 11405.9 11435.9 752570.0 763649.2 766467.0 767376.0 775781.0 82429.0 85142.1 88460.6 114139.0 115833.0 1082.7 1091.2 1120.4 1126.7 1128.7 18995.0 19692.2 19714.6 19773.8 19893.3 47232.0 47586.5 48029.3 48358.0 48848.8 65338.7 65908.7 65967.7 66347.7 66457.0 9048.2 9094.0 9198.1 9228.1 9473.8 10206.5 13053.1 13422.2 13618.2 14123.6 10598.2 11654.7 11716.4 11753.2 12027.3 10893.8 11383.5 11478.2 11667.1 12032.3 7952.8 10901.5 11581.7 11594.4 11678.6 8839.7 8848.0 8933.7 8988.4 9069.6 10835.9 11218.0 11686.4 11766.2 12150.7 16:58:21 INFO - PROCESS | 9456 | 16:58:21 INFO - PROCESS | 9456 | [#3] mootools.html Cycles:180 Average:5337.82 Median:5388.19 stddev:2843.90 (52.8%) stddev-sans-first:2837.24 16:58:21 INFO - PROCESS | 9456 | Values: 1498.9 1515.0 1515.2 1520.6 1531.5 7763.3 8215.0 8216.1 8216.8 8219.3 7162.1 7372.8 7387.8 7579.5 8091.1 2293.1 2385.7 2390.9 2400.2 2409.2 2428.2 2448.7 2469.6 2472.2 2474.3 2387.1 2387.1 2410.0 2418.0 2420.0 2309.8 2310.8 2327.4 2334.4 2339.9 2301.1 2381.9 2385.0 2385.3 2385.8 8136.1 8162.2 8169.9 8170.1 8189.5 5038.8 5161.0 5164.8 5174.0 5176.1 2981.8 3175.9 3182.5 3199.6 3207.6 1127.8 1149.5 1178.2 1195.5 1198.0 10531.7 10657.8 10674.6 10687.5 10698.0 4320.8 4485.7 4543.6 4548.5 4552.8 9448.5 9450.4 9470.8 9473.5 9478.9 5208.8 5213.2 5216.9 5223.2 5223.7 4719.0 4790.8 4802.8 4844.8 4888.4 3825.2 3872.1 3905.6 3934.1 3945.9 1284.5 1293.9 1296.8 1308.7 1313.6 983.3 987.6 994.3 998.2 1019.8 5552.7 5624.5 5625.4 5640.9 5720.5 652.3 652.9 655.7 665.3 674.8 3765.7 3785.5 3788.4 3830.3 3871.3 9286.8 9653.8 9656.4 9665.5 9665.9 9480.8 9570.8 9629.2 9633.9 9647.2 7394.3 7576.2 7608.3 7696.2 7709.0 5729.4 5856.9 5925.3 6039.2 6043.7 8748.0 8956.8 9007.7 9117.6 9121.9 8212.3 8608.2 8926.4 9063.2 9073.0 6624.7 6986.1 7049.9 7149.8 7309.1 7635.7 7759.3 7804.2 7857.7 7895.1 6696.7 6772.3 6779.6 6792.9 6826.9 6630.7 6658.1 6679.4 6690.3 6728.5 6007.8 6016.9 6027.0 6095.1 6112.1 4746.9 4747.8 4857.6 4866.2 4869.6 4787.1 4841.8 4971.5 4977.3 5028.4 16:58:21 INFO - PROCESS | 9456 | 16:58:21 INFO - PROCESS | 9456 | [#4] prototype.html Cycles:180 Average:4615.75 Median:5267.05 stddev:1320.88 (25.1%) stddev-sans-first:1285.65 16:58:21 INFO - PROCESS | 9456 | Values: 374.5 379.8 380.9 383.6 386.4 5225.8 5304.3 5328.9 5330.6 5336.0 5212.6 5225.9 5235.6 5266.9 5267.2 3717.8 3939.9 4138.5 4211.5 4223.6 4158.5 4162.6 4170.7 4184.6 4206.2 4120.2 4131.7 4140.5 4182.7 4189.5 4019.8 4116.9 4190.3 4194.8 4202.8 3658.3 3717.4 3752.5 3755.6 3764.6 5067.2 5103.4 5104.3 5107.9 5114.3 4627.5 4721.4 4755.6 4760.2 4770.0 5429.8 5473.0 5483.8 5484.0 5536.8 4099.0 4108.6 4131.1 4135.3 4147.8 5430.6 5538.6 5571.4 5676.5 5693.6 4195.1 4209.3 4222.5 4223.1 4231.5 4229.6 4255.7 4267.4 4288.7 4307.7 4421.4 4565.5 4737.4 4780.5 4795.1 5493.4 5500.4 5515.5 5578.3 5597.2 1359.0 1372.8 1384.5 1396.0 1401.8 910.0 937.4 947.2 950.5 952.7 5204.0 5206.8 5210.9 5245.2 5245.4 5408.6 5454.0 5455.7 5465.7 5484.3 5059.8 5152.3 5179.8 5224.2 5286.8 2861.2 2882.5 2893.8 2900.3 2903.6 5668.0 5671.9 5674.2 5693.5 5716.9 5401.8 5488.7 5555.7 5560.9 5571.3 5624.5 5625.4 5638.0 5662.4 5664.8 5266.4 5422.8 5456.8 5487.7 5510.0 5381.9 5383.9 5386.8 5397.6 5453.0 5526.0 5535.6 5717.2 5753.0 5791.6 4035.6 4051.6 4053.0 4053.9 4063.8 5533.3 5659.7 5665.3 5668.3 5673.4 5464.1 5477.7 5478.5 5479.6 5510.9 5458.5 5460.3 5461.8 5473.7 5475.4 5208.5 5418.7 5426.7 5431.1 5489.9 5461.0 5469.8 5470.7 5493.8 5506.7 5341.5 5351.7 5362.2 5385.9 5415.2 16:58:21 INFO - PROCESS | 9456 | 16:58:21 INFO - PROCESS | 9456 | [#5] yui.html Cycles:180 Average:18380.85 Median:4546.94 stddev:22902.43 (503.7%) stddev-sans-first:22928.74 16:58:21 INFO - PROCESS | 9456 | Values: 827.4 852.3 852.9 928.8 935.6 14890.9 16570.8 16723.4 16765.5 16765.8 16171.2 16541.2 16821.6 16833.7 16849.9 701.3 724.6 831.1 846.2 848.6 811.2 828.4 830.7 834.2 837.8 851.3 851.9 852.5 854.4 855.6 740.6 759.8 848.1 850.8 851.0 621.4 636.2 636.3 636.5 641.6 16740.4 16959.7 16967.1 16991.1 17016.3 15119.0 15449.4 15814.7 15890.0 16039.7 13445.5 13542.2 13656.9 13697.4 13773.7 4679.9 4699.8 4700.3 4753.8 4773.5 77218.6 77318.0 77405.6 77979.0 79373.2 11643.7 11735.9 11926.8 11927.5 11968.9 23235.5 23539.5 23654.8 23845.8 24117.0 8133.0 8193.4 8241.1 8290.1 8450.8 4399.6 4480.3 4527.3 4566.6 4630.8 3659.2 3745.0 3770.8 3778.2 3784.0 614.1 621.5 624.6 631.5 631.8 620.1 627.4 631.5 637.2 669.9 9908.4 10681.2 10846.2 10851.3 10855.6 784.4 786.4 805.8 809.3 828.5 5267.9 5337.7 5349.6 5387.8 5425.7 82983.6 84307.6 84849.6 85129.0 86510.0 71184.6 72078.6 73139.6 73470.0 75226.0 62471.0 62509.4 63457.4 64019.0 65238.3 10355.4 10362.1 10406.3 10458.6 10469.7 37202.6 37309.3 37979.2 38216.2 46458.0 52592.7 54035.0 54203.5 54977.0 56952.4 14414.2 14525.3 14574.8 14667.6 15522.9 16425.0 16641.5 16784.6 17792.7 17837.7 14670.7 15295.0 15308.5 15336.4 16257.0 14105.0 14106.7 14198.1 14409.0 14535.6 13770.0 14008.4 14246.6 14279.6 14410.5 12059.7 12177.9 12216.6 12251.6 12688.9 11222.9 11284.3 11501.6 11534.2 11539.8 16:58:21 INFO - PROCESS | 9456 | -------- Summary: end -------- 16:58:21 INFO - PROCESS | 9456 | 16:58:22 INFO - TEST-INFO | 9456: exit 0 16:58:22 INFO - TEST-OK | dromaeo_css | took 1114768ms 16:58:22 INFO - TEST-START | kraken 16:58:22 INFO - operating with platform_type : linux_ 16:58:22 INFO - Initialising browser for kraken test... 16:58:22 INFO - TEST-INFO | started process 9773 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoW8IC7/profile http://localhost:52856/getInfo.html) 16:58:27 INFO - PROCESS | 9773 | __metrics Screen width/height:1600/1200 16:58:27 INFO - PROCESS | 9773 | colorDepth:24 16:58:27 INFO - PROCESS | 9773 | Browser inner width/height: 1024/697 16:58:27 INFO - PROCESS | 9773 | __metrics 16:58:27 INFO - PROCESS | 9773 | 1486256307727 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 16:58:27 INFO - PROCESS | 9773 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:58:27 INFO - PROCESS | 9773 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:58:28 INFO - PROCESS | 9773 | console.error: PushService: 16:58:28 INFO - PROCESS | 9773 | stateChangeProcessEnqueue: Error transitioning state 16:58:28 INFO - PROCESS | 9773 | UnknownError 16:58:29 INFO - TEST-INFO | 9773: exit 0 16:58:29 INFO - Browser initialized. 16:58:29 INFO - Running cycle 1/1 for kraken test... 16:58:29 INFO - TEST-INFO | started process 9867 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoW8IC7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:58:40 INFO - PROCESS | 9867 | RSS: Main: 141619200 16:58:40 INFO - PROCESS | 9867 | 16:58:43 INFO - PROCESS | 9867 | Cycle 1(1): loaded http://localhost:52856/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52856/tests/kraken/driver.html?testName=audio-beat-detection) 16:58:44 INFO - PROCESS | 9867 | RSS: Main: 185614336 16:58:44 INFO - PROCESS | 9867 | 16:58:46 INFO - PROCESS | 9867 | Cycle 1(1): loaded http://localhost:52856/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52856/tests/kraken/driver.html?testName=audio-dft) 16:58:47 INFO - PROCESS | 9867 | RSS: Main: 175501312 16:58:47 INFO - PROCESS | 9867 | 16:58:50 INFO - PROCESS | 9867 | Cycle 1(1): loaded http://localhost:52856/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52856/tests/kraken/driver.html?testName=audio-fft) 16:58:50 INFO - PROCESS | 9867 | RSS: Main: 267657216 16:58:50 INFO - PROCESS | 9867 | 16:58:52 INFO - PROCESS | 9867 | Cycle 1(1): loaded http://localhost:52856/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52856/tests/kraken/driver.html?testName=audio-oscillator) 16:58:53 INFO - PROCESS | 9867 | RSS: Main: 174071808 16:58:53 INFO - PROCESS | 9867 | 16:58:55 INFO - PROCESS | 9867 | Cycle 1(1): loaded http://localhost:52856/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52856/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:58:56 INFO - PROCESS | 9867 | RSS: Main: 176463872 16:58:56 INFO - PROCESS | 9867 | 16:58:59 INFO - PROCESS | 9867 | Cycle 1(1): loaded http://localhost:52856/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52856/tests/kraken/driver.html?testName=imaging-darkroom) 16:58:59 INFO - PROCESS | 9867 | RSS: Main: 211955712 16:58:59 INFO - PROCESS | 9867 | 16:59:03 INFO - PROCESS | 9867 | Cycle 1(1): loaded http://localhost:52856/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52856/tests/kraken/driver.html?testName=imaging-desaturate) 16:59:03 INFO - PROCESS | 9867 | RSS: Main: 212594688 16:59:03 INFO - PROCESS | 9867 | 16:59:06 INFO - PROCESS | 9867 | Cycle 1(1): loaded http://localhost:52856/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52856/tests/kraken/driver.html?testName=json-parse-financial) 16:59:06 INFO - PROCESS | 9867 | RSS: Main: 211582976 16:59:06 INFO - PROCESS | 9867 | 16:59:08 INFO - PROCESS | 9867 | Cycle 1(1): loaded http://localhost:52856/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52856/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:59:09 INFO - PROCESS | 9867 | RSS: Main: 174272512 16:59:09 INFO - PROCESS | 9867 | 16:59:10 INFO - PROCESS | 9867 | Cycle 1(1): loaded http://localhost:52856/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52856/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:59:11 INFO - PROCESS | 9867 | RSS: Main: 181678080 16:59:11 INFO - PROCESS | 9867 | 16:59:13 INFO - PROCESS | 9867 | Cycle 1(1): loaded http://localhost:52856/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52856/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:59:14 INFO - PROCESS | 9867 | RSS: Main: 188194816 16:59:14 INFO - PROCESS | 9867 | 16:59:16 INFO - PROCESS | 9867 | Cycle 1(1): loaded http://localhost:52856/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52856/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:59:17 INFO - PROCESS | 9867 | RSS: Main: 197783552 16:59:17 INFO - PROCESS | 9867 | 16:59:19 INFO - PROCESS | 9867 | Cycle 1(1): loaded http://localhost:52856/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52856/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:59:20 INFO - PROCESS | 9867 | RSS: Main: 193609728 16:59:20 INFO - PROCESS | 9867 | 16:59:22 INFO - PROCESS | 9867 | Cycle 1(1): loaded http://localhost:52856/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52856/tests/kraken/driver.html?testName=ai-astar) 16:59:22 INFO - PROCESS | 9867 | RSS: Main: 245460992 16:59:22 INFO - PROCESS | 9867 | 16:59:22 INFO - PROCESS | 9867 | __start_tp_report 16:59:22 INFO - PROCESS | 9867 | _x_x_mozilla_page_load 16:59:22 INFO - PROCESS | 9867 | _x_x_mozilla_page_load_details 16:59:22 INFO - PROCESS | 9867 | |i|pagename|runs| 16:59:22 INFO - PROCESS | 9867 | |0;ai-astar;111;111;113;112;112;113;111;111;112;112 16:59:22 INFO - PROCESS | 9867 | |1;audio-beat-detection;139;118;116;152;118;121;117;126;138;118 16:59:22 INFO - PROCESS | 9867 | |2;audio-dft;160;160;160;159;158;161;160;161;161;159 16:59:22 INFO - PROCESS | 9867 | |3;audio-fft;76;76;76;118;75;76;76;75;104;75 16:59:22 INFO - PROCESS | 9867 | |4;audio-oscillator;83;82;105;82;82;83;94;82;82;110 16:59:22 INFO - PROCESS | 9867 | |5;imaging-gaussian-blur;117;118;116;118;118;117;117;117;117;117 16:59:22 INFO - PROCESS | 9867 | |6;imaging-darkroom;147;147;147;147;147;150;147;147;146;147 16:59:22 INFO - PROCESS | 9867 | |7;imaging-desaturate;96;95;96;95;96;95;95;96;97;96 16:59:22 INFO - PROCESS | 9867 | |8;json-parse-financial;48;48;49;48;49;49;51;53;48;48 16:59:22 INFO - PROCESS | 9867 | |9;json-stringify-tinderbox;63;65;64;65;64;64;64;65;64;64 16:59:22 INFO - PROCESS | 9867 | |10;stanford-crypto-aes;77;83;77;77;79;81;83;83;76;81 16:59:22 INFO - PROCESS | 9867 | |11;stanford-crypto-ccm;132;122;121;127;128;135;132;120;159;122 16:59:22 INFO - PROCESS | 9867 | |12;stanford-crypto-pbkdf2;165;162;158;158;159;163;163;162;156;164 16:59:22 INFO - PROCESS | 9867 | |13;stanford-crypto-sha256-iterative;63;62;64;62;62;60;64;62;61;62 16:59:22 INFO - PROCESS | 9867 | __end_tp_report 16:59:22 INFO - PROCESS | 9867 | __start_cc_report 16:59:22 INFO - PROCESS | 9867 | _x_x_mozilla_cycle_collect,1529 16:59:22 INFO - PROCESS | 9867 | __end_cc_report 16:59:22 INFO - PROCESS | 9867 | __startTimestamp1486256362475__endTimestamp 16:59:22 INFO - PROCESS | 9867 | 16:59:22 INFO - PROCESS | 9867 | ------- Summary: start ------- 16:59:22 INFO - PROCESS | 9867 | Number of tests: 14 16:59:22 INFO - PROCESS | 9867 | 16:59:22 INFO - PROCESS | 9867 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.00 stddev:0.79 (0.7%) stddev-sans-first:0.78 16:59:22 INFO - PROCESS | 9867 | Values: 111.0 111.0 113.0 112.0 112.0 113.0 111.0 111.0 112.0 112.0 16:59:22 INFO - PROCESS | 9867 | 16:59:22 INFO - PROCESS | 9867 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:123.50 stddev:12.41 (10.0%) stddev-sans-first:12.28 16:59:22 INFO - PROCESS | 9867 | Values: 139.0 118.0 116.0 152.0 118.0 121.0 117.0 126.0 138.0 118.0 16:59:22 INFO - PROCESS | 9867 | 16:59:22 INFO - PROCESS | 9867 | [#2] audio-dft Cycles:10 Average:159.90 Median:160.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 16:59:22 INFO - PROCESS | 9867 | Values: 160.0 160.0 160.0 159.0 158.0 161.0 160.0 161.0 161.0 159.0 16:59:22 INFO - PROCESS | 9867 | 16:59:22 INFO - PROCESS | 9867 | [#3] audio-fft Cycles:10 Average:82.70 Median:76.00 stddev:15.28 (20.1%) stddev-sans-first:16.02 16:59:22 INFO - PROCESS | 9867 | Values: 76.0 76.0 76.0 118.0 75.0 76.0 76.0 75.0 104.0 75.0 16:59:22 INFO - PROCESS | 9867 | 16:59:22 INFO - PROCESS | 9867 | [#4] audio-oscillator Cycles:10 Average:88.50 Median:82.00 stddev:10.73 (13.1%) stddev-sans-first:11.20 16:59:22 INFO - PROCESS | 9867 | Values: 83.0 82.0 105.0 82.0 82.0 83.0 94.0 82.0 82.0 110.0 16:59:22 INFO - PROCESS | 9867 | 16:59:22 INFO - PROCESS | 9867 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 16:59:22 INFO - PROCESS | 9867 | Values: 117.0 118.0 116.0 118.0 118.0 117.0 117.0 117.0 117.0 117.0 16:59:22 INFO - PROCESS | 9867 | 16:59:22 INFO - PROCESS | 9867 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:1.03 (0.7%) stddev-sans-first:1.09 16:59:22 INFO - PROCESS | 9867 | Values: 147.0 147.0 147.0 147.0 147.0 150.0 147.0 147.0 146.0 147.0 16:59:22 INFO - PROCESS | 9867 | 16:59:22 INFO - PROCESS | 9867 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 16:59:22 INFO - PROCESS | 9867 | Values: 96.0 95.0 96.0 95.0 96.0 95.0 95.0 96.0 97.0 96.0 16:59:22 INFO - PROCESS | 9867 | 16:59:22 INFO - PROCESS | 9867 | [#8] json-parse-financial Cycles:10 Average:49.10 Median:49.00 stddev:1.66 (3.4%) stddev-sans-first:1.72 16:59:22 INFO - PROCESS | 9867 | Values: 48.0 48.0 49.0 48.0 49.0 49.0 51.0 53.0 48.0 48.0 16:59:22 INFO - PROCESS | 9867 | 16:59:22 INFO - PROCESS | 9867 | [#9] json-stringify-tinderbox Cycles:10 Average:64.20 Median:64.00 stddev:0.63 (1.0%) stddev-sans-first:0.50 16:59:22 INFO - PROCESS | 9867 | Values: 63.0 65.0 64.0 65.0 64.0 64.0 64.0 65.0 64.0 64.0 16:59:22 INFO - PROCESS | 9867 | 16:59:22 INFO - PROCESS | 9867 | [#10] stanford-crypto-aes Cycles:10 Average:79.70 Median:81.00 stddev:2.83 (3.5%) stddev-sans-first:2.83 16:59:22 INFO - PROCESS | 9867 | Values: 77.0 83.0 77.0 77.0 79.0 81.0 83.0 83.0 76.0 81.0 16:59:22 INFO - PROCESS | 9867 | 16:59:22 INFO - PROCESS | 9867 | [#11] stanford-crypto-ccm Cycles:10 Average:129.80 Median:130.00 stddev:11.53 (8.9%) stddev-sans-first:12.20 16:59:22 INFO - PROCESS | 9867 | Values: 132.0 122.0 121.0 127.0 128.0 135.0 132.0 120.0 159.0 122.0 16:59:22 INFO - PROCESS | 9867 | 16:59:22 INFO - PROCESS | 9867 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.00 Median:162.50 stddev:3.02 (1.9%) stddev-sans-first:2.83 16:59:22 INFO - PROCESS | 9867 | Values: 165.0 162.0 158.0 158.0 159.0 163.0 163.0 162.0 156.0 164.0 16:59:22 INFO - PROCESS | 9867 | 16:59:22 INFO - PROCESS | 9867 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.00 stddev:1.23 (2.0%) stddev-sans-first:1.27 16:59:22 INFO - PROCESS | 9867 | Values: 63.0 62.0 64.0 62.0 62.0 60.0 64.0 62.0 61.0 62.0 16:59:22 INFO - PROCESS | 9867 | -------- Summary: end -------- 16:59:22 INFO - PROCESS | 9867 | 16:59:23 INFO - TEST-INFO | 9867: exit 0 16:59:23 INFO - TEST-OK | kraken | took 60886ms 16:59:23 INFO - SUITE-END | took 1175s 16:59:23 INFO - Completed test suite (00:19:35) 16:59:23 INFO - javascript benchmark 16:59:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3269.918252043699, 3297.406466835969, 3308.858443836876, 3310.8675652973884, 3357.244619960509, 5358.169483730018, 5450.3459584849825, 5451.536619387357, 5458.4541545845395, 5465.781368745255, 5434.510894019538, 5467.289252397193, 5467.890642187163, 5468.9179662305, 5471.671699698021, 4960.379952505928, 4973.303737476756, 5032.194848824189, 5053.620978426614, 5058.038972595195, 4844.733539655317, 4863.14894893394, 5014.423341315741, 5049.141645920203, 5049.2173713074535, 4926.285688575154, 4954.925676114861, 4962.3548938638105, 5017.322661440695, 5041.243813427996, 5055.494450554952, 5057.0897238497055, 5060.1650727630085, 5061.215511595708, 5087.084324821531, 4855.858873164803, 4981.028699403618, 4992.700437973734, 5008.524190201925, 5051.823186188502, 5266.92099618506, 5273.367195936474, 5326.44072372403, 5331.893362132736, 5396.43337449566, 5752.0, 5756.942430575725, 5762.250907382056, 5763.596548241625, 5767.653940763567, 5796.710164491801, 5837.474627283523, 5849.239598852165, 5871.2073870027425, 5873.060310350324, 5287.4976877196605, 5314.362276526842, 5324.760385782649, 5348.759305831247, 5411.56707463402, 5850.0932355484965, 5859.44335288147, 5861.88276234473, 5865.736041878125, 5888.146218798251, 5599.776008959596, 5642.915356269659, 5648.830535083954, 5650.5479561634975, 5656.377798442168, 5696.629719068247, 5731.455511726379, 5741.0, 5749.88500229993, 5758.136279558016, 5727.3699893011735, 5733.569982251348, 5790.913136302959, 5818.359980402152, 5818.505427038749, 5804.100364443518, 5806.622569532967, 5807.593468457168, 5811.0992796115825, 5811.94188058114, 4371.5628437156665, 4441.222786012435, 4442.68901176921, 4443.244648409778, 4470.508244093147, 3877.476540666974, 3936.7637941723588, 3951.209758048402, 3973.443717879499, 4031.0, 5663.801766938137, 5675.77296908119, 5677.545796336283, 5677.943220567824, 5678.290213723285, 5732.226149469769, 5798.362180160263, 5802.709864506758, 5876.706164691748, 5913.497352725067, 5550.666959982414, 5569.693666848281, 5589.133684278943, 5684.9431505684415, 5694.373618901917, 5025.0, 5027.195648696191, 5030.798768049237, 5032.043911656773, 5034.723090230073, 6099.451049405486, 6113.8165855023535, 6117.355800906391, 6123.203983482119, 6140.473573783631, 5677.801276955287, 5716.885662286814, 5768.192453056491, 5794.1308803679785, 5796.101604251347, 6002.429769171922, 6057.878842423215, 6069.605475644069, 6073.453389194994, 6090.482309003784, 5708.0, 5751.051076572486, 5752.395998420273, 5755.769769209185, 5760.222369980167, 5664.546836253007, 5708.600397972102, 5770.480656740913, 5811.738471768866, 5852.7658893645485, 5749.655020698771, 5753.309602847517, 5757.510611597893, 5776.220210271559, 5795.536357091339, 5750.166225897138, 5755.65466072037, 5757.942420575908, 5758.3090029196765, 5763.0, 5561.972190139023, 5575.972120139373, 5593.608447408805, 5722.685252311163, 5725.971370143123, 5677.347105082803, 5678.801241956512, 5753.654780713171, 5757.366689664217, 5761.539076873756, 5619.297587801525, 5621.52217061538, 5628.634138780966, 5631.493165615114, 5635.295588051494], "value": 5379.597602413794, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.9144624029516, 615.8675884684793, 653.875763604915, 658.4271683635235, 663.6515829189672, 45942.54057459424, 47475.999999999956, 47479.525204747944, 53719.19421208675, 53866.46133538664, 50847.49152508474, 51542.0, 51566.0, 51576.742116289366, 51739.0, 8809.691660791874, 9298.349115561914, 9521.285903557226, 9528.904710952871, 9536.761580960461, 8703.042665306812, 11150.944245278763, 11399.3730344831, 11430.142739294544, 11863.0, 11683.766324673501, 11733.237339572901, 11743.177977541576, 11743.941280293586, 11751.471183796706, 11260.04289635382, 11289.209755317132, 11311.0, 11326.546938122465, 11327.037201837853, 11472.196946213768, 11552.249103808226, 11562.653120406401, 11564.074874010059, 11567.710807229803, 47270.0, 47640.761796191146, 47708.52291477076, 47765.761171194266, 47800.521994779956, 4363.432753742025, 4416.447944006999, 4439.75581343026, 4496.595306422405, 4547.0, 34259.31481370359, 34339.14152146191, 34593.13517162066, 35142.0, 35986.0, 4498.79755411004, 4502.729836209838, 4533.750643714594, 4555.772211389417, 4571.451425828888, 337559.0, 338080.0, 338901.0, 339078.0, 340129.0, 71578.64210678914, 71941.0, 71951.0, 72001.6399917997, 72160.63919680368, 73793.6310318445, 73802.63098684579, 73869.6306518464, 74072.0, 74103.0, 20448.795512044988, 21073.683894741593, 21096.261630842848, 21096.683549746765, 21113.26103586367, 14678.41286348534, 14843.257837108102, 15720.371185152695, 15888.364465421484, 15965.042097474188, 19721.704174437396, 21067.0, 21249.68125478119, 21269.36191914245, 21323.0, 7448.180700122982, 7619.180833154296, 7622.352100071556, 7640.503367281109, 7690.0, 1005.6882366466418, 1012.7620009297809, 1018.4653057145058, 1019.7399663085865, 1030.3096925060227, 34305.0, 34469.0, 34744.47883281753, 34976.82511587426, 35103.4734478983, 1098.9998203269972, 1165.1901928159848, 1174.301290732013, 1198.7362780188344, 1206.252123683322, 18263.269469221083, 18290.17694203764, 18323.450296491126, 18380.632387352445, 18456.723149152775, 566855.0, 569063.0, 569711.0, 570465.0, 571263.0, 70526.64736676283, 70697.93953090708, 70934.0, 70978.0, 71053.644731776, 1536.8232653244797, 1548.3729089718681, 1575.2911189964561, 1588.4043483693615, 1589.4516391844795, 32311.51532727058, 34052.0, 34078.82960585181, 34102.317953641286, 34147.14632134217, 59791.40208598032, 60448.0, 60508.69745651243, 60513.69743151256, 60562.0, 423685.0, 423764.0, 425223.0, 426228.0, 426329.0, 40961.590384095776, 41145.38281925833, 45980.080398392514, 49430.0, 49801.750991244815, 48984.0, 54741.726291368286, 55965.72017139888, 56155.0, 56788.148177778196, 45170.322445164005, 46088.308675370565, 46172.769136155446, 47306.52693473021, 47914.5208547924, 45001.0, 46177.0, 46323.768381157875, 46452.0, 47922.5207747918, 46080.769596151804, 46560.53439465562, 46623.76688116673, 46784.298235527174, 46909.2963605553, 38980.0, 40609.1878162441, 40906.38640420337, 44264.55735442733, 44947.550524494334, 30870.69129308678, 30974.690253097182, 31081.222969425944, 31492.370152596362, 31669.841650791597], "value": 23010.854864134515, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.1093958157659, 433.57199681782026, 439.77571438566343, 445.39203986558346, 456.1879853860128, 20621.793782062174, 21785.564288714217, 22263.0, 22386.552268954612, 22739.658905116437, 21725.23961661342, 22447.775522244774, 22646.094156233732, 22730.204442844464, 23040.42398940023, 5487.874985627956, 5866.941330586704, 5981.28224613047, 6023.30731965823, 6067.453929146376, 5879.970600146993, 5938.8218353449465, 5983.491403230731, 5993.101034844786, 5994.070919007539, 5773.769049238025, 5862.413758624146, 5913.704314784265, 5921.911171332434, 5957.434043765836, 5861.970690146543, 5898.911516327258, 5923.170756094129, 5928.051511758098, 5940.198073260098, 5308.814191503279, 5402.972985135049, 5601.271834661508, 5638.182463542805, 5741.425857414267, 14831.925840370837, 14896.0, 14949.327280272306, 15046.924765376103, 15066.924665376713, 2731.617573539706, 2731.740484653955, 2738.52075886719, 2752.862356882148, 2754.311422144464, 10985.0, 10989.945050274777, 11098.112151027899, 11469.827952580717, 11528.077753779677, 1947.5228569000544, 1992.7409436773128, 1995.281698588507, 1997.0, 2008.8694234874686, 89999.55000224957, 91559.54220228923, 93833.53083234541, 94173.0, 95309.52345238299, 12025.579104731292, 12239.326837023871, 12305.50777968889, 12382.442790074469, 12591.811122833165, 17635.382761603283, 17644.382446614305, 17689.823101768816, 17695.0, 17699.557511062325, 9337.299702522203, 9410.576524056434, 9470.621175152917, 9507.049295070437, 9603.279754018477, 9052.683156089637, 9189.402688825205, 9198.908010919939, 9215.815683686289, 9456.007119252517, 5277.577793776489, 5277.973610131925, 5300.734963251822, 5317.1226747586215, 5383.838484845461, 2040.9387718368473, 2062.9277975270793, 2090.153487837428, 2141.1542440736134, 2151.9462013449474, 912.3066469483108, 931.3201363005044, 931.8928323242778, 933.7618907178626, 939.2204470289644, 19915.60168796616, 20387.388378348674, 21135.78864211369, 21511.67732484014, 22545.43636409103, 1040.323789536792, 1049.2026060194307, 1064.8030114428855, 1076.822324316479, 1084.1543595995136, 10735.731606709895, 11348.319100853974, 11396.088312934946, 11405.942970285261, 11435.94282028601, 752570.0, 763649.1796394626, 766467.0, 767376.0, 775781.0, 82429.0, 85142.14857851467, 88460.5576972111, 114139.0, 115833.0, 1082.723905404117, 1091.2252300866314, 1120.3725913488472, 1126.7014241226082, 1128.6501184632791, 18995.0, 19692.21231150738, 19714.60570788605, 19773.802261977195, 19893.303734369227, 47232.0, 47586.52413475821, 48029.279560807314, 48358.0, 48848.75575622099, 65338.673306633165, 65908.6704566474, 65967.6701616508, 66347.66826165839, 66457.0, 9048.230900373543, 9094.0, 9198.126178013077, 9228.077192280718, 9473.810523789347, 10206.489675516046, 13053.086283960032, 13422.194668319931, 13618.18290902549, 14123.64690882736, 10598.15214782831, 11654.650360489199, 11716.355600441717, 11753.236039657395, 12027.338496382434, 10893.782124357627, 11383.54465821358, 11478.196526243417, 11667.124965627441, 12032.338221397908, 7952.801179970547, 10901.454927253448, 11581.7104572383, 11594.362310073027, 11678.591249306233, 8839.734807955514, 8848.0, 8933.73198804011, 8988.370814043215, 9069.591868366071, 10835.945820270848, 11218.0, 11686.357250350971, 11766.176367654181, 12150.696232594255], "value": 9839.468829824616, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1498.9100653960763, 1514.9697006059873, 1515.1666583379147, 1520.5514373259891, 1531.471642283412, 7763.340116090132, 8214.958925205381, 8216.13730558291, 8216.84964105025, 8219.342452603792, 7162.1405431348185, 7372.815679608012, 7387.817949128141, 7579.4694371394025, 8091.069527004396, 2293.128611127769, 2385.666006759055, 2390.8685022323766, 2400.1719406804623, 2409.1567951217107, 2428.2229686500327, 2448.6571879936823, 2469.64190192422, 2472.24596498068, 2474.307193985687, 2387.1048356866177, 2387.140629373424, 2409.987950060256, 2417.9516409671796, 2420.007796803311, 2309.815214782813, 2310.8151347892126, 2327.3832434404894, 2334.4397344637337, 2339.894704738291, 2301.0910690277246, 2381.868997205153, 2385.0340612051973, 2385.33210701004, 2385.773351531619, 8136.145704700981, 8162.224588664068, 8169.877451838227, 8170.101288858221, 8189.549574773385, 5038.823641172578, 5161.0, 5164.819231326923, 5174.0, 5176.0683077046115, 2981.757120740782, 3175.857086431116, 3182.5067114597273, 3199.6160460744627, 3207.6471588125287, 1127.8421021057059, 1149.4769879704797, 1178.1635039122234, 1195.48594104535, 1197.9760404791855, 10531.73670658232, 10657.84013239802, 10674.573017079307, 10687.465626718633, 10698.0, 4320.783960801948, 4485.663575231871, 4543.568361005699, 4548.476925153584, 4552.772361381918, 9448.480333581581, 9450.385724927857, 9470.810583788285, 9473.47895865734, 9478.857817132748, 5208.817691380784, 5213.244079608436, 5216.921746173811, 5223.216517522402, 5223.712695801767, 4718.976405118021, 4790.784414701347, 4802.783874725646, 4844.8304309349005, 4888.364512613338, 3825.2349530094093, 3872.0900588362274, 3905.5508616509464, 3934.09515811365, 3945.940810887839, 1284.537566476068, 1293.9417726202344, 1296.786030305008, 1308.738252349534, 1313.6124843171287, 983.3362480325751, 987.6444479987199, 994.3139233928711, 998.2164001258977, 1019.8470229465639, 5552.666839989613, 5624.521915637217, 5625.353084395347, 5640.85897852557, 5720.513756330675, 652.2857471069171, 652.9347065293528, 655.7180412422704, 665.3246954341347, 674.844785699292, 3765.6987441005203, 3785.488958990556, 3788.3749181385315, 3830.3296923038356, 3871.322518559241, 9286.814263714688, 9653.758656033655, 9656.420614763138, 9665.516724163763, 9665.855012174823, 9480.762980925532, 9570.760730981781, 9629.229661627052, 9633.9036609633, 9647.228221742243, 7394.26057394267, 7576.24237576238, 7608.277213664693, 7696.15342312334, 7709.036370453693, 5729.369769325454, 5856.94143058564, 5925.288965324189, 6039.154518367344, 6043.667598282137, 8747.956260218658, 8956.776080598038, 9007.729768106967, 9117.635294588408, 9121.908780912107, 8212.30195433371, 8608.182222688834, 8926.419782714103, 9063.184313411824, 9072.95463522678, 6624.735010599715, 6986.091808064919, 7049.929500704928, 7149.82125446868, 7309.086364204474, 7635.694572217049, 7759.30166285037, 7804.180561041236, 7857.685692572233, 7895.052593688794, 6696.698648560729, 6772.288909664611, 6779.559328643622, 6792.93207067923, 6826.8634627308165, 6630.668466576555, 6658.1344425224415, 6679.365460281266, 6690.330966903271, 6728.461723062045, 6007.8197654070445, 6016.939830601638, 6027.035674292092, 6095.116208149882, 6112.113743507255, 4746.857594272177, 4747.810087596457, 4857.611391088775, 4866.197077482176, 4869.586085182799, 4787.114383839001, 4841.830535931225, 4971.527704868032, 4977.253411988231, 5028.421731500925], "value": 4359.311114646096, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.52061361457345, 379.83477187423455, 380.9276237514872, 383.5627384781348, 386.37793153023637, 5225.817096401622, 5304.310439642846, 5328.893422131555, 5330.626856120073, 5336.0, 5212.556932660719, 5225.928684619654, 5235.58115350772, 5266.868328291795, 5267.23625074364, 3717.7769333840056, 3939.9409008864745, 4138.544760076389, 4211.452511173543, 4223.598758117974, 4158.542560318362, 4162.646175075122, 4170.749755014694, 4184.581541845821, 4206.221848957937, 4120.196561670476, 4131.727549847572, 4140.461739973799, 4182.6862985276075, 4189.533663217864, 4019.77891215983, 4116.938245926283, 4190.28765109932, 4194.832206711743, 4202.8022013726095, 3658.304922064826, 3717.423799311111, 3752.5496940367057, 3755.586885442599, 3764.5858955514873, 5067.163917953533, 5103.438621751641, 5104.25988231708, 5107.901801112742, 5114.28400023997, 4627.537246275379, 4721.3862197914395, 4755.643326750507, 4760.214564596838, 4769.976150119227, 5429.837104886859, 5473.0, 5483.75323110461, 5484.0, 5536.750846211889, 4098.959010409888, 4108.569344597265, 4131.111810960658, 4135.31767258402, 4147.834086636531, 5430.642339415146, 5538.584606154554, 5571.4428557144365, 5676.460736230051, 5693.601447898649, 4195.139996300751, 4209.34755112958, 4222.451081359403, 4223.070924396628, 4231.492220933507, 4229.555896630871, 4255.723377980421, 4267.402563641031, 4288.656907447396, 4307.6769242306955, 4421.447319085114, 4565.539027511231, 4737.431508218967, 4780.49804770501, 4795.136875362398, 5493.395726470085, 5500.3949565547755, 5515.503604675519, 5578.2748242728185, 5597.188407680864, 1358.9796153057712, 1372.848986611472, 1384.515419603151, 1396.015808854755, 1401.8317801863639, 910.006666893753, 937.3579098317674, 947.1806887042659, 950.5104870991445, 952.7427594549433, 5204.0, 5206.817761378334, 5210.947890521122, 5245.213218017328, 5245.449227831099, 5408.621396502207, 5453.973044960086, 5455.672659640434, 5465.726713664301, 5484.287042684426, 5059.848204553869, 5152.252923326172, 5179.81870634526, 5224.190250511177, 5286.841394758163, 2861.1988643179943, 2882.466743652389, 2893.8119022263486, 2900.3329234276234, 2903.6225290712073, 5668.0, 5671.914921276184, 5674.2339784129435, 5693.505766934928, 5716.857078573069, 5401.756920938567, 5488.694748205763, 5555.694436805934, 5560.86097847549, 5571.275734154534, 5624.493795558419, 5625.409332020243, 5638.0, 5662.37713851476, 5664.801731939362, 5266.368035835724, 5422.752766863565, 5456.753231827057, 5487.670739755627, 5510.008198524144, 5381.8923621526565, 5383.946160538345, 5386.841829006626, 5397.595180361567, 5452.972735136299, 5526.005319042611, 5535.640183388067, 5717.1995920570325, 5752.971235143965, 5791.594588378773, 4035.596440355941, 4051.5948405159247, 4052.979735101306, 4053.858114965962, 4063.817128229296, 5533.253010843646, 5659.660420374791, 5665.263515742927, 5668.291463567054, 5673.4114447954635, 5464.1257398816, 5477.685355514727, 5478.452154784649, 5479.561635069105, 5510.889782204414, 5458.454154584669, 5460.31746031746, 5461.836144915659, 5473.658953556404, 5475.4250803665045, 5208.5312321891215, 5418.674879507243, 5426.67439953604, 5431.131019036935, 5489.945100548944, 5460.972695136499, 5469.808556700656, 5470.699111548903, 5493.775021116454, 5506.724663766876, 5341.5459685927135, 5351.705656188947, 5362.168863826113, 5385.919211211756, 5415.241866138665], "value": 4206.109258202537, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.3794654009492, 852.2500199824154, 852.9488230706158, 928.7678080479878, 935.602368993178, 14890.92554537227, 16570.83429165708, 16723.414680486185, 16765.497035088967, 16765.832341676578, 16171.191440427961, 16541.172941352943, 16821.57946051346, 16833.747493787603, 16849.91575042126, 701.340739704676, 724.5652608434949, 831.0733532111682, 846.1622993236682, 848.6308455821707, 811.2414892075899, 828.4159667434475, 830.7383174300077, 834.2366734437982, 837.811492414208, 851.3231980575457, 851.902031266403, 852.4501696405814, 854.4360721923532, 855.589317127779, 740.6333864736989, 759.8062494063978, 848.0798333807795, 850.808568072185, 850.9574521273912, 621.4438077920281, 636.2206297285788, 636.2937139774846, 636.5416899832113, 641.5829710688043, 16740.41408550707, 16959.745603815954, 16967.06681132537, 16991.065491397967, 17016.319347226097, 15119.0, 15449.382024718998, 15814.68370632581, 15890.046597204204, 16039.679206415807, 13445.462181512728, 13542.187468751907, 13656.93171534136, 13697.383617737225, 13773.724525509533, 4679.859604211879, 4699.812007519695, 4700.29495575663, 4753.809847606091, 4773.498782627808, 77218.61390693067, 77318.0, 77405.61297193535, 77978.99999999943, 79373.20626793716, 11643.650690479299, 11735.88264117365, 11926.821097683542, 11927.499900521207, 11968.88031119695, 23235.53528929412, 23539.529209415716, 23654.763452365256, 23845.761542384702, 24117.0, 8132.959335203404, 8193.426460147732, 8241.093479717227, 8290.08809031006, 8450.830983380298, 4399.6040356368085, 4480.283154695233, 4527.298268768346, 4566.58900698932, 4630.814767409266, 3659.1766852457986, 3744.9812750936617, 3770.849166033328, 3778.2065766189407, 3784.016155799512, 614.1402037148029, 621.5431657731564, 624.6408315218767, 631.4758750237293, 631.7504585688628, 620.1194304088206, 627.3945642455053, 631.4916492223712, 637.171676820132, 669.9296573859772, 9908.355956862924, 10681.198910081775, 10846.240763146505, 10851.348919064882, 10855.620053298097, 784.3607459920261, 786.4337676872757, 805.769905729863, 809.3039985612369, 828.5235989306173, 5267.91747823668, 5337.706426146521, 5349.598780091509, 5387.8114266001285, 5425.728713564306, 82983.5850820742, 84307.5784621073, 84849.57575212084, 85129.0, 86510.0, 71184.64407677928, 72078.63960680163, 73139.63430182815, 73470.0, 75226.0, 62471.0, 62509.374906251265, 63457.365426345146, 64019.0, 65238.34761652323, 10355.43045132525, 10362.067413932782, 10406.323588966692, 10458.633947812094, 10469.738256543647, 37202.627973719915, 37309.25381492409, 37979.24041519099, 38216.235675286895, 46458.0, 52592.737036314575, 54035.0, 54203.45796541984, 54977.0, 56952.43047569637, 14414.207218603078, 14525.346359414063, 14574.781378279122, 14667.559973200821, 15522.922385388001, 16425.0, 16641.50075497737, 16784.58038549046, 17792.733109003115, 17837.73243401376, 14670.706585868436, 15295.0, 15308.464203752816, 15336.386544538094, 16257.0, 14105.0, 14106.717865642835, 14198.077124986843, 14409.0, 14535.636609084857, 13770.0, 14008.369623367176, 14246.572602821932, 14279.57161285163, 14410.495632652806, 12059.683587013642, 12177.939110304393, 12216.572419965258, 12251.571195008488, 12688.936555317165, 11222.943885280521, 11284.333343288412, 11501.597444089417, 11534.192606517463, 11539.8269025963], "value": 7645.407677747367, "unit": "score"}], "name": "dromaeo_css", "value": 7448.482564059789, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 113.0, 112.0, 112.0, 113.0, 111.0, 111.0, 112.0, 112.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 118.0, 116.0, 152.0, 118.0, 121.0, 117.0, 126.0, 138.0, 118.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 159.0, 158.0, 161.0, 160.0, 161.0, 161.0, 159.0], "value": 159.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 118.0, 75.0, 76.0, 76.0, 75.0, 104.0, 75.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 105.0, 82.0, 82.0, 83.0, 94.0, 82.0, 82.0, 110.0], "value": 88.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 116.0, 118.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 147.0, 150.0, 147.0, 147.0, 146.0, 147.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0, 97.0, 96.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 48.0, 49.0, 48.0, 49.0, 49.0, 51.0, 53.0, 48.0, 48.0], "value": 49.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 64.0, 65.0, 64.0, 64.0, 64.0, 65.0, 64.0, 64.0], "value": 64.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 83.0, 77.0, 77.0, 79.0, 81.0, 83.0, 83.0, 76.0, 81.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 122.0, 121.0, 127.0, 128.0, 135.0, 132.0, 120.0, 159.0, 122.0], "value": 129.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 158.0, 158.0, 159.0, 163.0, 163.0, 162.0, 156.0, 164.0], "value": 161.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 64.0, 62.0, 62.0, 60.0, 64.0, 62.0, 61.0, 62.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1475.3000000000002, "alertThreshold": 2.0}]} 16:59:23 INFO - Return code: 0 16:59:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:59:23 ERROR - # TBPL SUCCESS # 16:59:23 INFO - Running post-action listener: _resource_record_post_action 16:59:23 INFO - [mozharness: 2017-02-05 00:59:23.812718Z] Finished run-tests step (success) 16:59:23 INFO - Running post-run listener: _resource_record_post_run 16:59:23 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 266240; Write bytes: 359862272; Read time: 40; Write time: 502184 16:59:23 INFO - TinderboxPrint: CPU usage
13.1% 16:59:23 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 40 16:59:23 INFO - TinderboxPrint: I/O write bytes / time
359,862,272 / 502,184 16:59:23 INFO - TinderboxPrint: CPU idle
8,269.3 (86.9%) 16:59:23 INFO - TinderboxPrint: CPU user
1,192.5 (12.5%) 16:59:23 INFO - TinderboxPrint: Swap in / out
0 / 0 16:59:23 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 110190592; Read time: 0; Write time: 257852 16:59:23 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 266240; Write bytes: 249671680; Read time: 40; Write time: 244332 16:59:24 INFO - Running post-run listener: _upload_blobber_files 16:59:24 INFO - Blob upload gear active. 16:59:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:59:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:59:24 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'] 16:59:24 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 16:59:24 INFO - (blobuploader) - INFO - Open directory for files ... 16:59:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:59:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:59:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:59:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:59:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:59:25 INFO - (blobuploader) - INFO - Done attempting. 16:59:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:59:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:59:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:59:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:59:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:59:28 INFO - (blobuploader) - INFO - Done attempting. 16:59:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:59:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:59:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:59:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:59:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:59:29 INFO - (blobuploader) - INFO - Done attempting. 16:59:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:59:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:59:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:59:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:59:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:59:30 INFO - (blobuploader) - INFO - Done attempting. 16:59:30 INFO - (blobuploader) - INFO - Iteration through files over. 16:59:30 INFO - Return code: 0 16:59:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:59:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:59:30 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed85560b709aded4a8479e330f17088c00ea4e4f1c87d1f6da623fc4d6f8bd69de65ec3d362bce1297bb60ad3842efd3c247602217a664b8eb5ec266220e99c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/843d60f5961b502a93edde36f704a284634cf43c15824baa2de1962c09a7b43a3b933914deba4adba3841afc7d00efcfb115aa5e396a7df30c56563db27ee456", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef0a7b35dfb402844d00edd4d0ca984f136cd19e63f5db5e43fe858867082d94ddefde8de861d4bd345adbd1737861fd0860a3714d1b5d8173b38f60ea712c28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb0cf270ef16bb90192cee69eed7dcbda49e1a5f0c40aa563108e0716c33de5f5bdcbf60989cf719dbbfcc291179bd932c775a3a1553469c388d4ce83dc8604f"} 16:59:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:59:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:59:30 INFO - Contents: 16:59:30 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed85560b709aded4a8479e330f17088c00ea4e4f1c87d1f6da623fc4d6f8bd69de65ec3d362bce1297bb60ad3842efd3c247602217a664b8eb5ec266220e99c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/843d60f5961b502a93edde36f704a284634cf43c15824baa2de1962c09a7b43a3b933914deba4adba3841afc7d00efcfb115aa5e396a7df30c56563db27ee456", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef0a7b35dfb402844d00edd4d0ca984f136cd19e63f5db5e43fe858867082d94ddefde8de861d4bd345adbd1737861fd0860a3714d1b5d8173b38f60ea712c28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb0cf270ef16bb90192cee69eed7dcbda49e1a5f0c40aa563108e0716c33de5f5bdcbf60989cf719dbbfcc291179bd932c775a3a1553469c388d4ce83dc8604f"} 16:59:30 INFO - Running post-run listener: copy_logs_to_upload_dir 16:59:30 INFO - Copying logs to upload dir... 16:59:30 INFO - mkdir: /builds/slave/test/build/upload/logs 16:59:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.591788 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-02-04 16:59:29.657363) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-04 16:59:29.661097) ========= 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=745a4373a2a16df560f216e000000264-1486245375.767225-598311644 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed85560b709aded4a8479e330f17088c00ea4e4f1c87d1f6da623fc4d6f8bd69de65ec3d362bce1297bb60ad3842efd3c247602217a664b8eb5ec266220e99c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/843d60f5961b502a93edde36f704a284634cf43c15824baa2de1962c09a7b43a3b933914deba4adba3841afc7d00efcfb115aa5e396a7df30c56563db27ee456", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef0a7b35dfb402844d00edd4d0ca984f136cd19e63f5db5e43fe858867082d94ddefde8de861d4bd345adbd1737861fd0860a3714d1b5d8173b38f60ea712c28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb0cf270ef16bb90192cee69eed7dcbda49e1a5f0c40aa563108e0716c33de5f5bdcbf60989cf719dbbfcc291179bd932c775a3a1553469c388d4ce83dc8604f"} build_url:https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011170 build_url: 'https://queue.taskcluster.net/v1/task/aKgd_HSBQauTGNYTd51wCA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed85560b709aded4a8479e330f17088c00ea4e4f1c87d1f6da623fc4d6f8bd69de65ec3d362bce1297bb60ad3842efd3c247602217a664b8eb5ec266220e99c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/843d60f5961b502a93edde36f704a284634cf43c15824baa2de1962c09a7b43a3b933914deba4adba3841afc7d00efcfb115aa5e396a7df30c56563db27ee456", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef0a7b35dfb402844d00edd4d0ca984f136cd19e63f5db5e43fe858867082d94ddefde8de861d4bd345adbd1737861fd0860a3714d1b5d8173b38f60ea712c28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb0cf270ef16bb90192cee69eed7dcbda49e1a5f0c40aa563108e0716c33de5f5bdcbf60989cf719dbbfcc291179bd932c775a3a1553469c388d4ce83dc8604f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-04 16:59:29.688946) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 16:59:29.689594) ========= 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=745a4373a2a16df560f216e000000264-1486245375.767225-598311644 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004480 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 16:59:29.773688) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-04 16:59:29.774038) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-04 16:59:29.774380) ========= ========= Total master_lag: 0.17 =========