builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-035 starttime: 1486633972.22 results: success (0) revision: 522951ff7046fd2bdf3916c973fbfde27be0af31 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 01:52:52.216613) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 01:52:52.217068) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 01:52:52.217339) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 01:52:52.231675) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 01:52:52.231947) ========= 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=ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-09 01:52:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2017-02-09 01:52:52 (222 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126294 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 01:52:52.371418) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 01:52:52.371749) ========= 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=ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020074 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 01:52:52.421761) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 01:52:52.422115) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 522951ff7046fd2bdf3916c973fbfde27be0af31 --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 522951ff7046fd2bdf3916c973fbfde27be0af31 --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=ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-09 01:52:52,744 truncating revision to first 12 chars 2017-02-09 01:52:52,744 Setting DEBUG logging. 2017-02-09 01:52:52,744 attempt 1/10 2017-02-09 01:52:52,744 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/522951ff7046?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-09 01:52:53,052 unpacking tar archive at: mozilla-aurora-522951ff7046/testing/mozharness/ program finished with exit code 0 elapsedTime=0.713368 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 01:52:53.148386) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 01:52:53.148722) ========= script_repo_revision: 522951ff7046fd2bdf3916c973fbfde27be0af31 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 01:52:53.149103) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 01:52:53.149366) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 01:52:53.157823) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-02-09 01:52:53.158097) ========= /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=ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:52:53 INFO - MultiFileLogger online at 20170209 01:52:53 in /builds/slave/test 01:52:53 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 01:52:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:52:53 INFO - {'append_to_log': False, 01:52:53 INFO - 'base_work_dir': '/builds/slave/test', 01:52:53 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:52:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:52:53 INFO - 'branch': 'Mozilla-Aurora', 01:52:53 INFO - 'buildbot_json_path': 'buildprops.json', 01:52:53 INFO - 'config_files': ('talos/linux_config.py',), 01:52:53 INFO - 'default_actions': ('clobber', 01:52:53 INFO - 'read-buildbot-config', 01:52:53 INFO - 'download-and-extract', 01:52:53 INFO - 'populate-webroot', 01:52:53 INFO - 'create-virtualenv', 01:52:53 INFO - 'install', 01:52:53 INFO - 'run-tests'), 01:52:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:52:53 INFO - 'download_minidump_stackwalk': True, 01:52:53 INFO - 'download_symbols': 'ondemand', 01:52:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:52:53 INFO - 'tooltool.py': '/tools/tooltool.py', 01:52:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:52:53 INFO - '/tools/misc-python/virtualenv.py')}, 01:52:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:52:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:52:53 INFO - 'installer_path': 'installer.exe', 01:52:53 INFO - 'log_level': 'info', 01:52:53 INFO - 'log_name': 'talos', 01:52:53 INFO - 'log_to_console': True, 01:52:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:52:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:52:53 INFO - 'opt_config_files': (), 01:52:53 INFO - 'pip_index': False, 01:52:53 INFO - 'sps_profile': False, 01:52:53 INFO - 'sps_profile_interval': 0, 01:52:53 INFO - 'suite': 'dromaeojs', 01:52:53 INFO - 'system_bits': '32', 01:52:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:52:53 INFO - 'title': 'talos-linux64-ix-035', 01:52:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:52:53 INFO - 'use_talos_json': True, 01:52:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:52:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:52:53 INFO - 'work_dir': 'build'} 01:52:53 INFO - [mozharness: 2017-02-09 09:52:53.578014Z] Running clobber step. 01:52:53 INFO - Running pre-action listener: _resource_record_pre_action 01:52:53 INFO - Running main action method: clobber 01:52:53 INFO - rmtree: /builds/slave/test/build 01:52:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:52:54 INFO - Running post-action listener: _resource_record_post_action 01:52:54 INFO - [mozharness: 2017-02-09 09:52:54.561019Z] Finished clobber step (success) 01:52:54 INFO - [mozharness: 2017-02-09 09:52:54.561119Z] Running read-buildbot-config step. 01:52:54 INFO - Running pre-action listener: _resource_record_pre_action 01:52:54 INFO - Running main action method: read_buildbot_config 01:52:54 INFO - Using buildbot properties: 01:52:54 INFO - { 01:52:54 INFO - "project": "", 01:52:54 INFO - "product": "firefox", 01:52:54 INFO - "who": "cbook@mozilla.com", 01:52:54 INFO - "installer_path": "public/build/target.tar.bz2", 01:52:54 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 01:52:54 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 01:52:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 01:52:54 INFO - "stage_platform": "linux64-pgo", 01:52:54 INFO - "basedir": "/builds/slave/test", 01:52:54 INFO - "buildnumber": 1045, 01:52:54 INFO - "platform": "ubuntu64_hw", 01:52:54 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 01:52:54 INFO - "slavebuilddir": "test", 01:52:54 INFO - "taskId": "E6pY10RoQViKTPp-MV2tug", 01:52:54 INFO - "branch": "mozilla-aurora", 01:52:54 INFO - "script_repo_revision": "production", 01:52:54 INFO - "revision": "522951ff7046fd2bdf3916c973fbfde27be0af31", 01:52:54 INFO - "slavename": "talos-linux64-ix-035", 01:52:54 INFO - "repo_path": "releases/mozilla-aurora" 01:52:54 INFO - } 01:52:54 INFO - Finding installer, test and symbols from parent task. 01:52:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6pY10RoQViKTPp-MV2tug'}, attempt #1 01:52:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6pY10RoQViKTPp-MV2tug'}, attempt #1 01:52:55 INFO - Task dependencies: FUuKIePkQQaPW58P4plGAg 01:52:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg'}, attempt #1 01:52:55 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.tar.bz2 01:52:55 INFO - Set test_url: https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/test_packages.json 01:52:55 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.crashreporter-symbols.zip 01:52:55 INFO - Running post-action listener: _resource_record_post_action 01:52:55 INFO - [mozharness: 2017-02-09 09:52:55.972287Z] Finished read-buildbot-config step (success) 01:52:55 INFO - [mozharness: 2017-02-09 09:52:55.972506Z] Running download-and-extract step. 01:52:55 INFO - Running pre-action listener: _resource_record_pre_action 01:52:55 INFO - Running main action method: download_and_extract 01:52:55 INFO - mkdir: /builds/slave/test/build/tests 01:52:55 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')]} 01:52:55 INFO - trying https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.test_packages.json 01:52:55 INFO - Downloading https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 01:52:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 01:52:56 INFO - Downloaded 896 bytes. 01:52:56 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 01:52:56 INFO - Using the following test package requirements: 01:52:56 INFO - {u'common': [u'target.common.tests.zip'], 01:52:56 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 01:52:56 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 01:52:56 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 01:52:56 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 01:52:56 INFO - u'mozbase': [u'target.common.tests.zip'], 01:52:56 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 01:52:56 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 01:52:56 INFO - u'web-platform': [u'target.common.tests.zip', 01:52:56 INFO - u'target.web-platform.tests.zip'], 01:52:56 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 01:52:56 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 01:52:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.common.tests.zip 01:52:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:52:56 INFO - Fetch https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.common.tests.zip into memory 01:52:57 INFO - Expected file size: 21861951 01:52:57 INFO - Obtained file size: 21861951 01:53:01 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 01:53:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.common.tests.zip 01:53:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:53:01 INFO - Fetch https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.common.tests.zip into memory 01:53:02 INFO - Expected file size: 21861951 01:53:02 INFO - Obtained file size: 21861951 01:53:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.talos.tests.zip 01:53:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 01:53:06 INFO - Fetch https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.talos.tests.zip into memory 01:53:07 INFO - Expected file size: 14403485 01:53:07 INFO - Obtained file size: 14403485 01:53:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:53:07 INFO - trying https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.tar.bz2 01:53:07 INFO - Downloading https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 01:53:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 01:53:08 INFO - Downloaded 66050891 bytes. 01:53:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.tar.bz2 01:53:08 INFO - mkdir: /builds/slave/test/properties 01:53:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:53:08 INFO - Writing to file /builds/slave/test/properties/build_url 01:53:08 INFO - Contents: 01:53:08 INFO - build_url:https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.tar.bz2 01:53:08 INFO - Running post-action listener: _resource_record_post_action 01:53:08 INFO - Running post-action listener: set_extra_try_arguments 01:53:08 INFO - [mozharness: 2017-02-09 09:53:08.886839Z] Finished download-and-extract step (success) 01:53:08 INFO - [mozharness: 2017-02-09 09:53:08.886936Z] Running populate-webroot step. 01:53:08 INFO - Running pre-action listener: _resource_record_pre_action 01:53:08 INFO - Running main action method: populate_webroot 01:53:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:53:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:53:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:53:08 INFO - '--tppagecycles', 01:53:08 INFO - '7'], 01:53:08 INFO - 'tests': ['tsvgm']}}, 01:53:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:53:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:53:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:53:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:53:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:53:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:53:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:53:08 INFO - 'talos_options': ['--disable-e10s'], 01:53:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:53:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:53:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:53:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:53:08 INFO - 'talos_options': ['--disable-e10s'], 01:53:08 INFO - 'tests': ['damp', 'tps']}, 01:53:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:53:08 INFO - 'tests': ['damp', 'tps']}, 01:53:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:53:08 INFO - 'tests': ['dromaeo_dom']}, 01:53:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:53:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:53:08 INFO - 'tests': ['basic_compositor_video']}, 01:53:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:53:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:53:08 INFO - 'tests': ['a11yr', 01:53:08 INFO - 'ts_paint', 01:53:08 INFO - 'tpaint', 01:53:08 INFO - 'sessionrestore', 01:53:08 INFO - 'sessionrestore_no_auto_restore', 01:53:08 INFO - 'tabpaint']}, 01:53:08 INFO - 'other-e10s': {'tests': ['a11yr', 01:53:08 INFO - 'ts_paint', 01:53:08 INFO - 'tpaint', 01:53:08 INFO - 'sessionrestore', 01:53:08 INFO - 'sessionrestore_no_auto_restore', 01:53:08 INFO - 'tabpaint']}, 01:53:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:53:08 INFO - 'ts_paint', 01:53:08 INFO - 'tpaint', 01:53:08 INFO - 'sessionrestore', 01:53:08 INFO - 'sessionrestore_no_auto_restore', 01:53:08 INFO - 'tabpaint']}, 01:53:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:53:08 INFO - 'ts_paint', 01:53:08 INFO - 'tpaint', 01:53:08 INFO - 'sessionrestore', 01:53:08 INFO - 'sessionrestore_no_auto_restore', 01:53:08 INFO - 'tabpaint']}, 01:53:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:53:08 INFO - 'tests': ['a11yr', 01:53:08 INFO - 'ts_paint', 01:53:08 INFO - 'tpaint', 01:53:08 INFO - 'sessionrestore', 01:53:08 INFO - 'sessionrestore_no_auto_restore', 01:53:08 INFO - 'tabpaint']}, 01:53:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:53:08 INFO - 'tests': ['a11yr', 01:53:08 INFO - 'ts_paint', 01:53:08 INFO - 'tpaint', 01:53:08 INFO - 'sessionrestore', 01:53:08 INFO - 'sessionrestore_no_auto_restore', 01:53:08 INFO - 'tabpaint']}, 01:53:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:53:08 INFO - 'tests': ['tsvgx', 01:53:08 INFO - 'tsvgr_opacity', 01:53:08 INFO - 'tart', 01:53:08 INFO - 'tscrollx', 01:53:08 INFO - 'cart', 01:53:08 INFO - 'tsvg_static']}, 01:53:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:53:08 INFO - 'tsvgr_opacity', 01:53:08 INFO - 'tart', 01:53:08 INFO - 'tscrollx', 01:53:08 INFO - 'cart', 01:53:08 INFO - 'tsvg_static']}, 01:53:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:53:08 INFO - 'talos_options': ['--disable-e10s'], 01:53:08 INFO - 'tests': ['tp5o']}, 01:53:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:53:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:53:08 INFO - 'talos_options': ['--disable-e10s', 01:53:08 INFO - '--xperf_path', 01:53:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:53:08 INFO - 'tests': ['tp5n']}, 01:53:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:53:08 INFO - 'talos_options': ['--xperf_path', 01:53:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:53:08 INFO - 'tests': ['tp5n']}}, 01:53:08 INFO - 'talos.zip': {'path': '', 01:53:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:53:08 INFO - Running post-action listener: _resource_record_post_action 01:53:08 INFO - [mozharness: 2017-02-09 09:53:08.900118Z] Finished populate-webroot step (success) 01:53:08 INFO - [mozharness: 2017-02-09 09:53:08.900207Z] Running create-virtualenv step. 01:53:08 INFO - Running pre-action listener: _resource_record_pre_action 01:53:08 INFO - Running main action method: create_virtualenv 01:53:08 INFO - Creating virtualenv /builds/slave/test/build/venv 01:53: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 01:53:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:53:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:53:08 INFO - Using real prefix '/usr' 01:53:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:53:09 INFO - Installing distribute.............................................................................................................................................................................................done. 01:53:11 INFO - Installing pip.................done. 01:53:11 INFO - Return code: 0 01:53:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:53:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:53:11 INFO - Reading from file tmpfile_stdout 01:53:11 INFO - Output received: 01:53: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) 01:53:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:53: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')]} 01:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53: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')]} 01:53:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53: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 0x25511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294c850>, '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 0x28c16d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1b60>, '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': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', '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 01:53: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 01:53: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 01:53:11 INFO - Using env: {'DISPLAY': ':0', 01:53:11 INFO - 'HOME': '/home/cltbld', 01:53:11 INFO - 'LANG': 'en_US.UTF-8', 01:53:11 INFO - 'LANGUAGE': 'en_US:en', 01:53:11 INFO - 'LOGNAME': 'cltbld', 01:53:11 INFO - 'MAIL': '/var/mail/cltbld', 01:53:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:11 INFO - 'MOZ_NO_REMOTE': '1', 01:53:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:53:11 INFO - 'NO_EM_RESTART': '1', 01:53:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:53:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:11 INFO - 'PWD': '/builds/slave/test', 01:53:11 INFO - 'SHELL': '/bin/bash', 01:53:11 INFO - 'SHLVL': '1', 01:53:11 INFO - 'TERM': 'linux', 01:53:11 INFO - 'TMOUT': '86400', 01:53:11 INFO - 'USER': 'cltbld', 01:53:11 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', 01:53:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:11 INFO - '_': '/tools/buildbot/bin/python'} 01:53:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53: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 01:53:11 INFO - Cleaning up... 01:53:11 INFO - Return code: 0 01:53:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:53: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')]} 01:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53: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')]} 01:53:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53: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 0x25511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294c850>, '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 0x28c16d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1b60>, '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': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', '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 01:53: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 01:53: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 01:53:11 INFO - Using env: {'DISPLAY': ':0', 01:53:11 INFO - 'HOME': '/home/cltbld', 01:53:11 INFO - 'LANG': 'en_US.UTF-8', 01:53:11 INFO - 'LANGUAGE': 'en_US:en', 01:53:11 INFO - 'LOGNAME': 'cltbld', 01:53:11 INFO - 'MAIL': '/var/mail/cltbld', 01:53:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:11 INFO - 'MOZ_NO_REMOTE': '1', 01:53:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:53:11 INFO - 'NO_EM_RESTART': '1', 01:53:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:53:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:11 INFO - 'PWD': '/builds/slave/test', 01:53:11 INFO - 'SHELL': '/bin/bash', 01:53:11 INFO - 'SHLVL': '1', 01:53:11 INFO - 'TERM': 'linux', 01:53:11 INFO - 'TMOUT': '86400', 01:53:11 INFO - 'USER': 'cltbld', 01:53:11 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', 01:53:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:11 INFO - '_': '/tools/buildbot/bin/python'} 01:53:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:12 INFO - Downloading/unpacking psutil>=3.1.1 01:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:53:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:53:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:53:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:53:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:53:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:53:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:53:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:53:14 INFO - Installing collected packages: psutil 01:53:14 INFO - Running setup.py install for psutil 01:53:14 INFO - building 'psutil._psutil_linux' extension 01:53:14 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 01:53: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_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 01:53:14 INFO - building 'psutil._psutil_posix' extension 01:53:14 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 01:53: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 01:53:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:53:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:53:14 INFO - Successfully installed psutil 01:53:14 INFO - Cleaning up... 01:53:14 INFO - Return code: 0 01:53:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:53: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')]} 01:53:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53: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')]} 01:53:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53: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 0x25511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294c850>, '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 0x28c16d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1b60>, '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': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', '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 01:53: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 01:53: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 01:53:14 INFO - Using env: {'DISPLAY': ':0', 01:53:14 INFO - 'HOME': '/home/cltbld', 01:53:14 INFO - 'LANG': 'en_US.UTF-8', 01:53:14 INFO - 'LANGUAGE': 'en_US:en', 01:53:14 INFO - 'LOGNAME': 'cltbld', 01:53:14 INFO - 'MAIL': '/var/mail/cltbld', 01:53:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:14 INFO - 'MOZ_NO_REMOTE': '1', 01:53:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:53:14 INFO - 'NO_EM_RESTART': '1', 01:53:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:53:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:14 INFO - 'PWD': '/builds/slave/test', 01:53:14 INFO - 'SHELL': '/bin/bash', 01:53:14 INFO - 'SHLVL': '1', 01:53:14 INFO - 'TERM': 'linux', 01:53:14 INFO - 'TMOUT': '86400', 01:53:14 INFO - 'USER': 'cltbld', 01:53:14 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', 01:53:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:14 INFO - '_': '/tools/buildbot/bin/python'} 01:53:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:53:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:53:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:53:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:53:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:53:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:53:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:53:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:53:17 INFO - Installing collected packages: mozsystemmonitor 01:53:17 INFO - Running setup.py install for mozsystemmonitor 01:53:17 INFO - Successfully installed mozsystemmonitor 01:53:17 INFO - Cleaning up... 01:53:17 INFO - Return code: 0 01:53:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:53:17 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')]} 01:53:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:17 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')]} 01:53:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:17 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 0x25511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294c850>, '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 0x28c16d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1b60>, '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': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', '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 01:53:17 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 01:53:17 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 01:53:17 INFO - Using env: {'DISPLAY': ':0', 01:53:17 INFO - 'HOME': '/home/cltbld', 01:53:17 INFO - 'LANG': 'en_US.UTF-8', 01:53:17 INFO - 'LANGUAGE': 'en_US:en', 01:53:17 INFO - 'LOGNAME': 'cltbld', 01:53:17 INFO - 'MAIL': '/var/mail/cltbld', 01:53:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:17 INFO - 'MOZ_NO_REMOTE': '1', 01:53:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:53:17 INFO - 'NO_EM_RESTART': '1', 01:53:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:53:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:17 INFO - 'PWD': '/builds/slave/test', 01:53:17 INFO - 'SHELL': '/bin/bash', 01:53:17 INFO - 'SHLVL': '1', 01:53:17 INFO - 'TERM': 'linux', 01:53:17 INFO - 'TMOUT': '86400', 01:53:17 INFO - 'USER': 'cltbld', 01:53:17 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', 01:53:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:17 INFO - '_': '/tools/buildbot/bin/python'} 01:53:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:17 INFO - Downloading/unpacking jsonschema==2.5.1 01:53:17 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 01:53:17 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 01:53:17 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 01:53:17 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 01:53:17 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 01:53:17 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 01:53:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:53:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:53: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 01:53: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 01:53: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 01:53: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 01:53: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 01:53: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 01:53:19 INFO - Downloading functools32-3.2.3-2.tar.gz 01:53:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:53:19 INFO - warning: no files found matching '*.txt' 01:53:19 INFO - no previously-included directories found matching 'build' 01:53:19 INFO - no previously-included directories found matching 'dist' 01:53:19 INFO - no previously-included directories found matching '.git*' 01:53:19 INFO - Installing collected packages: jsonschema, functools32 01:53:19 INFO - Running setup.py install for functools32 01:53:19 INFO - warning: no files found matching '*.txt' 01:53:19 INFO - no previously-included directories found matching 'build' 01:53:19 INFO - no previously-included directories found matching 'dist' 01:53:19 INFO - no previously-included directories found matching '.git*' 01:53:19 INFO - Successfully installed jsonschema functools32 01:53:19 INFO - Cleaning up... 01:53:19 INFO - Return code: 0 01:53:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:53: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')]} 01:53:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53: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')]} 01:53:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53: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 0x25511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294c850>, '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 0x28c16d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1b60>, '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': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', '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 01:53: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 01:53: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 01:53:19 INFO - Using env: {'DISPLAY': ':0', 01:53:19 INFO - 'HOME': '/home/cltbld', 01:53:19 INFO - 'LANG': 'en_US.UTF-8', 01:53:19 INFO - 'LANGUAGE': 'en_US:en', 01:53:19 INFO - 'LOGNAME': 'cltbld', 01:53:19 INFO - 'MAIL': '/var/mail/cltbld', 01:53:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:19 INFO - 'MOZ_NO_REMOTE': '1', 01:53:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:53:19 INFO - 'NO_EM_RESTART': '1', 01:53:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:53:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:19 INFO - 'PWD': '/builds/slave/test', 01:53:19 INFO - 'SHELL': '/bin/bash', 01:53:19 INFO - 'SHLVL': '1', 01:53:19 INFO - 'TERM': 'linux', 01:53:19 INFO - 'TMOUT': '86400', 01:53:19 INFO - 'USER': 'cltbld', 01:53:19 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', 01:53:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:19 INFO - '_': '/tools/buildbot/bin/python'} 01:53:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:53:20 INFO - Cleaning up... 01:53:20 INFO - Return code: 0 01:53:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:53:20 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')]} 01:53:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:20 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')]} 01:53:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:20 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 0x25511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294c850>, '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 0x28c16d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1b60>, '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': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', '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 01:53:20 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 01:53:20 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 01:53:20 INFO - Using env: {'DISPLAY': ':0', 01:53:20 INFO - 'HOME': '/home/cltbld', 01:53:20 INFO - 'LANG': 'en_US.UTF-8', 01:53:20 INFO - 'LANGUAGE': 'en_US:en', 01:53:20 INFO - 'LOGNAME': 'cltbld', 01:53:20 INFO - 'MAIL': '/var/mail/cltbld', 01:53:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:20 INFO - 'MOZ_NO_REMOTE': '1', 01:53:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:53:20 INFO - 'NO_EM_RESTART': '1', 01:53:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:53:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:20 INFO - 'PWD': '/builds/slave/test', 01:53:20 INFO - 'SHELL': '/bin/bash', 01:53:20 INFO - 'SHLVL': '1', 01:53:20 INFO - 'TERM': 'linux', 01:53:20 INFO - 'TMOUT': '86400', 01:53:20 INFO - 'USER': 'cltbld', 01:53:20 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', 01:53:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:20 INFO - '_': '/tools/buildbot/bin/python'} 01:53:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:20 INFO - Downloading/unpacking blobuploader==1.2.4 01:53:20 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 01:53:20 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 01:53:20 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 01:53:20 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 01:53:20 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 01:53:20 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 01:53:22 INFO - Downloading blobuploader-1.2.4.tar.gz 01:53:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:53:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:53: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 01:53: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 01:53: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 01:53: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 01:53: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 01:53: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 01:53:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:53:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:53: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 01:53: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 01:53: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 01:53: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 01:53: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 01:53: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 01:53:23 INFO - Downloading docopt-0.6.1.tar.gz 01:53:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:53:23 INFO - Installing collected packages: blobuploader, requests, docopt 01:53:23 INFO - Running setup.py install for blobuploader 01:53:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:53:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:53:23 INFO - Running setup.py install for requests 01:53:23 INFO - Running setup.py install for docopt 01:53:23 INFO - Successfully installed blobuploader requests docopt 01:53:23 INFO - Cleaning up... 01:53:23 INFO - Return code: 0 01:53:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:53: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')]} 01:53:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53: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')]} 01:53:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53: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 0x25511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294c850>, '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 0x28c16d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1b60>, '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': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', '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 01:53: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 01:53: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 01:53:23 INFO - Using env: {'DISPLAY': ':0', 01:53:23 INFO - 'HOME': '/home/cltbld', 01:53:23 INFO - 'LANG': 'en_US.UTF-8', 01:53:23 INFO - 'LANGUAGE': 'en_US:en', 01:53:23 INFO - 'LOGNAME': 'cltbld', 01:53:23 INFO - 'MAIL': '/var/mail/cltbld', 01:53:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:23 INFO - 'MOZ_NO_REMOTE': '1', 01:53:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:53:23 INFO - 'NO_EM_RESTART': '1', 01:53:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:53:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:23 INFO - 'PWD': '/builds/slave/test', 01:53:23 INFO - 'SHELL': '/bin/bash', 01:53:23 INFO - 'SHLVL': '1', 01:53:23 INFO - 'TERM': 'linux', 01:53:23 INFO - 'TMOUT': '86400', 01:53:23 INFO - 'USER': 'cltbld', 01:53:23 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', 01:53:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:23 INFO - '_': '/tools/buildbot/bin/python'} 01:53:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:53:24 INFO - Running setup.py (path:/tmp/pip-qjtrxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:53:24 INFO - Running setup.py (path:/tmp/pip-ihLo1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:53:24 INFO - Running setup.py (path:/tmp/pip-ADDXbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:53:24 INFO - Running setup.py (path:/tmp/pip-f4iTDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:53:24 INFO - Running setup.py (path:/tmp/pip-jsUNHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:53:24 INFO - Running setup.py (path:/tmp/pip-apaodn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:53:24 INFO - Running setup.py (path:/tmp/pip-yepUAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:53:24 INFO - Running setup.py (path:/tmp/pip-yNXngN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:53:24 INFO - Running setup.py (path:/tmp/pip-pd7sie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:53:24 INFO - Running setup.py (path:/tmp/pip-iIwyf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:53:24 INFO - Running setup.py (path:/tmp/pip-AmpJNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:53:24 INFO - Running setup.py (path:/tmp/pip-oqaPkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:53:24 INFO - Running setup.py (path:/tmp/pip-lmq4Iu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:53:24 INFO - Running setup.py (path:/tmp/pip-cyfZa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:53:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:53:25 INFO - Running setup.py (path:/tmp/pip-1w9cRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:53:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:53:25 INFO - Running setup.py (path:/tmp/pip-xU6lny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:53:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:53:25 INFO - Running setup.py (path:/tmp/pip-uJ3ha4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:53:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:53:25 INFO - Running setup.py install for manifestparser 01:53:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:53:25 INFO - Running setup.py install for mozcrash 01:53:25 INFO - Running setup.py install for mozdebug 01:53:25 INFO - Running setup.py install for mozdevice 01:53:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:53:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:53:25 INFO - Running setup.py install for mozfile 01:53:25 INFO - Running setup.py install for mozhttpd 01:53:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:53:25 INFO - Running setup.py install for mozinfo 01:53:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:53:25 INFO - Running setup.py install for mozInstall 01:53:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:53:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:53:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:53:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:53:26 INFO - Running setup.py install for mozleak 01:53:26 INFO - Running setup.py install for mozlog 01:53:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:53:26 INFO - Running setup.py install for moznetwork 01:53:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:53:26 INFO - Running setup.py install for mozprocess 01:53:26 INFO - Running setup.py install for mozprofile 01:53:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:53:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:53:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:53:26 INFO - Running setup.py install for mozrunner 01:53:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:53:26 INFO - Running setup.py install for mozscreenshot 01:53:27 INFO - Running setup.py install for moztest 01:53:27 INFO - Running setup.py install for mozversion 01:53:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:53:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:53:27 INFO - Cleaning up... 01:53:27 INFO - Return code: 0 01:53:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:53:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:53:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:53:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:27 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 0x25511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294c850>, '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 0x28c16d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1b60>, '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': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', '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 01:53:27 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 01:53:27 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 01:53:27 INFO - Using env: {'DISPLAY': ':0', 01:53:27 INFO - 'HOME': '/home/cltbld', 01:53:27 INFO - 'LANG': 'en_US.UTF-8', 01:53:27 INFO - 'LANGUAGE': 'en_US:en', 01:53:27 INFO - 'LOGNAME': 'cltbld', 01:53:27 INFO - 'MAIL': '/var/mail/cltbld', 01:53:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:27 INFO - 'MOZ_NO_REMOTE': '1', 01:53:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:53:27 INFO - 'NO_EM_RESTART': '1', 01:53:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:53:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:27 INFO - 'PWD': '/builds/slave/test', 01:53:27 INFO - 'SHELL': '/bin/bash', 01:53:27 INFO - 'SHLVL': '1', 01:53:27 INFO - 'TERM': 'linux', 01:53:27 INFO - 'TMOUT': '86400', 01:53:27 INFO - 'USER': 'cltbld', 01:53:27 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', 01:53:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:27 INFO - '_': '/tools/buildbot/bin/python'} 01:53:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:53:27 INFO - Running setup.py (path:/tmp/pip-3RS9sS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:53:27 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)) 01:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:53:27 INFO - Running setup.py (path:/tmp/pip-CanSdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:53:27 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)) 01:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:53:27 INFO - Running setup.py (path:/tmp/pip-UM98Mp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:53:27 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)) 01:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:53:27 INFO - Running setup.py (path:/tmp/pip-_JXeJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:53:27 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)) 01:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:53:27 INFO - Running setup.py (path:/tmp/pip-GfzkML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:53: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)) 01:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:53:27 INFO - Running setup.py (path:/tmp/pip-NDi0YV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:53: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)) 01:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:53:27 INFO - Running setup.py (path:/tmp/pip-b2_4jY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:53:28 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)) 01:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:53:28 INFO - Running setup.py (path:/tmp/pip-cvsQ5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:53:28 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)) 01:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:53:28 INFO - Running setup.py (path:/tmp/pip-Tg7C23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:53:28 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)) 01:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:53:28 INFO - Running setup.py (path:/tmp/pip-cuR5X2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:53:28 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)) 01:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:53:28 INFO - Running setup.py (path:/tmp/pip-Xbg6RL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:53:28 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)) 01:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:53:28 INFO - Running setup.py (path:/tmp/pip-_BY9zN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:53:28 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)) 01:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:53:28 INFO - Running setup.py (path:/tmp/pip-9Q5zNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:53:28 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)) 01:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:53:28 INFO - Running setup.py (path:/tmp/pip-6rf_HY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:53:28 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)) 01:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:53:28 INFO - Running setup.py (path:/tmp/pip-Iccdto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:53:28 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)) 01:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:53:28 INFO - Running setup.py (path:/tmp/pip-m7n75b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:53:28 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)) 01:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:53:28 INFO - Running setup.py (path:/tmp/pip-dX4N5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:53:28 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)) 01:53:28 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)) 01:53:28 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)) 01:53:28 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)) 01:53:28 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)) 01:53:28 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)) 01:53:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:53:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:53:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:53:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:53:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:53:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:53:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:53:30 INFO - Downloading blessings-1.6.tar.gz 01:53:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:53:31 INFO - Installing collected packages: blessings 01:53:31 INFO - Running setup.py install for blessings 01:53:31 INFO - Successfully installed blessings 01:53:31 INFO - Cleaning up... 01:53:31 INFO - Return code: 0 01:53:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:53:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:53:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:53:31 INFO - Reading from file tmpfile_stdout 01:53:31 INFO - Current package versions: 01:53:31 INFO - argparse == 1.2.1 01:53:31 INFO - blessings == 1.6 01:53:31 INFO - blobuploader == 1.2.4 01:53:31 INFO - docopt == 0.6.1 01:53:31 INFO - functools32 == 3.2.3-2 01:53:31 INFO - jsonschema == 2.5.1 01:53:31 INFO - manifestparser == 1.1 01:53:31 INFO - mozInstall == 1.12 01:53:31 INFO - mozcrash == 1.0 01:53:31 INFO - mozdebug == 0.1 01:53:31 INFO - mozdevice == 0.49 01:53:31 INFO - mozfile == 1.2 01:53:31 INFO - mozhttpd == 0.7 01:53:31 INFO - mozinfo == 0.9 01:53:31 INFO - mozleak == 0.1 01:53:31 INFO - mozlog == 3.4 01:53:31 INFO - moznetwork == 0.27 01:53:31 INFO - mozprocess == 0.24 01:53:31 INFO - mozprofile == 0.28 01:53:31 INFO - mozrunner == 6.13 01:53:31 INFO - mozscreenshot == 0.1 01:53:31 INFO - mozsystemmonitor == 0.3 01:53:31 INFO - moztest == 0.8 01:53:31 INFO - mozversion == 1.4 01:53:31 INFO - psutil == 3.1.1 01:53:31 INFO - requests == 1.2.3 01:53:31 INFO - wsgiref == 0.1.2 01:53:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:53:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:53:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:53:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:31 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 0x25511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294c850>, '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 0x28c16d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1b60>, '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': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', '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 01:53:31 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 01:53:31 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 01:53:31 INFO - Using env: {'DISPLAY': ':0', 01:53:31 INFO - 'HOME': '/home/cltbld', 01:53:31 INFO - 'LANG': 'en_US.UTF-8', 01:53:31 INFO - 'LANGUAGE': 'en_US:en', 01:53:31 INFO - 'LOGNAME': 'cltbld', 01:53:31 INFO - 'MAIL': '/var/mail/cltbld', 01:53:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:31 INFO - 'MOZ_NO_REMOTE': '1', 01:53:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:53:31 INFO - 'NO_EM_RESTART': '1', 01:53:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:53:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:31 INFO - 'PWD': '/builds/slave/test', 01:53:31 INFO - 'SHELL': '/bin/bash', 01:53:31 INFO - 'SHLVL': '1', 01:53:31 INFO - 'TERM': 'linux', 01:53:31 INFO - 'TMOUT': '86400', 01:53:31 INFO - 'USER': 'cltbld', 01:53:31 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', 01:53:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:31 INFO - '_': '/tools/buildbot/bin/python'} 01:53:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:31 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)) 01:53:31 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)) 01:53:31 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)) 01:53:31 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)) 01:53:31 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)) 01:53:31 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)) 01:53:31 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)) 01:53:31 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)) 01:53:31 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)) 01:53:31 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)) 01:53:31 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)) 01:53:31 INFO - Cleaning up... 01:53:31 INFO - Return code: 0 01:53:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:53:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:53:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:53:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2661a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294c850>, '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 0x28c16d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1b60>, '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': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', '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 01:53:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:53:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:53:31 INFO - Using env: {'DISPLAY': ':0', 01:53:31 INFO - 'HOME': '/home/cltbld', 01:53:31 INFO - 'LANG': 'en_US.UTF-8', 01:53:31 INFO - 'LANGUAGE': 'en_US:en', 01:53:31 INFO - 'LOGNAME': 'cltbld', 01:53:31 INFO - 'MAIL': '/var/mail/cltbld', 01:53:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:31 INFO - 'MOZ_NO_REMOTE': '1', 01:53:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:53:31 INFO - 'NO_EM_RESTART': '1', 01:53:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:53:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:31 INFO - 'PWD': '/builds/slave/test', 01:53:31 INFO - 'SHELL': '/bin/bash', 01:53:31 INFO - 'SHLVL': '1', 01:53:31 INFO - 'TERM': 'linux', 01:53:31 INFO - 'TMOUT': '86400', 01:53:31 INFO - 'USER': 'cltbld', 01:53:31 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', 01:53:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:31 INFO - '_': '/tools/buildbot/bin/python'} 01:53:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:53:31 INFO - Cleaning up... 01:53:31 INFO - Return code: 0 01:53:31 INFO - Running post-action listener: _resource_record_post_action 01:53:31 INFO - Running post-action listener: _start_resource_monitoring 01:53:31 INFO - Starting resource monitoring. 01:53:31 INFO - [mozharness: 2017-02-09 09:53:31.923319Z] Finished create-virtualenv step (success) 01:53:31 INFO - [mozharness: 2017-02-09 09:53:31.923769Z] Running install step. 01:53:31 INFO - Running pre-action listener: _resource_record_pre_action 01:53:31 INFO - Running main action method: install 01:53:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:53:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:53:32 INFO - Reading from file tmpfile_stdout 01:53:32 INFO - Detecting whether we're running mozinstall >=1.0... 01:53:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:53:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:53:32 INFO - Reading from file tmpfile_stdout 01:53:32 INFO - Output received: 01:53:32 INFO - Usage: mozinstall [options] installer 01:53:32 INFO - Options: 01:53:32 INFO - -h, --help show this help message and exit 01:53:32 INFO - -d DEST, --destination=DEST 01:53:32 INFO - Directory to install application into. [default: 01:53:32 INFO - "/builds/slave/test"] 01:53:32 INFO - --app=APP Application being installed. [default: firefox] 01:53:32 INFO - mkdir: /builds/slave/test/build/application 01:53:32 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'] 01:53:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 01:53:48 INFO - Reading from file tmpfile_stdout 01:53:48 INFO - Output received: 01:53:48 INFO - /builds/slave/test/build/application/firefox/firefox 01:53:48 INFO - Running post-action listener: _resource_record_post_action 01:53:48 INFO - [mozharness: 2017-02-09 09:53:48.632359Z] Finished install step (success) 01:53:48 INFO - [mozharness: 2017-02-09 09:53:48.632633Z] Running run-tests step. 01:53:48 INFO - Running pre-action listener: _resource_record_pre_action 01:53:48 INFO - Running main action method: run_tests 01:53:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:53:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:53:48 INFO - Python 2.7.3 01:53:48 INFO - Return code: 0 01:53:48 INFO - grabbing minidump binary from tooltool 01:53:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:53:48 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 0x294c850>, '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 0x28c16d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28c1b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:53:48 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 01:53:48 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 01:53:48 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 01:53:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:53:48 INFO - Return code: 0 01:53:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:53:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:53:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:53:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:53:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:53:48 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/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 01:53:48 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/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-035 --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 01:53:48 INFO - Using env: {'DISPLAY': ':0', 01:53:48 INFO - 'HOME': '/home/cltbld', 01:53:48 INFO - 'LANG': 'en_US.UTF-8', 01:53:48 INFO - 'LANGUAGE': 'en_US:en', 01:53:48 INFO - 'LOGNAME': 'cltbld', 01:53:48 INFO - 'MAIL': '/var/mail/cltbld', 01:53:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:53:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:53:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:48 INFO - 'MOZ_NO_REMOTE': '1', 01:53:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:53:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:53:48 INFO - 'NO_EM_RESTART': '1', 01:53:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:53:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:48 INFO - 'PWD': '/builds/slave/test', 01:53:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:53:48 INFO - 'SHELL': '/bin/bash', 01:53:48 INFO - 'SHLVL': '1', 01:53:48 INFO - 'TERM': 'linux', 01:53:48 INFO - 'TMOUT': '86400', 01:53:48 INFO - 'USER': 'cltbld', 01:53:48 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995', 01:53:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:48 INFO - '_': '/tools/buildbot/bin/python'} 01:53:48 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/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 01:53:48 INFO - mozversion application_buildid: 20170209084855 01:53:48 INFO - mozversion application_changeset: 522951ff7046fd2bdf3916c973fbfde27be0af31 01:53:48 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:53:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:53:48 INFO - mozversion application_name: Firefox 01:53:48 INFO - mozversion application_remotingname: firefox-dev 01:53:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:53:48 INFO - mozversion application_vendor: Mozilla 01:53:48 INFO - mozversion application_version: 53.0a2 01:53:48 INFO - mozversion platform_buildid: 20170209084855 01:53:48 INFO - mozversion platform_changeset: 522951ff7046fd2bdf3916c973fbfde27be0af31 01:53:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:53:48 INFO - mozversion platform_version: 53.0a2 01:53:48 INFO - using testdate: 1486634028 01:53:48 INFO - actual date: 1486634028 01:53:48 INFO - starting webserver on 'localhost:55194' 01:53:48 INFO - SUITE-START | Running 2 tests 01:53:48 INFO - TEST-START | dromaeo_css 01:53:48 INFO - operating with platform_type : linux_ 01:53:48 INFO - Initialising browser for dromaeo_css test... 01:53:48 INFO - TEST-INFO | started process 21839 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnbiMMg/profile http://localhost:55194/getInfo.html) 01:53:52 INFO - PROCESS | 21839 | __metrics Screen width/height:1600/1200 01:53:52 INFO - PROCESS | 21839 | colorDepth:24 01:53:52 INFO - PROCESS | 21839 | Browser inner width/height: 1024/697 01:53:52 INFO - PROCESS | 21839 | __metrics 01:53:53 INFO - PROCESS | 21839 | 1486634033083 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 01:53:53 INFO - PROCESS | 21839 | 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" 01:53:53 INFO - PROCESS | 21839 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:53:53 INFO - PROCESS | 21839 | console.error: PushService: 01:53:53 INFO - PROCESS | 21839 | stateChangeProcessEnqueue: Error transitioning state 01:53:53 INFO - PROCESS | 21839 | UnknownError 01:53:54 INFO - TEST-INFO | 21839: exit 0 01:53:54 INFO - Browser initialized. 01:53:54 INFO - Running cycle 1/1 for dromaeo_css test... 01:53:54 INFO - TEST-INFO | started process 21934 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnbiMMg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:54:06 INFO - PROCESS | 21934 | RSS: Main: 138592256 01:54:06 INFO - PROCESS | 21934 | 01:57:06 INFO - PROCESS | 21934 | Cycle 1(1): loaded http://localhost:55194/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55194/tests/dromaeo/cssquery-ext.html) 01:57:07 INFO - PROCESS | 21934 | RSS: Main: 242946048 01:57:07 INFO - PROCESS | 21934 | 01:58:56 INFO - PROCESS | 21934 | ************************* 01:58:56 INFO - PROCESS | 21934 | A coding exception was thrown and uncaught in a Task. 01:58:56 INFO - PROCESS | 21934 | 01:58:56 ERROR - PROCESS | 21934 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:58:56 INFO - PROCESS | 21934 | Full stack: 01:58:56 INFO - PROCESS | 21934 | ************************* 02:00:09 INFO - PROCESS | 21934 | Cycle 1(1): loaded http://localhost:55194/tests/dromaeo/cssquery-ext.html (next: http://localhost:55194/tests/dromaeo/cssquery-jquery.html) 02:00:10 INFO - PROCESS | 21934 | RSS: Main: 229494784 02:00:10 INFO - PROCESS | 21934 | 02:03:12 INFO - PROCESS | 21934 | Cycle 1(1): loaded http://localhost:55194/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55194/tests/dromaeo/cssquery-mootools.html) 02:03:12 INFO - PROCESS | 21934 | RSS: Main: 199249920 02:03:12 INFO - PROCESS | 21934 | 02:06:14 INFO - PROCESS | 21934 | Cycle 1(1): loaded http://localhost:55194/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55194/tests/dromaeo/cssquery-prototype.html) 02:06:15 INFO - PROCESS | 21934 | RSS: Main: 213000192 02:06:15 INFO - PROCESS | 21934 | 02:09:18 INFO - PROCESS | 21934 | Cycle 1(1): loaded http://localhost:55194/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55194/tests/dromaeo/cssquery-yui.html) 02:09:18 INFO - PROCESS | 21934 | RSS: Main: 174346240 02:09:18 INFO - PROCESS | 21934 | 02:12:21 INFO - PROCESS | 21934 | Cycle 1(1): loaded http://localhost:55194/tests/dromaeo/cssquery-yui.html (next: http://localhost:55194/tests/dromaeo/cssquery-dojo.html) 02:12:21 INFO - PROCESS | 21934 | RSS: Main: 264142848 02:12:21 INFO - PROCESS | 21934 | 02:12:21 INFO - PROCESS | 21934 | __start_tp_report 02:12:21 INFO - PROCESS | 21934 | _x_x_mozilla_page_load 02:12:21 INFO - PROCESS | 21934 | _x_x_mozilla_page_load_details 02:12:21 INFO - PROCESS | 21934 | |i|pagename|runs| 02:12:21 INFO - PROCESS | 21934 | |0;dojo.html;3248.7888287261326;3262.4127657021727;3273.116258610176;3296.241864371193;3301.1912081540027;5346.866328341794;5384.946150538493;5419.810306639263;5422.864428389283;5462.071447853865;5463.125899856024;5486.286782718251;5499.7250137493065;5500.477454641803;5506;4984.476629953857;5006.774695138709;5039.496050394968;5065.214891691794;5079.74601269935;4924.704517728947;5025.195968645017;5076.492350764913;5080.695158290514;5110.488951104897;4882.633802464811;4894.021195760844;4902.901941961159;4932.358793356859;5022.995400919813;4982.501749825006;5028.522290382408;5028.673136246132;5030.144875371196;5057.393112826448;4942.135126352874;4976.42771081323;4995.525425084611;5007.749612519359;5008.24876268559;5154;5235.502627250406;5304.443033481468;5362.088444964365;5382.407935127133;5843.503302219274;5850.385709500526;5859.648421094748;5864.1203819427;5874.941250587525;5881.05903055509;5908.113782932594;5912.113183022538;5920.674362910038;5946.316173640043;5290.629655924087;5325.973370133163;5406.162044883089;5460.262864513319;5483.424240454775;5896.469317761422;5896.7936122235515;5901.144334071621;5921.378255283219;5939;5766.135079738073;5768.250127483484;5773.624714393635;5778.508826749773;5779.2775903012125;5629.662220266797;5667.773289068474;5720.628159169641;5775.6823374714795;5786.855328616818;5759.2512973313205;5824.446677565625;5824.679642619697;5838.562107841929;5855.707214639251;5798.710064496759;5801.477866991991;5806.477417032487;5808.361080281165;5833.300003999462;4419.668524860648;4421.40311058003;4478.417805685305;4542.545745425431;4569.702969306984;4001.8199181036925;4030.9798451008146;4050.594940505926;4067.8372865085657;4079.5104587449696;5674.148877668383;5680.11958146488;5681.829545113653;5683.033884239607;5685.715714214272;5660.915086273709;5752.25220721312;5765.654060756368;5915.792947246911;5931.377205393458;5527.088030474926;5592.636478628876;5594.496495315441;5626.634268772516;5632.2678051853;4933.383327084114;4944.208926571731;4961.206207006861;4964.702117872939;4986.800527978913;5923.318818335861;5944.464998150187;6084.939150608526;6088.817335479943;6099.69501524922;5804.94195058044;5856.619319744203;5859.296884373903;5927.170196172453;5959.0763431668165;6141.815745527641;6144.201253836973;6150.477209437248;6153.292371377349;6154.353792851686;5551.139573366135;5777.595568310178;5834.38738932406;5837.299524056971;5840.94159058421;5672.404397538364;5770.2498675171955;5811.4479124483105;5826.067829147486;5946.048632218824;5714.685692286964;5752.165935939349;5763.250777398911;5766.682832444256;5766.798162064308;5764.7405866736935;5769.567282453917;5778.27771528559;5781.971090144522;5787.739551720267;5483;5499.395066542597;5655.575831812548;5684.090545512699;5702.0306547887785;5816.796412125555;5817.389174136655;5817.418258174149;5818.2436283282905;5820.097884827858;5656;5672.092465205547;5677.801276955287;5688.431156884279;5714.142878568248 02:12:21 INFO - PROCESS | 21934 | |1;ext.html;544.9616577232301;576.1530550091367;602.7347966894566;619.3311223878212;650.6193876582199;39568.80215598925;53373;54429;54456.455435445634;54492.45507544933;51542.48457515424;51846.22230666534;51971.22043169355;51997;52155.739221303935;8605.526696031715;8805.691800786974;9497.145256926859;9693.515324233795;11294.435278236097;11418.31490110592;11522.135839812006;11535.769284614302;11538.423078846066;11544.249623774469;11570;11690.649280521555;11698.590549330775;11701.2979221247;11707.5902343418;11562.479688414041;11601.883981160165;11627.360495172763;11628.76742465146;11635.418229088511;11421.942890285496;11516.654500365003;11520.94239528797;11522.654320370402;11523.711907202303;48608.27087593689;49095.50904490945;49097.50902491001;49100.263496047584;49142.26286605704;4525.796339164745;4542.841000564998;4617.191991401525;4656.32483289921;4688.742119183444;34754.30491390158;35014.824925875466;35395.823020884985;35709;36454;4599.264117741145;4612.630989520831;4661.033757784915;4683.648726345504;4715.3869996900185;361682;361900;362433;362434;362579.18710406544;71330.2866971334;71674.28325716677;71740;71762.64118679373;71812.64093679603;77560;77643.61178194072;77692.61153694196;77749.61125194338;77778;18157.27370905149;18559.164837582342;18565.907170464332;18575;18584.349547765763;14466;14859;15275.61810954735;15559;15715.528534143994;21323.46691332698;21547.56904861894;21602.45993850135;21650;21836.672449913265;7141.96429017862;7271.745488907969;7292;7323;7422.323652690214;981.3032746749744;992.062500936608;1001.7846163074973;1002.7844013536978;1005.7938122684832;32853.50719739206;33845.83077084599;33919.321613568085;34299.48550771741;34504.474972931406;1126.3354620773619;1145.5188820695328;1150.6605551362368;1158.2182027131653;1163.7497937943383;17830.643387132186;18031.63936721258;18112.547186320448;18190.090495475175;18258.178381972844;452822;453641;454143;457387;458625;68555.31444685589;68781.3121868785;68852.31147688559;68861;68879.65560172267;1508.3392317738242;1539.1150088698819;1541.368039103962;1549.4422008077083;1574.8188958269716;31040.68959310378;32672.836635816668;32891.17772055718;33175.33649327048;33211.33577328488;58816;59490.70254648699;59592.40407596042;59686.40313596982;59700.40299596948;377293;378371;380020;380631;380797;40479.79760101181;45520.77239613913;51546;51752.74123629358;53441.732791335795;54812;55015.724921375135;55424.445755543544;56178.719106404205;57513.712431437576;44870;45564;46208.30687539622;46361.76819115883;46458.53541464542;44109.21035031157;45921.31118033166;46016.309755353024;47005.059898802516;47521.28718069162;45514;45702.54297456983;45826.77086614545;45840.312395314766;46187;36218;40909.38635920523;41126.1774764509;41161;41662.166756665305;31321.530177047818;31341.52987705232;31414.535961258185;31451.370972580877;31728.841355793997 02:12:21 INFO - PROCESS | 21934 | |2;jquery.html;435.6536553440015;439.3848611943281;443.21329639889206;443.68498366160026;462.2580757883597;20560.485987850294;22110.22614208501;22412.439689007762;22507.09971601138;22857.42856428586;22639;23223.303300900956;23265.418364540896;23336.8833155834;23572;5975.073863551156;6018.127371531125;6187.041008643668;6188.44304012641;6245.40668636481;4282.400463935051;6322.367763223687;6342.9365706342805;6363.9045414318825;6366.808995730135;4465.977670111645;6174.598651087665;6414.454771344442;6421.64680942548;6557;6355.68221588921;6430.549861509696;6513.283538810727;6537.5423720339595;6546.836329091763;6370.108184854124;6377.202849643794;6386.425221730066;6386.8722625547225;6389.808305750788;15487;15621.218939053002;15718.764218536731;15931.362745490282;17589.648207035916;3096.2259435141214;3201.5197720341903;3204.423203823311;3261.5270785735934;3291.4240007998505;10657.360558366523;11443.675967882982;11655.42727933775;11809.763804723858;15734;2011.6580181369202;2059.4130672758342;2063.9484012899798;2065.700473431359;2068.038362161587;87800;92783.53608231916;92943.53528232315;93172;93968;12057.276563406223;12298.20061695987;12346.444410001392;12362.567310144103;12826.80759788604;17872;17892.10539473021;17906.194221260073;17910.10449477521;17951.73072403915;8873.447146749293;8931.598078086372;8978.86531702025;9029.593668284942;9032.23226025782;8250.504969701837;8274.751757447286;8402.705905293285;8552.486850788973;8561.743147705578;4995.150824359867;5054.418741844662;5233.921491177635;5341.732913354317;5483.067878460672;1873.6159087387052;2191.2878314547515;2210.977890221109;2217.7116974793175;2246.0117548278704;935.4948327902993;947.3273975477488;964.7539877331236;988.6737376665683;995.7062666513395;19727.309544165884;19986.300479483438;20077.49806254855;21186.470338241357;21305;972.7422233108232;984.0602224875227;985.5023213277249;986.6990567876867;990.4601991914424;11549.711257218636;11689.18175727708;11760.411979400995;11886.940565297291;11899.464524096436;639362;661485;663344;667693;700628;105661;106368.46815765872;107429;108797;110401;1075.2849355178719;1082.653550863716;1111.0889070961896;1114.9609763658234;1122.5790328626765;18272;18407.447776566725;18721.157547910105;18810.717839232697;18905.71641425407;45846.54153458423;46030.84922876955;47995;49476.50523494719;50026.249606256664;64546;64987.67506162439;65824.34175658373;65831.46459361102;67288;9380.624775009197;9518.333716639769;9526.761830954281;9682.75793105178;9710.951445242728;13935.860641393456;14292.642683932569;14474.131552106906;14513.78229326582;15069.773953390488;8823.60293786794;9802.65690700822;10310.536025879004;12269.38653067361;12320.322382268703;12623.30571818522;12660.87339126597;12812.679683008;12857.485700571873;13076.542321018718;13229.933850331072;13310.06829521963;13357;13441.327933603085;13493.527726529917;9608.423494590346;9723;9724.95137524308;9882.604695812088;9959.651412200537;12912.483500659868;12945;13073.607791766262;13179.538716145273;13237.735245295233 02:12:21 INFO - PROCESS | 21934 | |3;mootools.html;1505.4204131409404;1505.9924700376496;1512.2136489025706;1532.08075154907;1537.6924615076985;8039.6382162802665;8535.018472875621;8536.274416674582;8552.102029286922;8558.358123140759;7496.662650180741;7665.616719164047;8252;8376.204260595234;8434.704785332515;2424.854508729482;2464.2484042367073;2466.691663542057;2470.8023358131395;2475.6781618389673;2399.2657548459197;2437.2810021043833;2437.3297343230665;2442.023190723707;2448.5837407640743;2417.625268083441;2438.743931887153;2456.4841383309526;2460.065175233409;2465.2480993297036;2292.2321022457495;2368.3842201027683;2384.129792625688;2409.963850542243;2423.151896836111;2355.1756885090253;2388.295452841416;2407.3018824540864;2410.0480310277344;2410.9397265068337;8667;8727.04002559718;8736.301095912311;8741.606627701769;8745.344099192522;5117;5247.47525247476;5250.684958902478;5257.421683614799;5263.499967503081;3390.491426286052;3432.828358582061;3479.7216222702123;3500.1599616092208;3545.680888720002;1375.4841934274648;1408.837983631875;1411.174462939182;1412.5691664042463;1487.8735307498766;10133.543990520444;11750.471228794724;11769.941150294278;11778.057755379548;11791.882081179165;4593.425821772278;4686.086213188463;4686.1799185143145;4717.3159891816185;4739.691920025188;9473.242140628734;9489;9490.715278541655;9493.762655933519;9495.905040949501;5104.795808167706;5269.683818970874;5272.103742363807;5273.446288139767;5277.076511610453;4330.805113769888;4452.799624016927;4854.126257273725;4963.8014479421145;5002.924956125661;3871.670907972854;3952.584978577208;3958.8614398495915;3987.8404863805704;4030.395440683862;1215.1797536662718;1222.5109956017664;1241.503398640551;1243.1981372015;1251.599488163779;854.6111519258723;859.5831021954347;866.5450638414757;872.1409411729442;894.3918135667678;5896.6756828374855;5970.164177015221;6028.668423236764;6054.606450580698;6109.289398968296;592.7895597063067;610.0239616613383;613.9723712432951;626.3454689849119;628.3496581038694;3882.456456096149;3908.5895980921596;4121.505419349637;4180.456540649696;4184.497860256728;10090.142337901214;10315.381077135396;10430.791384172275;10431.21765867563;10443.791124177475;10230.6419275325;10259.230557708202;10302;10303.948480257552;10311.381317120997;7806.57063861493;8056.355491560661;8091.554964476893;8091.797705057303;8160.143184965612;5879.353271140086;5957.76168953237;6033.909491357545;6108.419700128481;6137.41694539018;9186.219171370509;9258.72223833286;9265.490398028172;9267.953660231655;9326.626934922528;9211.493367864832;9399.624015039322;9413.52932353366;9554.426734395958;9555.140037396668;7384.150822655463;7591.848163036819;7644.732434364771;7644.847103058019;7655.770326890202;8045.275925166763;8067.475614085066;8115.350771938112;8124.268815806606;8155.918440815516;7126.572405655677;7135;7302.926970730225;7305.488615796843;7372.668229929559;7288.96355518219;7347.118345798538;7412;7412.888806667796;7439.107307122963;6407.1350367701625;6478.449331806849;6487.0593763903025;6490.8052758417325;6499.447546958372;5082.872928176826;5087.847364579068;5152.690838549699;5157.535821776059;5316.787328506816;5104.540591346797;5136.794528218829;5142.408623008402;5156.458571850052;5338.813141540028 02:12:21 INFO - PROCESS | 21934 | |4;prototype.html;400.90979529605863;415.814962341758;418.8764314527214;427.5809706487642;429.259527315381;5124.5914420222525;5243.659162154458;5294.788208471657;5344.599155063372;5387.380451248103;5147.2021836615295;5255.579553635709;5264.894702105956;5275.868103297419;5279.762410691518;4282.657387409015;4291.8497852575165;4293.141371725652;4309.547497512763;4325.2647050001415;3982.3030969580354;4102.671786257107;4319.848805291816;4320.5031421386475;4332.631726303268;4256.170046840867;4260.3822445745345;4263.079790343609;4279.272523670983;4289.227938970984;4231.830726770941;4233.894152646193;4249.787510624456;4265.9786701066605;4285.442892423996;3547.4324108142573;3888.6500214980506;3901.8049097545295;3907.5701672816253;3915.8237879295502;5142.820001299937;5146.742662866879;5153.56194723449;5194.584433245332;5198.532132108091;4763.976180119112;4774.928376074362;4775.403074615673;4787.80848766049;4795.136875362434;5371.650842695251;5516;5528.502434780889;5535.197396377503;5541.030319694038;4082.9183416331507;4084.7344922579937;4090.6522945549664;4093.2018256440006;4107.240160570274;5640.8154287599655;5648.209250704864;5666.546676265848;5673.149027645886;5683.175939488751;4027.375756757707;4152.968333116842;4164.541900390955;4168.562300958416;4176.5405805362;4213.494380674339;4219.430376899079;4235.69154810629;4245.745255284693;4254.446921900133;4789.688670236424;4795.784189711471;4795.880102997383;4800.711957282574;4812;5352.0213482306035;5571.804986825523;5588.161775733591;5606.271184745957;5607.831765047055;1356.6269275948996;1380.9240491773048;1398.9020768546306;1414.370605080729;1416.525463969561;973.1922504321396;980.1717548671361;994.8806143262855;1000.2398177385239;1006.436395618456;5167;5203.699075231192;5216.295800067019;5218.877941242575;5227.215917612389;5442.183672449085;5480.835574932758;5494.203340515603;5502.560899437852;5556.833295001156;5000.6748211723925;5161.406438259648;5184.870378240499;5213.3056756553515;5263.7104959226845;2917.5186094294204;2936.9133420634503;2944.9411011779644;2948.6756456789735;2950.2181921790743;5724.685142317129;5731.570132240098;5754.223179870748;5758.683272419973;5763.394843541451;5531.280933478622;5646.4353564644025;5842.692136606017;5884.823455296348;5886.970565147147;5966.552508561876;5984.713286643392;5989.64062156272;6005.249343832021;6008.909866352008;5521.481592562078;5656.60403771732;5769.278840144982;5772.884542309214;5778.913316300175;5609.88780224385;5637.802676906289;5638.774449021993;5651.321841379061;5656.6888821115235;5875.265591801025;5904.439078287556;5934.228550288435;5951.375105614022;5969.820905372846;3996.8409161342884;4110.403991421167;4113.621936651255;4114.897127571835;4134.813933373066;5414;5616.9047035827625;5658.292713410824;5675.602707810413;5695.174199741097;5390.460953904578;5434.891302174014;5546.94917795219;5555.527800120942;5575.916361254665;5559.4162612926675;5567.526760225264;5590.189422533791;5595.496405323541;5602.215689803512;5327.907778905393;5518.19986102021;5556.222128902038;5562.805301814417;5571.192177134374;5559.138333558305;5568.832935011957;5575.247341608993;5595.328560572757;5615.809834164761;5426.70153141581;5462.235287059735;5476.479734425223;5493.395726470005;5503.862403439946 02:12:21 INFO - PROCESS | 21934 | |5;yui.html;793.8769490728939;815.3436483630675;840.9159084091588;923.1968187676721;929.8419268724318;14021.509247176338;15688.607784805386;15907.602309942256;15926.20368981552;16034.358625654975;16023.83976160241;16830.66338673229;16882.831171688278;16951.660966780688;16985.150742462858;710.7015053677468;729.1032030602362;825.6160885188372;837.7570504553703;840.0591337701783;809.3848675007008;824.4764574495179;832.1304237072274;838.8909441772561;839.1147339556766;856.9485830850169;858.1847245117164;859.6905114158898;861.336770686571;861.8319427711599;737.1633196322159;756.3533179131819;857.5755001274377;857.6055014693248;857.6483641706901;613.4601550635413;628.3151365012159;631.8483563944666;631.9304876463585;632.5003247434568;14589.927050364786;14593.489227876973;14602.26988650574;14611.123332600078;14738.705225895423;14850.554483365517;15072.472463463726;15084.692550344615;15085.170315632637;15665.608359790982;14587.343569539396;14623;14684.339204735812;14780.334884930096;14826.184559849204;4548.8862778430475;4589.632829373642;4615.215413379734;4740.336352910595;4743.075100355432;81864.9999999994;82539.17460825374;82707.5864620679;82982.58508707478;82985.58507207486;12420.192687475284;12501.624951251477;12516.31160286193;12545.184563003559;12656.493740250471;23057.19299824498;23312;23321.53356932852;23333.300000999996;23582.646260306108;8858.11418858122;9008.774780630536;9050.27597792175;9061;9078.09219078087;4912.828051018198;4939.6542242042715;4951.752412379366;5046.243063540498;5061.949380506221;3381.6618338166477;3446.2590543032866;3463.0822831949554;3481.8433170507387;3540.097275194809;637.5059329020047;640.3756337570924;643.7489379142045;653.6274323635578;657.710607332772;700.166801506206;739.5303981971487;785.4815821557744;790.537535541703;823.9958800206081;10498.42258675765;10903.126397932334;11055.11559075272;11238.66284011481;11295.152863535268;780.149636895775;782.5852298281806;796.9880451793227;797.4139007829241;807.7859367267562;4793.472718001017;5091.287219789232;5091.7963281469065;5093.719845408538;5103.260027296095;80277;82074.58962705148;82802.58598706967;83499.16500835035;84878.57560712157;58966.70516647475;59910;65608.67195663991;65952.67023664851;71841;58194;59653;59676.403235967955;60071.39928600746;60570.69714651487;11056.889431105586;11480.598179063692;11541.192116552096;11793.410329483662;11845.703857403634;40886.79556602198;41683.16633667249;41947.790261048496;42272;42978;50816.491835082656;51844.481555183964;52691.473085268655;52766.62550185174;54791.17813232725;13436.462541498231;13786.379612917643;14180;14267.500637477639;14440.566782996528;16018;16263.024218546925;16283.674326513641;16372.426965056165;16597.502074937773;15151.772723409378;15440.45958391451;15444.150571718663;15710;15921.761173582638;15207.543773686808;15235.314410851317;16192;16235.59411014734;16273.43042993537;14486.348114334669;14579.271036448346;15411.845881541489;15449.073055616249;15458.5362439127;11682.24065435744;11736.354500502553;11872.168948173545;12166.087543434101;12394.938025309815;9560.235181185628;10188.388696678223;10201.336913100624;10292.279540432397;10304.793904122025 02:12:21 INFO - PROCESS | 21934 | __end_tp_report 02:12:21 INFO - PROCESS | 21934 | __start_cc_report 02:12:21 INFO - PROCESS | 21934 | _x_x_mozilla_cycle_collect,953 02:12:21 INFO - PROCESS | 21934 | __end_cc_report 02:12:21 INFO - PROCESS | 21934 | __startTimestamp1486635141534__endTimestamp 02:12:21 INFO - PROCESS | 21934 | 02:12:21 INFO - PROCESS | 21934 | ------- Summary: start ------- 02:12:21 INFO - PROCESS | 21934 | Number of tests: 6 02:12:21 INFO - PROCESS | 21934 | 02:12:21 INFO - PROCESS | 21934 | [#0] dojo.html Cycles:165 Average:5439.77 Median:5680.12 stddev:601.63 (10.6%) stddev-sans-first:578.40 02:12:21 INFO - PROCESS | 21934 | Values: 3248.8 3262.4 3273.1 3296.2 3301.2 5346.9 5384.9 5419.8 5422.9 5462.1 5463.1 5486.3 5499.7 5500.5 5506.0 4984.5 5006.8 5039.5 5065.2 5079.7 4924.7 5025.2 5076.5 5080.7 5110.5 4882.6 4894.0 4902.9 4932.4 5023.0 4982.5 5028.5 5028.7 5030.1 5057.4 4942.1 4976.4 4995.5 5007.7 5008.2 5154.0 5235.5 5304.4 5362.1 5382.4 5843.5 5850.4 5859.6 5864.1 5874.9 5881.1 5908.1 5912.1 5920.7 5946.3 5290.6 5326.0 5406.2 5460.3 5483.4 5896.5 5896.8 5901.1 5921.4 5939.0 5766.1 5768.3 5773.6 5778.5 5779.3 5629.7 5667.8 5720.6 5775.7 5786.9 5759.3 5824.4 5824.7 5838.6 5855.7 5798.7 5801.5 5806.5 5808.4 5833.3 4419.7 4421.4 4478.4 4542.5 4569.7 4001.8 4031.0 4050.6 4067.8 4079.5 5674.1 5680.1 5681.8 5683.0 5685.7 5660.9 5752.3 5765.7 5915.8 5931.4 5527.1 5592.6 5594.5 5626.6 5632.3 4933.4 4944.2 4961.2 4964.7 4986.8 5923.3 5944.5 6084.9 6088.8 6099.7 5804.9 5856.6 5859.3 5927.2 5959.1 6141.8 6144.2 6150.5 6153.3 6154.4 5551.1 5777.6 5834.4 5837.3 5840.9 5672.4 5770.2 5811.4 5826.1 5946.0 5714.7 5752.2 5763.3 5766.7 5766.8 5764.7 5769.6 5778.3 5782.0 5787.7 5483.0 5499.4 5655.6 5684.1 5702.0 5816.8 5817.4 5817.4 5818.2 5820.1 5656.0 5672.1 5677.8 5688.4 5714.1 02:12:21 INFO - PROCESS | 21934 | 02:12:21 INFO - PROCESS | 21934 | [#1] ext.html Cycles:180 Average:60904.09 Median:42885.69 stddev:105192.63 (245.3%) stddev-sans-first:105390.10 02:12:21 INFO - PROCESS | 21934 | Values: 545.0 576.2 602.7 619.3 650.6 39568.8 53373.0 54429.0 54456.5 54492.5 51542.5 51846.2 51971.2 51997.0 52155.7 8605.5 8805.7 9497.1 9693.5 11294.4 11418.3 11522.1 11535.8 11538.4 11544.2 11570.0 11690.6 11698.6 11701.3 11707.6 11562.5 11601.9 11627.4 11628.8 11635.4 11421.9 11516.7 11520.9 11522.7 11523.7 48608.3 49095.5 49097.5 49100.3 49142.3 4525.8 4542.8 4617.2 4656.3 4688.7 34754.3 35014.8 35395.8 35709.0 36454.0 4599.3 4612.6 4661.0 4683.6 4715.4 361682.0 361900.0 362433.0 362434.0 362579.2 71330.3 71674.3 71740.0 71762.6 71812.6 77560.0 77643.6 77692.6 77749.6 77778.0 18157.3 18559.2 18565.9 18575.0 18584.3 14466.0 14859.0 15275.6 15559.0 15715.5 21323.5 21547.6 21602.5 21650.0 21836.7 7142.0 7271.7 7292.0 7323.0 7422.3 981.3 992.1 1001.8 1002.8 1005.8 32853.5 33845.8 33919.3 34299.5 34504.5 1126.3 1145.5 1150.7 1158.2 1163.7 17830.6 18031.6 18112.5 18190.1 18258.2 452822.0 453641.0 454143.0 457387.0 458625.0 68555.3 68781.3 68852.3 68861.0 68879.7 1508.3 1539.1 1541.4 1549.4 1574.8 31040.7 32672.8 32891.2 33175.3 33211.3 58816.0 59490.7 59592.4 59686.4 59700.4 377293.0 378371.0 380020.0 380631.0 380797.0 40479.8 45520.8 51546.0 51752.7 53441.7 54812.0 55015.7 55424.4 56178.7 57513.7 44870.0 45564.0 46208.3 46361.8 46458.5 44109.2 45921.3 46016.3 47005.1 47521.3 45514.0 45702.5 45826.8 45840.3 46187.0 36218.0 40909.4 41126.2 41161.0 41662.2 31321.5 31341.5 31414.5 31451.4 31728.8 02:12:21 INFO - PROCESS | 21934 | 02:12:21 INFO - PROCESS | 21934 | [#2] jquery.html Cycles:180 Average:35635.19 Median:3232.98 stddev:109576.32 (3389.3%) stddev-sans-first:109851.83 02:12:21 INFO - PROCESS | 21934 | Values: 435.7 439.4 443.2 443.7 462.3 20560.5 22110.2 22412.4 22507.1 22857.4 22639.0 23223.3 23265.4 23336.9 23572.0 5975.1 6018.1 6187.0 6188.4 6245.4 4282.4 6322.4 6342.9 6363.9 6366.8 4466.0 6174.6 6414.5 6421.6 6557.0 6355.7 6430.5 6513.3 6537.5 6546.8 6370.1 6377.2 6386.4 6386.9 6389.8 15487.0 15621.2 15718.8 15931.4 17589.6 3096.2 3201.5 3204.4 3261.5 3291.4 10657.4 11443.7 11655.4 11809.8 15734.0 2011.7 2059.4 2063.9 2065.7 2068.0 87800.0 92783.5 92943.5 93172.0 93968.0 12057.3 12298.2 12346.4 12362.6 12826.8 17872.0 17892.1 17906.2 17910.1 17951.7 8873.4 8931.6 8978.9 9029.6 9032.2 8250.5 8274.8 8402.7 8552.5 8561.7 4995.2 5054.4 5233.9 5341.7 5483.1 1873.6 2191.3 2211.0 2217.7 2246.0 935.5 947.3 964.8 988.7 995.7 19727.3 19986.3 20077.5 21186.5 21305.0 972.7 984.1 985.5 986.7 990.5 11549.7 11689.2 11760.4 11886.9 11899.5 639362.0 661485.0 663344.0 667693.0 700628.0 105661.0 106368.5 107429.0 108797.0 110401.0 1075.3 1082.7 1111.1 1115.0 1122.6 18272.0 18407.4 18721.2 18810.7 18905.7 45846.5 46030.8 47995.0 49476.5 50026.2 64546.0 64987.7 65824.3 65831.5 67288.0 9380.6 9518.3 9526.8 9682.8 9711.0 13935.9 14292.6 14474.1 14513.8 15069.8 8823.6 9802.7 10310.5 12269.4 12320.3 12623.3 12660.9 12812.7 12857.5 13076.5 13229.9 13310.1 13357.0 13441.3 13493.5 9608.4 9723.0 9725.0 9882.6 9959.7 12912.5 12945.0 13073.6 13179.5 13237.7 02:12:21 INFO - PROCESS | 21934 | 02:12:21 INFO - PROCESS | 21934 | [#3] mootools.html Cycles:180 Average:5605.04 Median:5249.08 stddev:3046.86 (58.0%) stddev-sans-first:3039.83 02:12:21 INFO - PROCESS | 21934 | Values: 1505.4 1506.0 1512.2 1532.1 1537.7 8039.6 8535.0 8536.3 8552.1 8558.4 7496.7 7665.6 8252.0 8376.2 8434.7 2424.9 2464.2 2466.7 2470.8 2475.7 2399.3 2437.3 2437.3 2442.0 2448.6 2417.6 2438.7 2456.5 2460.1 2465.2 2292.2 2368.4 2384.1 2410.0 2423.2 2355.2 2388.3 2407.3 2410.0 2410.9 8667.0 8727.0 8736.3 8741.6 8745.3 5117.0 5247.5 5250.7 5257.4 5263.5 3390.5 3432.8 3479.7 3500.2 3545.7 1375.5 1408.8 1411.2 1412.6 1487.9 10133.5 11750.5 11769.9 11778.1 11791.9 4593.4 4686.1 4686.2 4717.3 4739.7 9473.2 9489.0 9490.7 9493.8 9495.9 5104.8 5269.7 5272.1 5273.4 5277.1 4330.8 4452.8 4854.1 4963.8 5002.9 3871.7 3952.6 3958.9 3987.8 4030.4 1215.2 1222.5 1241.5 1243.2 1251.6 854.6 859.6 866.5 872.1 894.4 5896.7 5970.2 6028.7 6054.6 6109.3 592.8 610.0 614.0 626.3 628.3 3882.5 3908.6 4121.5 4180.5 4184.5 10090.1 10315.4 10430.8 10431.2 10443.8 10230.6 10259.2 10302.0 10303.9 10311.4 7806.6 8056.4 8091.6 8091.8 8160.1 5879.4 5957.8 6033.9 6108.4 6137.4 9186.2 9258.7 9265.5 9268.0 9326.6 9211.5 9399.6 9413.5 9554.4 9555.1 7384.2 7591.8 7644.7 7644.8 7655.8 8045.3 8067.5 8115.4 8124.3 8155.9 7126.6 7135.0 7302.9 7305.5 7372.7 7289.0 7347.1 7412.0 7412.9 7439.1 6407.1 6478.4 6487.1 6490.8 6499.4 5082.9 5087.8 5152.7 5157.5 5316.8 5104.5 5136.8 5142.4 5156.5 5338.8 02:12:21 INFO - PROCESS | 21934 | 02:12:21 INFO - PROCESS | 21934 | [#4] prototype.html Cycles:180 Average:4695.27 Median:5264.30 stddev:1346.07 (25.6%) stddev-sans-first:1310.69 02:12:21 INFO - PROCESS | 21934 | Values: 400.9 415.8 418.9 427.6 429.3 5124.6 5243.7 5294.8 5344.6 5387.4 5147.2 5255.6 5264.9 5275.9 5279.8 4282.7 4291.8 4293.1 4309.5 4325.3 3982.3 4102.7 4319.8 4320.5 4332.6 4256.2 4260.4 4263.1 4279.3 4289.2 4231.8 4233.9 4249.8 4266.0 4285.4 3547.4 3888.7 3901.8 3907.6 3915.8 5142.8 5146.7 5153.6 5194.6 5198.5 4764.0 4774.9 4775.4 4787.8 4795.1 5371.7 5516.0 5528.5 5535.2 5541.0 4082.9 4084.7 4090.7 4093.2 4107.2 5640.8 5648.2 5666.5 5673.1 5683.2 4027.4 4153.0 4164.5 4168.6 4176.5 4213.5 4219.4 4235.7 4245.7 4254.4 4789.7 4795.8 4795.9 4800.7 4812.0 5352.0 5571.8 5588.2 5606.3 5607.8 1356.6 1380.9 1398.9 1414.4 1416.5 973.2 980.2 994.9 1000.2 1006.4 5167.0 5203.7 5216.3 5218.9 5227.2 5442.2 5480.8 5494.2 5502.6 5556.8 5000.7 5161.4 5184.9 5213.3 5263.7 2917.5 2936.9 2944.9 2948.7 2950.2 5724.7 5731.6 5754.2 5758.7 5763.4 5531.3 5646.4 5842.7 5884.8 5887.0 5966.6 5984.7 5989.6 6005.2 6008.9 5521.5 5656.6 5769.3 5772.9 5778.9 5609.9 5637.8 5638.8 5651.3 5656.7 5875.3 5904.4 5934.2 5951.4 5969.8 3996.8 4110.4 4113.6 4114.9 4134.8 5414.0 5616.9 5658.3 5675.6 5695.2 5390.5 5434.9 5546.9 5555.5 5575.9 5559.4 5567.5 5590.2 5595.5 5602.2 5327.9 5518.2 5556.2 5562.8 5571.2 5559.1 5568.8 5575.2 5595.3 5615.8 5426.7 5462.2 5476.5 5493.4 5503.9 02:12:21 INFO - PROCESS | 21934 | 02:12:21 INFO - PROCESS | 21934 | [#5] yui.html Cycles:180 Average:18112.23 Median:4602.42 stddev:22331.63 (485.2%) stddev-sans-first:22356.41 02:12:21 INFO - PROCESS | 21934 | Values: 793.9 815.3 840.9 923.2 929.8 14021.5 15688.6 15907.6 15926.2 16034.4 16023.8 16830.7 16882.8 16951.7 16985.2 710.7 729.1 825.6 837.8 840.1 809.4 824.5 832.1 838.9 839.1 856.9 858.2 859.7 861.3 861.8 737.2 756.4 857.6 857.6 857.6 613.5 628.3 631.8 631.9 632.5 14589.9 14593.5 14602.3 14611.1 14738.7 14850.6 15072.5 15084.7 15085.2 15665.6 14587.3 14623.0 14684.3 14780.3 14826.2 4548.9 4589.6 4615.2 4740.3 4743.1 81865.0 82539.2 82707.6 82982.6 82985.6 12420.2 12501.6 12516.3 12545.2 12656.5 23057.2 23312.0 23321.5 23333.3 23582.6 8858.1 9008.8 9050.3 9061.0 9078.1 4912.8 4939.7 4951.8 5046.2 5061.9 3381.7 3446.3 3463.1 3481.8 3540.1 637.5 640.4 643.7 653.6 657.7 700.2 739.5 785.5 790.5 824.0 10498.4 10903.1 11055.1 11238.7 11295.2 780.1 782.6 797.0 797.4 807.8 4793.5 5091.3 5091.8 5093.7 5103.3 80277.0 82074.6 82802.6 83499.2 84878.6 58966.7 59910.0 65608.7 65952.7 71841.0 58194.0 59653.0 59676.4 60071.4 60570.7 11056.9 11480.6 11541.2 11793.4 11845.7 40886.8 41683.2 41947.8 42272.0 42978.0 50816.5 51844.5 52691.5 52766.6 54791.2 13436.5 13786.4 14180.0 14267.5 14440.6 16018.0 16263.0 16283.7 16372.4 16597.5 15151.8 15440.5 15444.2 15710.0 15921.8 15207.5 15235.3 16192.0 16235.6 16273.4 14486.3 14579.3 15411.8 15449.1 15458.5 11682.2 11736.4 11872.2 12166.1 12394.9 9560.2 10188.4 10201.3 10292.3 10304.8 02:12:21 INFO - PROCESS | 21934 | -------- Summary: end -------- 02:12:21 INFO - PROCESS | 21934 | 02:12:22 INFO - TEST-INFO | 21934: exit 0 02:12:22 INFO - TEST-OK | dromaeo_css | took 1113362ms 02:12:22 INFO - TEST-START | kraken 02:12:22 INFO - operating with platform_type : linux_ 02:12:22 INFO - Initialising browser for kraken test... 02:12:22 INFO - TEST-INFO | started process 22249 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuTfAfD/profile http://localhost:55194/getInfo.html) 02:12:25 INFO - PROCESS | 22249 | __metrics Screen width/height:1600/1200 02:12:25 INFO - PROCESS | 22249 | colorDepth:24 02:12:25 INFO - PROCESS | 22249 | Browser inner width/height: 1024/697 02:12:25 INFO - PROCESS | 22249 | __metrics 02:12:26 INFO - PROCESS | 22249 | 1486635146346 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 02:12:26 INFO - PROCESS | 22249 | 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" 02:12:26 INFO - PROCESS | 22249 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:12:26 INFO - PROCESS | 22249 | console.error: PushService: 02:12:26 INFO - PROCESS | 22249 | stateChangeProcessEnqueue: Error transitioning state 02:12:26 INFO - PROCESS | 22249 | UnknownError 02:12:27 INFO - TEST-INFO | 22249: exit 0 02:12:27 INFO - Browser initialized. 02:12:27 INFO - Running cycle 1/1 for kraken test... 02:12:27 INFO - TEST-INFO | started process 22342 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuTfAfD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:12:39 INFO - PROCESS | 22342 | RSS: Main: 138371072 02:12:39 INFO - PROCESS | 22342 | 02:12:41 INFO - PROCESS | 22342 | Cycle 1(1): loaded http://localhost:55194/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55194/tests/kraken/driver.html?testName=audio-beat-detection) 02:12:42 INFO - PROCESS | 22342 | RSS: Main: 182562816 02:12:42 INFO - PROCESS | 22342 | 02:12:45 INFO - PROCESS | 22342 | Cycle 1(1): loaded http://localhost:55194/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55194/tests/kraken/driver.html?testName=audio-dft) 02:12:45 INFO - PROCESS | 22342 | RSS: Main: 172810240 02:12:45 INFO - PROCESS | 22342 | 02:12:48 INFO - PROCESS | 22342 | Cycle 1(1): loaded http://localhost:55194/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55194/tests/kraken/driver.html?testName=audio-fft) 02:12:49 INFO - PROCESS | 22342 | RSS: Main: 264187904 02:12:49 INFO - PROCESS | 22342 | 02:12:51 INFO - PROCESS | 22342 | Cycle 1(1): loaded http://localhost:55194/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55194/tests/kraken/driver.html?testName=audio-oscillator) 02:12:51 INFO - PROCESS | 22342 | RSS: Main: 170422272 02:12:51 INFO - PROCESS | 22342 | 02:12:53 INFO - PROCESS | 22342 | Cycle 1(1): loaded http://localhost:55194/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55194/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:12:54 INFO - PROCESS | 22342 | RSS: Main: 173191168 02:12:54 INFO - PROCESS | 22342 | 02:12:57 INFO - PROCESS | 22342 | Cycle 1(1): loaded http://localhost:55194/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55194/tests/kraken/driver.html?testName=imaging-darkroom) 02:12:58 INFO - PROCESS | 22342 | RSS: Main: 211877888 02:12:58 INFO - PROCESS | 22342 | 02:13:01 INFO - PROCESS | 22342 | Cycle 1(1): loaded http://localhost:55194/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55194/tests/kraken/driver.html?testName=imaging-desaturate) 02:13:02 INFO - PROCESS | 22342 | RSS: Main: 210083840 02:13:02 INFO - PROCESS | 22342 | 02:13:04 INFO - PROCESS | 22342 | Cycle 1(1): loaded http://localhost:55194/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55194/tests/kraken/driver.html?testName=json-parse-financial) 02:13:05 INFO - PROCESS | 22342 | RSS: Main: 209035264 02:13:05 INFO - PROCESS | 22342 | 02:13:06 INFO - PROCESS | 22342 | Cycle 1(1): loaded http://localhost:55194/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55194/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:13:07 INFO - PROCESS | 22342 | RSS: Main: 170889216 02:13:07 INFO - PROCESS | 22342 | 02:13:09 INFO - PROCESS | 22342 | Cycle 1(1): loaded http://localhost:55194/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55194/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:13:09 INFO - PROCESS | 22342 | RSS: Main: 178540544 02:13:09 INFO - PROCESS | 22342 | 02:13:11 INFO - PROCESS | 22342 | Cycle 1(1): loaded http://localhost:55194/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55194/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:13:12 INFO - PROCESS | 22342 | RSS: Main: 187572224 02:13:12 INFO - PROCESS | 22342 | 02:13:14 INFO - PROCESS | 22342 | Cycle 1(1): loaded http://localhost:55194/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55194/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:13:15 INFO - PROCESS | 22342 | RSS: Main: 196689920 02:13:15 INFO - PROCESS | 22342 | 02:13:18 INFO - PROCESS | 22342 | Cycle 1(1): loaded http://localhost:55194/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55194/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:13:18 INFO - PROCESS | 22342 | RSS: Main: 193175552 02:13:18 INFO - PROCESS | 22342 | 02:13:20 INFO - PROCESS | 22342 | Cycle 1(1): loaded http://localhost:55194/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55194/tests/kraken/driver.html?testName=ai-astar) 02:13:20 INFO - PROCESS | 22342 | RSS: Main: 245334016 02:13:20 INFO - PROCESS | 22342 | 02:13:20 INFO - PROCESS | 22342 | __start_tp_report 02:13:20 INFO - PROCESS | 22342 | _x_x_mozilla_page_load 02:13:20 INFO - PROCESS | 22342 | _x_x_mozilla_page_load_details 02:13:20 INFO - PROCESS | 22342 | |i|pagename|runs| 02:13:20 INFO - PROCESS | 22342 | |0;ai-astar;103;111;114;111;112;114;111;110;113;111 02:13:20 INFO - PROCESS | 22342 | |1;audio-beat-detection;148;117;118;160;118;118;121;117;139;117 02:13:20 INFO - PROCESS | 22342 | |2;audio-dft;161;162;162;161;161;161;161;160;160;159 02:13:20 INFO - PROCESS | 22342 | |3;audio-fft;75;75;75;107;75;75;75;75;96;75 02:13:20 INFO - PROCESS | 22342 | |4;audio-oscillator;82;83;111;83;81;81;95;82;82;100 02:13:20 INFO - PROCESS | 22342 | |5;imaging-gaussian-blur;117;118;118;117;116;117;117;117;117;116 02:13:20 INFO - PROCESS | 22342 | |6;imaging-darkroom;146;146;146;146;146;145;145;146;145;148 02:13:20 INFO - PROCESS | 22342 | |7;imaging-desaturate;97;96;96;97;96;96;95;96;96;95 02:13:20 INFO - PROCESS | 22342 | |8;json-parse-financial;51;49;49;48;48;51;49;49;49;48 02:13:20 INFO - PROCESS | 22342 | |9;json-stringify-tinderbox;62;63;62;63;63;64;63;63;63;63 02:13:20 INFO - PROCESS | 22342 | |10;stanford-crypto-aes;77;84;78;75;75;75;75;76;77;75 02:13:20 INFO - PROCESS | 22342 | |11;stanford-crypto-ccm;124;131;133;121;122;119;119;118;158;119 02:13:20 INFO - PROCESS | 22342 | |12;stanford-crypto-pbkdf2;157;156;163;158;156;155;156;158;163;162 02:13:20 INFO - PROCESS | 22342 | |13;stanford-crypto-sha256-iterative;63;60;60;61;61;59;61;60;59;60 02:13:20 INFO - PROCESS | 22342 | __end_tp_report 02:13:20 INFO - PROCESS | 22342 | __start_cc_report 02:13:20 INFO - PROCESS | 22342 | _x_x_mozilla_cycle_collect,1498 02:13:20 INFO - PROCESS | 22342 | __end_cc_report 02:13:20 INFO - PROCESS | 22342 | __startTimestamp1486635200699__endTimestamp 02:13:20 INFO - PROCESS | 22342 | 02:13:20 INFO - PROCESS | 22342 | ------- Summary: start ------- 02:13:20 INFO - PROCESS | 22342 | Number of tests: 14 02:13:20 INFO - PROCESS | 22342 | 02:13:20 INFO - PROCESS | 22342 | [#0] ai-astar Cycles:10 Average:111.00 Median:111.50 stddev:3.13 (2.8%) stddev-sans-first:1.45 02:13:20 INFO - PROCESS | 22342 | Values: 103.0 111.0 114.0 111.0 112.0 114.0 111.0 110.0 113.0 111.0 02:13:20 INFO - PROCESS | 22342 | 02:13:20 INFO - PROCESS | 22342 | [#1] audio-beat-detection Cycles:10 Average:127.30 Median:119.50 stddev:15.82 (13.2%) stddev-sans-first:14.90 02:13:20 INFO - PROCESS | 22342 | Values: 148.0 117.0 118.0 160.0 118.0 118.0 121.0 117.0 139.0 117.0 02:13:20 INFO - PROCESS | 22342 | 02:13:20 INFO - PROCESS | 22342 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 02:13:20 INFO - PROCESS | 22342 | Values: 161.0 162.0 162.0 161.0 161.0 161.0 161.0 160.0 160.0 159.0 02:13:20 INFO - PROCESS | 22342 | 02:13:20 INFO - PROCESS | 22342 | [#3] audio-fft Cycles:10 Average:80.30 Median:75.00 stddev:11.47 (15.3%) stddev-sans-first:12.00 02:13:20 INFO - PROCESS | 22342 | Values: 75.0 75.0 75.0 107.0 75.0 75.0 75.0 75.0 96.0 75.0 02:13:20 INFO - PROCESS | 22342 | 02:13:20 INFO - PROCESS | 22342 | [#4] audio-oscillator Cycles:10 Average:88.00 Median:82.00 stddev:10.42 (12.7%) stddev-sans-first:10.83 02:13:20 INFO - PROCESS | 22342 | Values: 82.0 83.0 111.0 83.0 81.0 81.0 95.0 82.0 82.0 100.0 02:13:20 INFO - PROCESS | 22342 | 02:13:20 INFO - PROCESS | 22342 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 02:13:20 INFO - PROCESS | 22342 | Values: 117.0 118.0 118.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 02:13:20 INFO - PROCESS | 22342 | 02:13:20 INFO - PROCESS | 22342 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 02:13:20 INFO - PROCESS | 22342 | Values: 146.0 146.0 146.0 146.0 146.0 145.0 145.0 146.0 145.0 148.0 02:13:20 INFO - PROCESS | 22342 | 02:13:20 INFO - PROCESS | 22342 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 02:13:20 INFO - PROCESS | 22342 | Values: 97.0 96.0 96.0 97.0 96.0 96.0 95.0 96.0 96.0 95.0 02:13:20 INFO - PROCESS | 22342 | 02:13:20 INFO - PROCESS | 22342 | [#8] json-parse-financial Cycles:10 Average:49.10 Median:49.00 stddev:1.10 (2.2%) stddev-sans-first:0.93 02:13:20 INFO - PROCESS | 22342 | Values: 51.0 49.0 49.0 48.0 48.0 51.0 49.0 49.0 49.0 48.0 02:13:20 INFO - PROCESS | 22342 | 02:13:20 INFO - PROCESS | 22342 | [#9] json-stringify-tinderbox Cycles:10 Average:62.90 Median:63.00 stddev:0.57 (0.9%) stddev-sans-first:0.50 02:13:20 INFO - PROCESS | 22342 | Values: 62.0 63.0 62.0 63.0 63.0 64.0 63.0 63.0 63.0 63.0 02:13:20 INFO - PROCESS | 22342 | 02:13:20 INFO - PROCESS | 22342 | [#10] stanford-crypto-aes Cycles:10 Average:76.70 Median:76.50 stddev:2.79 (3.6%) stddev-sans-first:2.96 02:13:20 INFO - PROCESS | 22342 | Values: 77.0 84.0 78.0 75.0 75.0 75.0 75.0 76.0 77.0 75.0 02:13:20 INFO - PROCESS | 22342 | 02:13:20 INFO - PROCESS | 22342 | [#11] stanford-crypto-ccm Cycles:10 Average:126.40 Median:123.00 stddev:12.26 (10.0%) stddev-sans-first:12.97 02:13:20 INFO - PROCESS | 22342 | Values: 124.0 131.0 133.0 121.0 122.0 119.0 119.0 118.0 158.0 119.0 02:13:20 INFO - PROCESS | 22342 | 02:13:20 INFO - PROCESS | 22342 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.40 Median:158.00 stddev:3.10 (2.0%) stddev-sans-first:3.24 02:13:20 INFO - PROCESS | 22342 | Values: 157.0 156.0 163.0 158.0 156.0 155.0 156.0 158.0 163.0 162.0 02:13:20 INFO - PROCESS | 22342 | 02:13:20 INFO - PROCESS | 22342 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.40 Median:60.50 stddev:1.17 (1.9%) stddev-sans-first:0.78 02:13:20 INFO - PROCESS | 22342 | Values: 63.0 60.0 60.0 61.0 61.0 59.0 61.0 60.0 59.0 60.0 02:13:20 INFO - PROCESS | 22342 | -------- Summary: end -------- 02:13:20 INFO - PROCESS | 22342 | 02:13:21 INFO - TEST-INFO | 22342: exit 0 02:13:21 INFO - TEST-OK | kraken | took 59444ms 02:13:21 INFO - SUITE-END | took 1172s 02:13:21 INFO - Completed test suite (00:19:32) 02:13:21 INFO - javascript benchmark 02:13:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3248.7888287261326, 3262.4127657021727, 3273.116258610176, 3296.241864371193, 3301.1912081540027, 5346.866328341794, 5384.946150538493, 5419.810306639263, 5422.864428389283, 5462.071447853865, 5463.125899856024, 5486.286782718251, 5499.7250137493065, 5500.477454641803, 5506.0, 4984.476629953857, 5006.774695138709, 5039.496050394968, 5065.214891691794, 5079.74601269935, 4924.704517728947, 5025.195968645017, 5076.492350764913, 5080.695158290514, 5110.488951104897, 4882.633802464811, 4894.021195760844, 4902.901941961159, 4932.358793356859, 5022.995400919813, 4982.501749825006, 5028.522290382408, 5028.673136246132, 5030.144875371196, 5057.393112826448, 4942.135126352874, 4976.42771081323, 4995.525425084611, 5007.749612519359, 5008.24876268559, 5154.0, 5235.502627250406, 5304.443033481468, 5362.088444964365, 5382.407935127133, 5843.503302219274, 5850.385709500526, 5859.648421094748, 5864.1203819427, 5874.941250587525, 5881.05903055509, 5908.113782932594, 5912.113183022538, 5920.674362910038, 5946.316173640043, 5290.629655924087, 5325.973370133163, 5406.162044883089, 5460.262864513319, 5483.424240454775, 5896.469317761422, 5896.7936122235515, 5901.144334071621, 5921.378255283219, 5939.0, 5766.135079738073, 5768.250127483484, 5773.624714393635, 5778.508826749773, 5779.2775903012125, 5629.662220266797, 5667.773289068474, 5720.628159169641, 5775.6823374714795, 5786.855328616818, 5759.2512973313205, 5824.446677565625, 5824.679642619697, 5838.562107841929, 5855.707214639251, 5798.710064496759, 5801.477866991991, 5806.477417032487, 5808.361080281165, 5833.300003999462, 4419.668524860648, 4421.40311058003, 4478.417805685305, 4542.545745425431, 4569.702969306984, 4001.8199181036925, 4030.9798451008146, 4050.594940505926, 4067.8372865085657, 4079.5104587449696, 5674.148877668383, 5680.11958146488, 5681.829545113653, 5683.033884239607, 5685.715714214272, 5660.915086273709, 5752.25220721312, 5765.654060756368, 5915.792947246911, 5931.377205393458, 5527.088030474926, 5592.636478628876, 5594.496495315441, 5626.634268772516, 5632.2678051853, 4933.383327084114, 4944.208926571731, 4961.206207006861, 4964.702117872939, 4986.800527978913, 5923.318818335861, 5944.464998150187, 6084.939150608526, 6088.817335479943, 6099.69501524922, 5804.94195058044, 5856.619319744203, 5859.296884373903, 5927.170196172453, 5959.0763431668165, 6141.815745527641, 6144.201253836973, 6150.477209437248, 6153.292371377349, 6154.353792851686, 5551.139573366135, 5777.595568310178, 5834.38738932406, 5837.299524056971, 5840.94159058421, 5672.404397538364, 5770.2498675171955, 5811.4479124483105, 5826.067829147486, 5946.048632218824, 5714.685692286964, 5752.165935939349, 5763.250777398911, 5766.682832444256, 5766.798162064308, 5764.7405866736935, 5769.567282453917, 5778.27771528559, 5781.971090144522, 5787.739551720267, 5483.0, 5499.395066542597, 5655.575831812548, 5684.090545512699, 5702.0306547887785, 5816.796412125555, 5817.389174136655, 5817.418258174149, 5818.2436283282905, 5820.097884827858, 5656.0, 5672.092465205547, 5677.801276955287, 5688.431156884279, 5714.142878568248], "value": 5400.867516195019, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.9616577232301, 576.1530550091367, 602.7347966894566, 619.3311223878212, 650.6193876582199, 39568.80215598925, 53373.0, 54429.0, 54456.455435445634, 54492.45507544933, 51542.48457515424, 51846.22230666534, 51971.22043169355, 51997.0, 52155.739221303935, 8605.526696031715, 8805.691800786974, 9497.145256926859, 9693.515324233795, 11294.435278236097, 11418.31490110592, 11522.135839812006, 11535.769284614302, 11538.423078846066, 11544.249623774469, 11570.0, 11690.649280521555, 11698.590549330775, 11701.2979221247, 11707.5902343418, 11562.479688414041, 11601.883981160165, 11627.360495172763, 11628.76742465146, 11635.418229088511, 11421.942890285496, 11516.654500365003, 11520.94239528797, 11522.654320370402, 11523.711907202303, 48608.27087593689, 49095.50904490945, 49097.50902491001, 49100.263496047584, 49142.26286605704, 4525.796339164745, 4542.841000564998, 4617.191991401525, 4656.32483289921, 4688.742119183444, 34754.30491390158, 35014.824925875466, 35395.823020884985, 35709.0, 36454.0, 4599.264117741145, 4612.630989520831, 4661.033757784915, 4683.648726345504, 4715.3869996900185, 361682.0, 361900.0, 362433.0, 362434.0, 362579.18710406544, 71330.2866971334, 71674.28325716677, 71740.0, 71762.64118679373, 71812.64093679603, 77560.0, 77643.61178194072, 77692.61153694196, 77749.61125194338, 77778.0, 18157.27370905149, 18559.164837582342, 18565.907170464332, 18575.0, 18584.349547765763, 14466.0, 14859.0, 15275.61810954735, 15559.0, 15715.528534143994, 21323.46691332698, 21547.56904861894, 21602.45993850135, 21650.0, 21836.672449913265, 7141.96429017862, 7271.745488907969, 7292.0, 7323.0, 7422.323652690214, 981.3032746749744, 992.062500936608, 1001.7846163074973, 1002.7844013536978, 1005.7938122684832, 32853.50719739206, 33845.83077084599, 33919.321613568085, 34299.48550771741, 34504.474972931406, 1126.3354620773619, 1145.5188820695328, 1150.6605551362368, 1158.2182027131653, 1163.7497937943383, 17830.643387132186, 18031.63936721258, 18112.547186320448, 18190.090495475175, 18258.178381972844, 452822.0, 453641.0, 454143.0, 457387.0, 458625.0, 68555.31444685589, 68781.3121868785, 68852.31147688559, 68861.0, 68879.65560172267, 1508.3392317738242, 1539.1150088698819, 1541.368039103962, 1549.4422008077083, 1574.8188958269716, 31040.68959310378, 32672.836635816668, 32891.17772055718, 33175.33649327048, 33211.33577328488, 58816.0, 59490.70254648699, 59592.40407596042, 59686.40313596982, 59700.40299596948, 377293.0, 378371.0, 380020.0, 380631.0, 380797.0, 40479.79760101181, 45520.77239613913, 51546.0, 51752.74123629358, 53441.732791335795, 54812.0, 55015.724921375135, 55424.445755543544, 56178.719106404205, 57513.712431437576, 44870.0, 45564.0, 46208.30687539622, 46361.76819115883, 46458.53541464542, 44109.21035031157, 45921.31118033166, 46016.309755353024, 47005.059898802516, 47521.28718069162, 45514.0, 45702.54297456983, 45826.77086614545, 45840.312395314766, 46187.0, 36218.0, 40909.38635920523, 41126.1774764509, 41161.0, 41662.166756665305, 31321.530177047818, 31341.52987705232, 31414.535961258185, 31451.370972580877, 31728.841355793997], "value": 22789.475264326717, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.6536553440015, 439.3848611943281, 443.21329639889206, 443.68498366160026, 462.2580757883597, 20560.485987850294, 22110.22614208501, 22412.439689007762, 22507.09971601138, 22857.42856428586, 22639.0, 23223.303300900956, 23265.418364540896, 23336.8833155834, 23572.0, 5975.073863551156, 6018.127371531125, 6187.041008643668, 6188.44304012641, 6245.40668636481, 4282.400463935051, 6322.367763223687, 6342.9365706342805, 6363.9045414318825, 6366.808995730135, 4465.977670111645, 6174.598651087665, 6414.454771344442, 6421.64680942548, 6557.0, 6355.68221588921, 6430.549861509696, 6513.283538810727, 6537.5423720339595, 6546.836329091763, 6370.108184854124, 6377.202849643794, 6386.425221730066, 6386.8722625547225, 6389.808305750788, 15487.0, 15621.218939053002, 15718.764218536731, 15931.362745490282, 17589.648207035916, 3096.2259435141214, 3201.5197720341903, 3204.423203823311, 3261.5270785735934, 3291.4240007998505, 10657.360558366523, 11443.675967882982, 11655.42727933775, 11809.763804723858, 15734.0, 2011.6580181369202, 2059.4130672758342, 2063.9484012899798, 2065.700473431359, 2068.038362161587, 87800.0, 92783.53608231916, 92943.53528232315, 93172.0, 93968.0, 12057.276563406223, 12298.20061695987, 12346.444410001392, 12362.567310144103, 12826.80759788604, 17872.0, 17892.10539473021, 17906.194221260073, 17910.10449477521, 17951.73072403915, 8873.447146749293, 8931.598078086372, 8978.86531702025, 9029.593668284942, 9032.23226025782, 8250.504969701837, 8274.751757447286, 8402.705905293285, 8552.486850788973, 8561.743147705578, 4995.150824359867, 5054.418741844662, 5233.921491177635, 5341.732913354317, 5483.067878460672, 1873.6159087387052, 2191.2878314547515, 2210.977890221109, 2217.7116974793175, 2246.0117548278704, 935.4948327902993, 947.3273975477488, 964.7539877331236, 988.6737376665683, 995.7062666513395, 19727.309544165884, 19986.300479483438, 20077.49806254855, 21186.470338241357, 21305.0, 972.7422233108232, 984.0602224875227, 985.5023213277249, 986.6990567876867, 990.4601991914424, 11549.711257218636, 11689.18175727708, 11760.411979400995, 11886.940565297291, 11899.464524096436, 639362.0, 661485.0, 663344.0, 667693.0, 700628.0, 105661.0, 106368.46815765872, 107429.0, 108797.0, 110401.0, 1075.2849355178719, 1082.653550863716, 1111.0889070961896, 1114.9609763658234, 1122.5790328626765, 18272.0, 18407.447776566725, 18721.157547910105, 18810.717839232697, 18905.71641425407, 45846.54153458423, 46030.84922876955, 47995.0, 49476.50523494719, 50026.249606256664, 64546.0, 64987.67506162439, 65824.34175658373, 65831.46459361102, 67288.0, 9380.624775009197, 9518.333716639769, 9526.761830954281, 9682.75793105178, 9710.951445242728, 13935.860641393456, 14292.642683932569, 14474.131552106906, 14513.78229326582, 15069.773953390488, 8823.60293786794, 9802.65690700822, 10310.536025879004, 12269.38653067361, 12320.322382268703, 12623.30571818522, 12660.87339126597, 12812.679683008, 12857.485700571873, 13076.542321018718, 13229.933850331072, 13310.06829521963, 13357.0, 13441.327933603085, 13493.527726529917, 9608.423494590346, 9723.0, 9724.95137524308, 9882.604695812088, 9959.651412200537, 12912.483500659868, 12945.0, 13073.607791766262, 13179.538716145273, 13237.735245295233], "value": 10130.120784180963, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1505.4204131409404, 1505.9924700376496, 1512.2136489025706, 1532.08075154907, 1537.6924615076985, 8039.6382162802665, 8535.018472875621, 8536.274416674582, 8552.102029286922, 8558.358123140759, 7496.662650180741, 7665.616719164047, 8252.0, 8376.204260595234, 8434.704785332515, 2424.854508729482, 2464.2484042367073, 2466.691663542057, 2470.8023358131395, 2475.6781618389673, 2399.2657548459197, 2437.2810021043833, 2437.3297343230665, 2442.023190723707, 2448.5837407640743, 2417.625268083441, 2438.743931887153, 2456.4841383309526, 2460.065175233409, 2465.2480993297036, 2292.2321022457495, 2368.3842201027683, 2384.129792625688, 2409.963850542243, 2423.151896836111, 2355.1756885090253, 2388.295452841416, 2407.3018824540864, 2410.0480310277344, 2410.9397265068337, 8667.0, 8727.04002559718, 8736.301095912311, 8741.606627701769, 8745.344099192522, 5117.0, 5247.47525247476, 5250.684958902478, 5257.421683614799, 5263.499967503081, 3390.491426286052, 3432.828358582061, 3479.7216222702123, 3500.1599616092208, 3545.680888720002, 1375.4841934274648, 1408.837983631875, 1411.174462939182, 1412.5691664042463, 1487.8735307498766, 10133.543990520444, 11750.471228794724, 11769.941150294278, 11778.057755379548, 11791.882081179165, 4593.425821772278, 4686.086213188463, 4686.1799185143145, 4717.3159891816185, 4739.691920025188, 9473.242140628734, 9489.0, 9490.715278541655, 9493.762655933519, 9495.905040949501, 5104.795808167706, 5269.683818970874, 5272.103742363807, 5273.446288139767, 5277.076511610453, 4330.805113769888, 4452.799624016927, 4854.126257273725, 4963.8014479421145, 5002.924956125661, 3871.670907972854, 3952.584978577208, 3958.8614398495915, 3987.8404863805704, 4030.395440683862, 1215.1797536662718, 1222.5109956017664, 1241.503398640551, 1243.1981372015, 1251.599488163779, 854.6111519258723, 859.5831021954347, 866.5450638414757, 872.1409411729442, 894.3918135667678, 5896.6756828374855, 5970.164177015221, 6028.668423236764, 6054.606450580698, 6109.289398968296, 592.7895597063067, 610.0239616613383, 613.9723712432951, 626.3454689849119, 628.3496581038694, 3882.456456096149, 3908.5895980921596, 4121.505419349637, 4180.456540649696, 4184.497860256728, 10090.142337901214, 10315.381077135396, 10430.791384172275, 10431.21765867563, 10443.791124177475, 10230.6419275325, 10259.230557708202, 10302.0, 10303.948480257552, 10311.381317120997, 7806.57063861493, 8056.355491560661, 8091.554964476893, 8091.797705057303, 8160.143184965612, 5879.353271140086, 5957.76168953237, 6033.909491357545, 6108.419700128481, 6137.41694539018, 9186.219171370509, 9258.72223833286, 9265.490398028172, 9267.953660231655, 9326.626934922528, 9211.493367864832, 9399.624015039322, 9413.52932353366, 9554.426734395958, 9555.140037396668, 7384.150822655463, 7591.848163036819, 7644.732434364771, 7644.847103058019, 7655.770326890202, 8045.275925166763, 8067.475614085066, 8115.350771938112, 8124.268815806606, 8155.918440815516, 7126.572405655677, 7135.0, 7302.926970730225, 7305.488615796843, 7372.668229929559, 7288.96355518219, 7347.118345798538, 7412.0, 7412.888806667796, 7439.107307122963, 6407.1350367701625, 6478.449331806849, 6487.0593763903025, 6490.8052758417325, 6499.447546958372, 5082.872928176826, 5087.847364579068, 5152.690838549699, 5157.535821776059, 5316.787328506816, 5104.540591346797, 5136.794528218829, 5142.408623008402, 5156.458571850052, 5338.813141540028], "value": 4523.811542403634, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.90979529605863, 415.814962341758, 418.8764314527214, 427.5809706487642, 429.259527315381, 5124.5914420222525, 5243.659162154458, 5294.788208471657, 5344.599155063372, 5387.380451248103, 5147.2021836615295, 5255.579553635709, 5264.894702105956, 5275.868103297419, 5279.762410691518, 4282.657387409015, 4291.8497852575165, 4293.141371725652, 4309.547497512763, 4325.2647050001415, 3982.3030969580354, 4102.671786257107, 4319.848805291816, 4320.5031421386475, 4332.631726303268, 4256.170046840867, 4260.3822445745345, 4263.079790343609, 4279.272523670983, 4289.227938970984, 4231.830726770941, 4233.894152646193, 4249.787510624456, 4265.9786701066605, 4285.442892423996, 3547.4324108142573, 3888.6500214980506, 3901.8049097545295, 3907.5701672816253, 3915.8237879295502, 5142.820001299937, 5146.742662866879, 5153.56194723449, 5194.584433245332, 5198.532132108091, 4763.976180119112, 4774.928376074362, 4775.403074615673, 4787.80848766049, 4795.136875362434, 5371.650842695251, 5516.0, 5528.502434780889, 5535.197396377503, 5541.030319694038, 4082.9183416331507, 4084.7344922579937, 4090.6522945549664, 4093.2018256440006, 4107.240160570274, 5640.8154287599655, 5648.209250704864, 5666.546676265848, 5673.149027645886, 5683.175939488751, 4027.375756757707, 4152.968333116842, 4164.541900390955, 4168.562300958416, 4176.5405805362, 4213.494380674339, 4219.430376899079, 4235.69154810629, 4245.745255284693, 4254.446921900133, 4789.688670236424, 4795.784189711471, 4795.880102997383, 4800.711957282574, 4812.0, 5352.0213482306035, 5571.804986825523, 5588.161775733591, 5606.271184745957, 5607.831765047055, 1356.6269275948996, 1380.9240491773048, 1398.9020768546306, 1414.370605080729, 1416.525463969561, 973.1922504321396, 980.1717548671361, 994.8806143262855, 1000.2398177385239, 1006.436395618456, 5167.0, 5203.699075231192, 5216.295800067019, 5218.877941242575, 5227.215917612389, 5442.183672449085, 5480.835574932758, 5494.203340515603, 5502.560899437852, 5556.833295001156, 5000.6748211723925, 5161.406438259648, 5184.870378240499, 5213.3056756553515, 5263.7104959226845, 2917.5186094294204, 2936.9133420634503, 2944.9411011779644, 2948.6756456789735, 2950.2181921790743, 5724.685142317129, 5731.570132240098, 5754.223179870748, 5758.683272419973, 5763.394843541451, 5531.280933478622, 5646.4353564644025, 5842.692136606017, 5884.823455296348, 5886.970565147147, 5966.552508561876, 5984.713286643392, 5989.64062156272, 6005.249343832021, 6008.909866352008, 5521.481592562078, 5656.60403771732, 5769.278840144982, 5772.884542309214, 5778.913316300175, 5609.88780224385, 5637.802676906289, 5638.774449021993, 5651.321841379061, 5656.6888821115235, 5875.265591801025, 5904.439078287556, 5934.228550288435, 5951.375105614022, 5969.820905372846, 3996.8409161342884, 4110.403991421167, 4113.621936651255, 4114.897127571835, 4134.813933373066, 5414.0, 5616.9047035827625, 5658.292713410824, 5675.602707810413, 5695.174199741097, 5390.460953904578, 5434.891302174014, 5546.94917795219, 5555.527800120942, 5575.916361254665, 5559.4162612926675, 5567.526760225264, 5590.189422533791, 5595.496405323541, 5602.215689803512, 5327.907778905393, 5518.19986102021, 5556.222128902038, 5562.805301814417, 5571.192177134374, 5559.138333558305, 5568.832935011957, 5575.247341608993, 5595.328560572757, 5615.809834164761, 5426.70153141581, 5462.235287059735, 5476.479734425223, 5493.395726470005, 5503.862403439946], "value": 4288.124145117265, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [793.8769490728939, 815.3436483630675, 840.9159084091588, 923.1968187676721, 929.8419268724318, 14021.509247176338, 15688.607784805386, 15907.602309942256, 15926.20368981552, 16034.358625654975, 16023.83976160241, 16830.66338673229, 16882.831171688278, 16951.660966780688, 16985.150742462858, 710.7015053677468, 729.1032030602362, 825.6160885188372, 837.7570504553703, 840.0591337701783, 809.3848675007008, 824.4764574495179, 832.1304237072274, 838.8909441772561, 839.1147339556766, 856.9485830850169, 858.1847245117164, 859.6905114158898, 861.336770686571, 861.8319427711599, 737.1633196322159, 756.3533179131819, 857.5755001274377, 857.6055014693248, 857.6483641706901, 613.4601550635413, 628.3151365012159, 631.8483563944666, 631.9304876463585, 632.5003247434568, 14589.927050364786, 14593.489227876973, 14602.26988650574, 14611.123332600078, 14738.705225895423, 14850.554483365517, 15072.472463463726, 15084.692550344615, 15085.170315632637, 15665.608359790982, 14587.343569539396, 14623.0, 14684.339204735812, 14780.334884930096, 14826.184559849204, 4548.8862778430475, 4589.632829373642, 4615.215413379734, 4740.336352910595, 4743.075100355432, 81864.9999999994, 82539.17460825374, 82707.5864620679, 82982.58508707478, 82985.58507207486, 12420.192687475284, 12501.624951251477, 12516.31160286193, 12545.184563003559, 12656.493740250471, 23057.19299824498, 23312.0, 23321.53356932852, 23333.300000999996, 23582.646260306108, 8858.11418858122, 9008.774780630536, 9050.27597792175, 9061.0, 9078.09219078087, 4912.828051018198, 4939.6542242042715, 4951.752412379366, 5046.243063540498, 5061.949380506221, 3381.6618338166477, 3446.2590543032866, 3463.0822831949554, 3481.8433170507387, 3540.097275194809, 637.5059329020047, 640.3756337570924, 643.7489379142045, 653.6274323635578, 657.710607332772, 700.166801506206, 739.5303981971487, 785.4815821557744, 790.537535541703, 823.9958800206081, 10498.42258675765, 10903.126397932334, 11055.11559075272, 11238.66284011481, 11295.152863535268, 780.149636895775, 782.5852298281806, 796.9880451793227, 797.4139007829241, 807.7859367267562, 4793.472718001017, 5091.287219789232, 5091.7963281469065, 5093.719845408538, 5103.260027296095, 80277.0, 82074.58962705148, 82802.58598706967, 83499.16500835035, 84878.57560712157, 58966.70516647475, 59910.0, 65608.67195663991, 65952.67023664851, 71841.0, 58194.0, 59653.0, 59676.403235967955, 60071.39928600746, 60570.69714651487, 11056.889431105586, 11480.598179063692, 11541.192116552096, 11793.410329483662, 11845.703857403634, 40886.79556602198, 41683.16633667249, 41947.790261048496, 42272.0, 42978.0, 50816.491835082656, 51844.481555183964, 52691.473085268655, 52766.62550185174, 54791.17813232725, 13436.462541498231, 13786.379612917643, 14180.0, 14267.500637477639, 14440.566782996528, 16018.0, 16263.024218546925, 16283.674326513641, 16372.426965056165, 16597.502074937773, 15151.772723409378, 15440.45958391451, 15444.150571718663, 15710.0, 15921.761173582638, 15207.543773686808, 15235.314410851317, 16192.0, 16235.59411014734, 16273.43042993537, 14486.348114334669, 14579.271036448346, 15411.845881541489, 15449.073055616249, 15458.5362439127, 11682.24065435744, 11736.354500502553, 11872.168948173545, 12166.087543434101, 12394.938025309815, 9560.235181185628, 10188.388696678223, 10201.336913100624, 10292.279540432397, 10304.793904122025], "value": 7655.517547140838, "unit": "score"}], "name": "dromaeo_css", "value": 7549.792452191066, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 114.0, 111.0, 112.0, 114.0, 111.0, 110.0, 113.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 117.0, 118.0, 160.0, 118.0, 118.0, 121.0, 117.0, 139.0, 117.0], "value": 127.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 161.0, 161.0, 161.0, 161.0, 160.0, 160.0, 159.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 107.0, 75.0, 75.0, 75.0, 75.0, 96.0, 75.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 111.0, 83.0, 81.0, 81.0, 95.0, 82.0, 82.0, 100.0], "value": 88.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 148.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 97.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 49.0, 48.0, 48.0, 51.0, 49.0, 49.0, 49.0, 48.0], "value": 49.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 63.0, 63.0, 64.0, 63.0, 63.0, 63.0, 63.0], "value": 62.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 84.0, 78.0, 75.0, 75.0, 75.0, 75.0, 76.0, 77.0, 75.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 131.0, 133.0, 121.0, 122.0, 119.0, 119.0, 118.0, 158.0, 119.0], "value": 126.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 156.0, 163.0, 158.0, 156.0, 155.0, 156.0, 158.0, 163.0, 162.0], "value": 158.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 60.0, 61.0, 61.0, 59.0, 61.0, 60.0, 59.0, 60.0], "value": 60.4, "unit": "score"}], "name": "kraken", "value": 1460.2000000000005, "alertThreshold": 2.0}]} 02:13:21 INFO - Return code: 0 02:13:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:13:22 ERROR - # TBPL SUCCESS # 02:13:22 INFO - Running post-action listener: _resource_record_post_action 02:13:22 INFO - [mozharness: 2017-02-09 10:13:22.014105Z] Finished run-tests step (success) 02:13:22 INFO - Running post-run listener: _resource_record_post_run 02:13:22 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 365809664; Read time: 8; Write time: 231248 02:13:22 INFO - TinderboxPrint: CPU usage
13.0% 02:13:22 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 02:13:22 INFO - TinderboxPrint: I/O write bytes / time
365,809,664 / 231,248 02:13:22 INFO - TinderboxPrint: CPU idle
8,264.9 (87.0%) 02:13:22 INFO - TinderboxPrint: CPU user
1,190.2 (12.5%) 02:13:22 INFO - TinderboxPrint: Swap in / out
0 / 0 02:13:22 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110088192; Read time: 0; Write time: 112320 02:13:22 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 255721472; Read time: 8; Write time: 118928 02:13:22 INFO - Running post-run listener: _upload_blobber_files 02:13:22 INFO - Blob upload gear active. 02:13:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:13:22 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 02:13:22 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'] 02:13:22 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 02:13:23 INFO - (blobuploader) - INFO - Open directory for files ... 02:13:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:13:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:13:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:13:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:13:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:13:23 INFO - (blobuploader) - INFO - Done attempting. 02:13:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:13:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:13:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:13:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:13:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:13:27 INFO - (blobuploader) - INFO - Done attempting. 02:13:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:13:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:13:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:13:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:13:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:13:28 INFO - (blobuploader) - INFO - Done attempting. 02:13:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:13:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:13:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:13:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:13:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:13:29 INFO - (blobuploader) - INFO - Done attempting. 02:13:29 INFO - (blobuploader) - INFO - Iteration through files over. 02:13:29 INFO - Return code: 0 02:13:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:13:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:13:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c79ddd9ae7b741d77caae292badafd1fb3e496c6b74c4810eebca96796df94f27d4c75cb70e515465b11ffd6472cfa4ca41c1cee918efe58c84850dab5032625", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/471738b18011a42ed61a14806d1ffba9dc7a663ca1cdb1134df393e9aaa14aa6c6a39c444c03a05d81c12eafcf942b2a5fc6716622d5f3046a8c6e5510a5deea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55a071cbbb5a3ea694c5394b08a2ac24af2df7a89e7b88c40aca5eb58d991b7dfaaa7687ce27410413e2cc8c11ebeff2ed4c799146c5f8bccbf6409d767b7dc9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6a56960b1b27c4f97f798034e3f7d0d61e78f48afaf5734f3abe6074452203b4cd7eb8f702d912e4d087ffc19160926eca4b59b3ea92106f7a07ec96a5359a0"} 02:13:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:13:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:13:29 INFO - Contents: 02:13:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c79ddd9ae7b741d77caae292badafd1fb3e496c6b74c4810eebca96796df94f27d4c75cb70e515465b11ffd6472cfa4ca41c1cee918efe58c84850dab5032625", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/471738b18011a42ed61a14806d1ffba9dc7a663ca1cdb1134df393e9aaa14aa6c6a39c444c03a05d81c12eafcf942b2a5fc6716622d5f3046a8c6e5510a5deea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55a071cbbb5a3ea694c5394b08a2ac24af2df7a89e7b88c40aca5eb58d991b7dfaaa7687ce27410413e2cc8c11ebeff2ed4c799146c5f8bccbf6409d767b7dc9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6a56960b1b27c4f97f798034e3f7d0d61e78f48afaf5734f3abe6074452203b4cd7eb8f702d912e4d087ffc19160926eca4b59b3ea92106f7a07ec96a5359a0"} 02:13:29 INFO - Running post-run listener: copy_logs_to_upload_dir 02:13:29 INFO - Copying logs to upload dir... 02:13:29 INFO - mkdir: /builds/slave/test/build/upload/logs 02:13:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.741115 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-02-09 02:13:28.928390) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 02:13:28.934875) ========= 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=ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c79ddd9ae7b741d77caae292badafd1fb3e496c6b74c4810eebca96796df94f27d4c75cb70e515465b11ffd6472cfa4ca41c1cee918efe58c84850dab5032625", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/471738b18011a42ed61a14806d1ffba9dc7a663ca1cdb1134df393e9aaa14aa6c6a39c444c03a05d81c12eafcf942b2a5fc6716622d5f3046a8c6e5510a5deea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55a071cbbb5a3ea694c5394b08a2ac24af2df7a89e7b88c40aca5eb58d991b7dfaaa7687ce27410413e2cc8c11ebeff2ed4c799146c5f8bccbf6409d767b7dc9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6a56960b1b27c4f97f798034e3f7d0d61e78f48afaf5734f3abe6074452203b4cd7eb8f702d912e4d087ffc19160926eca4b59b3ea92106f7a07ec96a5359a0"} build_url:https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011289 build_url: 'https://queue.taskcluster.net/v1/task/FUuKIePkQQaPW58P4plGAg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c79ddd9ae7b741d77caae292badafd1fb3e496c6b74c4810eebca96796df94f27d4c75cb70e515465b11ffd6472cfa4ca41c1cee918efe58c84850dab5032625", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/471738b18011a42ed61a14806d1ffba9dc7a663ca1cdb1134df393e9aaa14aa6c6a39c444c03a05d81c12eafcf942b2a5fc6716622d5f3046a8c6e5510a5deea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55a071cbbb5a3ea694c5394b08a2ac24af2df7a89e7b88c40aca5eb58d991b7dfaaa7687ce27410413e2cc8c11ebeff2ed4c799146c5f8bccbf6409d767b7dc9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6a56960b1b27c4f97f798034e3f7d0d61e78f48afaf5734f3abe6074452203b4cd7eb8f702d912e4d087ffc19160926eca4b59b3ea92106f7a07ec96a5359a0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 02:13:28.962454) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 02:13:28.962843) ========= 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=ed14279e38b05397cf4aa391000001a4-1486633971.902254-1899642995 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004428 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 02:13:29.046655) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 02:13:29.047044) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 02:13:29.047377) ========= ========= Total master_lag: 0.18 =========