builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-058 starttime: 1492098445.73 results: success (0) revision: d56c36ff0b8bc34183f056866de81a6a61c782ec ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 08:47:25.729837) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 08:47:25.730298) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 08:47:25.730599) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 08:47:25.905913) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 08:47:25.906220) ========= 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=0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-13 08:47:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 114M=0s 2017-04-13 08:47:26 (114 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.225898 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 08:47:26.148223) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 08:47:26.148805) ========= 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=0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017621 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 08:47:26.199514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 08:47:26.199843) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d56c36ff0b8bc34183f056866de81a6a61c782ec --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 d56c36ff0b8bc34183f056866de81a6a61c782ec --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=0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-13 08:47:26,234 truncating revision to first 12 chars 2017-04-13 08:47:26,234 Setting DEBUG logging. 2017-04-13 08:47:26,234 attempt 1/10 2017-04-13 08:47:26,234 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d56c36ff0b8b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-13 08:47:26,620 unpacking tar archive at: mozilla-aurora-d56c36ff0b8b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.711289 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 08:47:26.924386) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 08:47:26.924725) ========= script_repo_revision: d56c36ff0b8bc34183f056866de81a6a61c782ec ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 08:47:26.925091) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 08:47:26.925360) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 08:47:26.933802) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-04-13 08:47:26.934191) ========= /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=0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:47:27 INFO - MultiFileLogger online at 20170413 08:47:27 in /builds/slave/test 08:47:27 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 08:47:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:47:27 INFO - {'append_to_log': False, 08:47:27 INFO - 'base_work_dir': '/builds/slave/test', 08:47:27 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:47:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:47:27 INFO - 'branch': 'Mozilla-Aurora', 08:47:27 INFO - 'buildbot_json_path': 'buildprops.json', 08:47:27 INFO - 'config_files': ('talos/linux_config.py',), 08:47:27 INFO - 'default_actions': ('clobber', 08:47:27 INFO - 'read-buildbot-config', 08:47:27 INFO - 'download-and-extract', 08:47:27 INFO - 'populate-webroot', 08:47:27 INFO - 'create-virtualenv', 08:47:27 INFO - 'install', 08:47:27 INFO - 'run-tests'), 08:47:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:47:27 INFO - 'download_minidump_stackwalk': True, 08:47:27 INFO - 'download_symbols': 'ondemand', 08:47:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:47:27 INFO - 'tooltool.py': '/tools/tooltool.py', 08:47:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:47:27 INFO - '/tools/misc-python/virtualenv.py')}, 08:47:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:47:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:47:27 INFO - 'gecko_profile': False, 08:47:27 INFO - 'gecko_profile_interval': 0, 08:47:27 INFO - 'installer_path': 'installer.exe', 08:47:27 INFO - 'log_level': 'info', 08:47:27 INFO - 'log_name': 'talos', 08:47:27 INFO - 'log_to_console': True, 08:47:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:47:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:47:27 INFO - 'opt_config_files': (), 08:47:27 INFO - 'pip_index': False, 08:47:27 INFO - 'suite': 'dromaeojs', 08:47:27 INFO - 'system_bits': '32', 08:47:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:47:27 INFO - 'title': 'talos-linux64-ix-058', 08:47:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:47:27 INFO - 'use_talos_json': True, 08:47:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:47:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:47:27 INFO - 'work_dir': 'build'} 08:47:27 INFO - [mozharness: 2017-04-13 15:47:27.073500Z] Running clobber step. 08:47:27 INFO - Running pre-action listener: _resource_record_pre_action 08:47:27 INFO - Running main action method: clobber 08:47:27 INFO - rmtree: /builds/slave/test/build 08:47:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:47:28 INFO - Running post-action listener: _resource_record_post_action 08:47:28 INFO - [mozharness: 2017-04-13 15:47:28.518387Z] Finished clobber step (success) 08:47:28 INFO - [mozharness: 2017-04-13 15:47:28.518483Z] Running read-buildbot-config step. 08:47:28 INFO - Running pre-action listener: _resource_record_pre_action 08:47:28 INFO - Running main action method: read_buildbot_config 08:47:28 INFO - Using buildbot properties: 08:47:28 INFO - { 08:47:28 INFO - "project": "", 08:47:28 INFO - "product": "firefox", 08:47:28 INFO - "who": "ffxbld@noreply.mozilla.org", 08:47:28 INFO - "installer_path": "public/build/target.tar.bz2", 08:47:28 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:47:28 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 08:47:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 08:47:28 INFO - "stage_platform": "linux64-pgo", 08:47:28 INFO - "basedir": "/builds/slave/test", 08:47:28 INFO - "buildnumber": 885, 08:47:28 INFO - "platform": "ubuntu64_hw", 08:47:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:47:28 INFO - "slavebuilddir": "test", 08:47:28 INFO - "taskId": "cu_xHGPPTlWcrwja0Sjf2w", 08:47:28 INFO - "branch": "mozilla-aurora", 08:47:28 INFO - "script_repo_revision": "production", 08:47:28 INFO - "revision": "d56c36ff0b8bc34183f056866de81a6a61c782ec", 08:47:28 INFO - "slavename": "talos-linux64-ix-058", 08:47:28 INFO - "repo_path": "releases/mozilla-aurora" 08:47:28 INFO - } 08:47:28 INFO - Finding installer, test and symbols from parent task. 08:47:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cu_xHGPPTlWcrwja0Sjf2w'}, attempt #1 08:47:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cu_xHGPPTlWcrwja0Sjf2w'}, attempt #1 08:47:29 INFO - Task dependencies: Bs2ESpugQM-xNVlnGLjnGw 08:47:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw'}, attempt #1 08:47:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.tar.bz2 08:47:30 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/test_packages.json 08:47:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.crashreporter-symbols.zip 08:47:30 INFO - Running post-action listener: _resource_record_post_action 08:47:30 INFO - [mozharness: 2017-04-13 15:47:30.138775Z] Finished read-buildbot-config step (success) 08:47:30 INFO - [mozharness: 2017-04-13 15:47:30.138991Z] Running download-and-extract step. 08:47:30 INFO - Running pre-action listener: _resource_record_pre_action 08:47:30 INFO - Running main action method: download_and_extract 08:47:30 INFO - mkdir: /builds/slave/test/build/tests 08:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:30 INFO - trying https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.test_packages.json 08:47:30 INFO - Downloading https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:47:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:47:30 INFO - Downloaded 896 bytes. 08:47:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:47:30 INFO - Using the following test package requirements: 08:47:30 INFO - {u'common': [u'target.common.tests.zip'], 08:47:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:47:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:47:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:47:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:47:30 INFO - u'mozbase': [u'target.common.tests.zip'], 08:47:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:47:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:47:30 INFO - u'web-platform': [u'target.common.tests.zip', 08:47:30 INFO - u'target.web-platform.tests.zip'], 08:47:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:47:30 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:47:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.common.tests.zip 08:47:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:47:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.common.tests.zip into memory 08:47:31 INFO - Expected file size: 59543856 08:47:31 INFO - Obtained file size: 59543856 08:47:41 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:47:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.common.tests.zip 08:47:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:47:41 INFO - Fetch https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.common.tests.zip into memory 08:47:47 INFO - Expected file size: 59543856 08:47:47 INFO - Obtained file size: 59543856 08:47:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.talos.tests.zip 08:47:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:47:57 INFO - Fetch https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.talos.tests.zip into memory 08:47:59 INFO - Expected file size: 14404424 08:47:59 INFO - Obtained file size: 14404424 08:47:59 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')]} 08:47:59 INFO - trying https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.tar.bz2 08:47:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:47:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:48:00 INFO - Downloaded 66782249 bytes. 08:48:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.tar.bz2 08:48:00 INFO - mkdir: /builds/slave/test/properties 08:48:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:48:00 INFO - Writing to file /builds/slave/test/properties/build_url 08:48:00 INFO - Contents: 08:48:00 INFO - build_url:https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.tar.bz2 08:48:00 INFO - Running post-action listener: _resource_record_post_action 08:48:00 INFO - Running post-action listener: set_extra_try_arguments 08:48:00 INFO - [mozharness: 2017-04-13 15:48:00.550555Z] Finished download-and-extract step (success) 08:48:00 INFO - [mozharness: 2017-04-13 15:48:00.550651Z] Running populate-webroot step. 08:48:00 INFO - Running pre-action listener: _resource_record_pre_action 08:48:00 INFO - Running main action method: populate_webroot 08:48:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:48:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:48:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:48:00 INFO - '--tppagecycles', 08:48:00 INFO - '7'], 08:48:00 INFO - 'tests': ['tsvgm']}}, 08:48:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:48:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:48:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:48:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:48:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:48:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:48:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:48:00 INFO - 'talos_options': ['--disable-e10s'], 08:48:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:48:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:48:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:48:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:48:00 INFO - 'talos_options': ['--disable-e10s'], 08:48:00 INFO - 'tests': ['damp', 'tps']}, 08:48:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:48:00 INFO - 'tests': ['damp', 'tps']}, 08:48:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:48:00 INFO - 'tests': ['dromaeo_dom']}, 08:48:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:48:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:48:00 INFO - 'tests': ['basic_compositor_video']}, 08:48:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:48:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:48:00 INFO - 'tests': ['a11yr', 08:48:00 INFO - 'ts_paint', 08:48:00 INFO - 'tpaint', 08:48:00 INFO - 'sessionrestore', 08:48:00 INFO - 'sessionrestore_no_auto_restore', 08:48:00 INFO - 'tabpaint']}, 08:48:00 INFO - 'other-e10s': {'tests': ['a11yr', 08:48:00 INFO - 'ts_paint', 08:48:00 INFO - 'tpaint', 08:48:00 INFO - 'sessionrestore', 08:48:00 INFO - 'sessionrestore_no_auto_restore', 08:48:00 INFO - 'tabpaint']}, 08:48:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:48:00 INFO - 'ts_paint', 08:48:00 INFO - 'tpaint', 08:48:00 INFO - 'sessionrestore', 08:48:00 INFO - 'sessionrestore_no_auto_restore', 08:48:00 INFO - 'tabpaint']}, 08:48:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:48:00 INFO - 'ts_paint', 08:48:00 INFO - 'tpaint', 08:48:00 INFO - 'sessionrestore', 08:48:00 INFO - 'sessionrestore_no_auto_restore', 08:48:00 INFO - 'tabpaint']}, 08:48:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:48:00 INFO - 'tests': ['a11yr', 08:48:00 INFO - 'ts_paint', 08:48:00 INFO - 'tpaint', 08:48:00 INFO - 'sessionrestore', 08:48:00 INFO - 'sessionrestore_no_auto_restore', 08:48:00 INFO - 'tabpaint']}, 08:48:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:48:00 INFO - 'tests': ['a11yr', 08:48:00 INFO - 'ts_paint', 08:48:00 INFO - 'tpaint', 08:48:00 INFO - 'sessionrestore', 08:48:00 INFO - 'sessionrestore_no_auto_restore', 08:48:00 INFO - 'tabpaint']}, 08:48:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:48:00 INFO - 'tests': ['tsvgx', 08:48:00 INFO - 'tsvgr_opacity', 08:48:00 INFO - 'tart', 08:48:00 INFO - 'tscrollx', 08:48:00 INFO - 'cart', 08:48:00 INFO - 'tsvg_static']}, 08:48:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:48:00 INFO - 'tsvgr_opacity', 08:48:00 INFO - 'tart', 08:48:00 INFO - 'tscrollx', 08:48:00 INFO - 'cart', 08:48:00 INFO - 'tsvg_static']}, 08:48:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:48:00 INFO - 'talos_options': ['--disable-e10s'], 08:48:00 INFO - 'tests': ['tp5o']}, 08:48:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:48:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:48:00 INFO - 'talos_options': ['--disable-e10s', 08:48:00 INFO - '--xperf_path', 08:48:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:48:00 INFO - 'tests': ['tp5n']}, 08:48:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:48:00 INFO - 'talos_options': ['--xperf_path', 08:48:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:48:00 INFO - 'tests': ['tp5n']}}, 08:48:00 INFO - 'talos.zip': {'path': '', 08:48:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:48:00 INFO - Running post-action listener: _resource_record_post_action 08:48:00 INFO - [mozharness: 2017-04-13 15:48:00.563677Z] Finished populate-webroot step (success) 08:48:00 INFO - [mozharness: 2017-04-13 15:48:00.563766Z] Running create-virtualenv step. 08:48:00 INFO - Running pre-action listener: _resource_record_pre_action 08:48:00 INFO - Running main action method: create_virtualenv 08:48:00 INFO - Creating virtualenv /builds/slave/test/build/venv 08:48:00 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 08:48:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:48:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:48:00 INFO - Using real prefix '/usr' 08:48:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:48:01 INFO - Installing distribute.............................................................................................................................................................................................done. 08:48:03 INFO - Installing pip.................done. 08:48:03 INFO - Return code: 0 08:48:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:48:03 INFO - Reading from file tmpfile_stdout 08:48:03 INFO - Output received: 08:48:03 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) 08:48:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:48:03 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')]} 08:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:03 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')]} 08:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:03 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 0x13071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a4b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a2290>, '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 0x15a2b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a3040>, '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': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', '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 08:48:03 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 08:48:03 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 08:48:03 INFO - Using env: {'DISPLAY': ':0', 08:48:03 INFO - 'HOME': '/home/cltbld', 08:48:03 INFO - 'LANG': 'en_US.UTF-8', 08:48:03 INFO - 'LANGUAGE': 'en_US:en', 08:48:03 INFO - 'LOGNAME': 'cltbld', 08:48:03 INFO - 'MAIL': '/var/mail/cltbld', 08:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:03 INFO - 'MOZ_NO_REMOTE': '1', 08:48:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:03 INFO - 'NO_EM_RESTART': '1', 08:48:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:03 INFO - 'PWD': '/builds/slave/test', 08:48:03 INFO - 'SHELL': '/bin/bash', 08:48:03 INFO - 'SHLVL': '1', 08:48:03 INFO - 'TERM': 'linux', 08:48:03 INFO - 'TMOUT': '86400', 08:48:03 INFO - 'USER': 'cltbld', 08:48:03 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', 08:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:03 INFO - '_': '/tools/buildbot/bin/python'} 08:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:03 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 08:48:03 INFO - Cleaning up... 08:48:03 INFO - Return code: 0 08:48:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:48:03 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')]} 08:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:03 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')]} 08:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:03 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 0x13071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a4b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a2290>, '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 0x15a2b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a3040>, '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': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', '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 08:48:03 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 08:48:03 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 08:48:03 INFO - Using env: {'DISPLAY': ':0', 08:48:03 INFO - 'HOME': '/home/cltbld', 08:48:03 INFO - 'LANG': 'en_US.UTF-8', 08:48:03 INFO - 'LANGUAGE': 'en_US:en', 08:48:03 INFO - 'LOGNAME': 'cltbld', 08:48:03 INFO - 'MAIL': '/var/mail/cltbld', 08:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:03 INFO - 'MOZ_NO_REMOTE': '1', 08:48:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:03 INFO - 'NO_EM_RESTART': '1', 08:48:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:03 INFO - 'PWD': '/builds/slave/test', 08:48:03 INFO - 'SHELL': '/bin/bash', 08:48:03 INFO - 'SHLVL': '1', 08:48:03 INFO - 'TERM': 'linux', 08:48:03 INFO - 'TMOUT': '86400', 08:48:03 INFO - 'USER': 'cltbld', 08:48:03 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', 08:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:03 INFO - '_': '/tools/buildbot/bin/python'} 08:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:03 INFO - Downloading/unpacking psutil>=3.1.1 08:48:03 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 08:48:03 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 08:48:03 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 08:48:03 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 08:48:03 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 08:48:03 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 08:48:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:48:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:48:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:48:05 INFO - Installing collected packages: psutil 08:48:05 INFO - Running setup.py install for psutil 08:48:05 INFO - building 'psutil._psutil_linux' extension 08:48:05 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 08:48:06 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 08:48:06 INFO - building 'psutil._psutil_posix' extension 08:48:06 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 08:48:06 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 08:48:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:48:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:48:06 INFO - Successfully installed psutil 08:48:06 INFO - Cleaning up... 08:48:06 INFO - Return code: 0 08:48:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:48:06 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')]} 08:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:06 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')]} 08:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:06 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 0x13071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a4b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a2290>, '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 0x15a2b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a3040>, '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': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', '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 08:48:06 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 08:48:06 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 08:48:06 INFO - Using env: {'DISPLAY': ':0', 08:48:06 INFO - 'HOME': '/home/cltbld', 08:48:06 INFO - 'LANG': 'en_US.UTF-8', 08:48:06 INFO - 'LANGUAGE': 'en_US:en', 08:48:06 INFO - 'LOGNAME': 'cltbld', 08:48:06 INFO - 'MAIL': '/var/mail/cltbld', 08:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:06 INFO - 'MOZ_NO_REMOTE': '1', 08:48:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:06 INFO - 'NO_EM_RESTART': '1', 08:48:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:06 INFO - 'PWD': '/builds/slave/test', 08:48:06 INFO - 'SHELL': '/bin/bash', 08:48:06 INFO - 'SHLVL': '1', 08:48:06 INFO - 'TERM': 'linux', 08:48:06 INFO - 'TMOUT': '86400', 08:48:06 INFO - 'USER': 'cltbld', 08:48:06 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', 08:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:06 INFO - '_': '/tools/buildbot/bin/python'} 08:48:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:48:06 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 08:48:06 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 08:48:06 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 08:48:06 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 08:48:06 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 08:48:06 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 08:48:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:48:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:48:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:48:08 INFO - Installing collected packages: mozsystemmonitor 08:48:08 INFO - Running setup.py install for mozsystemmonitor 08:48:08 INFO - Successfully installed mozsystemmonitor 08:48:08 INFO - Cleaning up... 08:48:08 INFO - Return code: 0 08:48:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:48:08 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')]} 08:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:08 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')]} 08:48:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:08 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 0x13071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a4b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a2290>, '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 0x15a2b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a3040>, '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': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', '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 08:48:08 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 08:48:08 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 08:48:08 INFO - Using env: {'DISPLAY': ':0', 08:48:08 INFO - 'HOME': '/home/cltbld', 08:48:08 INFO - 'LANG': 'en_US.UTF-8', 08:48:08 INFO - 'LANGUAGE': 'en_US:en', 08:48:08 INFO - 'LOGNAME': 'cltbld', 08:48:08 INFO - 'MAIL': '/var/mail/cltbld', 08:48:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:08 INFO - 'MOZ_NO_REMOTE': '1', 08:48:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:08 INFO - 'NO_EM_RESTART': '1', 08:48:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:08 INFO - 'PWD': '/builds/slave/test', 08:48:08 INFO - 'SHELL': '/bin/bash', 08:48:08 INFO - 'SHLVL': '1', 08:48:08 INFO - 'TERM': 'linux', 08:48:08 INFO - 'TMOUT': '86400', 08:48:08 INFO - 'USER': 'cltbld', 08:48:08 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', 08:48:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:08 INFO - '_': '/tools/buildbot/bin/python'} 08:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:09 INFO - Downloading/unpacking jsonschema==2.5.1 08:48:09 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 08:48:09 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 08:48:09 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 08:48:09 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 08:48:09 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 08:48:09 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 08:48:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:48:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:11 INFO - Downloading functools32-3.2.3-2.tar.gz 08:48:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:48:11 INFO - warning: no files found matching '*.txt' 08:48:11 INFO - no previously-included directories found matching 'build' 08:48:11 INFO - no previously-included directories found matching 'dist' 08:48:11 INFO - no previously-included directories found matching '.git*' 08:48:11 INFO - Installing collected packages: jsonschema, functools32 08:48:11 INFO - Running setup.py install for functools32 08:48:11 INFO - warning: no files found matching '*.txt' 08:48:11 INFO - no previously-included directories found matching 'build' 08:48:11 INFO - no previously-included directories found matching 'dist' 08:48:11 INFO - no previously-included directories found matching '.git*' 08:48:11 INFO - Successfully installed jsonschema functools32 08:48:11 INFO - Cleaning up... 08:48:11 INFO - Return code: 0 08:48:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:48: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')]} 08:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48: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')]} 08:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48: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', '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 0x13071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a4b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a2290>, '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 0x15a2b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a3040>, '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': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', '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 08:48: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:48: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 functools32==3.2.3-2 08:48:11 INFO - Using env: {'DISPLAY': ':0', 08:48:11 INFO - 'HOME': '/home/cltbld', 08:48:11 INFO - 'LANG': 'en_US.UTF-8', 08:48:11 INFO - 'LANGUAGE': 'en_US:en', 08:48:11 INFO - 'LOGNAME': 'cltbld', 08:48:11 INFO - 'MAIL': '/var/mail/cltbld', 08:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:11 INFO - 'MOZ_NO_REMOTE': '1', 08:48:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:11 INFO - 'NO_EM_RESTART': '1', 08:48:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:11 INFO - 'PWD': '/builds/slave/test', 08:48:11 INFO - 'SHELL': '/bin/bash', 08:48:11 INFO - 'SHLVL': '1', 08:48:11 INFO - 'TERM': 'linux', 08:48:11 INFO - 'TMOUT': '86400', 08:48:11 INFO - 'USER': 'cltbld', 08:48:11 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', 08:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:11 INFO - '_': '/tools/buildbot/bin/python'} 08:48:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:48:11 INFO - Cleaning up... 08:48:11 INFO - Return code: 0 08:48:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:48: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')]} 08:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48: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')]} 08:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48: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', '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 0x13071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a4b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a2290>, '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 0x15a2b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a3040>, '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': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', '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 08:48: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:48: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 blobuploader==1.2.4 08:48:11 INFO - Using env: {'DISPLAY': ':0', 08:48:11 INFO - 'HOME': '/home/cltbld', 08:48:11 INFO - 'LANG': 'en_US.UTF-8', 08:48:11 INFO - 'LANGUAGE': 'en_US:en', 08:48:11 INFO - 'LOGNAME': 'cltbld', 08:48:11 INFO - 'MAIL': '/var/mail/cltbld', 08:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:11 INFO - 'MOZ_NO_REMOTE': '1', 08:48:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:11 INFO - 'NO_EM_RESTART': '1', 08:48:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:11 INFO - 'PWD': '/builds/slave/test', 08:48:11 INFO - 'SHELL': '/bin/bash', 08:48:11 INFO - 'SHLVL': '1', 08:48:11 INFO - 'TERM': 'linux', 08:48:11 INFO - 'TMOUT': '86400', 08:48:11 INFO - 'USER': 'cltbld', 08:48:11 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', 08:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:11 INFO - '_': '/tools/buildbot/bin/python'} 08:48:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:12 INFO - Downloading/unpacking blobuploader==1.2.4 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48:14 INFO - Downloading blobuploader-1.2.4.tar.gz 08:48:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:48:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:48:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:14 INFO - Downloading docopt-0.6.1.tar.gz 08:48:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:48:14 INFO - Installing collected packages: blobuploader, requests, docopt 08:48:14 INFO - Running setup.py install for blobuploader 08:48:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:48:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:48:15 INFO - Running setup.py install for requests 08:48:15 INFO - Running setup.py install for docopt 08:48:15 INFO - Successfully installed blobuploader requests docopt 08:48:15 INFO - Cleaning up... 08:48:15 INFO - Return code: 0 08:48:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:48:15 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')]} 08:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:15 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')]} 08:48:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:15 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 0x13071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a4b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a2290>, '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 0x15a2b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a3040>, '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': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', '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 08:48:15 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 08:48:15 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 08:48:15 INFO - Using env: {'DISPLAY': ':0', 08:48:15 INFO - 'HOME': '/home/cltbld', 08:48:15 INFO - 'LANG': 'en_US.UTF-8', 08:48:15 INFO - 'LANGUAGE': 'en_US:en', 08:48:15 INFO - 'LOGNAME': 'cltbld', 08:48:15 INFO - 'MAIL': '/var/mail/cltbld', 08:48:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:15 INFO - 'MOZ_NO_REMOTE': '1', 08:48:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:15 INFO - 'NO_EM_RESTART': '1', 08:48:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:15 INFO - 'PWD': '/builds/slave/test', 08:48:15 INFO - 'SHELL': '/bin/bash', 08:48:15 INFO - 'SHLVL': '1', 08:48:15 INFO - 'TERM': 'linux', 08:48:15 INFO - 'TMOUT': '86400', 08:48:15 INFO - 'USER': 'cltbld', 08:48:15 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', 08:48:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:15 INFO - '_': '/tools/buildbot/bin/python'} 08:48:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:48:15 INFO - Running setup.py (path:/tmp/pip-KYYQaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:48:15 INFO - Running setup.py (path:/tmp/pip-Ycqm8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:48:15 INFO - Running setup.py (path:/tmp/pip-BbQpAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:48:15 INFO - Running setup.py (path:/tmp/pip-JAOtRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:48:16 INFO - Running setup.py (path:/tmp/pip-qPyCRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:48:16 INFO - Running setup.py (path:/tmp/pip-afG7wh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:48:16 INFO - Running setup.py (path:/tmp/pip-bjrjUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:48:16 INFO - Running setup.py (path:/tmp/pip-zJrDcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:48:16 INFO - Running setup.py (path:/tmp/pip-GYEp4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:48:16 INFO - Running setup.py (path:/tmp/pip-DyV7Jo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:48:16 INFO - Running setup.py (path:/tmp/pip-bbyJjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:48:16 INFO - Running setup.py (path:/tmp/pip-Syf81c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:48:16 INFO - Running setup.py (path:/tmp/pip-0nwakc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:48:16 INFO - Running setup.py (path:/tmp/pip-ZM8mNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:48:16 INFO - Running setup.py (path:/tmp/pip-5lOjGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:48:16 INFO - Running setup.py (path:/tmp/pip-DOvEMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:48:16 INFO - Running setup.py (path:/tmp/pip-GxZ_ai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:48:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:48:16 INFO - Running setup.py install for manifestparser 08:48:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:48:17 INFO - Running setup.py install for mozcrash 08:48:17 INFO - Running setup.py install for mozdebug 08:48:17 INFO - Running setup.py install for mozdevice 08:48:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:48:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:48:17 INFO - Running setup.py install for mozfile 08:48:17 INFO - Running setup.py install for mozhttpd 08:48:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:48:17 INFO - Running setup.py install for mozinfo 08:48:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:48:17 INFO - Running setup.py install for mozInstall 08:48:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:48:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:48:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:48:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:48:17 INFO - Running setup.py install for mozleak 08:48:17 INFO - Running setup.py install for mozlog 08:48:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:48:18 INFO - Running setup.py install for moznetwork 08:48:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:48:18 INFO - Running setup.py install for mozprocess 08:48:18 INFO - Running setup.py install for mozprofile 08:48:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:48:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:48:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:48:18 INFO - Running setup.py install for mozrunner 08:48:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:48:18 INFO - Running setup.py install for mozscreenshot 08:48:18 INFO - Running setup.py install for moztest 08:48:18 INFO - Running setup.py install for mozversion 08:48:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:48:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:48:18 INFO - Cleaning up... 08:48:18 INFO - Return code: 0 08:48:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:48:18 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')]} 08:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:18 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')]} 08:48:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a4b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a2290>, '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 0x15a2b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a3040>, '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': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', '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 08:48:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:48:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:48:19 INFO - Using env: {'DISPLAY': ':0', 08:48:19 INFO - 'HOME': '/home/cltbld', 08:48:19 INFO - 'LANG': 'en_US.UTF-8', 08:48:19 INFO - 'LANGUAGE': 'en_US:en', 08:48:19 INFO - 'LOGNAME': 'cltbld', 08:48:19 INFO - 'MAIL': '/var/mail/cltbld', 08:48:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:19 INFO - 'MOZ_NO_REMOTE': '1', 08:48:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:19 INFO - 'NO_EM_RESTART': '1', 08:48:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:19 INFO - 'PWD': '/builds/slave/test', 08:48:19 INFO - 'SHELL': '/bin/bash', 08:48:19 INFO - 'SHLVL': '1', 08:48:19 INFO - 'TERM': 'linux', 08:48:19 INFO - 'TMOUT': '86400', 08:48:19 INFO - 'USER': 'cltbld', 08:48:19 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', 08:48:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:19 INFO - '_': '/tools/buildbot/bin/python'} 08:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:48:19 INFO - Running setup.py (path:/tmp/pip-_aR0CC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:48:19 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)) 08:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:48:19 INFO - Running setup.py (path:/tmp/pip-s_yHW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:48:19 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)) 08:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:48:19 INFO - Running setup.py (path:/tmp/pip-uUOEd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:48:19 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)) 08:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:48:19 INFO - Running setup.py (path:/tmp/pip-j7WYb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:48:19 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)) 08:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:48:19 INFO - Running setup.py (path:/tmp/pip-d8NCv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:48:19 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)) 08:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:48:19 INFO - Running setup.py (path:/tmp/pip-05Yfls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:48:19 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)) 08:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:48:19 INFO - Running setup.py (path:/tmp/pip-_C_net-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:48:19 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)) 08:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:48:19 INFO - Running setup.py (path:/tmp/pip-rNcfPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:48:19 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)) 08:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:48:19 INFO - Running setup.py (path:/tmp/pip-c1c0F2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:48:19 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)) 08:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:48:19 INFO - Running setup.py (path:/tmp/pip-mxkH9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:48:20 INFO - Running setup.py (path:/tmp/pip-NGITi9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:48:20 INFO - Running setup.py (path:/tmp/pip-gGDVjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 08:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:48:20 INFO - Running setup.py (path:/tmp/pip-vnXNuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:48:20 INFO - Running setup.py (path:/tmp/pip-h5s8uu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:48:20 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)) 08:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:48:20 INFO - Running setup.py (path:/tmp/pip-v9BPZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:48:20 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)) 08:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:48:20 INFO - Running setup.py (path:/tmp/pip-LWfC9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:48:20 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)) 08:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:48:20 INFO - Running setup.py (path:/tmp/pip-A4xo2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:48:20 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)) 08:48:20 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)) 08:48:20 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)) 08:48:20 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)) 08:48:20 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)) 08:48:20 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)) 08:48:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48:23 INFO - Downloading blessings-1.6.tar.gz 08:48:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:48:23 INFO - Installing collected packages: blessings 08:48:23 INFO - Running setup.py install for blessings 08:48:23 INFO - Successfully installed blessings 08:48:23 INFO - Cleaning up... 08:48:23 INFO - Return code: 0 08:48:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:48:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:48:23 INFO - Reading from file tmpfile_stdout 08:48:23 INFO - Current package versions: 08:48:23 INFO - argparse == 1.2.1 08:48:23 INFO - blessings == 1.6 08:48:23 INFO - blobuploader == 1.2.4 08:48:23 INFO - docopt == 0.6.1 08:48:23 INFO - functools32 == 3.2.3-2 08:48:23 INFO - jsonschema == 2.5.1 08:48:23 INFO - manifestparser == 1.1 08:48:23 INFO - mozInstall == 1.12 08:48:23 INFO - mozcrash == 1.0 08:48:23 INFO - mozdebug == 0.1 08:48:23 INFO - mozdevice == 0.49 08:48:23 INFO - mozfile == 1.2 08:48:23 INFO - mozhttpd == 0.7 08:48:23 INFO - mozinfo == 0.9 08:48:23 INFO - mozleak == 0.1 08:48:23 INFO - mozlog == 3.4 08:48:23 INFO - moznetwork == 0.27 08:48:23 INFO - mozprocess == 0.25 08:48:23 INFO - mozprofile == 0.28 08:48:23 INFO - mozrunner == 6.13 08:48:23 INFO - mozscreenshot == 0.1 08:48:23 INFO - mozsystemmonitor == 0.3 08:48:23 INFO - moztest == 0.8 08:48:23 INFO - mozversion == 1.4 08:48:23 INFO - psutil == 3.1.1 08:48:23 INFO - requests == 1.2.3 08:48:23 INFO - wsgiref == 0.1.2 08:48:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:48: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')]} 08:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48: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')]} 08:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:24 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 0x13071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a4b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a2290>, '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 0x15a2b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a3040>, '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': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', '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 08:48:24 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 08:48:24 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 08:48:24 INFO - Using env: {'DISPLAY': ':0', 08:48:24 INFO - 'HOME': '/home/cltbld', 08:48:24 INFO - 'LANG': 'en_US.UTF-8', 08:48:24 INFO - 'LANGUAGE': 'en_US:en', 08:48:24 INFO - 'LOGNAME': 'cltbld', 08:48:24 INFO - 'MAIL': '/var/mail/cltbld', 08:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:24 INFO - 'MOZ_NO_REMOTE': '1', 08:48:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:24 INFO - 'NO_EM_RESTART': '1', 08:48:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:24 INFO - 'PWD': '/builds/slave/test', 08:48:24 INFO - 'SHELL': '/bin/bash', 08:48:24 INFO - 'SHLVL': '1', 08:48:24 INFO - 'TERM': 'linux', 08:48:24 INFO - 'TMOUT': '86400', 08:48:24 INFO - 'USER': 'cltbld', 08:48:24 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', 08:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:24 INFO - '_': '/tools/buildbot/bin/python'} 08:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:24 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)) 08:48:24 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)) 08:48:24 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)) 08:48:24 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)) 08:48:24 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)) 08:48:24 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)) 08:48:24 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)) 08:48:24 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)) 08:48:24 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)) 08:48:24 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)) 08:48:24 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)) 08:48:24 INFO - Cleaning up... 08:48:24 INFO - Return code: 0 08:48:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a4b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a2290>, '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 0x15a2b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a3040>, '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': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', '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 08:48:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:48:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:48:24 INFO - Using env: {'DISPLAY': ':0', 08:48:24 INFO - 'HOME': '/home/cltbld', 08:48:24 INFO - 'LANG': 'en_US.UTF-8', 08:48:24 INFO - 'LANGUAGE': 'en_US:en', 08:48:24 INFO - 'LOGNAME': 'cltbld', 08:48:24 INFO - 'MAIL': '/var/mail/cltbld', 08:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:24 INFO - 'MOZ_NO_REMOTE': '1', 08:48:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:24 INFO - 'NO_EM_RESTART': '1', 08:48:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:24 INFO - 'PWD': '/builds/slave/test', 08:48:24 INFO - 'SHELL': '/bin/bash', 08:48:24 INFO - 'SHLVL': '1', 08:48:24 INFO - 'TERM': 'linux', 08:48:24 INFO - 'TMOUT': '86400', 08:48:24 INFO - 'USER': 'cltbld', 08:48:24 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', 08:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:24 INFO - '_': '/tools/buildbot/bin/python'} 08:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:48:24 INFO - Cleaning up... 08:48:24 INFO - Return code: 0 08:48:24 INFO - Running post-action listener: _resource_record_post_action 08:48:24 INFO - Running post-action listener: _start_resource_monitoring 08:48:24 INFO - Starting resource monitoring. 08:48:24 INFO - [mozharness: 2017-04-13 15:48:24.770115Z] Finished create-virtualenv step (success) 08:48:24 INFO - [mozharness: 2017-04-13 15:48:24.770582Z] Running install step. 08:48:24 INFO - Running pre-action listener: _resource_record_pre_action 08:48:24 INFO - Running main action method: install 08:48:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:48:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:48:24 INFO - Reading from file tmpfile_stdout 08:48:24 INFO - Detecting whether we're running mozinstall >=1.0... 08:48:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:48:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:48:25 INFO - Reading from file tmpfile_stdout 08:48:25 INFO - Output received: 08:48:25 INFO - Usage: mozinstall [options] installer 08:48:25 INFO - Options: 08:48:25 INFO - -h, --help show this help message and exit 08:48:25 INFO - -d DEST, --destination=DEST 08:48:25 INFO - Directory to install application into. [default: 08:48:25 INFO - "/builds/slave/test"] 08:48:25 INFO - --app=APP Application being installed. [default: firefox] 08:48:25 INFO - mkdir: /builds/slave/test/build/application 08:48:25 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'] 08:48:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:48:41 INFO - Reading from file tmpfile_stdout 08:48:41 INFO - Output received: 08:48:41 INFO - /builds/slave/test/build/application/firefox/firefox 08:48:41 INFO - Running post-action listener: _resource_record_post_action 08:48:41 INFO - [mozharness: 2017-04-13 15:48:41.675502Z] Finished install step (success) 08:48:41 INFO - [mozharness: 2017-04-13 15:48:41.675778Z] Running run-tests step. 08:48:41 INFO - Running pre-action listener: _resource_record_pre_action 08:48:41 INFO - Running main action method: run_tests 08:48:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:48:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:48:41 INFO - Python 2.7.3 08:48:41 INFO - Return code: 0 08:48:41 INFO - grabbing minidump binary from tooltool 08:48:41 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')]} 08:48:41 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 0x15a2290>, '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 0x15a2b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a3040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:48:41 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 08:48:41 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 08:48:41 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 08:48:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:48:41 INFO - Return code: 0 08:48:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:48:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:48:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:48:41 INFO - ENV: RUST_BACKTRACE is now 1 08:48:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:48:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:48:41 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/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 08:48:41 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/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-058 --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 08:48:41 INFO - Using env: {'DISPLAY': ':0', 08:48:41 INFO - 'HOME': '/home/cltbld', 08:48:41 INFO - 'LANG': 'en_US.UTF-8', 08:48:41 INFO - 'LANGUAGE': 'en_US:en', 08:48:41 INFO - 'LOGNAME': 'cltbld', 08:48:41 INFO - 'MAIL': '/var/mail/cltbld', 08:48:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:48:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:48:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:41 INFO - 'MOZ_NO_REMOTE': '1', 08:48:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:48:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:41 INFO - 'NO_EM_RESTART': '1', 08:48:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:41 INFO - 'PWD': '/builds/slave/test', 08:48:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:48:41 INFO - 'RUST_BACKTRACE': '1', 08:48:41 INFO - 'SHELL': '/bin/bash', 08:48:41 INFO - 'SHLVL': '1', 08:48:41 INFO - 'TERM': 'linux', 08:48:41 INFO - 'TMOUT': '86400', 08:48:41 INFO - 'USER': 'cltbld', 08:48:41 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368', 08:48:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:41 INFO - '_': '/tools/buildbot/bin/python'} 08:48:41 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/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 08:48:41 INFO - mozversion application_buildid: 20170413145002 08:48:41 INFO - mozversion application_changeset: d56c36ff0b8bc34183f056866de81a6a61c782ec 08:48:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:48:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:48:41 INFO - mozversion application_name: Firefox 08:48:41 INFO - mozversion application_remotingname: firefox-dev 08:48:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:48:41 INFO - mozversion application_vendor: Mozilla 08:48:41 INFO - mozversion application_version: 54.0a2 08:48:41 INFO - mozversion platform_buildid: 20170413145002 08:48:41 INFO - mozversion platform_changeset: d56c36ff0b8bc34183f056866de81a6a61c782ec 08:48:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:48:41 INFO - mozversion platform_version: 54.0a2 08:48:41 INFO - using testdate: 1492098521 08:48:41 INFO - actual date: 1492098521 08:48:41 INFO - starting webserver on 'localhost:36560' 08:48:41 INFO - SUITE-START | Running 2 tests 08:48:41 INFO - TEST-START | dromaeo_css 08:48:41 INFO - operating with platform_type : linux_ 08:48:41 INFO - Initialising browser for dromaeo_css test... 08:48:41 INFO - TEST-INFO | started process 9384 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNmFUWb/profile http://localhost:36560/getInfo.html) 08:48:46 INFO - PROCESS | 9384 | __metrics Screen width/height:1600/1200 08:48:46 INFO - PROCESS | 9384 | colorDepth:24 08:48:46 INFO - PROCESS | 9384 | Browser inner width/height: 1024/697 08:48:46 INFO - PROCESS | 9384 | __metrics 08:48:47 INFO - PROCESS | 9384 | 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" 08:48:47 INFO - PROCESS | 9384 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:48:47 INFO - TEST-INFO | 9384: exit 0 08:48:47 INFO - Browser initialized. 08:48:47 INFO - Running cycle 1/1 for dromaeo_css test... 08:48:48 INFO - TEST-INFO | started process 9476 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNmFUWb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:48:59 INFO - PROCESS | 9476 | RSS: Main: 139816960 08:48:59 INFO - PROCESS | 9476 | 08:52:01 INFO - PROCESS | 9476 | Cycle 1(1): loaded http://localhost:36560/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36560/tests/dromaeo/cssquery-ext.html) 08:52:02 INFO - PROCESS | 9476 | RSS: Main: 254664704 08:52:02 INFO - PROCESS | 9476 | 08:53:49 INFO - PROCESS | 9476 | ************************* 08:53:49 INFO - PROCESS | 9476 | A coding exception was thrown and uncaught in a Task. 08:53:49 INFO - PROCESS | 9476 | 08:53:49 ERROR - PROCESS | 9476 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:53:49 INFO - PROCESS | 9476 | Full stack: 08:53:49 INFO - PROCESS | 9476 | ************************* 08:53:49 INFO - PROCESS | 9476 | ************************* 08:53:49 INFO - PROCESS | 9476 | A coding exception was thrown and uncaught in a Task. 08:53:49 INFO - PROCESS | 9476 | 08:53:49 ERROR - PROCESS | 9476 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:53:49 INFO - PROCESS | 9476 | Full stack: 08:53:49 INFO - PROCESS | 9476 | ************************* 08:53:49 INFO - PROCESS | 9476 | ************************* 08:53:49 INFO - PROCESS | 9476 | A coding exception was thrown and uncaught in a Task. 08:53:49 INFO - PROCESS | 9476 | 08:53:49 ERROR - PROCESS | 9476 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:53:49 INFO - PROCESS | 9476 | Full stack: 08:53:49 INFO - PROCESS | 9476 | ************************* 08:53:49 INFO - PROCESS | 9476 | ************************* 08:53:49 INFO - PROCESS | 9476 | A coding exception was thrown and uncaught in a Task. 08:53:49 INFO - PROCESS | 9476 | 08:53:49 ERROR - PROCESS | 9476 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:53:49 INFO - PROCESS | 9476 | Full stack: 08:53:49 INFO - PROCESS | 9476 | ************************* 08:55:04 INFO - PROCESS | 9476 | Cycle 1(1): loaded http://localhost:36560/tests/dromaeo/cssquery-ext.html (next: http://localhost:36560/tests/dromaeo/cssquery-jquery.html) 08:55:05 INFO - PROCESS | 9476 | RSS: Main: 215162880 08:55:05 INFO - PROCESS | 9476 | 08:58:07 INFO - PROCESS | 9476 | Cycle 1(1): loaded http://localhost:36560/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36560/tests/dromaeo/cssquery-mootools.html) 08:58:07 INFO - PROCESS | 9476 | RSS: Main: 193699840 08:58:07 INFO - PROCESS | 9476 | 09:01:09 INFO - PROCESS | 9476 | Cycle 1(1): loaded http://localhost:36560/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36560/tests/dromaeo/cssquery-prototype.html) 09:01:10 INFO - PROCESS | 9476 | RSS: Main: 200937472 09:01:10 INFO - PROCESS | 9476 | 09:04:12 INFO - PROCESS | 9476 | Cycle 1(1): loaded http://localhost:36560/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36560/tests/dromaeo/cssquery-yui.html) 09:04:13 INFO - PROCESS | 9476 | RSS: Main: 176685056 09:04:13 INFO - PROCESS | 9476 | 09:07:16 INFO - PROCESS | 9476 | Cycle 1(1): loaded http://localhost:36560/tests/dromaeo/cssquery-yui.html (next: http://localhost:36560/tests/dromaeo/cssquery-dojo.html) 09:07:16 INFO - PROCESS | 9476 | RSS: Main: 266752000 09:07:16 INFO - PROCESS | 9476 | 09:07:16 INFO - PROCESS | 9476 | __start_tp_report 09:07:16 INFO - PROCESS | 9476 | _x_x_mozilla_page_load 09:07:16 INFO - PROCESS | 9476 | _x_x_mozilla_page_load_details 09:07:16 INFO - PROCESS | 9476 | |i|pagename|runs| 09:07:16 INFO - PROCESS | 9476 | |0;dojo.html;3312.48656458249;3313.78460400074;3352.027911905546;3359.6472370401093;3372.713319367855;5565.833025009246;5634.7182640867995;5646.322441307038;5655.915161272579;5662.207290979266;5630.436956304367;5658.858528536789;5670.376258611549;5680.318361796591;5737.7991770288045;5027.547520723143;5040.218766091261;5064.11378008847;5089.109405853961;5115.744212789365;4923.753812309388;4932.753362331887;5120.283160357553;5124.5131712487255;5152.046871328797;4950.777215025332;4960.925586116211;5054.797808087672;5076.086304465195;5084.3644544431945;5088.872778180538;5095.4140273868425;5100.719460429675;5101.285819985205;5124.461931497176;4774.546418090275;5003.17447621142;5018.774155163026;5063;5079.390473143247;5495.835124946258;5496.1755736639425;5502.119660854244;5505.504504594565;5509.862253443656;5844.298684157886;5873.3245676746865;5891.204687367236;5891.263592050994;5895.852603684899;5916.792912248051;5925.05199168131;5925.674087925162;5928.7924922627935;5951.940480595225;5688.146777983336;5697.800576979827;5720.828375148711;5728.713564321809;5728.742206600755;6169.259688837368;6175.814725558241;6175.938240617626;6178.41305076017;6178.845528861724;5755.913661295084;5944.4352786485215;5954.85112872173;5956.434138756811;5968;5968.612040217372;5974.312954010345;5994.220751302303;6013.939860601426;6031.909521357183;6038.728257228435;6054.848628784315;6058.182145410401;6061.090836374491;6065.4541091302;5808.215890854765;5856.765729370863;5891.440313170242;5931.525477961753;6013.248343957005;4647.535246475393;4668.299755036772;4669.95330046702;4672.602828759525;4673.626109911199;4098.938515922264;4110.506739191256;4122.134351786159;4129.380592911088;4134.689898257643;5596.38439771633;5850.707464626837;5858.2677165354335;5859.970700146472;5864.149698293723;5936.9406305937255;5939.049752039653;6017.037274036221;6095.75616975316;6116.755329786848;5784.1034639630925;5810.941890581124;5812.88374232513;5819.592628516048;5821.650700957956;5139.203423469294;5145.691258524501;5158.148905430637;5173.275741396207;5175.353080864892;6024.3975602439405;6077.878442431215;6135.661410523283;6213.875722485525;6278.780242691484;6063.5452341073715;6066.7269972850445;6074.210352654303;6074.240719910012;6103.969480152571;6244.375562443719;6265.373462653881;6275.121482992476;6275.403836635513;6275.497960163268;5756;5814.622049566765;5857.355690873916;5952.315483719428;6048.7278072487725;6097.115918191926;6111.541634377529;6112.938870611415;6113.172264937138;6128.295246046761;5904.734286957011;5943.732532035982;5962.910556341745;5965.164876917322;5967.8209653710455;5640.153976903333;5675.290588676416;5825.300963884361;5948.821535353946;5965.850853728692;5932.644041357532;5932.822015339547;5941.376155503783;5959.970200148971;5970.641761494324;5928.79249226275;5963.552733545088;5968.104784282393;5970.820875373745;5971.611845230046;5615.494605485526;5657.660540367591;5748.281464816898;5753.1370294456165;5754.4533269339345 09:07:16 INFO - PROCESS | 9476 | |1;ext.html;765.6631082323777;821.4291067707943;837.7821766340752;896.0904681748023;907.9682211122604;31090.84454577727;43095.84520773961;43595;43826.56173438261;43875.78062109693;43615;43824.342634860426;43846.561534384644;43979.34030989538;44045.77977110118;7754.651040703182;7908.683652653887;9117.544122793866;9347.29895257855;9350.859737103949;10615.416152111631;10826.079783218436;10829.133669306437;10832.187585931048;11128.777424451506;10837.67486975388;10879.184061195403;10886.67339979802;10889.618863339785;10890.564377424893;10773.138148948105;10791.892081079168;10808.621698240564;10809.459527023657;10813.459327033617;10588.523516441779;10607.15142788575;10613.681589552325;10672;10674.146068314594;40750;40795.38806917899;40897.18205635871;41229.587704122874;41421.58578414207;3939.8030098495246;4004.4227953261543;4034.213328400963;4070.206309769596;4116.320807066831;29392.412151756846;30475.390492190254;30513.389732205454;30608.2347941301;30934.690653093632;4451.332300154971;4462.642988560908;4485.439320084989;4655.348251244828;4697.060587882403;222195;222355;222988;223480.99999999837;223921;53121;53221;53238.73380633072;53273.2009019865;53344.46655533395;55348.446515535135;55570.72214638982;55580.44419555834;55638;55665;16559.420420285474;16859.662806743796;16865.409710660315;16895.408660696816;16897.493075207763;11969.401529923469;12092.939535302266;14585.635359115895;14753.262336883114;14766.114033158045;13045;13101;13177.143485673401;13179.538716144889;13309.66725831862;5402.648827826179;5409.24270602116;5458.836234912959;5553.806875808738;5560.944390556043;974.8976357482362;975.3757595139117;993.3841018568389;1003.9196864250843;1018.2922868606396;27655.58516622252;27824.860875695897;28045.76958543626;28229.43541129166;28280.29299267535;1068.273573969708;1078.2991055813627;1079.7246702090918;1099.6426161497545;1101.1026013798728;14544.345504452325;14961.850381496046;15009.549713508612;15039.624009399853;15159.545213643609;285769;291069;291076;291108;291780;56483.43516564864;56871.43128568744;56963.14555281674;57022.14466783001;57054.7147264261;1488.3376897280823;1521.3154080663746;1542.406173623163;1563.4215340323926;1563.6481791597027;25847;26898.327541810828;27108.72891271141;27441.451170976867;27465;43741.56258437375;43743;43744.562554374046;43816;43870.34194487021;270250;271875;272073;272899;273015;30066.398672026873;30564.388712226075;32679.673203267663;35558.46662300119;35662.64337356593;43856.78071609749;44324.77837610791;44355;44585;44616.33075503935;34337.48493772546;34379.14052148616;34590.82704586461;34616;34619.30761384808;33851.83074084614;34036;34076.488852666735;34240.828795855865;34452.138696532784;38199;38276.42585361166;39241.215175695754;39285.214295713355;39535.60464395319;32658.346833063682;32858.17854553655;32920.176995575304;32920.83539582287;33151.50272745959;25894.858486336572;26204;26207.082752103586;26323;26767.598486023115 09:07:16 INFO - PROCESS | 9476 | |2;jquery.html;465.96039336656384;467.39238989313884;482.36568911880875;487.77318546875694;502.2867528110084;19495.70756438653;21113.366599002016;21137;21163.68254476182;21218.787812121875;21433.785662143375;21665.783342166575;22244.221452249174;22349.888250558764;22618.09527618893;6598.340165983412;6706.865862682768;6715.832104197398;6815.045893574904;6880.20877599075;6660.733570657192;6786.524943253974;6788.185417749877;7690.846183076363;8007.639656215455;7903.1701671324545;7998.440109192359;8178.754637360888;8180.304674102708;8192.34461243099;7785.493942893694;7883.487573307717;7883.526988380658;7888.092869320016;7891.1714270001685;7513.323800857895;7935.880961785578;7985.800354991114;7985.96007019967;7989.480683755595;29233.853830730925;29531.409371812657;29782.404351913057;30121.849390752905;30174.849125754452;3196.184972831936;3256.0557438342853;3276.639569647337;3288.3007804762115;3295.950560741591;18179.818201817943;18273.17770700322;18500.16749246287;18515.907420462947;18541.62916741671;2383.74970628085;2401.931140642415;2417.2627348658652;2443.2670198940423;2471.7281099079087;75726.42824662793;85558;117221.4138929317;117976;118099;20981.692013878463;21126.47183820386;21228.575428491342;21520.569588608454;24280.514389712105;41881.581184187766;41951.58048419477;42028.57971420308;42079.7896010518;42112.15775684469;10227.846582301272;10677.786444271227;10690.412027338416;10743;10968.725781855517;8704.909757036161;8818.647254109765;8901.777455563662;8959.148880856308;9037.638494460149;8901.7092521584;9274.489903055262;9409.952950235342;9414.764630884145;9630.566624501775;3676.632336766302;3686.2627474505207;3696.7819308414737;3729.850805967731;3756.1874697182384;931.5528546297679;952.8618350339301;961.4759955823953;966.1497881864055;967.5984466446487;19720.40838774839;20948.685769713466;21083.367498975054;21232.256871009442;21505.462363441038;961.5288508630721;965.5510187762584;969.6363863551169;980.181548407088;1006.4263369879245;10877.564897404016;11201.887981120084;11489.368084755251;11587.88412115885;11639.418029098511;609837;622969;623211;652900;655110;79303.60348198222;99605.50197248967;110659.99999999838;111796.44101779602;112863;1325.8210141630757;1346.6767975685962;1371.6707990082507;1372.9107608005447;1382.8893688504895;18767.249310027993;19011.524711882314;19061.23755049838;19098.236070557577;19234.8076519233;36677.08307292339;44641.33038004497;47192.7640361796;49303.753481232365;52064;65716.67141664261;66064.6696766513;70538;76366.61816690881;78101.60949195217;12107.705670678744;12844.394450693664;12996.155249908727;13020.348982551024;13291.867081329063;13863.584092477242;14284.500042498463;14475.986680932234;14486;14913.105213687213;12092.334921579046;12215.450304736485;12899.93550032244;12921.870781292442;12977.805332919825;11624.6512604622;11966.70083247891;12021.639350819489;12419.0064794818;12461.626151215478;12636.936815315865;12731.2997785119;12852.935735321264;13059.281739504038;13242.80135797983;11833.585824496102;11917.284962902253;12214.328211948072;12315.507379704712;12336.50653973831;11995.460204291003;12090.214136081127;12104.939475302566;12938.547150849676;12946.417411216706 09:07:16 INFO - PROCESS | 9476 | |3;mootools.html;1552.3092223960343;1584.2474824458384;1596.1699916043656;1602.6313947792005;1603.4548253593769;10951.233413661046;11708.648740537787;11764.411779411039;11769.176157668966;11774.411279436037;11323.490442930068;11790.115741319392;11795.410229488534;11798;11803.763924721521;2381.142788596104;2417.468157005456;2419.806415486757;2441.292025312657;2441.426264827764;2298.080767692928;2365.0894405653776;2385.522895420914;2389.95220095598;2390.3665528634924;2259.9435014124615;2290.061074959267;2299.41364951937;2354.3878591566145;2357.1867705641525;2265.9773402265932;2286.6455699366625;2291.873946932918;2293.025464177726;2309.722833260003;2274.0221704666988;2276.3512398966213;2286.5198308355098;2287.9428014299774;2291.1980806717515;11459.77080458386;11482.88517114835;11532.769344613145;11554.711132221677;11560.190786644938;5461.644993075437;5582.692951887645;5710.343310519261;5725.312962444534;5726.656400615976;3299.5875515560556;3349.5645566076496;3441.1569165554347;3444.3111377724554;3465.8786942457145;1365.7951307303886;1373.491808031035;1440.0855456784911;1467.457040894865;1477.837437881832;14781.187034713086;15074.321655525418;16936;17516.299348026063;17849.643007139784;4728.05438912219;4744.59670927975;4775.450823155382;4874.8781280467565;4882.121218180761;13114.278714670785;13143.934280328536;13146.802797958038;13147.868521314856;13156.210627362389;4884.218525035977;5034.370703662043;5685.090385538294;5809.82570522885;5813.94186058134;5306.416294207634;5341.919871201935;5439.700816455133;5439.945600544023;5441.891162176735;4026;4026.9597304026584;4086.9182616348103;4098.200850834059;4193.811278492475;1024.2727663358949;1031.6853359725355;1036.6112707734599;1036.652721338345;1055.794120146564;973.5813600151861;1001.6594357918343;1002.0780881588955;1002.734275417015;1020.7652239984845;6956.633842065078;7100.857982840314;7170.2471240520035;7336.2296958819625;7430.888536671954;1041.8541404203265;1058.7300238439302;1060.0865437884693;1065.8347956066823;1067.6530127708527;4321.891952701208;4420.690551661417;4468.106378724268;4521.61566266871;4526.32105184225;14693.706125877423;15277.159756213516;15294.388224470897;15306.617334566725;15313.081215127128;14224.14655120696;14679.339429725687;15244.542663720105;15261.847381526042;15277.6180595486;9021.188093071523;10455.16358691303;10659.893401066045;10671.35971841692;10723.088537474247;6269.655168965751;7345.824668053086;7485.7754267372065;7632.542047477169;7634.923650763421;12600.11799174049;12600.936995314965;12689.746205076031;12750.298733569743;12933.611991640266;12983.091183617435;12985.155964862253;13013.609591712264;13020.869791301966;13033.15284506542;9760.511974401394;9859.457729824928;9870.802583948425;9974.251931105342;9980.500974951368;10820.188485863435;10822.026017658449;10831.24181307301;10910.61812836547;10934.179936504635;9142.177204051668;9230.123138301851;9309.767255818388;9377.249820014518;9483.288753343664;9018;9093.954530227307;9235.168834804632;9452.291078169028;9463.810723785347;8420.200080992296;8528.06191318942;8601.096884827002;8625.439346442461;8633.179847914438;5827.76688932438;5870.855183239228;5972.074328479092;6026.306974697965;6120.051392034064;5969.820905372846;5975.940240597538;6061.090836374403;6111.7249723763425;6292.559520833498 09:07:16 INFO - PROCESS | 9476 | |4;prototype.html;420.9455314437334;423.23183422088925;438.6885311428885;438.7955063351101;447.63965008168424;5124.2569827375055;5249.632525723201;5269.6311258211945;5279.4984476474765;5344.732763361836;4944.283078953549;5147.665401748884;5169.198774190007;5170.9224361634515;5263;4318.373835793824;4319.546447623002;4326.199653064194;4343.174796788616;4368.803403846819;4359.215341238576;4380.67144964129;4389.385486031958;4397.406350142722;4403.251447253959;4267.295896177127;4299.8065087071;4352.760598167115;4366.934495982563;4406.97796511017;4266.3813747006825;4277.679174061942;4277.850275240383;4284.400183974246;4339.435873336478;4036.2331157080353;4038.454808600831;4047.959520404788;4052;4061.1065565575527;4998.80052776777;5024.397072351305;5069.543741063322;5089.371401151633;5092.745362731848;5081.822136225252;5094.694318340874;5126.820561280375;5151.150060240094;5163.922541161885;5415.862668839549;5441.755121019563;5453.209284653703;5460.034387620453;5473.496961944359;4396.934045989313;4534.863954081383;4576.977115114403;4600.677952543323;4660.347551342781;5424.674519528801;5560.582956278255;5581.6371935824045;5679.699721612754;5734.856628584277;4706.505816889177;4709.270063140219;4718.740469274154;4723.244280915037;4784.784684689197;4633.4208223972;4758.738269395148;4874.171390863562;4877.268409738567;4877.487863774323;5014.674046186986;5021.623378246646;5033.345665063518;5075.847724568269;5094.4141423736;5521.591994041574;5528.695921724344;5543.25166102571;5554.333479982428;5560.860023695132;1574.5827355750735;1637.6479057002803;1658.3283770072899;1660.4806601959313;1663.8336166383508;1262.7727009138443;1262.8926541244098;1279.705667696435;1288.826008488842;1293.36625053723;5273.973630131824;5275.57795376369;5370.704611246419;5376.327959005124;5407.675539467645;5515.227868098469;5563.527100196528;5609.326880774252;5644.955683198621;5649.802256920988;5124.30821839047;5421.1055175895535;5430.185472179126;5475.687209238433;5485.670859748428;2970.0792754246254;2980.9254768630526;2994.8352840593975;3003.588313492655;3010.819350838957;5577.721113944286;5784.913226301609;5831.591788574844;5900.527957763455;5910.940890591039;5706.686132262683;5706.942930570641;5710.371859095496;5746.166805813134;5752.482276595043;5841.757824407495;5865.706714664249;5871.74478841443;5875.206847075676;5919.792807251812;5443.755031023535;5669.137085771824;5698.088305871041;5732.942670573241;5759.939242833876;5401.756920938488;5458.536024437967;5649.350324712711;5656.208130861601;5693.430656934273;5712.571557133148;5867.647941123546;5870.412958704095;5892.616979896465;5908.763649453974;4677.082396217535;4726.338312636165;4729.527047295243;4730.787114579921;4743.075100355398;5442.8500742205815;5587.664740115606;5647.656222667547;5673.631213971079;5730.455606717355;5367.758450869642;5525.4474552544425;5566.359868615161;5599.692016938944;5649.435056494318;5293.173855020166;5447.400785913467;5483.711327838035;5557.610967232416;5618.494335509824;5370.650907690988;5432.511074003359;5486.506214440719;5500.5874559409;5521.475459831309;5533.6403133796175;5541.647303381557;5587.53572784876;5595.328560572757;5608.653293697254;5329.280547126088;5434.402215756185;5510.393856675682;5535.750891209986;5547.944520554743 09:07:16 INFO - PROCESS | 9476 | |5;yui.html;852.1393392673398;852.3905407633541;857.3612658569367;893.2764460786763;921.1479381572044;16024.759628605565;16681.582960425996;16718.24767885445;16721.91639041803;16847.915760421198;16462.259198336073;16602.169891505408;16855.747163792552;17545.91227043866;17590.560235994108;838.1032295443878;852.2031900173342;863.87905693203;878.2183856367838;881.0220655072864;872.3457406944791;876.7150676030284;876.842168409686;878.3851304087165;879.5118709116438;912.9041450647653;913.0321858829628;914.1589737441569;914.4147745442922;915.2997956563235;906.0395980260945;910.3127139010038;910.4628269321099;910.7449914024083;911.4850109688005;669.357416879796;672.0993868216614;672.7006482115412;672.7342699633666;673.2997682410287;17156;17282.740758888627;17300.134993250413;18053.09734513282;18243.817561824344;15264.847351526565;15514.224288785628;15730.921345393313;15795.684086318324;15873.285702143432;12868.485260589565;13209.40557674907;13272.402741876638;13402;13520.459181632723;3910.824012919397;4037.0916543777707;4061.2486689962157;4110.301248787713;4175.478065241845;85372.54395293116;85427.57286213592;86217;88565;90227.09772902253;11939.88060119405;12006.51973921051;12072.215306005082;12117.39413029345;12440.564580239829;21303.68044479334;21881.67177492339;23331;23627.881860590587;23837.404064898168;8521.147885211429;8778.078301778349;9013.459192448474;9088.227500662386;9094.454332740057;5008.424031236382;5267.947320526746;5307.681539107666;5352.090144675414;5355.625106242525;3633;3706.314331848617;3738.700903927679;3739.8878033659034;3765.849366025383;648.9643069631121;653.4347789162435;662.493192707579;666.8332916770808;674.5345711459078;739.4195556488131;749.9287567681062;752.2402373602591;771.2172145272552;784.5920121536769;9536.809263814685;10213.234007449471;10462.372257664412;10752.354858708502;11306.547738090385;787.3189690917316;799.1050023973187;799.3285640062376;813.1055838577518;817.464560712734;5146.382434107931;5159.277701121846;5176.1459359206065;5249.553787927993;5259.579233661298;79540.60229698815;80936.19063809405;81148.18851811525;82081.58959205166;83249;60101.69949150314;61661;71654.64172679103;72623;73146;59414.70292648509;63131.368686313464;64903.350966490674;65353.67323163354;66846;10886.346819190874;11260.211785174959;11508.53965841357;11550.806403381099;11699.181057325906;41727.58272417237;42218.78890605527;42751.786241069836;42826.78586607172;43566.34650480182;50161.498385015686;50760.49239507705;50859;50888.745556271984;50984.74507627438;13439.059265851296;13583.117097389033;13691.794623080861;13863.584092476838;13994.230317332238;16579.419720309732;17056.82943170602;17177.398791042255;17209.56976075558;17296.827031730027;13460.326983650582;13819.239941803296;13868.682191385;14190.574282771533;14583.416663333348;14495.855041449451;14565.34455949506;14610.488632897797;14728.705425891209;14935.17856517902;14582.781258281346;14590.635234119232;14679.41282348694;14986.625334366729;15698;10756;10911.945440272748;11140.554377824796;11140.777184456427;11951.521939122338;11772.52909883595;12276.693082673004;12752.42614082387;12943.741125177632;12992.155509891827 09:07:16 INFO - PROCESS | 9476 | __end_tp_report 09:07:16 INFO - PROCESS | 9476 | __start_cc_report 09:07:16 INFO - PROCESS | 9476 | _x_x_mozilla_cycle_collect,1115 09:07:16 INFO - PROCESS | 9476 | __end_cc_report 09:07:16 INFO - PROCESS | 9476 | __startTimestamp1492099636358__endTimestamp 09:07:16 INFO - PROCESS | 9476 | 09:07:16 INFO - PROCESS | 9476 | ------- Summary: start ------- 09:07:16 INFO - PROCESS | 9476 | Number of tests: 6 09:07:16 INFO - PROCESS | 9476 | 09:07:16 INFO - PROCESS | 9476 | [#0] dojo.html Cycles:165 Average:5587.71 Median:5825.30 stddev:632.02 (10.8%) stddev-sans-first:608.23 09:07:16 INFO - PROCESS | 9476 | Values: 3312.5 3313.8 3352.0 3359.6 3372.7 5565.8 5634.7 5646.3 5655.9 5662.2 5630.4 5658.9 5670.4 5680.3 5737.8 5027.5 5040.2 5064.1 5089.1 5115.7 4923.8 4932.8 5120.3 5124.5 5152.0 4950.8 4960.9 5054.8 5076.1 5084.4 5088.9 5095.4 5100.7 5101.3 5124.5 4774.5 5003.2 5018.8 5063.0 5079.4 5495.8 5496.2 5502.1 5505.5 5509.9 5844.3 5873.3 5891.2 5891.3 5895.9 5916.8 5925.1 5925.7 5928.8 5951.9 5688.1 5697.8 5720.8 5728.7 5728.7 6169.3 6175.8 6175.9 6178.4 6178.8 5755.9 5944.4 5954.9 5956.4 5968.0 5968.6 5974.3 5994.2 6013.9 6031.9 6038.7 6054.8 6058.2 6061.1 6065.5 5808.2 5856.8 5891.4 5931.5 6013.2 4647.5 4668.3 4670.0 4672.6 4673.6 4098.9 4110.5 4122.1 4129.4 4134.7 5596.4 5850.7 5858.3 5860.0 5864.1 5936.9 5939.0 6017.0 6095.8 6116.8 5784.1 5810.9 5812.9 5819.6 5821.7 5139.2 5145.7 5158.1 5173.3 5175.4 6024.4 6077.9 6135.7 6213.9 6278.8 6063.5 6066.7 6074.2 6074.2 6104.0 6244.4 6265.4 6275.1 6275.4 6275.5 5756.0 5814.6 5857.4 5952.3 6048.7 6097.1 6111.5 6112.9 6113.2 6128.3 5904.7 5943.7 5962.9 5965.2 5967.8 5640.2 5675.3 5825.3 5948.8 5965.9 5932.6 5932.8 5941.4 5960.0 5970.6 5928.8 5963.6 5968.1 5970.8 5971.6 5615.5 5657.7 5748.3 5753.1 5754.5 09:07:16 INFO - PROCESS | 9476 | 09:07:16 INFO - PROCESS | 9476 | [#1] ext.html Cycles:180 Average:43857.27 Median:32669.01 stddev:68438.90 (209.5%) stddev-sans-first:68554.40 09:07:16 INFO - PROCESS | 9476 | Values: 765.7 821.4 837.8 896.1 908.0 31090.8 43095.8 43595.0 43826.6 43875.8 43615.0 43824.3 43846.6 43979.3 44045.8 7754.7 7908.7 9117.5 9347.3 9350.9 10615.4 10826.1 10829.1 10832.2 11128.8 10837.7 10879.2 10886.7 10889.6 10890.6 10773.1 10791.9 10808.6 10809.5 10813.5 10588.5 10607.2 10613.7 10672.0 10674.1 40750.0 40795.4 40897.2 41229.6 41421.6 3939.8 4004.4 4034.2 4070.2 4116.3 29392.4 30475.4 30513.4 30608.2 30934.7 4451.3 4462.6 4485.4 4655.3 4697.1 222195.0 222355.0 222988.0 223481.0 223921.0 53121.0 53221.0 53238.7 53273.2 53344.5 55348.4 55570.7 55580.4 55638.0 55665.0 16559.4 16859.7 16865.4 16895.4 16897.5 11969.4 12092.9 14585.6 14753.3 14766.1 13045.0 13101.0 13177.1 13179.5 13309.7 5402.6 5409.2 5458.8 5553.8 5560.9 974.9 975.4 993.4 1003.9 1018.3 27655.6 27824.9 28045.8 28229.4 28280.3 1068.3 1078.3 1079.7 1099.6 1101.1 14544.3 14961.9 15009.5 15039.6 15159.5 285769.0 291069.0 291076.0 291108.0 291780.0 56483.4 56871.4 56963.1 57022.1 57054.7 1488.3 1521.3 1542.4 1563.4 1563.6 25847.0 26898.3 27108.7 27441.5 27465.0 43741.6 43743.0 43744.6 43816.0 43870.3 270250.0 271875.0 272073.0 272899.0 273015.0 30066.4 30564.4 32679.7 35558.5 35662.6 43856.8 44324.8 44355.0 44585.0 44616.3 34337.5 34379.1 34590.8 34616.0 34619.3 33851.8 34036.0 34076.5 34240.8 34452.1 38199.0 38276.4 39241.2 39285.2 39535.6 32658.3 32858.2 32920.2 32920.8 33151.5 25894.9 26204.0 26207.1 26323.0 26767.6 09:07:16 INFO - PROCESS | 9476 | 09:07:16 INFO - PROCESS | 9476 | [#2] jquery.html Cycles:180 Average:36981.12 Median:2457.50 stddev:104042.30 (4233.7%) stddev-sans-first:104298.04 09:07:16 INFO - PROCESS | 9476 | Values: 466.0 467.4 482.4 487.8 502.3 19495.7 21113.4 21137.0 21163.7 21218.8 21433.8 21665.8 22244.2 22349.9 22618.1 6598.3 6706.9 6715.8 6815.0 6880.2 6660.7 6786.5 6788.2 7690.8 8007.6 7903.2 7998.4 8178.8 8180.3 8192.3 7785.5 7883.5 7883.5 7888.1 7891.2 7513.3 7935.9 7985.8 7986.0 7989.5 29233.9 29531.4 29782.4 30121.8 30174.8 3196.2 3256.1 3276.6 3288.3 3296.0 18179.8 18273.2 18500.2 18515.9 18541.6 2383.7 2401.9 2417.3 2443.3 2471.7 75726.4 85558.0 117221.4 117976.0 118099.0 20981.7 21126.5 21228.6 21520.6 24280.5 41881.6 41951.6 42028.6 42079.8 42112.2 10227.8 10677.8 10690.4 10743.0 10968.7 8704.9 8818.6 8901.8 8959.1 9037.6 8901.7 9274.5 9410.0 9414.8 9630.6 3676.6 3686.3 3696.8 3729.9 3756.2 931.6 952.9 961.5 966.1 967.6 19720.4 20948.7 21083.4 21232.3 21505.5 961.5 965.6 969.6 980.2 1006.4 10877.6 11201.9 11489.4 11587.9 11639.4 609837.0 622969.0 623211.0 652900.0 655110.0 79303.6 99605.5 110660.0 111796.4 112863.0 1325.8 1346.7 1371.7 1372.9 1382.9 18767.2 19011.5 19061.2 19098.2 19234.8 36677.1 44641.3 47192.8 49303.8 52064.0 65716.7 66064.7 70538.0 76366.6 78101.6 12107.7 12844.4 12996.2 13020.3 13291.9 13863.6 14284.5 14476.0 14486.0 14913.1 12092.3 12215.5 12899.9 12921.9 12977.8 11624.7 11966.7 12021.6 12419.0 12461.6 12636.9 12731.3 12852.9 13059.3 13242.8 11833.6 11917.3 12214.3 12315.5 12336.5 11995.5 12090.2 12104.9 12938.5 12946.4 09:07:16 INFO - PROCESS | 9476 | 09:07:16 INFO - PROCESS | 9476 | [#3] mootools.html Cycles:180 Average:7108.98 Median:2304.57 stddev:4596.18 (199.4%) stddev-sans-first:4590.11 09:07:16 INFO - PROCESS | 9476 | Values: 1552.3 1584.2 1596.2 1602.6 1603.5 10951.2 11708.6 11764.4 11769.2 11774.4 11323.5 11790.1 11795.4 11798.0 11803.8 2381.1 2417.5 2419.8 2441.3 2441.4 2298.1 2365.1 2385.5 2390.0 2390.4 2259.9 2290.1 2299.4 2354.4 2357.2 2266.0 2286.6 2291.9 2293.0 2309.7 2274.0 2276.4 2286.5 2287.9 2291.2 11459.8 11482.9 11532.8 11554.7 11560.2 5461.6 5582.7 5710.3 5725.3 5726.7 3299.6 3349.6 3441.2 3444.3 3465.9 1365.8 1373.5 1440.1 1467.5 1477.8 14781.2 15074.3 16936.0 17516.3 17849.6 4728.1 4744.6 4775.5 4874.9 4882.1 13114.3 13143.9 13146.8 13147.9 13156.2 4884.2 5034.4 5685.1 5809.8 5813.9 5306.4 5341.9 5439.7 5439.9 5441.9 4026.0 4027.0 4086.9 4098.2 4193.8 1024.3 1031.7 1036.6 1036.7 1055.8 973.6 1001.7 1002.1 1002.7 1020.8 6956.6 7100.9 7170.2 7336.2 7430.9 1041.9 1058.7 1060.1 1065.8 1067.7 4321.9 4420.7 4468.1 4521.6 4526.3 14693.7 15277.2 15294.4 15306.6 15313.1 14224.1 14679.3 15244.5 15261.8 15277.6 9021.2 10455.2 10659.9 10671.4 10723.1 6269.7 7345.8 7485.8 7632.5 7634.9 12600.1 12600.9 12689.7 12750.3 12933.6 12983.1 12985.2 13013.6 13020.9 13033.2 9760.5 9859.5 9870.8 9974.3 9980.5 10820.2 10822.0 10831.2 10910.6 10934.2 9142.2 9230.1 9309.8 9377.2 9483.3 9018.0 9094.0 9235.2 9452.3 9463.8 8420.2 8528.1 8601.1 8625.4 8633.2 5827.8 5870.9 5972.1 6026.3 6120.1 5969.8 5975.9 6061.1 6111.7 6292.6 09:07:16 INFO - PROCESS | 9476 | 09:07:16 INFO - PROCESS | 9476 | [#4] prototype.html Cycles:180 Average:4797.41 Median:5277.54 stddev:1277.74 (24.2%) stddev-sans-first:1238.38 09:07:16 INFO - PROCESS | 9476 | Values: 420.9 423.2 438.7 438.8 447.6 5124.3 5249.6 5269.6 5279.5 5344.7 4944.3 5147.7 5169.2 5170.9 5263.0 4318.4 4319.5 4326.2 4343.2 4368.8 4359.2 4380.7 4389.4 4397.4 4403.3 4267.3 4299.8 4352.8 4366.9 4407.0 4266.4 4277.7 4277.9 4284.4 4339.4 4036.2 4038.5 4048.0 4052.0 4061.1 4998.8 5024.4 5069.5 5089.4 5092.7 5081.8 5094.7 5126.8 5151.2 5163.9 5415.9 5441.8 5453.2 5460.0 5473.5 4396.9 4534.9 4577.0 4600.7 4660.3 5424.7 5560.6 5581.6 5679.7 5734.9 4706.5 4709.3 4718.7 4723.2 4784.8 4633.4 4758.7 4874.2 4877.3 4877.5 5014.7 5021.6 5033.3 5075.8 5094.4 5521.6 5528.7 5543.3 5554.3 5560.9 1574.6 1637.6 1658.3 1660.5 1663.8 1262.8 1262.9 1279.7 1288.8 1293.4 5274.0 5275.6 5370.7 5376.3 5407.7 5515.2 5563.5 5609.3 5645.0 5649.8 5124.3 5421.1 5430.2 5475.7 5485.7 2970.1 2980.9 2994.8 3003.6 3010.8 5577.7 5784.9 5831.6 5900.5 5910.9 5706.7 5706.9 5710.4 5746.2 5752.5 5841.8 5865.7 5871.7 5875.2 5919.8 5443.8 5669.1 5698.1 5732.9 5759.9 5401.8 5458.5 5649.4 5656.2 5693.4 5712.6 5867.6 5870.4 5892.6 5908.8 4677.1 4726.3 4729.5 4730.8 4743.1 5442.9 5587.7 5647.7 5673.6 5730.5 5367.8 5525.4 5566.4 5599.7 5649.4 5293.2 5447.4 5483.7 5557.6 5618.5 5370.7 5432.5 5486.5 5500.6 5521.5 5533.6 5541.6 5587.5 5595.3 5608.7 5329.3 5434.4 5510.4 5535.8 5547.9 09:07:16 INFO - PROCESS | 9476 | 09:07:16 INFO - PROCESS | 9476 | [#5] yui.html Cycles:180 Average:18418.19 Median:42485.29 stddev:22977.63 (54.1%) stddev-sans-first:23004.23 09:07:16 INFO - PROCESS | 9476 | Values: 852.1 852.4 857.4 893.3 921.1 16024.8 16681.6 16718.2 16721.9 16847.9 16462.3 16602.2 16855.7 17545.9 17590.6 838.1 852.2 863.9 878.2 881.0 872.3 876.7 876.8 878.4 879.5 912.9 913.0 914.2 914.4 915.3 906.0 910.3 910.5 910.7 911.5 669.4 672.1 672.7 672.7 673.3 17156.0 17282.7 17300.1 18053.1 18243.8 15264.8 15514.2 15730.9 15795.7 15873.3 12868.5 13209.4 13272.4 13402.0 13520.5 3910.8 4037.1 4061.2 4110.3 4175.5 85372.5 85427.6 86217.0 88565.0 90227.1 11939.9 12006.5 12072.2 12117.4 12440.6 21303.7 21881.7 23331.0 23627.9 23837.4 8521.1 8778.1 9013.5 9088.2 9094.5 5008.4 5267.9 5307.7 5352.1 5355.6 3633.0 3706.3 3738.7 3739.9 3765.8 649.0 653.4 662.5 666.8 674.5 739.4 749.9 752.2 771.2 784.6 9536.8 10213.2 10462.4 10752.4 11306.5 787.3 799.1 799.3 813.1 817.5 5146.4 5159.3 5176.1 5249.6 5259.6 79540.6 80936.2 81148.2 82081.6 83249.0 60101.7 61661.0 71654.6 72623.0 73146.0 59414.7 63131.4 64903.4 65353.7 66846.0 10886.3 11260.2 11508.5 11550.8 11699.2 41727.6 42218.8 42751.8 42826.8 43566.3 50161.5 50760.5 50859.0 50888.7 50984.7 13439.1 13583.1 13691.8 13863.6 13994.2 16579.4 17056.8 17177.4 17209.6 17296.8 13460.3 13819.2 13868.7 14190.6 14583.4 14495.9 14565.3 14610.5 14728.7 14935.2 14582.8 14590.6 14679.4 14986.6 15698.0 10756.0 10911.9 11140.6 11140.8 11951.5 11772.5 12276.7 12752.4 12943.7 12992.2 09:07:16 INFO - PROCESS | 9476 | -------- Summary: end -------- 09:07:16 INFO - PROCESS | 9476 | 09:07:17 INFO - TEST-INFO | 9476: exit 0 09:07:17 INFO - TEST-OK | dromaeo_css | took 1115120ms 09:07:17 INFO - TEST-START | kraken 09:07:17 INFO - operating with platform_type : linux_ 09:07:17 INFO - Initialising browser for kraken test... 09:07:17 INFO - TEST-INFO | started process 9799 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6CVHD2/profile http://localhost:36560/getInfo.html) 09:07:20 INFO - PROCESS | 9799 | __metrics Screen width/height:1600/1200 09:07:20 INFO - PROCESS | 9799 | colorDepth:24 09:07:20 INFO - PROCESS | 9799 | Browser inner width/height: 1024/697 09:07:20 INFO - PROCESS | 9799 | __metrics 09:07:21 INFO - PROCESS | 9799 | 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" 09:07:21 INFO - PROCESS | 9799 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:07:22 INFO - TEST-INFO | 9799: exit 0 09:07:22 INFO - Browser initialized. 09:07:22 INFO - Running cycle 1/1 for kraken test... 09:07:22 INFO - TEST-INFO | started process 9891 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6CVHD2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:07:34 INFO - PROCESS | 9891 | RSS: Main: 139943936 09:07:34 INFO - PROCESS | 9891 | 09:07:37 INFO - PROCESS | 9891 | Cycle 1(1): loaded http://localhost:36560/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36560/tests/kraken/driver.html?testName=audio-beat-detection) 09:07:38 INFO - PROCESS | 9891 | RSS: Main: 182587392 09:07:38 INFO - PROCESS | 9891 | 09:07:40 INFO - PROCESS | 9891 | Cycle 1(1): loaded http://localhost:36560/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36560/tests/kraken/driver.html?testName=audio-dft) 09:07:41 INFO - PROCESS | 9891 | RSS: Main: 173858816 09:07:41 INFO - PROCESS | 9891 | 09:07:44 INFO - PROCESS | 9891 | Cycle 1(1): loaded http://localhost:36560/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36560/tests/kraken/driver.html?testName=audio-fft) 09:07:44 INFO - PROCESS | 9891 | RSS: Main: 265707520 09:07:44 INFO - PROCESS | 9891 | 09:07:46 INFO - PROCESS | 9891 | Cycle 1(1): loaded http://localhost:36560/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36560/tests/kraken/driver.html?testName=audio-oscillator) 09:07:47 INFO - PROCESS | 9891 | RSS: Main: 171769856 09:07:47 INFO - PROCESS | 9891 | 09:07:49 INFO - PROCESS | 9891 | Cycle 1(1): loaded http://localhost:36560/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36560/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:07:49 INFO - PROCESS | 9891 | RSS: Main: 174690304 09:07:49 INFO - PROCESS | 9891 | 09:07:52 INFO - PROCESS | 9891 | Cycle 1(1): loaded http://localhost:36560/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36560/tests/kraken/driver.html?testName=imaging-darkroom) 09:07:53 INFO - PROCESS | 9891 | RSS: Main: 213704704 09:07:53 INFO - PROCESS | 9891 | 09:07:56 INFO - PROCESS | 9891 | Cycle 1(1): loaded http://localhost:36560/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36560/tests/kraken/driver.html?testName=imaging-desaturate) 09:07:57 INFO - PROCESS | 9891 | RSS: Main: 212430848 09:07:57 INFO - PROCESS | 9891 | 09:08:00 INFO - PROCESS | 9891 | Cycle 1(1): loaded http://localhost:36560/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36560/tests/kraken/driver.html?testName=json-parse-financial) 09:08:00 INFO - PROCESS | 9891 | RSS: Main: 211136512 09:08:00 INFO - PROCESS | 9891 | 09:08:02 INFO - PROCESS | 9891 | Cycle 1(1): loaded http://localhost:36560/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36560/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:08:02 INFO - PROCESS | 9891 | RSS: Main: 172953600 09:08:02 INFO - PROCESS | 9891 | 09:08:04 INFO - PROCESS | 9891 | Cycle 1(1): loaded http://localhost:36560/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36560/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:08:05 INFO - PROCESS | 9891 | RSS: Main: 178958336 09:08:05 INFO - PROCESS | 9891 | 09:08:07 INFO - PROCESS | 9891 | Cycle 1(1): loaded http://localhost:36560/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36560/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:08:07 INFO - PROCESS | 9891 | RSS: Main: 189054976 09:08:07 INFO - PROCESS | 9891 | 09:08:10 INFO - PROCESS | 9891 | Cycle 1(1): loaded http://localhost:36560/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36560/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:08:11 INFO - PROCESS | 9891 | RSS: Main: 199168000 09:08:11 INFO - PROCESS | 9891 | 09:08:13 INFO - PROCESS | 9891 | Cycle 1(1): loaded http://localhost:36560/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36560/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:08:14 INFO - PROCESS | 9891 | RSS: Main: 195338240 09:08:14 INFO - PROCESS | 9891 | 09:08:16 INFO - PROCESS | 9891 | Cycle 1(1): loaded http://localhost:36560/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36560/tests/kraken/driver.html?testName=ai-astar) 09:08:16 INFO - PROCESS | 9891 | RSS: Main: 244625408 09:08:16 INFO - PROCESS | 9891 | 09:08:16 INFO - PROCESS | 9891 | __start_tp_report 09:08:16 INFO - PROCESS | 9891 | _x_x_mozilla_page_load 09:08:16 INFO - PROCESS | 9891 | _x_x_mozilla_page_load_details 09:08:16 INFO - PROCESS | 9891 | |i|pagename|runs| 09:08:16 INFO - PROCESS | 9891 | |0;ai-astar;104;112;112;112;113;113;112;113;111;112 09:08:16 INFO - PROCESS | 9891 | |1;audio-beat-detection;135;117;117;159;117;116;117;115;135;118 09:08:16 INFO - PROCESS | 9891 | |2;audio-dft;162;164;166;162;162;163;162;163;165;162 09:08:16 INFO - PROCESS | 9891 | |3;audio-fft;75;76;76;110;76;76;79;76;89;75 09:08:16 INFO - PROCESS | 9891 | |4;audio-oscillator;82;82;121;83;82;83;92;82;83;99 09:08:16 INFO - PROCESS | 9891 | |5;imaging-gaussian-blur;113;112;113;113;112;113;112;113;113;113 09:08:16 INFO - PROCESS | 9891 | |6;imaging-darkroom;149;146;147;145;147;147;146;146;147;146 09:08:16 INFO - PROCESS | 9891 | |7;imaging-desaturate;94;94;94;94;95;94;94;94;94;94 09:08:16 INFO - PROCESS | 9891 | |8;json-parse-financial;65;53;54;54;54;55;60;54;54;53 09:08:16 INFO - PROCESS | 9891 | |9;json-stringify-tinderbox;52;60;54;53;53;53;55;52;54;54 09:08:16 INFO - PROCESS | 9891 | |10;stanford-crypto-aes;83;80;77;77;77;79;81;79;80;80 09:08:16 INFO - PROCESS | 9891 | |11;stanford-crypto-ccm;124;135;120;122;120;130;121;124;165;118 09:08:16 INFO - PROCESS | 9891 | |12;stanford-crypto-pbkdf2;162;163;161;175;163;165;166;161;158;166 09:08:16 INFO - PROCESS | 9891 | |13;stanford-crypto-sha256-iterative;66;61;59;59;64;60;59;60;58;64 09:08:16 INFO - PROCESS | 9891 | __end_tp_report 09:08:16 INFO - PROCESS | 9891 | __start_cc_report 09:08:16 INFO - PROCESS | 9891 | _x_x_mozilla_cycle_collect,1691 09:08:16 INFO - PROCESS | 9891 | __end_cc_report 09:08:16 INFO - PROCESS | 9891 | __startTimestamp1492099696353__endTimestamp 09:08:16 INFO - PROCESS | 9891 | 09:08:16 INFO - PROCESS | 9891 | ------- Summary: start ------- 09:08:16 INFO - PROCESS | 9891 | Number of tests: 14 09:08:16 INFO - PROCESS | 9891 | 09:08:16 INFO - PROCESS | 9891 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:2.67 (2.4%) stddev-sans-first:0.67 09:08:16 INFO - PROCESS | 9891 | Values: 104.0 112.0 112.0 112.0 113.0 113.0 112.0 113.0 111.0 112.0 09:08:16 INFO - PROCESS | 9891 | 09:08:16 INFO - PROCESS | 9891 | [#1] audio-beat-detection Cycles:10 Average:124.60 Median:117.50 stddev:14.30 (12.2%) stddev-sans-first:14.66 09:08:16 INFO - PROCESS | 9891 | Values: 135.0 117.0 117.0 159.0 117.0 116.0 117.0 115.0 135.0 118.0 09:08:16 INFO - PROCESS | 9891 | 09:08:16 INFO - PROCESS | 9891 | [#2] audio-dft Cycles:10 Average:163.10 Median:163.00 stddev:1.45 (0.9%) stddev-sans-first:1.48 09:08:16 INFO - PROCESS | 9891 | Values: 162.0 164.0 166.0 162.0 162.0 163.0 162.0 163.0 165.0 162.0 09:08:16 INFO - PROCESS | 9891 | 09:08:16 INFO - PROCESS | 9891 | [#3] audio-fft Cycles:10 Average:80.80 Median:76.00 stddev:11.08 (14.6%) stddev-sans-first:11.56 09:08:16 INFO - PROCESS | 9891 | Values: 75.0 76.0 76.0 110.0 76.0 76.0 79.0 76.0 89.0 75.0 09:08:16 INFO - PROCESS | 9891 | 09:08:16 INFO - PROCESS | 9891 | [#4] audio-oscillator Cycles:10 Average:88.90 Median:83.00 stddev:12.64 (15.2%) stddev-sans-first:13.15 09:08:16 INFO - PROCESS | 9891 | Values: 82.0 82.0 121.0 83.0 82.0 83.0 92.0 82.0 83.0 99.0 09:08:16 INFO - PROCESS | 9891 | 09:08:16 INFO - PROCESS | 9891 | [#5] imaging-gaussian-blur Cycles:10 Average:112.70 Median:113.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 09:08:16 INFO - PROCESS | 9891 | Values: 113.0 112.0 113.0 113.0 112.0 113.0 112.0 113.0 113.0 113.0 09:08:16 INFO - PROCESS | 9891 | 09:08:16 INFO - PROCESS | 9891 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:1.07 (0.7%) stddev-sans-first:0.71 09:08:16 INFO - PROCESS | 9891 | Values: 149.0 146.0 147.0 145.0 147.0 147.0 146.0 146.0 147.0 146.0 09:08:16 INFO - PROCESS | 9891 | 09:08:16 INFO - PROCESS | 9891 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:08:16 INFO - PROCESS | 9891 | Values: 94.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 94.0 94.0 09:08:16 INFO - PROCESS | 9891 | 09:08:16 INFO - PROCESS | 9891 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:54.00 stddev:3.86 (7.2%) stddev-sans-first:2.13 09:08:16 INFO - PROCESS | 9891 | Values: 65.0 53.0 54.0 54.0 54.0 55.0 60.0 54.0 54.0 53.0 09:08:16 INFO - PROCESS | 9891 | 09:08:16 INFO - PROCESS | 9891 | [#9] json-stringify-tinderbox Cycles:10 Average:54.00 Median:54.00 stddev:2.31 (4.3%) stddev-sans-first:2.33 09:08:16 INFO - PROCESS | 9891 | Values: 52.0 60.0 54.0 53.0 53.0 53.0 55.0 52.0 54.0 54.0 09:08:16 INFO - PROCESS | 9891 | 09:08:16 INFO - PROCESS | 9891 | [#10] stanford-crypto-aes Cycles:10 Average:79.30 Median:80.00 stddev:1.95 (2.4%) stddev-sans-first:1.54 09:08:16 INFO - PROCESS | 9891 | Values: 83.0 80.0 77.0 77.0 77.0 79.0 81.0 79.0 80.0 80.0 09:08:16 INFO - PROCESS | 9891 | 09:08:16 INFO - PROCESS | 9891 | [#11] stanford-crypto-ccm Cycles:10 Average:127.90 Median:124.00 stddev:14.01 (11.3%) stddev-sans-first:14.79 09:08:16 INFO - PROCESS | 9891 | Values: 124.0 135.0 120.0 122.0 120.0 130.0 121.0 124.0 165.0 118.0 09:08:16 INFO - PROCESS | 9891 | 09:08:16 INFO - PROCESS | 9891 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.00 Median:164.00 stddev:4.59 (2.8%) stddev-sans-first:4.82 09:08:16 INFO - PROCESS | 9891 | Values: 162.0 163.0 161.0 175.0 163.0 165.0 166.0 161.0 158.0 166.0 09:08:16 INFO - PROCESS | 9891 | 09:08:16 INFO - PROCESS | 9891 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.00 Median:60.50 stddev:2.71 (4.5%) stddev-sans-first:2.19 09:08:16 INFO - PROCESS | 9891 | Values: 66.0 61.0 59.0 59.0 64.0 60.0 59.0 60.0 58.0 64.0 09:08:16 INFO - PROCESS | 9891 | -------- Summary: end -------- 09:08:16 INFO - PROCESS | 9891 | 09:08:17 INFO - TEST-INFO | 9891: exit 0 09:08:17 INFO - TEST-OK | kraken | took 60391ms 09:08:17 INFO - SUITE-END | took 1175s 09:08:17 INFO - Completed test suite (00:19:35) 09:08:17 INFO - javascript benchmark 09:08:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7829.073778485742, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3312.48656458249, 3313.78460400074, 3352.027911905546, 3359.6472370401093, 3372.713319367855, 5565.833025009246, 5634.7182640867995, 5646.322441307038, 5655.915161272579, 5662.207290979266, 5630.436956304367, 5658.858528536789, 5670.376258611549, 5680.318361796591, 5737.7991770288045, 5027.547520723143, 5040.218766091261, 5064.11378008847, 5089.109405853961, 5115.744212789365, 4923.753812309388, 4932.753362331887, 5120.283160357553, 5124.5131712487255, 5152.046871328797, 4950.777215025332, 4960.925586116211, 5054.797808087672, 5076.086304465195, 5084.3644544431945, 5088.872778180538, 5095.4140273868425, 5100.719460429675, 5101.285819985205, 5124.461931497176, 4774.546418090275, 5003.17447621142, 5018.774155163026, 5063.0, 5079.390473143247, 5495.835124946258, 5496.1755736639425, 5502.119660854244, 5505.504504594565, 5509.862253443656, 5844.298684157886, 5873.3245676746865, 5891.204687367236, 5891.263592050994, 5895.852603684899, 5916.792912248051, 5925.05199168131, 5925.674087925162, 5928.7924922627935, 5951.940480595225, 5688.146777983336, 5697.800576979827, 5720.828375148711, 5728.713564321809, 5728.742206600755, 6169.259688837368, 6175.814725558241, 6175.938240617626, 6178.41305076017, 6178.845528861724, 5755.913661295084, 5944.4352786485215, 5954.85112872173, 5956.434138756811, 5968.0, 5968.612040217372, 5974.312954010345, 5994.220751302303, 6013.939860601426, 6031.909521357183, 6038.728257228435, 6054.848628784315, 6058.182145410401, 6061.090836374491, 6065.4541091302, 5808.215890854765, 5856.765729370863, 5891.440313170242, 5931.525477961753, 6013.248343957005, 4647.535246475393, 4668.299755036772, 4669.95330046702, 4672.602828759525, 4673.626109911199, 4098.938515922264, 4110.506739191256, 4122.134351786159, 4129.380592911088, 4134.689898257643, 5596.38439771633, 5850.707464626837, 5858.2677165354335, 5859.970700146472, 5864.149698293723, 5936.9406305937255, 5939.049752039653, 6017.037274036221, 6095.75616975316, 6116.755329786848, 5784.1034639630925, 5810.941890581124, 5812.88374232513, 5819.592628516048, 5821.650700957956, 5139.203423469294, 5145.691258524501, 5158.148905430637, 5173.275741396207, 5175.353080864892, 6024.3975602439405, 6077.878442431215, 6135.661410523283, 6213.875722485525, 6278.780242691484, 6063.5452341073715, 6066.7269972850445, 6074.210352654303, 6074.240719910012, 6103.969480152571, 6244.375562443719, 6265.373462653881, 6275.121482992476, 6275.403836635513, 6275.497960163268, 5756.0, 5814.622049566765, 5857.355690873916, 5952.315483719428, 6048.7278072487725, 6097.115918191926, 6111.541634377529, 6112.938870611415, 6113.172264937138, 6128.295246046761, 5904.734286957011, 5943.732532035982, 5962.910556341745, 5965.164876917322, 5967.8209653710455, 5640.153976903333, 5675.290588676416, 5825.300963884361, 5948.821535353946, 5965.850853728692, 5932.644041357532, 5932.822015339547, 5941.376155503783, 5959.970200148971, 5970.641761494324, 5928.79249226275, 5963.552733545088, 5968.104784282393, 5970.820875373745, 5971.611845230046, 5615.494605485526, 5657.660540367591, 5748.281464816898, 5753.1370294456165, 5754.4533269339345], "value": 5545.911396707135, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [765.6631082323777, 821.4291067707943, 837.7821766340752, 896.0904681748023, 907.9682211122604, 31090.84454577727, 43095.84520773961, 43595.0, 43826.56173438261, 43875.78062109693, 43615.0, 43824.342634860426, 43846.561534384644, 43979.34030989538, 44045.77977110118, 7754.651040703182, 7908.683652653887, 9117.544122793866, 9347.29895257855, 9350.859737103949, 10615.416152111631, 10826.079783218436, 10829.133669306437, 10832.187585931048, 11128.777424451506, 10837.67486975388, 10879.184061195403, 10886.67339979802, 10889.618863339785, 10890.564377424893, 10773.138148948105, 10791.892081079168, 10808.621698240564, 10809.459527023657, 10813.459327033617, 10588.523516441779, 10607.15142788575, 10613.681589552325, 10672.0, 10674.146068314594, 40750.0, 40795.38806917899, 40897.18205635871, 41229.587704122874, 41421.58578414207, 3939.8030098495246, 4004.4227953261543, 4034.213328400963, 4070.206309769596, 4116.320807066831, 29392.412151756846, 30475.390492190254, 30513.389732205454, 30608.2347941301, 30934.690653093632, 4451.332300154971, 4462.642988560908, 4485.439320084989, 4655.348251244828, 4697.060587882403, 222195.0, 222355.0, 222988.0, 223480.99999999837, 223921.0, 53121.0, 53221.0, 53238.73380633072, 53273.2009019865, 53344.46655533395, 55348.446515535135, 55570.72214638982, 55580.44419555834, 55638.0, 55665.0, 16559.420420285474, 16859.662806743796, 16865.409710660315, 16895.408660696816, 16897.493075207763, 11969.401529923469, 12092.939535302266, 14585.635359115895, 14753.262336883114, 14766.114033158045, 13045.0, 13101.0, 13177.143485673401, 13179.538716144889, 13309.66725831862, 5402.648827826179, 5409.24270602116, 5458.836234912959, 5553.806875808738, 5560.944390556043, 974.8976357482362, 975.3757595139117, 993.3841018568389, 1003.9196864250843, 1018.2922868606396, 27655.58516622252, 27824.860875695897, 28045.76958543626, 28229.43541129166, 28280.29299267535, 1068.273573969708, 1078.2991055813627, 1079.7246702090918, 1099.6426161497545, 1101.1026013798728, 14544.345504452325, 14961.850381496046, 15009.549713508612, 15039.624009399853, 15159.545213643609, 285769.0, 291069.0, 291076.0, 291108.0, 291780.0, 56483.43516564864, 56871.43128568744, 56963.14555281674, 57022.14466783001, 57054.7147264261, 1488.3376897280823, 1521.3154080663746, 1542.406173623163, 1563.4215340323926, 1563.6481791597027, 25847.0, 26898.327541810828, 27108.72891271141, 27441.451170976867, 27465.0, 43741.56258437375, 43743.0, 43744.562554374046, 43816.0, 43870.34194487021, 270250.0, 271875.0, 272073.0, 272899.0, 273015.0, 30066.398672026873, 30564.388712226075, 32679.673203267663, 35558.46662300119, 35662.64337356593, 43856.78071609749, 44324.77837610791, 44355.0, 44585.0, 44616.33075503935, 34337.48493772546, 34379.14052148616, 34590.82704586461, 34616.0, 34619.30761384808, 33851.83074084614, 34036.0, 34076.488852666735, 34240.828795855865, 34452.138696532784, 38199.0, 38276.42585361166, 39241.215175695754, 39285.214295713355, 39535.60464395319, 32658.346833063682, 32858.17854553655, 32920.176995575304, 32920.83539582287, 33151.50272745959, 25894.858486336572, 26204.0, 26207.082752103586, 26323.0, 26767.598486023115], "value": 18932.52553508667, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.96039336656384, 467.39238989313884, 482.36568911880875, 487.77318546875694, 502.2867528110084, 19495.70756438653, 21113.366599002016, 21137.0, 21163.68254476182, 21218.787812121875, 21433.785662143375, 21665.783342166575, 22244.221452249174, 22349.888250558764, 22618.09527618893, 6598.340165983412, 6706.865862682768, 6715.832104197398, 6815.045893574904, 6880.20877599075, 6660.733570657192, 6786.524943253974, 6788.185417749877, 7690.846183076363, 8007.639656215455, 7903.1701671324545, 7998.440109192359, 8178.754637360888, 8180.304674102708, 8192.34461243099, 7785.493942893694, 7883.487573307717, 7883.526988380658, 7888.092869320016, 7891.1714270001685, 7513.323800857895, 7935.880961785578, 7985.800354991114, 7985.96007019967, 7989.480683755595, 29233.853830730925, 29531.409371812657, 29782.404351913057, 30121.849390752905, 30174.849125754452, 3196.184972831936, 3256.0557438342853, 3276.639569647337, 3288.3007804762115, 3295.950560741591, 18179.818201817943, 18273.17770700322, 18500.16749246287, 18515.907420462947, 18541.62916741671, 2383.74970628085, 2401.931140642415, 2417.2627348658652, 2443.2670198940423, 2471.7281099079087, 75726.42824662793, 85558.0, 117221.4138929317, 117976.0, 118099.0, 20981.692013878463, 21126.47183820386, 21228.575428491342, 21520.569588608454, 24280.514389712105, 41881.581184187766, 41951.58048419477, 42028.57971420308, 42079.7896010518, 42112.15775684469, 10227.846582301272, 10677.786444271227, 10690.412027338416, 10743.0, 10968.725781855517, 8704.909757036161, 8818.647254109765, 8901.777455563662, 8959.148880856308, 9037.638494460149, 8901.7092521584, 9274.489903055262, 9409.952950235342, 9414.764630884145, 9630.566624501775, 3676.632336766302, 3686.2627474505207, 3696.7819308414737, 3729.850805967731, 3756.1874697182384, 931.5528546297679, 952.8618350339301, 961.4759955823953, 966.1497881864055, 967.5984466446487, 19720.40838774839, 20948.685769713466, 21083.367498975054, 21232.256871009442, 21505.462363441038, 961.5288508630721, 965.5510187762584, 969.6363863551169, 980.181548407088, 1006.4263369879245, 10877.564897404016, 11201.887981120084, 11489.368084755251, 11587.88412115885, 11639.418029098511, 609837.0, 622969.0, 623211.0, 652900.0, 655110.0, 79303.60348198222, 99605.50197248967, 110659.99999999838, 111796.44101779602, 112863.0, 1325.8210141630757, 1346.6767975685962, 1371.6707990082507, 1372.9107608005447, 1382.8893688504895, 18767.249310027993, 19011.524711882314, 19061.23755049838, 19098.236070557577, 19234.8076519233, 36677.08307292339, 44641.33038004497, 47192.7640361796, 49303.753481232365, 52064.0, 65716.67141664261, 66064.6696766513, 70538.0, 76366.61816690881, 78101.60949195217, 12107.705670678744, 12844.394450693664, 12996.155249908727, 13020.348982551024, 13291.867081329063, 13863.584092477242, 14284.500042498463, 14475.986680932234, 14486.0, 14913.105213687213, 12092.334921579046, 12215.450304736485, 12899.93550032244, 12921.870781292442, 12977.805332919825, 11624.6512604622, 11966.70083247891, 12021.639350819489, 12419.0064794818, 12461.626151215478, 12636.936815315865, 12731.2997785119, 12852.935735321264, 13059.281739504038, 13242.80135797983, 11833.585824496102, 11917.284962902253, 12214.328211948072, 12315.507379704712, 12336.50653973831, 11995.460204291003, 12090.214136081127, 12104.939475302566, 12938.547150849676, 12946.417411216706], "value": 11875.27133125113, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1552.3092223960343, 1584.2474824458384, 1596.1699916043656, 1602.6313947792005, 1603.4548253593769, 10951.233413661046, 11708.648740537787, 11764.411779411039, 11769.176157668966, 11774.411279436037, 11323.490442930068, 11790.115741319392, 11795.410229488534, 11798.0, 11803.763924721521, 2381.142788596104, 2417.468157005456, 2419.806415486757, 2441.292025312657, 2441.426264827764, 2298.080767692928, 2365.0894405653776, 2385.522895420914, 2389.95220095598, 2390.3665528634924, 2259.9435014124615, 2290.061074959267, 2299.41364951937, 2354.3878591566145, 2357.1867705641525, 2265.9773402265932, 2286.6455699366625, 2291.873946932918, 2293.025464177726, 2309.722833260003, 2274.0221704666988, 2276.3512398966213, 2286.5198308355098, 2287.9428014299774, 2291.1980806717515, 11459.77080458386, 11482.88517114835, 11532.769344613145, 11554.711132221677, 11560.190786644938, 5461.644993075437, 5582.692951887645, 5710.343310519261, 5725.312962444534, 5726.656400615976, 3299.5875515560556, 3349.5645566076496, 3441.1569165554347, 3444.3111377724554, 3465.8786942457145, 1365.7951307303886, 1373.491808031035, 1440.0855456784911, 1467.457040894865, 1477.837437881832, 14781.187034713086, 15074.321655525418, 16936.0, 17516.299348026063, 17849.643007139784, 4728.05438912219, 4744.59670927975, 4775.450823155382, 4874.8781280467565, 4882.121218180761, 13114.278714670785, 13143.934280328536, 13146.802797958038, 13147.868521314856, 13156.210627362389, 4884.218525035977, 5034.370703662043, 5685.090385538294, 5809.82570522885, 5813.94186058134, 5306.416294207634, 5341.919871201935, 5439.700816455133, 5439.945600544023, 5441.891162176735, 4026.0, 4026.9597304026584, 4086.9182616348103, 4098.200850834059, 4193.811278492475, 1024.2727663358949, 1031.6853359725355, 1036.6112707734599, 1036.652721338345, 1055.794120146564, 973.5813600151861, 1001.6594357918343, 1002.0780881588955, 1002.734275417015, 1020.7652239984845, 6956.633842065078, 7100.857982840314, 7170.2471240520035, 7336.2296958819625, 7430.888536671954, 1041.8541404203265, 1058.7300238439302, 1060.0865437884693, 1065.8347956066823, 1067.6530127708527, 4321.891952701208, 4420.690551661417, 4468.106378724268, 4521.61566266871, 4526.32105184225, 14693.706125877423, 15277.159756213516, 15294.388224470897, 15306.617334566725, 15313.081215127128, 14224.14655120696, 14679.339429725687, 15244.542663720105, 15261.847381526042, 15277.6180595486, 9021.188093071523, 10455.16358691303, 10659.893401066045, 10671.35971841692, 10723.088537474247, 6269.655168965751, 7345.824668053086, 7485.7754267372065, 7632.542047477169, 7634.923650763421, 12600.11799174049, 12600.936995314965, 12689.746205076031, 12750.298733569743, 12933.611991640266, 12983.091183617435, 12985.155964862253, 13013.609591712264, 13020.869791301966, 13033.15284506542, 9760.511974401394, 9859.457729824928, 9870.802583948425, 9974.251931105342, 9980.500974951368, 10820.188485863435, 10822.026017658449, 10831.24181307301, 10910.61812836547, 10934.179936504635, 9142.177204051668, 9230.123138301851, 9309.767255818388, 9377.249820014518, 9483.288753343664, 9018.0, 9093.954530227307, 9235.168834804632, 9452.291078169028, 9463.810723785347, 8420.200080992296, 8528.06191318942, 8601.096884827002, 8625.439346442461, 8633.179847914438, 5827.76688932438, 5870.855183239228, 5972.074328479092, 6026.306974697965, 6120.051392034064, 5969.820905372846, 5975.940240597538, 6061.090836374403, 6111.7249723763425, 6292.559520833498], "value": 5350.236013533096, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.9455314437334, 423.23183422088925, 438.6885311428885, 438.7955063351101, 447.63965008168424, 5124.2569827375055, 5249.632525723201, 5269.6311258211945, 5279.4984476474765, 5344.732763361836, 4944.283078953549, 5147.665401748884, 5169.198774190007, 5170.9224361634515, 5263.0, 4318.373835793824, 4319.546447623002, 4326.199653064194, 4343.174796788616, 4368.803403846819, 4359.215341238576, 4380.67144964129, 4389.385486031958, 4397.406350142722, 4403.251447253959, 4267.295896177127, 4299.8065087071, 4352.760598167115, 4366.934495982563, 4406.97796511017, 4266.3813747006825, 4277.679174061942, 4277.850275240383, 4284.400183974246, 4339.435873336478, 4036.2331157080353, 4038.454808600831, 4047.959520404788, 4052.0, 4061.1065565575527, 4998.80052776777, 5024.397072351305, 5069.543741063322, 5089.371401151633, 5092.745362731848, 5081.822136225252, 5094.694318340874, 5126.820561280375, 5151.150060240094, 5163.922541161885, 5415.862668839549, 5441.755121019563, 5453.209284653703, 5460.034387620453, 5473.496961944359, 4396.934045989313, 4534.863954081383, 4576.977115114403, 4600.677952543323, 4660.347551342781, 5424.674519528801, 5560.582956278255, 5581.6371935824045, 5679.699721612754, 5734.856628584277, 4706.505816889177, 4709.270063140219, 4718.740469274154, 4723.244280915037, 4784.784684689197, 4633.4208223972, 4758.738269395148, 4874.171390863562, 4877.268409738567, 4877.487863774323, 5014.674046186986, 5021.623378246646, 5033.345665063518, 5075.847724568269, 5094.4141423736, 5521.591994041574, 5528.695921724344, 5543.25166102571, 5554.333479982428, 5560.860023695132, 1574.5827355750735, 1637.6479057002803, 1658.3283770072899, 1660.4806601959313, 1663.8336166383508, 1262.7727009138443, 1262.8926541244098, 1279.705667696435, 1288.826008488842, 1293.36625053723, 5273.973630131824, 5275.57795376369, 5370.704611246419, 5376.327959005124, 5407.675539467645, 5515.227868098469, 5563.527100196528, 5609.326880774252, 5644.955683198621, 5649.802256920988, 5124.30821839047, 5421.1055175895535, 5430.185472179126, 5475.687209238433, 5485.670859748428, 2970.0792754246254, 2980.9254768630526, 2994.8352840593975, 3003.588313492655, 3010.819350838957, 5577.721113944286, 5784.913226301609, 5831.591788574844, 5900.527957763455, 5910.940890591039, 5706.686132262683, 5706.942930570641, 5710.371859095496, 5746.166805813134, 5752.482276595043, 5841.757824407495, 5865.706714664249, 5871.74478841443, 5875.206847075676, 5919.792807251812, 5443.755031023535, 5669.137085771824, 5698.088305871041, 5732.942670573241, 5759.939242833876, 5401.756920938488, 5458.536024437967, 5649.350324712711, 5656.208130861601, 5693.430656934273, 5712.571557133148, 5867.647941123546, 5870.412958704095, 5892.616979896465, 5908.763649453974, 4677.082396217535, 4726.338312636165, 4729.527047295243, 4730.787114579921, 4743.075100355398, 5442.8500742205815, 5587.664740115606, 5647.656222667547, 5673.631213971079, 5730.455606717355, 5367.758450869642, 5525.4474552544425, 5566.359868615161, 5599.692016938944, 5649.435056494318, 5293.173855020166, 5447.400785913467, 5483.711327838035, 5557.610967232416, 5618.494335509824, 5370.650907690988, 5432.511074003359, 5486.506214440719, 5500.5874559409, 5521.475459831309, 5533.6403133796175, 5541.647303381557, 5587.53572784876, 5595.328560572757, 5608.653293697254, 5329.280547126088, 5434.402215756185, 5510.393856675682, 5535.750891209986, 5547.944520554743], "value": 4430.493149758278, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [852.1393392673398, 852.3905407633541, 857.3612658569367, 893.2764460786763, 921.1479381572044, 16024.759628605565, 16681.582960425996, 16718.24767885445, 16721.91639041803, 16847.915760421198, 16462.259198336073, 16602.169891505408, 16855.747163792552, 17545.91227043866, 17590.560235994108, 838.1032295443878, 852.2031900173342, 863.87905693203, 878.2183856367838, 881.0220655072864, 872.3457406944791, 876.7150676030284, 876.842168409686, 878.3851304087165, 879.5118709116438, 912.9041450647653, 913.0321858829628, 914.1589737441569, 914.4147745442922, 915.2997956563235, 906.0395980260945, 910.3127139010038, 910.4628269321099, 910.7449914024083, 911.4850109688005, 669.357416879796, 672.0993868216614, 672.7006482115412, 672.7342699633666, 673.2997682410287, 17156.0, 17282.740758888627, 17300.134993250413, 18053.09734513282, 18243.817561824344, 15264.847351526565, 15514.224288785628, 15730.921345393313, 15795.684086318324, 15873.285702143432, 12868.485260589565, 13209.40557674907, 13272.402741876638, 13402.0, 13520.459181632723, 3910.824012919397, 4037.0916543777707, 4061.2486689962157, 4110.301248787713, 4175.478065241845, 85372.54395293116, 85427.57286213592, 86217.0, 88565.0, 90227.09772902253, 11939.88060119405, 12006.51973921051, 12072.215306005082, 12117.39413029345, 12440.564580239829, 21303.68044479334, 21881.67177492339, 23331.0, 23627.881860590587, 23837.404064898168, 8521.147885211429, 8778.078301778349, 9013.459192448474, 9088.227500662386, 9094.454332740057, 5008.424031236382, 5267.947320526746, 5307.681539107666, 5352.090144675414, 5355.625106242525, 3633.0, 3706.314331848617, 3738.700903927679, 3739.8878033659034, 3765.849366025383, 648.9643069631121, 653.4347789162435, 662.493192707579, 666.8332916770808, 674.5345711459078, 739.4195556488131, 749.9287567681062, 752.2402373602591, 771.2172145272552, 784.5920121536769, 9536.809263814685, 10213.234007449471, 10462.372257664412, 10752.354858708502, 11306.547738090385, 787.3189690917316, 799.1050023973187, 799.3285640062376, 813.1055838577518, 817.464560712734, 5146.382434107931, 5159.277701121846, 5176.1459359206065, 5249.553787927993, 5259.579233661298, 79540.60229698815, 80936.19063809405, 81148.18851811525, 82081.58959205166, 83249.0, 60101.69949150314, 61661.0, 71654.64172679103, 72623.0, 73146.0, 59414.70292648509, 63131.368686313464, 64903.350966490674, 65353.67323163354, 66846.0, 10886.346819190874, 11260.211785174959, 11508.53965841357, 11550.806403381099, 11699.181057325906, 41727.58272417237, 42218.78890605527, 42751.786241069836, 42826.78586607172, 43566.34650480182, 50161.498385015686, 50760.49239507705, 50859.0, 50888.745556271984, 50984.74507627438, 13439.059265851296, 13583.117097389033, 13691.794623080861, 13863.584092476838, 13994.230317332238, 16579.419720309732, 17056.82943170602, 17177.398791042255, 17209.56976075558, 17296.827031730027, 13460.326983650582, 13819.239941803296, 13868.682191385, 14190.574282771533, 14583.416663333348, 14495.855041449451, 14565.34455949506, 14610.488632897797, 14728.705425891209, 14935.17856517902, 14582.781258281346, 14590.635234119232, 14679.41282348694, 14986.625334366729, 15698.0, 10756.0, 10911.945440272748, 11140.554377824796, 11140.777184456427, 11951.521939122338, 11772.52909883595, 12276.693082673004, 12752.42614082387, 12943.741125177632, 12992.155509891827], "value": 7790.6261686087155, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1464.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 112.0, 112.0, 113.0, 113.0, 112.0, 113.0, 111.0, 112.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 117.0, 117.0, 159.0, 117.0, 116.0, 117.0, 115.0, 135.0, 118.0], "value": 124.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 166.0, 162.0, 162.0, 163.0, 162.0, 163.0, 165.0, 162.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 110.0, 76.0, 76.0, 79.0, 76.0, 89.0, 75.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 121.0, 83.0, 82.0, 83.0, 92.0, 82.0, 83.0, 99.0], "value": 88.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 113.0, 113.0, 112.0, 113.0, 112.0, 113.0, 113.0, 113.0], "value": 112.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 146.0, 147.0, 145.0, 147.0, 147.0, 146.0, 146.0, 147.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 53.0, 54.0, 54.0, 54.0, 55.0, 60.0, 54.0, 54.0, 53.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 60.0, 54.0, 53.0, 53.0, 53.0, 55.0, 52.0, 54.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 80.0, 77.0, 77.0, 77.0, 79.0, 81.0, 79.0, 80.0, 80.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 135.0, 120.0, 122.0, 120.0, 130.0, 121.0, 124.0, 165.0, 118.0], "value": 127.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 161.0, 175.0, 163.0, 165.0, 166.0, 161.0, 158.0, 166.0], "value": 164.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 61.0, 59.0, 59.0, 64.0, 60.0, 59.0, 60.0, 58.0, 64.0], "value": 61.0, "unit": "score"}]}]} 09:08:17 INFO - Return code: 0 09:08:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:08:17 ERROR - # TBPL SUCCESS # 09:08:17 INFO - Running post-action listener: _resource_record_post_action 09:08:17 INFO - [mozharness: 2017-04-13 16:08:17.689698Z] Finished run-tests step (success) 09:08:17 INFO - Running post-run listener: _resource_record_post_run 09:08:17 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 266240; Write bytes: 365965312; Read time: 40; Write time: 231840 09:08:17 INFO - TinderboxPrint: CPU usage
13.2% 09:08:17 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 40 09:08:17 INFO - TinderboxPrint: I/O write bytes / time
365,965,312 / 231,840 09:08:17 INFO - TinderboxPrint: CPU idle
8,261.2 (86.8%) 09:08:17 INFO - TinderboxPrint: CPU user
1,205.0 (12.7%) 09:08:17 INFO - TinderboxPrint: Swap in / out
0 / 0 09:08:17 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110968832; Read time: 0; Write time: 78148 09:08:17 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 266240; Write bytes: 254996480; Read time: 40; Write time: 153692 09:08:18 INFO - Running post-run listener: _upload_blobber_files 09:08:18 INFO - Blob upload gear active. 09:08:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:08:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:08:18 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'] 09:08:18 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 09:08:18 INFO - (blobuploader) - INFO - Open directory for files ... 09:08:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:08:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:08:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:08:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:08:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:08:19 INFO - (blobuploader) - INFO - Done attempting. 09:08:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:08:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:08:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:08:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:08:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:08:20 INFO - (blobuploader) - INFO - Done attempting. 09:08:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:08:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:08:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:08:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:08:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:08:21 INFO - (blobuploader) - INFO - Done attempting. 09:08:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:08:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:08:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:08:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:08:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:08:24 INFO - (blobuploader) - INFO - Done attempting. 09:08:24 INFO - (blobuploader) - INFO - Iteration through files over. 09:08:24 INFO - Return code: 0 09:08:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:08:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:08:24 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2ada2c291a4fd035c5a4214c3b23d7ed7d3258e7e255514afd50a93a03004737a87203c79ddfa5e6dc3855c4bea3317cd495be3fc9f74e6597c57c6fcc87374b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efe7b21eb296dd1647f44c9a833d278775af6e90ca988e81cde9e9d463e169ff09408330932fbf46f8b988277b9a594bf8b72196e0bf0769ad8497bc2dbb4a97", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a20188d776eb7313f781cbc6d1cf0a89b8c5c1b2f551511ba729ff77a5e7f6dd32eb3353fd02ac0bc024c2e0998e7bd0ceb13101517410e2f50d858a385ab987", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ea04b8391332dd56643095f61ce8511b82310a8288878d7d91515c9ba2917a82a38e0f8966f5eb834b3f42de652aa28e9bb3a09a4e9b777c8d65f5f13d98dda5"} 09:08:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:08:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:08:24 INFO - Contents: 09:08:24 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2ada2c291a4fd035c5a4214c3b23d7ed7d3258e7e255514afd50a93a03004737a87203c79ddfa5e6dc3855c4bea3317cd495be3fc9f74e6597c57c6fcc87374b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efe7b21eb296dd1647f44c9a833d278775af6e90ca988e81cde9e9d463e169ff09408330932fbf46f8b988277b9a594bf8b72196e0bf0769ad8497bc2dbb4a97", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a20188d776eb7313f781cbc6d1cf0a89b8c5c1b2f551511ba729ff77a5e7f6dd32eb3353fd02ac0bc024c2e0998e7bd0ceb13101517410e2f50d858a385ab987", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ea04b8391332dd56643095f61ce8511b82310a8288878d7d91515c9ba2917a82a38e0f8966f5eb834b3f42de652aa28e9bb3a09a4e9b777c8d65f5f13d98dda5"} 09:08:24 INFO - Running post-run listener: copy_logs_to_upload_dir 09:08:24 INFO - Copying logs to upload dir... 09:08:24 INFO - mkdir: /builds/slave/test/build/upload/logs 09:08:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.983275 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-04-13 09:08:24.935618) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 09:08:24.938867) ========= 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=0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2ada2c291a4fd035c5a4214c3b23d7ed7d3258e7e255514afd50a93a03004737a87203c79ddfa5e6dc3855c4bea3317cd495be3fc9f74e6597c57c6fcc87374b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efe7b21eb296dd1647f44c9a833d278775af6e90ca988e81cde9e9d463e169ff09408330932fbf46f8b988277b9a594bf8b72196e0bf0769ad8497bc2dbb4a97", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a20188d776eb7313f781cbc6d1cf0a89b8c5c1b2f551511ba729ff77a5e7f6dd32eb3353fd02ac0bc024c2e0998e7bd0ceb13101517410e2f50d858a385ab987", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ea04b8391332dd56643095f61ce8511b82310a8288878d7d91515c9ba2917a82a38e0f8966f5eb834b3f42de652aa28e9bb3a09a4e9b777c8d65f5f13d98dda5"} build_url:https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011144 build_url: 'https://queue.taskcluster.net/v1/task/Bs2ESpugQM-xNVlnGLjnGw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2ada2c291a4fd035c5a4214c3b23d7ed7d3258e7e255514afd50a93a03004737a87203c79ddfa5e6dc3855c4bea3317cd495be3fc9f74e6597c57c6fcc87374b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efe7b21eb296dd1647f44c9a833d278775af6e90ca988e81cde9e9d463e169ff09408330932fbf46f8b988277b9a594bf8b72196e0bf0769ad8497bc2dbb4a97", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a20188d776eb7313f781cbc6d1cf0a89b8c5c1b2f551511ba729ff77a5e7f6dd32eb3353fd02ac0bc024c2e0998e7bd0ceb13101517410e2f50d858a385ab987", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ea04b8391332dd56643095f61ce8511b82310a8288878d7d91515c9ba2917a82a38e0f8966f5eb834b3f42de652aa28e9bb3a09a4e9b777c8d65f5f13d98dda5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 09:08:24.967009) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 09:08:24.967786) ========= 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=0e2a256762fed810e2f0f810000001b3-1492098443.725948-1557222368 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004370 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 09:08:25.053139) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 09:08:25.053905) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 09:08:25.054646) ========= ========= Total master_lag: 0.18 =========