builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-073 starttime: 1506175936.22 results: success (0) revision: 3828e44f5b0a56e7933b817d430cf32d41b03fe6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 07:12:16.221173) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 07:12:16.221646) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 07:12:16.221942) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 07:12:16.242177) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 07:12:16.242546) ========= 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=9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-23 07:12:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 103M=0s 2017-09-23 07:12:16 (103 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.136351 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 07:12:16.422682) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 07:12:16.427094) ========= 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=9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022321 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 07:12:16.476727) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 07:12:16.479156) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3828e44f5b0a56e7933b817d430cf32d41b03fe6 --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 3828e44f5b0a56e7933b817d430cf32d41b03fe6 --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=9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-23 07:12:16,596 truncating revision to first 12 chars 2017-09-23 07:12:16,596 Setting DEBUG logging. 2017-09-23 07:12:16,596 attempt 1/10 2017-09-23 07:12:16,597 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3828e44f5b0a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-23 07:12:16,961 unpacking tar archive at: mozilla-central-3828e44f5b0a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.728074 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 07:12:17.234933) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 07:12:17.235266) ========= script_repo_revision: 3828e44f5b0a56e7933b817d430cf32d41b03fe6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 07:12:17.235651) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 07:12:17.235932) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 07:12:17.263981) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-23 07:12:17.264253) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:12:17 INFO - MultiFileLogger online at 20170923 07:12:17 in /builds/slave/test 07:12:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 07:12:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:12:17 INFO - {'append_to_log': False, 07:12:17 INFO - 'base_work_dir': '/builds/slave/test', 07:12:17 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:12:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:12:17 INFO - 'branch': 'Firefox-Non-PGO', 07:12:17 INFO - 'buildbot_json_path': 'buildprops.json', 07:12:17 INFO - 'code_coverage': False, 07:12:17 INFO - 'config_files': ('talos/linux_config.py',), 07:12:17 INFO - 'default_actions': ('clobber', 07:12:17 INFO - 'read-buildbot-config', 07:12:17 INFO - 'download-and-extract', 07:12:17 INFO - 'populate-webroot', 07:12:17 INFO - 'create-virtualenv', 07:12:17 INFO - 'install', 07:12:17 INFO - 'setup-mitmproxy', 07:12:17 INFO - 'run-tests'), 07:12:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:12:17 INFO - 'disable_ccov_upload': False, 07:12:17 INFO - 'disable_stylo': False, 07:12:17 INFO - 'download_minidump_stackwalk': True, 07:12:17 INFO - 'download_symbols': 'ondemand', 07:12:17 INFO - 'enable_stylo': False, 07:12:17 INFO - 'enable_webrender': False, 07:12:17 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 07:12:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:12:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:12:17 INFO - 'gecko_profile': False, 07:12:17 INFO - 'gecko_profile_interval': 0, 07:12:17 INFO - 'installer_path': 'installer.exe', 07:12:17 INFO - 'log_level': 'info', 07:12:17 INFO - 'log_name': 'talos', 07:12:17 INFO - 'log_to_console': True, 07:12:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:12:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:12:17 INFO - 'opt_config_files': (), 07:12:17 INFO - 'pip_index': False, 07:12:17 INFO - 'suite': 'dromaeojs-e10s', 07:12:17 INFO - 'system_bits': '32', 07:12:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:12:17 INFO - 'title': 'talos-linux64-ix-073', 07:12:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:12:17 INFO - 'use_talos_json': True, 07:12:17 INFO - 'verify': 'False', 07:12:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:12:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:12:17 INFO - 'work_dir': 'build'} 07:12:17 INFO - [mozharness: 2017-09-23 14:12:17.530913Z] Running clobber step. 07:12:17 INFO - Running pre-action listener: _resource_record_pre_action 07:12:17 INFO - Running main action method: clobber 07:12:17 INFO - rmtree: /builds/slave/test/build 07:12:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:12:18 INFO - Running post-action listener: _resource_record_post_action 07:12:18 INFO - [mozharness: 2017-09-23 14:12:18.526711Z] Finished clobber step (success) 07:12:18 INFO - [mozharness: 2017-09-23 14:12:18.526809Z] Running read-buildbot-config step. 07:12:18 INFO - Running pre-action listener: _resource_record_pre_action 07:12:18 INFO - Running main action method: read_buildbot_config 07:12:18 INFO - Using buildbot properties: 07:12:18 INFO - { 07:12:18 INFO - "project": "", 07:12:18 INFO - "product": "firefox", 07:12:18 INFO - "who": "philringnalda@gmail.com", 07:12:18 INFO - "installer_path": "public/build/target.tar.bz2", 07:12:18 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 07:12:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 07:12:18 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 07:12:18 INFO - "stage_platform": "linux64", 07:12:18 INFO - "basedir": "/builds/slave/test", 07:12:18 INFO - "buildnumber": 858, 07:12:18 INFO - "platform": "ubuntu64_hw", 07:12:18 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:12:18 INFO - "slavebuilddir": "test", 07:12:18 INFO - "taskId": "LNpHdkhrRC2YicZhZy-_WA", 07:12:18 INFO - "branch": "mozilla-central", 07:12:18 INFO - "script_repo_revision": "production", 07:12:18 INFO - "revision": "3828e44f5b0a56e7933b817d430cf32d41b03fe6", 07:12:18 INFO - "slavename": "talos-linux64-ix-073", 07:12:18 INFO - "repo_path": "mozilla-central" 07:12:18 INFO - } 07:12:18 INFO - Finding installer, test and symbols from parent task. 07:12:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LNpHdkhrRC2YicZhZy-_WA'}, attempt #1 07:12:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LNpHdkhrRC2YicZhZy-_WA'}, attempt #1 07:12:19 INFO - Task dependencies: RZ7fYPz7S4aFd-96ndeTBg 07:12:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg'}, attempt #1 07:12:22 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.tar.bz2 07:12:22 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.test_packages.json 07:12:22 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.crashreporter-symbols.zip 07:12:22 INFO - Running post-action listener: _resource_record_post_action 07:12:22 INFO - [mozharness: 2017-09-23 14:12:22.898603Z] Finished read-buildbot-config step (success) 07:12:22 INFO - [mozharness: 2017-09-23 14:12:22.898824Z] Running download-and-extract step. 07:12:22 INFO - Running pre-action listener: _resource_record_pre_action 07:12:22 INFO - Running main action method: download_and_extract 07:12:22 INFO - mkdir: /builds/slave/test/build/tests 07:12: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')]} 07:12:22 INFO - trying https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.test_packages.json 07:12:22 INFO - Downloading https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 07:12:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 07:12:23 INFO - Downloaded 989 bytes. 07:12:23 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 07:12:23 INFO - Using the following test package requirements: 07:12:23 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 07:12:23 INFO - u'common': [u'target.common.tests.zip'], 07:12:23 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 07:12:23 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 07:12:23 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 07:12:23 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 07:12:23 INFO - u'mozbase': [u'target.common.tests.zip'], 07:12:23 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 07:12:23 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 07:12:23 INFO - u'web-platform': [u'target.common.tests.zip', 07:12:23 INFO - u'target.web-platform.tests.tar.gz'], 07:12:23 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 07:12:23 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 07:12:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.common.tests.zip 07:12:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:12:23 INFO - Fetch https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.common.tests.zip into memory 07:12:24 INFO - Content-Length response header: 54943978 07:12:24 INFO - Bytes received: 54943978 07:12:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.talos.tests.zip 07:12:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 07:12:35 INFO - Fetch https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.talos.tests.zip into memory 07:12:35 INFO - Content-Length response header: 14513823 07:12:35 INFO - Bytes received: 14513823 07:12:36 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')]} 07:12:36 INFO - trying https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.tar.bz2 07:12:36 INFO - Downloading https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 07:12:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 07:12:38 INFO - Downloaded 61580232 bytes. 07:12:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.tar.bz2 07:12:38 INFO - mkdir: /builds/slave/test/properties 07:12:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:12:38 INFO - Writing to file /builds/slave/test/properties/build_url 07:12:38 INFO - Contents: 07:12:38 INFO - build_url:https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.tar.bz2 07:12:38 INFO - Running post-action listener: _resource_record_post_action 07:12:38 INFO - Running post-action listener: find_tests_for_verification 07:12:38 INFO - Running post-action listener: set_extra_try_arguments 07:12:38 INFO - [mozharness: 2017-09-23 14:12:38.312979Z] Finished download-and-extract step (success) 07:12:38 INFO - [mozharness: 2017-09-23 14:12:38.313080Z] Running populate-webroot step. 07:12:38 INFO - Running pre-action listener: _resource_record_pre_action 07:12:38 INFO - Running main action method: populate_webroot 07:12:38 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:12:38 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:12:38 INFO - 'tests': ['tresize', 07:12:38 INFO - 'tcanvasmark']}, 07:12:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:12:38 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:12:38 INFO - 'tests': ['dromaeo_css', 07:12:38 INFO - 'kraken']}, 07:12:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:12:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:12:38 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:12:38 INFO - 'talos_options': ['--disable-stylo'], 07:12:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:12:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:12:38 INFO - 'tests': ['damp', 'tps']}, 07:12:38 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:12:38 INFO - 'talos_options': ['--disable-stylo'], 07:12:38 INFO - 'tests': ['damp', 'tps']}, 07:12:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:12:38 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:12:38 INFO - 'tests': ['dromaeo_dom']}, 07:12:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:12:38 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:12:38 INFO - 'tests': ['basic_compositor_video', 07:12:38 INFO - 'glvideo']}, 07:12:38 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 07:12:38 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 07:12:38 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:12:38 INFO - 'talos_options': ['--disable-stylo'], 07:12:38 INFO - 'tests': ['ts_paint_webext', 07:12:38 INFO - 'tp5o_webext']}, 07:12:38 INFO - 'other-e10s': {'tests': ['a11yr', 07:12:38 INFO - 'ts_paint', 07:12:38 INFO - 'tpaint', 07:12:38 INFO - 'sessionrestore', 07:12:38 INFO - 'sessionrestore_many_windows', 07:12:38 INFO - 'sessionrestore_no_auto_restore', 07:12:38 INFO - 'tabpaint']}, 07:12:38 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:12:38 INFO - 'tests': ['a11yr', 07:12:38 INFO - 'ts_paint', 07:12:38 INFO - 'tpaint', 07:12:38 INFO - 'sessionrestore', 07:12:38 INFO - 'sessionrestore_many_windows', 07:12:38 INFO - 'sessionrestore_no_auto_restore', 07:12:38 INFO - 'tabpaint']}, 07:12:38 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 07:12:38 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 07:12:38 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:12:38 INFO - 'tests': ['perf_reftest_singletons']}, 07:12:38 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:12:38 INFO - 'tests': ['perf_reftest']}, 07:12:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:12:38 INFO - 'tsvgr_opacity', 07:12:38 INFO - 'tart', 07:12:38 INFO - 'tscrollx', 07:12:38 INFO - 'tsvg_static']}, 07:12:38 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:12:38 INFO - 'tests': ['tsvgx', 07:12:38 INFO - 'tsvgr_opacity', 07:12:38 INFO - 'tart', 07:12:38 INFO - 'tscrollx', 07:12:38 INFO - 'tsvg_static']}, 07:12:38 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:12:38 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:12:38 INFO - 'talos_options': ['--disable-stylo'], 07:12:38 INFO - 'tests': ['tp5o']}, 07:12:38 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:12:38 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 07:12:38 INFO - 'talos_options': ['--mitmproxy', 07:12:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:12:38 INFO - '--firstNonBlankPaint'], 07:12:38 INFO - 'tests': ['tp6_google', 07:12:38 INFO - 'tp6_youtube', 07:12:38 INFO - 'tp6_amazon', 07:12:38 INFO - 'tp6_facebook']}, 07:12:38 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:12:38 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 07:12:38 INFO - 'talos_options': ['--disable-stylo', 07:12:38 INFO - '--mitmproxy', 07:12:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:12:38 INFO - '--firstNonBlankPaint'], 07:12:38 INFO - 'tests': ['tp6_google', 07:12:38 INFO - 'tp6_youtube', 07:12:38 INFO - 'tp6_amazon', 07:12:38 INFO - 'tp6_facebook']}, 07:12:38 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:12:38 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 07:12:38 INFO - 'talos_options': ['--stylo-threads=1', 07:12:38 INFO - '--mitmproxy', 07:12:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:12:38 INFO - '--firstNonBlankPaint'], 07:12:38 INFO - 'tests': ['tp6_google', 07:12:38 INFO - 'tp6_youtube', 07:12:38 INFO - 'tp6_amazon', 07:12:38 INFO - 'tp6_facebook']}, 07:12:38 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:12:38 INFO - 'talos_options': ['--xperf_path', 07:12:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:12:38 INFO - 'tests': ['tp5n']}, 07:12:38 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:12:38 INFO - 'talos_options': ['--disable-stylo', 07:12:38 INFO - '--xperf_path', 07:12:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:12:38 INFO - 'tests': ['tp5n']}}} 07:12:38 INFO - Running post-action listener: _resource_record_post_action 07:12:38 INFO - [mozharness: 2017-09-23 14:12:38.328584Z] Finished populate-webroot step (success) 07:12:38 INFO - [mozharness: 2017-09-23 14:12:38.328674Z] Running create-virtualenv step. 07:12:38 INFO - Running pre-action listener: _resource_record_pre_action 07:12:38 INFO - Running main action method: create_virtualenv 07:12:38 INFO - Creating virtualenv /builds/slave/test/build/venv 07:12:38 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:12:38 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 07:12:38 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 07:12:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:12:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:12:38 INFO - Installing distribute.............................................................................................................................................................................................done. 07:12:39 INFO - Installing pip...............done. 07:12:39 INFO - Return code: 0 07:12:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:12:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:12:39 INFO - Reading from file tmpfile_stdout 07:12:39 INFO - Output received: 07:12:39 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 07:12:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:12: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')]} 07:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12: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')]} 07:12:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20799c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2076d60>, '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 0x2077a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2077ef0>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', '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 07:12: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', 'pip>=1.5'] in /builds/slave/test/build 07:12: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 pip>=1.5 07:12:39 INFO - Using env: {'DISPLAY': ':0', 07:12:39 INFO - 'HOME': '/home/cltbld', 07:12:39 INFO - 'LANG': 'en_US.UTF-8', 07:12:39 INFO - 'LANGUAGE': 'en_US:en', 07:12:39 INFO - 'LOGNAME': 'cltbld', 07:12:39 INFO - 'MAIL': '/var/mail/cltbld', 07:12:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:39 INFO - 'MOZ_NO_REMOTE': '1', 07:12:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:39 INFO - 'NO_EM_RESTART': '1', 07:12:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:39 INFO - 'PWD': '/builds/slave/test', 07:12:39 INFO - 'SHELL': '/bin/bash', 07:12:39 INFO - 'SHLVL': '1', 07:12:39 INFO - 'TERM': 'linux', 07:12:39 INFO - 'TMOUT': '86400', 07:12:39 INFO - 'USER': 'cltbld', 07:12:39 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', 07:12:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:39 INFO - '_': '/tools/buildbot/bin/python'} 07:12:40 INFO - Ignoring indexes: http://pypi.python.org/simple/ 07:12:40 INFO - Downloading/unpacking pip>=1.5 07:12:40 INFO - Running setup.py egg_info for package pip 07:12:40 INFO - warning: no files found matching 'pip/cacert.pem' 07:12:40 INFO - warning: no files found matching '*.html' under directory 'docs' 07:12:40 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 07:12:40 INFO - no previously-included directories found matching 'docs/_build/_sources' 07:12:40 INFO - Installing collected packages: pip 07:12:40 INFO - Found existing installation: pip 1.1 07:12:40 INFO - Uninstalling pip: 07:12:40 INFO - Successfully uninstalled pip 07:12:40 INFO - Running setup.py install for pip 07:12:40 INFO - warning: no files found matching 'pip/cacert.pem' 07:12:40 INFO - warning: no files found matching '*.html' under directory 'docs' 07:12:40 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 07:12:40 INFO - no previously-included directories found matching 'docs/_build/_sources' 07:12:40 INFO - Installing pip script to /builds/slave/test/build/venv/bin 07:12:40 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 07:12:40 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 07:12:40 INFO - Successfully installed pip 07:12:40 INFO - Cleaning up... 07:12:40 INFO - Return code: 0 07:12:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:12:40 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')]} 07:12:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:40 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')]} 07:12:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:40 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 0x1d0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20799c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2076d60>, '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 0x2077a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2077ef0>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', '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 07:12:40 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 07:12:40 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 07:12:40 INFO - Using env: {'DISPLAY': ':0', 07:12:40 INFO - 'HOME': '/home/cltbld', 07:12:40 INFO - 'LANG': 'en_US.UTF-8', 07:12:40 INFO - 'LANGUAGE': 'en_US:en', 07:12:40 INFO - 'LOGNAME': 'cltbld', 07:12:40 INFO - 'MAIL': '/var/mail/cltbld', 07:12:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:40 INFO - 'MOZ_NO_REMOTE': '1', 07:12:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:40 INFO - 'NO_EM_RESTART': '1', 07:12:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:40 INFO - 'PWD': '/builds/slave/test', 07:12:40 INFO - 'SHELL': '/bin/bash', 07:12:40 INFO - 'SHLVL': '1', 07:12:40 INFO - 'TERM': 'linux', 07:12:40 INFO - 'TMOUT': '86400', 07:12:40 INFO - 'USER': 'cltbld', 07:12:40 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', 07:12:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:40 INFO - '_': '/tools/buildbot/bin/python'} 07:12:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:40 INFO - Downloading/unpacking psutil>=3.1.1 07:12:40 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 07:12:40 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 07:12:40 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 07:12:40 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 07:12:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:12:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:12:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:12:41 INFO - Installing collected packages: psutil 07:12:41 INFO - Running setup.py install for psutil 07:12:42 INFO - building 'psutil._psutil_linux' extension 07:12:42 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 07:12:42 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 07:12:42 INFO - building 'psutil._psutil_posix' extension 07:12:42 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 07:12:42 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 07:12:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:12:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:12:42 INFO - Successfully installed psutil 07:12:42 INFO - Cleaning up... 07:12:43 INFO - Return code: 0 07:12:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:12:43 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')]} 07:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:43 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')]} 07:12:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:43 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 0x1d0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20799c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2076d60>, '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 0x2077a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2077ef0>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', '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 07:12:43 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 07:12:43 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 07:12:43 INFO - Using env: {'DISPLAY': ':0', 07:12:43 INFO - 'HOME': '/home/cltbld', 07:12:43 INFO - 'LANG': 'en_US.UTF-8', 07:12:43 INFO - 'LANGUAGE': 'en_US:en', 07:12:43 INFO - 'LOGNAME': 'cltbld', 07:12:43 INFO - 'MAIL': '/var/mail/cltbld', 07:12:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:43 INFO - 'MOZ_NO_REMOTE': '1', 07:12:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:43 INFO - 'NO_EM_RESTART': '1', 07:12:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:43 INFO - 'PWD': '/builds/slave/test', 07:12:43 INFO - 'SHELL': '/bin/bash', 07:12:43 INFO - 'SHLVL': '1', 07:12:43 INFO - 'TERM': 'linux', 07:12:43 INFO - 'TMOUT': '86400', 07:12:43 INFO - 'USER': 'cltbld', 07:12:43 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', 07:12:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:43 INFO - '_': '/tools/buildbot/bin/python'} 07:12:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:12:43 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 07:12:43 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 07:12:43 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 07:12:43 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 07:12:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:12:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:12:51 INFO - Installing collected packages: mozsystemmonitor 07:12:51 INFO - Running setup.py install for mozsystemmonitor 07:12:51 INFO - Successfully installed mozsystemmonitor 07:12:51 INFO - Cleaning up... 07:12:51 INFO - Return code: 0 07:12:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:12:51 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')]} 07:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:51 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')]} 07:12:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:51 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 0x1d0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20799c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2076d60>, '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 0x2077a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2077ef0>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', '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 07:12:51 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 07:12:51 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 07:12:51 INFO - Using env: {'DISPLAY': ':0', 07:12:51 INFO - 'HOME': '/home/cltbld', 07:12:51 INFO - 'LANG': 'en_US.UTF-8', 07:12:51 INFO - 'LANGUAGE': 'en_US:en', 07:12:51 INFO - 'LOGNAME': 'cltbld', 07:12:51 INFO - 'MAIL': '/var/mail/cltbld', 07:12:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:51 INFO - 'MOZ_NO_REMOTE': '1', 07:12:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:51 INFO - 'NO_EM_RESTART': '1', 07:12:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:51 INFO - 'PWD': '/builds/slave/test', 07:12:51 INFO - 'SHELL': '/bin/bash', 07:12:51 INFO - 'SHLVL': '1', 07:12:51 INFO - 'TERM': 'linux', 07:12:51 INFO - 'TMOUT': '86400', 07:12:51 INFO - 'USER': 'cltbld', 07:12:51 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', 07:12:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:51 INFO - '_': '/tools/buildbot/bin/python'} 07:12:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:52 INFO - Downloading/unpacking jsonschema==2.5.1 07:12:52 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 07:12:52 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 07:12:52 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 07:12:52 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 07:12:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:12:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:12:53 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 07:12:53 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 07:12:53 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 07:12:53 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 07:12:53 INFO - Downloading functools32-3.2.3-2.tar.gz 07:12:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:12:53 INFO - warning: no files found matching '*.txt' 07:12:53 INFO - no previously-included directories found matching 'build' 07:12:53 INFO - no previously-included directories found matching 'dist' 07:12:53 INFO - no previously-included directories found matching '.git*' 07:12:53 INFO - Installing collected packages: jsonschema, functools32 07:12:53 INFO - Running setup.py install for functools32 07:12:53 INFO - warning: no files found matching '*.txt' 07:12:53 INFO - no previously-included directories found matching 'build' 07:12:53 INFO - no previously-included directories found matching 'dist' 07:12:53 INFO - no previously-included directories found matching '.git*' 07:12:53 INFO - Successfully installed jsonschema functools32 07:12:53 INFO - Cleaning up... 07:12:53 INFO - Return code: 0 07:12:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:12:53 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')]} 07:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:53 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')]} 07:12:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:53 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 0x1d0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20799c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2076d60>, '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 0x2077a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2077ef0>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', '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 07:12:53 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 07:12:53 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 07:12:53 INFO - Using env: {'DISPLAY': ':0', 07:12:53 INFO - 'HOME': '/home/cltbld', 07:12:53 INFO - 'LANG': 'en_US.UTF-8', 07:12:53 INFO - 'LANGUAGE': 'en_US:en', 07:12:53 INFO - 'LOGNAME': 'cltbld', 07:12:53 INFO - 'MAIL': '/var/mail/cltbld', 07:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:53 INFO - 'MOZ_NO_REMOTE': '1', 07:12:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:53 INFO - 'NO_EM_RESTART': '1', 07:12:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:53 INFO - 'PWD': '/builds/slave/test', 07:12:53 INFO - 'SHELL': '/bin/bash', 07:12:53 INFO - 'SHLVL': '1', 07:12:53 INFO - 'TERM': 'linux', 07:12:53 INFO - 'TMOUT': '86400', 07:12:53 INFO - 'USER': 'cltbld', 07:12:53 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', 07:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:53 INFO - '_': '/tools/buildbot/bin/python'} 07:12:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:12:53 INFO - Cleaning up... 07:12:53 INFO - Return code: 0 07:12:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:12:53 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')]} 07:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:53 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')]} 07:12:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:53 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 0x1d0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20799c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2076d60>, '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 0x2077a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2077ef0>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', '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 07:12:53 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 07:12:53 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 07:12:53 INFO - Using env: {'DISPLAY': ':0', 07:12:53 INFO - 'HOME': '/home/cltbld', 07:12:53 INFO - 'LANG': 'en_US.UTF-8', 07:12:53 INFO - 'LANGUAGE': 'en_US:en', 07:12:53 INFO - 'LOGNAME': 'cltbld', 07:12:53 INFO - 'MAIL': '/var/mail/cltbld', 07:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:53 INFO - 'MOZ_NO_REMOTE': '1', 07:12:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:53 INFO - 'NO_EM_RESTART': '1', 07:12:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:53 INFO - 'PWD': '/builds/slave/test', 07:12:53 INFO - 'SHELL': '/bin/bash', 07:12:53 INFO - 'SHLVL': '1', 07:12:53 INFO - 'TERM': 'linux', 07:12:53 INFO - 'TMOUT': '86400', 07:12:53 INFO - 'USER': 'cltbld', 07:12:53 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', 07:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:53 INFO - '_': '/tools/buildbot/bin/python'} 07:12:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:54 INFO - Downloading/unpacking blobuploader==1.2.4 07:12:54 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 07:12:54 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 07:12:54 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 07:12:54 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 07:12:55 INFO - Downloading blobuploader-1.2.4.tar.gz 07:12:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:12:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:12:55 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 07:12:55 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 07:12:55 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 07:12:55 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 07:12:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:12:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:12:55 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 07:12:55 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 07:12:55 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 07:12:55 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 07:12:55 INFO - Downloading docopt-0.6.1.tar.gz 07:12:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:12:55 INFO - Installing collected packages: blobuploader, requests, docopt 07:12:55 INFO - Running setup.py install for blobuploader 07:12:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:12:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:12:55 INFO - Running setup.py install for requests 07:12:56 INFO - Running setup.py install for docopt 07:12:56 INFO - Successfully installed blobuploader requests docopt 07:12:56 INFO - Cleaning up... 07:12:56 INFO - Return code: 0 07:12:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:56 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')]} 07:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:56 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')]} 07:12:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:56 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 0x1d0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20799c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2076d60>, '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 0x2077a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2077ef0>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', '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 07:12:56 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 07:12:56 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 07:12:56 INFO - Using env: {'DISPLAY': ':0', 07:12:56 INFO - 'HOME': '/home/cltbld', 07:12:56 INFO - 'LANG': 'en_US.UTF-8', 07:12:56 INFO - 'LANGUAGE': 'en_US:en', 07:12:56 INFO - 'LOGNAME': 'cltbld', 07:12:56 INFO - 'MAIL': '/var/mail/cltbld', 07:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:56 INFO - 'MOZ_NO_REMOTE': '1', 07:12:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:56 INFO - 'NO_EM_RESTART': '1', 07:12:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:56 INFO - 'PWD': '/builds/slave/test', 07:12:56 INFO - 'SHELL': '/bin/bash', 07:12:56 INFO - 'SHLVL': '1', 07:12:56 INFO - 'TERM': 'linux', 07:12:56 INFO - 'TMOUT': '86400', 07:12:56 INFO - 'USER': 'cltbld', 07:12:56 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', 07:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:56 INFO - '_': '/tools/buildbot/bin/python'} 07:12:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:12:56 INFO - Running setup.py (path:/tmp/pip-Rw9457-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:12:56 INFO - Running setup.py (path:/tmp/pip-cMr2d_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:12:56 INFO - Running setup.py (path:/tmp/pip-fAV16M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:12:56 INFO - Running setup.py (path:/tmp/pip-UQH7Xe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:12:56 INFO - Running setup.py (path:/tmp/pip-2JRwTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:12:56 INFO - Running setup.py (path:/tmp/pip-CAdfCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:12:57 INFO - Running setup.py (path:/tmp/pip-n3zYXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:12:57 INFO - Running setup.py (path:/tmp/pip-jKWXX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:12:57 INFO - Running setup.py (path:/tmp/pip-D7ueo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:12:57 INFO - Running setup.py (path:/tmp/pip-iKDg4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:12:57 INFO - Running setup.py (path:/tmp/pip-UKwLig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:12:57 INFO - Running setup.py (path:/tmp/pip-WPhDRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:12:57 INFO - Running setup.py (path:/tmp/pip-Lo9fsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:12:57 INFO - Running setup.py (path:/tmp/pip-4rVXdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:57 INFO - Running setup.py (path:/tmp/pip-_EDDAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:12:57 INFO - Running setup.py (path:/tmp/pip-HPtsaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:12:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:12:57 INFO - Running setup.py (path:/tmp/pip-OAWsyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:12:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:12:57 INFO - Running setup.py install for manifestparser 07:12:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:12:57 INFO - Running setup.py install for mozcrash 07:12:58 INFO - Running setup.py install for mozdebug 07:12:58 INFO - Running setup.py install for mozdevice 07:12:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:12:58 INFO - Running setup.py install for mozfile 07:12:58 INFO - Running setup.py install for mozhttpd 07:12:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:12:58 INFO - Running setup.py install for mozinfo 07:12:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:12:58 INFO - Running setup.py install for mozInstall 07:12:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:12:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:12:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:12:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:12:58 INFO - Running setup.py install for mozleak 07:12:58 INFO - Running setup.py install for mozlog 07:12:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:12:58 INFO - Running setup.py install for moznetwork 07:12:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:12:58 INFO - Running setup.py install for mozprocess 07:12:59 INFO - Running setup.py install for mozprofile 07:12:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:12:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:12:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:12:59 INFO - Running setup.py install for mozrunner 07:12:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:12:59 INFO - Running setup.py install for mozscreenshot 07:12:59 INFO - Running setup.py install for moztest 07:12:59 INFO - Running setup.py install for mozversion 07:12:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:12:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:12:59 INFO - Cleaning up... 07:12:59 INFO - Return code: 0 07:12:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:59 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')]} 07:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:59 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')]} 07:12:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:59 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 0x1d0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20799c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2076d60>, '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 0x2077a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2077ef0>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', '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 07:12:59 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 07:12:59 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 07:12:59 INFO - Using env: {'DISPLAY': ':0', 07:12:59 INFO - 'HOME': '/home/cltbld', 07:12:59 INFO - 'LANG': 'en_US.UTF-8', 07:12:59 INFO - 'LANGUAGE': 'en_US:en', 07:12:59 INFO - 'LOGNAME': 'cltbld', 07:12:59 INFO - 'MAIL': '/var/mail/cltbld', 07:12:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:59 INFO - 'MOZ_NO_REMOTE': '1', 07:12:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:59 INFO - 'NO_EM_RESTART': '1', 07:12:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:59 INFO - 'PWD': '/builds/slave/test', 07:12:59 INFO - 'SHELL': '/bin/bash', 07:12:59 INFO - 'SHLVL': '1', 07:12:59 INFO - 'TERM': 'linux', 07:12:59 INFO - 'TMOUT': '86400', 07:12:59 INFO - 'USER': 'cltbld', 07:12:59 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', 07:12:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:59 INFO - '_': '/tools/buildbot/bin/python'} 07:12:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:12:59 INFO - Running setup.py (path:/tmp/pip-gPFfBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:12:59 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)) 07:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:12:59 INFO - Running setup.py (path:/tmp/pip-0vtAoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:12:59 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)) 07:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:12:59 INFO - Running setup.py (path:/tmp/pip-OYNyWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:13:00 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)) 07:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:13:00 INFO - Running setup.py (path:/tmp/pip-cawM85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:13:00 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)) 07:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:13:00 INFO - Running setup.py (path:/tmp/pip-hW2euB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:13:00 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)) 07:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:13:00 INFO - Running setup.py (path:/tmp/pip-zixGk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:13:00 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)) 07:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:13:00 INFO - Running setup.py (path:/tmp/pip-akxzUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:13:00 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)) 07:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:13:00 INFO - Running setup.py (path:/tmp/pip-NiZTUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:13:00 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)) 07:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:13:00 INFO - Running setup.py (path:/tmp/pip-zIFKlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:13:00 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)) 07:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:13:00 INFO - Running setup.py (path:/tmp/pip-MIrkc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:13:00 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)) 07:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:13:00 INFO - Running setup.py (path:/tmp/pip-aVj5X2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:13:00 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)) 07:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:13:00 INFO - Running setup.py (path:/tmp/pip-nGPAgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:13:00 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)) 07:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:13:00 INFO - Running setup.py (path:/tmp/pip-Mi11Cp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:13:00 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)) 07:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:13:00 INFO - Running setup.py (path:/tmp/pip-9SKAgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:13:00 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)) 07:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:13:00 INFO - Running setup.py (path:/tmp/pip-ZqKcPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:13:00 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)) 07:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:13:00 INFO - Running setup.py (path:/tmp/pip-AuTpE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:13:00 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)) 07:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:13:00 INFO - Running setup.py (path:/tmp/pip-tcZPbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:13:01 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)) 07:13:01 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)) 07:13:01 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)) 07:13:01 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)) 07:13:01 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)) 07:13:01 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)) 07:13:01 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)) 07:13:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:13:01 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 07:13:01 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 07:13:01 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 07:13:01 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 07:13:02 INFO - Downloading blessings-1.6.tar.gz 07:13:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:13:02 INFO - Installing collected packages: blessings 07:13:02 INFO - Running setup.py install for blessings 07:13:02 INFO - Successfully installed blessings 07:13:02 INFO - Cleaning up... 07:13:02 INFO - Return code: 0 07:13:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:13:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:13:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:13:02 INFO - Reading from file tmpfile_stdout 07:13:02 INFO - Current package versions: 07:13:02 INFO - argparse == 1.2.1 07:13:02 INFO - blessings == 1.6 07:13:02 INFO - blobuploader == 1.2.4 07:13:02 INFO - docopt == 0.6.1 07:13:02 INFO - functools32 == 3.2.3-2 07:13:02 INFO - jsonschema == 2.5.1 07:13:02 INFO - manifestparser == 1.1 07:13:02 INFO - mozInstall == 1.13 07:13:02 INFO - mozcrash == 1.0 07:13:02 INFO - mozdebug == 0.1 07:13:02 INFO - mozdevice == 0.51 07:13:02 INFO - mozfile == 1.2 07:13:02 INFO - mozhttpd == 0.7 07:13:02 INFO - mozinfo == 0.10 07:13:02 INFO - mozleak == 0.1 07:13:02 INFO - mozlog == 3.5 07:13:02 INFO - moznetwork == 0.27 07:13:02 INFO - mozprocess == 0.25 07:13:02 INFO - mozprofile == 0.28 07:13:02 INFO - mozrunner == 6.13 07:13:02 INFO - mozscreenshot == 0.1 07:13:02 INFO - mozsystemmonitor == 0.3 07:13:02 INFO - moztest == 0.8 07:13:02 INFO - mozversion == 1.4 07:13:02 INFO - psutil == 3.1.1 07:13:02 INFO - requests == 1.2.3 07:13:02 INFO - wsgiref == 0.1.2 07:13:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:13:02 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')]} 07:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:02 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')]} 07:13:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:02 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 0x1d0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20799c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2076d60>, '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 0x2077a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2077ef0>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', '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 07:13:02 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 07:13:02 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 07:13:02 INFO - Using env: {'DISPLAY': ':0', 07:13:02 INFO - 'HOME': '/home/cltbld', 07:13:02 INFO - 'LANG': 'en_US.UTF-8', 07:13:02 INFO - 'LANGUAGE': 'en_US:en', 07:13:02 INFO - 'LOGNAME': 'cltbld', 07:13:02 INFO - 'MAIL': '/var/mail/cltbld', 07:13:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:02 INFO - 'MOZ_NO_REMOTE': '1', 07:13:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:02 INFO - 'NO_EM_RESTART': '1', 07:13:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:02 INFO - 'PWD': '/builds/slave/test', 07:13:02 INFO - 'SHELL': '/bin/bash', 07:13:02 INFO - 'SHLVL': '1', 07:13:02 INFO - 'TERM': 'linux', 07:13:02 INFO - 'TMOUT': '86400', 07:13:02 INFO - 'USER': 'cltbld', 07:13:02 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', 07:13:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:02 INFO - '_': '/tools/buildbot/bin/python'} 07:13:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:02 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)) 07:13:02 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)) 07:13:02 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)) 07:13:02 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)) 07:13:02 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)) 07:13:02 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)) 07:13:02 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)) 07:13:02 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)) 07:13:02 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)) 07:13:02 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:13:02 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 07:13:02 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 07:13:02 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 07:13:02 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 07:13:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 07:13:03 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)) 07:13:03 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)) 07:13:03 INFO - Installing collected packages: simplejson 07:13:03 INFO - Running setup.py install for simplejson 07:13:04 INFO - building 'simplejson._speedups' extension 07:13:04 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 07:13:04 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 07:13:04 INFO - Successfully installed simplejson 07:13:04 INFO - Cleaning up... 07:13:05 INFO - Return code: 0 07:13:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:13:05 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')]} 07:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:05 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')]} 07:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:05 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 0x1d0c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20799c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2076d60>, '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 0x2077a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2077ef0>, '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': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', '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 07:13:05 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 07:13:05 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 07:13:05 INFO - Using env: {'DISPLAY': ':0', 07:13:05 INFO - 'HOME': '/home/cltbld', 07:13:05 INFO - 'LANG': 'en_US.UTF-8', 07:13:05 INFO - 'LANGUAGE': 'en_US:en', 07:13:05 INFO - 'LOGNAME': 'cltbld', 07:13:05 INFO - 'MAIL': '/var/mail/cltbld', 07:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:05 INFO - 'MOZ_NO_REMOTE': '1', 07:13:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:05 INFO - 'NO_EM_RESTART': '1', 07:13:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:05 INFO - 'PWD': '/builds/slave/test', 07:13:05 INFO - 'SHELL': '/bin/bash', 07:13:05 INFO - 'SHLVL': '1', 07:13:05 INFO - 'TERM': 'linux', 07:13:05 INFO - 'TMOUT': '86400', 07:13:05 INFO - 'USER': 'cltbld', 07:13:05 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', 07:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:05 INFO - '_': '/tools/buildbot/bin/python'} 07:13:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:13:05 INFO - Cleaning up... 07:13:05 INFO - Return code: 0 07:13:05 INFO - Running post-action listener: _resource_record_post_action 07:13:05 INFO - Running post-action listener: _start_resource_monitoring 07:13:05 INFO - Starting resource monitoring. 07:13:05 INFO - [mozharness: 2017-09-23 14:13:05.271432Z] Finished create-virtualenv step (success) 07:13:05 INFO - [mozharness: 2017-09-23 14:13:05.271939Z] Running install step. 07:13:05 INFO - Running pre-action listener: _resource_record_pre_action 07:13:05 INFO - Running main action method: install 07:13:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:13:05 INFO - Reading from file tmpfile_stdout 07:13:05 INFO - Detecting whether we're running mozinstall >=1.0... 07:13:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:13:05 INFO - Reading from file tmpfile_stdout 07:13:05 INFO - Output received: 07:13:05 INFO - Usage: mozinstall [options] installer 07:13:05 INFO - Options: 07:13:05 INFO - -h, --help show this help message and exit 07:13:05 INFO - -d DEST, --destination=DEST 07:13:05 INFO - Directory to install application into. [default: 07:13:05 INFO - "/builds/slave/test"] 07:13:05 INFO - --app=APP Application being installed. [default: firefox] 07:13:05 INFO - mkdir: /builds/slave/test/build/application 07:13:05 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'] 07:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 07:13:23 INFO - Reading from file tmpfile_stdout 07:13:23 INFO - Output received: 07:13:23 INFO - /builds/slave/test/build/application/firefox/firefox 07:13:23 INFO - Running post-action listener: _resource_record_post_action 07:13:23 INFO - [mozharness: 2017-09-23 14:13:23.380463Z] Finished install step (success) 07:13:23 INFO - [mozharness: 2017-09-23 14:13:23.380803Z] Running setup-mitmproxy step. 07:13:23 INFO - Running pre-action listener: _resource_record_pre_action 07:13:23 INFO - Running main action method: setup_mitmproxy 07:13:23 INFO - Skipping: mitmproxy is not required 07:13:23 INFO - Running post-action listener: _resource_record_post_action 07:13:23 INFO - [mozharness: 2017-09-23 14:13:23.382564Z] Finished setup-mitmproxy step (success) 07:13:23 INFO - [mozharness: 2017-09-23 14:13:23.382880Z] Running run-tests step. 07:13:23 INFO - Running pre-action listener: _resource_record_pre_action 07:13:23 INFO - Running pre-action listener: _set_gcov_prefix 07:13:23 INFO - Running main action method: run_tests 07:13:23 WARNING - Try message not found. 07:13:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:13:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:13:23 INFO - Python 2.7.3 07:13:23 INFO - Return code: 0 07:13:23 INFO - grabbing minidump binary from tooltool 07:13:23 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')]} 07:13:23 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 0x2076d60>, '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 0x2077a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2077ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:13:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 07:13:23 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 07:13:23 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 07:13:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:13:23 INFO - Return code: 0 07:13:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:13:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:13:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:13:23 INFO - ENV: RUST_BACKTRACE is now full 07:13:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:13:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:13:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-073', '--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 07:13:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-073 --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 07:13:23 INFO - Using env: {'DISPLAY': ':0', 07:13:23 INFO - 'HOME': '/home/cltbld', 07:13:23 INFO - 'LANG': 'en_US.UTF-8', 07:13:23 INFO - 'LANGUAGE': 'en_US:en', 07:13:23 INFO - 'LOGNAME': 'cltbld', 07:13:23 INFO - 'MAIL': '/var/mail/cltbld', 07:13:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:13:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:13:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:23 INFO - 'MOZ_NO_REMOTE': '1', 07:13:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:13:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:23 INFO - 'NO_EM_RESTART': '1', 07:13:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:23 INFO - 'PWD': '/builds/slave/test', 07:13:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:13:23 INFO - 'RUST_BACKTRACE': 'full', 07:13:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:13:23 INFO - 'SHELL': '/bin/bash', 07:13:23 INFO - 'SHLVL': '1', 07:13:23 INFO - 'TERM': 'linux', 07:13:23 INFO - 'TMOUT': '86400', 07:13:23 INFO - 'USER': 'cltbld', 07:13:23 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', 07:13:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:23 INFO - '_': '/tools/buildbot/bin/python'} 07:13:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-073', '--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 07:13:23 INFO - mozversion application_buildid: 20170923131849 07:13:23 INFO - mozversion application_changeset: 3828e44f5b0a56e7933b817d430cf32d41b03fe6 07:13:23 INFO - mozversion application_display_name: Nightly 07:13:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:13:23 INFO - mozversion application_name: Firefox 07:13:23 INFO - mozversion application_remotingname: firefox 07:13:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:13:23 INFO - mozversion application_vendor: Mozilla 07:13:23 INFO - mozversion application_version: 58.0a1 07:13:23 INFO - mozversion platform_buildid: 20170923131849 07:13:23 INFO - mozversion platform_changeset: 3828e44f5b0a56e7933b817d430cf32d41b03fe6 07:13:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:13:23 INFO - mozversion platform_version: 58.0a1 07:13:23 INFO - using testdate: 1506176003 07:13:23 INFO - actual date: 1506176003 07:13:23 INFO - starting webserver on 'localhost:46289' 07:13:23 INFO - SUITE-START | Running 2 tests 07:13:23 INFO - TEST-START | dromaeo_css 07:13:23 INFO - Initialising browser for dromaeo_css test... 07:13:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46289/getInfo.html -profile /tmp/tmpa4rSon/profile 07:13:23 INFO - TEST-INFO | started process 7288 (/builds/slave/test/build/application/firefox/firefox http://localhost:46289/getInfo.html) 07:13:28 INFO - TEST-INFO | 7288: exit 0 07:13:28 INFO - Browser initialized. 07:13:28 INFO - Running cycle 1/1 for dromaeo_css test... 07:13:28 INFO - Using env: {'DISPLAY': ':0', 07:13:28 INFO - 'HOME': '/home/cltbld', 07:13:28 INFO - 'JSGC_DISABLE_POISONING': '1', 07:13:28 INFO - 'LANG': 'en_US.UTF-8', 07:13:28 INFO - 'LANGUAGE': 'en_US:en', 07:13:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 07:13:28 INFO - 'LOGNAME': 'cltbld', 07:13:28 INFO - 'MAIL': '/var/mail/cltbld', 07:13:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:13:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:13:28 INFO - 'MOZ_CRASHREPORTER': '1', 07:13:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 07:13:28 INFO - 'MOZ_NO_REMOTE': '1', 07:13:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:13:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:28 INFO - 'NO_EM_RESTART': '1', 07:13:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:28 INFO - 'PWD': '/builds/slave/test', 07:13:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:13:28 INFO - 'RUST_BACKTRACE': 'full', 07:13:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:13:28 INFO - 'SHELL': '/bin/bash', 07:13:28 INFO - 'SHLVL': '1', 07:13:28 INFO - 'TERM': 'linux', 07:13:28 INFO - 'TMOUT': '86400', 07:13:28 INFO - 'USER': 'cltbld', 07:13:28 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', 07:13:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:28 INFO - '_': '/tools/buildbot/bin/python'} 07:13:28 INFO - TEST-INFO | started process 7520 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa4rSon/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 07:13:29 INFO - PID 7520 | 07:13:29 INFO - PID 7520 | (/builds/slave/test/build/application/firefox/firefox:7570): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 07:13:29 INFO - PID 7520 | 07:13:39 INFO - PID 7520 | RSS: Main: 153362432 07:13:39 INFO - PID 7520 | 07:16:37 INFO - PID 7520 | Cycle 1(1): loaded http://localhost:46289/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46289/tests/dromaeo/cssquery-ext.html) 07:16:38 INFO - PID 7520 | RSS: Main: 153849856 07:16:38 INFO - PID 7520 | 07:19:40 INFO - PID 7520 | Cycle 1(1): loaded http://localhost:46289/tests/dromaeo/cssquery-ext.html (next: http://localhost:46289/tests/dromaeo/cssquery-jquery.html) 07:19:41 INFO - PID 7520 | RSS: Main: 153882624 07:19:41 INFO - PID 7520 | 07:22:42 INFO - PID 7520 | Cycle 1(1): loaded http://localhost:46289/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46289/tests/dromaeo/cssquery-mootools.html) 07:22:43 INFO - PID 7520 | RSS: Main: 155738112 07:22:43 INFO - PID 7520 | 07:25:45 INFO - PID 7520 | Cycle 1(1): loaded http://localhost:46289/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46289/tests/dromaeo/cssquery-prototype.html) 07:25:45 INFO - PID 7520 | RSS: Main: 154050560 07:25:45 INFO - PID 7520 | 07:28:49 INFO - PID 7520 | Cycle 1(1): loaded http://localhost:46289/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46289/tests/dromaeo/cssquery-yui.html) 07:28:49 INFO - PID 7520 | RSS: Main: 155529216 07:28:49 INFO - PID 7520 | 07:31:51 INFO - PID 7520 | Cycle 1(1): loaded http://localhost:46289/tests/dromaeo/cssquery-yui.html (next: http://localhost:46289/tests/dromaeo/cssquery-dojo.html) 07:31:51 INFO - PID 7520 | RSS: Main: 157999104 07:31:51 INFO - PID 7520 | 07:31:51 INFO - PID 7520 | __start_tp_report 07:31:51 INFO - PID 7520 | _x_x_mozilla_page_load 07:31:51 INFO - PID 7520 | _x_x_mozilla_page_load_details 07:31:51 INFO - PID 7520 | |i|pagename|runs| 07:31:51 INFO - PID 7520 | |0;dojo.html;3119.5476655884895;3129.2333378322314;3147.4334619768433;3147.7796554241186;3148.9212769680753;6318.620882747027;6322.367763223675;6329.49364050876;6379.13881625981;6460.6769661516855;6368.203974503187;6408.26304974928;6426.196725409324;6448.64532450715;6499.545031847772;5636.126400407943;5695.601307908448;5823.06830907055;5871.9412805871825;5913.201717768111;5807.593468457209;5897.675627840467;5910.645361278315;5916.822495325147;5936.079907614327;5773.884522309551;5844.883102337972;5850.6489610623075;5857.76568937242;5861.472467477926;5786.132080187964;5821.476067153976;5842.883142337172;5846.473817356458;5907.911381329283;5777.335606405275;5809.215755872945;5848.122781582754;5860.648361098305;5893.734781934823;6141.416565426277;6276.780312689034;6360.681965901686;6425.550211485198;6511.4465270452065;6878.58728476288;6897.724091036353;6897.931020689779;6904.964255361686;6933.514653974224;6872.725090996354;6885.655717214119;6957.956306554007;6969.6515174241085;6994.475414343894;6208.534359922979;6253.155823963707;6290.025046117859;6311.558190926637;6343.9682801585695;6929.896051559231;6932.757353492603;6945.062416573799;6958.060661810692;6974.651267436608;6758.797236082925;6767.966160169266;6771.322867713189;6777.390034896883;6783.253842077367;6810.761623343159;6817.727290908408;6897.896531552031;6906.033155358254;6910.689018994158;6666.733330666816;6700.731970721116;6788.389044985975;6829.112215411965;6844.623545704934;6749.898751518731;6750.561213521204;6756.831079223059;6757.966210169016;6768.120144381199;5319.760610772524;5326.520613144836;5332.9466705333225;5337.279467271868;5342.198670199424;4669.486356500782;4701.271302948048;4761.142994260997;4767.570918617341;4783.162946484351;5889.617174883704;5892.1161825726485;5944.316403613553;5968.015277479168;5976.402359763989;6687.966560167168;6894.793156205322;6901.137357830271;6964.477664175207;7002.229754726976;5836.241288632451;5836.9124463133085;5837.970810145922;5845.707714614252;5863.560232982458;5873.647581145145;5921.437463441052;5949.613275137102;5964.926313263568;5970.820875373745;6661.3338666132995;6726.730930762813;6738.59568425896;6754.0881980931945;6755.189377274758;6880.931190688029;6890.414314783299;6955.965220173866;6968.198657154391;6983.196932352845;6419.101325814297;6528.412442880163;6633.170853643294;6654.700538475683;6743.966280168568;6673.833154171185;6744.0895479109695;6757.256701762703;6781.559198652072;6787.0498130262795;6864.622445765531;6868.210155831944;6958.199807022055;6975.476839236976;6988.336108069929;6544.345565443417;6546.901796472961;6637.037629543785;6638.20341559016;6652.135222421054;6550;6610.206775187008;6699.665016749241;6700.631465269457;6700.932990670226;6610.603363798187;6613.503987201076;6621.404073633396;6631.6684165792485;6637.502187336066;6658.533902626769;6674.566153200027;6678;6763.154605674291;6777.695003724745;6109.236345456818;6423.357664233539;6428.903566446601;6506.219253689587;6519.967400163158 07:31:51 INFO - PID 7520 | |1;ext.html;888.9866652000221;891.7235656946351;894.2979760887705;913.3743385780737;924.1497822003756;38944.610553894454;39816.20367592647;39900.201995960066;40035.799821000925;40073;35465.936021919384;35557;35705.821470892675;35760.82119589405;35769.82115089428;7044.154701435822;7133.607651579161;7152.070230870006;7165.462590305722;7180.425565954737;6915.239323674392;6987.161540615133;7017.403520700746;7031.578105313672;7059.647017649097;7685.923140768577;7894.2105789421175;7905.486143400689;7912.683492660287;7915.050193976731;7777.377809775205;7804.648790804371;7859.842803143905;7866.724664636738;7872.29149376556;7688.423368247404;7726.690932362699;7826.843463130763;7860.410469214775;7877.8030549236155;35906.461403078974;36247.63752362469;36249.63750362489;36269.81865090658;36337;3506.63180366063;3513.91215219619;3520;3560.732945029133;3563.82180890957;29460.852695736598;29506.70493295061;29771.40457190844;29789.55315670245;29804;4966.528179822947;4994.55049045584;4999.200127979506;5035.597152227813;5042.848714538533;171047;173009.13495432568;181586;182771.08614456977;184147.07926460417;46780.7660961693;47134.29298560524;47222.76388618035;47282.29076563854;47377;48622.75688621534;48762;48830.26754598684;48880.75559622179;48883.75558122186;15880.920595396949;16034.67930641404;16039.599010024842;16093.919530402272;16128.596785080466;13302.135361201492;13367.131136476099;13372.93313533426;13377.063605547519;13388.732225355438;13574.25341606221;13900.721985560434;13918.373673184731;13938.721225575431;14093.78859317111;5633.746481408347;5750.971245143831;5806.332271788799;5826.6795326256615;5878.588498805043;1007.1087087927091;1011.3881101933224;1018.7096677446892;1025.7845852370936;1046.1108058150512;22246.221382251544;22424.327270181922;22665.433364166027;22719.545609087727;22806.65790013151;1128.8588926384202;1130.2088538023415;1131.547381047571;1135.7444574970566;1136.5851464215502;11866.703332416586;11949.402529873472;11965.222260553037;12098.697532561757;12135.635930922086;202512;202889;204233;204505;204567;48445.27332089951;48774;48921.26618100731;49041.754791225816;49071.26393104106;1445.5518789175178;1457.6865973815468;1484.3246322923044;1484.6807936293749;1492.6566889615233;22083.779162208815;22117.447063823533;22265.888670556542;22415.66376504321;22487.887560562092;31921.201969950936;32059;33040.1739956503;34423.31153376969;35352.292954140255;173716;173769;174905;175433;175553;29475.705242947297;29846.25384365408;29871;31811.20471988126;32043.67956320407;39853.20293594054;39924.80037599793;39948.201035978534;39984.4002339971;40062;29425.11724648264;29514.704852951196;29831.850840745657;30078.849605752708;30572.59253705249;29066.709332907245;29385.70614293915;29501.26246843846;29674;29768.255793605334;32978;33012.51443685088;33561.83219083889;33691.494627581094;33728.49407258844;27225.319367015192;28910.710892891642;29011.274718132216;29221;29436.411271774872;22504.437389065406;22654;22790.886045569667;22900.198493052663;23040.769592303863 07:31:51 INFO - PID 7520 | |2;jquery.html;584.2113147251209;598.7515181199797;606.2997238189889;614.450067189865;621.1335187413558;21654;22529.887350563225;22560.54878902421;22618.321450356496;22625.886870565646;22596.43508912278;23794.286171414886;24062.278131656036;24241.27276181713;24246.636300455506;6468.320826313228;6695.732170713166;6698.631575263358;6703.765368212114;6704.061431399608;6560.639164845931;6570.244421891496;6846;7140.571565706075;7185.137783465992;7172.21105678375;7176.067111275527;7176.6052867092285;7180.748673796418;7182.066331376914;6794.898076528807;7143.464240181956;7176.6052867092285;7178.784636460915;7182.820429489252;6970.93029069713;6988.475864310197;7004.229534751174;7026.332498412643;7027.08647875778;29153.41693166146;29213.26966825825;29518.70481295181;29719.40561188764;29835.850820745974;2048.1397812918462;2067.400453868376;2078.4076538186546;2079.9896000519902;2081.5316553775433;20506;20606.79393206064;21133.5773284535;21369.572608547744;21393;2509.6235564665444;2539.657146285246;2552.1960582416477;2558.143022087584;2582.7546383093577;122928.38535807264;128515;128763;133108;138790.3060484701;19785.901070494554;20256.493587660425;20981.790182098288;23330.300090997298;24665.506689866463;41495.170096597896;41545.1690966179;41715.16569668589;41830.1633967325;41916.79041604773;11427.200095993367;11467.77064458706;11491.42542872853;11641;11644.825327620092;9439.622415103318;9561.474118923526;9600.75998100053;9619.663311784194;9749.561269742879;10813.243072984815;11029.724256893482;11392;11458.255213411101;11527.250728702607;3883.7281390302974;4010.478637777129;4021.9195616087513;4241.981924338136;4257.441544968434;952.9904700952902;968.6803354892868;970.4807927758616;980.4166520920041;989.3420875117966;18867.339643112424;18886.622267554572;19140.521486962938;19285.90357048234;19422.12600432984;1015.4110615842941;1035.3942943378208;1045.8744950605824;1051.1853313681804;1052.2476429353048;10223.233257505568;10442.738931526772;10577.9471102644;10609.575616975235;10715.24993250465;467212;476822;478307;481164;482774;108198;110572;111398;114099.42950285361;114496;1108.8558487396588;1173.600975668277;1181.9586314478952;1182.6156499137815;1187.3113594115393;16696.16519174061;17233.56916077108;17244.224009919333;17275.827241727926;17366.47900562985;54618;55734;56156;56629.15056274242;57694.7115264421;66728.33271667222;66826.66586667036;67256.66371668306;67487.66256168688;70183;19045.333413330467;19179.32872349461;19332;19494.805051949297;19650;21731.891340543196;21826.126954921627;21930.45173870666;21979.560408792055;22048.33854984353;17460.30158793634;17488.387906423213;17563.385281515086;17768.733468997718;17797.822021779615;15714;15772.60568485797;15848.207589620242;16332.265048073103;16507.91746041262;17939.551511212325;18031.819681803016;18138.909305453388;18224;18260.543486412946;18791.436256912315;18802.811971880106;18915.71626425577;18944.242230311185;18958.336458223897;17237.051962142203;17586.648267034845;17602.207900644757;17712.911435443257;17884.10579471047 07:31:51 INFO - PID 7520 | |3;mootools.html;1337.411538922874;1349.932503374831;1388.3197233355659;1392.6936074063703;1394.2331717555342;14706.999999999987;16063.518094457171;16080;16123.919380403082;16133.838661613381;15698.37206511741;16004.359825606962;16034.919825400886;16048.27782749776;16072;2724.5640697488357;2795.53873610855;2798.720127987205;2799.4401119776026;2811.325281932342;2707.9323016924536;2783.1928740665185;2792.2879665685323;2793.69269380368;2794.3433293176085;2661.7870570354326;2735.4529094181144;2759.2550011496883;2759.296379423254;2864.3841574061676;2851.17315978366;2883.913482595515;2884.033848660701;2884.5673149027602;2885.8845646174127;2542.6948766147993;2661.4943160799385;2686.973130268692;2727.4545090981883;2727.481778462106;15101.697966040618;15253.313600887986;15258.694826103416;15272.083674979536;15288.159151246677;8127.349812015024;8394.286485648727;8400.747977560683;8464.703735369294;8465.449745766506;5605.355384130837;5745.856353591152;5818.156367326714;5870.970645146747;5907.6750778707155;1991.8506112041655;2016.8084032016934;2016.9596608067902;2044.9284275050302;2054.3836848945257;25805.870970645214;26253.999999999807;26298;26307.07925222627;26362.86818565895;7253.238409966983;7427.405807535384;7566.1298950620285;7657.272559106876;7878.094019187753;20018.099185536685;20152;20178.991050447712;20181.091850866746;20245.696314555;8103.554304513191;8379.62291696875;8563.058063612998;8602.655893764304;8632.78418039554;7445.367143792838;7547.622618869034;7558.13081495624;7608.657610407434;7659.425543084291;5325.8934821303355;5493;5512.5314348280845;5533.584981126432;5547.472990065938;1586.9841301586837;1599.2963096237618;1603.4868841970458;1605.7350537161237;1619.481765834945;892.4957399069505;902.7427183252872;910.8269428808637;921.1755478846395;924.9213816825511;7503.549787012687;7513.39892808574;7535.585542795089;7577.507462015062;7604.8479030419085;724.8724992148062;726.2193142371972;728.581065887117;730.3062091013452;733.878909979858;4225.9577404226175;5164.9741751291;5490.533304669148;5539.529140023144;5629.352624448159;20659.17363305481;21571.352859414244;21869.234576790055;21906;21923.12307507682;20644.58710825775;21577.460563486038;21684.132634694437;21907.780922190574;21969.890150549145;11631.941840290914;11706.648800535997;11754.70613234676;11762.647120586225;11766.705832354088;8467.95766021166;8635.43869648496;8651;8657.696980605648;8691.130886911258;18046.2781488739;18113;18249.543761406072;18420.81579184191;18572.628547429245;19098.7135192975;19116;19156.616867662848;19248.61502769965;19694.21223151058;11165.609203677832;11225.494852731483;11358.659240222807;11385.715357116138;11428.885711142782;14208.78686819678;14321.785173222203;14355.138691678532;14370.568882933529;14373.209473479059;10973.341599504056;11054.336739795637;11063.50214240345;11075.667729968114;11291.548338066385;11124.443777811239;11530;11615.070794336601;11670;11775.882241177822;9817.410955342417;9847.753806154904;9901.455419951972;9903.158231550398;9907.256955728493;6959.478039147186;6973.476989225727;6985.965070174617;7004.4046256068805;7018.33325834045;7148.213696493485;7178.856422871618;7202.459815513962;7205.171405288459;7212.855742885218 07:31:51 INFO - PID 7520 | |4;prototype.html;509.5566856834552;512.2521119166016;533.8672005338672;542.156945949049;543.3506959183775;6239.4696450801675;6244.188255526781;6280.528960327971;6342.98512238042;6365.172527571409;6186.597871138373;6195.008798592226;6223.5332350073695;6234.376562343775;6267;5085.982803439309;5104.259882317062;5133.897322053566;5147.768350424221;5198.324217851693;4705.882352941187;4865.975670121645;5072.3405957225505;5094.872628184288;5154.60825577094;4837.661363704542;4884.951150488485;4891.1685013547785;4906.1168989581865;4945.7032578045255;4889.315495830587;4912.38595175603;4919.852404427855;4922.532359425849;4925.901481970341;4274.380214868857;4374.693771436001;4380.978095109536;4399.5820397062225;4420.756858372788;5897.262842144732;5918.201042859245;5938.7624495020145;6054.182685337467;6073.757049718006;5606.35526914406;5641.492265696107;5644.322681278273;5665.206871038058;5684.232628595169;6435.581687190318;6437.806865794034;6451.483881289532;6477.708503117324;6514.771982980573;4638;4843.564079232886;4856.562909338141;4879.316635760657;4883.902321953577;6570.375814297634;6702.530822842403;6702.631355275458;6750.62871542065;6827.351401616888;4868.586170175504;4923.753812309442;4928.704277743275;4946.950530494721;4949.208126699711;4943.72809495474;4981.726005069683;4991.326170966946;4998.451099719167;5011.448740638527;5499.862503437366;5572.4706152915405;5579.721013949286;5582.7766889323975;5625.324961004624;6383.042543618494;6391.840203994937;6453.645049522322;6488.837779055467;6502.60984340941;1737.8870373425686;1741.5559032446645;1757.6045389787196;1759.7008508553577;1762.3038899634573;1164.394514852289;1222.1628184693514;1229.2010193374379;1237.127824883455;1238.5293588436516;5497.39528651848;5551.555875529948;5598.944010559842;5666.433356664383;5697.971510142423;6571.737130514822;6576.901346479806;6715.529912906148;6722.798316050526;6737.326267373223;5579.944200557942;5594.1888426177975;5613.578981576398;5613.943860561342;5708.659449625634;3508.1580420698847;3513.7013353864695;3516.4373700207843;3516.4725291206014;3520;6358.1734374531015;6387.393197646216;6413;6446.645434501053;6498.285188629247;6361.522885783585;6719.697613607399;6756.459483241329;6771.8984215236815;6789.830254243683;6369.20384951881;6406.743730250831;6421.261554921244;6462.773802916875;6463.903041454382;6548.148740663683;6570.653016131777;6612.966935165486;6628.635425051669;6655.101561289358;6333.271673757393;6384.93615063862;6462.321456247027;6497.707603157964;6581.177352830896;6233.781817636361;6256.718447669957;6273.905891411541;6277.3408792078;6354.3963323484195;4927.778249978831;4928.753562321941;4934.728589927445;4934.8766280843265;4988.12707776145;6192.81421557354;6355.618662880242;6475.935240647534;6515.641639709672;6573.375529324707;6245.219347581552;6287.874242515032;6383.170187875547;6456.806295811133;6457.903131453126;6262.029385445081;6269.153664255267;6276.592021518587;6343.828085957021;6390;6254.2807577129215;6261.029540421242;6265.4361107500545;6342.524310676626;6397.26431460388;6428.228612566522;6492.967535162294;6511.641859697762;6519.445847103049;6536.150300460909;6198.667286533417;6230.190075290183;6241.906371404341;6245.750169993149;6253.749850005949 07:31:51 INFO - PID 7520 | |5;yui.html;898.9505577193255;904.6291020681517;914.643289117244;920.2408013388954;922.9769255768605;22397.44006399841;23800.404989875242;23862.76137238627;23893;23963.640545391834;21455.356339309845;21714.565708685815;21731.565368692616;21898;21911.233106841264;901.9729408117767;978.4031740638205;985.22167487685;985.8274801909673;988.5156273426005;997.1225321716879;998.9710598083949;1010.7725761703595;1011.6813203840767;1012.463394400965;1048.9213309001818;1049.716576524338;1052.9315594486372;1054.5887104029434;1056.550965839519;1036.0623635609786;1036.777092925021;1038.065740833256;1039.0544604409988;1044.373375974413;763.2634507700096;770.9421793365464;771.3135309574483;772.1043589436227;773.6479901644794;20017.099230534663;20133.597328053504;20174.697379539317;20312.79687203124;20356.69464958027;21767.891160544095;21954.231601893858;22608;22632.43418914524;22652.546949060925;16916.57708557296;17121.657566848593;17219.3973210937;17300.48098557045;17351;3529.3294274087843;3560.786352818839;3599.5680518337704;3622.9094272643133;3629.7096232301615;137301;138356;138907;139714;140807;16864.241109150116;16892.662146756997;17173.57066073333;17350.219240134225;17352.739708904377;25628.615570766455;26645.600315995274;31291.217719557193;31316.84341578323;31376.215594610316;8615.956920215358;8954.955225223963;8964;8976.102389760972;8999.19007289347;4162.396452514368;4242.7242229255;4247.723897946624;4253.191893540217;4263.8081286342185;5111.948880511147;5269.947300526946;5299.973500132474;5334.4398838122215;5364.22218778275;1119.1885882735114;1136.4658610453073;1157.8726340102583;1158.7798318319492;1159.2117360195148;680.8468094678658;695.5687473766196;701.9333163349473;707.1655446573093;709.6026225313841;10639.734006649896;11056.281341712764;11324.94337528307;11570.88429115698;11908.166428350105;638.5530128909782;646.4311405962817;656.1076935367896;658.2397331082607;661.490652197805;5062.265971434122;5228.529432351031;5268.262443257947;5345.812896548602;5369.6509726867625;118402.40798795951;119081;121909;122626;123836.38081809533;103940;105144.47427762812;108546.45726771315;113280.43359783148;113679.4316028431;90881.54559227293;92956.53521732349;93232.53383733038;93330.06669933214;95172.52413738026;9790;9848.409095454295;10060.647877324258;10106.343087699275;10252.282340236112;43038.78480607577;43591.34612980745;43898.12203755843;45228.547714522436;45753.31370029385;55765;55844.16233756578;57136.71431642982;57177.71411142917;57662.7116864413;12633.620991370273;13153.802692959805;13156.736865262832;13246.602601921957;13248.403821827847;14087.225202613954;14132.78800817968;14296.714065718836;14486.782698259323;14525.201113938412;13417.127886687334;13720.108192967426;13916.721665566834;13931.164130152223;13945.233012184428;14321.856781432052;14349.139051656934;14526.055806372975;14620.926895365455;14633.707325853211;15708.528744137695;15720.21398930026;15754.606134846721;15984.440544580884;16000.439984600484;11060.612878549213;11096.833547496955;11286.097112231166;11303.886961130613;11813.645590632295;11072;11337.866213378926;11392;11460.885391145981;11664.066874650178 07:31:51 INFO - PID 7520 | __end_tp_report 07:31:51 INFO - PID 7520 | __start_cc_report 07:31:51 INFO - PID 7520 | _x_x_mozilla_cycle_collect,263 07:31:51 INFO - PID 7520 | __end_cc_report 07:31:51 INFO - PID 7520 | __startTimestamp1506177111560__endTimestamp 07:31:51 INFO - PID 7520 | 07:31:51 INFO - PID 7520 | ------- Summary: start ------- 07:31:51 INFO - PID 7520 | Number of tests: 6 07:31:51 INFO - PID 7520 | 07:31:51 INFO - PID 7520 | [#0] dojo.html Cycles:165 Average:6281.86 Median:6544.35 stddev:767.60 (11.7%) stddev-sans-first:728.76 07:31:51 INFO - PID 7520 | Values: 3119.5 3129.2 3147.4 3147.8 3148.9 6318.6 6322.4 6329.5 6379.1 6460.7 6368.2 6408.3 6426.2 6448.6 6499.5 5636.1 5695.6 5823.1 5871.9 5913.2 5807.6 5897.7 5910.6 5916.8 5936.1 5773.9 5844.9 5850.6 5857.8 5861.5 5786.1 5821.5 5842.9 5846.5 5907.9 5777.3 5809.2 5848.1 5860.6 5893.7 6141.4 6276.8 6360.7 6425.6 6511.4 6878.6 6897.7 6897.9 6905.0 6933.5 6872.7 6885.7 6958.0 6969.7 6994.5 6208.5 6253.2 6290.0 6311.6 6344.0 6929.9 6932.8 6945.1 6958.1 6974.7 6758.8 6768.0 6771.3 6777.4 6783.3 6810.8 6817.7 6897.9 6906.0 6910.7 6666.7 6700.7 6788.4 6829.1 6844.6 6749.9 6750.6 6756.8 6758.0 6768.1 5319.8 5326.5 5332.9 5337.3 5342.2 4669.5 4701.3 4761.1 4767.6 4783.2 5889.6 5892.1 5944.3 5968.0 5976.4 6688.0 6894.8 6901.1 6964.5 7002.2 5836.2 5836.9 5838.0 5845.7 5863.6 5873.6 5921.4 5949.6 5964.9 5970.8 6661.3 6726.7 6738.6 6754.1 6755.2 6880.9 6890.4 6956.0 6968.2 6983.2 6419.1 6528.4 6633.2 6654.7 6744.0 6673.8 6744.1 6757.3 6781.6 6787.0 6864.6 6868.2 6958.2 6975.5 6988.3 6544.3 6546.9 6637.0 6638.2 6652.1 6550.0 6610.2 6699.7 6700.6 6700.9 6610.6 6613.5 6621.4 6631.7 6637.5 6658.5 6674.6 6678.0 6763.2 6777.7 6109.2 6423.4 6428.9 6506.2 6520.0 07:31:51 INFO - PID 7520 | 07:31:51 INFO - PID 7520 | [#1] ext.html Cycles:180 Average:34662.32 Median:32051.34 stddev:48066.43 (150.0%) stddev-sans-first:48134.37 07:31:51 INFO - PID 7520 | Values: 889.0 891.7 894.3 913.4 924.1 38944.6 39816.2 39900.2 40035.8 40073.0 35465.9 35557.0 35705.8 35760.8 35769.8 7044.2 7133.6 7152.1 7165.5 7180.4 6915.2 6987.2 7017.4 7031.6 7059.6 7685.9 7894.2 7905.5 7912.7 7915.1 7777.4 7804.6 7859.8 7866.7 7872.3 7688.4 7726.7 7826.8 7860.4 7877.8 35906.5 36247.6 36249.6 36269.8 36337.0 3506.6 3513.9 3520.0 3560.7 3563.8 29460.9 29506.7 29771.4 29789.6 29804.0 4966.5 4994.6 4999.2 5035.6 5042.8 171047.0 173009.1 181586.0 182771.1 184147.1 46780.8 47134.3 47222.8 47282.3 47377.0 48622.8 48762.0 48830.3 48880.8 48883.8 15880.9 16034.7 16039.6 16093.9 16128.6 13302.1 13367.1 13372.9 13377.1 13388.7 13574.3 13900.7 13918.4 13938.7 14093.8 5633.7 5751.0 5806.3 5826.7 5878.6 1007.1 1011.4 1018.7 1025.8 1046.1 22246.2 22424.3 22665.4 22719.5 22806.7 1128.9 1130.2 1131.5 1135.7 1136.6 11866.7 11949.4 11965.2 12098.7 12135.6 202512.0 202889.0 204233.0 204505.0 204567.0 48445.3 48774.0 48921.3 49041.8 49071.3 1445.6 1457.7 1484.3 1484.7 1492.7 22083.8 22117.4 22265.9 22415.7 22487.9 31921.2 32059.0 33040.2 34423.3 35352.3 173716.0 173769.0 174905.0 175433.0 175553.0 29475.7 29846.3 29871.0 31811.2 32043.7 39853.2 39924.8 39948.2 39984.4 40062.0 29425.1 29514.7 29831.9 30078.8 30572.6 29066.7 29385.7 29501.3 29674.0 29768.3 32978.0 33012.5 33561.8 33691.5 33728.5 27225.3 28910.7 29011.3 29221.0 29436.4 22504.4 22654.0 22790.9 22900.2 23040.8 07:31:51 INFO - PID 7520 | 07:31:51 INFO - PID 7520 | [#2] jquery.html Cycles:180 Average:34983.92 Median:21878.29 stddev:80053.07 (365.9%) stddev-sans-first:80235.98 07:31:51 INFO - PID 7520 | Values: 584.2 598.8 606.3 614.5 621.1 21654.0 22529.9 22560.5 22618.3 22625.9 22596.4 23794.3 24062.3 24241.3 24246.6 6468.3 6695.7 6698.6 6703.8 6704.1 6560.6 6570.2 6846.0 7140.6 7185.1 7172.2 7176.1 7176.6 7180.7 7182.1 6794.9 7143.5 7176.6 7178.8 7182.8 6970.9 6988.5 7004.2 7026.3 7027.1 29153.4 29213.3 29518.7 29719.4 29835.9 2048.1 2067.4 2078.4 2080.0 2081.5 20506.0 20606.8 21133.6 21369.6 21393.0 2509.6 2539.7 2552.2 2558.1 2582.8 122928.4 128515.0 128763.0 133108.0 138790.3 19785.9 20256.5 20981.8 23330.3 24665.5 41495.2 41545.2 41715.2 41830.2 41916.8 11427.2 11467.8 11491.4 11641.0 11644.8 9439.6 9561.5 9600.8 9619.7 9749.6 10813.2 11029.7 11392.0 11458.3 11527.3 3883.7 4010.5 4021.9 4242.0 4257.4 953.0 968.7 970.5 980.4 989.3 18867.3 18886.6 19140.5 19285.9 19422.1 1015.4 1035.4 1045.9 1051.2 1052.2 10223.2 10442.7 10577.9 10609.6 10715.2 467212.0 476822.0 478307.0 481164.0 482774.0 108198.0 110572.0 111398.0 114099.4 114496.0 1108.9 1173.6 1182.0 1182.6 1187.3 16696.2 17233.6 17244.2 17275.8 17366.5 54618.0 55734.0 56156.0 56629.2 57694.7 66728.3 66826.7 67256.7 67487.7 70183.0 19045.3 19179.3 19332.0 19494.8 19650.0 21731.9 21826.1 21930.5 21979.6 22048.3 17460.3 17488.4 17563.4 17768.7 17797.8 15714.0 15772.6 15848.2 16332.3 16507.9 17939.6 18031.8 18138.9 18224.0 18260.5 18791.4 18802.8 18915.7 18944.2 18958.3 17237.1 17586.6 17602.2 17712.9 17884.1 07:31:51 INFO - PID 7520 | 07:31:51 INFO - PID 7520 | [#3] mootools.html Cycles:180 Average:9534.05 Median:2726.01 stddev:6782.09 (248.8%) stddev-sans-first:6773.15 07:31:51 INFO - PID 7520 | Values: 1337.4 1349.9 1388.3 1392.7 1394.2 14707.0 16063.5 16080.0 16123.9 16133.8 15698.4 16004.4 16034.9 16048.3 16072.0 2724.6 2795.5 2798.7 2799.4 2811.3 2707.9 2783.2 2792.3 2793.7 2794.3 2661.8 2735.5 2759.3 2759.3 2864.4 2851.2 2883.9 2884.0 2884.6 2885.9 2542.7 2661.5 2687.0 2727.5 2727.5 15101.7 15253.3 15258.7 15272.1 15288.2 8127.3 8394.3 8400.7 8464.7 8465.4 5605.4 5745.9 5818.2 5871.0 5907.7 1991.9 2016.8 2017.0 2044.9 2054.4 25805.9 26254.0 26298.0 26307.1 26362.9 7253.2 7427.4 7566.1 7657.3 7878.1 20018.1 20152.0 20179.0 20181.1 20245.7 8103.6 8379.6 8563.1 8602.7 8632.8 7445.4 7547.6 7558.1 7608.7 7659.4 5325.9 5493.0 5512.5 5533.6 5547.5 1587.0 1599.3 1603.5 1605.7 1619.5 892.5 902.7 910.8 921.2 924.9 7503.5 7513.4 7535.6 7577.5 7604.8 724.9 726.2 728.6 730.3 733.9 4226.0 5165.0 5490.5 5539.5 5629.4 20659.2 21571.4 21869.2 21906.0 21923.1 20644.6 21577.5 21684.1 21907.8 21969.9 11631.9 11706.6 11754.7 11762.6 11766.7 8468.0 8635.4 8651.0 8657.7 8691.1 18046.3 18113.0 18249.5 18420.8 18572.6 19098.7 19116.0 19156.6 19248.6 19694.2 11165.6 11225.5 11358.7 11385.7 11428.9 14208.8 14321.8 14355.1 14370.6 14373.2 10973.3 11054.3 11063.5 11075.7 11291.5 11124.4 11530.0 11615.1 11670.0 11775.9 9817.4 9847.8 9901.5 9903.2 9907.3 6959.5 6973.5 6986.0 7004.4 7018.3 7148.2 7178.9 7202.5 7205.2 7212.9 07:31:51 INFO - PID 7520 | 07:31:51 INFO - PID 7520 | [#4] prototype.html Cycles:180 Average:5421.89 Median:6189.71 stddev:1504.85 (24.3%) stddev-sans-first:1463.21 07:31:51 INFO - PID 7520 | Values: 509.6 512.3 533.9 542.2 543.4 6239.5 6244.2 6280.5 6343.0 6365.2 6186.6 6195.0 6223.5 6234.4 6267.0 5086.0 5104.3 5133.9 5147.8 5198.3 4705.9 4866.0 5072.3 5094.9 5154.6 4837.7 4885.0 4891.2 4906.1 4945.7 4889.3 4912.4 4919.9 4922.5 4925.9 4274.4 4374.7 4381.0 4399.6 4420.8 5897.3 5918.2 5938.8 6054.2 6073.8 5606.4 5641.5 5644.3 5665.2 5684.2 6435.6 6437.8 6451.5 6477.7 6514.8 4638.0 4843.6 4856.6 4879.3 4883.9 6570.4 6702.5 6702.6 6750.6 6827.4 4868.6 4923.8 4928.7 4947.0 4949.2 4943.7 4981.7 4991.3 4998.5 5011.4 5499.9 5572.5 5579.7 5582.8 5625.3 6383.0 6391.8 6453.6 6488.8 6502.6 1737.9 1741.6 1757.6 1759.7 1762.3 1164.4 1222.2 1229.2 1237.1 1238.5 5497.4 5551.6 5598.9 5666.4 5698.0 6571.7 6576.9 6715.5 6722.8 6737.3 5579.9 5594.2 5613.6 5613.9 5708.7 3508.2 3513.7 3516.4 3516.5 3520.0 6358.2 6387.4 6413.0 6446.6 6498.3 6361.5 6719.7 6756.5 6771.9 6789.8 6369.2 6406.7 6421.3 6462.8 6463.9 6548.1 6570.7 6613.0 6628.6 6655.1 6333.3 6384.9 6462.3 6497.7 6581.2 6233.8 6256.7 6273.9 6277.3 6354.4 4927.8 4928.8 4934.7 4934.9 4988.1 6192.8 6355.6 6475.9 6515.6 6573.4 6245.2 6287.9 6383.2 6456.8 6457.9 6262.0 6269.2 6276.6 6343.8 6390.0 6254.3 6261.0 6265.4 6342.5 6397.3 6428.2 6493.0 6511.6 6519.4 6536.2 6198.7 6230.2 6241.9 6245.8 6253.7 07:31:51 INFO - PID 7520 | 07:31:51 INFO - PID 7520 | [#5] yui.html Cycles:180 Average:24046.16 Median:20906.03 stddev:35059.15 (167.7%) stddev-sans-first:35114.42 07:31:51 INFO - PID 7520 | Values: 899.0 904.6 914.6 920.2 923.0 22397.4 23800.4 23862.8 23893.0 23963.6 21455.4 21714.6 21731.6 21898.0 21911.2 902.0 978.4 985.2 985.8 988.5 997.1 999.0 1010.8 1011.7 1012.5 1048.9 1049.7 1052.9 1054.6 1056.6 1036.1 1036.8 1038.1 1039.1 1044.4 763.3 770.9 771.3 772.1 773.6 20017.1 20133.6 20174.7 20312.8 20356.7 21767.9 21954.2 22608.0 22632.4 22652.5 16916.6 17121.7 17219.4 17300.5 17351.0 3529.3 3560.8 3599.6 3622.9 3629.7 137301.0 138356.0 138907.0 139714.0 140807.0 16864.2 16892.7 17173.6 17350.2 17352.7 25628.6 26645.6 31291.2 31316.8 31376.2 8616.0 8955.0 8964.0 8976.1 8999.2 4162.4 4242.7 4247.7 4253.2 4263.8 5111.9 5269.9 5300.0 5334.4 5364.2 1119.2 1136.5 1157.9 1158.8 1159.2 680.8 695.6 701.9 707.2 709.6 10639.7 11056.3 11324.9 11570.9 11908.2 638.6 646.4 656.1 658.2 661.5 5062.3 5228.5 5268.3 5345.8 5369.7 118402.4 119081.0 121909.0 122626.0 123836.4 103940.0 105144.5 108546.5 113280.4 113679.4 90881.5 92956.5 93232.5 93330.1 95172.5 9790.0 9848.4 10060.6 10106.3 10252.3 43038.8 43591.3 43898.1 45228.5 45753.3 55765.0 55844.2 57136.7 57177.7 57662.7 12633.6 13153.8 13156.7 13246.6 13248.4 14087.2 14132.8 14296.7 14486.8 14525.2 13417.1 13720.1 13916.7 13931.2 13945.2 14321.9 14349.1 14526.1 14620.9 14633.7 15708.5 15720.2 15754.6 15984.4 16000.4 11060.6 11096.8 11286.1 11303.9 11813.6 11072.0 11337.9 11392.0 11460.9 11664.1 07:31:51 INFO - PID 7520 | -------- Summary: end -------- 07:31:51 INFO - PID 7520 | 07:31:52 INFO - TEST-INFO | 7520: exit 0 07:31:52 INFO - TEST-OK | dromaeo_css | took 1108559ms 07:31:52 INFO - TEST-START | kraken 07:31:52 INFO - Initialising browser for kraken test... 07:31:52 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46289/getInfo.html -profile /tmp/tmpEzkurl/profile 07:31:52 INFO - TEST-INFO | started process 7901 (/builds/slave/test/build/application/firefox/firefox http://localhost:46289/getInfo.html) 07:31:57 INFO - TEST-INFO | 7901: exit 0 07:31:57 INFO - Browser initialized. 07:31:57 INFO - Running cycle 1/1 for kraken test... 07:31:57 INFO - Using env: {'DISPLAY': ':0', 07:31:57 INFO - 'HOME': '/home/cltbld', 07:31:57 INFO - 'JSGC_DISABLE_POISONING': '1', 07:31:57 INFO - 'LANG': 'en_US.UTF-8', 07:31:57 INFO - 'LANGUAGE': 'en_US:en', 07:31:57 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 07:31:57 INFO - 'LOGNAME': 'cltbld', 07:31:57 INFO - 'MAIL': '/var/mail/cltbld', 07:31:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:31:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:31:57 INFO - 'MOZ_CRASHREPORTER': '1', 07:31:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:57 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 07:31:57 INFO - 'MOZ_NO_REMOTE': '1', 07:31:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:31:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:57 INFO - 'NO_EM_RESTART': '1', 07:31:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:57 INFO - 'PWD': '/builds/slave/test', 07:31:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:31:57 INFO - 'RUST_BACKTRACE': 'full', 07:31:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:31:57 INFO - 'SHELL': '/bin/bash', 07:31:57 INFO - 'SHLVL': '1', 07:31:57 INFO - 'TERM': 'linux', 07:31:57 INFO - 'TMOUT': '86400', 07:31:57 INFO - 'USER': 'cltbld', 07:31:57 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170', 07:31:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:57 INFO - '_': '/tools/buildbot/bin/python'} 07:31:57 INFO - TEST-INFO | started process 8138 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEzkurl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 07:31:58 INFO - PID 8138 | 07:31:58 INFO - PID 8138 | (/builds/slave/test/build/application/firefox/firefox:8187): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 07:31:58 INFO - PID 8138 | 07:32:08 INFO - PID 8138 | RSS: Main: 151478272 07:32:08 INFO - PID 8138 | 07:32:11 INFO - PID 8138 | Cycle 1(1): loaded http://localhost:46289/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46289/tests/kraken/driver.html?testName=audio-beat-detection) 07:32:12 INFO - PID 8138 | RSS: Main: 158494720 07:32:12 INFO - PID 8138 | 07:32:14 INFO - PID 8138 | Cycle 1(1): loaded http://localhost:46289/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46289/tests/kraken/driver.html?testName=audio-dft) 07:32:15 INFO - PID 8138 | RSS: Main: 158625792 07:32:15 INFO - PID 8138 | 07:32:18 INFO - PID 8138 | Cycle 1(1): loaded http://localhost:46289/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46289/tests/kraken/driver.html?testName=audio-fft) 07:32:19 INFO - PID 8138 | RSS: Main: 154030080 07:32:19 INFO - PID 8138 | 07:32:21 INFO - PID 8138 | Cycle 1(1): loaded http://localhost:46289/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46289/tests/kraken/driver.html?testName=audio-oscillator) 07:32:21 INFO - PID 8138 | RSS: Main: 154918912 07:32:21 INFO - PID 8138 | 07:32:23 INFO - PID 8138 | Cycle 1(1): loaded http://localhost:46289/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46289/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:32:24 INFO - PID 8138 | RSS: Main: 154853376 07:32:24 INFO - PID 8138 | 07:32:27 INFO - PID 8138 | Cycle 1(1): loaded http://localhost:46289/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46289/tests/kraken/driver.html?testName=imaging-darkroom) 07:32:28 INFO - PID 8138 | RSS: Main: 157421568 07:32:28 INFO - PID 8138 | 07:32:32 INFO - PID 8138 | Cycle 1(1): loaded http://localhost:46289/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46289/tests/kraken/driver.html?testName=imaging-desaturate) 07:32:32 INFO - PID 8138 | RSS: Main: 157839360 07:32:32 INFO - PID 8138 | 07:32:35 INFO - PID 8138 | Cycle 1(1): loaded http://localhost:46289/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46289/tests/kraken/driver.html?testName=json-parse-financial) 07:32:36 INFO - PID 8138 | RSS: Main: 157868032 07:32:36 INFO - PID 8138 | 07:32:37 INFO - PID 8138 | Cycle 1(1): loaded http://localhost:46289/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46289/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:32:38 INFO - PID 8138 | RSS: Main: 157700096 07:32:38 INFO - PID 8138 | 07:32:40 INFO - PID 8138 | Cycle 1(1): loaded http://localhost:46289/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46289/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:32:40 INFO - PID 8138 | RSS: Main: 157696000 07:32:40 INFO - PID 8138 | 07:32:42 INFO - PID 8138 | Cycle 1(1): loaded http://localhost:46289/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46289/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:32:43 INFO - PID 8138 | RSS: Main: 154701824 07:32:43 INFO - PID 8138 | 07:32:46 INFO - PID 8138 | Cycle 1(1): loaded http://localhost:46289/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46289/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:32:46 INFO - PID 8138 | RSS: Main: 155246592 07:32:46 INFO - PID 8138 | 07:32:49 INFO - PID 8138 | Cycle 1(1): loaded http://localhost:46289/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46289/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:32:50 INFO - PID 8138 | RSS: Main: 155320320 07:32:50 INFO - PID 8138 | 07:32:52 INFO - PID 8138 | Cycle 1(1): loaded http://localhost:46289/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46289/tests/kraken/driver.html?testName=ai-astar) 07:32:52 INFO - PID 8138 | RSS: Main: 156876800 07:32:52 INFO - PID 8138 | 07:32:52 INFO - PID 8138 | __start_tp_report 07:32:52 INFO - PID 8138 | _x_x_mozilla_page_load 07:32:52 INFO - PID 8138 | _x_x_mozilla_page_load_details 07:32:52 INFO - PID 8138 | |i|pagename|runs| 07:32:52 INFO - PID 8138 | |0;ai-astar;111;106;108;108;108;111;108;116;108;107 07:32:52 INFO - PID 8138 | |1;audio-beat-detection;116;116;115;116;115;116;118;117;117;117 07:32:52 INFO - PID 8138 | |2;audio-dft;162;162;163;163;161;162;163;167;165;162 07:32:52 INFO - PID 8138 | |3;audio-fft;78;73;76;74;77;74;74;73;74;74 07:32:52 INFO - PID 8138 | |4;audio-oscillator;80;80;80;79;79;80;80;80;80;79 07:32:52 INFO - PID 8138 | |5;imaging-gaussian-blur;115;113;114;113;113;115;117;115;114;115 07:32:52 INFO - PID 8138 | |6;imaging-darkroom;160;149;149;151;149;148;149;149;152;148 07:32:52 INFO - PID 8138 | |7;imaging-desaturate;94;95;94;95;95;94;94;94;94;95 07:32:52 INFO - PID 8138 | |8;json-parse-financial;58;53;54;58;53;55;56;56;54;53 07:32:52 INFO - PID 8138 | |9;json-stringify-tinderbox;37;38;37;39;38;38;38;39;39;38 07:32:52 INFO - PID 8138 | |10;stanford-crypto-aes;74;76;74;74;75;77;79;79;74;73 07:32:52 INFO - PID 8138 | |11;stanford-crypto-ccm;111;113;115;112;113;112;111;117;112;121 07:32:52 INFO - PID 8138 | |12;stanford-crypto-pbkdf2;173;166;175;167;174;166;164;168;168;173 07:32:52 INFO - PID 8138 | |13;stanford-crypto-sha256-iterative;77;66;70;64;65;66;72;67;64;67 07:32:52 INFO - PID 8138 | __end_tp_report 07:32:52 INFO - PID 8138 | __start_cc_report 07:32:52 INFO - PID 8138 | _x_x_mozilla_cycle_collect,765 07:32:52 INFO - PID 8138 | __end_cc_report 07:32:52 INFO - PID 8138 | __startTimestamp1506177172330__endTimestamp 07:32:52 INFO - PID 8138 | 07:32:52 INFO - PID 8138 | ------- Summary: start ------- 07:32:52 INFO - PID 8138 | Number of tests: 14 07:32:52 INFO - PID 8138 | 07:32:52 INFO - PID 8138 | [#0] ai-astar Cycles:10 Average:109.10 Median:108.00 stddev:2.88 (2.7%) stddev-sans-first:2.98 07:32:52 INFO - PID 8138 | Values: 111.0 106.0 108.0 108.0 108.0 111.0 108.0 116.0 108.0 107.0 07:32:52 INFO - PID 8138 | 07:32:52 INFO - PID 8138 | [#1] audio-beat-detection Cycles:10 Average:116.30 Median:116.50 stddev:0.95 (0.8%) stddev-sans-first:1.00 07:32:52 INFO - PID 8138 | Values: 116.0 116.0 115.0 116.0 115.0 116.0 118.0 117.0 117.0 117.0 07:32:52 INFO - PID 8138 | 07:32:52 INFO - PID 8138 | [#2] audio-dft Cycles:10 Average:163.00 Median:163.00 stddev:1.76 (1.1%) stddev-sans-first:1.83 07:32:52 INFO - PID 8138 | Values: 162.0 162.0 163.0 163.0 161.0 162.0 163.0 167.0 165.0 162.0 07:32:52 INFO - PID 8138 | 07:32:52 INFO - PID 8138 | [#3] audio-fft Cycles:10 Average:74.70 Median:74.00 stddev:1.70 (2.3%) stddev-sans-first:1.32 07:32:52 INFO - PID 8138 | Values: 78.0 73.0 76.0 74.0 77.0 74.0 74.0 73.0 74.0 74.0 07:32:52 INFO - PID 8138 | 07:32:52 INFO - PID 8138 | [#4] audio-oscillator Cycles:10 Average:79.70 Median:80.00 stddev:0.48 (0.6%) stddev-sans-first:0.50 07:32:52 INFO - PID 8138 | Values: 80.0 80.0 80.0 79.0 79.0 80.0 80.0 80.0 80.0 79.0 07:32:52 INFO - PID 8138 | 07:32:52 INFO - PID 8138 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:115.00 stddev:1.26 (1.1%) stddev-sans-first:1.32 07:32:52 INFO - PID 8138 | Values: 115.0 113.0 114.0 113.0 113.0 115.0 117.0 115.0 114.0 115.0 07:32:52 INFO - PID 8138 | 07:32:52 INFO - PID 8138 | [#6] imaging-darkroom Cycles:10 Average:150.40 Median:149.00 stddev:3.60 (2.4%) stddev-sans-first:1.32 07:32:52 INFO - PID 8138 | Values: 160.0 149.0 149.0 151.0 149.0 148.0 149.0 149.0 152.0 148.0 07:32:52 INFO - PID 8138 | 07:32:52 INFO - PID 8138 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 07:32:52 INFO - PID 8138 | Values: 94.0 95.0 94.0 95.0 95.0 94.0 94.0 94.0 94.0 95.0 07:32:52 INFO - PID 8138 | 07:32:52 INFO - PID 8138 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.50 stddev:1.94 (3.5%) stddev-sans-first:1.73 07:32:52 INFO - PID 8138 | Values: 58.0 53.0 54.0 58.0 53.0 55.0 56.0 56.0 54.0 53.0 07:32:52 INFO - PID 8138 | 07:32:52 INFO - PID 8138 | [#9] json-stringify-tinderbox Cycles:10 Average:38.10 Median:38.00 stddev:0.74 (1.9%) stddev-sans-first:0.67 07:32:52 INFO - PID 8138 | Values: 37.0 38.0 37.0 39.0 38.0 38.0 38.0 39.0 39.0 38.0 07:32:52 INFO - PID 8138 | 07:32:52 INFO - PID 8138 | [#10] stanford-crypto-aes Cycles:10 Average:75.50 Median:75.50 stddev:2.17 (2.9%) stddev-sans-first:2.24 07:32:52 INFO - PID 8138 | Values: 74.0 76.0 74.0 74.0 75.0 77.0 79.0 79.0 74.0 73.0 07:32:52 INFO - PID 8138 | 07:32:52 INFO - PID 8138 | [#11] stanford-crypto-ccm Cycles:10 Average:113.70 Median:113.00 stddev:3.16 (2.8%) stddev-sans-first:3.20 07:32:52 INFO - PID 8138 | Values: 111.0 113.0 115.0 112.0 113.0 112.0 111.0 117.0 112.0 121.0 07:32:52 INFO - PID 8138 | 07:32:52 INFO - PID 8138 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.40 Median:170.50 stddev:3.95 (2.3%) stddev-sans-first:3.97 07:32:52 INFO - PID 8138 | Values: 173.0 166.0 175.0 167.0 174.0 166.0 164.0 168.0 168.0 173.0 07:32:52 INFO - PID 8138 | 07:32:52 INFO - PID 8138 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:67.00 stddev:4.10 (6.1%) stddev-sans-first:2.68 07:32:52 INFO - PID 8138 | Values: 77.0 66.0 70.0 64.0 65.0 66.0 72.0 67.0 64.0 67.0 07:32:52 INFO - PID 8138 | -------- Summary: end -------- 07:32:52 INFO - PID 8138 | 07:32:52 INFO - PID 8138 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 07:32:52 INFO - TEST-INFO | 8138: exit 0 07:32:53 INFO - TEST-OK | kraken | took 60719ms 07:32:53 INFO - SUITE-END | took 1169s 07:32:53 INFO - Completed test suite (00:19:29) 07:32:53 INFO - javascript benchmark 07:32:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8542.37441697962, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3119.5476655884895, 3129.2333378322314, 3147.4334619768433, 3147.7796554241186, 3148.9212769680753, 6318.620882747027, 6322.367763223675, 6329.49364050876, 6379.13881625981, 6460.6769661516855, 6368.203974503187, 6408.26304974928, 6426.196725409324, 6448.64532450715, 6499.545031847772, 5636.126400407943, 5695.601307908448, 5823.06830907055, 5871.9412805871825, 5913.201717768111, 5807.593468457209, 5897.675627840467, 5910.645361278315, 5916.822495325147, 5936.079907614327, 5773.884522309551, 5844.883102337972, 5850.6489610623075, 5857.76568937242, 5861.472467477926, 5786.132080187964, 5821.476067153976, 5842.883142337172, 5846.473817356458, 5907.911381329283, 5777.335606405275, 5809.215755872945, 5848.122781582754, 5860.648361098305, 5893.734781934823, 6141.416565426277, 6276.780312689034, 6360.681965901686, 6425.550211485198, 6511.4465270452065, 6878.58728476288, 6897.724091036353, 6897.931020689779, 6904.964255361686, 6933.514653974224, 6872.725090996354, 6885.655717214119, 6957.956306554007, 6969.6515174241085, 6994.475414343894, 6208.534359922979, 6253.155823963707, 6290.025046117859, 6311.558190926637, 6343.9682801585695, 6929.896051559231, 6932.757353492603, 6945.062416573799, 6958.060661810692, 6974.651267436608, 6758.797236082925, 6767.966160169266, 6771.322867713189, 6777.390034896883, 6783.253842077367, 6810.761623343159, 6817.727290908408, 6897.896531552031, 6906.033155358254, 6910.689018994158, 6666.733330666816, 6700.731970721116, 6788.389044985975, 6829.112215411965, 6844.623545704934, 6749.898751518731, 6750.561213521204, 6756.831079223059, 6757.966210169016, 6768.120144381199, 5319.760610772524, 5326.520613144836, 5332.9466705333225, 5337.279467271868, 5342.198670199424, 4669.486356500782, 4701.271302948048, 4761.142994260997, 4767.570918617341, 4783.162946484351, 5889.617174883704, 5892.1161825726485, 5944.316403613553, 5968.015277479168, 5976.402359763989, 6687.966560167168, 6894.793156205322, 6901.137357830271, 6964.477664175207, 7002.229754726976, 5836.241288632451, 5836.9124463133085, 5837.970810145922, 5845.707714614252, 5863.560232982458, 5873.647581145145, 5921.437463441052, 5949.613275137102, 5964.926313263568, 5970.820875373745, 6661.3338666132995, 6726.730930762813, 6738.59568425896, 6754.0881980931945, 6755.189377274758, 6880.931190688029, 6890.414314783299, 6955.965220173866, 6968.198657154391, 6983.196932352845, 6419.101325814297, 6528.412442880163, 6633.170853643294, 6654.700538475683, 6743.966280168568, 6673.833154171185, 6744.0895479109695, 6757.256701762703, 6781.559198652072, 6787.0498130262795, 6864.622445765531, 6868.210155831944, 6958.199807022055, 6975.476839236976, 6988.336108069929, 6544.345565443417, 6546.901796472961, 6637.037629543785, 6638.20341559016, 6652.135222421054, 6550.0, 6610.206775187008, 6699.665016749241, 6700.631465269457, 6700.932990670226, 6610.603363798187, 6613.503987201076, 6621.404073633396, 6631.6684165792485, 6637.502187336066, 6658.533902626769, 6674.566153200027, 6678.0, 6763.154605674291, 6777.695003724745, 6109.236345456818, 6423.357664233539, 6428.903566446601, 6506.219253689587, 6519.967400163158], "value": 6221.866965091173, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [888.9866652000221, 891.7235656946351, 894.2979760887705, 913.3743385780737, 924.1497822003756, 38944.610553894454, 39816.20367592647, 39900.201995960066, 40035.799821000925, 40073.0, 35465.936021919384, 35557.0, 35705.821470892675, 35760.82119589405, 35769.82115089428, 7044.154701435822, 7133.607651579161, 7152.070230870006, 7165.462590305722, 7180.425565954737, 6915.239323674392, 6987.161540615133, 7017.403520700746, 7031.578105313672, 7059.647017649097, 7685.923140768577, 7894.2105789421175, 7905.486143400689, 7912.683492660287, 7915.050193976731, 7777.377809775205, 7804.648790804371, 7859.842803143905, 7866.724664636738, 7872.29149376556, 7688.423368247404, 7726.690932362699, 7826.843463130763, 7860.410469214775, 7877.8030549236155, 35906.461403078974, 36247.63752362469, 36249.63750362489, 36269.81865090658, 36337.0, 3506.63180366063, 3513.91215219619, 3520.0, 3560.732945029133, 3563.82180890957, 29460.852695736598, 29506.70493295061, 29771.40457190844, 29789.55315670245, 29804.0, 4966.528179822947, 4994.55049045584, 4999.200127979506, 5035.597152227813, 5042.848714538533, 171047.0, 173009.13495432568, 181586.0, 182771.08614456977, 184147.07926460417, 46780.7660961693, 47134.29298560524, 47222.76388618035, 47282.29076563854, 47377.0, 48622.75688621534, 48762.0, 48830.26754598684, 48880.75559622179, 48883.75558122186, 15880.920595396949, 16034.67930641404, 16039.599010024842, 16093.919530402272, 16128.596785080466, 13302.135361201492, 13367.131136476099, 13372.93313533426, 13377.063605547519, 13388.732225355438, 13574.25341606221, 13900.721985560434, 13918.373673184731, 13938.721225575431, 14093.78859317111, 5633.746481408347, 5750.971245143831, 5806.332271788799, 5826.6795326256615, 5878.588498805043, 1007.1087087927091, 1011.3881101933224, 1018.7096677446892, 1025.7845852370936, 1046.1108058150512, 22246.221382251544, 22424.327270181922, 22665.433364166027, 22719.545609087727, 22806.65790013151, 1128.8588926384202, 1130.2088538023415, 1131.547381047571, 1135.7444574970566, 1136.5851464215502, 11866.703332416586, 11949.402529873472, 11965.222260553037, 12098.697532561757, 12135.635930922086, 202512.0, 202889.0, 204233.0, 204505.0, 204567.0, 48445.27332089951, 48774.0, 48921.26618100731, 49041.754791225816, 49071.26393104106, 1445.5518789175178, 1457.6865973815468, 1484.3246322923044, 1484.6807936293749, 1492.6566889615233, 22083.779162208815, 22117.447063823533, 22265.888670556542, 22415.66376504321, 22487.887560562092, 31921.201969950936, 32059.0, 33040.1739956503, 34423.31153376969, 35352.292954140255, 173716.0, 173769.0, 174905.0, 175433.0, 175553.0, 29475.705242947297, 29846.25384365408, 29871.0, 31811.20471988126, 32043.67956320407, 39853.20293594054, 39924.80037599793, 39948.201035978534, 39984.4002339971, 40062.0, 29425.11724648264, 29514.704852951196, 29831.850840745657, 30078.849605752708, 30572.59253705249, 29066.709332907245, 29385.70614293915, 29501.26246843846, 29674.0, 29768.255793605334, 32978.0, 33012.51443685088, 33561.83219083889, 33691.494627581094, 33728.49407258844, 27225.319367015192, 28910.710892891642, 29011.274718132216, 29221.0, 29436.411271774872, 22504.437389065406, 22654.0, 22790.886045569667, 22900.198493052663, 23040.769592303863], "value": 16431.66654360039, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.2113147251209, 598.7515181199797, 606.2997238189889, 614.450067189865, 621.1335187413558, 21654.0, 22529.887350563225, 22560.54878902421, 22618.321450356496, 22625.886870565646, 22596.43508912278, 23794.286171414886, 24062.278131656036, 24241.27276181713, 24246.636300455506, 6468.320826313228, 6695.732170713166, 6698.631575263358, 6703.765368212114, 6704.061431399608, 6560.639164845931, 6570.244421891496, 6846.0, 7140.571565706075, 7185.137783465992, 7172.21105678375, 7176.067111275527, 7176.6052867092285, 7180.748673796418, 7182.066331376914, 6794.898076528807, 7143.464240181956, 7176.6052867092285, 7178.784636460915, 7182.820429489252, 6970.93029069713, 6988.475864310197, 7004.229534751174, 7026.332498412643, 7027.08647875778, 29153.41693166146, 29213.26966825825, 29518.70481295181, 29719.40561188764, 29835.850820745974, 2048.1397812918462, 2067.400453868376, 2078.4076538186546, 2079.9896000519902, 2081.5316553775433, 20506.0, 20606.79393206064, 21133.5773284535, 21369.572608547744, 21393.0, 2509.6235564665444, 2539.657146285246, 2552.1960582416477, 2558.143022087584, 2582.7546383093577, 122928.38535807264, 128515.0, 128763.0, 133108.0, 138790.3060484701, 19785.901070494554, 20256.493587660425, 20981.790182098288, 23330.300090997298, 24665.506689866463, 41495.170096597896, 41545.1690966179, 41715.16569668589, 41830.1633967325, 41916.79041604773, 11427.200095993367, 11467.77064458706, 11491.42542872853, 11641.0, 11644.825327620092, 9439.622415103318, 9561.474118923526, 9600.75998100053, 9619.663311784194, 9749.561269742879, 10813.243072984815, 11029.724256893482, 11392.0, 11458.255213411101, 11527.250728702607, 3883.7281390302974, 4010.478637777129, 4021.9195616087513, 4241.981924338136, 4257.441544968434, 952.9904700952902, 968.6803354892868, 970.4807927758616, 980.4166520920041, 989.3420875117966, 18867.339643112424, 18886.622267554572, 19140.521486962938, 19285.90357048234, 19422.12600432984, 1015.4110615842941, 1035.3942943378208, 1045.8744950605824, 1051.1853313681804, 1052.2476429353048, 10223.233257505568, 10442.738931526772, 10577.9471102644, 10609.575616975235, 10715.24993250465, 467212.0, 476822.0, 478307.0, 481164.0, 482774.0, 108198.0, 110572.0, 111398.0, 114099.42950285361, 114496.0, 1108.8558487396588, 1173.600975668277, 1181.9586314478952, 1182.6156499137815, 1187.3113594115393, 16696.16519174061, 17233.56916077108, 17244.224009919333, 17275.827241727926, 17366.47900562985, 54618.0, 55734.0, 56156.0, 56629.15056274242, 57694.7115264421, 66728.33271667222, 66826.66586667036, 67256.66371668306, 67487.66256168688, 70183.0, 19045.333413330467, 19179.32872349461, 19332.0, 19494.805051949297, 19650.0, 21731.891340543196, 21826.126954921627, 21930.45173870666, 21979.560408792055, 22048.33854984353, 17460.30158793634, 17488.387906423213, 17563.385281515086, 17768.733468997718, 17797.822021779615, 15714.0, 15772.60568485797, 15848.207589620242, 16332.265048073103, 16507.91746041262, 17939.551511212325, 18031.819681803016, 18138.909305453388, 18224.0, 18260.543486412946, 18791.436256912315, 18802.811971880106, 18915.71626425577, 18944.242230311185, 18958.336458223897, 17237.051962142203, 17586.648267034845, 17602.207900644757, 17712.911435443257, 17884.10579471047], "value": 12743.936117243602, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1337.411538922874, 1349.932503374831, 1388.3197233355659, 1392.6936074063703, 1394.2331717555342, 14706.999999999987, 16063.518094457171, 16080.0, 16123.919380403082, 16133.838661613381, 15698.37206511741, 16004.359825606962, 16034.919825400886, 16048.27782749776, 16072.0, 2724.5640697488357, 2795.53873610855, 2798.720127987205, 2799.4401119776026, 2811.325281932342, 2707.9323016924536, 2783.1928740665185, 2792.2879665685323, 2793.69269380368, 2794.3433293176085, 2661.7870570354326, 2735.4529094181144, 2759.2550011496883, 2759.296379423254, 2864.3841574061676, 2851.17315978366, 2883.913482595515, 2884.033848660701, 2884.5673149027602, 2885.8845646174127, 2542.6948766147993, 2661.4943160799385, 2686.973130268692, 2727.4545090981883, 2727.481778462106, 15101.697966040618, 15253.313600887986, 15258.694826103416, 15272.083674979536, 15288.159151246677, 8127.349812015024, 8394.286485648727, 8400.747977560683, 8464.703735369294, 8465.449745766506, 5605.355384130837, 5745.856353591152, 5818.156367326714, 5870.970645146747, 5907.6750778707155, 1991.8506112041655, 2016.8084032016934, 2016.9596608067902, 2044.9284275050302, 2054.3836848945257, 25805.870970645214, 26253.999999999807, 26298.0, 26307.07925222627, 26362.86818565895, 7253.238409966983, 7427.405807535384, 7566.1298950620285, 7657.272559106876, 7878.094019187753, 20018.099185536685, 20152.0, 20178.991050447712, 20181.091850866746, 20245.696314555, 8103.554304513191, 8379.62291696875, 8563.058063612998, 8602.655893764304, 8632.78418039554, 7445.367143792838, 7547.622618869034, 7558.13081495624, 7608.657610407434, 7659.425543084291, 5325.8934821303355, 5493.0, 5512.5314348280845, 5533.584981126432, 5547.472990065938, 1586.9841301586837, 1599.2963096237618, 1603.4868841970458, 1605.7350537161237, 1619.481765834945, 892.4957399069505, 902.7427183252872, 910.8269428808637, 921.1755478846395, 924.9213816825511, 7503.549787012687, 7513.39892808574, 7535.585542795089, 7577.507462015062, 7604.8479030419085, 724.8724992148062, 726.2193142371972, 728.581065887117, 730.3062091013452, 733.878909979858, 4225.9577404226175, 5164.9741751291, 5490.533304669148, 5539.529140023144, 5629.352624448159, 20659.17363305481, 21571.352859414244, 21869.234576790055, 21906.0, 21923.12307507682, 20644.58710825775, 21577.460563486038, 21684.132634694437, 21907.780922190574, 21969.890150549145, 11631.941840290914, 11706.648800535997, 11754.70613234676, 11762.647120586225, 11766.705832354088, 8467.95766021166, 8635.43869648496, 8651.0, 8657.696980605648, 8691.130886911258, 18046.2781488739, 18113.0, 18249.543761406072, 18420.81579184191, 18572.628547429245, 19098.7135192975, 19116.0, 19156.616867662848, 19248.61502769965, 19694.21223151058, 11165.609203677832, 11225.494852731483, 11358.659240222807, 11385.715357116138, 11428.885711142782, 14208.78686819678, 14321.785173222203, 14355.138691678532, 14370.568882933529, 14373.209473479059, 10973.341599504056, 11054.336739795637, 11063.50214240345, 11075.667729968114, 11291.548338066385, 11124.443777811239, 11530.0, 11615.070794336601, 11670.0, 11775.882241177822, 9817.410955342417, 9847.753806154904, 9901.455419951972, 9903.158231550398, 9907.256955728493, 6959.478039147186, 6973.476989225727, 6985.965070174617, 7004.4046256068805, 7018.33325834045, 7148.213696493485, 7178.856422871618, 7202.459815513962, 7205.171405288459, 7212.855742885218], "value": 6782.156121843995, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.5566856834552, 512.2521119166016, 533.8672005338672, 542.156945949049, 543.3506959183775, 6239.4696450801675, 6244.188255526781, 6280.528960327971, 6342.98512238042, 6365.172527571409, 6186.597871138373, 6195.008798592226, 6223.5332350073695, 6234.376562343775, 6267.0, 5085.982803439309, 5104.259882317062, 5133.897322053566, 5147.768350424221, 5198.324217851693, 4705.882352941187, 4865.975670121645, 5072.3405957225505, 5094.872628184288, 5154.60825577094, 4837.661363704542, 4884.951150488485, 4891.1685013547785, 4906.1168989581865, 4945.7032578045255, 4889.315495830587, 4912.38595175603, 4919.852404427855, 4922.532359425849, 4925.901481970341, 4274.380214868857, 4374.693771436001, 4380.978095109536, 4399.5820397062225, 4420.756858372788, 5897.262842144732, 5918.201042859245, 5938.7624495020145, 6054.182685337467, 6073.757049718006, 5606.35526914406, 5641.492265696107, 5644.322681278273, 5665.206871038058, 5684.232628595169, 6435.581687190318, 6437.806865794034, 6451.483881289532, 6477.708503117324, 6514.771982980573, 4638.0, 4843.564079232886, 4856.562909338141, 4879.316635760657, 4883.902321953577, 6570.375814297634, 6702.530822842403, 6702.631355275458, 6750.62871542065, 6827.351401616888, 4868.586170175504, 4923.753812309442, 4928.704277743275, 4946.950530494721, 4949.208126699711, 4943.72809495474, 4981.726005069683, 4991.326170966946, 4998.451099719167, 5011.448740638527, 5499.862503437366, 5572.4706152915405, 5579.721013949286, 5582.7766889323975, 5625.324961004624, 6383.042543618494, 6391.840203994937, 6453.645049522322, 6488.837779055467, 6502.60984340941, 1737.8870373425686, 1741.5559032446645, 1757.6045389787196, 1759.7008508553577, 1762.3038899634573, 1164.394514852289, 1222.1628184693514, 1229.2010193374379, 1237.127824883455, 1238.5293588436516, 5497.39528651848, 5551.555875529948, 5598.944010559842, 5666.433356664383, 5697.971510142423, 6571.737130514822, 6576.901346479806, 6715.529912906148, 6722.798316050526, 6737.326267373223, 5579.944200557942, 5594.1888426177975, 5613.578981576398, 5613.943860561342, 5708.659449625634, 3508.1580420698847, 3513.7013353864695, 3516.4373700207843, 3516.4725291206014, 3520.0, 6358.1734374531015, 6387.393197646216, 6413.0, 6446.645434501053, 6498.285188629247, 6361.522885783585, 6719.697613607399, 6756.459483241329, 6771.8984215236815, 6789.830254243683, 6369.20384951881, 6406.743730250831, 6421.261554921244, 6462.773802916875, 6463.903041454382, 6548.148740663683, 6570.653016131777, 6612.966935165486, 6628.635425051669, 6655.101561289358, 6333.271673757393, 6384.93615063862, 6462.321456247027, 6497.707603157964, 6581.177352830896, 6233.781817636361, 6256.718447669957, 6273.905891411541, 6277.3408792078, 6354.3963323484195, 4927.778249978831, 4928.753562321941, 4934.728589927445, 4934.8766280843265, 4988.12707776145, 6192.81421557354, 6355.618662880242, 6475.935240647534, 6515.641639709672, 6573.375529324707, 6245.219347581552, 6287.874242515032, 6383.170187875547, 6456.806295811133, 6457.903131453126, 6262.029385445081, 6269.153664255267, 6276.592021518587, 6343.828085957021, 6390.0, 6254.2807577129215, 6261.029540421242, 6265.4361107500545, 6342.524310676626, 6397.26431460388, 6428.228612566522, 6492.967535162294, 6511.641859697762, 6519.445847103049, 6536.150300460909, 6198.667286533417, 6230.190075290183, 6241.906371404341, 6245.750169993149, 6253.749850005949], "value": 4985.265908868855, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [898.9505577193255, 904.6291020681517, 914.643289117244, 920.2408013388954, 922.9769255768605, 22397.44006399841, 23800.404989875242, 23862.76137238627, 23893.0, 23963.640545391834, 21455.356339309845, 21714.565708685815, 21731.565368692616, 21898.0, 21911.233106841264, 901.9729408117767, 978.4031740638205, 985.22167487685, 985.8274801909673, 988.5156273426005, 997.1225321716879, 998.9710598083949, 1010.7725761703595, 1011.6813203840767, 1012.463394400965, 1048.9213309001818, 1049.716576524338, 1052.9315594486372, 1054.5887104029434, 1056.550965839519, 1036.0623635609786, 1036.777092925021, 1038.065740833256, 1039.0544604409988, 1044.373375974413, 763.2634507700096, 770.9421793365464, 771.3135309574483, 772.1043589436227, 773.6479901644794, 20017.099230534663, 20133.597328053504, 20174.697379539317, 20312.79687203124, 20356.69464958027, 21767.891160544095, 21954.231601893858, 22608.0, 22632.43418914524, 22652.546949060925, 16916.57708557296, 17121.657566848593, 17219.3973210937, 17300.48098557045, 17351.0, 3529.3294274087843, 3560.786352818839, 3599.5680518337704, 3622.9094272643133, 3629.7096232301615, 137301.0, 138356.0, 138907.0, 139714.0, 140807.0, 16864.241109150116, 16892.662146756997, 17173.57066073333, 17350.219240134225, 17352.739708904377, 25628.615570766455, 26645.600315995274, 31291.217719557193, 31316.84341578323, 31376.215594610316, 8615.956920215358, 8954.955225223963, 8964.0, 8976.102389760972, 8999.19007289347, 4162.396452514368, 4242.7242229255, 4247.723897946624, 4253.191893540217, 4263.8081286342185, 5111.948880511147, 5269.947300526946, 5299.973500132474, 5334.4398838122215, 5364.22218778275, 1119.1885882735114, 1136.4658610453073, 1157.8726340102583, 1158.7798318319492, 1159.2117360195148, 680.8468094678658, 695.5687473766196, 701.9333163349473, 707.1655446573093, 709.6026225313841, 10639.734006649896, 11056.281341712764, 11324.94337528307, 11570.88429115698, 11908.166428350105, 638.5530128909782, 646.4311405962817, 656.1076935367896, 658.2397331082607, 661.490652197805, 5062.265971434122, 5228.529432351031, 5268.262443257947, 5345.812896548602, 5369.6509726867625, 118402.40798795951, 119081.0, 121909.0, 122626.0, 123836.38081809533, 103940.0, 105144.47427762812, 108546.45726771315, 113280.43359783148, 113679.4316028431, 90881.54559227293, 92956.53521732349, 93232.53383733038, 93330.06669933214, 95172.52413738026, 9790.0, 9848.409095454295, 10060.647877324258, 10106.343087699275, 10252.282340236112, 43038.78480607577, 43591.34612980745, 43898.12203755843, 45228.547714522436, 45753.31370029385, 55765.0, 55844.16233756578, 57136.71431642982, 57177.71411142917, 57662.7116864413, 12633.620991370273, 13153.802692959805, 13156.736865262832, 13246.602601921957, 13248.403821827847, 14087.225202613954, 14132.78800817968, 14296.714065718836, 14486.782698259323, 14525.201113938412, 13417.127886687334, 13720.108192967426, 13916.721665566834, 13931.164130152223, 13945.233012184428, 14321.856781432052, 14349.139051656934, 14526.055806372975, 14620.926895365455, 14633.707325853211, 15708.528744137695, 15720.21398930026, 15754.606134846721, 15984.440544580884, 16000.439984600484, 11060.612878549213, 11096.833547496955, 11286.097112231166, 11303.886961130613, 11813.645590632295, 11072.0, 11337.866213378926, 11392.0, 11460.885391145981, 11664.066874650178], "value": 8820.311730270947, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1421.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 108.0, 108.0, 108.0, 111.0, 108.0, 116.0, 108.0, 107.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 115.0, 116.0, 115.0, 116.0, 118.0, 117.0, 117.0, 117.0], "value": 116.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 163.0, 161.0, 162.0, 163.0, 167.0, 165.0, 162.0], "value": 163.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 76.0, 74.0, 77.0, 74.0, 74.0, 73.0, 74.0, 74.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 80.0, 79.0, 79.0, 80.0, 80.0, 80.0, 80.0, 79.0], "value": 79.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 113.0, 114.0, 113.0, 113.0, 115.0, 117.0, 115.0, 114.0, 115.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 149.0, 149.0, 151.0, 149.0, 148.0, 149.0, 149.0, 152.0, 148.0], "value": 150.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 53.0, 54.0, 58.0, 53.0, 55.0, 56.0, 56.0, 54.0, 53.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 38.0, 37.0, 39.0, 38.0, 38.0, 38.0, 39.0, 39.0, 38.0], "value": 38.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 74.0, 74.0, 75.0, 77.0, 79.0, 79.0, 74.0, 73.0], "value": 75.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 115.0, 112.0, 113.0, 112.0, 111.0, 117.0, 112.0, 121.0], "value": 113.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 166.0, 175.0, 167.0, 174.0, 166.0, 164.0, 168.0, 168.0, 173.0], "value": 169.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 66.0, 70.0, 64.0, 65.0, 66.0, 72.0, 67.0, 64.0, 67.0], "value": 67.8, "unit": "score"}]}]} 07:32:53 INFO - Return code: 0 07:32:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:32:53 INFO - # TBPL SUCCESS # 07:32:53 INFO - Running post-action listener: _package_coverage_data 07:32:53 INFO - Running post-action listener: _resource_record_post_action 07:32:53 INFO - [mozharness: 2017-09-23 14:32:53.621268Z] Finished run-tests step (success) 07:32:53 INFO - Running post-run listener: _resource_record_post_run 07:32:53 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 12288; Write bytes: 410210304; Read time: 340; Write time: 302024 07:32:53 INFO - TinderboxPrint: CPU usage
13.1% 07:32:53 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 340 07:32:53 INFO - TinderboxPrint: I/O write bytes / time
410,210,304 / 302,024 07:32:53 INFO - TinderboxPrint: CPU idle
8,227.9 (86.9%) 07:32:53 INFO - TinderboxPrint: CPU user
1,193.8 (12.6%) 07:32:53 INFO - TinderboxPrint: Swap in / out
0 / 0 07:32:53 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 81813504; Read time: 168; Write time: 39832 07:32:53 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 07:32:53 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 4096; Write bytes: 328032256; Read time: 168; Write time: 262168 07:32:54 INFO - Running post-run listener: _upload_blobber_files 07:32:54 INFO - Blob upload gear active. 07:32:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:32:54 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 07:32:54 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:32:54 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:32:54 INFO - (blobuploader) - INFO - Open directory for files ... 07:32:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:32:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:33:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:00 INFO - (blobuploader) - INFO - Done attempting. 07:33:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:33:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:33:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:01 INFO - (blobuploader) - INFO - Done attempting. 07:33:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:33:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:33:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:03 INFO - (blobuploader) - INFO - Done attempting. 07:33:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:33:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:33:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:04 INFO - (blobuploader) - INFO - Done attempting. 07:33:04 INFO - (blobuploader) - INFO - Iteration through files over. 07:33:04 INFO - Return code: 0 07:33:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:33:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:33:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dffec9baa080b95947a6884a5fc0034dedc00394d0218794d62b3b3f75919c2579ca0913e154499d9e061c5be7e09abc315995643d4998a74ca79a4e3a7e9900", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/69ca901f81858fa63f25c7fc4a32407cff29e0139269078790c449564e57d3cdd91da5db9e401c4572596b5f120695a700eabd0eebe13829a22013b9a45c095c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4617146befa8a7987caab68a6214b528784b9ba651992ae348c670062f29a4ddabc87952d0f03f420197952533222402fb22e58d56b1831afe3f7761b233cc85", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f090a80711364509485a42fd1a36f0c7fd3880fac42f518f97c47b5cd8d476a50d2d8a8e26574d15df4adbc79dfbfcdeeb1faf6b92e75dacb37a1bbd67bec46c"} 07:33:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:33:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:33:04 INFO - Contents: 07:33:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dffec9baa080b95947a6884a5fc0034dedc00394d0218794d62b3b3f75919c2579ca0913e154499d9e061c5be7e09abc315995643d4998a74ca79a4e3a7e9900", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/69ca901f81858fa63f25c7fc4a32407cff29e0139269078790c449564e57d3cdd91da5db9e401c4572596b5f120695a700eabd0eebe13829a22013b9a45c095c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4617146befa8a7987caab68a6214b528784b9ba651992ae348c670062f29a4ddabc87952d0f03f420197952533222402fb22e58d56b1831afe3f7761b233cc85", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f090a80711364509485a42fd1a36f0c7fd3880fac42f518f97c47b5cd8d476a50d2d8a8e26574d15df4adbc79dfbfcdeeb1faf6b92e75dacb37a1bbd67bec46c"} 07:33:04 INFO - Running post-run listener: copy_logs_to_upload_dir 07:33:04 INFO - Copying logs to upload dir... 07:33:04 INFO - mkdir: /builds/slave/test/build/upload/logs 07:33:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.031398 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-23 07:33:04.363091) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 07:33:04.367358) ========= 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=9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dffec9baa080b95947a6884a5fc0034dedc00394d0218794d62b3b3f75919c2579ca0913e154499d9e061c5be7e09abc315995643d4998a74ca79a4e3a7e9900", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/69ca901f81858fa63f25c7fc4a32407cff29e0139269078790c449564e57d3cdd91da5db9e401c4572596b5f120695a700eabd0eebe13829a22013b9a45c095c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4617146befa8a7987caab68a6214b528784b9ba651992ae348c670062f29a4ddabc87952d0f03f420197952533222402fb22e58d56b1831afe3f7761b233cc85", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f090a80711364509485a42fd1a36f0c7fd3880fac42f518f97c47b5cd8d476a50d2d8a8e26574d15df4adbc79dfbfcdeeb1faf6b92e75dacb37a1bbd67bec46c"} build_url:https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011294 build_url: 'https://queue.taskcluster.net/v1/task/RZ7fYPz7S4aFd-96ndeTBg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dffec9baa080b95947a6884a5fc0034dedc00394d0218794d62b3b3f75919c2579ca0913e154499d9e061c5be7e09abc315995643d4998a74ca79a4e3a7e9900", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/69ca901f81858fa63f25c7fc4a32407cff29e0139269078790c449564e57d3cdd91da5db9e401c4572596b5f120695a700eabd0eebe13829a22013b9a45c095c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4617146befa8a7987caab68a6214b528784b9ba651992ae348c670062f29a4ddabc87952d0f03f420197952533222402fb22e58d56b1831afe3f7761b233cc85", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f090a80711364509485a42fd1a36f0c7fd3880fac42f518f97c47b5cd8d476a50d2d8a8e26574d15df4adbc79dfbfcdeeb1faf6b92e75dacb37a1bbd67bec46c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 07:33:04.404486) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 07:33:04.404828) ========= 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=9531f05ccb2eabcb9acf5f1b000001c0-1506174482.703465-2132256170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 07:33:04.485869) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 07:33:04.489285) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 07:33:04.489912) ========= ========= Total master_lag: 0.27 =========