builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-089 starttime: 1491473071.78 results: success (0) revision: 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 03:04:31.781003) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 03:04:31.781454) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 03:04:31.781740) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 03:04:31.796857) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:04:31.797264) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-06 03:04:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.2M=0s 2017-04-06 03:04:32 (46.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.087846 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:04:31.899067) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:04:31.899377) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022804 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:04:31.948460) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 03:04:31.948799) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 --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 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 --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=8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-06 03:04:32,250 truncating revision to first 12 chars 2017-04-06 03:04:32,250 Setting DEBUG logging. 2017-04-06 03:04:32,250 attempt 1/10 2017-04-06 03:04:32,250 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/632a3c9e2b4b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-06 03:04:32,946 unpacking tar archive at: mozilla-aurora-632a3c9e2b4b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.035112 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 03:04:32.997347) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 03:04:32.997715) ========= script_repo_revision: 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 03:04:32.998101) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 03:04:32.998365) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 03:04:33.008003) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-04-06 03:04:33.008397) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora-Non-PGO'] 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=8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:04:33 INFO - MultiFileLogger online at 20170406 03:04:33 in /builds/slave/test 03:04:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 03:04:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:04:33 INFO - {'append_to_log': False, 03:04:33 INFO - 'base_work_dir': '/builds/slave/test', 03:04:33 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 03:04:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:04:33 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 03:04:33 INFO - 'buildbot_json_path': 'buildprops.json', 03:04:33 INFO - 'config_files': ('talos/linux_config.py',), 03:04:33 INFO - 'default_actions': ('clobber', 03:04:33 INFO - 'read-buildbot-config', 03:04:33 INFO - 'download-and-extract', 03:04:33 INFO - 'populate-webroot', 03:04:33 INFO - 'create-virtualenv', 03:04:33 INFO - 'install', 03:04:33 INFO - 'run-tests'), 03:04:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:04:33 INFO - 'download_minidump_stackwalk': True, 03:04:33 INFO - 'download_symbols': 'ondemand', 03:04:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:04:33 INFO - 'tooltool.py': '/tools/tooltool.py', 03:04:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:04:33 INFO - '/tools/misc-python/virtualenv.py')}, 03:04:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:04:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:04:33 INFO - 'gecko_profile': False, 03:04:33 INFO - 'gecko_profile_interval': 0, 03:04:33 INFO - 'installer_path': 'installer.exe', 03:04:33 INFO - 'log_level': 'info', 03:04:33 INFO - 'log_name': 'talos', 03:04:33 INFO - 'log_to_console': True, 03:04:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:04:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:04:33 INFO - 'opt_config_files': (), 03:04:33 INFO - 'pip_index': False, 03:04:33 INFO - 'suite': 'dromaeojs', 03:04:33 INFO - 'system_bits': '32', 03:04:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:04:33 INFO - 'title': 'talos-linux64-ix-089', 03:04:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:04:33 INFO - 'use_talos_json': True, 03:04:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:04:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:04:33 INFO - 'work_dir': 'build'} 03:04:33 INFO - [mozharness: 2017-04-06 10:04:33.408290Z] Running clobber step. 03:04:33 INFO - Running pre-action listener: _resource_record_pre_action 03:04:33 INFO - Running main action method: clobber 03:04:33 INFO - rmtree: /builds/slave/test/build 03:04:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:04:34 INFO - Running post-action listener: _resource_record_post_action 03:04:34 INFO - [mozharness: 2017-04-06 10:04:34.345440Z] Finished clobber step (success) 03:04:34 INFO - [mozharness: 2017-04-06 10:04:34.345538Z] Running read-buildbot-config step. 03:04:34 INFO - Running pre-action listener: _resource_record_pre_action 03:04:34 INFO - Running main action method: read_buildbot_config 03:04:34 INFO - Using buildbot properties: 03:04:34 INFO - { 03:04:34 INFO - "project": "", 03:04:34 INFO - "product": "firefox", 03:04:34 INFO - "who": "jlorenzo@mozilla.com", 03:04:34 INFO - "installer_path": "public/build/target.tar.bz2", 03:04:34 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:04:34 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 03:04:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 03:04:34 INFO - "stage_platform": "linux64", 03:04:34 INFO - "basedir": "/builds/slave/test", 03:04:34 INFO - "buildnumber": 119, 03:04:34 INFO - "platform": "ubuntu64_hw", 03:04:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:04:34 INFO - "slavebuilddir": "test", 03:04:34 INFO - "taskId": "feM_-wLATrCCQ90ZwiKWAw", 03:04:34 INFO - "branch": "mozilla-aurora", 03:04:34 INFO - "script_repo_revision": "production", 03:04:34 INFO - "revision": "632a3c9e2b4b0dda89ca5924c616568af8c0dd85", 03:04:34 INFO - "slavename": "talos-linux64-ix-089", 03:04:34 INFO - "repo_path": "releases/mozilla-aurora" 03:04:34 INFO - } 03:04:34 INFO - Finding installer, test and symbols from parent task. 03:04:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/feM_-wLATrCCQ90ZwiKWAw'}, attempt #1 03:04:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/feM_-wLATrCCQ90ZwiKWAw'}, attempt #1 03:04:35 INFO - Task dependencies: AiYYT0HmT5Ck8k9WedpW3Q 03:04:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q'}, attempt #1 03:04:35 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.tar.bz2 03:04:35 INFO - Set test_url: https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/test_packages.json 03:04:35 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.crashreporter-symbols.zip 03:04:35 INFO - Running post-action listener: _resource_record_post_action 03:04:35 INFO - [mozharness: 2017-04-06 10:04:35.883246Z] Finished read-buildbot-config step (success) 03:04:35 INFO - [mozharness: 2017-04-06 10:04:35.883467Z] Running download-and-extract step. 03:04:35 INFO - Running pre-action listener: _resource_record_pre_action 03:04:35 INFO - Running main action method: download_and_extract 03:04:35 INFO - mkdir: /builds/slave/test/build/tests 03:04:35 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')]} 03:04:35 INFO - trying https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.test_packages.json 03:04:35 INFO - Downloading https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:04:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:04:36 INFO - Downloaded 896 bytes. 03:04:36 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:04:36 INFO - Using the following test package requirements: 03:04:36 INFO - {u'common': [u'target.common.tests.zip'], 03:04:36 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:04:36 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:04:36 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:04:36 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:04:36 INFO - u'mozbase': [u'target.common.tests.zip'], 03:04:36 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:04:36 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:04:36 INFO - u'web-platform': [u'target.common.tests.zip', 03:04:36 INFO - u'target.web-platform.tests.zip'], 03:04:36 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:04:36 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:04:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.common.tests.zip 03:04:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:04:36 INFO - Fetch https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.common.tests.zip into memory 03:04:37 INFO - Expected file size: 59540410 03:04:37 INFO - Obtained file size: 59540410 03:04:47 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:04:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.common.tests.zip 03:04:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:04:47 INFO - Fetch https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.common.tests.zip into memory 03:04:48 INFO - Expected file size: 59540410 03:04:48 INFO - Obtained file size: 59540410 03:04:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.talos.tests.zip 03:04:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:04:58 INFO - Fetch https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.talos.tests.zip into memory 03:04:59 INFO - Expected file size: 14404438 03:04:59 INFO - Obtained file size: 14404438 03:04: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')]} 03:04:59 INFO - trying https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.tar.bz2 03:04:59 INFO - Downloading https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:04:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:05:00 INFO - Downloaded 66769191 bytes. 03:05:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.tar.bz2 03:05:00 INFO - mkdir: /builds/slave/test/properties 03:05:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:05:00 INFO - Writing to file /builds/slave/test/properties/build_url 03:05:00 INFO - Contents: 03:05:00 INFO - build_url:https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.tar.bz2 03:05:00 INFO - Running post-action listener: _resource_record_post_action 03:05:00 INFO - Running post-action listener: set_extra_try_arguments 03:05:00 INFO - [mozharness: 2017-04-06 10:05:00.861221Z] Finished download-and-extract step (success) 03:05:00 INFO - [mozharness: 2017-04-06 10:05:00.861317Z] Running populate-webroot step. 03:05:00 INFO - Running pre-action listener: _resource_record_pre_action 03:05:00 INFO - Running main action method: populate_webroot 03:05:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:05:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:05:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:05:00 INFO - '--tppagecycles', 03:05:00 INFO - '7'], 03:05:00 INFO - 'tests': ['tsvgm']}}, 03:05:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:05:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:05:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:05:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:05:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:05:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:05:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:05:00 INFO - 'talos_options': ['--disable-e10s'], 03:05:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:05:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:05:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:05:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:05:00 INFO - 'talos_options': ['--disable-e10s'], 03:05:00 INFO - 'tests': ['damp', 'tps']}, 03:05:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:05:00 INFO - 'tests': ['damp', 'tps']}, 03:05:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:05:00 INFO - 'tests': ['dromaeo_dom']}, 03:05:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:05:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:05:00 INFO - 'tests': ['basic_compositor_video']}, 03:05:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:05:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:05:00 INFO - 'tests': ['a11yr', 03:05:00 INFO - 'ts_paint', 03:05:00 INFO - 'tpaint', 03:05:00 INFO - 'sessionrestore', 03:05:00 INFO - 'sessionrestore_no_auto_restore', 03:05:00 INFO - 'tabpaint']}, 03:05:00 INFO - 'other-e10s': {'tests': ['a11yr', 03:05:00 INFO - 'ts_paint', 03:05:00 INFO - 'tpaint', 03:05:00 INFO - 'sessionrestore', 03:05:00 INFO - 'sessionrestore_no_auto_restore', 03:05:00 INFO - 'tabpaint']}, 03:05:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:05:00 INFO - 'ts_paint', 03:05:00 INFO - 'tpaint', 03:05:00 INFO - 'sessionrestore', 03:05:00 INFO - 'sessionrestore_no_auto_restore', 03:05:00 INFO - 'tabpaint']}, 03:05:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:05:00 INFO - 'ts_paint', 03:05:00 INFO - 'tpaint', 03:05:00 INFO - 'sessionrestore', 03:05:00 INFO - 'sessionrestore_no_auto_restore', 03:05:00 INFO - 'tabpaint']}, 03:05:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:05:00 INFO - 'tests': ['a11yr', 03:05:00 INFO - 'ts_paint', 03:05:00 INFO - 'tpaint', 03:05:00 INFO - 'sessionrestore', 03:05:00 INFO - 'sessionrestore_no_auto_restore', 03:05:00 INFO - 'tabpaint']}, 03:05:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:05:00 INFO - 'tests': ['a11yr', 03:05:00 INFO - 'ts_paint', 03:05:00 INFO - 'tpaint', 03:05:00 INFO - 'sessionrestore', 03:05:00 INFO - 'sessionrestore_no_auto_restore', 03:05:00 INFO - 'tabpaint']}, 03:05:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:05:00 INFO - 'tests': ['tsvgx', 03:05:00 INFO - 'tsvgr_opacity', 03:05:00 INFO - 'tart', 03:05:00 INFO - 'tscrollx', 03:05:00 INFO - 'cart', 03:05:00 INFO - 'tsvg_static']}, 03:05:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:05:00 INFO - 'tsvgr_opacity', 03:05:00 INFO - 'tart', 03:05:00 INFO - 'tscrollx', 03:05:00 INFO - 'cart', 03:05:00 INFO - 'tsvg_static']}, 03:05:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:05:00 INFO - 'talos_options': ['--disable-e10s'], 03:05:00 INFO - 'tests': ['tp5o']}, 03:05:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:05:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:05:00 INFO - 'talos_options': ['--disable-e10s', 03:05:00 INFO - '--xperf_path', 03:05:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:05:00 INFO - 'tests': ['tp5n']}, 03:05:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:05:00 INFO - 'talos_options': ['--xperf_path', 03:05:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:05:00 INFO - 'tests': ['tp5n']}}, 03:05:00 INFO - 'talos.zip': {'path': '', 03:05:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:05:00 INFO - Running post-action listener: _resource_record_post_action 03:05:00 INFO - [mozharness: 2017-04-06 10:05:00.874358Z] Finished populate-webroot step (success) 03:05:00 INFO - [mozharness: 2017-04-06 10:05:00.874448Z] Running create-virtualenv step. 03:05:00 INFO - Running pre-action listener: _resource_record_pre_action 03:05:00 INFO - Running main action method: create_virtualenv 03:05:00 INFO - Creating virtualenv /builds/slave/test/build/venv 03:05: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 03:05:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:05:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:05:00 INFO - Using real prefix '/usr' 03:05:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:05:01 INFO - Installing distribute.............................................................................................................................................................................................done. 03:05:03 INFO - Installing pip.................done. 03:05:03 INFO - Return code: 0 03:05:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:05:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:05:03 INFO - Reading from file tmpfile_stdout 03:05:03 INFO - Output received: 03:05: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) 03:05:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:05: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')]} 03:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05: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')]} 03:05:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05: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 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', '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 03:05: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 03:05: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 03:05:03 INFO - Using env: {'DISPLAY': ':0', 03:05:03 INFO - 'HOME': '/home/cltbld', 03:05:03 INFO - 'LANG': 'en_US.UTF-8', 03:05:03 INFO - 'LANGUAGE': 'en_US:en', 03:05:03 INFO - 'LOGNAME': 'cltbld', 03:05:03 INFO - 'MAIL': '/var/mail/cltbld', 03:05:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:03 INFO - 'MOZ_NO_REMOTE': '1', 03:05:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:03 INFO - 'NO_EM_RESTART': '1', 03:05:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:03 INFO - 'PWD': '/builds/slave/test', 03:05:03 INFO - 'SHELL': '/bin/bash', 03:05:03 INFO - 'SHLVL': '1', 03:05:03 INFO - 'TERM': 'linux', 03:05:03 INFO - 'TMOUT': '86400', 03:05:03 INFO - 'USER': 'cltbld', 03:05:03 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', 03:05:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:03 INFO - '_': '/tools/buildbot/bin/python'} 03:05:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05: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 03:05:03 INFO - Cleaning up... 03:05:03 INFO - Return code: 0 03:05:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:05: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')]} 03:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05: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')]} 03:05:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05: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 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', '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 03:05: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 03:05: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 03:05:03 INFO - Using env: {'DISPLAY': ':0', 03:05:03 INFO - 'HOME': '/home/cltbld', 03:05:03 INFO - 'LANG': 'en_US.UTF-8', 03:05:03 INFO - 'LANGUAGE': 'en_US:en', 03:05:03 INFO - 'LOGNAME': 'cltbld', 03:05:03 INFO - 'MAIL': '/var/mail/cltbld', 03:05:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:03 INFO - 'MOZ_NO_REMOTE': '1', 03:05:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:03 INFO - 'NO_EM_RESTART': '1', 03:05:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:03 INFO - 'PWD': '/builds/slave/test', 03:05:03 INFO - 'SHELL': '/bin/bash', 03:05:03 INFO - 'SHLVL': '1', 03:05:03 INFO - 'TERM': 'linux', 03:05:03 INFO - 'TMOUT': '86400', 03:05:03 INFO - 'USER': 'cltbld', 03:05:03 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', 03:05:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:03 INFO - '_': '/tools/buildbot/bin/python'} 03:05:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:04 INFO - Downloading/unpacking psutil>=3.1.1 03:05:04 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 03:05:04 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 03:05:04 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 03:05:04 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 03:05:04 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 03:05:04 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 03:05:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:05:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:05:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:05:06 INFO - Installing collected packages: psutil 03:05:06 INFO - Running setup.py install for psutil 03:05:06 INFO - building 'psutil._psutil_linux' extension 03:05:06 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 03:05: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 03:05:06 INFO - building 'psutil._psutil_posix' extension 03:05: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 03:05:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:05:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:05:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:05:07 INFO - Successfully installed psutil 03:05:07 INFO - Cleaning up... 03:05:07 INFO - Return code: 0 03:05:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:05:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', '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 03:05:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:05:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:05:07 INFO - Using env: {'DISPLAY': ':0', 03:05:07 INFO - 'HOME': '/home/cltbld', 03:05:07 INFO - 'LANG': 'en_US.UTF-8', 03:05:07 INFO - 'LANGUAGE': 'en_US:en', 03:05:07 INFO - 'LOGNAME': 'cltbld', 03:05:07 INFO - 'MAIL': '/var/mail/cltbld', 03:05:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:07 INFO - 'MOZ_NO_REMOTE': '1', 03:05:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:07 INFO - 'NO_EM_RESTART': '1', 03:05:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:07 INFO - 'PWD': '/builds/slave/test', 03:05:07 INFO - 'SHELL': '/bin/bash', 03:05:07 INFO - 'SHLVL': '1', 03:05:07 INFO - 'TERM': 'linux', 03:05:07 INFO - 'TMOUT': '86400', 03:05:07 INFO - 'USER': 'cltbld', 03:05:07 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', 03:05:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:07 INFO - '_': '/tools/buildbot/bin/python'} 03:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:05:07 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 03:05:07 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 03:05:07 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 03:05:07 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 03:05:07 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 03:05:07 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 03:05:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:05:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:05:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:05:09 INFO - Installing collected packages: mozsystemmonitor 03:05:09 INFO - Running setup.py install for mozsystemmonitor 03:05:09 INFO - Successfully installed mozsystemmonitor 03:05:09 INFO - Cleaning up... 03:05:09 INFO - Return code: 0 03:05:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:05:09 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')]} 03:05:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:09 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')]} 03:05:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:09 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 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', '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 03:05:09 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 03:05:09 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 03:05:09 INFO - Using env: {'DISPLAY': ':0', 03:05:09 INFO - 'HOME': '/home/cltbld', 03:05:09 INFO - 'LANG': 'en_US.UTF-8', 03:05:09 INFO - 'LANGUAGE': 'en_US:en', 03:05:09 INFO - 'LOGNAME': 'cltbld', 03:05:09 INFO - 'MAIL': '/var/mail/cltbld', 03:05:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:09 INFO - 'MOZ_NO_REMOTE': '1', 03:05:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:09 INFO - 'NO_EM_RESTART': '1', 03:05:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:09 INFO - 'PWD': '/builds/slave/test', 03:05:09 INFO - 'SHELL': '/bin/bash', 03:05:09 INFO - 'SHLVL': '1', 03:05:09 INFO - 'TERM': 'linux', 03:05:09 INFO - 'TMOUT': '86400', 03:05:09 INFO - 'USER': 'cltbld', 03:05:09 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', 03:05:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:09 INFO - '_': '/tools/buildbot/bin/python'} 03:05:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:10 INFO - Downloading/unpacking jsonschema==2.5.1 03:05:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:05:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:05: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 03:05: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 03:05: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 03:05: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 03:05: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 03:05: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 03:05:12 INFO - Downloading functools32-3.2.3-2.tar.gz 03:05:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:05:12 INFO - warning: no files found matching '*.txt' 03:05:12 INFO - no previously-included directories found matching 'build' 03:05:12 INFO - no previously-included directories found matching 'dist' 03:05:12 INFO - no previously-included directories found matching '.git*' 03:05:12 INFO - Installing collected packages: jsonschema, functools32 03:05:12 INFO - Running setup.py install for functools32 03:05:12 INFO - warning: no files found matching '*.txt' 03:05:12 INFO - no previously-included directories found matching 'build' 03:05:12 INFO - no previously-included directories found matching 'dist' 03:05:12 INFO - no previously-included directories found matching '.git*' 03:05:12 INFO - Successfully installed jsonschema functools32 03:05:12 INFO - Cleaning up... 03:05:12 INFO - Return code: 0 03:05:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:05:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', '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 03:05:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:05:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:05:12 INFO - Using env: {'DISPLAY': ':0', 03:05:12 INFO - 'HOME': '/home/cltbld', 03:05:12 INFO - 'LANG': 'en_US.UTF-8', 03:05:12 INFO - 'LANGUAGE': 'en_US:en', 03:05:12 INFO - 'LOGNAME': 'cltbld', 03:05:12 INFO - 'MAIL': '/var/mail/cltbld', 03:05:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:12 INFO - 'MOZ_NO_REMOTE': '1', 03:05:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:12 INFO - 'NO_EM_RESTART': '1', 03:05:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:12 INFO - 'PWD': '/builds/slave/test', 03:05:12 INFO - 'SHELL': '/bin/bash', 03:05:12 INFO - 'SHLVL': '1', 03:05:12 INFO - 'TERM': 'linux', 03:05:12 INFO - 'TMOUT': '86400', 03:05:12 INFO - 'USER': 'cltbld', 03:05:12 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', 03:05:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:12 INFO - '_': '/tools/buildbot/bin/python'} 03:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:05:12 INFO - Cleaning up... 03:05:12 INFO - Return code: 0 03:05:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:05:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', '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 03:05:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:05:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:05:12 INFO - Using env: {'DISPLAY': ':0', 03:05:12 INFO - 'HOME': '/home/cltbld', 03:05:12 INFO - 'LANG': 'en_US.UTF-8', 03:05:12 INFO - 'LANGUAGE': 'en_US:en', 03:05:12 INFO - 'LOGNAME': 'cltbld', 03:05:12 INFO - 'MAIL': '/var/mail/cltbld', 03:05:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:12 INFO - 'MOZ_NO_REMOTE': '1', 03:05:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:12 INFO - 'NO_EM_RESTART': '1', 03:05:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:12 INFO - 'PWD': '/builds/slave/test', 03:05:12 INFO - 'SHELL': '/bin/bash', 03:05:12 INFO - 'SHLVL': '1', 03:05:12 INFO - 'TERM': 'linux', 03:05:12 INFO - 'TMOUT': '86400', 03:05:12 INFO - 'USER': 'cltbld', 03:05:12 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', 03:05:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:12 INFO - '_': '/tools/buildbot/bin/python'} 03:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:12 INFO - Downloading/unpacking blobuploader==1.2.4 03:05: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 03:05: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 03:05: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 03:05: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 03:05: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 03:05: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 03:05:14 INFO - Downloading blobuploader-1.2.4.tar.gz 03:05:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:05:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:05:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:15 INFO - Downloading docopt-0.6.1.tar.gz 03:05:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:05:15 INFO - Installing collected packages: blobuploader, requests, docopt 03:05:15 INFO - Running setup.py install for blobuploader 03:05:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:05:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:05:15 INFO - Running setup.py install for requests 03:05:16 INFO - Running setup.py install for docopt 03:05:16 INFO - Successfully installed blobuploader requests docopt 03:05:16 INFO - Cleaning up... 03:05:16 INFO - Return code: 0 03:05:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', '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 03:05:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:05:16 INFO - Using env: {'DISPLAY': ':0', 03:05:16 INFO - 'HOME': '/home/cltbld', 03:05:16 INFO - 'LANG': 'en_US.UTF-8', 03:05:16 INFO - 'LANGUAGE': 'en_US:en', 03:05:16 INFO - 'LOGNAME': 'cltbld', 03:05:16 INFO - 'MAIL': '/var/mail/cltbld', 03:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:16 INFO - 'MOZ_NO_REMOTE': '1', 03:05:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:16 INFO - 'NO_EM_RESTART': '1', 03:05:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:16 INFO - 'PWD': '/builds/slave/test', 03:05:16 INFO - 'SHELL': '/bin/bash', 03:05:16 INFO - 'SHLVL': '1', 03:05:16 INFO - 'TERM': 'linux', 03:05:16 INFO - 'TMOUT': '86400', 03:05:16 INFO - 'USER': 'cltbld', 03:05:16 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', 03:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:16 INFO - '_': '/tools/buildbot/bin/python'} 03:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:05:16 INFO - Running setup.py (path:/tmp/pip-JzOJ6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:05:16 INFO - Running setup.py (path:/tmp/pip-dvhgHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:05:16 INFO - Running setup.py (path:/tmp/pip-ssbP4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:05:16 INFO - Running setup.py (path:/tmp/pip-nz5F_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:05:16 INFO - Running setup.py (path:/tmp/pip-EnUhGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:05:16 INFO - Running setup.py (path:/tmp/pip-7LXMqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:05:17 INFO - Running setup.py (path:/tmp/pip-iVYOKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:05:17 INFO - Running setup.py (path:/tmp/pip-rb0iiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:05:17 INFO - Running setup.py (path:/tmp/pip-s9n6xK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:05:17 INFO - Running setup.py (path:/tmp/pip-8At1M8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:05:17 INFO - Running setup.py (path:/tmp/pip-0MvgH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:05:17 INFO - Running setup.py (path:/tmp/pip-iE4yE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:05:17 INFO - Running setup.py (path:/tmp/pip-8V20Ws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:05:17 INFO - Running setup.py (path:/tmp/pip-bNSFJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:17 INFO - Running setup.py (path:/tmp/pip-WNL98E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:05:17 INFO - Running setup.py (path:/tmp/pip-rVlAbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:05:17 INFO - Running setup.py (path:/tmp/pip-nLZe38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:05:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:05:17 INFO - Running setup.py install for manifestparser 03:05:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:05:17 INFO - Running setup.py install for mozcrash 03:05:18 INFO - Running setup.py install for mozdebug 03:05:18 INFO - Running setup.py install for mozdevice 03:05:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:05:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:05:18 INFO - Running setup.py install for mozfile 03:05:18 INFO - Running setup.py install for mozhttpd 03:05:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:05:18 INFO - Running setup.py install for mozinfo 03:05:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:05:18 INFO - Running setup.py install for mozInstall 03:05:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:05:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:05:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:05:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:05:18 INFO - Running setup.py install for mozleak 03:05:18 INFO - Running setup.py install for mozlog 03:05:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:05:18 INFO - Running setup.py install for moznetwork 03:05:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:05:18 INFO - Running setup.py install for mozprocess 03:05:19 INFO - Running setup.py install for mozprofile 03:05:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:05:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:05:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:05:19 INFO - Running setup.py install for mozrunner 03:05:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:05:19 INFO - Running setup.py install for mozscreenshot 03:05:19 INFO - Running setup.py install for moztest 03:05:19 INFO - Running setup.py install for mozversion 03:05:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:05:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:05:19 INFO - Cleaning up... 03:05:19 INFO - Return code: 0 03:05:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05: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 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', '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 03:05: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 03:05: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 03:05:19 INFO - Using env: {'DISPLAY': ':0', 03:05:19 INFO - 'HOME': '/home/cltbld', 03:05:19 INFO - 'LANG': 'en_US.UTF-8', 03:05:19 INFO - 'LANGUAGE': 'en_US:en', 03:05:19 INFO - 'LOGNAME': 'cltbld', 03:05:19 INFO - 'MAIL': '/var/mail/cltbld', 03:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:19 INFO - 'MOZ_NO_REMOTE': '1', 03:05:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:19 INFO - 'NO_EM_RESTART': '1', 03:05:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:19 INFO - 'PWD': '/builds/slave/test', 03:05:19 INFO - 'SHELL': '/bin/bash', 03:05:19 INFO - 'SHLVL': '1', 03:05:19 INFO - 'TERM': 'linux', 03:05:19 INFO - 'TMOUT': '86400', 03:05:19 INFO - 'USER': 'cltbld', 03:05:19 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', 03:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:19 INFO - '_': '/tools/buildbot/bin/python'} 03:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:05:19 INFO - Running setup.py (path:/tmp/pip-jd5aOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:05: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)) 03:05:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:05:19 INFO - Running setup.py (path:/tmp/pip-H0Z65k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:05:20 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)) 03:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:05:20 INFO - Running setup.py (path:/tmp/pip-iILILk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:05:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:05:20 INFO - Running setup.py (path:/tmp/pip-fs0dpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:05:20 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)) 03:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:05:20 INFO - Running setup.py (path:/tmp/pip-olyuZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:05:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:05:20 INFO - Running setup.py (path:/tmp/pip-wVquHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:05:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:05:20 INFO - Running setup.py (path:/tmp/pip-1_heJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:05:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:05:20 INFO - Running setup.py (path:/tmp/pip-tgSwGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:05:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:05:20 INFO - Running setup.py (path:/tmp/pip-3RYe9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:05:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:05:20 INFO - Running setup.py (path:/tmp/pip-_5yLSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:05: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)) 03:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:05:20 INFO - Running setup.py (path:/tmp/pip-NJkZ_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:05: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)) 03:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:05:20 INFO - Running setup.py (path:/tmp/pip-xHu_Lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:05: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)) 03:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:05:20 INFO - Running setup.py (path:/tmp/pip-TGDMMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:05: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)) 03:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:05:20 INFO - Running setup.py (path:/tmp/pip-YmXNhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:05: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)) 03:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:20 INFO - Running setup.py (path:/tmp/pip-eNVbbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:05: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)) 03:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:05:20 INFO - Running setup.py (path:/tmp/pip-igbQLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:05:21 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)) 03:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:05:21 INFO - Running setup.py (path:/tmp/pip-HV7El8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:05:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:23 INFO - Downloading blessings-1.6.tar.gz 03:05:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:05:23 INFO - Installing collected packages: blessings 03:05:23 INFO - Running setup.py install for blessings 03:05:23 INFO - Successfully installed blessings 03:05:23 INFO - Cleaning up... 03:05:23 INFO - Return code: 0 03:05:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:05:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:05:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:05:23 INFO - Reading from file tmpfile_stdout 03:05:23 INFO - Current package versions: 03:05:23 INFO - argparse == 1.2.1 03:05:23 INFO - blessings == 1.6 03:05:23 INFO - blobuploader == 1.2.4 03:05:23 INFO - docopt == 0.6.1 03:05:23 INFO - functools32 == 3.2.3-2 03:05:23 INFO - jsonschema == 2.5.1 03:05:23 INFO - manifestparser == 1.1 03:05:23 INFO - mozInstall == 1.12 03:05:23 INFO - mozcrash == 1.0 03:05:23 INFO - mozdebug == 0.1 03:05:23 INFO - mozdevice == 0.49 03:05:23 INFO - mozfile == 1.2 03:05:23 INFO - mozhttpd == 0.7 03:05:23 INFO - mozinfo == 0.9 03:05:23 INFO - mozleak == 0.1 03:05:23 INFO - mozlog == 3.4 03:05:23 INFO - moznetwork == 0.27 03:05:23 INFO - mozprocess == 0.25 03:05:23 INFO - mozprofile == 0.28 03:05:23 INFO - mozrunner == 6.13 03:05:23 INFO - mozscreenshot == 0.1 03:05:23 INFO - mozsystemmonitor == 0.3 03:05:23 INFO - moztest == 0.8 03:05:23 INFO - mozversion == 1.4 03:05:23 INFO - psutil == 3.1.1 03:05:23 INFO - requests == 1.2.3 03:05:23 INFO - wsgiref == 0.1.2 03:05:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:05: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')]} 03:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05: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')]} 03:05:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', '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 03:05:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:05:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:05:23 INFO - Using env: {'DISPLAY': ':0', 03:05:23 INFO - 'HOME': '/home/cltbld', 03:05:23 INFO - 'LANG': 'en_US.UTF-8', 03:05:23 INFO - 'LANGUAGE': 'en_US:en', 03:05:23 INFO - 'LOGNAME': 'cltbld', 03:05:23 INFO - 'MAIL': '/var/mail/cltbld', 03:05:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:23 INFO - 'MOZ_NO_REMOTE': '1', 03:05:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:23 INFO - 'NO_EM_RESTART': '1', 03:05:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:23 INFO - 'PWD': '/builds/slave/test', 03:05:23 INFO - 'SHELL': '/bin/bash', 03:05:23 INFO - 'SHLVL': '1', 03:05:23 INFO - 'TERM': 'linux', 03:05:23 INFO - 'TMOUT': '86400', 03:05:23 INFO - 'USER': 'cltbld', 03:05:23 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', 03:05:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:23 INFO - '_': '/tools/buildbot/bin/python'} 03:05:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05: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)) 03:05: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)) 03:05: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)) 03:05: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)) 03:05: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)) 03:05: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)) 03:05: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)) 03:05: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)) 03:05: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)) 03:05: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)) 03:05: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)) 03:05:24 INFO - Cleaning up... 03:05:24 INFO - Return code: 0 03:05:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:05: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')]} 03:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05: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')]} 03:05:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05: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 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', '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 03:05: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 03:05: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 03:05:24 INFO - Using env: {'DISPLAY': ':0', 03:05:24 INFO - 'HOME': '/home/cltbld', 03:05:24 INFO - 'LANG': 'en_US.UTF-8', 03:05:24 INFO - 'LANGUAGE': 'en_US:en', 03:05:24 INFO - 'LOGNAME': 'cltbld', 03:05:24 INFO - 'MAIL': '/var/mail/cltbld', 03:05:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:24 INFO - 'MOZ_NO_REMOTE': '1', 03:05:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:24 INFO - 'NO_EM_RESTART': '1', 03:05:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:24 INFO - 'PWD': '/builds/slave/test', 03:05:24 INFO - 'SHELL': '/bin/bash', 03:05:24 INFO - 'SHLVL': '1', 03:05:24 INFO - 'TERM': 'linux', 03:05:24 INFO - 'TMOUT': '86400', 03:05:24 INFO - 'USER': 'cltbld', 03:05:24 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', 03:05:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:24 INFO - '_': '/tools/buildbot/bin/python'} 03:05:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:05:24 INFO - Cleaning up... 03:05:24 INFO - Return code: 0 03:05:24 INFO - Running post-action listener: _resource_record_post_action 03:05:24 INFO - Running post-action listener: _start_resource_monitoring 03:05:24 INFO - Starting resource monitoring. 03:05:24 INFO - [mozharness: 2017-04-06 10:05:24.300957Z] Finished create-virtualenv step (success) 03:05:24 INFO - [mozharness: 2017-04-06 10:05:24.301538Z] Running install step. 03:05:24 INFO - Running pre-action listener: _resource_record_pre_action 03:05:24 INFO - Running main action method: install 03:05:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:05:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:05:24 INFO - Reading from file tmpfile_stdout 03:05:24 INFO - Detecting whether we're running mozinstall >=1.0... 03:05:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:05:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:05:24 INFO - Reading from file tmpfile_stdout 03:05:24 INFO - Output received: 03:05:24 INFO - Usage: mozinstall [options] installer 03:05:24 INFO - Options: 03:05:24 INFO - -h, --help show this help message and exit 03:05:24 INFO - -d DEST, --destination=DEST 03:05:24 INFO - Directory to install application into. [default: 03:05:24 INFO - "/builds/slave/test"] 03:05:24 INFO - --app=APP Application being installed. [default: firefox] 03:05:24 INFO - mkdir: /builds/slave/test/build/application 03:05:24 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'] 03:05:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:05:41 INFO - Reading from file tmpfile_stdout 03:05:41 INFO - Output received: 03:05:41 INFO - /builds/slave/test/build/application/firefox/firefox 03:05:41 INFO - Running post-action listener: _resource_record_post_action 03:05:41 INFO - [mozharness: 2017-04-06 10:05:41.171799Z] Finished install step (success) 03:05:41 INFO - [mozharness: 2017-04-06 10:05:41.172075Z] Running run-tests step. 03:05:41 INFO - Running pre-action listener: _resource_record_pre_action 03:05:41 INFO - Running main action method: run_tests 03:05:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:05:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:05:41 INFO - Python 2.7.3 03:05:41 INFO - Return code: 0 03:05:41 INFO - grabbing minidump binary from tooltool 03:05: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')]} 03:05: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 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:05: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 03:05: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 03:05: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 03:05:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:05:41 INFO - Return code: 0 03:05:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:05:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:05:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:05:41 INFO - ENV: RUST_BACKTRACE is now 1 03:05:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:05:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:05:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--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 03:05:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-089 --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 03:05:41 INFO - Using env: {'DISPLAY': ':0', 03:05:41 INFO - 'HOME': '/home/cltbld', 03:05:41 INFO - 'LANG': 'en_US.UTF-8', 03:05:41 INFO - 'LANGUAGE': 'en_US:en', 03:05:41 INFO - 'LOGNAME': 'cltbld', 03:05:41 INFO - 'MAIL': '/var/mail/cltbld', 03:05:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:05:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:05:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:41 INFO - 'MOZ_NO_REMOTE': '1', 03:05:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:05:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:41 INFO - 'NO_EM_RESTART': '1', 03:05:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:41 INFO - 'PWD': '/builds/slave/test', 03:05:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:05:41 INFO - 'RUST_BACKTRACE': '1', 03:05:41 INFO - 'SHELL': '/bin/bash', 03:05:41 INFO - 'SHLVL': '1', 03:05:41 INFO - 'TERM': 'linux', 03:05:41 INFO - 'TMOUT': '86400', 03:05:41 INFO - 'USER': 'cltbld', 03:05:41 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348', 03:05:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:41 INFO - '_': '/tools/buildbot/bin/python'} 03:05:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--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 03:05:41 INFO - mozversion application_buildid: 20170406090923 03:05:41 INFO - mozversion application_changeset: 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 03:05:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:05:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:05:41 INFO - mozversion application_name: Firefox 03:05:41 INFO - mozversion application_remotingname: firefox-dev 03:05:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:05:41 INFO - mozversion application_vendor: Mozilla 03:05:41 INFO - mozversion application_version: 54.0a2 03:05:41 INFO - mozversion platform_buildid: 20170406090923 03:05:41 INFO - mozversion platform_changeset: 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 03:05:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:05:41 INFO - mozversion platform_version: 54.0a2 03:05:41 INFO - using testdate: 1491473141 03:05:41 INFO - actual date: 1491473141 03:05:41 INFO - starting webserver on 'localhost:44208' 03:05:41 INFO - SUITE-START | Running 2 tests 03:05:41 INFO - TEST-START | dromaeo_css 03:05:41 INFO - operating with platform_type : linux_ 03:05:41 INFO - Initialising browser for dromaeo_css test... 03:05:41 INFO - TEST-INFO | started process 5578 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphcCAiC/profile http://localhost:44208/getInfo.html) 03:05:45 INFO - PROCESS | 5578 | __metrics Screen width/height:1600/1200 03:05:45 INFO - PROCESS | 5578 | colorDepth:24 03:05:45 INFO - PROCESS | 5578 | Browser inner width/height: 1024/697 03:05:45 INFO - PROCESS | 5578 | __metrics 03:05:46 INFO - PROCESS | 5578 | 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" 03:05:46 INFO - PROCESS | 5578 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:05:47 INFO - TEST-INFO | 5578: exit 0 03:05:47 INFO - Browser initialized. 03:05:47 INFO - Running cycle 1/1 for dromaeo_css test... 03:05:47 INFO - TEST-INFO | started process 5670 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphcCAiC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:05:59 INFO - PROCESS | 5670 | RSS: Main: 139243520 03:05:59 INFO - PROCESS | 5670 | 03:09:00 INFO - PROCESS | 5670 | Cycle 1(1): loaded http://localhost:44208/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44208/tests/dromaeo/cssquery-ext.html) 03:09:02 INFO - PROCESS | 5670 | RSS: Main: 261251072 03:09:02 INFO - PROCESS | 5670 | 03:10:49 INFO - PROCESS | 5670 | ************************* 03:10:49 INFO - PROCESS | 5670 | A coding exception was thrown and uncaught in a Task. 03:10:49 INFO - PROCESS | 5670 | 03:10:49 ERROR - PROCESS | 5670 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:10:49 INFO - PROCESS | 5670 | Full stack: 03:10:49 INFO - PROCESS | 5670 | ************************* 03:10:49 INFO - PROCESS | 5670 | ************************* 03:10:49 INFO - PROCESS | 5670 | A coding exception was thrown and uncaught in a Task. 03:10:49 INFO - PROCESS | 5670 | 03:10:49 ERROR - PROCESS | 5670 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:10:49 INFO - PROCESS | 5670 | Full stack: 03:10:49 INFO - PROCESS | 5670 | ************************* 03:10:49 INFO - PROCESS | 5670 | ************************* 03:10:49 INFO - PROCESS | 5670 | A coding exception was thrown and uncaught in a Task. 03:10:49 INFO - PROCESS | 5670 | 03:10:49 ERROR - PROCESS | 5670 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:10:49 INFO - PROCESS | 5670 | Full stack: 03:10:49 INFO - PROCESS | 5670 | ************************* 03:10:49 INFO - PROCESS | 5670 | ************************* 03:10:49 INFO - PROCESS | 5670 | A coding exception was thrown and uncaught in a Task. 03:10:49 INFO - PROCESS | 5670 | 03:10:49 ERROR - PROCESS | 5670 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:10:49 INFO - PROCESS | 5670 | Full stack: 03:10:49 INFO - PROCESS | 5670 | ************************* 03:12:04 INFO - PROCESS | 5670 | Cycle 1(1): loaded http://localhost:44208/tests/dromaeo/cssquery-ext.html (next: http://localhost:44208/tests/dromaeo/cssquery-jquery.html) 03:12:04 INFO - PROCESS | 5670 | RSS: Main: 231358464 03:12:04 INFO - PROCESS | 5670 | 03:15:06 INFO - PROCESS | 5670 | Cycle 1(1): loaded http://localhost:44208/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44208/tests/dromaeo/cssquery-mootools.html) 03:15:07 INFO - PROCESS | 5670 | RSS: Main: 169299968 03:15:07 INFO - PROCESS | 5670 | 03:18:09 INFO - PROCESS | 5670 | Cycle 1(1): loaded http://localhost:44208/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44208/tests/dromaeo/cssquery-prototype.html) 03:18:09 INFO - PROCESS | 5670 | RSS: Main: 170278912 03:18:09 INFO - PROCESS | 5670 | 03:21:12 INFO - PROCESS | 5670 | Cycle 1(1): loaded http://localhost:44208/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44208/tests/dromaeo/cssquery-yui.html) 03:21:13 INFO - PROCESS | 5670 | RSS: Main: 176279552 03:21:13 INFO - PROCESS | 5670 | 03:24:15 INFO - PROCESS | 5670 | Cycle 1(1): loaded http://localhost:44208/tests/dromaeo/cssquery-yui.html (next: http://localhost:44208/tests/dromaeo/cssquery-dojo.html) 03:24:16 INFO - PROCESS | 5670 | RSS: Main: 263782400 03:24:16 INFO - PROCESS | 5670 | 03:24:16 INFO - PROCESS | 5670 | __start_tp_report 03:24:16 INFO - PROCESS | 5670 | _x_x_mozilla_page_load 03:24:16 INFO - PROCESS | 5670 | _x_x_mozilla_page_load_details 03:24:16 INFO - PROCESS | 5670 | |i|pagename|runs| 03:24:16 INFO - PROCESS | 5670 | |0;dojo.html;3417.829108544573;3443.8966830995073;3446.086787001445;3462.7402944779146;3488.1802776347554;5781.19063331134;5892.734826932782;5894.528437724977;5903;5904.822855314336;5873.35393106759;5945.702714864261;5945.851353716149;5946.256717910262;5947.07820287855;5223.660462069963;5251.711155886424;5254.632175747699;5272.314599102111;5354;5074.340335756347;5108.029474399871;5308.256844041837;5318.574514038887;5323.60072994525;5122.590192784587;5173.896522069556;5189.299444574991;5196.246544251081;5196.324477817879;5177.042247184246;5179.611529135311;5194.091034069042;5206.114960456732;5288.8413347599635;5216.426193118754;5236.738163091868;5238.764255608507;5263.6841789492755;5326.387465441447;5820.301563812327;5839.9124013139835;5911.586188966774;5918.378570250148;5925.614835035709;5972;6021.126936594169;6155.446009859134;6201.81394558164;6220.968895155541;6355.682215889187;6371.904421433682;6376.617402955838;6377.8405539861415;6383.425491705722;5855.531557475391;5935.910961335583;5942.3760505146765;5965.224520812353;5971.014782560872;6138.725610151984;6175.722092505758;6331;6365;6460.547761656639;6226.782062627786;6234.781782637586;6235.438810506986;6241.407066328691;6243.282022567463;6300.30696623371;6307.653079080695;6308.085327627468;6308.3060863305;6309.936900631027;5996.520278377807;6006.639601623917;6025.6083354581815;6218.098375735493;6266.530010249249;6096.756129754849;6119.6645886031865;6126.785562505291;6147.047207682816;6156.415140561639;4648;4655.813767449264;4671.766411679402;4745.311929770164;4752.477227504972;4269.082147338338;4274.016976095453;4305.720128191658;4315.546867578859;4335.349697545394;6001.549883758736;6098.817035488943;6118.785842495491;6123.387661233841;6127.5404344674325;6061.151438798572;6121.173641558333;6203.379662033761;6213.906791398133;6328.810135695937;6063.848403789941;6082.330943596201;6092.664903430265;6092.786752463731;6118.449339559372;5324.893502129936;5339.919901201485;5339.973300133474;5358.758855851496;5365.195220716923;6259.311475737665;6289.182406287245;6405.42351188386;6439.098526206241;6456.7740129095255;6321.051842223703;6335.144755458138;6344.651044192614;6357.078223657451;6377.649229292433;6511.934880651323;6525.575837570543;6555.639439830855;6575.375339342755;6592.91216949198;5916.497097746567;5986.982213023884;6094.664793436404;6148.846278843064;6231.40801623845;6334.809955701337;6335.841603959753;6344.397282258178;6351.714172862325;6352.491800656029;6189.381061893775;6191.288001879841;6196.938030619636;6204.5967012144065;6205.6586887721605;5876.206712093984;5904.261967254093;6122.959096953439;6129.08063790435;6190.752369905334;6148.44663980244;6177.938220617736;6191.81424557264;6196.783112591038;6212.9068063979985;6244.250689917239;6244.781432649836;6246.187995560548;6246.593971391845;6253.062040693932;5788.363280039108;5829.358770535323;5947.078202878561;6000.459958603747;6086.269647642312 03:24:16 INFO - PROCESS | 5670 | |1;ext.html;753.5177486408696;808.9555074470903;814.9551774652396;908.9318301127417;913.383466160342;32388.352232955327;43933.56066439335;44880.775596122054;44915;45142.7742861286;46766.532334676725;46860;46961.76519117408;46962.7651861741;47083;7709.151993280735;8856.468611883292;8958.193762561368;8978.326625503081;8978.371513994023;7708.653110610008;10513.52689128991;10771.676849694522;11152.163587730913;11157;10898.400587967659;10909.127269818435;10928.453577321103;10930.56277748893;10930.836037459445;10789;10792.514336854822;10812.459377031117;10823.13414926804;10842.241043126905;10739.463026848627;10779.029887310102;10791.082757965582;10805.5677772889;10807.621733239377;43254;43389;43411.78294108541;43471.34792978108;43540.78229608832;4231.301835197189;4274.935875961863;4333.826646934119;4414.31578105391;4426.313921342197;31062.844685776425;31376.843115784504;31588.124854483423;31671.683283167105;32940.341193176006;4272.978635106836;4305.354196870463;4349.369341445505;4359.6948213625055;4422.734635921823;256548;256760;257107;258430;258776;48010.75994620004;48465;48487;48520.7573962135;48608.5139148604;50116.749416252685;50175;50195;50234.748826255636;50259.74870125626;15664.843351566566;15706.214689265491;15767.211639417983;15902.68194636124;16196.59508512273;12157.088218383657;12267.631971040882;14187.219702916438;14191.361388737532;14208.152940117718;12162.148649594621;12505.312207828656;12662.49350026007;12844.357782110857;12909.677258068623;5238.447245919338;5271.156614941591;5328.600354973392;5328.866778330495;5350.919736203882;1027.6505987964129;1053.2358299847724;1099.692086215845;1129.9265547739371;1148.615213903335;28941;29193.270168245963;29260.26849328741;29704.702952970194;29995.40009199804;1200.5677955935855;1218.8110842819199;1222.584321330752;1224.7183147876037;1225.7732319520917;15239.695206096038;15348.23258837076;15372.30824612895;15445.0732956023;15519.146446945526;367886;369004;369036;369319;370228;62856;62975;63352.36647633557;63455.68272158702;63564.68217658975;1499.8800095992294;1560.7346751052348;1568.686262747455;1582.6122599963082;1606.6304749907588;26155.73844261533;27367.178984629696;27964.161075167776;27988.16035518938;28025;44663.55336446594;44971.32543011923;44994;45028.549714503744;45081.774591128145;324513;324584;324899;324961;325205;33388;33514.162145946546;33975;34186.65813341835;34283;45606;45637.77181114073;46811.53188468208;46902;47092;34257.82871085629;34829.30341393107;35106.82446587751;35195.29609407849;35298;34566.48150277697;34631;34734;34780.30439391147;34968.12579685528;38807;38851;39872.60127398689;39928.60071399365;40462.393064103475;32587;32759.18102047468;32796.83601582072;32911.34177316392;32971;26076.73923260819;26361.340966475993;26364.868175659765;26468.338291542867;26689.96505242137 03:24:16 INFO - PROCESS | 5670 | |2;jquery.html;412.5358971157448;469.80737897462046;469.90601879624063;475.5316013726481;488.4187816498367;19248;20006.49983750407;21584.784152158474;21828.781712182874;22036.88981555094;21148.577028459422;21435;21824.563508729814;22062.448438788997;22074.779252207514;5431.158170483582;6437.8712425751455;6710.664466776666;6731.798046058601;6754;6511.088447617338;6593.175853018372;6601.63690996995;6603.372679595431;6611.966940165293;6295.622262664232;6424.229092508882;6464.191976003;6472.546921715481;6475.320091390407;6393.520485963525;6481.546291759579;6578.407943285128;6616.305287944769;6674.899876501856;6447.0651755495655;6453.1610890583925;6459.709313080923;6468.256150542654;6470.191226096738;24352.756472435227;24476.143334983368;24598.139065132815;24643.630345544832;24972.625410618853;3233.1270556949494;3259.6414394416597;3284.108222219999;3305.8016519008697;3339.916502087467;15849.366025359088;16016.919915400349;16279.186040698036;16361.672766544722;16843.494695159166;2289.301762962296;2334.684817549626;2367.360812580611;2413.312206021293;2425.92722218332;94901;95925.52037239839;97122;97727;98195.50902245443;14710.117392956457;17026.404075857285;18514.037753959303;19193.712094318595;19340.4197874064;27787.58318625222;29068.12795616135;34063.82968085193;34114.805981790516;34240.65759342375;10485.213608979204;10628.840567391495;10637.574497020032;10663.733406664895;10850.511726972147;9016.504092274856;9016.909830901606;9062.546872656341;9206.585703643352;9206.815863682688;8820.8676869847;8974.461532308082;9003.443639762258;9233.676821311223;9318.347715659838;3627.909302267464;3870.4774855394726;3913.7867261148567;3931.233409485123;3941.3299739044432;956.3496822160859;958.9041095890356;966.0773960867355;967.4678926590348;975.190591808797;19637.21451141967;21269.397734643917;21279.468013299793;21327.78672213289;21529.67705484419;958.7201086549395;966.8791401074866;975.2295686407658;991.3407583956588;992.846108853129;10979.28634638746;11053;11440.542378304941;11522.366269855238;11698.94150529242;582458;587643;609277;676736;782987;94530.52734736282;100570.4971475138;111692;112070.43964780286;113996;1205.4876677412065;1267.9366031698562;1271.1356277731047;1283.8459384873688;1292.101989117573;14223.644408889446;17980.640387192445;19467.123979420678;19568;19778.406647800588;53655;53676.73161634167;54478;54554;55261.7236913829;64893.67553162204;65925;67946.66026669835;68030.65984670045;70443;12273.87726122763;12324.50701971947;12519.248845069325;12533.87466125327;12621.179623324455;13623.250721210428;13978.930105349407;14076.155430674193;14344.713105738036;14361;11995.76008479843;12021.579244726392;12368.690782730504;12431.75136497247;12449.564265250672;11198.10415166801;11357.148213884157;11633.185677002528;11767.176297659082;11893.405329733652;12104.394780261127;12316.938415307866;12513.249205047727;12682.175658582162;12762.936185319015;12480.438380273092;12694.111412201057;12725.490980360682;12752.617421477371;12785.488580456678;12307.630771076882;12344.567940122053;12593.559225427067;12689.048321375749;12854.55009074678 03:24:16 INFO - PROCESS | 5670 | |3;mootools.html;1539.2765400261874;1564.6401327694623;1573.119053330135;1584.8969816961896;1585.1994742654958;11038.613648522303;11742.882571174287;11753.236039657428;11754.059675225983;11756.353400562977;11424.371659558721;11523.711907202323;11708.121890858178;11710.180287379886;11711.648650540476;2459;2492.22740950306;2495.563276426627;2497.1884137655243;2497.5254701606727;2357.4813541021035;2388.7850093491584;2388.868612226327;2390.8206884483648;2398.3164798032562;2322.477442575424;2379.4051487128218;2388.773066558683;2404.8557086574774;2406.458546826959;2279;2326.685897403863;2351.2123438647986;2358.2689366296504;2359.834811563191;2329.906803727849;2338.8012018978407;2346.296111166643;2351.1888398502488;2351.988240058789;11604;11905.821412678817;11929.403529823474;11941;11952.760944781143;5803.21656576361;5953.642781433128;5958.97020514899;5962.016267315931;5964.701764911737;3285.2772390074147;3348.1462227131924;3405.676460736226;3407.369636617234;3456.585209774843;1262.5391732017747;1269.7587458382877;1295.1840340585466;1311.2591385866954;1324.4304948871982;14664.560063198121;15066;16604.58488537784;17815.73276400855;17974.191161397768;4654.394928659252;4672.275797251432;4801.855944321606;4810.6151507879285;4816.133096042676;11323.773524529628;11563.88436115645;12653.177543459646;13428.798568021488;13450.260235686936;5783.1325301204315;5905.734241959122;5907.911381329283;5914.378990206053;5918.082697181858;5431.945680543144;5462.0441422751655;5463.043967305705;5467.234587157801;5477.096279113981;4044.211378781168;4133.028738246509;4169.45797046382;4170.979145104255;4206.326987682018;1031.4842578710645;1032.5456799008412;1043.7651528406047;1047.7956798424234;1051.4427353502656;843.717354686187;856.5331894117596;857.9485230886166;865.7272959017889;890.3767362846033;6854.760083397157;6867.862642747117;6934.653267336714;6971.58170509771;7127.92872071283;1033.679559336608;1054.7995880782628;1054.8734151901667;1074.6292529077455;1080.8973147551128;4092.242935057024;4167.333226683716;4224.704270701021;4229.386738922839;4285.935710964338;14396.640083997774;14927.850721492863;14953.775693364609;14956.700865982837;14959.476418325525;14126;14668.486602968846;14842.851571484363;14858.405663773545;14865.70268594622;10863.18526110545;11113.888861111285;11169.888301117047;11175.050120739666;11182.32906025641;6854.34883686049;7108.111486064242;7156.534825236343;7174.605396703231;7216.603086830275;13386.866131338951;13543.05198636086;13579.117357371739;13682.863171368159;13686.589402317946;13475.124116932368;13520.59438216855;13604.93197534006;13656.795148072588;13664.93167534156;8823.205911467998;9018.143276388733;9785.559649815634;10012.69961901144;10310.32982856112;11348.092152627934;11351.886481135083;11352.375619340684;11461.140414469115;11522;9474.478903660365;9500.382475139093;9559.426434413957;9625.71122866315;9770.218382529523;9290.16388525036;9355.06449355059;9467.289953253672;9548.236141108555;9625.951870240604;8586.184312490303;8599.011113722003;8739.781505462413;8754.29965602763;8805.339599530189;5984.491318237999;5984.940150598438;5996;6009.729562169626;6046.365131661112;6250.249970003629;6258.624482531062;6332.0818481320875;6428.8071357859335;6465.709043092978 03:24:16 INFO - PROCESS | 5670 | |4;prototype.html;406.8270984831446;410.92101623928244;414.48810718762326;414.8382130968921;423.105132644457;5236.973815130919;5279.340082489689;5307.442718514559;5350.5184533391985;5363.249145119681;5123.231515272711;5219.269302297681;5229.450907654698;5231.293775340328;5236.90523790484;4288.448373217931;4402.493713222988;4408.4048653431855;4420.911581768362;4443;4384.816099653091;4386.342048692705;4389.407429996957;4398.712669411904;4412.271975124101;4388.802503887317;4398.846040388587;4402.647788176938;4428.712133711315;4460.4647442306805;4200.894977625553;4306;4343.1747967886;4372.737635741865;4414.624756895651;4048.271311163989;4065.715399922007;4069.877903662895;4072.2715780427184;4079.204555111725;5094.363204599425;5096.216324286507;5116.897662046776;5130.48650648056;5152.819651312186;4946.183879659852;5076.339881032659;5078.542931136179;5120.6927584344685;5152;5425.240466334716;5485.670859748428;5491.28613280271;5509.171126067385;5520.696361700105;4583.6562257830465;4593.494715581283;4626.653001024936;4634.2353511670535;4645.674802763808;5577.721113944286;5702.971485142548;5737.684427356494;5742.799002034951;5776.566757493205;4734.28985652148;4741.739204343725;4747.8575642730775;4748.548887855648;4771.33201351811;4798.928016079762;4820.3128904883715;4838.903221935612;4842.564169224716;4856.78144483492;4912.091263116335;5079.3396858408905;5091.137963245092;5095.140273800111;5150.4849515048945;5461.907618476321;5478.123500239943;5478.342598888159;5482.835514934558;5541.307336582927;1659.468969929635;1666.616678164029;1676.2205574407958;1677.4399808178268;1678.1441464853012;1263.8357013588359;1289.7613941420868;1291.6964513339358;1294.2622705058027;1303.8500572434102;5425.864353391197;5470.179473078991;5499.7250137492965;5503.834884953458;5523.585731070106;5450.454954504517;5598.844444888748;5704.4295570443455;5763.077907534775;5794.710264486759;5330.9604627098115;5366.517013468807;5366.6779993200535;5385.219143224211;5398.271233383522;2943.3818898031654;2949.1594895455123;2974.3753811699353;2980.8658610362586;3005.63932328122;5614.24207731951;5819.679917604572;5867.501262392744;5884.558658100659;5894.646321220741;5511.889762204733;5722.856928576819;5750.913736293959;5787.627072900091;5822.912656310159;5669.518090962314;5707.885842283131;5843.954615884407;5870.26621672291;5892.499137573354;5650.830475085754;5655.971720141538;5667.1782591524825;5672.092465205547;5728.369879313355;5223.947760522498;5425.782968681208;5548.805791797267;5551.22282880389;5647.378788333198;5827.9417205829095;5831.271091113611;5849.385814489588;5850.385709500441;5876.500497457764;4534.863954081383;4704.411948506437;4745.335653008513;4768.356271903249;4778.5699287064335;5654.575906806924;5672.4547158026235;5695.206010106803;5698.572607054404;5704.714764261687;5560.19377190297;5563.471470210324;5584.357798853184;5600.439956004367;5603.299587551556;5284.154535274338;5433.293671822637;5454.699991500347;5454.9727251365075;5522.337319521523;5503.7798488060025;5508.173773933942;5520.779168833202;5533.667979921218;5574.275344205227;5312.654677445954;5557.972210138923;5570.212255666186;5580.149696709258;5644.266245388238;5305.469453054664;5331.466853314638;5411.2965314508865;5428.538574221077;5437.700926449083 03:24:16 INFO - PROCESS | 5670 | |5;yui.html;782.8786538086591;810.975670729878;811.3711873298195;820.5815034332488;828.5235989306151;16765.413210537634;17114.657706845857;17152.228149733277;17224.3110275589;17402;16346.427875024376;16915.06967116808;17050.06224657643;17063.40278090267;17081.658366832657;851.9275861551776;856.3149480415674;875.8248350329927;877.6533269358599;878.8110556230409;871.79948611819;872.0276891266225;872.7250915961483;872.9956350218272;873.7378786364065;928.1600151862575;929.3773171974759;930.9301802364815;932.2355668351954;932.4451951089125;927.6243121535789;927.8005228875824;928.3826255540058;928.7863791327965;928.8049509602992;681.0261326303385;682.1847891769338;683.3917813146304;683.7846078485261;683.8905775076014;16655.41706040283;16663.750043749355;16748.916255418644;18972.14625341863;19171.712424313646;14553.490627827976;15743.134127622976;15928;16040.35838566456;16349;13298.468061277634;13354.26551539665;13469.259190744506;13564.253966031863;13817.861821381757;4648.930266046012;4702.459217190033;4703.529647035269;4745.501722319142;4798.616110711135;49775;65249.67375163141;65514;86997.99999999937;93547.53226233825;11894.82157767634;12167.75664486723;12380.195287306477;12500.687482813002;12566.246025238515;18886.3389781357;19580.510487237934;19593.510162245773;19595.804041959396;21693.34919952404;8624.956875215585;8655.394122411497;8713.825723485495;8795.956020219857;8970.282377409792;4922.212446008622;5154.690718556899;5162.380514338229;5209.453007434165;5290.5238528531845;3683.9079023024638;3750.3436898542645;3813.1992281620614;3815.980920095382;3830.616938306147;590.6190507122882;592.602956019468;601.4046094366546;601.530805971343;612.3142080869454;712.8004158835535;715.7530651925077;718.9352958233679;728.5410191579272;740.4224704730211;10689.358638481715;10776.245662803685;10780.51487683056;10868.891311086945;11110.555577776799;610.578700696518;612.6446660936646;624.0545573456218;628.1864984844638;636.1316802564467;5148.588112950955;5241.502057304626;5342.519173274424;5352.946470535245;5370.650907690988;68291;75926.62036689781;80247;88295;91076.5446172765;69329.65335173393;70082;70117;71165;75462;62478.37521624816;62721.37278627246;62923;63594;63741.681291593246;10305.690829275134;10370.896291037296;10664.680059598224;10706.304090234109;10710.946445267724;44545.55454445414;44770;45538.54461455343;45742.771286144685;46380.7680961593;52655;52679.47320526746;53367;53832;55101;12679.492820287085;12682.619521414372;12766.786960302892;12802.231866088065;13094;15207.315670795062;15286.617834554225;15415;15515.069095854285;15759.369625215322;14362.353694084;14583.12501249886;14612.123272603678;15034.624134396727;15545.844541554892;14350.569482915529;14911;15143.545693629208;15159.393624254906;15185.848141518443;14223.928880355947;14249.643758905695;14992.100473971597;15020.549383518512;15027.624309392353;9984.151347135576;10107.54516046765;10240.385576865412;10491.475426228812;10808.621698240524;11785.941070294593;11901.880981190423;12201.877981220074;12248.510059597516;12260.080493963167 03:24:16 INFO - PROCESS | 5670 | __end_tp_report 03:24:16 INFO - PROCESS | 5670 | __start_cc_report 03:24:16 INFO - PROCESS | 5670 | _x_x_mozilla_cycle_collect,1192 03:24:16 INFO - PROCESS | 5670 | __end_cc_report 03:24:16 INFO - PROCESS | 5670 | __startTimestamp1491474256197__endTimestamp 03:24:16 INFO - PROCESS | 5670 | 03:24:16 INFO - PROCESS | 5670 | ------- Summary: start ------- 03:24:16 INFO - PROCESS | 5670 | Number of tests: 6 03:24:16 INFO - PROCESS | 5670 | 03:24:16 INFO - PROCESS | 5670 | [#0] dojo.html Cycles:165 Average:5811.38 Median:6086.27 stddev:671.48 (11.0%) stddev-sans-first:646.76 03:24:16 INFO - PROCESS | 5670 | Values: 3417.8 3443.9 3446.1 3462.7 3488.2 5781.2 5892.7 5894.5 5903.0 5904.8 5873.4 5945.7 5945.9 5946.3 5947.1 5223.7 5251.7 5254.6 5272.3 5354.0 5074.3 5108.0 5308.3 5318.6 5323.6 5122.6 5173.9 5189.3 5196.2 5196.3 5177.0 5179.6 5194.1 5206.1 5288.8 5216.4 5236.7 5238.8 5263.7 5326.4 5820.3 5839.9 5911.6 5918.4 5925.6 5972.0 6021.1 6155.4 6201.8 6221.0 6355.7 6371.9 6376.6 6377.8 6383.4 5855.5 5935.9 5942.4 5965.2 5971.0 6138.7 6175.7 6331.0 6365.0 6460.5 6226.8 6234.8 6235.4 6241.4 6243.3 6300.3 6307.7 6308.1 6308.3 6309.9 5996.5 6006.6 6025.6 6218.1 6266.5 6096.8 6119.7 6126.8 6147.0 6156.4 4648.0 4655.8 4671.8 4745.3 4752.5 4269.1 4274.0 4305.7 4315.5 4335.3 6001.5 6098.8 6118.8 6123.4 6127.5 6061.2 6121.2 6203.4 6213.9 6328.8 6063.8 6082.3 6092.7 6092.8 6118.4 5324.9 5339.9 5340.0 5358.8 5365.2 6259.3 6289.2 6405.4 6439.1 6456.8 6321.1 6335.1 6344.7 6357.1 6377.6 6511.9 6525.6 6555.6 6575.4 6592.9 5916.5 5987.0 6094.7 6148.8 6231.4 6334.8 6335.8 6344.4 6351.7 6352.5 6189.4 6191.3 6196.9 6204.6 6205.7 5876.2 5904.3 6123.0 6129.1 6190.8 6148.4 6177.9 6191.8 6196.8 6212.9 6244.3 6244.8 6246.2 6246.6 6253.1 5788.4 5829.4 5947.1 6000.5 6086.3 03:24:16 INFO - PROCESS | 5670 | 03:24:16 INFO - PROCESS | 5670 | [#1] ext.html Cycles:180 Average:48767.99 Median:33451.08 stddev:83959.49 (251.0%) stddev-sans-first:84117.62 03:24:16 INFO - PROCESS | 5670 | Values: 753.5 809.0 815.0 908.9 913.4 32388.4 43933.6 44880.8 44915.0 45142.8 46766.5 46860.0 46961.8 46962.8 47083.0 7709.2 8856.5 8958.2 8978.3 8978.4 7708.7 10513.5 10771.7 11152.2 11157.0 10898.4 10909.1 10928.5 10930.6 10930.8 10789.0 10792.5 10812.5 10823.1 10842.2 10739.5 10779.0 10791.1 10805.6 10807.6 43254.0 43389.0 43411.8 43471.3 43540.8 4231.3 4274.9 4333.8 4414.3 4426.3 31062.8 31376.8 31588.1 31671.7 32940.3 4273.0 4305.4 4349.4 4359.7 4422.7 256548.0 256760.0 257107.0 258430.0 258776.0 48010.8 48465.0 48487.0 48520.8 48608.5 50116.7 50175.0 50195.0 50234.7 50259.7 15664.8 15706.2 15767.2 15902.7 16196.6 12157.1 12267.6 14187.2 14191.4 14208.2 12162.1 12505.3 12662.5 12844.4 12909.7 5238.4 5271.2 5328.6 5328.9 5350.9 1027.7 1053.2 1099.7 1129.9 1148.6 28941.0 29193.3 29260.3 29704.7 29995.4 1200.6 1218.8 1222.6 1224.7 1225.8 15239.7 15348.2 15372.3 15445.1 15519.1 367886.0 369004.0 369036.0 369319.0 370228.0 62856.0 62975.0 63352.4 63455.7 63564.7 1499.9 1560.7 1568.7 1582.6 1606.6 26155.7 27367.2 27964.2 27988.2 28025.0 44663.6 44971.3 44994.0 45028.5 45081.8 324513.0 324584.0 324899.0 324961.0 325205.0 33388.0 33514.2 33975.0 34186.7 34283.0 45606.0 45637.8 46811.5 46902.0 47092.0 34257.8 34829.3 35106.8 35195.3 35298.0 34566.5 34631.0 34734.0 34780.3 34968.1 38807.0 38851.0 39872.6 39928.6 40462.4 32587.0 32759.2 32796.8 32911.3 32971.0 26076.7 26361.3 26364.9 26468.3 26690.0 03:24:16 INFO - PROCESS | 5670 | 03:24:16 INFO - PROCESS | 5670 | [#2] jquery.html Cycles:180 Average:36642.82 Median:24537.14 stddev:107144.68 (436.7%) stddev-sans-first:107410.71 03:24:16 INFO - PROCESS | 5670 | Values: 412.5 469.8 469.9 475.5 488.4 19248.0 20006.5 21584.8 21828.8 22036.9 21148.6 21435.0 21824.6 22062.4 22074.8 5431.2 6437.9 6710.7 6731.8 6754.0 6511.1 6593.2 6601.6 6603.4 6612.0 6295.6 6424.2 6464.2 6472.5 6475.3 6393.5 6481.5 6578.4 6616.3 6674.9 6447.1 6453.2 6459.7 6468.3 6470.2 24352.8 24476.1 24598.1 24643.6 24972.6 3233.1 3259.6 3284.1 3305.8 3339.9 15849.4 16016.9 16279.2 16361.7 16843.5 2289.3 2334.7 2367.4 2413.3 2425.9 94901.0 95925.5 97122.0 97727.0 98195.5 14710.1 17026.4 18514.0 19193.7 19340.4 27787.6 29068.1 34063.8 34114.8 34240.7 10485.2 10628.8 10637.6 10663.7 10850.5 9016.5 9016.9 9062.5 9206.6 9206.8 8820.9 8974.5 9003.4 9233.7 9318.3 3627.9 3870.5 3913.8 3931.2 3941.3 956.3 958.9 966.1 967.5 975.2 19637.2 21269.4 21279.5 21327.8 21529.7 958.7 966.9 975.2 991.3 992.8 10979.3 11053.0 11440.5 11522.4 11698.9 582458.0 587643.0 609277.0 676736.0 782987.0 94530.5 100570.5 111692.0 112070.4 113996.0 1205.5 1267.9 1271.1 1283.8 1292.1 14223.6 17980.6 19467.1 19568.0 19778.4 53655.0 53676.7 54478.0 54554.0 55261.7 64893.7 65925.0 67946.7 68030.7 70443.0 12273.9 12324.5 12519.2 12533.9 12621.2 13623.3 13978.9 14076.2 14344.7 14361.0 11995.8 12021.6 12368.7 12431.8 12449.6 11198.1 11357.1 11633.2 11767.2 11893.4 12104.4 12316.9 12513.2 12682.2 12762.9 12480.4 12694.1 12725.5 12752.6 12785.5 12307.6 12344.6 12593.6 12689.0 12854.6 03:24:16 INFO - PROCESS | 5670 | 03:24:16 INFO - PROCESS | 5670 | [#3] mootools.html Cycles:180 Average:7178.44 Median:2388.78 stddev:4638.10 (194.2%) stddev-sans-first:4631.76 03:24:16 INFO - PROCESS | 5670 | Values: 1539.3 1564.6 1573.1 1584.9 1585.2 11038.6 11742.9 11753.2 11754.1 11756.4 11424.4 11523.7 11708.1 11710.2 11711.6 2459.0 2492.2 2495.6 2497.2 2497.5 2357.5 2388.8 2388.9 2390.8 2398.3 2322.5 2379.4 2388.8 2404.9 2406.5 2279.0 2326.7 2351.2 2358.3 2359.8 2329.9 2338.8 2346.3 2351.2 2352.0 11604.0 11905.8 11929.4 11941.0 11952.8 5803.2 5953.6 5959.0 5962.0 5964.7 3285.3 3348.1 3405.7 3407.4 3456.6 1262.5 1269.8 1295.2 1311.3 1324.4 14664.6 15066.0 16604.6 17815.7 17974.2 4654.4 4672.3 4801.9 4810.6 4816.1 11323.8 11563.9 12653.2 13428.8 13450.3 5783.1 5905.7 5907.9 5914.4 5918.1 5431.9 5462.0 5463.0 5467.2 5477.1 4044.2 4133.0 4169.5 4171.0 4206.3 1031.5 1032.5 1043.8 1047.8 1051.4 843.7 856.5 857.9 865.7 890.4 6854.8 6867.9 6934.7 6971.6 7127.9 1033.7 1054.8 1054.9 1074.6 1080.9 4092.2 4167.3 4224.7 4229.4 4285.9 14396.6 14927.9 14953.8 14956.7 14959.5 14126.0 14668.5 14842.9 14858.4 14865.7 10863.2 11113.9 11169.9 11175.1 11182.3 6854.3 7108.1 7156.5 7174.6 7216.6 13386.9 13543.1 13579.1 13682.9 13686.6 13475.1 13520.6 13604.9 13656.8 13664.9 8823.2 9018.1 9785.6 10012.7 10310.3 11348.1 11351.9 11352.4 11461.1 11522.0 9474.5 9500.4 9559.4 9625.7 9770.2 9290.2 9355.1 9467.3 9548.2 9626.0 8586.2 8599.0 8739.8 8754.3 8805.3 5984.5 5984.9 5996.0 6009.7 6046.4 6250.2 6258.6 6332.1 6428.8 6465.7 03:24:16 INFO - PROCESS | 5670 | 03:24:16 INFO - PROCESS | 5670 | [#4] prototype.html Cycles:180 Average:4813.72 Median:5310.05 stddev:1278.13 (24.1%) stddev-sans-first:1238.18 03:24:16 INFO - PROCESS | 5670 | Values: 406.8 410.9 414.5 414.8 423.1 5237.0 5279.3 5307.4 5350.5 5363.2 5123.2 5219.3 5229.5 5231.3 5236.9 4288.4 4402.5 4408.4 4420.9 4443.0 4384.8 4386.3 4389.4 4398.7 4412.3 4388.8 4398.8 4402.6 4428.7 4460.5 4200.9 4306.0 4343.2 4372.7 4414.6 4048.3 4065.7 4069.9 4072.3 4079.2 5094.4 5096.2 5116.9 5130.5 5152.8 4946.2 5076.3 5078.5 5120.7 5152.0 5425.2 5485.7 5491.3 5509.2 5520.7 4583.7 4593.5 4626.7 4634.2 4645.7 5577.7 5703.0 5737.7 5742.8 5776.6 4734.3 4741.7 4747.9 4748.5 4771.3 4798.9 4820.3 4838.9 4842.6 4856.8 4912.1 5079.3 5091.1 5095.1 5150.5 5461.9 5478.1 5478.3 5482.8 5541.3 1659.5 1666.6 1676.2 1677.4 1678.1 1263.8 1289.8 1291.7 1294.3 1303.9 5425.9 5470.2 5499.7 5503.8 5523.6 5450.5 5598.8 5704.4 5763.1 5794.7 5331.0 5366.5 5366.7 5385.2 5398.3 2943.4 2949.2 2974.4 2980.9 3005.6 5614.2 5819.7 5867.5 5884.6 5894.6 5511.9 5722.9 5750.9 5787.6 5822.9 5669.5 5707.9 5844.0 5870.3 5892.5 5650.8 5656.0 5667.2 5672.1 5728.4 5223.9 5425.8 5548.8 5551.2 5647.4 5827.9 5831.3 5849.4 5850.4 5876.5 4534.9 4704.4 4745.3 4768.4 4778.6 5654.6 5672.5 5695.2 5698.6 5704.7 5560.2 5563.5 5584.4 5600.4 5603.3 5284.2 5433.3 5454.7 5455.0 5522.3 5503.8 5508.2 5520.8 5533.7 5574.3 5312.7 5558.0 5570.2 5580.1 5644.3 5305.5 5331.5 5411.3 5428.5 5437.7 03:24:16 INFO - PROCESS | 5670 | 03:24:16 INFO - PROCESS | 5670 | [#5] yui.html Cycles:180 Average:18070.66 Median:4702.99 stddev:22346.89 (475.2%) stddev-sans-first:22371.88 03:24:16 INFO - PROCESS | 5670 | Values: 782.9 811.0 811.4 820.6 828.5 16765.4 17114.7 17152.2 17224.3 17402.0 16346.4 16915.1 17050.1 17063.4 17081.7 851.9 856.3 875.8 877.7 878.8 871.8 872.0 872.7 873.0 873.7 928.2 929.4 930.9 932.2 932.4 927.6 927.8 928.4 928.8 928.8 681.0 682.2 683.4 683.8 683.9 16655.4 16663.8 16748.9 18972.1 19171.7 14553.5 15743.1 15928.0 16040.4 16349.0 13298.5 13354.3 13469.3 13564.3 13817.9 4648.9 4702.5 4703.5 4745.5 4798.6 49775.0 65249.7 65514.0 86998.0 93547.5 11894.8 12167.8 12380.2 12500.7 12566.2 18886.3 19580.5 19593.5 19595.8 21693.3 8625.0 8655.4 8713.8 8796.0 8970.3 4922.2 5154.7 5162.4 5209.5 5290.5 3683.9 3750.3 3813.2 3816.0 3830.6 590.6 592.6 601.4 601.5 612.3 712.8 715.8 718.9 728.5 740.4 10689.4 10776.2 10780.5 10868.9 11110.6 610.6 612.6 624.1 628.2 636.1 5148.6 5241.5 5342.5 5352.9 5370.7 68291.0 75926.6 80247.0 88295.0 91076.5 69329.7 70082.0 70117.0 71165.0 75462.0 62478.4 62721.4 62923.0 63594.0 63741.7 10305.7 10370.9 10664.7 10706.3 10710.9 44545.6 44770.0 45538.5 45742.8 46380.8 52655.0 52679.5 53367.0 53832.0 55101.0 12679.5 12682.6 12766.8 12802.2 13094.0 15207.3 15286.6 15415.0 15515.1 15759.4 14362.4 14583.1 14612.1 15034.6 15545.8 14350.6 14911.0 15143.5 15159.4 15185.8 14223.9 14249.6 14992.1 15020.5 15027.6 9984.2 10107.5 10240.4 10491.5 10808.6 11785.9 11901.9 12201.9 12248.5 12260.1 03:24:16 INFO - PROCESS | 5670 | -------- Summary: end -------- 03:24:16 INFO - PROCESS | 5670 | 03:24:16 INFO - TEST-INFO | 5670: exit 0 03:24:16 INFO - TEST-OK | dromaeo_css | took 1115519ms 03:24:16 INFO - TEST-START | kraken 03:24:16 INFO - operating with platform_type : linux_ 03:24:16 INFO - Initialising browser for kraken test... 03:24:16 INFO - TEST-INFO | started process 5995 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjw9tN0/profile http://localhost:44208/getInfo.html) 03:24:21 INFO - PROCESS | 5995 | __metrics Screen width/height:1600/1200 03:24:21 INFO - PROCESS | 5995 | colorDepth:24 03:24:21 INFO - PROCESS | 5995 | Browser inner width/height: 1024/697 03:24:21 INFO - PROCESS | 5995 | __metrics 03:24:21 INFO - PROCESS | 5995 | 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" 03:24:21 INFO - PROCESS | 5995 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:24:22 INFO - TEST-INFO | 5995: exit 0 03:24:22 INFO - Browser initialized. 03:24:22 INFO - Running cycle 1/1 for kraken test... 03:24:22 INFO - TEST-INFO | started process 6088 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjw9tN0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:24:34 INFO - PROCESS | 6088 | RSS: Main: 139243520 03:24:34 INFO - PROCESS | 6088 | 03:24:37 INFO - PROCESS | 6088 | Cycle 1(1): loaded http://localhost:44208/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44208/tests/kraken/driver.html?testName=audio-beat-detection) 03:24:38 INFO - PROCESS | 6088 | RSS: Main: 183746560 03:24:38 INFO - PROCESS | 6088 | 03:24:40 INFO - PROCESS | 6088 | Cycle 1(1): loaded http://localhost:44208/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44208/tests/kraken/driver.html?testName=audio-dft) 03:24:41 INFO - PROCESS | 6088 | RSS: Main: 173649920 03:24:41 INFO - PROCESS | 6088 | 03:24:44 INFO - PROCESS | 6088 | Cycle 1(1): loaded http://localhost:44208/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44208/tests/kraken/driver.html?testName=audio-fft) 03:24:44 INFO - PROCESS | 6088 | RSS: Main: 265629696 03:24:44 INFO - PROCESS | 6088 | 03:24:46 INFO - PROCESS | 6088 | Cycle 1(1): loaded http://localhost:44208/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44208/tests/kraken/driver.html?testName=audio-oscillator) 03:24:47 INFO - PROCESS | 6088 | RSS: Main: 171986944 03:24:47 INFO - PROCESS | 6088 | 03:24:49 INFO - PROCESS | 6088 | Cycle 1(1): loaded http://localhost:44208/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44208/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:24:49 INFO - PROCESS | 6088 | RSS: Main: 174567424 03:24:49 INFO - PROCESS | 6088 | 03:24:52 INFO - PROCESS | 6088 | Cycle 1(1): loaded http://localhost:44208/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44208/tests/kraken/driver.html?testName=imaging-darkroom) 03:24:53 INFO - PROCESS | 6088 | RSS: Main: 213172224 03:24:53 INFO - PROCESS | 6088 | 03:24:56 INFO - PROCESS | 6088 | Cycle 1(1): loaded http://localhost:44208/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44208/tests/kraken/driver.html?testName=imaging-desaturate) 03:24:57 INFO - PROCESS | 6088 | RSS: Main: 212275200 03:24:57 INFO - PROCESS | 6088 | 03:25:00 INFO - PROCESS | 6088 | Cycle 1(1): loaded http://localhost:44208/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44208/tests/kraken/driver.html?testName=json-parse-financial) 03:25:00 INFO - PROCESS | 6088 | RSS: Main: 210497536 03:25:00 INFO - PROCESS | 6088 | 03:25:02 INFO - PROCESS | 6088 | Cycle 1(1): loaded http://localhost:44208/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44208/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:25:02 INFO - PROCESS | 6088 | RSS: Main: 173236224 03:25:02 INFO - PROCESS | 6088 | 03:25:04 INFO - PROCESS | 6088 | Cycle 1(1): loaded http://localhost:44208/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44208/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:25:05 INFO - PROCESS | 6088 | RSS: Main: 180092928 03:25:05 INFO - PROCESS | 6088 | 03:25:07 INFO - PROCESS | 6088 | Cycle 1(1): loaded http://localhost:44208/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44208/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:25:07 INFO - PROCESS | 6088 | RSS: Main: 189255680 03:25:07 INFO - PROCESS | 6088 | 03:25:10 INFO - PROCESS | 6088 | Cycle 1(1): loaded http://localhost:44208/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44208/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:25:11 INFO - PROCESS | 6088 | RSS: Main: 197996544 03:25:11 INFO - PROCESS | 6088 | 03:25:13 INFO - PROCESS | 6088 | Cycle 1(1): loaded http://localhost:44208/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44208/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:25:14 INFO - PROCESS | 6088 | RSS: Main: 194232320 03:25:14 INFO - PROCESS | 6088 | 03:25:15 INFO - PROCESS | 6088 | Cycle 1(1): loaded http://localhost:44208/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44208/tests/kraken/driver.html?testName=ai-astar) 03:25:16 INFO - PROCESS | 6088 | RSS: Main: 246083584 03:25:16 INFO - PROCESS | 6088 | 03:25:16 INFO - PROCESS | 6088 | __start_tp_report 03:25:16 INFO - PROCESS | 6088 | _x_x_mozilla_page_load 03:25:16 INFO - PROCESS | 6088 | _x_x_mozilla_page_load_details 03:25:16 INFO - PROCESS | 6088 | |i|pagename|runs| 03:25:16 INFO - PROCESS | 6088 | |0;ai-astar;104;113;111;111;114;112;112;113;113;114 03:25:16 INFO - PROCESS | 6088 | |1;audio-beat-detection;140;117;116;154;117;115;117;117;132;115 03:25:16 INFO - PROCESS | 6088 | |2;audio-dft;162;170;161;161;161;161;163;162;161;160 03:25:16 INFO - PROCESS | 6088 | |3;audio-fft;74;75;75;103;77;77;77;76;95;75 03:25:16 INFO - PROCESS | 6088 | |4;audio-oscillator;82;81;102;81;82;82;91;81;81;106 03:25:16 INFO - PROCESS | 6088 | |5;imaging-gaussian-blur;113;113;115;113;113;113;113;113;114;113 03:25:16 INFO - PROCESS | 6088 | |6;imaging-darkroom;147;147;146;147;146;146;146;147;146;145 03:25:16 INFO - PROCESS | 6088 | |7;imaging-desaturate;95;94;94;94;93;94;95;94;94;94 03:25:16 INFO - PROCESS | 6088 | |8;json-parse-financial;54;53;54;54;54;55;55;54;54;55 03:25:16 INFO - PROCESS | 6088 | |9;json-stringify-tinderbox;52;52;52;52;52;52;53;54;51;52 03:25:16 INFO - PROCESS | 6088 | |10;stanford-crypto-aes;76;84;83;77;79;79;76;76;75;76 03:25:16 INFO - PROCESS | 6088 | |11;stanford-crypto-ccm;122;120;126;130;118;119;118;117;158;132 03:25:16 INFO - PROCESS | 6088 | |12;stanford-crypto-pbkdf2;160;159;161;164;160;158;157;157;159;159 03:25:16 INFO - PROCESS | 6088 | |13;stanford-crypto-sha256-iterative;64;60;60;59;59;60;60;60;59;59 03:25:16 INFO - PROCESS | 6088 | __end_tp_report 03:25:16 INFO - PROCESS | 6088 | __start_cc_report 03:25:16 INFO - PROCESS | 6088 | _x_x_mozilla_cycle_collect,1686 03:25:16 INFO - PROCESS | 6088 | __end_cc_report 03:25:16 INFO - PROCESS | 6088 | __startTimestamp1491474316236__endTimestamp 03:25:16 INFO - PROCESS | 6088 | 03:25:16 INFO - PROCESS | 6088 | ------- Summary: start ------- 03:25:16 INFO - PROCESS | 6088 | Number of tests: 14 03:25:16 INFO - PROCESS | 6088 | 03:25:16 INFO - PROCESS | 6088 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:2.91 (2.6%) stddev-sans-first:1.13 03:25:16 INFO - PROCESS | 6088 | Values: 104.0 113.0 111.0 111.0 114.0 112.0 112.0 113.0 113.0 114.0 03:25:16 INFO - PROCESS | 6088 | 03:25:16 INFO - PROCESS | 6088 | [#1] audio-beat-detection Cycles:10 Average:124.00 Median:117.00 stddev:13.51 (11.5%) stddev-sans-first:13.03 03:25:16 INFO - PROCESS | 6088 | Values: 140.0 117.0 116.0 154.0 117.0 115.0 117.0 117.0 132.0 115.0 03:25:16 INFO - PROCESS | 6088 | 03:25:16 INFO - PROCESS | 6088 | [#2] audio-dft Cycles:10 Average:162.20 Median:161.50 stddev:2.86 (1.8%) stddev-sans-first:3.03 03:25:16 INFO - PROCESS | 6088 | Values: 162.0 170.0 161.0 161.0 161.0 161.0 163.0 162.0 161.0 160.0 03:25:16 INFO - PROCESS | 6088 | 03:25:16 INFO - PROCESS | 6088 | [#3] audio-fft Cycles:10 Average:80.40 Median:76.50 stddev:10.04 (13.1%) stddev-sans-first:10.37 03:25:16 INFO - PROCESS | 6088 | Values: 74.0 75.0 75.0 103.0 77.0 77.0 77.0 76.0 95.0 75.0 03:25:16 INFO - PROCESS | 6088 | 03:25:16 INFO - PROCESS | 6088 | [#4] audio-oscillator Cycles:10 Average:86.90 Median:81.50 stddev:9.55 (11.7%) stddev-sans-first:9.96 03:25:16 INFO - PROCESS | 6088 | Values: 82.0 81.0 102.0 81.0 82.0 82.0 91.0 81.0 81.0 106.0 03:25:16 INFO - PROCESS | 6088 | 03:25:16 INFO - PROCESS | 6088 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:113.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 03:25:16 INFO - PROCESS | 6088 | Values: 113.0 113.0 115.0 113.0 113.0 113.0 113.0 113.0 114.0 113.0 03:25:16 INFO - PROCESS | 6088 | 03:25:16 INFO - PROCESS | 6088 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.67 03:25:16 INFO - PROCESS | 6088 | Values: 147.0 147.0 146.0 147.0 146.0 146.0 146.0 147.0 146.0 145.0 03:25:16 INFO - PROCESS | 6088 | 03:25:16 INFO - PROCESS | 6088 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 03:25:16 INFO - PROCESS | 6088 | Values: 95.0 94.0 94.0 94.0 93.0 94.0 95.0 94.0 94.0 94.0 03:25:16 INFO - PROCESS | 6088 | 03:25:16 INFO - PROCESS | 6088 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 03:25:16 INFO - PROCESS | 6088 | Values: 54.0 53.0 54.0 54.0 54.0 55.0 55.0 54.0 54.0 55.0 03:25:16 INFO - PROCESS | 6088 | 03:25:16 INFO - PROCESS | 6088 | [#9] json-stringify-tinderbox Cycles:10 Average:52.20 Median:52.00 stddev:0.79 (1.5%) stddev-sans-first:0.83 03:25:16 INFO - PROCESS | 6088 | Values: 52.0 52.0 52.0 52.0 52.0 52.0 53.0 54.0 51.0 52.0 03:25:16 INFO - PROCESS | 6088 | 03:25:16 INFO - PROCESS | 6088 | [#10] stanford-crypto-aes Cycles:10 Average:78.10 Median:78.00 stddev:3.14 (4.0%) stddev-sans-first:3.24 03:25:16 INFO - PROCESS | 6088 | Values: 76.0 84.0 83.0 77.0 79.0 79.0 76.0 76.0 75.0 76.0 03:25:16 INFO - PROCESS | 6088 | 03:25:16 INFO - PROCESS | 6088 | [#11] stanford-crypto-ccm Cycles:10 Average:126.00 Median:124.00 stddev:12.41 (10.0%) stddev-sans-first:13.08 03:25:16 INFO - PROCESS | 6088 | Values: 122.0 120.0 126.0 130.0 118.0 119.0 118.0 117.0 158.0 132.0 03:25:16 INFO - PROCESS | 6088 | 03:25:16 INFO - PROCESS | 6088 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.40 Median:159.50 stddev:2.07 (1.3%) stddev-sans-first:2.18 03:25:16 INFO - PROCESS | 6088 | Values: 160.0 159.0 161.0 164.0 160.0 158.0 157.0 157.0 159.0 159.0 03:25:16 INFO - PROCESS | 6088 | 03:25:16 INFO - PROCESS | 6088 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.00 Median:60.00 stddev:1.49 (2.5%) stddev-sans-first:0.53 03:25:16 INFO - PROCESS | 6088 | Values: 64.0 60.0 60.0 59.0 59.0 60.0 60.0 60.0 59.0 59.0 03:25:16 INFO - PROCESS | 6088 | -------- Summary: end -------- 03:25:16 INFO - PROCESS | 6088 | 03:25:17 INFO - TEST-INFO | 6088: exit 0 03:25:17 INFO - TEST-OK | kraken | took 60340ms 03:25:17 INFO - SUITE-END | took 1175s 03:25:17 INFO - Completed test suite (00:19:35) 03:25:17 INFO - javascript benchmark 03:25:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7838.7905992312035, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3417.829108544573, 3443.8966830995073, 3446.086787001445, 3462.7402944779146, 3488.1802776347554, 5781.19063331134, 5892.734826932782, 5894.528437724977, 5903.0, 5904.822855314336, 5873.35393106759, 5945.702714864261, 5945.851353716149, 5946.256717910262, 5947.07820287855, 5223.660462069963, 5251.711155886424, 5254.632175747699, 5272.314599102111, 5354.0, 5074.340335756347, 5108.029474399871, 5308.256844041837, 5318.574514038887, 5323.60072994525, 5122.590192784587, 5173.896522069556, 5189.299444574991, 5196.246544251081, 5196.324477817879, 5177.042247184246, 5179.611529135311, 5194.091034069042, 5206.114960456732, 5288.8413347599635, 5216.426193118754, 5236.738163091868, 5238.764255608507, 5263.6841789492755, 5326.387465441447, 5820.301563812327, 5839.9124013139835, 5911.586188966774, 5918.378570250148, 5925.614835035709, 5972.0, 6021.126936594169, 6155.446009859134, 6201.81394558164, 6220.968895155541, 6355.682215889187, 6371.904421433682, 6376.617402955838, 6377.8405539861415, 6383.425491705722, 5855.531557475391, 5935.910961335583, 5942.3760505146765, 5965.224520812353, 5971.014782560872, 6138.725610151984, 6175.722092505758, 6331.0, 6365.0, 6460.547761656639, 6226.782062627786, 6234.781782637586, 6235.438810506986, 6241.407066328691, 6243.282022567463, 6300.30696623371, 6307.653079080695, 6308.085327627468, 6308.3060863305, 6309.936900631027, 5996.520278377807, 6006.639601623917, 6025.6083354581815, 6218.098375735493, 6266.530010249249, 6096.756129754849, 6119.6645886031865, 6126.785562505291, 6147.047207682816, 6156.415140561639, 4648.0, 4655.813767449264, 4671.766411679402, 4745.311929770164, 4752.477227504972, 4269.082147338338, 4274.016976095453, 4305.720128191658, 4315.546867578859, 4335.349697545394, 6001.549883758736, 6098.817035488943, 6118.785842495491, 6123.387661233841, 6127.5404344674325, 6061.151438798572, 6121.173641558333, 6203.379662033761, 6213.906791398133, 6328.810135695937, 6063.848403789941, 6082.330943596201, 6092.664903430265, 6092.786752463731, 6118.449339559372, 5324.893502129936, 5339.919901201485, 5339.973300133474, 5358.758855851496, 5365.195220716923, 6259.311475737665, 6289.182406287245, 6405.42351188386, 6439.098526206241, 6456.7740129095255, 6321.051842223703, 6335.144755458138, 6344.651044192614, 6357.078223657451, 6377.649229292433, 6511.934880651323, 6525.575837570543, 6555.639439830855, 6575.375339342755, 6592.91216949198, 5916.497097746567, 5986.982213023884, 6094.664793436404, 6148.846278843064, 6231.40801623845, 6334.809955701337, 6335.841603959753, 6344.397282258178, 6351.714172862325, 6352.491800656029, 6189.381061893775, 6191.288001879841, 6196.938030619636, 6204.5967012144065, 6205.6586887721605, 5876.206712093984, 5904.261967254093, 6122.959096953439, 6129.08063790435, 6190.752369905334, 6148.44663980244, 6177.938220617736, 6191.81424557264, 6196.783112591038, 6212.9068063979985, 6244.250689917239, 6244.781432649836, 6246.187995560548, 6246.593971391845, 6253.062040693932, 5788.363280039108, 5829.358770535323, 5947.078202878561, 6000.459958603747, 6086.269647642312], "value": 5765.993892465045, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [753.5177486408696, 808.9555074470903, 814.9551774652396, 908.9318301127417, 913.383466160342, 32388.352232955327, 43933.56066439335, 44880.775596122054, 44915.0, 45142.7742861286, 46766.532334676725, 46860.0, 46961.76519117408, 46962.7651861741, 47083.0, 7709.151993280735, 8856.468611883292, 8958.193762561368, 8978.326625503081, 8978.371513994023, 7708.653110610008, 10513.52689128991, 10771.676849694522, 11152.163587730913, 11157.0, 10898.400587967659, 10909.127269818435, 10928.453577321103, 10930.56277748893, 10930.836037459445, 10789.0, 10792.514336854822, 10812.459377031117, 10823.13414926804, 10842.241043126905, 10739.463026848627, 10779.029887310102, 10791.082757965582, 10805.5677772889, 10807.621733239377, 43254.0, 43389.0, 43411.78294108541, 43471.34792978108, 43540.78229608832, 4231.301835197189, 4274.935875961863, 4333.826646934119, 4414.31578105391, 4426.313921342197, 31062.844685776425, 31376.843115784504, 31588.124854483423, 31671.683283167105, 32940.341193176006, 4272.978635106836, 4305.354196870463, 4349.369341445505, 4359.6948213625055, 4422.734635921823, 256548.0, 256760.0, 257107.0, 258430.0, 258776.0, 48010.75994620004, 48465.0, 48487.0, 48520.7573962135, 48608.5139148604, 50116.749416252685, 50175.0, 50195.0, 50234.748826255636, 50259.74870125626, 15664.843351566566, 15706.214689265491, 15767.211639417983, 15902.68194636124, 16196.59508512273, 12157.088218383657, 12267.631971040882, 14187.219702916438, 14191.361388737532, 14208.152940117718, 12162.148649594621, 12505.312207828656, 12662.49350026007, 12844.357782110857, 12909.677258068623, 5238.447245919338, 5271.156614941591, 5328.600354973392, 5328.866778330495, 5350.919736203882, 1027.6505987964129, 1053.2358299847724, 1099.692086215845, 1129.9265547739371, 1148.615213903335, 28941.0, 29193.270168245963, 29260.26849328741, 29704.702952970194, 29995.40009199804, 1200.5677955935855, 1218.8110842819199, 1222.584321330752, 1224.7183147876037, 1225.7732319520917, 15239.695206096038, 15348.23258837076, 15372.30824612895, 15445.0732956023, 15519.146446945526, 367886.0, 369004.0, 369036.0, 369319.0, 370228.0, 62856.0, 62975.0, 63352.36647633557, 63455.68272158702, 63564.68217658975, 1499.8800095992294, 1560.7346751052348, 1568.686262747455, 1582.6122599963082, 1606.6304749907588, 26155.73844261533, 27367.178984629696, 27964.161075167776, 27988.16035518938, 28025.0, 44663.55336446594, 44971.32543011923, 44994.0, 45028.549714503744, 45081.774591128145, 324513.0, 324584.0, 324899.0, 324961.0, 325205.0, 33388.0, 33514.162145946546, 33975.0, 34186.65813341835, 34283.0, 45606.0, 45637.77181114073, 46811.53188468208, 46902.0, 47092.0, 34257.82871085629, 34829.30341393107, 35106.82446587751, 35195.29609407849, 35298.0, 34566.48150277697, 34631.0, 34734.0, 34780.30439391147, 34968.12579685528, 38807.0, 38851.0, 39872.60127398689, 39928.60071399365, 40462.393064103475, 32587.0, 32759.18102047468, 32796.83601582072, 32911.34177316392, 32971.0, 26076.73923260819, 26361.340966475993, 26364.868175659765, 26468.338291542867, 26689.96505242137], "value": 19451.888781707195, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.5358971157448, 469.80737897462046, 469.90601879624063, 475.5316013726481, 488.4187816498367, 19248.0, 20006.49983750407, 21584.784152158474, 21828.781712182874, 22036.88981555094, 21148.577028459422, 21435.0, 21824.563508729814, 22062.448438788997, 22074.779252207514, 5431.158170483582, 6437.8712425751455, 6710.664466776666, 6731.798046058601, 6754.0, 6511.088447617338, 6593.175853018372, 6601.63690996995, 6603.372679595431, 6611.966940165293, 6295.622262664232, 6424.229092508882, 6464.191976003, 6472.546921715481, 6475.320091390407, 6393.520485963525, 6481.546291759579, 6578.407943285128, 6616.305287944769, 6674.899876501856, 6447.0651755495655, 6453.1610890583925, 6459.709313080923, 6468.256150542654, 6470.191226096738, 24352.756472435227, 24476.143334983368, 24598.139065132815, 24643.630345544832, 24972.625410618853, 3233.1270556949494, 3259.6414394416597, 3284.108222219999, 3305.8016519008697, 3339.916502087467, 15849.366025359088, 16016.919915400349, 16279.186040698036, 16361.672766544722, 16843.494695159166, 2289.301762962296, 2334.684817549626, 2367.360812580611, 2413.312206021293, 2425.92722218332, 94901.0, 95925.52037239839, 97122.0, 97727.0, 98195.50902245443, 14710.117392956457, 17026.404075857285, 18514.037753959303, 19193.712094318595, 19340.4197874064, 27787.58318625222, 29068.12795616135, 34063.82968085193, 34114.805981790516, 34240.65759342375, 10485.213608979204, 10628.840567391495, 10637.574497020032, 10663.733406664895, 10850.511726972147, 9016.504092274856, 9016.909830901606, 9062.546872656341, 9206.585703643352, 9206.815863682688, 8820.8676869847, 8974.461532308082, 9003.443639762258, 9233.676821311223, 9318.347715659838, 3627.909302267464, 3870.4774855394726, 3913.7867261148567, 3931.233409485123, 3941.3299739044432, 956.3496822160859, 958.9041095890356, 966.0773960867355, 967.4678926590348, 975.190591808797, 19637.21451141967, 21269.397734643917, 21279.468013299793, 21327.78672213289, 21529.67705484419, 958.7201086549395, 966.8791401074866, 975.2295686407658, 991.3407583956588, 992.846108853129, 10979.28634638746, 11053.0, 11440.542378304941, 11522.366269855238, 11698.94150529242, 582458.0, 587643.0, 609277.0, 676736.0, 782987.0, 94530.52734736282, 100570.4971475138, 111692.0, 112070.43964780286, 113996.0, 1205.4876677412065, 1267.9366031698562, 1271.1356277731047, 1283.8459384873688, 1292.101989117573, 14223.644408889446, 17980.640387192445, 19467.123979420678, 19568.0, 19778.406647800588, 53655.0, 53676.73161634167, 54478.0, 54554.0, 55261.7236913829, 64893.67553162204, 65925.0, 67946.66026669835, 68030.65984670045, 70443.0, 12273.87726122763, 12324.50701971947, 12519.248845069325, 12533.87466125327, 12621.179623324455, 13623.250721210428, 13978.930105349407, 14076.155430674193, 14344.713105738036, 14361.0, 11995.76008479843, 12021.579244726392, 12368.690782730504, 12431.75136497247, 12449.564265250672, 11198.10415166801, 11357.148213884157, 11633.185677002528, 11767.176297659082, 11893.405329733652, 12104.394780261127, 12316.938415307866, 12513.249205047727, 12682.175658582162, 12762.936185319015, 12480.438380273092, 12694.111412201057, 12725.490980360682, 12752.617421477371, 12785.488580456678, 12307.630771076882, 12344.567940122053, 12593.559225427067, 12689.048321375749, 12854.55009074678], "value": 11333.70154600796, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1539.2765400261874, 1564.6401327694623, 1573.119053330135, 1584.8969816961896, 1585.1994742654958, 11038.613648522303, 11742.882571174287, 11753.236039657428, 11754.059675225983, 11756.353400562977, 11424.371659558721, 11523.711907202323, 11708.121890858178, 11710.180287379886, 11711.648650540476, 2459.0, 2492.22740950306, 2495.563276426627, 2497.1884137655243, 2497.5254701606727, 2357.4813541021035, 2388.7850093491584, 2388.868612226327, 2390.8206884483648, 2398.3164798032562, 2322.477442575424, 2379.4051487128218, 2388.773066558683, 2404.8557086574774, 2406.458546826959, 2279.0, 2326.685897403863, 2351.2123438647986, 2358.2689366296504, 2359.834811563191, 2329.906803727849, 2338.8012018978407, 2346.296111166643, 2351.1888398502488, 2351.988240058789, 11604.0, 11905.821412678817, 11929.403529823474, 11941.0, 11952.760944781143, 5803.21656576361, 5953.642781433128, 5958.97020514899, 5962.016267315931, 5964.701764911737, 3285.2772390074147, 3348.1462227131924, 3405.676460736226, 3407.369636617234, 3456.585209774843, 1262.5391732017747, 1269.7587458382877, 1295.1840340585466, 1311.2591385866954, 1324.4304948871982, 14664.560063198121, 15066.0, 16604.58488537784, 17815.73276400855, 17974.191161397768, 4654.394928659252, 4672.275797251432, 4801.855944321606, 4810.6151507879285, 4816.133096042676, 11323.773524529628, 11563.88436115645, 12653.177543459646, 13428.798568021488, 13450.260235686936, 5783.1325301204315, 5905.734241959122, 5907.911381329283, 5914.378990206053, 5918.082697181858, 5431.945680543144, 5462.0441422751655, 5463.043967305705, 5467.234587157801, 5477.096279113981, 4044.211378781168, 4133.028738246509, 4169.45797046382, 4170.979145104255, 4206.326987682018, 1031.4842578710645, 1032.5456799008412, 1043.7651528406047, 1047.7956798424234, 1051.4427353502656, 843.717354686187, 856.5331894117596, 857.9485230886166, 865.7272959017889, 890.3767362846033, 6854.760083397157, 6867.862642747117, 6934.653267336714, 6971.58170509771, 7127.92872071283, 1033.679559336608, 1054.7995880782628, 1054.8734151901667, 1074.6292529077455, 1080.8973147551128, 4092.242935057024, 4167.333226683716, 4224.704270701021, 4229.386738922839, 4285.935710964338, 14396.640083997774, 14927.850721492863, 14953.775693364609, 14956.700865982837, 14959.476418325525, 14126.0, 14668.486602968846, 14842.851571484363, 14858.405663773545, 14865.70268594622, 10863.18526110545, 11113.888861111285, 11169.888301117047, 11175.050120739666, 11182.32906025641, 6854.34883686049, 7108.111486064242, 7156.534825236343, 7174.605396703231, 7216.603086830275, 13386.866131338951, 13543.05198636086, 13579.117357371739, 13682.863171368159, 13686.589402317946, 13475.124116932368, 13520.59438216855, 13604.93197534006, 13656.795148072588, 13664.93167534156, 8823.205911467998, 9018.143276388733, 9785.559649815634, 10012.69961901144, 10310.32982856112, 11348.092152627934, 11351.886481135083, 11352.375619340684, 11461.140414469115, 11522.0, 9474.478903660365, 9500.382475139093, 9559.426434413957, 9625.71122866315, 9770.218382529523, 9290.16388525036, 9355.06449355059, 9467.289953253672, 9548.236141108555, 9625.951870240604, 8586.184312490303, 8599.011113722003, 8739.781505462413, 8754.29965602763, 8805.339599530189, 5984.491318237999, 5984.940150598438, 5996.0, 6009.729562169626, 6046.365131661112, 6250.249970003629, 6258.624482531062, 6332.0818481320875, 6428.8071357859335, 6465.709043092978], "value": 5369.439338866973, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.8270984831446, 410.92101623928244, 414.48810718762326, 414.8382130968921, 423.105132644457, 5236.973815130919, 5279.340082489689, 5307.442718514559, 5350.5184533391985, 5363.249145119681, 5123.231515272711, 5219.269302297681, 5229.450907654698, 5231.293775340328, 5236.90523790484, 4288.448373217931, 4402.493713222988, 4408.4048653431855, 4420.911581768362, 4443.0, 4384.816099653091, 4386.342048692705, 4389.407429996957, 4398.712669411904, 4412.271975124101, 4388.802503887317, 4398.846040388587, 4402.647788176938, 4428.712133711315, 4460.4647442306805, 4200.894977625553, 4306.0, 4343.1747967886, 4372.737635741865, 4414.624756895651, 4048.271311163989, 4065.715399922007, 4069.877903662895, 4072.2715780427184, 4079.204555111725, 5094.363204599425, 5096.216324286507, 5116.897662046776, 5130.48650648056, 5152.819651312186, 4946.183879659852, 5076.339881032659, 5078.542931136179, 5120.6927584344685, 5152.0, 5425.240466334716, 5485.670859748428, 5491.28613280271, 5509.171126067385, 5520.696361700105, 4583.6562257830465, 4593.494715581283, 4626.653001024936, 4634.2353511670535, 4645.674802763808, 5577.721113944286, 5702.971485142548, 5737.684427356494, 5742.799002034951, 5776.566757493205, 4734.28985652148, 4741.739204343725, 4747.8575642730775, 4748.548887855648, 4771.33201351811, 4798.928016079762, 4820.3128904883715, 4838.903221935612, 4842.564169224716, 4856.78144483492, 4912.091263116335, 5079.3396858408905, 5091.137963245092, 5095.140273800111, 5150.4849515048945, 5461.907618476321, 5478.123500239943, 5478.342598888159, 5482.835514934558, 5541.307336582927, 1659.468969929635, 1666.616678164029, 1676.2205574407958, 1677.4399808178268, 1678.1441464853012, 1263.8357013588359, 1289.7613941420868, 1291.6964513339358, 1294.2622705058027, 1303.8500572434102, 5425.864353391197, 5470.179473078991, 5499.7250137492965, 5503.834884953458, 5523.585731070106, 5450.454954504517, 5598.844444888748, 5704.4295570443455, 5763.077907534775, 5794.710264486759, 5330.9604627098115, 5366.517013468807, 5366.6779993200535, 5385.219143224211, 5398.271233383522, 2943.3818898031654, 2949.1594895455123, 2974.3753811699353, 2980.8658610362586, 3005.63932328122, 5614.24207731951, 5819.679917604572, 5867.501262392744, 5884.558658100659, 5894.646321220741, 5511.889762204733, 5722.856928576819, 5750.913736293959, 5787.627072900091, 5822.912656310159, 5669.518090962314, 5707.885842283131, 5843.954615884407, 5870.26621672291, 5892.499137573354, 5650.830475085754, 5655.971720141538, 5667.1782591524825, 5672.092465205547, 5728.369879313355, 5223.947760522498, 5425.782968681208, 5548.805791797267, 5551.22282880389, 5647.378788333198, 5827.9417205829095, 5831.271091113611, 5849.385814489588, 5850.385709500441, 5876.500497457764, 4534.863954081383, 4704.411948506437, 4745.335653008513, 4768.356271903249, 4778.5699287064335, 5654.575906806924, 5672.4547158026235, 5695.206010106803, 5698.572607054404, 5704.714764261687, 5560.19377190297, 5563.471470210324, 5584.357798853184, 5600.439956004367, 5603.299587551556, 5284.154535274338, 5433.293671822637, 5454.699991500347, 5454.9727251365075, 5522.337319521523, 5503.7798488060025, 5508.173773933942, 5520.779168833202, 5533.667979921218, 5574.275344205227, 5312.654677445954, 5557.972210138923, 5570.212255666186, 5580.149696709258, 5644.266245388238, 5305.469453054664, 5331.466853314638, 5411.2965314508865, 5428.538574221077, 5437.700926449083], "value": 4442.235381385561, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [782.8786538086591, 810.975670729878, 811.3711873298195, 820.5815034332488, 828.5235989306151, 16765.413210537634, 17114.657706845857, 17152.228149733277, 17224.3110275589, 17402.0, 16346.427875024376, 16915.06967116808, 17050.06224657643, 17063.40278090267, 17081.658366832657, 851.9275861551776, 856.3149480415674, 875.8248350329927, 877.6533269358599, 878.8110556230409, 871.79948611819, 872.0276891266225, 872.7250915961483, 872.9956350218272, 873.7378786364065, 928.1600151862575, 929.3773171974759, 930.9301802364815, 932.2355668351954, 932.4451951089125, 927.6243121535789, 927.8005228875824, 928.3826255540058, 928.7863791327965, 928.8049509602992, 681.0261326303385, 682.1847891769338, 683.3917813146304, 683.7846078485261, 683.8905775076014, 16655.41706040283, 16663.750043749355, 16748.916255418644, 18972.14625341863, 19171.712424313646, 14553.490627827976, 15743.134127622976, 15928.0, 16040.35838566456, 16349.0, 13298.468061277634, 13354.26551539665, 13469.259190744506, 13564.253966031863, 13817.861821381757, 4648.930266046012, 4702.459217190033, 4703.529647035269, 4745.501722319142, 4798.616110711135, 49775.0, 65249.67375163141, 65514.0, 86997.99999999937, 93547.53226233825, 11894.82157767634, 12167.75664486723, 12380.195287306477, 12500.687482813002, 12566.246025238515, 18886.3389781357, 19580.510487237934, 19593.510162245773, 19595.804041959396, 21693.34919952404, 8624.956875215585, 8655.394122411497, 8713.825723485495, 8795.956020219857, 8970.282377409792, 4922.212446008622, 5154.690718556899, 5162.380514338229, 5209.453007434165, 5290.5238528531845, 3683.9079023024638, 3750.3436898542645, 3813.1992281620614, 3815.980920095382, 3830.616938306147, 590.6190507122882, 592.602956019468, 601.4046094366546, 601.530805971343, 612.3142080869454, 712.8004158835535, 715.7530651925077, 718.9352958233679, 728.5410191579272, 740.4224704730211, 10689.358638481715, 10776.245662803685, 10780.51487683056, 10868.891311086945, 11110.555577776799, 610.578700696518, 612.6446660936646, 624.0545573456218, 628.1864984844638, 636.1316802564467, 5148.588112950955, 5241.502057304626, 5342.519173274424, 5352.946470535245, 5370.650907690988, 68291.0, 75926.62036689781, 80247.0, 88295.0, 91076.5446172765, 69329.65335173393, 70082.0, 70117.0, 71165.0, 75462.0, 62478.37521624816, 62721.37278627246, 62923.0, 63594.0, 63741.681291593246, 10305.690829275134, 10370.896291037296, 10664.680059598224, 10706.304090234109, 10710.946445267724, 44545.55454445414, 44770.0, 45538.54461455343, 45742.771286144685, 46380.7680961593, 52655.0, 52679.47320526746, 53367.0, 53832.0, 55101.0, 12679.492820287085, 12682.619521414372, 12766.786960302892, 12802.231866088065, 13094.0, 15207.315670795062, 15286.617834554225, 15415.0, 15515.069095854285, 15759.369625215322, 14362.353694084, 14583.12501249886, 14612.123272603678, 15034.624134396727, 15545.844541554892, 14350.569482915529, 14911.0, 15143.545693629208, 15159.393624254906, 15185.848141518443, 14223.928880355947, 14249.643758905695, 14992.100473971597, 15020.549383518512, 15027.624309392353, 9984.151347135576, 10107.54516046765, 10240.385576865412, 10491.475426228812, 10808.621698240524, 11785.941070294593, 11901.880981190423, 12201.877981220074, 12248.510059597516, 12260.080493963167], "value": 7650.982293534827, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1448.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 111.0, 111.0, 114.0, 112.0, 112.0, 113.0, 113.0, 114.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 117.0, 116.0, 154.0, 117.0, 115.0, 117.0, 117.0, 132.0, 115.0], "value": 124.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 170.0, 161.0, 161.0, 161.0, 161.0, 163.0, 162.0, 161.0, 160.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 103.0, 77.0, 77.0, 77.0, 76.0, 95.0, 75.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 102.0, 81.0, 82.0, 82.0, 91.0, 81.0, 81.0, 106.0], "value": 86.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 115.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 145.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 93.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 54.0, 54.0, 55.0, 55.0, 54.0, 54.0, 55.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 52.0, 52.0, 52.0, 53.0, 54.0, 51.0, 52.0], "value": 52.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 84.0, 83.0, 77.0, 79.0, 79.0, 76.0, 76.0, 75.0, 76.0], "value": 78.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 120.0, 126.0, 130.0, 118.0, 119.0, 118.0, 117.0, 158.0, 132.0], "value": 126.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 161.0, 164.0, 160.0, 158.0, 157.0, 157.0, 159.0, 159.0], "value": 159.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 60.0, 60.0, 59.0, 59.0, 60.0, 60.0, 60.0, 59.0, 59.0], "value": 60.0, "unit": "score"}]}]} 03:25:17 INFO - Return code: 0 03:25:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:25:17 ERROR - # TBPL SUCCESS # 03:25:17 INFO - Running post-action listener: _resource_record_post_action 03:25:17 INFO - [mozharness: 2017-04-06 10:25:17.562022Z] Finished run-tests step (success) 03:25:17 INFO - Running post-run listener: _resource_record_post_run 03:25:17 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 266240; Write bytes: 365387776; Read time: 28; Write time: 202996 03:25:17 INFO - TinderboxPrint: CPU usage
13.1% 03:25:17 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 03:25:17 INFO - TinderboxPrint: I/O write bytes / time
365,387,776 / 202,996 03:25:17 INFO - TinderboxPrint: CPU idle
8,270.8 (86.9%) 03:25:17 INFO - TinderboxPrint: CPU user
1,203.5 (12.6%) 03:25:17 INFO - TinderboxPrint: Swap in / out
0 / 0 03:25:17 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 112844800; Read time: 0; Write time: 79880 03:25:17 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 266240; Write bytes: 252542976; Read time: 28; Write time: 123116 03:25:18 INFO - Running post-run listener: _upload_blobber_files 03:25:18 INFO - Blob upload gear active. 03:25:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:25: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 03:25: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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:25: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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:25:18 INFO - (blobuploader) - INFO - Open directory for files ... 03:25:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:25:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:25:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:25:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:25:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:25:19 INFO - (blobuploader) - INFO - Done attempting. 03:25:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:25:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:25:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:25:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:25:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:25:20 INFO - (blobuploader) - INFO - Done attempting. 03:25:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:25:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:25:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:25:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:25:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:25:21 INFO - (blobuploader) - INFO - Done attempting. 03:25:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:25:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:25:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:25:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:25:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:25:24 INFO - (blobuploader) - INFO - Done attempting. 03:25:24 INFO - (blobuploader) - INFO - Iteration through files over. 03:25:24 INFO - Return code: 0 03:25:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:25:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:25:24 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/6e56e5c97159c9fa4bb34c3b896d5b92bf075b1dfd4bfe41d69f1c9cf7a0e86a0d7c45eaec3454e903c64f2bd51ff75909d165458707cfe4dc93a5125bc864d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e66bf00fd117930cef97e813d54c8024444b9775c032cfefe4de49919dc82d1277e2df4c67b16aa6e701d650c355dc5db440a12ba4c9cd2899eb54931fc3a40b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/be141688ee6e5dbd543ed932cd4e3cc80fbde906fe8859a3ba0a668c602a7a21623cabb0c586d58022ef716578f6c7c8923e8bea58bdb553ec180b85a3a5edfc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/daa2a0b4bf493fb3588cc58468c280230513c5dd2afc135878b7274b8d3797b5d13d174794eaf5574c283c4c41ca056ce019b1cbfbfb8aff3beb9c69395da0b7"} 03:25:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:25:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:25:24 INFO - Contents: 03:25:24 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/6e56e5c97159c9fa4bb34c3b896d5b92bf075b1dfd4bfe41d69f1c9cf7a0e86a0d7c45eaec3454e903c64f2bd51ff75909d165458707cfe4dc93a5125bc864d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e66bf00fd117930cef97e813d54c8024444b9775c032cfefe4de49919dc82d1277e2df4c67b16aa6e701d650c355dc5db440a12ba4c9cd2899eb54931fc3a40b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/be141688ee6e5dbd543ed932cd4e3cc80fbde906fe8859a3ba0a668c602a7a21623cabb0c586d58022ef716578f6c7c8923e8bea58bdb553ec180b85a3a5edfc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/daa2a0b4bf493fb3588cc58468c280230513c5dd2afc135878b7274b8d3797b5d13d174794eaf5574c283c4c41ca056ce019b1cbfbfb8aff3beb9c69395da0b7"} 03:25:24 INFO - Running post-run listener: copy_logs_to_upload_dir 03:25:24 INFO - Copying logs to upload dir... 03:25:24 INFO - mkdir: /builds/slave/test/build/upload/logs 03:25:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.489076 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-04-06 03:25:24.514047) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 03:25:24.521018) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348 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-Non-PGO/sha512/6e56e5c97159c9fa4bb34c3b896d5b92bf075b1dfd4bfe41d69f1c9cf7a0e86a0d7c45eaec3454e903c64f2bd51ff75909d165458707cfe4dc93a5125bc864d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e66bf00fd117930cef97e813d54c8024444b9775c032cfefe4de49919dc82d1277e2df4c67b16aa6e701d650c355dc5db440a12ba4c9cd2899eb54931fc3a40b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/be141688ee6e5dbd543ed932cd4e3cc80fbde906fe8859a3ba0a668c602a7a21623cabb0c586d58022ef716578f6c7c8923e8bea58bdb553ec180b85a3a5edfc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/daa2a0b4bf493fb3588cc58468c280230513c5dd2afc135878b7274b8d3797b5d13d174794eaf5574c283c4c41ca056ce019b1cbfbfb8aff3beb9c69395da0b7"} build_url:https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011218 build_url: 'https://queue.taskcluster.net/v1/task/AiYYT0HmT5Ck8k9WedpW3Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/6e56e5c97159c9fa4bb34c3b896d5b92bf075b1dfd4bfe41d69f1c9cf7a0e86a0d7c45eaec3454e903c64f2bd51ff75909d165458707cfe4dc93a5125bc864d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e66bf00fd117930cef97e813d54c8024444b9775c032cfefe4de49919dc82d1277e2df4c67b16aa6e701d650c355dc5db440a12ba4c9cd2899eb54931fc3a40b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/be141688ee6e5dbd543ed932cd4e3cc80fbde906fe8859a3ba0a668c602a7a21623cabb0c586d58022ef716578f6c7c8923e8bea58bdb553ec180b85a3a5edfc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/daa2a0b4bf493fb3588cc58468c280230513c5dd2afc135878b7274b8d3797b5d13d174794eaf5574c283c4c41ca056ce019b1cbfbfb8aff3beb9c69395da0b7"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 03:25:24.545833) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:25:24.546139) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1491473071.415360-411494348 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004529 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:25:24.628508) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 03:25:24.628866) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 03:25:24.629150) ========= ========= Total master_lag: 0.16 =========