builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-020 starttime: 1504376121.77 results: success (0) revision: 59611e1583e7411bab615d29109894e4c657af33 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 11:15:21.773458) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 11:15:21.773940) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 11:15:21.774212) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 11:15:21.802375) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 11:15:21.802660) ========= 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=af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-02 11:15:22-- 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% 50.1M=0s 2017-09-02 11:15:22 (50.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.341018 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 11:15:22.178735) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 11:15:22.179054) ========= 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=af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020650 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 11:15:22.228347) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-02 11:15:22.231697) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 59611e1583e7411bab615d29109894e4c657af33 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 59611e1583e7411bab615d29109894e4c657af33 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-02 11:15:22,620 truncating revision to first 12 chars 2017-09-02 11:15:22,620 Setting DEBUG logging. 2017-09-02 11:15:22,620 attempt 1/10 2017-09-02 11:15:22,620 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/59611e1583e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-02 11:15:23,632 unpacking tar archive at: mozilla-central-59611e1583e7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.408174 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-02 11:15:23.658950) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 11:15:23.659248) ========= script_repo_revision: 59611e1583e7411bab615d29109894e4c657af33 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 11:15:23.659637) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 11:15:23.659897) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 11:15:23.672823) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-09-02 11:15:23.673163) ========= /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=af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:15:24 INFO - MultiFileLogger online at 20170902 11:15:24 in /builds/slave/test 11:15: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:15:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:15:24 INFO - {'append_to_log': False, 11:15:24 INFO - 'base_work_dir': '/builds/slave/test', 11:15:24 INFO - 'blob_upload_branch': 'Firefox', 11:15:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:15:24 INFO - 'branch': 'Firefox', 11:15:24 INFO - 'buildbot_json_path': 'buildprops.json', 11:15:24 INFO - 'code_coverage': False, 11:15:24 INFO - 'config_files': ('talos/linux_config.py',), 11:15:24 INFO - 'default_actions': ('clobber', 11:15:24 INFO - 'read-buildbot-config', 11:15:24 INFO - 'download-and-extract', 11:15:24 INFO - 'populate-webroot', 11:15:24 INFO - 'create-virtualenv', 11:15:24 INFO - 'install', 11:15:24 INFO - 'setup-mitmproxy', 11:15:24 INFO - 'run-tests'), 11:15:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:15:24 INFO - 'disable_ccov_upload': False, 11:15:24 INFO - 'disable_stylo': False, 11:15:24 INFO - 'download_minidump_stackwalk': True, 11:15:24 INFO - 'download_symbols': 'ondemand', 11:15:24 INFO - 'enable_stylo': False, 11:15:24 INFO - 'enable_webrender': False, 11:15:24 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:15:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:15:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:15:24 INFO - 'gecko_profile': False, 11:15:24 INFO - 'gecko_profile_interval': 0, 11:15:24 INFO - 'installer_path': 'installer.exe', 11:15:24 INFO - 'log_level': 'info', 11:15:24 INFO - 'log_name': 'talos', 11:15:24 INFO - 'log_to_console': True, 11:15:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:15:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:15:24 INFO - 'opt_config_files': (), 11:15:24 INFO - 'pip_index': False, 11:15:24 INFO - 'suite': 'dromaeojs-e10s', 11:15:24 INFO - 'system_bits': '32', 11:15:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:15:24 INFO - 'title': 'talos-linux64-ix-020', 11:15:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:15:24 INFO - 'use_talos_json': True, 11:15:24 INFO - 'verify': 'False', 11:15:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:15:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:15:24 INFO - 'work_dir': 'build'} 11:15:24 INFO - [mozharness: 2017-09-02 18:15:24.208595Z] Running clobber step. 11:15:24 INFO - Running pre-action listener: _resource_record_pre_action 11:15:24 INFO - Running main action method: clobber 11:15:24 INFO - rmtree: /builds/slave/test/build 11:15:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:15:25 INFO - Running post-action listener: _resource_record_post_action 11:15:25 INFO - [mozharness: 2017-09-02 18:15:25.226028Z] Finished clobber step (success) 11:15:25 INFO - [mozharness: 2017-09-02 18:15:25.226127Z] Running read-buildbot-config step. 11:15:25 INFO - Running pre-action listener: _resource_record_pre_action 11:15:25 INFO - Running main action method: read_buildbot_config 11:15:25 INFO - Using buildbot properties: 11:15:25 INFO - { 11:15:25 INFO - "project": "", 11:15:25 INFO - "product": "firefox", 11:15:25 INFO - "who": "ffxbld@noreply.mozilla.org", 11:15:25 INFO - "installer_path": "public/build/target.tar.bz2", 11:15:25 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 11:15:25 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:15:25 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 11:15:25 INFO - "stage_platform": "linux64-stylo", 11:15:25 INFO - "basedir": "/builds/slave/test", 11:15:25 INFO - "buildnumber": 116, 11:15:25 INFO - "platform": "ubuntu64_hw_stylo", 11:15:25 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:15:25 INFO - "slavebuilddir": "test", 11:15:25 INFO - "taskId": "V9UUdzuzRb6ygeXnaFaVwA", 11:15:25 INFO - "branch": "mozilla-central", 11:15:25 INFO - "script_repo_revision": "production", 11:15:25 INFO - "revision": "59611e1583e7411bab615d29109894e4c657af33", 11:15:25 INFO - "slavename": "talos-linux64-ix-020", 11:15:25 INFO - "repo_path": "mozilla-central" 11:15:25 INFO - } 11:15:25 INFO - Finding installer, test and symbols from parent task. 11:15:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V9UUdzuzRb6ygeXnaFaVwA'}, attempt #1 11:15:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V9UUdzuzRb6ygeXnaFaVwA'}, attempt #1 11:15:26 INFO - Task dependencies: C-9NEaTqQja8SGXabZPikw 11:15:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw'}, attempt #1 11:15:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.tar.bz2 11:15:26 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.test_packages.json 11:15:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.crashreporter-symbols.zip 11:15:26 INFO - Running post-action listener: _resource_record_post_action 11:15:26 INFO - [mozharness: 2017-09-02 18:15:26.571768Z] Finished read-buildbot-config step (success) 11:15:26 INFO - [mozharness: 2017-09-02 18:15:26.571990Z] Running download-and-extract step. 11:15:26 INFO - Running pre-action listener: _resource_record_pre_action 11:15:26 INFO - Running main action method: download_and_extract 11:15:26 INFO - mkdir: /builds/slave/test/build/tests 11:15: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:15:26 INFO - trying https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.test_packages.json 11:15:26 INFO - Downloading https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:15:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:15:27 INFO - Downloaded 989 bytes. 11:15:27 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:15:27 INFO - Using the following test package requirements: 11:15:27 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:15:27 INFO - u'common': [u'target.common.tests.zip'], 11:15:27 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:15:27 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:15:27 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:15:27 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:15:27 INFO - u'mozbase': [u'target.common.tests.zip'], 11:15:27 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:15:27 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:15:27 INFO - u'web-platform': [u'target.common.tests.zip', 11:15:27 INFO - u'target.web-platform.tests.tar.gz'], 11:15:27 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:15:27 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:15:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.common.tests.zip 11:15:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:15:27 INFO - Fetch https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.common.tests.zip into memory 11:15:28 INFO - Content-Length response header: 55022419 11:15:28 INFO - Bytes received: 55022419 11:15:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:15:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.common.tests.zip 11:15:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:15:39 INFO - Fetch https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.common.tests.zip into memory 11:15:40 INFO - Content-Length response header: 55022419 11:15:40 INFO - Bytes received: 55022419 11:15:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.talos.tests.zip 11:15:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:15:52 INFO - Fetch https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.talos.tests.zip into memory 11:15:53 INFO - Content-Length response header: 14496650 11:15:53 INFO - Bytes received: 14496650 11:15:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:53 INFO - trying https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.tar.bz2 11:15:53 INFO - Downloading https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:15:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:15:54 INFO - Downloaded 62714781 bytes. 11:15:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.tar.bz2 11:15:54 INFO - mkdir: /builds/slave/test/properties 11:15:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:15:54 INFO - Writing to file /builds/slave/test/properties/build_url 11:15:54 INFO - Contents: 11:15:54 INFO - build_url:https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.tar.bz2 11:15:54 INFO - Running post-action listener: _resource_record_post_action 11:15:54 INFO - Running post-action listener: find_tests_for_verification 11:15:54 INFO - Running post-action listener: set_extra_try_arguments 11:15:54 INFO - [mozharness: 2017-09-02 18:15:54.526484Z] Finished download-and-extract step (success) 11:15:54 INFO - [mozharness: 2017-09-02 18:15:54.526582Z] Running populate-webroot step. 11:15:54 INFO - Running pre-action listener: _resource_record_pre_action 11:15:54 INFO - Running main action method: populate_webroot 11:15:54 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:15:54 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:15:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:15:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:15:54 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:15:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:15:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:15:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:15:54 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:15:54 INFO - 'talos_options': ['--enable-stylo'], 11:15:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:15:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:15:54 INFO - 'tests': ['damp', 'tps']}, 11:15:54 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:15:54 INFO - 'talos_options': ['--enable-stylo'], 11:15:54 INFO - 'tests': ['damp', 'tps']}, 11:15:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:15:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:15:54 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:15:54 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:15:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:15:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:15:54 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:15:54 INFO - 'talos_options': ['--enable-stylo'], 11:15:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:15:54 INFO - 'other-e10s': {'tests': ['a11yr', 11:15:54 INFO - 'ts_paint', 11:15:54 INFO - 'tpaint', 11:15:54 INFO - 'sessionrestore', 11:15:54 INFO - 'sessionrestore_many_windows', 11:15:54 INFO - 'sessionrestore_no_auto_restore', 11:15:54 INFO - 'tabpaint']}, 11:15:54 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:15:54 INFO - 'tests': ['a11yr', 11:15:54 INFO - 'ts_paint', 11:15:54 INFO - 'tpaint', 11:15:54 INFO - 'sessionrestore', 11:15:54 INFO - 'sessionrestore_many_windows', 11:15:54 INFO - 'sessionrestore_no_auto_restore', 11:15:54 INFO - 'tabpaint']}, 11:15:54 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:15:54 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:15:54 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:15:54 INFO - 'tests': ['bloom_basic_singleton']}, 11:15:54 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:15:54 INFO - 'tests': ['bloom_basic']}, 11:15:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:15:54 INFO - 'tsvgr_opacity', 11:15:54 INFO - 'tart', 11:15:54 INFO - 'tscrollx', 11:15:54 INFO - 'tsvg_static']}, 11:15:54 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:15:54 INFO - 'tests': ['tsvgx', 11:15:54 INFO - 'tsvgr_opacity', 11:15:54 INFO - 'tart', 11:15:54 INFO - 'tscrollx', 11:15:54 INFO - 'tsvg_static']}, 11:15:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:15:54 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:15:54 INFO - 'talos_options': ['--enable-stylo'], 11:15:54 INFO - 'tests': ['tp5o']}, 11:15:54 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:15:54 INFO - 'talos_options': ['--mitmproxy', 11:15:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:15:54 INFO - '--firstNonBlankPaint'], 11:15:54 INFO - 'tests': ['tp6_google', 11:15:54 INFO - 'tp6_youtube', 11:15:54 INFO - 'tp6_amazon', 11:15:54 INFO - 'tp6_facebook']}, 11:15:54 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:15:54 INFO - 'talos_options': ['--enable-stylo', 11:15:54 INFO - '--mitmproxy', 11:15:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:15:54 INFO - '--firstNonBlankPaint'], 11:15:54 INFO - 'tests': ['tp6_google', 11:15:54 INFO - 'tp6_youtube', 11:15:54 INFO - 'tp6_amazon', 11:15:54 INFO - 'tp6_facebook']}, 11:15:54 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:15:54 INFO - 'talos_options': ['--enable-stylo', 11:15:54 INFO - '--stylo-threads=1', 11:15:54 INFO - '--mitmproxy', 11:15:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:15:54 INFO - '--firstNonBlankPaint'], 11:15:54 INFO - 'tests': ['tp6_google', 11:15:54 INFO - 'tp6_youtube', 11:15:54 INFO - 'tp6_amazon', 11:15:54 INFO - 'tp6_facebook']}, 11:15:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:15:54 INFO - 'talos_options': ['--xperf_path', 11:15:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:15:54 INFO - 'tests': ['tp5n']}, 11:15:54 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:15:54 INFO - 'talos_options': ['--enable-stylo', 11:15:54 INFO - '--xperf_path', 11:15:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:15:54 INFO - 'tests': ['tp5n']}}} 11:15:54 INFO - Running post-action listener: _resource_record_post_action 11:15:54 INFO - [mozharness: 2017-09-02 18:15:54.540903Z] Finished populate-webroot step (success) 11:15:54 INFO - [mozharness: 2017-09-02 18:15:54.540993Z] Running create-virtualenv step. 11:15:54 INFO - Running pre-action listener: _resource_record_pre_action 11:15:54 INFO - Running main action method: create_virtualenv 11:15:54 INFO - Creating virtualenv /builds/slave/test/build/venv 11:15:54 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:15:54 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:15:54 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:15:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:15:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:15:55 INFO - Installing distribute.............................................................................................................................................................................................done. 11:15:55 INFO - Installing pip...............done. 11:15:55 INFO - Return code: 0 11:15:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:15:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:15:55 INFO - Reading from file tmpfile_stdout 11:15:55 INFO - Output received: 11:15:55 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:15:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:15:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbda60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc1090>, '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 0x2bc1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbbf90>, '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': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', '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:15:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:15:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:15:55 INFO - Using env: {'DISPLAY': ':0', 11:15:55 INFO - 'HOME': '/home/cltbld', 11:15:55 INFO - 'LANG': 'en_US.UTF-8', 11:15:55 INFO - 'LANGUAGE': 'en_US:en', 11:15:55 INFO - 'LOGNAME': 'cltbld', 11:15:55 INFO - 'MAIL': '/var/mail/cltbld', 11:15:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:55 INFO - 'MOZ_NO_REMOTE': '1', 11:15:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:55 INFO - 'NO_EM_RESTART': '1', 11:15:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:55 INFO - 'PWD': '/builds/slave/test', 11:15:55 INFO - 'SHELL': '/bin/bash', 11:15:55 INFO - 'SHLVL': '1', 11:15:55 INFO - 'TERM': 'linux', 11:15:55 INFO - 'TMOUT': '86400', 11:15:55 INFO - 'USER': 'cltbld', 11:15:55 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', 11:15:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:55 INFO - '_': '/tools/buildbot/bin/python'} 11:15:56 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:15:56 INFO - Downloading/unpacking pip>=1.5 11:15:56 INFO - Running setup.py egg_info for package pip 11:15:56 INFO - warning: no files found matching 'pip/cacert.pem' 11:15:56 INFO - warning: no files found matching '*.html' under directory 'docs' 11:15:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:15:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:15:56 INFO - Installing collected packages: pip 11:15:56 INFO - Found existing installation: pip 1.1 11:15:56 INFO - Uninstalling pip: 11:15:56 INFO - Successfully uninstalled pip 11:15:56 INFO - Running setup.py install for pip 11:15:56 INFO - warning: no files found matching 'pip/cacert.pem' 11:15:56 INFO - warning: no files found matching '*.html' under directory 'docs' 11:15:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:15:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:15:56 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:15:56 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:15:56 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:15:56 INFO - Successfully installed pip 11:15:56 INFO - Cleaning up... 11:15:56 INFO - Return code: 0 11:15:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:15: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:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15: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:15:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15: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 0x28511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbda60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc1090>, '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 0x2bc1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbbf90>, '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': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', '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:15: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:15: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:15:56 INFO - Using env: {'DISPLAY': ':0', 11:15:56 INFO - 'HOME': '/home/cltbld', 11:15:56 INFO - 'LANG': 'en_US.UTF-8', 11:15:56 INFO - 'LANGUAGE': 'en_US:en', 11:15:56 INFO - 'LOGNAME': 'cltbld', 11:15:56 INFO - 'MAIL': '/var/mail/cltbld', 11:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:56 INFO - 'MOZ_NO_REMOTE': '1', 11:15:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:56 INFO - 'NO_EM_RESTART': '1', 11:15:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:56 INFO - 'PWD': '/builds/slave/test', 11:15:56 INFO - 'SHELL': '/bin/bash', 11:15:56 INFO - 'SHLVL': '1', 11:15:56 INFO - 'TERM': 'linux', 11:15:56 INFO - 'TMOUT': '86400', 11:15:56 INFO - 'USER': 'cltbld', 11:15:56 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', 11:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:56 INFO - '_': '/tools/buildbot/bin/python'} 11:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:56 INFO - Downloading/unpacking psutil>=3.1.1 11:15: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:15: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:15: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:15: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:15:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:15:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:15:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:15:58 INFO - Installing collected packages: psutil 11:15:58 INFO - Running setup.py install for psutil 11:15:58 INFO - building 'psutil._psutil_linux' extension 11:15:58 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:15: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_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:15:58 INFO - building 'psutil._psutil_posix' extension 11:15:58 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:15: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:15:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:15:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:15:58 INFO - Successfully installed psutil 11:15:58 INFO - Cleaning up... 11:15:58 INFO - Return code: 0 11:15:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:15: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:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15: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:15:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15: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 0x28511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbda60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc1090>, '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 0x2bc1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbbf90>, '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': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', '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:15: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:15: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:15:58 INFO - Using env: {'DISPLAY': ':0', 11:15:58 INFO - 'HOME': '/home/cltbld', 11:15:58 INFO - 'LANG': 'en_US.UTF-8', 11:15:58 INFO - 'LANGUAGE': 'en_US:en', 11:15:58 INFO - 'LOGNAME': 'cltbld', 11:15:58 INFO - 'MAIL': '/var/mail/cltbld', 11:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:58 INFO - 'MOZ_NO_REMOTE': '1', 11:15:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:58 INFO - 'NO_EM_RESTART': '1', 11:15:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:58 INFO - 'PWD': '/builds/slave/test', 11:15:58 INFO - 'SHELL': '/bin/bash', 11:15:58 INFO - 'SHLVL': '1', 11:15:58 INFO - 'TERM': 'linux', 11:15:58 INFO - 'TMOUT': '86400', 11:15:58 INFO - 'USER': 'cltbld', 11:15:58 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', 11:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:58 INFO - '_': '/tools/buildbot/bin/python'} 11:15:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:15: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:15: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:15: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:15: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:15:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:16:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:16:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:16:00 INFO - Installing collected packages: mozsystemmonitor 11:16:00 INFO - Running setup.py install for mozsystemmonitor 11:16:00 INFO - Successfully installed mozsystemmonitor 11:16:00 INFO - Cleaning up... 11:16:00 INFO - Return code: 0 11:16:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:00 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 0x28511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbda60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc1090>, '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 0x2bc1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbbf90>, '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': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', '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:16:00 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:16:00 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:16:00 INFO - Using env: {'DISPLAY': ':0', 11:16:00 INFO - 'HOME': '/home/cltbld', 11:16:00 INFO - 'LANG': 'en_US.UTF-8', 11:16:00 INFO - 'LANGUAGE': 'en_US:en', 11:16:00 INFO - 'LOGNAME': 'cltbld', 11:16:00 INFO - 'MAIL': '/var/mail/cltbld', 11:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:00 INFO - 'MOZ_NO_REMOTE': '1', 11:16:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:00 INFO - 'NO_EM_RESTART': '1', 11:16:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:00 INFO - 'PWD': '/builds/slave/test', 11:16:00 INFO - 'SHELL': '/bin/bash', 11:16:00 INFO - 'SHLVL': '1', 11:16:00 INFO - 'TERM': 'linux', 11:16:00 INFO - 'TMOUT': '86400', 11:16:00 INFO - 'USER': 'cltbld', 11:16:00 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', 11:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:00 INFO - '_': '/tools/buildbot/bin/python'} 11:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:00 INFO - Downloading/unpacking jsonschema==2.5.1 11:16:00 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:16:00 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:16:00 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:16:00 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:16:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:16:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:16: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:16: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:16: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:16: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:16:01 INFO - Downloading functools32-3.2.3-2.tar.gz 11:16:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:16:01 INFO - warning: no files found matching '*.txt' 11:16:01 INFO - no previously-included directories found matching 'build' 11:16:01 INFO - no previously-included directories found matching 'dist' 11:16:01 INFO - no previously-included directories found matching '.git*' 11:16:01 INFO - Installing collected packages: jsonschema, functools32 11:16:01 INFO - Running setup.py install for functools32 11:16:01 INFO - warning: no files found matching '*.txt' 11:16:01 INFO - no previously-included directories found matching 'build' 11:16:01 INFO - no previously-included directories found matching 'dist' 11:16:01 INFO - no previously-included directories found matching '.git*' 11:16:01 INFO - Successfully installed jsonschema functools32 11:16:01 INFO - Cleaning up... 11:16:01 INFO - Return code: 0 11:16:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:16: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:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16: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:16:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16: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 0x28511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbda60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc1090>, '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 0x2bc1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbbf90>, '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': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', '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:16: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:16: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:16:01 INFO - Using env: {'DISPLAY': ':0', 11:16:01 INFO - 'HOME': '/home/cltbld', 11:16:01 INFO - 'LANG': 'en_US.UTF-8', 11:16:01 INFO - 'LANGUAGE': 'en_US:en', 11:16:01 INFO - 'LOGNAME': 'cltbld', 11:16:01 INFO - 'MAIL': '/var/mail/cltbld', 11:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:01 INFO - 'MOZ_NO_REMOTE': '1', 11:16:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:01 INFO - 'NO_EM_RESTART': '1', 11:16:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:01 INFO - 'PWD': '/builds/slave/test', 11:16:01 INFO - 'SHELL': '/bin/bash', 11:16:01 INFO - 'SHLVL': '1', 11:16:01 INFO - 'TERM': 'linux', 11:16:01 INFO - 'TMOUT': '86400', 11:16:01 INFO - 'USER': 'cltbld', 11:16:01 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', 11:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:01 INFO - '_': '/tools/buildbot/bin/python'} 11:16:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:16:02 INFO - Cleaning up... 11:16:02 INFO - Return code: 0 11:16:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:16:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:02 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 0x28511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbda60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc1090>, '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 0x2bc1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbbf90>, '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': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', '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:16:02 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:16:02 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:16:02 INFO - Using env: {'DISPLAY': ':0', 11:16:02 INFO - 'HOME': '/home/cltbld', 11:16:02 INFO - 'LANG': 'en_US.UTF-8', 11:16:02 INFO - 'LANGUAGE': 'en_US:en', 11:16:02 INFO - 'LOGNAME': 'cltbld', 11:16:02 INFO - 'MAIL': '/var/mail/cltbld', 11:16:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:02 INFO - 'MOZ_NO_REMOTE': '1', 11:16:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:02 INFO - 'NO_EM_RESTART': '1', 11:16:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:02 INFO - 'PWD': '/builds/slave/test', 11:16:02 INFO - 'SHELL': '/bin/bash', 11:16:02 INFO - 'SHLVL': '1', 11:16:02 INFO - 'TERM': 'linux', 11:16:02 INFO - 'TMOUT': '86400', 11:16:02 INFO - 'USER': 'cltbld', 11:16:02 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', 11:16:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:02 INFO - '_': '/tools/buildbot/bin/python'} 11:16:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:02 INFO - Downloading/unpacking blobuploader==1.2.4 11:16: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:16: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:16: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:16: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:16:03 INFO - Downloading blobuploader-1.2.4.tar.gz 11:16:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:16:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:16: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:16: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:16: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:16: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:16:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:16:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:16: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:16: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:16: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:16: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:16:03 INFO - Downloading docopt-0.6.1.tar.gz 11:16:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:16:04 INFO - Installing collected packages: blobuploader, requests, docopt 11:16:04 INFO - Running setup.py install for blobuploader 11:16:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:16:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:16:04 INFO - Running setup.py install for requests 11:16:04 INFO - Running setup.py install for docopt 11:16:04 INFO - Successfully installed blobuploader requests docopt 11:16:04 INFO - Cleaning up... 11:16:04 INFO - Return code: 0 11:16:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:16: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:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16: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:16:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16: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 0x28511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbda60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc1090>, '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 0x2bc1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbbf90>, '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': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', '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:16: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:16: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:16:04 INFO - Using env: {'DISPLAY': ':0', 11:16:04 INFO - 'HOME': '/home/cltbld', 11:16:04 INFO - 'LANG': 'en_US.UTF-8', 11:16:04 INFO - 'LANGUAGE': 'en_US:en', 11:16:04 INFO - 'LOGNAME': 'cltbld', 11:16:04 INFO - 'MAIL': '/var/mail/cltbld', 11:16:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:04 INFO - 'MOZ_NO_REMOTE': '1', 11:16:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:04 INFO - 'NO_EM_RESTART': '1', 11:16:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:04 INFO - 'PWD': '/builds/slave/test', 11:16:04 INFO - 'SHELL': '/bin/bash', 11:16:04 INFO - 'SHLVL': '1', 11:16:04 INFO - 'TERM': 'linux', 11:16:04 INFO - 'TMOUT': '86400', 11:16:04 INFO - 'USER': 'cltbld', 11:16:04 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', 11:16:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:04 INFO - '_': '/tools/buildbot/bin/python'} 11:16:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:16:04 INFO - Running setup.py (path:/tmp/pip-zd5Fol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:16:04 INFO - Running setup.py (path:/tmp/pip-QAlqYW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:16:04 INFO - Running setup.py (path:/tmp/pip-gjUAsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:16:05 INFO - Running setup.py (path:/tmp/pip-gC4Eym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:16:05 INFO - Running setup.py (path:/tmp/pip-BbM5jb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:16:05 INFO - Running setup.py (path:/tmp/pip-B7l6Ny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:16:05 INFO - Running setup.py (path:/tmp/pip-u4ctQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:16:05 INFO - Running setup.py (path:/tmp/pip-faI2cC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:16:05 INFO - Running setup.py (path:/tmp/pip-NmBAHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:16:05 INFO - Running setup.py (path:/tmp/pip-KrrxaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:16:05 INFO - Running setup.py (path:/tmp/pip-xAFFd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:16:05 INFO - Running setup.py (path:/tmp/pip-S8DiKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:16:05 INFO - Running setup.py (path:/tmp/pip-kJyTUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:16:05 INFO - Running setup.py (path:/tmp/pip-6FlXAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:05 INFO - Running setup.py (path:/tmp/pip-fpLp6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:16:05 INFO - Running setup.py (path:/tmp/pip-2zdzgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:16:05 INFO - Running setup.py (path:/tmp/pip-UdGw1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:16:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:16:06 INFO - Running setup.py install for manifestparser 11:16:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:16:06 INFO - Running setup.py install for mozcrash 11:16:06 INFO - Running setup.py install for mozdebug 11:16:06 INFO - Running setup.py install for mozdevice 11:16:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:16:06 INFO - Running setup.py install for mozfile 11:16:06 INFO - Running setup.py install for mozhttpd 11:16:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:16:06 INFO - Running setup.py install for mozinfo 11:16:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:16:06 INFO - Running setup.py install for mozInstall 11:16:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:16:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:16:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:16:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:16:06 INFO - Running setup.py install for mozleak 11:16:06 INFO - Running setup.py install for mozlog 11:16:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:16:07 INFO - Running setup.py install for moznetwork 11:16:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:16:07 INFO - Running setup.py install for mozprocess 11:16:07 INFO - Running setup.py install for mozprofile 11:16:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:16:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:16:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:16:07 INFO - Running setup.py install for mozrunner 11:16:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:16:07 INFO - Running setup.py install for mozscreenshot 11:16:07 INFO - Running setup.py install for moztest 11:16:07 INFO - Running setup.py install for mozversion 11:16:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:16:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:16:07 INFO - Cleaning up... 11:16:07 INFO - Return code: 0 11:16:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:16: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:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16: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:16:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16: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 0x28511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbda60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc1090>, '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 0x2bc1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbbf90>, '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': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', '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:16: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:16: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:16:07 INFO - Using env: {'DISPLAY': ':0', 11:16:07 INFO - 'HOME': '/home/cltbld', 11:16:07 INFO - 'LANG': 'en_US.UTF-8', 11:16:07 INFO - 'LANGUAGE': 'en_US:en', 11:16:07 INFO - 'LOGNAME': 'cltbld', 11:16:07 INFO - 'MAIL': '/var/mail/cltbld', 11:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:07 INFO - 'MOZ_NO_REMOTE': '1', 11:16:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:07 INFO - 'NO_EM_RESTART': '1', 11:16:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:07 INFO - 'PWD': '/builds/slave/test', 11:16:07 INFO - 'SHELL': '/bin/bash', 11:16:07 INFO - 'SHLVL': '1', 11:16:07 INFO - 'TERM': 'linux', 11:16:07 INFO - 'TMOUT': '86400', 11:16:07 INFO - 'USER': 'cltbld', 11:16:07 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', 11:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:07 INFO - '_': '/tools/buildbot/bin/python'} 11:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:16:08 INFO - Running setup.py (path:/tmp/pip-_5IlBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:16:08 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:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:16:08 INFO - Running setup.py (path:/tmp/pip-m88Dab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:16:08 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:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:16:08 INFO - Running setup.py (path:/tmp/pip-tzLf9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:16:08 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:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:16:08 INFO - Running setup.py (path:/tmp/pip-0dbJf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:16:08 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:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:16:08 INFO - Running setup.py (path:/tmp/pip-UzIPFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:16:08 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:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:16:08 INFO - Running setup.py (path:/tmp/pip-EBvyls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:16:08 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:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:16:08 INFO - Running setup.py (path:/tmp/pip-HeGis2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:16:08 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:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:16:08 INFO - Running setup.py (path:/tmp/pip-tMAkt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:16: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:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:16:08 INFO - Running setup.py (path:/tmp/pip-JDXmNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:16: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:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:16:08 INFO - Running setup.py (path:/tmp/pip-DEdnG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:16: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:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:16:08 INFO - Running setup.py (path:/tmp/pip-g9mCEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:16: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:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:16:08 INFO - Running setup.py (path:/tmp/pip-79DtmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:16: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:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:16:08 INFO - Running setup.py (path:/tmp/pip-l9NRJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:16: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:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:16:08 INFO - Running setup.py (path:/tmp/pip-qI6qJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:16:09 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:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:09 INFO - Running setup.py (path:/tmp/pip-8Ig1nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:09 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:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:16:09 INFO - Running setup.py (path:/tmp/pip-sBPR4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:16:09 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:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:16:09 INFO - Running setup.py (path:/tmp/pip-jn3EW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:16:09 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:16:09 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:16:09 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:16:09 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:16:09 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:16:09 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:16:09 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:16:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:16:09 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:16:09 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:16:09 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:16:09 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:16:10 INFO - Downloading blessings-1.6.tar.gz 11:16:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:16:10 INFO - Installing collected packages: blessings 11:16:10 INFO - Running setup.py install for blessings 11:16:10 INFO - Successfully installed blessings 11:16:10 INFO - Cleaning up... 11:16:10 INFO - Return code: 0 11:16:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:16:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:16:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:16:10 INFO - Reading from file tmpfile_stdout 11:16:10 INFO - Current package versions: 11:16:10 INFO - argparse == 1.2.1 11:16:10 INFO - blessings == 1.6 11:16:10 INFO - blobuploader == 1.2.4 11:16:10 INFO - docopt == 0.6.1 11:16:10 INFO - functools32 == 3.2.3-2 11:16:10 INFO - jsonschema == 2.5.1 11:16:10 INFO - manifestparser == 1.1 11:16:10 INFO - mozInstall == 1.13 11:16:10 INFO - mozcrash == 1.0 11:16:10 INFO - mozdebug == 0.1 11:16:10 INFO - mozdevice == 0.51 11:16:10 INFO - mozfile == 1.2 11:16:10 INFO - mozhttpd == 0.7 11:16:10 INFO - mozinfo == 0.10 11:16:10 INFO - mozleak == 0.1 11:16:10 INFO - mozlog == 3.5 11:16:10 INFO - moznetwork == 0.27 11:16:10 INFO - mozprocess == 0.25 11:16:10 INFO - mozprofile == 0.28 11:16:10 INFO - mozrunner == 6.13 11:16:10 INFO - mozscreenshot == 0.1 11:16:10 INFO - mozsystemmonitor == 0.3 11:16:10 INFO - moztest == 0.8 11:16:10 INFO - mozversion == 1.4 11:16:10 INFO - psutil == 3.1.1 11:16:10 INFO - requests == 1.2.3 11:16:10 INFO - wsgiref == 0.1.2 11:16:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:16: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:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16: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:16:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16: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 0x28511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbda60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc1090>, '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 0x2bc1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbbf90>, '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': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', '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:16: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:16: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:16:10 INFO - Using env: {'DISPLAY': ':0', 11:16:10 INFO - 'HOME': '/home/cltbld', 11:16:10 INFO - 'LANG': 'en_US.UTF-8', 11:16:10 INFO - 'LANGUAGE': 'en_US:en', 11:16:10 INFO - 'LOGNAME': 'cltbld', 11:16:10 INFO - 'MAIL': '/var/mail/cltbld', 11:16:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:10 INFO - 'MOZ_NO_REMOTE': '1', 11:16:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:10 INFO - 'NO_EM_RESTART': '1', 11:16:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:10 INFO - 'PWD': '/builds/slave/test', 11:16:10 INFO - 'SHELL': '/bin/bash', 11:16:10 INFO - 'SHLVL': '1', 11:16:10 INFO - 'TERM': 'linux', 11:16:10 INFO - 'TMOUT': '86400', 11:16:10 INFO - 'USER': 'cltbld', 11:16:10 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', 11:16:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:10 INFO - '_': '/tools/buildbot/bin/python'} 11:16:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:11 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:16:11 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:16:11 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:16:11 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:16:11 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:16:11 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:16:11 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:16:11 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:16:11 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:16:11 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:16:11 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:16:11 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:16:11 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:16:11 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:16:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:16:12 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:16:12 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:16:12 INFO - Installing collected packages: simplejson 11:16:12 INFO - Running setup.py install for simplejson 11:16:12 INFO - building 'simplejson._speedups' extension 11:16:12 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:16:13 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:16:13 INFO - Successfully installed simplejson 11:16:13 INFO - Cleaning up... 11:16:13 INFO - Return code: 0 11:16:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:16:13 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:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:13 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:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:13 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 0x28511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bbda60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc1090>, '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 0x2bc1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbbf90>, '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': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', '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:16:13 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:16:13 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:16:13 INFO - Using env: {'DISPLAY': ':0', 11:16:13 INFO - 'HOME': '/home/cltbld', 11:16:13 INFO - 'LANG': 'en_US.UTF-8', 11:16:13 INFO - 'LANGUAGE': 'en_US:en', 11:16:13 INFO - 'LOGNAME': 'cltbld', 11:16:13 INFO - 'MAIL': '/var/mail/cltbld', 11:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:13 INFO - 'MOZ_NO_REMOTE': '1', 11:16:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:13 INFO - 'NO_EM_RESTART': '1', 11:16:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:13 INFO - 'PWD': '/builds/slave/test', 11:16:13 INFO - 'SHELL': '/bin/bash', 11:16:13 INFO - 'SHLVL': '1', 11:16:13 INFO - 'TERM': 'linux', 11:16:13 INFO - 'TMOUT': '86400', 11:16:13 INFO - 'USER': 'cltbld', 11:16:13 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', 11:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:13 INFO - '_': '/tools/buildbot/bin/python'} 11:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:16:13 INFO - Cleaning up... 11:16:13 INFO - Return code: 0 11:16:13 INFO - Running post-action listener: _resource_record_post_action 11:16:13 INFO - Running post-action listener: _start_resource_monitoring 11:16:13 INFO - Starting resource monitoring. 11:16:13 INFO - [mozharness: 2017-09-02 18:16:13.514417Z] Finished create-virtualenv step (success) 11:16:13 INFO - [mozharness: 2017-09-02 18:16:13.514937Z] Running install step. 11:16:13 INFO - Running pre-action listener: _resource_record_pre_action 11:16:13 INFO - Running main action method: install 11:16:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:16:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:16:13 INFO - Reading from file tmpfile_stdout 11:16:13 INFO - Detecting whether we're running mozinstall >=1.0... 11:16:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:16:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:16:13 INFO - Reading from file tmpfile_stdout 11:16:13 INFO - Output received: 11:16:13 INFO - Usage: mozinstall [options] installer 11:16:13 INFO - Options: 11:16:13 INFO - -h, --help show this help message and exit 11:16:13 INFO - -d DEST, --destination=DEST 11:16:13 INFO - Directory to install application into. [default: 11:16:13 INFO - "/builds/slave/test"] 11:16:13 INFO - --app=APP Application being installed. [default: firefox] 11:16:13 INFO - mkdir: /builds/slave/test/build/application 11:16: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:16: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:16:30 INFO - Reading from file tmpfile_stdout 11:16:30 INFO - Output received: 11:16:30 INFO - /builds/slave/test/build/application/firefox/firefox 11:16:30 INFO - Running post-action listener: _resource_record_post_action 11:16:30 INFO - [mozharness: 2017-09-02 18:16:30.444187Z] Finished install step (success) 11:16:30 INFO - [mozharness: 2017-09-02 18:16:30.444440Z] Running setup-mitmproxy step. 11:16:30 INFO - Running pre-action listener: _resource_record_pre_action 11:16:30 INFO - Running main action method: setup_mitmproxy 11:16:30 INFO - Skipping: mitmproxy is not required 11:16:30 INFO - Running post-action listener: _resource_record_post_action 11:16:30 INFO - [mozharness: 2017-09-02 18:16:30.445540Z] Finished setup-mitmproxy step (success) 11:16:30 INFO - [mozharness: 2017-09-02 18:16:30.445761Z] Running run-tests step. 11:16:30 INFO - Running pre-action listener: _resource_record_pre_action 11:16:30 INFO - Running pre-action listener: _set_gcov_prefix 11:16:30 INFO - Running main action method: run_tests 11:16:30 WARNING - Try message not found. 11:16:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:16:30 INFO - Python 2.7.3 11:16:30 INFO - Return code: 0 11:16:30 INFO - grabbing minidump binary from tooltool 11:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:16:30 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 0x2bc1090>, '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 0x2bc1d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bbbf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:16:30 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:16:30 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:16:30 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:16:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:16:30 INFO - Return code: 0 11:16:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:16:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:16:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:16:30 INFO - ENV: RUST_BACKTRACE is now full 11:16:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:16:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:16: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/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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:16: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/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-020 --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:16:30 INFO - Using env: {'DISPLAY': ':0', 11:16:30 INFO - 'HOME': '/home/cltbld', 11:16:30 INFO - 'LANG': 'en_US.UTF-8', 11:16:30 INFO - 'LANGUAGE': 'en_US:en', 11:16:30 INFO - 'LOGNAME': 'cltbld', 11:16:30 INFO - 'MAIL': '/var/mail/cltbld', 11:16:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:16:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:16:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:30 INFO - 'MOZ_NO_REMOTE': '1', 11:16:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:16:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:30 INFO - 'NO_EM_RESTART': '1', 11:16:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:30 INFO - 'PWD': '/builds/slave/test', 11:16:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:16:30 INFO - 'RUST_BACKTRACE': 'full', 11:16:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:16:30 INFO - 'SHELL': '/bin/bash', 11:16:30 INFO - 'SHLVL': '1', 11:16:30 INFO - 'STYLO_FORCE_ENABLED': '1', 11:16:30 INFO - 'TERM': 'linux', 11:16:30 INFO - 'TMOUT': '86400', 11:16:30 INFO - 'USER': 'cltbld', 11:16:30 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', 11:16:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:30 INFO - '_': '/tools/buildbot/bin/python'} 11:16: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/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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:16:30 INFO - mozversion application_buildid: 20170902172544 11:16:30 INFO - mozversion application_changeset: 59611e1583e7411bab615d29109894e4c657af33 11:16:30 INFO - mozversion application_display_name: Nightly 11:16:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:16:30 INFO - mozversion application_name: Firefox 11:16:30 INFO - mozversion application_remotingname: firefox 11:16:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:16:30 INFO - mozversion application_vendor: Mozilla 11:16:30 INFO - mozversion application_version: 57.0a1 11:16:30 INFO - mozversion platform_buildid: 20170902172544 11:16:30 INFO - mozversion platform_changeset: 59611e1583e7411bab615d29109894e4c657af33 11:16:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:16:30 INFO - mozversion platform_version: 57.0a1 11:16:30 INFO - using testdate: 1504376190 11:16:30 INFO - actual date: 1504376190 11:16:30 INFO - starting webserver on 'localhost:34805' 11:16:30 INFO - SUITE-START | Running 2 tests 11:16:30 INFO - TEST-START | dromaeo_css 11:16:30 INFO - Initialising browser for dromaeo_css test... 11:16:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34805/getInfo.html -profile /tmp/tmpBP6pxz/profile 11:16:30 INFO - TEST-INFO | started process 20428 (/builds/slave/test/build/application/firefox/firefox http://localhost:34805/getInfo.html) 11:16:36 INFO - TEST-INFO | 20428: exit 0 11:16:36 INFO - Browser initialized. 11:16:36 INFO - Running cycle 1/1 for dromaeo_css test... 11:16:36 INFO - Using env: {'DISPLAY': ':0', 11:16:36 INFO - 'HOME': '/home/cltbld', 11:16:36 INFO - 'JSGC_DISABLE_POISONING': '1', 11:16:36 INFO - 'LANG': 'en_US.UTF-8', 11:16:36 INFO - 'LANGUAGE': 'en_US:en', 11:16:36 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:16:36 INFO - 'LOGNAME': 'cltbld', 11:16:36 INFO - 'MAIL': '/var/mail/cltbld', 11:16:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:16:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:16:36 INFO - 'MOZ_CRASHREPORTER': '1', 11:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:36 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:16:36 INFO - 'MOZ_NO_REMOTE': '1', 11:16:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:16:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:16:36 INFO - 'NO_EM_RESTART': '1', 11:16:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:36 INFO - 'PWD': '/builds/slave/test', 11:16:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:16:36 INFO - 'RUST_BACKTRACE': 'full', 11:16:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:16:36 INFO - 'SHELL': '/bin/bash', 11:16:36 INFO - 'SHLVL': '1', 11:16:36 INFO - 'STYLO_FORCE_ENABLED': '1', 11:16:36 INFO - 'TERM': 'linux', 11:16:36 INFO - 'TMOUT': '86400', 11:16:36 INFO - 'USER': 'cltbld', 11:16:36 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', 11:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:36 INFO - '_': '/tools/buildbot/bin/python'} 11:16:36 INFO - TEST-INFO | started process 20626 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBP6pxz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:16:37 INFO - PID 20626 | 11:16:37 INFO - PID 20626 | (/builds/slave/test/build/application/firefox/firefox:20678): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:16:37 INFO - PID 20626 | 11:16:48 INFO - PID 20626 | RSS: Main: 172134400 11:16:48 INFO - PID 20626 | 11:19:47 INFO - PID 20626 | Cycle 1(1): loaded http://localhost:34805/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34805/tests/dromaeo/cssquery-ext.html) 11:19:48 INFO - PID 20626 | RSS: Main: 174022656 11:19:48 INFO - PID 20626 | 11:22:49 INFO - PID 20626 | Cycle 1(1): loaded http://localhost:34805/tests/dromaeo/cssquery-ext.html (next: http://localhost:34805/tests/dromaeo/cssquery-jquery.html) 11:22:50 INFO - PID 20626 | RSS: Main: 168853504 11:22:50 INFO - PID 20626 | 11:25:52 INFO - PID 20626 | Cycle 1(1): loaded http://localhost:34805/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34805/tests/dromaeo/cssquery-mootools.html) 11:25:52 INFO - PID 20626 | RSS: Main: 172597248 11:25:52 INFO - PID 20626 | 11:28:54 INFO - PID 20626 | Cycle 1(1): loaded http://localhost:34805/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34805/tests/dromaeo/cssquery-prototype.html) 11:28:55 INFO - PID 20626 | RSS: Main: 171368448 11:28:55 INFO - PID 20626 | 11:31:59 INFO - PID 20626 | Cycle 1(1): loaded http://localhost:34805/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34805/tests/dromaeo/cssquery-yui.html) 11:31:59 INFO - PID 20626 | RSS: Main: 172511232 11:31:59 INFO - PID 20626 | 11:35:01 INFO - PID 20626 | Cycle 1(1): loaded http://localhost:34805/tests/dromaeo/cssquery-yui.html (next: http://localhost:34805/tests/dromaeo/cssquery-dojo.html) 11:35:01 INFO - PID 20626 | RSS: Main: 175456256 11:35:01 INFO - PID 20626 | 11:35:01 INFO - PID 20626 | __start_tp_report 11:35:01 INFO - PID 20626 | _x_x_mozilla_page_load 11:35:01 INFO - PID 20626 | _x_x_mozilla_page_load_details 11:35:01 INFO - PID 20626 | |i|pagename|runs| 11:35:01 INFO - PID 20626 | |0;dojo.html;3290.588676415448;3333.233356328043;3335.082852215641;3354.412977728897;3382.2897191589764;6356.237251529811;6401.487880969523;6470.3529647035275;6497.25281592617;6583.67081645917;6304.558680892339;6385.584936979105;6401.423871851533;6418.261899881515;6447.935520644792;5867.88264234715;5879.941200588003;5894.58737888348;5975.581709280352;6016.458518733313;5954.315253745832;5957.434043765836;5968.462838344548;5970.910436343458;5982.8803423931495;5827.504662103726;5885.49973252272;5898.498627616636;5939.851503712399;6040.063790112539;5890.823275301726;5973.8805223895715;6000.009998350267;6015.428534289235;6050.6369617823075;5709.371969083398;5725.971370143165;5816.563757718188;5882.705864706791;5957.165996760457;6264.9686751566405;6312.210973628296;6334.398232167937;6356.9364306357265;6392.328805475451;6887;6933.930660693379;6936.167659880846;6937.895931561031;6951.6871740771785;6885.277045910208;6909.896351554731;6926.792196234121;6958.582485050913;6980.511364204508;6257.155284036688;6373.203349581302;6423.068655045039;6466.93533064668;6482;7027.402670773042;7042.366187043191;7090.751823686146;7097.21930587635;7133.037039999637;6734.865302693918;6798.932010679929;6803.2856550062525;6814.625195614289;6820.761273355409;6843.726250950006;6872.347127022925;6932.341427564374;6944.02783610295;6969.616671083139;6684.63234522106;6780.593164410151;6781.8643627127185;6795.422389096883;6806.965965170142;6812.931870681329;6815.965920170466;6819.318068193141;6821.488388370892;6882.105326307648;5034.974825125851;5095.974520127375;5137.948620513822;5141.820036298712;5148.819791307286;4405.537418571068;4495.415595972568;4498.100379924028;4519.231730605805;4528.705634133771;6071.7571297147615;6083.878322433527;6142.508599312045;6144.907826382608;6147.415995480512;6828.351306625863;6896.965515172392;6904.827379315457;6912.792616221522;6919.481038922101;5988.610740301867;6012.909806352908;6016.157737916695;6031.9396806032255;6056.3338032816355;5889.705514724246;5896.233489646404;5898.764049437975;5899.46904778572;5921.437463440966;6304.71628776697;6703.497237707192;6739.663016849138;6739.797806065826;6741.494387920926;6633.668316584248;6815.625140617313;6881.277465866012;6930.203026652;6983.6857341418745;6653.301403352772;6657.40083392497;6749.9662501689145;6761.93238067613;6764.8985265222045;6639.767608133692;6724.092247546519;6740.427063699444;6821.386075253251;6833.009213664091;6882.174139103339;6927.134108236471;6973.686184121625;6974;6991.615461149686;6575.30959249291;6671.566348187353;6681.498887583353;6703.095082164041;6717.429018533479;6561.606303621798;6626.900596491153;6657.3342665733035;6657.434118100015;6687.498437617295;6565.277819439762;6580.605163690194;6584.407403333723;6586.110875032001;6610.512634657163;6631.966840165768;6695.464362851058;6769.424598909148;6776.186857577122;6885.586864788129;6287.528435367274;6439.00195469703;6449.516286278454;6475.158229430144;6563.901541476786 11:35:01 INFO - PID 20626 | |1;ext.html;665.5806841689734;698.7624207769358;712.5297303779504;718.8670096032234;1120.434180738728;31361;40407.79796101019;41172.794136029355;41179.794101029525;41314;41849.790751046276;42197.78901105498;42242.78878605603;42271.78864105683;42327.78836105823;7298.416126709851;7778.45550811442;7942.920570794276;7953;7958.164392738766;7730.18833022533;7850.921490785105;7851.921480785205;7852.646630901595;7852.803679908019;7834.72578459754;7926.28663420292;7927.365810735157;7927.643256053462;7934.841303173933;7815.374770018385;7862.68549258029;7878.72424465141;7885.448018638726;7886.526808391457;7734.883976740297;7771.145174030852;7802.570858602775;7807.453478256524;7811.2188781122;37446.43830342547;37801.62198378036;37821.24357512834;37834.43248351277;37865;3448.6034106077873;3463.8614455421753;3503.054175372635;3503.281827225438;3505.772124811879;30695.846520767253;31007.689923100705;31025.689743102506;31026.84486577575;31032.379352413052;5241.925405291944;5246.632735708502;5263.210518422229;5314.0700377433795;5327.680339179623;187493;187808;188022;188622;188775;47363.5263647366;47698.28452573214;47778.9999999993;47912.60436978137;48019.75990120097;49514.25728614074;49515.75242123839;49636.503634963185;49764.502354975986;49816.75091624591;14320.427182912776;15845.841541584667;15935.521934341989;15983.280752366172;16040.43858464948;11947.581834635746;13020.934895325463;13210;13230.933845330712;13250;14435;14449.49426770058;14498.927505362617;14500.057496262709;14580.708385832437;5623.775048997994;5768.433047293914;5794.710264486759;5795.536357091422;5866.061430171238;992.8858181309098;1017.4099022566893;1021.4075836015094;1034.177828626244;1039.7868436970402;22402.214538404576;23044.423889402562;23113.88443057774;23136.4215894604;23349.533009339717;1102.614085070219;1116.8994790468896;1120.2662256222188;1122.0686829931137;1128.6049882541045;12076.275423474619;12239.20445171061;12255.632331030083;12314;12377.443015064344;218499;219126;219234;219246;219985;52917.47082529125;53052.46947530552;53062.46937530652;53074.469255307726;53100.46899531032;1436.4685066525244;1471.6026672798284;1471.8822494200438;1475.6753514226853;1480.3486465954945;22668.433289167242;22732;22834.200802971816;22994.770052299264;23417.297481075595;36828;36967.630323696416;36996.26007479889;37025;37063.8146809275;194466;194639;194885;194923;195010;32498.187545311557;33626.49560256647;33635.831820840736;33893.152671183416;33898.322033558696;42279.788601056796;42461.57538424576;43001.354979675954;43014.78492607517;43485.3477197836;30970.22574435567;31054.22364440817;31230.21924451907;31309.84345078351;31513.684863151077;31206.687933120378;31210.375792484476;31403.371932561677;31481.37037259288;32258.193545161557;36367.27265454729;36646.633533664324;36789.26421471609;36818.63181368152;36836.81581592182;30895.536566951065;31179.376412472076;31228;31313.686863131075;31402.68597314089;22790.65814012758;22985.310440686808;23051.077956882207;23057.847107644888;23168.6524702133 11:35:01 INFO - PID 20626 | |2;jquery.html;563.9633423827454;569.8945695046417;573.4265734265729;579.933307669618;588.8881112588608;22212.555748885014;22957.081716731333;23033.4241643959;23054.538909221847;23077;23755.643665345033;25776.355591110183;25951;26022.349441263977;26031.869840650772;6518.087467754506;6640.634765087918;6651.900221496681;6653.4677225821815;6664.733410663568;6233.594816336947;6519.511036672245;6552.115464412314;6554.639494827783;6565;6501.187351581052;6501.284858665543;6503.414692677658;6505.869882602345;6509.381608747185;6505.4795616350575;6508.902366464483;6510.316416776242;6534.444572211368;6542.116814230066;6064.696765161769;6075.119107729354;6085.6348619083;6085.786997455112;6096.7561297548045;28160;28771.56842647362;28963.131106066852;29077.854610727023;29442.411151777058;2070.078814927358;2108.9261875834272;2109.799569040939;2115.502856828644;2120.9787902120934;19110.71333929992;19125.13936872844;20050.398488045383;20065.699014514794;20074;2380.8333416660666;2411.1078900806638;2444.498877730061;2461.5815311397105;2499.237732491589;142691;150324.2483787585;150763.9999999989;150855;150894.24552877276;14723.558293251219;14780.48268310604;14880.776788348183;14895.70208595822;15228.93397462167;23338.416539586648;23346.533069338857;23392.18127365534;23428.882855585955;23436;9894.901050989542;10055.59777608902;10086.193104551618;10129.240306977006;10365.792684146274;9476.620935162655;9512.714618561455;9546.570404331822;9666.806663866684;9682.177014953668;8863.290936725047;8913.97489288727;9083.772905677279;9090.681826136053;9223.216026637676;2624.915724024264;2651.695055068692;2665.267051560836;2681.4905168018013;2692.151972128773;954.1698722111807;968.2205824311357;970.1123472023161;975.23444096384;991.5191132300828;19142.138603762585;19410.70883936742;19871.60256794856;19904.70142947857;19933.501662458555;1029.6820070310016;1037.4138611684375;1037.896210378956;1044.065561322612;1048.2871647279771;10540.736481588021;11062.22564420483;13228.470861165446;13826.032177747462;13830;491644;495841;496641;497022;499297;114824;117468;118089.40955295169;118656;121288.39355803341;1084.1760262200785;1184.7156682396162;1244.4151248912997;1256.120715499136;1264.6016504800957;20619.793802061788;20907.26824561133;21599.568008639424;21978;21983.890080550136;57507.712461437426;57796.422035779105;58475;58476.707616461645;60138.39861601328;70815.29184708293;71135.64432177806;71271.28728712647;71726;71893.640531797;14497.420103195755;14653.047551909092;14857.257137143315;14918.254087295809;14920.030198037093;23022.884885575466;23361.182358617367;23570;23573.764262357156;23746.643800342663;18322.908385457988;18545.53636159107;18613.534661633566;18696.53258668544;18783.34258300953;15784.684306314039;15879.888407811342;16316.510504684878;16341.754873676666;17029.91485042567;18920;19036.430081939314;19101.522461938563;19104.71342929885;19300.420987370402;14523.346449410014;14579;14663.780043299572;14724.55826325212;14754.114753114847;18247;18535.53661158482;18603.44189674312;18760.249590016792;18764.718529222344 11:35:01 INFO - PID 20626 | |3;mootools.html;1339.6784771654802;1355.3020194599776;1378.083574423008;1379.4964837834182;1391.0957877379706;11581.768364632702;14995.775063374045;15695.136767477785;15703;15706.214689265547;12871.678208044803;14404;15622.453214137508;15624.609384765386;15630.921845390758;2650.4566563854364;2721.18364490652;2723.155821695271;2729.208529526435;2729.727027297264;2717.7689896358834;2751.064638023072;2758.517259479593;2770.570561562961;2771.6673999120135;2498.287987923442;2507.5110353481077;2519.6598459207958;2519.8992040318367;2520.4959008198343;2570.267473769976;2593.170185540618;2593.753593408623;2598.4803039392195;2600.77893379063;2512.296556964053;2520.243926821946;2524.204875464223;2532.7847132993716;2541.339251794528;14871.330790114363;14973.326200321011;15056.473023444127;15071.54785356441;15083.773743393858;7956.920430795676;8264.132266112092;8267.379946503976;8277.503349799032;8284.420090593661;5217.713025783581;5466.480684335022;5471.124620060771;5477.808276710336;5607.971960140173;1921.798211187819;1925.7881633020356;1939.0789375046882;1948.9805101948941;1969.045013168612;26540.33649158767;26658.86670566654;26683.866580666974;26687.73312266872;26697.19908402751;7255.347018768336;7280.708771649075;7312.782375754651;7436.628168591549;7461.403087752967;19816.40550783479;19920.3027894023;19951.501212469513;19967.600647986957;20006.49983750389;8325.750227493185;8333.749987500385;8336.416450848503;8338.666453341919;8340.541270230196;5721.713914304351;6351.237851457762;6355.713992870239;6361.395667411589;7259.092613423322;5574;5719.227904232958;5785.50823180026;5857.970710146422;5858.472737453564;1761.7621621081246;1763.3475614022893;1784.6252287019872;1784.8393644572052;1790.274938649849;896.6234181643725;915.2952226785338;917.9541022948825;932.4451951089092;938.8450905600636;8473.745787626382;8555.828883422297;8774.210321071007;8858.557072146366;8951.820963580692;714.2393351081082;718.6909628859536;721.8881073433627;727.8798998165244;729.6607077708891;4581.518940511265;4609.792559334838;4622.352870598119;4622.791974361162;4661.510541393172;21358.252461163785;22096.779032209794;22113.66829497559;22128.557428851334;22169.339679238274;20850.791492085187;21275;21781.673274900888;21783.45541361447;21857.125714971542;10823.404712740874;11003.22977391575;11009.174311926477;11039.39283339424;11047.889521104848;9070.637174512945;9105;9182.127697868691;9226.723198304062;9250.537473126451;18063.367782127563;18496;18798.436046918614;18886.622267554845;19172.328968486036;18717.2559965374;18731;19245;19383.321583745066;19945.800541994395;12136.939315303367;12205.206661567325;12403.875961240272;12408.255504669749;12417.937910310391;14017.299135043004;14188.929055354658;14189.858101418853;14230.501932432315;14233.074850134708;11390.886091138982;11439.370834604177;11533.192676512563;11754;11974.22167559106;10686.412247326472;10920.836187457353;11235.66293011211;11290.15323850698;11510.36692981894;9811.362261452983;9988.700338989842;10246.53890574911;10253.333533320609;10256.897431025594;7313.23211062852;7479.102507699111;7508.62456877143;7526.849463010819;7541.622918854145;7505.362044226302;7505.399568034653;7538.434617403826;7590.240975902587;7595.962020189863 11:35:01 INFO - PID 20626 | |4;prototype.html;505.13873845094116;508.9796408143672;535.9517643412092;538.8194954690179;539.072794792956;6405;6415.326390728977;6451.806445806622;6486.027095935612;6486.61080335179;6085.513158947285;6217;6345.684934244712;6347.841303967403;6371.617702937827;5084.847454576368;5099.4390617032095;5149.665271757323;5165.147750621143;5168.379794424674;4945.455999840015;5079.288899554065;5089.949100508985;5095.84712458625;5105.463926287742;5013.523715247045;5030.899382012376;5045.293658887758;5055.3933527976515;5072.3768394113895;4916.532929371704;4987.202047672355;5070.72111033893;5090.16753968571;5099.490050994871;4516.232240519087;4518.096380723869;4529.297958816389;4579.335996280521;4583.289590113538;6156.969215153896;6179.165812615284;6212.782552610637;6253.499720022387;6264.906026409608;5774.48029677331;5817.272840894888;5822.33043200029;5842.844863773557;5869.178315035899;6511.609303441809;6575.80272591823;6611.446310117119;6612.768553100666;6622.072909792633;4913.852584422473;4917.311576379309;4955.603551715854;4994.775235114393;4994.800207991676;6964;7045.964770176168;7114.395276401512;7146;7191.42468602521;5010.248462730619;5071.366079240095;5080.618953578496;5127.359080114986;5129.256257842667;5122.9743851281255;5179.922301165486;5185.637005409585;5195.012947539955;5226.817061402832;5813.912791308134;5817.0983497557945;5821.253623912809;5828.5337173026055;5837.270341207349;6624;6657.633830139389;6716.194056713226;6788.898166527506;6791.4906382021545;1678.3370568625326;1694.635818166388;1708.9914550427168;1728.87897847152;1766.3552803226987;1230.993845030769;1235.1909499277988;1238.751665726703;1248.8126780982743;1263.0905747861525;5679.176519404663;5846.356900740915;5850.5612079094235;5856.569523647639;5870.442307980735;6532.41208291256;6577.934220657732;6738.292479289702;6798.694058767367;6846.931530684629;6007.729652165575;6018.668973206428;6058.818235452944;6076.3316035236085;6078.361772013964;3662.7069834413182;3687.723420743401;3697.8705745298785;3710.703143748494;3714.1643130295465;6764.951432527966;6779.254282028973;6782.389584937379;6794.184697836193;6801.693923773442;6993.825154371181;7017.298270173044;7068.395118489608;7091.1490621125795;7120.465965052642;6615.900761488581;6620.271770105284;6643.302453242437;6678.83302917431;6692.765753198614;6768.526203165731;6870.20992585859;6877.89683154743;6896.448284137156;6930.099087118642;6782.28785977485;6789.219239787488;6840.9315906842285;6845.349691779273;6916.20463646687;6617.503687223381;6671.699773510107;6741.1236539249585;6747.932520674927;6754.898676519954;5514.172874068921;5542.224088627515;5559.44405559457;5579.386267510489;5638.85902852432;6838.863222735617;6904.689288982108;6913.274106218976;6919.481038922;6996.335348141918;6569.80290591283;6636.20365556117;6643.368879956396;6667;6751.696173672295;6260.436560709558;6580.8354791130605;6595.604263744191;6597.8783606786;6645.933540664531;6037;6442.486015786193;6498.935010649833;6557.868842623217;6582.564451153104;6588.176477940257;6623.966880165568;6671.733130674913;6678.7328506859185;6726.529142959946;6215.627062376272;6303.6532990685955;6356.2054743157105;6462.870742585028;6475.676216189266 11:35:01 INFO - PID 20626 | |5;yui.html;897.7665806890207;910.8497097978833;912.8219997100568;922.5848368234297;927.6474939522981;17543;17775.28898844048;17786.288548458062;17842.732359014608;21930.780692193075;21200.57598848026;21714.674279885774;21768.782312176874;21878.45303867404;21881.67177492339;964.9951750241256;987.6641941739807;990.5839547390077;991.7074463033387;992.6624947517843;955.7562821480512;1002.6290272599149;1004.573056451009;1004.75383531035;1006.3408467453793;1036.7304500829764;1050.1703654113242;1051.574112484445;1051.64769802116;1054.125076186767;1043.6660268714015;1044.7440377107657;1047.8899715529835;1050.8581341518873;1051.1590727418072;758.9544627322323;763.290140169642;764.9043869516311;765.1468612497081;765.6554550452263;19737.40787776369;19759.703604445946;19954.102065406947;19956;20712;21198.36404907855;21239.68140477894;21475.67786483204;21594.1362345506;21657.458563536038;17032.48902532926;17135.143242837934;17301.048442335665;17325.566860828454;17349.219285132072;3595.676389124991;3623.1304486923214;3627.6735093841417;3632.364336241147;3640.7633503822167;116429.41785291104;117851;125141;127610;130975;17274.740878886827;17286.827131728773;17530.91234543819;17586.472405827844;17826.5543361417;30780.38439231203;31669.52495712566;31677.8416107918;31678.208044799063;31680.841595791873;7820.569868657165;8164;8970.68602598919;9226.169644732006;9365.953170234241;4126.546079931205;4190.8533201337805;4223.345381465878;4225.281702110648;4227.492700875914;5337.332833395825;5418.701971391532;5490.862728431821;5497.065498865202;5510.752016159283;1056.1550759392455;1073.3720773347522;1084.1922767538235;1086.5165001574383;1089.6676513663408;686.4954258619911;698.6506746626686;703.085988215318;715.4562532475356;720.3840716187689;11587.420628968517;11719.648410547697;13539;13833;13952.720945581032;691.4606606846668;707.7628994286972;709.61326077288;710.0804458226609;724.0587236592409;5589.8602534936135;5779.739911703983;5787.363390027093;5896.528277737771;6020.247469066367;111565.44217278862;112295;113634;113679.43160284146;114482;95554.52222738841;96272.51863740636;96467.51766241124;98977.50511247541;100320.99679003263;83947.16052839515;84915.57542212249;85126;85375;87000;10468.895311046792;10572.36565806054;10831.458427078773;11030.172737044593;12423.875761242272;41560;41929.580704192565;42532.574674252864;42633.36049959315;42817;67844.6607766958;69526.304736954;71753.6412317935;71946.64026680043;72487.27512724805;15978.440754573534;19110.808891910903;19680.90159549193;19722.1125049376;19997.800021999592;19629.214831406585;20733.89633051825;21844.563108738053;22153.66769498491;22160.445988850406;18829.623407531497;19214.327498537485;19720;19728.210871564977;19855.305064322678;18263.45209643713;19044;19099;19225.03874806282;19247.326343577908;17830.91084544569;17886.821131788514;18209.089545522937;18830.905845471232;18876.905615471835;13004.8049279259;13084.214947102824;13116.868831311565;13309.46762129504;13317.533886313933;14105.858941410455;14546.418143274574;14568.41726330935;14684.486042988445;14807.851921481079 11:35:01 INFO - PID 20626 | __end_tp_report 11:35:01 INFO - PID 20626 | __start_cc_report 11:35:01 INFO - PID 20626 | _x_x_mozilla_cycle_collect,387 11:35:01 INFO - PID 20626 | __end_cc_report 11:35:01 INFO - PID 20626 | __startTimestamp1504377301653__endTimestamp 11:35:01 INFO - PID 20626 | 11:35:01 INFO - PID 20626 | ------- Summary: start ------- 11:35:01 INFO - PID 20626 | Number of tests: 6 11:35:01 INFO - PID 20626 | 11:35:01 INFO - PID 20626 | [#0] dojo.html Cycles:165 Average:6312.86 Median:6575.31 stddev:766.52 (11.7%) stddev-sans-first:731.29 11:35:01 INFO - PID 20626 | Values: 3290.6 3333.2 3335.1 3354.4 3382.3 6356.2 6401.5 6470.4 6497.3 6583.7 6304.6 6385.6 6401.4 6418.3 6447.9 5867.9 5879.9 5894.6 5975.6 6016.5 5954.3 5957.4 5968.5 5970.9 5982.9 5827.5 5885.5 5898.5 5939.9 6040.1 5890.8 5973.9 6000.0 6015.4 6050.6 5709.4 5726.0 5816.6 5882.7 5957.2 6265.0 6312.2 6334.4 6356.9 6392.3 6887.0 6933.9 6936.2 6937.9 6951.7 6885.3 6909.9 6926.8 6958.6 6980.5 6257.2 6373.2 6423.1 6466.9 6482.0 7027.4 7042.4 7090.8 7097.2 7133.0 6734.9 6798.9 6803.3 6814.6 6820.8 6843.7 6872.3 6932.3 6944.0 6969.6 6684.6 6780.6 6781.9 6795.4 6807.0 6812.9 6816.0 6819.3 6821.5 6882.1 5035.0 5096.0 5137.9 5141.8 5148.8 4405.5 4495.4 4498.1 4519.2 4528.7 6071.8 6083.9 6142.5 6144.9 6147.4 6828.4 6897.0 6904.8 6912.8 6919.5 5988.6 6012.9 6016.2 6031.9 6056.3 5889.7 5896.2 5898.8 5899.5 5921.4 6304.7 6703.5 6739.7 6739.8 6741.5 6633.7 6815.6 6881.3 6930.2 6983.7 6653.3 6657.4 6750.0 6761.9 6764.9 6639.8 6724.1 6740.4 6821.4 6833.0 6882.2 6927.1 6973.7 6974.0 6991.6 6575.3 6671.6 6681.5 6703.1 6717.4 6561.6 6626.9 6657.3 6657.4 6687.5 6565.3 6580.6 6584.4 6586.1 6610.5 6632.0 6695.5 6769.4 6776.2 6885.6 6287.5 6439.0 6449.5 6475.2 6563.9 11:35:01 INFO - PID 20626 | 11:35:01 INFO - PID 20626 | [#1] ext.html Cycles:180 Average:36913.25 Median:18673.46 stddev:52043.31 (278.7%) stddev-sans-first:52118.13 11:35:01 INFO - PID 20626 | Values: 665.6 698.8 712.5 718.9 1120.4 31361.0 40407.8 41172.8 41179.8 41314.0 41849.8 42197.8 42242.8 42271.8 42327.8 7298.4 7778.5 7942.9 7953.0 7958.2 7730.2 7850.9 7851.9 7852.6 7852.8 7834.7 7926.3 7927.4 7927.6 7934.8 7815.4 7862.7 7878.7 7885.4 7886.5 7734.9 7771.1 7802.6 7807.5 7811.2 37446.4 37801.6 37821.2 37834.4 37865.0 3448.6 3463.9 3503.1 3503.3 3505.8 30695.8 31007.7 31025.7 31026.8 31032.4 5241.9 5246.6 5263.2 5314.1 5327.7 187493.0 187808.0 188022.0 188622.0 188775.0 47363.5 47698.3 47779.0 47912.6 48019.8 49514.3 49515.8 49636.5 49764.5 49816.8 14320.4 15845.8 15935.5 15983.3 16040.4 11947.6 13020.9 13210.0 13230.9 13250.0 14435.0 14449.5 14498.9 14500.1 14580.7 5623.8 5768.4 5794.7 5795.5 5866.1 992.9 1017.4 1021.4 1034.2 1039.8 22402.2 23044.4 23113.9 23136.4 23349.5 1102.6 1116.9 1120.3 1122.1 1128.6 12076.3 12239.2 12255.6 12314.0 12377.4 218499.0 219126.0 219234.0 219246.0 219985.0 52917.5 53052.5 53062.5 53074.5 53100.5 1436.5 1471.6 1471.9 1475.7 1480.3 22668.4 22732.0 22834.2 22994.8 23417.3 36828.0 36967.6 36996.3 37025.0 37063.8 194466.0 194639.0 194885.0 194923.0 195010.0 32498.2 33626.5 33635.8 33893.2 33898.3 42279.8 42461.6 43001.4 43014.8 43485.3 30970.2 31054.2 31230.2 31309.8 31513.7 31206.7 31210.4 31403.4 31481.4 32258.2 36367.3 36646.6 36789.3 36818.6 36836.8 30895.5 31179.4 31228.0 31313.7 31402.7 22790.7 22985.3 23051.1 23057.8 23168.7 11:35:01 INFO - PID 20626 | 11:35:01 INFO - PID 20626 | [#2] jquery.html Cycles:180 Average:35534.85 Median:23342.47 stddev:83954.37 (359.7%) stddev-sans-first:84148.82 11:35:01 INFO - PID 20626 | Values: 564.0 569.9 573.4 579.9 588.9 22212.6 22957.1 23033.4 23054.5 23077.0 23755.6 25776.4 25951.0 26022.3 26031.9 6518.1 6640.6 6651.9 6653.5 6664.7 6233.6 6519.5 6552.1 6554.6 6565.0 6501.2 6501.3 6503.4 6505.9 6509.4 6505.5 6508.9 6510.3 6534.4 6542.1 6064.7 6075.1 6085.6 6085.8 6096.8 28160.0 28771.6 28963.1 29077.9 29442.4 2070.1 2108.9 2109.8 2115.5 2121.0 19110.7 19125.1 20050.4 20065.7 20074.0 2380.8 2411.1 2444.5 2461.6 2499.2 142691.0 150324.2 150764.0 150855.0 150894.2 14723.6 14780.5 14880.8 14895.7 15228.9 23338.4 23346.5 23392.2 23428.9 23436.0 9894.9 10055.6 10086.2 10129.2 10365.8 9476.6 9512.7 9546.6 9666.8 9682.2 8863.3 8914.0 9083.8 9090.7 9223.2 2624.9 2651.7 2665.3 2681.5 2692.2 954.2 968.2 970.1 975.2 991.5 19142.1 19410.7 19871.6 19904.7 19933.5 1029.7 1037.4 1037.9 1044.1 1048.3 10540.7 11062.2 13228.5 13826.0 13830.0 491644.0 495841.0 496641.0 497022.0 499297.0 114824.0 117468.0 118089.4 118656.0 121288.4 1084.2 1184.7 1244.4 1256.1 1264.6 20619.8 20907.3 21599.6 21978.0 21983.9 57507.7 57796.4 58475.0 58476.7 60138.4 70815.3 71135.6 71271.3 71726.0 71893.6 14497.4 14653.0 14857.3 14918.3 14920.0 23022.9 23361.2 23570.0 23573.8 23746.6 18322.9 18545.5 18613.5 18696.5 18783.3 15784.7 15879.9 16316.5 16341.8 17029.9 18920.0 19036.4 19101.5 19104.7 19300.4 14523.3 14579.0 14663.8 14724.6 14754.1 18247.0 18535.5 18603.4 18760.2 18764.7 11:35:01 INFO - PID 20626 | 11:35:01 INFO - PID 20626 | [#3] mootools.html Cycles:180 Average:9492.53 Median:2528.49 stddev:6814.33 (269.5%) stddev-sans-first:6805.91 11:35:01 INFO - PID 20626 | Values: 1339.7 1355.3 1378.1 1379.5 1391.1 11581.8 14995.8 15695.1 15703.0 15706.2 12871.7 14404.0 15622.5 15624.6 15630.9 2650.5 2721.2 2723.2 2729.2 2729.7 2717.8 2751.1 2758.5 2770.6 2771.7 2498.3 2507.5 2519.7 2519.9 2520.5 2570.3 2593.2 2593.8 2598.5 2600.8 2512.3 2520.2 2524.2 2532.8 2541.3 14871.3 14973.3 15056.5 15071.5 15083.8 7956.9 8264.1 8267.4 8277.5 8284.4 5217.7 5466.5 5471.1 5477.8 5608.0 1921.8 1925.8 1939.1 1949.0 1969.0 26540.3 26658.9 26683.9 26687.7 26697.2 7255.3 7280.7 7312.8 7436.6 7461.4 19816.4 19920.3 19951.5 19967.6 20006.5 8325.8 8333.7 8336.4 8338.7 8340.5 5721.7 6351.2 6355.7 6361.4 7259.1 5574.0 5719.2 5785.5 5858.0 5858.5 1761.8 1763.3 1784.6 1784.8 1790.3 896.6 915.3 918.0 932.4 938.8 8473.7 8555.8 8774.2 8858.6 8951.8 714.2 718.7 721.9 727.9 729.7 4581.5 4609.8 4622.4 4622.8 4661.5 21358.3 22096.8 22113.7 22128.6 22169.3 20850.8 21275.0 21781.7 21783.5 21857.1 10823.4 11003.2 11009.2 11039.4 11047.9 9070.6 9105.0 9182.1 9226.7 9250.5 18063.4 18496.0 18798.4 18886.6 19172.3 18717.3 18731.0 19245.0 19383.3 19945.8 12136.9 12205.2 12403.9 12408.3 12417.9 14017.3 14188.9 14189.9 14230.5 14233.1 11390.9 11439.4 11533.2 11754.0 11974.2 10686.4 10920.8 11235.7 11290.2 11510.4 9811.4 9988.7 10246.5 10253.3 10256.9 7313.2 7479.1 7508.6 7526.8 7541.6 7505.4 7505.4 7538.4 7590.2 7596.0 11:35:01 INFO - PID 20626 | 11:35:01 INFO - PID 20626 | [#4] prototype.html Cycles:180 Average:5632.89 Median:6216.31 stddev:1579.50 (25.4%) stddev-sans-first:1536.32 11:35:01 INFO - PID 20626 | Values: 505.1 509.0 536.0 538.8 539.1 6405.0 6415.3 6451.8 6486.0 6486.6 6085.5 6217.0 6345.7 6347.8 6371.6 5084.8 5099.4 5149.7 5165.1 5168.4 4945.5 5079.3 5089.9 5095.8 5105.5 5013.5 5030.9 5045.3 5055.4 5072.4 4916.5 4987.2 5070.7 5090.2 5099.5 4516.2 4518.1 4529.3 4579.3 4583.3 6157.0 6179.2 6212.8 6253.5 6264.9 5774.5 5817.3 5822.3 5842.8 5869.2 6511.6 6575.8 6611.4 6612.8 6622.1 4913.9 4917.3 4955.6 4994.8 4994.8 6964.0 7046.0 7114.4 7146.0 7191.4 5010.2 5071.4 5080.6 5127.4 5129.3 5123.0 5179.9 5185.6 5195.0 5226.8 5813.9 5817.1 5821.3 5828.5 5837.3 6624.0 6657.6 6716.2 6788.9 6791.5 1678.3 1694.6 1709.0 1728.9 1766.4 1231.0 1235.2 1238.8 1248.8 1263.1 5679.2 5846.4 5850.6 5856.6 5870.4 6532.4 6577.9 6738.3 6798.7 6846.9 6007.7 6018.7 6058.8 6076.3 6078.4 3662.7 3687.7 3697.9 3710.7 3714.2 6765.0 6779.3 6782.4 6794.2 6801.7 6993.8 7017.3 7068.4 7091.1 7120.5 6615.9 6620.3 6643.3 6678.8 6692.8 6768.5 6870.2 6877.9 6896.4 6930.1 6782.3 6789.2 6840.9 6845.3 6916.2 6617.5 6671.7 6741.1 6747.9 6754.9 5514.2 5542.2 5559.4 5579.4 5638.9 6838.9 6904.7 6913.3 6919.5 6996.3 6569.8 6636.2 6643.4 6667.0 6751.7 6260.4 6580.8 6595.6 6597.9 6645.9 6037.0 6442.5 6498.9 6557.9 6582.6 6588.2 6624.0 6671.7 6678.7 6726.5 6215.6 6303.7 6356.2 6462.9 6475.7 11:35:01 INFO - PID 20626 | 11:35:01 INFO - PID 20626 | [#5] yui.html Cycles:180 Average:24033.28 Median:20966.13 stddev:32109.75 (153.2%) stddev-sans-first:32152.83 11:35:01 INFO - PID 20626 | Values: 897.8 910.8 912.8 922.6 927.6 17543.0 17775.3 17786.3 17842.7 21930.8 21200.6 21714.7 21768.8 21878.5 21881.7 965.0 987.7 990.6 991.7 992.7 955.8 1002.6 1004.6 1004.8 1006.3 1036.7 1050.2 1051.6 1051.6 1054.1 1043.7 1044.7 1047.9 1050.9 1051.2 759.0 763.3 764.9 765.1 765.7 19737.4 19759.7 19954.1 19956.0 20712.0 21198.4 21239.7 21475.7 21594.1 21657.5 17032.5 17135.1 17301.0 17325.6 17349.2 3595.7 3623.1 3627.7 3632.4 3640.8 116429.4 117851.0 125141.0 127610.0 130975.0 17274.7 17286.8 17530.9 17586.5 17826.6 30780.4 31669.5 31677.8 31678.2 31680.8 7820.6 8164.0 8970.7 9226.2 9366.0 4126.5 4190.9 4223.3 4225.3 4227.5 5337.3 5418.7 5490.9 5497.1 5510.8 1056.2 1073.4 1084.2 1086.5 1089.7 686.5 698.7 703.1 715.5 720.4 11587.4 11719.6 13539.0 13833.0 13952.7 691.5 707.8 709.6 710.1 724.1 5589.9 5779.7 5787.4 5896.5 6020.2 111565.4 112295.0 113634.0 113679.4 114482.0 95554.5 96272.5 96467.5 98977.5 100321.0 83947.2 84915.6 85126.0 85375.0 87000.0 10468.9 10572.4 10831.5 11030.2 12423.9 41560.0 41929.6 42532.6 42633.4 42817.0 67844.7 69526.3 71753.6 71946.6 72487.3 15978.4 19110.8 19680.9 19722.1 19997.8 19629.2 20733.9 21844.6 22153.7 22160.4 18829.6 19214.3 19720.0 19728.2 19855.3 18263.5 19044.0 19099.0 19225.0 19247.3 17830.9 17886.8 18209.1 18830.9 18876.9 13004.8 13084.2 13116.9 13309.5 13317.5 14105.9 14546.4 14568.4 14684.5 14807.9 11:35:01 INFO - PID 20626 | -------- Summary: end -------- 11:35:01 INFO - PID 20626 | 11:35:02 INFO - TEST-INFO | 20626: exit 0 11:35:02 INFO - TEST-OK | dromaeo_css | took 1111593ms 11:35:02 INFO - TEST-START | kraken 11:35:02 INFO - Initialising browser for kraken test... 11:35:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34805/getInfo.html -profile /tmp/tmpcJpUBP/profile 11:35:02 INFO - TEST-INFO | started process 21007 (/builds/slave/test/build/application/firefox/firefox http://localhost:34805/getInfo.html) 11:35:07 INFO - TEST-INFO | 21007: exit 0 11:35:08 INFO - Browser initialized. 11:35:08 INFO - Running cycle 1/1 for kraken test... 11:35:08 INFO - Using env: {'DISPLAY': ':0', 11:35:08 INFO - 'HOME': '/home/cltbld', 11:35:08 INFO - 'JSGC_DISABLE_POISONING': '1', 11:35:08 INFO - 'LANG': 'en_US.UTF-8', 11:35:08 INFO - 'LANGUAGE': 'en_US:en', 11:35:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:35:08 INFO - 'LOGNAME': 'cltbld', 11:35:08 INFO - 'MAIL': '/var/mail/cltbld', 11:35:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:35:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:35:08 INFO - 'MOZ_CRASHREPORTER': '1', 11:35:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:35:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:35:08 INFO - 'MOZ_NO_REMOTE': '1', 11:35:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:35:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:35:08 INFO - 'NO_EM_RESTART': '1', 11:35:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:35:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:35:08 INFO - 'PWD': '/builds/slave/test', 11:35:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:35:08 INFO - 'RUST_BACKTRACE': 'full', 11:35:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:35:08 INFO - 'SHELL': '/bin/bash', 11:35:08 INFO - 'SHLVL': '1', 11:35:08 INFO - 'STYLO_FORCE_ENABLED': '1', 11:35:08 INFO - 'TERM': 'linux', 11:35:08 INFO - 'TMOUT': '86400', 11:35:08 INFO - 'USER': 'cltbld', 11:35:08 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267', 11:35:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:35:08 INFO - '_': '/tools/buildbot/bin/python'} 11:35:08 INFO - TEST-INFO | started process 21202 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcJpUBP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:35:09 INFO - PID 21202 | 11:35:09 INFO - PID 21202 | (/builds/slave/test/build/application/firefox/firefox:21254): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:35:09 INFO - PID 21202 | 11:35:19 INFO - PID 21202 | RSS: Main: 172736512 11:35:19 INFO - PID 21202 | 11:35:22 INFO - PID 21202 | Cycle 1(1): loaded http://localhost:34805/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34805/tests/kraken/driver.html?testName=audio-beat-detection) 11:35:23 INFO - PID 21202 | RSS: Main: 177332224 11:35:23 INFO - PID 21202 | 11:35:25 INFO - PID 21202 | Cycle 1(1): loaded http://localhost:34805/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34805/tests/kraken/driver.html?testName=audio-dft) 11:35:26 INFO - PID 21202 | RSS: Main: 178102272 11:35:26 INFO - PID 21202 | 11:35:29 INFO - PID 21202 | Cycle 1(1): loaded http://localhost:34805/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34805/tests/kraken/driver.html?testName=audio-fft) 11:35:29 INFO - PID 21202 | RSS: Main: 172363776 11:35:29 INFO - PID 21202 | 11:35:31 INFO - PID 21202 | Cycle 1(1): loaded http://localhost:34805/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34805/tests/kraken/driver.html?testName=audio-oscillator) 11:35:32 INFO - PID 21202 | RSS: Main: 173064192 11:35:32 INFO - PID 21202 | 11:35:34 INFO - PID 21202 | Cycle 1(1): loaded http://localhost:34805/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34805/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:35:35 INFO - PID 21202 | RSS: Main: 173395968 11:35:35 INFO - PID 21202 | 11:35:38 INFO - PID 21202 | Cycle 1(1): loaded http://localhost:34805/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34805/tests/kraken/driver.html?testName=imaging-darkroom) 11:35:39 INFO - PID 21202 | RSS: Main: 176185344 11:35:39 INFO - PID 21202 | 11:35:42 INFO - PID 21202 | Cycle 1(1): loaded http://localhost:34805/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34805/tests/kraken/driver.html?testName=imaging-desaturate) 11:35:43 INFO - PID 21202 | RSS: Main: 175828992 11:35:43 INFO - PID 21202 | 11:35:46 INFO - PID 21202 | Cycle 1(1): loaded http://localhost:34805/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34805/tests/kraken/driver.html?testName=json-parse-financial) 11:35:46 INFO - PID 21202 | RSS: Main: 176627712 11:35:46 INFO - PID 21202 | 11:35:48 INFO - PID 21202 | Cycle 1(1): loaded http://localhost:34805/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34805/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:35:49 INFO - PID 21202 | RSS: Main: 176107520 11:35:49 INFO - PID 21202 | 11:35:50 INFO - PID 21202 | Cycle 1(1): loaded http://localhost:34805/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34805/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:35:51 INFO - PID 21202 | RSS: Main: 176062464 11:35:51 INFO - PID 21202 | 11:35:53 INFO - PID 21202 | Cycle 1(1): loaded http://localhost:34805/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34805/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:35:54 INFO - PID 21202 | RSS: Main: 172773376 11:35:54 INFO - PID 21202 | 11:35:56 INFO - PID 21202 | Cycle 1(1): loaded http://localhost:34805/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34805/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:35:57 INFO - PID 21202 | RSS: Main: 173416448 11:35:57 INFO - PID 21202 | 11:36:00 INFO - PID 21202 | Cycle 1(1): loaded http://localhost:34805/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34805/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:36:01 INFO - PID 21202 | RSS: Main: 174006272 11:36:01 INFO - PID 21202 | 11:36:02 INFO - PID 21202 | Cycle 1(1): loaded http://localhost:34805/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34805/tests/kraken/driver.html?testName=ai-astar) 11:36:03 INFO - PID 21202 | RSS: Main: 174944256 11:36:03 INFO - PID 21202 | 11:36:03 INFO - PID 21202 | __start_tp_report 11:36:03 INFO - PID 21202 | _x_x_mozilla_page_load 11:36:03 INFO - PID 21202 | _x_x_mozilla_page_load_details 11:36:03 INFO - PID 21202 | |i|pagename|runs| 11:36:03 INFO - PID 21202 | |0;ai-astar;100;107;100;107;107;108;115;99;109;109 11:36:03 INFO - PID 21202 | |1;audio-beat-detection;147;119;139;163;116;139;146;119;169;121 11:36:03 INFO - PID 21202 | |2;audio-dft;163;162;163;162;167;165;163;169;167;163 11:36:03 INFO - PID 21202 | |3;audio-fft;73;75;76;180;74;74;74;73;150;75 11:36:03 INFO - PID 21202 | |4;audio-oscillator;79;79;137;79;81;79;128;80;80;129 11:36:03 INFO - PID 21202 | |5;imaging-gaussian-blur;113;113;115;115;114;114;115;115;114;114 11:36:03 INFO - PID 21202 | |6;imaging-darkroom;148;148;148;148;147;148;148;147;148;149 11:36:03 INFO - PID 21202 | |7;imaging-desaturate;94;94;94;94;94;93;94;94;94;94 11:36:03 INFO - PID 21202 | |8;json-parse-financial;53;51;51;53;53;52;52;52;52;53 11:36:03 INFO - PID 21202 | |9;json-stringify-tinderbox;38;39;39;39;39;39;38;38;39;38 11:36:03 INFO - PID 21202 | |10;stanford-crypto-aes;75;76;79;75;81;77;75;77;73;76 11:36:03 INFO - PID 21202 | |11;stanford-crypto-ccm;128;127;125;117;127;115;120;125;127;170 11:36:03 INFO - PID 21202 | |12;stanford-crypto-pbkdf2;172;177;175;168;167;170;166;169;168;168 11:36:03 INFO - PID 21202 | |13;stanford-crypto-sha256-iterative;65;66;67;63;63;63;63;65;64;62 11:36:03 INFO - PID 21202 | __end_tp_report 11:36:03 INFO - PID 21202 | __start_cc_report 11:36:03 INFO - PID 21202 | _x_x_mozilla_cycle_collect,960 11:36:03 INFO - PID 21202 | __end_cc_report 11:36:03 INFO - PID 21202 | __startTimestamp1504377363042__endTimestamp 11:36:03 INFO - PID 21202 | 11:36:03 INFO - PID 21202 | ------- Summary: start ------- 11:36:03 INFO - PID 21202 | Number of tests: 14 11:36:03 INFO - PID 21202 | 11:36:03 INFO - PID 21202 | [#0] ai-astar Cycles:10 Average:106.10 Median:108.50 stddev:5.02 (4.6%) stddev-sans-first:4.82 11:36:03 INFO - PID 21202 | Values: 100.0 107.0 100.0 107.0 107.0 108.0 115.0 99.0 109.0 109.0 11:36:03 INFO - PID 21202 | 11:36:03 INFO - PID 21202 | [#1] audio-beat-detection Cycles:10 Average:137.80 Median:142.50 stddev:18.94 (13.3%) stddev-sans-first:19.79 11:36:03 INFO - PID 21202 | Values: 147.0 119.0 139.0 163.0 116.0 139.0 146.0 119.0 169.0 121.0 11:36:03 INFO - PID 21202 | 11:36:03 INFO - PID 21202 | [#2] audio-dft Cycles:10 Average:164.40 Median:164.00 stddev:2.46 (1.5%) stddev-sans-first:2.55 11:36:03 INFO - PID 21202 | Values: 163.0 162.0 163.0 162.0 167.0 165.0 163.0 169.0 167.0 163.0 11:36:03 INFO - PID 21202 | 11:36:03 INFO - PID 21202 | [#3] audio-fft Cycles:10 Average:92.40 Median:74.00 stddev:38.92 (52.6%) stddev-sans-first:40.65 11:36:03 INFO - PID 21202 | Values: 73.0 75.0 76.0 180.0 74.0 74.0 74.0 73.0 150.0 75.0 11:36:03 INFO - PID 21202 | 11:36:03 INFO - PID 21202 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:79.00 stddev:25.12 (31.8%) stddev-sans-first:25.96 11:36:03 INFO - PID 21202 | Values: 79.0 79.0 137.0 79.0 81.0 79.0 128.0 80.0 80.0 129.0 11:36:03 INFO - PID 21202 | 11:36:03 INFO - PID 21202 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.50 stddev:0.79 (0.7%) stddev-sans-first:0.71 11:36:03 INFO - PID 21202 | Values: 113.0 113.0 115.0 115.0 114.0 114.0 115.0 115.0 114.0 114.0 11:36:03 INFO - PID 21202 | 11:36:03 INFO - PID 21202 | [#6] imaging-darkroom Cycles:10 Average:147.90 Median:148.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 11:36:03 INFO - PID 21202 | Values: 148.0 148.0 148.0 148.0 147.0 148.0 148.0 147.0 148.0 149.0 11:36:03 INFO - PID 21202 | 11:36:03 INFO - PID 21202 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 11:36:03 INFO - PID 21202 | Values: 94.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 94.0 94.0 11:36:03 INFO - PID 21202 | 11:36:03 INFO - PID 21202 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:52.50 stddev:0.79 (1.5%) stddev-sans-first:0.78 11:36:03 INFO - PID 21202 | Values: 53.0 51.0 51.0 53.0 53.0 52.0 52.0 52.0 52.0 53.0 11:36:03 INFO - PID 21202 | 11:36:03 INFO - PID 21202 | [#9] json-stringify-tinderbox Cycles:10 Average:38.60 Median:39.00 stddev:0.52 (1.3%) stddev-sans-first:0.50 11:36:03 INFO - PID 21202 | Values: 38.0 39.0 39.0 39.0 39.0 39.0 38.0 38.0 39.0 38.0 11:36:03 INFO - PID 21202 | 11:36:03 INFO - PID 21202 | [#10] stanford-crypto-aes Cycles:10 Average:76.40 Median:76.50 stddev:2.27 (3.0%) stddev-sans-first:2.35 11:36:03 INFO - PID 21202 | Values: 75.0 76.0 79.0 75.0 81.0 77.0 75.0 77.0 73.0 76.0 11:36:03 INFO - PID 21202 | 11:36:03 INFO - PID 21202 | [#11] stanford-crypto-ccm Cycles:10 Average:128.10 Median:127.00 stddev:15.42 (12.1%) stddev-sans-first:16.35 11:36:03 INFO - PID 21202 | Values: 128.0 127.0 125.0 117.0 127.0 115.0 120.0 125.0 127.0 170.0 11:36:03 INFO - PID 21202 | 11:36:03 INFO - PID 21202 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.00 Median:169.50 stddev:3.59 (2.1%) stddev-sans-first:3.73 11:36:03 INFO - PID 21202 | Values: 172.0 177.0 175.0 168.0 167.0 170.0 166.0 169.0 168.0 168.0 11:36:03 INFO - PID 21202 | 11:36:03 INFO - PID 21202 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.50 stddev:1.60 (2.5%) stddev-sans-first:1.66 11:36:03 INFO - PID 21202 | Values: 65.0 66.0 67.0 63.0 63.0 63.0 63.0 65.0 64.0 62.0 11:36:03 INFO - PID 21202 | -------- Summary: end -------- 11:36:03 INFO - PID 21202 | 11:36:03 INFO - PID 21202 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:36:03 INFO - TEST-INFO | 21202: exit 0 11:36:03 INFO - TEST-OK | kraken | took 61410ms 11:36:03 INFO - SUITE-END | took 1173s 11:36:04 INFO - Completed test suite (00:19:33) 11:36:04 INFO - javascript benchmark 11:36:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8646.016750861685, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3290.588676415448, 3333.233356328043, 3335.082852215641, 3354.412977728897, 3382.2897191589764, 6356.237251529811, 6401.487880969523, 6470.3529647035275, 6497.25281592617, 6583.67081645917, 6304.558680892339, 6385.584936979105, 6401.423871851533, 6418.261899881515, 6447.935520644792, 5867.88264234715, 5879.941200588003, 5894.58737888348, 5975.581709280352, 6016.458518733313, 5954.315253745832, 5957.434043765836, 5968.462838344548, 5970.910436343458, 5982.8803423931495, 5827.504662103726, 5885.49973252272, 5898.498627616636, 5939.851503712399, 6040.063790112539, 5890.823275301726, 5973.8805223895715, 6000.009998350267, 6015.428534289235, 6050.6369617823075, 5709.371969083398, 5725.971370143165, 5816.563757718188, 5882.705864706791, 5957.165996760457, 6264.9686751566405, 6312.210973628296, 6334.398232167937, 6356.9364306357265, 6392.328805475451, 6887.0, 6933.930660693379, 6936.167659880846, 6937.895931561031, 6951.6871740771785, 6885.277045910208, 6909.896351554731, 6926.792196234121, 6958.582485050913, 6980.511364204508, 6257.155284036688, 6373.203349581302, 6423.068655045039, 6466.93533064668, 6482.0, 7027.402670773042, 7042.366187043191, 7090.751823686146, 7097.21930587635, 7133.037039999637, 6734.865302693918, 6798.932010679929, 6803.2856550062525, 6814.625195614289, 6820.761273355409, 6843.726250950006, 6872.347127022925, 6932.341427564374, 6944.02783610295, 6969.616671083139, 6684.63234522106, 6780.593164410151, 6781.8643627127185, 6795.422389096883, 6806.965965170142, 6812.931870681329, 6815.965920170466, 6819.318068193141, 6821.488388370892, 6882.105326307648, 5034.974825125851, 5095.974520127375, 5137.948620513822, 5141.820036298712, 5148.819791307286, 4405.537418571068, 4495.415595972568, 4498.100379924028, 4519.231730605805, 4528.705634133771, 6071.7571297147615, 6083.878322433527, 6142.508599312045, 6144.907826382608, 6147.415995480512, 6828.351306625863, 6896.965515172392, 6904.827379315457, 6912.792616221522, 6919.481038922101, 5988.610740301867, 6012.909806352908, 6016.157737916695, 6031.9396806032255, 6056.3338032816355, 5889.705514724246, 5896.233489646404, 5898.764049437975, 5899.46904778572, 5921.437463440966, 6304.71628776697, 6703.497237707192, 6739.663016849138, 6739.797806065826, 6741.494387920926, 6633.668316584248, 6815.625140617313, 6881.277465866012, 6930.203026652, 6983.6857341418745, 6653.301403352772, 6657.40083392497, 6749.9662501689145, 6761.93238067613, 6764.8985265222045, 6639.767608133692, 6724.092247546519, 6740.427063699444, 6821.386075253251, 6833.009213664091, 6882.174139103339, 6927.134108236471, 6973.686184121625, 6974.0, 6991.615461149686, 6575.30959249291, 6671.566348187353, 6681.498887583353, 6703.095082164041, 6717.429018533479, 6561.606303621798, 6626.900596491153, 6657.3342665733035, 6657.434118100015, 6687.498437617295, 6565.277819439762, 6580.605163690194, 6584.407403333723, 6586.110875032001, 6610.512634657163, 6631.966840165768, 6695.464362851058, 6769.424598909148, 6776.186857577122, 6885.586864788129, 6287.528435367274, 6439.00195469703, 6449.516286278454, 6475.158229430144, 6563.901541476786], "value": 6254.6841235168295, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.5806841689734, 698.7624207769358, 712.5297303779504, 718.8670096032234, 1120.434180738728, 31361.0, 40407.79796101019, 41172.794136029355, 41179.794101029525, 41314.0, 41849.790751046276, 42197.78901105498, 42242.78878605603, 42271.78864105683, 42327.78836105823, 7298.416126709851, 7778.45550811442, 7942.920570794276, 7953.0, 7958.164392738766, 7730.18833022533, 7850.921490785105, 7851.921480785205, 7852.646630901595, 7852.803679908019, 7834.72578459754, 7926.28663420292, 7927.365810735157, 7927.643256053462, 7934.841303173933, 7815.374770018385, 7862.68549258029, 7878.72424465141, 7885.448018638726, 7886.526808391457, 7734.883976740297, 7771.145174030852, 7802.570858602775, 7807.453478256524, 7811.2188781122, 37446.43830342547, 37801.62198378036, 37821.24357512834, 37834.43248351277, 37865.0, 3448.6034106077873, 3463.8614455421753, 3503.054175372635, 3503.281827225438, 3505.772124811879, 30695.846520767253, 31007.689923100705, 31025.689743102506, 31026.84486577575, 31032.379352413052, 5241.925405291944, 5246.632735708502, 5263.210518422229, 5314.0700377433795, 5327.680339179623, 187493.0, 187808.0, 188022.0, 188622.0, 188775.0, 47363.5263647366, 47698.28452573214, 47778.9999999993, 47912.60436978137, 48019.75990120097, 49514.25728614074, 49515.75242123839, 49636.503634963185, 49764.502354975986, 49816.75091624591, 14320.427182912776, 15845.841541584667, 15935.521934341989, 15983.280752366172, 16040.43858464948, 11947.581834635746, 13020.934895325463, 13210.0, 13230.933845330712, 13250.0, 14435.0, 14449.49426770058, 14498.927505362617, 14500.057496262709, 14580.708385832437, 5623.775048997994, 5768.433047293914, 5794.710264486759, 5795.536357091422, 5866.061430171238, 992.8858181309098, 1017.4099022566893, 1021.4075836015094, 1034.177828626244, 1039.7868436970402, 22402.214538404576, 23044.423889402562, 23113.88443057774, 23136.4215894604, 23349.533009339717, 1102.614085070219, 1116.8994790468896, 1120.2662256222188, 1122.0686829931137, 1128.6049882541045, 12076.275423474619, 12239.20445171061, 12255.632331030083, 12314.0, 12377.443015064344, 218499.0, 219126.0, 219234.0, 219246.0, 219985.0, 52917.47082529125, 53052.46947530552, 53062.46937530652, 53074.469255307726, 53100.46899531032, 1436.4685066525244, 1471.6026672798284, 1471.8822494200438, 1475.6753514226853, 1480.3486465954945, 22668.433289167242, 22732.0, 22834.200802971816, 22994.770052299264, 23417.297481075595, 36828.0, 36967.630323696416, 36996.26007479889, 37025.0, 37063.8146809275, 194466.0, 194639.0, 194885.0, 194923.0, 195010.0, 32498.187545311557, 33626.49560256647, 33635.831820840736, 33893.152671183416, 33898.322033558696, 42279.788601056796, 42461.57538424576, 43001.354979675954, 43014.78492607517, 43485.3477197836, 30970.22574435567, 31054.22364440817, 31230.21924451907, 31309.84345078351, 31513.684863151077, 31206.687933120378, 31210.375792484476, 31403.371932561677, 31481.37037259288, 32258.193545161557, 36367.27265454729, 36646.633533664324, 36789.26421471609, 36818.63181368152, 36836.81581592182, 30895.536566951065, 31179.376412472076, 31228.0, 31313.686863131075, 31402.68597314089, 22790.65814012758, 22985.310440686808, 23051.077956882207, 23057.847107644888, 23168.6524702133], "value": 17026.706131123738, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.9633423827454, 569.8945695046417, 573.4265734265729, 579.933307669618, 588.8881112588608, 22212.555748885014, 22957.081716731333, 23033.4241643959, 23054.538909221847, 23077.0, 23755.643665345033, 25776.355591110183, 25951.0, 26022.349441263977, 26031.869840650772, 6518.087467754506, 6640.634765087918, 6651.900221496681, 6653.4677225821815, 6664.733410663568, 6233.594816336947, 6519.511036672245, 6552.115464412314, 6554.639494827783, 6565.0, 6501.187351581052, 6501.284858665543, 6503.414692677658, 6505.869882602345, 6509.381608747185, 6505.4795616350575, 6508.902366464483, 6510.316416776242, 6534.444572211368, 6542.116814230066, 6064.696765161769, 6075.119107729354, 6085.6348619083, 6085.786997455112, 6096.7561297548045, 28160.0, 28771.56842647362, 28963.131106066852, 29077.854610727023, 29442.411151777058, 2070.078814927358, 2108.9261875834272, 2109.799569040939, 2115.502856828644, 2120.9787902120934, 19110.71333929992, 19125.13936872844, 20050.398488045383, 20065.699014514794, 20074.0, 2380.8333416660666, 2411.1078900806638, 2444.498877730061, 2461.5815311397105, 2499.237732491589, 142691.0, 150324.2483787585, 150763.9999999989, 150855.0, 150894.24552877276, 14723.558293251219, 14780.48268310604, 14880.776788348183, 14895.70208595822, 15228.93397462167, 23338.416539586648, 23346.533069338857, 23392.18127365534, 23428.882855585955, 23436.0, 9894.901050989542, 10055.59777608902, 10086.193104551618, 10129.240306977006, 10365.792684146274, 9476.620935162655, 9512.714618561455, 9546.570404331822, 9666.806663866684, 9682.177014953668, 8863.290936725047, 8913.97489288727, 9083.772905677279, 9090.681826136053, 9223.216026637676, 2624.915724024264, 2651.695055068692, 2665.267051560836, 2681.4905168018013, 2692.151972128773, 954.1698722111807, 968.2205824311357, 970.1123472023161, 975.23444096384, 991.5191132300828, 19142.138603762585, 19410.70883936742, 19871.60256794856, 19904.70142947857, 19933.501662458555, 1029.6820070310016, 1037.4138611684375, 1037.896210378956, 1044.065561322612, 1048.2871647279771, 10540.736481588021, 11062.22564420483, 13228.470861165446, 13826.032177747462, 13830.0, 491644.0, 495841.0, 496641.0, 497022.0, 499297.0, 114824.0, 117468.0, 118089.40955295169, 118656.0, 121288.39355803341, 1084.1760262200785, 1184.7156682396162, 1244.4151248912997, 1256.120715499136, 1264.6016504800957, 20619.793802061788, 20907.26824561133, 21599.568008639424, 21978.0, 21983.890080550136, 57507.712461437426, 57796.422035779105, 58475.0, 58476.707616461645, 60138.39861601328, 70815.29184708293, 71135.64432177806, 71271.28728712647, 71726.0, 71893.640531797, 14497.420103195755, 14653.047551909092, 14857.257137143315, 14918.254087295809, 14920.030198037093, 23022.884885575466, 23361.182358617367, 23570.0, 23573.764262357156, 23746.643800342663, 18322.908385457988, 18545.53636159107, 18613.534661633566, 18696.53258668544, 18783.34258300953, 15784.684306314039, 15879.888407811342, 16316.510504684878, 16341.754873676666, 17029.91485042567, 18920.0, 19036.430081939314, 19101.522461938563, 19104.71342929885, 19300.420987370402, 14523.346449410014, 14579.0, 14663.780043299572, 14724.55826325212, 14754.114753114847, 18247.0, 18535.53661158482, 18603.44189674312, 18760.249590016792, 18764.718529222344], "value": 12155.634974200779, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1339.6784771654802, 1355.3020194599776, 1378.083574423008, 1379.4964837834182, 1391.0957877379706, 11581.768364632702, 14995.775063374045, 15695.136767477785, 15703.0, 15706.214689265547, 12871.678208044803, 14404.0, 15622.453214137508, 15624.609384765386, 15630.921845390758, 2650.4566563854364, 2721.18364490652, 2723.155821695271, 2729.208529526435, 2729.727027297264, 2717.7689896358834, 2751.064638023072, 2758.517259479593, 2770.570561562961, 2771.6673999120135, 2498.287987923442, 2507.5110353481077, 2519.6598459207958, 2519.8992040318367, 2520.4959008198343, 2570.267473769976, 2593.170185540618, 2593.753593408623, 2598.4803039392195, 2600.77893379063, 2512.296556964053, 2520.243926821946, 2524.204875464223, 2532.7847132993716, 2541.339251794528, 14871.330790114363, 14973.326200321011, 15056.473023444127, 15071.54785356441, 15083.773743393858, 7956.920430795676, 8264.132266112092, 8267.379946503976, 8277.503349799032, 8284.420090593661, 5217.713025783581, 5466.480684335022, 5471.124620060771, 5477.808276710336, 5607.971960140173, 1921.798211187819, 1925.7881633020356, 1939.0789375046882, 1948.9805101948941, 1969.045013168612, 26540.33649158767, 26658.86670566654, 26683.866580666974, 26687.73312266872, 26697.19908402751, 7255.347018768336, 7280.708771649075, 7312.782375754651, 7436.628168591549, 7461.403087752967, 19816.40550783479, 19920.3027894023, 19951.501212469513, 19967.600647986957, 20006.49983750389, 8325.750227493185, 8333.749987500385, 8336.416450848503, 8338.666453341919, 8340.541270230196, 5721.713914304351, 6351.237851457762, 6355.713992870239, 6361.395667411589, 7259.092613423322, 5574.0, 5719.227904232958, 5785.50823180026, 5857.970710146422, 5858.472737453564, 1761.7621621081246, 1763.3475614022893, 1784.6252287019872, 1784.8393644572052, 1790.274938649849, 896.6234181643725, 915.2952226785338, 917.9541022948825, 932.4451951089092, 938.8450905600636, 8473.745787626382, 8555.828883422297, 8774.210321071007, 8858.557072146366, 8951.820963580692, 714.2393351081082, 718.6909628859536, 721.8881073433627, 727.8798998165244, 729.6607077708891, 4581.518940511265, 4609.792559334838, 4622.352870598119, 4622.791974361162, 4661.510541393172, 21358.252461163785, 22096.779032209794, 22113.66829497559, 22128.557428851334, 22169.339679238274, 20850.791492085187, 21275.0, 21781.673274900888, 21783.45541361447, 21857.125714971542, 10823.404712740874, 11003.22977391575, 11009.174311926477, 11039.39283339424, 11047.889521104848, 9070.637174512945, 9105.0, 9182.127697868691, 9226.723198304062, 9250.537473126451, 18063.367782127563, 18496.0, 18798.436046918614, 18886.622267554845, 19172.328968486036, 18717.2559965374, 18731.0, 19245.0, 19383.321583745066, 19945.800541994395, 12136.939315303367, 12205.206661567325, 12403.875961240272, 12408.255504669749, 12417.937910310391, 14017.299135043004, 14188.929055354658, 14189.858101418853, 14230.501932432315, 14233.074850134708, 11390.886091138982, 11439.370834604177, 11533.192676512563, 11754.0, 11974.22167559106, 10686.412247326472, 10920.836187457353, 11235.66293011211, 11290.15323850698, 11510.36692981894, 9811.362261452983, 9988.700338989842, 10246.53890574911, 10253.333533320609, 10256.897431025594, 7313.23211062852, 7479.102507699111, 7508.62456877143, 7526.849463010819, 7541.622918854145, 7505.362044226302, 7505.399568034653, 7538.434617403826, 7590.240975902587, 7595.962020189863], "value": 6718.124513759165, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.13873845094116, 508.9796408143672, 535.9517643412092, 538.8194954690179, 539.072794792956, 6405.0, 6415.326390728977, 6451.806445806622, 6486.027095935612, 6486.61080335179, 6085.513158947285, 6217.0, 6345.684934244712, 6347.841303967403, 6371.617702937827, 5084.847454576368, 5099.4390617032095, 5149.665271757323, 5165.147750621143, 5168.379794424674, 4945.455999840015, 5079.288899554065, 5089.949100508985, 5095.84712458625, 5105.463926287742, 5013.523715247045, 5030.899382012376, 5045.293658887758, 5055.3933527976515, 5072.3768394113895, 4916.532929371704, 4987.202047672355, 5070.72111033893, 5090.16753968571, 5099.490050994871, 4516.232240519087, 4518.096380723869, 4529.297958816389, 4579.335996280521, 4583.289590113538, 6156.969215153896, 6179.165812615284, 6212.782552610637, 6253.499720022387, 6264.906026409608, 5774.48029677331, 5817.272840894888, 5822.33043200029, 5842.844863773557, 5869.178315035899, 6511.609303441809, 6575.80272591823, 6611.446310117119, 6612.768553100666, 6622.072909792633, 4913.852584422473, 4917.311576379309, 4955.603551715854, 4994.775235114393, 4994.800207991676, 6964.0, 7045.964770176168, 7114.395276401512, 7146.0, 7191.42468602521, 5010.248462730619, 5071.366079240095, 5080.618953578496, 5127.359080114986, 5129.256257842667, 5122.9743851281255, 5179.922301165486, 5185.637005409585, 5195.012947539955, 5226.817061402832, 5813.912791308134, 5817.0983497557945, 5821.253623912809, 5828.5337173026055, 5837.270341207349, 6624.0, 6657.633830139389, 6716.194056713226, 6788.898166527506, 6791.4906382021545, 1678.3370568625326, 1694.635818166388, 1708.9914550427168, 1728.87897847152, 1766.3552803226987, 1230.993845030769, 1235.1909499277988, 1238.751665726703, 1248.8126780982743, 1263.0905747861525, 5679.176519404663, 5846.356900740915, 5850.5612079094235, 5856.569523647639, 5870.442307980735, 6532.41208291256, 6577.934220657732, 6738.292479289702, 6798.694058767367, 6846.931530684629, 6007.729652165575, 6018.668973206428, 6058.818235452944, 6076.3316035236085, 6078.361772013964, 3662.7069834413182, 3687.723420743401, 3697.8705745298785, 3710.703143748494, 3714.1643130295465, 6764.951432527966, 6779.254282028973, 6782.389584937379, 6794.184697836193, 6801.693923773442, 6993.825154371181, 7017.298270173044, 7068.395118489608, 7091.1490621125795, 7120.465965052642, 6615.900761488581, 6620.271770105284, 6643.302453242437, 6678.83302917431, 6692.765753198614, 6768.526203165731, 6870.20992585859, 6877.89683154743, 6896.448284137156, 6930.099087118642, 6782.28785977485, 6789.219239787488, 6840.9315906842285, 6845.349691779273, 6916.20463646687, 6617.503687223381, 6671.699773510107, 6741.1236539249585, 6747.932520674927, 6754.898676519954, 5514.172874068921, 5542.224088627515, 5559.44405559457, 5579.386267510489, 5638.85902852432, 6838.863222735617, 6904.689288982108, 6913.274106218976, 6919.481038922, 6996.335348141918, 6569.80290591283, 6636.20365556117, 6643.368879956396, 6667.0, 6751.696173672295, 6260.436560709558, 6580.8354791130605, 6595.604263744191, 6597.8783606786, 6645.933540664531, 6037.0, 6442.486015786193, 6498.935010649833, 6557.868842623217, 6582.564451153104, 6588.176477940257, 6623.966880165568, 6671.733130674913, 6678.7328506859185, 6726.529142959946, 6215.627062376272, 6303.6532990685955, 6356.2054743157105, 6462.870742585028, 6475.676216189266], "value": 5164.942750674531, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [897.7665806890207, 910.8497097978833, 912.8219997100568, 922.5848368234297, 927.6474939522981, 17543.0, 17775.28898844048, 17786.288548458062, 17842.732359014608, 21930.780692193075, 21200.57598848026, 21714.674279885774, 21768.782312176874, 21878.45303867404, 21881.67177492339, 964.9951750241256, 987.6641941739807, 990.5839547390077, 991.7074463033387, 992.6624947517843, 955.7562821480512, 1002.6290272599149, 1004.573056451009, 1004.75383531035, 1006.3408467453793, 1036.7304500829764, 1050.1703654113242, 1051.574112484445, 1051.64769802116, 1054.125076186767, 1043.6660268714015, 1044.7440377107657, 1047.8899715529835, 1050.8581341518873, 1051.1590727418072, 758.9544627322323, 763.290140169642, 764.9043869516311, 765.1468612497081, 765.6554550452263, 19737.40787776369, 19759.703604445946, 19954.102065406947, 19956.0, 20712.0, 21198.36404907855, 21239.68140477894, 21475.67786483204, 21594.1362345506, 21657.458563536038, 17032.48902532926, 17135.143242837934, 17301.048442335665, 17325.566860828454, 17349.219285132072, 3595.676389124991, 3623.1304486923214, 3627.6735093841417, 3632.364336241147, 3640.7633503822167, 116429.41785291104, 117851.0, 125141.0, 127610.0, 130975.0, 17274.740878886827, 17286.827131728773, 17530.91234543819, 17586.472405827844, 17826.5543361417, 30780.38439231203, 31669.52495712566, 31677.8416107918, 31678.208044799063, 31680.841595791873, 7820.569868657165, 8164.0, 8970.68602598919, 9226.169644732006, 9365.953170234241, 4126.546079931205, 4190.8533201337805, 4223.345381465878, 4225.281702110648, 4227.492700875914, 5337.332833395825, 5418.701971391532, 5490.862728431821, 5497.065498865202, 5510.752016159283, 1056.1550759392455, 1073.3720773347522, 1084.1922767538235, 1086.5165001574383, 1089.6676513663408, 686.4954258619911, 698.6506746626686, 703.085988215318, 715.4562532475356, 720.3840716187689, 11587.420628968517, 11719.648410547697, 13539.0, 13833.0, 13952.720945581032, 691.4606606846668, 707.7628994286972, 709.61326077288, 710.0804458226609, 724.0587236592409, 5589.8602534936135, 5779.739911703983, 5787.363390027093, 5896.528277737771, 6020.247469066367, 111565.44217278862, 112295.0, 113634.0, 113679.43160284146, 114482.0, 95554.52222738841, 96272.51863740636, 96467.51766241124, 98977.50511247541, 100320.99679003263, 83947.16052839515, 84915.57542212249, 85126.0, 85375.0, 87000.0, 10468.895311046792, 10572.36565806054, 10831.458427078773, 11030.172737044593, 12423.875761242272, 41560.0, 41929.580704192565, 42532.574674252864, 42633.36049959315, 42817.0, 67844.6607766958, 69526.304736954, 71753.6412317935, 71946.64026680043, 72487.27512724805, 15978.440754573534, 19110.808891910903, 19680.90159549193, 19722.1125049376, 19997.800021999592, 19629.214831406585, 20733.89633051825, 21844.563108738053, 22153.66769498491, 22160.445988850406, 18829.623407531497, 19214.327498537485, 19720.0, 19728.210871564977, 19855.305064322678, 18263.45209643713, 19044.0, 19099.0, 19225.03874806282, 19247.326343577908, 17830.91084544569, 17886.821131788514, 18209.089545522937, 18830.905845471232, 18876.905615471835, 13004.8049279259, 13084.214947102824, 13116.868831311565, 13309.46762129504, 13317.533886313933, 14105.858941410455, 14546.418143274574, 14568.41726330935, 14684.486042988445, 14807.851921481079], "value": 9299.176474882324, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1481.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 107.0, 100.0, 107.0, 107.0, 108.0, 115.0, 99.0, 109.0, 109.0], "value": 106.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 119.0, 139.0, 163.0, 116.0, 139.0, 146.0, 119.0, 169.0, 121.0], "value": 137.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 162.0, 167.0, 165.0, 163.0, 169.0, 167.0, 163.0], "value": 164.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 76.0, 180.0, 74.0, 74.0, 74.0, 73.0, 150.0, 75.0], "value": 92.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 137.0, 79.0, 81.0, 79.0, 128.0, 80.0, 80.0, 129.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 115.0, 115.0, 114.0, 114.0, 115.0, 115.0, 114.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 148.0, 147.0, 148.0, 148.0, 147.0, 148.0, 149.0], "value": 147.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 51.0, 53.0, 53.0, 52.0, 52.0, 52.0, 52.0, 53.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 39.0, 39.0, 39.0, 39.0, 38.0, 38.0, 39.0, 38.0], "value": 38.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 79.0, 75.0, 81.0, 77.0, 75.0, 77.0, 73.0, 76.0], "value": 76.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 127.0, 125.0, 117.0, 127.0, 115.0, 120.0, 125.0, 127.0, 170.0], "value": 128.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 177.0, 175.0, 168.0, 167.0, 170.0, 166.0, 169.0, 168.0, 168.0], "value": 170.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 63.0, 63.0, 63.0, 63.0, 65.0, 64.0, 62.0], "value": 64.1, "unit": "score"}]}]} 11:36:04 INFO - Return code: 0 11:36:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:36:04 INFO - # TBPL SUCCESS # 11:36:04 INFO - Running post-action listener: _package_coverage_data 11:36:04 INFO - Running post-action listener: _resource_record_post_action 11:36:04 INFO - [mozharness: 2017-09-02 18:36:04.336081Z] Finished run-tests step (success) 11:36:04 INFO - Running post-run listener: _resource_record_post_run 11:36:04 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 20480; Write bytes: 391311360; Read time: 404; Write time: 291316 11:36:04 INFO - TinderboxPrint: CPU usage
13.1% 11:36:04 INFO - TinderboxPrint: I/O read bytes / time
20,480 / 404 11:36:04 INFO - TinderboxPrint: I/O write bytes / time
391,311,360 / 291,316 11:36:04 INFO - TinderboxPrint: CPU idle
8,239.6 (86.8%) 11:36:04 INFO - TinderboxPrint: CPU user
1,194.0 (12.6%) 11:36:04 INFO - TinderboxPrint: Swap in / out
0 / 0 11:36:04 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 12288; Write bytes: 106258432; Read time: 160; Write time: 119712 11:36: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:36:04 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 8192; Write bytes: 285052928; Read time: 244; Write time: 171604 11:36:04 INFO - Running post-run listener: _upload_blobber_files 11:36:04 INFO - Blob upload gear active. 11:36:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:36:04 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:36:04 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:36:04 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:36:05 INFO - (blobuploader) - INFO - Open directory for files ... 11:36:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:36:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:36:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:36:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:36:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:36:12 INFO - (blobuploader) - INFO - Done attempting. 11:36:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:36:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:36:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:36:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:36:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:36:13 INFO - (blobuploader) - INFO - Done attempting. 11:36:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:36:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:36:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:36:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:36:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:36:14 INFO - (blobuploader) - INFO - Done attempting. 11:36:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:36:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:36:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:36:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:36:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:36:15 INFO - (blobuploader) - INFO - Done attempting. 11:36:15 INFO - (blobuploader) - INFO - Iteration through files over. 11:36:15 INFO - Return code: 0 11:36:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:36:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:36:15 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b62faa5df7da981b655b34f051e3fc515f859f102d5da642dfb82074c894366fa68d611c736ccfc5d132e85775ba1b143ccce4f76e7818f35d0f3476e0b5e7a3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b18a45e031bcc0c54c8fe6d86bff1150114349fc934f5190b1425ce4b0b49a802f056bdce590c8471873190fe6379cd9f25d051be0a4acd6c35fabcdaf6034cc", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e406971687ed13a6e29cb57013d9d7f9e51786ddea489afbcef5a9ead722bba94041e1af09df958bbd63077d980512d14c4ece6142b33c7bb87ee31874ddeea6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43f35e6a50fd4ba73a944e6855695cfe17e7665ebfd66743477f2277ecbd557df0a4280b3b80ca6bebf8dcc2f7e5d9d4892e70df3324a408c793ab62732e2981"} 11:36:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:36:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:36:15 INFO - Contents: 11:36:15 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b62faa5df7da981b655b34f051e3fc515f859f102d5da642dfb82074c894366fa68d611c736ccfc5d132e85775ba1b143ccce4f76e7818f35d0f3476e0b5e7a3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b18a45e031bcc0c54c8fe6d86bff1150114349fc934f5190b1425ce4b0b49a802f056bdce590c8471873190fe6379cd9f25d051be0a4acd6c35fabcdaf6034cc", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e406971687ed13a6e29cb57013d9d7f9e51786ddea489afbcef5a9ead722bba94041e1af09df958bbd63077d980512d14c4ece6142b33c7bb87ee31874ddeea6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43f35e6a50fd4ba73a944e6855695cfe17e7665ebfd66743477f2277ecbd557df0a4280b3b80ca6bebf8dcc2f7e5d9d4892e70df3324a408c793ab62732e2981"} 11:36:15 INFO - Running post-run listener: copy_logs_to_upload_dir 11:36:15 INFO - Copying logs to upload dir... 11:36:15 INFO - mkdir: /builds/slave/test/build/upload/logs 11:36:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.539280 ========= master_lag: -0.33 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-09-02 11:36:14.883821) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 11:36:14.886915) ========= 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=af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267 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/b62faa5df7da981b655b34f051e3fc515f859f102d5da642dfb82074c894366fa68d611c736ccfc5d132e85775ba1b143ccce4f76e7818f35d0f3476e0b5e7a3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b18a45e031bcc0c54c8fe6d86bff1150114349fc934f5190b1425ce4b0b49a802f056bdce590c8471873190fe6379cd9f25d051be0a4acd6c35fabcdaf6034cc", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e406971687ed13a6e29cb57013d9d7f9e51786ddea489afbcef5a9ead722bba94041e1af09df958bbd63077d980512d14c4ece6142b33c7bb87ee31874ddeea6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43f35e6a50fd4ba73a944e6855695cfe17e7665ebfd66743477f2277ecbd557df0a4280b3b80ca6bebf8dcc2f7e5d9d4892e70df3324a408c793ab62732e2981"} build_url:https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011357 build_url: 'https://queue.taskcluster.net/v1/task/C-9NEaTqQja8SGXabZPikw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b62faa5df7da981b655b34f051e3fc515f859f102d5da642dfb82074c894366fa68d611c736ccfc5d132e85775ba1b143ccce4f76e7818f35d0f3476e0b5e7a3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b18a45e031bcc0c54c8fe6d86bff1150114349fc934f5190b1425ce4b0b49a802f056bdce590c8471873190fe6379cd9f25d051be0a4acd6c35fabcdaf6034cc", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e406971687ed13a6e29cb57013d9d7f9e51786ddea489afbcef5a9ead722bba94041e1af09df958bbd63077d980512d14c4ece6142b33c7bb87ee31874ddeea6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43f35e6a50fd4ba73a944e6855695cfe17e7665ebfd66743477f2277ecbd557df0a4280b3b80ca6bebf8dcc2f7e5d9d4892e70df3324a408c793ab62732e2981"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 11:36:14.922715) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 11:36:14.923050) ========= 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=af724c981e2a0f424dbabf99000001e7-1504356364.658205-506635267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004530 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 11:36:15.002399) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 11:36:15.005730) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 11:36:15.006035) ========= ========= Total master_lag: -0.15 =========