builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-042 starttime: 1504289483.41 results: success (0) revision: d1292636950a4ac1bb818cf05b113e3fcd6babe1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 11:11:23.413249) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 11:11:23.413700) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 11:11:23.413970) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 11:11:23.431623) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 11:11:23.431982) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-01 11:11:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.2M=0s 2017-09-01 11:11:23 (46.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127659 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 11:11:23.576093) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 11:11:23.576413) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016051 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 11:11:23.629503) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 11:11:23.629846) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d1292636950a4ac1bb818cf05b113e3fcd6babe1 --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 d1292636950a4ac1bb818cf05b113e3fcd6babe1 --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=74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-01 11:11:23,577 truncating revision to first 12 chars 2017-09-01 11:11:23,578 Setting DEBUG logging. 2017-09-01 11:11:23,578 attempt 1/10 2017-09-01 11:11:23,578 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d1292636950a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-01 11:11:23,858 unpacking tar archive at: mozilla-central-d1292636950a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.559583 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 11:11:24.209727) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 11:11:24.210057) ========= script_repo_revision: d1292636950a4ac1bb818cf05b113e3fcd6babe1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 11:11:24.210420) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 11:11:24.210686) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 11:11:24.223327) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-09-01 11:11:24.223724) ========= /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=74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:11:24 INFO - MultiFileLogger online at 20170901 11:11:24 in /builds/slave/test 11:11:24 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:11:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:11:24 INFO - {'append_to_log': False, 11:11:24 INFO - 'base_work_dir': '/builds/slave/test', 11:11:24 INFO - 'blob_upload_branch': 'Firefox', 11:11:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:11:24 INFO - 'branch': 'Firefox', 11:11:24 INFO - 'buildbot_json_path': 'buildprops.json', 11:11:24 INFO - 'code_coverage': False, 11:11:24 INFO - 'config_files': ('talos/linux_config.py',), 11:11:24 INFO - 'default_actions': ('clobber', 11:11:24 INFO - 'read-buildbot-config', 11:11:24 INFO - 'download-and-extract', 11:11:24 INFO - 'populate-webroot', 11:11:24 INFO - 'create-virtualenv', 11:11:24 INFO - 'install', 11:11:24 INFO - 'setup-mitmproxy', 11:11:24 INFO - 'run-tests'), 11:11:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:11:24 INFO - 'disable_ccov_upload': False, 11:11:24 INFO - 'disable_stylo': False, 11:11:24 INFO - 'download_minidump_stackwalk': True, 11:11:24 INFO - 'download_symbols': 'ondemand', 11:11:24 INFO - 'enable_stylo': False, 11:11:24 INFO - 'enable_webrender': False, 11:11:24 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:11:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:11:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:11:24 INFO - 'gecko_profile': False, 11:11:24 INFO - 'gecko_profile_interval': 0, 11:11:24 INFO - 'installer_path': 'installer.exe', 11:11:24 INFO - 'log_level': 'info', 11:11:24 INFO - 'log_name': 'talos', 11:11:24 INFO - 'log_to_console': True, 11:11:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:11:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:11:24 INFO - 'opt_config_files': (), 11:11:24 INFO - 'pip_index': False, 11:11:24 INFO - 'suite': 'dromaeojs-e10s', 11:11:24 INFO - 'system_bits': '32', 11:11:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:11:24 INFO - 'title': 'talos-linux64-ix-042', 11:11:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:11:24 INFO - 'use_talos_json': True, 11:11:24 INFO - 'verify': 'False', 11:11:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:11:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:11:24 INFO - 'work_dir': 'build'} 11:11:24 INFO - [mozharness: 2017-09-01 18:11:24.313220Z] Running clobber step. 11:11:24 INFO - Running pre-action listener: _resource_record_pre_action 11:11:24 INFO - Running main action method: clobber 11:11:24 INFO - rmtree: /builds/slave/test/build 11:11:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:11:25 INFO - Running post-action listener: _resource_record_post_action 11:11:25 INFO - [mozharness: 2017-09-01 18:11:25.426523Z] Finished clobber step (success) 11:11:25 INFO - [mozharness: 2017-09-01 18:11:25.426623Z] Running read-buildbot-config step. 11:11:25 INFO - Running pre-action listener: _resource_record_pre_action 11:11:25 INFO - Running main action method: read_buildbot_config 11:11:25 INFO - Using buildbot properties: 11:11:25 INFO - { 11:11:25 INFO - "project": "", 11:11:25 INFO - "product": "firefox", 11:11:25 INFO - "who": "ffxbld@noreply.mozilla.org", 11:11:25 INFO - "installer_path": "public/build/target.tar.bz2", 11:11:25 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 11:11:25 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:11:25 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 11:11:25 INFO - "stage_platform": "linux64-stylosequential", 11:11:25 INFO - "basedir": "/builds/slave/test", 11:11:25 INFO - "buildnumber": 54, 11:11:25 INFO - "platform": "ubuntu64_hw_styloseq", 11:11:25 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 11:11:25 INFO - "slavebuilddir": "test", 11:11:25 INFO - "taskId": "U4ExOV0bQzS3omRw3QTG5w", 11:11:25 INFO - "branch": "mozilla-central", 11:11:25 INFO - "script_repo_revision": "production", 11:11:25 INFO - "revision": "d1292636950a4ac1bb818cf05b113e3fcd6babe1", 11:11:25 INFO - "slavename": "talos-linux64-ix-042", 11:11:25 INFO - "repo_path": "mozilla-central" 11:11:25 INFO - } 11:11:25 INFO - Finding installer, test and symbols from parent task. 11:11:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U4ExOV0bQzS3omRw3QTG5w'}, attempt #1 11:11:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U4ExOV0bQzS3omRw3QTG5w'}, attempt #1 11:11:26 INFO - Task dependencies: Aa8zsAsLT3StNXVbTCD9Tg 11:11:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg'}, attempt #1 11:11:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.tar.bz2 11:11:26 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.test_packages.json 11:11:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.crashreporter-symbols.zip 11:11:26 INFO - Running post-action listener: _resource_record_post_action 11:11:26 INFO - [mozharness: 2017-09-01 18:11:26.781040Z] Finished read-buildbot-config step (success) 11:11:26 INFO - [mozharness: 2017-09-01 18:11:26.781256Z] Running download-and-extract step. 11:11:26 INFO - Running pre-action listener: _resource_record_pre_action 11:11:26 INFO - Running main action method: download_and_extract 11:11:26 INFO - mkdir: /builds/slave/test/build/tests 11:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:11:26 INFO - trying https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.test_packages.json 11:11:26 INFO - Downloading https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:11:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:11:27 INFO - Downloaded 989 bytes. 11:11:27 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:11:27 INFO - Using the following test package requirements: 11:11:27 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:11:27 INFO - u'common': [u'target.common.tests.zip'], 11:11:27 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:11:27 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:11:27 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:11:27 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:11:27 INFO - u'mozbase': [u'target.common.tests.zip'], 11:11:27 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:11:27 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:11:27 INFO - u'web-platform': [u'target.common.tests.zip', 11:11:27 INFO - u'target.web-platform.tests.tar.gz'], 11:11:27 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:11:27 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:11:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.common.tests.zip 11:11:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:11:27 INFO - Fetch https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.common.tests.zip into memory 11:11:28 INFO - Content-Length response header: 54957534 11:11:28 INFO - Bytes received: 54957534 11:11:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:11:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.common.tests.zip 11:11:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:11:39 INFO - Fetch https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.common.tests.zip into memory 11:11:40 INFO - Content-Length response header: 54957534 11:11:40 INFO - Bytes received: 54957534 11:11:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.talos.tests.zip 11:11:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:11:51 INFO - Fetch https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.talos.tests.zip into memory 11:11:52 INFO - Content-Length response header: 14496650 11:11:52 INFO - Bytes received: 14496650 11:11: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:11:52 INFO - trying https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.tar.bz2 11:11:52 INFO - Downloading https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:11:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:11:53 INFO - Downloaded 62605694 bytes. 11:11:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.tar.bz2 11:11:53 INFO - mkdir: /builds/slave/test/properties 11:11:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:11:53 INFO - Writing to file /builds/slave/test/properties/build_url 11:11:53 INFO - Contents: 11:11:53 INFO - build_url:https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.tar.bz2 11:11:53 INFO - Running post-action listener: _resource_record_post_action 11:11:53 INFO - Running post-action listener: find_tests_for_verification 11:11:53 INFO - Running post-action listener: set_extra_try_arguments 11:11:53 INFO - [mozharness: 2017-09-01 18:11:53.934903Z] Finished download-and-extract step (success) 11:11:53 INFO - [mozharness: 2017-09-01 18:11:53.934998Z] Running populate-webroot step. 11:11:53 INFO - Running pre-action listener: _resource_record_pre_action 11:11:53 INFO - Running main action method: populate_webroot 11:11:53 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:11:53 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:11:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:11:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:11:53 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:11:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:11:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:11:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:11:53 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:11:53 INFO - 'talos_options': ['--enable-stylo'], 11:11:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:11:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:11:53 INFO - 'tests': ['damp', 'tps']}, 11:11:53 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:11:53 INFO - 'talos_options': ['--enable-stylo'], 11:11:53 INFO - 'tests': ['damp', 'tps']}, 11:11:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:11:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:11:53 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:11:53 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:11:53 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:11:53 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:11:53 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:11:53 INFO - 'talos_options': ['--enable-stylo'], 11:11:53 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:11:53 INFO - 'other-e10s': {'tests': ['a11yr', 11:11:53 INFO - 'ts_paint', 11:11:53 INFO - 'tpaint', 11:11:53 INFO - 'sessionrestore', 11:11:53 INFO - 'sessionrestore_many_windows', 11:11:53 INFO - 'sessionrestore_no_auto_restore', 11:11:53 INFO - 'tabpaint']}, 11:11:53 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:11:53 INFO - 'tests': ['a11yr', 11:11:53 INFO - 'ts_paint', 11:11:53 INFO - 'tpaint', 11:11:53 INFO - 'sessionrestore', 11:11:53 INFO - 'sessionrestore_many_windows', 11:11:53 INFO - 'sessionrestore_no_auto_restore', 11:11:53 INFO - 'tabpaint']}, 11:11:53 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:11:53 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:11:53 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:11:53 INFO - 'tests': ['bloom_basic_singleton']}, 11:11:53 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:11:53 INFO - 'tests': ['bloom_basic']}, 11:11:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:11:53 INFO - 'tsvgr_opacity', 11:11:53 INFO - 'tart', 11:11:53 INFO - 'tscrollx', 11:11:53 INFO - 'tsvg_static']}, 11:11:53 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:11:53 INFO - 'tests': ['tsvgx', 11:11:53 INFO - 'tsvgr_opacity', 11:11:53 INFO - 'tart', 11:11:53 INFO - 'tscrollx', 11:11:53 INFO - 'tsvg_static']}, 11:11:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:11:53 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:11:53 INFO - 'talos_options': ['--enable-stylo'], 11:11:53 INFO - 'tests': ['tp5o']}, 11:11:53 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:11:53 INFO - 'talos_options': ['--mitmproxy', 11:11:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:11:53 INFO - '--firstNonBlankPaint'], 11:11:53 INFO - 'tests': ['tp6_google', 11:11:53 INFO - 'tp6_youtube', 11:11:53 INFO - 'tp6_amazon', 11:11:53 INFO - 'tp6_facebook']}, 11:11:53 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:11:53 INFO - 'talos_options': ['--enable-stylo', 11:11:53 INFO - '--mitmproxy', 11:11:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:11:53 INFO - '--firstNonBlankPaint'], 11:11:53 INFO - 'tests': ['tp6_google', 11:11:53 INFO - 'tp6_youtube', 11:11:53 INFO - 'tp6_amazon', 11:11:53 INFO - 'tp6_facebook']}, 11:11:53 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:11:53 INFO - 'talos_options': ['--enable-stylo', 11:11:53 INFO - '--stylo-threads=1', 11:11:53 INFO - '--mitmproxy', 11:11:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:11:53 INFO - '--firstNonBlankPaint'], 11:11:53 INFO - 'tests': ['tp6_google', 11:11:53 INFO - 'tp6_youtube', 11:11:53 INFO - 'tp6_amazon', 11:11:53 INFO - 'tp6_facebook']}, 11:11:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:11:53 INFO - 'talos_options': ['--xperf_path', 11:11:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:11:53 INFO - 'tests': ['tp5n']}, 11:11:53 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:11:53 INFO - 'talos_options': ['--enable-stylo', 11:11:53 INFO - '--xperf_path', 11:11:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:11:53 INFO - 'tests': ['tp5n']}}} 11:11:53 INFO - Running post-action listener: _resource_record_post_action 11:11:53 INFO - [mozharness: 2017-09-01 18:11:53.949190Z] Finished populate-webroot step (success) 11:11:53 INFO - [mozharness: 2017-09-01 18:11:53.949277Z] Running create-virtualenv step. 11:11:53 INFO - Running pre-action listener: _resource_record_pre_action 11:11:53 INFO - Running main action method: create_virtualenv 11:11:53 INFO - Creating virtualenv /builds/slave/test/build/venv 11:11:53 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:11:53 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:11:53 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:11:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:11:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:11:54 INFO - Installing distribute.............................................................................................................................................................................................done. 11:11:54 INFO - Installing pip...............done. 11:11:54 INFO - Return code: 0 11:11:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:11:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:11:54 INFO - Reading from file tmpfile_stdout 11:11:54 INFO - Output received: 11:11:54 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:11:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:11: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:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11: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:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e4090>, '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 0x16e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16def90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', '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:11: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', 'pip>=1.5'] in /builds/slave/test/build 11:11: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 pip>=1.5 11:11:54 INFO - Using env: {'DISPLAY': ':0', 11:11:54 INFO - 'HOME': '/home/cltbld', 11:11:54 INFO - 'LANG': 'en_US.UTF-8', 11:11:54 INFO - 'LANGUAGE': 'en_US:en', 11:11:54 INFO - 'LOGNAME': 'cltbld', 11:11:54 INFO - 'MAIL': '/var/mail/cltbld', 11:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:54 INFO - 'MOZ_NO_REMOTE': '1', 11:11:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:54 INFO - 'NO_EM_RESTART': '1', 11:11:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:11:54 INFO - 'PWD': '/builds/slave/test', 11:11:54 INFO - 'SHELL': '/bin/bash', 11:11:54 INFO - 'SHLVL': '1', 11:11:54 INFO - 'TERM': 'linux', 11:11:54 INFO - 'TMOUT': '86400', 11:11:54 INFO - 'USER': 'cltbld', 11:11:54 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', 11:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:54 INFO - '_': '/tools/buildbot/bin/python'} 11:11:56 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:11:56 INFO - Downloading/unpacking pip>=1.5 11:11:56 INFO - Running setup.py egg_info for package pip 11:11:56 INFO - warning: no files found matching 'pip/cacert.pem' 11:11:56 INFO - warning: no files found matching '*.html' under directory 'docs' 11:11:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:11:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:11:56 INFO - Installing collected packages: pip 11:11:56 INFO - Found existing installation: pip 1.1 11:11:56 INFO - Uninstalling pip: 11:11:56 INFO - Successfully uninstalled pip 11:11:56 INFO - Running setup.py install for pip 11:11:56 INFO - warning: no files found matching 'pip/cacert.pem' 11:11:56 INFO - warning: no files found matching '*.html' under directory 'docs' 11:11:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:11:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:11:56 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:11:56 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:11:56 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:11:56 INFO - Successfully installed pip 11:11:56 INFO - Cleaning up... 11:11:56 INFO - Return code: 0 11:11:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:11:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:11:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11:56 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 0x13741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e4090>, '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 0x16e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16def90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', '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:11:56 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:11:56 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:11:56 INFO - Using env: {'DISPLAY': ':0', 11:11:56 INFO - 'HOME': '/home/cltbld', 11:11:56 INFO - 'LANG': 'en_US.UTF-8', 11:11:56 INFO - 'LANGUAGE': 'en_US:en', 11:11:56 INFO - 'LOGNAME': 'cltbld', 11:11:56 INFO - 'MAIL': '/var/mail/cltbld', 11:11:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:56 INFO - 'MOZ_NO_REMOTE': '1', 11:11:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:56 INFO - 'NO_EM_RESTART': '1', 11:11:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:11:56 INFO - 'PWD': '/builds/slave/test', 11:11:56 INFO - 'SHELL': '/bin/bash', 11:11:56 INFO - 'SHLVL': '1', 11:11:56 INFO - 'TERM': 'linux', 11:11:56 INFO - 'TMOUT': '86400', 11:11:56 INFO - 'USER': 'cltbld', 11:11:56 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', 11:11:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:56 INFO - '_': '/tools/buildbot/bin/python'} 11:11:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:11:56 INFO - Downloading/unpacking psutil>=3.1.1 11:11: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:11: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:11: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:11: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:11:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:11:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:11:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:11:57 INFO - Installing collected packages: psutil 11:11:57 INFO - Running setup.py install for psutil 11:11:57 INFO - building 'psutil._psutil_linux' extension 11:11:57 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:11:57 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:11:57 INFO - building 'psutil._psutil_posix' extension 11:11:57 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:11:58 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:11:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:11:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:11:58 INFO - Successfully installed psutil 11:11:58 INFO - Cleaning up... 11:11:58 INFO - Return code: 0 11:11:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:11:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11:58 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 0x13741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e4090>, '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 0x16e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16def90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', '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:11:58 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:11:58 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:11:58 INFO - Using env: {'DISPLAY': ':0', 11:11:58 INFO - 'HOME': '/home/cltbld', 11:11:58 INFO - 'LANG': 'en_US.UTF-8', 11:11:58 INFO - 'LANGUAGE': 'en_US:en', 11:11:58 INFO - 'LOGNAME': 'cltbld', 11:11:58 INFO - 'MAIL': '/var/mail/cltbld', 11:11:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:58 INFO - 'MOZ_NO_REMOTE': '1', 11:11:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:58 INFO - 'NO_EM_RESTART': '1', 11:11:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:11:58 INFO - 'PWD': '/builds/slave/test', 11:11:58 INFO - 'SHELL': '/bin/bash', 11:11:58 INFO - 'SHLVL': '1', 11:11:58 INFO - 'TERM': 'linux', 11:11:58 INFO - 'TMOUT': '86400', 11:11:58 INFO - 'USER': 'cltbld', 11:11:58 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', 11:11:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:58 INFO - '_': '/tools/buildbot/bin/python'} 11:11:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:11:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:11: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:11: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:11: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:11: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:11:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:11:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:11:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:11:59 INFO - Installing collected packages: mozsystemmonitor 11:11:59 INFO - Running setup.py install for mozsystemmonitor 11:11:59 INFO - Successfully installed mozsystemmonitor 11:11:59 INFO - Cleaning up... 11:11:59 INFO - Return code: 0 11:11:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:11:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11:59 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 0x13741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e4090>, '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 0x16e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16def90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', '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:11:59 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:11:59 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:11:59 INFO - Using env: {'DISPLAY': ':0', 11:11:59 INFO - 'HOME': '/home/cltbld', 11:11:59 INFO - 'LANG': 'en_US.UTF-8', 11:11:59 INFO - 'LANGUAGE': 'en_US:en', 11:11:59 INFO - 'LOGNAME': 'cltbld', 11:11:59 INFO - 'MAIL': '/var/mail/cltbld', 11:11:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:59 INFO - 'MOZ_NO_REMOTE': '1', 11:11:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:59 INFO - 'NO_EM_RESTART': '1', 11:11:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:11:59 INFO - 'PWD': '/builds/slave/test', 11:11:59 INFO - 'SHELL': '/bin/bash', 11:11:59 INFO - 'SHLVL': '1', 11:11:59 INFO - 'TERM': 'linux', 11:11:59 INFO - 'TMOUT': '86400', 11:11:59 INFO - 'USER': 'cltbld', 11:11:59 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', 11:11:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:59 INFO - '_': '/tools/buildbot/bin/python'} 11:11:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:11:59 INFO - Downloading/unpacking jsonschema==2.5.1 11:11: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:11: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:11: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:11: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:12:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:12:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:01 INFO - Downloading functools32-3.2.3-2.tar.gz 11:12:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:12:01 INFO - warning: no files found matching '*.txt' 11:12:01 INFO - no previously-included directories found matching 'build' 11:12:01 INFO - no previously-included directories found matching 'dist' 11:12:01 INFO - no previously-included directories found matching '.git*' 11:12:01 INFO - Installing collected packages: jsonschema, functools32 11:12:01 INFO - Running setup.py install for functools32 11:12:01 INFO - warning: no files found matching '*.txt' 11:12:01 INFO - no previously-included directories found matching 'build' 11:12:01 INFO - no previously-included directories found matching 'dist' 11:12:01 INFO - no previously-included directories found matching '.git*' 11:12:01 INFO - Successfully installed jsonschema functools32 11:12:01 INFO - Cleaning up... 11:12:01 INFO - Return code: 0 11:12:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:12:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:01 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 0x13741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e4090>, '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 0x16e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16def90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', '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:12:01 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:12:01 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:12:01 INFO - Using env: {'DISPLAY': ':0', 11:12:01 INFO - 'HOME': '/home/cltbld', 11:12:01 INFO - 'LANG': 'en_US.UTF-8', 11:12:01 INFO - 'LANGUAGE': 'en_US:en', 11:12:01 INFO - 'LOGNAME': 'cltbld', 11:12:01 INFO - 'MAIL': '/var/mail/cltbld', 11:12:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:01 INFO - 'MOZ_NO_REMOTE': '1', 11:12:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:01 INFO - 'NO_EM_RESTART': '1', 11:12:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:01 INFO - 'PWD': '/builds/slave/test', 11:12:01 INFO - 'SHELL': '/bin/bash', 11:12:01 INFO - 'SHLVL': '1', 11:12:01 INFO - 'TERM': 'linux', 11:12:01 INFO - 'TMOUT': '86400', 11:12:01 INFO - 'USER': 'cltbld', 11:12:01 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', 11:12:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:01 INFO - '_': '/tools/buildbot/bin/python'} 11:12:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:12:01 INFO - Cleaning up... 11:12:01 INFO - Return code: 0 11:12:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:12:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:01 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 0x13741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e4090>, '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 0x16e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16def90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', '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:12:01 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:12:01 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:12:01 INFO - Using env: {'DISPLAY': ':0', 11:12:01 INFO - 'HOME': '/home/cltbld', 11:12:01 INFO - 'LANG': 'en_US.UTF-8', 11:12:01 INFO - 'LANGUAGE': 'en_US:en', 11:12:01 INFO - 'LOGNAME': 'cltbld', 11:12:01 INFO - 'MAIL': '/var/mail/cltbld', 11:12:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:01 INFO - 'MOZ_NO_REMOTE': '1', 11:12:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:01 INFO - 'NO_EM_RESTART': '1', 11:12:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:01 INFO - 'PWD': '/builds/slave/test', 11:12:01 INFO - 'SHELL': '/bin/bash', 11:12:01 INFO - 'SHLVL': '1', 11:12:01 INFO - 'TERM': 'linux', 11:12:01 INFO - 'TMOUT': '86400', 11:12:01 INFO - 'USER': 'cltbld', 11:12:01 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', 11:12:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:01 INFO - '_': '/tools/buildbot/bin/python'} 11:12:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:01 INFO - Downloading/unpacking blobuploader==1.2.4 11:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:02 INFO - Downloading blobuploader-1.2.4.tar.gz 11:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:12:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:12:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:12:03 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:12:03 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:12:03 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:12:03 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:12:03 INFO - Downloading docopt-0.6.1.tar.gz 11:12:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:12:03 INFO - Installing collected packages: blobuploader, requests, docopt 11:12:03 INFO - Running setup.py install for blobuploader 11:12:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:12:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:12:03 INFO - Running setup.py install for requests 11:12:03 INFO - Running setup.py install for docopt 11:12:04 INFO - Successfully installed blobuploader requests docopt 11:12:04 INFO - Cleaning up... 11:12:04 INFO - Return code: 0 11:12:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:12:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:04 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 0x13741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e4090>, '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 0x16e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16def90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', '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:12:04 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:12:04 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:12:04 INFO - Using env: {'DISPLAY': ':0', 11:12:04 INFO - 'HOME': '/home/cltbld', 11:12:04 INFO - 'LANG': 'en_US.UTF-8', 11:12:04 INFO - 'LANGUAGE': 'en_US:en', 11:12:04 INFO - 'LOGNAME': 'cltbld', 11:12:04 INFO - 'MAIL': '/var/mail/cltbld', 11:12:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:04 INFO - 'MOZ_NO_REMOTE': '1', 11:12:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:04 INFO - 'NO_EM_RESTART': '1', 11:12:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:04 INFO - 'PWD': '/builds/slave/test', 11:12:04 INFO - 'SHELL': '/bin/bash', 11:12:04 INFO - 'SHLVL': '1', 11:12:04 INFO - 'TERM': 'linux', 11:12:04 INFO - 'TMOUT': '86400', 11:12:04 INFO - 'USER': 'cltbld', 11:12:04 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', 11:12:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:04 INFO - '_': '/tools/buildbot/bin/python'} 11:12:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:12:04 INFO - Running setup.py (path:/tmp/pip-VHfDPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:12:04 INFO - Running setup.py (path:/tmp/pip-nZbBz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:12:04 INFO - Running setup.py (path:/tmp/pip-F15Gtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:12:04 INFO - Running setup.py (path:/tmp/pip-uHixnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:12:04 INFO - Running setup.py (path:/tmp/pip-ebKHNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:12:04 INFO - Running setup.py (path:/tmp/pip-7rt6Ma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:12:04 INFO - Running setup.py (path:/tmp/pip-ogZ5iU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:12:04 INFO - Running setup.py (path:/tmp/pip-MbG6_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:12:04 INFO - Running setup.py (path:/tmp/pip-hYOD47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:12:04 INFO - Running setup.py (path:/tmp/pip-tm5jiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:12:05 INFO - Running setup.py (path:/tmp/pip-BEb3HO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:12:05 INFO - Running setup.py (path:/tmp/pip-0lHyNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:12:05 INFO - Running setup.py (path:/tmp/pip-ybqNrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:12:05 INFO - Running setup.py (path:/tmp/pip-VNdo3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:05 INFO - Running setup.py (path:/tmp/pip-1TT5ok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:12:05 INFO - Running setup.py (path:/tmp/pip-hwUJ9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:12:05 INFO - Running setup.py (path:/tmp/pip-LGfYTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:12:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:12:05 INFO - Running setup.py install for manifestparser 11:12:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:12:05 INFO - Running setup.py install for mozcrash 11:12:05 INFO - Running setup.py install for mozdebug 11:12:05 INFO - Running setup.py install for mozdevice 11:12:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:12:05 INFO - Running setup.py install for mozfile 11:12:06 INFO - Running setup.py install for mozhttpd 11:12:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:12:06 INFO - Running setup.py install for mozinfo 11:12:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:12:06 INFO - Running setup.py install for mozInstall 11:12:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:12:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:12:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:12:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:12:06 INFO - Running setup.py install for mozleak 11:12:06 INFO - Running setup.py install for mozlog 11:12:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:12:06 INFO - Running setup.py install for moznetwork 11:12:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:12:06 INFO - Running setup.py install for mozprocess 11:12:06 INFO - Running setup.py install for mozprofile 11:12:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:12:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:12:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:12:06 INFO - Running setup.py install for mozrunner 11:12:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:12:06 INFO - Running setup.py install for mozscreenshot 11:12:07 INFO - Running setup.py install for moztest 11:12:07 INFO - Running setup.py install for mozversion 11:12:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:12:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:12:07 INFO - Cleaning up... 11:12:07 INFO - Return code: 0 11:12:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:12:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:07 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 0x13741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e4090>, '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 0x16e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16def90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', '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:12:07 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:12:07 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:12:07 INFO - Using env: {'DISPLAY': ':0', 11:12:07 INFO - 'HOME': '/home/cltbld', 11:12:07 INFO - 'LANG': 'en_US.UTF-8', 11:12:07 INFO - 'LANGUAGE': 'en_US:en', 11:12:07 INFO - 'LOGNAME': 'cltbld', 11:12:07 INFO - 'MAIL': '/var/mail/cltbld', 11:12:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:07 INFO - 'MOZ_NO_REMOTE': '1', 11:12:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:07 INFO - 'NO_EM_RESTART': '1', 11:12:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:07 INFO - 'PWD': '/builds/slave/test', 11:12:07 INFO - 'SHELL': '/bin/bash', 11:12:07 INFO - 'SHLVL': '1', 11:12:07 INFO - 'TERM': 'linux', 11:12:07 INFO - 'TMOUT': '86400', 11:12:07 INFO - 'USER': 'cltbld', 11:12:07 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', 11:12:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:07 INFO - '_': '/tools/buildbot/bin/python'} 11:12:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:12:07 INFO - Running setup.py (path:/tmp/pip-GQRKBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:12:07 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:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:12:07 INFO - Running setup.py (path:/tmp/pip-EfJowe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:12:07 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:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:12:07 INFO - Running setup.py (path:/tmp/pip-oQkc47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:12:07 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:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:12:07 INFO - Running setup.py (path:/tmp/pip-4B0yIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:12:07 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:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:12:07 INFO - Running setup.py (path:/tmp/pip-8yIi1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:12:07 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:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:12:07 INFO - Running setup.py (path:/tmp/pip-bXDvre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:12:07 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:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:12:07 INFO - Running setup.py (path:/tmp/pip-xoBnWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:12:07 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:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:12:07 INFO - Running setup.py (path:/tmp/pip-Sx5lmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:12:08 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:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:12:08 INFO - Running setup.py (path:/tmp/pip-5pApKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:12:08 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:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:12:08 INFO - Running setup.py (path:/tmp/pip-JcjT_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:12:08 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:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:12:08 INFO - Running setup.py (path:/tmp/pip-ylXRWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:12:08 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:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:12:08 INFO - Running setup.py (path:/tmp/pip-9lWip9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:12:08 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:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:12:08 INFO - Running setup.py (path:/tmp/pip-K6WG4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:12:08 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:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:12:08 INFO - Running setup.py (path:/tmp/pip-jxwQpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:12:08 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:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:08 INFO - Running setup.py (path:/tmp/pip-hez5AQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:08 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:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:12:08 INFO - Running setup.py (path:/tmp/pip-XgIJ7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:12:08 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:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:12:08 INFO - Running setup.py (path:/tmp/pip-hXEc54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:12:08 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:12:08 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:12:08 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:12:08 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:12:08 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:12:08 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:12:08 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:12:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:12:08 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:12:08 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:12:08 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:12:08 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:12:09 INFO - Downloading blessings-1.6.tar.gz 11:12:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:12:09 INFO - Installing collected packages: blessings 11:12:09 INFO - Running setup.py install for blessings 11:12:09 INFO - Successfully installed blessings 11:12:10 INFO - Cleaning up... 11:12:10 INFO - Return code: 0 11:12:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:12:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:12:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:12:10 INFO - Reading from file tmpfile_stdout 11:12:10 INFO - Current package versions: 11:12:10 INFO - argparse == 1.2.1 11:12:10 INFO - blessings == 1.6 11:12:10 INFO - blobuploader == 1.2.4 11:12:10 INFO - docopt == 0.6.1 11:12:10 INFO - functools32 == 3.2.3-2 11:12:10 INFO - jsonschema == 2.5.1 11:12:10 INFO - manifestparser == 1.1 11:12:10 INFO - mozInstall == 1.13 11:12:10 INFO - mozcrash == 1.0 11:12:10 INFO - mozdebug == 0.1 11:12:10 INFO - mozdevice == 0.51 11:12:10 INFO - mozfile == 1.2 11:12:10 INFO - mozhttpd == 0.7 11:12:10 INFO - mozinfo == 0.10 11:12:10 INFO - mozleak == 0.1 11:12:10 INFO - mozlog == 3.5 11:12:10 INFO - moznetwork == 0.27 11:12:10 INFO - mozprocess == 0.25 11:12:10 INFO - mozprofile == 0.28 11:12:10 INFO - mozrunner == 6.13 11:12:10 INFO - mozscreenshot == 0.1 11:12:10 INFO - mozsystemmonitor == 0.3 11:12:10 INFO - moztest == 0.8 11:12:10 INFO - mozversion == 1.4 11:12:10 INFO - psutil == 3.1.1 11:12:10 INFO - requests == 1.2.3 11:12:10 INFO - wsgiref == 0.1.2 11:12:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:12:10 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:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:10 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:12:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:10 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 0x13741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e4090>, '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 0x16e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16def90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', '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:12:10 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:12:10 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:12:10 INFO - Using env: {'DISPLAY': ':0', 11:12:10 INFO - 'HOME': '/home/cltbld', 11:12:10 INFO - 'LANG': 'en_US.UTF-8', 11:12:10 INFO - 'LANGUAGE': 'en_US:en', 11:12:10 INFO - 'LOGNAME': 'cltbld', 11:12:10 INFO - 'MAIL': '/var/mail/cltbld', 11:12:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:10 INFO - 'MOZ_NO_REMOTE': '1', 11:12:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:10 INFO - 'NO_EM_RESTART': '1', 11:12:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:10 INFO - 'PWD': '/builds/slave/test', 11:12:10 INFO - 'SHELL': '/bin/bash', 11:12:10 INFO - 'SHLVL': '1', 11:12:10 INFO - 'TERM': 'linux', 11:12:10 INFO - 'TMOUT': '86400', 11:12:10 INFO - 'USER': 'cltbld', 11:12:10 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', 11:12:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:10 INFO - '_': '/tools/buildbot/bin/python'} 11:12:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:10 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:12:10 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:12:10 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:12:10 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:12:10 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:12:10 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:12:10 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:12:10 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:12:10 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:12:10 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:12:11 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:12:11 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:12:11 INFO - Installing collected packages: simplejson 11:12:11 INFO - Running setup.py install for simplejson 11:12:11 INFO - building 'simplejson._speedups' extension 11:12:11 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:12:12 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:12:12 INFO - Successfully installed simplejson 11:12:12 INFO - Cleaning up... 11:12:12 INFO - Return code: 0 11:12:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:12:12 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:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:12 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:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x13741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e4090>, '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 0x16e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16def90>, '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': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', '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:12:12 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:12:12 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:12:12 INFO - Using env: {'DISPLAY': ':0', 11:12:12 INFO - 'HOME': '/home/cltbld', 11:12:12 INFO - 'LANG': 'en_US.UTF-8', 11:12:12 INFO - 'LANGUAGE': 'en_US:en', 11:12:12 INFO - 'LOGNAME': 'cltbld', 11:12:12 INFO - 'MAIL': '/var/mail/cltbld', 11:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:12 INFO - 'MOZ_NO_REMOTE': '1', 11:12:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:12 INFO - 'NO_EM_RESTART': '1', 11:12:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:12 INFO - 'PWD': '/builds/slave/test', 11:12:12 INFO - 'SHELL': '/bin/bash', 11:12:12 INFO - 'SHLVL': '1', 11:12:12 INFO - 'TERM': 'linux', 11:12:12 INFO - 'TMOUT': '86400', 11:12:12 INFO - 'USER': 'cltbld', 11:12:12 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', 11:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:12 INFO - '_': '/tools/buildbot/bin/python'} 11:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:12:12 INFO - Cleaning up... 11:12:13 INFO - Return code: 0 11:12:13 INFO - Running post-action listener: _resource_record_post_action 11:12:13 INFO - Running post-action listener: _start_resource_monitoring 11:12:13 INFO - Starting resource monitoring. 11:12:13 INFO - [mozharness: 2017-09-01 18:12:13.036023Z] Finished create-virtualenv step (success) 11:12:13 INFO - [mozharness: 2017-09-01 18:12:13.036487Z] Running install step. 11:12:13 INFO - Running pre-action listener: _resource_record_pre_action 11:12:13 INFO - Running main action method: install 11:12:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:12:13 INFO - Reading from file tmpfile_stdout 11:12:13 INFO - Detecting whether we're running mozinstall >=1.0... 11:12:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:12:13 INFO - Reading from file tmpfile_stdout 11:12:13 INFO - Output received: 11:12:13 INFO - Usage: mozinstall [options] installer 11:12:13 INFO - Options: 11:12:13 INFO - -h, --help show this help message and exit 11:12:13 INFO - -d DEST, --destination=DEST 11:12:13 INFO - Directory to install application into. [default: 11:12:13 INFO - "/builds/slave/test"] 11:12:13 INFO - --app=APP Application being installed. [default: firefox] 11:12:13 INFO - mkdir: /builds/slave/test/build/application 11:12:13 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:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:12:29 INFO - Reading from file tmpfile_stdout 11:12:29 INFO - Output received: 11:12:29 INFO - /builds/slave/test/build/application/firefox/firefox 11:12:29 INFO - Running post-action listener: _resource_record_post_action 11:12:29 INFO - [mozharness: 2017-09-01 18:12:29.877922Z] Finished install step (success) 11:12:29 INFO - [mozharness: 2017-09-01 18:12:29.878073Z] Running setup-mitmproxy step. 11:12:29 INFO - Running pre-action listener: _resource_record_pre_action 11:12:29 INFO - Running main action method: setup_mitmproxy 11:12:29 INFO - Skipping: mitmproxy is not required 11:12:29 INFO - Running post-action listener: _resource_record_post_action 11:12:29 INFO - [mozharness: 2017-09-01 18:12:29.878592Z] Finished setup-mitmproxy step (success) 11:12:29 INFO - [mozharness: 2017-09-01 18:12:29.878694Z] Running run-tests step. 11:12:29 INFO - Running pre-action listener: _resource_record_pre_action 11:12:29 INFO - Running pre-action listener: _set_gcov_prefix 11:12:29 INFO - Running main action method: run_tests 11:12:29 WARNING - Try message not found. 11:12:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:12:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:12:29 INFO - Python 2.7.3 11:12:29 INFO - Return code: 0 11:12:29 INFO - grabbing minidump binary from tooltool 11:12:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:29 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 0x16e4090>, '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 0x16e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16def90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:12:29 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:12:29 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:12:29 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:12:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:12:30 INFO - Return code: 0 11:12:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:12:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:12:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:12:30 INFO - ENV: RUST_BACKTRACE is now full 11:12:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:12:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:12:30 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/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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:12:30 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/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-042 --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:12:30 INFO - Using env: {'DISPLAY': ':0', 11:12:30 INFO - 'HOME': '/home/cltbld', 11:12:30 INFO - 'LANG': 'en_US.UTF-8', 11:12:30 INFO - 'LANGUAGE': 'en_US:en', 11:12:30 INFO - 'LOGNAME': 'cltbld', 11:12:30 INFO - 'MAIL': '/var/mail/cltbld', 11:12:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:12:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:30 INFO - 'MOZ_NO_REMOTE': '1', 11:12:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:12:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:30 INFO - 'NO_EM_RESTART': '1', 11:12:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:30 INFO - 'PWD': '/builds/slave/test', 11:12:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:12:30 INFO - 'RUST_BACKTRACE': 'full', 11:12:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:12:30 INFO - 'SHELL': '/bin/bash', 11:12:30 INFO - 'SHLVL': '1', 11:12:30 INFO - 'STYLO_FORCE_ENABLED': '1', 11:12:30 INFO - 'STYLO_THREADS': '1', 11:12:30 INFO - 'TERM': 'linux', 11:12:30 INFO - 'TMOUT': '86400', 11:12:30 INFO - 'USER': 'cltbld', 11:12:30 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', 11:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:30 INFO - '_': '/tools/buildbot/bin/python'} 11:12:30 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/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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:12:30 INFO - mozversion application_buildid: 20170901171938 11:12:30 INFO - mozversion application_changeset: d1292636950a4ac1bb818cf05b113e3fcd6babe1 11:12:30 INFO - mozversion application_display_name: Nightly 11:12:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:12:30 INFO - mozversion application_name: Firefox 11:12:30 INFO - mozversion application_remotingname: firefox 11:12:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:12:30 INFO - mozversion application_vendor: Mozilla 11:12:30 INFO - mozversion application_version: 57.0a1 11:12:30 INFO - mozversion platform_buildid: 20170901171938 11:12:30 INFO - mozversion platform_changeset: d1292636950a4ac1bb818cf05b113e3fcd6babe1 11:12:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:12:30 INFO - mozversion platform_version: 57.0a1 11:12:30 INFO - using testdate: 1504289550 11:12:30 INFO - actual date: 1504289550 11:12:30 INFO - starting webserver on 'localhost:40985' 11:12:30 INFO - SUITE-START | Running 2 tests 11:12:30 INFO - TEST-START | dromaeo_css 11:12:30 INFO - Initialising browser for dromaeo_css test... 11:12:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40985/getInfo.html -profile /tmp/tmpGl3HA0/profile 11:12:30 INFO - TEST-INFO | started process 19355 (/builds/slave/test/build/application/firefox/firefox http://localhost:40985/getInfo.html) 11:12:36 INFO - TEST-INFO | 19355: exit 0 11:12:36 INFO - Browser initialized. 11:12:36 INFO - Running cycle 1/1 for dromaeo_css test... 11:12:36 INFO - Using env: {'DISPLAY': ':0', 11:12:36 INFO - 'HOME': '/home/cltbld', 11:12:36 INFO - 'JSGC_DISABLE_POISONING': '1', 11:12:36 INFO - 'LANG': 'en_US.UTF-8', 11:12:36 INFO - 'LANGUAGE': 'en_US:en', 11:12:36 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:12:36 INFO - 'LOGNAME': 'cltbld', 11:12:36 INFO - 'MAIL': '/var/mail/cltbld', 11:12:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:12:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:12:36 INFO - 'MOZ_CRASHREPORTER': '1', 11:12:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:36 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:12:36 INFO - 'MOZ_NO_REMOTE': '1', 11:12:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:12:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:36 INFO - 'NO_EM_RESTART': '1', 11:12:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:36 INFO - 'PWD': '/builds/slave/test', 11:12:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:12:36 INFO - 'RUST_BACKTRACE': 'full', 11:12:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:12:36 INFO - 'SHELL': '/bin/bash', 11:12:36 INFO - 'SHLVL': '1', 11:12:36 INFO - 'STYLO_FORCE_ENABLED': '1', 11:12:36 INFO - 'STYLO_THREADS': '1', 11:12:36 INFO - 'TERM': 'linux', 11:12:36 INFO - 'TMOUT': '86400', 11:12:36 INFO - 'USER': 'cltbld', 11:12:36 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', 11:12:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:36 INFO - '_': '/tools/buildbot/bin/python'} 11:12:36 INFO - TEST-INFO | started process 19540 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGl3HA0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:12:37 INFO - PID 19540 | 11:12:37 INFO - PID 19540 | (/builds/slave/test/build/application/firefox/firefox:19592): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:12:37 INFO - PID 19540 | 11:12:47 INFO - PID 19540 | RSS: Main: 171372544 11:12:47 INFO - PID 19540 | 11:15:46 INFO - PID 19540 | Cycle 1(1): loaded http://localhost:40985/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40985/tests/dromaeo/cssquery-ext.html) 11:15:48 INFO - PID 19540 | RSS: Main: 173953024 11:15:48 INFO - PID 19540 | 11:18:49 INFO - PID 19540 | Cycle 1(1): loaded http://localhost:40985/tests/dromaeo/cssquery-ext.html (next: http://localhost:40985/tests/dromaeo/cssquery-jquery.html) 11:18:50 INFO - PID 19540 | RSS: Main: 170602496 11:18:50 INFO - PID 19540 | 11:21:52 INFO - PID 19540 | Cycle 1(1): loaded http://localhost:40985/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40985/tests/dromaeo/cssquery-mootools.html) 11:21:52 INFO - PID 19540 | RSS: Main: 173215744 11:21:52 INFO - PID 19540 | 11:24:54 INFO - PID 19540 | Cycle 1(1): loaded http://localhost:40985/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40985/tests/dromaeo/cssquery-prototype.html) 11:24:55 INFO - PID 19540 | RSS: Main: 169758720 11:24:55 INFO - PID 19540 | 11:27:58 INFO - PID 19540 | Cycle 1(1): loaded http://localhost:40985/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40985/tests/dromaeo/cssquery-yui.html) 11:27:59 INFO - PID 19540 | RSS: Main: 172802048 11:27:59 INFO - PID 19540 | 11:31:01 INFO - PID 19540 | Cycle 1(1): loaded http://localhost:40985/tests/dromaeo/cssquery-yui.html (next: http://localhost:40985/tests/dromaeo/cssquery-dojo.html) 11:31:01 INFO - PID 19540 | RSS: Main: 175501312 11:31:01 INFO - PID 19540 | 11:31:01 INFO - PID 19540 | __start_tp_report 11:31:01 INFO - PID 19540 | _x_x_mozilla_page_load 11:31:01 INFO - PID 19540 | _x_x_mozilla_page_load_details 11:31:01 INFO - PID 19540 | |i|pagename|runs| 11:31:01 INFO - PID 19540 | |0;dojo.html;3265.591801024872;3296.967030329696;3332.46680531115;3341.8997430077097;3367.949480757789;6235.781747638834;6239.376062393759;6258.812235632927;6279.717412716427;6371.872562548746;6430.517711171669;6473.6763161841955;6482.5786323888915;6486.643234622105;6487.3836985486305;5868.647881127146;5924.170616113747;5968.432998865112;5978.581499295051;5985.1022346647915;5658.632188907708;5759.712014399284;5760.049591817345;5849.268841394825;6036.849078773043;5726.541876649879;5736;5776.59563830532;5789.565782566303;5838.4453476919625;5966.701664916737;5990.011648078062;5992.2809262888295;5995.940040599582;6000.549958753111;5830.329512106077;5837.474627283565;5846.064629659234;5920.023196172627;5952.7321270542925;6101.267847858241;6199.132121502993;6389.424951754318;6398.424141827211;6491;6911.654417279166;6915.89626155608;6920.238773734885;6922.06552115463;6924.723011079551;6737.090492783461;6926.203486599055;6935.895961560581;6935.895961560581;6943.27095654954;6331.081993111019;6364.427201551883;6406.2953075161695;6418;6453.645049522274;6944.201416837027;7106.395956343768;7183.353498185188;7188.209296977328;7189.856202876018;6792.049113124167;6812.080369150201;6828.658567071627;6836.829079272959;6881.036654868322;6796.592204467748;6858.588484690935;6942.305769423017;6952.44380449573;7012.053372794608;6574.605523668595;6673.966630166915;6677.899831502531;6825.726970921207;6942.722291108299;6689.063531105649;6698.062271282024;6704.966475167593;6709.161354830646;6715;5314.601404894648;5327.38735045467;5392.595555333366;5401.783928642889;5466.234727138204;4784.545468180587;4853.0779151961015;4875.07373599015;4884.975575122102;4891.3396691446915;6034.48706859913;6052.969735151384;6057.666828324396;6146.016637338004;6148.231471066117;6629.801105966829;6741.898871516931;6876.793696189123;6886.793396198122;7002.929970700329;5998.610090344114;6043.8791224175275;6052.152698622196;6072.574919755663;6073.362296958844;5911.9113213301835;5987.6108052976415;5990.970045149834;5994.130851026577;5999.310079340931;6686.83282917931;6687.799366019027;6760.594364338155;6779.084823548857;6780.948952912405;6635.4359879410795;6705.2959439258175;6728.865422691617;6814.454843612598;6953.826154346182;6737.562058466184;6738.056672065817;6756.69594868242;6761.864762704817;6763.5941843489545;6738.056672066013;6837.1111755471475;6845.965770171118;6854.862902741817;6866.759663411756;6899.655017249218;6913.965430172817;6925.376716095374;7015.052967849275;7017.438604911493;6384.042393640991;6446.903296450463;6653.367930046637;6727.562708423937;6742;6491.318411566717;6515.54391192612;6517.9348206517325;6518.120053792677;6518.771842985473;6372.713227904848;6581.3747693969;6638.705452436729;6660.0675905374255;6661.200655921127;6687.531872768859;6761.797146085625;6765.797026089225;6793;6845.418139458202;6504.0243963405865;6513.641749703812;6580.901286480803;6598.967005164944;6613.933860661525 11:31:01 INFO - PID 19540 | |1;ext.html;792.8810678398239;852.2415050604963;880.8502554565725;887.7824932891442;1144.9313041217533;29381.2654683633;30341.69658303416;39780.2043959121;40708.389374159415;40716.389254161135;40484.19031619366;40676.79661601688;40717.38923916136;40766.388504172384;40868.79565602168;7521.887171692442;7696.538207707527;7706.229377062305;7708.11356693979;7709.1134519530415;7530.322270995609;7640.235976402343;7642.465027448081;7642.656080476392;7645.923540764605;7625.65684544194;7684.039495063117;7687.846243075135;7692.269234422721;7696.268854458817;7581.279778421069;7632.045994250719;7636.3890888728765;7639;7644.770656880247;7520.736774212876;7557.130929943072;7583;7583.279588439144;7587.279208475186;36410.27179456423;36503.4524482133;36512.634873651186;36613;36695.26609467796;3547.7516573839844;3575.463680447928;3576.2311103112957;3578.2306804037257;3582.9283414331826;29750.851245743634;29753.25616859574;29774;29778.55332170019;29783.851080744673;4998.425181104147;5152.948470515322;5188.066148093341;5225.581953443715;5277.102892508245;190016.0499197509;190239;190689;190895.04552477287;190968;46550;46567.534324657;46650.533494665295;46690.766546167055;46735.29897051547;48774.26838597424;49080.509194907594;51654.48345516497;52311.21533177005;52402.21396679053;12679.429425675682;12722.936385318015;14204.36080376365;14524.419023239163;14649.194294313702;11657.941710291394;11682.065434765198;11691.064714822794;11697.532098716127;11699.23954942943;13265.734685306239;13294.933525332504;13299;13356.465741370237;13458;5353.973230133903;5371.516563509303;5382.192671099288;5446.319210098738;5482.205080263419;1010.4038617215731;1020.0717347213999;1021.3259248895841;1036.9429681367596;1037.123630532199;22934.541309174056;23357.59854408741;23584;30480.390392192032;30673.84663076715;1080.529969463286;1116.4696769034645;1120.8374785656035;1126.8985791278824;1136.0684238924168;12208.08439367051;12310.938445307716;12489.375531223403;12586.43361048755;12597.874021259853;220758;222119;223152;223282;223622;51412.22881656778;51960.22059669108;52020.73989630028;52215.69460763435;52261.2160817588;1446.2190417174609;1479.297333766474;1498.3781730581686;1507.2463768115942;1510.735621266274;23203.303900883002;23300.76699232986;23373.532529349657;23377.76622233824;23562.882185588962;37680.24639507249;37847.81076094712;38024;38107.809460952514;38154.61845381511;209221;210298;210399;210506;211611;34868.302633947686;35014.299714005065;35583.644163558034;37207.44188837224;37429.6257037437;44244.55755442404;45737.71311434348;45742;45834.77082614566;45927.31109033434;32917.17707057343;33642;33881.32237355288;34033.48949765706;34461.310773784884;33164.50253246155;33400.83299583486;33424.83287583644;33689.831550842086;33909.49135762916;37792.811035944644;37963.050923727125;38066.80966595149;38097.80951095227;38113.80943095267;32666.510002349507;33047.834760826045;33143.83428082844;33518.162045949044;33599.328013440085;24777;24957.251282461555;25003;25342.746572534037;25404 11:31:01 INFO - PID 19540 | |2;jquery.html;563.4534501533514;563.7575842387774;563.9351474580425;575.9164921086442;577.6562945047697;21219.893900530515;21906.23328183514;21936;21972.121115155376;22125.446863828412;24872.87563562184;25264.2420727378;25277.873610631967;25297.74702252977;25351.2394628161;6504.154459920203;6655.700493477781;6662.800115996528;6663.133792606955;6680.031395447657;6328.145700330466;6575.901361479581;6615.702293396785;6615.933840661604;6620.569662971916;6569.638669873155;6571.901421478682;6585.374389432996;6589.505787065989;6600.042993765901;6563.901541476857;6576.243731970837;6581.407673309425;6581.703823327962;6589.374009469093;6089.330173680892;6101.664408457533;6112.480439162677;6121.693915304217;6121.938780612181;28757.424851502852;28975.85512072447;29709;29779.851100744574;29904;2089.0808044460387;2108.968365474519;2111.3138230075133;2119.0993827623197;2122.543653114588;19230.71153932692;19919.701204481946;19920.90039549793;19935.800641993683;19946.80053199464;2329.743728189898;2360.0581449414412;2400.303911865557;2435.330284171867;2456.680631517891;134462;137372.999999999;137417;137639;137748;14434.278286085864;14549.78175327371;14636.195009274592;14896.180710060835;15231.390744370101;23746.287611371685;23759;23772;23782.405439864142;23820.166294179966;9879.555420006118;9980.80038399228;9981.401115933068;10017.499125043865;10111;7224.1692205396;7518.511296765692;8732;9270.212031977222;9438.669646562337;8953.59708813105;9074.727758167266;9081.59132839024;9132.543372831331;9455.952720236355;2645.936169787569;2675.892964281407;2701.257154282588;2752.40823223008;2845.7581105606255;963.6868017894212;972.3388096094724;977.8044391121804;985.1822986920838;995.4922989275522;19238.134283957257;19650.312239071565;19737.605247895248;20085.196592136443;20100.095495702728;1027.7790275090892;1030.3354336453096;1032.390889375272;1038.1539045677748;1043.6295115234134;10711.357318560755;10902.345859248471;10982;11010.50452729629;11097.83353249702;486618;491600;494169;518642;519970;109227.45386273018;114550.42724786323;114867.42566287114;115952.42023789827;119231;1191.3090407563593;1204.0348285780997;1251.2805137046053;1255.9372031398577;1260.3698150924538;20446.693299600814;20554.17783288652;20856;20924.267650632155;20935.58128837445;54901.17648235359;55008.724956374965;55906.44093559012;57154.428455716574;57948.710256448445;72737;72957.6352118236;73182;73367;73435.63282183555;12262.26426414418;12694.746105078031;14153.716925661634;14328.785068223777;14653.267336632913;22986.655200172347;23111.30666079953;23141.88429057844;23272.767272327736;23302;18166.636667266317;18282.085895704895;18521.81478185201;18721.595880309305;18740.718889216947;16664;16816.831831681528;17052.06213658261;17230.569235769206;17378.47864564065;16655.91672041632;18031.909840450713;18135.909320453313;18315.633687325913;18317.084145792924;14440.494582689555;14539.781903271249;14571.781423278871;14587.781183282046;14617.122972621677;16541.090240036912;16578.336866525686;16738.330466781194;16754.748678769585;18401 11:31:01 INFO - PID 19540 | |3;mootools.html;1316.993415032925;1364.188307956766;1369.5138225929795;1377.2769296119536;1379.7102608452224;12136.39318034097;14789.704205915888;15442;15463.994840335385;15585.532434026969;12849.678758031054;14379.137251764902;15632.062076275432;15669.529914102566;15679.843201567981;2650.5626571615708;2710.9593356099676;2718.6329845470805;2723.7820974322008;2724.482348353816;2581.6256642786784;2666.2267942296808;2745.6842463116795;2866.183137805726;2867.211516832867;2780.986095069522;2793.3016745813547;2813.71862813718;2815.0569559197693;2819.873105710248;2749.42262124954;2752.4357506711176;2779.3885345224016;2782.8608569571543;2788.6514185726683;2721.2380533450664;2728.236093893713;2736.2338545207176;2738.5755207942602;2793.608894754736;15024.849751502454;15063.472778452702;15227.923860380628;15236.314365853563;15236.771448428282;8015.639296231683;8360.623771930277;8366;8369.246767790928;8374.623141958626;5417.783288668488;5451.809186678487;5584.21820945068;5743.913841292342;5811.186433899257;1939.360011196302;1974.0820518458843;1976.1107501624326;1994.1524851938013;2003.4590660521578;25040;25237.36906577332;25240.873795631087;25271.74728252712;25288.620670689954;7040.683169257396;7155;7320.7071717131785;7403.333699966921;7404.815229563244;20524.179032838518;20646.07092680833;20665.690014649794;20706.378808635764;20709.171633134807;8132.308753755997;8353.624086916103;8366.498010119412;8368.121347258571;8371.911651485269;7431.851362972819;7454.068241469817;7499.737509187153;7509.173990861001;7510.511816731895;5183.14478111115;5350.946490535045;5566.526845218121;5726.627769194989;5750.769969201268;1790.8477779388897;1791.6864548703925;1831.6977698679834;1838.4944140361508;1861.0973677766272;899.8920129584492;917.3670167584345;926.6108234541508;932.9486878221763;937.7749340158313;8464.280536154496;8775.166359195866;8778.824423511494;8795.956020219857;8841.16008979146;981.3326937682302;1005.5424781724298;1006.3257617396362;1012.5342342522375;1017.31839667423;4357.411749413852;4375.496817865923;4470;5416.295881535375;5604.243427137366;20873.26943556968;21009.26467573658;21573.892130539247;21628.13487460484;21634.350969470943;20904.37286881396;21325.36023919285;21495.46261343479;21688.891555542123;21700.34898953034;11102;11358.375289359165;11364.94317528407;11387.658370248906;11404.429778510876;8225;8606.440581362442;8638.82722345537;8706.347023973101;8871.112888711077;18206.45380638583;18696.7195492065;18773.90613046926;18975.33586324472;18978.43064708061;18555.721664174776;18979.956102413948;19021.619567608846;19029.809701902803;19220.519487012938;11741.76711445286;11806.940965295118;11928.224665396721;11982.460789264678;12009.879901200877;11163.274387164809;11436.142289328167;11489.942550287195;13754.793678095037;13981.790273145707;11394.943025285153;11522.539098436304;11586.594469193538;11795.823062653895;11980.101492387932;10812.513436895515;10819.621313253998;10832.295900766425;11036.172287078662;11143;8301.83396332058;8338.458000229966;8367.246947774729;8415.326773857954;8441.535715535705;6571.112899758663;6629.933700663125;6656.7670131545165;6769.627670478171;6771.525993180627;6747.763828265987;6749.730010799513;6777.93222067773;6835.213950395768;6867.931320686729 11:31:01 INFO - PID 19540 | |4;prototype.html;457.40994117588315;506.54664075652295;510.41812333939293;510.6578592343131;533.8665333666584;6355.8093257202245;6406.199225096863;6414.198225221848;6479.935200647992;6523.412892839643;6311.77908773193;6333.398327158912;6341.365863413656;6420.1653785008;6422.325655806143;5203.323567936163;5206.739663016853;5219.450773474036;5244.842654720364;5253.658512196714;4898.632602554805;5031.295618613397;5169.09540830355;5242.764075616587;5272.868178295555;5044.798208071691;5079.111155547765;5100.8778068824795;5129.435522165738;5147.485251474841;4992.850214493571;5044.5622997445735;5049.949500504985;5086.542211200973;5130.4613015633195;4333.848315308981;4462.286034234539;4503.48209955856;4572.314152877062;4580.267157254856;6260.342664020303;6279.466245369149;6289.96855015722;6310.453938784981;6330.208723909512;5831.591788574801;5851.385604511507;5856.326522449887;5871.706414679249;5900.232969713953;6751.594904305708;6781.796546103625;6793.252742198354;6806.353396427331;6821.283765204632;4987.900241995139;4988.87527811804;4997.50024997501;5026.396832380101;5036.395632524121;6852;6971.581705097659;6996.825079373006;7008.229094799568;7150.642467876585;4972.801087956514;5112.948870511322;5115.291413725094;5132.127538318495;5132.692038477703;5197.792088316501;5224.223763920209;5228;5228.6339956203465;5236.136037553761;5777.700017496029;5789.45000224978;5811.157382179645;5832.212651292106;5833.970830145822;6657.434118099919;6826.692798824065;6842.692078856463;6842.965785171043;6856.657167141623;1750.5732827745346;1797.2271923073204;1807.593291509426;1813.0871930113856;1820.453863840869;1156.2484385149553;1219.7438537906962;1226.589092653971;1237.0227520259075;1250.6810763255312;5975.133605627247;5995.970020149958;6069.423404776539;6075.301340345828;6075.5747097703625;6919.584824910521;6923.930760692429;6936.063631409796;6989.860202795916;7018.684159212848;5922.2597175353085;5972.372900845436;6032.4570788629235;6040.244969378828;6078.179445774852;3634.436662317345;3636.9272614547563;3651.653092956165;3652.8538858445895;3695.519582454264;6660.770410789184;6696.263411024784;6721.798346049626;6721.966390168116;6763.782519146503;6693.698783554654;6934.578411425646;7017.508774385846;7096.467764917652;7121.394681452031;6534.9673251634385;6619.602823830585;6654.767083152066;6702.564333318416;6747.426468750211;6800.979853022086;6821.863562728817;6828.385445309747;6965.616891071039;6970.407515361251;6777.45975133998;6806;6820.283870193558;6856.5200435969;6898.965505172643;6677.833054173684;6743.763968261087;6794;6802.421794147553;6813.398851270047;5066.949330506648;5103.5599028845145;5138.537531622172;5147.459516750687;5153;6681;6903.965480172567;6905.343992320722;6915.792526224222;6981.476389270927;6441.323661015525;6562.573432726857;6675.365840245169;6715.93284067153;6732.855414579435;6618.7352505899225;6659.300773418721;6681.699323530355;6687.465002799861;6742.471573160065;6530.869382612416;6559.0817285579105;6640.568363056386;6645.568038077509;6669.7665581704405;6709.530332876652;6727.798166055026;6765.902570203253;6812.761553345609;6858.794236172923;6455;6468.353164683494;6493.675316234075;6525.9021114684265;6580.7038683260325 11:31:01 INFO - PID 19540 | |5;yui.html;890.7639475538988;907.5144797533321;913.8629205619161;918.6922381002364;925.7454200094973;18861.905690471547;19883;19915.402537923866;19932.50168745782;19946;16194.190290485487;16391.09848958307;21572.13711451544;21594.24420145295;21611.45971350713;965.3869792681645;988.8912219655832;991.5092029445399;992.5979978108877;994.636957510507;900.4462255712755;983.4640121133999;1002.8194924913549;1007.5012868630001;1007.7329507680471;1045.9163266938665;1047.3820445936897;1048.821700310949;1051.2220956492179;1051.8001579699871;1039.6153423233377;1045.1168762395764;1045.46158728255;1046.0376453662648;1046.8429735539692;774.6552784011118;775.670340105453;777.063638315828;777.793884620576;779.174075479993;19607.705884411604;19830.900845495824;20237.595248094956;20252.291169809134;20430.591388172303;21433.892830535748;21495.892520537298;21873.453163671034;22007.22974695878;22007.449813754625;16703.582410439714;16886.240119194666;17089.572760681083;17091.743623845654;17268.56828579283;3741.9438708419398;3757.5678796938428;3769.792661403649;3771.698264138862;3774.169682669808;123710;123735.38132309371;125606;128889.71110288872;129625;15861.920690396704;16201.432949846934;16233.2695028724;16265.756013659804;16305.510834674978;27894.3026424341;28686;29079.85460072686;29086.27284317909;29204.85397573041;8291.33669306466;8327.042390125212;8352.582370881553;8399.328053755808;8404.957975210207;4030.556638769733;4051.8987025324604;4106.589341065869;4148.543660197376;4162.895927601834;5278.736063196825;5334.813281535127;5344.144936810169;5362.812301569426;5380.892382152335;952.1430712358932;967.2262190247915;986.7730422002979;988.6984469736656;990.7126933189294;665.5840099937539;677;683.6718375179842;698.3296035805582;699.1191099214924;10899.945500272448;11172.87715334084;11723.706907327385;12467.87532124667;12980.480780768852;692.3180667042959;696.4428457234193;696.4776417686735;712.9964350178216;713.6717110129399;5272.209168624737;5308.946910530845;5313.44208858072;5338.91991620126;5500.22996780451;107003.46498267615;108035.45982270039;110647;112578.43710781394;113367.43316283365;96783;96859.51570242245;100036;100650;101004.4949775261;90638;92235.5388223068;92832;93405;94279.52860235654;11910.702232444259;11946.223495472766;11971.64085077449;12111.81832272499;12154.817677735018;42287.577124228366;43834.56165438432;43891.56108438875;43929.34105988477;44180.558194417645;69782;70462;71543.64228179034;72543.18642033992;75924;17646.73529897027;17902.641947160722;18088.457346279632;18262.36081737133;18289.542761431072;18349.724754128965;18690.439286821416;18966.52583685419;19004.42986710401;19515.316963906196;16290.755638665667;16347.673046539241;17635.823641763418;18403.355882544045;18447.07764611791;17305.480835574952;17654.117294134983;17790.110494474964;17834.37579684705;18016.639667206844;16897.408590699266;16913.069781161663;17124;17287.7406838895;17424.738628920324;11867.406629668654;11940.343281119274;11946.701332466759;12763.298018609066;12822;13371.450200711299;13949.721005580035;13990.370433330729;14009.369578368794;14043.83515105514 11:31:01 INFO - PID 19540 | __end_tp_report 11:31:01 INFO - PID 19540 | __start_cc_report 11:31:01 INFO - PID 19540 | _x_x_mozilla_cycle_collect,359 11:31:01 INFO - PID 19540 | __end_cc_report 11:31:01 INFO - PID 19540 | __startTimestamp1504290661466__endTimestamp 11:31:01 INFO - PID 19540 | 11:31:01 INFO - PID 19540 | ------- Summary: start ------- 11:31:01 INFO - PID 19540 | Number of tests: 6 11:31:01 INFO - PID 19540 | 11:31:01 INFO - PID 19540 | [#0] dojo.html Cycles:165 Average:6319.36 Median:6517.93 stddev:733.62 (11.3%) stddev-sans-first:695.65 11:31:01 INFO - PID 19540 | Values: 3265.6 3297.0 3332.5 3341.9 3367.9 6235.8 6239.4 6258.8 6279.7 6371.9 6430.5 6473.7 6482.6 6486.6 6487.4 5868.6 5924.2 5968.4 5978.6 5985.1 5658.6 5759.7 5760.0 5849.3 6036.8 5726.5 5736.0 5776.6 5789.6 5838.4 5966.7 5990.0 5992.3 5995.9 6000.5 5830.3 5837.5 5846.1 5920.0 5952.7 6101.3 6199.1 6389.4 6398.4 6491.0 6911.7 6915.9 6920.2 6922.1 6924.7 6737.1 6926.2 6935.9 6935.9 6943.3 6331.1 6364.4 6406.3 6418.0 6453.6 6944.2 7106.4 7183.4 7188.2 7189.9 6792.0 6812.1 6828.7 6836.8 6881.0 6796.6 6858.6 6942.3 6952.4 7012.1 6574.6 6674.0 6677.9 6825.7 6942.7 6689.1 6698.1 6705.0 6709.2 6715.0 5314.6 5327.4 5392.6 5401.8 5466.2 4784.5 4853.1 4875.1 4885.0 4891.3 6034.5 6053.0 6057.7 6146.0 6148.2 6629.8 6741.9 6876.8 6886.8 7002.9 5998.6 6043.9 6052.2 6072.6 6073.4 5911.9 5987.6 5991.0 5994.1 5999.3 6686.8 6687.8 6760.6 6779.1 6780.9 6635.4 6705.3 6728.9 6814.5 6953.8 6737.6 6738.1 6756.7 6761.9 6763.6 6738.1 6837.1 6846.0 6854.9 6866.8 6899.7 6914.0 6925.4 7015.1 7017.4 6384.0 6446.9 6653.4 6727.6 6742.0 6491.3 6515.5 6517.9 6518.1 6518.8 6372.7 6581.4 6638.7 6660.1 6661.2 6687.5 6761.8 6765.8 6793.0 6845.4 6504.0 6513.6 6580.9 6599.0 6613.9 11:31:01 INFO - PID 19540 | 11:31:01 INFO - PID 19540 | [#1] ext.html Cycles:180 Average:37692.59 Median:19565.70 stddev:53946.50 (275.7%) stddev-sans-first:54026.69 11:31:01 INFO - PID 19540 | Values: 792.9 852.2 880.9 887.8 1144.9 29381.3 30341.7 39780.2 40708.4 40716.4 40484.2 40676.8 40717.4 40766.4 40868.8 7521.9 7696.5 7706.2 7708.1 7709.1 7530.3 7640.2 7642.5 7642.7 7645.9 7625.7 7684.0 7687.8 7692.3 7696.3 7581.3 7632.0 7636.4 7639.0 7644.8 7520.7 7557.1 7583.0 7583.3 7587.3 36410.3 36503.5 36512.6 36613.0 36695.3 3547.8 3575.5 3576.2 3578.2 3582.9 29750.9 29753.3 29774.0 29778.6 29783.9 4998.4 5152.9 5188.1 5225.6 5277.1 190016.0 190239.0 190689.0 190895.0 190968.0 46550.0 46567.5 46650.5 46690.8 46735.3 48774.3 49080.5 51654.5 52311.2 52402.2 12679.4 12722.9 14204.4 14524.4 14649.2 11657.9 11682.1 11691.1 11697.5 11699.2 13265.7 13294.9 13299.0 13356.5 13458.0 5354.0 5371.5 5382.2 5446.3 5482.2 1010.4 1020.1 1021.3 1036.9 1037.1 22934.5 23357.6 23584.0 30480.4 30673.8 1080.5 1116.5 1120.8 1126.9 1136.1 12208.1 12310.9 12489.4 12586.4 12597.9 220758.0 222119.0 223152.0 223282.0 223622.0 51412.2 51960.2 52020.7 52215.7 52261.2 1446.2 1479.3 1498.4 1507.2 1510.7 23203.3 23300.8 23373.5 23377.8 23562.9 37680.2 37847.8 38024.0 38107.8 38154.6 209221.0 210298.0 210399.0 210506.0 211611.0 34868.3 35014.3 35583.6 37207.4 37429.6 44244.6 45737.7 45742.0 45834.8 45927.3 32917.2 33642.0 33881.3 34033.5 34461.3 33164.5 33400.8 33424.8 33689.8 33909.5 37792.8 37963.1 38066.8 38097.8 38113.8 32666.5 33047.8 33143.8 33518.2 33599.3 24777.0 24957.3 25003.0 25342.7 25404.0 11:31:01 INFO - PID 19540 | 11:31:01 INFO - PID 19540 | [#2] jquery.html Cycles:180 Average:35062.76 Median:12801.26 stddev:84454.39 (659.7%) stddev-sans-first:84651.59 11:31:01 INFO - PID 19540 | Values: 563.5 563.8 563.9 575.9 577.7 21219.9 21906.2 21936.0 21972.1 22125.4 24872.9 25264.2 25277.9 25297.7 25351.2 6504.2 6655.7 6662.8 6663.1 6680.0 6328.1 6575.9 6615.7 6615.9 6620.6 6569.6 6571.9 6585.4 6589.5 6600.0 6563.9 6576.2 6581.4 6581.7 6589.4 6089.3 6101.7 6112.5 6121.7 6121.9 28757.4 28975.9 29709.0 29779.9 29904.0 2089.1 2109.0 2111.3 2119.1 2122.5 19230.7 19919.7 19920.9 19935.8 19946.8 2329.7 2360.1 2400.3 2435.3 2456.7 134462.0 137373.0 137417.0 137639.0 137748.0 14434.3 14549.8 14636.2 14896.2 15231.4 23746.3 23759.0 23772.0 23782.4 23820.2 9879.6 9980.8 9981.4 10017.5 10111.0 7224.2 7518.5 8732.0 9270.2 9438.7 8953.6 9074.7 9081.6 9132.5 9456.0 2645.9 2675.9 2701.3 2752.4 2845.8 963.7 972.3 977.8 985.2 995.5 19238.1 19650.3 19737.6 20085.2 20100.1 1027.8 1030.3 1032.4 1038.2 1043.6 10711.4 10902.3 10982.0 11010.5 11097.8 486618.0 491600.0 494169.0 518642.0 519970.0 109227.5 114550.4 114867.4 115952.4 119231.0 1191.3 1204.0 1251.3 1255.9 1260.4 20446.7 20554.2 20856.0 20924.3 20935.6 54901.2 55008.7 55906.4 57154.4 57948.7 72737.0 72957.6 73182.0 73367.0 73435.6 12262.3 12694.7 14153.7 14328.8 14653.3 22986.7 23111.3 23141.9 23272.8 23302.0 18166.6 18282.1 18521.8 18721.6 18740.7 16664.0 16816.8 17052.1 17230.6 17378.5 16655.9 18031.9 18135.9 18315.6 18317.1 14440.5 14539.8 14571.8 14587.8 14617.1 16541.1 16578.3 16738.3 16754.7 18401.0 11:31:01 INFO - PID 19540 | 11:31:01 INFO - PID 19540 | [#3] mootools.html Cycles:180 Average:9348.41 Median:2658.39 stddev:6647.49 (250.1%) stddev-sans-first:6638.75 11:31:01 INFO - PID 19540 | Values: 1317.0 1364.2 1369.5 1377.3 1379.7 12136.4 14789.7 15442.0 15464.0 15585.5 12849.7 14379.1 15632.1 15669.5 15679.8 2650.6 2711.0 2718.6 2723.8 2724.5 2581.6 2666.2 2745.7 2866.2 2867.2 2781.0 2793.3 2813.7 2815.1 2819.9 2749.4 2752.4 2779.4 2782.9 2788.7 2721.2 2728.2 2736.2 2738.6 2793.6 15024.8 15063.5 15227.9 15236.3 15236.8 8015.6 8360.6 8366.0 8369.2 8374.6 5417.8 5451.8 5584.2 5743.9 5811.2 1939.4 1974.1 1976.1 1994.2 2003.5 25040.0 25237.4 25240.9 25271.7 25288.6 7040.7 7155.0 7320.7 7403.3 7404.8 20524.2 20646.1 20665.7 20706.4 20709.2 8132.3 8353.6 8366.5 8368.1 8371.9 7431.9 7454.1 7499.7 7509.2 7510.5 5183.1 5350.9 5566.5 5726.6 5750.8 1790.8 1791.7 1831.7 1838.5 1861.1 899.9 917.4 926.6 932.9 937.8 8464.3 8775.2 8778.8 8796.0 8841.2 981.3 1005.5 1006.3 1012.5 1017.3 4357.4 4375.5 4470.0 5416.3 5604.2 20873.3 21009.3 21573.9 21628.1 21634.4 20904.4 21325.4 21495.5 21688.9 21700.3 11102.0 11358.4 11364.9 11387.7 11404.4 8225.0 8606.4 8638.8 8706.3 8871.1 18206.5 18696.7 18773.9 18975.3 18978.4 18555.7 18980.0 19021.6 19029.8 19220.5 11741.8 11806.9 11928.2 11982.5 12009.9 11163.3 11436.1 11489.9 13754.8 13981.8 11394.9 11522.5 11586.6 11795.8 11980.1 10812.5 10819.6 10832.3 11036.2 11143.0 8301.8 8338.5 8367.2 8415.3 8441.5 6571.1 6629.9 6656.8 6769.6 6771.5 6747.8 6749.7 6777.9 6835.2 6867.9 11:31:01 INFO - PID 19540 | 11:31:01 INFO - PID 19540 | [#4] prototype.html Cycles:180 Average:5662.96 Median:6331.80 stddev:1596.39 (25.2%) stddev-sans-first:1552.32 11:31:01 INFO - PID 19540 | Values: 457.4 506.5 510.4 510.7 533.9 6355.8 6406.2 6414.2 6479.9 6523.4 6311.8 6333.4 6341.4 6420.2 6422.3 5203.3 5206.7 5219.5 5244.8 5253.7 4898.6 5031.3 5169.1 5242.8 5272.9 5044.8 5079.1 5100.9 5129.4 5147.5 4992.9 5044.6 5049.9 5086.5 5130.5 4333.8 4462.3 4503.5 4572.3 4580.3 6260.3 6279.5 6290.0 6310.5 6330.2 5831.6 5851.4 5856.3 5871.7 5900.2 6751.6 6781.8 6793.3 6806.4 6821.3 4987.9 4988.9 4997.5 5026.4 5036.4 6852.0 6971.6 6996.8 7008.2 7150.6 4972.8 5112.9 5115.3 5132.1 5132.7 5197.8 5224.2 5228.0 5228.6 5236.1 5777.7 5789.5 5811.2 5832.2 5834.0 6657.4 6826.7 6842.7 6843.0 6856.7 1750.6 1797.2 1807.6 1813.1 1820.5 1156.2 1219.7 1226.6 1237.0 1250.7 5975.1 5996.0 6069.4 6075.3 6075.6 6919.6 6923.9 6936.1 6989.9 7018.7 5922.3 5972.4 6032.5 6040.2 6078.2 3634.4 3636.9 3651.7 3652.9 3695.5 6660.8 6696.3 6721.8 6722.0 6763.8 6693.7 6934.6 7017.5 7096.5 7121.4 6535.0 6619.6 6654.8 6702.6 6747.4 6801.0 6821.9 6828.4 6965.6 6970.4 6777.5 6806.0 6820.3 6856.5 6899.0 6677.8 6743.8 6794.0 6802.4 6813.4 5066.9 5103.6 5138.5 5147.5 5153.0 6681.0 6904.0 6905.3 6915.8 6981.5 6441.3 6562.6 6675.4 6715.9 6732.9 6618.7 6659.3 6681.7 6687.5 6742.5 6530.9 6559.1 6640.6 6645.6 6669.8 6709.5 6727.8 6765.9 6812.8 6858.8 6455.0 6468.4 6493.7 6525.9 6580.7 11:31:01 INFO - PID 19540 | 11:31:01 INFO - PID 19540 | [#5] yui.html Cycles:180 Average:23924.79 Median:20244.94 stddev:32777.12 (161.9%) stddev-sans-first:32823.44 11:31:01 INFO - PID 19540 | Values: 890.8 907.5 913.9 918.7 925.7 18861.9 19883.0 19915.4 19932.5 19946.0 16194.2 16391.1 21572.1 21594.2 21611.5 965.4 988.9 991.5 992.6 994.6 900.4 983.5 1002.8 1007.5 1007.7 1045.9 1047.4 1048.8 1051.2 1051.8 1039.6 1045.1 1045.5 1046.0 1046.8 774.7 775.7 777.1 777.8 779.2 19607.7 19830.9 20237.6 20252.3 20430.6 21433.9 21495.9 21873.5 22007.2 22007.4 16703.6 16886.2 17089.6 17091.7 17268.6 3741.9 3757.6 3769.8 3771.7 3774.2 123710.0 123735.4 125606.0 128889.7 129625.0 15861.9 16201.4 16233.3 16265.8 16305.5 27894.3 28686.0 29079.9 29086.3 29204.9 8291.3 8327.0 8352.6 8399.3 8405.0 4030.6 4051.9 4106.6 4148.5 4162.9 5278.7 5334.8 5344.1 5362.8 5380.9 952.1 967.2 986.8 988.7 990.7 665.6 677.0 683.7 698.3 699.1 10899.9 11172.9 11723.7 12467.9 12980.5 692.3 696.4 696.5 713.0 713.7 5272.2 5308.9 5313.4 5338.9 5500.2 107003.5 108035.5 110647.0 112578.4 113367.4 96783.0 96859.5 100036.0 100650.0 101004.5 90638.0 92235.5 92832.0 93405.0 94279.5 11910.7 11946.2 11971.6 12111.8 12154.8 42287.6 43834.6 43891.6 43929.3 44180.6 69782.0 70462.0 71543.6 72543.2 75924.0 17646.7 17902.6 18088.5 18262.4 18289.5 18349.7 18690.4 18966.5 19004.4 19515.3 16290.8 16347.7 17635.8 18403.4 18447.1 17305.5 17654.1 17790.1 17834.4 18016.6 16897.4 16913.1 17124.0 17287.7 17424.7 11867.4 11940.3 11946.7 12763.3 12822.0 13371.5 13949.7 13990.4 14009.4 14043.8 11:31:01 INFO - PID 19540 | -------- Summary: end -------- 11:31:01 INFO - PID 19540 | 11:31:02 INFO - TEST-INFO | 19540: exit 0 11:31:02 INFO - TEST-OK | dromaeo_css | took 1111948ms 11:31:02 INFO - TEST-START | kraken 11:31:02 INFO - Initialising browser for kraken test... 11:31:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40985/getInfo.html -profile /tmp/tmp6W1gX7/profile 11:31:02 INFO - TEST-INFO | started process 19919 (/builds/slave/test/build/application/firefox/firefox http://localhost:40985/getInfo.html) 11:31:08 INFO - TEST-INFO | 19919: exit 0 11:31:08 INFO - Browser initialized. 11:31:08 INFO - Running cycle 1/1 for kraken test... 11:31:08 INFO - Using env: {'DISPLAY': ':0', 11:31:08 INFO - 'HOME': '/home/cltbld', 11:31:08 INFO - 'JSGC_DISABLE_POISONING': '1', 11:31:08 INFO - 'LANG': 'en_US.UTF-8', 11:31:08 INFO - 'LANGUAGE': 'en_US:en', 11:31:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:31:08 INFO - 'LOGNAME': 'cltbld', 11:31:08 INFO - 'MAIL': '/var/mail/cltbld', 11:31:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:31:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:31:08 INFO - 'MOZ_CRASHREPORTER': '1', 11:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:31:08 INFO - 'MOZ_NO_REMOTE': '1', 11:31:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:31:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:08 INFO - 'NO_EM_RESTART': '1', 11:31:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:08 INFO - 'PWD': '/builds/slave/test', 11:31:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:31:08 INFO - 'RUST_BACKTRACE': 'full', 11:31:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:31:08 INFO - 'SHELL': '/bin/bash', 11:31:08 INFO - 'SHLVL': '1', 11:31:08 INFO - 'STYLO_FORCE_ENABLED': '1', 11:31:08 INFO - 'STYLO_THREADS': '1', 11:31:08 INFO - 'TERM': 'linux', 11:31:08 INFO - 'TMOUT': '86400', 11:31:08 INFO - 'USER': 'cltbld', 11:31:08 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581', 11:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:08 INFO - '_': '/tools/buildbot/bin/python'} 11:31:08 INFO - TEST-INFO | started process 20104 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6W1gX7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:31:09 INFO - PID 20104 | 11:31:09 INFO - PID 20104 | (/builds/slave/test/build/application/firefox/firefox:20155): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:31:09 INFO - PID 20104 | 11:31:19 INFO - PID 20104 | RSS: Main: 167522304 11:31:19 INFO - PID 20104 | 11:31:22 INFO - PID 20104 | Cycle 1(1): loaded http://localhost:40985/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40985/tests/kraken/driver.html?testName=audio-beat-detection) 11:31:23 INFO - PID 20104 | RSS: Main: 171876352 11:31:23 INFO - PID 20104 | 11:31:25 INFO - PID 20104 | Cycle 1(1): loaded http://localhost:40985/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40985/tests/kraken/driver.html?testName=audio-dft) 11:31:26 INFO - PID 20104 | RSS: Main: 172687360 11:31:26 INFO - PID 20104 | 11:31:29 INFO - PID 20104 | Cycle 1(1): loaded http://localhost:40985/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40985/tests/kraken/driver.html?testName=audio-fft) 11:31:29 INFO - PID 20104 | RSS: Main: 167411712 11:31:29 INFO - PID 20104 | 11:31:32 INFO - PID 20104 | Cycle 1(1): loaded http://localhost:40985/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40985/tests/kraken/driver.html?testName=audio-oscillator) 11:31:32 INFO - PID 20104 | RSS: Main: 167919616 11:31:32 INFO - PID 20104 | 11:31:34 INFO - PID 20104 | Cycle 1(1): loaded http://localhost:40985/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40985/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:31:35 INFO - PID 20104 | RSS: Main: 168316928 11:31:35 INFO - PID 20104 | 11:31:38 INFO - PID 20104 | Cycle 1(1): loaded http://localhost:40985/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40985/tests/kraken/driver.html?testName=imaging-darkroom) 11:31:39 INFO - PID 20104 | RSS: Main: 170745856 11:31:39 INFO - PID 20104 | 11:31:42 INFO - PID 20104 | Cycle 1(1): loaded http://localhost:40985/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40985/tests/kraken/driver.html?testName=imaging-desaturate) 11:31:43 INFO - PID 20104 | RSS: Main: 170913792 11:31:43 INFO - PID 20104 | 11:31:46 INFO - PID 20104 | Cycle 1(1): loaded http://localhost:40985/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40985/tests/kraken/driver.html?testName=json-parse-financial) 11:31:46 INFO - PID 20104 | RSS: Main: 171106304 11:31:46 INFO - PID 20104 | 11:31:48 INFO - PID 20104 | Cycle 1(1): loaded http://localhost:40985/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40985/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:31:49 INFO - PID 20104 | RSS: Main: 171167744 11:31:49 INFO - PID 20104 | 11:31:50 INFO - PID 20104 | Cycle 1(1): loaded http://localhost:40985/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40985/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:31:51 INFO - PID 20104 | RSS: Main: 171032576 11:31:51 INFO - PID 20104 | 11:31:53 INFO - PID 20104 | Cycle 1(1): loaded http://localhost:40985/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40985/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:31:54 INFO - PID 20104 | RSS: Main: 170950656 11:31:54 INFO - PID 20104 | 11:31:56 INFO - PID 20104 | Cycle 1(1): loaded http://localhost:40985/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40985/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:31:57 INFO - PID 20104 | RSS: Main: 171548672 11:31:57 INFO - PID 20104 | 11:32:00 INFO - PID 20104 | Cycle 1(1): loaded http://localhost:40985/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40985/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:32:01 INFO - PID 20104 | RSS: Main: 171786240 11:32:01 INFO - PID 20104 | 11:32:02 INFO - PID 20104 | Cycle 1(1): loaded http://localhost:40985/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40985/tests/kraken/driver.html?testName=ai-astar) 11:32:03 INFO - PID 20104 | RSS: Main: 173481984 11:32:03 INFO - PID 20104 | 11:32:03 INFO - PID 20104 | __start_tp_report 11:32:03 INFO - PID 20104 | _x_x_mozilla_page_load 11:32:03 INFO - PID 20104 | _x_x_mozilla_page_load_details 11:32:03 INFO - PID 20104 | |i|pagename|runs| 11:32:03 INFO - PID 20104 | |0;ai-astar;99;107;100;107;109;108;110;100;109;110 11:32:03 INFO - PID 20104 | |1;audio-beat-detection;144;116;144;170;117;141;146;116;174;116 11:32:03 INFO - PID 20104 | |2;audio-dft;163;162;163;164;162;161;162;164;172;168 11:32:03 INFO - PID 20104 | |3;audio-fft;73;76;75;180;74;73;74;85;94;74 11:32:03 INFO - PID 20104 | |4;audio-oscillator;80;81;125;78;79;79;131;79;79;126 11:32:03 INFO - PID 20104 | |5;imaging-gaussian-blur;114;114;115;114;117;120;114;114;114;114 11:32:03 INFO - PID 20104 | |6;imaging-darkroom;148;148;148;148;147;150;149;148;150;149 11:32:03 INFO - PID 20104 | |7;imaging-desaturate;94;94;94;94;93;93;94;93;94;94 11:32:03 INFO - PID 20104 | |8;json-parse-financial;54;57;56;55;53;55;54;54;53;55 11:32:03 INFO - PID 20104 | |9;json-stringify-tinderbox;39;38;39;38;38;39;39;39;39;39 11:32:03 INFO - PID 20104 | |10;stanford-crypto-aes;81;76;75;75;75;75;76;74;84;73 11:32:03 INFO - PID 20104 | |11;stanford-crypto-ccm;130;124;117;118;121;120;129;119;126;154 11:32:03 INFO - PID 20104 | |12;stanford-crypto-pbkdf2;173;175;171;179;167;172;177;191;172;168 11:32:03 INFO - PID 20104 | |13;stanford-crypto-sha256-iterative;65;64;69;63;64;65;63;62;64;64 11:32:03 INFO - PID 20104 | __end_tp_report 11:32:03 INFO - PID 20104 | __start_cc_report 11:32:03 INFO - PID 20104 | _x_x_mozilla_cycle_collect,933 11:32:03 INFO - PID 20104 | __end_cc_report 11:32:03 INFO - PID 20104 | __startTimestamp1504290723132__endTimestamp 11:32:03 INFO - PID 20104 | 11:32:03 INFO - PID 20104 | ------- Summary: start ------- 11:32:03 INFO - PID 20104 | Number of tests: 14 11:32:03 INFO - PID 20104 | 11:32:03 INFO - PID 20104 | [#0] ai-astar Cycles:10 Average:105.90 Median:109.00 stddev:4.43 (4.1%) stddev-sans-first:3.94 11:32:03 INFO - PID 20104 | Values: 99.0 107.0 100.0 107.0 109.0 108.0 110.0 100.0 109.0 110.0 11:32:03 INFO - PID 20104 | 11:32:03 INFO - PID 20104 | [#1] audio-beat-detection Cycles:10 Average:138.40 Median:144.00 stddev:22.00 (15.3%) stddev-sans-first:23.24 11:32:03 INFO - PID 20104 | Values: 144.0 116.0 144.0 170.0 117.0 141.0 146.0 116.0 174.0 116.0 11:32:03 INFO - PID 20104 | 11:32:03 INFO - PID 20104 | [#2] audio-dft Cycles:10 Average:164.10 Median:163.50 stddev:3.38 (2.1%) stddev-sans-first:3.56 11:32:03 INFO - PID 20104 | Values: 163.0 162.0 163.0 164.0 162.0 161.0 162.0 164.0 172.0 168.0 11:32:03 INFO - PID 20104 | 11:32:03 INFO - PID 20104 | [#3] audio-fft Cycles:10 Average:87.80 Median:74.50 stddev:33.10 (44.4%) stddev-sans-first:34.67 11:32:03 INFO - PID 20104 | Values: 73.0 76.0 75.0 180.0 74.0 73.0 74.0 85.0 94.0 74.0 11:32:03 INFO - PID 20104 | 11:32:03 INFO - PID 20104 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:79.00 stddev:23.27 (29.5%) stddev-sans-first:24.15 11:32:03 INFO - PID 20104 | Values: 80.0 81.0 125.0 78.0 79.0 79.0 131.0 79.0 79.0 126.0 11:32:03 INFO - PID 20104 | 11:32:03 INFO - PID 20104 | [#5] imaging-gaussian-blur Cycles:10 Average:115.00 Median:114.00 stddev:2.00 (1.8%) stddev-sans-first:2.09 11:32:03 INFO - PID 20104 | Values: 114.0 114.0 115.0 114.0 117.0 120.0 114.0 114.0 114.0 114.0 11:32:03 INFO - PID 20104 | 11:32:03 INFO - PID 20104 | [#6] imaging-darkroom Cycles:10 Average:148.50 Median:148.50 stddev:0.97 (0.7%) stddev-sans-first:1.01 11:32:03 INFO - PID 20104 | Values: 148.0 148.0 148.0 148.0 147.0 150.0 149.0 148.0 150.0 149.0 11:32:03 INFO - PID 20104 | 11:32:03 INFO - PID 20104 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 11:32:03 INFO - PID 20104 | Values: 94.0 94.0 94.0 94.0 93.0 93.0 94.0 93.0 94.0 94.0 11:32:03 INFO - PID 20104 | 11:32:03 INFO - PID 20104 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:1.26 (2.3%) stddev-sans-first:1.32 11:32:03 INFO - PID 20104 | Values: 54.0 57.0 56.0 55.0 53.0 55.0 54.0 54.0 53.0 55.0 11:32:03 INFO - PID 20104 | 11:32:03 INFO - PID 20104 | [#9] json-stringify-tinderbox Cycles:10 Average:38.70 Median:39.00 stddev:0.48 (1.2%) stddev-sans-first:0.50 11:32:03 INFO - PID 20104 | Values: 39.0 38.0 39.0 38.0 38.0 39.0 39.0 39.0 39.0 39.0 11:32:03 INFO - PID 20104 | 11:32:03 INFO - PID 20104 | [#10] stanford-crypto-aes Cycles:10 Average:76.40 Median:75.50 stddev:3.41 (4.5%) stddev-sans-first:3.18 11:32:03 INFO - PID 20104 | Values: 81.0 76.0 75.0 75.0 75.0 75.0 76.0 74.0 84.0 73.0 11:32:03 INFO - PID 20104 | 11:32:03 INFO - PID 20104 | [#11] stanford-crypto-ccm Cycles:10 Average:125.80 Median:125.00 stddev:10.89 (8.7%) stddev-sans-first:11.45 11:32:03 INFO - PID 20104 | Values: 130.0 124.0 117.0 118.0 121.0 120.0 129.0 119.0 126.0 154.0 11:32:03 INFO - PID 20104 | 11:32:03 INFO - PID 20104 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.50 Median:174.00 stddev:6.87 (3.9%) stddev-sans-first:7.26 11:32:03 INFO - PID 20104 | Values: 173.0 175.0 171.0 179.0 167.0 172.0 177.0 191.0 172.0 168.0 11:32:03 INFO - PID 20104 | 11:32:03 INFO - PID 20104 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.00 stddev:1.89 (3.0%) stddev-sans-first:1.99 11:32:03 INFO - PID 20104 | Values: 65.0 64.0 69.0 63.0 64.0 65.0 63.0 62.0 64.0 64.0 11:32:03 INFO - PID 20104 | -------- Summary: end -------- 11:32:03 INFO - PID 20104 | 11:32:03 INFO - PID 20104 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:32:03 INFO - TEST-INFO | 20104: exit 0 11:32:03 INFO - TEST-OK | kraken | took 61667ms 11:32:03 INFO - SUITE-END | took 1173s 11:32:04 INFO - Completed test suite (00:19:34) 11:32:04 INFO - javascript benchmark 11:32:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8609.414449724558, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3265.591801024872, 3296.967030329696, 3332.46680531115, 3341.8997430077097, 3367.949480757789, 6235.781747638834, 6239.376062393759, 6258.812235632927, 6279.717412716427, 6371.872562548746, 6430.517711171669, 6473.6763161841955, 6482.5786323888915, 6486.643234622105, 6487.3836985486305, 5868.647881127146, 5924.170616113747, 5968.432998865112, 5978.581499295051, 5985.1022346647915, 5658.632188907708, 5759.712014399284, 5760.049591817345, 5849.268841394825, 6036.849078773043, 5726.541876649879, 5736.0, 5776.59563830532, 5789.565782566303, 5838.4453476919625, 5966.701664916737, 5990.011648078062, 5992.2809262888295, 5995.940040599582, 6000.549958753111, 5830.329512106077, 5837.474627283565, 5846.064629659234, 5920.023196172627, 5952.7321270542925, 6101.267847858241, 6199.132121502993, 6389.424951754318, 6398.424141827211, 6491.0, 6911.654417279166, 6915.89626155608, 6920.238773734885, 6922.06552115463, 6924.723011079551, 6737.090492783461, 6926.203486599055, 6935.895961560581, 6935.895961560581, 6943.27095654954, 6331.081993111019, 6364.427201551883, 6406.2953075161695, 6418.0, 6453.645049522274, 6944.201416837027, 7106.395956343768, 7183.353498185188, 7188.209296977328, 7189.856202876018, 6792.049113124167, 6812.080369150201, 6828.658567071627, 6836.829079272959, 6881.036654868322, 6796.592204467748, 6858.588484690935, 6942.305769423017, 6952.44380449573, 7012.053372794608, 6574.605523668595, 6673.966630166915, 6677.899831502531, 6825.726970921207, 6942.722291108299, 6689.063531105649, 6698.062271282024, 6704.966475167593, 6709.161354830646, 6715.0, 5314.601404894648, 5327.38735045467, 5392.595555333366, 5401.783928642889, 5466.234727138204, 4784.545468180587, 4853.0779151961015, 4875.07373599015, 4884.975575122102, 4891.3396691446915, 6034.48706859913, 6052.969735151384, 6057.666828324396, 6146.016637338004, 6148.231471066117, 6629.801105966829, 6741.898871516931, 6876.793696189123, 6886.793396198122, 7002.929970700329, 5998.610090344114, 6043.8791224175275, 6052.152698622196, 6072.574919755663, 6073.362296958844, 5911.9113213301835, 5987.6108052976415, 5990.970045149834, 5994.130851026577, 5999.310079340931, 6686.83282917931, 6687.799366019027, 6760.594364338155, 6779.084823548857, 6780.948952912405, 6635.4359879410795, 6705.2959439258175, 6728.865422691617, 6814.454843612598, 6953.826154346182, 6737.562058466184, 6738.056672065817, 6756.69594868242, 6761.864762704817, 6763.5941843489545, 6738.056672066013, 6837.1111755471475, 6845.965770171118, 6854.862902741817, 6866.759663411756, 6899.655017249218, 6913.965430172817, 6925.376716095374, 7015.052967849275, 7017.438604911493, 6384.042393640991, 6446.903296450463, 6653.367930046637, 6727.562708423937, 6742.0, 6491.318411566717, 6515.54391192612, 6517.9348206517325, 6518.120053792677, 6518.771842985473, 6372.713227904848, 6581.3747693969, 6638.705452436729, 6660.0675905374255, 6661.200655921127, 6687.531872768859, 6761.797146085625, 6765.797026089225, 6793.0, 6845.418139458202, 6504.0243963405865, 6513.641749703812, 6580.901286480803, 6598.967005164944, 6613.933860661525], "value": 6266.108082148723, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.8810678398239, 852.2415050604963, 880.8502554565725, 887.7824932891442, 1144.9313041217533, 29381.2654683633, 30341.69658303416, 39780.2043959121, 40708.389374159415, 40716.389254161135, 40484.19031619366, 40676.79661601688, 40717.38923916136, 40766.388504172384, 40868.79565602168, 7521.887171692442, 7696.538207707527, 7706.229377062305, 7708.11356693979, 7709.1134519530415, 7530.322270995609, 7640.235976402343, 7642.465027448081, 7642.656080476392, 7645.923540764605, 7625.65684544194, 7684.039495063117, 7687.846243075135, 7692.269234422721, 7696.268854458817, 7581.279778421069, 7632.045994250719, 7636.3890888728765, 7639.0, 7644.770656880247, 7520.736774212876, 7557.130929943072, 7583.0, 7583.279588439144, 7587.279208475186, 36410.27179456423, 36503.4524482133, 36512.634873651186, 36613.0, 36695.26609467796, 3547.7516573839844, 3575.463680447928, 3576.2311103112957, 3578.2306804037257, 3582.9283414331826, 29750.851245743634, 29753.25616859574, 29774.0, 29778.55332170019, 29783.851080744673, 4998.425181104147, 5152.948470515322, 5188.066148093341, 5225.581953443715, 5277.102892508245, 190016.0499197509, 190239.0, 190689.0, 190895.04552477287, 190968.0, 46550.0, 46567.534324657, 46650.533494665295, 46690.766546167055, 46735.29897051547, 48774.26838597424, 49080.509194907594, 51654.48345516497, 52311.21533177005, 52402.21396679053, 12679.429425675682, 12722.936385318015, 14204.36080376365, 14524.419023239163, 14649.194294313702, 11657.941710291394, 11682.065434765198, 11691.064714822794, 11697.532098716127, 11699.23954942943, 13265.734685306239, 13294.933525332504, 13299.0, 13356.465741370237, 13458.0, 5353.973230133903, 5371.516563509303, 5382.192671099288, 5446.319210098738, 5482.205080263419, 1010.4038617215731, 1020.0717347213999, 1021.3259248895841, 1036.9429681367596, 1037.123630532199, 22934.541309174056, 23357.59854408741, 23584.0, 30480.390392192032, 30673.84663076715, 1080.529969463286, 1116.4696769034645, 1120.8374785656035, 1126.8985791278824, 1136.0684238924168, 12208.08439367051, 12310.938445307716, 12489.375531223403, 12586.43361048755, 12597.874021259853, 220758.0, 222119.0, 223152.0, 223282.0, 223622.0, 51412.22881656778, 51960.22059669108, 52020.73989630028, 52215.69460763435, 52261.2160817588, 1446.2190417174609, 1479.297333766474, 1498.3781730581686, 1507.2463768115942, 1510.735621266274, 23203.303900883002, 23300.76699232986, 23373.532529349657, 23377.76622233824, 23562.882185588962, 37680.24639507249, 37847.81076094712, 38024.0, 38107.809460952514, 38154.61845381511, 209221.0, 210298.0, 210399.0, 210506.0, 211611.0, 34868.302633947686, 35014.299714005065, 35583.644163558034, 37207.44188837224, 37429.6257037437, 44244.55755442404, 45737.71311434348, 45742.0, 45834.77082614566, 45927.31109033434, 32917.17707057343, 33642.0, 33881.32237355288, 34033.48949765706, 34461.310773784884, 33164.50253246155, 33400.83299583486, 33424.83287583644, 33689.831550842086, 33909.49135762916, 37792.811035944644, 37963.050923727125, 38066.80966595149, 38097.80951095227, 38113.80943095267, 32666.510002349507, 33047.834760826045, 33143.83428082844, 33518.162045949044, 33599.328013440085, 24777.0, 24957.251282461555, 25003.0, 25342.746572534037, 25404.0], "value": 17126.984896697973, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.4534501533514, 563.7575842387774, 563.9351474580425, 575.9164921086442, 577.6562945047697, 21219.893900530515, 21906.23328183514, 21936.0, 21972.121115155376, 22125.446863828412, 24872.87563562184, 25264.2420727378, 25277.873610631967, 25297.74702252977, 25351.2394628161, 6504.154459920203, 6655.700493477781, 6662.800115996528, 6663.133792606955, 6680.031395447657, 6328.145700330466, 6575.901361479581, 6615.702293396785, 6615.933840661604, 6620.569662971916, 6569.638669873155, 6571.901421478682, 6585.374389432996, 6589.505787065989, 6600.042993765901, 6563.901541476857, 6576.243731970837, 6581.407673309425, 6581.703823327962, 6589.374009469093, 6089.330173680892, 6101.664408457533, 6112.480439162677, 6121.693915304217, 6121.938780612181, 28757.424851502852, 28975.85512072447, 29709.0, 29779.851100744574, 29904.0, 2089.0808044460387, 2108.968365474519, 2111.3138230075133, 2119.0993827623197, 2122.543653114588, 19230.71153932692, 19919.701204481946, 19920.90039549793, 19935.800641993683, 19946.80053199464, 2329.743728189898, 2360.0581449414412, 2400.303911865557, 2435.330284171867, 2456.680631517891, 134462.0, 137372.999999999, 137417.0, 137639.0, 137748.0, 14434.278286085864, 14549.78175327371, 14636.195009274592, 14896.180710060835, 15231.390744370101, 23746.287611371685, 23759.0, 23772.0, 23782.405439864142, 23820.166294179966, 9879.555420006118, 9980.80038399228, 9981.401115933068, 10017.499125043865, 10111.0, 7224.1692205396, 7518.511296765692, 8732.0, 9270.212031977222, 9438.669646562337, 8953.59708813105, 9074.727758167266, 9081.59132839024, 9132.543372831331, 9455.952720236355, 2645.936169787569, 2675.892964281407, 2701.257154282588, 2752.40823223008, 2845.7581105606255, 963.6868017894212, 972.3388096094724, 977.8044391121804, 985.1822986920838, 995.4922989275522, 19238.134283957257, 19650.312239071565, 19737.605247895248, 20085.196592136443, 20100.095495702728, 1027.7790275090892, 1030.3354336453096, 1032.390889375272, 1038.1539045677748, 1043.6295115234134, 10711.357318560755, 10902.345859248471, 10982.0, 11010.50452729629, 11097.83353249702, 486618.0, 491600.0, 494169.0, 518642.0, 519970.0, 109227.45386273018, 114550.42724786323, 114867.42566287114, 115952.42023789827, 119231.0, 1191.3090407563593, 1204.0348285780997, 1251.2805137046053, 1255.9372031398577, 1260.3698150924538, 20446.693299600814, 20554.17783288652, 20856.0, 20924.267650632155, 20935.58128837445, 54901.17648235359, 55008.724956374965, 55906.44093559012, 57154.428455716574, 57948.710256448445, 72737.0, 72957.6352118236, 73182.0, 73367.0, 73435.63282183555, 12262.26426414418, 12694.746105078031, 14153.716925661634, 14328.785068223777, 14653.267336632913, 22986.655200172347, 23111.30666079953, 23141.88429057844, 23272.767272327736, 23302.0, 18166.636667266317, 18282.085895704895, 18521.81478185201, 18721.595880309305, 18740.718889216947, 16664.0, 16816.831831681528, 17052.06213658261, 17230.569235769206, 17378.47864564065, 16655.91672041632, 18031.909840450713, 18135.909320453313, 18315.633687325913, 18317.084145792924, 14440.494582689555, 14539.781903271249, 14571.781423278871, 14587.781183282046, 14617.122972621677, 16541.090240036912, 16578.336866525686, 16738.330466781194, 16754.748678769585, 18401.0], "value": 11958.647357771611, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1316.993415032925, 1364.188307956766, 1369.5138225929795, 1377.2769296119536, 1379.7102608452224, 12136.39318034097, 14789.704205915888, 15442.0, 15463.994840335385, 15585.532434026969, 12849.678758031054, 14379.137251764902, 15632.062076275432, 15669.529914102566, 15679.843201567981, 2650.5626571615708, 2710.9593356099676, 2718.6329845470805, 2723.7820974322008, 2724.482348353816, 2581.6256642786784, 2666.2267942296808, 2745.6842463116795, 2866.183137805726, 2867.211516832867, 2780.986095069522, 2793.3016745813547, 2813.71862813718, 2815.0569559197693, 2819.873105710248, 2749.42262124954, 2752.4357506711176, 2779.3885345224016, 2782.8608569571543, 2788.6514185726683, 2721.2380533450664, 2728.236093893713, 2736.2338545207176, 2738.5755207942602, 2793.608894754736, 15024.849751502454, 15063.472778452702, 15227.923860380628, 15236.314365853563, 15236.771448428282, 8015.639296231683, 8360.623771930277, 8366.0, 8369.246767790928, 8374.623141958626, 5417.783288668488, 5451.809186678487, 5584.21820945068, 5743.913841292342, 5811.186433899257, 1939.360011196302, 1974.0820518458843, 1976.1107501624326, 1994.1524851938013, 2003.4590660521578, 25040.0, 25237.36906577332, 25240.873795631087, 25271.74728252712, 25288.620670689954, 7040.683169257396, 7155.0, 7320.7071717131785, 7403.333699966921, 7404.815229563244, 20524.179032838518, 20646.07092680833, 20665.690014649794, 20706.378808635764, 20709.171633134807, 8132.308753755997, 8353.624086916103, 8366.498010119412, 8368.121347258571, 8371.911651485269, 7431.851362972819, 7454.068241469817, 7499.737509187153, 7509.173990861001, 7510.511816731895, 5183.14478111115, 5350.946490535045, 5566.526845218121, 5726.627769194989, 5750.769969201268, 1790.8477779388897, 1791.6864548703925, 1831.6977698679834, 1838.4944140361508, 1861.0973677766272, 899.8920129584492, 917.3670167584345, 926.6108234541508, 932.9486878221763, 937.7749340158313, 8464.280536154496, 8775.166359195866, 8778.824423511494, 8795.956020219857, 8841.16008979146, 981.3326937682302, 1005.5424781724298, 1006.3257617396362, 1012.5342342522375, 1017.31839667423, 4357.411749413852, 4375.496817865923, 4470.0, 5416.295881535375, 5604.243427137366, 20873.26943556968, 21009.26467573658, 21573.892130539247, 21628.13487460484, 21634.350969470943, 20904.37286881396, 21325.36023919285, 21495.46261343479, 21688.891555542123, 21700.34898953034, 11102.0, 11358.375289359165, 11364.94317528407, 11387.658370248906, 11404.429778510876, 8225.0, 8606.440581362442, 8638.82722345537, 8706.347023973101, 8871.112888711077, 18206.45380638583, 18696.7195492065, 18773.90613046926, 18975.33586324472, 18978.43064708061, 18555.721664174776, 18979.956102413948, 19021.619567608846, 19029.809701902803, 19220.519487012938, 11741.76711445286, 11806.940965295118, 11928.224665396721, 11982.460789264678, 12009.879901200877, 11163.274387164809, 11436.142289328167, 11489.942550287195, 13754.793678095037, 13981.790273145707, 11394.943025285153, 11522.539098436304, 11586.594469193538, 11795.823062653895, 11980.101492387932, 10812.513436895515, 10819.621313253998, 10832.295900766425, 11036.172287078662, 11143.0, 8301.83396332058, 8338.458000229966, 8367.246947774729, 8415.326773857954, 8441.535715535705, 6571.112899758663, 6629.933700663125, 6656.7670131545165, 6769.627670478171, 6771.525993180627, 6747.763828265987, 6749.730010799513, 6777.93222067773, 6835.213950395768, 6867.931320686729], "value": 6742.211297526149, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.40994117588315, 506.54664075652295, 510.41812333939293, 510.6578592343131, 533.8665333666584, 6355.8093257202245, 6406.199225096863, 6414.198225221848, 6479.935200647992, 6523.412892839643, 6311.77908773193, 6333.398327158912, 6341.365863413656, 6420.1653785008, 6422.325655806143, 5203.323567936163, 5206.739663016853, 5219.450773474036, 5244.842654720364, 5253.658512196714, 4898.632602554805, 5031.295618613397, 5169.09540830355, 5242.764075616587, 5272.868178295555, 5044.798208071691, 5079.111155547765, 5100.8778068824795, 5129.435522165738, 5147.485251474841, 4992.850214493571, 5044.5622997445735, 5049.949500504985, 5086.542211200973, 5130.4613015633195, 4333.848315308981, 4462.286034234539, 4503.48209955856, 4572.314152877062, 4580.267157254856, 6260.342664020303, 6279.466245369149, 6289.96855015722, 6310.453938784981, 6330.208723909512, 5831.591788574801, 5851.385604511507, 5856.326522449887, 5871.706414679249, 5900.232969713953, 6751.594904305708, 6781.796546103625, 6793.252742198354, 6806.353396427331, 6821.283765204632, 4987.900241995139, 4988.87527811804, 4997.50024997501, 5026.396832380101, 5036.395632524121, 6852.0, 6971.581705097659, 6996.825079373006, 7008.229094799568, 7150.642467876585, 4972.801087956514, 5112.948870511322, 5115.291413725094, 5132.127538318495, 5132.692038477703, 5197.792088316501, 5224.223763920209, 5228.0, 5228.6339956203465, 5236.136037553761, 5777.700017496029, 5789.45000224978, 5811.157382179645, 5832.212651292106, 5833.970830145822, 6657.434118099919, 6826.692798824065, 6842.692078856463, 6842.965785171043, 6856.657167141623, 1750.5732827745346, 1797.2271923073204, 1807.593291509426, 1813.0871930113856, 1820.453863840869, 1156.2484385149553, 1219.7438537906962, 1226.589092653971, 1237.0227520259075, 1250.6810763255312, 5975.133605627247, 5995.970020149958, 6069.423404776539, 6075.301340345828, 6075.5747097703625, 6919.584824910521, 6923.930760692429, 6936.063631409796, 6989.860202795916, 7018.684159212848, 5922.2597175353085, 5972.372900845436, 6032.4570788629235, 6040.244969378828, 6078.179445774852, 3634.436662317345, 3636.9272614547563, 3651.653092956165, 3652.8538858445895, 3695.519582454264, 6660.770410789184, 6696.263411024784, 6721.798346049626, 6721.966390168116, 6763.782519146503, 6693.698783554654, 6934.578411425646, 7017.508774385846, 7096.467764917652, 7121.394681452031, 6534.9673251634385, 6619.602823830585, 6654.767083152066, 6702.564333318416, 6747.426468750211, 6800.979853022086, 6821.863562728817, 6828.385445309747, 6965.616891071039, 6970.407515361251, 6777.45975133998, 6806.0, 6820.283870193558, 6856.5200435969, 6898.965505172643, 6677.833054173684, 6743.763968261087, 6794.0, 6802.421794147553, 6813.398851270047, 5066.949330506648, 5103.5599028845145, 5138.537531622172, 5147.459516750687, 5153.0, 6681.0, 6903.965480172567, 6905.343992320722, 6915.792526224222, 6981.476389270927, 6441.323661015525, 6562.573432726857, 6675.365840245169, 6715.93284067153, 6732.855414579435, 6618.7352505899225, 6659.300773418721, 6681.699323530355, 6687.465002799861, 6742.471573160065, 6530.869382612416, 6559.0817285579105, 6640.568363056386, 6645.568038077509, 6669.7665581704405, 6709.530332876652, 6727.798166055026, 6765.902570203253, 6812.761553345609, 6858.794236172923, 6455.0, 6468.353164683494, 6493.675316234075, 6525.9021114684265, 6580.7038683260325], "value": 5184.8600741818755, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [890.7639475538988, 907.5144797533321, 913.8629205619161, 918.6922381002364, 925.7454200094973, 18861.905690471547, 19883.0, 19915.402537923866, 19932.50168745782, 19946.0, 16194.190290485487, 16391.09848958307, 21572.13711451544, 21594.24420145295, 21611.45971350713, 965.3869792681645, 988.8912219655832, 991.5092029445399, 992.5979978108877, 994.636957510507, 900.4462255712755, 983.4640121133999, 1002.8194924913549, 1007.5012868630001, 1007.7329507680471, 1045.9163266938665, 1047.3820445936897, 1048.821700310949, 1051.2220956492179, 1051.8001579699871, 1039.6153423233377, 1045.1168762395764, 1045.46158728255, 1046.0376453662648, 1046.8429735539692, 774.6552784011118, 775.670340105453, 777.063638315828, 777.793884620576, 779.174075479993, 19607.705884411604, 19830.900845495824, 20237.595248094956, 20252.291169809134, 20430.591388172303, 21433.892830535748, 21495.892520537298, 21873.453163671034, 22007.22974695878, 22007.449813754625, 16703.582410439714, 16886.240119194666, 17089.572760681083, 17091.743623845654, 17268.56828579283, 3741.9438708419398, 3757.5678796938428, 3769.792661403649, 3771.698264138862, 3774.169682669808, 123710.0, 123735.38132309371, 125606.0, 128889.71110288872, 129625.0, 15861.920690396704, 16201.432949846934, 16233.2695028724, 16265.756013659804, 16305.510834674978, 27894.3026424341, 28686.0, 29079.85460072686, 29086.27284317909, 29204.85397573041, 8291.33669306466, 8327.042390125212, 8352.582370881553, 8399.328053755808, 8404.957975210207, 4030.556638769733, 4051.8987025324604, 4106.589341065869, 4148.543660197376, 4162.895927601834, 5278.736063196825, 5334.813281535127, 5344.144936810169, 5362.812301569426, 5380.892382152335, 952.1430712358932, 967.2262190247915, 986.7730422002979, 988.6984469736656, 990.7126933189294, 665.5840099937539, 677.0, 683.6718375179842, 698.3296035805582, 699.1191099214924, 10899.945500272448, 11172.87715334084, 11723.706907327385, 12467.87532124667, 12980.480780768852, 692.3180667042959, 696.4428457234193, 696.4776417686735, 712.9964350178216, 713.6717110129399, 5272.209168624737, 5308.946910530845, 5313.44208858072, 5338.91991620126, 5500.22996780451, 107003.46498267615, 108035.45982270039, 110647.0, 112578.43710781394, 113367.43316283365, 96783.0, 96859.51570242245, 100036.0, 100650.0, 101004.4949775261, 90638.0, 92235.5388223068, 92832.0, 93405.0, 94279.52860235654, 11910.702232444259, 11946.223495472766, 11971.64085077449, 12111.81832272499, 12154.817677735018, 42287.577124228366, 43834.56165438432, 43891.56108438875, 43929.34105988477, 44180.558194417645, 69782.0, 70462.0, 71543.64228179034, 72543.18642033992, 75924.0, 17646.73529897027, 17902.641947160722, 18088.457346279632, 18262.36081737133, 18289.542761431072, 18349.724754128965, 18690.439286821416, 18966.52583685419, 19004.42986710401, 19515.316963906196, 16290.755638665667, 16347.673046539241, 17635.823641763418, 18403.355882544045, 18447.07764611791, 17305.480835574952, 17654.117294134983, 17790.110494474964, 17834.37579684705, 18016.639667206844, 16897.408590699266, 16913.069781161663, 17124.0, 17287.7406838895, 17424.738628920324, 11867.406629668654, 11940.343281119274, 11946.701332466759, 12763.298018609066, 12822.0, 13371.450200711299, 13949.721005580035, 13990.370433330729, 14009.369578368794, 14043.83515105514], "value": 9076.472534696706, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1481.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 107.0, 100.0, 107.0, 109.0, 108.0, 110.0, 100.0, 109.0, 110.0], "value": 105.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 116.0, 144.0, 170.0, 117.0, 141.0, 146.0, 116.0, 174.0, 116.0], "value": 138.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 164.0, 162.0, 161.0, 162.0, 164.0, 172.0, 168.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 75.0, 180.0, 74.0, 73.0, 74.0, 85.0, 94.0, 74.0], "value": 87.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 125.0, 78.0, 79.0, 79.0, 131.0, 79.0, 79.0, 126.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 114.0, 117.0, 120.0, 114.0, 114.0, 114.0, 114.0], "value": 115.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 148.0, 147.0, 150.0, 149.0, 148.0, 150.0, 149.0], "value": 148.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 93.0, 94.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 57.0, 56.0, 55.0, 53.0, 55.0, 54.0, 54.0, 53.0, 55.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 39.0, 38.0, 38.0, 39.0, 39.0, 39.0, 39.0, 39.0], "value": 38.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 76.0, 75.0, 75.0, 75.0, 75.0, 76.0, 74.0, 84.0, 73.0], "value": 76.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 124.0, 117.0, 118.0, 121.0, 120.0, 129.0, 119.0, 126.0, 154.0], "value": 125.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 175.0, 171.0, 179.0, 167.0, 172.0, 177.0, 191.0, 172.0, 168.0], "value": 174.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 69.0, 63.0, 64.0, 65.0, 63.0, 62.0, 64.0, 64.0], "value": 64.3, "unit": "score"}]}]} 11:32:04 INFO - Return code: 0 11:32:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:32:04 INFO - # TBPL SUCCESS # 11:32:04 INFO - Running post-action listener: _package_coverage_data 11:32:04 INFO - Running post-action listener: _resource_record_post_action 11:32:04 INFO - [mozharness: 2017-09-01 18:32:04.406792Z] Finished run-tests step (success) 11:32:04 INFO - Running post-run listener: _resource_record_post_run 11:32:04 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 241664; Write bytes: 392458240; Read time: 260; Write time: 241096 11:32:04 INFO - TinderboxPrint: CPU usage
13.1% 11:32:04 INFO - TinderboxPrint: I/O read bytes / time
241,664 / 260 11:32:04 INFO - TinderboxPrint: I/O write bytes / time
392,458,240 / 241,096 11:32:04 INFO - TinderboxPrint: CPU idle
8,252.0 (86.9%) 11:32:04 INFO - TinderboxPrint: CPU user
1,193.3 (12.6%) 11:32:04 INFO - TinderboxPrint: Swap in / out
0 / 0 11:32:04 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 99065856; Read time: 48; Write time: 72336 11:32:04 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:32:04 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 237568; Write bytes: 293392384; Read time: 212; Write time: 168760 11:32:05 INFO - Running post-run listener: _upload_blobber_files 11:32:05 INFO - Blob upload gear active. 11:32:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:32:05 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 11:32:05 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'] 11:32:05 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 11:32:05 INFO - (blobuploader) - INFO - Open directory for files ... 11:32:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:32:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:32:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:32:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:32:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:32:06 INFO - (blobuploader) - INFO - Done attempting. 11:32:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:32:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:32:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:32:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:32:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:32:07 INFO - (blobuploader) - INFO - Done attempting. 11:32:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:32:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:32:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:32:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:32:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:32:08 INFO - (blobuploader) - INFO - Done attempting. 11:32:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:32:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:32:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:32:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:32:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:32:12 INFO - (blobuploader) - INFO - Done attempting. 11:32:12 INFO - (blobuploader) - INFO - Iteration through files over. 11:32:12 INFO - Return code: 0 11:32:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:32:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:32:12 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2398d4f9280efbd921a01f71c5a2c071918b5c4918d731c3cbb55d90718fe8f930e1644edd0392f36fb459c5afe19ed0165b960fd089ba066fc2d64c7130fa3e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/beb183a29384e688ca1e9494ad49cac5aa4b8014f2f62bbd761dcf35fdd34f1228a0ae44415c3e1d396a79210535e0120bc38f13e0c476db04b2cf349db3ac8c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adc15edc485115011d8f0259609edac25695d9de887bc2ad2de78b0082817da923981b82a7ca143252512d1f8a5d5fe128217696efffc81493af5196efc5efe5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c664a0dc33bb73d6d0c985379c53d455828cea864bf162508c4952fe891496244b4dde25fabcff022eb6544580865f29c9c47d5d1e99afba6ff99c4a44776e03"} 11:32:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:32:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:32:12 INFO - Contents: 11:32:12 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2398d4f9280efbd921a01f71c5a2c071918b5c4918d731c3cbb55d90718fe8f930e1644edd0392f36fb459c5afe19ed0165b960fd089ba066fc2d64c7130fa3e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/beb183a29384e688ca1e9494ad49cac5aa4b8014f2f62bbd761dcf35fdd34f1228a0ae44415c3e1d396a79210535e0120bc38f13e0c476db04b2cf349db3ac8c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adc15edc485115011d8f0259609edac25695d9de887bc2ad2de78b0082817da923981b82a7ca143252512d1f8a5d5fe128217696efffc81493af5196efc5efe5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c664a0dc33bb73d6d0c985379c53d455828cea864bf162508c4952fe891496244b4dde25fabcff022eb6544580865f29c9c47d5d1e99afba6ff99c4a44776e03"} 11:32:12 INFO - Running post-run listener: copy_logs_to_upload_dir 11:32:12 INFO - Copying logs to upload dir... 11:32:12 INFO - mkdir: /builds/slave/test/build/upload/logs 11:32:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.213009 ========= master_lag: 0.53 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-09-01 11:32:12.965519) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 11:32:12.974008) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2398d4f9280efbd921a01f71c5a2c071918b5c4918d731c3cbb55d90718fe8f930e1644edd0392f36fb459c5afe19ed0165b960fd089ba066fc2d64c7130fa3e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/beb183a29384e688ca1e9494ad49cac5aa4b8014f2f62bbd761dcf35fdd34f1228a0ae44415c3e1d396a79210535e0120bc38f13e0c476db04b2cf349db3ac8c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adc15edc485115011d8f0259609edac25695d9de887bc2ad2de78b0082817da923981b82a7ca143252512d1f8a5d5fe128217696efffc81493af5196efc5efe5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c664a0dc33bb73d6d0c985379c53d455828cea864bf162508c4952fe891496244b4dde25fabcff022eb6544580865f29c9c47d5d1e99afba6ff99c4a44776e03"} build_url:https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.009580 build_url: 'https://queue.taskcluster.net/v1/task/Aa8zsAsLT3StNXVbTCD9Tg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2398d4f9280efbd921a01f71c5a2c071918b5c4918d731c3cbb55d90718fe8f930e1644edd0392f36fb459c5afe19ed0165b960fd089ba066fc2d64c7130fa3e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/beb183a29384e688ca1e9494ad49cac5aa4b8014f2f62bbd761dcf35fdd34f1228a0ae44415c3e1d396a79210535e0120bc38f13e0c476db04b2cf349db3ac8c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adc15edc485115011d8f0259609edac25695d9de887bc2ad2de78b0082817da923981b82a7ca143252512d1f8a5d5fe128217696efffc81493af5196efc5efe5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c664a0dc33bb73d6d0c985379c53d455828cea864bf162508c4952fe891496244b4dde25fabcff022eb6544580865f29c9c47d5d1e99afba6ff99c4a44776e03"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 11:32:13.012220) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 11:32:13.016624) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1504289482.218920-1577372581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004627 ========= master_lag: 0.72 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 11:32:13.736836) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 11:32:13.737221) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 11:32:13.743686) ========= ========= Total master_lag: 1.35 =========