builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-035 starttime: 1508961800.67 results: success (0) revision: 921ae95a11f5bc29a129892d88e69e70264e20cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-25 13:03:20.665800) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-25 13:03:20.666483) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-25 13:03:20.667023) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-25 13:03:20.683818) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-25 13:03:20.684134) ========= 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=ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-25 13:03:20-- 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% 45.5M=0s 2017-10-25 13:03:20 (45.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129715 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-25 13:03:20.832292) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-25 13:03:20.832646) ========= 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=ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019803 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-25 13:03:20.882700) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-25 13:03:20.883027) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 921ae95a11f5bc29a129892d88e69e70264e20cf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 921ae95a11f5bc29a129892d88e69e70264e20cf --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=ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-25 13:03:20,932 truncating revision to first 12 chars 2017-10-25 13:03:20,933 Setting DEBUG logging. 2017-10-25 13:03:20,933 attempt 1/10 2017-10-25 13:03:20,933 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/921ae95a11f5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-25 13:03:21,747 unpacking tar archive at: mozilla-beta-921ae95a11f5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.192796 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-25 13:03:22.093845) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-25 13:03:22.094391) ========= script_repo_revision: 921ae95a11f5bc29a129892d88e69e70264e20cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-25 13:03:22.095006) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-25 13:03:22.095487) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-25 13:03:22.111912) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2017-10-25 13:03:22.112486) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:03:22 INFO - MultiFileLogger online at 20171025 13:03:22 in /builds/slave/test 13:03:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 13:03:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:03:22 INFO - {'append_to_log': False, 13:03:22 INFO - 'base_work_dir': '/builds/slave/test', 13:03:22 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 13:03:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:03:22 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 13:03:22 INFO - 'buildbot_json_path': 'buildprops.json', 13:03:22 INFO - 'code_coverage': False, 13:03:22 INFO - 'config_files': ('talos/linux_config.py',), 13:03:22 INFO - 'default_actions': ('clobber', 13:03:22 INFO - 'read-buildbot-config', 13:03:22 INFO - 'download-and-extract', 13:03:22 INFO - 'populate-webroot', 13:03:22 INFO - 'create-virtualenv', 13:03:22 INFO - 'install', 13:03:22 INFO - 'setup-mitmproxy', 13:03:22 INFO - 'run-tests'), 13:03:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:03:22 INFO - 'disable_ccov_upload': False, 13:03:22 INFO - 'disable_stylo': False, 13:03:22 INFO - 'download_minidump_stackwalk': True, 13:03:22 INFO - 'download_symbols': 'ondemand', 13:03:22 INFO - 'enable_stylo': False, 13:03:22 INFO - 'enable_webrender': False, 13:03:22 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 13:03:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:03:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:03:22 INFO - 'gecko_profile': False, 13:03:22 INFO - 'gecko_profile_interval': 0, 13:03:22 INFO - 'installer_path': 'installer.exe', 13:03:22 INFO - 'log_level': 'info', 13:03:22 INFO - 'log_name': 'talos', 13:03:22 INFO - 'log_to_console': True, 13:03:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:03:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:03:22 INFO - 'opt_config_files': (), 13:03:22 INFO - 'pip_index': False, 13:03:22 INFO - 'suite': 'dromaeojs-e10s', 13:03:22 INFO - 'system_bits': '32', 13:03:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:03:22 INFO - 'title': 'talos-linux64-ix-035', 13:03:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:03:22 INFO - 'use_talos_json': True, 13:03:22 INFO - 'verify': 'False', 13:03:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:03:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:03:22 INFO - 'work_dir': 'build'} 13:03:22 INFO - [mozharness: 2017-10-25 20:03:22.304247Z] Running clobber step. 13:03:22 INFO - Running pre-action listener: _resource_record_pre_action 13:03:22 INFO - Running main action method: clobber 13:03:22 INFO - rmtree: /builds/slave/test/build 13:03:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:03:23 INFO - Running post-action listener: _resource_record_post_action 13:03:23 INFO - [mozharness: 2017-10-25 20:03:23.380423Z] Finished clobber step (success) 13:03:23 INFO - [mozharness: 2017-10-25 20:03:23.380520Z] Running read-buildbot-config step. 13:03:23 INFO - Running pre-action listener: _resource_record_pre_action 13:03:23 INFO - Running main action method: read_buildbot_config 13:03:23 INFO - Using buildbot properties: 13:03:23 INFO - { 13:03:23 INFO - "project": "", 13:03:23 INFO - "product": "firefox", 13:03:23 INFO - "who": "ryanvm@gmail.com", 13:03:23 INFO - "installer_path": "public/build/target.tar.bz2", 13:03:23 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 13:03:23 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 13:03:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 13:03:23 INFO - "stage_platform": "linux64", 13:03:23 INFO - "basedir": "/builds/slave/test", 13:03:23 INFO - "buildnumber": 152, 13:03:23 INFO - "platform": "ubuntu64_hw", 13:03:23 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 13:03:23 INFO - "slavebuilddir": "test", 13:03:23 INFO - "taskId": "Ayuoep_hT7mWUZdoMoIz9g", 13:03:23 INFO - "branch": "mozilla-beta", 13:03:23 INFO - "script_repo_revision": "production", 13:03:23 INFO - "revision": "921ae95a11f5bc29a129892d88e69e70264e20cf", 13:03:23 INFO - "slavename": "talos-linux64-ix-035", 13:03:23 INFO - "repo_path": "releases/mozilla-beta" 13:03:23 INFO - } 13:03:23 INFO - Finding installer, test and symbols from parent task. 13:03:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ayuoep_hT7mWUZdoMoIz9g'}, attempt #1 13:03:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ayuoep_hT7mWUZdoMoIz9g'}, attempt #1 13:03:24 INFO - Task dependencies: GJmw3ptRT3O9Q-vnx-CwPg 13:03:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg'}, attempt #1 13:03:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.tar.bz2 13:03:24 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.test_packages.json 13:03:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.crashreporter-symbols.zip 13:03:24 INFO - Running post-action listener: _resource_record_post_action 13:03:24 INFO - [mozharness: 2017-10-25 20:03:24.790891Z] Finished read-buildbot-config step (success) 13:03:24 INFO - [mozharness: 2017-10-25 20:03:24.791107Z] Running download-and-extract step. 13:03:24 INFO - Running pre-action listener: _resource_record_pre_action 13:03:24 INFO - Running main action method: download_and_extract 13:03:24 INFO - mkdir: /builds/slave/test/build/tests 13:03:24 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')]} 13:03:24 INFO - trying https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.test_packages.json 13:03:24 INFO - Downloading https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 13:03:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 13:03:25 INFO - Downloaded 989 bytes. 13:03:25 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 13:03:25 INFO - Using the following test package requirements: 13:03:25 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 13:03:25 INFO - u'common': [u'target.common.tests.zip'], 13:03:25 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 13:03:25 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 13:03:25 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 13:03:25 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 13:03:25 INFO - u'mozbase': [u'target.common.tests.zip'], 13:03:25 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 13:03:25 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 13:03:25 INFO - u'web-platform': [u'target.common.tests.zip', 13:03:25 INFO - u'target.web-platform.tests.tar.gz'], 13:03:25 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 13:03:25 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 13:03:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.common.tests.zip 13:03:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:03:25 INFO - Fetch https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.common.tests.zip into memory 13:03:27 INFO - Content-Length response header: 55177995 13:03:27 INFO - Bytes received: 55177995 13:03:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.talos.tests.zip 13:03:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 13:03:37 INFO - Fetch https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.talos.tests.zip into memory 13:03:38 INFO - Content-Length response header: 14509096 13:03:38 INFO - Bytes received: 14509096 13:03:38 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')]} 13:03:38 INFO - trying https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.tar.bz2 13:03:38 INFO - Downloading https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 13:03:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 13:03:40 INFO - Downloaded 53558965 bytes. 13:03:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.tar.bz2 13:03:40 INFO - mkdir: /builds/slave/test/properties 13:03:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:03:40 INFO - Writing to file /builds/slave/test/properties/build_url 13:03:40 INFO - Contents: 13:03:40 INFO - build_url:https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.tar.bz2 13:03:40 INFO - Running post-action listener: _resource_record_post_action 13:03:40 INFO - Running post-action listener: find_tests_for_verification 13:03:40 INFO - Running post-action listener: set_extra_try_arguments 13:03:40 INFO - [mozharness: 2017-10-25 20:03:40.383757Z] Finished download-and-extract step (success) 13:03:40 INFO - [mozharness: 2017-10-25 20:03:40.383946Z] Running populate-webroot step. 13:03:40 INFO - Running pre-action listener: _resource_record_pre_action 13:03:40 INFO - Running main action method: populate_webroot 13:03:40 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:03:40 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:03:40 INFO - 'tests': ['tresize', 13:03:40 INFO - 'tcanvasmark']}, 13:03:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:03:40 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:03:40 INFO - 'tests': ['dromaeo_css', 13:03:40 INFO - 'kraken']}, 13:03:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:03:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:03:40 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 13:03:40 INFO - 'talos_options': ['--disable-stylo'], 13:03:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:03:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:03:40 INFO - 'tests': ['damp', 'tps']}, 13:03:40 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 13:03:40 INFO - 'talos_options': ['--disable-stylo'], 13:03:40 INFO - 'tests': ['damp', 'tps']}, 13:03:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:03:40 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:03:40 INFO - 'tests': ['dromaeo_dom']}, 13:03:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 13:03:40 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:03:40 INFO - 'tests': ['basic_compositor_video', 13:03:40 INFO - 'glvideo']}, 13:03:40 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 13:03:40 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 13:03:40 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 13:03:40 INFO - 'talos_options': ['--disable-stylo'], 13:03:40 INFO - 'tests': ['ts_paint_webext', 13:03:40 INFO - 'tp5o_webext']}, 13:03:40 INFO - 'other-e10s': {'tests': ['a11yr', 13:03:40 INFO - 'ts_paint', 13:03:40 INFO - 'tpaint', 13:03:40 INFO - 'sessionrestore', 13:03:40 INFO - 'sessionrestore_many_windows', 13:03:40 INFO - 'sessionrestore_no_auto_restore', 13:03:40 INFO - 'tabpaint']}, 13:03:40 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:03:40 INFO - 'tests': ['a11yr', 13:03:40 INFO - 'ts_paint', 13:03:40 INFO - 'tpaint', 13:03:40 INFO - 'sessionrestore', 13:03:40 INFO - 'sessionrestore_many_windows', 13:03:40 INFO - 'sessionrestore_no_auto_restore', 13:03:40 INFO - 'tabpaint']}, 13:03:40 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 13:03:40 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 13:03:40 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:03:40 INFO - 'tests': ['perf_reftest_singletons']}, 13:03:40 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:03:40 INFO - 'tests': ['bloom_basic']}, 13:03:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:03:40 INFO - 'tsvgr_opacity', 13:03:40 INFO - 'tart', 13:03:40 INFO - 'tscrollx', 13:03:40 INFO - 'tsvg_static']}, 13:03:40 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 13:03:40 INFO - 'tests': ['tsvgx', 13:03:40 INFO - 'tsvgr_opacity', 13:03:40 INFO - 'tart', 13:03:40 INFO - 'tscrollx', 13:03:40 INFO - 'tsvg_static']}, 13:03:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:03:40 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 13:03:40 INFO - 'talos_options': ['--disable-stylo'], 13:03:40 INFO - 'tests': ['tp5o']}, 13:03:40 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 13:03:40 INFO - 'talos_options': ['--mitmproxy', 13:03:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 13:03:40 INFO - '--firstNonBlankPaint'], 13:03:40 INFO - 'tests': ['tp6_google', 13:03:40 INFO - 'tp6_youtube', 13:03:40 INFO - 'tp6_amazon', 13:03:40 INFO - 'tp6_facebook']}, 13:03:40 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 13:03:40 INFO - 'talos_options': ['--disable-stylo', 13:03:40 INFO - '--mitmproxy', 13:03:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 13:03:40 INFO - '--firstNonBlankPaint'], 13:03:40 INFO - 'tests': ['tp6_google', 13:03:40 INFO - 'tp6_youtube', 13:03:40 INFO - 'tp6_amazon', 13:03:40 INFO - 'tp6_facebook']}, 13:03:40 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 13:03:40 INFO - 'talos_options': ['--stylo-threads=1', 13:03:40 INFO - '--mitmproxy', 13:03:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 13:03:40 INFO - '--firstNonBlankPaint'], 13:03:40 INFO - 'tests': ['tp6_google', 13:03:40 INFO - 'tp6_youtube', 13:03:40 INFO - 'tp6_amazon', 13:03:40 INFO - 'tp6_facebook']}, 13:03:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:03:40 INFO - 'talos_options': ['--xperf_path', 13:03:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:03:40 INFO - 'tests': ['tp5n']}, 13:03:40 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 13:03:40 INFO - 'talos_options': ['--disable-stylo', 13:03:40 INFO - '--xperf_path', 13:03:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:03:40 INFO - 'tests': ['tp5n']}}} 13:03:40 INFO - Running post-action listener: _resource_record_post_action 13:03:40 INFO - [mozharness: 2017-10-25 20:03:40.398979Z] Finished populate-webroot step (success) 13:03:40 INFO - [mozharness: 2017-10-25 20:03:40.399067Z] Running create-virtualenv step. 13:03:40 INFO - Running pre-action listener: _resource_record_pre_action 13:03:40 INFO - Running main action method: create_virtualenv 13:03:40 INFO - Creating virtualenv /builds/slave/test/build/venv 13:03:40 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:03:40 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 13:03:40 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 13:03:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:03:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:03:41 INFO - Installing distribute.............................................................................................................................................................................................done. 13:03:41 INFO - Installing pip...............done. 13:03:41 INFO - Return code: 0 13:03:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:03:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:03:41 INFO - Reading from file tmpfile_stdout 13:03:41 INFO - Output received: 13:03:41 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 13:03:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:03:41 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')]} 13:03:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:03:41 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')]} 13:03:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:03:41 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 0x25fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2969a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d090>, '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 0x296dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2967f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', '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 13:03:41 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 13:03:41 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 13:03:41 INFO - Using env: {'DISPLAY': ':0', 13:03:41 INFO - 'HOME': '/home/cltbld', 13:03:41 INFO - 'LANG': 'en_US.UTF-8', 13:03:41 INFO - 'LANGUAGE': 'en_US:en', 13:03:41 INFO - 'LOGNAME': 'cltbld', 13:03:41 INFO - 'MAIL': '/var/mail/cltbld', 13:03:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:41 INFO - 'MOZ_NO_REMOTE': '1', 13:03:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:03:41 INFO - 'NO_EM_RESTART': '1', 13:03:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:03:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:03:41 INFO - 'PWD': '/builds/slave/test', 13:03:41 INFO - 'SHELL': '/bin/bash', 13:03:41 INFO - 'SHLVL': '1', 13:03:41 INFO - 'TERM': 'linux', 13:03:41 INFO - 'TMOUT': '86400', 13:03:41 INFO - 'USER': 'cltbld', 13:03:41 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', 13:03:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:03:41 INFO - '_': '/tools/buildbot/bin/python'} 13:03:43 INFO - Ignoring indexes: http://pypi.python.org/simple/ 13:03:43 INFO - Downloading/unpacking pip>=1.5 13:03:43 INFO - Running setup.py egg_info for package pip 13:03:43 INFO - warning: no files found matching 'pip/cacert.pem' 13:03:43 INFO - warning: no files found matching '*.html' under directory 'docs' 13:03:43 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 13:03:43 INFO - no previously-included directories found matching 'docs/_build/_sources' 13:03:43 INFO - Installing collected packages: pip 13:03:43 INFO - Found existing installation: pip 1.1 13:03:43 INFO - Uninstalling pip: 13:03:43 INFO - Successfully uninstalled pip 13:03:43 INFO - Running setup.py install for pip 13:03:43 INFO - warning: no files found matching 'pip/cacert.pem' 13:03:43 INFO - warning: no files found matching '*.html' under directory 'docs' 13:03:43 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 13:03:43 INFO - no previously-included directories found matching 'docs/_build/_sources' 13:03:43 INFO - Installing pip script to /builds/slave/test/build/venv/bin 13:03:43 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 13:03:43 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 13:03:43 INFO - Successfully installed pip 13:03:43 INFO - Cleaning up... 13:03:43 INFO - Return code: 0 13:03:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:03:43 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')]} 13:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:03:43 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')]} 13:03:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:03:43 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 0x25fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2969a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d090>, '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 0x296dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2967f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', '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 13:03:43 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 13:03:43 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 13:03:43 INFO - Using env: {'DISPLAY': ':0', 13:03:43 INFO - 'HOME': '/home/cltbld', 13:03:43 INFO - 'LANG': 'en_US.UTF-8', 13:03:43 INFO - 'LANGUAGE': 'en_US:en', 13:03:43 INFO - 'LOGNAME': 'cltbld', 13:03:43 INFO - 'MAIL': '/var/mail/cltbld', 13:03:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:43 INFO - 'MOZ_NO_REMOTE': '1', 13:03:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:03:43 INFO - 'NO_EM_RESTART': '1', 13:03:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:03:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:03:43 INFO - 'PWD': '/builds/slave/test', 13:03:43 INFO - 'SHELL': '/bin/bash', 13:03:43 INFO - 'SHLVL': '1', 13:03:43 INFO - 'TERM': 'linux', 13:03:43 INFO - 'TMOUT': '86400', 13:03:43 INFO - 'USER': 'cltbld', 13:03:43 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', 13:03:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:03:43 INFO - '_': '/tools/buildbot/bin/python'} 13:03:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:03:43 INFO - Downloading/unpacking psutil>=3.1.1 13:03:43 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 13:03:43 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 13:03:43 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 13:03:43 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 13:03:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:03:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:03:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:03:44 INFO - Installing collected packages: psutil 13:03:44 INFO - Running setup.py install for psutil 13:03:44 INFO - building 'psutil._psutil_linux' extension 13:03:44 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 13:03:45 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 13:03:45 INFO - building 'psutil._psutil_posix' extension 13:03:45 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 13:03:45 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 13:03:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:03:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:03:45 INFO - Successfully installed psutil 13:03:45 INFO - Cleaning up... 13:03:45 INFO - Return code: 0 13:03:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:03:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:03:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:03:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:03:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:03:45 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 0x25fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2969a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d090>, '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 0x296dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2967f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', '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 13:03:45 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 13:03:45 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 13:03:45 INFO - Using env: {'DISPLAY': ':0', 13:03:45 INFO - 'HOME': '/home/cltbld', 13:03:45 INFO - 'LANG': 'en_US.UTF-8', 13:03:45 INFO - 'LANGUAGE': 'en_US:en', 13:03:45 INFO - 'LOGNAME': 'cltbld', 13:03:45 INFO - 'MAIL': '/var/mail/cltbld', 13:03:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:45 INFO - 'MOZ_NO_REMOTE': '1', 13:03:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:03:45 INFO - 'NO_EM_RESTART': '1', 13:03:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:03:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:03:45 INFO - 'PWD': '/builds/slave/test', 13:03:45 INFO - 'SHELL': '/bin/bash', 13:03:45 INFO - 'SHLVL': '1', 13:03:45 INFO - 'TERM': 'linux', 13:03:45 INFO - 'TMOUT': '86400', 13:03:45 INFO - 'USER': 'cltbld', 13:03:45 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', 13:03:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:03:45 INFO - '_': '/tools/buildbot/bin/python'} 13:03:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:03:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:03:46 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 13:03:46 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 13:03:46 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 13:03:46 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 13:03:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:03:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:03:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:03:47 INFO - Installing collected packages: mozsystemmonitor 13:03:47 INFO - Running setup.py install for mozsystemmonitor 13:03:47 INFO - Successfully installed mozsystemmonitor 13:03:47 INFO - Cleaning up... 13:03:48 INFO - Return code: 0 13:03:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:03:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:03:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:03:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:03:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:03:48 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 0x25fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2969a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d090>, '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 0x296dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2967f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', '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 13:03:48 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 13:03:48 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 13:03:48 INFO - Using env: {'DISPLAY': ':0', 13:03:48 INFO - 'HOME': '/home/cltbld', 13:03:48 INFO - 'LANG': 'en_US.UTF-8', 13:03:48 INFO - 'LANGUAGE': 'en_US:en', 13:03:48 INFO - 'LOGNAME': 'cltbld', 13:03:48 INFO - 'MAIL': '/var/mail/cltbld', 13:03:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:48 INFO - 'MOZ_NO_REMOTE': '1', 13:03:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:03:48 INFO - 'NO_EM_RESTART': '1', 13:03:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:03:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:03:48 INFO - 'PWD': '/builds/slave/test', 13:03:48 INFO - 'SHELL': '/bin/bash', 13:03:48 INFO - 'SHLVL': '1', 13:03:48 INFO - 'TERM': 'linux', 13:03:48 INFO - 'TMOUT': '86400', 13:03:48 INFO - 'USER': 'cltbld', 13:03:48 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', 13:03:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:03:48 INFO - '_': '/tools/buildbot/bin/python'} 13:03:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:03:48 INFO - Downloading/unpacking jsonschema==2.5.1 13:03:48 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 13:03:48 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 13:03:48 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 13:03:48 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 13:03:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:03:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:03:49 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 13:03:49 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 13:03:49 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 13:03:49 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 13:03:49 INFO - Downloading functools32-3.2.3-2.tar.gz 13:03:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:03:49 INFO - warning: no files found matching '*.txt' 13:03:49 INFO - no previously-included directories found matching 'build' 13:03:49 INFO - no previously-included directories found matching 'dist' 13:03:49 INFO - no previously-included directories found matching '.git*' 13:03:49 INFO - Installing collected packages: jsonschema, functools32 13:03:50 INFO - Running setup.py install for functools32 13:03:50 INFO - warning: no files found matching '*.txt' 13:03:50 INFO - no previously-included directories found matching 'build' 13:03:50 INFO - no previously-included directories found matching 'dist' 13:03:50 INFO - no previously-included directories found matching '.git*' 13:03:50 INFO - Successfully installed jsonschema functools32 13:03:50 INFO - Cleaning up... 13:03:50 INFO - Return code: 0 13:03:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:03:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:03:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2969a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d090>, '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 0x296dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2967f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', '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 13:03:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:03:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:03:50 INFO - Using env: {'DISPLAY': ':0', 13:03:50 INFO - 'HOME': '/home/cltbld', 13:03:50 INFO - 'LANG': 'en_US.UTF-8', 13:03:50 INFO - 'LANGUAGE': 'en_US:en', 13:03:50 INFO - 'LOGNAME': 'cltbld', 13:03:50 INFO - 'MAIL': '/var/mail/cltbld', 13:03:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:50 INFO - 'MOZ_NO_REMOTE': '1', 13:03:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:03:50 INFO - 'NO_EM_RESTART': '1', 13:03:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:03:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:03:50 INFO - 'PWD': '/builds/slave/test', 13:03:50 INFO - 'SHELL': '/bin/bash', 13:03:50 INFO - 'SHLVL': '1', 13:03:50 INFO - 'TERM': 'linux', 13:03:50 INFO - 'TMOUT': '86400', 13:03:50 INFO - 'USER': 'cltbld', 13:03:50 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', 13:03:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:03:50 INFO - '_': '/tools/buildbot/bin/python'} 13:03:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:03:51 INFO - Cleaning up... 13:03:51 INFO - Return code: 0 13:03:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:03:51 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')]} 13:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:03:51 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')]} 13:03:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:03:51 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 0x25fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2969a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d090>, '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 0x296dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2967f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', '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 13:03:51 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 13:03:51 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 13:03:51 INFO - Using env: {'DISPLAY': ':0', 13:03:51 INFO - 'HOME': '/home/cltbld', 13:03:51 INFO - 'LANG': 'en_US.UTF-8', 13:03:51 INFO - 'LANGUAGE': 'en_US:en', 13:03:51 INFO - 'LOGNAME': 'cltbld', 13:03:51 INFO - 'MAIL': '/var/mail/cltbld', 13:03:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:51 INFO - 'MOZ_NO_REMOTE': '1', 13:03:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:03:51 INFO - 'NO_EM_RESTART': '1', 13:03:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:03:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:03:51 INFO - 'PWD': '/builds/slave/test', 13:03:51 INFO - 'SHELL': '/bin/bash', 13:03:51 INFO - 'SHLVL': '1', 13:03:51 INFO - 'TERM': 'linux', 13:03:51 INFO - 'TMOUT': '86400', 13:03:51 INFO - 'USER': 'cltbld', 13:03:51 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', 13:03:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:03:51 INFO - '_': '/tools/buildbot/bin/python'} 13:03:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:03:51 INFO - Downloading/unpacking blobuploader==1.2.4 13:03:51 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 13:03:51 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 13:03:51 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 13:03:51 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 13:03:52 INFO - Downloading blobuploader-1.2.4.tar.gz 13:03:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:03:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:03:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:03:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:03:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:03:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:03:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:03:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:03:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:03:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:03:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:03:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:03:52 INFO - Downloading docopt-0.6.1.tar.gz 13:03:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:03:52 INFO - Installing collected packages: blobuploader, requests, docopt 13:03:52 INFO - Running setup.py install for blobuploader 13:03:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:03:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:03:53 INFO - Running setup.py install for requests 13:03:53 INFO - Running setup.py install for docopt 13:03:53 INFO - Successfully installed blobuploader requests docopt 13:03:53 INFO - Cleaning up... 13:03:53 INFO - Return code: 0 13:03:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:03:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:03:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:03:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:03:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:03:53 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 0x25fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2969a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d090>, '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 0x296dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2967f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', '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 13:03:53 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 13:03:53 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 13:03:53 INFO - Using env: {'DISPLAY': ':0', 13:03:53 INFO - 'HOME': '/home/cltbld', 13:03:53 INFO - 'LANG': 'en_US.UTF-8', 13:03:53 INFO - 'LANGUAGE': 'en_US:en', 13:03:53 INFO - 'LOGNAME': 'cltbld', 13:03:53 INFO - 'MAIL': '/var/mail/cltbld', 13:03:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:53 INFO - 'MOZ_NO_REMOTE': '1', 13:03:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:03:53 INFO - 'NO_EM_RESTART': '1', 13:03:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:03:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:03:53 INFO - 'PWD': '/builds/slave/test', 13:03:53 INFO - 'SHELL': '/bin/bash', 13:03:53 INFO - 'SHLVL': '1', 13:03:53 INFO - 'TERM': 'linux', 13:03:53 INFO - 'TMOUT': '86400', 13:03:53 INFO - 'USER': 'cltbld', 13:03:53 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', 13:03:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:03:53 INFO - '_': '/tools/buildbot/bin/python'} 13:03:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:03:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:03:53 INFO - Running setup.py (path:/tmp/pip-AKbVDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:03:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:03:53 INFO - Running setup.py (path:/tmp/pip-F3zkSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:03:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:03:53 INFO - Running setup.py (path:/tmp/pip-n4Q8an-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:03:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:03:53 INFO - Running setup.py (path:/tmp/pip-jilCqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:03:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:03:54 INFO - Running setup.py (path:/tmp/pip-CN6ZP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:03:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:03:54 INFO - Running setup.py (path:/tmp/pip-R4TszI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:03:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:03:54 INFO - Running setup.py (path:/tmp/pip-i8YF87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:03:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:03:54 INFO - Running setup.py (path:/tmp/pip-EfZFHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:03:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:03:54 INFO - Running setup.py (path:/tmp/pip-1H05G3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:03:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:03:54 INFO - Running setup.py (path:/tmp/pip-UhOFy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:03:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:03:54 INFO - Running setup.py (path:/tmp/pip-bpvi7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:03:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:03:54 INFO - Running setup.py (path:/tmp/pip-jvvBfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:03:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:03:54 INFO - Running setup.py (path:/tmp/pip-EvUHr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:03:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:03:54 INFO - Running setup.py (path:/tmp/pip-RVHvV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:03:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:03:54 INFO - Running setup.py (path:/tmp/pip-_mjCwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:03:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:03:54 INFO - Running setup.py (path:/tmp/pip-yvPHj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:03:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:03:54 INFO - Running setup.py (path:/tmp/pip-0GU4cH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:03:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:03:54 INFO - Running setup.py install for manifestparser 13:03:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:03:55 INFO - Running setup.py install for mozcrash 13:03:55 INFO - Running setup.py install for mozdebug 13:03:55 INFO - Running setup.py install for mozdevice 13:03:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:03:55 INFO - Running setup.py install for mozfile 13:03:55 INFO - Running setup.py install for mozhttpd 13:03:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:03:55 INFO - Running setup.py install for mozinfo 13:03:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:03:55 INFO - Running setup.py install for mozInstall 13:03:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:03:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:03:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:03:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:03:55 INFO - Running setup.py install for mozleak 13:03:55 INFO - Running setup.py install for mozlog 13:03:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:03:55 INFO - Running setup.py install for moznetwork 13:03:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:03:56 INFO - Running setup.py install for mozprocess 13:03:56 INFO - Running setup.py install for mozprofile 13:03:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:03:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:03:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:03:56 INFO - Running setup.py install for mozrunner 13:03:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:03:56 INFO - Running setup.py install for mozscreenshot 13:03:56 INFO - Running setup.py install for moztest 13:03:56 INFO - Running setup.py install for mozversion 13:03:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:03:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:03:56 INFO - Cleaning up... 13:03:56 INFO - Return code: 0 13:03:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:03:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:03:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:03:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:03:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:03:56 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 0x25fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2969a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d090>, '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 0x296dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2967f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', '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 13:03:56 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 13:03:56 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 13:03:56 INFO - Using env: {'DISPLAY': ':0', 13:03:56 INFO - 'HOME': '/home/cltbld', 13:03:56 INFO - 'LANG': 'en_US.UTF-8', 13:03:56 INFO - 'LANGUAGE': 'en_US:en', 13:03:56 INFO - 'LOGNAME': 'cltbld', 13:03:56 INFO - 'MAIL': '/var/mail/cltbld', 13:03:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:56 INFO - 'MOZ_NO_REMOTE': '1', 13:03:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:03:56 INFO - 'NO_EM_RESTART': '1', 13:03:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:03:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:03:56 INFO - 'PWD': '/builds/slave/test', 13:03:56 INFO - 'SHELL': '/bin/bash', 13:03:56 INFO - 'SHLVL': '1', 13:03:56 INFO - 'TERM': 'linux', 13:03:56 INFO - 'TMOUT': '86400', 13:03:56 INFO - 'USER': 'cltbld', 13:03:56 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', 13:03:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:03:56 INFO - '_': '/tools/buildbot/bin/python'} 13:03:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:03:56 INFO - Running setup.py (path:/tmp/pip-LoY_W0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:03:57 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)) 13:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:03:57 INFO - Running setup.py (path:/tmp/pip-AJhTuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:03:57 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)) 13:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:03:57 INFO - Running setup.py (path:/tmp/pip-y8tDln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:03:57 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)) 13:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:03:57 INFO - Running setup.py (path:/tmp/pip-QP21cb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:03:57 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)) 13:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:03:57 INFO - Running setup.py (path:/tmp/pip-Vc9JlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:03:57 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)) 13:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:03:57 INFO - Running setup.py (path:/tmp/pip-P1d3pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:03:57 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)) 13:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:03:57 INFO - Running setup.py (path:/tmp/pip-wOsWqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:03:57 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)) 13:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:03:57 INFO - Running setup.py (path:/tmp/pip-2YfYN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:03:57 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)) 13:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:03:57 INFO - Running setup.py (path:/tmp/pip-IDHS0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:03:57 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)) 13:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:03:57 INFO - Running setup.py (path:/tmp/pip-TLvAA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:03:57 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)) 13:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:03:57 INFO - Running setup.py (path:/tmp/pip-7UKdGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:03:57 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)) 13:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:03:57 INFO - Running setup.py (path:/tmp/pip-FTA77l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:03:57 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)) 13:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:03:57 INFO - Running setup.py (path:/tmp/pip-lxe3DD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:03:57 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)) 13:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:03:57 INFO - Running setup.py (path:/tmp/pip-G_o6gM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:03:57 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)) 13:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:03:57 INFO - Running setup.py (path:/tmp/pip-zZl8XN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:03:58 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)) 13:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:03:58 INFO - Running setup.py (path:/tmp/pip-s27zBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:03:58 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)) 13:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:03:58 INFO - Running setup.py (path:/tmp/pip-AOE04c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:03:58 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)) 13:03:58 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)) 13:03:58 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)) 13:03:58 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)) 13:03:58 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)) 13:03:58 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)) 13:03:58 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)) 13:03:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:03: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 13:03: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 13:03: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 13:03: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 13:03:59 INFO - Downloading blessings-1.6.tar.gz 13:03:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:03:59 INFO - Installing collected packages: blessings 13:03:59 INFO - Running setup.py install for blessings 13:03:59 INFO - Successfully installed blessings 13:03:59 INFO - Cleaning up... 13:03:59 INFO - Return code: 0 13:03:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:03:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:03:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:03:59 INFO - Reading from file tmpfile_stdout 13:03:59 INFO - Current package versions: 13:03:59 INFO - argparse == 1.2.1 13:03:59 INFO - blessings == 1.6 13:03:59 INFO - blobuploader == 1.2.4 13:03:59 INFO - docopt == 0.6.1 13:03:59 INFO - functools32 == 3.2.3-2 13:03:59 INFO - jsonschema == 2.5.1 13:03:59 INFO - manifestparser == 1.1 13:03:59 INFO - mozInstall == 1.13 13:03:59 INFO - mozcrash == 1.0 13:03:59 INFO - mozdebug == 0.1 13:03:59 INFO - mozdevice == 0.51 13:03:59 INFO - mozfile == 1.2 13:03:59 INFO - mozhttpd == 0.7 13:03:59 INFO - mozinfo == 0.10 13:03:59 INFO - mozleak == 0.1 13:03:59 INFO - mozlog == 3.5 13:03:59 INFO - moznetwork == 0.27 13:03:59 INFO - mozprocess == 0.25 13:03:59 INFO - mozprofile == 0.28 13:03:59 INFO - mozrunner == 6.13 13:03:59 INFO - mozscreenshot == 0.1 13:03:59 INFO - mozsystemmonitor == 0.3 13:03:59 INFO - moztest == 0.8 13:03:59 INFO - mozversion == 1.4 13:03:59 INFO - psutil == 3.1.1 13:03:59 INFO - requests == 1.2.3 13:03:59 INFO - wsgiref == 0.1.2 13:03:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:03:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:03:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:03:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:03:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:03:59 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 0x25fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2969a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d090>, '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 0x296dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2967f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', '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 13:03:59 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 13:03:59 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 13:03:59 INFO - Using env: {'DISPLAY': ':0', 13:03:59 INFO - 'HOME': '/home/cltbld', 13:03:59 INFO - 'LANG': 'en_US.UTF-8', 13:03:59 INFO - 'LANGUAGE': 'en_US:en', 13:03:59 INFO - 'LOGNAME': 'cltbld', 13:03:59 INFO - 'MAIL': '/var/mail/cltbld', 13:03:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:59 INFO - 'MOZ_NO_REMOTE': '1', 13:03:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:03:59 INFO - 'NO_EM_RESTART': '1', 13:03:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:03:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:03:59 INFO - 'PWD': '/builds/slave/test', 13:03:59 INFO - 'SHELL': '/bin/bash', 13:03:59 INFO - 'SHLVL': '1', 13:03:59 INFO - 'TERM': 'linux', 13:03:59 INFO - 'TMOUT': '86400', 13:03:59 INFO - 'USER': 'cltbld', 13:03:59 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', 13:03:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:03:59 INFO - '_': '/tools/buildbot/bin/python'} 13:03:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:03:59 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)) 13:03:59 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)) 13:03:59 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)) 13:03:59 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)) 13:03:59 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)) 13:03:59 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)) 13:03:59 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)) 13:03:59 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)) 13:03:59 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)) 13:03:59 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 13:03:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:03:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:03:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:03:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:04:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 13:04:01 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)) 13:04:01 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)) 13:04:01 INFO - Installing collected packages: simplejson 13:04:01 INFO - Running setup.py install for simplejson 13:04:01 INFO - building 'simplejson._speedups' extension 13:04:01 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 13:04:02 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 13:04:02 INFO - Successfully installed simplejson 13:04:02 INFO - Cleaning up... 13:04:02 INFO - Return code: 0 13:04:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:04:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:04:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:04:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:04:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:04:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25fe1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2969a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d090>, '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 0x296dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2967f90>, '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': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', '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 13:04:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:04:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:04:02 INFO - Using env: {'DISPLAY': ':0', 13:04:02 INFO - 'HOME': '/home/cltbld', 13:04:02 INFO - 'LANG': 'en_US.UTF-8', 13:04:02 INFO - 'LANGUAGE': 'en_US:en', 13:04:02 INFO - 'LOGNAME': 'cltbld', 13:04:02 INFO - 'MAIL': '/var/mail/cltbld', 13:04:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:04:02 INFO - 'MOZ_NO_REMOTE': '1', 13:04:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:04:02 INFO - 'NO_EM_RESTART': '1', 13:04:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:04:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:04:02 INFO - 'PWD': '/builds/slave/test', 13:04:02 INFO - 'SHELL': '/bin/bash', 13:04:02 INFO - 'SHLVL': '1', 13:04:02 INFO - 'TERM': 'linux', 13:04:02 INFO - 'TMOUT': '86400', 13:04:02 INFO - 'USER': 'cltbld', 13:04:02 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', 13:04:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:04:02 INFO - '_': '/tools/buildbot/bin/python'} 13:04:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:04:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:04:02 INFO - Cleaning up... 13:04:02 INFO - Return code: 0 13:04:02 INFO - Running post-action listener: _resource_record_post_action 13:04:02 INFO - Running post-action listener: _start_resource_monitoring 13:04:02 INFO - Starting resource monitoring. 13:04:02 INFO - [mozharness: 2017-10-25 20:04:02.457070Z] Finished create-virtualenv step (success) 13:04:02 INFO - [mozharness: 2017-10-25 20:04:02.457578Z] Running install step. 13:04:02 INFO - Running pre-action listener: _resource_record_pre_action 13:04:02 INFO - Running main action method: install 13:04:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:04:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:04:02 INFO - Reading from file tmpfile_stdout 13:04:02 INFO - Detecting whether we're running mozinstall >=1.0... 13:04:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:04:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:04:02 INFO - Reading from file tmpfile_stdout 13:04:02 INFO - Output received: 13:04:02 INFO - Usage: mozinstall [options] installer 13:04:02 INFO - Options: 13:04:02 INFO - -h, --help show this help message and exit 13:04:02 INFO - -d DEST, --destination=DEST 13:04:02 INFO - Directory to install application into. [default: 13:04:02 INFO - "/builds/slave/test"] 13:04:02 INFO - --app=APP Application being installed. [default: firefox] 13:04:02 INFO - mkdir: /builds/slave/test/build/application 13:04:02 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'] 13:04:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 13:04:17 INFO - Reading from file tmpfile_stdout 13:04:17 INFO - Output received: 13:04:17 INFO - /builds/slave/test/build/application/firefox/firefox 13:04:17 INFO - Running post-action listener: _resource_record_post_action 13:04:17 INFO - [mozharness: 2017-10-25 20:04:17.349692Z] Finished install step (success) 13:04:17 INFO - [mozharness: 2017-10-25 20:04:17.350035Z] Running setup-mitmproxy step. 13:04:17 INFO - Running pre-action listener: _resource_record_pre_action 13:04:17 INFO - Running main action method: setup_mitmproxy 13:04:17 INFO - Skipping: mitmproxy is not required 13:04:17 INFO - Running post-action listener: _resource_record_post_action 13:04:17 INFO - [mozharness: 2017-10-25 20:04:17.351651Z] Finished setup-mitmproxy step (success) 13:04:17 INFO - [mozharness: 2017-10-25 20:04:17.351963Z] Running run-tests step. 13:04:17 INFO - Running pre-action listener: _resource_record_pre_action 13:04:17 INFO - Running pre-action listener: _set_gcov_prefix 13:04:17 INFO - Running main action method: run_tests 13:04:17 WARNING - Try message not found. 13:04:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:04:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:04:17 INFO - Python 2.7.3 13:04:17 INFO - Return code: 0 13:04:17 INFO - grabbing minidump binary from tooltool 13:04:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:04:17 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 0x296d090>, '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 0x296dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2967f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:04:17 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 13:04:17 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 13:04:17 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 13:04:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:04:17 INFO - Return code: 0 13:04:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:04:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:04:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:04:17 INFO - ENV: RUST_BACKTRACE is now full 13:04:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:04:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:04:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 13:04:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-035 --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 13:04:17 INFO - Using env: {'DISPLAY': ':0', 13:04:17 INFO - 'HOME': '/home/cltbld', 13:04:17 INFO - 'LANG': 'en_US.UTF-8', 13:04:17 INFO - 'LANGUAGE': 'en_US:en', 13:04:17 INFO - 'LOGNAME': 'cltbld', 13:04:17 INFO - 'MAIL': '/var/mail/cltbld', 13:04:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:04:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:04:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:04:17 INFO - 'MOZ_NO_REMOTE': '1', 13:04:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:04:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:04:17 INFO - 'NO_EM_RESTART': '1', 13:04:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:04:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:04:17 INFO - 'PWD': '/builds/slave/test', 13:04:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:04:17 INFO - 'RUST_BACKTRACE': 'full', 13:04:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 13:04:17 INFO - 'SHELL': '/bin/bash', 13:04:17 INFO - 'SHLVL': '1', 13:04:17 INFO - 'TERM': 'linux', 13:04:17 INFO - 'TMOUT': '86400', 13:04:17 INFO - 'USER': 'cltbld', 13:04:17 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', 13:04:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:04:17 INFO - '_': '/tools/buildbot/bin/python'} 13:04:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 13:04:17 INFO - mozversion application_buildid: 20171025183916 13:04:17 INFO - mozversion application_changeset: 921ae95a11f5bc29a129892d88e69e70264e20cf 13:04:17 INFO - mozversion application_display_name: Firefox 13:04:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:04:17 INFO - mozversion application_name: Firefox 13:04:17 INFO - mozversion application_remotingname: firefox 13:04:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:04:17 INFO - mozversion application_vendor: Mozilla 13:04:17 INFO - mozversion application_version: 57.0 13:04:17 INFO - mozversion platform_buildid: 20171025183916 13:04:17 INFO - mozversion platform_changeset: 921ae95a11f5bc29a129892d88e69e70264e20cf 13:04:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:04:17 INFO - mozversion platform_version: 57.0 13:04:17 INFO - using testdate: 1508961857 13:04:17 INFO - actual date: 1508961857 13:04:17 INFO - starting webserver on 'localhost:52615' 13:04:17 INFO - SUITE-START | Running 2 tests 13:04:17 INFO - TEST-START | dromaeo_css 13:04:17 INFO - Initialising browser for dromaeo_css test... 13:04:17 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52615/getInfo.html -profile /tmp/tmpsbpv_c/profile 13:04:17 INFO - TEST-INFO | started process 14560 (/builds/slave/test/build/application/firefox/firefox http://localhost:52615/getInfo.html) 13:04:22 INFO - TEST-INFO | 14560: exit 0 13:04:23 INFO - Browser initialized. 13:04:23 INFO - Running cycle 1/1 for dromaeo_css test... 13:04:23 INFO - Using env: {'DISPLAY': ':0', 13:04:23 INFO - 'HOME': '/home/cltbld', 13:04:23 INFO - 'JSGC_DISABLE_POISONING': '1', 13:04:23 INFO - 'LANG': 'en_US.UTF-8', 13:04:23 INFO - 'LANGUAGE': 'en_US:en', 13:04:23 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 13:04:23 INFO - 'LOGNAME': 'cltbld', 13:04:23 INFO - 'MAIL': '/var/mail/cltbld', 13:04:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:04:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:04:23 INFO - 'MOZ_CRASHREPORTER': '1', 13:04:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:04:23 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 13:04:23 INFO - 'MOZ_NO_REMOTE': '1', 13:04:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:04:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:04:23 INFO - 'NO_EM_RESTART': '1', 13:04:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:04:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:04:23 INFO - 'PWD': '/builds/slave/test', 13:04:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:04:23 INFO - 'RUST_BACKTRACE': 'full', 13:04:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 13:04:23 INFO - 'SHELL': '/bin/bash', 13:04:23 INFO - 'SHLVL': '1', 13:04:23 INFO - 'TERM': 'linux', 13:04:23 INFO - 'TMOUT': '86400', 13:04:23 INFO - 'USER': 'cltbld', 13:04:23 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', 13:04:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:04:23 INFO - '_': '/tools/buildbot/bin/python'} 13:04:23 INFO - TEST-INFO | started process 14793 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsbpv_c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 13:04:23 INFO - PID 14793 | 13:04:23 INFO - PID 14793 | (/builds/slave/test/build/application/firefox/firefox:14842): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 13:04:23 INFO - PID 14793 | 13:04:34 INFO - PID 14793 | RSS: Main: 142516224 13:04:34 INFO - PID 14793 | 13:07:31 INFO - PID 14793 | Cycle 1(1): loaded http://localhost:52615/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52615/tests/dromaeo/cssquery-ext.html) 13:07:32 INFO - PID 14793 | RSS: Main: 145842176 13:07:32 INFO - PID 14793 | 13:10:34 INFO - PID 14793 | Cycle 1(1): loaded http://localhost:52615/tests/dromaeo/cssquery-ext.html (next: http://localhost:52615/tests/dromaeo/cssquery-jquery.html) 13:10:34 INFO - PID 14793 | RSS: Main: 144670720 13:10:34 INFO - PID 14793 | 13:13:36 INFO - PID 14793 | Cycle 1(1): loaded http://localhost:52615/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52615/tests/dromaeo/cssquery-mootools.html) 13:13:36 INFO - PID 14793 | RSS: Main: 145330176 13:13:36 INFO - PID 14793 | 13:16:38 INFO - PID 14793 | Cycle 1(1): loaded http://localhost:52615/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52615/tests/dromaeo/cssquery-prototype.html) 13:16:39 INFO - PID 14793 | RSS: Main: 145887232 13:16:39 INFO - PID 14793 | 13:19:42 INFO - PID 14793 | Cycle 1(1): loaded http://localhost:52615/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52615/tests/dromaeo/cssquery-yui.html) 13:19:42 INFO - PID 14793 | RSS: Main: 145014784 13:19:42 INFO - PID 14793 | 13:22:44 INFO - PID 14793 | Cycle 1(1): loaded http://localhost:52615/tests/dromaeo/cssquery-yui.html (next: http://localhost:52615/tests/dromaeo/cssquery-dojo.html) 13:22:44 INFO - PID 14793 | RSS: Main: 149286912 13:22:44 INFO - PID 14793 | 13:22:44 INFO - PID 14793 | __start_tp_report 13:22:44 INFO - PID 14793 | _x_x_mozilla_page_load 13:22:44 INFO - PID 14793 | _x_x_mozilla_page_load_details 13:22:44 INFO - PID 14793 | |i|pagename|runs| 13:22:44 INFO - PID 14793 | |0;dojo.html;4015.6385925266723;4043.312636851735;4048.9190216195693;4062.1063366059475;4076.9184616307675;6507.967460162698;6607.603543787376;6640.966795166018;6689.230738465078;6699.765508207213;6627.900581491282;6644.667766611674;6665;6707.12807335047;6730.091437655915;5966.105084237377;5997.940020599804;6017.909731354033;6025.9096113558335;6065.575409721321;5868.325142608591;6011.609245399035;6074.5747797654185;6083.513318934474;6098.32918378978;6021.825743979925;6027.909581356261;6079.483243924272;6090.725917333709;6095.908561371583;5982.8803423931495;5997.460228579449;6027.306859711101;6065.969670151665;6073.908891366589;5863.76544938202;5882.6470411775;5883.117532370136;5931.822045338604;5948.40515948406;6540.869182616369;6548.607083575001;6605.669716514155;6618.86762264752;6624.53628246023;7155.212926578072;7158.534695244844;7163.212046674862;7237.421006319534;7249.311315425026;7210.67551960163;7235.674394652201;7285.490015698903;7289.453291003196;7299.343059124704;6637.535372523974;6685;6711.66441677919;6721.0926524919;6735.696893639798;7346.081739782528;7347.55914645123;7351.411887049023;7441.144268409094;7450.96274518624;6984.196817366068;7001.054857594159;7010.719571217196;7011.544249623758;7030.226675065739;7088.220295767462;7090.539114957614;7096.432285417154;7100.9289907100265;7202.53183543068;7136.92863071373;7198.244184360671;7199.424046076301;7201;7205.675744591506;7123.43012558994;7127.10911136108;7127.928720712726;7202.819929501804;7223.494355395177;5251.973740131275;5303.575713942875;5309.070912590281;5330.093884039723;5405.4324295949145;4913.9017219656125;4916.680415772963;4940.382452193476;4943.332650092263;4961.950380496149;6700.296468870698;6751.324867513209;6778.762743303961;6787.524873258824;6794.898076528856;7145.321194486515;7151.678174482161;7156.212816590171;7219.530730502501;7229.674664640103;6577.309382514863;6669.599824010575;6730.966345168243;6756.425703815133;6791.252962174157;5934.080217566284;6040.516758659297;6069.878602427927;6070.362611925772;6086.300075491286;7360.0431943848025;7474.364679002346;7478.439117066133;7501.549907005598;7597.506162099557;7163.570185788869;7169.498135130595;7240.891386629205;7264.854702906018;7270.781876543712;7431.851362972819;7450.022146024364;7460.440466965108;7511.661975211019;7540.736074237376;7040.471964602573;7111.288871112847;7120.60836653989;7137.321954414322;7141.357277845019;7086.043384143075;7099.290070992859;7127.216006239206;7197.172325182671;7232;6792.864142717217;6868.244493105654;6892.517523773287;6920;6938.167419909643;6952.895706564506;6977.302269772982;7017.228104908354;7029.0862187915245;7034.402075823612;6843.623600702009;6895.103636527219;6898.965505172442;6967.407770339375;6972.1284839395075;6826.93173068263;6908.274631163656;6999.440044796506;7006.089208402875;7082.18554866197;6771.898421523582;6822.692978816064;6859.485538584527;6860.656967151523;6884.51808373409 13:22:44 INFO - PID 14793 | |1;ext.html;957.186391567168;988.0465350936345;1005.9446730429828;1006.4967516241879;1016.5273147986187;45620;46551;46570.767146164304;46616.766916165456;46647.76676116623;45866.54133458665;46292.76853615735;46586.30120548195;46662.76668616661;46777.0644587108;8747.431416957894;8795.076516965722;8875.822483550324;8915.68795092172;8944.776380590472;8723.38936274461;8907.109289071106;8932.955335223314;8940;8942.687005954793;8857.379983401132;8896.288296936229;8931;8932.464052156858;8940.06129356418;8866.60100295485;8924.955375223148;8941.687040953568;8973.551322433917;8976.551172441352;8746.212840844355;8786;8801.383903126783;8833.60248788806;8853.601587928559;40815.387769183486;40971.590284097074;41048.58951410507;41094.178116437804;41226.38160427596;3843.5195600549932;3885.9028524286837;3888.6889048876023;3888.922221555553;3890.3191941410423;34070.31859362824;34113.488297675554;34115.658843411744;34132.31735365279;34180.31639367199;5650.124230744241;5695.060315048012;5702.144678298247;5721.170430287585;5742.885142297131;222287;225555;226259;227493;228544.99999999834;52656;53251.201231981555;53272.73363633235;53298.46701532935;53323.93352132935;54936.72531637396;55100;55332;55497;55775.721121394134;18168;18269.360572379905;18404.17181226848;18487.53781155482;18494.16776245072;15392;15458.149801760786;15465.458708945358;15466.5360039199;15493.922530387275;13570.660733481742;13620.863791362157;13627.18236905769;13675.863241367459;13878.722425551432;5404.756785944642;5422.864428389322;5581.6371935824045;5591.4688104629995;5646.713081202038;1126.1610100475198;1134.2513940799033;1145.2098052343856;1147.75897061618;1149.454009345571;25946;26067.217983460527;26069.348266293495;26103.725259612173;26142.73857261441;1230.501646833034;1234.1299383934304;1235.4131787401093;1249.537671061713;1261.1109168036512;13722.45110195601;13784;13854.653633659038;13982.300884955712;14173.929130354281;274762;276553;278975;285451;285726;62179.378206218265;62359.688201559606;62737;62744;62821.37178628246;1506.1791323728594;1562.3360071969369;1567.5454118305563;1568.6392129810208;1571.449992502615;29413.55879661764;29739.70260297369;29921.401571968872;30319;30807.845960770053;40528.392074118325;41082.589174109075;41366;41499.58500414957;41505;238234;238302;239141;239386;239448;35705;36253.818730906176;36444.45333319949;36510.087247819014;36612.26775464529;44629.55370446254;46242.30636540387;47004.29493557531;47022.76488617535;47059;33992;35093;35102.64897350994;35138.64861351456;35550.11124721903;35028.299434011686;35112.64887351094;35377;35430.8228458856;35641.82179089088;39637.2072558553;40982;41036.58963410447;41119;41232.58767412287;33798.32403351968;33883;34114.31771364609;34613;34966.125846853014;25282.620760688238;25471.872640636677;25653;26113.216603501925;26333 13:22:44 INFO - PID 14793 | |2;jquery.html;641.7753786174837;647.1716203259826;648.5589798936721;652.8074218105659;654.8624788794353;24583.63124553133;24921.25236242911;25029.874850625765;25513.872430637868;25798.226053218412;27350;27531.449371012568;27553;27645.861770691117;27664.308392290204;6800.829979250533;7020.192677842037;7232.13214414271;7235.276472352749;7255.165655949554;6937.340952609494;6958.652067396635;6959.547629404073;6968.407685346751;6972.79081627552;6931.757388491404;6944.236134025253;6947.548409353376;6948.722051117975;6949.617771022592;6853.588784672911;6881.483888708367;6903.171619405678;6944.1666999959825;6946.79159625212;6470.417662410406;6475.158229430144;6507.414332710032;6524.282328943812;6533.869322613568;30258.84870575655;32649.836750816332;32678.6732132678;32700;32773;2410.529946660402;2441.511697660475;2457.0294733580313;2458.1273647854932;2460.766227208412;19482.123304451332;19779.20883164686;19964.001799909947;20127.295544655866;20195.495112622157;2565.115035312814;2602.7267136950536;2611.451595165018;2627.1330460947843;2640.577507598794;144554;149130;160808;169421.15289423597;169490.99999999878;16917.7462338065;17665.293388264326;17725.025123618325;18047.909760451115;18071.367502137364;27900.302492437444;27926.743296551704;27951.860240699072;27955.860220698767;27971.860140699166;10922.781544369069;10930.836037459285;10952.288101273392;11083.833742493869;11106.666799996014;9805.656802012038;9811.067948544876;9850.556724947395;9881.555330010167;9926.354786938826;9962.651307204358;10053.69838904834;10114.443705596115;10120.240981926238;10537.681710023782;2878.438190010892;2977.6277965254344;2993.4162838246334;3018.170003249124;3111.4088323218516;1057.9375199234976;1059.7562560610947;1079.1097344690504;1080.9081228095542;1093.9070179034711;22108.2262120825;22395.55208895813;22475;22528.32415027552;22671.886640566692;1152.573547787324;1152.6311580294225;1154.099802154321;1172.0916289875245;1177.1524502358288;11769.882301176878;12433.37833108359;12449.25304481734;12453.315067671183;12465.875341246652;587873;595266;613455;639366;645485;123444.38277808733;123850.38074809569;131604;131681;131693;1145.7823013627385;1188.6018183908316;1256.4848412150975;1262.9368531573568;1315.684235783405;22945.88527057354;23249.3025209244;23270.301890943298;23346.88326558424;23355.53288934246;61795;62241.377586223556;62867.6856615714;63811.680941595;64005.67997159984;73628.63185684252;73892;74398.62800685962;76493;78692;16401.507954761375;16633.50099497017;16688.24902879349;16720;16738.330466781194;24862.751372486768;25022.37444063913;25102.37244068913;25304.11435599745;25442;20410;20732.481687957923;20741.27405540799;20864.165433382495;20904.1638334471;15953.04281743099;16701;17981.820181798015;18611.441656750318;18749.437516874517;20688.379348619565;20714.378568642966;20901.581968360242;21039.05324260381;21092.78907210908;16355.672886541965;16452.424165154163;16472.42346517914;16500.66998659996;16534.421295254608;20247.4938126548;20266;20381.898090509454;20390.592188155857;20575.897120514303 13:22:44 INFO - PID 14793 | |3;mootools.html;1466.4500812195427;1496.3341313115661;1514.7273490771656;1527.450117957536;1535.3398038843297;17576.47270581883;18286.90856545717;18309.908450457762;18311.17599708013;18336.724949125775;17801.198946047425;17921.193546290415;17930.10349482527;17932;17986.28054877803;3124.0783968729165;3141.764367672428;3180.7137357637807;3196.2488815128427;3204.423203823311;3092.8144311341393;3101.286704058068;3114.330418959923;3116.7194952454274;3126.1090589182086;3078.168894398511;3090.8300043497597;3125.796823206484;3126.515390114536;3181.5068664357063;3015.472292348852;3050.42042012017;3070.6008218931506;3087.243625311813;3088.90733278002;3062.5406189071596;3096.4735994880925;3110.6578276389578;3110.7200351968204;3114.283714745621;17319.134043297912;17705.645887082188;17709.557261068447;17732.645347093116;17737.467875963743;9043.231325337354;9313;9327.253819694408;9342.626294948193;9347.345685801996;6225.533085018642;6295.716692748837;6309.085182648536;6418.743250269984;6434.099226108302;2159.0931808640407;2194.264921251383;2234.5866014787152;2263.3549438409973;2276.658501224813;32205.838970804994;32373.838130809432;32644.6735532644;32651.18372040694;32692.83653581717;7348.926510734931;7370.189279179286;7607.250332423573;7719.922800772033;7732.110807257125;20610.58778824445;20796.896015519826;20817.791822081886;20830.1667933284;20867.686984695243;8766.956165219133;9050.27597792175;9081.500517471603;9093.954530227438;9097.818043639223;8067.193280672003;8161.510309381456;8225.629846656915;8279.420440569224;8362.958185209034;5886.440788125121;6124.448799608057;6142.907856382158;6171.537134714914;6211.81364559064;1777.8755487115777;1795.8024617292087;1798.4784412520223;1837.7427160197583;1845.6954602490707;871.2115535440437;1012.0233974214771;1012.6506355307407;1028.2647906746713;1038.9064984151462;9878.753031174278;9887.851682224773;9923.305368624127;10000.899991000142;10042.297039207331;834.5118105908111;836.1554829622125;836.93304535637;840.7183593496125;908.3369140527452;6071.241094863142;6599.8020059398295;6659.600423974577;6676;6722.29415911332;26137.477250455264;26679.33301667474;27039.729602703723;27378;27496.312592185357;25400.872995634905;26609.866950665124;26617;26866.731332686424;26890.193294201206;12516;12583.182093163916;12767.808482872764;12849.229046257255;12871.163374380823;9651.758706032406;10162.136218421221;10273.126784223126;10314.123299519324;10365.274430789772;22342.553148937255;22349.553008940056;22571.210007649654;22775.202867899545;23020.654690179996;23438.17966371169;23577.174798881955;23824.761752382256;23842.165524206568;23901.04395824217;13073.542426015425;13186.340682966005;13263.734725305247;13286.402111904794;13307;16714.832851671326;16918;16985.74521382205;16995.830041699424;17007.404740834012;13564.525241616497;13639.113457625619;13660.316984150952;13733;13781.655458613213;12665.81001285;13056.412461439068;13378.397972091085;13441;13524.526641567498;11177.72055698614;11239.325640461599;11367.829482557934;11377.146713996648;11380.146489013192;8813.383063185516;8975;9043.909560904307;9112.726618201465;9163.725088247364;9045.909540904508;9169.083091690778;9176.908230917605;9193.954030229806;9287.535623218677 13:22:44 INFO - PID 14793 | |4;prototype.html;533.9599530035246;552.9778808847647;560.529155509372;564.4017341617886;595.122194762725;6241.594296370733;6352.745890164394;6442.7745028924;6450.8064758057335;6489.1888513935755;6367.140436041133;6394.200724909387;6448.45188159007;6450.677466126686;6455.967720161392;5297.867553311179;5326.813561525348;5371.355437347524;5408.540274076689;5411.675299482044;5018.422881368635;5174.560162386202;5206.140986737184;5261.447548007462;5351.946480535184;5218.921716174261;5224.111900976843;5224.791008359661;5231.319928409302;5258.316418865542;5163.122269214243;5171.844844654629;5204.661696989683;5220.347456567929;5227.79088836446;4734.431868175807;4756.643251756098;4767.856964291077;4773.689710168863;4786.664933454659;6398.0083087121575;6422.775202867877;6451.806445806634;6456.354364563553;6462.838429039311;5982.521398288127;5987.550933679991;5996.580239383245;6039.3960603939695;6091.51267898567;6782.72869085236;6833.3166683331765;6847.760328388532;6871.896921546181;6933.479989000794;5244.842654720364;5250.212468129773;5267.683938963674;5269.657472264329;5299.470052994709;7040.577565346096;7051.294870512907;7061.940708893656;7066.505344625878;7166.53417527859;5376.73116344189;5403.43300442875;5429.701366424884;5449.3460784706085;5454.345478542521;5493.681516436027;5503.944960550423;5508.44915508446;5512.0905050667;5512.255845460892;5947.643560797087;5956.970215148896;6019.638821670714;6070.2412198475195;6089.177960975212;6583;6768.69540870662;6846.760363387357;6861.759838405632;6879.287687933781;1809.873308868393;1830.9267629294945;1843.6865732825452;1857.1921214271836;1891.5743957609702;1330.9534166304134;1337.2511393619604;1343.2209318595192;1351.7499262636247;1352.5604178641981;6214.161088253119;6238.719257633417;6400.199975003125;6418.582792118497;6424.26120996082;6894.965525172342;6942.444604431634;6989.916562932754;7044.542104763276;7095.432365410755;6337.588056776387;6432.51756118293;6464.806055818333;6499;6532.248791389051;3618.3306088373733;3656.3418584654487;3680.8895733128047;3695.796731179811;3698.4637227602384;7204.82120370184;7223.3025239068775;7236.240194779472;7303.707851685979;7323.450741194325;7154.499185056996;7254.419646428273;7292.307230813064;7310.232425595343;7313;7245.166805817293;7272.3818475429125;7288.453365997465;7324.121105467378;7339.633018349061;7015.508914375945;7026.473014524034;7046.401055910305;7062.516871457052;7080.221175670569;6958.234594194736;6984.720611175496;7012.597480504021;7034.437245020284;7089.858202836018;7130.251323611154;7176.677049532679;7241.275872412716;7243.167035790952;7276.672549735169;5419.160030195326;5454.4545545445135;5458.044842152687;5460.535854452416;5465.234867118527;6891.3797758202;7031.09774075393;7089.964550177217;7109.786706398816;7189.836471952324;6700.095487109178;6785.830354241183;6830.795076147723;6848.657567121524;6883.208431030295;6641.369069938348;6736.562123461959;6820.35206655367;6837.3162683731225;6860.794176174722;6668.233153187445;6716.589516201652;6725.798226053226;6742.190937087581;6822.11312529368;6800.31996800316;6809.93190068093;6818.93181068183;6854.383105520527;6880.69036893331;6708.161479815023;6725.19297684281;6753.662316884038;6769.661516924036;6812.965935170292 13:22:44 INFO - PID 14793 | |5;yui.html;1009.2077718990593;1014.9746256343591;1033.6537260017897;1036.80819048476;1046.3512622174253;20104.4973875653;20475.48811279715;20483.590328193426;21244.362669119913;21655.56688866222;20575.485612859688;20662;20772.376828695127;20812.479688007807;20826.271080512186;1071.2394200117906;1094.485591771866;1101.6254473479016;1104.1553211792946;1107;1101.1906248907046;1101.3612104979093;1108.384846410242;1109.9056580190654;1110.2450333773031;1015.5176291261629;1131.6718151735986;1132.2131118872387;1136.2443974756777;1148.3913525831322;1116.5087361560886;1122.4163435013747;1122.7586068995206;1122.825961975895;1124.4771181400647;833.6123702478316;837.1252041616525;837.4933165434885;842.1830824100613;843.7468759372164;21960.45098872525;22096.447588810246;22232.333030008966;22265.109395624157;22527.54944901109;24019;24378.390540236636;24381.512369752683;24687.382815429577;24796.25611231666;17526.64946701059;17933.37233196832;17982.1008949552;18134.637307253914;18163.63672726538;4158.4178215049915;4234.25900467417;4235.470566179228;4238.533761286256;4238.618524332825;131539;142394;155263;166478;167512.16243918703;18181.54546136357;18535.53661158455;18701.438956831316;18746.156422961;18784.53038674044;35066.64933350685;35089.649103509146;35320.64679353225;35509;35537.28925421477;9810.509474526245;9831.213502919893;9886.4562449066;9918.553665084944;9931.304808663324;4663.976680116578;4727.290906364073;4743.786529606176;4749.762511874393;4770.880727981828;6463.838404039842;6542.607443553402;6587.703553340111;6589.17635295588;6596.80209593713;1159.113278342069;1188.3701638131804;1194.8386967759416;1197.203859433467;1198.0355813569986;704.4082970304866;706.8444942112727;716.0941409117471;722.7253643615393;742.6658003898267;13428.992825538124;13869.791953120712;14197.645058873404;14494.420223191166;14707.117572945657;723.2405973727565;724.4819953733106;730.0728075344389;730.2624349407031;735.9659678152204;6522.543421960418;6556.4427023702565;6571.474282057424;6673.399394054559;6725.630090345078;147999;148885;149557;152481;157189;123264;125753;125933;126687;132763;111866;112137;114510;117287;118158;14006.579802605938;14084.577462676136;14104.717905641624;14222.502212422929;14606.926965365104;55798;57190.856182876945;57606.4239357601;57637;58399.70800145972;69649;70262.29737702558;71000;72826;74570;21701;21978.45053873666;21997.23009694653;22238.888805555867;22271.665925010813;23451.17920872761;23690.28929132129;23765.64351534763;23809.761902381448;24144.758552414252;21320.466988325417;21441.249556265455;21702.56594868062;21760.89119554392;22069.33791986243;19390.32133875364;19543.21827126899;19678.212871485553;19681.507962301057;19935.700964485834;20401.387958361272;20486.97565121768;20646.483837904172;20684;20909.89545052265;14213.360398781873;14276.500322488662;14343.282835858374;14350.138991660533;14494.275286235435;14604.26978651042;14673.826093912676;14809.18549479789;14839.03546269489;14956.326965286806 13:22:44 INFO - PID 14793 | __end_tp_report 13:22:44 INFO - PID 14793 | __start_cc_report 13:22:44 INFO - PID 14793 | _x_x_mozilla_cycle_collect,263 13:22:44 INFO - PID 14793 | __end_cc_report 13:22:44 INFO - PID 14793 | __startTimestamp1508962964447__endTimestamp 13:22:44 INFO - PID 14793 | 13:22:44 INFO - PID 14793 | ------- Summary: start ------- 13:22:44 INFO - PID 14793 | Number of tests: 6 13:22:44 INFO - PID 14793 | 13:22:44 INFO - PID 14793 | [#0] dojo.html Cycles:165 Average:6635.84 Median:6868.24 stddev:760.26 (11.1%) stddev-sans-first:734.28 13:22:44 INFO - PID 14793 | Values: 4015.6 4043.3 4048.9 4062.1 4076.9 6508.0 6607.6 6641.0 6689.2 6699.8 6627.9 6644.7 6665.0 6707.1 6730.1 5966.1 5997.9 6017.9 6025.9 6065.6 5868.3 6011.6 6074.6 6083.5 6098.3 6021.8 6027.9 6079.5 6090.7 6095.9 5982.9 5997.5 6027.3 6066.0 6073.9 5863.8 5882.6 5883.1 5931.8 5948.4 6540.9 6548.6 6605.7 6618.9 6624.5 7155.2 7158.5 7163.2 7237.4 7249.3 7210.7 7235.7 7285.5 7289.5 7299.3 6637.5 6685.0 6711.7 6721.1 6735.7 7346.1 7347.6 7351.4 7441.1 7451.0 6984.2 7001.1 7010.7 7011.5 7030.2 7088.2 7090.5 7096.4 7100.9 7202.5 7136.9 7198.2 7199.4 7201.0 7205.7 7123.4 7127.1 7127.9 7202.8 7223.5 5252.0 5303.6 5309.1 5330.1 5405.4 4913.9 4916.7 4940.4 4943.3 4962.0 6700.3 6751.3 6778.8 6787.5 6794.9 7145.3 7151.7 7156.2 7219.5 7229.7 6577.3 6669.6 6731.0 6756.4 6791.3 5934.1 6040.5 6069.9 6070.4 6086.3 7360.0 7474.4 7478.4 7501.5 7597.5 7163.6 7169.5 7240.9 7264.9 7270.8 7431.9 7450.0 7460.4 7511.7 7540.7 7040.5 7111.3 7120.6 7137.3 7141.4 7086.0 7099.3 7127.2 7197.2 7232.0 6792.9 6868.2 6892.5 6920.0 6938.2 6952.9 6977.3 7017.2 7029.1 7034.4 6843.6 6895.1 6899.0 6967.4 6972.1 6826.9 6908.3 6999.4 7006.1 7082.2 6771.9 6822.7 6859.5 6860.7 6884.5 13:22:44 INFO - PID 14793 | 13:22:44 INFO - PID 14793 | [#1] ext.html Cycles:180 Average:43368.09 Median:36477.27 stddev:64765.37 (177.5%) stddev-sans-first:64868.76 13:22:44 INFO - PID 14793 | Values: 957.2 988.0 1005.9 1006.5 1016.5 45620.0 46551.0 46570.8 46616.8 46647.8 45866.5 46292.8 46586.3 46662.8 46777.1 8747.4 8795.1 8875.8 8915.7 8944.8 8723.4 8907.1 8933.0 8940.0 8942.7 8857.4 8896.3 8931.0 8932.5 8940.1 8866.6 8925.0 8941.7 8973.6 8976.6 8746.2 8786.0 8801.4 8833.6 8853.6 40815.4 40971.6 41048.6 41094.2 41226.4 3843.5 3885.9 3888.7 3888.9 3890.3 34070.3 34113.5 34115.7 34132.3 34180.3 5650.1 5695.1 5702.1 5721.2 5742.9 222287.0 225555.0 226259.0 227493.0 228545.0 52656.0 53251.2 53272.7 53298.5 53323.9 54936.7 55100.0 55332.0 55497.0 55775.7 18168.0 18269.4 18404.2 18487.5 18494.2 15392.0 15458.1 15465.5 15466.5 15493.9 13570.7 13620.9 13627.2 13675.9 13878.7 5404.8 5422.9 5581.6 5591.5 5646.7 1126.2 1134.3 1145.2 1147.8 1149.5 25946.0 26067.2 26069.3 26103.7 26142.7 1230.5 1234.1 1235.4 1249.5 1261.1 13722.5 13784.0 13854.7 13982.3 14173.9 274762.0 276553.0 278975.0 285451.0 285726.0 62179.4 62359.7 62737.0 62744.0 62821.4 1506.2 1562.3 1567.5 1568.6 1571.4 29413.6 29739.7 29921.4 30319.0 30807.8 40528.4 41082.6 41366.0 41499.6 41505.0 238234.0 238302.0 239141.0 239386.0 239448.0 35705.0 36253.8 36444.5 36510.1 36612.3 44629.6 46242.3 47004.3 47022.8 47059.0 33992.0 35093.0 35102.6 35138.6 35550.1 35028.3 35112.6 35377.0 35430.8 35641.8 39637.2 40982.0 41036.6 41119.0 41232.6 33798.3 33883.0 34114.3 34613.0 34966.1 25282.6 25471.9 25653.0 26113.2 26333.0 13:22:44 INFO - PID 14793 | 13:22:44 INFO - PID 14793 | [#2] jquery.html Cycles:180 Average:40713.28 Median:2449.27 stddev:103107.74 (4209.7%) stddev-sans-first:103353.09 13:22:44 INFO - PID 14793 | Values: 641.8 647.2 648.6 652.8 654.9 24583.6 24921.3 25029.9 25513.9 25798.2 27350.0 27531.4 27553.0 27645.9 27664.3 6800.8 7020.2 7232.1 7235.3 7255.2 6937.3 6958.7 6959.5 6968.4 6972.8 6931.8 6944.2 6947.5 6948.7 6949.6 6853.6 6881.5 6903.2 6944.2 6946.8 6470.4 6475.2 6507.4 6524.3 6533.9 30258.8 32649.8 32678.7 32700.0 32773.0 2410.5 2441.5 2457.0 2458.1 2460.8 19482.1 19779.2 19964.0 20127.3 20195.5 2565.1 2602.7 2611.5 2627.1 2640.6 144554.0 149130.0 160808.0 169421.2 169491.0 16917.7 17665.3 17725.0 18047.9 18071.4 27900.3 27926.7 27951.9 27955.9 27971.9 10922.8 10930.8 10952.3 11083.8 11106.7 9805.7 9811.1 9850.6 9881.6 9926.4 9962.7 10053.7 10114.4 10120.2 10537.7 2878.4 2977.6 2993.4 3018.2 3111.4 1057.9 1059.8 1079.1 1080.9 1093.9 22108.2 22395.6 22475.0 22528.3 22671.9 1152.6 1152.6 1154.1 1172.1 1177.2 11769.9 12433.4 12449.3 12453.3 12465.9 587873.0 595266.0 613455.0 639366.0 645485.0 123444.4 123850.4 131604.0 131681.0 131693.0 1145.8 1188.6 1256.5 1262.9 1315.7 22945.9 23249.3 23270.3 23346.9 23355.5 61795.0 62241.4 62867.7 63811.7 64005.7 73628.6 73892.0 74398.6 76493.0 78692.0 16401.5 16633.5 16688.2 16720.0 16738.3 24862.8 25022.4 25102.4 25304.1 25442.0 20410.0 20732.5 20741.3 20864.2 20904.2 15953.0 16701.0 17981.8 18611.4 18749.4 20688.4 20714.4 20901.6 21039.1 21092.8 16355.7 16452.4 16472.4 16500.7 16534.4 20247.5 20266.0 20381.9 20390.6 20575.9 13:22:44 INFO - PID 14793 | 13:22:44 INFO - PID 14793 | [#3] mootools.html Cycles:180 Average:11170.45 Median:26878.46 stddev:8212.14 (30.6%) stddev-sans-first:8202.81 13:22:44 INFO - PID 14793 | Values: 1466.5 1496.3 1514.7 1527.5 1535.3 17576.5 18286.9 18309.9 18311.2 18336.7 17801.2 17921.2 17930.1 17932.0 17986.3 3124.1 3141.8 3180.7 3196.2 3204.4 3092.8 3101.3 3114.3 3116.7 3126.1 3078.2 3090.8 3125.8 3126.5 3181.5 3015.5 3050.4 3070.6 3087.2 3088.9 3062.5 3096.5 3110.7 3110.7 3114.3 17319.1 17705.6 17709.6 17732.6 17737.5 9043.2 9313.0 9327.3 9342.6 9347.3 6225.5 6295.7 6309.1 6418.7 6434.1 2159.1 2194.3 2234.6 2263.4 2276.7 32205.8 32373.8 32644.7 32651.2 32692.8 7348.9 7370.2 7607.3 7719.9 7732.1 20610.6 20796.9 20817.8 20830.2 20867.7 8767.0 9050.3 9081.5 9094.0 9097.8 8067.2 8161.5 8225.6 8279.4 8363.0 5886.4 6124.4 6142.9 6171.5 6211.8 1777.9 1795.8 1798.5 1837.7 1845.7 871.2 1012.0 1012.7 1028.3 1038.9 9878.8 9887.9 9923.3 10000.9 10042.3 834.5 836.2 836.9 840.7 908.3 6071.2 6599.8 6659.6 6676.0 6722.3 26137.5 26679.3 27039.7 27378.0 27496.3 25400.9 26609.9 26617.0 26866.7 26890.2 12516.0 12583.2 12767.8 12849.2 12871.2 9651.8 10162.1 10273.1 10314.1 10365.3 22342.6 22349.6 22571.2 22775.2 23020.7 23438.2 23577.2 23824.8 23842.2 23901.0 13073.5 13186.3 13263.7 13286.4 13307.0 16714.8 16918.0 16985.7 16995.8 17007.4 13564.5 13639.1 13660.3 13733.0 13781.7 12665.8 13056.4 13378.4 13441.0 13524.5 11177.7 11239.3 11367.8 11377.1 11380.1 8813.4 8975.0 9043.9 9112.7 9163.7 9045.9 9169.1 9176.9 9194.0 9287.5 13:22:44 INFO - PID 14793 | 13:22:44 INFO - PID 14793 | [#4] prototype.html Cycles:180 Average:5852.52 Median:6450.74 stddev:1633.15 (25.3%) stddev-sans-first:1588.19 13:22:44 INFO - PID 14793 | Values: 534.0 553.0 560.5 564.4 595.1 6241.6 6352.7 6442.8 6450.8 6489.2 6367.1 6394.2 6448.5 6450.7 6456.0 5297.9 5326.8 5371.4 5408.5 5411.7 5018.4 5174.6 5206.1 5261.4 5351.9 5218.9 5224.1 5224.8 5231.3 5258.3 5163.1 5171.8 5204.7 5220.3 5227.8 4734.4 4756.6 4767.9 4773.7 4786.7 6398.0 6422.8 6451.8 6456.4 6462.8 5982.5 5987.6 5996.6 6039.4 6091.5 6782.7 6833.3 6847.8 6871.9 6933.5 5244.8 5250.2 5267.7 5269.7 5299.5 7040.6 7051.3 7061.9 7066.5 7166.5 5376.7 5403.4 5429.7 5449.3 5454.3 5493.7 5503.9 5508.4 5512.1 5512.3 5947.6 5957.0 6019.6 6070.2 6089.2 6583.0 6768.7 6846.8 6861.8 6879.3 1809.9 1830.9 1843.7 1857.2 1891.6 1331.0 1337.3 1343.2 1351.7 1352.6 6214.2 6238.7 6400.2 6418.6 6424.3 6895.0 6942.4 6989.9 7044.5 7095.4 6337.6 6432.5 6464.8 6499.0 6532.2 3618.3 3656.3 3680.9 3695.8 3698.5 7204.8 7223.3 7236.2 7303.7 7323.5 7154.5 7254.4 7292.3 7310.2 7313.0 7245.2 7272.4 7288.5 7324.1 7339.6 7015.5 7026.5 7046.4 7062.5 7080.2 6958.2 6984.7 7012.6 7034.4 7089.9 7130.3 7176.7 7241.3 7243.2 7276.7 5419.2 5454.5 5458.0 5460.5 5465.2 6891.4 7031.1 7090.0 7109.8 7189.8 6700.1 6785.8 6830.8 6848.7 6883.2 6641.4 6736.6 6820.4 6837.3 6860.8 6668.2 6716.6 6725.8 6742.2 6822.1 6800.3 6809.9 6818.9 6854.4 6880.7 6708.2 6725.2 6753.7 6769.7 6813.0 13:22:44 INFO - PID 14793 | 13:22:44 INFO - PID 14793 | [#5] yui.html Cycles:180 Average:28907.83 Median:20610.98 stddev:41367.89 (200.7%) stddev-sans-first:41430.90 13:22:44 INFO - PID 14793 | Values: 1009.2 1015.0 1033.7 1036.8 1046.4 20104.5 20475.5 20483.6 21244.4 21655.6 20575.5 20662.0 20772.4 20812.5 20826.3 1071.2 1094.5 1101.6 1104.2 1107.0 1101.2 1101.4 1108.4 1109.9 1110.2 1015.5 1131.7 1132.2 1136.2 1148.4 1116.5 1122.4 1122.8 1122.8 1124.5 833.6 837.1 837.5 842.2 843.7 21960.5 22096.4 22232.3 22265.1 22527.5 24019.0 24378.4 24381.5 24687.4 24796.3 17526.6 17933.4 17982.1 18134.6 18163.6 4158.4 4234.3 4235.5 4238.5 4238.6 131539.0 142394.0 155263.0 166478.0 167512.2 18181.5 18535.5 18701.4 18746.2 18784.5 35066.6 35089.6 35320.6 35509.0 35537.3 9810.5 9831.2 9886.5 9918.6 9931.3 4664.0 4727.3 4743.8 4749.8 4770.9 6463.8 6542.6 6587.7 6589.2 6596.8 1159.1 1188.4 1194.8 1197.2 1198.0 704.4 706.8 716.1 722.7 742.7 13429.0 13869.8 14197.6 14494.4 14707.1 723.2 724.5 730.1 730.3 736.0 6522.5 6556.4 6571.5 6673.4 6725.6 147999.0 148885.0 149557.0 152481.0 157189.0 123264.0 125753.0 125933.0 126687.0 132763.0 111866.0 112137.0 114510.0 117287.0 118158.0 14006.6 14084.6 14104.7 14222.5 14606.9 55798.0 57190.9 57606.4 57637.0 58399.7 69649.0 70262.3 71000.0 72826.0 74570.0 21701.0 21978.5 21997.2 22238.9 22271.7 23451.2 23690.3 23765.6 23809.8 24144.8 21320.5 21441.2 21702.6 21760.9 22069.3 19390.3 19543.2 19678.2 19681.5 19935.7 20401.4 20487.0 20646.5 20684.0 20909.9 14213.4 14276.5 14343.3 14350.1 14494.3 14604.3 14673.8 14809.2 14839.0 14956.3 13:22:44 INFO - PID 14793 | -------- Summary: end -------- 13:22:44 INFO - PID 14793 | 13:22:45 INFO - TEST-INFO | 14793: exit 0 13:22:45 INFO - TEST-OK | dromaeo_css | took 1107331ms 13:22:45 INFO - TEST-START | kraken 13:22:45 INFO - Initialising browser for kraken test... 13:22:45 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52615/getInfo.html -profile /tmp/tmpvShbBS/profile 13:22:45 INFO - TEST-INFO | started process 15164 (/builds/slave/test/build/application/firefox/firefox http://localhost:52615/getInfo.html) 13:22:49 INFO - TEST-INFO | 15164: exit 0 13:22:49 INFO - Browser initialized. 13:22:49 INFO - Running cycle 1/1 for kraken test... 13:22:49 INFO - Using env: {'DISPLAY': ':0', 13:22:49 INFO - 'HOME': '/home/cltbld', 13:22:49 INFO - 'JSGC_DISABLE_POISONING': '1', 13:22:49 INFO - 'LANG': 'en_US.UTF-8', 13:22:49 INFO - 'LANGUAGE': 'en_US:en', 13:22:49 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 13:22:49 INFO - 'LOGNAME': 'cltbld', 13:22:49 INFO - 'MAIL': '/var/mail/cltbld', 13:22:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:22:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:22:49 INFO - 'MOZ_CRASHREPORTER': '1', 13:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:49 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 13:22:49 INFO - 'MOZ_NO_REMOTE': '1', 13:22:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:22:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:22:49 INFO - 'NO_EM_RESTART': '1', 13:22:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:49 INFO - 'PWD': '/builds/slave/test', 13:22:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:22:49 INFO - 'RUST_BACKTRACE': 'full', 13:22:49 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 13:22:49 INFO - 'SHELL': '/bin/bash', 13:22:49 INFO - 'SHLVL': '1', 13:22:49 INFO - 'TERM': 'linux', 13:22:49 INFO - 'TMOUT': '86400', 13:22:49 INFO - 'USER': 'cltbld', 13:22:49 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473', 13:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:49 INFO - '_': '/tools/buildbot/bin/python'} 13:22:49 INFO - TEST-INFO | started process 15393 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvShbBS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 13:22:50 INFO - PID 15393 | 13:22:50 INFO - PID 15393 | (/builds/slave/test/build/application/firefox/firefox:15442): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 13:22:50 INFO - PID 15393 | 13:23:00 INFO - PID 15393 | RSS: Main: 144089088 13:23:00 INFO - PID 15393 | 13:23:03 INFO - PID 15393 | Cycle 1(1): loaded http://localhost:52615/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52615/tests/kraken/driver.html?testName=audio-beat-detection) 13:23:04 INFO - PID 15393 | RSS: Main: 150986752 13:23:04 INFO - PID 15393 | 13:23:06 INFO - PID 15393 | Cycle 1(1): loaded http://localhost:52615/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52615/tests/kraken/driver.html?testName=audio-dft) 13:23:07 INFO - PID 15393 | RSS: Main: 152076288 13:23:07 INFO - PID 15393 | 13:23:10 INFO - PID 15393 | Cycle 1(1): loaded http://localhost:52615/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52615/tests/kraken/driver.html?testName=audio-fft) 13:23:10 INFO - PID 15393 | RSS: Main: 153247744 13:23:10 INFO - PID 15393 | 13:23:12 INFO - PID 15393 | Cycle 1(1): loaded http://localhost:52615/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52615/tests/kraken/driver.html?testName=audio-oscillator) 13:23:13 INFO - PID 15393 | RSS: Main: 153116672 13:23:13 INFO - PID 15393 | 13:23:15 INFO - PID 15393 | Cycle 1(1): loaded http://localhost:52615/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52615/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:23:15 INFO - PID 15393 | RSS: Main: 152854528 13:23:15 INFO - PID 15393 | 13:23:19 INFO - PID 15393 | Cycle 1(1): loaded http://localhost:52615/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52615/tests/kraken/driver.html?testName=imaging-darkroom) 13:23:19 INFO - PID 15393 | RSS: Main: 152719360 13:23:19 INFO - PID 15393 | 13:23:23 INFO - PID 15393 | Cycle 1(1): loaded http://localhost:52615/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52615/tests/kraken/driver.html?testName=imaging-desaturate) 13:23:23 INFO - PID 15393 | RSS: Main: 155025408 13:23:23 INFO - PID 15393 | 13:23:26 INFO - PID 15393 | Cycle 1(1): loaded http://localhost:52615/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52615/tests/kraken/driver.html?testName=json-parse-financial) 13:23:27 INFO - PID 15393 | RSS: Main: 155471872 13:23:27 INFO - PID 15393 | 13:23:28 INFO - PID 15393 | Cycle 1(1): loaded http://localhost:52615/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52615/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:23:29 INFO - PID 15393 | RSS: Main: 155869184 13:23:29 INFO - PID 15393 | 13:23:30 INFO - PID 15393 | Cycle 1(1): loaded http://localhost:52615/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52615/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:23:31 INFO - PID 15393 | RSS: Main: 155762688 13:23:31 INFO - PID 15393 | 13:23:33 INFO - PID 15393 | Cycle 1(1): loaded http://localhost:52615/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52615/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:23:34 INFO - PID 15393 | RSS: Main: 152502272 13:23:34 INFO - PID 15393 | 13:23:36 INFO - PID 15393 | Cycle 1(1): loaded http://localhost:52615/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52615/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:23:37 INFO - PID 15393 | RSS: Main: 152498176 13:23:37 INFO - PID 15393 | 13:23:39 INFO - PID 15393 | Cycle 1(1): loaded http://localhost:52615/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52615/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:23:40 INFO - PID 15393 | RSS: Main: 152666112 13:23:40 INFO - PID 15393 | 13:23:42 INFO - PID 15393 | Cycle 1(1): loaded http://localhost:52615/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52615/tests/kraken/driver.html?testName=ai-astar) 13:23:42 INFO - PID 15393 | RSS: Main: 154222592 13:23:42 INFO - PID 15393 | 13:23:42 INFO - PID 15393 | __start_tp_report 13:23:42 INFO - PID 15393 | _x_x_mozilla_page_load 13:23:42 INFO - PID 15393 | _x_x_mozilla_page_load_details 13:23:42 INFO - PID 15393 | |i|pagename|runs| 13:23:42 INFO - PID 15393 | |0;ai-astar;104;105;104;104;104;104;104;107;105;106 13:23:42 INFO - PID 15393 | |1;audio-beat-detection;116;113;121;113;116;112;113;111;111;119 13:23:42 INFO - PID 15393 | |2;audio-dft;162;162;160;161;161;164;161;160;161;161 13:23:42 INFO - PID 15393 | |3;audio-fft;75;73;72;73;74;73;73;72;74;74 13:23:42 INFO - PID 15393 | |4;audio-oscillator;76;78;77;76;79;80;77;77;78;78 13:23:42 INFO - PID 15393 | |5;imaging-gaussian-blur;109;113;114;114;116;114;114;114;112;115 13:23:42 INFO - PID 15393 | |6;imaging-darkroom;148;149;148;148;149;149;149;148;148;148 13:23:42 INFO - PID 15393 | |7;imaging-desaturate;94;94;103;98;94;94;93;94;96;94 13:23:42 INFO - PID 15393 | |8;json-parse-financial;44;43;43;44;44;43;44;44;44;44 13:23:42 INFO - PID 15393 | |9;json-stringify-tinderbox;38;40;39;39;37;38;38;37;37;37 13:23:42 INFO - PID 15393 | |10;stanford-crypto-aes;70;72;70;71;73;70;73;73;70;69 13:23:42 INFO - PID 15393 | |11;stanford-crypto-ccm;108;105;103;105;111;111;107;109;103;112 13:23:42 INFO - PID 15393 | |12;stanford-crypto-pbkdf2;157;149;150;157;148;148;151;153;152;153 13:23:42 INFO - PID 15393 | |13;stanford-crypto-sha256-iterative;62;62;57;59;71;59;59;59;60;62 13:23:42 INFO - PID 15393 | __end_tp_report 13:23:42 INFO - PID 15393 | __start_cc_report 13:23:42 INFO - PID 15393 | _x_x_mozilla_cycle_collect,696 13:23:42 INFO - PID 15393 | __end_cc_report 13:23:42 INFO - PID 15393 | __startTimestamp1508963022272__endTimestamp 13:23:42 INFO - PID 15393 | 13:23:42 INFO - PID 15393 | ------- Summary: start ------- 13:23:42 INFO - PID 15393 | Number of tests: 14 13:23:42 INFO - PID 15393 | 13:23:42 INFO - PID 15393 | [#0] ai-astar Cycles:10 Average:104.70 Median:104.50 stddev:1.06 (1.0%) stddev-sans-first:1.09 13:23:42 INFO - PID 15393 | Values: 104.0 105.0 104.0 104.0 104.0 104.0 104.0 107.0 105.0 106.0 13:23:42 INFO - PID 15393 | 13:23:42 INFO - PID 15393 | [#1] audio-beat-detection Cycles:10 Average:114.50 Median:114.50 stddev:3.41 (3.0%) stddev-sans-first:3.57 13:23:42 INFO - PID 15393 | Values: 116.0 113.0 121.0 113.0 116.0 112.0 113.0 111.0 111.0 119.0 13:23:42 INFO - PID 15393 | 13:23:42 INFO - PID 15393 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:1.16 (0.7%) stddev-sans-first:1.20 13:23:42 INFO - PID 15393 | Values: 162.0 162.0 160.0 161.0 161.0 164.0 161.0 160.0 161.0 161.0 13:23:42 INFO - PID 15393 | 13:23:42 INFO - PID 15393 | [#3] audio-fft Cycles:10 Average:73.30 Median:73.50 stddev:0.95 (1.3%) stddev-sans-first:0.78 13:23:42 INFO - PID 15393 | Values: 75.0 73.0 72.0 73.0 74.0 73.0 73.0 72.0 74.0 74.0 13:23:42 INFO - PID 15393 | 13:23:42 INFO - PID 15393 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:78.00 stddev:1.26 (1.6%) stddev-sans-first:1.20 13:23:42 INFO - PID 15393 | Values: 76.0 78.0 77.0 76.0 79.0 80.0 77.0 77.0 78.0 78.0 13:23:42 INFO - PID 15393 | 13:23:42 INFO - PID 15393 | [#5] imaging-gaussian-blur Cycles:10 Average:113.50 Median:114.00 stddev:1.90 (1.7%) stddev-sans-first:1.12 13:23:42 INFO - PID 15393 | Values: 109.0 113.0 114.0 114.0 116.0 114.0 114.0 114.0 112.0 115.0 13:23:42 INFO - PID 15393 | 13:23:42 INFO - PID 15393 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:148.50 stddev:0.52 (0.3%) stddev-sans-first:0.53 13:23:42 INFO - PID 15393 | Values: 148.0 149.0 148.0 148.0 149.0 149.0 149.0 148.0 148.0 148.0 13:23:42 INFO - PID 15393 | 13:23:42 INFO - PID 15393 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:94.00 stddev:3.03 (3.2%) stddev-sans-first:3.17 13:23:42 INFO - PID 15393 | Values: 94.0 94.0 103.0 98.0 94.0 94.0 93.0 94.0 96.0 94.0 13:23:42 INFO - PID 15393 | 13:23:42 INFO - PID 15393 | [#8] json-parse-financial Cycles:10 Average:43.70 Median:44.00 stddev:0.48 (1.1%) stddev-sans-first:0.50 13:23:42 INFO - PID 15393 | Values: 44.0 43.0 43.0 44.0 44.0 43.0 44.0 44.0 44.0 44.0 13:23:42 INFO - PID 15393 | 13:23:42 INFO - PID 15393 | [#9] json-stringify-tinderbox Cycles:10 Average:38.00 Median:38.00 stddev:1.05 (2.8%) stddev-sans-first:1.12 13:23:42 INFO - PID 15393 | Values: 38.0 40.0 39.0 39.0 37.0 38.0 38.0 37.0 37.0 37.0 13:23:42 INFO - PID 15393 | 13:23:42 INFO - PID 15393 | [#10] stanford-crypto-aes Cycles:10 Average:71.10 Median:71.50 stddev:1.52 (2.1%) stddev-sans-first:1.56 13:23:42 INFO - PID 15393 | Values: 70.0 72.0 70.0 71.0 73.0 70.0 73.0 73.0 70.0 69.0 13:23:42 INFO - PID 15393 | 13:23:42 INFO - PID 15393 | [#11] stanford-crypto-ccm Cycles:10 Average:107.40 Median:108.50 stddev:3.34 (3.1%) stddev-sans-first:3.54 13:23:42 INFO - PID 15393 | Values: 108.0 105.0 103.0 105.0 111.0 111.0 107.0 109.0 103.0 112.0 13:23:42 INFO - PID 15393 | 13:23:42 INFO - PID 15393 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.80 Median:152.50 stddev:3.29 (2.2%) stddev-sans-first:2.91 13:23:42 INFO - PID 15393 | Values: 157.0 149.0 150.0 157.0 148.0 148.0 151.0 153.0 152.0 153.0 13:23:42 INFO - PID 15393 | 13:23:42 INFO - PID 15393 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.00 Median:61.00 stddev:3.89 (6.4%) stddev-sans-first:4.11 13:23:42 INFO - PID 15393 | Values: 62.0 62.0 57.0 59.0 71.0 59.0 59.0 59.0 60.0 62.0 13:23:42 INFO - PID 15393 | -------- Summary: end -------- 13:23:42 INFO - PID 15393 | 13:23:42 INFO - PID 15393 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 13:23:42 INFO - TEST-INFO | 15393: exit 0 13:23:42 INFO - TEST-OK | kraken | took 57811ms 13:23:42 INFO - SUITE-END | took 1165s 13:23:42 INFO - Completed test suite (00:19:25) 13:23:42 INFO - javascript benchmark 13:23:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9513.290564582261, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [4015.6385925266723, 4043.312636851735, 4048.9190216195693, 4062.1063366059475, 4076.9184616307675, 6507.967460162698, 6607.603543787376, 6640.966795166018, 6689.230738465078, 6699.765508207213, 6627.900581491282, 6644.667766611674, 6665.0, 6707.12807335047, 6730.091437655915, 5966.105084237377, 5997.940020599804, 6017.909731354033, 6025.9096113558335, 6065.575409721321, 5868.325142608591, 6011.609245399035, 6074.5747797654185, 6083.513318934474, 6098.32918378978, 6021.825743979925, 6027.909581356261, 6079.483243924272, 6090.725917333709, 6095.908561371583, 5982.8803423931495, 5997.460228579449, 6027.306859711101, 6065.969670151665, 6073.908891366589, 5863.76544938202, 5882.6470411775, 5883.117532370136, 5931.822045338604, 5948.40515948406, 6540.869182616369, 6548.607083575001, 6605.669716514155, 6618.86762264752, 6624.53628246023, 7155.212926578072, 7158.534695244844, 7163.212046674862, 7237.421006319534, 7249.311315425026, 7210.67551960163, 7235.674394652201, 7285.490015698903, 7289.453291003196, 7299.343059124704, 6637.535372523974, 6685.0, 6711.66441677919, 6721.0926524919, 6735.696893639798, 7346.081739782528, 7347.55914645123, 7351.411887049023, 7441.144268409094, 7450.96274518624, 6984.196817366068, 7001.054857594159, 7010.719571217196, 7011.544249623758, 7030.226675065739, 7088.220295767462, 7090.539114957614, 7096.432285417154, 7100.9289907100265, 7202.53183543068, 7136.92863071373, 7198.244184360671, 7199.424046076301, 7201.0, 7205.675744591506, 7123.43012558994, 7127.10911136108, 7127.928720712726, 7202.819929501804, 7223.494355395177, 5251.973740131275, 5303.575713942875, 5309.070912590281, 5330.093884039723, 5405.4324295949145, 4913.9017219656125, 4916.680415772963, 4940.382452193476, 4943.332650092263, 4961.950380496149, 6700.296468870698, 6751.324867513209, 6778.762743303961, 6787.524873258824, 6794.898076528856, 7145.321194486515, 7151.678174482161, 7156.212816590171, 7219.530730502501, 7229.674664640103, 6577.309382514863, 6669.599824010575, 6730.966345168243, 6756.425703815133, 6791.252962174157, 5934.080217566284, 6040.516758659297, 6069.878602427927, 6070.362611925772, 6086.300075491286, 7360.0431943848025, 7474.364679002346, 7478.439117066133, 7501.549907005598, 7597.506162099557, 7163.570185788869, 7169.498135130595, 7240.891386629205, 7264.854702906018, 7270.781876543712, 7431.851362972819, 7450.022146024364, 7460.440466965108, 7511.661975211019, 7540.736074237376, 7040.471964602573, 7111.288871112847, 7120.60836653989, 7137.321954414322, 7141.357277845019, 7086.043384143075, 7099.290070992859, 7127.216006239206, 7197.172325182671, 7232.0, 6792.864142717217, 6868.244493105654, 6892.517523773287, 6920.0, 6938.167419909643, 6952.895706564506, 6977.302269772982, 7017.228104908354, 7029.0862187915245, 7034.402075823612, 6843.623600702009, 6895.103636527219, 6898.965505172442, 6967.407770339375, 6972.1284839395075, 6826.93173068263, 6908.274631163656, 6999.440044796506, 7006.089208402875, 7082.18554866197, 6771.898421523582, 6822.692978816064, 6859.485538584527, 6860.656967151523, 6884.51808373409], "value": 6585.5633891189655, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [957.186391567168, 988.0465350936345, 1005.9446730429828, 1006.4967516241879, 1016.5273147986187, 45620.0, 46551.0, 46570.767146164304, 46616.766916165456, 46647.76676116623, 45866.54133458665, 46292.76853615735, 46586.30120548195, 46662.76668616661, 46777.0644587108, 8747.431416957894, 8795.076516965722, 8875.822483550324, 8915.68795092172, 8944.776380590472, 8723.38936274461, 8907.109289071106, 8932.955335223314, 8940.0, 8942.687005954793, 8857.379983401132, 8896.288296936229, 8931.0, 8932.464052156858, 8940.06129356418, 8866.60100295485, 8924.955375223148, 8941.687040953568, 8973.551322433917, 8976.551172441352, 8746.212840844355, 8786.0, 8801.383903126783, 8833.60248788806, 8853.601587928559, 40815.387769183486, 40971.590284097074, 41048.58951410507, 41094.178116437804, 41226.38160427596, 3843.5195600549932, 3885.9028524286837, 3888.6889048876023, 3888.922221555553, 3890.3191941410423, 34070.31859362824, 34113.488297675554, 34115.658843411744, 34132.31735365279, 34180.31639367199, 5650.124230744241, 5695.060315048012, 5702.144678298247, 5721.170430287585, 5742.885142297131, 222287.0, 225555.0, 226259.0, 227493.0, 228544.99999999834, 52656.0, 53251.201231981555, 53272.73363633235, 53298.46701532935, 53323.93352132935, 54936.72531637396, 55100.0, 55332.0, 55497.0, 55775.721121394134, 18168.0, 18269.360572379905, 18404.17181226848, 18487.53781155482, 18494.16776245072, 15392.0, 15458.149801760786, 15465.458708945358, 15466.5360039199, 15493.922530387275, 13570.660733481742, 13620.863791362157, 13627.18236905769, 13675.863241367459, 13878.722425551432, 5404.756785944642, 5422.864428389322, 5581.6371935824045, 5591.4688104629995, 5646.713081202038, 1126.1610100475198, 1134.2513940799033, 1145.2098052343856, 1147.75897061618, 1149.454009345571, 25946.0, 26067.217983460527, 26069.348266293495, 26103.725259612173, 26142.73857261441, 1230.501646833034, 1234.1299383934304, 1235.4131787401093, 1249.537671061713, 1261.1109168036512, 13722.45110195601, 13784.0, 13854.653633659038, 13982.300884955712, 14173.929130354281, 274762.0, 276553.0, 278975.0, 285451.0, 285726.0, 62179.378206218265, 62359.688201559606, 62737.0, 62744.0, 62821.37178628246, 1506.1791323728594, 1562.3360071969369, 1567.5454118305563, 1568.6392129810208, 1571.449992502615, 29413.55879661764, 29739.70260297369, 29921.401571968872, 30319.0, 30807.845960770053, 40528.392074118325, 41082.589174109075, 41366.0, 41499.58500414957, 41505.0, 238234.0, 238302.0, 239141.0, 239386.0, 239448.0, 35705.0, 36253.818730906176, 36444.45333319949, 36510.087247819014, 36612.26775464529, 44629.55370446254, 46242.30636540387, 47004.29493557531, 47022.76488617535, 47059.0, 33992.0, 35093.0, 35102.64897350994, 35138.64861351456, 35550.11124721903, 35028.299434011686, 35112.64887351094, 35377.0, 35430.8228458856, 35641.82179089088, 39637.2072558553, 40982.0, 41036.58963410447, 41119.0, 41232.58767412287, 33798.32403351968, 33883.0, 34114.31771364609, 34613.0, 34966.125846853014, 25282.620760688238, 25471.872640636677, 25653.0, 26113.216603501925, 26333.0], "value": 19215.93252882638, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.7753786174837, 647.1716203259826, 648.5589798936721, 652.8074218105659, 654.8624788794353, 24583.63124553133, 24921.25236242911, 25029.874850625765, 25513.872430637868, 25798.226053218412, 27350.0, 27531.449371012568, 27553.0, 27645.861770691117, 27664.308392290204, 6800.829979250533, 7020.192677842037, 7232.13214414271, 7235.276472352749, 7255.165655949554, 6937.340952609494, 6958.652067396635, 6959.547629404073, 6968.407685346751, 6972.79081627552, 6931.757388491404, 6944.236134025253, 6947.548409353376, 6948.722051117975, 6949.617771022592, 6853.588784672911, 6881.483888708367, 6903.171619405678, 6944.1666999959825, 6946.79159625212, 6470.417662410406, 6475.158229430144, 6507.414332710032, 6524.282328943812, 6533.869322613568, 30258.84870575655, 32649.836750816332, 32678.6732132678, 32700.0, 32773.0, 2410.529946660402, 2441.511697660475, 2457.0294733580313, 2458.1273647854932, 2460.766227208412, 19482.123304451332, 19779.20883164686, 19964.001799909947, 20127.295544655866, 20195.495112622157, 2565.115035312814, 2602.7267136950536, 2611.451595165018, 2627.1330460947843, 2640.577507598794, 144554.0, 149130.0, 160808.0, 169421.15289423597, 169490.99999999878, 16917.7462338065, 17665.293388264326, 17725.025123618325, 18047.909760451115, 18071.367502137364, 27900.302492437444, 27926.743296551704, 27951.860240699072, 27955.860220698767, 27971.860140699166, 10922.781544369069, 10930.836037459285, 10952.288101273392, 11083.833742493869, 11106.666799996014, 9805.656802012038, 9811.067948544876, 9850.556724947395, 9881.555330010167, 9926.354786938826, 9962.651307204358, 10053.69838904834, 10114.443705596115, 10120.240981926238, 10537.681710023782, 2878.438190010892, 2977.6277965254344, 2993.4162838246334, 3018.170003249124, 3111.4088323218516, 1057.9375199234976, 1059.7562560610947, 1079.1097344690504, 1080.9081228095542, 1093.9070179034711, 22108.2262120825, 22395.55208895813, 22475.0, 22528.32415027552, 22671.886640566692, 1152.573547787324, 1152.6311580294225, 1154.099802154321, 1172.0916289875245, 1177.1524502358288, 11769.882301176878, 12433.37833108359, 12449.25304481734, 12453.315067671183, 12465.875341246652, 587873.0, 595266.0, 613455.0, 639366.0, 645485.0, 123444.38277808733, 123850.38074809569, 131604.0, 131681.0, 131693.0, 1145.7823013627385, 1188.6018183908316, 1256.4848412150975, 1262.9368531573568, 1315.684235783405, 22945.88527057354, 23249.3025209244, 23270.301890943298, 23346.88326558424, 23355.53288934246, 61795.0, 62241.377586223556, 62867.6856615714, 63811.680941595, 64005.67997159984, 73628.63185684252, 73892.0, 74398.62800685962, 76493.0, 78692.0, 16401.507954761375, 16633.50099497017, 16688.24902879349, 16720.0, 16738.330466781194, 24862.751372486768, 25022.37444063913, 25102.37244068913, 25304.11435599745, 25442.0, 20410.0, 20732.481687957923, 20741.27405540799, 20864.165433382495, 20904.1638334471, 15953.04281743099, 16701.0, 17981.820181798015, 18611.441656750318, 18749.437516874517, 20688.379348619565, 20714.378568642966, 20901.581968360242, 21039.05324260381, 21092.78907210908, 16355.672886541965, 16452.424165154163, 16472.42346517914, 16500.66998659996, 16534.421295254608, 20247.4938126548, 20266.0, 20381.898090509454, 20390.592188155857, 20575.897120514303], "value": 13307.26748731597, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1466.4500812195427, 1496.3341313115661, 1514.7273490771656, 1527.450117957536, 1535.3398038843297, 17576.47270581883, 18286.90856545717, 18309.908450457762, 18311.17599708013, 18336.724949125775, 17801.198946047425, 17921.193546290415, 17930.10349482527, 17932.0, 17986.28054877803, 3124.0783968729165, 3141.764367672428, 3180.7137357637807, 3196.2488815128427, 3204.423203823311, 3092.8144311341393, 3101.286704058068, 3114.330418959923, 3116.7194952454274, 3126.1090589182086, 3078.168894398511, 3090.8300043497597, 3125.796823206484, 3126.515390114536, 3181.5068664357063, 3015.472292348852, 3050.42042012017, 3070.6008218931506, 3087.243625311813, 3088.90733278002, 3062.5406189071596, 3096.4735994880925, 3110.6578276389578, 3110.7200351968204, 3114.283714745621, 17319.134043297912, 17705.645887082188, 17709.557261068447, 17732.645347093116, 17737.467875963743, 9043.231325337354, 9313.0, 9327.253819694408, 9342.626294948193, 9347.345685801996, 6225.533085018642, 6295.716692748837, 6309.085182648536, 6418.743250269984, 6434.099226108302, 2159.0931808640407, 2194.264921251383, 2234.5866014787152, 2263.3549438409973, 2276.658501224813, 32205.838970804994, 32373.838130809432, 32644.6735532644, 32651.18372040694, 32692.83653581717, 7348.926510734931, 7370.189279179286, 7607.250332423573, 7719.922800772033, 7732.110807257125, 20610.58778824445, 20796.896015519826, 20817.791822081886, 20830.1667933284, 20867.686984695243, 8766.956165219133, 9050.27597792175, 9081.500517471603, 9093.954530227438, 9097.818043639223, 8067.193280672003, 8161.510309381456, 8225.629846656915, 8279.420440569224, 8362.958185209034, 5886.440788125121, 6124.448799608057, 6142.907856382158, 6171.537134714914, 6211.81364559064, 1777.8755487115777, 1795.8024617292087, 1798.4784412520223, 1837.7427160197583, 1845.6954602490707, 871.2115535440437, 1012.0233974214771, 1012.6506355307407, 1028.2647906746713, 1038.9064984151462, 9878.753031174278, 9887.851682224773, 9923.305368624127, 10000.899991000142, 10042.297039207331, 834.5118105908111, 836.1554829622125, 836.93304535637, 840.7183593496125, 908.3369140527452, 6071.241094863142, 6599.8020059398295, 6659.600423974577, 6676.0, 6722.29415911332, 26137.477250455264, 26679.33301667474, 27039.729602703723, 27378.0, 27496.312592185357, 25400.872995634905, 26609.866950665124, 26617.0, 26866.731332686424, 26890.193294201206, 12516.0, 12583.182093163916, 12767.808482872764, 12849.229046257255, 12871.163374380823, 9651.758706032406, 10162.136218421221, 10273.126784223126, 10314.123299519324, 10365.274430789772, 22342.553148937255, 22349.553008940056, 22571.210007649654, 22775.202867899545, 23020.654690179996, 23438.17966371169, 23577.174798881955, 23824.761752382256, 23842.165524206568, 23901.04395824217, 13073.542426015425, 13186.340682966005, 13263.734725305247, 13286.402111904794, 13307.0, 16714.832851671326, 16918.0, 16985.74521382205, 16995.830041699424, 17007.404740834012, 13564.525241616497, 13639.113457625619, 13660.316984150952, 13733.0, 13781.655458613213, 12665.81001285, 13056.412461439068, 13378.397972091085, 13441.0, 13524.526641567498, 11177.72055698614, 11239.325640461599, 11367.829482557934, 11377.146713996648, 11380.146489013192, 8813.383063185516, 8975.0, 9043.909560904307, 9112.726618201465, 9163.725088247364, 9045.909540904508, 9169.083091690778, 9176.908230917605, 9193.954030229806, 9287.535623218677], "value": 7819.683514692113, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [533.9599530035246, 552.9778808847647, 560.529155509372, 564.4017341617886, 595.122194762725, 6241.594296370733, 6352.745890164394, 6442.7745028924, 6450.8064758057335, 6489.1888513935755, 6367.140436041133, 6394.200724909387, 6448.45188159007, 6450.677466126686, 6455.967720161392, 5297.867553311179, 5326.813561525348, 5371.355437347524, 5408.540274076689, 5411.675299482044, 5018.422881368635, 5174.560162386202, 5206.140986737184, 5261.447548007462, 5351.946480535184, 5218.921716174261, 5224.111900976843, 5224.791008359661, 5231.319928409302, 5258.316418865542, 5163.122269214243, 5171.844844654629, 5204.661696989683, 5220.347456567929, 5227.79088836446, 4734.431868175807, 4756.643251756098, 4767.856964291077, 4773.689710168863, 4786.664933454659, 6398.0083087121575, 6422.775202867877, 6451.806445806634, 6456.354364563553, 6462.838429039311, 5982.521398288127, 5987.550933679991, 5996.580239383245, 6039.3960603939695, 6091.51267898567, 6782.72869085236, 6833.3166683331765, 6847.760328388532, 6871.896921546181, 6933.479989000794, 5244.842654720364, 5250.212468129773, 5267.683938963674, 5269.657472264329, 5299.470052994709, 7040.577565346096, 7051.294870512907, 7061.940708893656, 7066.505344625878, 7166.53417527859, 5376.73116344189, 5403.43300442875, 5429.701366424884, 5449.3460784706085, 5454.345478542521, 5493.681516436027, 5503.944960550423, 5508.44915508446, 5512.0905050667, 5512.255845460892, 5947.643560797087, 5956.970215148896, 6019.638821670714, 6070.2412198475195, 6089.177960975212, 6583.0, 6768.69540870662, 6846.760363387357, 6861.759838405632, 6879.287687933781, 1809.873308868393, 1830.9267629294945, 1843.6865732825452, 1857.1921214271836, 1891.5743957609702, 1330.9534166304134, 1337.2511393619604, 1343.2209318595192, 1351.7499262636247, 1352.5604178641981, 6214.161088253119, 6238.719257633417, 6400.199975003125, 6418.582792118497, 6424.26120996082, 6894.965525172342, 6942.444604431634, 6989.916562932754, 7044.542104763276, 7095.432365410755, 6337.588056776387, 6432.51756118293, 6464.806055818333, 6499.0, 6532.248791389051, 3618.3306088373733, 3656.3418584654487, 3680.8895733128047, 3695.796731179811, 3698.4637227602384, 7204.82120370184, 7223.3025239068775, 7236.240194779472, 7303.707851685979, 7323.450741194325, 7154.499185056996, 7254.419646428273, 7292.307230813064, 7310.232425595343, 7313.0, 7245.166805817293, 7272.3818475429125, 7288.453365997465, 7324.121105467378, 7339.633018349061, 7015.508914375945, 7026.473014524034, 7046.401055910305, 7062.516871457052, 7080.221175670569, 6958.234594194736, 6984.720611175496, 7012.597480504021, 7034.437245020284, 7089.858202836018, 7130.251323611154, 7176.677049532679, 7241.275872412716, 7243.167035790952, 7276.672549735169, 5419.160030195326, 5454.4545545445135, 5458.044842152687, 5460.535854452416, 5465.234867118527, 6891.3797758202, 7031.09774075393, 7089.964550177217, 7109.786706398816, 7189.836471952324, 6700.095487109178, 6785.830354241183, 6830.795076147723, 6848.657567121524, 6883.208431030295, 6641.369069938348, 6736.562123461959, 6820.35206655367, 6837.3162683731225, 6860.794176174722, 6668.233153187445, 6716.589516201652, 6725.798226053226, 6742.190937087581, 6822.11312529368, 6800.31996800316, 6809.93190068093, 6818.93181068183, 6854.383105520527, 6880.69036893331, 6708.161479815023, 6725.19297684281, 6753.662316884038, 6769.661516924036, 6812.965935170292], "value": 5376.733670259463, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1009.2077718990593, 1014.9746256343591, 1033.6537260017897, 1036.80819048476, 1046.3512622174253, 20104.4973875653, 20475.48811279715, 20483.590328193426, 21244.362669119913, 21655.56688866222, 20575.485612859688, 20662.0, 20772.376828695127, 20812.479688007807, 20826.271080512186, 1071.2394200117906, 1094.485591771866, 1101.6254473479016, 1104.1553211792946, 1107.0, 1101.1906248907046, 1101.3612104979093, 1108.384846410242, 1109.9056580190654, 1110.2450333773031, 1015.5176291261629, 1131.6718151735986, 1132.2131118872387, 1136.2443974756777, 1148.3913525831322, 1116.5087361560886, 1122.4163435013747, 1122.7586068995206, 1122.825961975895, 1124.4771181400647, 833.6123702478316, 837.1252041616525, 837.4933165434885, 842.1830824100613, 843.7468759372164, 21960.45098872525, 22096.447588810246, 22232.333030008966, 22265.109395624157, 22527.54944901109, 24019.0, 24378.390540236636, 24381.512369752683, 24687.382815429577, 24796.25611231666, 17526.64946701059, 17933.37233196832, 17982.1008949552, 18134.637307253914, 18163.63672726538, 4158.4178215049915, 4234.25900467417, 4235.470566179228, 4238.533761286256, 4238.618524332825, 131539.0, 142394.0, 155263.0, 166478.0, 167512.16243918703, 18181.54546136357, 18535.53661158455, 18701.438956831316, 18746.156422961, 18784.53038674044, 35066.64933350685, 35089.649103509146, 35320.64679353225, 35509.0, 35537.28925421477, 9810.509474526245, 9831.213502919893, 9886.4562449066, 9918.553665084944, 9931.304808663324, 4663.976680116578, 4727.290906364073, 4743.786529606176, 4749.762511874393, 4770.880727981828, 6463.838404039842, 6542.607443553402, 6587.703553340111, 6589.17635295588, 6596.80209593713, 1159.113278342069, 1188.3701638131804, 1194.8386967759416, 1197.203859433467, 1198.0355813569986, 704.4082970304866, 706.8444942112727, 716.0941409117471, 722.7253643615393, 742.6658003898267, 13428.992825538124, 13869.791953120712, 14197.645058873404, 14494.420223191166, 14707.117572945657, 723.2405973727565, 724.4819953733106, 730.0728075344389, 730.2624349407031, 735.9659678152204, 6522.543421960418, 6556.4427023702565, 6571.474282057424, 6673.399394054559, 6725.630090345078, 147999.0, 148885.0, 149557.0, 152481.0, 157189.0, 123264.0, 125753.0, 125933.0, 126687.0, 132763.0, 111866.0, 112137.0, 114510.0, 117287.0, 118158.0, 14006.579802605938, 14084.577462676136, 14104.717905641624, 14222.502212422929, 14606.926965365104, 55798.0, 57190.856182876945, 57606.4239357601, 57637.0, 58399.70800145972, 69649.0, 70262.29737702558, 71000.0, 72826.0, 74570.0, 21701.0, 21978.45053873666, 21997.23009694653, 22238.888805555867, 22271.665925010813, 23451.17920872761, 23690.28929132129, 23765.64351534763, 23809.761902381448, 24144.758552414252, 21320.466988325417, 21441.249556265455, 21702.56594868062, 21760.89119554392, 22069.33791986243, 19390.32133875364, 19543.21827126899, 19678.212871485553, 19681.507962301057, 19935.700964485834, 20401.387958361272, 20486.97565121768, 20646.483837904172, 20684.0, 20909.89545052265, 14213.360398781873, 14276.500322488662, 14343.282835858374, 14350.138991660533, 14494.275286235435, 14604.26978651042, 14673.826093912676, 14809.18549479789, 14839.03546269489, 14956.326965286806], "value": 10469.079982708188, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1361.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 105.0, 104.0, 104.0, 104.0, 104.0, 104.0, 107.0, 105.0, 106.0], "value": 104.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 113.0, 121.0, 113.0, 116.0, 112.0, 113.0, 111.0, 111.0, 119.0], "value": 114.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 160.0, 161.0, 161.0, 164.0, 161.0, 160.0, 161.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 72.0, 73.0, 74.0, 73.0, 73.0, 72.0, 74.0, 74.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 77.0, 76.0, 79.0, 80.0, 77.0, 77.0, 78.0, 78.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 113.0, 114.0, 114.0, 116.0, 114.0, 114.0, 114.0, 112.0, 115.0], "value": 113.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 148.0, 148.0, 149.0, 149.0, 149.0, 148.0, 148.0, 148.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 103.0, 98.0, 94.0, 94.0, 93.0, 94.0, 96.0, 94.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 43.0, 43.0, 44.0, 44.0, 43.0, 44.0, 44.0, 44.0, 44.0], "value": 43.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 40.0, 39.0, 39.0, 37.0, 38.0, 38.0, 37.0, 37.0, 37.0], "value": 38.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 70.0, 71.0, 73.0, 70.0, 73.0, 73.0, 70.0, 69.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 105.0, 103.0, 105.0, 111.0, 111.0, 107.0, 109.0, 103.0, 112.0], "value": 107.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 149.0, 150.0, 157.0, 148.0, 148.0, 151.0, 153.0, 152.0, 153.0], "value": 151.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 57.0, 59.0, 71.0, 59.0, 59.0, 59.0, 60.0, 62.0], "value": 61.0, "unit": "score"}]}]} 13:23:43 INFO - Return code: 0 13:23:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:23:43 INFO - # TBPL SUCCESS # 13:23:43 INFO - Running post-action listener: _package_coverage_data 13:23:43 INFO - Running post-action listener: _resource_record_post_action 13:23:43 INFO - [mozharness: 2017-10-25 20:23:43.076649Z] Finished run-tests step (success) 13:23:43 INFO - Running post-run listener: _resource_record_post_run 13:23:43 INFO - Total resource usage - Wall time: 1180s; CPU: 13.0%; Read bytes: 40960; Write bytes: 285880320; Read time: 64; Write time: 168788 13:23:43 INFO - TinderboxPrint: CPU usage
13.0% 13:23:43 INFO - TinderboxPrint: I/O read bytes / time
40,960 / 64 13:23:43 INFO - TinderboxPrint: I/O write bytes / time
285,880,320 / 168,788 13:23:43 INFO - TinderboxPrint: CPU idle
8,182.3 (87.0%) 13:23:43 INFO - TinderboxPrint: CPU user
1,179.2 (12.5%) 13:23:43 INFO - TinderboxPrint: Swap in / out
0 / 0 13:23:43 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 7733248; Read time: 0; Write time: 1044 13:23:43 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 13:23:43 INFO - run-tests - Wall time: 1166s; CPU: 13.0%; Read bytes: 40960; Write bytes: 277745664; Read time: 64; Write time: 167712 13:23:43 INFO - Running post-run listener: _upload_blobber_files 13:23:43 INFO - Blob upload gear active. 13:23:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:23:43 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 13:23:43 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', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:23:43 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 Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:23:44 INFO - (blobuploader) - INFO - Open directory for files ... 13:23:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:23:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:23:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:23:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:23:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:23:45 INFO - (blobuploader) - INFO - Done attempting. 13:23:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:23:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:23:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:23:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:23:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:23:52 INFO - (blobuploader) - INFO - Done attempting. 13:23:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:23:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:23:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:23:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:23:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:23:53 INFO - (blobuploader) - INFO - Done attempting. 13:23:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:23:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:23:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:23:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:23:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:23:54 INFO - (blobuploader) - INFO - Done attempting. 13:23:54 INFO - (blobuploader) - INFO - Iteration through files over. 13:23:54 INFO - Return code: 0 13:23:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:23:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:23:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e19185546f8e7b24bfe7959950f7fd05f8d94ca6478462efd2faa715c3cef83943004b0dc30ae3aa7980d08bd7efd91815e91c607b5c4a5b1aaacdf1b4b8135c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/37705b23040a2ce42fe0e01a4050522b45d88066251c7fd904679da67b8a8174f77165fcd198aa54213ef5d68e7a1fa5c0956ff71b3fabcaeb02bc62b95295b2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/79ee02f45bbd73f6ddf1ef9e8c9492cafbacd6af33d684c061bd303af35ec7b4e6712a74e63deea7a2c69fab0143bd0d901ad26efada9558bf327042f1413497", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/cc171f74123bbe109f1e7e3a9c3caf21c8e04d9a84d3ca1af2bc0604d794b6c82251966ef932682dc705332e24b5adaaebce7c7ee5fc3afd54f3ad6c2cf501bf"} 13:23:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:23:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:23:54 INFO - Contents: 13:23:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e19185546f8e7b24bfe7959950f7fd05f8d94ca6478462efd2faa715c3cef83943004b0dc30ae3aa7980d08bd7efd91815e91c607b5c4a5b1aaacdf1b4b8135c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/37705b23040a2ce42fe0e01a4050522b45d88066251c7fd904679da67b8a8174f77165fcd198aa54213ef5d68e7a1fa5c0956ff71b3fabcaeb02bc62b95295b2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/79ee02f45bbd73f6ddf1ef9e8c9492cafbacd6af33d684c061bd303af35ec7b4e6712a74e63deea7a2c69fab0143bd0d901ad26efada9558bf327042f1413497", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/cc171f74123bbe109f1e7e3a9c3caf21c8e04d9a84d3ca1af2bc0604d794b6c82251966ef932682dc705332e24b5adaaebce7c7ee5fc3afd54f3ad6c2cf501bf"} 13:23:54 INFO - Running post-run listener: copy_logs_to_upload_dir 13:23:54 INFO - Copying logs to upload dir... 13:23:54 INFO - mkdir: /builds/slave/test/build/upload/logs 13:23:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.114025 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2017-10-25 13:23:54.259852) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-25 13:23:54.263842) ========= 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=ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473 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/Mozilla-Beta-Non-PGO/sha512/e19185546f8e7b24bfe7959950f7fd05f8d94ca6478462efd2faa715c3cef83943004b0dc30ae3aa7980d08bd7efd91815e91c607b5c4a5b1aaacdf1b4b8135c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/37705b23040a2ce42fe0e01a4050522b45d88066251c7fd904679da67b8a8174f77165fcd198aa54213ef5d68e7a1fa5c0956ff71b3fabcaeb02bc62b95295b2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/79ee02f45bbd73f6ddf1ef9e8c9492cafbacd6af33d684c061bd303af35ec7b4e6712a74e63deea7a2c69fab0143bd0d901ad26efada9558bf327042f1413497", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/cc171f74123bbe109f1e7e3a9c3caf21c8e04d9a84d3ca1af2bc0604d794b6c82251966ef932682dc705332e24b5adaaebce7c7ee5fc3afd54f3ad6c2cf501bf"} build_url:https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011372 build_url: 'https://queue.taskcluster.net/v1/task/GJmw3ptRT3O9Q-vnx-CwPg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e19185546f8e7b24bfe7959950f7fd05f8d94ca6478462efd2faa715c3cef83943004b0dc30ae3aa7980d08bd7efd91815e91c607b5c4a5b1aaacdf1b4b8135c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/37705b23040a2ce42fe0e01a4050522b45d88066251c7fd904679da67b8a8174f77165fcd198aa54213ef5d68e7a1fa5c0956ff71b3fabcaeb02bc62b95295b2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/79ee02f45bbd73f6ddf1ef9e8c9492cafbacd6af33d684c061bd303af35ec7b4e6712a74e63deea7a2c69fab0143bd0d901ad26efada9558bf327042f1413497", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/cc171f74123bbe109f1e7e3a9c3caf21c8e04d9a84d3ca1af2bc0604d794b6c82251966ef932682dc705332e24b5adaaebce7c7ee5fc3afd54f3ad6c2cf501bf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-25 13:23:54.297589) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-25 13:23:54.297918) ========= 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=ed14279e38b05397cf4aa391000001a4-1508961799.753221-1890774473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004488 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-25 13:23:54.383199) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-25 13:23:54.383555) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-25 13:23:54.383847) ========= ========= Total master_lag: 0.20 =========