builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-069 starttime: 1504377913.41 results: success (0) revision: 59611e1583e7411bab615d29109894e4c657af33 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 11:45:13.409005) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 11:45:13.411448) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 11:45:13.411743) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 11:45:13.430497) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 11:45:13.430896) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-02 11:45:13-- 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% 224M=0s 2017-09-02 11:45:13 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124499 ========= master_lag: 0.20 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 11:45:13.750475) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 11:45:13.750863) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018298 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 11:45:13.815154) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 11:45:13.815588) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 59611e1583e7411bab615d29109894e4c657af33 --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 59611e1583e7411bab615d29109894e4c657af33 --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=05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-02 11:45:13,960 truncating revision to first 12 chars 2017-09-02 11:45:13,960 Setting DEBUG logging. 2017-09-02 11:45:13,960 attempt 1/10 2017-09-02 11:45:13,960 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/59611e1583e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-02 11:45:14,458 unpacking tar archive at: mozilla-central-59611e1583e7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.897160 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 11:45:14.741917) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 11:45:14.742213) ========= script_repo_revision: 59611e1583e7411bab615d29109894e4c657af33 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 11:45:14.742621) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 11:45:14.742882) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 11:45:14.861274) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-09-02 11:45:14.861617) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:45:15 INFO - MultiFileLogger online at 20170902 11:45:15 in /builds/slave/test 11:45:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 11:45:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:45:15 INFO - {'append_to_log': False, 11:45:15 INFO - 'base_work_dir': '/builds/slave/test', 11:45:15 INFO - 'blob_upload_branch': 'Firefox', 11:45:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:45:15 INFO - 'branch': 'Firefox', 11:45:15 INFO - 'buildbot_json_path': 'buildprops.json', 11:45:15 INFO - 'code_coverage': False, 11:45:15 INFO - 'config_files': ('talos/linux_config.py',), 11:45:15 INFO - 'default_actions': ('clobber', 11:45:15 INFO - 'read-buildbot-config', 11:45:15 INFO - 'download-and-extract', 11:45:15 INFO - 'populate-webroot', 11:45:15 INFO - 'create-virtualenv', 11:45:15 INFO - 'install', 11:45:15 INFO - 'setup-mitmproxy', 11:45:15 INFO - 'run-tests'), 11:45:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:45:15 INFO - 'disable_ccov_upload': False, 11:45:15 INFO - 'disable_stylo': False, 11:45:15 INFO - 'download_minidump_stackwalk': True, 11:45:15 INFO - 'download_symbols': 'ondemand', 11:45:15 INFO - 'enable_stylo': False, 11:45:15 INFO - 'enable_webrender': False, 11:45:15 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:45:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:45:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:45:15 INFO - 'gecko_profile': False, 11:45:15 INFO - 'gecko_profile_interval': 0, 11:45:15 INFO - 'installer_path': 'installer.exe', 11:45:15 INFO - 'log_level': 'info', 11:45:15 INFO - 'log_name': 'talos', 11:45:15 INFO - 'log_to_console': True, 11:45:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:45:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:45:15 INFO - 'opt_config_files': (), 11:45:15 INFO - 'pip_index': False, 11:45:15 INFO - 'suite': 'dromaeojs-e10s', 11:45:15 INFO - 'system_bits': '32', 11:45:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:45:15 INFO - 'title': 'talos-linux64-ix-069', 11:45:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:45:15 INFO - 'use_talos_json': True, 11:45:15 INFO - 'verify': 'False', 11:45:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:45:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:45:15 INFO - 'work_dir': 'build'} 11:45:15 INFO - [mozharness: 2017-09-02 18:45:15.169170Z] Running clobber step. 11:45:15 INFO - Running pre-action listener: _resource_record_pre_action 11:45:15 INFO - Running main action method: clobber 11:45:15 INFO - rmtree: /builds/slave/test/build 11:45:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:45:16 INFO - Running post-action listener: _resource_record_post_action 11:45:16 INFO - [mozharness: 2017-09-02 18:45:16.171757Z] Finished clobber step (success) 11:45:16 INFO - [mozharness: 2017-09-02 18:45:16.171855Z] Running read-buildbot-config step. 11:45:16 INFO - Running pre-action listener: _resource_record_pre_action 11:45:16 INFO - Running main action method: read_buildbot_config 11:45:16 INFO - Using buildbot properties: 11:45:16 INFO - { 11:45:16 INFO - "project": "", 11:45:16 INFO - "product": "firefox", 11:45:16 INFO - "who": "ffxbld@noreply.mozilla.org", 11:45:16 INFO - "installer_path": "public/build/target.tar.bz2", 11:45:16 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:45:16 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:45:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 11:45:16 INFO - "stage_platform": "linux64-pgo", 11:45:16 INFO - "basedir": "/builds/slave/test", 11:45:16 INFO - "buildnumber": 137, 11:45:16 INFO - "platform": "ubuntu64_hw", 11:45:16 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:45:16 INFO - "slavebuilddir": "test", 11:45:16 INFO - "taskId": "W-iWwze-T0Khq_1cIyVqeg", 11:45:16 INFO - "branch": "mozilla-central", 11:45:16 INFO - "script_repo_revision": "production", 11:45:16 INFO - "revision": "59611e1583e7411bab615d29109894e4c657af33", 11:45:16 INFO - "slavename": "talos-linux64-ix-069", 11:45:16 INFO - "repo_path": "mozilla-central" 11:45:16 INFO - } 11:45:16 INFO - Finding installer, test and symbols from parent task. 11:45:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W-iWwze-T0Khq_1cIyVqeg'}, attempt #1 11:45:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W-iWwze-T0Khq_1cIyVqeg'}, attempt #1 11:45:17 INFO - Task dependencies: DhiApqhVQxKcRyPRgESVyw 11:45:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw'}, attempt #1 11:45:17 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.tar.bz2 11:45:17 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.test_packages.json 11:45:17 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.crashreporter-symbols.zip 11:45:17 INFO - Running post-action listener: _resource_record_post_action 11:45:17 INFO - [mozharness: 2017-09-02 18:45:17.572907Z] Finished read-buildbot-config step (success) 11:45:17 INFO - [mozharness: 2017-09-02 18:45:17.573120Z] Running download-and-extract step. 11:45:17 INFO - Running pre-action listener: _resource_record_pre_action 11:45:17 INFO - Running main action method: download_and_extract 11:45:17 INFO - mkdir: /builds/slave/test/build/tests 11:45:17 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:45:17 INFO - trying https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.test_packages.json 11:45:17 INFO - Downloading https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:45:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:45:18 INFO - Downloaded 989 bytes. 11:45:18 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:45:18 INFO - Using the following test package requirements: 11:45:18 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:45:18 INFO - u'common': [u'target.common.tests.zip'], 11:45:18 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:45:18 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:45:18 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:45:18 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:45:18 INFO - u'mozbase': [u'target.common.tests.zip'], 11:45:18 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:45:18 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:45:18 INFO - u'web-platform': [u'target.common.tests.zip', 11:45:18 INFO - u'target.web-platform.tests.tar.gz'], 11:45:18 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:45:18 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:45:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.common.tests.zip 11:45:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:45:18 INFO - Fetch https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.common.tests.zip into memory 11:45:20 INFO - Content-Length response header: 55683982 11:45:20 INFO - Bytes received: 55683982 11:45:31 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:45:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.common.tests.zip 11:45:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:45:31 INFO - Fetch https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.common.tests.zip into memory 11:45:32 INFO - Content-Length response header: 55683982 11:45:32 INFO - Bytes received: 55683982 11:45:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.talos.tests.zip 11:45:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:45:47 INFO - Fetch https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.talos.tests.zip into memory 11:45:48 INFO - Content-Length response header: 14496650 11:45:48 INFO - Bytes received: 14496650 11:45:48 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:45:48 INFO - trying https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.tar.bz2 11:45:48 INFO - Downloading https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:45:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:45:49 INFO - Downloaded 63494370 bytes. 11:45:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.tar.bz2 11:45:49 INFO - mkdir: /builds/slave/test/properties 11:45:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:45:49 INFO - Writing to file /builds/slave/test/properties/build_url 11:45:49 INFO - Contents: 11:45:49 INFO - build_url:https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.tar.bz2 11:45:49 INFO - Running post-action listener: _resource_record_post_action 11:45:49 INFO - Running post-action listener: find_tests_for_verification 11:45:49 INFO - Running post-action listener: set_extra_try_arguments 11:45:49 INFO - [mozharness: 2017-09-02 18:45:49.939013Z] Finished download-and-extract step (success) 11:45:49 INFO - [mozharness: 2017-09-02 18:45:49.939110Z] Running populate-webroot step. 11:45:49 INFO - Running pre-action listener: _resource_record_pre_action 11:45:49 INFO - Running main action method: populate_webroot 11:45:49 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:45:49 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:45:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:45:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:45:49 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:45:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:45:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:45:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:45:49 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:45:49 INFO - 'talos_options': ['--enable-stylo'], 11:45:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:45:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:45:49 INFO - 'tests': ['damp', 'tps']}, 11:45:49 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:45:49 INFO - 'talos_options': ['--enable-stylo'], 11:45:49 INFO - 'tests': ['damp', 'tps']}, 11:45:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:45:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:45:49 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:45:49 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:45:49 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:45:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:45:49 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:45:49 INFO - 'talos_options': ['--enable-stylo'], 11:45:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:45:49 INFO - 'other-e10s': {'tests': ['a11yr', 11:45:49 INFO - 'ts_paint', 11:45:49 INFO - 'tpaint', 11:45:49 INFO - 'sessionrestore', 11:45:49 INFO - 'sessionrestore_many_windows', 11:45:49 INFO - 'sessionrestore_no_auto_restore', 11:45:49 INFO - 'tabpaint']}, 11:45:49 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:45:49 INFO - 'tests': ['a11yr', 11:45:49 INFO - 'ts_paint', 11:45:49 INFO - 'tpaint', 11:45:49 INFO - 'sessionrestore', 11:45:49 INFO - 'sessionrestore_many_windows', 11:45:49 INFO - 'sessionrestore_no_auto_restore', 11:45:49 INFO - 'tabpaint']}, 11:45:49 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:45:49 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:45:49 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:45:49 INFO - 'tests': ['bloom_basic_singleton']}, 11:45:49 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:45:49 INFO - 'tests': ['bloom_basic']}, 11:45:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:45:49 INFO - 'tsvgr_opacity', 11:45:49 INFO - 'tart', 11:45:49 INFO - 'tscrollx', 11:45:49 INFO - 'tsvg_static']}, 11:45:49 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:45:49 INFO - 'tests': ['tsvgx', 11:45:49 INFO - 'tsvgr_opacity', 11:45:49 INFO - 'tart', 11:45:49 INFO - 'tscrollx', 11:45:49 INFO - 'tsvg_static']}, 11:45:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:45:49 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:45:49 INFO - 'talos_options': ['--enable-stylo'], 11:45:49 INFO - 'tests': ['tp5o']}, 11:45:49 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:45:49 INFO - 'talos_options': ['--mitmproxy', 11:45:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:45:49 INFO - '--firstNonBlankPaint'], 11:45:49 INFO - 'tests': ['tp6_google', 11:45:49 INFO - 'tp6_youtube', 11:45:49 INFO - 'tp6_amazon', 11:45:49 INFO - 'tp6_facebook']}, 11:45:49 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:45:49 INFO - 'talos_options': ['--enable-stylo', 11:45:49 INFO - '--mitmproxy', 11:45:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:45:49 INFO - '--firstNonBlankPaint'], 11:45:49 INFO - 'tests': ['tp6_google', 11:45:49 INFO - 'tp6_youtube', 11:45:49 INFO - 'tp6_amazon', 11:45:49 INFO - 'tp6_facebook']}, 11:45:49 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:45:49 INFO - 'talos_options': ['--enable-stylo', 11:45:49 INFO - '--stylo-threads=1', 11:45:49 INFO - '--mitmproxy', 11:45:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:45:49 INFO - '--firstNonBlankPaint'], 11:45:49 INFO - 'tests': ['tp6_google', 11:45:49 INFO - 'tp6_youtube', 11:45:49 INFO - 'tp6_amazon', 11:45:49 INFO - 'tp6_facebook']}, 11:45:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:45:49 INFO - 'talos_options': ['--xperf_path', 11:45:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:45:49 INFO - 'tests': ['tp5n']}, 11:45:49 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:45:49 INFO - 'talos_options': ['--enable-stylo', 11:45:49 INFO - '--xperf_path', 11:45:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:45:49 INFO - 'tests': ['tp5n']}}} 11:45:49 INFO - Running post-action listener: _resource_record_post_action 11:45:49 INFO - [mozharness: 2017-09-02 18:45:49.953504Z] Finished populate-webroot step (success) 11:45:49 INFO - [mozharness: 2017-09-02 18:45:49.953594Z] Running create-virtualenv step. 11:45:49 INFO - Running pre-action listener: _resource_record_pre_action 11:45:49 INFO - Running main action method: create_virtualenv 11:45:49 INFO - Creating virtualenv /builds/slave/test/build/venv 11:45:49 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:45:49 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:45:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:45:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:45:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:45:50 INFO - Installing distribute.............................................................................................................................................................................................done. 11:45:50 INFO - Installing pip...............done. 11:45:50 INFO - Return code: 0 11:45:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:45:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:45:50 INFO - Reading from file tmpfile_stdout 11:45:50 INFO - Output received: 11:45:50 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:45:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:45: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:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45: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:45:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2790a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2933a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937090>, '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 0x2937d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2931f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', '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:45: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', 'pip>=1.5'] in /builds/slave/test/build 11:45: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 pip>=1.5 11:45:50 INFO - Using env: {'DISPLAY': ':0', 11:45:50 INFO - 'HOME': '/home/cltbld', 11:45:50 INFO - 'LANG': 'en_US.UTF-8', 11:45:50 INFO - 'LANGUAGE': 'en_US:en', 11:45:50 INFO - 'LOGNAME': 'cltbld', 11:45:50 INFO - 'MAIL': '/var/mail/cltbld', 11:45:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:50 INFO - 'MOZ_NO_REMOTE': '1', 11:45:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:45:50 INFO - 'NO_EM_RESTART': '1', 11:45:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:45:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:50 INFO - 'PWD': '/builds/slave/test', 11:45:50 INFO - 'SHELL': '/bin/bash', 11:45:50 INFO - 'SHLVL': '1', 11:45:50 INFO - 'TERM': 'linux', 11:45:50 INFO - 'TMOUT': '86400', 11:45:50 INFO - 'USER': 'cltbld', 11:45:50 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', 11:45:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:50 INFO - '_': '/tools/buildbot/bin/python'} 11:45:52 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:45:52 INFO - Downloading/unpacking pip>=1.5 11:45:52 INFO - Running setup.py egg_info for package pip 11:45:52 INFO - warning: no files found matching 'pip/cacert.pem' 11:45:52 INFO - warning: no files found matching '*.html' under directory 'docs' 11:45:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:45:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:45:52 INFO - Installing collected packages: pip 11:45:52 INFO - Found existing installation: pip 1.1 11:45:52 INFO - Uninstalling pip: 11:45:52 INFO - Successfully uninstalled pip 11:45:52 INFO - Running setup.py install for pip 11:45:52 INFO - warning: no files found matching 'pip/cacert.pem' 11:45:52 INFO - warning: no files found matching '*.html' under directory 'docs' 11:45:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:45:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:45:52 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:45:52 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:45:52 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:45:52 INFO - Successfully installed pip 11:45:52 INFO - Cleaning up... 11:45:52 INFO - Return code: 0 11:45:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:45: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:45:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45: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:45:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45: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', '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 0x25c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2790a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2933a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937090>, '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 0x2937d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2931f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', '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:45: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:45: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 psutil>=3.1.1 11:45:52 INFO - Using env: {'DISPLAY': ':0', 11:45:52 INFO - 'HOME': '/home/cltbld', 11:45:52 INFO - 'LANG': 'en_US.UTF-8', 11:45:52 INFO - 'LANGUAGE': 'en_US:en', 11:45:52 INFO - 'LOGNAME': 'cltbld', 11:45:52 INFO - 'MAIL': '/var/mail/cltbld', 11:45:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:52 INFO - 'MOZ_NO_REMOTE': '1', 11:45:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:45:52 INFO - 'NO_EM_RESTART': '1', 11:45:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:45:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:52 INFO - 'PWD': '/builds/slave/test', 11:45:52 INFO - 'SHELL': '/bin/bash', 11:45:52 INFO - 'SHLVL': '1', 11:45:52 INFO - 'TERM': 'linux', 11:45:52 INFO - 'TMOUT': '86400', 11:45:52 INFO - 'USER': 'cltbld', 11:45:52 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', 11:45:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:52 INFO - '_': '/tools/buildbot/bin/python'} 11:45:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45:52 INFO - Downloading/unpacking psutil>=3.1.1 11:45: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:45: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:45: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:45: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:45:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:45:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:45:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:45:53 INFO - Installing collected packages: psutil 11:45:53 INFO - Running setup.py install for psutil 11:45:53 INFO - building 'psutil._psutil_linux' extension 11:45:53 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:45:53 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:45:53 INFO - building 'psutil._psutil_posix' extension 11:45:53 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:45:53 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:45:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:45:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:45:54 INFO - Successfully installed psutil 11:45:54 INFO - Cleaning up... 11:45:54 INFO - Return code: 0 11:45:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:45:54 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:45:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45:54 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:45:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45:54 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 0x25c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2790a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2933a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937090>, '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 0x2937d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2931f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', '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:45:54 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:45:54 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:45:54 INFO - Using env: {'DISPLAY': ':0', 11:45:54 INFO - 'HOME': '/home/cltbld', 11:45:54 INFO - 'LANG': 'en_US.UTF-8', 11:45:54 INFO - 'LANGUAGE': 'en_US:en', 11:45:54 INFO - 'LOGNAME': 'cltbld', 11:45:54 INFO - 'MAIL': '/var/mail/cltbld', 11:45:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:54 INFO - 'MOZ_NO_REMOTE': '1', 11:45:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:45:54 INFO - 'NO_EM_RESTART': '1', 11:45:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:45:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:54 INFO - 'PWD': '/builds/slave/test', 11:45:54 INFO - 'SHELL': '/bin/bash', 11:45:54 INFO - 'SHLVL': '1', 11:45:54 INFO - 'TERM': 'linux', 11:45:54 INFO - 'TMOUT': '86400', 11:45:54 INFO - 'USER': 'cltbld', 11:45:54 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', 11:45:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:54 INFO - '_': '/tools/buildbot/bin/python'} 11:45:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:45: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:45: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:45: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:45: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:45:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:45:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:45:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:45:55 INFO - Installing collected packages: mozsystemmonitor 11:45:55 INFO - Running setup.py install for mozsystemmonitor 11:45:55 INFO - Successfully installed mozsystemmonitor 11:45:55 INFO - Cleaning up... 11:45:55 INFO - Return code: 0 11:45:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:45: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:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45: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:45:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45: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', '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 0x25c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2790a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2933a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937090>, '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 0x2937d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2931f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', '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:45: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:45: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 jsonschema==2.5.1 11:45:55 INFO - Using env: {'DISPLAY': ':0', 11:45:55 INFO - 'HOME': '/home/cltbld', 11:45:55 INFO - 'LANG': 'en_US.UTF-8', 11:45:55 INFO - 'LANGUAGE': 'en_US:en', 11:45:55 INFO - 'LOGNAME': 'cltbld', 11:45:55 INFO - 'MAIL': '/var/mail/cltbld', 11:45:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:55 INFO - 'MOZ_NO_REMOTE': '1', 11:45:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:45:55 INFO - 'NO_EM_RESTART': '1', 11:45:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:45:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:55 INFO - 'PWD': '/builds/slave/test', 11:45:55 INFO - 'SHELL': '/bin/bash', 11:45:55 INFO - 'SHLVL': '1', 11:45:55 INFO - 'TERM': 'linux', 11:45:55 INFO - 'TMOUT': '86400', 11:45:55 INFO - 'USER': 'cltbld', 11:45:55 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', 11:45:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:55 INFO - '_': '/tools/buildbot/bin/python'} 11:45:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45:55 INFO - Downloading/unpacking jsonschema==2.5.1 11:45: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:45: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:45: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:45: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:45:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:45:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:45: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:45: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:45: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:45: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:45:57 INFO - Downloading functools32-3.2.3-2.tar.gz 11:45:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:45:57 INFO - warning: no files found matching '*.txt' 11:45:57 INFO - no previously-included directories found matching 'build' 11:45:57 INFO - no previously-included directories found matching 'dist' 11:45:57 INFO - no previously-included directories found matching '.git*' 11:45:57 INFO - Installing collected packages: jsonschema, functools32 11:45:57 INFO - Running setup.py install for functools32 11:45:57 INFO - warning: no files found matching '*.txt' 11:45:57 INFO - no previously-included directories found matching 'build' 11:45:57 INFO - no previously-included directories found matching 'dist' 11:45:57 INFO - no previously-included directories found matching '.git*' 11:45:57 INFO - Successfully installed jsonschema functools32 11:45:57 INFO - Cleaning up... 11:45:57 INFO - Return code: 0 11:45:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:45:57 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:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45:57 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:45:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45:57 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 0x25c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2790a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2933a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937090>, '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 0x2937d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2931f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', '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:45:57 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:45:57 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:45:57 INFO - Using env: {'DISPLAY': ':0', 11:45:57 INFO - 'HOME': '/home/cltbld', 11:45:57 INFO - 'LANG': 'en_US.UTF-8', 11:45:57 INFO - 'LANGUAGE': 'en_US:en', 11:45:57 INFO - 'LOGNAME': 'cltbld', 11:45:57 INFO - 'MAIL': '/var/mail/cltbld', 11:45:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:57 INFO - 'MOZ_NO_REMOTE': '1', 11:45:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:45:57 INFO - 'NO_EM_RESTART': '1', 11:45:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:45:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:57 INFO - 'PWD': '/builds/slave/test', 11:45:57 INFO - 'SHELL': '/bin/bash', 11:45:57 INFO - 'SHLVL': '1', 11:45:57 INFO - 'TERM': 'linux', 11:45:57 INFO - 'TMOUT': '86400', 11:45:57 INFO - 'USER': 'cltbld', 11:45:57 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', 11:45:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:57 INFO - '_': '/tools/buildbot/bin/python'} 11:45:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:45:57 INFO - Cleaning up... 11:45:57 INFO - Return code: 0 11:45:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:45:57 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:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45:57 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:45:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45:57 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 0x25c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2790a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2933a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937090>, '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 0x2937d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2931f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', '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:45:57 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:45:57 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:45:57 INFO - Using env: {'DISPLAY': ':0', 11:45:57 INFO - 'HOME': '/home/cltbld', 11:45:57 INFO - 'LANG': 'en_US.UTF-8', 11:45:57 INFO - 'LANGUAGE': 'en_US:en', 11:45:57 INFO - 'LOGNAME': 'cltbld', 11:45:57 INFO - 'MAIL': '/var/mail/cltbld', 11:45:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:57 INFO - 'MOZ_NO_REMOTE': '1', 11:45:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:45:57 INFO - 'NO_EM_RESTART': '1', 11:45:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:45:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:57 INFO - 'PWD': '/builds/slave/test', 11:45:57 INFO - 'SHELL': '/bin/bash', 11:45:57 INFO - 'SHLVL': '1', 11:45:57 INFO - 'TERM': 'linux', 11:45:57 INFO - 'TMOUT': '86400', 11:45:57 INFO - 'USER': 'cltbld', 11:45:57 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', 11:45:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:57 INFO - '_': '/tools/buildbot/bin/python'} 11:45:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45:57 INFO - Downloading/unpacking blobuploader==1.2.4 11:45: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:45: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:45: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:45: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:45:58 INFO - Downloading blobuploader-1.2.4.tar.gz 11:45:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:45:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:45:58 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:45:58 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:45:58 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:45:58 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:45:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:45:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:45:59 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:45:59 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:45:59 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:45:59 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:45:59 INFO - Downloading docopt-0.6.1.tar.gz 11:45:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:45:59 INFO - Installing collected packages: blobuploader, requests, docopt 11:45:59 INFO - Running setup.py install for blobuploader 11:45:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:45:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:45:59 INFO - Running setup.py install for requests 11:45:59 INFO - Running setup.py install for docopt 11:46:00 INFO - Successfully installed blobuploader requests docopt 11:46:00 INFO - Cleaning up... 11:46:00 INFO - Return code: 0 11:46:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:46:00 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:46:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:00 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:46:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:00 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 0x25c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2790a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2933a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937090>, '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 0x2937d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2931f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', '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:46:00 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:46:00 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:46:00 INFO - Using env: {'DISPLAY': ':0', 11:46:00 INFO - 'HOME': '/home/cltbld', 11:46:00 INFO - 'LANG': 'en_US.UTF-8', 11:46:00 INFO - 'LANGUAGE': 'en_US:en', 11:46:00 INFO - 'LOGNAME': 'cltbld', 11:46:00 INFO - 'MAIL': '/var/mail/cltbld', 11:46:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:00 INFO - 'MOZ_NO_REMOTE': '1', 11:46:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:46:00 INFO - 'NO_EM_RESTART': '1', 11:46:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:46:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:00 INFO - 'PWD': '/builds/slave/test', 11:46:00 INFO - 'SHELL': '/bin/bash', 11:46:00 INFO - 'SHLVL': '1', 11:46:00 INFO - 'TERM': 'linux', 11:46:00 INFO - 'TMOUT': '86400', 11:46:00 INFO - 'USER': 'cltbld', 11:46:00 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', 11:46:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:00 INFO - '_': '/tools/buildbot/bin/python'} 11:46:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:46:00 INFO - Running setup.py (path:/tmp/pip-Xh47hM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:46:00 INFO - Running setup.py (path:/tmp/pip-WbfIaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:46:00 INFO - Running setup.py (path:/tmp/pip-qxhjpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:46:00 INFO - Running setup.py (path:/tmp/pip-rYbrFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:46:00 INFO - Running setup.py (path:/tmp/pip-0m13NS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:46:00 INFO - Running setup.py (path:/tmp/pip-S3ZeMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:46:00 INFO - Running setup.py (path:/tmp/pip-Ac_nLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:46:00 INFO - Running setup.py (path:/tmp/pip-lFpQIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:46:00 INFO - Running setup.py (path:/tmp/pip-DVuT5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:46:00 INFO - Running setup.py (path:/tmp/pip-D1tmVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:46:00 INFO - Running setup.py (path:/tmp/pip-ErIcQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:46:01 INFO - Running setup.py (path:/tmp/pip-LTJags-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:46:01 INFO - Running setup.py (path:/tmp/pip-WoCFy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:46:01 INFO - Running setup.py (path:/tmp/pip-ecWB49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:46:01 INFO - Running setup.py (path:/tmp/pip-G_UwTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:46:01 INFO - Running setup.py (path:/tmp/pip-GCB90S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:46:01 INFO - Running setup.py (path:/tmp/pip-3lfRMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:46:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:46:01 INFO - Running setup.py install for manifestparser 11:46:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:46:01 INFO - Running setup.py install for mozcrash 11:46:01 INFO - Running setup.py install for mozdebug 11:46:01 INFO - Running setup.py install for mozdevice 11:46:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:46:01 INFO - Running setup.py install for mozfile 11:46:02 INFO - Running setup.py install for mozhttpd 11:46:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:46:02 INFO - Running setup.py install for mozinfo 11:46:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:46:02 INFO - Running setup.py install for mozInstall 11:46:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:46:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:46:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:46:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:46:02 INFO - Running setup.py install for mozleak 11:46:02 INFO - Running setup.py install for mozlog 11:46:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:46:02 INFO - Running setup.py install for moznetwork 11:46:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:46:02 INFO - Running setup.py install for mozprocess 11:46:02 INFO - Running setup.py install for mozprofile 11:46:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:46:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:46:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:46:02 INFO - Running setup.py install for mozrunner 11:46:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:46:02 INFO - Running setup.py install for mozscreenshot 11:46:03 INFO - Running setup.py install for moztest 11:46:03 INFO - Running setup.py install for mozversion 11:46:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:46:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:46:03 INFO - Cleaning up... 11:46:03 INFO - Return code: 0 11:46:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:46:03 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:46:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:03 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:46:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:03 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 0x25c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2790a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2933a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937090>, '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 0x2937d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2931f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', '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:46:03 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:46:03 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:46:03 INFO - Using env: {'DISPLAY': ':0', 11:46:03 INFO - 'HOME': '/home/cltbld', 11:46:03 INFO - 'LANG': 'en_US.UTF-8', 11:46:03 INFO - 'LANGUAGE': 'en_US:en', 11:46:03 INFO - 'LOGNAME': 'cltbld', 11:46:03 INFO - 'MAIL': '/var/mail/cltbld', 11:46:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:03 INFO - 'MOZ_NO_REMOTE': '1', 11:46:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:46:03 INFO - 'NO_EM_RESTART': '1', 11:46:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:46:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:03 INFO - 'PWD': '/builds/slave/test', 11:46:03 INFO - 'SHELL': '/bin/bash', 11:46:03 INFO - 'SHLVL': '1', 11:46:03 INFO - 'TERM': 'linux', 11:46:03 INFO - 'TMOUT': '86400', 11:46:03 INFO - 'USER': 'cltbld', 11:46:03 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', 11:46:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:03 INFO - '_': '/tools/buildbot/bin/python'} 11:46:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:46:03 INFO - Running setup.py (path:/tmp/pip-Yr7NWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:46:03 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:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:46:03 INFO - Running setup.py (path:/tmp/pip-7T2vvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:46:03 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:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:46:03 INFO - Running setup.py (path:/tmp/pip-gfPj8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:46:03 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:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:46:03 INFO - Running setup.py (path:/tmp/pip-nX64Zk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:46:03 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:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:46:03 INFO - Running setup.py (path:/tmp/pip-LNzvHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:46:03 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:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:46:03 INFO - Running setup.py (path:/tmp/pip-zBYPxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:46:03 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:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:46:03 INFO - Running setup.py (path:/tmp/pip-PcOyrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:46:04 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:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:46:04 INFO - Running setup.py (path:/tmp/pip-24lmNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:46:04 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:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:46:04 INFO - Running setup.py (path:/tmp/pip-liqcw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:46:04 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:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:46:04 INFO - Running setup.py (path:/tmp/pip-_vPYxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:46:04 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:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:46:04 INFO - Running setup.py (path:/tmp/pip-3V64_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:46:04 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:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:46:04 INFO - Running setup.py (path:/tmp/pip-ZIGi8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:46:04 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:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:46:04 INFO - Running setup.py (path:/tmp/pip-Cw2shg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:46:04 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:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:46:04 INFO - Running setup.py (path:/tmp/pip-A1CmpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:46:04 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:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:46:04 INFO - Running setup.py (path:/tmp/pip-Ms7Zcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:46:04 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:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:46:04 INFO - Running setup.py (path:/tmp/pip-UPBn77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:46:04 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:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:46:04 INFO - Running setup.py (path:/tmp/pip-XpgBqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:46:04 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:46:04 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:46:04 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:46:04 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:46:04 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:46:04 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:46:04 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:46:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:46: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:46: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:46: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:46: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:46:05 INFO - Downloading blessings-1.6.tar.gz 11:46:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:46:05 INFO - Installing collected packages: blessings 11:46:05 INFO - Running setup.py install for blessings 11:46:06 INFO - Successfully installed blessings 11:46:06 INFO - Cleaning up... 11:46:06 INFO - Return code: 0 11:46:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:46:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:46:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:46:06 INFO - Reading from file tmpfile_stdout 11:46:06 INFO - Current package versions: 11:46:06 INFO - argparse == 1.2.1 11:46:06 INFO - blessings == 1.6 11:46:06 INFO - blobuploader == 1.2.4 11:46:06 INFO - docopt == 0.6.1 11:46:06 INFO - functools32 == 3.2.3-2 11:46:06 INFO - jsonschema == 2.5.1 11:46:06 INFO - manifestparser == 1.1 11:46:06 INFO - mozInstall == 1.13 11:46:06 INFO - mozcrash == 1.0 11:46:06 INFO - mozdebug == 0.1 11:46:06 INFO - mozdevice == 0.51 11:46:06 INFO - mozfile == 1.2 11:46:06 INFO - mozhttpd == 0.7 11:46:06 INFO - mozinfo == 0.10 11:46:06 INFO - mozleak == 0.1 11:46:06 INFO - mozlog == 3.5 11:46:06 INFO - moznetwork == 0.27 11:46:06 INFO - mozprocess == 0.25 11:46:06 INFO - mozprofile == 0.28 11:46:06 INFO - mozrunner == 6.13 11:46:06 INFO - mozscreenshot == 0.1 11:46:06 INFO - mozsystemmonitor == 0.3 11:46:06 INFO - moztest == 0.8 11:46:06 INFO - mozversion == 1.4 11:46:06 INFO - psutil == 3.1.1 11:46:06 INFO - requests == 1.2.3 11:46:06 INFO - wsgiref == 0.1.2 11:46:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:46:06 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:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:06 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:46:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:06 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 0x25c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2790a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2933a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937090>, '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 0x2937d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2931f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', '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:46:06 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:46:06 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:46:06 INFO - Using env: {'DISPLAY': ':0', 11:46:06 INFO - 'HOME': '/home/cltbld', 11:46:06 INFO - 'LANG': 'en_US.UTF-8', 11:46:06 INFO - 'LANGUAGE': 'en_US:en', 11:46:06 INFO - 'LOGNAME': 'cltbld', 11:46:06 INFO - 'MAIL': '/var/mail/cltbld', 11:46:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:06 INFO - 'MOZ_NO_REMOTE': '1', 11:46:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:46:06 INFO - 'NO_EM_RESTART': '1', 11:46:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:46:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:06 INFO - 'PWD': '/builds/slave/test', 11:46:06 INFO - 'SHELL': '/bin/bash', 11:46:06 INFO - 'SHLVL': '1', 11:46:06 INFO - 'TERM': 'linux', 11:46:06 INFO - 'TMOUT': '86400', 11:46:06 INFO - 'USER': 'cltbld', 11:46:06 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', 11:46:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:06 INFO - '_': '/tools/buildbot/bin/python'} 11:46:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:06 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:46:06 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:46:06 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:46:06 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:46:06 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:46:06 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:46:06 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:46:06 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:46:06 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:46:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:46:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:46:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:46:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:46:07 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:46:07 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:46:07 INFO - Installing collected packages: simplejson 11:46:07 INFO - Running setup.py install for simplejson 11:46:07 INFO - building 'simplejson._speedups' extension 11:46:07 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:46:08 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:46:08 INFO - Successfully installed simplejson 11:46:08 INFO - Cleaning up... 11:46:08 INFO - Return code: 0 11:46:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:46:08 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:46:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:08 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:46:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x25c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2790a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2933a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2937090>, '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 0x2937d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2931f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', '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:46:08 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:46:08 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:46:08 INFO - Using env: {'DISPLAY': ':0', 11:46:08 INFO - 'HOME': '/home/cltbld', 11:46:08 INFO - 'LANG': 'en_US.UTF-8', 11:46:08 INFO - 'LANGUAGE': 'en_US:en', 11:46:08 INFO - 'LOGNAME': 'cltbld', 11:46:08 INFO - 'MAIL': '/var/mail/cltbld', 11:46:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:08 INFO - 'MOZ_NO_REMOTE': '1', 11:46:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:46:08 INFO - 'NO_EM_RESTART': '1', 11:46:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:46:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:08 INFO - 'PWD': '/builds/slave/test', 11:46:08 INFO - 'SHELL': '/bin/bash', 11:46:08 INFO - 'SHLVL': '1', 11:46:08 INFO - 'TERM': 'linux', 11:46:08 INFO - 'TMOUT': '86400', 11:46:08 INFO - 'USER': 'cltbld', 11:46:08 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', 11:46:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:08 INFO - '_': '/tools/buildbot/bin/python'} 11:46:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:46:08 INFO - Cleaning up... 11:46:08 INFO - Return code: 0 11:46:08 INFO - Running post-action listener: _resource_record_post_action 11:46:08 INFO - Running post-action listener: _start_resource_monitoring 11:46:08 INFO - Starting resource monitoring. 11:46:08 INFO - [mozharness: 2017-09-02 18:46:08.972358Z] Finished create-virtualenv step (success) 11:46:08 INFO - [mozharness: 2017-09-02 18:46:08.972872Z] Running install step. 11:46:08 INFO - Running pre-action listener: _resource_record_pre_action 11:46:08 INFO - Running main action method: install 11:46:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:46:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:46:09 INFO - Reading from file tmpfile_stdout 11:46:09 INFO - Detecting whether we're running mozinstall >=1.0... 11:46:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:46:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:46:09 INFO - Reading from file tmpfile_stdout 11:46:09 INFO - Output received: 11:46:09 INFO - Usage: mozinstall [options] installer 11:46:09 INFO - Options: 11:46:09 INFO - -h, --help show this help message and exit 11:46:09 INFO - -d DEST, --destination=DEST 11:46:09 INFO - Directory to install application into. [default: 11:46:09 INFO - "/builds/slave/test"] 11:46:09 INFO - --app=APP Application being installed. [default: firefox] 11:46:09 INFO - mkdir: /builds/slave/test/build/application 11:46:09 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:46:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:46:25 INFO - Reading from file tmpfile_stdout 11:46:25 INFO - Output received: 11:46:25 INFO - /builds/slave/test/build/application/firefox/firefox 11:46:25 INFO - Running post-action listener: _resource_record_post_action 11:46:25 INFO - [mozharness: 2017-09-02 18:46:25.660591Z] Finished install step (success) 11:46:25 INFO - [mozharness: 2017-09-02 18:46:25.660840Z] Running setup-mitmproxy step. 11:46:25 INFO - Running pre-action listener: _resource_record_pre_action 11:46:25 INFO - Running main action method: setup_mitmproxy 11:46:25 INFO - Skipping: mitmproxy is not required 11:46:25 INFO - Running post-action listener: _resource_record_post_action 11:46:25 INFO - [mozharness: 2017-09-02 18:46:25.661964Z] Finished setup-mitmproxy step (success) 11:46:25 INFO - [mozharness: 2017-09-02 18:46:25.662184Z] Running run-tests step. 11:46:25 INFO - Running pre-action listener: _resource_record_pre_action 11:46:25 INFO - Running pre-action listener: _set_gcov_prefix 11:46:25 INFO - Running main action method: run_tests 11:46:25 WARNING - Try message not found. 11:46:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:46:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:46:25 INFO - Python 2.7.3 11:46:25 INFO - Return code: 0 11:46:25 INFO - grabbing minidump binary from tooltool 11:46: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:46: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 0x2937090>, '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 0x2937d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2931f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:46: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:46: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:46: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:46:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:46:25 INFO - Return code: 0 11:46:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:46:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:46:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:46:25 INFO - ENV: RUST_BACKTRACE is now full 11:46:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:46:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:46:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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:46:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-069 --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:46:25 INFO - Using env: {'DISPLAY': ':0', 11:46:25 INFO - 'HOME': '/home/cltbld', 11:46:25 INFO - 'LANG': 'en_US.UTF-8', 11:46:25 INFO - 'LANGUAGE': 'en_US:en', 11:46:25 INFO - 'LOGNAME': 'cltbld', 11:46:25 INFO - 'MAIL': '/var/mail/cltbld', 11:46:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:46:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:46:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:25 INFO - 'MOZ_NO_REMOTE': '1', 11:46:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:46:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:46:25 INFO - 'NO_EM_RESTART': '1', 11:46:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:46:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:25 INFO - 'PWD': '/builds/slave/test', 11:46:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:46:25 INFO - 'RUST_BACKTRACE': 'full', 11:46:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:46:25 INFO - 'SHELL': '/bin/bash', 11:46:25 INFO - 'SHLVL': '1', 11:46:25 INFO - 'TERM': 'linux', 11:46:25 INFO - 'TMOUT': '86400', 11:46:25 INFO - 'USER': 'cltbld', 11:46:25 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', 11:46:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:25 INFO - '_': '/tools/buildbot/bin/python'} 11:46:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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:46:26 INFO - mozversion application_buildid: 20170902172544 11:46:26 INFO - mozversion application_changeset: 59611e1583e7411bab615d29109894e4c657af33 11:46:26 INFO - mozversion application_display_name: Nightly 11:46:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:46:26 INFO - mozversion application_name: Firefox 11:46:26 INFO - mozversion application_remotingname: firefox 11:46:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:46:26 INFO - mozversion application_vendor: Mozilla 11:46:26 INFO - mozversion application_version: 57.0a1 11:46:26 INFO - mozversion platform_buildid: 20170902172544 11:46:26 INFO - mozversion platform_changeset: 59611e1583e7411bab615d29109894e4c657af33 11:46:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:46:26 INFO - mozversion platform_version: 57.0a1 11:46:26 INFO - using testdate: 1504377986 11:46:26 INFO - actual date: 1504377986 11:46:26 INFO - starting webserver on 'localhost:36257' 11:46:26 INFO - SUITE-START | Running 2 tests 11:46:26 INFO - TEST-START | dromaeo_css 11:46:26 INFO - Initialising browser for dromaeo_css test... 11:46:26 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36257/getInfo.html -profile /tmp/tmpFF46s7/profile 11:46:26 INFO - TEST-INFO | started process 5595 (/builds/slave/test/build/application/firefox/firefox http://localhost:36257/getInfo.html) 11:46:31 INFO - TEST-INFO | 5595: exit 0 11:46:31 INFO - Browser initialized. 11:46:31 INFO - Running cycle 1/1 for dromaeo_css test... 11:46:31 INFO - Using env: {'DISPLAY': ':0', 11:46:31 INFO - 'HOME': '/home/cltbld', 11:46:31 INFO - 'JSGC_DISABLE_POISONING': '1', 11:46:31 INFO - 'LANG': 'en_US.UTF-8', 11:46:31 INFO - 'LANGUAGE': 'en_US:en', 11:46:31 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:46:31 INFO - 'LOGNAME': 'cltbld', 11:46:31 INFO - 'MAIL': '/var/mail/cltbld', 11:46:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:46:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:46:31 INFO - 'MOZ_CRASHREPORTER': '1', 11:46:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:31 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:46:31 INFO - 'MOZ_NO_REMOTE': '1', 11:46:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:46:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:46:31 INFO - 'NO_EM_RESTART': '1', 11:46:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:46:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:31 INFO - 'PWD': '/builds/slave/test', 11:46:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:46:31 INFO - 'RUST_BACKTRACE': 'full', 11:46:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:46:31 INFO - 'SHELL': '/bin/bash', 11:46:31 INFO - 'SHLVL': '1', 11:46:31 INFO - 'TERM': 'linux', 11:46:31 INFO - 'TMOUT': '86400', 11:46:31 INFO - 'USER': 'cltbld', 11:46:31 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', 11:46:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:31 INFO - '_': '/tools/buildbot/bin/python'} 11:46:31 INFO - TEST-INFO | started process 5783 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFF46s7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:46:32 INFO - PID 5783 | 11:46:32 INFO - PID 5783 | (/builds/slave/test/build/application/firefox/firefox:5836): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:46:32 INFO - PID 5783 | 11:46:42 INFO - PID 5783 | RSS: Main: 157978624 11:46:42 INFO - PID 5783 | 11:49:41 INFO - PID 5783 | Cycle 1(1): loaded http://localhost:36257/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36257/tests/dromaeo/cssquery-ext.html) 11:49:43 INFO - PID 5783 | RSS: Main: 164368384 11:49:43 INFO - PID 5783 | 11:52:44 INFO - PID 5783 | Cycle 1(1): loaded http://localhost:36257/tests/dromaeo/cssquery-ext.html (next: http://localhost:36257/tests/dromaeo/cssquery-jquery.html) 11:52:45 INFO - PID 5783 | RSS: Main: 160346112 11:52:45 INFO - PID 5783 | 11:55:46 INFO - PID 5783 | Cycle 1(1): loaded http://localhost:36257/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36257/tests/dromaeo/cssquery-mootools.html) 11:55:47 INFO - PID 5783 | RSS: Main: 162795520 11:55:47 INFO - PID 5783 | 11:58:49 INFO - PID 5783 | Cycle 1(1): loaded http://localhost:36257/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36257/tests/dromaeo/cssquery-prototype.html) 11:58:49 INFO - PID 5783 | RSS: Main: 160940032 11:58:49 INFO - PID 5783 | 12:01:53 INFO - PID 5783 | Cycle 1(1): loaded http://localhost:36257/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36257/tests/dromaeo/cssquery-yui.html) 12:01:53 INFO - PID 5783 | RSS: Main: 164765696 12:01:53 INFO - PID 5783 | 12:04:55 INFO - PID 5783 | Cycle 1(1): loaded http://localhost:36257/tests/dromaeo/cssquery-yui.html (next: http://localhost:36257/tests/dromaeo/cssquery-dojo.html) 12:04:55 INFO - PID 5783 | RSS: Main: 165478400 12:04:55 INFO - PID 5783 | 12:04:55 INFO - PID 5783 | __start_tp_report 12:04:55 INFO - PID 5783 | _x_x_mozilla_page_load 12:04:55 INFO - PID 5783 | _x_x_mozilla_page_load_details 12:04:55 INFO - PID 5783 | |i|pagename|runs| 12:04:55 INFO - PID 5783 | |0;dojo.html;3826.789526576039;3879.1659793144468;3880.592537783532;3896.2791883501554;3923.333033384324;6672.399484046435;6708.262091169968;6757.324267573253;6799.592024478528;6804.659767011648;6700.9664951675295;6746.257911629729;6761.526693131483;6785.932140678592;6820.863582728342;5989.520838332923;6024.9698751506185;6026.87946241076;6045.818625441244;6052.273727152748;5997.160397544348;6088.6042407243385;6088.604240724361;6095.786647467339;6140.140380346755;6131.601445906002;6151.292601350835;6156.076588511737;6183.041628547559;6186.3813618638005;6054.334023257438;6075.210222671047;6109.20580324555;6145.661988590626;6215.34738852423;5806.622569532967;5921.230240068807;5942.435468630516;5954.255718035246;6002.939970600281;6745.021971814109;6757.121574195372;6822.113125293729;6825.35159159879;6892.104026476526;7159.3914517265575;7261.673224704901;7273.63631818407;7370.631468426557;7384;7265.273472652692;7334.339909408125;7353;7354.227806080392;7367.484276100675;6774.0854984576945;6780.389764921131;6780.457563394916;6795;6803.9453884647955;7433.293837085468;7485.139208990927;7504.699812007459;7510;7526.5107767994905;7195.964020179866;7197.532160409557;7203.819904502429;7208.27917208275;7217.314355136254;7282.762468368264;7313.780586582411;7330.047093877762;7372.815679608053;7436.96281518589;7150.141982961974;7161.606111663908;7254.455915806336;7260.564366138048;7275.854482910418;7109.502334836512;7223.711051557984;7293.9635301823155;7429.4799364044;7433.9256607433235;6302.432781049728;6346.333634968354;6358.586691865014;6367.586106903036;6368.77709280173;5364.597655175878;5450.427705090988;5474.178873169057;5475.397706252309;5527.447255274521;6183.443490085824;6301.558890877686;6317.810465686037;6322.114903913456;6322.841928951721;7266.091738532684;7281.381082607932;7286.70853165878;7292.906064090429;7295.744648937261;5559.555235581144;6218.81343559694;6303.936960630427;6309.274433440121;6315.273743519462;6552.672366381662;6582.308857569887;6612.305707900698;6640.369164929324;6642.435392991553;7009.12385951756;7086.149662040485;7086.362227399559;7090.787276381717;7109.182444019004;7191.496595238283;7197.532160409557;7204.603746793977;7300.233475484997;7311.780646580611;6986.895196572157;7046.753363632249;7072.25741297177;7082.150141982995;7091.716331346895;7207.783766486804;7208.387287080657;7219.711211551479;7219.747308844166;7226.060612120601;7149.9285007149265;7220.0181874506425;7302.233265507045;7314.451416143704;7318.1584117825005;6958.095447591781;6995.895061573979;7024.438044956289;7052.753153639598;7066.328698773608;6947.444204463732;6972.58164510131;7047.048648432396;7054.435645148274;7058.717651294096;6874.072000279898;6893.7931862044215;6960.408365289007;6963.547369420971;7008.649567521706;7053.506254562131;7060.046893669494;7106.822329441598;7134.607596582237;7143.607101609461;6826.522143450109;6874.587524748531;6912.654367281515;6920.757773477904;6921.1002569667635 12:04:55 INFO - PID 5783 | |1;ext.html;884.4295429448006;902.539704750577;949.5204921514636;957.4351132831628;959.4003747657714;41583.58416415839;42529.14941701168;42652.57347426525;42729;42730;39801.20397592046;39930;40195.799021004925;40206;40244.19511609766;6941.201761797395;6974.651267436633;6981.616011119387;6985.930140698579;7007.089078419798;7167.820804479878;7237;7238.05905232319;7247.855042899138;7252.166000909884;7809.101953275361;7986.720464783733;8080.595970201496;8084.878726819103;8088.231617996281;7955.443118981673;8019.558924259163;8022.879656805123;8023.358131349478;8024.799380015488;7549.698012079511;7725.845483090363;7732.845343093107;7737.729179478748;7913.564753938531;37067;37207.44188837169;37383.69157079516;37416;37469.437958430644;3610.0433385152955;3654.872079477232;3661.8901432956786;3671.1739858531882;3687.2625474905126;31375.215619609466;31406;31492.21269468259;31583.684163158534;31614.209644759067;5272.868178295535;5301.6818990860675;5318.441563635801;5343.171808369709;5360.249565060894;201454;202050;202402;203375;203794;47614;47858;47907.281390779164;48068.759656201495;48080;49710;49876.75061624741;49895.50104498908;49930.251046234334;50142.74928625333;15208.695826083416;15523.844761552466;15530.1458419788;15530.456434024754;15545.611359715871;12524.499020039279;12547.24716517012;12549.435275412628;12555.183913045623;12605.865472107555;13988.370523326475;14220.644483887778;14229.50196743109;14264.286785660883;14375.424983000563;5664.461876121762;5738.598298119092;5774.971125144348;5788.421157884178;5936.554758393051;1088.8530048443517;1097.4567589043475;1101.867775866885;1121.6522877907876;1122.5958654884234;25501.744982549935;25591;25671.74328256731;25693;26010.34974125662;1209.1173443386376;1213.2477863724548;1216.6920560397607;1232.550119206501;1238.5603110895502;13256.867431325756;13407.32963351848;13621.863781362059;13650.249236292098;13830.308484575933;283260;283854;285741;285988;286902;54362.45637543574;54688.17967730487;54970.72514637401;59764.40235597675;59887.10169347463;1446.985530144685;1481.7332880081474;1486.561464368014;1494.2006026775405;1502.4440956846038;29461.85269073641;29741;29928.25179370533;30187.396252074395;30707.385852282383;40574;40579.1884162321;40695.79652101839;40737;40774.388384174854;229058;229682;229969;230976;230979;34004.14989625279;34114.82942585271;34150.82924585361;34155.316893662486;34170.8291458551;42449.57550424456;43056;43255.78372108119;43476;43587.12825743404;32816.1795955103;33011.33977320488;33024.834875825465;33073.173170670925;33202.667973320924;32653.836730816194;33109.05508865538;33647.663523364456;33778.32443351168;33797;37423.06442338963;37472.81263593664;37799.81100094482;37832.243355133294;37850.43224351692;31472.213194670316;32085.67914320827;32437.675623243467;32438.513422299155;32570.34859302848;24188.63717044281;24539.50920981606;24782.256532304058;25303.114390996227;25356.746432535438 12:04:55 INFO - PID 5783 | |2;jquery.html;595.4521839907285;596.829903477509;599.7331187621508;602.3494625804132;636.5798572941858;22361.888190559046;22654.54690906181;23097.76902230977;23144.65283020756;23148;26864.59703104455;27159.592606110924;27207.86396068017;27234.182974510746;27384.315392115208;6455.289918109004;6594.7032383542855;6600.372964568358;6614.107095542088;6629.535932484728;6445.709943052574;6560.671966401685;6611.669416529178;6675.2323482799375;6702.932970670279;6663.100481435017;6669.5331326807145;6672.666366681671;6676.399124078832;6680.93319066808;6657.633830139341;6661.300563440817;6662.533622646417;6669.43309818666;6671.866562668743;6241.563090583661;6243.968780156116;6247.750089996394;6248.218972628421;6248.843778905518;29324.706752932412;29358;30060;30161.396772064654;30187;2246.61807492725;2281.2129815213725;2281.5779080870093;2282.657601359793;2286.919957801469;20202.595948081103;20314.796852031584;20962.685559716618;21236;21269.8936505318;2490.663760392342;2494.3764058985253;2547.4140947581973;2601.1676263595655;2635.0645520840208;164064.17967910203;171865;172308;172401;172448.1377593104;15852.841471585367;16278.837211627731;16323.999999999762;16617.839680841655;16827.74758378625;26031;26056.8697156513;26090.86954565215;26239.344016399362;26268.605970910452;9458.763530911645;9637.951810240904;9763.97478264772;9831.066048725359;9968;8379.95810020946;8411.873821892677;8471.364647651451;8507.979042514939;8569.871451928226;9790.265730070274;9925.2059835213;10249.412705552997;10259.17926565873;10278.845817312746;2906.651201855791;2986.3728616990243;3013.668496465387;3024.9243768905953;3110.3623757129735;1021.8262895307815;1055.9736006599742;1062.888396718349;1062.9946850265699;1080.6811990462832;21070.578588428147;21467.78532214689;21670.34988950334;21683;22173.33479995603;1119.2109562758235;1125.150511363915;1127.1602656021316;1128.0862501373913;1139.065965907964;11862.584809531625;11980;11982.460789264329;12041.217320874115;12051.397430128458;544152;564754;573007;594127;596527;126743;134060.3296983509;136038;136385;141266;1173.1142987044727;1228.6375519221672;1256.221142891395;1293.1336004876566;1316.1313533068128;22414.215502457337;22468.438289042904;22581.209657661904;22787.544249114595;22978.655320169877;63735.68132159495;65084.674576626814;65541.36146596374;65729.34270657232;66758.33241667715;81957.59021205094;82872.58563707143;83801;86923.56538217269;86979.56510217408;15964.600884977504;15998.360065597246;16202.594935126244;16266.51200463988;16279.67440651204;25307.240782776546;25420.237392878244;25722.871385642953;25753.484930301664;26491.470170596866;20500.69248961237;20684;20740.896295518425;20944.47638809042;21214.36356909295;17599;17893.37373191932;18182.81817181811;18299.451016469528;18630.254789808798;21083.894580526998;21176;21489.57020859543;21521.139154434273;21568.245111421023;15674.608134796264;16043.598910027342;16182.838171618605;16210.837891620933;16317;20720.171193152106;20744.58510829805;20792.27227047046;20938.05778739991;20997 12:04:55 INFO - PID 5783 | |3;mootools.html;1390.9513167039154;1440.2222799688168;1460.6713489464878;1465.2893346726835;1468.1411374346008;16692.91653541732;17163.313467461317;17247.568810779736;17420;17574;17789.199486023128;18031.188596513155;18056.729149062743;18067.367642132496;18073.7288940666;2693.0843513205505;2710.7695845853127;2717.6738791345065;2718.4563087382503;2718.5786203138546;2581.8709064546792;2621.5543357629153;2645.8412495250345;2681.8793154308105;2685.6508653875067;2552.2981180175507;2565.8332208406487;2588.8576128312934;2597.3766296088897;2655.3361659585103;2586.314626623946;2598.727133650968;2604.4530648563823;2605.557055300594;2605.6091586262023;2620.489004644095;2631.2369412870244;2646.735326467357;2647.0470630572977;2647.6293318935363;16694.4156954506;16886.915565422216;16891.239894204668;16911.154442277835;16940.152992350457;8426.452280601741;8668.566571671392;8679.305655547541;8680.392372533925;8687.869681954775;5105.974470127626;5856;5886.882262354772;5926.4073592640825;5961.463468287831;2156.2807135978696;2198.1427243375097;2199.164317559333;2210.9005094770773;2227.3318004598555;30196.24509387261;30342;30385.69614303851;30402.69597304021;30408.54387184194;6923.273056329113;7074.151101867808;7080.468964827659;7266.600336981517;7290.4896657233485;19838.305659301855;19866;19868.10593523295;19878.70181947272;19879.701804472945;8438.53588052663;8540.359473039547;8544.2310192083;8553.657853685783;8635;7809.531428114331;7907.288344048948;7946.364290856717;7969.760907172794;7972.202779722097;5024.547790698854;5488.231647569343;5549.500544950893;5605.103183490622;5642.351129620064;1477.4976507987335;1541.799566056428;1558.4311726219848;1562.898411603242;1585.8493443122884;942.0814705661925;956.4596003258137;962.4417837654144;963.5904740485265;969.898160693117;8634.611442485104;8881.24509416694;8918.420302680304;8952.23905968;8959.969603495681;1036.9688909332733;1047.4972013433442;1056.4295280548417;1076.6016573867719;1087.472575800736;6697.430718388894;6919.515633905679;6934.167899851949;6976.337247961435;7043.154821421463;24685.136020239206;24960.126395576066;25096.498070038862;25186.496270074495;25207;23227.53544929092;24522.386940326276;25067.87466062658;25213.62179567308;25251.116210932898;12857.292848893401;13125.60623181306;13168.73662526744;13187.340632968313;13235.139715918434;9857.802843942938;10310.793784124426;10583.364998100138;10871.945640272064;10908.290961087503;20733.481662958548;20796.1681532737;21242.893785530974;21472.67790983105;21560.137594496045;21147.365579032652;21323.46691332667;21324.680129798377;21581.568368632852;21746.782532174475;15466.84533154699;15945.123018233644;15973.680526389175;15976.440824571084;15981.360745570048;15715.44995925183;15744.448944286894;15772.447964321194;15905;15917.363305467652;11532.077433805107;11703.88296117062;11775;12059.698507537381;12171.087168462223;11865.940670296593;11872.881271187178;12104.394780261127;12212.75574488523;12469.688257793265;11247.325160490396;11407.315561066363;11411.486483108447;11417.885821141683;11418.885811141783;8238.176182381714;8308.5430301334;8320.791980200544;8338.874916876373;8352.958235208785;8631.784205394915;8723.52020638871;8730.301575874042;8757.605907734294;8858.069902660129 12:04:55 INFO - PID 5783 | |4;prototype.html;535.357570914902;552.8838943821797;575.1401654526481;575.5942060847103;582.8659408336083;6626.867462650756;6765.391114799667;6799.464278575144;6815.727370905164;6862.931370686291;6617.3713497217805;6646.900296495556;6683.832904177386;6692.39768420842;6727.966360168192;5154.07226699195;5184.688918664892;5193.221016847466;5256.316678831747;5336.946630533703;5181.637285390024;5182.030828285247;5210.218467229908;5268.789248430058;5281.419043905167;5250.692063999675;5289.646107482172;5293.920591191135;5306.442823503535;5329.2539044533605;5226.491284183613;5230.181967163953;5248.160294352904;5255.395629502618;5298.36419629643;4737.384140061805;4768.976155119202;4773.4510531289;4798.664093513456;4836.274558816206;6673.799786006428;6735.427488663469;6741.067635578539;6745.831354216135;6839.692213850389;6188.226471691039;6207.689615519206;6217.233331167632;6241.781537646206;6267;7100.502964792467;7140.607266600335;7165.176004759467;7193;7206.49554531183;5244.449332820075;5277.525022747933;5312.362516498007;5318.388385335658;5333.866653333697;7147.714091436336;7228.783136505966;7275.43503683202;7323.7070517179245;7563.394928405769;5400.135978243462;5410.97294513525;5418.564080518666;5432.674039557639;5465.152901300305;5507.5593952483705;5507.862303442366;5540.612157149041;5576.414476479992;5579.163125531203;6154.7230374633245;6159.384061593805;6164.938350616437;6201.1938448002375;6240.251169859646;7241.755055932677;7256.455765817479;7257.528260663145;7261.237570055174;7264.564126152448;1920.5225259415197;1927.441042097804;1935.7224232006809;1947.7467929169118;1949.483386902472;1291.560869304441;1332.4137379552965;1344.623505418465;1349.6963183283683;1351.2703140304322;6104.511639068864;6241.500679945684;6257.78097766576;6281.874362512815;6312.916737476714;7132.536385134949;7170.354668079898;7199.928000719926;7258.201597824235;7267.63661816907;6253.779303658619;6263.3736626337;6285.8742825143245;6297.307296197415;6325.367463253638;3798.442638518194;3800.8289626966853;3844.308024555551;3853.556840963325;3859.1895701902354;7040.401565866959;7077.681504332318;7078.186008608973;7089;7150.678219480136;7347.448941329421;7436.628168591549;7489.988851504977;7492.70029198826;7548.169701332849;6984.406325462291;7014.298570142945;7048;7086.326798954091;7163.53437026602;7288.303327768547;7293.9635301823155;7313;7318.890216646648;7382.298681625237;7151.60666163366;7212.629600375912;7282.817929551804;7308.378787802883;7343.2289609590225;7020.122484689414;7176.138863336433;7204.279572042754;7204.387627051759;7224.927750722425;5475.09660905962;5483.313176543385;5484.61607687458;5488.917666235089;5489.34127904638;7143.678534465857;7333.559986400833;7339.522931009467;7456.813579660552;7471.1322169457635;6666.766573254023;6857.485688573277;6934.791956241321;6943.409810166192;7016.5790052597;6901.62041087745;6961.477889158435;6963.61700106499;6987.301269872972;7039.648017599202;6887.795905310252;6950.687219075255;6956.652167391509;7004.754833580801;7072.752453664097;7088.787336379917;7094.361507464353;7124.25195354501;7146.499745018007;7165.7850264492145;6830.624315662686;6891.518323560458;6918;6967.512274140964;6975.441964642918 12:04:55 INFO - PID 5783 | |5;yui.html;966.3235734985511;986.6891929042356;1014.1278500489582;1020.0207800511508;1030.7732298894243;19114.71327930082;19568.11943462544;19592.90203548982;19593.216271349145;22954.196603118893;23076.769232307674;23183.76816231837;23319.183828566005;23413.5317293654;23428.180013699523;1043.5356266461429;1077.3212875888219;1078.1967434261446;1078.557791305565;1079.854219680341;1017.409902256693;1074.2802322443981;1093.6937657455926;1097.4841824342545;1097.7091070866227;1136.5567428702811;1142.0121594820503;1142.177632104883;1142.4573327669373;1142.5829572206126;1135.2961164078242;1136.511300140939;1137.2607804926781;1137.2607804926822;1140.7832511822728;829.1708291708292;834.7203686764941;835.4653022065883;835.8161204534993;836.1304243586662;20980.649934266665;21427.892860535598;21555.67666485004;21778.564428711496;21977.780222197733;22509.324720258417;22955.311340659806;23040.42398940023;23338.299851004496;23416.882915585484;18293.451196464128;18349.7247541287;18405.171767270505;18630.53423664419;18707.90646046761;4062.045419326456;4073.8370465181356;4081.1837632473325;4084.326086195774;4095.3242714952;149295;150225;150607;151330;152308.2384588081;17350.739738903925;17688.734668979974;17864.55338616519;17869.46391608254;17877.821221787617;32585.674143258264;33337.3332533348;33504.497432538534;34088.65911340855;34321;8255.628496717662;8540.231379175904;9657.082577155159;9708;9979.60081596728;4798.616110711135;4879.902401951941;4883.975580122076;4888.633352498577;4895.975520122448;5936.673482958478;6044.788432404845;6045.33501314855;6077.604955677867;6111.5110791137495;1252.7056141806668;1276.5340650662627;1287.6973911130876;1291.3607764156616;1294.6180876641224;745.932866042048;760.2511526146741;761.7219714804056;775.747881938361;780.2509590792789;13924.58226253214;13990.580282591334;15158.31787569562;15220.771688424682;15538.611534711497;756.0738095832561;764.7820371194183;767.6583920155477;771.733751855609;776.1850057439666;6590.637514936724;6692.498062645321;6736.360045795642;6755.493337999571;6811.829704257334;130459.09299678578;139252;141328;144459;145617.27191363976;121010;123490;123649;124837;125796;105043;106508;107991.46004270086;108764.45617772019;109317.45341273243;14535.85464145345;14901.403943842124;15118.848811511743;15227.390904364145;15360.692786144438;50813;51532.74233628808;52627.73686131545;53134.46865531295;53278.46721532735;67500;68038;68167.65916170388;68788.3121168782;70695.29304706887;20122.09450574757;20560.794392055886;20588.69116963217;20658.380248592566;20712.896435517727;23341;23524.52950941006;23614;23626.29121126369;23803.047878084682;21838.45403864916;22167.77832221657;22314.442138946008;22449.775502245422;22584.20955266558;21561.353159405244;21815.672764908857;21887;22440.43898902475;22482;20554.691679625117;20660.690089648368;21098.367048988555;21128.471788205417;21477.67783483218;14700.485483008044;15051.924740376227;15277.694446111238;15508.612284692974;15832.366705331659;16133;16400.261988210797;16671.91664041672;16808.747868781735;16822.242999064827 12:04:55 INFO - PID 5783 | __end_tp_report 12:04:55 INFO - PID 5783 | __start_cc_report 12:04:55 INFO - PID 5783 | _x_x_mozilla_cycle_collect,333 12:04:55 INFO - PID 5783 | __end_cc_report 12:04:55 INFO - PID 5783 | __startTimestamp1504379095534__endTimestamp 12:04:55 INFO - PID 5783 | 12:04:55 INFO - PID 5783 | ------- Summary: start ------- 12:04:55 INFO - PID 5783 | Number of tests: 6 12:04:55 INFO - PID 5783 | 12:04:55 INFO - PID 5783 | [#0] dojo.html Cycles:165 Average:6710.24 Median:6947.44 stddev:719.14 (10.4%) stddev-sans-first:684.85 12:04:55 INFO - PID 5783 | Values: 3826.8 3879.2 3880.6 3896.3 3923.3 6672.4 6708.3 6757.3 6799.6 6804.7 6701.0 6746.3 6761.5 6785.9 6820.9 5989.5 6025.0 6026.9 6045.8 6052.3 5997.2 6088.6 6088.6 6095.8 6140.1 6131.6 6151.3 6156.1 6183.0 6186.4 6054.3 6075.2 6109.2 6145.7 6215.3 5806.6 5921.2 5942.4 5954.3 6002.9 6745.0 6757.1 6822.1 6825.4 6892.1 7159.4 7261.7 7273.6 7370.6 7384.0 7265.3 7334.3 7353.0 7354.2 7367.5 6774.1 6780.4 6780.5 6795.0 6803.9 7433.3 7485.1 7504.7 7510.0 7526.5 7196.0 7197.5 7203.8 7208.3 7217.3 7282.8 7313.8 7330.0 7372.8 7437.0 7150.1 7161.6 7254.5 7260.6 7275.9 7109.5 7223.7 7294.0 7429.5 7433.9 6302.4 6346.3 6358.6 6367.6 6368.8 5364.6 5450.4 5474.2 5475.4 5527.4 6183.4 6301.6 6317.8 6322.1 6322.8 7266.1 7281.4 7286.7 7292.9 7295.7 5559.6 6218.8 6303.9 6309.3 6315.3 6552.7 6582.3 6612.3 6640.4 6642.4 7009.1 7086.1 7086.4 7090.8 7109.2 7191.5 7197.5 7204.6 7300.2 7311.8 6986.9 7046.8 7072.3 7082.2 7091.7 7207.8 7208.4 7219.7 7219.7 7226.1 7149.9 7220.0 7302.2 7314.5 7318.2 6958.1 6995.9 7024.4 7052.8 7066.3 6947.4 6972.6 7047.0 7054.4 7058.7 6874.1 6893.8 6960.4 6963.5 7008.6 7053.5 7060.0 7106.8 7134.6 7143.6 6826.5 6874.6 6912.7 6920.8 6921.1 12:04:55 INFO - PID 5783 | 12:04:55 INFO - PID 5783 | [#1] ext.html Cycles:180 Average:40883.58 Median:3632.46 stddev:62898.37 (1731.6%) stddev-sans-first:63003.11 12:04:55 INFO - PID 5783 | Values: 884.4 902.5 949.5 957.4 959.4 41583.6 42529.1 42652.6 42729.0 42730.0 39801.2 39930.0 40195.8 40206.0 40244.2 6941.2 6974.7 6981.6 6985.9 7007.1 7167.8 7237.0 7238.1 7247.9 7252.2 7809.1 7986.7 8080.6 8084.9 8088.2 7955.4 8019.6 8022.9 8023.4 8024.8 7549.7 7725.8 7732.8 7737.7 7913.6 37067.0 37207.4 37383.7 37416.0 37469.4 3610.0 3654.9 3661.9 3671.2 3687.3 31375.2 31406.0 31492.2 31583.7 31614.2 5272.9 5301.7 5318.4 5343.2 5360.2 201454.0 202050.0 202402.0 203375.0 203794.0 47614.0 47858.0 47907.3 48068.8 48080.0 49710.0 49876.8 49895.5 49930.3 50142.7 15208.7 15523.8 15530.1 15530.5 15545.6 12524.5 12547.2 12549.4 12555.2 12605.9 13988.4 14220.6 14229.5 14264.3 14375.4 5664.5 5738.6 5775.0 5788.4 5936.6 1088.9 1097.5 1101.9 1121.7 1122.6 25501.7 25591.0 25671.7 25693.0 26010.3 1209.1 1213.2 1216.7 1232.6 1238.6 13256.9 13407.3 13621.9 13650.2 13830.3 283260.0 283854.0 285741.0 285988.0 286902.0 54362.5 54688.2 54970.7 59764.4 59887.1 1447.0 1481.7 1486.6 1494.2 1502.4 29461.9 29741.0 29928.3 30187.4 30707.4 40574.0 40579.2 40695.8 40737.0 40774.4 229058.0 229682.0 229969.0 230976.0 230979.0 34004.1 34114.8 34150.8 34155.3 34170.8 42449.6 43056.0 43255.8 43476.0 43587.1 32816.2 33011.3 33024.8 33073.2 33202.7 32653.8 33109.1 33647.7 33778.3 33797.0 37423.1 37472.8 37799.8 37832.2 37850.4 31472.2 32085.7 32437.7 32438.5 32570.3 24188.6 24539.5 24782.3 25303.1 25356.7 12:04:55 INFO - PID 5783 | 12:04:55 INFO - PID 5783 | [#2] jquery.html Cycles:180 Average:40038.56 Median:23121.21 stddev:97381.29 (421.2%) stddev-sans-first:97609.44 12:04:55 INFO - PID 5783 | Values: 595.5 596.8 599.7 602.3 636.6 22361.9 22654.5 23097.8 23144.7 23148.0 26864.6 27159.6 27207.9 27234.2 27384.3 6455.3 6594.7 6600.4 6614.1 6629.5 6445.7 6560.7 6611.7 6675.2 6702.9 6663.1 6669.5 6672.7 6676.4 6680.9 6657.6 6661.3 6662.5 6669.4 6671.9 6241.6 6244.0 6247.8 6248.2 6248.8 29324.7 29358.0 30060.0 30161.4 30187.0 2246.6 2281.2 2281.6 2282.7 2286.9 20202.6 20314.8 20962.7 21236.0 21269.9 2490.7 2494.4 2547.4 2601.2 2635.1 164064.2 171865.0 172308.0 172401.0 172448.1 15852.8 16278.8 16324.0 16617.8 16827.7 26031.0 26056.9 26090.9 26239.3 26268.6 9458.8 9638.0 9764.0 9831.1 9968.0 8380.0 8411.9 8471.4 8508.0 8569.9 9790.3 9925.2 10249.4 10259.2 10278.8 2906.7 2986.4 3013.7 3024.9 3110.4 1021.8 1056.0 1062.9 1063.0 1080.7 21070.6 21467.8 21670.3 21683.0 22173.3 1119.2 1125.2 1127.2 1128.1 1139.1 11862.6 11980.0 11982.5 12041.2 12051.4 544152.0 564754.0 573007.0 594127.0 596527.0 126743.0 134060.3 136038.0 136385.0 141266.0 1173.1 1228.6 1256.2 1293.1 1316.1 22414.2 22468.4 22581.2 22787.5 22978.7 63735.7 65084.7 65541.4 65729.3 66758.3 81957.6 82872.6 83801.0 86923.6 86979.6 15964.6 15998.4 16202.6 16266.5 16279.7 25307.2 25420.2 25722.9 25753.5 26491.5 20500.7 20684.0 20740.9 20944.5 21214.4 17599.0 17893.4 18182.8 18299.5 18630.3 21083.9 21176.0 21489.6 21521.1 21568.2 15674.6 16043.6 16182.8 16210.8 16317.0 20720.2 20744.6 20792.3 20938.1 20997.0 12:04:55 INFO - PID 5783 | 12:04:55 INFO - PID 5783 | [#3] mootools.html Cycles:180 Average:10607.32 Median:25196.75 stddev:7758.96 (30.8%) stddev-sans-first:7749.82 12:04:55 INFO - PID 5783 | Values: 1391.0 1440.2 1460.7 1465.3 1468.1 16692.9 17163.3 17247.6 17420.0 17574.0 17789.2 18031.2 18056.7 18067.4 18073.7 2693.1 2710.8 2717.7 2718.5 2718.6 2581.9 2621.6 2645.8 2681.9 2685.7 2552.3 2565.8 2588.9 2597.4 2655.3 2586.3 2598.7 2604.5 2605.6 2605.6 2620.5 2631.2 2646.7 2647.0 2647.6 16694.4 16886.9 16891.2 16911.2 16940.2 8426.5 8668.6 8679.3 8680.4 8687.9 5106.0 5856.0 5886.9 5926.4 5961.5 2156.3 2198.1 2199.2 2210.9 2227.3 30196.2 30342.0 30385.7 30402.7 30408.5 6923.3 7074.2 7080.5 7266.6 7290.5 19838.3 19866.0 19868.1 19878.7 19879.7 8438.5 8540.4 8544.2 8553.7 8635.0 7809.5 7907.3 7946.4 7969.8 7972.2 5024.5 5488.2 5549.5 5605.1 5642.4 1477.5 1541.8 1558.4 1562.9 1585.8 942.1 956.5 962.4 963.6 969.9 8634.6 8881.2 8918.4 8952.2 8960.0 1037.0 1047.5 1056.4 1076.6 1087.5 6697.4 6919.5 6934.2 6976.3 7043.2 24685.1 24960.1 25096.5 25186.5 25207.0 23227.5 24522.4 25067.9 25213.6 25251.1 12857.3 13125.6 13168.7 13187.3 13235.1 9857.8 10310.8 10583.4 10871.9 10908.3 20733.5 20796.2 21242.9 21472.7 21560.1 21147.4 21323.5 21324.7 21581.6 21746.8 15466.8 15945.1 15973.7 15976.4 15981.4 15715.4 15744.4 15772.4 15905.0 15917.4 11532.1 11703.9 11775.0 12059.7 12171.1 11865.9 11872.9 12104.4 12212.8 12469.7 11247.3 11407.3 11411.5 11417.9 11418.9 8238.2 8308.5 8320.8 8338.9 8353.0 8631.8 8723.5 8730.3 8757.6 8858.1 12:04:55 INFO - PID 5783 | 12:04:55 INFO - PID 5783 | [#4] prototype.html Cycles:180 Average:5967.39 Median:6688.12 stddev:1682.18 (25.2%) stddev-sans-first:1636.75 12:04:55 INFO - PID 5783 | Values: 535.4 552.9 575.1 575.6 582.9 6626.9 6765.4 6799.5 6815.7 6862.9 6617.4 6646.9 6683.8 6692.4 6728.0 5154.1 5184.7 5193.2 5256.3 5336.9 5181.6 5182.0 5210.2 5268.8 5281.4 5250.7 5289.6 5293.9 5306.4 5329.3 5226.5 5230.2 5248.2 5255.4 5298.4 4737.4 4769.0 4773.5 4798.7 4836.3 6673.8 6735.4 6741.1 6745.8 6839.7 6188.2 6207.7 6217.2 6241.8 6267.0 7100.5 7140.6 7165.2 7193.0 7206.5 5244.4 5277.5 5312.4 5318.4 5333.9 7147.7 7228.8 7275.4 7323.7 7563.4 5400.1 5411.0 5418.6 5432.7 5465.2 5507.6 5507.9 5540.6 5576.4 5579.2 6154.7 6159.4 6164.9 6201.2 6240.3 7241.8 7256.5 7257.5 7261.2 7264.6 1920.5 1927.4 1935.7 1947.7 1949.5 1291.6 1332.4 1344.6 1349.7 1351.3 6104.5 6241.5 6257.8 6281.9 6312.9 7132.5 7170.4 7199.9 7258.2 7267.6 6253.8 6263.4 6285.9 6297.3 6325.4 3798.4 3800.8 3844.3 3853.6 3859.2 7040.4 7077.7 7078.2 7089.0 7150.7 7347.4 7436.6 7490.0 7492.7 7548.2 6984.4 7014.3 7048.0 7086.3 7163.5 7288.3 7294.0 7313.0 7318.9 7382.3 7151.6 7212.6 7282.8 7308.4 7343.2 7020.1 7176.1 7204.3 7204.4 7224.9 5475.1 5483.3 5484.6 5488.9 5489.3 7143.7 7333.6 7339.5 7456.8 7471.1 6666.8 6857.5 6934.8 6943.4 7016.6 6901.6 6961.5 6963.6 6987.3 7039.6 6887.8 6950.7 6956.7 7004.8 7072.8 7088.8 7094.4 7124.3 7146.5 7165.8 6830.6 6891.5 6918.0 6967.5 6975.4 12:04:55 INFO - PID 5783 | 12:04:55 INFO - PID 5783 | [#5] yui.html Cycles:180 Average:28112.39 Median:21113.42 stddev:39407.36 (186.6%) stddev-sans-first:39465.20 12:04:55 INFO - PID 5783 | Values: 966.3 986.7 1014.1 1020.0 1030.8 19114.7 19568.1 19592.9 19593.2 22954.2 23076.8 23183.8 23319.2 23413.5 23428.2 1043.5 1077.3 1078.2 1078.6 1079.9 1017.4 1074.3 1093.7 1097.5 1097.7 1136.6 1142.0 1142.2 1142.5 1142.6 1135.3 1136.5 1137.3 1137.3 1140.8 829.2 834.7 835.5 835.8 836.1 20980.6 21427.9 21555.7 21778.6 21977.8 22509.3 22955.3 23040.4 23338.3 23416.9 18293.5 18349.7 18405.2 18630.5 18707.9 4062.0 4073.8 4081.2 4084.3 4095.3 149295.0 150225.0 150607.0 151330.0 152308.2 17350.7 17688.7 17864.6 17869.5 17877.8 32585.7 33337.3 33504.5 34088.7 34321.0 8255.6 8540.2 9657.1 9708.0 9979.6 4798.6 4879.9 4884.0 4888.6 4896.0 5936.7 6044.8 6045.3 6077.6 6111.5 1252.7 1276.5 1287.7 1291.4 1294.6 745.9 760.3 761.7 775.7 780.3 13924.6 13990.6 15158.3 15220.8 15538.6 756.1 764.8 767.7 771.7 776.2 6590.6 6692.5 6736.4 6755.5 6811.8 130459.1 139252.0 141328.0 144459.0 145617.3 121010.0 123490.0 123649.0 124837.0 125796.0 105043.0 106508.0 107991.5 108764.5 109317.5 14535.9 14901.4 15118.8 15227.4 15360.7 50813.0 51532.7 52627.7 53134.5 53278.5 67500.0 68038.0 68167.7 68788.3 70695.3 20122.1 20560.8 20588.7 20658.4 20712.9 23341.0 23524.5 23614.0 23626.3 23803.0 21838.5 22167.8 22314.4 22449.8 22584.2 21561.4 21815.7 21887.0 22440.4 22482.0 20554.7 20660.7 21098.4 21128.5 21477.7 14700.5 15051.9 15277.7 15508.6 15832.4 16133.0 16400.3 16671.9 16808.7 16822.2 12:04:55 INFO - PID 5783 | -------- Summary: end -------- 12:04:55 INFO - PID 5783 | 12:04:56 INFO - TEST-INFO | 5783: exit 0 12:04:56 INFO - TEST-OK | dromaeo_css | took 1110256ms 12:04:56 INFO - TEST-START | kraken 12:04:56 INFO - Initialising browser for kraken test... 12:04:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36257/getInfo.html -profile /tmp/tmpFX43S0/profile 12:04:56 INFO - TEST-INFO | started process 6160 (/builds/slave/test/build/application/firefox/firefox http://localhost:36257/getInfo.html) 12:05:01 INFO - TEST-INFO | 6160: exit 0 12:05:01 INFO - Browser initialized. 12:05:01 INFO - Running cycle 1/1 for kraken test... 12:05:01 INFO - Using env: {'DISPLAY': ':0', 12:05:01 INFO - 'HOME': '/home/cltbld', 12:05:01 INFO - 'JSGC_DISABLE_POISONING': '1', 12:05:01 INFO - 'LANG': 'en_US.UTF-8', 12:05:01 INFO - 'LANGUAGE': 'en_US:en', 12:05:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:05:01 INFO - 'LOGNAME': 'cltbld', 12:05:01 INFO - 'MAIL': '/var/mail/cltbld', 12:05:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:05:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:05:01 INFO - 'MOZ_CRASHREPORTER': '1', 12:05:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:05:01 INFO - 'MOZ_NO_REMOTE': '1', 12:05:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:05:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:01 INFO - 'NO_EM_RESTART': '1', 12:05:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:01 INFO - 'PWD': '/builds/slave/test', 12:05:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:05:01 INFO - 'RUST_BACKTRACE': 'full', 12:05:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:05:01 INFO - 'SHELL': '/bin/bash', 12:05:01 INFO - 'SHLVL': '1', 12:05:01 INFO - 'TERM': 'linux', 12:05:01 INFO - 'TMOUT': '86400', 12:05:01 INFO - 'USER': 'cltbld', 12:05:01 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328', 12:05:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:01 INFO - '_': '/tools/buildbot/bin/python'} 12:05:01 INFO - TEST-INFO | started process 6342 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFX43S0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:05:02 INFO - PID 6342 | 12:05:02 INFO - PID 6342 | (/builds/slave/test/build/application/firefox/firefox:6395): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:05:02 INFO - PID 6342 | 12:05:12 INFO - PID 6342 | RSS: Main: 163352576 12:05:12 INFO - PID 6342 | 12:05:14 INFO - PID 6342 | Cycle 1(1): loaded http://localhost:36257/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36257/tests/kraken/driver.html?testName=audio-beat-detection) 12:05:15 INFO - PID 6342 | RSS: Main: 167510016 12:05:15 INFO - PID 6342 | 12:05:18 INFO - PID 6342 | Cycle 1(1): loaded http://localhost:36257/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36257/tests/kraken/driver.html?testName=audio-dft) 12:05:19 INFO - PID 6342 | RSS: Main: 171851776 12:05:19 INFO - PID 6342 | 12:05:21 INFO - PID 6342 | Cycle 1(1): loaded http://localhost:36257/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36257/tests/kraken/driver.html?testName=audio-fft) 12:05:22 INFO - PID 6342 | RSS: Main: 164909056 12:05:22 INFO - PID 6342 | 12:05:24 INFO - PID 6342 | Cycle 1(1): loaded http://localhost:36257/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36257/tests/kraken/driver.html?testName=audio-oscillator) 12:05:25 INFO - PID 6342 | RSS: Main: 165744640 12:05:25 INFO - PID 6342 | 12:05:27 INFO - PID 6342 | Cycle 1(1): loaded http://localhost:36257/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36257/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:05:28 INFO - PID 6342 | RSS: Main: 166092800 12:05:28 INFO - PID 6342 | 12:05:31 INFO - PID 6342 | Cycle 1(1): loaded http://localhost:36257/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36257/tests/kraken/driver.html?testName=imaging-darkroom) 12:05:31 INFO - PID 6342 | RSS: Main: 166891520 12:05:31 INFO - PID 6342 | 12:05:35 INFO - PID 6342 | Cycle 1(1): loaded http://localhost:36257/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36257/tests/kraken/driver.html?testName=imaging-desaturate) 12:05:35 INFO - PID 6342 | RSS: Main: 167710720 12:05:35 INFO - PID 6342 | 12:05:38 INFO - PID 6342 | Cycle 1(1): loaded http://localhost:36257/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36257/tests/kraken/driver.html?testName=json-parse-financial) 12:05:39 INFO - PID 6342 | RSS: Main: 168321024 12:05:39 INFO - PID 6342 | 12:05:40 INFO - PID 6342 | Cycle 1(1): loaded http://localhost:36257/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36257/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:05:41 INFO - PID 6342 | RSS: Main: 168054784 12:05:41 INFO - PID 6342 | 12:05:42 INFO - PID 6342 | Cycle 1(1): loaded http://localhost:36257/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36257/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:05:43 INFO - PID 6342 | RSS: Main: 167985152 12:05:43 INFO - PID 6342 | 12:05:45 INFO - PID 6342 | Cycle 1(1): loaded http://localhost:36257/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36257/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:05:45 INFO - PID 6342 | RSS: Main: 165068800 12:05:45 INFO - PID 6342 | 12:05:48 INFO - PID 6342 | Cycle 1(1): loaded http://localhost:36257/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36257/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:05:49 INFO - PID 6342 | RSS: Main: 164413440 12:05:49 INFO - PID 6342 | 12:05:51 INFO - PID 6342 | Cycle 1(1): loaded http://localhost:36257/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36257/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:05:52 INFO - PID 6342 | RSS: Main: 164945920 12:05:52 INFO - PID 6342 | 12:05:54 INFO - PID 6342 | Cycle 1(1): loaded http://localhost:36257/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36257/tests/kraken/driver.html?testName=ai-astar) 12:05:54 INFO - PID 6342 | RSS: Main: 166363136 12:05:54 INFO - PID 6342 | 12:05:54 INFO - PID 6342 | __start_tp_report 12:05:54 INFO - PID 6342 | _x_x_mozilla_page_load 12:05:54 INFO - PID 6342 | _x_x_mozilla_page_load_details 12:05:54 INFO - PID 6342 | |i|pagename|runs| 12:05:54 INFO - PID 6342 | |0;ai-astar;100;105;92;106;106;105;109;106;106;107 12:05:54 INFO - PID 6342 | |1;audio-beat-detection;140;113;147;168;114;139;142;118;178;118 12:05:54 INFO - PID 6342 | |2;audio-dft;162;162;161;160;162;161;161;162;162;168 12:05:54 INFO - PID 6342 | |3;audio-fft;73;72;74;136;75;72;73;88;98;72 12:05:54 INFO - PID 6342 | |4;audio-oscillator;79;78;128;77;78;77;127;78;77;128 12:05:54 INFO - PID 6342 | |5;imaging-gaussian-blur;114;113;114;114;113;114;113;114;118;115 12:05:54 INFO - PID 6342 | |6;imaging-darkroom;149;150;152;149;149;151;149;149;149;150 12:05:54 INFO - PID 6342 | |7;imaging-desaturate;94;94;94;94;95;93;94;94;94;95 12:05:54 INFO - PID 6342 | |8;json-parse-financial;44;43;43;47;46;48;44;43;44;47 12:05:54 INFO - PID 6342 | |9;json-stringify-tinderbox;38;38;39;39;39;37;38;38;39;38 12:05:54 INFO - PID 6342 | |10;stanford-crypto-aes;72;73;72;71;71;74;76;72;72;77 12:05:54 INFO - PID 6342 | |11;stanford-crypto-ccm;113;124;114;117;116;118;112;117;113;145 12:05:54 INFO - PID 6342 | |12;stanford-crypto-pbkdf2;154;154;157;154;155;155;158;158;164;161 12:05:54 INFO - PID 6342 | |13;stanford-crypto-sha256-iterative;59;59;59;60;59;59;59;61;64;61 12:05:54 INFO - PID 6342 | __end_tp_report 12:05:54 INFO - PID 6342 | __start_cc_report 12:05:54 INFO - PID 6342 | _x_x_mozilla_cycle_collect,892 12:05:54 INFO - PID 6342 | __end_cc_report 12:05:54 INFO - PID 6342 | __startTimestamp1504379154447__endTimestamp 12:05:54 INFO - PID 6342 | 12:05:54 INFO - PID 6342 | ------- Summary: start ------- 12:05:54 INFO - PID 6342 | Number of tests: 14 12:05:54 INFO - PID 6342 | 12:05:54 INFO - PID 6342 | [#0] ai-astar Cycles:10 Average:104.20 Median:106.00 stddev:4.85 (4.6%) stddev-sans-first:4.90 12:05:54 INFO - PID 6342 | Values: 100.0 105.0 92.0 106.0 106.0 105.0 109.0 106.0 106.0 107.0 12:05:54 INFO - PID 6342 | 12:05:54 INFO - PID 6342 | [#1] audio-beat-detection Cycles:10 Average:137.70 Median:141.00 stddev:22.61 (16.0%) stddev-sans-first:23.97 12:05:54 INFO - PID 6342 | Values: 140.0 113.0 147.0 168.0 114.0 139.0 142.0 118.0 178.0 118.0 12:05:54 INFO - PID 6342 | 12:05:54 INFO - PID 6342 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.00 stddev:2.18 (1.3%) stddev-sans-first:2.32 12:05:54 INFO - PID 6342 | Values: 162.0 162.0 161.0 160.0 162.0 161.0 161.0 162.0 162.0 168.0 12:05:54 INFO - PID 6342 | 12:05:54 INFO - PID 6342 | [#3] audio-fft Cycles:10 Average:83.30 Median:73.50 stddev:20.46 (27.8%) stddev-sans-first:21.35 12:05:54 INFO - PID 6342 | Values: 73.0 72.0 74.0 136.0 75.0 72.0 73.0 88.0 98.0 72.0 12:05:54 INFO - PID 6342 | 12:05:54 INFO - PID 6342 | [#4] audio-oscillator Cycles:10 Average:92.70 Median:77.50 stddev:24.14 (31.1%) stddev-sans-first:25.09 12:05:54 INFO - PID 6342 | Values: 79.0 78.0 128.0 77.0 78.0 77.0 127.0 78.0 77.0 128.0 12:05:54 INFO - PID 6342 | 12:05:54 INFO - PID 6342 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:1.48 (1.3%) stddev-sans-first:1.56 12:05:54 INFO - PID 6342 | Values: 114.0 113.0 114.0 114.0 113.0 114.0 113.0 114.0 118.0 115.0 12:05:54 INFO - PID 6342 | 12:05:54 INFO - PID 6342 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:149.50 stddev:1.06 (0.7%) stddev-sans-first:1.09 12:05:54 INFO - PID 6342 | Values: 149.0 150.0 152.0 149.0 149.0 151.0 149.0 149.0 149.0 150.0 12:05:54 INFO - PID 6342 | 12:05:54 INFO - PID 6342 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 12:05:54 INFO - PID 6342 | Values: 94.0 94.0 94.0 94.0 95.0 93.0 94.0 94.0 94.0 95.0 12:05:54 INFO - PID 6342 | 12:05:54 INFO - PID 6342 | [#8] json-parse-financial Cycles:10 Average:44.90 Median:45.00 stddev:1.91 (4.2%) stddev-sans-first:2.00 12:05:54 INFO - PID 6342 | Values: 44.0 43.0 43.0 47.0 46.0 48.0 44.0 43.0 44.0 47.0 12:05:54 INFO - PID 6342 | 12:05:54 INFO - PID 6342 | [#9] json-stringify-tinderbox Cycles:10 Average:38.30 Median:38.50 stddev:0.67 (1.8%) stddev-sans-first:0.71 12:05:54 INFO - PID 6342 | Values: 38.0 38.0 39.0 39.0 39.0 37.0 38.0 38.0 39.0 38.0 12:05:54 INFO - PID 6342 | 12:05:54 INFO - PID 6342 | [#10] stanford-crypto-aes Cycles:10 Average:73.00 Median:72.50 stddev:2.05 (2.8%) stddev-sans-first:2.15 12:05:54 INFO - PID 6342 | Values: 72.0 73.0 72.0 71.0 71.0 74.0 76.0 72.0 72.0 77.0 12:05:54 INFO - PID 6342 | 12:05:54 INFO - PID 6342 | [#11] stanford-crypto-ccm Cycles:10 Average:118.90 Median:117.00 stddev:9.80 (8.4%) stddev-sans-first:10.16 12:05:54 INFO - PID 6342 | Values: 113.0 124.0 114.0 117.0 116.0 118.0 112.0 117.0 113.0 145.0 12:05:54 INFO - PID 6342 | 12:05:54 INFO - PID 6342 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.00 Median:157.50 stddev:3.37 (2.1%) stddev-sans-first:3.39 12:05:54 INFO - PID 6342 | Values: 154.0 154.0 157.0 154.0 155.0 155.0 158.0 158.0 164.0 161.0 12:05:54 INFO - PID 6342 | 12:05:54 INFO - PID 6342 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.00 Median:59.50 stddev:1.63 (2.7%) stddev-sans-first:1.69 12:05:54 INFO - PID 6342 | Values: 59.0 59.0 59.0 60.0 59.0 59.0 59.0 61.0 64.0 61.0 12:05:54 INFO - PID 6342 | -------- Summary: end -------- 12:05:54 INFO - PID 6342 | 12:05:54 INFO - PID 6342 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:05:55 INFO - TEST-INFO | 6342: exit 0 12:05:55 INFO - TEST-OK | kraken | took 58882ms 12:05:55 INFO - SUITE-END | took 1169s 12:05:55 INFO - Completed test suite (00:19:29) 12:05:55 INFO - javascript benchmark 12:05:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9316.501393206288, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3826.789526576039, 3879.1659793144468, 3880.592537783532, 3896.2791883501554, 3923.333033384324, 6672.399484046435, 6708.262091169968, 6757.324267573253, 6799.592024478528, 6804.659767011648, 6700.9664951675295, 6746.257911629729, 6761.526693131483, 6785.932140678592, 6820.863582728342, 5989.520838332923, 6024.9698751506185, 6026.87946241076, 6045.818625441244, 6052.273727152748, 5997.160397544348, 6088.6042407243385, 6088.604240724361, 6095.786647467339, 6140.140380346755, 6131.601445906002, 6151.292601350835, 6156.076588511737, 6183.041628547559, 6186.3813618638005, 6054.334023257438, 6075.210222671047, 6109.20580324555, 6145.661988590626, 6215.34738852423, 5806.622569532967, 5921.230240068807, 5942.435468630516, 5954.255718035246, 6002.939970600281, 6745.021971814109, 6757.121574195372, 6822.113125293729, 6825.35159159879, 6892.104026476526, 7159.3914517265575, 7261.673224704901, 7273.63631818407, 7370.631468426557, 7384.0, 7265.273472652692, 7334.339909408125, 7353.0, 7354.227806080392, 7367.484276100675, 6774.0854984576945, 6780.389764921131, 6780.457563394916, 6795.0, 6803.9453884647955, 7433.293837085468, 7485.139208990927, 7504.699812007459, 7510.0, 7526.5107767994905, 7195.964020179866, 7197.532160409557, 7203.819904502429, 7208.27917208275, 7217.314355136254, 7282.762468368264, 7313.780586582411, 7330.047093877762, 7372.815679608053, 7436.96281518589, 7150.141982961974, 7161.606111663908, 7254.455915806336, 7260.564366138048, 7275.854482910418, 7109.502334836512, 7223.711051557984, 7293.9635301823155, 7429.4799364044, 7433.9256607433235, 6302.432781049728, 6346.333634968354, 6358.586691865014, 6367.586106903036, 6368.77709280173, 5364.597655175878, 5450.427705090988, 5474.178873169057, 5475.397706252309, 5527.447255274521, 6183.443490085824, 6301.558890877686, 6317.810465686037, 6322.114903913456, 6322.841928951721, 7266.091738532684, 7281.381082607932, 7286.70853165878, 7292.906064090429, 7295.744648937261, 5559.555235581144, 6218.81343559694, 6303.936960630427, 6309.274433440121, 6315.273743519462, 6552.672366381662, 6582.308857569887, 6612.305707900698, 6640.369164929324, 6642.435392991553, 7009.12385951756, 7086.149662040485, 7086.362227399559, 7090.787276381717, 7109.182444019004, 7191.496595238283, 7197.532160409557, 7204.603746793977, 7300.233475484997, 7311.780646580611, 6986.895196572157, 7046.753363632249, 7072.25741297177, 7082.150141982995, 7091.716331346895, 7207.783766486804, 7208.387287080657, 7219.711211551479, 7219.747308844166, 7226.060612120601, 7149.9285007149265, 7220.0181874506425, 7302.233265507045, 7314.451416143704, 7318.1584117825005, 6958.095447591781, 6995.895061573979, 7024.438044956289, 7052.753153639598, 7066.328698773608, 6947.444204463732, 6972.58164510131, 7047.048648432396, 7054.435645148274, 7058.717651294096, 6874.072000279898, 6893.7931862044215, 6960.408365289007, 6963.547369420971, 7008.649567521706, 7053.506254562131, 7060.046893669494, 7106.822329441598, 7134.607596582237, 7143.607101609461, 6826.522143450109, 6874.587524748531, 6912.654367281515, 6920.757773477904, 6921.1002569667635], "value": 6664.536861785612, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [884.4295429448006, 902.539704750577, 949.5204921514636, 957.4351132831628, 959.4003747657714, 41583.58416415839, 42529.14941701168, 42652.57347426525, 42729.0, 42730.0, 39801.20397592046, 39930.0, 40195.799021004925, 40206.0, 40244.19511609766, 6941.201761797395, 6974.651267436633, 6981.616011119387, 6985.930140698579, 7007.089078419798, 7167.820804479878, 7237.0, 7238.05905232319, 7247.855042899138, 7252.166000909884, 7809.101953275361, 7986.720464783733, 8080.595970201496, 8084.878726819103, 8088.231617996281, 7955.443118981673, 8019.558924259163, 8022.879656805123, 8023.358131349478, 8024.799380015488, 7549.698012079511, 7725.845483090363, 7732.845343093107, 7737.729179478748, 7913.564753938531, 37067.0, 37207.44188837169, 37383.69157079516, 37416.0, 37469.437958430644, 3610.0433385152955, 3654.872079477232, 3661.8901432956786, 3671.1739858531882, 3687.2625474905126, 31375.215619609466, 31406.0, 31492.21269468259, 31583.684163158534, 31614.209644759067, 5272.868178295535, 5301.6818990860675, 5318.441563635801, 5343.171808369709, 5360.249565060894, 201454.0, 202050.0, 202402.0, 203375.0, 203794.0, 47614.0, 47858.0, 47907.281390779164, 48068.759656201495, 48080.0, 49710.0, 49876.75061624741, 49895.50104498908, 49930.251046234334, 50142.74928625333, 15208.695826083416, 15523.844761552466, 15530.1458419788, 15530.456434024754, 15545.611359715871, 12524.499020039279, 12547.24716517012, 12549.435275412628, 12555.183913045623, 12605.865472107555, 13988.370523326475, 14220.644483887778, 14229.50196743109, 14264.286785660883, 14375.424983000563, 5664.461876121762, 5738.598298119092, 5774.971125144348, 5788.421157884178, 5936.554758393051, 1088.8530048443517, 1097.4567589043475, 1101.867775866885, 1121.6522877907876, 1122.5958654884234, 25501.744982549935, 25591.0, 25671.74328256731, 25693.0, 26010.34974125662, 1209.1173443386376, 1213.2477863724548, 1216.6920560397607, 1232.550119206501, 1238.5603110895502, 13256.867431325756, 13407.32963351848, 13621.863781362059, 13650.249236292098, 13830.308484575933, 283260.0, 283854.0, 285741.0, 285988.0, 286902.0, 54362.45637543574, 54688.17967730487, 54970.72514637401, 59764.40235597675, 59887.10169347463, 1446.985530144685, 1481.7332880081474, 1486.561464368014, 1494.2006026775405, 1502.4440956846038, 29461.85269073641, 29741.0, 29928.25179370533, 30187.396252074395, 30707.385852282383, 40574.0, 40579.1884162321, 40695.79652101839, 40737.0, 40774.388384174854, 229058.0, 229682.0, 229969.0, 230976.0, 230979.0, 34004.14989625279, 34114.82942585271, 34150.82924585361, 34155.316893662486, 34170.8291458551, 42449.57550424456, 43056.0, 43255.78372108119, 43476.0, 43587.12825743404, 32816.1795955103, 33011.33977320488, 33024.834875825465, 33073.173170670925, 33202.667973320924, 32653.836730816194, 33109.05508865538, 33647.663523364456, 33778.32443351168, 33797.0, 37423.06442338963, 37472.81263593664, 37799.81100094482, 37832.243355133294, 37850.43224351692, 31472.213194670316, 32085.67914320827, 32437.675623243467, 32438.513422299155, 32570.34859302848, 24188.63717044281, 24539.50920981606, 24782.256532304058, 25303.114390996227, 25356.746432535438], "value": 17825.55674935447, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [595.4521839907285, 596.829903477509, 599.7331187621508, 602.3494625804132, 636.5798572941858, 22361.888190559046, 22654.54690906181, 23097.76902230977, 23144.65283020756, 23148.0, 26864.59703104455, 27159.592606110924, 27207.86396068017, 27234.182974510746, 27384.315392115208, 6455.289918109004, 6594.7032383542855, 6600.372964568358, 6614.107095542088, 6629.535932484728, 6445.709943052574, 6560.671966401685, 6611.669416529178, 6675.2323482799375, 6702.932970670279, 6663.100481435017, 6669.5331326807145, 6672.666366681671, 6676.399124078832, 6680.93319066808, 6657.633830139341, 6661.300563440817, 6662.533622646417, 6669.43309818666, 6671.866562668743, 6241.563090583661, 6243.968780156116, 6247.750089996394, 6248.218972628421, 6248.843778905518, 29324.706752932412, 29358.0, 30060.0, 30161.396772064654, 30187.0, 2246.61807492725, 2281.2129815213725, 2281.5779080870093, 2282.657601359793, 2286.919957801469, 20202.595948081103, 20314.796852031584, 20962.685559716618, 21236.0, 21269.8936505318, 2490.663760392342, 2494.3764058985253, 2547.4140947581973, 2601.1676263595655, 2635.0645520840208, 164064.17967910203, 171865.0, 172308.0, 172401.0, 172448.1377593104, 15852.841471585367, 16278.837211627731, 16323.999999999762, 16617.839680841655, 16827.74758378625, 26031.0, 26056.8697156513, 26090.86954565215, 26239.344016399362, 26268.605970910452, 9458.763530911645, 9637.951810240904, 9763.97478264772, 9831.066048725359, 9968.0, 8379.95810020946, 8411.873821892677, 8471.364647651451, 8507.979042514939, 8569.871451928226, 9790.265730070274, 9925.2059835213, 10249.412705552997, 10259.17926565873, 10278.845817312746, 2906.651201855791, 2986.3728616990243, 3013.668496465387, 3024.9243768905953, 3110.3623757129735, 1021.8262895307815, 1055.9736006599742, 1062.888396718349, 1062.9946850265699, 1080.6811990462832, 21070.578588428147, 21467.78532214689, 21670.34988950334, 21683.0, 22173.33479995603, 1119.2109562758235, 1125.150511363915, 1127.1602656021316, 1128.0862501373913, 1139.065965907964, 11862.584809531625, 11980.0, 11982.460789264329, 12041.217320874115, 12051.397430128458, 544152.0, 564754.0, 573007.0, 594127.0, 596527.0, 126743.0, 134060.3296983509, 136038.0, 136385.0, 141266.0, 1173.1142987044727, 1228.6375519221672, 1256.221142891395, 1293.1336004876566, 1316.1313533068128, 22414.215502457337, 22468.438289042904, 22581.209657661904, 22787.544249114595, 22978.655320169877, 63735.68132159495, 65084.674576626814, 65541.36146596374, 65729.34270657232, 66758.33241667715, 81957.59021205094, 82872.58563707143, 83801.0, 86923.56538217269, 86979.56510217408, 15964.600884977504, 15998.360065597246, 16202.594935126244, 16266.51200463988, 16279.67440651204, 25307.240782776546, 25420.237392878244, 25722.871385642953, 25753.484930301664, 26491.470170596866, 20500.69248961237, 20684.0, 20740.896295518425, 20944.47638809042, 21214.36356909295, 17599.0, 17893.37373191932, 18182.81817181811, 18299.451016469528, 18630.254789808798, 21083.894580526998, 21176.0, 21489.57020859543, 21521.139154434273, 21568.245111421023, 15674.608134796264, 16043.598910027342, 16182.838171618605, 16210.837891620933, 16317.0, 20720.171193152106, 20744.58510829805, 20792.27227047046, 20938.05778739991, 20997.0], "value": 13000.853435723224, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1390.9513167039154, 1440.2222799688168, 1460.6713489464878, 1465.2893346726835, 1468.1411374346008, 16692.91653541732, 17163.313467461317, 17247.568810779736, 17420.0, 17574.0, 17789.199486023128, 18031.188596513155, 18056.729149062743, 18067.367642132496, 18073.7288940666, 2693.0843513205505, 2710.7695845853127, 2717.6738791345065, 2718.4563087382503, 2718.5786203138546, 2581.8709064546792, 2621.5543357629153, 2645.8412495250345, 2681.8793154308105, 2685.6508653875067, 2552.2981180175507, 2565.8332208406487, 2588.8576128312934, 2597.3766296088897, 2655.3361659585103, 2586.314626623946, 2598.727133650968, 2604.4530648563823, 2605.557055300594, 2605.6091586262023, 2620.489004644095, 2631.2369412870244, 2646.735326467357, 2647.0470630572977, 2647.6293318935363, 16694.4156954506, 16886.915565422216, 16891.239894204668, 16911.154442277835, 16940.152992350457, 8426.452280601741, 8668.566571671392, 8679.305655547541, 8680.392372533925, 8687.869681954775, 5105.974470127626, 5856.0, 5886.882262354772, 5926.4073592640825, 5961.463468287831, 2156.2807135978696, 2198.1427243375097, 2199.164317559333, 2210.9005094770773, 2227.3318004598555, 30196.24509387261, 30342.0, 30385.69614303851, 30402.69597304021, 30408.54387184194, 6923.273056329113, 7074.151101867808, 7080.468964827659, 7266.600336981517, 7290.4896657233485, 19838.305659301855, 19866.0, 19868.10593523295, 19878.70181947272, 19879.701804472945, 8438.53588052663, 8540.359473039547, 8544.2310192083, 8553.657853685783, 8635.0, 7809.531428114331, 7907.288344048948, 7946.364290856717, 7969.760907172794, 7972.202779722097, 5024.547790698854, 5488.231647569343, 5549.500544950893, 5605.103183490622, 5642.351129620064, 1477.4976507987335, 1541.799566056428, 1558.4311726219848, 1562.898411603242, 1585.8493443122884, 942.0814705661925, 956.4596003258137, 962.4417837654144, 963.5904740485265, 969.898160693117, 8634.611442485104, 8881.24509416694, 8918.420302680304, 8952.23905968, 8959.969603495681, 1036.9688909332733, 1047.4972013433442, 1056.4295280548417, 1076.6016573867719, 1087.472575800736, 6697.430718388894, 6919.515633905679, 6934.167899851949, 6976.337247961435, 7043.154821421463, 24685.136020239206, 24960.126395576066, 25096.498070038862, 25186.496270074495, 25207.0, 23227.53544929092, 24522.386940326276, 25067.87466062658, 25213.62179567308, 25251.116210932898, 12857.292848893401, 13125.60623181306, 13168.73662526744, 13187.340632968313, 13235.139715918434, 9857.802843942938, 10310.793784124426, 10583.364998100138, 10871.945640272064, 10908.290961087503, 20733.481662958548, 20796.1681532737, 21242.893785530974, 21472.67790983105, 21560.137594496045, 21147.365579032652, 21323.46691332667, 21324.680129798377, 21581.568368632852, 21746.782532174475, 15466.84533154699, 15945.123018233644, 15973.680526389175, 15976.440824571084, 15981.360745570048, 15715.44995925183, 15744.448944286894, 15772.447964321194, 15905.0, 15917.363305467652, 11532.077433805107, 11703.88296117062, 11775.0, 12059.698507537381, 12171.087168462223, 11865.940670296593, 11872.881271187178, 12104.394780261127, 12212.75574488523, 12469.688257793265, 11247.325160490396, 11407.315561066363, 11411.486483108447, 11417.885821141683, 11418.885811141783, 8238.176182381714, 8308.5430301334, 8320.791980200544, 8338.874916876373, 8352.958235208785, 8631.784205394915, 8723.52020638871, 8730.301575874042, 8757.605907734294, 8858.069902660129], "value": 7388.015722193099, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [535.357570914902, 552.8838943821797, 575.1401654526481, 575.5942060847103, 582.8659408336083, 6626.867462650756, 6765.391114799667, 6799.464278575144, 6815.727370905164, 6862.931370686291, 6617.3713497217805, 6646.900296495556, 6683.832904177386, 6692.39768420842, 6727.966360168192, 5154.07226699195, 5184.688918664892, 5193.221016847466, 5256.316678831747, 5336.946630533703, 5181.637285390024, 5182.030828285247, 5210.218467229908, 5268.789248430058, 5281.419043905167, 5250.692063999675, 5289.646107482172, 5293.920591191135, 5306.442823503535, 5329.2539044533605, 5226.491284183613, 5230.181967163953, 5248.160294352904, 5255.395629502618, 5298.36419629643, 4737.384140061805, 4768.976155119202, 4773.4510531289, 4798.664093513456, 4836.274558816206, 6673.799786006428, 6735.427488663469, 6741.067635578539, 6745.831354216135, 6839.692213850389, 6188.226471691039, 6207.689615519206, 6217.233331167632, 6241.781537646206, 6267.0, 7100.502964792467, 7140.607266600335, 7165.176004759467, 7193.0, 7206.49554531183, 5244.449332820075, 5277.525022747933, 5312.362516498007, 5318.388385335658, 5333.866653333697, 7147.714091436336, 7228.783136505966, 7275.43503683202, 7323.7070517179245, 7563.394928405769, 5400.135978243462, 5410.97294513525, 5418.564080518666, 5432.674039557639, 5465.152901300305, 5507.5593952483705, 5507.862303442366, 5540.612157149041, 5576.414476479992, 5579.163125531203, 6154.7230374633245, 6159.384061593805, 6164.938350616437, 6201.1938448002375, 6240.251169859646, 7241.755055932677, 7256.455765817479, 7257.528260663145, 7261.237570055174, 7264.564126152448, 1920.5225259415197, 1927.441042097804, 1935.7224232006809, 1947.7467929169118, 1949.483386902472, 1291.560869304441, 1332.4137379552965, 1344.623505418465, 1349.6963183283683, 1351.2703140304322, 6104.511639068864, 6241.500679945684, 6257.78097766576, 6281.874362512815, 6312.916737476714, 7132.536385134949, 7170.354668079898, 7199.928000719926, 7258.201597824235, 7267.63661816907, 6253.779303658619, 6263.3736626337, 6285.8742825143245, 6297.307296197415, 6325.367463253638, 3798.442638518194, 3800.8289626966853, 3844.308024555551, 3853.556840963325, 3859.1895701902354, 7040.401565866959, 7077.681504332318, 7078.186008608973, 7089.0, 7150.678219480136, 7347.448941329421, 7436.628168591549, 7489.988851504977, 7492.70029198826, 7548.169701332849, 6984.406325462291, 7014.298570142945, 7048.0, 7086.326798954091, 7163.53437026602, 7288.303327768547, 7293.9635301823155, 7313.0, 7318.890216646648, 7382.298681625237, 7151.60666163366, 7212.629600375912, 7282.817929551804, 7308.378787802883, 7343.2289609590225, 7020.122484689414, 7176.138863336433, 7204.279572042754, 7204.387627051759, 7224.927750722425, 5475.09660905962, 5483.313176543385, 5484.61607687458, 5488.917666235089, 5489.34127904638, 7143.678534465857, 7333.559986400833, 7339.522931009467, 7456.813579660552, 7471.1322169457635, 6666.766573254023, 6857.485688573277, 6934.791956241321, 6943.409810166192, 7016.5790052597, 6901.62041087745, 6961.477889158435, 6963.61700106499, 6987.301269872972, 7039.648017599202, 6887.795905310252, 6950.687219075255, 6956.652167391509, 7004.754833580801, 7072.752453664097, 7088.787336379917, 7094.361507464353, 7124.25195354501, 7146.499745018007, 7165.7850264492145, 6830.624315662686, 6891.518323560458, 6918.0, 6967.512274140964, 6975.441964642918], "value": 5476.3270001471265, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [966.3235734985511, 986.6891929042356, 1014.1278500489582, 1020.0207800511508, 1030.7732298894243, 19114.71327930082, 19568.11943462544, 19592.90203548982, 19593.216271349145, 22954.196603118893, 23076.769232307674, 23183.76816231837, 23319.183828566005, 23413.5317293654, 23428.180013699523, 1043.5356266461429, 1077.3212875888219, 1078.1967434261446, 1078.557791305565, 1079.854219680341, 1017.409902256693, 1074.2802322443981, 1093.6937657455926, 1097.4841824342545, 1097.7091070866227, 1136.5567428702811, 1142.0121594820503, 1142.177632104883, 1142.4573327669373, 1142.5829572206126, 1135.2961164078242, 1136.511300140939, 1137.2607804926781, 1137.2607804926822, 1140.7832511822728, 829.1708291708292, 834.7203686764941, 835.4653022065883, 835.8161204534993, 836.1304243586662, 20980.649934266665, 21427.892860535598, 21555.67666485004, 21778.564428711496, 21977.780222197733, 22509.324720258417, 22955.311340659806, 23040.42398940023, 23338.299851004496, 23416.882915585484, 18293.451196464128, 18349.7247541287, 18405.171767270505, 18630.53423664419, 18707.90646046761, 4062.045419326456, 4073.8370465181356, 4081.1837632473325, 4084.326086195774, 4095.3242714952, 149295.0, 150225.0, 150607.0, 151330.0, 152308.2384588081, 17350.739738903925, 17688.734668979974, 17864.55338616519, 17869.46391608254, 17877.821221787617, 32585.674143258264, 33337.3332533348, 33504.497432538534, 34088.65911340855, 34321.0, 8255.628496717662, 8540.231379175904, 9657.082577155159, 9708.0, 9979.60081596728, 4798.616110711135, 4879.902401951941, 4883.975580122076, 4888.633352498577, 4895.975520122448, 5936.673482958478, 6044.788432404845, 6045.33501314855, 6077.604955677867, 6111.5110791137495, 1252.7056141806668, 1276.5340650662627, 1287.6973911130876, 1291.3607764156616, 1294.6180876641224, 745.932866042048, 760.2511526146741, 761.7219714804056, 775.747881938361, 780.2509590792789, 13924.58226253214, 13990.580282591334, 15158.31787569562, 15220.771688424682, 15538.611534711497, 756.0738095832561, 764.7820371194183, 767.6583920155477, 771.733751855609, 776.1850057439666, 6590.637514936724, 6692.498062645321, 6736.360045795642, 6755.493337999571, 6811.829704257334, 130459.09299678578, 139252.0, 141328.0, 144459.0, 145617.27191363976, 121010.0, 123490.0, 123649.0, 124837.0, 125796.0, 105043.0, 106508.0, 107991.46004270086, 108764.45617772019, 109317.45341273243, 14535.85464145345, 14901.403943842124, 15118.848811511743, 15227.390904364145, 15360.692786144438, 50813.0, 51532.74233628808, 52627.73686131545, 53134.46865531295, 53278.46721532735, 67500.0, 68038.0, 68167.65916170388, 68788.3121168782, 70695.29304706887, 20122.09450574757, 20560.794392055886, 20588.69116963217, 20658.380248592566, 20712.896435517727, 23341.0, 23524.52950941006, 23614.0, 23626.29121126369, 23803.047878084682, 21838.45403864916, 22167.77832221657, 22314.442138946008, 22449.775502245422, 22584.20955266558, 21561.353159405244, 21815.672764908857, 21887.0, 22440.43898902475, 22482.0, 20554.691679625117, 20660.690089648368, 21098.367048988555, 21128.471788205417, 21477.67783483218, 14700.485483008044, 15051.924740376227, 15277.694446111238, 15508.612284692974, 15832.366705331659, 16133.0, 16400.261988210797, 16671.91664041672, 16808.747868781735, 16822.242999064827], "value": 10463.86599557754, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1430.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 105.0, 92.0, 106.0, 106.0, 105.0, 109.0, 106.0, 106.0, 107.0], "value": 104.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 113.0, 147.0, 168.0, 114.0, 139.0, 142.0, 118.0, 178.0, 118.0], "value": 137.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 160.0, 162.0, 161.0, 161.0, 162.0, 162.0, 168.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 74.0, 136.0, 75.0, 72.0, 73.0, 88.0, 98.0, 72.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 128.0, 77.0, 78.0, 77.0, 127.0, 78.0, 77.0, 128.0], "value": 92.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 114.0, 113.0, 114.0, 113.0, 114.0, 118.0, 115.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 152.0, 149.0, 149.0, 151.0, 149.0, 149.0, 149.0, 150.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 93.0, 94.0, 94.0, 94.0, 95.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 43.0, 43.0, 47.0, 46.0, 48.0, 44.0, 43.0, 44.0, 47.0], "value": 44.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 39.0, 39.0, 39.0, 37.0, 38.0, 38.0, 39.0, 38.0], "value": 38.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 72.0, 71.0, 71.0, 74.0, 76.0, 72.0, 72.0, 77.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 124.0, 114.0, 117.0, 116.0, 118.0, 112.0, 117.0, 113.0, 145.0], "value": 118.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 154.0, 157.0, 154.0, 155.0, 155.0, 158.0, 158.0, 164.0, 161.0], "value": 157.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 60.0, 59.0, 59.0, 59.0, 61.0, 64.0, 61.0], "value": 60.0, "unit": "score"}]}]} 12:05:55 INFO - Return code: 0 12:05:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:05:55 INFO - # TBPL SUCCESS # 12:05:55 INFO - Running post-action listener: _package_coverage_data 12:05:55 INFO - Running post-action listener: _resource_record_post_action 12:05:55 INFO - [mozharness: 2017-09-02 19:05:55.749736Z] Finished run-tests step (success) 12:05:55 INFO - Running post-run listener: _resource_record_post_run 12:05:55 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 626688; Write bytes: 388681728; Read time: 500; Write time: 334424 12:05:55 INFO - TinderboxPrint: CPU usage
13.1% 12:05:55 INFO - TinderboxPrint: I/O read bytes / time
626,688 / 500 12:05:55 INFO - TinderboxPrint: I/O write bytes / time
388,681,728 / 334,424 12:05:55 INFO - TinderboxPrint: CPU idle
8,219.3 (86.9%) 12:05:55 INFO - TinderboxPrint: CPU user
1,187.3 (12.6%) 12:05:55 INFO - TinderboxPrint: Swap in / out
0 / 0 12:05:55 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 111546368; Read time: 288; Write time: 112732 12:05:55 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:05:55 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 622592; Write bytes: 277135360; Read time: 212; Write time: 221692 12:05:56 INFO - Running post-run listener: _upload_blobber_files 12:05:56 INFO - Blob upload gear active. 12:05:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:05:56 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:05:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:05:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:05:56 INFO - (blobuploader) - INFO - Open directory for files ... 12:05:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:05:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:05:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:05:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:05:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:05:57 INFO - (blobuploader) - INFO - Done attempting. 12:05:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:05:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:05:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:06:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:06:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:06:03 INFO - (blobuploader) - INFO - Done attempting. 12:06:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:06:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:06:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:06:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:06:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:06:04 INFO - (blobuploader) - INFO - Done attempting. 12:06:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:06:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:06:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:06:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:06:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:06:05 INFO - (blobuploader) - INFO - Done attempting. 12:06:05 INFO - (blobuploader) - INFO - Iteration through files over. 12:06:05 INFO - Return code: 0 12:06:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:06:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:06:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6acbacd89f70e015fe05acd141299f4366d7f69aa30b8ad92902b46f3be17b7c0d8b2e2266eda5d38c7d8981ca78cb04290dff94aa81ab6ccb54726332e4694a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a4a7c39ea1d95de6cd00c991a0f25600e04393621fa08752030967cad5ad0eedb6fa6f3c0ba7d5434b274601cd898041dee59fc5e3d08851636aa8c4672d1af8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/812a9d9cd6e05374393c72565b6bbc086f8a24364358a69ce3b3c7adb118779dbd70e287508fe7be6be353fc182b721cbc89dc87c60fdd899165bd7d34976c07", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7db95955a9198b6281b6db3b87a97fa38b6213fc431b2e4016c93a4c2ab79ad23daeabb150ee53ed95fd812d6dac27f2476023035696ee40549b610c31e71861"} 12:06:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:06:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:06:05 INFO - Contents: 12:06:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6acbacd89f70e015fe05acd141299f4366d7f69aa30b8ad92902b46f3be17b7c0d8b2e2266eda5d38c7d8981ca78cb04290dff94aa81ab6ccb54726332e4694a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a4a7c39ea1d95de6cd00c991a0f25600e04393621fa08752030967cad5ad0eedb6fa6f3c0ba7d5434b274601cd898041dee59fc5e3d08851636aa8c4672d1af8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/812a9d9cd6e05374393c72565b6bbc086f8a24364358a69ce3b3c7adb118779dbd70e287508fe7be6be353fc182b721cbc89dc87c60fdd899165bd7d34976c07", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7db95955a9198b6281b6db3b87a97fa38b6213fc431b2e4016c93a4c2ab79ad23daeabb150ee53ed95fd812d6dac27f2476023035696ee40549b610c31e71861"} 12:06:05 INFO - Running post-run listener: copy_logs_to_upload_dir 12:06:05 INFO - Copying logs to upload dir... 12:06:05 INFO - mkdir: /builds/slave/test/build/upload/logs 12:06:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.034729 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-09-02 12:06:06.013790) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 12:06:06.021824) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6acbacd89f70e015fe05acd141299f4366d7f69aa30b8ad92902b46f3be17b7c0d8b2e2266eda5d38c7d8981ca78cb04290dff94aa81ab6ccb54726332e4694a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a4a7c39ea1d95de6cd00c991a0f25600e04393621fa08752030967cad5ad0eedb6fa6f3c0ba7d5434b274601cd898041dee59fc5e3d08851636aa8c4672d1af8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/812a9d9cd6e05374393c72565b6bbc086f8a24364358a69ce3b3c7adb118779dbd70e287508fe7be6be353fc182b721cbc89dc87c60fdd899165bd7d34976c07", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7db95955a9198b6281b6db3b87a97fa38b6213fc431b2e4016c93a4c2ab79ad23daeabb150ee53ed95fd812d6dac27f2476023035696ee40549b610c31e71861"} build_url:https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011301 build_url: 'https://queue.taskcluster.net/v1/task/DhiApqhVQxKcRyPRgESVyw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6acbacd89f70e015fe05acd141299f4366d7f69aa30b8ad92902b46f3be17b7c0d8b2e2266eda5d38c7d8981ca78cb04290dff94aa81ab6ccb54726332e4694a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a4a7c39ea1d95de6cd00c991a0f25600e04393621fa08752030967cad5ad0eedb6fa6f3c0ba7d5434b274601cd898041dee59fc5e3d08851636aa8c4672d1af8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/812a9d9cd6e05374393c72565b6bbc086f8a24364358a69ce3b3c7adb118779dbd70e287508fe7be6be353fc182b721cbc89dc87c60fdd899165bd7d34976c07", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7db95955a9198b6281b6db3b87a97fa38b6213fc431b2e4016c93a4c2ab79ad23daeabb150ee53ed95fd812d6dac27f2476023035696ee40549b610c31e71861"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 12:06:06.057523) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 12:06:06.057893) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1504376929.862364-1872639328 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004522 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 12:06:06.142607) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 12:06:06.142951) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 12:06:06.143377) ========= ========= Total master_lag: 0.49 =========