builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-076 starttime: 1502485607.87 results: success (0) revision: 80ff3f300e05f38f96c385b03d1973a966a2bd35 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-11 14:06:47.870521) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-11 14:06:47.871000) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-11 14:06:47.871278) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-11 14:06:47.887683) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 14:06:47.888048) ========= 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=9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-11 14:06:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.6M=0s 2017-08-11 14:06:48 (42.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.102259 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 14:06:48.003866) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 14:06:48.004179) ========= 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=9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018534 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 14:06:48.053516) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-11 14:06:48.056939) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 80ff3f300e05f38f96c385b03d1973a966a2bd35 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 80ff3f300e05f38f96c385b03d1973a966a2bd35 --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=9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-11 14:06:48,388 truncating revision to first 12 chars 2017-08-11 14:06:48,388 Setting DEBUG logging. 2017-08-11 14:06:48,388 attempt 1/10 2017-08-11 14:06:48,388 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/80ff3f300e05?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-11 14:06:48,888 unpacking tar archive at: mozilla-central-80ff3f300e05/testing/mozharness/ program finished with exit code 0 elapsedTime=1.069264 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-11 14:06:49.142829) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-11 14:06:49.143226) ========= script_repo_revision: 80ff3f300e05f38f96c385b03d1973a966a2bd35 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-11 14:06:49.144297) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-11 14:06:49.144586) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-11 14:06:49.156698) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-08-11 14:06:49.157076) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:06:49 INFO - MultiFileLogger online at 20170811 14:06:49 in /builds/slave/test 14:06:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:06:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:06:49 INFO - {'append_to_log': False, 14:06:49 INFO - 'base_work_dir': '/builds/slave/test', 14:06:49 INFO - 'blob_upload_branch': 'Firefox', 14:06:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:06:49 INFO - 'branch': 'Firefox', 14:06:49 INFO - 'buildbot_json_path': 'buildprops.json', 14:06:49 INFO - 'code_coverage': False, 14:06:49 INFO - 'config_files': ('talos/linux_config.py',), 14:06:49 INFO - 'default_actions': ('clobber', 14:06:49 INFO - 'read-buildbot-config', 14:06:49 INFO - 'download-and-extract', 14:06:49 INFO - 'populate-webroot', 14:06:49 INFO - 'create-virtualenv', 14:06:49 INFO - 'install', 14:06:49 INFO - 'setup-mitmproxy', 14:06:49 INFO - 'run-tests'), 14:06:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:06:49 INFO - 'disable_ccov_upload': False, 14:06:49 INFO - 'download_minidump_stackwalk': True, 14:06:49 INFO - 'download_symbols': 'ondemand', 14:06:49 INFO - 'enable_stylo': False, 14:06:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:06:49 INFO - 'tooltool.py': '/tools/tooltool.py', 14:06:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:06:49 INFO - '/tools/misc-python/virtualenv.py')}, 14:06:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:06:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:06:49 INFO - 'gecko_profile': False, 14:06:49 INFO - 'gecko_profile_interval': 0, 14:06:49 INFO - 'installer_path': 'installer.exe', 14:06:49 INFO - 'log_level': 'info', 14:06:49 INFO - 'log_name': 'talos', 14:06:49 INFO - 'log_to_console': True, 14:06:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:06:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:06:49 INFO - 'opt_config_files': (), 14:06:49 INFO - 'pip_index': False, 14:06:49 INFO - 'suite': 'dromaeojs-e10s', 14:06:49 INFO - 'system_bits': '32', 14:06:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:06:49 INFO - 'title': 'talos-linux64-ix-076', 14:06:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:06:49 INFO - 'use_talos_json': True, 14:06:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:06:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:06:49 INFO - 'work_dir': 'build'} 14:06:49 INFO - [mozharness: 2017-08-11 21:06:49.594554Z] Running clobber step. 14:06:49 INFO - Running pre-action listener: _resource_record_pre_action 14:06:49 INFO - Running main action method: clobber 14:06:49 INFO - rmtree: /builds/slave/test/build 14:06:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:06:51 INFO - Running post-action listener: _resource_record_post_action 14:06:51 INFO - [mozharness: 2017-08-11 21:06:51.234985Z] Finished clobber step (success) 14:06:51 INFO - [mozharness: 2017-08-11 21:06:51.235086Z] Running read-buildbot-config step. 14:06:51 INFO - Running pre-action listener: _resource_record_pre_action 14:06:51 INFO - Running main action method: read_buildbot_config 14:06:51 INFO - Using buildbot properties: 14:06:51 INFO - { 14:06:51 INFO - "project": "", 14:06:51 INFO - "product": "firefox", 14:06:51 INFO - "who": "kwierso@gmail.com", 14:06:51 INFO - "installer_path": "public/build/target.tar.bz2", 14:06:51 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 14:06:51 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 14:06:51 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 14:06:51 INFO - "stage_platform": "linux64-stylo", 14:06:51 INFO - "basedir": "/builds/slave/test", 14:06:51 INFO - "buildnumber": 78, 14:06:51 INFO - "platform": "ubuntu64_hw_stylo", 14:06:51 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:06:51 INFO - "slavebuilddir": "test", 14:06:51 INFO - "taskId": "P6oZgGRFS0eXgfDa8uoXSg", 14:06:51 INFO - "branch": "mozilla-central", 14:06:51 INFO - "script_repo_revision": "production", 14:06:51 INFO - "revision": "80ff3f300e05f38f96c385b03d1973a966a2bd35", 14:06:51 INFO - "slavename": "talos-linux64-ix-076", 14:06:51 INFO - "repo_path": "mozilla-central" 14:06:51 INFO - } 14:06:51 INFO - Finding installer, test and symbols from parent task. 14:06:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P6oZgGRFS0eXgfDa8uoXSg'}, attempt #1 14:06:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P6oZgGRFS0eXgfDa8uoXSg'}, attempt #1 14:06:52 INFO - Task dependencies: ciIwbBSeQxOcIhzBZHfbuA 14:06:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA'}, attempt #1 14:06:52 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.tar.bz2 14:06:52 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.test_packages.json 14:06:52 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.crashreporter-symbols.zip 14:06:52 INFO - Running post-action listener: _resource_record_post_action 14:06:52 INFO - [mozharness: 2017-08-11 21:06:52.595895Z] Finished read-buildbot-config step (success) 14:06:52 INFO - [mozharness: 2017-08-11 21:06:52.596116Z] Running download-and-extract step. 14:06:52 INFO - Running pre-action listener: _resource_record_pre_action 14:06:52 INFO - Running main action method: download_and_extract 14:06:52 INFO - mkdir: /builds/slave/test/build/tests 14:06:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:06:52 INFO - trying https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.test_packages.json 14:06:52 INFO - Downloading https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:06:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:06:53 INFO - Downloaded 989 bytes. 14:06:53 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:06:53 INFO - Using the following test package requirements: 14:06:53 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 14:06:53 INFO - u'common': [u'target.common.tests.zip'], 14:06:53 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:06:53 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:06:53 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:06:53 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:06:53 INFO - u'mozbase': [u'target.common.tests.zip'], 14:06:53 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:06:53 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:06:53 INFO - u'web-platform': [u'target.common.tests.zip', 14:06:53 INFO - u'target.web-platform.tests.tar.gz'], 14:06:53 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:06:53 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 14:06:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.common.tests.zip 14:06:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:06:53 INFO - Fetch https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.common.tests.zip into memory 14:06:54 INFO - Content-Length response header: 54036779 14:06:54 INFO - Bytes received: 54036779 14:07:05 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:07:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.common.tests.zip 14:07:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:07:05 INFO - Fetch https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.common.tests.zip into memory 14:07:06 INFO - Content-Length response header: 54036779 14:07:06 INFO - Bytes received: 54036779 14:07:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.talos.tests.zip 14:07:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:07:17 INFO - Fetch https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.talos.tests.zip into memory 14:07:18 INFO - Content-Length response header: 14496050 14:07:18 INFO - Bytes received: 14496050 14:07:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:18 INFO - trying https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.tar.bz2 14:07:18 INFO - Downloading https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:07:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:07:19 INFO - Downloaded 61811921 bytes. 14:07:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.tar.bz2 14:07:19 INFO - mkdir: /builds/slave/test/properties 14:07:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:07:19 INFO - Writing to file /builds/slave/test/properties/build_url 14:07:19 INFO - Contents: 14:07:19 INFO - build_url:https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.tar.bz2 14:07:19 INFO - Running post-action listener: _resource_record_post_action 14:07:19 INFO - Running post-action listener: set_extra_try_arguments 14:07:19 INFO - [mozharness: 2017-08-11 21:07:19.954797Z] Finished download-and-extract step (success) 14:07:19 INFO - [mozharness: 2017-08-11 21:07:19.954894Z] Running populate-webroot step. 14:07:19 INFO - Running pre-action listener: _resource_record_pre_action 14:07:19 INFO - Running main action method: populate_webroot 14:07:19 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:07:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:07:19 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:07:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:07:19 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:07:19 INFO - 'tests': ['damp', 'tps']}, 14:07:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:07:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:07:19 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:07:19 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:07:19 INFO - 'other-e10s': {'tests': ['a11yr', 14:07:19 INFO - 'ts_paint', 14:07:19 INFO - 'tpaint', 14:07:19 INFO - 'sessionrestore', 14:07:19 INFO - 'sessionrestore_many_windows', 14:07:19 INFO - 'sessionrestore_no_auto_restore', 14:07:19 INFO - 'tabpaint']}, 14:07:19 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 14:07:19 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 14:07:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:07:19 INFO - 'tsvgr_opacity', 14:07:19 INFO - 'tart', 14:07:19 INFO - 'tscrollx', 14:07:19 INFO - 'tsvg_static']}, 14:07:19 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:07:19 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:07:19 INFO - 'talos_options': ['--mitmproxy', 14:07:19 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:07:19 INFO - '--firstNonBlankPaint'], 14:07:19 INFO - 'tests': ['tp6_google', 14:07:19 INFO - 'tp6_youtube', 14:07:19 INFO - 'tp6_amazon', 14:07:19 INFO - 'tp6_facebook']}, 14:07:19 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:07:19 INFO - 'talos_options': ['--stylo', 14:07:19 INFO - '--mitmproxy', 14:07:19 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:07:19 INFO - '--firstNonBlankPaint'], 14:07:19 INFO - 'tests': ['tp6_google', 14:07:19 INFO - 'tp6_youtube', 14:07:19 INFO - 'tp6_amazon', 14:07:19 INFO - 'tp6_facebook']}, 14:07:19 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:07:19 INFO - 'talos_options': ['--stylo', 14:07:19 INFO - '--stylo-threads=1', 14:07:19 INFO - '--mitmproxy', 14:07:19 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:07:19 INFO - '--firstNonBlankPaint'], 14:07:19 INFO - 'tests': ['tp6_google', 14:07:19 INFO - 'tp6_youtube', 14:07:19 INFO - 'tp6_amazon', 14:07:19 INFO - 'tp6_facebook']}, 14:07:19 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:07:19 INFO - 'talos_options': ['--xperf_path', 14:07:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:07:19 INFO - 'tests': ['tp5n']}}} 14:07:19 INFO - Running post-action listener: _resource_record_post_action 14:07:19 INFO - [mozharness: 2017-08-11 21:07:19.963331Z] Finished populate-webroot step (success) 14:07:19 INFO - [mozharness: 2017-08-11 21:07:19.963422Z] Running create-virtualenv step. 14:07:19 INFO - Running pre-action listener: _resource_record_pre_action 14:07:19 INFO - Running main action method: create_virtualenv 14:07:19 INFO - Creating virtualenv /builds/slave/test/build/venv 14:07:19 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 14:07:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:07:19 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 14:07:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:07:20 INFO - Using real prefix '/usr' 14:07:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:07:20 INFO - Installing distribute.............................................................................................................................................................................................done. 14:07:22 INFO - Installing pip.................done. 14:07:22 INFO - Return code: 0 14:07:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:07:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:07:22 INFO - Reading from file tmpfile_stdout 14:07:22 INFO - Output received: 14:07:22 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) 14:07:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:22 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/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 0x21281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2495b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2498f00>, '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 0x2493ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2494050>, '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': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', '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 14:07:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 14:07:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:07:22 INFO - Using env: {'DISPLAY': ':0', 14:07:22 INFO - 'HOME': '/home/cltbld', 14:07:22 INFO - 'LANG': 'en_US.UTF-8', 14:07:22 INFO - 'LANGUAGE': 'en_US:en', 14:07:22 INFO - 'LOGNAME': 'cltbld', 14:07:22 INFO - 'MAIL': '/var/mail/cltbld', 14:07:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:22 INFO - 'MOZ_NO_REMOTE': '1', 14:07:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:22 INFO - 'NO_EM_RESTART': '1', 14:07:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:22 INFO - 'PWD': '/builds/slave/test', 14:07:22 INFO - 'SHELL': '/bin/bash', 14:07:22 INFO - 'SHLVL': '1', 14:07:22 INFO - 'TERM': 'linux', 14:07:22 INFO - 'TMOUT': '86400', 14:07:22 INFO - 'USER': 'cltbld', 14:07:22 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', 14:07:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:22 INFO - '_': '/tools/buildbot/bin/python'} 14:07:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:22 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 14:07:22 INFO - Cleaning up... 14:07:22 INFO - Return code: 0 14:07:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:22 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/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 0x21281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2495b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2498f00>, '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 0x2493ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2494050>, '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': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', '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 14:07:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 14:07:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:07:22 INFO - Using env: {'DISPLAY': ':0', 14:07:23 INFO - 'HOME': '/home/cltbld', 14:07:23 INFO - 'LANG': 'en_US.UTF-8', 14:07:23 INFO - 'LANGUAGE': 'en_US:en', 14:07:23 INFO - 'LOGNAME': 'cltbld', 14:07:23 INFO - 'MAIL': '/var/mail/cltbld', 14:07:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:23 INFO - 'MOZ_NO_REMOTE': '1', 14:07:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:23 INFO - 'NO_EM_RESTART': '1', 14:07:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:23 INFO - 'PWD': '/builds/slave/test', 14:07:23 INFO - 'SHELL': '/bin/bash', 14:07:23 INFO - 'SHLVL': '1', 14:07:23 INFO - 'TERM': 'linux', 14:07:23 INFO - 'TMOUT': '86400', 14:07:23 INFO - 'USER': 'cltbld', 14:07:23 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', 14:07:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:23 INFO - '_': '/tools/buildbot/bin/python'} 14:07:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:23 INFO - Downloading/unpacking psutil>=3.1.1 14:07:23 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 14:07:23 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 14:07:23 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 14:07:23 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 14:07:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:07:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:07:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:07:24 INFO - Installing collected packages: psutil 14:07:24 INFO - Running setup.py install for psutil 14:07:24 INFO - building 'psutil._psutil_linux' extension 14:07:24 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 14:07:24 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 14:07:24 INFO - building 'psutil._psutil_posix' extension 14:07:24 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 14:07:24 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 14:07:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:07:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:07:24 INFO - Successfully installed psutil 14:07:24 INFO - Cleaning up... 14:07:24 INFO - Return code: 0 14:07:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:07:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07: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/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 0x21281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2495b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2498f00>, '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 0x2493ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2494050>, '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': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', '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 14:07:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 14:07:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:07:24 INFO - Using env: {'DISPLAY': ':0', 14:07:24 INFO - 'HOME': '/home/cltbld', 14:07:24 INFO - 'LANG': 'en_US.UTF-8', 14:07:24 INFO - 'LANGUAGE': 'en_US:en', 14:07:24 INFO - 'LOGNAME': 'cltbld', 14:07:24 INFO - 'MAIL': '/var/mail/cltbld', 14:07:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:24 INFO - 'MOZ_NO_REMOTE': '1', 14:07:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:24 INFO - 'NO_EM_RESTART': '1', 14:07:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:24 INFO - 'PWD': '/builds/slave/test', 14:07:24 INFO - 'SHELL': '/bin/bash', 14:07:24 INFO - 'SHLVL': '1', 14:07:24 INFO - 'TERM': 'linux', 14:07:24 INFO - 'TMOUT': '86400', 14:07:24 INFO - 'USER': 'cltbld', 14:07:24 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', 14:07:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:24 INFO - '_': '/tools/buildbot/bin/python'} 14:07:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:07:25 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 14:07:25 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 14:07:25 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 14:07:25 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 14:07:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:07:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:07:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:07:26 INFO - Installing collected packages: mozsystemmonitor 14:07:26 INFO - Running setup.py install for mozsystemmonitor 14:07:26 INFO - Successfully installed mozsystemmonitor 14:07:26 INFO - Cleaning up... 14:07:26 INFO - Return code: 0 14:07:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:07:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:26 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/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 0x21281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2495b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2498f00>, '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 0x2493ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2494050>, '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': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', '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 14:07:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 14:07:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:07:26 INFO - Using env: {'DISPLAY': ':0', 14:07:26 INFO - 'HOME': '/home/cltbld', 14:07:26 INFO - 'LANG': 'en_US.UTF-8', 14:07:26 INFO - 'LANGUAGE': 'en_US:en', 14:07:26 INFO - 'LOGNAME': 'cltbld', 14:07:26 INFO - 'MAIL': '/var/mail/cltbld', 14:07:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:26 INFO - 'MOZ_NO_REMOTE': '1', 14:07:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:26 INFO - 'NO_EM_RESTART': '1', 14:07:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:26 INFO - 'PWD': '/builds/slave/test', 14:07:26 INFO - 'SHELL': '/bin/bash', 14:07:26 INFO - 'SHLVL': '1', 14:07:26 INFO - 'TERM': 'linux', 14:07:26 INFO - 'TMOUT': '86400', 14:07:26 INFO - 'USER': 'cltbld', 14:07:26 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', 14:07:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:26 INFO - '_': '/tools/buildbot/bin/python'} 14:07:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:26 INFO - Downloading/unpacking jsonschema==2.5.1 14:07:26 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 14:07:26 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 14:07:26 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 14:07:26 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 14:07:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:07:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:07:27 INFO - Downloading functools32-3.2.3-2.tar.gz 14:07:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:07:27 INFO - warning: no files found matching '*.txt' 14:07:27 INFO - no previously-included directories found matching 'build' 14:07:27 INFO - no previously-included directories found matching 'dist' 14:07:27 INFO - no previously-included directories found matching '.git*' 14:07:27 INFO - Installing collected packages: jsonschema, functools32 14:07:27 INFO - Running setup.py install for functools32 14:07:28 INFO - warning: no files found matching '*.txt' 14:07:28 INFO - no previously-included directories found matching 'build' 14:07:28 INFO - no previously-included directories found matching 'dist' 14:07:28 INFO - no previously-included directories found matching '.git*' 14:07:28 INFO - Successfully installed jsonschema functools32 14:07:28 INFO - Cleaning up... 14:07:28 INFO - Return code: 0 14:07:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:07:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:28 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/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 0x21281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2495b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2498f00>, '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 0x2493ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2494050>, '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': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', '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 14:07:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 14:07:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:07:28 INFO - Using env: {'DISPLAY': ':0', 14:07:28 INFO - 'HOME': '/home/cltbld', 14:07:28 INFO - 'LANG': 'en_US.UTF-8', 14:07:28 INFO - 'LANGUAGE': 'en_US:en', 14:07:28 INFO - 'LOGNAME': 'cltbld', 14:07:28 INFO - 'MAIL': '/var/mail/cltbld', 14:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:28 INFO - 'MOZ_NO_REMOTE': '1', 14:07:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:28 INFO - 'NO_EM_RESTART': '1', 14:07:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:28 INFO - 'PWD': '/builds/slave/test', 14:07:28 INFO - 'SHELL': '/bin/bash', 14:07:28 INFO - 'SHLVL': '1', 14:07:28 INFO - 'TERM': 'linux', 14:07:28 INFO - 'TMOUT': '86400', 14:07:28 INFO - 'USER': 'cltbld', 14:07:28 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', 14:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:28 INFO - '_': '/tools/buildbot/bin/python'} 14:07:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:07:28 INFO - Cleaning up... 14:07:28 INFO - Return code: 0 14:07:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:07:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:28 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/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 0x21281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2495b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2498f00>, '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 0x2493ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2494050>, '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': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', '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 14:07:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 14:07:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:07:28 INFO - Using env: {'DISPLAY': ':0', 14:07:28 INFO - 'HOME': '/home/cltbld', 14:07:28 INFO - 'LANG': 'en_US.UTF-8', 14:07:28 INFO - 'LANGUAGE': 'en_US:en', 14:07:28 INFO - 'LOGNAME': 'cltbld', 14:07:28 INFO - 'MAIL': '/var/mail/cltbld', 14:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:28 INFO - 'MOZ_NO_REMOTE': '1', 14:07:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:28 INFO - 'NO_EM_RESTART': '1', 14:07:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:28 INFO - 'PWD': '/builds/slave/test', 14:07:28 INFO - 'SHELL': '/bin/bash', 14:07:28 INFO - 'SHLVL': '1', 14:07:28 INFO - 'TERM': 'linux', 14:07:28 INFO - 'TMOUT': '86400', 14:07:28 INFO - 'USER': 'cltbld', 14:07:28 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', 14:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:28 INFO - '_': '/tools/buildbot/bin/python'} 14:07:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:28 INFO - Downloading/unpacking blobuploader==1.2.4 14:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:07:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:07:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:07:29 INFO - Downloading blobuploader-1.2.4.tar.gz 14:07:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:07:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:07:29 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 14:07:29 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 14:07:29 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 14:07:29 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 14:07:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:07:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:07:30 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 14:07:30 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 14:07:30 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 14:07:30 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 14:07:30 INFO - Downloading docopt-0.6.1.tar.gz 14:07:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:07:30 INFO - Installing collected packages: blobuploader, requests, docopt 14:07:30 INFO - Running setup.py install for blobuploader 14:07:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:07:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:07:30 INFO - Running setup.py install for requests 14:07:30 INFO - Running setup.py install for docopt 14:07:30 INFO - Successfully installed blobuploader requests docopt 14:07:30 INFO - Cleaning up... 14:07:30 INFO - Return code: 0 14:07:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:07:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:30 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/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 0x21281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2495b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2498f00>, '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 0x2493ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2494050>, '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': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', '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 14:07:30 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:07:30 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:07:30 INFO - Using env: {'DISPLAY': ':0', 14:07:30 INFO - 'HOME': '/home/cltbld', 14:07:30 INFO - 'LANG': 'en_US.UTF-8', 14:07:30 INFO - 'LANGUAGE': 'en_US:en', 14:07:30 INFO - 'LOGNAME': 'cltbld', 14:07:30 INFO - 'MAIL': '/var/mail/cltbld', 14:07:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:30 INFO - 'MOZ_NO_REMOTE': '1', 14:07:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:30 INFO - 'NO_EM_RESTART': '1', 14:07:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:30 INFO - 'PWD': '/builds/slave/test', 14:07:30 INFO - 'SHELL': '/bin/bash', 14:07:30 INFO - 'SHLVL': '1', 14:07:30 INFO - 'TERM': 'linux', 14:07:30 INFO - 'TMOUT': '86400', 14:07:30 INFO - 'USER': 'cltbld', 14:07:30 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', 14:07:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:30 INFO - '_': '/tools/buildbot/bin/python'} 14:07:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:07:31 INFO - Running setup.py (path:/tmp/pip-LeKTXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:07:31 INFO - Running setup.py (path:/tmp/pip-xlNH8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:07:31 INFO - Running setup.py (path:/tmp/pip-GnmxNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:07:31 INFO - Running setup.py (path:/tmp/pip-GQHJDz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:07:31 INFO - Running setup.py (path:/tmp/pip-8w3UkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:07:31 INFO - Running setup.py (path:/tmp/pip-UL6a50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:07:31 INFO - Running setup.py (path:/tmp/pip-16J0ss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:07:31 INFO - Running setup.py (path:/tmp/pip-H3DeBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:07:31 INFO - Running setup.py (path:/tmp/pip-DKfnyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:07:31 INFO - Running setup.py (path:/tmp/pip-0PwnZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:07:31 INFO - Running setup.py (path:/tmp/pip-I_hHHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:07:31 INFO - Running setup.py (path:/tmp/pip-VoxDvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:07:31 INFO - Running setup.py (path:/tmp/pip-vd7fhi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:07:31 INFO - Running setup.py (path:/tmp/pip-ARDsxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:07:32 INFO - Running setup.py (path:/tmp/pip-EIxdie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:07:32 INFO - Running setup.py (path:/tmp/pip-7ZThWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:07:32 INFO - Running setup.py (path:/tmp/pip-xMEtjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:07:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:07:32 INFO - Running setup.py install for manifestparser 14:07:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:07:32 INFO - Running setup.py install for mozcrash 14:07:32 INFO - Running setup.py install for mozdebug 14:07:32 INFO - Running setup.py install for mozdevice 14:07:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:07:32 INFO - Running setup.py install for mozfile 14:07:32 INFO - Running setup.py install for mozhttpd 14:07:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:07:32 INFO - Running setup.py install for mozinfo 14:07:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:07:32 INFO - Running setup.py install for mozInstall 14:07:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:07:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:07:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:07:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:07:33 INFO - Running setup.py install for mozleak 14:07:33 INFO - Running setup.py install for mozlog 14:07:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:07:33 INFO - Running setup.py install for moznetwork 14:07:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:07:33 INFO - Running setup.py install for mozprocess 14:07:33 INFO - Running setup.py install for mozprofile 14:07:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:07:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:07:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:07:33 INFO - Running setup.py install for mozrunner 14:07:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:07:33 INFO - Running setup.py install for mozscreenshot 14:07:33 INFO - Running setup.py install for moztest 14:07:33 INFO - Running setup.py install for mozversion 14:07:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:07:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:07:33 INFO - Cleaning up... 14:07:34 INFO - Return code: 0 14:07:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:07:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:34 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/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 0x21281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2495b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2498f00>, '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 0x2493ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2494050>, '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': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', '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 14:07:34 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:07:34 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:07:34 INFO - Using env: {'DISPLAY': ':0', 14:07:34 INFO - 'HOME': '/home/cltbld', 14:07:34 INFO - 'LANG': 'en_US.UTF-8', 14:07:34 INFO - 'LANGUAGE': 'en_US:en', 14:07:34 INFO - 'LOGNAME': 'cltbld', 14:07:34 INFO - 'MAIL': '/var/mail/cltbld', 14:07:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:34 INFO - 'MOZ_NO_REMOTE': '1', 14:07:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:34 INFO - 'NO_EM_RESTART': '1', 14:07:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:34 INFO - 'PWD': '/builds/slave/test', 14:07:34 INFO - 'SHELL': '/bin/bash', 14:07:34 INFO - 'SHLVL': '1', 14:07:34 INFO - 'TERM': 'linux', 14:07:34 INFO - 'TMOUT': '86400', 14:07:34 INFO - 'USER': 'cltbld', 14:07:34 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', 14:07:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:34 INFO - '_': '/tools/buildbot/bin/python'} 14:07:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:07:34 INFO - Running setup.py (path:/tmp/pip-eAV0FY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:07:34 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)) 14:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:07:34 INFO - Running setup.py (path:/tmp/pip-oblml0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:07:34 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)) 14:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:07:34 INFO - Running setup.py (path:/tmp/pip-RpgnOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:07:34 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)) 14:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:07:34 INFO - Running setup.py (path:/tmp/pip-bcnzUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:07:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 14:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:07:34 INFO - Running setup.py (path:/tmp/pip-Kc7_BJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:07:34 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)) 14:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:07:34 INFO - Running setup.py (path:/tmp/pip-sajpB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:07:34 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)) 14:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:07:34 INFO - Running setup.py (path:/tmp/pip-iwJjed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:07:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 14:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:07:34 INFO - Running setup.py (path:/tmp/pip-3uCq02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:07:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 14:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:07:34 INFO - Running setup.py (path:/tmp/pip-ZgJg4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:07:34 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)) 14:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:07:34 INFO - Running setup.py (path:/tmp/pip-EEgt7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:07:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 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)) 14:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:07:34 INFO - Running setup.py (path:/tmp/pip-7myS7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:07:35 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)) 14:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:07:35 INFO - Running setup.py (path:/tmp/pip-qzpZmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:07:35 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)) 14:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:07:35 INFO - Running setup.py (path:/tmp/pip-rZGLqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:07:35 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)) 14:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:07:35 INFO - Running setup.py (path:/tmp/pip-8qe2S4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:07:35 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)) 14:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:07:35 INFO - Running setup.py (path:/tmp/pip-xZIzbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:07:35 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)) 14:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:07:35 INFO - Running setup.py (path:/tmp/pip-esRgW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:07:35 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)) 14:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:07:35 INFO - Running setup.py (path:/tmp/pip-cN98Ka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:07:35 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)) 14:07:35 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)) 14:07:35 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)) 14:07:35 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)) 14:07:35 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:07:35 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:07:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:07:35 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 14:07:35 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 14:07:35 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 14:07:35 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 14:07:36 INFO - Downloading blessings-1.6.tar.gz 14:07:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:07:36 INFO - Installing collected packages: blessings 14:07:36 INFO - Running setup.py install for blessings 14:07:36 INFO - Successfully installed blessings 14:07:36 INFO - Cleaning up... 14:07:36 INFO - Return code: 0 14:07:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:07:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:07:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:07:37 INFO - Reading from file tmpfile_stdout 14:07:37 INFO - Current package versions: 14:07:37 INFO - argparse == 1.2.1 14:07:37 INFO - blessings == 1.6 14:07:37 INFO - blobuploader == 1.2.4 14:07:37 INFO - docopt == 0.6.1 14:07:37 INFO - functools32 == 3.2.3-2 14:07:37 INFO - jsonschema == 2.5.1 14:07:37 INFO - manifestparser == 1.1 14:07:37 INFO - mozInstall == 1.13 14:07:37 INFO - mozcrash == 1.0 14:07:37 INFO - mozdebug == 0.1 14:07:37 INFO - mozdevice == 0.51 14:07:37 INFO - mozfile == 1.2 14:07:37 INFO - mozhttpd == 0.7 14:07:37 INFO - mozinfo == 0.10 14:07:37 INFO - mozleak == 0.1 14:07:37 INFO - mozlog == 3.5 14:07:37 INFO - moznetwork == 0.27 14:07:37 INFO - mozprocess == 0.25 14:07:37 INFO - mozprofile == 0.28 14:07:37 INFO - mozrunner == 6.13 14:07:37 INFO - mozscreenshot == 0.1 14:07:37 INFO - mozsystemmonitor == 0.3 14:07:37 INFO - moztest == 0.8 14:07:37 INFO - mozversion == 1.4 14:07:37 INFO - psutil == 3.1.1 14:07:37 INFO - requests == 1.2.3 14:07:37 INFO - wsgiref == 0.1.2 14:07:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:07:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:37 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/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 0x21281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2495b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2498f00>, '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 0x2493ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2494050>, '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': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', '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 14:07:37 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:07:37 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:07:37 INFO - Using env: {'DISPLAY': ':0', 14:07:37 INFO - 'HOME': '/home/cltbld', 14:07:37 INFO - 'LANG': 'en_US.UTF-8', 14:07:37 INFO - 'LANGUAGE': 'en_US:en', 14:07:37 INFO - 'LOGNAME': 'cltbld', 14:07:37 INFO - 'MAIL': '/var/mail/cltbld', 14:07:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:37 INFO - 'MOZ_NO_REMOTE': '1', 14:07:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:37 INFO - 'NO_EM_RESTART': '1', 14:07:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:37 INFO - 'PWD': '/builds/slave/test', 14:07:37 INFO - 'SHELL': '/bin/bash', 14:07:37 INFO - 'SHLVL': '1', 14:07:37 INFO - 'TERM': 'linux', 14:07:37 INFO - 'TMOUT': '86400', 14:07:37 INFO - 'USER': 'cltbld', 14:07:37 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', 14:07:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:37 INFO - '_': '/tools/buildbot/bin/python'} 14:07:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:37 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)) 14:07:37 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)) 14:07:37 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)) 14:07:37 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)) 14:07:37 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)) 14:07:37 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)) 14:07:37 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)) 14:07:37 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)) 14:07:37 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)) 14:07:37 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:07:37 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 14:07:37 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 14:07:37 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 14:07:37 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 14:07:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 14:07:38 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)) 14:07:38 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)) 14:07:38 INFO - Installing collected packages: simplejson 14:07:38 INFO - Running setup.py install for simplejson 14:07:38 INFO - building 'simplejson._speedups' extension 14:07:38 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 14:07:39 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 14:07:39 INFO - Successfully installed simplejson 14:07:39 INFO - Cleaning up... 14:07:39 INFO - Return code: 0 14:07:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:39 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/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 0x21281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2495b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2498f00>, '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 0x2493ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2494050>, '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': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', '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 14:07:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:07:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:07:39 INFO - Using env: {'DISPLAY': ':0', 14:07:39 INFO - 'HOME': '/home/cltbld', 14:07:39 INFO - 'LANG': 'en_US.UTF-8', 14:07:39 INFO - 'LANGUAGE': 'en_US:en', 14:07:39 INFO - 'LOGNAME': 'cltbld', 14:07:39 INFO - 'MAIL': '/var/mail/cltbld', 14:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:39 INFO - 'MOZ_NO_REMOTE': '1', 14:07:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:39 INFO - 'NO_EM_RESTART': '1', 14:07:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:39 INFO - 'PWD': '/builds/slave/test', 14:07:39 INFO - 'SHELL': '/bin/bash', 14:07:39 INFO - 'SHLVL': '1', 14:07:39 INFO - 'TERM': 'linux', 14:07:39 INFO - 'TMOUT': '86400', 14:07:39 INFO - 'USER': 'cltbld', 14:07:39 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', 14:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:39 INFO - '_': '/tools/buildbot/bin/python'} 14:07:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:07:39 INFO - Cleaning up... 14:07:39 INFO - Return code: 0 14:07:39 INFO - Running post-action listener: _resource_record_post_action 14:07:39 INFO - Running post-action listener: _start_resource_monitoring 14:07:39 INFO - Starting resource monitoring. 14:07:39 INFO - [mozharness: 2017-08-11 21:07:39.732041Z] Finished create-virtualenv step (success) 14:07:39 INFO - [mozharness: 2017-08-11 21:07:39.732560Z] Running install step. 14:07:39 INFO - Running pre-action listener: _resource_record_pre_action 14:07:39 INFO - Running main action method: install 14:07:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:07:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:07:39 INFO - Reading from file tmpfile_stdout 14:07:39 INFO - Detecting whether we're running mozinstall >=1.0... 14:07:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:07:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:07:40 INFO - Reading from file tmpfile_stdout 14:07:40 INFO - Output received: 14:07:40 INFO - Usage: mozinstall [options] installer 14:07:40 INFO - Options: 14:07:40 INFO - -h, --help show this help message and exit 14:07:40 INFO - -d DEST, --destination=DEST 14:07:40 INFO - Directory to install application into. [default: 14:07:40 INFO - "/builds/slave/test"] 14:07:40 INFO - --app=APP Application being installed. [default: firefox] 14:07:40 INFO - mkdir: /builds/slave/test/build/application 14:07:40 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'] 14:07:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:07:58 INFO - Reading from file tmpfile_stdout 14:07:58 INFO - Output received: 14:07:58 INFO - /builds/slave/test/build/application/firefox/firefox 14:07:58 INFO - Running post-action listener: _resource_record_post_action 14:07:58 INFO - [mozharness: 2017-08-11 21:07:58.029387Z] Finished install step (success) 14:07:58 INFO - [mozharness: 2017-08-11 21:07:58.029785Z] Running setup-mitmproxy step. 14:07:58 INFO - Running pre-action listener: _resource_record_pre_action 14:07:58 INFO - Running main action method: setup_mitmproxy 14:07:58 INFO - Skipping: mitmproxy is not required 14:07:58 INFO - Running post-action listener: _resource_record_post_action 14:07:58 INFO - [mozharness: 2017-08-11 21:07:58.031389Z] Finished setup-mitmproxy step (success) 14:07:58 INFO - [mozharness: 2017-08-11 21:07:58.031705Z] Running run-tests step. 14:07:58 INFO - Running pre-action listener: _resource_record_pre_action 14:07:58 INFO - Running pre-action listener: _set_gcov_prefix 14:07:58 INFO - Running main action method: run_tests 14:07:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:07:58 INFO - Python 2.7.3 14:07:58 INFO - Return code: 0 14:07:58 INFO - grabbing minidump binary from tooltool 14:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 14:07:58 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 0x2498f00>, '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 0x2493ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2494050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:07:58 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 14:07:58 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 14:07:58 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 14:07:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:07:58 INFO - Return code: 0 14:07:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:07:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:07:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:07:58 INFO - ENV: RUST_BACKTRACE is now full 14:07:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:07:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:07:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-076', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 14:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-076 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 14:07:58 INFO - Using env: {'DISPLAY': ':0', 14:07:58 INFO - 'HOME': '/home/cltbld', 14:07:58 INFO - 'LANG': 'en_US.UTF-8', 14:07:58 INFO - 'LANGUAGE': 'en_US:en', 14:07:58 INFO - 'LOGNAME': 'cltbld', 14:07:58 INFO - 'MAIL': '/var/mail/cltbld', 14:07:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:07:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:07:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:58 INFO - 'MOZ_NO_REMOTE': '1', 14:07:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:07:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:07:58 INFO - 'NO_EM_RESTART': '1', 14:07:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:07:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:58 INFO - 'PWD': '/builds/slave/test', 14:07:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:07:58 INFO - 'RUST_BACKTRACE': 'full', 14:07:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:07:58 INFO - 'SHELL': '/bin/bash', 14:07:58 INFO - 'SHLVL': '1', 14:07:58 INFO - 'STYLO_FORCE_ENABLED': '1', 14:07:58 INFO - 'TERM': 'linux', 14:07:58 INFO - 'TMOUT': '86400', 14:07:58 INFO - 'USER': 'cltbld', 14:07:58 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408', 14:07:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:58 INFO - '_': '/tools/buildbot/bin/python'} 14:07:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-076', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 14:07:58 INFO - mozversion application_buildid: 20170811201601 14:07:58 INFO - mozversion application_changeset: 80ff3f300e05f38f96c385b03d1973a966a2bd35 14:07:58 INFO - mozversion application_display_name: Nightly 14:07:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:07:58 INFO - mozversion application_name: Firefox 14:07:58 INFO - mozversion application_remotingname: firefox 14:07:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:07:58 INFO - mozversion application_vendor: Mozilla 14:07:58 INFO - mozversion application_version: 57.0a1 14:07:58 INFO - mozversion platform_buildid: 20170811201601 14:07:58 INFO - mozversion platform_changeset: 80ff3f300e05f38f96c385b03d1973a966a2bd35 14:07:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:07:58 INFO - mozversion platform_version: 57.0a1 14:07:58 INFO - using testdate: 1502485678 14:07:58 INFO - actual date: 1502485678 14:07:58 INFO - starting webserver on 'localhost:39537' 14:07:58 INFO - SUITE-START | Running 2 tests 14:07:58 INFO - TEST-START | dromaeo_css 14:07:58 INFO - Initialising browser for dromaeo_css test... 14:07:58 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39537/getInfo.html -profile /tmp/tmpFwRXNZ/profile 14:07:58 INFO - TEST-INFO | started process 14597 (/builds/slave/test/build/application/firefox/firefox http://localhost:39537/getInfo.html) 14:08:04 INFO - TEST-INFO | 14597: exit 0 14:08:04 INFO - Browser initialized. 14:08:04 INFO - Running cycle 1/1 for dromaeo_css test... 14:08:04 INFO - TEST-INFO | started process 14833 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFwRXNZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 14:08:05 INFO - PID 14833 | 14:08:05 INFO - PID 14833 | (/builds/slave/test/build/application/firefox/firefox:14887): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 14:08:05 INFO - PID 14833 | 14:08:16 INFO - PID 14833 | RSS: Main: 167698432 14:08:16 INFO - PID 14833 | 14:11:15 INFO - PID 14833 | Cycle 1(1): loaded http://localhost:39537/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39537/tests/dromaeo/cssquery-ext.html) 14:11:16 INFO - PID 14833 | RSS: Main: 175210496 14:11:16 INFO - PID 14833 | 14:14:18 INFO - PID 14833 | Cycle 1(1): loaded http://localhost:39537/tests/dromaeo/cssquery-ext.html (next: http://localhost:39537/tests/dromaeo/cssquery-jquery.html) 14:14:19 INFO - PID 14833 | RSS: Main: 172896256 14:14:19 INFO - PID 14833 | 14:17:20 INFO - PID 14833 | Cycle 1(1): loaded http://localhost:39537/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39537/tests/dromaeo/cssquery-mootools.html) 14:17:21 INFO - PID 14833 | RSS: Main: 174710784 14:17:21 INFO - PID 14833 | 14:20:23 INFO - PID 14833 | Cycle 1(1): loaded http://localhost:39537/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39537/tests/dromaeo/cssquery-prototype.html) 14:20:23 INFO - PID 14833 | RSS: Main: 173256704 14:20:23 INFO - PID 14833 | 14:23:27 INFO - PID 14833 | Cycle 1(1): loaded http://localhost:39537/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39537/tests/dromaeo/cssquery-yui.html) 14:23:28 INFO - PID 14833 | RSS: Main: 176521216 14:23:28 INFO - PID 14833 | 14:26:29 INFO - PID 14833 | Cycle 1(1): loaded http://localhost:39537/tests/dromaeo/cssquery-yui.html (next: http://localhost:39537/tests/dromaeo/cssquery-dojo.html) 14:26:30 INFO - PID 14833 | RSS: Main: 177795072 14:26:30 INFO - PID 14833 | 14:26:30 INFO - PID 14833 | __start_tp_report 14:26:30 INFO - PID 14833 | _x_x_mozilla_page_load 14:26:30 INFO - PID 14833 | _x_x_mozilla_page_load_details 14:26:30 INFO - PID 14833 | |i|pagename|runs| 14:26:30 INFO - PID 14833 | |0;dojo.html;3137.6705445928164;3165.398574270887;3185.4266232078226;3191.9999999999995;3210.341879914618;6097.207363042804;6294.527910406715;6341.524385671081;6349.841253968647;6368.29948705642;6086.665233412172;6227.626342419458;6239.157713708648;6244.094606282087;6251.093591429251;5673.886522269562;5722.256106706122;5792.507636850873;5820.214271073393;5868.706564671771;5708.800191993281;5775.018246898037;5797.275340582427;5799.3620701722775;5810.389909059551;5482.205080263359;5597.160425936101;5620.718964051802;5699.572532060112;5733.082706766897;5653.660780353192;5658.43415658435;5722.399148089474;5726.198332233491;5737.225474560964;5537.501624853742;5563.916541251884;5612.102063669834;5642.689652069134;5724.685142317171;6167.044108163242;6202.3797620238065;6223.875522489525;6247.531435142382;6272.780452684134;6687.531872768908;6692.531522793358;6788.185417749902;6789.1174147360525;6804.149481314836;6677.766278180289;6747.628880411576;6758.357955994173;6766.357196066366;6860.725570977155;6205.03821907605;6240.375962403768;6249.812505624838;6256.374362563753;6265.436110750009;6816.147981502312;6846.315368463213;6912.758053468205;6912.861742765116;6920.930790692129;6459.870802583922;6519.706613202417;6527.445167160845;6570.080188773575;6614.636195009224;6623.602583844985;6701.29636388182;6720.2943690912725;6720.596764194164;6781.220159681699;6664.067030615718;6699.899501507482;6711.932880671228;6740.696668649923;6759.594424334556;6654.168228971379;6667;6753.561018533779;6757.087793147862;6761.661916904135;5325.6272060956135;5355.357357117093;5407.270018547446;5414.972925135349;5419.160030195326;4598.1033698429155;4620.953790462119;4631.606313463326;4634.86095417138;4635.629149668019;5756.165356023437;5792.82621521355;5794.536437085023;5851.561132915049;5857.560682948711;6585.86828263432;6664.900026499607;6690.866182676319;6701.631410272482;6731.831704207336;5754.1944127822135;5756.251687280626;5813.970930145322;5830.067189249699;5836.649801011953;5888.793892213752;5898.587098903035;5918.940810591839;5959.433853783884;5960.731767070492;6338.873222535616;6439.098526206335;6465.353464653591;6569.375909288515;6572.704228309737;6717.798466046026;6738.764143254963;6742;6748.595084294958;6757.425618822455;6450.387213214737;6493.675316234264;6516.120323756232;6517.73929042852;6524.412802847766;6574.27682954865;6597.967010164918;6676.365745254193;6690.063391125149;6696.129503164558;6582.17722784652;6619.304972977768;6622.63575503352;6626.337366263335;6709.664516774239;6399.168108145911;6456.192975878015;6486.286508484157;6488.870222595616;6490.8052758417325;6159.5688301818445;6472.35276472349;6481.8703625928165;6589.538732288694;6598.637074960923;6432.710528026344;6503.187101612299;6511.967440162769;6519.282878883414;6551.050097735897;6526.706298216497;6552.934470655233;6558.934410655832;6583.078369028244;6631.701573429111;6243.469305109116;6350.523710721622;6374.74501019954;6406.679666016587;6410.326915673787 14:26:30 INFO - PID 14833 | |1;ext.html;832.0680837462044;834.64110432514;858.7938894665281;887.0641473245723;891.8929728432589;40418.1916361673;41236;41422.17155656885;41492.17015659685;41617.16765664685;37909.810450947705;38062.429063564;38287.61712382875;38323;38382.424263636065;6741.191057073146;6845.520813543054;6876.312368763109;6878.931210687904;6893.103896493449;6843.828904277408;6851.760188393408;6880.8967865482055;6931.6880740366805;6939.618320992344;7599.392048636096;7773.261540153676;7783.377329813601;7785.2214778521975;7791.961040194791;7707.576083315415;7761.805954851117;7766.145723970359;7769.572673502955;7775;7386.667599957961;7603.2776886195725;7604.125525564576;7622.8475430491635;7704.499207551491;39407.8029609853;39708.40437393441;39782;40064.39903401451;40108;3536.734744894143;3613.2773445311045;3626.12972886508;3628.0385697790107;3634.890953271406;31577.526337104962;31821.681783182103;31919.680803191903;31942;32001.199970000704;5077.847664570068;5111.974440127813;5117.309163262949;5117.872053198663;5153.201253805666;188662;189480.0525997361;190800.04599976912;191732;192416;49228;49451.258231126565;49715.75142124266;49739;49805.50194498081;51893.74053129786;51895;51922.74038629858;51936;51976.740116299174;16000.199990000687;16126.113063781604;16140.43508477221;16147;16153.192340382933;13053.412596432994;13062.934685326512;13067.607971760863;13089.345532723326;13101.082924195405;14610.123392596479;14798.48205312809;14840.40638374453;14843.554693359216;14980.775288370683;5921.5558833086825;5998.1002849572915;6007.219061522062;6029.306629737636;6102.603330783485;975.1321324021626;1004.7789486313;1005.8843233028149;1006.8389057750725;1020.2908978260039;23067.077316907136;23111.422214444436;23157.768422315898;23195.53608927846;23336.883315583313;1091.5470079916904;1097.9341239525654;1103.6578660615075;1112.6995711157942;1116.7989761842784;12070.758584828254;12348.506059757508;12399.566015189424;12457.439415226336;12497.75004499905;209255;209714;210182;210247;211553;53749;54014.72992635012;54619.45380546144;56708;56803;1454.2365258239508;1478.7042591481747;1480.9703805924037;1492.2986196487634;1498.572906721601;27184.184474465797;27320.726792732614;27410.725892741615;27495.862520687268;27704.86147569249;34120.82939585286;34143.14642133967;34145.14637134092;34165.31669366549;34197.487037694955;194230;194546;194586;195929;196050;32765.50851737178;33016.33967320688;33068.33863322768;33101.503477447375;33121;42856.57143428526;42906;46047.76976115098;46984.53015469938;47081;34078.65921340755;34131;34132.317353653285;34176.316473670886;34492.31015379728;34015;34119;34345.82827085849;34414.655853441145;34456.483152752226;37391.06522336963;37504;37532.43701344427;37685.4347184787;37736.433953490174;32764;33313.666863331055;33993.490097649046;34769;35007;25195.874020630512;25328.873355633103;25436.364090897874;25549.61675574905;25551.23346299614 14:26:30 INFO - PID 14833 | |2;jquery.html;563.9718014099296;580.7967211475981;581.7818318130703;590.5187272373016;592.2715060475615;22571.210007649737;22607.660885086738;23526;23625.881870590645;23656.05375784971;25744.098956536523;26353.472930541426;26370.47259054818;26431.735682643168;26448.074317398896;6498.122753428274;6650.933490665104;6657.733690652368;6658.201015878077;6658.5671931324305;6186.659733714644;6483.319251478598;6517.315681853408;6542.182227221598;6620.503462240327;6458.93541064588;6465.773697920574;6471.126397936289;6473.6115833049935;6476.870462590746;6399.200099987502;6410.583312084699;6410.8076757697345;6435.8391040223905;6441.645709485976;5976.163337132805;6010.729517171694;6025.728842202111;6035.245594300713;6043.244594425697;29294.70705292941;29976.55035174474;30445.391092178255;30967.690323096707;31185.844070779727;1949.717290992798;1987.5031242189452;1990.3829812757947;1992.0139530932136;1997;19886.105125269398;20494.38516844497;20524.89737551303;20531.794682053285;20610.484737881525;2390.378501589599;2415.8792060397086;2463.864487453189;2521.71000334962;2539.619057141425;135008.32495837458;139983.30008349995;140218.29890850582;140226.89659655115;140801;14224.21766802815;14747.262636868114;15206.543803685907;15243.695126097638;15317.23413829304;23009.19467818652;23539;23633.881830591083;23692;23803.40491487727;10016.899831001743;10045.698629040995;10176.542055607515;10208.897911020944;10350.430726309974;9247.583858726373;9262.861057084148;9291.256699464027;9447.905520944703;9482.715518534455;9353;9414.811703766023;9545.949945505989;9740.512974351253;9890.406575605486;2846.900358487443;2852.443773464196;2897.6522817261643;2923.4591600553968;2991.0727674421;960.6685693435753;985.1478471122427;990.2919412620012;997.3068717241592;998.1515711645188;19897.403077907686;20103.899480502798;20205.797942020392;20244.392668219978;20315.101959415548;1034.4879284754095;1045.362328979322;1046.3251202974038;1048.3290693955873;1050;11022.614208502664;11041.00630943219;11042.33745975228;11089.445527723581;11225.887741122648;434615;462059;519856;527224;532695;121039;121538;123583.382083089;125930.3703481495;126622;1273.840769903762;1302.7134030513273;1316.7300703355788;1328.7408955253827;1335.6994676197778;20277.391678249674;20618.175272988912;21077.683834742184;21246.25638102659;22351.329460116223;61110.69444652748;61575;62063;62725;63992;69092.30907691059;69998.65000674964;70210.64894675698;70369.64815176096;70690;14820.555383338517;15032.549023529311;15045.097294162386;15055.343912169872;15095.094294341936;23624.055037798295;23638.52722945566;23839.880800595885;23901.641475377535;23958;18676.81323186751;18754.6249075015;18755.437336879917;19220.807791921903;19262.036898155315;16461.506154815375;16947.91526042362;17302.826971730625;17448;17888.642227155644;18762.061896905372;19081;19297.710534341717;19446.708299375237;19558.11988460494;14826.555203343916;14887.10677359362;14953.40186392532;14964.775528367301;15019.249037547861;18661.440156795317;18734.063296835375;18854;18956.336528221447;18963.90518047456 14:26:30 INFO - PID 14833 | |3;mootools.html;1368.8015237790519;1381.5717127690416;1420.126622127392;1421.4101148023572;1425.5367005723142;14133.363998620061;14311;14680.706385872289;14718;14750.852491475081;14958.401663933457;15045.398184072625;15087.547373578811;15164.469243576477;15241.92379038106;2739.6027576001516;2801.6638003439516;2801.789865760066;2801.9439611207763;2802.2854172186167;2576.1369941069765;2755.1734479656125;2759.213624117127;2766.0318888389106;2766.460540194663;2739.369944912671;2748.670159580843;2772.556390977444;2776.361436869521;2791.818531795437;2652.854093024883;2676.892924283036;2707.6615423072117;2715.456908618274;2726.9590956135576;2642.4318771464227;2652.5490666586725;2662.3077999720017;2664.8667566621593;2667.039865648365;14344.211068391234;14614.34235459407;14685.926570367186;14710.264486775619;14736.042157259744;7789;8012.719554815553;8041.597920103972;8067.394945379061;8069.95965020177;5122.846314610568;5232.9738351308;5277.47225277473;5381.5425688816495;5383.569314454834;1844.2991663168045;1855.8979256140906;1879.577095153593;1902.9524261893425;1913.770347558288;24144.154954576683;24505.63241551378;24543.754562454327;24544.913682337025;24577.508449831083;6448.871022579522;6485.383888530582;6654.168228971379;6672.666366681647;6688.398044176048;16636.08501532403;16641.83358166403;16651;16660.58348541272;16664.916675416545;7678.078630564368;7824.882626760603;7827.295543401121;7834.4907581007055;7836.843263134705;6948.5483443576;7108.8933665995055;7141.571505709674;7178.353948144692;7179.174394944544;5043.193089105799;5295.311609490818;5315.468453154731;5351.25082488452;5352.4915133062295;1508.720886635954;1519.3011214841076;1532.3793863485307;1537.3005282596391;1552.8214255360551;839.9538025408539;865.3466632692403;873.4060838629792;877.7937184761576;883.015437786863;7620.3522700569965;7879.33025692811;7886.960565197137;7897.68409263623;7971.920280797234;701.4668851672664;723.7539236659561;726.3136336162381;730.868443680132;739.9963000184964;4293.592108749664;4295.548967358445;4763.118823017753;4963.032208719265;5011;19166.329178478685;19341.709874351614;19739.40781776549;20007.899960500104;20177.192912283637;20335.491612709506;20453.48866278355;20455.590888182152;20476.180952761722;20502;10522;11133.220674552866;11207.047400970845;11302.434878256054;11307.886921130848;7501.774946751606;7608.277213664693;7667.118281397487;7680.884786728092;7687.846243074995;16874;17106.91446542759;17144.485665430057;17255.82744172542;17501.124943752508;17901.283948641907;17963.281468741105;17978;17985.100744962438;18101.547461313046;10925.071368933412;11008.669739907815;11119.16606254518;11127.443627818739;11161.274517156684;13571;13573.253471059186;13634.659133521345;13742.175469471864;13746.381412836246;11100.500477478372;11269.154813388865;11290.209685321948;11362.829557556477;11389.601363952223;10046.69859904204;10176.592936282466;10247.333923294962;10441.895581044397;10488.580456781643;9521.238300936047;9653.131218190396;9675.564599592895;9686.660966866126;9688.5640146195;6884.380405763505;6916.170059592881;6986.54587451814;7023.859522809413;7071.186813516511;6937.340952609696;6960.23437421873;7049.753258635923;7085.114360704912;7107.928920710727 14:26:30 INFO - PID 14833 | |4;prototype.html;513.8484147176582;516.1663912780858;520.5237207954722;535.2390634811472;535.2880668710616;6288.717007734657;6304.71628776705;6314.842128946779;6319.652419116953;6341.778037768679;6136.907946380797;6206.565540412162;6209.782657606985;6212.285587157478;6225.190725205728;5156.948430515694;5160.638755287131;5164.896702065957;5169.483051694838;5171.974140129294;4895.975520122394;5137.486251374851;5143.717095559743;5176.0331074191945;5207.973960130194;4854.368932038848;4878.585320247783;4883.560479556839;4928.728919909404;4933.260010998343;4892.315075889361;4909.45995940448;4909.950900491021;4932.224399216315;5013.573846223076;4367.2575662137515;4433.933490997638;4442.333649952533;4443.511213766483;4451.688381813242;5915.5859089863725;5935.762569497215;6005.879882402328;6012.789552365646;6037.879242415171;5674.177244299594;5687.74405151765;5701.942980570183;5733.569982251348;5756.424357564252;6518.348165183491;6559.540832141752;6568.244651865001;6574.309697481744;6607.768728094494;4860.975695121501;4863.270509423614;4877.902441951177;4908;4927.1870141426625;6545.312742162052;6709.664516774142;6811.591304521745;6846.486513511457;6879.5184337096425;4969.975150124226;5014.598832093424;5014.874628134253;5016.498350164954;5043.293938848564;4987.600991920638;5034.496550344937;5066.417362003417;5093.911496286505;5123.97438012815;5620.538659948443;5651.26533550644;5655.773769049274;5681.914771278434;5701.230333904953;6585.703643336062;6597.536293445979;6620;6634.369734875179;6730.495212859055;1773.796013458443;1779.590694140355;1781.5902342461306;1787.436957358454;1799.4884297190422;1175.2419689300352;1206.9698257543632;1219.5365761010937;1235.9011279097651;1252.5866464066837;5781.306243250837;5789.536837053026;5812.94187058124;5862.5896187267335;5881.735321910524;6628.5976928802875;6703.396694297537;6704.363085506869;6760.594364338155;6766.5263431559315;5716.914246286309;5787.913181302284;5789.9710501447225;5851.795187168429;5884.558658100659;3524.453709674953;3534.4168212245204;3538.628444013393;3544.858205671796;3586.479960405779;6406.263279722892;6431.74273029083;6455.322191169954;6554.475641948633;6593.406593406617;6785.185777706608;6822.420094292041;6848.623325717133;6851.417629501449;6907.689153988083;6467.579607325415;6490.318516555788;6498.740050397932;6519.674016299166;6616.86766264672;6136.378369868335;6517.967410162919;6663.666816659244;6674.229060580757;6777.695003724943;6585.670716464062;6611.537192396486;6650.37065918853;6724.193096828412;6725.529212955046;6475.190601174853;6503.284638689645;6526.24948130971;6565.868682626417;6582.637954912526;5031.320771695774;5040.697558146523;5071.467495913023;5082.644214904921;5085.898282034264;6534.379233972765;6639.336066393322;6682.131322927989;6754.797356079325;6791.3548212919695;6361.363863613602;6542.8364290893105;6595.208574970842;6598.40614344692;6626.105475760904;6559.868802624016;6566.573172743756;6571.408573228241;6572.432159648998;6572.474202063919;6360.872782544416;6477.546571740121;6489.643069631215;6494.545381823416;6531.836704082246;6579.342065793382;6584.703688333941;6644;6649.202095748347;6651.933480665131;6379.776707815204;6385.541566497025;6389.808305750835;6390.936090639034;6414.711337989708 14:26:30 INFO - PID 14833 | |5;yui.html;893.334465822962;893.5710858787781;910.2990697163183;910.6858133943787;921.3642586615235;18762.718559221623;19726.90136549317;19818;19938.70091948622;22526.88736556319;21435.571288574258;21589.352319430403;21683.13267469299;21839.563208735857;21958.121675132974;1009.8535712321709;1053.7629033467485;1053.8050460664763;1054.6572363981697;1054.7204990677476;1026.1636766035695;1059.4384975962753;1067.765091679829;1071.4803320389606;1073.8013467508497;1112.7440688641618;1116.547798141754;1118.3066498770775;1118.8098023336051;1119.1774046076127;1106.269861891148;1107.1530279336314;1115.8326251062324;1116.2576886370555;1116.7878103160415;810.4488947515687;810.4812919731377;810.7648781853255;811;811.1645005644137;18763.718544221847;18866.71699924502;19103.23587056516;19883;20455.079521421572;21335.146594136215;21357.145714171413;21455.46361340979;21800.127994880346;21804.672929906064;16575.25411356492;16622.667546648998;16771.413000544922;16771.664566708718;16870.15649217534;3609.2420591675777;3624.963750362489;3627.038834708804;3636.072801435643;3637.2179981303884;126827;127280.36359818235;127754.36122819419;129209;130921.34539327242;16989.575260618338;17072.317107315568;17224.224909879085;17550.297988080332;17553.736693949602;30753.538696919564;31064.844675776476;31184;31661.52507712386;31744.682553174174;7837.255460731221;8132.105468398471;8133.674653013932;8259.504429734236;8383.874241886377;4311.374850646702;4377.956220437755;4387.868363949023;4396.780160991938;4400.44994375703;5230.738463076831;5303.973480132652;5320.920186197211;5337.759800808973;5354.250404943311;1074.8763892152347;1087.6682611803317;1098.357460885375;1098.8241881299114;1099.6700989703218;664.5647101148755;681.1383599746325;685.1059367525427;693.660106547798;699.9965000174966;11417.959623794384;11465.541378344773;11936.880631193577;12054.879451205552;12070.577529786415;680.8638272345551;685.4447897203281;685.7085738561092;703.0754557756534;703.6341102626606;5563.860903477445;5569.832905012856;5639.464250896158;5655.208270842086;5656.123300888369;108215;110237;110654;110914.44542777234;112867.43566282115;98159.50920245353;98685;99794;103332.48333758283;103709.48145259226;85866;86806.13193868106;87056.12943870606;88370.11629883619;90189;10486.056254937092;10581.735456613646;10634.09610183112;10714.892851071701;10947.34315941046;41069.58930410777;41107.7944610275;41670;42948.78525607352;43461.348079778196;51091.489085108675;53362;53991.73004134954;54101;55223.17165242605;14762.114273143647;14924.925375373054;14982.775258370915;15102.697946041237;15650.765238521659;18303;21433.678494822903;21522.354329370144;21605.891970540048;21936.780632193473;18516.537086572403;18526.62946741031;18601.348952786586;18704.7194292083;18831.529211769815;17687;17753.822461775217;17859.107044647975;17890.28438862483;18021.63956720832;18442.35451759182;18483.16825742818;18500.722489162403;18506.352277670216;18521.629567408847;13276.867231327564;13615.251161185834;13723.931380343034;13777.242251676254;13819.516316928859;13294.069415140848;13295.534656286984;13335.199888006751;13413;13461.125026873222 14:26:30 INFO - PID 14833 | __end_tp_report 14:26:30 INFO - PID 14833 | __start_cc_report 14:26:30 INFO - PID 14833 | _x_x_mozilla_cycle_collect,366 14:26:30 INFO - PID 14833 | __end_cc_report 14:26:30 INFO - PID 14833 | __startTimestamp1502486790044__endTimestamp 14:26:30 INFO - PID 14833 | 14:26:30 INFO - PID 14833 | ------- Summary: start ------- 14:26:30 INFO - PID 14833 | Number of tests: 6 14:26:30 INFO - PID 14833 | 14:26:30 INFO - PID 14833 | [#0] dojo.html Cycles:165 Average:6160.29 Median:6410.33 stddev:729.78 (11.4%) stddev-sans-first:692.43 14:26:30 INFO - PID 14833 | Values: 3137.7 3165.4 3185.4 3192.0 3210.3 6097.2 6294.5 6341.5 6349.8 6368.3 6086.7 6227.6 6239.2 6244.1 6251.1 5673.9 5722.3 5792.5 5820.2 5868.7 5708.8 5775.0 5797.3 5799.4 5810.4 5482.2 5597.2 5620.7 5699.6 5733.1 5653.7 5658.4 5722.4 5726.2 5737.2 5537.5 5563.9 5612.1 5642.7 5724.7 6167.0 6202.4 6223.9 6247.5 6272.8 6687.5 6692.5 6788.2 6789.1 6804.1 6677.8 6747.6 6758.4 6766.4 6860.7 6205.0 6240.4 6249.8 6256.4 6265.4 6816.1 6846.3 6912.8 6912.9 6920.9 6459.9 6519.7 6527.4 6570.1 6614.6 6623.6 6701.3 6720.3 6720.6 6781.2 6664.1 6699.9 6711.9 6740.7 6759.6 6654.2 6667.0 6753.6 6757.1 6761.7 5325.6 5355.4 5407.3 5415.0 5419.2 4598.1 4621.0 4631.6 4634.9 4635.6 5756.2 5792.8 5794.5 5851.6 5857.6 6585.9 6664.9 6690.9 6701.6 6731.8 5754.2 5756.3 5814.0 5830.1 5836.6 5888.8 5898.6 5918.9 5959.4 5960.7 6338.9 6439.1 6465.4 6569.4 6572.7 6717.8 6738.8 6742.0 6748.6 6757.4 6450.4 6493.7 6516.1 6517.7 6524.4 6574.3 6598.0 6676.4 6690.1 6696.1 6582.2 6619.3 6622.6 6626.3 6709.7 6399.2 6456.2 6486.3 6488.9 6490.8 6159.6 6472.4 6481.9 6589.5 6598.6 6432.7 6503.2 6512.0 6519.3 6551.1 6526.7 6552.9 6558.9 6583.1 6631.7 6243.5 6350.5 6374.7 6406.7 6410.3 14:26:30 INFO - PID 14833 | 14:26:30 INFO - PID 14833 | [#1] ext.html Cycles:180 Average:37344.31 Median:34474.40 stddev:51412.13 (149.1%) stddev-sans-first:51483.25 14:26:30 INFO - PID 14833 | Values: 832.1 834.6 858.8 887.1 891.9 40418.2 41236.0 41422.2 41492.2 41617.2 37909.8 38062.4 38287.6 38323.0 38382.4 6741.2 6845.5 6876.3 6878.9 6893.1 6843.8 6851.8 6880.9 6931.7 6939.6 7599.4 7773.3 7783.4 7785.2 7792.0 7707.6 7761.8 7766.1 7769.6 7775.0 7386.7 7603.3 7604.1 7622.8 7704.5 39407.8 39708.4 39782.0 40064.4 40108.0 3536.7 3613.3 3626.1 3628.0 3634.9 31577.5 31821.7 31919.7 31942.0 32001.2 5077.8 5112.0 5117.3 5117.9 5153.2 188662.0 189480.1 190800.0 191732.0 192416.0 49228.0 49451.3 49715.8 49739.0 49805.5 51893.7 51895.0 51922.7 51936.0 51976.7 16000.2 16126.1 16140.4 16147.0 16153.2 13053.4 13062.9 13067.6 13089.3 13101.1 14610.1 14798.5 14840.4 14843.6 14980.8 5921.6 5998.1 6007.2 6029.3 6102.6 975.1 1004.8 1005.9 1006.8 1020.3 23067.1 23111.4 23157.8 23195.5 23336.9 1091.5 1097.9 1103.7 1112.7 1116.8 12070.8 12348.5 12399.6 12457.4 12497.8 209255.0 209714.0 210182.0 210247.0 211553.0 53749.0 54014.7 54619.5 56708.0 56803.0 1454.2 1478.7 1481.0 1492.3 1498.6 27184.2 27320.7 27410.7 27495.9 27704.9 34120.8 34143.1 34145.1 34165.3 34197.5 194230.0 194546.0 194586.0 195929.0 196050.0 32765.5 33016.3 33068.3 33101.5 33121.0 42856.6 42906.0 46047.8 46984.5 47081.0 34078.7 34131.0 34132.3 34176.3 34492.3 34015.0 34119.0 34345.8 34414.7 34456.5 37391.1 37504.0 37532.4 37685.4 37736.4 32764.0 33313.7 33993.5 34769.0 35007.0 25195.9 25328.9 25436.4 25549.6 25551.2 14:26:30 INFO - PID 14833 | 14:26:30 INFO - PID 14833 | [#2] jquery.html Cycles:180 Average:35612.32 Median:23624.97 stddev:83890.37 (355.1%) stddev-sans-first:84084.43 14:26:30 INFO - PID 14833 | Values: 564.0 580.8 581.8 590.5 592.3 22571.2 22607.7 23526.0 23625.9 23656.1 25744.1 26353.5 26370.5 26431.7 26448.1 6498.1 6650.9 6657.7 6658.2 6658.6 6186.7 6483.3 6517.3 6542.2 6620.5 6458.9 6465.8 6471.1 6473.6 6476.9 6399.2 6410.6 6410.8 6435.8 6441.6 5976.2 6010.7 6025.7 6035.2 6043.2 29294.7 29976.6 30445.4 30967.7 31185.8 1949.7 1987.5 1990.4 1992.0 1997.0 19886.1 20494.4 20524.9 20531.8 20610.5 2390.4 2415.9 2463.9 2521.7 2539.6 135008.3 139983.3 140218.3 140226.9 140801.0 14224.2 14747.3 15206.5 15243.7 15317.2 23009.2 23539.0 23633.9 23692.0 23803.4 10016.9 10045.7 10176.5 10208.9 10350.4 9247.6 9262.9 9291.3 9447.9 9482.7 9353.0 9414.8 9545.9 9740.5 9890.4 2846.9 2852.4 2897.7 2923.5 2991.1 960.7 985.1 990.3 997.3 998.2 19897.4 20103.9 20205.8 20244.4 20315.1 1034.5 1045.4 1046.3 1048.3 1050.0 11022.6 11041.0 11042.3 11089.4 11225.9 434615.0 462059.0 519856.0 527224.0 532695.0 121039.0 121538.0 123583.4 125930.4 126622.0 1273.8 1302.7 1316.7 1328.7 1335.7 20277.4 20618.2 21077.7 21246.3 22351.3 61110.7 61575.0 62063.0 62725.0 63992.0 69092.3 69998.7 70210.6 70369.6 70690.0 14820.6 15032.5 15045.1 15055.3 15095.1 23624.1 23638.5 23839.9 23901.6 23958.0 18676.8 18754.6 18755.4 19220.8 19262.0 16461.5 16947.9 17302.8 17448.0 17888.6 18762.1 19081.0 19297.7 19446.7 19558.1 14826.6 14887.1 14953.4 14964.8 15019.2 18661.4 18734.1 18854.0 18956.3 18963.9 14:26:30 INFO - PID 14833 | 14:26:30 INFO - PID 14833 | [#3] mootools.html Cycles:180 Average:8941.83 Median:2665.95 stddev:6237.63 (234.0%) stddev-sans-first:6229.17 14:26:30 INFO - PID 14833 | Values: 1368.8 1381.6 1420.1 1421.4 1425.5 14133.4 14311.0 14680.7 14718.0 14750.9 14958.4 15045.4 15087.5 15164.5 15241.9 2739.6 2801.7 2801.8 2801.9 2802.3 2576.1 2755.2 2759.2 2766.0 2766.5 2739.4 2748.7 2772.6 2776.4 2791.8 2652.9 2676.9 2707.7 2715.5 2727.0 2642.4 2652.5 2662.3 2664.9 2667.0 14344.2 14614.3 14685.9 14710.3 14736.0 7789.0 8012.7 8041.6 8067.4 8070.0 5122.8 5233.0 5277.5 5381.5 5383.6 1844.3 1855.9 1879.6 1903.0 1913.8 24144.2 24505.6 24543.8 24544.9 24577.5 6448.9 6485.4 6654.2 6672.7 6688.4 16636.1 16641.8 16651.0 16660.6 16664.9 7678.1 7824.9 7827.3 7834.5 7836.8 6948.5 7108.9 7141.6 7178.4 7179.2 5043.2 5295.3 5315.5 5351.3 5352.5 1508.7 1519.3 1532.4 1537.3 1552.8 840.0 865.3 873.4 877.8 883.0 7620.4 7879.3 7887.0 7897.7 7971.9 701.5 723.8 726.3 730.9 740.0 4293.6 4295.5 4763.1 4963.0 5011.0 19166.3 19341.7 19739.4 20007.9 20177.2 20335.5 20453.5 20455.6 20476.2 20502.0 10522.0 11133.2 11207.0 11302.4 11307.9 7501.8 7608.3 7667.1 7680.9 7687.8 16874.0 17106.9 17144.5 17255.8 17501.1 17901.3 17963.3 17978.0 17985.1 18101.5 10925.1 11008.7 11119.2 11127.4 11161.3 13571.0 13573.3 13634.7 13742.2 13746.4 11100.5 11269.2 11290.2 11362.8 11389.6 10046.7 10176.6 10247.3 10441.9 10488.6 9521.2 9653.1 9675.6 9686.7 9688.6 6884.4 6916.2 6986.5 7023.9 7071.2 6937.3 6960.2 7049.8 7085.1 7107.9 14:26:30 INFO - PID 14833 | 14:26:30 INFO - PID 14833 | [#4] prototype.html Cycles:180 Average:5518.58 Median:6136.64 stddev:1541.15 (25.1%) stddev-sans-first:1498.99 14:26:30 INFO - PID 14833 | Values: 513.8 516.2 520.5 535.2 535.3 6288.7 6304.7 6314.8 6319.7 6341.8 6136.9 6206.6 6209.8 6212.3 6225.2 5156.9 5160.6 5164.9 5169.5 5172.0 4896.0 5137.5 5143.7 5176.0 5208.0 4854.4 4878.6 4883.6 4928.7 4933.3 4892.3 4909.5 4910.0 4932.2 5013.6 4367.3 4433.9 4442.3 4443.5 4451.7 5915.6 5935.8 6005.9 6012.8 6037.9 5674.2 5687.7 5701.9 5733.6 5756.4 6518.3 6559.5 6568.2 6574.3 6607.8 4861.0 4863.3 4877.9 4908.0 4927.2 6545.3 6709.7 6811.6 6846.5 6879.5 4970.0 5014.6 5014.9 5016.5 5043.3 4987.6 5034.5 5066.4 5093.9 5124.0 5620.5 5651.3 5655.8 5681.9 5701.2 6585.7 6597.5 6620.0 6634.4 6730.5 1773.8 1779.6 1781.6 1787.4 1799.5 1175.2 1207.0 1219.5 1235.9 1252.6 5781.3 5789.5 5812.9 5862.6 5881.7 6628.6 6703.4 6704.4 6760.6 6766.5 5716.9 5787.9 5790.0 5851.8 5884.6 3524.5 3534.4 3538.6 3544.9 3586.5 6406.3 6431.7 6455.3 6554.5 6593.4 6785.2 6822.4 6848.6 6851.4 6907.7 6467.6 6490.3 6498.7 6519.7 6616.9 6136.4 6518.0 6663.7 6674.2 6777.7 6585.7 6611.5 6650.4 6724.2 6725.5 6475.2 6503.3 6526.2 6565.9 6582.6 5031.3 5040.7 5071.5 5082.6 5085.9 6534.4 6639.3 6682.1 6754.8 6791.4 6361.4 6542.8 6595.2 6598.4 6626.1 6559.9 6566.6 6571.4 6572.4 6572.5 6360.9 6477.5 6489.6 6494.5 6531.8 6579.3 6584.7 6644.0 6649.2 6651.9 6379.8 6385.5 6389.8 6390.9 6414.7 14:26:30 INFO - PID 14833 | 14:26:30 INFO - PID 14833 | [#5] yui.html Cycles:180 Average:23532.04 Median:18984.98 stddev:32148.19 (169.3%) stddev-sans-first:32193.43 14:26:30 INFO - PID 14833 | Values: 893.3 893.6 910.3 910.7 921.4 18762.7 19726.9 19818.0 19938.7 22526.9 21435.6 21589.4 21683.1 21839.6 21958.1 1009.9 1053.8 1053.8 1054.7 1054.7 1026.2 1059.4 1067.8 1071.5 1073.8 1112.7 1116.5 1118.3 1118.8 1119.2 1106.3 1107.2 1115.8 1116.3 1116.8 810.4 810.5 810.8 811.0 811.2 18763.7 18866.7 19103.2 19883.0 20455.1 21335.1 21357.1 21455.5 21800.1 21804.7 16575.3 16622.7 16771.4 16771.7 16870.2 3609.2 3625.0 3627.0 3636.1 3637.2 126827.0 127280.4 127754.4 129209.0 130921.3 16989.6 17072.3 17224.2 17550.3 17553.7 30753.5 31064.8 31184.0 31661.5 31744.7 7837.3 8132.1 8133.7 8259.5 8383.9 4311.4 4378.0 4387.9 4396.8 4400.4 5230.7 5304.0 5320.9 5337.8 5354.3 1074.9 1087.7 1098.4 1098.8 1099.7 664.6 681.1 685.1 693.7 700.0 11418.0 11465.5 11936.9 12054.9 12070.6 680.9 685.4 685.7 703.1 703.6 5563.9 5569.8 5639.5 5655.2 5656.1 108215.0 110237.0 110654.0 110914.4 112867.4 98159.5 98685.0 99794.0 103332.5 103709.5 85866.0 86806.1 87056.1 88370.1 90189.0 10486.1 10581.7 10634.1 10714.9 10947.3 41069.6 41107.8 41670.0 42948.8 43461.3 51091.5 53362.0 53991.7 54101.0 55223.2 14762.1 14924.9 14982.8 15102.7 15650.8 18303.0 21433.7 21522.4 21605.9 21936.8 18516.5 18526.6 18601.3 18704.7 18831.5 17687.0 17753.8 17859.1 17890.3 18021.6 18442.4 18483.2 18500.7 18506.4 18521.6 13276.9 13615.3 13723.9 13777.2 13819.5 13294.1 13295.5 13335.2 13413.0 13461.1 14:26:30 INFO - PID 14833 | -------- Summary: end -------- 14:26:30 INFO - PID 14833 | 14:26:30 INFO - TEST-INFO | 14833: exit 0 14:26:30 INFO - TEST-OK | dromaeo_css | took 1112354ms 14:26:30 INFO - TEST-START | kraken 14:26:30 INFO - Initialising browser for kraken test... 14:26:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39537/getInfo.html -profile /tmp/tmp75FPHh/profile 14:26:30 INFO - TEST-INFO | started process 15209 (/builds/slave/test/build/application/firefox/firefox http://localhost:39537/getInfo.html) 14:26:36 INFO - TEST-INFO | 15209: exit 0 14:26:37 INFO - Browser initialized. 14:26:37 INFO - Running cycle 1/1 for kraken test... 14:26:37 INFO - TEST-INFO | started process 15442 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp75FPHh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 14:26:37 INFO - PID 15442 | 14:26:37 INFO - PID 15442 | (/builds/slave/test/build/application/firefox/firefox:15496): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 14:26:37 INFO - PID 15442 | 14:26:48 INFO - PID 15442 | RSS: Main: 167866368 14:26:48 INFO - PID 15442 | 14:26:51 INFO - PID 15442 | Cycle 1(1): loaded http://localhost:39537/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39537/tests/kraken/driver.html?testName=audio-beat-detection) 14:26:51 INFO - PID 15442 | RSS: Main: 174170112 14:26:51 INFO - PID 15442 | 14:26:54 INFO - PID 15442 | Cycle 1(1): loaded http://localhost:39537/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39537/tests/kraken/driver.html?testName=audio-dft) 14:26:55 INFO - PID 15442 | RSS: Main: 174501888 14:26:55 INFO - PID 15442 | 14:26:58 INFO - PID 15442 | Cycle 1(1): loaded http://localhost:39537/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39537/tests/kraken/driver.html?testName=audio-fft) 14:26:59 INFO - PID 15442 | RSS: Main: 169508864 14:26:59 INFO - PID 15442 | 14:27:01 INFO - PID 15442 | Cycle 1(1): loaded http://localhost:39537/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39537/tests/kraken/driver.html?testName=audio-oscillator) 14:27:01 INFO - PID 15442 | RSS: Main: 170225664 14:27:01 INFO - PID 15442 | 14:27:04 INFO - PID 15442 | Cycle 1(1): loaded http://localhost:39537/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39537/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:27:04 INFO - PID 15442 | RSS: Main: 170409984 14:27:04 INFO - PID 15442 | 14:27:07 INFO - PID 15442 | Cycle 1(1): loaded http://localhost:39537/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39537/tests/kraken/driver.html?testName=imaging-darkroom) 14:27:08 INFO - PID 15442 | RSS: Main: 173072384 14:27:08 INFO - PID 15442 | 14:27:12 INFO - PID 15442 | Cycle 1(1): loaded http://localhost:39537/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39537/tests/kraken/driver.html?testName=imaging-desaturate) 14:27:12 INFO - PID 15442 | RSS: Main: 172654592 14:27:12 INFO - PID 15442 | 14:27:15 INFO - PID 15442 | Cycle 1(1): loaded http://localhost:39537/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39537/tests/kraken/driver.html?testName=json-parse-financial) 14:27:16 INFO - PID 15442 | RSS: Main: 173477888 14:27:16 INFO - PID 15442 | 14:27:17 INFO - PID 15442 | Cycle 1(1): loaded http://localhost:39537/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39537/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:27:18 INFO - PID 15442 | RSS: Main: 173232128 14:27:18 INFO - PID 15442 | 14:27:20 INFO - PID 15442 | Cycle 1(1): loaded http://localhost:39537/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39537/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:27:21 INFO - PID 15442 | RSS: Main: 173342720 14:27:21 INFO - PID 15442 | 14:27:23 INFO - PID 15442 | Cycle 1(1): loaded http://localhost:39537/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39537/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:27:23 INFO - PID 15442 | RSS: Main: 173596672 14:27:23 INFO - PID 15442 | 14:27:26 INFO - PID 15442 | Cycle 1(1): loaded http://localhost:39537/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39537/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:27:27 INFO - PID 15442 | RSS: Main: 173793280 14:27:27 INFO - PID 15442 | 14:27:30 INFO - PID 15442 | Cycle 1(1): loaded http://localhost:39537/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39537/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:27:31 INFO - PID 15442 | RSS: Main: 174018560 14:27:31 INFO - PID 15442 | 14:27:33 INFO - PID 15442 | Cycle 1(1): loaded http://localhost:39537/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39537/tests/kraken/driver.html?testName=ai-astar) 14:27:33 INFO - PID 15442 | RSS: Main: 175661056 14:27:33 INFO - PID 15442 | 14:27:33 INFO - PID 15442 | __start_tp_report 14:27:33 INFO - PID 15442 | _x_x_mozilla_page_load 14:27:33 INFO - PID 15442 | _x_x_mozilla_page_load_details 14:27:33 INFO - PID 15442 | |i|pagename|runs| 14:27:33 INFO - PID 15442 | |0;ai-astar;101;112;101;108;117;118;109;99;109;109 14:27:33 INFO - PID 15442 | |1;audio-beat-detection;148;117;149;163;118;150;150;125;170;121 14:27:33 INFO - PID 15442 | |2;audio-dft;162;163;168;163;163;162;164;164;164;164 14:27:33 INFO - PID 15442 | |3;audio-fft;74;75;73;189;83;86;73;74;163;82 14:27:33 INFO - PID 15442 | |4;audio-oscillator;79;79;126;78;81;78;138;79;78;136 14:27:33 INFO - PID 15442 | |5;imaging-gaussian-blur;119;113;113;113;113;119;113;119;121;115 14:27:33 INFO - PID 15442 | |6;imaging-darkroom;150;151;150;154;151;149;150;150;149;150 14:27:33 INFO - PID 15442 | |7;imaging-desaturate;94;94;94;99;93;94;94;95;93;94 14:27:33 INFO - PID 15442 | |8;json-parse-financial;55;55;61;56;64;56;58;63;56;56 14:27:33 INFO - PID 15442 | |9;json-stringify-tinderbox;58;56;55;55;56;57;58;63;55;55 14:27:33 INFO - PID 15442 | |10;stanford-crypto-aes;86;83;77;84;81;78;77;86;93;80 14:27:33 INFO - PID 15442 | |11;stanford-crypto-ccm;137;124;126;122;122;132;129;122;120;168 14:27:33 INFO - PID 15442 | |12;stanford-crypto-pbkdf2;190;175;184;176;180;187;177;181;180;171 14:27:33 INFO - PID 15442 | |13;stanford-crypto-sha256-iterative;73;69;71;68;76;80;67;66;68;72 14:27:33 INFO - PID 15442 | __end_tp_report 14:27:33 INFO - PID 15442 | __start_cc_report 14:27:33 INFO - PID 15442 | _x_x_mozilla_cycle_collect,987 14:27:33 INFO - PID 15442 | __end_cc_report 14:27:33 INFO - PID 15442 | __startTimestamp1502486853242__endTimestamp 14:27:33 INFO - PID 15442 | 14:27:33 INFO - PID 15442 | ------- Summary: start ------- 14:27:33 INFO - PID 15442 | Number of tests: 14 14:27:33 INFO - PID 15442 | 14:27:33 INFO - PID 15442 | [#0] ai-astar Cycles:10 Average:108.30 Median:110.50 stddev:6.48 (5.9%) stddev-sans-first:6.31 14:27:33 INFO - PID 15442 | Values: 101.0 112.0 101.0 108.0 117.0 118.0 109.0 99.0 109.0 109.0 14:27:33 INFO - PID 15442 | 14:27:33 INFO - PID 15442 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:149.50 stddev:19.32 (12.9%) stddev-sans-first:20.33 14:27:33 INFO - PID 15442 | Values: 148.0 117.0 149.0 163.0 118.0 150.0 150.0 125.0 170.0 121.0 14:27:33 INFO - PID 15442 | 14:27:33 INFO - PID 15442 | [#2] audio-dft Cycles:10 Average:163.70 Median:164.00 stddev:1.70 (1.0%) stddev-sans-first:1.69 14:27:33 INFO - PID 15442 | Values: 162.0 163.0 168.0 163.0 163.0 162.0 164.0 164.0 164.0 164.0 14:27:33 INFO - PID 15442 | 14:27:33 INFO - PID 15442 | [#3] audio-fft Cycles:10 Average:97.20 Median:74.50 stddev:42.24 (56.7%) stddev-sans-first:43.96 14:27:33 INFO - PID 15442 | Values: 74.0 75.0 73.0 189.0 83.0 86.0 73.0 74.0 163.0 82.0 14:27:33 INFO - PID 15442 | 14:27:33 INFO - PID 15442 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:78.50 stddev:26.50 (33.8%) stddev-sans-first:27.45 14:27:33 INFO - PID 15442 | Values: 79.0 79.0 126.0 78.0 81.0 78.0 138.0 79.0 78.0 136.0 14:27:33 INFO - PID 15442 | 14:27:33 INFO - PID 15442 | [#5] imaging-gaussian-blur Cycles:10 Average:115.80 Median:117.00 stddev:3.29 (2.8%) stddev-sans-first:3.28 14:27:33 INFO - PID 15442 | Values: 119.0 113.0 113.0 113.0 113.0 119.0 113.0 119.0 121.0 115.0 14:27:33 INFO - PID 15442 | 14:27:33 INFO - PID 15442 | [#6] imaging-darkroom Cycles:10 Average:150.40 Median:150.00 stddev:1.43 (1.0%) stddev-sans-first:1.51 14:27:33 INFO - PID 15442 | Values: 150.0 151.0 150.0 154.0 151.0 149.0 150.0 150.0 149.0 150.0 14:27:33 INFO - PID 15442 | 14:27:33 INFO - PID 15442 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.00 stddev:1.71 (1.8%) stddev-sans-first:1.81 14:27:33 INFO - PID 15442 | Values: 94.0 94.0 94.0 99.0 93.0 94.0 94.0 95.0 93.0 94.0 14:27:33 INFO - PID 15442 | 14:27:33 INFO - PID 15442 | [#8] json-parse-financial Cycles:10 Average:58.00 Median:57.00 stddev:3.40 (6.0%) stddev-sans-first:3.43 14:27:33 INFO - PID 15442 | Values: 55.0 55.0 61.0 56.0 64.0 56.0 58.0 63.0 56.0 56.0 14:27:33 INFO - PID 15442 | 14:27:33 INFO - PID 15442 | [#9] json-stringify-tinderbox Cycles:10 Average:56.80 Median:56.50 stddev:2.49 (4.4%) stddev-sans-first:2.60 14:27:33 INFO - PID 15442 | Values: 58.0 56.0 55.0 55.0 56.0 57.0 58.0 63.0 55.0 55.0 14:27:33 INFO - PID 15442 | 14:27:33 INFO - PID 15442 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:83.50 stddev:5.02 (6.0%) stddev-sans-first:5.16 14:27:33 INFO - PID 15442 | Values: 86.0 83.0 77.0 84.0 81.0 78.0 77.0 86.0 93.0 80.0 14:27:33 INFO - PID 15442 | 14:27:33 INFO - PID 15442 | [#11] stanford-crypto-ccm Cycles:10 Average:130.20 Median:127.50 stddev:14.30 (11.2%) stddev-sans-first:14.96 14:27:33 INFO - PID 15442 | Values: 137.0 124.0 126.0 122.0 122.0 132.0 129.0 122.0 120.0 168.0 14:27:33 INFO - PID 15442 | 14:27:33 INFO - PID 15442 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.10 Median:180.50 stddev:5.74 (3.2%) stddev-sans-first:4.85 14:27:33 INFO - PID 15442 | Values: 190.0 175.0 184.0 176.0 180.0 187.0 177.0 181.0 180.0 171.0 14:27:33 INFO - PID 15442 | 14:27:33 INFO - PID 15442 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:71.50 stddev:4.40 (6.1%) stddev-sans-first:4.60 14:27:33 INFO - PID 15442 | Values: 73.0 69.0 71.0 68.0 76.0 80.0 67.0 66.0 68.0 72.0 14:27:33 INFO - PID 15442 | -------- Summary: end -------- 14:27:33 INFO - PID 15442 | 14:27:33 INFO - PID 15442 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 14:27:34 INFO - TEST-INFO | 15442: exit 0 14:27:34 INFO - TEST-OK | kraken | took 63360ms 14:27:34 INFO - SUITE-END | took 1175s 14:27:34 INFO - Completed test suite (00:19:35) 14:27:34 INFO - javascript benchmark 14:27:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8536.578412315845, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3137.6705445928164, 3165.398574270887, 3185.4266232078226, 3191.9999999999995, 3210.341879914618, 6097.207363042804, 6294.527910406715, 6341.524385671081, 6349.841253968647, 6368.29948705642, 6086.665233412172, 6227.626342419458, 6239.157713708648, 6244.094606282087, 6251.093591429251, 5673.886522269562, 5722.256106706122, 5792.507636850873, 5820.214271073393, 5868.706564671771, 5708.800191993281, 5775.018246898037, 5797.275340582427, 5799.3620701722775, 5810.389909059551, 5482.205080263359, 5597.160425936101, 5620.718964051802, 5699.572532060112, 5733.082706766897, 5653.660780353192, 5658.43415658435, 5722.399148089474, 5726.198332233491, 5737.225474560964, 5537.501624853742, 5563.916541251884, 5612.102063669834, 5642.689652069134, 5724.685142317171, 6167.044108163242, 6202.3797620238065, 6223.875522489525, 6247.531435142382, 6272.780452684134, 6687.531872768908, 6692.531522793358, 6788.185417749902, 6789.1174147360525, 6804.149481314836, 6677.766278180289, 6747.628880411576, 6758.357955994173, 6766.357196066366, 6860.725570977155, 6205.03821907605, 6240.375962403768, 6249.812505624838, 6256.374362563753, 6265.436110750009, 6816.147981502312, 6846.315368463213, 6912.758053468205, 6912.861742765116, 6920.930790692129, 6459.870802583922, 6519.706613202417, 6527.445167160845, 6570.080188773575, 6614.636195009224, 6623.602583844985, 6701.29636388182, 6720.2943690912725, 6720.596764194164, 6781.220159681699, 6664.067030615718, 6699.899501507482, 6711.932880671228, 6740.696668649923, 6759.594424334556, 6654.168228971379, 6667.0, 6753.561018533779, 6757.087793147862, 6761.661916904135, 5325.6272060956135, 5355.357357117093, 5407.270018547446, 5414.972925135349, 5419.160030195326, 4598.1033698429155, 4620.953790462119, 4631.606313463326, 4634.86095417138, 4635.629149668019, 5756.165356023437, 5792.82621521355, 5794.536437085023, 5851.561132915049, 5857.560682948711, 6585.86828263432, 6664.900026499607, 6690.866182676319, 6701.631410272482, 6731.831704207336, 5754.1944127822135, 5756.251687280626, 5813.970930145322, 5830.067189249699, 5836.649801011953, 5888.793892213752, 5898.587098903035, 5918.940810591839, 5959.433853783884, 5960.731767070492, 6338.873222535616, 6439.098526206335, 6465.353464653591, 6569.375909288515, 6572.704228309737, 6717.798466046026, 6738.764143254963, 6742.0, 6748.595084294958, 6757.425618822455, 6450.387213214737, 6493.675316234264, 6516.120323756232, 6517.73929042852, 6524.412802847766, 6574.27682954865, 6597.967010164918, 6676.365745254193, 6690.063391125149, 6696.129503164558, 6582.17722784652, 6619.304972977768, 6622.63575503352, 6626.337366263335, 6709.664516774239, 6399.168108145911, 6456.192975878015, 6486.286508484157, 6488.870222595616, 6490.8052758417325, 6159.5688301818445, 6472.35276472349, 6481.8703625928165, 6589.538732288694, 6598.637074960923, 6432.710528026344, 6503.187101612299, 6511.967440162769, 6519.282878883414, 6551.050097735897, 6526.706298216497, 6552.934470655233, 6558.934410655832, 6583.078369028244, 6631.701573429111, 6243.469305109116, 6350.523710721622, 6374.74501019954, 6406.679666016587, 6410.326915673787], "value": 6105.57218970397, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [832.0680837462044, 834.64110432514, 858.7938894665281, 887.0641473245723, 891.8929728432589, 40418.1916361673, 41236.0, 41422.17155656885, 41492.17015659685, 41617.16765664685, 37909.810450947705, 38062.429063564, 38287.61712382875, 38323.0, 38382.424263636065, 6741.191057073146, 6845.520813543054, 6876.312368763109, 6878.931210687904, 6893.103896493449, 6843.828904277408, 6851.760188393408, 6880.8967865482055, 6931.6880740366805, 6939.618320992344, 7599.392048636096, 7773.261540153676, 7783.377329813601, 7785.2214778521975, 7791.961040194791, 7707.576083315415, 7761.805954851117, 7766.145723970359, 7769.572673502955, 7775.0, 7386.667599957961, 7603.2776886195725, 7604.125525564576, 7622.8475430491635, 7704.499207551491, 39407.8029609853, 39708.40437393441, 39782.0, 40064.39903401451, 40108.0, 3536.734744894143, 3613.2773445311045, 3626.12972886508, 3628.0385697790107, 3634.890953271406, 31577.526337104962, 31821.681783182103, 31919.680803191903, 31942.0, 32001.199970000704, 5077.847664570068, 5111.974440127813, 5117.309163262949, 5117.872053198663, 5153.201253805666, 188662.0, 189480.0525997361, 190800.04599976912, 191732.0, 192416.0, 49228.0, 49451.258231126565, 49715.75142124266, 49739.0, 49805.50194498081, 51893.74053129786, 51895.0, 51922.74038629858, 51936.0, 51976.740116299174, 16000.199990000687, 16126.113063781604, 16140.43508477221, 16147.0, 16153.192340382933, 13053.412596432994, 13062.934685326512, 13067.607971760863, 13089.345532723326, 13101.082924195405, 14610.123392596479, 14798.48205312809, 14840.40638374453, 14843.554693359216, 14980.775288370683, 5921.5558833086825, 5998.1002849572915, 6007.219061522062, 6029.306629737636, 6102.603330783485, 975.1321324021626, 1004.7789486313, 1005.8843233028149, 1006.8389057750725, 1020.2908978260039, 23067.077316907136, 23111.422214444436, 23157.768422315898, 23195.53608927846, 23336.883315583313, 1091.5470079916904, 1097.9341239525654, 1103.6578660615075, 1112.6995711157942, 1116.7989761842784, 12070.758584828254, 12348.506059757508, 12399.566015189424, 12457.439415226336, 12497.75004499905, 209255.0, 209714.0, 210182.0, 210247.0, 211553.0, 53749.0, 54014.72992635012, 54619.45380546144, 56708.0, 56803.0, 1454.2365258239508, 1478.7042591481747, 1480.9703805924037, 1492.2986196487634, 1498.572906721601, 27184.184474465797, 27320.726792732614, 27410.725892741615, 27495.862520687268, 27704.86147569249, 34120.82939585286, 34143.14642133967, 34145.14637134092, 34165.31669366549, 34197.487037694955, 194230.0, 194546.0, 194586.0, 195929.0, 196050.0, 32765.50851737178, 33016.33967320688, 33068.33863322768, 33101.503477447375, 33121.0, 42856.57143428526, 42906.0, 46047.76976115098, 46984.53015469938, 47081.0, 34078.65921340755, 34131.0, 34132.317353653285, 34176.316473670886, 34492.31015379728, 34015.0, 34119.0, 34345.82827085849, 34414.655853441145, 34456.483152752226, 37391.06522336963, 37504.0, 37532.43701344427, 37685.4347184787, 37736.433953490174, 32764.0, 33313.666863331055, 33993.490097649046, 34769.0, 35007.0, 25195.874020630512, 25328.873355633103, 25436.364090897874, 25549.61675574905, 25551.23346299614], "value": 17279.504603078876, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.9718014099296, 580.7967211475981, 581.7818318130703, 590.5187272373016, 592.2715060475615, 22571.210007649737, 22607.660885086738, 23526.0, 23625.881870590645, 23656.05375784971, 25744.098956536523, 26353.472930541426, 26370.47259054818, 26431.735682643168, 26448.074317398896, 6498.122753428274, 6650.933490665104, 6657.733690652368, 6658.201015878077, 6658.5671931324305, 6186.659733714644, 6483.319251478598, 6517.315681853408, 6542.182227221598, 6620.503462240327, 6458.93541064588, 6465.773697920574, 6471.126397936289, 6473.6115833049935, 6476.870462590746, 6399.200099987502, 6410.583312084699, 6410.8076757697345, 6435.8391040223905, 6441.645709485976, 5976.163337132805, 6010.729517171694, 6025.728842202111, 6035.245594300713, 6043.244594425697, 29294.70705292941, 29976.55035174474, 30445.391092178255, 30967.690323096707, 31185.844070779727, 1949.717290992798, 1987.5031242189452, 1990.3829812757947, 1992.0139530932136, 1997.0, 19886.105125269398, 20494.38516844497, 20524.89737551303, 20531.794682053285, 20610.484737881525, 2390.378501589599, 2415.8792060397086, 2463.864487453189, 2521.71000334962, 2539.619057141425, 135008.32495837458, 139983.30008349995, 140218.29890850582, 140226.89659655115, 140801.0, 14224.21766802815, 14747.262636868114, 15206.543803685907, 15243.695126097638, 15317.23413829304, 23009.19467818652, 23539.0, 23633.881830591083, 23692.0, 23803.40491487727, 10016.899831001743, 10045.698629040995, 10176.542055607515, 10208.897911020944, 10350.430726309974, 9247.583858726373, 9262.861057084148, 9291.256699464027, 9447.905520944703, 9482.715518534455, 9353.0, 9414.811703766023, 9545.949945505989, 9740.512974351253, 9890.406575605486, 2846.900358487443, 2852.443773464196, 2897.6522817261643, 2923.4591600553968, 2991.0727674421, 960.6685693435753, 985.1478471122427, 990.2919412620012, 997.3068717241592, 998.1515711645188, 19897.403077907686, 20103.899480502798, 20205.797942020392, 20244.392668219978, 20315.101959415548, 1034.4879284754095, 1045.362328979322, 1046.3251202974038, 1048.3290693955873, 1050.0, 11022.614208502664, 11041.00630943219, 11042.33745975228, 11089.445527723581, 11225.887741122648, 434615.0, 462059.0, 519856.0, 527224.0, 532695.0, 121039.0, 121538.0, 123583.382083089, 125930.3703481495, 126622.0, 1273.840769903762, 1302.7134030513273, 1316.7300703355788, 1328.7408955253827, 1335.6994676197778, 20277.391678249674, 20618.175272988912, 21077.683834742184, 21246.25638102659, 22351.329460116223, 61110.69444652748, 61575.0, 62063.0, 62725.0, 63992.0, 69092.30907691059, 69998.65000674964, 70210.64894675698, 70369.64815176096, 70690.0, 14820.555383338517, 15032.549023529311, 15045.097294162386, 15055.343912169872, 15095.094294341936, 23624.055037798295, 23638.52722945566, 23839.880800595885, 23901.641475377535, 23958.0, 18676.81323186751, 18754.6249075015, 18755.437336879917, 19220.807791921903, 19262.036898155315, 16461.506154815375, 16947.91526042362, 17302.826971730625, 17448.0, 17888.642227155644, 18762.061896905372, 19081.0, 19297.710534341717, 19446.708299375237, 19558.11988460494, 14826.555203343916, 14887.10677359362, 14953.40186392532, 14964.775528367301, 15019.249037547861, 18661.440156795317, 18734.063296835375, 18854.0, 18956.336528221447, 18963.90518047456], "value": 12276.158660424693, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.8015237790519, 1381.5717127690416, 1420.126622127392, 1421.4101148023572, 1425.5367005723142, 14133.363998620061, 14311.0, 14680.706385872289, 14718.0, 14750.852491475081, 14958.401663933457, 15045.398184072625, 15087.547373578811, 15164.469243576477, 15241.92379038106, 2739.6027576001516, 2801.6638003439516, 2801.789865760066, 2801.9439611207763, 2802.2854172186167, 2576.1369941069765, 2755.1734479656125, 2759.213624117127, 2766.0318888389106, 2766.460540194663, 2739.369944912671, 2748.670159580843, 2772.556390977444, 2776.361436869521, 2791.818531795437, 2652.854093024883, 2676.892924283036, 2707.6615423072117, 2715.456908618274, 2726.9590956135576, 2642.4318771464227, 2652.5490666586725, 2662.3077999720017, 2664.8667566621593, 2667.039865648365, 14344.211068391234, 14614.34235459407, 14685.926570367186, 14710.264486775619, 14736.042157259744, 7789.0, 8012.719554815553, 8041.597920103972, 8067.394945379061, 8069.95965020177, 5122.846314610568, 5232.9738351308, 5277.47225277473, 5381.5425688816495, 5383.569314454834, 1844.2991663168045, 1855.8979256140906, 1879.577095153593, 1902.9524261893425, 1913.770347558288, 24144.154954576683, 24505.63241551378, 24543.754562454327, 24544.913682337025, 24577.508449831083, 6448.871022579522, 6485.383888530582, 6654.168228971379, 6672.666366681647, 6688.398044176048, 16636.08501532403, 16641.83358166403, 16651.0, 16660.58348541272, 16664.916675416545, 7678.078630564368, 7824.882626760603, 7827.295543401121, 7834.4907581007055, 7836.843263134705, 6948.5483443576, 7108.8933665995055, 7141.571505709674, 7178.353948144692, 7179.174394944544, 5043.193089105799, 5295.311609490818, 5315.468453154731, 5351.25082488452, 5352.4915133062295, 1508.720886635954, 1519.3011214841076, 1532.3793863485307, 1537.3005282596391, 1552.8214255360551, 839.9538025408539, 865.3466632692403, 873.4060838629792, 877.7937184761576, 883.015437786863, 7620.3522700569965, 7879.33025692811, 7886.960565197137, 7897.68409263623, 7971.920280797234, 701.4668851672664, 723.7539236659561, 726.3136336162381, 730.868443680132, 739.9963000184964, 4293.592108749664, 4295.548967358445, 4763.118823017753, 4963.032208719265, 5011.0, 19166.329178478685, 19341.709874351614, 19739.40781776549, 20007.899960500104, 20177.192912283637, 20335.491612709506, 20453.48866278355, 20455.590888182152, 20476.180952761722, 20502.0, 10522.0, 11133.220674552866, 11207.047400970845, 11302.434878256054, 11307.886921130848, 7501.774946751606, 7608.277213664693, 7667.118281397487, 7680.884786728092, 7687.846243074995, 16874.0, 17106.91446542759, 17144.485665430057, 17255.82744172542, 17501.124943752508, 17901.283948641907, 17963.281468741105, 17978.0, 17985.100744962438, 18101.547461313046, 10925.071368933412, 11008.669739907815, 11119.16606254518, 11127.443627818739, 11161.274517156684, 13571.0, 13573.253471059186, 13634.659133521345, 13742.175469471864, 13746.381412836246, 11100.500477478372, 11269.154813388865, 11290.209685321948, 11362.829557556477, 11389.601363952223, 10046.69859904204, 10176.592936282466, 10247.333923294962, 10441.895581044397, 10488.580456781643, 9521.238300936047, 9653.131218190396, 9675.564599592895, 9686.660966866126, 9688.5640146195, 6884.380405763505, 6916.170059592881, 6986.54587451814, 7023.859522809413, 7071.186813516511, 6937.340952609696, 6960.23437421873, 7049.753258635923, 7085.114360704912, 7107.928920710727], "value": 6437.741273732032, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [513.8484147176582, 516.1663912780858, 520.5237207954722, 535.2390634811472, 535.2880668710616, 6288.717007734657, 6304.71628776705, 6314.842128946779, 6319.652419116953, 6341.778037768679, 6136.907946380797, 6206.565540412162, 6209.782657606985, 6212.285587157478, 6225.190725205728, 5156.948430515694, 5160.638755287131, 5164.896702065957, 5169.483051694838, 5171.974140129294, 4895.975520122394, 5137.486251374851, 5143.717095559743, 5176.0331074191945, 5207.973960130194, 4854.368932038848, 4878.585320247783, 4883.560479556839, 4928.728919909404, 4933.260010998343, 4892.315075889361, 4909.45995940448, 4909.950900491021, 4932.224399216315, 5013.573846223076, 4367.2575662137515, 4433.933490997638, 4442.333649952533, 4443.511213766483, 4451.688381813242, 5915.5859089863725, 5935.762569497215, 6005.879882402328, 6012.789552365646, 6037.879242415171, 5674.177244299594, 5687.74405151765, 5701.942980570183, 5733.569982251348, 5756.424357564252, 6518.348165183491, 6559.540832141752, 6568.244651865001, 6574.309697481744, 6607.768728094494, 4860.975695121501, 4863.270509423614, 4877.902441951177, 4908.0, 4927.1870141426625, 6545.312742162052, 6709.664516774142, 6811.591304521745, 6846.486513511457, 6879.5184337096425, 4969.975150124226, 5014.598832093424, 5014.874628134253, 5016.498350164954, 5043.293938848564, 4987.600991920638, 5034.496550344937, 5066.417362003417, 5093.911496286505, 5123.97438012815, 5620.538659948443, 5651.26533550644, 5655.773769049274, 5681.914771278434, 5701.230333904953, 6585.703643336062, 6597.536293445979, 6620.0, 6634.369734875179, 6730.495212859055, 1773.796013458443, 1779.590694140355, 1781.5902342461306, 1787.436957358454, 1799.4884297190422, 1175.2419689300352, 1206.9698257543632, 1219.5365761010937, 1235.9011279097651, 1252.5866464066837, 5781.306243250837, 5789.536837053026, 5812.94187058124, 5862.5896187267335, 5881.735321910524, 6628.5976928802875, 6703.396694297537, 6704.363085506869, 6760.594364338155, 6766.5263431559315, 5716.914246286309, 5787.913181302284, 5789.9710501447225, 5851.795187168429, 5884.558658100659, 3524.453709674953, 3534.4168212245204, 3538.628444013393, 3544.858205671796, 3586.479960405779, 6406.263279722892, 6431.74273029083, 6455.322191169954, 6554.475641948633, 6593.406593406617, 6785.185777706608, 6822.420094292041, 6848.623325717133, 6851.417629501449, 6907.689153988083, 6467.579607325415, 6490.318516555788, 6498.740050397932, 6519.674016299166, 6616.86766264672, 6136.378369868335, 6517.967410162919, 6663.666816659244, 6674.229060580757, 6777.695003724943, 6585.670716464062, 6611.537192396486, 6650.37065918853, 6724.193096828412, 6725.529212955046, 6475.190601174853, 6503.284638689645, 6526.24948130971, 6565.868682626417, 6582.637954912526, 5031.320771695774, 5040.697558146523, 5071.467495913023, 5082.644214904921, 5085.898282034264, 6534.379233972765, 6639.336066393322, 6682.131322927989, 6754.797356079325, 6791.3548212919695, 6361.363863613602, 6542.8364290893105, 6595.208574970842, 6598.40614344692, 6626.105475760904, 6559.868802624016, 6566.573172743756, 6571.408573228241, 6572.432159648998, 6572.474202063919, 6360.872782544416, 6477.546571740121, 6489.643069631215, 6494.545381823416, 6531.836704082246, 6579.342065793382, 6584.703688333941, 6644.0, 6649.202095748347, 6651.933480665131, 6379.776707815204, 6385.541566497025, 6389.808305750835, 6390.936090639034, 6414.711337989708], "value": 5067.245846677087, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [893.334465822962, 893.5710858787781, 910.2990697163183, 910.6858133943787, 921.3642586615235, 18762.718559221623, 19726.90136549317, 19818.0, 19938.70091948622, 22526.88736556319, 21435.571288574258, 21589.352319430403, 21683.13267469299, 21839.563208735857, 21958.121675132974, 1009.8535712321709, 1053.7629033467485, 1053.8050460664763, 1054.6572363981697, 1054.7204990677476, 1026.1636766035695, 1059.4384975962753, 1067.765091679829, 1071.4803320389606, 1073.8013467508497, 1112.7440688641618, 1116.547798141754, 1118.3066498770775, 1118.8098023336051, 1119.1774046076127, 1106.269861891148, 1107.1530279336314, 1115.8326251062324, 1116.2576886370555, 1116.7878103160415, 810.4488947515687, 810.4812919731377, 810.7648781853255, 811.0, 811.1645005644137, 18763.718544221847, 18866.71699924502, 19103.23587056516, 19883.0, 20455.079521421572, 21335.146594136215, 21357.145714171413, 21455.46361340979, 21800.127994880346, 21804.672929906064, 16575.25411356492, 16622.667546648998, 16771.413000544922, 16771.664566708718, 16870.15649217534, 3609.2420591675777, 3624.963750362489, 3627.038834708804, 3636.072801435643, 3637.2179981303884, 126827.0, 127280.36359818235, 127754.36122819419, 129209.0, 130921.34539327242, 16989.575260618338, 17072.317107315568, 17224.224909879085, 17550.297988080332, 17553.736693949602, 30753.538696919564, 31064.844675776476, 31184.0, 31661.52507712386, 31744.682553174174, 7837.255460731221, 8132.105468398471, 8133.674653013932, 8259.504429734236, 8383.874241886377, 4311.374850646702, 4377.956220437755, 4387.868363949023, 4396.780160991938, 4400.44994375703, 5230.738463076831, 5303.973480132652, 5320.920186197211, 5337.759800808973, 5354.250404943311, 1074.8763892152347, 1087.6682611803317, 1098.357460885375, 1098.8241881299114, 1099.6700989703218, 664.5647101148755, 681.1383599746325, 685.1059367525427, 693.660106547798, 699.9965000174966, 11417.959623794384, 11465.541378344773, 11936.880631193577, 12054.879451205552, 12070.577529786415, 680.8638272345551, 685.4447897203281, 685.7085738561092, 703.0754557756534, 703.6341102626606, 5563.860903477445, 5569.832905012856, 5639.464250896158, 5655.208270842086, 5656.123300888369, 108215.0, 110237.0, 110654.0, 110914.44542777234, 112867.43566282115, 98159.50920245353, 98685.0, 99794.0, 103332.48333758283, 103709.48145259226, 85866.0, 86806.13193868106, 87056.12943870606, 88370.11629883619, 90189.0, 10486.056254937092, 10581.735456613646, 10634.09610183112, 10714.892851071701, 10947.34315941046, 41069.58930410777, 41107.7944610275, 41670.0, 42948.78525607352, 43461.348079778196, 51091.489085108675, 53362.0, 53991.73004134954, 54101.0, 55223.17165242605, 14762.114273143647, 14924.925375373054, 14982.775258370915, 15102.697946041237, 15650.765238521659, 18303.0, 21433.678494822903, 21522.354329370144, 21605.891970540048, 21936.780632193473, 18516.537086572403, 18526.62946741031, 18601.348952786586, 18704.7194292083, 18831.529211769815, 17687.0, 17753.822461775217, 17859.107044647975, 17890.28438862483, 18021.63956720832, 18442.35451759182, 18483.16825742818, 18500.722489162403, 18506.352277670216, 18521.629567408847, 13276.867231327564, 13615.251161185834, 13723.931380343034, 13777.242251676254, 13819.516316928859, 13294.069415140848, 13295.534656286984, 13335.199888006751, 13413.0, 13461.125026873222], "value": 9159.049091550442, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1544.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 101.0, 108.0, 117.0, 118.0, 109.0, 99.0, 109.0, 109.0], "value": 108.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 117.0, 149.0, 163.0, 118.0, 150.0, 150.0, 125.0, 170.0, 121.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 168.0, 163.0, 163.0, 162.0, 164.0, 164.0, 164.0, 164.0], "value": 163.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 73.0, 189.0, 83.0, 86.0, 73.0, 74.0, 163.0, 82.0], "value": 97.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 126.0, 78.0, 81.0, 78.0, 138.0, 79.0, 78.0, 136.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 113.0, 113.0, 113.0, 113.0, 119.0, 113.0, 119.0, 121.0, 115.0], "value": 115.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 150.0, 154.0, 151.0, 149.0, 150.0, 150.0, 149.0, 150.0], "value": 150.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 99.0, 93.0, 94.0, 94.0, 95.0, 93.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 61.0, 56.0, 64.0, 56.0, 58.0, 63.0, 56.0, 56.0], "value": 58.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 55.0, 55.0, 56.0, 57.0, 58.0, 63.0, 55.0, 55.0], "value": 56.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 77.0, 84.0, 81.0, 78.0, 77.0, 86.0, 93.0, 80.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 124.0, 126.0, 122.0, 122.0, 132.0, 129.0, 122.0, 120.0, 168.0], "value": 130.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 175.0, 184.0, 176.0, 180.0, 187.0, 177.0, 181.0, 180.0, 171.0], "value": 180.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 69.0, 71.0, 68.0, 76.0, 80.0, 67.0, 66.0, 68.0, 72.0], "value": 71.0, "unit": "score"}]}]} 14:27:34 INFO - Return code: 0 14:27:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:27:34 INFO - # TBPL SUCCESS # 14:27:34 INFO - Running post-action listener: _package_coverage_data 14:27:34 INFO - Running post-action listener: _resource_record_post_action 14:27:34 INFO - [mozharness: 2017-08-11 21:27:34.524857Z] Finished run-tests step (success) 14:27:34 INFO - Running post-run listener: _resource_record_post_run 14:27:34 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 53248; Write bytes: 442642432; Read time: 108; Write time: 392156 14:27:34 INFO - TinderboxPrint: CPU usage
13.1% 14:27:34 INFO - TinderboxPrint: I/O read bytes / time
53,248 / 108 14:27:34 INFO - TinderboxPrint: I/O write bytes / time
442,642,432 / 392,156 14:27:34 INFO - TinderboxPrint: CPU idle
8,268.0 (86.8%) 14:27:34 INFO - TinderboxPrint: CPU user
1,199.9 (12.6%) 14:27:34 INFO - TinderboxPrint: Swap in / out
0 / 0 14:27:34 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 109961216; Read time: 0; Write time: 151076 14:27:34 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 14:27:34 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 53248; Write bytes: 332681216; Read time: 108; Write time: 241080 14:27:35 INFO - Running post-run listener: _upload_blobber_files 14:27:35 INFO - Blob upload gear active. 14:27:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:27:35 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 14:27:35 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:27:35 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:27:35 INFO - (blobuploader) - INFO - Open directory for files ... 14:27:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:27:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:27:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:27:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:27:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:27:36 INFO - (blobuploader) - INFO - Done attempting. 14:27:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:27:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:27:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:27:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:27:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:27:39 INFO - (blobuploader) - INFO - Done attempting. 14:27:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:27:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:27:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:27:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:27:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:27:40 INFO - (blobuploader) - INFO - Done attempting. 14:27:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:27:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:27:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:27:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:27:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:27:41 INFO - (blobuploader) - INFO - Done attempting. 14:27:41 INFO - (blobuploader) - INFO - Iteration through files over. 14:27:41 INFO - Return code: 0 14:27:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:27:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:27:41 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3ddd4621f4af8ca461adbe5d974d00625e1d2ac64b36e1b1696e5aa04ba94215dc945ddf2a0bdbded14a6898b2d2f02a8e5b087e91ad1e46cd2e4af41e5b9fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb98c33abac6807c8bb3d7931b98c3d6d3d625c7dd05342ca407e4d815bb4566c94c7655a79c2cff66e12f841116940479f64c89e57107b0678661dd3f73a7eb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41f47ad6f01c7179b38036afd2e88b14a5c871ca23c95c698f2e52200ccfe54b5e94552a5009b6316e9fe5e1219b433ff7bdda5b64fbe899600444965240fa23", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/608b245c89f9fc2c325b842a2a7c3e5f0c7f42e77ef541dfafa0041badf9a0cd4e3dae6504a136ae07f0ffd9848b8559784e5776dec60ad52ebcb65e07ff9d99"} 14:27:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:27:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:27:41 INFO - Contents: 14:27:41 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3ddd4621f4af8ca461adbe5d974d00625e1d2ac64b36e1b1696e5aa04ba94215dc945ddf2a0bdbded14a6898b2d2f02a8e5b087e91ad1e46cd2e4af41e5b9fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb98c33abac6807c8bb3d7931b98c3d6d3d625c7dd05342ca407e4d815bb4566c94c7655a79c2cff66e12f841116940479f64c89e57107b0678661dd3f73a7eb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41f47ad6f01c7179b38036afd2e88b14a5c871ca23c95c698f2e52200ccfe54b5e94552a5009b6316e9fe5e1219b433ff7bdda5b64fbe899600444965240fa23", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/608b245c89f9fc2c325b842a2a7c3e5f0c7f42e77ef541dfafa0041badf9a0cd4e3dae6504a136ae07f0ffd9848b8559784e5776dec60ad52ebcb65e07ff9d99"} 14:27:41 INFO - Running post-run listener: copy_logs_to_upload_dir 14:27:41 INFO - Copying logs to upload dir... 14:27:41 INFO - mkdir: /builds/slave/test/build/upload/logs 14:27:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.634001 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-08-11 14:27:40.825075) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-11 14:27:40.829014) ========= 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=9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3ddd4621f4af8ca461adbe5d974d00625e1d2ac64b36e1b1696e5aa04ba94215dc945ddf2a0bdbded14a6898b2d2f02a8e5b087e91ad1e46cd2e4af41e5b9fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb98c33abac6807c8bb3d7931b98c3d6d3d625c7dd05342ca407e4d815bb4566c94c7655a79c2cff66e12f841116940479f64c89e57107b0678661dd3f73a7eb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41f47ad6f01c7179b38036afd2e88b14a5c871ca23c95c698f2e52200ccfe54b5e94552a5009b6316e9fe5e1219b433ff7bdda5b64fbe899600444965240fa23", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/608b245c89f9fc2c325b842a2a7c3e5f0c7f42e77ef541dfafa0041badf9a0cd4e3dae6504a136ae07f0ffd9848b8559784e5776dec60ad52ebcb65e07ff9d99"} build_url:https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011308 build_url: 'https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3ddd4621f4af8ca461adbe5d974d00625e1d2ac64b36e1b1696e5aa04ba94215dc945ddf2a0bdbded14a6898b2d2f02a8e5b087e91ad1e46cd2e4af41e5b9fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb98c33abac6807c8bb3d7931b98c3d6d3d625c7dd05342ca407e4d815bb4566c94c7655a79c2cff66e12f841116940479f64c89e57107b0678661dd3f73a7eb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41f47ad6f01c7179b38036afd2e88b14a5c871ca23c95c698f2e52200ccfe54b5e94552a5009b6316e9fe5e1219b433ff7bdda5b64fbe899600444965240fa23", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/608b245c89f9fc2c325b842a2a7c3e5f0c7f42e77ef541dfafa0041badf9a0cd4e3dae6504a136ae07f0ffd9848b8559784e5776dec60ad52ebcb65e07ff9d99"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-11 14:27:40.856542) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 14:27:40.856906) ========= 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=9bfb7e13875ed353a705960e000001c0-1502485607.450679-265744408 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004478 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 14:27:40.941603) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-11 14:27:40.941954) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-11 14:27:40.942297) ========= ========= Total master_lag: 0.19 =========