builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-044 starttime: 1503857243.65 results: success (0) revision: 4caca1d0ba0e35cbe57a88493ebf162aa2cb3144 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-27 11:07:23.647943) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-27 11:07:23.648338) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-27 11:07:23.648606) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-27 11:07:23.665484) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 11:07:23.665845) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-27 11:07:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.2M=0s 2017-08-27 11:07:23 (46.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127311 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 11:07:23.817774) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 11:07:23.818078) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018047 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 11:07:23.873406) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-27 11:07:23.875757) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4caca1d0ba0e35cbe57a88493ebf162aa2cb3144 --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 4caca1d0ba0e35cbe57a88493ebf162aa2cb3144 --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=88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-27 11:07:24,071 truncating revision to first 12 chars 2017-08-27 11:07:24,071 Setting DEBUG logging. 2017-08-27 11:07:24,071 attempt 1/10 2017-08-27 11:07:24,071 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4caca1d0ba0e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-27 11:07:25,151 unpacking tar archive at: mozilla-central-4caca1d0ba0e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.361526 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-27 11:07:25.261519) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-27 11:07:25.261799) ========= script_repo_revision: 4caca1d0ba0e35cbe57a88493ebf162aa2cb3144 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-27 11:07:25.262149) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-27 11:07:25.262409) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-27 11:07:25.280369) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-08-27 11:07:25.280689) ========= /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=88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:07:25 INFO - MultiFileLogger online at 20170827 11:07:25 in /builds/slave/test 11:07:25 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:07:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:07:25 INFO - {'append_to_log': False, 11:07:25 INFO - 'base_work_dir': '/builds/slave/test', 11:07:25 INFO - 'blob_upload_branch': 'Firefox', 11:07:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:07:25 INFO - 'branch': 'Firefox', 11:07:25 INFO - 'buildbot_json_path': 'buildprops.json', 11:07:25 INFO - 'code_coverage': False, 11:07:25 INFO - 'config_files': ('talos/linux_config.py',), 11:07:25 INFO - 'default_actions': ('clobber', 11:07:25 INFO - 'read-buildbot-config', 11:07:25 INFO - 'download-and-extract', 11:07:25 INFO - 'populate-webroot', 11:07:25 INFO - 'create-virtualenv', 11:07:25 INFO - 'install', 11:07:25 INFO - 'setup-mitmproxy', 11:07:25 INFO - 'run-tests'), 11:07:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:07:25 INFO - 'disable_ccov_upload': False, 11:07:25 INFO - 'download_minidump_stackwalk': True, 11:07:25 INFO - 'download_symbols': 'ondemand', 11:07:25 INFO - 'enable_stylo': False, 11:07:25 INFO - 'enable_webrender': False, 11:07:25 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:07:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:07:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:07:25 INFO - 'gecko_profile': False, 11:07:25 INFO - 'gecko_profile_interval': 0, 11:07:25 INFO - 'installer_path': 'installer.exe', 11:07:25 INFO - 'log_level': 'info', 11:07:25 INFO - 'log_name': 'talos', 11:07:25 INFO - 'log_to_console': True, 11:07:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:07:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:07:25 INFO - 'opt_config_files': (), 11:07:25 INFO - 'pip_index': False, 11:07:25 INFO - 'suite': 'dromaeojs-e10s', 11:07:25 INFO - 'system_bits': '32', 11:07:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:07:25 INFO - 'title': 'talos-linux64-ix-044', 11:07:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:07:25 INFO - 'use_talos_json': True, 11:07:25 INFO - 'verify': 'False', 11:07:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:07:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:07:25 INFO - 'work_dir': 'build'} 11:07:25 INFO - [mozharness: 2017-08-27 18:07:25.616044Z] Running clobber step. 11:07:25 INFO - Running pre-action listener: _resource_record_pre_action 11:07:25 INFO - Running main action method: clobber 11:07:25 INFO - rmtree: /builds/slave/test/build 11:07:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:07:26 INFO - Running post-action listener: _resource_record_post_action 11:07:26 INFO - [mozharness: 2017-08-27 18:07:26.636606Z] Finished clobber step (success) 11:07:26 INFO - [mozharness: 2017-08-27 18:07:26.636705Z] Running read-buildbot-config step. 11:07:26 INFO - Running pre-action listener: _resource_record_pre_action 11:07:26 INFO - Running main action method: read_buildbot_config 11:07:26 INFO - Using buildbot properties: 11:07:26 INFO - { 11:07:26 INFO - "project": "", 11:07:26 INFO - "product": "firefox", 11:07:26 INFO - "who": "ffxbld@noreply.mozilla.org", 11:07:26 INFO - "installer_path": "public/build/target.tar.bz2", 11:07:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 11:07:26 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:07:26 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 11:07:26 INFO - "stage_platform": "linux64-stylo", 11:07:26 INFO - "basedir": "/builds/slave/test", 11:07:26 INFO - "buildnumber": 58, 11:07:26 INFO - "platform": "ubuntu64_hw_stylo", 11:07:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 11:07:26 INFO - "slavebuilddir": "test", 11:07:26 INFO - "taskId": "Eb5iWNpcQziEqtBj5rEnZA", 11:07:26 INFO - "branch": "mozilla-central", 11:07:26 INFO - "script_repo_revision": "production", 11:07:26 INFO - "revision": "4caca1d0ba0e35cbe57a88493ebf162aa2cb3144", 11:07:26 INFO - "slavename": "talos-linux64-ix-044", 11:07:26 INFO - "repo_path": "mozilla-central" 11:07:26 INFO - } 11:07:26 INFO - Finding installer, test and symbols from parent task. 11:07:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Eb5iWNpcQziEqtBj5rEnZA'}, attempt #1 11:07:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Eb5iWNpcQziEqtBj5rEnZA'}, attempt #1 11:07:27 INFO - Task dependencies: J7HevFiySL2NFW_sLpKW8Q 11:07:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q'}, attempt #1 11:07:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.tar.bz2 11:07:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.test_packages.json 11:07:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.crashreporter-symbols.zip 11:07:28 INFO - Running post-action listener: _resource_record_post_action 11:07:28 INFO - [mozharness: 2017-08-27 18:07:28.032938Z] Finished read-buildbot-config step (success) 11:07:28 INFO - [mozharness: 2017-08-27 18:07:28.033155Z] Running download-and-extract step. 11:07:28 INFO - Running pre-action listener: _resource_record_pre_action 11:07:28 INFO - Running main action method: download_and_extract 11:07:28 INFO - mkdir: /builds/slave/test/build/tests 11:07:28 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:07:28 INFO - trying https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.test_packages.json 11:07:28 INFO - Downloading https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:07:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:07:28 INFO - Downloaded 989 bytes. 11:07:28 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:07:28 INFO - Using the following test package requirements: 11:07:28 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:07:28 INFO - u'common': [u'target.common.tests.zip'], 11:07:28 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:07:28 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:07:28 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:07:28 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:07:28 INFO - u'mozbase': [u'target.common.tests.zip'], 11:07:28 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:07:28 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:07:28 INFO - u'web-platform': [u'target.common.tests.zip', 11:07:28 INFO - u'target.web-platform.tests.tar.gz'], 11:07:28 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:07:28 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:07:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.common.tests.zip 11:07:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:07:28 INFO - Fetch https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.common.tests.zip into memory 11:07:29 INFO - Content-Length response header: 54977554 11:07:29 INFO - Bytes received: 54977554 11:07:40 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:07:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.common.tests.zip 11:07:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:07:40 INFO - Fetch https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.common.tests.zip into memory 11:07:41 INFO - Content-Length response header: 54977554 11:07:41 INFO - Bytes received: 54977554 11:07:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.talos.tests.zip 11:07:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:07:53 INFO - Fetch https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.talos.tests.zip into memory 11:07:54 INFO - Content-Length response header: 14496611 11:07:54 INFO - Bytes received: 14496611 11:07:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:54 INFO - trying https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.tar.bz2 11:07:54 INFO - Downloading https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:07:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:07:56 INFO - Downloaded 61209299 bytes. 11:07:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.tar.bz2 11:07:56 INFO - mkdir: /builds/slave/test/properties 11:07:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:07:56 INFO - Writing to file /builds/slave/test/properties/build_url 11:07:56 INFO - Contents: 11:07:56 INFO - build_url:https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.tar.bz2 11:07:56 INFO - Running post-action listener: _resource_record_post_action 11:07:56 INFO - Running post-action listener: find_tests_for_verification 11:07:56 INFO - Running post-action listener: set_extra_try_arguments 11:07:56 INFO - [mozharness: 2017-08-27 18:07:56.034748Z] Finished download-and-extract step (success) 11:07:56 INFO - [mozharness: 2017-08-27 18:07:56.034847Z] Running populate-webroot step. 11:07:56 INFO - Running pre-action listener: _resource_record_pre_action 11:07:56 INFO - Running main action method: populate_webroot 11:07:56 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:07:56 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 11:07:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:07:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:07:56 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 11:07:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:07:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:07:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:07:56 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:07:56 INFO - 'talos_options': ['--stylo'], 11:07:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:07:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:07:56 INFO - 'tests': ['damp', 'tps']}, 11:07:56 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:07:56 INFO - 'talos_options': ['--stylo'], 11:07:56 INFO - 'tests': ['damp', 'tps']}, 11:07:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:07:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:07:56 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 11:07:56 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:07:56 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:07:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:07:56 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:07:56 INFO - 'talos_options': ['--stylo'], 11:07:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:07:56 INFO - 'other-e10s': {'tests': ['a11yr', 11:07:56 INFO - 'ts_paint', 11:07:56 INFO - 'tpaint', 11:07:56 INFO - 'sessionrestore', 11:07:56 INFO - 'sessionrestore_many_windows', 11:07:56 INFO - 'sessionrestore_no_auto_restore', 11:07:56 INFO - 'tabpaint']}, 11:07:56 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 11:07:56 INFO - 'tests': ['a11yr', 11:07:56 INFO - 'ts_paint', 11:07:56 INFO - 'tpaint', 11:07:56 INFO - 'sessionrestore', 11:07:56 INFO - 'sessionrestore_many_windows', 11:07:56 INFO - 'sessionrestore_no_auto_restore', 11:07:56 INFO - 'tabpaint']}, 11:07:56 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:07:56 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:07:56 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 11:07:56 INFO - 'tests': ['bloom_basic_singleton']}, 11:07:56 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 11:07:56 INFO - 'tests': ['bloom_basic']}, 11:07:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:07:56 INFO - 'tsvgr_opacity', 11:07:56 INFO - 'tart', 11:07:56 INFO - 'tscrollx', 11:07:56 INFO - 'tsvg_static']}, 11:07:56 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 11:07:56 INFO - 'tests': ['tsvgx', 11:07:56 INFO - 'tsvgr_opacity', 11:07:56 INFO - 'tart', 11:07:56 INFO - 'tscrollx', 11:07:56 INFO - 'tsvg_static']}, 11:07:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:07:56 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:07:56 INFO - 'talos_options': ['--stylo'], 11:07:56 INFO - 'tests': ['tp5o']}, 11:07:56 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:07:56 INFO - 'talos_options': ['--mitmproxy', 11:07:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:07:56 INFO - '--firstNonBlankPaint'], 11:07:56 INFO - 'tests': ['tp6_google', 11:07:56 INFO - 'tp6_youtube', 11:07:56 INFO - 'tp6_amazon', 11:07:56 INFO - 'tp6_facebook']}, 11:07:56 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:07:56 INFO - 'talos_options': ['--stylo', 11:07:56 INFO - '--mitmproxy', 11:07:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:07:56 INFO - '--firstNonBlankPaint'], 11:07:56 INFO - 'tests': ['tp6_google', 11:07:56 INFO - 'tp6_youtube', 11:07:56 INFO - 'tp6_amazon', 11:07:56 INFO - 'tp6_facebook']}, 11:07:56 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:07:56 INFO - 'talos_options': ['--stylo', 11:07:56 INFO - '--stylo-threads=1', 11:07:56 INFO - '--mitmproxy', 11:07:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:07:56 INFO - '--firstNonBlankPaint'], 11:07:56 INFO - 'tests': ['tp6_google', 11:07:56 INFO - 'tp6_youtube', 11:07:56 INFO - 'tp6_amazon', 11:07:56 INFO - 'tp6_facebook']}, 11:07:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:07:56 INFO - 'talos_options': ['--xperf_path', 11:07:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:07:56 INFO - 'tests': ['tp5n']}, 11:07:56 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:07:56 INFO - 'talos_options': ['--stylo', 11:07:56 INFO - '--xperf_path', 11:07:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:07:56 INFO - 'tests': ['tp5n']}}} 11:07:56 INFO - Running post-action listener: _resource_record_post_action 11:07:56 INFO - [mozharness: 2017-08-27 18:07:56.049236Z] Finished populate-webroot step (success) 11:07:56 INFO - [mozharness: 2017-08-27 18:07:56.049327Z] Running create-virtualenv step. 11:07:56 INFO - Running pre-action listener: _resource_record_pre_action 11:07:56 INFO - Running main action method: create_virtualenv 11:07:56 INFO - Creating virtualenv /builds/slave/test/build/venv 11:07:56 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:07:56 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:07:56 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:07:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:07:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:07:56 INFO - Installing distribute.............................................................................................................................................................................................done. 11:07:56 INFO - Installing pip...............done. 11:07:56 INFO - Return code: 0 11:07:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:07:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:07:57 INFO - Reading from file tmpfile_stdout 11:07:57 INFO - Output received: 11:07:57 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:07:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:07:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11de130>, '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 0x11df030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11df4c0>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', '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:07:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:07:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:07:57 INFO - Using env: {'DISPLAY': ':0', 11:07:57 INFO - 'HOME': '/home/cltbld', 11:07:57 INFO - 'LANG': 'en_US.UTF-8', 11:07:57 INFO - 'LANGUAGE': 'en_US:en', 11:07:57 INFO - 'LOGNAME': 'cltbld', 11:07:57 INFO - 'MAIL': '/var/mail/cltbld', 11:07:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:57 INFO - 'MOZ_NO_REMOTE': '1', 11:07:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:07:57 INFO - 'NO_EM_RESTART': '1', 11:07:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:07:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:57 INFO - 'PWD': '/builds/slave/test', 11:07:57 INFO - 'SHELL': '/bin/bash', 11:07:57 INFO - 'SHLVL': '1', 11:07:57 INFO - 'TERM': 'linux', 11:07:57 INFO - 'TMOUT': '86400', 11:07:57 INFO - 'USER': 'cltbld', 11:07:57 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', 11:07:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:57 INFO - '_': '/tools/buildbot/bin/python'} 11:07:58 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:07:58 INFO - Downloading/unpacking pip>=1.5 11:07:58 INFO - Running setup.py egg_info for package pip 11:07:58 INFO - warning: no files found matching 'pip/cacert.pem' 11:07:58 INFO - warning: no files found matching '*.html' under directory 'docs' 11:07:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:07:58 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:07:58 INFO - Installing collected packages: pip 11:07:58 INFO - Found existing installation: pip 1.1 11:07:58 INFO - Uninstalling pip: 11:07:58 INFO - Successfully uninstalled pip 11:07:58 INFO - Running setup.py install for pip 11:07:58 INFO - warning: no files found matching 'pip/cacert.pem' 11:07:58 INFO - warning: no files found matching '*.html' under directory 'docs' 11:07:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:07:58 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:07:58 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:07:58 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:07:58 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:07:58 INFO - Successfully installed pip 11:07:58 INFO - Cleaning up... 11:07:58 INFO - Return code: 0 11:07:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:07: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:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07: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:07:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07: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', '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 0xe721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11de130>, '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 0x11df030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11df4c0>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', '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:07: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:07: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 psutil>=3.1.1 11:07:58 INFO - Using env: {'DISPLAY': ':0', 11:07:58 INFO - 'HOME': '/home/cltbld', 11:07:58 INFO - 'LANG': 'en_US.UTF-8', 11:07:58 INFO - 'LANGUAGE': 'en_US:en', 11:07:58 INFO - 'LOGNAME': 'cltbld', 11:07:58 INFO - 'MAIL': '/var/mail/cltbld', 11:07:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:58 INFO - 'MOZ_NO_REMOTE': '1', 11:07:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:07:58 INFO - 'NO_EM_RESTART': '1', 11:07:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:07:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:58 INFO - 'PWD': '/builds/slave/test', 11:07:58 INFO - 'SHELL': '/bin/bash', 11:07:58 INFO - 'SHLVL': '1', 11:07:58 INFO - 'TERM': 'linux', 11:07:58 INFO - 'TMOUT': '86400', 11:07:58 INFO - 'USER': 'cltbld', 11:07:58 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', 11:07:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:58 INFO - '_': '/tools/buildbot/bin/python'} 11:07:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:58 INFO - Downloading/unpacking psutil>=3.1.1 11:07: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:07: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:07: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:07: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:07:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:07:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:07:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:07:59 INFO - Installing collected packages: psutil 11:07:59 INFO - Running setup.py install for psutil 11:07:59 INFO - building 'psutil._psutil_linux' extension 11:07:59 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:07:59 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:07:59 INFO - building 'psutil._psutil_posix' extension 11:07:59 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:08:00 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:08:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:08:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:08:00 INFO - Successfully installed psutil 11:08:00 INFO - Cleaning up... 11:08:00 INFO - Return code: 0 11:08:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:08: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:08:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08: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:08:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11de130>, '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 0x11df030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11df4c0>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', '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:08: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:08: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 mozsystemmonitor==0.3 11:08:00 INFO - Using env: {'DISPLAY': ':0', 11:08:00 INFO - 'HOME': '/home/cltbld', 11:08:00 INFO - 'LANG': 'en_US.UTF-8', 11:08:00 INFO - 'LANGUAGE': 'en_US:en', 11:08:00 INFO - 'LOGNAME': 'cltbld', 11:08:00 INFO - 'MAIL': '/var/mail/cltbld', 11:08:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:00 INFO - 'MOZ_NO_REMOTE': '1', 11:08:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:08:00 INFO - 'NO_EM_RESTART': '1', 11:08:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:08:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:00 INFO - 'PWD': '/builds/slave/test', 11:08:00 INFO - 'SHELL': '/bin/bash', 11:08:00 INFO - 'SHLVL': '1', 11:08:00 INFO - 'TERM': 'linux', 11:08:00 INFO - 'TMOUT': '86400', 11:08:00 INFO - 'USER': 'cltbld', 11:08:00 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', 11:08:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:00 INFO - '_': '/tools/buildbot/bin/python'} 11:08:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:08: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:08: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:08: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:08: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:08:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:08:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:08:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:08:01 INFO - Installing collected packages: mozsystemmonitor 11:08:01 INFO - Running setup.py install for mozsystemmonitor 11:08:01 INFO - Successfully installed mozsystemmonitor 11:08:01 INFO - Cleaning up... 11:08:01 INFO - Return code: 0 11:08:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:08: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:08:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08: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:08:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08: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', '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 0xe721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11de130>, '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 0x11df030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11df4c0>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', '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:08: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:08: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 jsonschema==2.5.1 11:08:01 INFO - Using env: {'DISPLAY': ':0', 11:08:01 INFO - 'HOME': '/home/cltbld', 11:08:01 INFO - 'LANG': 'en_US.UTF-8', 11:08:01 INFO - 'LANGUAGE': 'en_US:en', 11:08:01 INFO - 'LOGNAME': 'cltbld', 11:08:01 INFO - 'MAIL': '/var/mail/cltbld', 11:08:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:01 INFO - 'MOZ_NO_REMOTE': '1', 11:08:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:08:01 INFO - 'NO_EM_RESTART': '1', 11:08:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:08:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:01 INFO - 'PWD': '/builds/slave/test', 11:08:01 INFO - 'SHELL': '/bin/bash', 11:08:01 INFO - 'SHLVL': '1', 11:08:01 INFO - 'TERM': 'linux', 11:08:01 INFO - 'TMOUT': '86400', 11:08:01 INFO - 'USER': 'cltbld', 11:08:01 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', 11:08:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:01 INFO - '_': '/tools/buildbot/bin/python'} 11:08:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:01 INFO - Downloading/unpacking jsonschema==2.5.1 11:08: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:08: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:08: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:08: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:08:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:08:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:08: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:08: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:08: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:08: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:08:03 INFO - Downloading functools32-3.2.3-2.tar.gz 11:08:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:08:03 INFO - warning: no files found matching '*.txt' 11:08:03 INFO - no previously-included directories found matching 'build' 11:08:03 INFO - no previously-included directories found matching 'dist' 11:08:03 INFO - no previously-included directories found matching '.git*' 11:08:03 INFO - Installing collected packages: jsonschema, functools32 11:08:03 INFO - Running setup.py install for functools32 11:08:03 INFO - warning: no files found matching '*.txt' 11:08:03 INFO - no previously-included directories found matching 'build' 11:08:03 INFO - no previously-included directories found matching 'dist' 11:08:03 INFO - no previously-included directories found matching '.git*' 11:08:03 INFO - Successfully installed jsonschema functools32 11:08:03 INFO - Cleaning up... 11:08:03 INFO - Return code: 0 11:08:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:03 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 0xe721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11de130>, '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 0x11df030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11df4c0>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', '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:08:03 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:08:03 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:08:03 INFO - Using env: {'DISPLAY': ':0', 11:08:03 INFO - 'HOME': '/home/cltbld', 11:08:03 INFO - 'LANG': 'en_US.UTF-8', 11:08:03 INFO - 'LANGUAGE': 'en_US:en', 11:08:03 INFO - 'LOGNAME': 'cltbld', 11:08:03 INFO - 'MAIL': '/var/mail/cltbld', 11:08:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:03 INFO - 'MOZ_NO_REMOTE': '1', 11:08:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:08:03 INFO - 'NO_EM_RESTART': '1', 11:08:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:08:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:03 INFO - 'PWD': '/builds/slave/test', 11:08:03 INFO - 'SHELL': '/bin/bash', 11:08:03 INFO - 'SHLVL': '1', 11:08:03 INFO - 'TERM': 'linux', 11:08:03 INFO - 'TMOUT': '86400', 11:08:03 INFO - 'USER': 'cltbld', 11:08:03 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', 11:08:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:03 INFO - '_': '/tools/buildbot/bin/python'} 11:08:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:08:03 INFO - Cleaning up... 11:08:03 INFO - Return code: 0 11:08:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:03 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 0xe721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11de130>, '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 0x11df030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11df4c0>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', '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:08:03 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:08:03 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:08:03 INFO - Using env: {'DISPLAY': ':0', 11:08:03 INFO - 'HOME': '/home/cltbld', 11:08:03 INFO - 'LANG': 'en_US.UTF-8', 11:08:03 INFO - 'LANGUAGE': 'en_US:en', 11:08:03 INFO - 'LOGNAME': 'cltbld', 11:08:03 INFO - 'MAIL': '/var/mail/cltbld', 11:08:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:03 INFO - 'MOZ_NO_REMOTE': '1', 11:08:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:08:03 INFO - 'NO_EM_RESTART': '1', 11:08:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:08:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:03 INFO - 'PWD': '/builds/slave/test', 11:08:03 INFO - 'SHELL': '/bin/bash', 11:08:03 INFO - 'SHLVL': '1', 11:08:03 INFO - 'TERM': 'linux', 11:08:03 INFO - 'TMOUT': '86400', 11:08:03 INFO - 'USER': 'cltbld', 11:08:03 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', 11:08:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:03 INFO - '_': '/tools/buildbot/bin/python'} 11:08:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:03 INFO - Downloading/unpacking blobuploader==1.2.4 11:08: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:08: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:08: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:08: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:08:04 INFO - Downloading blobuploader-1.2.4.tar.gz 11:08:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:08:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:08:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:08:05 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:08:05 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:08:05 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:08:05 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:08:05 INFO - Downloading docopt-0.6.1.tar.gz 11:08:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:08:05 INFO - Installing collected packages: blobuploader, requests, docopt 11:08:05 INFO - Running setup.py install for blobuploader 11:08:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:08:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:08:05 INFO - Running setup.py install for requests 11:08:05 INFO - Running setup.py install for docopt 11:08:06 INFO - Successfully installed blobuploader requests docopt 11:08:06 INFO - Cleaning up... 11:08:06 INFO - Return code: 0 11:08:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:08:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:06 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 0xe721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11de130>, '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 0x11df030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11df4c0>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', '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:08:06 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:08:06 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:08:06 INFO - Using env: {'DISPLAY': ':0', 11:08:06 INFO - 'HOME': '/home/cltbld', 11:08:06 INFO - 'LANG': 'en_US.UTF-8', 11:08:06 INFO - 'LANGUAGE': 'en_US:en', 11:08:06 INFO - 'LOGNAME': 'cltbld', 11:08:06 INFO - 'MAIL': '/var/mail/cltbld', 11:08:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:06 INFO - 'MOZ_NO_REMOTE': '1', 11:08:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:08:06 INFO - 'NO_EM_RESTART': '1', 11:08:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:08:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:06 INFO - 'PWD': '/builds/slave/test', 11:08:06 INFO - 'SHELL': '/bin/bash', 11:08:06 INFO - 'SHLVL': '1', 11:08:06 INFO - 'TERM': 'linux', 11:08:06 INFO - 'TMOUT': '86400', 11:08:06 INFO - 'USER': 'cltbld', 11:08:06 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', 11:08:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:06 INFO - '_': '/tools/buildbot/bin/python'} 11:08:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:08:06 INFO - Running setup.py (path:/tmp/pip-HIlRb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:08:06 INFO - Running setup.py (path:/tmp/pip-sLmmUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:08:06 INFO - Running setup.py (path:/tmp/pip-AEikD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:08:06 INFO - Running setup.py (path:/tmp/pip-6dzNcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:08:06 INFO - Running setup.py (path:/tmp/pip-gAuo2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:08:06 INFO - Running setup.py (path:/tmp/pip-gKODa3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:08:06 INFO - Running setup.py (path:/tmp/pip-qfZ9UV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:08:06 INFO - Running setup.py (path:/tmp/pip-eIlUzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:08:06 INFO - Running setup.py (path:/tmp/pip-HF0E9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:08:06 INFO - Running setup.py (path:/tmp/pip-ZscVCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:08:06 INFO - Running setup.py (path:/tmp/pip-IVe5dr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:08:07 INFO - Running setup.py (path:/tmp/pip-xbaG0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:08:07 INFO - Running setup.py (path:/tmp/pip-aUYNfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:08:07 INFO - Running setup.py (path:/tmp/pip-8hLq_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:08:07 INFO - Running setup.py (path:/tmp/pip-RkBMy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:08:07 INFO - Running setup.py (path:/tmp/pip-r8nGDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:08:07 INFO - Running setup.py (path:/tmp/pip-zIvBeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:08:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:08:07 INFO - Running setup.py install for manifestparser 11:08:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:08:07 INFO - Running setup.py install for mozcrash 11:08:07 INFO - Running setup.py install for mozdebug 11:08:07 INFO - Running setup.py install for mozdevice 11:08:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:08:07 INFO - Running setup.py install for mozfile 11:08:07 INFO - Running setup.py install for mozhttpd 11:08:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:08:08 INFO - Running setup.py install for mozinfo 11:08:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:08:08 INFO - Running setup.py install for mozInstall 11:08:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:08:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:08:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:08:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:08:08 INFO - Running setup.py install for mozleak 11:08:08 INFO - Running setup.py install for mozlog 11:08:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:08:08 INFO - Running setup.py install for moznetwork 11:08:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:08:08 INFO - Running setup.py install for mozprocess 11:08:08 INFO - Running setup.py install for mozprofile 11:08:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:08:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:08:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:08:08 INFO - Running setup.py install for mozrunner 11:08:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:08:08 INFO - Running setup.py install for mozscreenshot 11:08:09 INFO - Running setup.py install for moztest 11:08:09 INFO - Running setup.py install for mozversion 11:08:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:08:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:08:09 INFO - Cleaning up... 11:08:09 INFO - Return code: 0 11:08:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:08:09 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:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:09 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:08:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:09 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 0xe721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11de130>, '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 0x11df030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11df4c0>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', '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:08:09 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:08:09 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:08:09 INFO - Using env: {'DISPLAY': ':0', 11:08:09 INFO - 'HOME': '/home/cltbld', 11:08:09 INFO - 'LANG': 'en_US.UTF-8', 11:08:09 INFO - 'LANGUAGE': 'en_US:en', 11:08:09 INFO - 'LOGNAME': 'cltbld', 11:08:09 INFO - 'MAIL': '/var/mail/cltbld', 11:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:09 INFO - 'MOZ_NO_REMOTE': '1', 11:08:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:08:09 INFO - 'NO_EM_RESTART': '1', 11:08:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:08:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:09 INFO - 'PWD': '/builds/slave/test', 11:08:09 INFO - 'SHELL': '/bin/bash', 11:08:09 INFO - 'SHLVL': '1', 11:08:09 INFO - 'TERM': 'linux', 11:08:09 INFO - 'TMOUT': '86400', 11:08:09 INFO - 'USER': 'cltbld', 11:08:09 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', 11:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:09 INFO - '_': '/tools/buildbot/bin/python'} 11:08:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:08:09 INFO - Running setup.py (path:/tmp/pip-Dxae8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:08:09 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:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:08:09 INFO - Running setup.py (path:/tmp/pip-zlbjxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:08:09 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:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:08:09 INFO - Running setup.py (path:/tmp/pip-3IyzS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:08:09 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:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:08:09 INFO - Running setup.py (path:/tmp/pip-MM9j_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:08:09 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:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:08:09 INFO - Running setup.py (path:/tmp/pip-1IkTVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:08:09 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:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:08:09 INFO - Running setup.py (path:/tmp/pip-nT3QRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:08:09 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:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:08:09 INFO - Running setup.py (path:/tmp/pip-E3W3z1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:08:09 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:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:08:09 INFO - Running setup.py (path:/tmp/pip-3dafog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:08:10 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:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:08:10 INFO - Running setup.py (path:/tmp/pip-cEL3i3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:08:10 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:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:08:10 INFO - Running setup.py (path:/tmp/pip-4qULFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:08:10 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:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:08:10 INFO - Running setup.py (path:/tmp/pip-MMoCcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:08:10 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:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:08:10 INFO - Running setup.py (path:/tmp/pip-NHO14l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:08:10 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:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:08:10 INFO - Running setup.py (path:/tmp/pip-nYKz_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:08:10 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:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:08:10 INFO - Running setup.py (path:/tmp/pip-dNksLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:08:10 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:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:08:10 INFO - Running setup.py (path:/tmp/pip-oCBTcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:08:10 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:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:08:10 INFO - Running setup.py (path:/tmp/pip-DCLHOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:08:10 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:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:08:10 INFO - Running setup.py (path:/tmp/pip-hCUMil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:08:10 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:08:10 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:08:10 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:08:10 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:08:10 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:08:10 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:08:10 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:08:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:11 INFO - Downloading blessings-1.6.tar.gz 11:08:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:08:11 INFO - Installing collected packages: blessings 11:08:11 INFO - Running setup.py install for blessings 11:08:11 INFO - Successfully installed blessings 11:08:11 INFO - Cleaning up... 11:08:12 INFO - Return code: 0 11:08:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:08:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:08:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:08:12 INFO - Reading from file tmpfile_stdout 11:08:12 INFO - Current package versions: 11:08:12 INFO - argparse == 1.2.1 11:08:12 INFO - blessings == 1.6 11:08:12 INFO - blobuploader == 1.2.4 11:08:12 INFO - docopt == 0.6.1 11:08:12 INFO - functools32 == 3.2.3-2 11:08:12 INFO - jsonschema == 2.5.1 11:08:12 INFO - manifestparser == 1.1 11:08:12 INFO - mozInstall == 1.13 11:08:12 INFO - mozcrash == 1.0 11:08:12 INFO - mozdebug == 0.1 11:08:12 INFO - mozdevice == 0.51 11:08:12 INFO - mozfile == 1.2 11:08:12 INFO - mozhttpd == 0.7 11:08:12 INFO - mozinfo == 0.10 11:08:12 INFO - mozleak == 0.1 11:08:12 INFO - mozlog == 3.5 11:08:12 INFO - moznetwork == 0.27 11:08:12 INFO - mozprocess == 0.25 11:08:12 INFO - mozprofile == 0.28 11:08:12 INFO - mozrunner == 6.13 11:08:12 INFO - mozscreenshot == 0.1 11:08:12 INFO - mozsystemmonitor == 0.3 11:08:12 INFO - moztest == 0.8 11:08:12 INFO - mozversion == 1.4 11:08:12 INFO - psutil == 3.1.1 11:08:12 INFO - requests == 1.2.3 11:08:12 INFO - wsgiref == 0.1.2 11:08:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:08:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:12 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 0xe721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11de130>, '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 0x11df030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11df4c0>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', '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:08:12 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:08:12 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:08:12 INFO - Using env: {'DISPLAY': ':0', 11:08:12 INFO - 'HOME': '/home/cltbld', 11:08:12 INFO - 'LANG': 'en_US.UTF-8', 11:08:12 INFO - 'LANGUAGE': 'en_US:en', 11:08:12 INFO - 'LOGNAME': 'cltbld', 11:08:12 INFO - 'MAIL': '/var/mail/cltbld', 11:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:12 INFO - 'MOZ_NO_REMOTE': '1', 11:08:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:08:12 INFO - 'NO_EM_RESTART': '1', 11:08:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:08:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:12 INFO - 'PWD': '/builds/slave/test', 11:08:12 INFO - 'SHELL': '/bin/bash', 11:08:12 INFO - 'SHLVL': '1', 11:08:12 INFO - 'TERM': 'linux', 11:08:12 INFO - 'TMOUT': '86400', 11:08:12 INFO - 'USER': 'cltbld', 11:08:12 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', 11:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:12 INFO - '_': '/tools/buildbot/bin/python'} 11:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:12 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:08:12 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:08:12 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:08:12 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:08:12 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:08:12 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:08:12 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:08:12 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:08:12 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:08:12 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:08:12 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:08:12 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:08:12 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:08:12 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:08:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:08:13 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:08:13 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:08:13 INFO - Installing collected packages: simplejson 11:08:13 INFO - Running setup.py install for simplejson 11:08:13 INFO - building 'simplejson._speedups' extension 11:08:13 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:08:14 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:08:14 INFO - Successfully installed simplejson 11:08:14 INFO - Cleaning up... 11:08:14 INFO - Return code: 0 11:08:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:08:14 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:08:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:14 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:08:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:14 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 0xe721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11e0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11de130>, '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 0x11df030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11df4c0>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', '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:08:14 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:08:14 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:08:14 INFO - Using env: {'DISPLAY': ':0', 11:08:14 INFO - 'HOME': '/home/cltbld', 11:08:14 INFO - 'LANG': 'en_US.UTF-8', 11:08:14 INFO - 'LANGUAGE': 'en_US:en', 11:08:14 INFO - 'LOGNAME': 'cltbld', 11:08:14 INFO - 'MAIL': '/var/mail/cltbld', 11:08:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:14 INFO - 'MOZ_NO_REMOTE': '1', 11:08:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:08:14 INFO - 'NO_EM_RESTART': '1', 11:08:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:08:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:14 INFO - 'PWD': '/builds/slave/test', 11:08:14 INFO - 'SHELL': '/bin/bash', 11:08:14 INFO - 'SHLVL': '1', 11:08:14 INFO - 'TERM': 'linux', 11:08:14 INFO - 'TMOUT': '86400', 11:08:14 INFO - 'USER': 'cltbld', 11:08:14 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', 11:08:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:14 INFO - '_': '/tools/buildbot/bin/python'} 11:08:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:08:14 INFO - Cleaning up... 11:08:14 INFO - Return code: 0 11:08:14 INFO - Running post-action listener: _resource_record_post_action 11:08:14 INFO - Running post-action listener: _start_resource_monitoring 11:08:14 INFO - Starting resource monitoring. 11:08:14 INFO - [mozharness: 2017-08-27 18:08:14.921944Z] Finished create-virtualenv step (success) 11:08:14 INFO - [mozharness: 2017-08-27 18:08:14.922463Z] Running install step. 11:08:14 INFO - Running pre-action listener: _resource_record_pre_action 11:08:14 INFO - Running main action method: install 11:08:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:08:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:08:15 INFO - Reading from file tmpfile_stdout 11:08:15 INFO - Detecting whether we're running mozinstall >=1.0... 11:08:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:08:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:08:15 INFO - Reading from file tmpfile_stdout 11:08:15 INFO - Output received: 11:08:15 INFO - Usage: mozinstall [options] installer 11:08:15 INFO - Options: 11:08:15 INFO - -h, --help show this help message and exit 11:08:15 INFO - -d DEST, --destination=DEST 11:08:15 INFO - Directory to install application into. [default: 11:08:15 INFO - "/builds/slave/test"] 11:08:15 INFO - --app=APP Application being installed. [default: firefox] 11:08:15 INFO - mkdir: /builds/slave/test/build/application 11:08:15 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:08:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:08:32 INFO - Reading from file tmpfile_stdout 11:08:32 INFO - Output received: 11:08:32 INFO - /builds/slave/test/build/application/firefox/firefox 11:08:32 INFO - Running post-action listener: _resource_record_post_action 11:08:32 INFO - [mozharness: 2017-08-27 18:08:32.936224Z] Finished install step (success) 11:08:32 INFO - [mozharness: 2017-08-27 18:08:32.936373Z] Running setup-mitmproxy step. 11:08:32 INFO - Running pre-action listener: _resource_record_pre_action 11:08:32 INFO - Running main action method: setup_mitmproxy 11:08:32 INFO - Skipping: mitmproxy is not required 11:08:32 INFO - Running post-action listener: _resource_record_post_action 11:08:32 INFO - [mozharness: 2017-08-27 18:08:32.937266Z] Finished setup-mitmproxy step (success) 11:08:32 INFO - [mozharness: 2017-08-27 18:08:32.937487Z] Running run-tests step. 11:08:32 INFO - Running pre-action listener: _resource_record_pre_action 11:08:32 INFO - Running pre-action listener: _set_gcov_prefix 11:08:32 INFO - Running main action method: run_tests 11:08:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:08:32 INFO - Python 2.7.3 11:08:32 INFO - Return code: 0 11:08:32 INFO - grabbing minidump binary from tooltool 11:08:32 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:08:32 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 0x11de130>, '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 0x11df030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11df4c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:08:32 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:08:32 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:08:32 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:08:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:08:33 INFO - Return code: 0 11:08:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:08:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:08:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:08:33 INFO - ENV: RUST_BACKTRACE is now full 11:08:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:08:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:08:33 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/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--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:08:33 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/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-044 --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:08:33 INFO - Using env: {'DISPLAY': ':0', 11:08:33 INFO - 'HOME': '/home/cltbld', 11:08:33 INFO - 'LANG': 'en_US.UTF-8', 11:08:33 INFO - 'LANGUAGE': 'en_US:en', 11:08:33 INFO - 'LOGNAME': 'cltbld', 11:08:33 INFO - 'MAIL': '/var/mail/cltbld', 11:08:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:08:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:08:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:33 INFO - 'MOZ_NO_REMOTE': '1', 11:08:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:08:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:08:33 INFO - 'NO_EM_RESTART': '1', 11:08:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:08:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:33 INFO - 'PWD': '/builds/slave/test', 11:08:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:08:33 INFO - 'RUST_BACKTRACE': 'full', 11:08:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:08:33 INFO - 'SHELL': '/bin/bash', 11:08:33 INFO - 'SHLVL': '1', 11:08:33 INFO - 'STYLO_FORCE_ENABLED': '1', 11:08:33 INFO - 'TERM': 'linux', 11:08:33 INFO - 'TMOUT': '86400', 11:08:33 INFO - 'USER': 'cltbld', 11:08:33 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', 11:08:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:33 INFO - '_': '/tools/buildbot/bin/python'} 11:08:33 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/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--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:08:33 INFO - mozversion application_buildid: 20170827171556 11:08:33 INFO - mozversion application_changeset: 4caca1d0ba0e35cbe57a88493ebf162aa2cb3144 11:08:33 INFO - mozversion application_display_name: Nightly 11:08:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:08:33 INFO - mozversion application_name: Firefox 11:08:33 INFO - mozversion application_remotingname: firefox 11:08:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:08:33 INFO - mozversion application_vendor: Mozilla 11:08:33 INFO - mozversion application_version: 57.0a1 11:08:33 INFO - mozversion platform_buildid: 20170827171556 11:08:33 INFO - mozversion platform_changeset: 4caca1d0ba0e35cbe57a88493ebf162aa2cb3144 11:08:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:08:33 INFO - mozversion platform_version: 57.0a1 11:08:33 INFO - using testdate: 1503857313 11:08:33 INFO - actual date: 1503857313 11:08:33 INFO - starting webserver on 'localhost:34300' 11:08:33 INFO - SUITE-START | Running 2 tests 11:08:33 INFO - TEST-START | dromaeo_css 11:08:33 INFO - Initialising browser for dromaeo_css test... 11:08:33 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34300/getInfo.html -profile /tmp/tmp85gGwG/profile 11:08:33 INFO - TEST-INFO | started process 9273 (/builds/slave/test/build/application/firefox/firefox http://localhost:34300/getInfo.html) 11:08:38 INFO - TEST-INFO | 9273: exit 0 11:08:38 INFO - Browser initialized. 11:08:38 INFO - Running cycle 1/1 for dromaeo_css test... 11:08:38 INFO - Using env: {'DISPLAY': ':0', 11:08:38 INFO - 'HOME': '/home/cltbld', 11:08:38 INFO - 'JSGC_DISABLE_POISONING': '1', 11:08:38 INFO - 'LANG': 'en_US.UTF-8', 11:08:38 INFO - 'LANGUAGE': 'en_US:en', 11:08:38 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:08:38 INFO - 'LOGNAME': 'cltbld', 11:08:38 INFO - 'MAIL': '/var/mail/cltbld', 11:08:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:08:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:08:38 INFO - 'MOZ_CRASHREPORTER': '1', 11:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:38 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:08:38 INFO - 'MOZ_NO_REMOTE': '1', 11:08:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:08:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:08:38 INFO - 'NO_EM_RESTART': '1', 11:08:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:38 INFO - 'PWD': '/builds/slave/test', 11:08:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:08:38 INFO - 'RUST_BACKTRACE': 'full', 11:08:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:08:38 INFO - 'SHELL': '/bin/bash', 11:08:38 INFO - 'SHLVL': '1', 11:08:38 INFO - 'STYLO_FORCE_ENABLED': '1', 11:08:38 INFO - 'TERM': 'linux', 11:08:38 INFO - 'TMOUT': '86400', 11:08:38 INFO - 'USER': 'cltbld', 11:08:38 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', 11:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:38 INFO - '_': '/tools/buildbot/bin/python'} 11:08:38 INFO - TEST-INFO | started process 9460 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp85gGwG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:08:39 INFO - PID 9460 | 11:08:39 INFO - PID 9460 | (/builds/slave/test/build/application/firefox/firefox:9511): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:08:39 INFO - PID 9460 | 11:08:40 INFO - PID 9460 | 11:08:40 INFO - PID 9460 | (/builds/slave/test/build/application/firefox/firefox:9571): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:08:40 INFO - PID 9460 | 11:08:40 INFO - PID 9460 | __metrics Screen width/height:1600/1200 11:08:40 INFO - PID 9460 | colorDepth:24 11:08:40 INFO - PID 9460 | Browser inner width/height: 1024/768 11:08:40 INFO - PID 9460 | __metrics 11:08:50 INFO - PID 9460 | RSS: Main: 174088192 11:08:50 INFO - PID 9460 | 11:11:48 INFO - PID 9460 | Cycle 1(1): loaded http://localhost:34300/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34300/tests/dromaeo/cssquery-ext.html) 11:11:50 INFO - PID 9460 | RSS: Main: 179216384 11:11:50 INFO - PID 9460 | 11:14:51 INFO - PID 9460 | Cycle 1(1): loaded http://localhost:34300/tests/dromaeo/cssquery-ext.html (next: http://localhost:34300/tests/dromaeo/cssquery-jquery.html) 11:14:52 INFO - PID 9460 | RSS: Main: 174907392 11:14:52 INFO - PID 9460 | 11:17:54 INFO - PID 9460 | Cycle 1(1): loaded http://localhost:34300/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34300/tests/dromaeo/cssquery-mootools.html) 11:17:54 INFO - PID 9460 | RSS: Main: 178044928 11:17:54 INFO - PID 9460 | 11:20:56 INFO - PID 9460 | Cycle 1(1): loaded http://localhost:34300/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34300/tests/dromaeo/cssquery-prototype.html) 11:20:56 INFO - PID 9460 | RSS: Main: 174587904 11:20:56 INFO - PID 9460 | 11:24:00 INFO - PID 9460 | Cycle 1(1): loaded http://localhost:34300/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34300/tests/dromaeo/cssquery-yui.html) 11:24:00 INFO - PID 9460 | RSS: Main: 180408320 11:24:00 INFO - PID 9460 | 11:27:02 INFO - PID 9460 | Cycle 1(1): loaded http://localhost:34300/tests/dromaeo/cssquery-yui.html (next: http://localhost:34300/tests/dromaeo/cssquery-dojo.html) 11:27:02 INFO - PID 9460 | RSS: Main: 180383744 11:27:02 INFO - PID 9460 | 11:27:02 INFO - PID 9460 | __start_tp_report 11:27:02 INFO - PID 9460 | _x_x_mozilla_page_load 11:27:02 INFO - PID 9460 | _x_x_mozilla_page_load_details 11:27:02 INFO - PID 9460 | |i|pagename|runs| 11:27:02 INFO - PID 9460 | |0;dojo.html;3268.444364458042;3326.3846188455136;3349.6315405305395;3365.9831700841505;3370.713489353404;6297.748090076398;6311.337309582485;6340.397662222087;6386.968065159679;6507.381798729125;6383.202099737533;6451.645159516224;6470.126532918055;6492.999999999988;6540.836479088025;5840.824775256728;5881.794137205188;5911.142884281777;5971.193888825018;5988.101784732282;5719.799807006755;5822.097574875901;5843.970780146094;5934.851628709295;5954.612950158225;5833.854153646151;5868.149118377854;5934.228550288478;5941.465268125889;5955.523558115362;5862.14998825168;5882.794102206445;5935.970320148372;5961.135635332896;5975.133605627159;5721.885562288772;5809.651420914759;5813.912791308134;5883.352831188566;5915.5859089863725;6317.96841015792;6348.333424990402;6405.135306733578;6419.037144428373;6429.421352078289;6764.966175169093;6785.084013658142;6802.523823332369;6843.726250949956;6867.107276054131;6822.044913712084;6889;6890.655467226668;6906.689198986058;6976.058232138647;6311.305756366888;6397.584157029778;6407.807765767081;6409.807705768834;6418.812519683874;6850.212225594021;6981.89527157093;6988.615626140611;7061.576305421589;7084.822879427952;6641.1366522352755;6643.236027856762;6676.933230667728;6683.465322774166;6690.832729181712;6769.815282325573;6784.59292442455;6808.659567021629;6876.415504682158;6878.243393226741;6865.244823069558;6887.793366199022;6889.207741109736;6892.448604111659;6906;6807.421369183576;6823.112995310677;6845.486588505882;6850.246472887979;6940.271271516519;5364.383095943938;5372.586920427245;5401.108817045222;5407.080796264644;5437.13005919051;4745.501722319176;4768.47546769855;4794.712317260975;4802.231642937183;4804.975975120172;5989.131575921554;5995.430434108753;6080.726367313481;6086.513078953673;6087.604305720114;6828.760993365208;6844.5208835382045;6845.418139458202;6928.341807528277;6945.236024037352;6059.030555111249;6075.149479072933;6075.817725468243;6081.483073938764;6095.299040610298;5877.823665289962;5937.940620593825;5938.584299099108;5950.107483877367;5960.254968128984;6599.1091202687985;6614.900776488356;6705.463562914858;6719.328067193339;6720.395164435225;6719.4624430044505;6854.554453960476;6871.896921546081;6872.587644741331;6947.895781563382;6620.271770105381;6628.668566571556;6641.667916604054;6644.767433139817;6667.299933507106;6643.800685979429;6817.625030623363;6830.043793868763;6836.384725374542;6839.760608378683;6852.862942741018;6863.073485079451;6879.621620810904;6949.930500694928;6955.721771129098;6511.674416279262;6550.803475895731;6592;6606.273309935999;6678.432333251728;6596.901046484402;6673.099131617364;6685.197776266879;6690.330966903271;6694.928811390154;6614;6642.468602511691;6658.866822663616;6667.233268174222;6670;6681.164854393201;6684.4652427804685;6697.464202863662;6766.898496522655;6783.89824152648;6363.61818290904;6440.323766004502;6459.838504037249;6473.643949582818;6549.967250163719 11:27:02 INFO - PID 9460 | |1;ext.html;827.7558120354495;858;887.7691800131963;895;1138.0269869261786;30866.691333086663;31566;39841.0039749006;40806.38790418146;41328.17343653125;41451.17097658045;41754.58245417545;41789.79105104478;41822.16355672893;41826.58173418265;7780.961095194516;7803.1026431960345;7978.521288722666;7981.161977992285;8003.4797738146835;7784.727534536292;7889.763307100768;7896.565688887109;7903.36773058154;7926.564038977854;7909.881351779728;7945.761627151194;7961.044674639023;7963.163867793885;7964.920350796505;7891.881621775678;7896.289333959942;7907.2488113629115;7935.444518883681;7944.563049032301;7824;7847.450678452511;7873;7885.881711774328;7885.881711774328;36676.816615917014;36879.63120368816;36967.81516092429;36976.81511592425;37021.44467832958;3464.359093567673;3477.2871561329866;3487.8953631390846;3503.527023851773;3505.1938054247667;31485.842570787;31521.21196970094;31572.36855262905;31572.526412103838;31600.683993160004;5226.2944502492055;5227.50338717825;5238.423773384925;5241.895162096775;5283.392409872876;180484;180911;181760.09119954315;182450;182663.08668456707;46054;46079.5392046082;46080.308795368095;46105.76947115243;46109;47969.5203047972;48154.75922620435;48155.518444815105;48272;48365.75817120962;14207.431702731776;15800.841991580168;16101.194940253175;16116.67766644684;16123.919380403024;11538.826917596243;11654.242474239147;11661.18371713989;11677.649670509898;11882.94058529719;14063.15621062719;14115.153090814583;14138.787918181235;14144.929275353557;14246.501372451914;5775.826725198251;5827.3298570664065;5853.151293062482;5896.262967129109;5911.852203694942;991.9355241909253;1006.2955930848435;1007.9344842585208;1013.159077965287;1017.9185665146771;21991.450213744465;22065.227717029826;22322.330330090124;22440.32679019632;22473.88763056174;1109.8612673415823;1116.8938950799661;1117.5920788911988;1120.4509790202742;1122.6632010396847;11693.824592631117;11789.823152652718;11800.114991375682;11893.762124757457;11911.940440297916;229692;231046;231679;231799;232389;51858;52097.21854172191;52114.478855211724;52148;52160.47839521632;1441.0561082490985;1442.646551594845;1444.2995147353524;1446.1178681004578;1461.0941216445872;21997.23009694717;22142.88928555347;22263.220787272367;22520;22652.207172748876;34411.82794086014;36474;36531.634683652825;36589.81705091458;36629.26741465209;195413;197933;198380;198800;199162;29916.401671966003;30344.84827575848;32769.836150819094;33248.335033299685;33328.16710579628;42304;42326.15347693091;42463.787681061396;42537.5746242546;42777.57222427861;31077.378452431276;31103.68896311008;31371.215719607193;31447;31931;30563.2359191022;30675.84662076675;31085.844570777;31435.214119646276;31598.210044748146;33892.98321050373;34918.825405872805;34950.475742864386;34984.300313994085;35228.47157292591;30062.098137055047;30232.639531221586;30540.84729576338;30622.84688576543;30685.693143069177;23336.883315583313;23378.532429351657;23474.061037558306;23626.881865590563;24071.277861664177 11:27:02 INFO - PID 9460 | |2;jquery.html;552.1579591123534;557.3590371073266;563.6533531877893;570.2842932120188;584.6988800767604;15869;21900.45248868779;22675;22714.432139196528;22722.54554908901;18198.090095495205;21377;23796.762032379673;23887.641685374732;23898.641520377165;6517.9674101629425;6660.167479065117;6693.6318502482345;6695.46436285096;6714.597124172541;5885.529157667376;6457.160569126007;6585.967070164643;6587.670616469181;6589.571677840949;6462.386073323027;6510.707018184169;6514.055461958013;6519.771807986722;6519.93480065198;6509.381608747161;6515.4787616990525;6515.576487528296;6517.4460170885295;6517.608943463384;6037.969810150965;6044.637321760664;6058.606190597597;6059.636421814661;6061.181740465025;26502;27292.181234562795;27319.45361092787;27881.16356509308;27924.44151116966;2090.1430413530534;2130.4886827161527;2131.456478597948;2137.0917360122044;2143.0998980428258;18834.34079807214;19363.128659210368;19363.515912102168;19414.80585194144;19415.22339106434;2409.241089056959;2435.3789783605243;2489.4025433895918;2498.912538061159;2505.987470062638;129290;133632;134945;137748.999999999;138085;13636.250006249751;13789.172649641054;14106.71786564263;14264.42942282297;14606.926965365104;21789.23737669174;21814.127434902428;21920.671189932164;21976.89011554932;21979.890100549394;9859.950700246452;10031;10245.641402550875;10268.845967310497;10288.228382871315;9381.484018379055;9551.283653726;9568.425894446356;9587.808243835085;9616.663416780379;7389.778306650809;8446.239838414573;8506.489610623259;8803.119688031145;9015.549222538846;2630.2240838952557;2638.3667919699133;2681.7720493758247;2685.7179996100126;2749.9450010999667;929.5723966975132;949.852772820214;952.2667545989549;961.33667769239;972.7422233108232;18078.186481608358;18400;18562.164702588416;18756.249750009847;18908.86546807196;989.9802003960025;1023.0843395161438;1031.9174466042698;1043.968680939573;1048.046277887119;10165.695029149138;10294.691159265085;10419.895801041892;10622.36265824053;10626.096781773636;512049;513339;514401;515800;516844;118479.40760296315;119632.8036719639;120017;120637.39681301537;121383;1196.1925700152362;1198.43074539593;1211.3156066822216;1215.5137944821965;1224.6387315741877;16837.989720616803;19334.419967401;20108.497287567927;20294.69557956602;20657;56472.43527564672;57144.428555715574;57674;58056.70971645115;58143.4185658138;66194.33805661881;66194.66902665456;66348;66438;66591;11588.304701717923;14167.929160354131;14482.493112741004;14484.782728258873;14495.565133046026;18335.266589336276;20947.581048379252;21085.15659373608;21086.84022378784;21326.36020919375;16893.493195204162;17157.399491017753;17256.568585785455;17272.05003724807;18124;15198.240087995777;15650.608734781721;15799.763003554726;15807.525774226791;15840.841591583936;17748.112594370486;18595.81404185941;18694;18718;18741.531461713566;13876.653083672989;13937.442502299795;13957.3719182635;13990.16059036461;14085.225312607496;17146.828531714524;17194.742078868578;18023.72964405509;18026.36907708224;18039.639207216045 11:27:02 INFO - PID 9460 | |3;mootools.html;1303.9804402933958;1358.5720498043115;1360.1227208450543;1361.7684993551095;1385.4665953607862;14367.78448323276;14393.352299146538;15054.172020538866;15091.39634414622;15107.395704171833;15457.613559661015;15705.293261803217;15723.213839308046;15734.134622595753;15752.527424177293;2674.3581540430255;2799.0902956539103;2834.0789243495947;2843.7724982001496;2845.146456063183;2614.0458732562574;2639.2741995951073;2641.03602185202;2643.4977354302623;2643.933901652455;2665.280374298938;2789.5955086512445;2814.3949050954043;2827.123591686584;2829.2078218098964;2584.405586715066;2637.3802156493207;2710.6611673540806;2722.3874628208596;2759.986200069007;2739.2740923655247;2763.06055940981;2764.958525622117;2776.403073339242;2776.5557510798176;14367.928160359235;14751.336189871481;14772;14774.70450590993;14784.63038424048;7849.568273744999;8165;8168.877466838002;8173.427860049799;8175.509469431853;5289.3917199521775;5351.678899266057;5415.1335786274;5442.12925931853;5468.26178465906;1939.156466936887;1942.9611407771765;1944.5333120051228;1981.5145289404045;2002.8497862660358;25149.24552263435;25604.23187304384;25627.231183064538;25637.3590660235;25645.743542564524;6457.967710161513;6650.9667451662435;6672.666366681647;6868.484863635147;6893.517453778288;16444.506664800076;16451.753223701653;16456.753148702777;16459.5885102871;16461;7683;7785.961070194613;7800.570968596781;7808.141104478503;7810.29707326332;7102.538335008208;7141.464390170758;7205.819854503679;7240.1311842579225;7264.309890560388;4832.106060378841;4980.726060066659;5001.399832020181;5036.496350365008;5052;1536.178144692584;1543.2592355669342;1556.377449020401;1574.9055056696634;1584.6847116892952;852.4586887326592;882.280941033056;893.5442924108752;894.0790985285166;900.4057322167338;7297.744578939818;7388.852222955511;7392.188913716066;7460.70157193717;7495.800671892471;673.619405036153;699.9335063168991;705.5413980912448;708.3164746020012;710.6588837358097;4348.478182618106;4766.737829419415;5034.521720436553;5046.823361182414;5082.669626474268;19739.802601973795;20224.797752022587;20529.79470205279;20546.794532054788;20549.89725051365;19724.70412943807;19906.402807915496;20258.392248232576;20479.69280460794;20511.487212819502;10828.72928176786;10954.780904381867;11121.443927803577;11158.1631377647;11188.440577971069;7736.110347310131;7792.805179870548;7808.609569521387;7850.136484986761;7878.487898286593;16971.91514042422;16976.745348820026;17072.74390884113;17169.399071032454;17311.913440432716;17796.822031780033;17871.195796189462;17922.103894805467;17976.91011544934;18384.264629414673;10672.679819605424;10827.079698225423;10828.458577071273;10845.620403285848;10895;12226.449809758404;12410.75178496443;12531.122821402414;12536.561220357244;12578.937105314415;11004.889951100387;11020.5040773167;11095;11256;11452.14108941816;10645.840312395165;10718;10767.6231331903;10867.782644347228;10935.671929842118;9065.4560726354;9101.499417531835;9108.316876234176;9173.633054677739;9228.584713687766;6974.651267436507;7055.2591977841585;7104.11198600175;7127.073480447509;7188.460865435218;6811.625360605214;6908.963655451723;6919.6886140124825;7005.299470052954;7124.857502850017 11:27:02 INFO - PID 9460 | |4;prototype.html;510.74462768615695;515.8349328214973;517.3507248403254;526.9288646032786;528.3290221418796;6285.894225434479;6312.936870631293;6323.513974216055;6373.713182906768;6386.904196437057;6242.157308763316;6278.623282603047;6300.2989192917885;6318.5892916960365;6351.300697203693;5175.637705360627;5215.947840521603;5258.158694608864;5259.211118332242;5299.682019078868;4944.554990050895;5264.710440925747;5274.367075950891;5274.709890955995;5297.523222909937;4999.375078115236;5012.724300163508;5024.497550244964;5041.143005689041;5067.113255180348;4993.126202914512;5053.924191137155;5058.59531237502;5066.240063990394;5083.949160508421;4474.1499115168335;4512.638988880882;4513.300438432048;4518.480374756912;4518.615917647004;6232.189815323979;6266.717997690115;6267.843303917393;6269.655168965705;6283.183186185766;5706.543476521868;5749.223854779593;5780.971095144498;5785.27684039495;5829.358770535237;6519.804405867784;6526.836829079263;6542.280349161589;6600.5049621278595;6607.3392660734025;4869.393100276936;4888.73111978841;4900.632452566037;4905.386826646669;4928.176574667396;6588.40704336612;6717.832054198684;6924.169099708017;6955.130608673916;6980.895286570705;4960.156773348539;4967.453580106186;5017.7491125443585;5042.873928151826;5068.290439338495;5049.722265275372;5085.974570127126;5106.744662766847;5108.208227724708;5124.666896651705;5673.517750991212;5700.828975130753;5715.599908006399;5737.807284407618;5819.359870414251;6618.338166183343;6660.267370589231;6678.465722742169;6699.899501507482;6728.932710672929;1756.9560760980821;1785.169895998341;1791.8208179181977;1792;1796.568823482381;1153.9653810385703;1211.454845319592;1220.5300959130798;1222.6148763139583;1233.1676118619896;5892.764289428461;5911.320198177179;5927.525797936155;5995.490383317379;6015.157877897098;6815.352541508549;6867.416269617039;6892.310768923067;6908.378245957888;6946.409555187764;5861.648301101947;5868.706564671835;5887.528997680175;5912.793052243237;5922.881542369215;3600.171960449059;3608.8195590220384;3614.241009388058;3632.146445585285;3645.3438381091128;6364.395382438661;6546.967265163644;6568.67156642166;6587.694306992515;6663.367474968663;6537.754100391643;6857.451403887677;6943.652817359111;6984.476164287698;6988.895166572505;6438.742450302029;6454.419102280724;6530.755009243934;6548.672566371758;6553.442957348679;6735.528513004043;6809.625470599165;6816.625085620339;6820.454363650995;6821.420179284816;6660.666966651745;6725;6775.559588626724;6778.186617605918;6785;6480.675966201577;6555.967220163869;6572.2583515367505;6612.636305003271;6629.801105966829;5043.470435604356;5050.949490505048;5051.74741262928;5069.366329208849;5074.238864170404;6503.414692677682;6646.202455705346;6688.3646053624825;6826.146731658543;6890.38254916173;6552.148220731275;6556.868862622626;6558.508111891531;6601.53789234749;6655.866882662416;6501.6099034056215;6539;6548.377904099103;6614;6625.867482650224;6400.487960963019;6434.549581529248;6456.709448074754;6481.736061467968;6553.639549824806;6621.139251897415;6648.972063520479;6666.400023997669;6667.799966001027;6712.026756120433;6351.333110023381;6360.300366959723;6361.713722882574;6421.8715625688155;6503.219613646393 11:27:02 INFO - PID 9460 | |5;yui.html;863.9738817112258;879.4327658660163;884.677092861105;885.9069797671244;891.2780647675381;18069.457916262516;18998.620027599474;19006.04969751512;19015.429537113872;20010.199592016303;19801.702974455395;19850.503737406572;19868.304609338677;19879;19925.800741992574;874.8425283448977;918.8437965545874;922.1101636920371;922.1470140120396;922.9538523073819;890.6971629645919;911.680911680913;924.0943875002501;926.2451102351562;948.4451595816421;988.1501908358806;988.1600639456476;988.3081842710096;988.6885631026204;994.6121012804975;981.6024510073431;984.1536278800262;984.5963155106372;985.1478471122499;990.1336330710628;720.2905138438633;722.479814533535;726.1467775363978;727.6470911607865;730.6748496918873;17558.122093895254;18243.087845607668;18384;18657.90671046636;18671.346502872333;20360.880151591657;20389.8980505098;20782.48043798902;20795.064222110042;21075.894620526953;15706.685866282725;16620.750688739678;16696.332146714118;16717.66564668712;16760.58098547546;3672.2839046385966;3713.832877520518;3713.9628603713886;3719.6652301293016;3728.328900797855;129005;130223;130624.34687826691;130634;133718;15373.38506459729;15486;15885;15930.442434514967;15981.840181598267;28989.85505072461;29664.851675741484;29707.257318567208;29732.851335743184;30443;7391.963040184764;7704.460687751804;8409.915900840913;8501;8639.827203455896;4378.058717375779;4471.8434854779925;4476.529964353761;4487.057717879216;4487.349334346567;5053.343065401475;5173.1722924331925;5179;5190.532852043334;5203.16749320107;993.6621548673486;997.9421812878372;1009.0968583118068;1014.60937539048;1016.8474728790593;607.4229481992037;614.9754009839556;616.3866952382408;617.7343742190901;636.474908200727;10576.36541807494;10893.509792059376;11261.887381126084;11287.774244515063;12081.093917956188;694.836713372357;695.5339922252103;697.9092717946635;706.2372637551432;713.1157364867527;5248.212768084818;5256.894862102737;5363.597730170253;5412.485813847678;5412.891742165213;107681;109882;111271;112740.43629781799;113403;94467;96192;97004.51497742467;99032.99999999856;102925.48537257266;82909.58545207356;85664;89060.10939890648;90307;90472.5476372627;11111.277766945122;11324.6036388726;11420.600278990196;11530.884691152982;11653.184277100521;39828.40257396083;39874.601253987086;40386;40615.79692101521;42663.36004959866;67751;77563.61218193873;80685.19314807012;81435;82129.17870821455;19763.901180494006;19979;20144.09351579212;20308.59382812306;20325.49186270355;16844.831551684816;21160.259390921245;21599;21856.344309670734;22556.548869022856;16386.918065409598;16568.751468727736;16624.50126496207;16863.241154147847;16868;16557.66884662324;16799;17643.55891102733;18119.547011324823;18203.908980455013;16727.498175054767;17004.489865304062;17043.914780426017;17084.829151708323;17099.572510687332;12211.69470763238;12232.082593805679;12429.37853107323;12880;13117.278549679859;12882;13080.542181023618;13124.868751312364;13293.401796919356;13408 11:27:02 INFO - PID 9460 | __end_tp_report 11:27:02 INFO - PID 9460 | __start_cc_report 11:27:02 INFO - PID 9460 | _x_x_mozilla_cycle_collect,370 11:27:02 INFO - PID 9460 | __end_cc_report 11:27:02 INFO - PID 9460 | __startTimestamp1503858422893__endTimestamp 11:27:02 INFO - PID 9460 | 11:27:02 INFO - PID 9460 | ------- Summary: start ------- 11:27:02 INFO - PID 9460 | Number of tests: 6 11:27:02 INFO - PID 9460 | 11:27:02 INFO - PID 9460 | [#0] dojo.html Cycles:165 Average:6314.42 Median:6592.00 stddev:727.02 (11.0%) stddev-sans-first:688.87 11:27:02 INFO - PID 9460 | Values: 3268.4 3326.4 3349.6 3366.0 3370.7 6297.7 6311.3 6340.4 6387.0 6507.4 6383.2 6451.6 6470.1 6493.0 6540.8 5840.8 5881.8 5911.1 5971.2 5988.1 5719.8 5822.1 5844.0 5934.9 5954.6 5833.9 5868.1 5934.2 5941.5 5955.5 5862.1 5882.8 5936.0 5961.1 5975.1 5721.9 5809.7 5813.9 5883.4 5915.6 6318.0 6348.3 6405.1 6419.0 6429.4 6765.0 6785.1 6802.5 6843.7 6867.1 6822.0 6889.0 6890.7 6906.7 6976.1 6311.3 6397.6 6407.8 6409.8 6418.8 6850.2 6981.9 6988.6 7061.6 7084.8 6641.1 6643.2 6676.9 6683.5 6690.8 6769.8 6784.6 6808.7 6876.4 6878.2 6865.2 6887.8 6889.2 6892.4 6906.0 6807.4 6823.1 6845.5 6850.2 6940.3 5364.4 5372.6 5401.1 5407.1 5437.1 4745.5 4768.5 4794.7 4802.2 4805.0 5989.1 5995.4 6080.7 6086.5 6087.6 6828.8 6844.5 6845.4 6928.3 6945.2 6059.0 6075.1 6075.8 6081.5 6095.3 5877.8 5937.9 5938.6 5950.1 5960.3 6599.1 6614.9 6705.5 6719.3 6720.4 6719.5 6854.6 6871.9 6872.6 6947.9 6620.3 6628.7 6641.7 6644.8 6667.3 6643.8 6817.6 6830.0 6836.4 6839.8 6852.9 6863.1 6879.6 6949.9 6955.7 6511.7 6550.8 6592.0 6606.3 6678.4 6596.9 6673.1 6685.2 6690.3 6694.9 6614.0 6642.5 6658.9 6667.2 6670.0 6681.2 6684.5 6697.5 6766.9 6783.9 6363.6 6440.3 6459.8 6473.6 6550.0 11:27:02 INFO - PID 9460 | 11:27:02 INFO - PID 9460 | [#1] ext.html Cycles:180 Average:36738.69 Median:33288.25 stddev:53015.37 (159.3%) stddev-sans-first:53095.52 11:27:02 INFO - PID 9460 | Values: 827.8 858.0 887.8 895.0 1138.0 30866.7 31566.0 39841.0 40806.4 41328.2 41451.2 41754.6 41789.8 41822.2 41826.6 7781.0 7803.1 7978.5 7981.2 8003.5 7784.7 7889.8 7896.6 7903.4 7926.6 7909.9 7945.8 7961.0 7963.2 7964.9 7891.9 7896.3 7907.2 7935.4 7944.6 7824.0 7847.5 7873.0 7885.9 7885.9 36676.8 36879.6 36967.8 36976.8 37021.4 3464.4 3477.3 3487.9 3503.5 3505.2 31485.8 31521.2 31572.4 31572.5 31600.7 5226.3 5227.5 5238.4 5241.9 5283.4 180484.0 180911.0 181760.1 182450.0 182663.1 46054.0 46079.5 46080.3 46105.8 46109.0 47969.5 48154.8 48155.5 48272.0 48365.8 14207.4 15800.8 16101.2 16116.7 16123.9 11538.8 11654.2 11661.2 11677.6 11882.9 14063.2 14115.2 14138.8 14144.9 14246.5 5775.8 5827.3 5853.2 5896.3 5911.9 991.9 1006.3 1007.9 1013.2 1017.9 21991.5 22065.2 22322.3 22440.3 22473.9 1109.9 1116.9 1117.6 1120.5 1122.7 11693.8 11789.8 11800.1 11893.8 11911.9 229692.0 231046.0 231679.0 231799.0 232389.0 51858.0 52097.2 52114.5 52148.0 52160.5 1441.1 1442.6 1444.3 1446.1 1461.1 21997.2 22142.9 22263.2 22520.0 22652.2 34411.8 36474.0 36531.6 36589.8 36629.3 195413.0 197933.0 198380.0 198800.0 199162.0 29916.4 30344.8 32769.8 33248.3 33328.2 42304.0 42326.2 42463.8 42537.6 42777.6 31077.4 31103.7 31371.2 31447.0 31931.0 30563.2 30675.8 31085.8 31435.2 31598.2 33893.0 34918.8 34950.5 34984.3 35228.5 30062.1 30232.6 30540.8 30622.8 30685.7 23336.9 23378.5 23474.1 23626.9 24071.3 11:27:02 INFO - PID 9460 | 11:27:02 INFO - PID 9460 | [#2] jquery.html Cycles:180 Average:34900.36 Median:11966.17 stddev:86396.99 (722.0%) stddev-sans-first:86600.87 11:27:02 INFO - PID 9460 | Values: 552.2 557.4 563.7 570.3 584.7 15869.0 21900.5 22675.0 22714.4 22722.5 18198.1 21377.0 23796.8 23887.6 23898.6 6518.0 6660.2 6693.6 6695.5 6714.6 5885.5 6457.2 6586.0 6587.7 6589.6 6462.4 6510.7 6514.1 6519.8 6519.9 6509.4 6515.5 6515.6 6517.4 6517.6 6038.0 6044.6 6058.6 6059.6 6061.2 26502.0 27292.2 27319.5 27881.2 27924.4 2090.1 2130.5 2131.5 2137.1 2143.1 18834.3 19363.1 19363.5 19414.8 19415.2 2409.2 2435.4 2489.4 2498.9 2506.0 129290.0 133632.0 134945.0 137749.0 138085.0 13636.3 13789.2 14106.7 14264.4 14606.9 21789.2 21814.1 21920.7 21976.9 21979.9 9860.0 10031.0 10245.6 10268.8 10288.2 9381.5 9551.3 9568.4 9587.8 9616.7 7389.8 8446.2 8506.5 8803.1 9015.5 2630.2 2638.4 2681.8 2685.7 2749.9 929.6 949.9 952.3 961.3 972.7 18078.2 18400.0 18562.2 18756.2 18908.9 990.0 1023.1 1031.9 1044.0 1048.0 10165.7 10294.7 10419.9 10622.4 10626.1 512049.0 513339.0 514401.0 515800.0 516844.0 118479.4 119632.8 120017.0 120637.4 121383.0 1196.2 1198.4 1211.3 1215.5 1224.6 16838.0 19334.4 20108.5 20294.7 20657.0 56472.4 57144.4 57674.0 58056.7 58143.4 66194.3 66194.7 66348.0 66438.0 66591.0 11588.3 14167.9 14482.5 14484.8 14495.6 18335.3 20947.6 21085.2 21086.8 21326.4 16893.5 17157.4 17256.6 17272.1 18124.0 15198.2 15650.6 15799.8 15807.5 15840.8 17748.1 18595.8 18694.0 18718.0 18741.5 13876.7 13937.4 13957.4 13990.2 14085.2 17146.8 17194.7 18023.7 18026.4 18039.6 11:27:02 INFO - PID 9460 | 11:27:02 INFO - PID 9460 | [#3] mootools.html Cycles:180 Average:8959.83 Median:2643.72 stddev:6320.93 (239.1%) stddev-sans-first:6312.49 11:27:02 INFO - PID 9460 | Values: 1304.0 1358.6 1360.1 1361.8 1385.5 14367.8 14393.4 15054.2 15091.4 15107.4 15457.6 15705.3 15723.2 15734.1 15752.5 2674.4 2799.1 2834.1 2843.8 2845.1 2614.0 2639.3 2641.0 2643.5 2643.9 2665.3 2789.6 2814.4 2827.1 2829.2 2584.4 2637.4 2710.7 2722.4 2760.0 2739.3 2763.1 2765.0 2776.4 2776.6 14367.9 14751.3 14772.0 14774.7 14784.6 7849.6 8165.0 8168.9 8173.4 8175.5 5289.4 5351.7 5415.1 5442.1 5468.3 1939.2 1943.0 1944.5 1981.5 2002.8 25149.2 25604.2 25627.2 25637.4 25645.7 6458.0 6651.0 6672.7 6868.5 6893.5 16444.5 16451.8 16456.8 16459.6 16461.0 7683.0 7786.0 7800.6 7808.1 7810.3 7102.5 7141.5 7205.8 7240.1 7264.3 4832.1 4980.7 5001.4 5036.5 5052.0 1536.2 1543.3 1556.4 1574.9 1584.7 852.5 882.3 893.5 894.1 900.4 7297.7 7388.9 7392.2 7460.7 7495.8 673.6 699.9 705.5 708.3 710.7 4348.5 4766.7 5034.5 5046.8 5082.7 19739.8 20224.8 20529.8 20546.8 20549.9 19724.7 19906.4 20258.4 20479.7 20511.5 10828.7 10954.8 11121.4 11158.2 11188.4 7736.1 7792.8 7808.6 7850.1 7878.5 16971.9 16976.7 17072.7 17169.4 17311.9 17796.8 17871.2 17922.1 17976.9 18384.3 10672.7 10827.1 10828.5 10845.6 10895.0 12226.4 12410.8 12531.1 12536.6 12578.9 11004.9 11020.5 11095.0 11256.0 11452.1 10645.8 10718.0 10767.6 10867.8 10935.7 9065.5 9101.5 9108.3 9173.6 9228.6 6974.7 7055.3 7104.1 7127.1 7188.5 6811.6 6909.0 6919.7 7005.3 7124.9 11:27:02 INFO - PID 9460 | 11:27:02 INFO - PID 9460 | [#4] prototype.html Cycles:180 Average:5579.15 Median:6280.90 stddev:1557.30 (24.8%) stddev-sans-first:1514.49 11:27:02 INFO - PID 9460 | Values: 510.7 515.8 517.4 526.9 528.3 6285.9 6312.9 6323.5 6373.7 6386.9 6242.2 6278.6 6300.3 6318.6 6351.3 5175.6 5215.9 5258.2 5259.2 5299.7 4944.6 5264.7 5274.4 5274.7 5297.5 4999.4 5012.7 5024.5 5041.1 5067.1 4993.1 5053.9 5058.6 5066.2 5083.9 4474.1 4512.6 4513.3 4518.5 4518.6 6232.2 6266.7 6267.8 6269.7 6283.2 5706.5 5749.2 5781.0 5785.3 5829.4 6519.8 6526.8 6542.3 6600.5 6607.3 4869.4 4888.7 4900.6 4905.4 4928.2 6588.4 6717.8 6924.2 6955.1 6980.9 4960.2 4967.5 5017.7 5042.9 5068.3 5049.7 5086.0 5106.7 5108.2 5124.7 5673.5 5700.8 5715.6 5737.8 5819.4 6618.3 6660.3 6678.5 6699.9 6728.9 1757.0 1785.2 1791.8 1792.0 1796.6 1154.0 1211.5 1220.5 1222.6 1233.2 5892.8 5911.3 5927.5 5995.5 6015.2 6815.4 6867.4 6892.3 6908.4 6946.4 5861.6 5868.7 5887.5 5912.8 5922.9 3600.2 3608.8 3614.2 3632.1 3645.3 6364.4 6547.0 6568.7 6587.7 6663.4 6537.8 6857.5 6943.7 6984.5 6988.9 6438.7 6454.4 6530.8 6548.7 6553.4 6735.5 6809.6 6816.6 6820.5 6821.4 6660.7 6725.0 6775.6 6778.2 6785.0 6480.7 6556.0 6572.3 6612.6 6629.8 5043.5 5050.9 5051.7 5069.4 5074.2 6503.4 6646.2 6688.4 6826.1 6890.4 6552.1 6556.9 6558.5 6601.5 6655.9 6501.6 6539.0 6548.4 6614.0 6625.9 6400.5 6434.5 6456.7 6481.7 6553.6 6621.1 6649.0 6666.4 6667.8 6712.0 6351.3 6360.3 6361.7 6421.9 6503.2 11:27:02 INFO - PID 9460 | 11:27:02 INFO - PID 9460 | [#5] yui.html Cycles:180 Average:23910.08 Median:29686.05 stddev:33132.02 (111.6%) stddev-sans-first:33179.77 11:27:02 INFO - PID 9460 | Values: 864.0 879.4 884.7 885.9 891.3 18069.5 18998.6 19006.0 19015.4 20010.2 19801.7 19850.5 19868.3 19879.0 19925.8 874.8 918.8 922.1 922.1 923.0 890.7 911.7 924.1 926.2 948.4 988.2 988.2 988.3 988.7 994.6 981.6 984.2 984.6 985.1 990.1 720.3 722.5 726.1 727.6 730.7 17558.1 18243.1 18384.0 18657.9 18671.3 20360.9 20389.9 20782.5 20795.1 21075.9 15706.7 16620.8 16696.3 16717.7 16760.6 3672.3 3713.8 3714.0 3719.7 3728.3 129005.0 130223.0 130624.3 130634.0 133718.0 15373.4 15486.0 15885.0 15930.4 15981.8 28989.9 29664.9 29707.3 29732.9 30443.0 7392.0 7704.5 8409.9 8501.0 8639.8 4378.1 4471.8 4476.5 4487.1 4487.3 5053.3 5173.2 5179.0 5190.5 5203.2 993.7 997.9 1009.1 1014.6 1016.8 607.4 615.0 616.4 617.7 636.5 10576.4 10893.5 11261.9 11287.8 12081.1 694.8 695.5 697.9 706.2 713.1 5248.2 5256.9 5363.6 5412.5 5412.9 107681.0 109882.0 111271.0 112740.4 113403.0 94467.0 96192.0 97004.5 99033.0 102925.5 82909.6 85664.0 89060.1 90307.0 90472.5 11111.3 11324.6 11420.6 11530.9 11653.2 39828.4 39874.6 40386.0 40615.8 42663.4 67751.0 77563.6 80685.2 81435.0 82129.2 19763.9 19979.0 20144.1 20308.6 20325.5 16844.8 21160.3 21599.0 21856.3 22556.5 16386.9 16568.8 16624.5 16863.2 16868.0 16557.7 16799.0 17643.6 18119.5 18203.9 16727.5 17004.5 17043.9 17084.8 17099.6 12211.7 12232.1 12429.4 12880.0 13117.3 12882.0 13080.5 13124.9 13293.4 13408.0 11:27:02 INFO - PID 9460 | -------- Summary: end -------- 11:27:02 INFO - PID 9460 | 11:27:03 INFO - TEST-INFO | 9460: exit 0 11:27:03 INFO - TEST-OK | dromaeo_css | took 1110421ms 11:27:03 INFO - TEST-START | kraken 11:27:03 INFO - Initialising browser for kraken test... 11:27:03 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34300/getInfo.html -profile /tmp/tmpYPgu3j/profile 11:27:03 INFO - TEST-INFO | started process 9882 (/builds/slave/test/build/application/firefox/firefox http://localhost:34300/getInfo.html) 11:27:09 INFO - TEST-INFO | 9882: exit 0 11:27:09 INFO - Browser initialized. 11:27:09 INFO - Running cycle 1/1 for kraken test... 11:27:09 INFO - Using env: {'DISPLAY': ':0', 11:27:09 INFO - 'HOME': '/home/cltbld', 11:27:09 INFO - 'JSGC_DISABLE_POISONING': '1', 11:27:09 INFO - 'LANG': 'en_US.UTF-8', 11:27:09 INFO - 'LANGUAGE': 'en_US:en', 11:27:09 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:27:09 INFO - 'LOGNAME': 'cltbld', 11:27:09 INFO - 'MAIL': '/var/mail/cltbld', 11:27:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:27:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:27:09 INFO - 'MOZ_CRASHREPORTER': '1', 11:27:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:09 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:27:09 INFO - 'MOZ_NO_REMOTE': '1', 11:27:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:27:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:09 INFO - 'NO_EM_RESTART': '1', 11:27:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:09 INFO - 'PWD': '/builds/slave/test', 11:27:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:27:09 INFO - 'RUST_BACKTRACE': 'full', 11:27:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:27:09 INFO - 'SHELL': '/bin/bash', 11:27:09 INFO - 'SHLVL': '1', 11:27:09 INFO - 'STYLO_FORCE_ENABLED': '1', 11:27:09 INFO - 'TERM': 'linux', 11:27:09 INFO - 'TMOUT': '86400', 11:27:09 INFO - 'USER': 'cltbld', 11:27:09 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841', 11:27:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:09 INFO - '_': '/tools/buildbot/bin/python'} 11:27:09 INFO - TEST-INFO | started process 10068 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYPgu3j/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:27:10 INFO - PID 10068 | 11:27:10 INFO - PID 10068 | (/builds/slave/test/build/application/firefox/firefox:10119): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:27:10 INFO - PID 10068 | 11:27:10 INFO - PID 10068 | 11:27:10 INFO - PID 10068 | (/builds/slave/test/build/application/firefox/firefox:10179): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:27:10 INFO - PID 10068 | 11:27:10 INFO - PID 10068 | __metrics Screen width/height:1600/1200 11:27:10 INFO - PID 10068 | colorDepth:24 11:27:10 INFO - PID 10068 | Browser inner width/height: 1024/768 11:27:10 INFO - PID 10068 | __metrics 11:27:20 INFO - PID 10068 | RSS: Main: 175042560 11:27:20 INFO - PID 10068 | 11:27:23 INFO - PID 10068 | Cycle 1(1): loaded http://localhost:34300/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34300/tests/kraken/driver.html?testName=audio-beat-detection) 11:27:24 INFO - PID 10068 | RSS: Main: 179761152 11:27:24 INFO - PID 10068 | 11:27:26 INFO - PID 10068 | Cycle 1(1): loaded http://localhost:34300/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34300/tests/kraken/driver.html?testName=audio-dft) 11:27:27 INFO - PID 10068 | RSS: Main: 179732480 11:27:27 INFO - PID 10068 | 11:27:30 INFO - PID 10068 | Cycle 1(1): loaded http://localhost:34300/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34300/tests/kraken/driver.html?testName=audio-fft) 11:27:31 INFO - PID 10068 | RSS: Main: 174161920 11:27:31 INFO - PID 10068 | 11:27:33 INFO - PID 10068 | Cycle 1(1): loaded http://localhost:34300/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34300/tests/kraken/driver.html?testName=audio-oscillator) 11:27:33 INFO - PID 10068 | RSS: Main: 174727168 11:27:33 INFO - PID 10068 | 11:27:35 INFO - PID 10068 | Cycle 1(1): loaded http://localhost:34300/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34300/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:27:36 INFO - PID 10068 | RSS: Main: 174981120 11:27:36 INFO - PID 10068 | 11:27:39 INFO - PID 10068 | Cycle 1(1): loaded http://localhost:34300/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34300/tests/kraken/driver.html?testName=imaging-darkroom) 11:27:40 INFO - PID 10068 | RSS: Main: 175783936 11:27:40 INFO - PID 10068 | 11:27:43 INFO - PID 10068 | Cycle 1(1): loaded http://localhost:34300/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34300/tests/kraken/driver.html?testName=imaging-desaturate) 11:27:44 INFO - PID 10068 | RSS: Main: 175853568 11:27:44 INFO - PID 10068 | 11:27:47 INFO - PID 10068 | Cycle 1(1): loaded http://localhost:34300/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34300/tests/kraken/driver.html?testName=json-parse-financial) 11:27:47 INFO - PID 10068 | RSS: Main: 176492544 11:27:47 INFO - PID 10068 | 11:27:49 INFO - PID 10068 | Cycle 1(1): loaded http://localhost:34300/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34300/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:27:50 INFO - PID 10068 | RSS: Main: 176234496 11:27:50 INFO - PID 10068 | 11:27:51 INFO - PID 10068 | Cycle 1(1): loaded http://localhost:34300/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34300/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:27:52 INFO - PID 10068 | RSS: Main: 176517120 11:27:52 INFO - PID 10068 | 11:27:54 INFO - PID 10068 | Cycle 1(1): loaded http://localhost:34300/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34300/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:27:55 INFO - PID 10068 | RSS: Main: 176472064 11:27:55 INFO - PID 10068 | 11:27:57 INFO - PID 10068 | Cycle 1(1): loaded http://localhost:34300/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34300/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:27:58 INFO - PID 10068 | RSS: Main: 176480256 11:27:58 INFO - PID 10068 | 11:28:01 INFO - PID 10068 | Cycle 1(1): loaded http://localhost:34300/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34300/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:28:02 INFO - PID 10068 | RSS: Main: 176656384 11:28:02 INFO - PID 10068 | 11:28:03 INFO - PID 10068 | Cycle 1(1): loaded http://localhost:34300/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34300/tests/kraken/driver.html?testName=ai-astar) 11:28:04 INFO - PID 10068 | RSS: Main: 177934336 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | __start_tp_report 11:28:04 INFO - PID 10068 | _x_x_mozilla_page_load 11:28:04 INFO - PID 10068 | _x_x_mozilla_page_load_details 11:28:04 INFO - PID 10068 | |i|pagename|runs| 11:28:04 INFO - PID 10068 | |0;ai-astar;99;108;99;107;106;109;108;99;108;111 11:28:04 INFO - PID 10068 | |1;audio-beat-detection;143;118;146;181;118;145;144;122;174;122 11:28:04 INFO - PID 10068 | |2;audio-dft;164;165;162;162;162;161;163;168;163;162 11:28:04 INFO - PID 10068 | |3;audio-fft;75;73;74;176;73;73;73;92;97;74 11:28:04 INFO - PID 10068 | |4;audio-oscillator;79;79;127;79;80;80;132;79;79;123 11:28:04 INFO - PID 10068 | |5;imaging-gaussian-blur;114;114;114;114;114;114;120;114;113;114 11:28:04 INFO - PID 10068 | |6;imaging-darkroom;149;148;148;147;148;148;149;147;150;149 11:28:04 INFO - PID 10068 | |7;imaging-desaturate;94;94;94;95;94;94;94;93;94;94 11:28:04 INFO - PID 10068 | |8;json-parse-financial;53;53;53;62;58;53;53;52;52;53 11:28:04 INFO - PID 10068 | |9;json-stringify-tinderbox;39;38;38;38;39;38;38;38;38;38 11:28:04 INFO - PID 10068 | |10;stanford-crypto-aes;75;75;74;74;80;74;78;72;75;74 11:28:04 INFO - PID 10068 | |11;stanford-crypto-ccm;125;119;117;117;120;130;115;119;115;158 11:28:04 INFO - PID 10068 | |12;stanford-crypto-pbkdf2;173;168;172;168;167;169;176;172;169;179 11:28:04 INFO - PID 10068 | |13;stanford-crypto-sha256-iterative;66;64;66;69;64;74;61;64;63;71 11:28:04 INFO - PID 10068 | __end_tp_report 11:28:04 INFO - PID 10068 | __start_cc_report 11:28:04 INFO - PID 10068 | _x_x_mozilla_cycle_collect,924 11:28:04 INFO - PID 10068 | __end_cc_report 11:28:04 INFO - PID 10068 | __startTimestamp1503858484078__endTimestamp 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | ------- Summary: start ------- 11:28:04 INFO - PID 10068 | Number of tests: 14 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | [#0] ai-astar Cycles:10 Average:105.40 Median:110.00 stddev:4.60 (4.2%) stddev-sans-first:4.26 11:28:04 INFO - PID 10068 | Values: 99.0 108.0 99.0 107.0 106.0 109.0 108.0 99.0 108.0 111.0 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:144.50 stddev:22.42 (15.5%) stddev-sans-first:23.77 11:28:04 INFO - PID 10068 | Values: 143.0 118.0 146.0 181.0 118.0 145.0 144.0 122.0 174.0 122.0 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | [#2] audio-dft Cycles:10 Average:163.20 Median:163.00 stddev:2.04 (1.3%) stddev-sans-first:2.15 11:28:04 INFO - PID 10068 | Values: 164.0 165.0 162.0 162.0 162.0 161.0 163.0 168.0 163.0 162.0 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | [#3] audio-fft Cycles:10 Average:88.00 Median:74.00 stddev:32.15 (43.4%) stddev-sans-first:33.75 11:28:04 INFO - PID 10068 | Values: 75.0 73.0 74.0 176.0 73.0 73.0 73.0 92.0 97.0 74.0 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:79.00 stddev:23.31 (29.5%) stddev-sans-first:24.11 11:28:04 INFO - PID 10068 | Values: 79.0 79.0 127.0 79.0 80.0 80.0 132.0 79.0 79.0 123.0 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | [#5] imaging-gaussian-blur Cycles:10 Average:114.50 Median:114.00 stddev:1.96 (1.7%) stddev-sans-first:2.07 11:28:04 INFO - PID 10068 | Values: 114.0 114.0 114.0 114.0 114.0 114.0 120.0 114.0 113.0 114.0 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:148.50 stddev:0.95 (0.6%) stddev-sans-first:0.97 11:28:04 INFO - PID 10068 | Values: 149.0 148.0 148.0 147.0 148.0 148.0 149.0 147.0 150.0 149.0 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 11:28:04 INFO - PID 10068 | Values: 94.0 94.0 94.0 95.0 94.0 94.0 94.0 93.0 94.0 94.0 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:53.00 stddev:3.22 (6.1%) stddev-sans-first:3.39 11:28:04 INFO - PID 10068 | Values: 53.0 53.0 53.0 62.0 58.0 53.0 53.0 52.0 52.0 53.0 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:0.42 (1.1%) stddev-sans-first:0.33 11:28:04 INFO - PID 10068 | Values: 39.0 38.0 38.0 38.0 39.0 38.0 38.0 38.0 38.0 38.0 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | [#10] stanford-crypto-aes Cycles:10 Average:75.10 Median:75.00 stddev:2.28 (3.0%) stddev-sans-first:2.42 11:28:04 INFO - PID 10068 | Values: 75.0 75.0 74.0 74.0 80.0 74.0 78.0 72.0 75.0 74.0 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | [#11] stanford-crypto-ccm Cycles:10 Average:123.50 Median:119.50 stddev:12.98 (10.9%) stddev-sans-first:13.76 11:28:04 INFO - PID 10068 | Values: 125.0 119.0 117.0 117.0 120.0 130.0 115.0 119.0 115.0 158.0 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.30 Median:172.00 stddev:3.89 (2.3%) stddev-sans-first:4.08 11:28:04 INFO - PID 10068 | Values: 173.0 168.0 172.0 168.0 167.0 169.0 176.0 172.0 169.0 179.0 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.00 stddev:3.99 (6.1%) stddev-sans-first:4.24 11:28:04 INFO - PID 10068 | Values: 66.0 64.0 66.0 69.0 64.0 74.0 61.0 64.0 63.0 71.0 11:28:04 INFO - PID 10068 | -------- Summary: end -------- 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 11:28:04 INFO - PID 10068 | 11:28:04 INFO - PID 10068 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:28:04 INFO - TEST-INFO | 10068: exit 0 11:28:04 INFO - TEST-OK | kraken | took 61064ms 11:28:04 INFO - SUITE-END | took 1171s 11:28:05 INFO - Completed test suite (00:19:31) 11:28:05 INFO - javascript benchmark 11:28:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8447.113757846684, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3268.444364458042, 3326.3846188455136, 3349.6315405305395, 3365.9831700841505, 3370.713489353404, 6297.748090076398, 6311.337309582485, 6340.397662222087, 6386.968065159679, 6507.381798729125, 6383.202099737533, 6451.645159516224, 6470.126532918055, 6492.999999999988, 6540.836479088025, 5840.824775256728, 5881.794137205188, 5911.142884281777, 5971.193888825018, 5988.101784732282, 5719.799807006755, 5822.097574875901, 5843.970780146094, 5934.851628709295, 5954.612950158225, 5833.854153646151, 5868.149118377854, 5934.228550288478, 5941.465268125889, 5955.523558115362, 5862.14998825168, 5882.794102206445, 5935.970320148372, 5961.135635332896, 5975.133605627159, 5721.885562288772, 5809.651420914759, 5813.912791308134, 5883.352831188566, 5915.5859089863725, 6317.96841015792, 6348.333424990402, 6405.135306733578, 6419.037144428373, 6429.421352078289, 6764.966175169093, 6785.084013658142, 6802.523823332369, 6843.726250949956, 6867.107276054131, 6822.044913712084, 6889.0, 6890.655467226668, 6906.689198986058, 6976.058232138647, 6311.305756366888, 6397.584157029778, 6407.807765767081, 6409.807705768834, 6418.812519683874, 6850.212225594021, 6981.89527157093, 6988.615626140611, 7061.576305421589, 7084.822879427952, 6641.1366522352755, 6643.236027856762, 6676.933230667728, 6683.465322774166, 6690.832729181712, 6769.815282325573, 6784.59292442455, 6808.659567021629, 6876.415504682158, 6878.243393226741, 6865.244823069558, 6887.793366199022, 6889.207741109736, 6892.448604111659, 6906.0, 6807.421369183576, 6823.112995310677, 6845.486588505882, 6850.246472887979, 6940.271271516519, 5364.383095943938, 5372.586920427245, 5401.108817045222, 5407.080796264644, 5437.13005919051, 4745.501722319176, 4768.47546769855, 4794.712317260975, 4802.231642937183, 4804.975975120172, 5989.131575921554, 5995.430434108753, 6080.726367313481, 6086.513078953673, 6087.604305720114, 6828.760993365208, 6844.5208835382045, 6845.418139458202, 6928.341807528277, 6945.236024037352, 6059.030555111249, 6075.149479072933, 6075.817725468243, 6081.483073938764, 6095.299040610298, 5877.823665289962, 5937.940620593825, 5938.584299099108, 5950.107483877367, 5960.254968128984, 6599.1091202687985, 6614.900776488356, 6705.463562914858, 6719.328067193339, 6720.395164435225, 6719.4624430044505, 6854.554453960476, 6871.896921546081, 6872.587644741331, 6947.895781563382, 6620.271770105381, 6628.668566571556, 6641.667916604054, 6644.767433139817, 6667.299933507106, 6643.800685979429, 6817.625030623363, 6830.043793868763, 6836.384725374542, 6839.760608378683, 6852.862942741018, 6863.073485079451, 6879.621620810904, 6949.930500694928, 6955.721771129098, 6511.674416279262, 6550.803475895731, 6592.0, 6606.273309935999, 6678.432333251728, 6596.901046484402, 6673.099131617364, 6685.197776266879, 6690.330966903271, 6694.928811390154, 6614.0, 6642.468602511691, 6658.866822663616, 6667.233268174222, 6670.0, 6681.164854393201, 6684.4652427804685, 6697.464202863662, 6766.898496522655, 6783.89824152648, 6363.61818290904, 6440.323766004502, 6459.838504037249, 6473.643949582818, 6549.967250163719], "value": 6261.7263924177905, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.7558120354495, 858.0, 887.7691800131963, 895.0, 1138.0269869261786, 30866.691333086663, 31566.0, 39841.0039749006, 40806.38790418146, 41328.17343653125, 41451.17097658045, 41754.58245417545, 41789.79105104478, 41822.16355672893, 41826.58173418265, 7780.961095194516, 7803.1026431960345, 7978.521288722666, 7981.161977992285, 8003.4797738146835, 7784.727534536292, 7889.763307100768, 7896.565688887109, 7903.36773058154, 7926.564038977854, 7909.881351779728, 7945.761627151194, 7961.044674639023, 7963.163867793885, 7964.920350796505, 7891.881621775678, 7896.289333959942, 7907.2488113629115, 7935.444518883681, 7944.563049032301, 7824.0, 7847.450678452511, 7873.0, 7885.881711774328, 7885.881711774328, 36676.816615917014, 36879.63120368816, 36967.81516092429, 36976.81511592425, 37021.44467832958, 3464.359093567673, 3477.2871561329866, 3487.8953631390846, 3503.527023851773, 3505.1938054247667, 31485.842570787, 31521.21196970094, 31572.36855262905, 31572.526412103838, 31600.683993160004, 5226.2944502492055, 5227.50338717825, 5238.423773384925, 5241.895162096775, 5283.392409872876, 180484.0, 180911.0, 181760.09119954315, 182450.0, 182663.08668456707, 46054.0, 46079.5392046082, 46080.308795368095, 46105.76947115243, 46109.0, 47969.5203047972, 48154.75922620435, 48155.518444815105, 48272.0, 48365.75817120962, 14207.431702731776, 15800.841991580168, 16101.194940253175, 16116.67766644684, 16123.919380403024, 11538.826917596243, 11654.242474239147, 11661.18371713989, 11677.649670509898, 11882.94058529719, 14063.15621062719, 14115.153090814583, 14138.787918181235, 14144.929275353557, 14246.501372451914, 5775.826725198251, 5827.3298570664065, 5853.151293062482, 5896.262967129109, 5911.852203694942, 991.9355241909253, 1006.2955930848435, 1007.9344842585208, 1013.159077965287, 1017.9185665146771, 21991.450213744465, 22065.227717029826, 22322.330330090124, 22440.32679019632, 22473.88763056174, 1109.8612673415823, 1116.8938950799661, 1117.5920788911988, 1120.4509790202742, 1122.6632010396847, 11693.824592631117, 11789.823152652718, 11800.114991375682, 11893.762124757457, 11911.940440297916, 229692.0, 231046.0, 231679.0, 231799.0, 232389.0, 51858.0, 52097.21854172191, 52114.478855211724, 52148.0, 52160.47839521632, 1441.0561082490985, 1442.646551594845, 1444.2995147353524, 1446.1178681004578, 1461.0941216445872, 21997.23009694717, 22142.88928555347, 22263.220787272367, 22520.0, 22652.207172748876, 34411.82794086014, 36474.0, 36531.634683652825, 36589.81705091458, 36629.26741465209, 195413.0, 197933.0, 198380.0, 198800.0, 199162.0, 29916.401671966003, 30344.84827575848, 32769.836150819094, 33248.335033299685, 33328.16710579628, 42304.0, 42326.15347693091, 42463.787681061396, 42537.5746242546, 42777.57222427861, 31077.378452431276, 31103.68896311008, 31371.215719607193, 31447.0, 31931.0, 30563.2359191022, 30675.84662076675, 31085.844570777, 31435.214119646276, 31598.210044748146, 33892.98321050373, 34918.825405872805, 34950.475742864386, 34984.300313994085, 35228.47157292591, 30062.098137055047, 30232.639531221586, 30540.84729576338, 30622.84688576543, 30685.693143069177, 23336.883315583313, 23378.532429351657, 23474.061037558306, 23626.881865590563, 24071.277861664177], "value": 16886.273775512902, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.1579591123534, 557.3590371073266, 563.6533531877893, 570.2842932120188, 584.6988800767604, 15869.0, 21900.45248868779, 22675.0, 22714.432139196528, 22722.54554908901, 18198.090095495205, 21377.0, 23796.762032379673, 23887.641685374732, 23898.641520377165, 6517.9674101629425, 6660.167479065117, 6693.6318502482345, 6695.46436285096, 6714.597124172541, 5885.529157667376, 6457.160569126007, 6585.967070164643, 6587.670616469181, 6589.571677840949, 6462.386073323027, 6510.707018184169, 6514.055461958013, 6519.771807986722, 6519.93480065198, 6509.381608747161, 6515.4787616990525, 6515.576487528296, 6517.4460170885295, 6517.608943463384, 6037.969810150965, 6044.637321760664, 6058.606190597597, 6059.636421814661, 6061.181740465025, 26502.0, 27292.181234562795, 27319.45361092787, 27881.16356509308, 27924.44151116966, 2090.1430413530534, 2130.4886827161527, 2131.456478597948, 2137.0917360122044, 2143.0998980428258, 18834.34079807214, 19363.128659210368, 19363.515912102168, 19414.80585194144, 19415.22339106434, 2409.241089056959, 2435.3789783605243, 2489.4025433895918, 2498.912538061159, 2505.987470062638, 129290.0, 133632.0, 134945.0, 137748.999999999, 138085.0, 13636.250006249751, 13789.172649641054, 14106.71786564263, 14264.42942282297, 14606.926965365104, 21789.23737669174, 21814.127434902428, 21920.671189932164, 21976.89011554932, 21979.890100549394, 9859.950700246452, 10031.0, 10245.641402550875, 10268.845967310497, 10288.228382871315, 9381.484018379055, 9551.283653726, 9568.425894446356, 9587.808243835085, 9616.663416780379, 7389.778306650809, 8446.239838414573, 8506.489610623259, 8803.119688031145, 9015.549222538846, 2630.2240838952557, 2638.3667919699133, 2681.7720493758247, 2685.7179996100126, 2749.9450010999667, 929.5723966975132, 949.852772820214, 952.2667545989549, 961.33667769239, 972.7422233108232, 18078.186481608358, 18400.0, 18562.164702588416, 18756.249750009847, 18908.86546807196, 989.9802003960025, 1023.0843395161438, 1031.9174466042698, 1043.968680939573, 1048.046277887119, 10165.695029149138, 10294.691159265085, 10419.895801041892, 10622.36265824053, 10626.096781773636, 512049.0, 513339.0, 514401.0, 515800.0, 516844.0, 118479.40760296315, 119632.8036719639, 120017.0, 120637.39681301537, 121383.0, 1196.1925700152362, 1198.43074539593, 1211.3156066822216, 1215.5137944821965, 1224.6387315741877, 16837.989720616803, 19334.419967401, 20108.497287567927, 20294.69557956602, 20657.0, 56472.43527564672, 57144.428555715574, 57674.0, 58056.70971645115, 58143.4185658138, 66194.33805661881, 66194.66902665456, 66348.0, 66438.0, 66591.0, 11588.304701717923, 14167.929160354131, 14482.493112741004, 14484.782728258873, 14495.565133046026, 18335.266589336276, 20947.581048379252, 21085.15659373608, 21086.84022378784, 21326.36020919375, 16893.493195204162, 17157.399491017753, 17256.568585785455, 17272.05003724807, 18124.0, 15198.240087995777, 15650.608734781721, 15799.763003554726, 15807.525774226791, 15840.841591583936, 17748.112594370486, 18595.81404185941, 18694.0, 18718.0, 18741.531461713566, 13876.653083672989, 13937.442502299795, 13957.3719182635, 13990.16059036461, 14085.225312607496, 17146.828531714524, 17194.742078868578, 18023.72964405509, 18026.36907708224, 18039.639207216045], "value": 11686.277933783449, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1303.9804402933958, 1358.5720498043115, 1360.1227208450543, 1361.7684993551095, 1385.4665953607862, 14367.78448323276, 14393.352299146538, 15054.172020538866, 15091.39634414622, 15107.395704171833, 15457.613559661015, 15705.293261803217, 15723.213839308046, 15734.134622595753, 15752.527424177293, 2674.3581540430255, 2799.0902956539103, 2834.0789243495947, 2843.7724982001496, 2845.146456063183, 2614.0458732562574, 2639.2741995951073, 2641.03602185202, 2643.4977354302623, 2643.933901652455, 2665.280374298938, 2789.5955086512445, 2814.3949050954043, 2827.123591686584, 2829.2078218098964, 2584.405586715066, 2637.3802156493207, 2710.6611673540806, 2722.3874628208596, 2759.986200069007, 2739.2740923655247, 2763.06055940981, 2764.958525622117, 2776.403073339242, 2776.5557510798176, 14367.928160359235, 14751.336189871481, 14772.0, 14774.70450590993, 14784.63038424048, 7849.568273744999, 8165.0, 8168.877466838002, 8173.427860049799, 8175.509469431853, 5289.3917199521775, 5351.678899266057, 5415.1335786274, 5442.12925931853, 5468.26178465906, 1939.156466936887, 1942.9611407771765, 1944.5333120051228, 1981.5145289404045, 2002.8497862660358, 25149.24552263435, 25604.23187304384, 25627.231183064538, 25637.3590660235, 25645.743542564524, 6457.967710161513, 6650.9667451662435, 6672.666366681647, 6868.484863635147, 6893.517453778288, 16444.506664800076, 16451.753223701653, 16456.753148702777, 16459.5885102871, 16461.0, 7683.0, 7785.961070194613, 7800.570968596781, 7808.141104478503, 7810.29707326332, 7102.538335008208, 7141.464390170758, 7205.819854503679, 7240.1311842579225, 7264.309890560388, 4832.106060378841, 4980.726060066659, 5001.399832020181, 5036.496350365008, 5052.0, 1536.178144692584, 1543.2592355669342, 1556.377449020401, 1574.9055056696634, 1584.6847116892952, 852.4586887326592, 882.280941033056, 893.5442924108752, 894.0790985285166, 900.4057322167338, 7297.744578939818, 7388.852222955511, 7392.188913716066, 7460.70157193717, 7495.800671892471, 673.619405036153, 699.9335063168991, 705.5413980912448, 708.3164746020012, 710.6588837358097, 4348.478182618106, 4766.737829419415, 5034.521720436553, 5046.823361182414, 5082.669626474268, 19739.802601973795, 20224.797752022587, 20529.79470205279, 20546.794532054788, 20549.89725051365, 19724.70412943807, 19906.402807915496, 20258.392248232576, 20479.69280460794, 20511.487212819502, 10828.72928176786, 10954.780904381867, 11121.443927803577, 11158.1631377647, 11188.440577971069, 7736.110347310131, 7792.805179870548, 7808.609569521387, 7850.136484986761, 7878.487898286593, 16971.91514042422, 16976.745348820026, 17072.74390884113, 17169.399071032454, 17311.913440432716, 17796.822031780033, 17871.195796189462, 17922.103894805467, 17976.91011544934, 18384.264629414673, 10672.679819605424, 10827.079698225423, 10828.458577071273, 10845.620403285848, 10895.0, 12226.449809758404, 12410.75178496443, 12531.122821402414, 12536.561220357244, 12578.937105314415, 11004.889951100387, 11020.5040773167, 11095.0, 11256.0, 11452.14108941816, 10645.840312395165, 10718.0, 10767.6231331903, 10867.782644347228, 10935.671929842118, 9065.4560726354, 9101.499417531835, 9108.316876234176, 9173.633054677739, 9228.584713687766, 6974.651267436507, 7055.2591977841585, 7104.11198600175, 7127.073480447509, 7188.460865435218, 6811.625360605214, 6908.963655451723, 6919.6886140124825, 7005.299470052954, 7124.857502850017], "value": 6439.306985132763, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.74462768615695, 515.8349328214973, 517.3507248403254, 526.9288646032786, 528.3290221418796, 6285.894225434479, 6312.936870631293, 6323.513974216055, 6373.713182906768, 6386.904196437057, 6242.157308763316, 6278.623282603047, 6300.2989192917885, 6318.5892916960365, 6351.300697203693, 5175.637705360627, 5215.947840521603, 5258.158694608864, 5259.211118332242, 5299.682019078868, 4944.554990050895, 5264.710440925747, 5274.367075950891, 5274.709890955995, 5297.523222909937, 4999.375078115236, 5012.724300163508, 5024.497550244964, 5041.143005689041, 5067.113255180348, 4993.126202914512, 5053.924191137155, 5058.59531237502, 5066.240063990394, 5083.949160508421, 4474.1499115168335, 4512.638988880882, 4513.300438432048, 4518.480374756912, 4518.615917647004, 6232.189815323979, 6266.717997690115, 6267.843303917393, 6269.655168965705, 6283.183186185766, 5706.543476521868, 5749.223854779593, 5780.971095144498, 5785.27684039495, 5829.358770535237, 6519.804405867784, 6526.836829079263, 6542.280349161589, 6600.5049621278595, 6607.3392660734025, 4869.393100276936, 4888.73111978841, 4900.632452566037, 4905.386826646669, 4928.176574667396, 6588.40704336612, 6717.832054198684, 6924.169099708017, 6955.130608673916, 6980.895286570705, 4960.156773348539, 4967.453580106186, 5017.7491125443585, 5042.873928151826, 5068.290439338495, 5049.722265275372, 5085.974570127126, 5106.744662766847, 5108.208227724708, 5124.666896651705, 5673.517750991212, 5700.828975130753, 5715.599908006399, 5737.807284407618, 5819.359870414251, 6618.338166183343, 6660.267370589231, 6678.465722742169, 6699.899501507482, 6728.932710672929, 1756.9560760980821, 1785.169895998341, 1791.8208179181977, 1792.0, 1796.568823482381, 1153.9653810385703, 1211.454845319592, 1220.5300959130798, 1222.6148763139583, 1233.1676118619896, 5892.764289428461, 5911.320198177179, 5927.525797936155, 5995.490383317379, 6015.157877897098, 6815.352541508549, 6867.416269617039, 6892.310768923067, 6908.378245957888, 6946.409555187764, 5861.648301101947, 5868.706564671835, 5887.528997680175, 5912.793052243237, 5922.881542369215, 3600.171960449059, 3608.8195590220384, 3614.241009388058, 3632.146445585285, 3645.3438381091128, 6364.395382438661, 6546.967265163644, 6568.67156642166, 6587.694306992515, 6663.367474968663, 6537.754100391643, 6857.451403887677, 6943.652817359111, 6984.476164287698, 6988.895166572505, 6438.742450302029, 6454.419102280724, 6530.755009243934, 6548.672566371758, 6553.442957348679, 6735.528513004043, 6809.625470599165, 6816.625085620339, 6820.454363650995, 6821.420179284816, 6660.666966651745, 6725.0, 6775.559588626724, 6778.186617605918, 6785.0, 6480.675966201577, 6555.967220163869, 6572.2583515367505, 6612.636305003271, 6629.801105966829, 5043.470435604356, 5050.949490505048, 5051.74741262928, 5069.366329208849, 5074.238864170404, 6503.414692677682, 6646.202455705346, 6688.3646053624825, 6826.146731658543, 6890.38254916173, 6552.148220731275, 6556.868862622626, 6558.508111891531, 6601.53789234749, 6655.866882662416, 6501.6099034056215, 6539.0, 6548.377904099103, 6614.0, 6625.867482650224, 6400.487960963019, 6434.549581529248, 6456.709448074754, 6481.736061467968, 6553.639549824806, 6621.139251897415, 6648.972063520479, 6666.400023997669, 6667.799966001027, 6712.026756120433, 6351.333110023381, 6360.300366959723, 6361.713722882574, 6421.8715625688155, 6503.219613646393], "value": 5118.532490752759, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [863.9738817112258, 879.4327658660163, 884.677092861105, 885.9069797671244, 891.2780647675381, 18069.457916262516, 18998.620027599474, 19006.04969751512, 19015.429537113872, 20010.199592016303, 19801.702974455395, 19850.503737406572, 19868.304609338677, 19879.0, 19925.800741992574, 874.8425283448977, 918.8437965545874, 922.1101636920371, 922.1470140120396, 922.9538523073819, 890.6971629645919, 911.680911680913, 924.0943875002501, 926.2451102351562, 948.4451595816421, 988.1501908358806, 988.1600639456476, 988.3081842710096, 988.6885631026204, 994.6121012804975, 981.6024510073431, 984.1536278800262, 984.5963155106372, 985.1478471122499, 990.1336330710628, 720.2905138438633, 722.479814533535, 726.1467775363978, 727.6470911607865, 730.6748496918873, 17558.122093895254, 18243.087845607668, 18384.0, 18657.90671046636, 18671.346502872333, 20360.880151591657, 20389.8980505098, 20782.48043798902, 20795.064222110042, 21075.894620526953, 15706.685866282725, 16620.750688739678, 16696.332146714118, 16717.66564668712, 16760.58098547546, 3672.2839046385966, 3713.832877520518, 3713.9628603713886, 3719.6652301293016, 3728.328900797855, 129005.0, 130223.0, 130624.34687826691, 130634.0, 133718.0, 15373.38506459729, 15486.0, 15885.0, 15930.442434514967, 15981.840181598267, 28989.85505072461, 29664.851675741484, 29707.257318567208, 29732.851335743184, 30443.0, 7391.963040184764, 7704.460687751804, 8409.915900840913, 8501.0, 8639.827203455896, 4378.058717375779, 4471.8434854779925, 4476.529964353761, 4487.057717879216, 4487.349334346567, 5053.343065401475, 5173.1722924331925, 5179.0, 5190.532852043334, 5203.16749320107, 993.6621548673486, 997.9421812878372, 1009.0968583118068, 1014.60937539048, 1016.8474728790593, 607.4229481992037, 614.9754009839556, 616.3866952382408, 617.7343742190901, 636.474908200727, 10576.36541807494, 10893.509792059376, 11261.887381126084, 11287.774244515063, 12081.093917956188, 694.836713372357, 695.5339922252103, 697.9092717946635, 706.2372637551432, 713.1157364867527, 5248.212768084818, 5256.894862102737, 5363.597730170253, 5412.485813847678, 5412.891742165213, 107681.0, 109882.0, 111271.0, 112740.43629781799, 113403.0, 94467.0, 96192.0, 97004.51497742467, 99032.99999999856, 102925.48537257266, 82909.58545207356, 85664.0, 89060.10939890648, 90307.0, 90472.5476372627, 11111.277766945122, 11324.6036388726, 11420.600278990196, 11530.884691152982, 11653.184277100521, 39828.40257396083, 39874.601253987086, 40386.0, 40615.79692101521, 42663.36004959866, 67751.0, 77563.61218193873, 80685.19314807012, 81435.0, 82129.17870821455, 19763.901180494006, 19979.0, 20144.09351579212, 20308.59382812306, 20325.49186270355, 16844.831551684816, 21160.259390921245, 21599.0, 21856.344309670734, 22556.548869022856, 16386.918065409598, 16568.751468727736, 16624.50126496207, 16863.241154147847, 16868.0, 16557.66884662324, 16799.0, 17643.55891102733, 18119.547011324823, 18203.908980455013, 16727.498175054767, 17004.489865304062, 17043.914780426017, 17084.829151708323, 17099.572510687332, 12211.69470763238, 12232.082593805679, 12429.37853107323, 12880.0, 13117.278549679859, 12882.0, 13080.542181023618, 13124.868751312364, 13293.401796919356, 13408.0], "value": 8919.419776401875, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1476.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 108.0, 99.0, 107.0, 106.0, 109.0, 108.0, 99.0, 108.0, 111.0], "value": 105.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 118.0, 146.0, 181.0, 118.0, 145.0, 144.0, 122.0, 174.0, 122.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 162.0, 162.0, 162.0, 161.0, 163.0, 168.0, 163.0, 162.0], "value": 163.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 74.0, 176.0, 73.0, 73.0, 73.0, 92.0, 97.0, 74.0], "value": 88.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 127.0, 79.0, 80.0, 80.0, 132.0, 79.0, 79.0, 123.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 120.0, 114.0, 113.0, 114.0], "value": 114.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 148.0, 147.0, 148.0, 148.0, 149.0, 147.0, 150.0, 149.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 62.0, 58.0, 53.0, 53.0, 52.0, 52.0, 53.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 38.0, 38.0, 39.0, 38.0, 38.0, 38.0, 38.0, 38.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 74.0, 80.0, 74.0, 78.0, 72.0, 75.0, 74.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 119.0, 117.0, 117.0, 120.0, 130.0, 115.0, 119.0, 115.0, 158.0], "value": 123.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 168.0, 172.0, 168.0, 167.0, 169.0, 176.0, 172.0, 169.0, 179.0], "value": 171.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 66.0, 69.0, 64.0, 74.0, 61.0, 64.0, 63.0, 71.0], "value": 66.2, "unit": "score"}]}]} 11:28:05 INFO - Return code: 0 11:28:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:28:05 INFO - # TBPL SUCCESS # 11:28:05 INFO - Running post-action listener: _package_coverage_data 11:28:05 INFO - Running post-action listener: _resource_record_post_action 11:28:05 INFO - [mozharness: 2017-08-27 18:28:05.337188Z] Finished run-tests step (success) 11:28:05 INFO - Running post-run listener: _resource_record_post_run 11:28:05 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 536576; Write bytes: 444346368; Read time: 104; Write time: 298460 11:28:05 INFO - TinderboxPrint: CPU usage
13.1% 11:28:05 INFO - TinderboxPrint: I/O read bytes / time
536,576 / 104 11:28:05 INFO - TinderboxPrint: I/O write bytes / time
444,346,368 / 298,460 11:28:05 INFO - TinderboxPrint: CPU idle
8,245.8 (86.9%) 11:28:05 INFO - TinderboxPrint: CPU user
1,193.9 (12.6%) 11:28:05 INFO - TinderboxPrint: Swap in / out
0 / 0 11:28:05 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 119529472; Read time: 0; Write time: 103596 11:28:05 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:28:05 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 536576; Write bytes: 324816896; Read time: 104; Write time: 194864 11:28:05 INFO - Running post-run listener: _upload_blobber_files 11:28:05 INFO - Blob upload gear active. 11:28:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:28:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:28:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:28:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:28:06 INFO - (blobuploader) - INFO - Open directory for files ... 11:28:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:28:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:28:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:28:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:28:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:28:07 INFO - (blobuploader) - INFO - Done attempting. 11:28:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:28:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:28:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:28:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:28:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:28:14 INFO - (blobuploader) - INFO - Done attempting. 11:28:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:28:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:28:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:28:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:28:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:28:15 INFO - (blobuploader) - INFO - Done attempting. 11:28:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:28:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:28:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:28:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:28:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:28:16 INFO - (blobuploader) - INFO - Done attempting. 11:28:16 INFO - (blobuploader) - INFO - Iteration through files over. 11:28:16 INFO - Return code: 0 11:28:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:28:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:28:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fad1ed7a464ee4733e8222b58cd7dbbeecc382b1ac1b60382f047ea95e9408015fd5a57c00b2b8a9d8c47c08c02811df6e10f2fee2d76ed91eea2c95e38e784", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a02057e701b6e6b0e1be7fb55e04556a7156eb32b7715ad9fd6d8bb8cb955c12f5a79c7ecb9e162fb8ab7ba0cd74186534029ed83d9f79513de7d1cdf6de7e6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d06a5e928dda204311b985c9d8903855e1738bf2a05ee5326b6efa8838b32b97b4932e7471c37dcf9914a74fe2927dd911d21739cd0a7ce952743b8ee84665e8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae3bca079fc7ec11cb2565cd8222f2952fc74d2bb407fb81a9539d787bc8db15562b7265f822c8606e8771340762cfeb04bc56288597dbf594e67a39f409dc62"} 11:28:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:28:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:28:16 INFO - Contents: 11:28:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fad1ed7a464ee4733e8222b58cd7dbbeecc382b1ac1b60382f047ea95e9408015fd5a57c00b2b8a9d8c47c08c02811df6e10f2fee2d76ed91eea2c95e38e784", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a02057e701b6e6b0e1be7fb55e04556a7156eb32b7715ad9fd6d8bb8cb955c12f5a79c7ecb9e162fb8ab7ba0cd74186534029ed83d9f79513de7d1cdf6de7e6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d06a5e928dda204311b985c9d8903855e1738bf2a05ee5326b6efa8838b32b97b4932e7471c37dcf9914a74fe2927dd911d21739cd0a7ce952743b8ee84665e8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae3bca079fc7ec11cb2565cd8222f2952fc74d2bb407fb81a9539d787bc8db15562b7265f822c8606e8771340762cfeb04bc56288597dbf594e67a39f409dc62"} 11:28:16 INFO - Running post-run listener: copy_logs_to_upload_dir 11:28:16 INFO - Copying logs to upload dir... 11:28:16 INFO - mkdir: /builds/slave/test/build/upload/logs 11:28:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.385921 ========= master_lag: 0.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-08-27 11:28:16.871370) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-27 11:28:16.877527) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fad1ed7a464ee4733e8222b58cd7dbbeecc382b1ac1b60382f047ea95e9408015fd5a57c00b2b8a9d8c47c08c02811df6e10f2fee2d76ed91eea2c95e38e784", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a02057e701b6e6b0e1be7fb55e04556a7156eb32b7715ad9fd6d8bb8cb955c12f5a79c7ecb9e162fb8ab7ba0cd74186534029ed83d9f79513de7d1cdf6de7e6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d06a5e928dda204311b985c9d8903855e1738bf2a05ee5326b6efa8838b32b97b4932e7471c37dcf9914a74fe2927dd911d21739cd0a7ce952743b8ee84665e8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae3bca079fc7ec11cb2565cd8222f2952fc74d2bb407fb81a9539d787bc8db15562b7265f822c8606e8771340762cfeb04bc56288597dbf594e67a39f409dc62"} build_url:https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011220 build_url: 'https://queue.taskcluster.net/v1/task/J7HevFiySL2NFW_sLpKW8Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fad1ed7a464ee4733e8222b58cd7dbbeecc382b1ac1b60382f047ea95e9408015fd5a57c00b2b8a9d8c47c08c02811df6e10f2fee2d76ed91eea2c95e38e784", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a02057e701b6e6b0e1be7fb55e04556a7156eb32b7715ad9fd6d8bb8cb955c12f5a79c7ecb9e162fb8ab7ba0cd74186534029ed83d9f79513de7d1cdf6de7e6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d06a5e928dda204311b985c9d8903855e1738bf2a05ee5326b6efa8838b32b97b4932e7471c37dcf9914a74fe2927dd911d21739cd0a7ce952743b8ee84665e8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae3bca079fc7ec11cb2565cd8222f2952fc74d2bb407fb81a9539d787bc8db15562b7265f822c8606e8771340762cfeb04bc56288597dbf594e67a39f409dc62"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-27 11:28:16.909866) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 11:28:16.910225) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1503857242.526909-1301817841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004539 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 11:28:16.964663) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-27 11:28:16.964968) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-27 11:28:16.965261) ========= ========= Total master_lag: 0.36 =========