builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-027 starttime: 1506884005.99 results: success (0) revision: 3cfe8f48847512909238760bcf8410115cb55ad6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:25.985905) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:25.986288) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:25.986554) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:26.061210) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:26.061467) ========= 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=1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-01 11:53:26-- 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% 31.4M=0s 2017-10-01 11:53:26 (31.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133941 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:26.216624) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:26.216895) ========= 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=1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018228 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:26.266593) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:26.266922) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3cfe8f48847512909238760bcf8410115cb55ad6 --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 3cfe8f48847512909238760bcf8410115cb55ad6 --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=1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-01 11:53:26,488 truncating revision to first 12 chars 2017-10-01 11:53:26,488 Setting DEBUG logging. 2017-10-01 11:53:26,488 attempt 1/10 2017-10-01 11:53:26,489 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3cfe8f488475?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-01 11:53:26,748 unpacking tar archive at: mozilla-central-3cfe8f488475/testing/mozharness/ program finished with exit code 0 elapsedTime=0.575405 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:26.859554) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:26.859840) ========= script_repo_revision: 3cfe8f48847512909238760bcf8410115cb55ad6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:26.863201) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:26.863553) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:26.883660) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 28 secs) (at 2017-10-01 11:53:26.883938) ========= /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=1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:53:27 INFO - MultiFileLogger online at 20171001 11:53:27 in /builds/slave/test 11:53:27 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 11:53:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:53:27 INFO - {'append_to_log': False, 11:53:27 INFO - 'base_work_dir': '/builds/slave/test', 11:53:27 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:53:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:53:27 INFO - 'branch': 'Firefox-Non-PGO', 11:53:27 INFO - 'buildbot_json_path': 'buildprops.json', 11:53:27 INFO - 'code_coverage': False, 11:53:27 INFO - 'config_files': ('talos/linux_config.py',), 11:53:27 INFO - 'default_actions': ('clobber', 11:53:27 INFO - 'read-buildbot-config', 11:53:27 INFO - 'download-and-extract', 11:53:27 INFO - 'populate-webroot', 11:53:27 INFO - 'create-virtualenv', 11:53:27 INFO - 'install', 11:53:27 INFO - 'setup-mitmproxy', 11:53:27 INFO - 'run-tests'), 11:53:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:53:27 INFO - 'disable_ccov_upload': False, 11:53:27 INFO - 'disable_stylo': False, 11:53:27 INFO - 'download_minidump_stackwalk': True, 11:53:27 INFO - 'download_symbols': 'ondemand', 11:53:27 INFO - 'enable_stylo': False, 11:53:27 INFO - 'enable_webrender': False, 11:53:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:53:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:53:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:53:27 INFO - 'gecko_profile': False, 11:53:27 INFO - 'gecko_profile_interval': 0, 11:53:27 INFO - 'installer_path': 'installer.exe', 11:53:27 INFO - 'log_level': 'info', 11:53:27 INFO - 'log_name': 'talos', 11:53:27 INFO - 'log_to_console': True, 11:53:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:53:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:53:27 INFO - 'opt_config_files': (), 11:53:27 INFO - 'pip_index': False, 11:53:27 INFO - 'suite': 'dromaeojs-e10s', 11:53:27 INFO - 'system_bits': '32', 11:53:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:53:27 INFO - 'title': 'talos-linux64-ix-027', 11:53:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:53:27 INFO - 'use_talos_json': True, 11:53:27 INFO - 'verify': 'False', 11:53:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:53:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:53:27 INFO - 'work_dir': 'build'} 11:53:27 INFO - [mozharness: 2017-10-01 18:53:27.252547Z] Running clobber step. 11:53:27 INFO - Running pre-action listener: _resource_record_pre_action 11:53:27 INFO - Running main action method: clobber 11:53:27 INFO - rmtree: /builds/slave/test/build 11:53:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:53:28 INFO - Running post-action listener: _resource_record_post_action 11:53:28 INFO - [mozharness: 2017-10-01 18:53:28.252300Z] Finished clobber step (success) 11:53:28 INFO - [mozharness: 2017-10-01 18:53:28.252398Z] Running read-buildbot-config step. 11:53:28 INFO - Running pre-action listener: _resource_record_pre_action 11:53:28 INFO - Running main action method: read_buildbot_config 11:53:28 INFO - Using buildbot properties: 11:53:28 INFO - { 11:53:28 INFO - "project": "", 11:53:28 INFO - "product": "firefox", 11:53:28 INFO - "who": "ffxbld@noreply.mozilla.org", 11:53:28 INFO - "installer_path": "public/build/target.tar.bz2", 11:53:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:53:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:53:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 11:53:28 INFO - "stage_platform": "linux64", 11:53:28 INFO - "basedir": "/builds/slave/test", 11:53:28 INFO - "buildnumber": 888, 11:53:28 INFO - "platform": "ubuntu64_hw", 11:53:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:53:28 INFO - "slavebuilddir": "test", 11:53:28 INFO - "taskId": "LTalhf06Rb6k9eQLcDwUGQ", 11:53:28 INFO - "branch": "mozilla-central", 11:53:28 INFO - "script_repo_revision": "production", 11:53:28 INFO - "revision": "3cfe8f48847512909238760bcf8410115cb55ad6", 11:53:28 INFO - "slavename": "talos-linux64-ix-027", 11:53:28 INFO - "repo_path": "mozilla-central" 11:53:28 INFO - } 11:53:28 INFO - Finding installer, test and symbols from parent task. 11:53:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LTalhf06Rb6k9eQLcDwUGQ'}, attempt #1 11:53:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LTalhf06Rb6k9eQLcDwUGQ'}, attempt #1 11:53:29 INFO - Task dependencies: aaFg7CtMSLCpUxmqnoapeA 11:53:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA'}, attempt #1 11:53:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.tar.bz2 11:53:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.test_packages.json 11:53:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.crashreporter-symbols.zip 11:53:29 INFO - Running post-action listener: _resource_record_post_action 11:53:29 INFO - [mozharness: 2017-10-01 18:53:29.615124Z] Finished read-buildbot-config step (success) 11:53:29 INFO - [mozharness: 2017-10-01 18:53:29.615341Z] Running download-and-extract step. 11:53:29 INFO - Running pre-action listener: _resource_record_pre_action 11:53:29 INFO - Running main action method: download_and_extract 11:53:29 INFO - mkdir: /builds/slave/test/build/tests 11:53:29 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')]} 11:53:29 INFO - trying https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.test_packages.json 11:53:29 INFO - Downloading https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:53:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:53:30 INFO - Downloaded 989 bytes. 11:53:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:53:30 INFO - Using the following test package requirements: 11:53:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:53:30 INFO - u'common': [u'target.common.tests.zip'], 11:53:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:53:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:53:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:53:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:53:30 INFO - u'mozbase': [u'target.common.tests.zip'], 11:53:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:53:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:53:30 INFO - u'web-platform': [u'target.common.tests.zip', 11:53:30 INFO - u'target.web-platform.tests.tar.gz'], 11:53:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:53:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 11:53:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.common.tests.zip 11:53:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:53:30 INFO - Fetch https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.common.tests.zip into memory 11:53:32 INFO - Content-Length response header: 55366754 11:53:32 INFO - Bytes received: 55366754 11:53:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.talos.tests.zip 11:53:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:53:42 INFO - Fetch https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.talos.tests.zip into memory 11:53:44 INFO - Content-Length response header: 14522385 11:53:44 INFO - Bytes received: 14522385 11:53:45 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')]} 11:53:45 INFO - trying https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.tar.bz2 11:53:45 INFO - Downloading https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:53:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:53:47 INFO - Downloaded 61656394 bytes. 11:53:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.tar.bz2 11:53:48 INFO - mkdir: /builds/slave/test/properties 11:53:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:53:48 INFO - Writing to file /builds/slave/test/properties/build_url 11:53:48 INFO - Contents: 11:53:48 INFO - build_url:https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.tar.bz2 11:53:48 INFO - Running post-action listener: _resource_record_post_action 11:53:48 INFO - Running post-action listener: find_tests_for_verification 11:53:48 INFO - Running post-action listener: set_extra_try_arguments 11:53:48 INFO - [mozharness: 2017-10-01 18:53:48.027013Z] Finished download-and-extract step (success) 11:53:48 INFO - [mozharness: 2017-10-01 18:53:48.027112Z] Running populate-webroot step. 11:53:48 INFO - Running pre-action listener: _resource_record_pre_action 11:53:48 INFO - Running main action method: populate_webroot 11:53:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:53:48 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['tresize', 11:53:48 INFO - 'tcanvasmark']}, 11:53:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:53:48 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['dromaeo_css', 11:53:48 INFO - 'kraken']}, 11:53:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:53:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:53:48 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:53:48 INFO - 'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:53:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:53:48 INFO - 'tests': ['damp', 'tps']}, 11:53:48 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:53:48 INFO - 'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['damp', 'tps']}, 11:53:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:53:48 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['dromaeo_dom']}, 11:53:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:53:48 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['basic_compositor_video', 11:53:48 INFO - 'glvideo']}, 11:53:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:53:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:53:48 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:53:48 INFO - 'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['ts_paint_webext', 11:53:48 INFO - 'tp5o_webext']}, 11:53:48 INFO - 'other-e10s': {'tests': ['a11yr', 11:53:48 INFO - 'ts_paint', 11:53:48 INFO - 'tpaint', 11:53:48 INFO - 'sessionrestore', 11:53:48 INFO - 'sessionrestore_many_windows', 11:53:48 INFO - 'sessionrestore_no_auto_restore', 11:53:48 INFO - 'tabpaint', 11:53:48 INFO - 'cpstartup']}, 11:53:48 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['a11yr', 11:53:48 INFO - 'ts_paint', 11:53:48 INFO - 'tpaint', 11:53:48 INFO - 'sessionrestore', 11:53:48 INFO - 'sessionrestore_many_windows', 11:53:48 INFO - 'sessionrestore_no_auto_restore', 11:53:48 INFO - 'tabpaint', 11:53:48 INFO - 'cpstartup']}, 11:53:48 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 11:53:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 11:53:48 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['perf_reftest_singletons']}, 11:53:48 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['perf_reftest']}, 11:53:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:53:48 INFO - 'tsvgr_opacity', 11:53:48 INFO - 'tart', 11:53:48 INFO - 'tscrollx', 11:53:48 INFO - 'tsvg_static']}, 11:53:48 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['tsvgx', 11:53:48 INFO - 'tsvgr_opacity', 11:53:48 INFO - 'tart', 11:53:48 INFO - 'tscrollx', 11:53:48 INFO - 'tsvg_static']}, 11:53:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:53:48 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:53:48 INFO - 'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['tp5o']}, 11:53:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:53:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:53:48 INFO - 'talos_options': ['--mitmproxy', 11:53:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:53:48 INFO - '--firstNonBlankPaint'], 11:53:48 INFO - 'tests': ['tp6_google', 11:53:48 INFO - 'tp6_youtube', 11:53:48 INFO - 'tp6_amazon', 11:53:48 INFO - 'tp6_facebook']}, 11:53:48 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:53:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:53:48 INFO - 'talos_options': ['--disable-stylo', 11:53:48 INFO - '--mitmproxy', 11:53:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:53:48 INFO - '--firstNonBlankPaint'], 11:53:48 INFO - 'tests': ['tp6_google', 11:53:48 INFO - 'tp6_youtube', 11:53:48 INFO - 'tp6_amazon', 11:53:48 INFO - 'tp6_facebook']}, 11:53:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:53:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:53:48 INFO - 'talos_options': ['--stylo-threads=1', 11:53:48 INFO - '--mitmproxy', 11:53:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:53:48 INFO - '--firstNonBlankPaint'], 11:53:48 INFO - 'tests': ['tp6_google', 11:53:48 INFO - 'tp6_youtube', 11:53:48 INFO - 'tp6_amazon', 11:53:48 INFO - 'tp6_facebook']}, 11:53:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:53:48 INFO - 'talos_options': ['--xperf_path', 11:53:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:53:48 INFO - 'tests': ['tp5n']}, 11:53:48 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:53:48 INFO - 'talos_options': ['--disable-stylo', 11:53:48 INFO - '--xperf_path', 11:53:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:53:48 INFO - 'tests': ['tp5n']}}} 11:53:48 INFO - Running post-action listener: _resource_record_post_action 11:53:48 INFO - [mozharness: 2017-10-01 18:53:48.044151Z] Finished populate-webroot step (success) 11:53:48 INFO - [mozharness: 2017-10-01 18:53:48.044240Z] Running create-virtualenv step. 11:53:48 INFO - Running pre-action listener: _resource_record_pre_action 11:53:48 INFO - Running main action method: create_virtualenv 11:53:48 INFO - Creating virtualenv /builds/slave/test/build/venv 11:53:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:53:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:53:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:53:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:53:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:53:48 INFO - Installing distribute.............................................................................................................................................................................................done. 11:53:48 INFO - Installing pip...............done. 11:53:48 INFO - Return code: 0 11:53:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:53:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:53:49 INFO - Reading from file tmpfile_stdout 11:53:49 INFO - Output received: 11:53:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:53:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:53:49 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')]} 11:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:49 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')]} 11:53:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:49 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 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', '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 11:53:49 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 11:53:49 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 11:53:49 INFO - Using env: {'DISPLAY': ':0', 11:53:49 INFO - 'HOME': '/home/cltbld', 11:53:49 INFO - 'LANG': 'en_US.UTF-8', 11:53:49 INFO - 'LANGUAGE': 'en_US:en', 11:53:49 INFO - 'LOGNAME': 'cltbld', 11:53:49 INFO - 'MAIL': '/var/mail/cltbld', 11:53:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:49 INFO - 'MOZ_NO_REMOTE': '1', 11:53:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:49 INFO - 'NO_EM_RESTART': '1', 11:53:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:49 INFO - 'PWD': '/builds/slave/test', 11:53:49 INFO - 'SHELL': '/bin/bash', 11:53:49 INFO - 'SHLVL': '1', 11:53:49 INFO - 'TERM': 'linux', 11:53:49 INFO - 'TMOUT': '86400', 11:53:49 INFO - 'USER': 'cltbld', 11:53:49 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', 11:53:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:49 INFO - '_': '/tools/buildbot/bin/python'} 11:53:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:53:50 INFO - Downloading/unpacking pip>=1.5 11:53:50 INFO - Running setup.py egg_info for package pip 11:53:50 INFO - warning: no files found matching 'pip/cacert.pem' 11:53:50 INFO - warning: no files found matching '*.html' under directory 'docs' 11:53:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:53:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:53:50 INFO - Installing collected packages: pip 11:53:50 INFO - Found existing installation: pip 1.1 11:53:50 INFO - Uninstalling pip: 11:53:50 INFO - Successfully uninstalled pip 11:53:50 INFO - Running setup.py install for pip 11:53:50 INFO - warning: no files found matching 'pip/cacert.pem' 11:53:50 INFO - warning: no files found matching '*.html' under directory 'docs' 11:53:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:53:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:53:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:53:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:53:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:53:50 INFO - Successfully installed pip 11:53:50 INFO - Cleaning up... 11:53:50 INFO - Return code: 0 11:53:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:53:50 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')]} 11:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:50 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')]} 11:53:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:50 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 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', '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 11:53:50 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 11:53:50 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 11:53:50 INFO - Using env: {'DISPLAY': ':0', 11:53:50 INFO - 'HOME': '/home/cltbld', 11:53:50 INFO - 'LANG': 'en_US.UTF-8', 11:53:50 INFO - 'LANGUAGE': 'en_US:en', 11:53:50 INFO - 'LOGNAME': 'cltbld', 11:53:50 INFO - 'MAIL': '/var/mail/cltbld', 11:53:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:50 INFO - 'MOZ_NO_REMOTE': '1', 11:53:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:50 INFO - 'NO_EM_RESTART': '1', 11:53:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:50 INFO - 'PWD': '/builds/slave/test', 11:53:50 INFO - 'SHELL': '/bin/bash', 11:53:50 INFO - 'SHLVL': '1', 11:53:50 INFO - 'TERM': 'linux', 11:53:50 INFO - 'TMOUT': '86400', 11:53:50 INFO - 'USER': 'cltbld', 11:53:50 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', 11:53:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:50 INFO - '_': '/tools/buildbot/bin/python'} 11:53:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:50 INFO - Downloading/unpacking psutil>=3.1.1 11:53:50 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 11:53:50 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 11:53:50 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 11:53:50 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 11:53:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:53:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:53:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:53:51 INFO - Installing collected packages: psutil 11:53:51 INFO - Running setup.py install for psutil 11:53:51 INFO - building 'psutil._psutil_linux' extension 11:53:51 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 11:53:51 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 11:53:51 INFO - building 'psutil._psutil_posix' extension 11:53:51 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 11:53:52 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 11:53:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:53:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:53:52 INFO - Successfully installed psutil 11:53:52 INFO - Cleaning up... 11:53:52 INFO - Return code: 0 11:53:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:52 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 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', '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 11:53:52 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 11:53:52 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 11:53:52 INFO - Using env: {'DISPLAY': ':0', 11:53:52 INFO - 'HOME': '/home/cltbld', 11:53:52 INFO - 'LANG': 'en_US.UTF-8', 11:53:52 INFO - 'LANGUAGE': 'en_US:en', 11:53:52 INFO - 'LOGNAME': 'cltbld', 11:53:52 INFO - 'MAIL': '/var/mail/cltbld', 11:53:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:52 INFO - 'MOZ_NO_REMOTE': '1', 11:53:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:52 INFO - 'NO_EM_RESTART': '1', 11:53:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:52 INFO - 'PWD': '/builds/slave/test', 11:53:52 INFO - 'SHELL': '/bin/bash', 11:53:52 INFO - 'SHLVL': '1', 11:53:52 INFO - 'TERM': 'linux', 11:53:52 INFO - 'TMOUT': '86400', 11:53:52 INFO - 'USER': 'cltbld', 11:53:52 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', 11:53:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:52 INFO - '_': '/tools/buildbot/bin/python'} 11:53:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:53: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 11:53: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 11:53: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 11:53: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 11:53:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:53:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:53:53 INFO - Installing collected packages: mozsystemmonitor 11:53:53 INFO - Running setup.py install for mozsystemmonitor 11:53:53 INFO - Successfully installed mozsystemmonitor 11:53:53 INFO - Cleaning up... 11:53:53 INFO - Return code: 0 11:53:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:53: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')]} 11:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53: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')]} 11:53:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53: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', '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 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', '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 11:53: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:53: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 jsonschema==2.5.1 11:53:53 INFO - Using env: {'DISPLAY': ':0', 11:53:53 INFO - 'HOME': '/home/cltbld', 11:53:53 INFO - 'LANG': 'en_US.UTF-8', 11:53:53 INFO - 'LANGUAGE': 'en_US:en', 11:53:53 INFO - 'LOGNAME': 'cltbld', 11:53:53 INFO - 'MAIL': '/var/mail/cltbld', 11:53:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:53 INFO - 'MOZ_NO_REMOTE': '1', 11:53:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:53 INFO - 'NO_EM_RESTART': '1', 11:53:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:53 INFO - 'PWD': '/builds/slave/test', 11:53:53 INFO - 'SHELL': '/bin/bash', 11:53:53 INFO - 'SHLVL': '1', 11:53:53 INFO - 'TERM': 'linux', 11:53:53 INFO - 'TMOUT': '86400', 11:53:53 INFO - 'USER': 'cltbld', 11:53:53 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', 11:53:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:53 INFO - '_': '/tools/buildbot/bin/python'} 11:53:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:54 INFO - Downloading/unpacking jsonschema==2.5.1 11:53: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 11:53: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 11:53: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 11:53: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 11:53:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:53:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:53: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 11:53: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 11:53: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 11:53: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 11:53:55 INFO - Downloading functools32-3.2.3-2.tar.gz 11:53:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:53:55 INFO - warning: no files found matching '*.txt' 11:53:55 INFO - no previously-included directories found matching 'build' 11:53:55 INFO - no previously-included directories found matching 'dist' 11:53:55 INFO - no previously-included directories found matching '.git*' 11:53:55 INFO - Installing collected packages: jsonschema, functools32 11:53:55 INFO - Running setup.py install for functools32 11:53:55 INFO - warning: no files found matching '*.txt' 11:53:55 INFO - no previously-included directories found matching 'build' 11:53:55 INFO - no previously-included directories found matching 'dist' 11:53:55 INFO - no previously-included directories found matching '.git*' 11:53:55 INFO - Successfully installed jsonschema functools32 11:53:55 INFO - Cleaning up... 11:53:55 INFO - Return code: 0 11:53:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:53:55 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')]} 11:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:55 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')]} 11:53:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:55 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 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', '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 11:53:55 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 11:53:55 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 11:53:55 INFO - Using env: {'DISPLAY': ':0', 11:53:55 INFO - 'HOME': '/home/cltbld', 11:53:55 INFO - 'LANG': 'en_US.UTF-8', 11:53:55 INFO - 'LANGUAGE': 'en_US:en', 11:53:55 INFO - 'LOGNAME': 'cltbld', 11:53:55 INFO - 'MAIL': '/var/mail/cltbld', 11:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:55 INFO - 'MOZ_NO_REMOTE': '1', 11:53:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:55 INFO - 'NO_EM_RESTART': '1', 11:53:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:55 INFO - 'PWD': '/builds/slave/test', 11:53:55 INFO - 'SHELL': '/bin/bash', 11:53:55 INFO - 'SHLVL': '1', 11:53:55 INFO - 'TERM': 'linux', 11:53:55 INFO - 'TMOUT': '86400', 11:53:55 INFO - 'USER': 'cltbld', 11:53:55 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', 11:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:55 INFO - '_': '/tools/buildbot/bin/python'} 11:53:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:53:55 INFO - Cleaning up... 11:53:55 INFO - Return code: 0 11:53:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:53:55 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')]} 11:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:55 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')]} 11:53:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:55 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 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', '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 11:53:55 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 11:53:55 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 11:53:55 INFO - Using env: {'DISPLAY': ':0', 11:53:55 INFO - 'HOME': '/home/cltbld', 11:53:55 INFO - 'LANG': 'en_US.UTF-8', 11:53:55 INFO - 'LANGUAGE': 'en_US:en', 11:53:55 INFO - 'LOGNAME': 'cltbld', 11:53:55 INFO - 'MAIL': '/var/mail/cltbld', 11:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:55 INFO - 'MOZ_NO_REMOTE': '1', 11:53:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:55 INFO - 'NO_EM_RESTART': '1', 11:53:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:55 INFO - 'PWD': '/builds/slave/test', 11:53:55 INFO - 'SHELL': '/bin/bash', 11:53:55 INFO - 'SHLVL': '1', 11:53:55 INFO - 'TERM': 'linux', 11:53:55 INFO - 'TMOUT': '86400', 11:53:55 INFO - 'USER': 'cltbld', 11:53:55 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', 11:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:55 INFO - '_': '/tools/buildbot/bin/python'} 11:53:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:56 INFO - Downloading/unpacking blobuploader==1.2.4 11:53:56 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 11:53:56 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 11:53:56 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 11:53:56 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 11:53:57 INFO - Downloading blobuploader-1.2.4.tar.gz 11:53:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:53:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:53:57 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 11:53:57 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 11:53:57 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 11:53:57 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 11:53:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:53:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:53:57 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 11:53:57 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 11:53:57 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 11:53:57 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 11:53:57 INFO - Downloading docopt-0.6.1.tar.gz 11:53:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:53:57 INFO - Installing collected packages: blobuploader, requests, docopt 11:53:57 INFO - Running setup.py install for blobuploader 11:53:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:53:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:53:57 INFO - Running setup.py install for requests 11:53:58 INFO - Running setup.py install for docopt 11:53:58 INFO - Successfully installed blobuploader requests docopt 11:53:58 INFO - Cleaning up... 11:53:58 INFO - Return code: 0 11:53:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:58 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 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', '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 11:53:58 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 11:53:58 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 11:53:58 INFO - Using env: {'DISPLAY': ':0', 11:53:58 INFO - 'HOME': '/home/cltbld', 11:53:58 INFO - 'LANG': 'en_US.UTF-8', 11:53:58 INFO - 'LANGUAGE': 'en_US:en', 11:53:58 INFO - 'LOGNAME': 'cltbld', 11:53:58 INFO - 'MAIL': '/var/mail/cltbld', 11:53:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:58 INFO - 'MOZ_NO_REMOTE': '1', 11:53:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:58 INFO - 'NO_EM_RESTART': '1', 11:53:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:58 INFO - 'PWD': '/builds/slave/test', 11:53:58 INFO - 'SHELL': '/bin/bash', 11:53:58 INFO - 'SHLVL': '1', 11:53:58 INFO - 'TERM': 'linux', 11:53:58 INFO - 'TMOUT': '86400', 11:53:58 INFO - 'USER': 'cltbld', 11:53:58 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', 11:53:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:58 INFO - '_': '/tools/buildbot/bin/python'} 11:53:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:53:58 INFO - Running setup.py (path:/tmp/pip-hhcPBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:53:58 INFO - Running setup.py (path:/tmp/pip-4Kt8SZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:53:58 INFO - Running setup.py (path:/tmp/pip-VLq0fI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:53:58 INFO - Running setup.py (path:/tmp/pip-5Bwxjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:53:58 INFO - Running setup.py (path:/tmp/pip-zsUmL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:53:58 INFO - Running setup.py (path:/tmp/pip-LBGP7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:53:59 INFO - Running setup.py (path:/tmp/pip-iQmcqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:53:59 INFO - Running setup.py (path:/tmp/pip-WhI4D7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:53:59 INFO - Running setup.py (path:/tmp/pip-GRrMHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:53:59 INFO - Running setup.py (path:/tmp/pip-ZYdLK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:53:59 INFO - Running setup.py (path:/tmp/pip-i3wHc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:53:59 INFO - Running setup.py (path:/tmp/pip-MdbfsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:53:59 INFO - Running setup.py (path:/tmp/pip-HSOEWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:53:59 INFO - Running setup.py (path:/tmp/pip-0lva3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:53:59 INFO - Running setup.py (path:/tmp/pip-H072GY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:53:59 INFO - Running setup.py (path:/tmp/pip-miPEJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:53:59 INFO - Running setup.py (path:/tmp/pip-6PExss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:53:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:53:59 INFO - Running setup.py install for manifestparser 11:53:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:53:59 INFO - Running setup.py install for mozcrash 11:54:00 INFO - Running setup.py install for mozdebug 11:54:00 INFO - Running setup.py install for mozdevice 11:54:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:54:00 INFO - Running setup.py install for mozfile 11:54:00 INFO - Running setup.py install for mozhttpd 11:54:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:54:00 INFO - Running setup.py install for mozinfo 11:54:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:54:00 INFO - Running setup.py install for mozInstall 11:54:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:54:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:54:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:54:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:54:00 INFO - Running setup.py install for mozleak 11:54:00 INFO - Running setup.py install for mozlog 11:54:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:54:00 INFO - Running setup.py install for moznetwork 11:54:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:54:00 INFO - Running setup.py install for mozprocess 11:54:01 INFO - Running setup.py install for mozprofile 11:54:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:54:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:54:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:54:01 INFO - Running setup.py install for mozrunner 11:54:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:54:01 INFO - Running setup.py install for mozscreenshot 11:54:01 INFO - Running setup.py install for moztest 11:54:01 INFO - Running setup.py install for mozversion 11:54:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:54:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:54:01 INFO - Cleaning up... 11:54:01 INFO - Return code: 0 11:54:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:54:01 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')]} 11:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:01 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')]} 11:54:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:01 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 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', '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 11:54:01 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 11:54:01 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 11:54:01 INFO - Using env: {'DISPLAY': ':0', 11:54:01 INFO - 'HOME': '/home/cltbld', 11:54:01 INFO - 'LANG': 'en_US.UTF-8', 11:54:01 INFO - 'LANGUAGE': 'en_US:en', 11:54:01 INFO - 'LOGNAME': 'cltbld', 11:54:01 INFO - 'MAIL': '/var/mail/cltbld', 11:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:01 INFO - 'MOZ_NO_REMOTE': '1', 11:54:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:01 INFO - 'NO_EM_RESTART': '1', 11:54:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:01 INFO - 'PWD': '/builds/slave/test', 11:54:01 INFO - 'SHELL': '/bin/bash', 11:54:01 INFO - 'SHLVL': '1', 11:54:01 INFO - 'TERM': 'linux', 11:54:01 INFO - 'TMOUT': '86400', 11:54:01 INFO - 'USER': 'cltbld', 11:54:01 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', 11:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:01 INFO - '_': '/tools/buildbot/bin/python'} 11:54:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:54:01 INFO - Running setup.py (path:/tmp/pip-zYIXjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:54:01 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)) 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:54:01 INFO - Running setup.py (path:/tmp/pip-UiFvAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:54:01 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)) 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:54:01 INFO - Running setup.py (path:/tmp/pip-uHky68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:54:01 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)) 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:54:01 INFO - Running setup.py (path:/tmp/pip-QwOgJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:54:02 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)) 11:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:54:02 INFO - Running setup.py (path:/tmp/pip-sgvZWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:54:02 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)) 11:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:54:02 INFO - Running setup.py (path:/tmp/pip-OJYEm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:54:02 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)) 11:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:54:02 INFO - Running setup.py (path:/tmp/pip-dKsMGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:54:02 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)) 11:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:54:02 INFO - Running setup.py (path:/tmp/pip-_iqW8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:54:02 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)) 11:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:54:02 INFO - Running setup.py (path:/tmp/pip-a9QTB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:54:02 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)) 11:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:54:02 INFO - Running setup.py (path:/tmp/pip-4VmQzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:54:02 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)) 11:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:54:02 INFO - Running setup.py (path:/tmp/pip-bYytRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:54:02 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)) 11:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:54:02 INFO - Running setup.py (path:/tmp/pip-kaoEiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:54:02 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)) 11:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:54:02 INFO - Running setup.py (path:/tmp/pip-fwurj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:54:02 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)) 11:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:54:02 INFO - Running setup.py (path:/tmp/pip-VLxL_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:54:02 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)) 11:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:54:02 INFO - Running setup.py (path:/tmp/pip-T3yE3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:54:02 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)) 11:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:54:02 INFO - Running setup.py (path:/tmp/pip-dSDL86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:54:02 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)) 11:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:54:02 INFO - Running setup.py (path:/tmp/pip-cbBt32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:54:02 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)) 11:54:02 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)) 11:54:02 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)) 11:54:02 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)) 11:54:02 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)) 11:54:02 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)) 11:54:02 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)) 11:54:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:54: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 11:54: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 11:54: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 11:54: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 11:54:04 INFO - Downloading blessings-1.6.tar.gz 11:54:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:54:04 INFO - Installing collected packages: blessings 11:54:04 INFO - Running setup.py install for blessings 11:54:04 INFO - Successfully installed blessings 11:54:04 INFO - Cleaning up... 11:54:04 INFO - Return code: 0 11:54:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:54:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:54:04 INFO - Reading from file tmpfile_stdout 11:54:04 INFO - Current package versions: 11:54:04 INFO - argparse == 1.2.1 11:54:04 INFO - blessings == 1.6 11:54:04 INFO - blobuploader == 1.2.4 11:54:04 INFO - docopt == 0.6.1 11:54:04 INFO - functools32 == 3.2.3-2 11:54:04 INFO - jsonschema == 2.5.1 11:54:04 INFO - manifestparser == 1.1 11:54:04 INFO - mozInstall == 1.13 11:54:04 INFO - mozcrash == 1.0 11:54:04 INFO - mozdebug == 0.1 11:54:04 INFO - mozdevice == 0.51 11:54:04 INFO - mozfile == 1.2 11:54:04 INFO - mozhttpd == 0.7 11:54:04 INFO - mozinfo == 0.10 11:54:04 INFO - mozleak == 0.1 11:54:04 INFO - mozlog == 3.5 11:54:04 INFO - moznetwork == 0.27 11:54:04 INFO - mozprocess == 0.25 11:54:04 INFO - mozprofile == 0.28 11:54:04 INFO - mozrunner == 6.13 11:54:04 INFO - mozscreenshot == 0.1 11:54:04 INFO - mozsystemmonitor == 0.3 11:54:04 INFO - moztest == 0.8 11:54:04 INFO - mozversion == 1.4 11:54:04 INFO - psutil == 3.1.1 11:54:04 INFO - requests == 1.2.3 11:54:04 INFO - wsgiref == 0.1.2 11:54:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:54:04 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')]} 11:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:04 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')]} 11:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:04 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 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', '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 11:54:04 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 11:54:04 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 11:54:04 INFO - Using env: {'DISPLAY': ':0', 11:54:04 INFO - 'HOME': '/home/cltbld', 11:54:04 INFO - 'LANG': 'en_US.UTF-8', 11:54:04 INFO - 'LANGUAGE': 'en_US:en', 11:54:04 INFO - 'LOGNAME': 'cltbld', 11:54:04 INFO - 'MAIL': '/var/mail/cltbld', 11:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:04 INFO - 'MOZ_NO_REMOTE': '1', 11:54:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:04 INFO - 'NO_EM_RESTART': '1', 11:54:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:04 INFO - 'PWD': '/builds/slave/test', 11:54:04 INFO - 'SHELL': '/bin/bash', 11:54:04 INFO - 'SHLVL': '1', 11:54:04 INFO - 'TERM': 'linux', 11:54:04 INFO - 'TMOUT': '86400', 11:54:04 INFO - 'USER': 'cltbld', 11:54:04 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', 11:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:04 INFO - '_': '/tools/buildbot/bin/python'} 11:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:04 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)) 11:54:04 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)) 11:54:04 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)) 11:54:04 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)) 11:54:04 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)) 11:54:04 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)) 11:54:04 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)) 11:54:04 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)) 11:54:04 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)) 11:54:04 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:54:05 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)) 11:54:05 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)) 11:54:05 INFO - Installing collected packages: simplejson 11:54:05 INFO - Running setup.py install for simplejson 11:54:06 INFO - building 'simplejson._speedups' extension 11:54:06 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 11:54:06 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 11:54:06 INFO - Successfully installed simplejson 11:54:06 INFO - Cleaning up... 11:54:07 INFO - Return code: 0 11:54:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:54:07 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')]} 11:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:07 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')]} 11:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', '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 11:54:07 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 11:54:07 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 11:54:07 INFO - Using env: {'DISPLAY': ':0', 11:54:07 INFO - 'HOME': '/home/cltbld', 11:54:07 INFO - 'LANG': 'en_US.UTF-8', 11:54:07 INFO - 'LANGUAGE': 'en_US:en', 11:54:07 INFO - 'LOGNAME': 'cltbld', 11:54:07 INFO - 'MAIL': '/var/mail/cltbld', 11:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:07 INFO - 'MOZ_NO_REMOTE': '1', 11:54:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:07 INFO - 'NO_EM_RESTART': '1', 11:54:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:07 INFO - 'PWD': '/builds/slave/test', 11:54:07 INFO - 'SHELL': '/bin/bash', 11:54:07 INFO - 'SHLVL': '1', 11:54:07 INFO - 'TERM': 'linux', 11:54:07 INFO - 'TMOUT': '86400', 11:54:07 INFO - 'USER': 'cltbld', 11:54:07 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', 11:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:07 INFO - '_': '/tools/buildbot/bin/python'} 11:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:54:07 INFO - Cleaning up... 11:54:07 INFO - Return code: 0 11:54:07 INFO - Running post-action listener: _resource_record_post_action 11:54:07 INFO - Running post-action listener: _start_resource_monitoring 11:54:07 INFO - Starting resource monitoring. 11:54:07 INFO - [mozharness: 2017-10-01 18:54:07.262964Z] Finished create-virtualenv step (success) 11:54:07 INFO - [mozharness: 2017-10-01 18:54:07.263488Z] Running install step. 11:54:07 INFO - Running pre-action listener: _resource_record_pre_action 11:54:07 INFO - Running main action method: install 11:54:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:54:07 INFO - Reading from file tmpfile_stdout 11:54:07 INFO - Detecting whether we're running mozinstall >=1.0... 11:54:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:54:07 INFO - Reading from file tmpfile_stdout 11:54:07 INFO - Output received: 11:54:07 INFO - Usage: mozinstall [options] installer 11:54:07 INFO - Options: 11:54:07 INFO - -h, --help show this help message and exit 11:54:07 INFO - -d DEST, --destination=DEST 11:54:07 INFO - Directory to install application into. [default: 11:54:07 INFO - "/builds/slave/test"] 11:54:07 INFO - --app=APP Application being installed. [default: firefox] 11:54:07 INFO - mkdir: /builds/slave/test/build/application 11:54:07 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'] 11:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:54:25 INFO - Reading from file tmpfile_stdout 11:54:25 INFO - Output received: 11:54:25 INFO - /builds/slave/test/build/application/firefox/firefox 11:54:25 INFO - Running post-action listener: _resource_record_post_action 11:54:25 INFO - [mozharness: 2017-10-01 18:54:25.432283Z] Finished install step (success) 11:54:25 INFO - [mozharness: 2017-10-01 18:54:25.432536Z] Running setup-mitmproxy step. 11:54:25 INFO - Running pre-action listener: _resource_record_pre_action 11:54:25 INFO - Running main action method: setup_mitmproxy 11:54:25 INFO - Skipping: mitmproxy is not required 11:54:25 INFO - Running post-action listener: _resource_record_post_action 11:54:25 INFO - [mozharness: 2017-10-01 18:54:25.433659Z] Finished setup-mitmproxy step (success) 11:54:25 INFO - [mozharness: 2017-10-01 18:54:25.433894Z] Running run-tests step. 11:54:25 INFO - Running pre-action listener: _resource_record_pre_action 11:54:25 INFO - Running pre-action listener: _set_gcov_prefix 11:54:25 INFO - Running main action method: run_tests 11:54:25 WARNING - Try message not found. 11:54:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:54:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:54:25 INFO - Python 2.7.3 11:54:25 INFO - Return code: 0 11:54:25 INFO - grabbing minidump binary from tooltool 11:54:25 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')]} 11:54:25 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 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:54:25 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 11:54:25 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 11:54:25 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 11:54:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:54:25 INFO - Return code: 0 11:54:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:54:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:54:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:54:25 INFO - ENV: RUST_BACKTRACE is now full 11:54:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:54:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:54:25 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/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 11:54:25 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/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-027 --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 11:54:25 INFO - Using env: {'DISPLAY': ':0', 11:54:25 INFO - 'HOME': '/home/cltbld', 11:54:25 INFO - 'LANG': 'en_US.UTF-8', 11:54:25 INFO - 'LANGUAGE': 'en_US:en', 11:54:25 INFO - 'LOGNAME': 'cltbld', 11:54:25 INFO - 'MAIL': '/var/mail/cltbld', 11:54:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:54:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:54:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:25 INFO - 'MOZ_NO_REMOTE': '1', 11:54:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:54:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:25 INFO - 'NO_EM_RESTART': '1', 11:54:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:25 INFO - 'PWD': '/builds/slave/test', 11:54:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:54:25 INFO - 'RUST_BACKTRACE': 'full', 11:54:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:54:25 INFO - 'SHELL': '/bin/bash', 11:54:25 INFO - 'SHLVL': '1', 11:54:25 INFO - 'TERM': 'linux', 11:54:25 INFO - 'TMOUT': '86400', 11:54:25 INFO - 'USER': 'cltbld', 11:54:25 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', 11:54:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:25 INFO - '_': '/tools/buildbot/bin/python'} 11:54:25 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/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 11:54:25 INFO - mozversion application_buildid: 20171001175841 11:54:25 INFO - mozversion application_changeset: 3cfe8f48847512909238760bcf8410115cb55ad6 11:54:25 INFO - mozversion application_display_name: FirefoxNightly 11:54:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:54:25 INFO - mozversion application_name: Firefox 11:54:25 INFO - mozversion application_remotingname: firefox 11:54:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:54:25 INFO - mozversion application_vendor: Mozilla 11:54:25 INFO - mozversion application_version: 58.0a1 11:54:25 INFO - mozversion platform_buildid: 20171001175841 11:54:25 INFO - mozversion platform_changeset: 3cfe8f48847512909238760bcf8410115cb55ad6 11:54:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:54:25 INFO - mozversion platform_version: 58.0a1 11:54:25 INFO - using testdate: 1506884065 11:54:25 INFO - actual date: 1506884065 11:54:25 INFO - starting webserver on 'localhost:44883' 11:54:25 INFO - SUITE-START | Running 2 tests 11:54:25 INFO - TEST-START | dromaeo_css 11:54:25 INFO - Initialising browser for dromaeo_css test... 11:54:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44883/getInfo.html -profile /tmp/tmpAYeskR/profile 11:54:25 INFO - TEST-INFO | started process 14249 (/builds/slave/test/build/application/firefox/firefox http://localhost:44883/getInfo.html) 11:54:30 INFO - TEST-INFO | 14249: exit 0 11:54:30 INFO - Browser initialized. 11:54:30 INFO - Running cycle 1/1 for dromaeo_css test... 11:54:30 INFO - Using env: {'DISPLAY': ':0', 11:54:30 INFO - 'HOME': '/home/cltbld', 11:54:30 INFO - 'JSGC_DISABLE_POISONING': '1', 11:54:30 INFO - 'LANG': 'en_US.UTF-8', 11:54:30 INFO - 'LANGUAGE': 'en_US:en', 11:54:30 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:54:30 INFO - 'LOGNAME': 'cltbld', 11:54:30 INFO - 'MAIL': '/var/mail/cltbld', 11:54:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:54:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:54:30 INFO - 'MOZ_CRASHREPORTER': '1', 11:54:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:30 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:54:30 INFO - 'MOZ_NO_REMOTE': '1', 11:54:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:54:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:30 INFO - 'NO_EM_RESTART': '1', 11:54:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:30 INFO - 'PWD': '/builds/slave/test', 11:54:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:54:30 INFO - 'RUST_BACKTRACE': 'full', 11:54:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:54:30 INFO - 'SHELL': '/bin/bash', 11:54:30 INFO - 'SHLVL': '1', 11:54:30 INFO - 'TERM': 'linux', 11:54:30 INFO - 'TMOUT': '86400', 11:54:30 INFO - 'USER': 'cltbld', 11:54:30 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', 11:54:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:30 INFO - '_': '/tools/buildbot/bin/python'} 11:54:30 INFO - TEST-INFO | started process 14482 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAYeskR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:54:31 INFO - PID 14482 | 11:54:31 INFO - PID 14482 | (/builds/slave/test/build/application/firefox/firefox:14531): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:54:31 INFO - PID 14482 | 11:54:41 INFO - PID 14482 | RSS: Main: 153546752 11:54:41 INFO - PID 14482 | 11:57:39 INFO - PID 14482 | Cycle 1(1): loaded http://localhost:44883/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44883/tests/dromaeo/cssquery-ext.html) 11:57:40 INFO - PID 14482 | RSS: Main: 150896640 11:57:40 INFO - PID 14482 | 12:00:42 INFO - PID 14482 | Cycle 1(1): loaded http://localhost:44883/tests/dromaeo/cssquery-ext.html (next: http://localhost:44883/tests/dromaeo/cssquery-jquery.html) 12:00:43 INFO - PID 14482 | RSS: Main: 150188032 12:00:43 INFO - PID 14482 | 12:03:44 INFO - PID 14482 | Cycle 1(1): loaded http://localhost:44883/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44883/tests/dromaeo/cssquery-mootools.html) 12:03:45 INFO - PID 14482 | RSS: Main: 152756224 12:03:45 INFO - PID 14482 | 12:06:47 INFO - PID 14482 | Cycle 1(1): loaded http://localhost:44883/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44883/tests/dromaeo/cssquery-prototype.html) 12:06:47 INFO - PID 14482 | RSS: Main: 151412736 12:06:47 INFO - PID 14482 | 12:09:50 INFO - PID 14482 | Cycle 1(1): loaded http://localhost:44883/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44883/tests/dromaeo/cssquery-yui.html) 12:09:51 INFO - PID 14482 | RSS: Main: 155316224 12:09:51 INFO - PID 14482 | 12:12:53 INFO - PID 14482 | Cycle 1(1): loaded http://localhost:44883/tests/dromaeo/cssquery-yui.html (next: http://localhost:44883/tests/dromaeo/cssquery-dojo.html) 12:12:53 INFO - PID 14482 | RSS: Main: 155398144 12:12:53 INFO - PID 14482 | 12:12:53 INFO - PID 14482 | __start_tp_report 12:12:53 INFO - PID 14482 | _x_x_mozilla_page_load 12:12:53 INFO - PID 14482 | _x_x_mozilla_page_load_details 12:12:53 INFO - PID 14482 | |i|pagename|runs| 12:12:53 INFO - PID 14482 | |0;dojo.html;3179.904602861914;3196.3607278544296;3197.792143510672;3199.9840000799977;3202.407554602398;6134.0798880167995;6213.968930155354;6228.906566401508;6261.592996455227;6338.556301058922;6171.753129874811;6208.1618981437605;6220.377962203777;6240.500759939206;6261.874762504747;5696.572757043217;5781.306243250816;5792.623479473821;5813.331466881321;5813.44772246638;5611.971940140294;5819.185314056035;5854.531637469014;5916;5920.585559010871;5286.788528458857;5670.206171136044;5762.4525670061275;5783.826485205472;5840.532757379399;5766.913496297517;5780.0751879699465;5849.678267695275;5856.619319744245;5874.9412505874825;5545.750441230114;5637.126245431965;5638.182463542763;5653.491185793298;5807.5644326675665;6161.075838624198;6171.814845554641;6201.751929922798;6209.59637623553;6211.223597050369;6547.836304092388;6628.2046154461295;6639.601623902581;6641.070250164981;6648.401643852028;6696.5647232929705;6730.865382692367;6750.696218670171;6758.628275444848;6773.356531129584;5987.850303742398;6150.600210986317;6224.439800417984;6226.283977342573;6348.396902294228;6900.861982760317;6998.230194678581;7024.719011239596;7027.754028609076;7028.683709233097;6439.388258115471;6533.18335208099;6609.1077704510235;6669.366410191024;6674.032265321501;6590;6647;6657.367550082734;6659.467242620675;6661.566998145105;6716.932830671729;6741.359570840762;6746.797596072126;6748.122744043243;6751.527393082535;6689.732410703615;6701.463882889455;6772;6780.932190678129;6793.490488213404;5216.817411390583;5243.685378877203;5265.578753699695;5332.520073193431;5339.492748188916;4476.910461790746;4553.704009239389;4571.131485017902;4576.954230457653;4607.884802879955;5809.82570522885;5811.3026436827;5813.215215945877;5852.970735146382;5854.092615644582;6709.161354830646;6747.696353663999;6822.556533825285;6830.419414349836;6838.863222735517;5769.85575360611;5782.248307720053;5852.502537284378;5857.531397488191;5872.823815285548;5606.21512988182;5711.8000869969355;5728.083506639001;5729.656220626775;5833.38749431312;6503.512236582275;6546.967265163739;6566.310537393505;6567.047778072153;6608.2730899601;6661.4337781287195;6761.628110453776;6854.074699915447;6855.965720171367;6867.278935711678;6450.129232553545;6518.869622607616;6520.934790652223;6593.934060659524;6627.1716035495565;6744.7976560703255;6756.054152418764;6761.0534525167495;6766.8646627068165;6786.796396108125;6593.637349945607;6711.295313992156;6717.865642687217;6727.058211850247;6818.863622727418;6340.080688300079;6539.836504087436;6558.934410656023;6561.475081993333;6562.77030303937;6444.548881578245;6454.806355809334;6530.6081635100145;6552.803415897531;6553.279139294578;6383.521235907233;6389.9680501597195;6449.6130232186215;6467;6472.644004579794;6541.443977261986;6557.344265573404;6597.3072827352435;6601.702923368556;6620.933790662224;6313.936860631335;6343.9682801585695;6385.393387628168;6395.616263024233;6445.484361250995 12:12:53 INFO - PID 14482 | |1;ext.html;842.3008902610834;862;865.5369377383097;868.2402897464718;875.5490922175085;42326.57673423269;42938;43068.99999999996;43156.568434315726;43232.56767432325;39560;39731.20537589247;39909.401358979565;39944;40245.597544024626;7260.818479538001;7393.3345998860095;7400.074990626172;7402.888956665654;7403.074615673041;7214.278572142796;7226.747063852767;7232.8915066273785;7251.057362542862;7282.854342913139;7236.638168091601;7247.818804529877;7264.673089710976;7269.854602907939;7884.60576971152;7975.40184486163;8050.557219352962;8060.193980601952;8063.475874068166;8064.677412903477;7900.604969751547;7959.482633628795;7964.084130324971;7997.800054998614;7999.480033797785;38534;39525.60474395248;39608.405873911914;39740.20519589621;40112.39831402502;3493.1441796759705;3554.680078792921;3564.3940530109685;3565.0552603560077;3576.3204991051616;33217.33565328704;33474.6652533474;33548.161295967555;33589.49615755765;33733.83133084319;5145.434002259748;5173.3016042834115;5182;5184.222366644995;5217.399999000126;185325;186825;187352;187537;188077.99999999863;42732.359014614805;43626.127477450274;47343.28985065158;47919.76040119777;47946.5205347942;50716.49283507118;50749.49250507448;50806;50820.491795082315;50830.49169508258;16339.836601633831;16501.422450214184;16504.4223452181;16506.917465412836;16524.25640846165;13457.730845383037;13512.93243533776;13517.797233041512;13545;13590.59228223155;13778;13822.723545529034;13867.86132138686;13881.514147004806;14007.159570425807;5593.468620481048;5679.290088738908;5694.5159661428415;5698.6580805151825;5738.254026976467;1006.4162785584343;1006.7885743993697;1013.9898601014042;1020.1379834040224;1031.6028329093353;23475.178368757013;23487.647685284734;23548.293551193492;23607.645885311733;23792;1107.3189988157387;1132.4054871192525;1133.5749094089717;1134.1890548258036;1143.54258296682;12415.565455209024;12515.749685006249;12587.37063146839;12668.873311266954;12766;230863;232350;233321;233433;234280;55145.44854551483;55225.72387138039;55391.44608553863;55402;55443.16835247474;1436.3823555870867;1441.5170917742673;1467.0537503310518;1468.728285267229;1474.8009018782327;23112;23298.76701232966;23360.883195583912;23646.763532364457;23750.88124559435;37134.44298335581;37571.24857502889;37635.435468468546;37701.05747356338;37753.62246377611;190673;191188;191722;191792;193011;30678.386432271676;31506.842465787526;31513.21216969594;34109.82945085358;34339.82830085834;43634.34548481712;43853.78073109742;44056.339154912064;44342.55657443513;44627.55372446234;31911.68088319087;32183;32511.674883250864;32909.17727056843;34002.829985849916;32552.34895302128;32757;32788.180295492806;33054.50418243776;33844;36582.8170859144;37017;37178.07054823651;37182.44226336661;37261.627383726904;32181.195470113435;32246.19384515312;32628.184295392806;32662;33401.49897751487;22765.772342276367;23494;23665.52668946646;24093.277201683977;24178.758212418354 12:12:53 INFO - PID 14482 | |2;jquery.html;569.9800506982257;587.8631175676418;591.4854076953052;593.3147214966713;622.5890911998081;22451;23341.533169336624;23355.532889342205;23382.181623643155;23385;23759.168429104942;24967.250982470556;24979.625305620386;25158.867850946706;25166.496670066586;6606.273309935915;6669.9666501667425;6695.531312808105;6701.631410272433;6706.798796036127;6493.805185844432;6657.300983396722;6664.666766661671;6665.100211471462;6668.76659316924;6631.336866313378;6632.237292711352;6634.237062737775;6643.833904152387;6645.33546645334;6249.7187626556915;6570.967145164267;6590.835229119262;6590.93409065908;6591.604503729744;6061.363556826558;6072.544559158081;6082.361352058015;6101.023836186233;6123.571350005502;27595.586066209024;27860.16419507418;27869.721302786915;27932.16203513898;27950.16149515518;2037.6739721644465;2071.399294204679;2072.3161356752234;2077.885716285604;2086.0716980943484;17822.55443613907;19446.70829937552;19559.21763129473;19611.411657650293;19629;2346.23747282134;2390.6055500842513;2404.386881345246;2427.526632306683;2506.4736405354897;127354.36322818419;132950.33524832412;133159.33420332934;133725.33137334252;140158.29920850432;14048.719025619635;14629.414823406945;14768.628156319573;14959.027663201858;15330.233488325539;23005.07979680794;23083.53832923332;23302.533949320918;23390.181343653232;23396.88301558481;9983.850242246372;10046.849297260547;10123.1901447884;10152.898471015344;10281.280310378352;9387.5306234688;9412.199963003084;9430.151286384122;9474.810503790024;9489.952550237344;8749.650013999497;8879.24526415249;8985.865212021694;9068.410553314014;9094;2612.7517885800817;2805.4809860175933;2847.515922293215;2862.4990626640256;2865.226388874992;993.9353941993747;997.1054995508591;1015.9644412445676;1023.1456733627356;1030.4487099401783;19732.60534789296;19922.60154796896;19943.800561994485;20061.598768024556;20067.59864802725;1047.8533005379254;1067.6209945469247;1069.8769641491328;1076.4402510694395;1086.190787863047;10887.83668244977;10905.618303359344;10982.395968221665;11060.281081729823;11070.667879963614;470244;471295;472828;484041;491854;112251;114640.42679786548;114688;114909;115054;1053.373242920461;1112.527175950218;1128.0355296221612;1177.60550215677;1220.969475763113;20344.287949921683;20458.79541204569;20525.58948821045;20543.794562054787;20666.68999965032;57101.428985711274;57809.4219057804;58058.129128063956;58932;59002;69277;69440.30559694338;69754.30245697478;70246.29753702397;70611;14349.282535872955;14552.126872387691;14782.704345913236;14784.260786960394;14867.330970106155;22988.885055574614;23227.767722323235;23285.88357058204;23367.532649347257;23459;18280;18348.90825545917;18541.536461588566;18631.906840465712;18822.811771882105;15150.393984240947;15902.840971590136;16699.415520456725;16897.155142243086;17363.652726945642;18937.621247575247;18961.525961851064;18978.43064708061;19014;19222.903885480482;14497.420103196177;14568.781468277772;14591;14685.706285874436;14791.112533248039;18170.90914545419;18259.634807304046;18310.35913743066;18395.356162534248;18444.723329149805 12:12:53 INFO - PID 14482 | |3;mootools.html;1313.3302015971854;1413.1521087347587;1424.736423761604;1427.307755738466;1435.2249785116041;15019.549413517598;15639.296231669574;15648.295826687798;15676.059436433821;15701.371945122182;15394.692106157898;15396.84603153968;15397.538073857802;15457.072575645441;15491.845081549181;2780.569011803174;2783.03985125132;2859.2280084377207;2865.527188013986;2869.4404591104744;2747.766439852615;2774.4312415954782;2786.3591373983995;2794.18968499136;2795.8602069896424;2175.945601359963;2268.25147701259;2717.6874659414225;2815.4650616382824;2818.5349417346115;2706.688730795944;2732.8496932668695;2747.6702795664546;2750.147454289177;2763.2124844419445;2719.5648696208514;2721.306066952934;2733.330334068146;2751.17464760571;2753.187809596174;14113.717725645532;14332.355044023045;14388.712225755426;14395.42418303277;14446.205458699767;7901.328387087105;8117.391195660349;8128.268455839003;8167.754967350989;8176.223258790405;5296.841094767163;5393.460653934614;5485.232067510552;5598.776048958037;5616.129499927477;1941.1652989214774;1954.853385996056;1956.1392987085637;1998.3605246321183;2012.2856385982911;25545.616815747777;25635.871820640965;25644.230673079837;25674.358141046625;25681.871590642302;7065.258147894499;7283.616112938525;7330.8900366494545;7411.184769675331;7426.9257307427315;19145.904270478557;19229.230830766894;19231.423057308304;19254.22983080661;19278.71081933772;7905.920940790634;8160.469569477965;8188.754337369888;8194.836103277901;8196.877046844302;7126.893096603555;7188.317109874553;7212.639368031578;7281.7087316507805;7287.089113860768;4989.875253118628;5169.767360468788;5182.455842136596;5202.92195617066;5210.713410762369;1420.2969530082676;1426.6718654709475;1491.6494623763406;1518.7038527487255;1523.9390424383123;917.6512925088424;952.0812416018575;961.0725649748026;964.064857088623;979.6620166042846;8295.04606970194;8421.284190843848;8440.324774018063;8484.066752657203;8567.957160214159;707.6143501791536;723.5477826358526;752.4845480845529;761.744815486807;836.5357226739218;5304.655197412156;5318.308619879391;5322.547583455373;5388.299521062237;5394.757235924393;19701.11344989479;20008.49978750543;20833.1666733332;20882.37352879416;21084.578308433745;20414.69377959332;20462.079206435745;20555;20569.69145462819;20682.79317206839;10202.744931376626;11055.889441105646;11245.493952772145;11260.774784504429;11260.831087533694;7884.684612615661;8410.663573456994;8519.531425771642;8533.31733461334;8543.273821725223;17609.735853961945;17647.470575882744;17694.734578981584;17721.468355949342;17856.46430607084;18116.547086322946;18298.817011830244;18310.26758929628;18331.266749329876;18411;10285.691429257135;10475.109615682448;10504.002119798606;10512.526936287732;10512.632057877938;13276.867231327564;13345.866541334462;13423.798643020558;13586.592402227949;13636.727265454438;11237.4381280934;11319.773604528027;11664.94167529157;11678.065754739431;11683;10906.618268360886;10929;11018.449077546251;11231.55073797039;11237.3257604544;9681.177099946302;9697.6605818796;9713.611455541699;9750.902490974999;9767.853482197916;7296.525725827804;7313.195548489555;7366.4106871449085;7383.778486645409;7388.889166662612;7349.8162545936775;7436.293552112541;7520.548767073993;7533.510321829063;7554.395648348009 12:12:53 INFO - PID 14482 | |4;prototype.html;511.84132918795154;517.9715115668638;530.7876651284387;539.749016707231;539.9676019438831;6286.277078136016;6368.3631636836235;6377.872442551146;6392.296847346788;6393;6189.4738947189435;6250.40621140992;6271.905921411182;6280.842978925529;6289.402506761861;5228.372595288552;5233.58131349493;5254.500822421864;5272.92090618641;5287.67808047988;4862.7325497097645;4962.89077142215;5165.354330708661;5166.96660667866;5268.473152684739;4949.1935443563025;4991.550760431561;4997.175466048097;5002.949970500303;5030.924536131961;4947.703137811707;4965.851024469272;4985.476524964899;4998.40019197695;5016.322796422491;4422.248217802949;4483.708558943658;4494.1461122386645;4519.389882365871;4526.094781043805;6033.306169790487;6039.8792024159275;6086.782643471279;6104.755809767604;6134.9386506134815;5637.323521177444;5693.316801983747;5739.110437882135;5744.788256521228;5763.971180144114;6462.515311351667;6501.609903405811;6503.9349606504275;6537.378948999837;6591.736330546821;4743.35964644772;4881.902361952741;4922.581580565657;4936.654434189608;4947.763059235192;6691.19705635322;6728.764493242762;6758.459323254128;6782.355676210752;6948.478864085113;4960.429550601655;5023.472535383732;5059.468755780664;5064.518870707277;5082.907174957402;5060.1903695408555;5088.872778180501;5091.847244582668;5162.225666150108;5181.4300426953005;5660.594247444819;5707.1153971134545;5710.8001219957105;5768.019436695772;5783.479486846204;6556.704948277339;6565.671716414256;6609.347663084229;6611.272759996396;6637.269900310962;1775.2632657447273;1775.698131317679;1788.678037953155;1789.91945362459;1799.0734771592379;1189.6193218170276;1207.2394391533453;1224.2287358963795;1239.1326071749631;1251.974960500803;5589.189567512688;5631.436856314336;5658.453695836807;5674.375818659944;5685.118806584986;6511.772087976898;6551.312112228243;6707.899381509281;6748.122744043243;6809.114815074107;5635.21107045014;5670.716464176775;5682.602217844794;5696.829095127153;5719.3136823581435;3493.475978603179;3547.9310396506835;3549.1836877518317;3564.4296912494397;3583.8387272572795;6498.187726534184;6524.543281970216;6527.15147030883;6539.182602174728;6543.149390579194;6398.552101352953;6681.732730690718;6772.627505487246;6785.253622101565;6874.243833178346;6332.936670633327;6341.175647165839;6366.172397588284;6367.872642547123;6433.710483028181;6661.267260601233;6673.833154171185;6688.4983626229205;6720.630365329758;6764.188297404146;6542.411182993554;6606.867862642816;6616.239132499824;6618.2719900811835;6650.301718319507;6426.550141490051;6432.710528026157;6461.773837915651;6499.5775274607;6522.576032558059;5021.824236151717;5077.492250774893;5111.258867464271;5118.488151184852;5121.308623335951;6375.968120159369;6551.246606640298;6615;6625.536212465081;6643.269240383651;6273.341299163522;6471.676416179078;6484.481241500763;6488.480921526361;6516.6415847128865;6497.310699218143;6508.511861610493;6539.11721917554;6552;6582.703778330082;6365.586236894587;6430.260520040255;6432.614043157426;6481.5786973848435;6491.188601424822;6499.6425196612745;6535.183102112236;6539.542232043711;6621.801345959629;6624.701888414937;6304.464120549805;6362.840928976812;6367.192769459029;6439.195100612424;6462.610538734007 12:12:53 INFO - PID 14482 | |5;yui.html;890.0876601483478;892.4823602310661;908.2688435809171;910.1762904571364;911.8814554107969;18470;19536;20015.399538013848;20191.39425817226;20316.288929887476;16942.915285423554;17015.57461063474;17096.316147354122;17160.570985725364;17189.82810171898;940.2571968145174;965.6427121964883;966.5312323523086;972.1202311907699;972.45542496202;975.9219262459022;977.4672803322206;980.1129977370491;981.5582987655548;983.0808194338316;969.1132613658494;1008.485672307125;1019.5666841592331;1019.638028499883;1020.2093377632357;1002.1331548210782;1006.6527048168371;1007.0734923870057;1008.0171832463363;1008.2236677758085;710.5878590417545;735.0187499687923;737.1927739125649;741.6032422653855;748.6182047155935;20529.973501324876;20659.69010464844;20754;20801.16795328185;20802.47993800152;20005.29981450657;20146.798532014785;20725.689114663142;20830.791692083036;20838.895805521028;16999.83000169995;17171.313147474088;17299;17562.560935976577;17631.5592110197;3747.3254814133447;3747.8126093695205;3772.4907137536356;3777.452269420946;3777.9433308500393;106669;142791;143257.56742432548;144197;147022.26488867594;17055;17209.397671081453;17617.647647047244;17659.911700441673;17663.73504397435;32461.837690811393;33380.499292510634;33386;33573.83213083919;33574;9155.313351498531;9394.530273486298;9493.952530237304;9503.42979421223;9517.524123793783;4553.863384098482;4621.607163391082;4638.095571363552;4638.373819534387;4644.465886423037;5558.388577256498;5559.916601250903;5638.069718496484;5707.71461426927;5716.942365662366;1096.13405409727;1104.9944750276197;1117.9379589390112;1119.6249256499002;1125.2404626876826;687.0964681443985;693.9375456208966;703.1562125449481;703.6938931564683;707.1584814071239;12315.4458049388;12403.503859845685;12424.503019879285;12430.192037517532;12653.36733163338;696.6029363262892;700.8212905709012;711.6406214861462;711.7259854955776;714.9142102947679;5451.182322651554;5497.752601132878;5537.141743029837;5550.47270509301;5559.777608895599;125005;127595;128320;128525.35737321254;130736.3463182678;110833;111301;111464;114172.42913785377;115609;94418;96946;97482.51258743803;100062.49968750255;100429;10427.165826733995;10529.315594486334;10558.366498010144;10569.841452378374;10681.145508359157;44105.57419341967;45037.32444013408;45682.086358273315;46188;46546;54176.45823541714;55568.72215638896;55856;56734.432655672914;57291.42708572861;14687.706245875237;15214.163221022507;15250.923745381202;15274.694506110038;15278.77081843751;17281.827181728022;17350.652986940444;17453.214605342535;17528.386506472212;17599.208035638683;14614.41542338337;14800.62998425048;14829.703405931605;14987.77518337204;14997.32512036939;15866.841331586536;16017.839821602101;16111.838881611035;16220.27008784584;16263.918680406523;15371.231438428258;15504.689906202038;15550.222488875284;15702.528924132293;15709.057456552186;11904.58333958342;11959.461824218106;12188.57339993096;12466.5013399463;12526.248425094524;11811.940940295244;12035.638930832089;12139.210951288138;12186.451609677762;12206.633800985985 12:12:53 INFO - PID 14482 | __end_tp_report 12:12:53 INFO - PID 14482 | __start_cc_report 12:12:53 INFO - PID 14482 | _x_x_mozilla_cycle_collect,282 12:12:53 INFO - PID 14482 | __end_cc_report 12:12:53 INFO - PID 14482 | __startTimestamp1506885173411__endTimestamp 12:12:53 INFO - PID 14482 | 12:12:53 INFO - PID 14482 | ------- Summary: start ------- 12:12:53 INFO - PID 14482 | Number of tests: 6 12:12:53 INFO - PID 14482 | 12:12:53 INFO - PID 14482 | [#0] dojo.html Cycles:165 Average:6171.35 Median:6439.39 stddev:745.61 (11.6%) stddev-sans-first:710.00 12:12:53 INFO - PID 14482 | Values: 3179.9 3196.4 3197.8 3200.0 3202.4 6134.1 6214.0 6228.9 6261.6 6338.6 6171.8 6208.2 6220.4 6240.5 6261.9 5696.6 5781.3 5792.6 5813.3 5813.4 5612.0 5819.2 5854.5 5916.0 5920.6 5286.8 5670.2 5762.5 5783.8 5840.5 5766.9 5780.1 5849.7 5856.6 5874.9 5545.8 5637.1 5638.2 5653.5 5807.6 6161.1 6171.8 6201.8 6209.6 6211.2 6547.8 6628.2 6639.6 6641.1 6648.4 6696.6 6730.9 6750.7 6758.6 6773.4 5987.9 6150.6 6224.4 6226.3 6348.4 6900.9 6998.2 7024.7 7027.8 7028.7 6439.4 6533.2 6609.1 6669.4 6674.0 6590.0 6647.0 6657.4 6659.5 6661.6 6716.9 6741.4 6746.8 6748.1 6751.5 6689.7 6701.5 6772.0 6780.9 6793.5 5216.8 5243.7 5265.6 5332.5 5339.5 4476.9 4553.7 4571.1 4577.0 4607.9 5809.8 5811.3 5813.2 5853.0 5854.1 6709.2 6747.7 6822.6 6830.4 6838.9 5769.9 5782.2 5852.5 5857.5 5872.8 5606.2 5711.8 5728.1 5729.7 5833.4 6503.5 6547.0 6566.3 6567.0 6608.3 6661.4 6761.6 6854.1 6856.0 6867.3 6450.1 6518.9 6520.9 6593.9 6627.2 6744.8 6756.1 6761.1 6766.9 6786.8 6593.6 6711.3 6717.9 6727.1 6818.9 6340.1 6539.8 6558.9 6561.5 6562.8 6444.5 6454.8 6530.6 6552.8 6553.3 6383.5 6390.0 6449.6 6467.0 6472.6 6541.4 6557.3 6597.3 6601.7 6620.9 6313.9 6344.0 6385.4 6395.6 6445.5 12:12:53 INFO - PID 14482 | 12:12:53 INFO - PID 14482 | [#1] ext.html Cycles:180 Average:37579.71 Median:3523.91 stddev:53065.92 (1505.9%) stddev-sans-first:53143.09 12:12:53 INFO - PID 14482 | Values: 842.3 862.0 865.5 868.2 875.5 42326.6 42938.0 43069.0 43156.6 43232.6 39560.0 39731.2 39909.4 39944.0 40245.6 7260.8 7393.3 7400.1 7402.9 7403.1 7214.3 7226.7 7232.9 7251.1 7282.9 7236.6 7247.8 7264.7 7269.9 7884.6 7975.4 8050.6 8060.2 8063.5 8064.7 7900.6 7959.5 7964.1 7997.8 7999.5 38534.0 39525.6 39608.4 39740.2 40112.4 3493.1 3554.7 3564.4 3565.1 3576.3 33217.3 33474.7 33548.2 33589.5 33733.8 5145.4 5173.3 5182.0 5184.2 5217.4 185325.0 186825.0 187352.0 187537.0 188078.0 42732.4 43626.1 47343.3 47919.8 47946.5 50716.5 50749.5 50806.0 50820.5 50830.5 16339.8 16501.4 16504.4 16506.9 16524.3 13457.7 13512.9 13517.8 13545.0 13590.6 13778.0 13822.7 13867.9 13881.5 14007.2 5593.5 5679.3 5694.5 5698.7 5738.3 1006.4 1006.8 1014.0 1020.1 1031.6 23475.2 23487.6 23548.3 23607.6 23792.0 1107.3 1132.4 1133.6 1134.2 1143.5 12415.6 12515.7 12587.4 12668.9 12766.0 230863.0 232350.0 233321.0 233433.0 234280.0 55145.4 55225.7 55391.4 55402.0 55443.2 1436.4 1441.5 1467.1 1468.7 1474.8 23112.0 23298.8 23360.9 23646.8 23750.9 37134.4 37571.2 37635.4 37701.1 37753.6 190673.0 191188.0 191722.0 191792.0 193011.0 30678.4 31506.8 31513.2 34109.8 34339.8 43634.3 43853.8 44056.3 44342.6 44627.6 31911.7 32183.0 32511.7 32909.2 34002.8 32552.3 32757.0 32788.2 33054.5 33844.0 36582.8 37017.0 37178.1 37182.4 37261.6 32181.2 32246.2 32628.2 32662.0 33401.5 22765.8 23494.0 23665.5 24093.3 24178.8 12:12:53 INFO - PID 14482 | 12:12:53 INFO - PID 14482 | [#2] jquery.html Cycles:180 Average:34314.56 Median:23155.65 stddev:80529.43 (347.8%) stddev-sans-first:80715.48 12:12:53 INFO - PID 14482 | Values: 570.0 587.9 591.5 593.3 622.6 22451.0 23341.5 23355.5 23382.2 23385.0 23759.2 24967.3 24979.6 25158.9 25166.5 6606.3 6670.0 6695.5 6701.6 6706.8 6493.8 6657.3 6664.7 6665.1 6668.8 6631.3 6632.2 6634.2 6643.8 6645.3 6249.7 6571.0 6590.8 6590.9 6591.6 6061.4 6072.5 6082.4 6101.0 6123.6 27595.6 27860.2 27869.7 27932.2 27950.2 2037.7 2071.4 2072.3 2077.9 2086.1 17822.6 19446.7 19559.2 19611.4 19629.0 2346.2 2390.6 2404.4 2427.5 2506.5 127354.4 132950.3 133159.3 133725.3 140158.3 14048.7 14629.4 14768.6 14959.0 15330.2 23005.1 23083.5 23302.5 23390.2 23396.9 9983.9 10046.8 10123.2 10152.9 10281.3 9387.5 9412.2 9430.2 9474.8 9490.0 8749.7 8879.2 8985.9 9068.4 9094.0 2612.8 2805.5 2847.5 2862.5 2865.2 993.9 997.1 1016.0 1023.1 1030.4 19732.6 19922.6 19943.8 20061.6 20067.6 1047.9 1067.6 1069.9 1076.4 1086.2 10887.8 10905.6 10982.4 11060.3 11070.7 470244.0 471295.0 472828.0 484041.0 491854.0 112251.0 114640.4 114688.0 114909.0 115054.0 1053.4 1112.5 1128.0 1177.6 1221.0 20344.3 20458.8 20525.6 20543.8 20666.7 57101.4 57809.4 58058.1 58932.0 59002.0 69277.0 69440.3 69754.3 70246.3 70611.0 14349.3 14552.1 14782.7 14784.3 14867.3 22988.9 23227.8 23285.9 23367.5 23459.0 18280.0 18348.9 18541.5 18631.9 18822.8 15150.4 15902.8 16699.4 16897.2 17363.7 18937.6 18961.5 18978.4 19014.0 19222.9 14497.4 14568.8 14591.0 14685.7 14791.1 18170.9 18259.6 18310.4 18395.4 18444.7 12:12:53 INFO - PID 14482 | 12:12:53 INFO - PID 14482 | [#3] mootools.html Cycles:180 Average:9250.17 Median:2727.08 stddev:6481.95 (237.7%) stddev-sans-first:6472.70 12:12:53 INFO - PID 14482 | Values: 1313.3 1413.2 1424.7 1427.3 1435.2 15019.5 15639.3 15648.3 15676.1 15701.4 15394.7 15396.8 15397.5 15457.1 15491.8 2780.6 2783.0 2859.2 2865.5 2869.4 2747.8 2774.4 2786.4 2794.2 2795.9 2175.9 2268.3 2717.7 2815.5 2818.5 2706.7 2732.8 2747.7 2750.1 2763.2 2719.6 2721.3 2733.3 2751.2 2753.2 14113.7 14332.4 14388.7 14395.4 14446.2 7901.3 8117.4 8128.3 8167.8 8176.2 5296.8 5393.5 5485.2 5598.8 5616.1 1941.2 1954.9 1956.1 1998.4 2012.3 25545.6 25635.9 25644.2 25674.4 25681.9 7065.3 7283.6 7330.9 7411.2 7426.9 19145.9 19229.2 19231.4 19254.2 19278.7 7905.9 8160.5 8188.8 8194.8 8196.9 7126.9 7188.3 7212.6 7281.7 7287.1 4989.9 5169.8 5182.5 5202.9 5210.7 1420.3 1426.7 1491.6 1518.7 1523.9 917.7 952.1 961.1 964.1 979.7 8295.0 8421.3 8440.3 8484.1 8568.0 707.6 723.5 752.5 761.7 836.5 5304.7 5318.3 5322.5 5388.3 5394.8 19701.1 20008.5 20833.2 20882.4 21084.6 20414.7 20462.1 20555.0 20569.7 20682.8 10202.7 11055.9 11245.5 11260.8 11260.8 7884.7 8410.7 8519.5 8533.3 8543.3 17609.7 17647.5 17694.7 17721.5 17856.5 18116.5 18298.8 18310.3 18331.3 18411.0 10285.7 10475.1 10504.0 10512.5 10512.6 13276.9 13345.9 13423.8 13586.6 13636.7 11237.4 11319.8 11664.9 11678.1 11683.0 10906.6 10929.0 11018.4 11231.6 11237.3 9681.2 9697.7 9713.6 9750.9 9767.9 7296.5 7313.2 7366.4 7383.8 7388.9 7349.8 7436.3 7520.5 7533.5 7554.4 12:12:53 INFO - PID 14482 | 12:12:53 INFO - PID 14482 | [#4] prototype.html Cycles:180 Average:5510.19 Median:6219.94 stddev:1524.88 (24.5%) stddev-sans-first:1482.29 12:12:53 INFO - PID 14482 | Values: 511.8 518.0 530.8 539.7 540.0 6286.3 6368.4 6377.9 6392.3 6393.0 6189.5 6250.4 6271.9 6280.8 6289.4 5228.4 5233.6 5254.5 5272.9 5287.7 4862.7 4962.9 5165.4 5167.0 5268.5 4949.2 4991.6 4997.2 5002.9 5030.9 4947.7 4965.9 4985.5 4998.4 5016.3 4422.2 4483.7 4494.1 4519.4 4526.1 6033.3 6039.9 6086.8 6104.8 6134.9 5637.3 5693.3 5739.1 5744.8 5764.0 6462.5 6501.6 6503.9 6537.4 6591.7 4743.4 4881.9 4922.6 4936.7 4947.8 6691.2 6728.8 6758.5 6782.4 6948.5 4960.4 5023.5 5059.5 5064.5 5082.9 5060.2 5088.9 5091.8 5162.2 5181.4 5660.6 5707.1 5710.8 5768.0 5783.5 6556.7 6565.7 6609.3 6611.3 6637.3 1775.3 1775.7 1788.7 1789.9 1799.1 1189.6 1207.2 1224.2 1239.1 1252.0 5589.2 5631.4 5658.5 5674.4 5685.1 6511.8 6551.3 6707.9 6748.1 6809.1 5635.2 5670.7 5682.6 5696.8 5719.3 3493.5 3547.9 3549.2 3564.4 3583.8 6498.2 6524.5 6527.2 6539.2 6543.1 6398.6 6681.7 6772.6 6785.3 6874.2 6332.9 6341.2 6366.2 6367.9 6433.7 6661.3 6673.8 6688.5 6720.6 6764.2 6542.4 6606.9 6616.2 6618.3 6650.3 6426.6 6432.7 6461.8 6499.6 6522.6 5021.8 5077.5 5111.3 5118.5 5121.3 6376.0 6551.2 6615.0 6625.5 6643.3 6273.3 6471.7 6484.5 6488.5 6516.6 6497.3 6508.5 6539.1 6552.0 6582.7 6365.6 6430.3 6432.6 6481.6 6491.2 6499.6 6535.2 6539.5 6621.8 6624.7 6304.5 6362.8 6367.2 6439.2 6462.6 12:12:53 INFO - PID 14482 | 12:12:53 INFO - PID 14482 | [#5] yui.html Cycles:180 Average:24619.50 Median:18066.87 stddev:35943.87 (198.9%) stddev-sans-first:36000.54 12:12:53 INFO - PID 14482 | Values: 890.1 892.5 908.3 910.2 911.9 18470.0 19536.0 20015.4 20191.4 20316.3 16942.9 17015.6 17096.3 17160.6 17189.8 940.3 965.6 966.5 972.1 972.5 975.9 977.5 980.1 981.6 983.1 969.1 1008.5 1019.6 1019.6 1020.2 1002.1 1006.7 1007.1 1008.0 1008.2 710.6 735.0 737.2 741.6 748.6 20530.0 20659.7 20754.0 20801.2 20802.5 20005.3 20146.8 20725.7 20830.8 20838.9 16999.8 17171.3 17299.0 17562.6 17631.6 3747.3 3747.8 3772.5 3777.5 3777.9 106669.0 142791.0 143257.6 144197.0 147022.3 17055.0 17209.4 17617.6 17659.9 17663.7 32461.8 33380.5 33386.0 33573.8 33574.0 9155.3 9394.5 9494.0 9503.4 9517.5 4553.9 4621.6 4638.1 4638.4 4644.5 5558.4 5559.9 5638.1 5707.7 5716.9 1096.1 1105.0 1117.9 1119.6 1125.2 687.1 693.9 703.2 703.7 707.2 12315.4 12403.5 12424.5 12430.2 12653.4 696.6 700.8 711.6 711.7 714.9 5451.2 5497.8 5537.1 5550.5 5559.8 125005.0 127595.0 128320.0 128525.4 130736.3 110833.0 111301.0 111464.0 114172.4 115609.0 94418.0 96946.0 97482.5 100062.5 100429.0 10427.2 10529.3 10558.4 10569.8 10681.1 44105.6 45037.3 45682.1 46188.0 46546.0 54176.5 55568.7 55856.0 56734.4 57291.4 14687.7 15214.2 15250.9 15274.7 15278.8 17281.8 17350.7 17453.2 17528.4 17599.2 14614.4 14800.6 14829.7 14987.8 14997.3 15866.8 16017.8 16111.8 16220.3 16263.9 15371.2 15504.7 15550.2 15702.5 15709.1 11904.6 11959.5 12188.6 12466.5 12526.2 11811.9 12035.6 12139.2 12186.5 12206.6 12:12:53 INFO - PID 14482 | -------- Summary: end -------- 12:12:53 INFO - PID 14482 | 12:12:53 INFO - TEST-INFO | 14482: exit 0 12:12:53 INFO - TEST-OK | dromaeo_css | took 1108105ms 12:12:53 INFO - TEST-START | kraken 12:12:53 INFO - Initialising browser for kraken test... 12:12:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44883/getInfo.html -profile /tmp/tmpDjH2hM/profile 12:12:53 INFO - TEST-INFO | started process 14842 (/builds/slave/test/build/application/firefox/firefox http://localhost:44883/getInfo.html) 12:12:58 INFO - TEST-INFO | 14842: exit 0 12:12:59 INFO - Browser initialized. 12:12:59 INFO - Running cycle 1/1 for kraken test... 12:12:59 INFO - Using env: {'DISPLAY': ':0', 12:12:59 INFO - 'HOME': '/home/cltbld', 12:12:59 INFO - 'JSGC_DISABLE_POISONING': '1', 12:12:59 INFO - 'LANG': 'en_US.UTF-8', 12:12:59 INFO - 'LANGUAGE': 'en_US:en', 12:12:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:12:59 INFO - 'LOGNAME': 'cltbld', 12:12:59 INFO - 'MAIL': '/var/mail/cltbld', 12:12:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:12:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:12:59 INFO - 'MOZ_CRASHREPORTER': '1', 12:12:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:12:59 INFO - 'MOZ_NO_REMOTE': '1', 12:12:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:12:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:59 INFO - 'NO_EM_RESTART': '1', 12:12:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:59 INFO - 'PWD': '/builds/slave/test', 12:12:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:12:59 INFO - 'RUST_BACKTRACE': 'full', 12:12:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:12:59 INFO - 'SHELL': '/bin/bash', 12:12:59 INFO - 'SHLVL': '1', 12:12:59 INFO - 'TERM': 'linux', 12:12:59 INFO - 'TMOUT': '86400', 12:12:59 INFO - 'USER': 'cltbld', 12:12:59 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181', 12:12:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:59 INFO - '_': '/tools/buildbot/bin/python'} 12:12:59 INFO - TEST-INFO | started process 15078 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDjH2hM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:12:59 INFO - PID 15078 | 12:12:59 INFO - PID 15078 | (/builds/slave/test/build/application/firefox/firefox:15127): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:12:59 INFO - PID 15078 | 12:13:10 INFO - PID 15078 | RSS: Main: 152141824 12:13:10 INFO - PID 15078 | 12:13:12 INFO - PID 15078 | Cycle 1(1): loaded http://localhost:44883/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44883/tests/kraken/driver.html?testName=audio-beat-detection) 12:13:13 INFO - PID 15078 | RSS: Main: 158085120 12:13:13 INFO - PID 15078 | 12:13:16 INFO - PID 15078 | Cycle 1(1): loaded http://localhost:44883/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44883/tests/kraken/driver.html?testName=audio-dft) 12:13:17 INFO - PID 15078 | RSS: Main: 158691328 12:13:17 INFO - PID 15078 | 12:13:20 INFO - PID 15078 | Cycle 1(1): loaded http://localhost:44883/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44883/tests/kraken/driver.html?testName=audio-fft) 12:13:20 INFO - PID 15078 | RSS: Main: 155189248 12:13:20 INFO - PID 15078 | 12:13:22 INFO - PID 15078 | Cycle 1(1): loaded http://localhost:44883/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44883/tests/kraken/driver.html?testName=audio-oscillator) 12:13:23 INFO - PID 15078 | RSS: Main: 155660288 12:13:23 INFO - PID 15078 | 12:13:25 INFO - PID 15078 | Cycle 1(1): loaded http://localhost:44883/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44883/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:13:26 INFO - PID 15078 | RSS: Main: 155963392 12:13:26 INFO - PID 15078 | 12:13:29 INFO - PID 15078 | Cycle 1(1): loaded http://localhost:44883/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44883/tests/kraken/driver.html?testName=imaging-darkroom) 12:13:30 INFO - PID 15078 | RSS: Main: 157986816 12:13:30 INFO - PID 15078 | 12:13:33 INFO - PID 15078 | Cycle 1(1): loaded http://localhost:44883/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44883/tests/kraken/driver.html?testName=imaging-desaturate) 12:13:34 INFO - PID 15078 | RSS: Main: 158740480 12:13:34 INFO - PID 15078 | 12:13:37 INFO - PID 15078 | Cycle 1(1): loaded http://localhost:44883/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44883/tests/kraken/driver.html?testName=json-parse-financial) 12:13:37 INFO - PID 15078 | RSS: Main: 158175232 12:13:37 INFO - PID 15078 | 12:13:39 INFO - PID 15078 | Cycle 1(1): loaded http://localhost:44883/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44883/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:13:40 INFO - PID 15078 | RSS: Main: 158425088 12:13:40 INFO - PID 15078 | 12:13:41 INFO - PID 15078 | Cycle 1(1): loaded http://localhost:44883/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44883/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:13:42 INFO - PID 15078 | RSS: Main: 155688960 12:13:42 INFO - PID 15078 | 12:13:44 INFO - PID 15078 | Cycle 1(1): loaded http://localhost:44883/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44883/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:13:45 INFO - PID 15078 | RSS: Main: 155410432 12:13:45 INFO - PID 15078 | 12:13:47 INFO - PID 15078 | Cycle 1(1): loaded http://localhost:44883/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44883/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:13:48 INFO - PID 15078 | RSS: Main: 155549696 12:13:48 INFO - PID 15078 | 12:13:51 INFO - PID 15078 | Cycle 1(1): loaded http://localhost:44883/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44883/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:13:51 INFO - PID 15078 | RSS: Main: 155619328 12:13:51 INFO - PID 15078 | 12:13:53 INFO - PID 15078 | Cycle 1(1): loaded http://localhost:44883/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44883/tests/kraken/driver.html?testName=ai-astar) 12:13:53 INFO - PID 15078 | RSS: Main: 157016064 12:13:53 INFO - PID 15078 | 12:13:53 INFO - PID 15078 | __start_tp_report 12:13:53 INFO - PID 15078 | _x_x_mozilla_page_load 12:13:53 INFO - PID 15078 | _x_x_mozilla_page_load_details 12:13:53 INFO - PID 15078 | |i|pagename|runs| 12:13:53 INFO - PID 15078 | |0;ai-astar;106;106;108;105;108;109;119;107;106;107 12:13:53 INFO - PID 15078 | |1;audio-beat-detection;115;115;119;121;147;113;117;118;120;116 12:13:53 INFO - PID 15078 | |2;audio-dft;167;162;165;164;162;164;164;164;169;168 12:13:53 INFO - PID 15078 | |3;audio-fft;77;74;74;73;75;77;74;76;84;76 12:13:53 INFO - PID 15078 | |4;audio-oscillator;80;79;79;78;79;79;81;82;84;81 12:13:53 INFO - PID 15078 | |5;imaging-gaussian-blur;118;112;112;111;112;112;112;112;112;112 12:13:53 INFO - PID 15078 | |6;imaging-darkroom;158;147;148;148;149;149;148;149;149;147 12:13:53 INFO - PID 15078 | |7;imaging-desaturate;94;94;96;94;94;96;94;94;94;94 12:13:53 INFO - PID 15078 | |8;json-parse-financial;51;51;51;50;50;51;52;48;49;48 12:13:53 INFO - PID 15078 | |9;json-stringify-tinderbox;37;36;36;46;38;38;37;37;37;37 12:13:53 INFO - PID 15078 | |10;stanford-crypto-aes;73;75;76;74;76;77;76;74;74;76 12:13:53 INFO - PID 15078 | |11;stanford-crypto-ccm;113;112;117;117;110;106;114;109;109;112 12:13:53 INFO - PID 15078 | |12;stanford-crypto-pbkdf2;169;164;162;163;160;172;163;162;170;161 12:13:53 INFO - PID 15078 | |13;stanford-crypto-sha256-iterative;65;63;64;65;64;64;62;63;64;62 12:13:53 INFO - PID 15078 | __end_tp_report 12:13:53 INFO - PID 15078 | __start_cc_report 12:13:53 INFO - PID 15078 | _x_x_mozilla_cycle_collect,742 12:13:53 INFO - PID 15078 | __end_cc_report 12:13:53 INFO - PID 15078 | __startTimestamp1506885233821__endTimestamp 12:13:53 INFO - PID 15078 | 12:13:53 INFO - PID 15078 | ------- Summary: start ------- 12:13:53 INFO - PID 15078 | Number of tests: 14 12:13:53 INFO - PID 15078 | 12:13:53 INFO - PID 15078 | [#0] ai-astar Cycles:10 Average:108.10 Median:107.50 stddev:4.01 (3.7%) stddev-sans-first:4.18 12:13:53 INFO - PID 15078 | Values: 106.0 106.0 108.0 105.0 108.0 109.0 119.0 107.0 106.0 107.0 12:13:53 INFO - PID 15078 | 12:13:53 INFO - PID 15078 | [#1] audio-beat-detection Cycles:10 Average:120.10 Median:118.50 stddev:9.77 (8.2%) stddev-sans-first:10.19 12:13:53 INFO - PID 15078 | Values: 115.0 115.0 119.0 121.0 147.0 113.0 117.0 118.0 120.0 116.0 12:13:53 INFO - PID 15078 | 12:13:53 INFO - PID 15078 | [#2] audio-dft Cycles:10 Average:164.90 Median:164.50 stddev:2.38 (1.4%) stddev-sans-first:2.40 12:13:53 INFO - PID 15078 | Values: 167.0 162.0 165.0 164.0 162.0 164.0 164.0 164.0 169.0 168.0 12:13:53 INFO - PID 15078 | 12:13:53 INFO - PID 15078 | [#3] audio-fft Cycles:10 Average:76.00 Median:76.00 stddev:3.13 (4.1%) stddev-sans-first:3.30 12:13:53 INFO - PID 15078 | Values: 77.0 74.0 74.0 73.0 75.0 77.0 74.0 76.0 84.0 76.0 12:13:53 INFO - PID 15078 | 12:13:53 INFO - PID 15078 | [#4] audio-oscillator Cycles:10 Average:80.20 Median:80.50 stddev:1.81 (2.3%) stddev-sans-first:1.92 12:13:53 INFO - PID 15078 | Values: 80.0 79.0 79.0 78.0 79.0 79.0 81.0 82.0 84.0 81.0 12:13:53 INFO - PID 15078 | 12:13:53 INFO - PID 15078 | [#5] imaging-gaussian-blur Cycles:10 Average:112.50 Median:112.00 stddev:1.96 (1.7%) stddev-sans-first:0.33 12:13:53 INFO - PID 15078 | Values: 118.0 112.0 112.0 111.0 112.0 112.0 112.0 112.0 112.0 112.0 12:13:53 INFO - PID 15078 | 12:13:53 INFO - PID 15078 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:149.00 stddev:3.19 (2.1%) stddev-sans-first:0.83 12:13:53 INFO - PID 15078 | Values: 158.0 147.0 148.0 148.0 149.0 149.0 148.0 149.0 149.0 147.0 12:13:53 INFO - PID 15078 | 12:13:53 INFO - PID 15078 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.00 stddev:0.84 (0.9%) stddev-sans-first:0.88 12:13:53 INFO - PID 15078 | Values: 94.0 94.0 96.0 94.0 94.0 96.0 94.0 94.0 94.0 94.0 12:13:53 INFO - PID 15078 | 12:13:53 INFO - PID 15078 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:51.00 stddev:1.37 (2.7%) stddev-sans-first:1.41 12:13:53 INFO - PID 15078 | Values: 51.0 51.0 51.0 50.0 50.0 51.0 52.0 48.0 49.0 48.0 12:13:53 INFO - PID 15078 | 12:13:53 INFO - PID 15078 | [#9] json-stringify-tinderbox Cycles:10 Average:37.90 Median:37.00 stddev:2.92 (7.9%) stddev-sans-first:3.08 12:13:53 INFO - PID 15078 | Values: 37.0 36.0 36.0 46.0 38.0 38.0 37.0 37.0 37.0 37.0 12:13:53 INFO - PID 15078 | 12:13:53 INFO - PID 15078 | [#10] stanford-crypto-aes Cycles:10 Average:75.10 Median:76.00 stddev:1.29 (1.7%) stddev-sans-first:1.12 12:13:53 INFO - PID 15078 | Values: 73.0 75.0 76.0 74.0 76.0 77.0 76.0 74.0 74.0 76.0 12:13:53 INFO - PID 15078 | 12:13:53 INFO - PID 15078 | [#11] stanford-crypto-ccm Cycles:10 Average:111.90 Median:112.50 stddev:3.54 (3.1%) stddev-sans-first:3.73 12:13:53 INFO - PID 15078 | Values: 113.0 112.0 117.0 117.0 110.0 106.0 114.0 109.0 109.0 112.0 12:13:53 INFO - PID 15078 | 12:13:53 INFO - PID 15078 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.60 Median:163.50 stddev:4.17 (2.5%) stddev-sans-first:4.11 12:13:53 INFO - PID 15078 | Values: 169.0 164.0 162.0 163.0 160.0 172.0 163.0 162.0 170.0 161.0 12:13:53 INFO - PID 15078 | 12:13:53 INFO - PID 15078 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.60 Median:64.00 stddev:1.07 (1.7%) stddev-sans-first:1.01 12:13:53 INFO - PID 15078 | Values: 65.0 63.0 64.0 65.0 64.0 64.0 62.0 63.0 64.0 62.0 12:13:53 INFO - PID 15078 | -------- Summary: end -------- 12:13:53 INFO - PID 15078 | 12:13:54 INFO - PID 15078 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:13:54 INFO - TEST-INFO | 15078: exit 0 12:13:54 INFO - TEST-OK | kraken | took 60573ms 12:13:54 INFO - SUITE-END | took 1168s 12:13:54 INFO - Completed test suite (00:19:28) 12:13:54 INFO - javascript benchmark 12:13:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8528.099227342082, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3179.904602861914, 3196.3607278544296, 3197.792143510672, 3199.9840000799977, 3202.407554602398, 6134.0798880167995, 6213.968930155354, 6228.906566401508, 6261.592996455227, 6338.556301058922, 6171.753129874811, 6208.1618981437605, 6220.377962203777, 6240.500759939206, 6261.874762504747, 5696.572757043217, 5781.306243250816, 5792.623479473821, 5813.331466881321, 5813.44772246638, 5611.971940140294, 5819.185314056035, 5854.531637469014, 5916.0, 5920.585559010871, 5286.788528458857, 5670.206171136044, 5762.4525670061275, 5783.826485205472, 5840.532757379399, 5766.913496297517, 5780.0751879699465, 5849.678267695275, 5856.619319744245, 5874.9412505874825, 5545.750441230114, 5637.126245431965, 5638.182463542763, 5653.491185793298, 5807.5644326675665, 6161.075838624198, 6171.814845554641, 6201.751929922798, 6209.59637623553, 6211.223597050369, 6547.836304092388, 6628.2046154461295, 6639.601623902581, 6641.070250164981, 6648.401643852028, 6696.5647232929705, 6730.865382692367, 6750.696218670171, 6758.628275444848, 6773.356531129584, 5987.850303742398, 6150.600210986317, 6224.439800417984, 6226.283977342573, 6348.396902294228, 6900.861982760317, 6998.230194678581, 7024.719011239596, 7027.754028609076, 7028.683709233097, 6439.388258115471, 6533.18335208099, 6609.1077704510235, 6669.366410191024, 6674.032265321501, 6590.0, 6647.0, 6657.367550082734, 6659.467242620675, 6661.566998145105, 6716.932830671729, 6741.359570840762, 6746.797596072126, 6748.122744043243, 6751.527393082535, 6689.732410703615, 6701.463882889455, 6772.0, 6780.932190678129, 6793.490488213404, 5216.817411390583, 5243.685378877203, 5265.578753699695, 5332.520073193431, 5339.492748188916, 4476.910461790746, 4553.704009239389, 4571.131485017902, 4576.954230457653, 4607.884802879955, 5809.82570522885, 5811.3026436827, 5813.215215945877, 5852.970735146382, 5854.092615644582, 6709.161354830646, 6747.696353663999, 6822.556533825285, 6830.419414349836, 6838.863222735517, 5769.85575360611, 5782.248307720053, 5852.502537284378, 5857.531397488191, 5872.823815285548, 5606.21512988182, 5711.8000869969355, 5728.083506639001, 5729.656220626775, 5833.38749431312, 6503.512236582275, 6546.967265163739, 6566.310537393505, 6567.047778072153, 6608.2730899601, 6661.4337781287195, 6761.628110453776, 6854.074699915447, 6855.965720171367, 6867.278935711678, 6450.129232553545, 6518.869622607616, 6520.934790652223, 6593.934060659524, 6627.1716035495565, 6744.7976560703255, 6756.054152418764, 6761.0534525167495, 6766.8646627068165, 6786.796396108125, 6593.637349945607, 6711.295313992156, 6717.865642687217, 6727.058211850247, 6818.863622727418, 6340.080688300079, 6539.836504087436, 6558.934410656023, 6561.475081993333, 6562.77030303937, 6444.548881578245, 6454.806355809334, 6530.6081635100145, 6552.803415897531, 6553.279139294578, 6383.521235907233, 6389.9680501597195, 6449.6130232186215, 6467.0, 6472.644004579794, 6541.443977261986, 6557.344265573404, 6597.3072827352435, 6601.702923368556, 6620.933790662224, 6313.936860631335, 6343.9682801585695, 6385.393387628168, 6395.616263024233, 6445.484361250995], "value": 6114.896505683548, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [842.3008902610834, 862.0, 865.5369377383097, 868.2402897464718, 875.5490922175085, 42326.57673423269, 42938.0, 43068.99999999996, 43156.568434315726, 43232.56767432325, 39560.0, 39731.20537589247, 39909.401358979565, 39944.0, 40245.597544024626, 7260.818479538001, 7393.3345998860095, 7400.074990626172, 7402.888956665654, 7403.074615673041, 7214.278572142796, 7226.747063852767, 7232.8915066273785, 7251.057362542862, 7282.854342913139, 7236.638168091601, 7247.818804529877, 7264.673089710976, 7269.854602907939, 7884.60576971152, 7975.40184486163, 8050.557219352962, 8060.193980601952, 8063.475874068166, 8064.677412903477, 7900.604969751547, 7959.482633628795, 7964.084130324971, 7997.800054998614, 7999.480033797785, 38534.0, 39525.60474395248, 39608.405873911914, 39740.20519589621, 40112.39831402502, 3493.1441796759705, 3554.680078792921, 3564.3940530109685, 3565.0552603560077, 3576.3204991051616, 33217.33565328704, 33474.6652533474, 33548.161295967555, 33589.49615755765, 33733.83133084319, 5145.434002259748, 5173.3016042834115, 5182.0, 5184.222366644995, 5217.399999000126, 185325.0, 186825.0, 187352.0, 187537.0, 188077.99999999863, 42732.359014614805, 43626.127477450274, 47343.28985065158, 47919.76040119777, 47946.5205347942, 50716.49283507118, 50749.49250507448, 50806.0, 50820.491795082315, 50830.49169508258, 16339.836601633831, 16501.422450214184, 16504.4223452181, 16506.917465412836, 16524.25640846165, 13457.730845383037, 13512.93243533776, 13517.797233041512, 13545.0, 13590.59228223155, 13778.0, 13822.723545529034, 13867.86132138686, 13881.514147004806, 14007.159570425807, 5593.468620481048, 5679.290088738908, 5694.5159661428415, 5698.6580805151825, 5738.254026976467, 1006.4162785584343, 1006.7885743993697, 1013.9898601014042, 1020.1379834040224, 1031.6028329093353, 23475.178368757013, 23487.647685284734, 23548.293551193492, 23607.645885311733, 23792.0, 1107.3189988157387, 1132.4054871192525, 1133.5749094089717, 1134.1890548258036, 1143.54258296682, 12415.565455209024, 12515.749685006249, 12587.37063146839, 12668.873311266954, 12766.0, 230863.0, 232350.0, 233321.0, 233433.0, 234280.0, 55145.44854551483, 55225.72387138039, 55391.44608553863, 55402.0, 55443.16835247474, 1436.3823555870867, 1441.5170917742673, 1467.0537503310518, 1468.728285267229, 1474.8009018782327, 23112.0, 23298.76701232966, 23360.883195583912, 23646.763532364457, 23750.88124559435, 37134.44298335581, 37571.24857502889, 37635.435468468546, 37701.05747356338, 37753.62246377611, 190673.0, 191188.0, 191722.0, 191792.0, 193011.0, 30678.386432271676, 31506.842465787526, 31513.21216969594, 34109.82945085358, 34339.82830085834, 43634.34548481712, 43853.78073109742, 44056.339154912064, 44342.55657443513, 44627.55372446234, 31911.68088319087, 32183.0, 32511.674883250864, 32909.17727056843, 34002.829985849916, 32552.34895302128, 32757.0, 32788.180295492806, 33054.50418243776, 33844.0, 36582.8170859144, 37017.0, 37178.07054823651, 37182.44226336661, 37261.627383726904, 32181.195470113435, 32246.19384515312, 32628.184295392806, 32662.0, 33401.49897751487, 22765.772342276367, 23494.0, 23665.52668946646, 24093.277201683977, 24178.758212418354], "value": 17246.881604760118, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.9800506982257, 587.8631175676418, 591.4854076953052, 593.3147214966713, 622.5890911998081, 22451.0, 23341.533169336624, 23355.532889342205, 23382.181623643155, 23385.0, 23759.168429104942, 24967.250982470556, 24979.625305620386, 25158.867850946706, 25166.496670066586, 6606.273309935915, 6669.9666501667425, 6695.531312808105, 6701.631410272433, 6706.798796036127, 6493.805185844432, 6657.300983396722, 6664.666766661671, 6665.100211471462, 6668.76659316924, 6631.336866313378, 6632.237292711352, 6634.237062737775, 6643.833904152387, 6645.33546645334, 6249.7187626556915, 6570.967145164267, 6590.835229119262, 6590.93409065908, 6591.604503729744, 6061.363556826558, 6072.544559158081, 6082.361352058015, 6101.023836186233, 6123.571350005502, 27595.586066209024, 27860.16419507418, 27869.721302786915, 27932.16203513898, 27950.16149515518, 2037.6739721644465, 2071.399294204679, 2072.3161356752234, 2077.885716285604, 2086.0716980943484, 17822.55443613907, 19446.70829937552, 19559.21763129473, 19611.411657650293, 19629.0, 2346.23747282134, 2390.6055500842513, 2404.386881345246, 2427.526632306683, 2506.4736405354897, 127354.36322818419, 132950.33524832412, 133159.33420332934, 133725.33137334252, 140158.29920850432, 14048.719025619635, 14629.414823406945, 14768.628156319573, 14959.027663201858, 15330.233488325539, 23005.07979680794, 23083.53832923332, 23302.533949320918, 23390.181343653232, 23396.88301558481, 9983.850242246372, 10046.849297260547, 10123.1901447884, 10152.898471015344, 10281.280310378352, 9387.5306234688, 9412.199963003084, 9430.151286384122, 9474.810503790024, 9489.952550237344, 8749.650013999497, 8879.24526415249, 8985.865212021694, 9068.410553314014, 9094.0, 2612.7517885800817, 2805.4809860175933, 2847.515922293215, 2862.4990626640256, 2865.226388874992, 993.9353941993747, 997.1054995508591, 1015.9644412445676, 1023.1456733627356, 1030.4487099401783, 19732.60534789296, 19922.60154796896, 19943.800561994485, 20061.598768024556, 20067.59864802725, 1047.8533005379254, 1067.6209945469247, 1069.8769641491328, 1076.4402510694395, 1086.190787863047, 10887.83668244977, 10905.618303359344, 10982.395968221665, 11060.281081729823, 11070.667879963614, 470244.0, 471295.0, 472828.0, 484041.0, 491854.0, 112251.0, 114640.42679786548, 114688.0, 114909.0, 115054.0, 1053.373242920461, 1112.527175950218, 1128.0355296221612, 1177.60550215677, 1220.969475763113, 20344.287949921683, 20458.79541204569, 20525.58948821045, 20543.794562054787, 20666.68999965032, 57101.428985711274, 57809.4219057804, 58058.129128063956, 58932.0, 59002.0, 69277.0, 69440.30559694338, 69754.30245697478, 70246.29753702397, 70611.0, 14349.282535872955, 14552.126872387691, 14782.704345913236, 14784.260786960394, 14867.330970106155, 22988.885055574614, 23227.767722323235, 23285.88357058204, 23367.532649347257, 23459.0, 18280.0, 18348.90825545917, 18541.536461588566, 18631.906840465712, 18822.811771882105, 15150.393984240947, 15902.840971590136, 16699.415520456725, 16897.155142243086, 17363.652726945642, 18937.621247575247, 18961.525961851064, 18978.43064708061, 19014.0, 19222.903885480482, 14497.420103196177, 14568.781468277772, 14591.0, 14685.706285874436, 14791.112533248039, 18170.90914545419, 18259.634807304046, 18310.35913743066, 18395.356162534248, 18444.723329149805], "value": 12023.158375803332, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1313.3302015971854, 1413.1521087347587, 1424.736423761604, 1427.307755738466, 1435.2249785116041, 15019.549413517598, 15639.296231669574, 15648.295826687798, 15676.059436433821, 15701.371945122182, 15394.692106157898, 15396.84603153968, 15397.538073857802, 15457.072575645441, 15491.845081549181, 2780.569011803174, 2783.03985125132, 2859.2280084377207, 2865.527188013986, 2869.4404591104744, 2747.766439852615, 2774.4312415954782, 2786.3591373983995, 2794.18968499136, 2795.8602069896424, 2175.945601359963, 2268.25147701259, 2717.6874659414225, 2815.4650616382824, 2818.5349417346115, 2706.688730795944, 2732.8496932668695, 2747.6702795664546, 2750.147454289177, 2763.2124844419445, 2719.5648696208514, 2721.306066952934, 2733.330334068146, 2751.17464760571, 2753.187809596174, 14113.717725645532, 14332.355044023045, 14388.712225755426, 14395.42418303277, 14446.205458699767, 7901.328387087105, 8117.391195660349, 8128.268455839003, 8167.754967350989, 8176.223258790405, 5296.841094767163, 5393.460653934614, 5485.232067510552, 5598.776048958037, 5616.129499927477, 1941.1652989214774, 1954.853385996056, 1956.1392987085637, 1998.3605246321183, 2012.2856385982911, 25545.616815747777, 25635.871820640965, 25644.230673079837, 25674.358141046625, 25681.871590642302, 7065.258147894499, 7283.616112938525, 7330.8900366494545, 7411.184769675331, 7426.9257307427315, 19145.904270478557, 19229.230830766894, 19231.423057308304, 19254.22983080661, 19278.71081933772, 7905.920940790634, 8160.469569477965, 8188.754337369888, 8194.836103277901, 8196.877046844302, 7126.893096603555, 7188.317109874553, 7212.639368031578, 7281.7087316507805, 7287.089113860768, 4989.875253118628, 5169.767360468788, 5182.455842136596, 5202.92195617066, 5210.713410762369, 1420.2969530082676, 1426.6718654709475, 1491.6494623763406, 1518.7038527487255, 1523.9390424383123, 917.6512925088424, 952.0812416018575, 961.0725649748026, 964.064857088623, 979.6620166042846, 8295.04606970194, 8421.284190843848, 8440.324774018063, 8484.066752657203, 8567.957160214159, 707.6143501791536, 723.5477826358526, 752.4845480845529, 761.744815486807, 836.5357226739218, 5304.655197412156, 5318.308619879391, 5322.547583455373, 5388.299521062237, 5394.757235924393, 19701.11344989479, 20008.49978750543, 20833.1666733332, 20882.37352879416, 21084.578308433745, 20414.69377959332, 20462.079206435745, 20555.0, 20569.69145462819, 20682.79317206839, 10202.744931376626, 11055.889441105646, 11245.493952772145, 11260.774784504429, 11260.831087533694, 7884.684612615661, 8410.663573456994, 8519.531425771642, 8533.31733461334, 8543.273821725223, 17609.735853961945, 17647.470575882744, 17694.734578981584, 17721.468355949342, 17856.46430607084, 18116.547086322946, 18298.817011830244, 18310.26758929628, 18331.266749329876, 18411.0, 10285.691429257135, 10475.109615682448, 10504.002119798606, 10512.526936287732, 10512.632057877938, 13276.867231327564, 13345.866541334462, 13423.798643020558, 13586.592402227949, 13636.727265454438, 11237.4381280934, 11319.773604528027, 11664.94167529157, 11678.065754739431, 11683.0, 10906.618268360886, 10929.0, 11018.449077546251, 11231.55073797039, 11237.3257604544, 9681.177099946302, 9697.6605818796, 9713.611455541699, 9750.902490974999, 9767.853482197916, 7296.525725827804, 7313.195548489555, 7366.4106871449085, 7383.778486645409, 7388.889166662612, 7349.8162545936775, 7436.293552112541, 7520.548767073993, 7533.510321829063, 7554.395648348009], "value": 6643.174256751998, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.84132918795154, 517.9715115668638, 530.7876651284387, 539.749016707231, 539.9676019438831, 6286.277078136016, 6368.3631636836235, 6377.872442551146, 6392.296847346788, 6393.0, 6189.4738947189435, 6250.40621140992, 6271.905921411182, 6280.842978925529, 6289.402506761861, 5228.372595288552, 5233.58131349493, 5254.500822421864, 5272.92090618641, 5287.67808047988, 4862.7325497097645, 4962.89077142215, 5165.354330708661, 5166.96660667866, 5268.473152684739, 4949.1935443563025, 4991.550760431561, 4997.175466048097, 5002.949970500303, 5030.924536131961, 4947.703137811707, 4965.851024469272, 4985.476524964899, 4998.40019197695, 5016.322796422491, 4422.248217802949, 4483.708558943658, 4494.1461122386645, 4519.389882365871, 4526.094781043805, 6033.306169790487, 6039.8792024159275, 6086.782643471279, 6104.755809767604, 6134.9386506134815, 5637.323521177444, 5693.316801983747, 5739.110437882135, 5744.788256521228, 5763.971180144114, 6462.515311351667, 6501.609903405811, 6503.9349606504275, 6537.378948999837, 6591.736330546821, 4743.35964644772, 4881.902361952741, 4922.581580565657, 4936.654434189608, 4947.763059235192, 6691.19705635322, 6728.764493242762, 6758.459323254128, 6782.355676210752, 6948.478864085113, 4960.429550601655, 5023.472535383732, 5059.468755780664, 5064.518870707277, 5082.907174957402, 5060.1903695408555, 5088.872778180501, 5091.847244582668, 5162.225666150108, 5181.4300426953005, 5660.594247444819, 5707.1153971134545, 5710.8001219957105, 5768.019436695772, 5783.479486846204, 6556.704948277339, 6565.671716414256, 6609.347663084229, 6611.272759996396, 6637.269900310962, 1775.2632657447273, 1775.698131317679, 1788.678037953155, 1789.91945362459, 1799.0734771592379, 1189.6193218170276, 1207.2394391533453, 1224.2287358963795, 1239.1326071749631, 1251.974960500803, 5589.189567512688, 5631.436856314336, 5658.453695836807, 5674.375818659944, 5685.118806584986, 6511.772087976898, 6551.312112228243, 6707.899381509281, 6748.122744043243, 6809.114815074107, 5635.21107045014, 5670.716464176775, 5682.602217844794, 5696.829095127153, 5719.3136823581435, 3493.475978603179, 3547.9310396506835, 3549.1836877518317, 3564.4296912494397, 3583.8387272572795, 6498.187726534184, 6524.543281970216, 6527.15147030883, 6539.182602174728, 6543.149390579194, 6398.552101352953, 6681.732730690718, 6772.627505487246, 6785.253622101565, 6874.243833178346, 6332.936670633327, 6341.175647165839, 6366.172397588284, 6367.872642547123, 6433.710483028181, 6661.267260601233, 6673.833154171185, 6688.4983626229205, 6720.630365329758, 6764.188297404146, 6542.411182993554, 6606.867862642816, 6616.239132499824, 6618.2719900811835, 6650.301718319507, 6426.550141490051, 6432.710528026157, 6461.773837915651, 6499.5775274607, 6522.576032558059, 5021.824236151717, 5077.492250774893, 5111.258867464271, 5118.488151184852, 5121.308623335951, 6375.968120159369, 6551.246606640298, 6615.0, 6625.536212465081, 6643.269240383651, 6273.341299163522, 6471.676416179078, 6484.481241500763, 6488.480921526361, 6516.6415847128865, 6497.310699218143, 6508.511861610493, 6539.11721917554, 6552.0, 6582.703778330082, 6365.586236894587, 6430.260520040255, 6432.614043157426, 6481.5786973848435, 6491.188601424822, 6499.6425196612745, 6535.183102112236, 6539.542232043711, 6621.801345959629, 6624.701888414937, 6304.464120549805, 6362.840928976812, 6367.192769459029, 6439.195100612424, 6462.610538734007], "value": 5064.52253065246, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [890.0876601483478, 892.4823602310661, 908.2688435809171, 910.1762904571364, 911.8814554107969, 18470.0, 19536.0, 20015.399538013848, 20191.39425817226, 20316.288929887476, 16942.915285423554, 17015.57461063474, 17096.316147354122, 17160.570985725364, 17189.82810171898, 940.2571968145174, 965.6427121964883, 966.5312323523086, 972.1202311907699, 972.45542496202, 975.9219262459022, 977.4672803322206, 980.1129977370491, 981.5582987655548, 983.0808194338316, 969.1132613658494, 1008.485672307125, 1019.5666841592331, 1019.638028499883, 1020.2093377632357, 1002.1331548210782, 1006.6527048168371, 1007.0734923870057, 1008.0171832463363, 1008.2236677758085, 710.5878590417545, 735.0187499687923, 737.1927739125649, 741.6032422653855, 748.6182047155935, 20529.973501324876, 20659.69010464844, 20754.0, 20801.16795328185, 20802.47993800152, 20005.29981450657, 20146.798532014785, 20725.689114663142, 20830.791692083036, 20838.895805521028, 16999.83000169995, 17171.313147474088, 17299.0, 17562.560935976577, 17631.5592110197, 3747.3254814133447, 3747.8126093695205, 3772.4907137536356, 3777.452269420946, 3777.9433308500393, 106669.0, 142791.0, 143257.56742432548, 144197.0, 147022.26488867594, 17055.0, 17209.397671081453, 17617.647647047244, 17659.911700441673, 17663.73504397435, 32461.837690811393, 33380.499292510634, 33386.0, 33573.83213083919, 33574.0, 9155.313351498531, 9394.530273486298, 9493.952530237304, 9503.42979421223, 9517.524123793783, 4553.863384098482, 4621.607163391082, 4638.095571363552, 4638.373819534387, 4644.465886423037, 5558.388577256498, 5559.916601250903, 5638.069718496484, 5707.71461426927, 5716.942365662366, 1096.13405409727, 1104.9944750276197, 1117.9379589390112, 1119.6249256499002, 1125.2404626876826, 687.0964681443985, 693.9375456208966, 703.1562125449481, 703.6938931564683, 707.1584814071239, 12315.4458049388, 12403.503859845685, 12424.503019879285, 12430.192037517532, 12653.36733163338, 696.6029363262892, 700.8212905709012, 711.6406214861462, 711.7259854955776, 714.9142102947679, 5451.182322651554, 5497.752601132878, 5537.141743029837, 5550.47270509301, 5559.777608895599, 125005.0, 127595.0, 128320.0, 128525.35737321254, 130736.3463182678, 110833.0, 111301.0, 111464.0, 114172.42913785377, 115609.0, 94418.0, 96946.0, 97482.51258743803, 100062.49968750255, 100429.0, 10427.165826733995, 10529.315594486334, 10558.366498010144, 10569.841452378374, 10681.145508359157, 44105.57419341967, 45037.32444013408, 45682.086358273315, 46188.0, 46546.0, 54176.45823541714, 55568.72215638896, 55856.0, 56734.432655672914, 57291.42708572861, 14687.706245875237, 15214.163221022507, 15250.923745381202, 15274.694506110038, 15278.77081843751, 17281.827181728022, 17350.652986940444, 17453.214605342535, 17528.386506472212, 17599.208035638683, 14614.41542338337, 14800.62998425048, 14829.703405931605, 14987.77518337204, 14997.32512036939, 15866.841331586536, 16017.839821602101, 16111.838881611035, 16220.27008784584, 16263.918680406523, 15371.231438428258, 15504.689906202038, 15550.222488875284, 15702.528924132293, 15709.057456552186, 11904.58333958342, 11959.461824218106, 12188.57339993096, 12466.5013399463, 12526.248425094524, 11811.940940295244, 12035.638930832089, 12139.210951288138, 12186.451609677762, 12206.633800985985], "value": 9016.853146523554, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1408.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 106.0, 108.0, 105.0, 108.0, 109.0, 119.0, 107.0, 106.0, 107.0], "value": 108.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 119.0, 121.0, 147.0, 113.0, 117.0, 118.0, 120.0, 116.0], "value": 120.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 162.0, 165.0, 164.0, 162.0, 164.0, 164.0, 164.0, 169.0, 168.0], "value": 164.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 74.0, 73.0, 75.0, 77.0, 74.0, 76.0, 84.0, 76.0], "value": 76.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 78.0, 79.0, 79.0, 81.0, 82.0, 84.0, 81.0], "value": 80.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 112.0, 112.0, 111.0, 112.0, 112.0, 112.0, 112.0, 112.0, 112.0], "value": 112.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 147.0, 148.0, 148.0, 149.0, 149.0, 148.0, 149.0, 149.0, 147.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 94.0, 94.0, 96.0, 94.0, 94.0, 94.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 51.0, 50.0, 50.0, 51.0, 52.0, 48.0, 49.0, 48.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 36.0, 36.0, 46.0, 38.0, 38.0, 37.0, 37.0, 37.0, 37.0], "value": 37.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 76.0, 74.0, 76.0, 77.0, 76.0, 74.0, 74.0, 76.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 117.0, 117.0, 110.0, 106.0, 114.0, 109.0, 109.0, 112.0], "value": 111.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 164.0, 162.0, 163.0, 160.0, 172.0, 163.0, 162.0, 170.0, 161.0], "value": 164.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 64.0, 65.0, 64.0, 64.0, 62.0, 63.0, 64.0, 62.0], "value": 63.6, "unit": "score"}]}]} 12:13:54 INFO - Return code: 0 12:13:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:13:54 INFO - # TBPL SUCCESS # 12:13:54 INFO - Running post-action listener: _package_coverage_data 12:13:54 INFO - Running post-action listener: _resource_record_post_action 12:13:54 INFO - [mozharness: 2017-10-01 19:13:54.619519Z] Finished run-tests step (success) 12:13:54 INFO - Running post-run listener: _resource_record_post_run 12:13:54 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 8192; Write bytes: 345645056; Read time: 224; Write time: 223740 12:13:54 INFO - TinderboxPrint: CPU usage
13.1% 12:13:54 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 224 12:13:54 INFO - TinderboxPrint: I/O write bytes / time
345,645,056 / 223,740 12:13:54 INFO - TinderboxPrint: CPU idle
8,223.7 (86.9%) 12:13:54 INFO - TinderboxPrint: CPU user
1,193.2 (12.6%) 12:13:54 INFO - TinderboxPrint: Swap in / out
0 / 0 12:13:54 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12771328; Read time: 0; Write time: 1672 12:13:54 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:13:54 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 4096; Write bytes: 332451840; Read time: 208; Write time: 222052 12:13:55 INFO - Running post-run listener: _upload_blobber_files 12:13:55 INFO - Blob upload gear active. 12:13:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:13:55 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 12:13:55 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'] 12:13:55 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 12:13:55 INFO - Traceback (most recent call last): 12:13:55 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 12:13:55 INFO - main() 12:13:55 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 12:13:55 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 12:13:55 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 12:13:55 INFO - return set(response.json().get('whitelist', [])) 12:13:55 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 12:13:55 INFO - return json.loads(self.text or self.content, **kwargs) 12:13:55 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/__init__.py", line 488, in loads 12:13:55 INFO - return _default_decoder.decode(s) 12:13:55 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 370, in decode 12:13:55 INFO - obj, end = self.raw_decode(s) 12:13:55 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 389, in raw_decode 12:13:55 INFO - return self.scan_once(s, idx=_w(s, idx).end()) 12:13:55 INFO - simplejson.scanner.JSONDecodeError: Expecting value: line 1 column 1 (char 0) 12:13:55 ERROR - Return code: 1 12:13:55 INFO - Setting buildbot property blobber_files to {} 12:13:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:13:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:13:55 INFO - Contents: 12:13:55 INFO - blobber_files:{} 12:13:55 INFO - Running post-run listener: copy_logs_to_upload_dir 12:13:55 INFO - Copying logs to upload dir... 12:13:55 INFO - mkdir: /builds/slave/test/build/upload/logs 12:13:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1228.646292 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 28 secs) (at 2017-10-01 12:13:55.576929) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-01 12:13:55.584490) ========= 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=1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011396 build_url: 'https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.tar.bz2' blobber_files: '{}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-01 12:13:55.638201) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 12:13:55.638522) ========= 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=1f5564757af27664ca02d71f000001b0-1506876829.868572-2041480181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004706 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 12:13:55.687723) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-01 12:13:55.688026) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-01 12:13:55.688310) ========= ========= Total master_lag: 0.20 =========