builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-019 starttime: 1508859028.95 results: success (0) revision: cae5754b8af50a9b81e92b261365d6a6d4cbffb2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-24 08:30:28.946690) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-24 08:30:28.947432) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-24 08:30:28.947734) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-24 08:30:28.998886) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-24 08:30:28.999502) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-24 08:30:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.1M=0s 2017-10-24 08:30:29 (42.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.091774 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-24 08:30:29.113286) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-24 08:30:29.113680) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018062 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-24 08:30:29.163625) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-24 08:30:29.164008) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev cae5754b8af50a9b81e92b261365d6a6d4cbffb2 --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 cae5754b8af50a9b81e92b261365d6a6d4cbffb2 --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=78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-24 08:30:29,217 truncating revision to first 12 chars 2017-10-24 08:30:29,217 Setting DEBUG logging. 2017-10-24 08:30:29,217 attempt 1/10 2017-10-24 08:30:29,217 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/cae5754b8af5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-24 08:30:29,613 unpacking tar archive at: mozilla-beta-cae5754b8af5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.702293 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-24 08:30:29.887141) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-24 08:30:29.887548) ========= script_repo_revision: cae5754b8af50a9b81e92b261365d6a6d4cbffb2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-24 08:30:29.888028) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-24 08:30:29.888311) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-24 08:30:29.904660) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2017-10-24 08:30:29.905062) ========= /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=78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:30:30 INFO - MultiFileLogger online at 20171024 08:30:30 in /builds/slave/test 08:30:30 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 08:30:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:30:30 INFO - {'append_to_log': False, 08:30:30 INFO - 'base_work_dir': '/builds/slave/test', 08:30:30 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 08:30:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:30:30 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 08:30:30 INFO - 'buildbot_json_path': 'buildprops.json', 08:30:30 INFO - 'code_coverage': False, 08:30:30 INFO - 'config_files': ('talos/linux_config.py',), 08:30:30 INFO - 'default_actions': ('clobber', 08:30:30 INFO - 'read-buildbot-config', 08:30:30 INFO - 'download-and-extract', 08:30:30 INFO - 'populate-webroot', 08:30:30 INFO - 'create-virtualenv', 08:30:30 INFO - 'install', 08:30:30 INFO - 'setup-mitmproxy', 08:30:30 INFO - 'run-tests'), 08:30:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:30:30 INFO - 'disable_ccov_upload': False, 08:30:30 INFO - 'disable_stylo': False, 08:30:30 INFO - 'download_minidump_stackwalk': True, 08:30:30 INFO - 'download_symbols': 'ondemand', 08:30:30 INFO - 'enable_stylo': False, 08:30:30 INFO - 'enable_webrender': False, 08:30:30 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 08:30:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:30:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:30:30 INFO - 'gecko_profile': False, 08:30:30 INFO - 'gecko_profile_interval': 0, 08:30:30 INFO - 'installer_path': 'installer.exe', 08:30:30 INFO - 'log_level': 'info', 08:30:30 INFO - 'log_name': 'talos', 08:30:30 INFO - 'log_to_console': True, 08:30:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:30:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:30:30 INFO - 'opt_config_files': (), 08:30:30 INFO - 'pip_index': False, 08:30:30 INFO - 'suite': 'dromaeojs-e10s', 08:30:30 INFO - 'system_bits': '32', 08:30:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:30:30 INFO - 'title': 'talos-linux64-ix-019', 08:30:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:30:30 INFO - 'use_talos_json': True, 08:30:30 INFO - 'verify': 'False', 08:30:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:30:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:30:30 INFO - 'work_dir': 'build'} 08:30:30 INFO - [mozharness: 2017-10-24 15:30:30.097107Z] Running clobber step. 08:30:30 INFO - Running pre-action listener: _resource_record_pre_action 08:30:30 INFO - Running main action method: clobber 08:30:30 INFO - rmtree: /builds/slave/test/build 08:30:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:30:31 INFO - Running post-action listener: _resource_record_post_action 08:30:31 INFO - [mozharness: 2017-10-24 15:30:31.092746Z] Finished clobber step (success) 08:30:31 INFO - [mozharness: 2017-10-24 15:30:31.092843Z] Running read-buildbot-config step. 08:30:31 INFO - Running pre-action listener: _resource_record_pre_action 08:30:31 INFO - Running main action method: read_buildbot_config 08:30:31 INFO - Using buildbot properties: 08:30:31 INFO - { 08:30:31 INFO - "project": "", 08:30:31 INFO - "product": "firefox", 08:30:31 INFO - "who": "ryanvm@gmail.com", 08:30:31 INFO - "installer_path": "public/build/target.tar.bz2", 08:30:31 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 08:30:31 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 08:30:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 08:30:31 INFO - "stage_platform": "linux64", 08:30:31 INFO - "basedir": "/builds/slave/test", 08:30:31 INFO - "buildnumber": 150, 08:30:31 INFO - "platform": "ubuntu64_hw", 08:30:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:30:31 INFO - "slavebuilddir": "test", 08:30:31 INFO - "taskId": "Y9gyUFqMSU-H36kvC4fmlQ", 08:30:31 INFO - "branch": "mozilla-beta", 08:30:31 INFO - "script_repo_revision": "production", 08:30:31 INFO - "revision": "cae5754b8af50a9b81e92b261365d6a6d4cbffb2", 08:30:31 INFO - "slavename": "talos-linux64-ix-019", 08:30:31 INFO - "repo_path": "releases/mozilla-beta" 08:30:31 INFO - } 08:30:31 INFO - Finding installer, test and symbols from parent task. 08:30:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y9gyUFqMSU-H36kvC4fmlQ'}, attempt #1 08:30:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y9gyUFqMSU-H36kvC4fmlQ'}, attempt #1 08:30:32 INFO - Task dependencies: HFRzibwGRdanUV-4xuWB2Q 08:30:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q'}, attempt #1 08:30:32 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.tar.bz2 08:30:32 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.test_packages.json 08:30:32 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.crashreporter-symbols.zip 08:30:32 INFO - Running post-action listener: _resource_record_post_action 08:30:32 INFO - [mozharness: 2017-10-24 15:30:32.473737Z] Finished read-buildbot-config step (success) 08:30:32 INFO - [mozharness: 2017-10-24 15:30:32.473953Z] Running download-and-extract step. 08:30:32 INFO - Running pre-action listener: _resource_record_pre_action 08:30:32 INFO - Running main action method: download_and_extract 08:30:32 INFO - mkdir: /builds/slave/test/build/tests 08:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:32 INFO - trying https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.test_packages.json 08:30:32 INFO - Downloading https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:30:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:30:33 INFO - Downloaded 989 bytes. 08:30:33 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:30:33 INFO - Using the following test package requirements: 08:30:33 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:30:33 INFO - u'common': [u'target.common.tests.zip'], 08:30:33 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:30:33 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:30:33 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:30:33 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:30:33 INFO - u'mozbase': [u'target.common.tests.zip'], 08:30:33 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:30:33 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:30:33 INFO - u'web-platform': [u'target.common.tests.zip', 08:30:33 INFO - u'target.web-platform.tests.tar.gz'], 08:30:33 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:30:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 08:30:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.common.tests.zip 08:30:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:30:33 INFO - Fetch https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.common.tests.zip into memory 08:30:35 INFO - Content-Length response header: 55177154 08:30:35 INFO - Bytes received: 55177154 08:30:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.talos.tests.zip 08:30:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:30:46 INFO - Fetch https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.talos.tests.zip into memory 08:30:47 INFO - Content-Length response header: 14509096 08:30:47 INFO - Bytes received: 14509096 08:30:47 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')]} 08:30:47 INFO - trying https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.tar.bz2 08:30:47 INFO - Downloading https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:30:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:30:49 INFO - Downloaded 53639083 bytes. 08:30:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.tar.bz2 08:30:49 INFO - mkdir: /builds/slave/test/properties 08:30:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:30:49 INFO - Writing to file /builds/slave/test/properties/build_url 08:30:49 INFO - Contents: 08:30:49 INFO - build_url:https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.tar.bz2 08:30:49 INFO - Running post-action listener: _resource_record_post_action 08:30:49 INFO - Running post-action listener: find_tests_for_verification 08:30:49 INFO - Running post-action listener: set_extra_try_arguments 08:30:49 INFO - [mozharness: 2017-10-24 15:30:49.943237Z] Finished download-and-extract step (success) 08:30:49 INFO - [mozharness: 2017-10-24 15:30:49.943374Z] Running populate-webroot step. 08:30:49 INFO - Running pre-action listener: _resource_record_pre_action 08:30:49 INFO - Running main action method: populate_webroot 08:30:49 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:30:49 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:30:49 INFO - 'tests': ['tresize', 08:30:49 INFO - 'tcanvasmark']}, 08:30:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:30:49 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:30:49 INFO - 'tests': ['dromaeo_css', 08:30:49 INFO - 'kraken']}, 08:30:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:30:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:49 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:30:49 INFO - 'talos_options': ['--disable-stylo'], 08:30:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:30:49 INFO - 'tests': ['damp', 'tps']}, 08:30:49 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:30:49 INFO - 'talos_options': ['--disable-stylo'], 08:30:49 INFO - 'tests': ['damp', 'tps']}, 08:30:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:30:49 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:30:49 INFO - 'tests': ['dromaeo_dom']}, 08:30:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:30:49 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:30:49 INFO - 'tests': ['basic_compositor_video', 08:30:49 INFO - 'glvideo']}, 08:30:49 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:30:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:30:49 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:30:49 INFO - 'talos_options': ['--disable-stylo'], 08:30:49 INFO - 'tests': ['ts_paint_webext', 08:30:49 INFO - 'tp5o_webext']}, 08:30:49 INFO - 'other-e10s': {'tests': ['a11yr', 08:30:49 INFO - 'ts_paint', 08:30:49 INFO - 'tpaint', 08:30:49 INFO - 'sessionrestore', 08:30:49 INFO - 'sessionrestore_many_windows', 08:30:49 INFO - 'sessionrestore_no_auto_restore', 08:30:49 INFO - 'tabpaint']}, 08:30:49 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:30:49 INFO - 'tests': ['a11yr', 08:30:49 INFO - 'ts_paint', 08:30:49 INFO - 'tpaint', 08:30:49 INFO - 'sessionrestore', 08:30:49 INFO - 'sessionrestore_many_windows', 08:30:49 INFO - 'sessionrestore_no_auto_restore', 08:30:49 INFO - 'tabpaint']}, 08:30:49 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 08:30:49 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 08:30:49 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:30:49 INFO - 'tests': ['perf_reftest_singletons']}, 08:30:49 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:30:49 INFO - 'tests': ['bloom_basic']}, 08:30:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:30:49 INFO - 'tsvgr_opacity', 08:30:49 INFO - 'tart', 08:30:49 INFO - 'tscrollx', 08:30:49 INFO - 'tsvg_static']}, 08:30:49 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:30:49 INFO - 'tests': ['tsvgx', 08:30:49 INFO - 'tsvgr_opacity', 08:30:49 INFO - 'tart', 08:30:49 INFO - 'tscrollx', 08:30:49 INFO - 'tsvg_static']}, 08:30:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:30:49 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:30:49 INFO - 'talos_options': ['--disable-stylo'], 08:30:49 INFO - 'tests': ['tp5o']}, 08:30:49 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:30:49 INFO - 'talos_options': ['--mitmproxy', 08:30:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:30:49 INFO - '--firstNonBlankPaint'], 08:30:49 INFO - 'tests': ['tp6_google', 08:30:49 INFO - 'tp6_youtube', 08:30:49 INFO - 'tp6_amazon', 08:30:49 INFO - 'tp6_facebook']}, 08:30:49 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:30:49 INFO - 'talos_options': ['--disable-stylo', 08:30:49 INFO - '--mitmproxy', 08:30:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:30:49 INFO - '--firstNonBlankPaint'], 08:30:49 INFO - 'tests': ['tp6_google', 08:30:49 INFO - 'tp6_youtube', 08:30:49 INFO - 'tp6_amazon', 08:30:49 INFO - 'tp6_facebook']}, 08:30:49 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:30:49 INFO - 'talos_options': ['--stylo-threads=1', 08:30:49 INFO - '--mitmproxy', 08:30:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:30:49 INFO - '--firstNonBlankPaint'], 08:30:49 INFO - 'tests': ['tp6_google', 08:30:49 INFO - 'tp6_youtube', 08:30:49 INFO - 'tp6_amazon', 08:30:49 INFO - 'tp6_facebook']}, 08:30:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:30:49 INFO - 'talos_options': ['--xperf_path', 08:30:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:49 INFO - 'tests': ['tp5n']}, 08:30:49 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:30:49 INFO - 'talos_options': ['--disable-stylo', 08:30:49 INFO - '--xperf_path', 08:30:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:49 INFO - 'tests': ['tp5n']}}} 08:30:49 INFO - Running post-action listener: _resource_record_post_action 08:30:49 INFO - [mozharness: 2017-10-24 15:30:49.959304Z] Finished populate-webroot step (success) 08:30:49 INFO - [mozharness: 2017-10-24 15:30:49.959393Z] Running create-virtualenv step. 08:30:49 INFO - Running pre-action listener: _resource_record_pre_action 08:30:49 INFO - Running main action method: create_virtualenv 08:30:49 INFO - Creating virtualenv /builds/slave/test/build/venv 08:30:49 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:30:49 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 08:30:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:30:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:30:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:30:50 INFO - Installing distribute.............................................................................................................................................................................................done. 08:30:50 INFO - Installing pip...............done. 08:30:50 INFO - Return code: 0 08:30:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:30:50 INFO - Reading from file tmpfile_stdout 08:30:50 INFO - Output received: 08:30:50 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 08:30:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:30: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')]} 08:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30: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')]} 08:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cb090>, '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 0x11cbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', '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 08:30:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:30:50 INFO - Using env: {'DISPLAY': ':0', 08:30:50 INFO - 'HOME': '/home/cltbld', 08:30:50 INFO - 'LANG': 'en_US.UTF-8', 08:30:50 INFO - 'LANGUAGE': 'en_US:en', 08:30:50 INFO - 'LOGNAME': 'cltbld', 08:30:50 INFO - 'MAIL': '/var/mail/cltbld', 08:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:50 INFO - 'MOZ_NO_REMOTE': '1', 08:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:50 INFO - 'NO_EM_RESTART': '1', 08:30:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:50 INFO - 'PWD': '/builds/slave/test', 08:30:50 INFO - 'SHELL': '/bin/bash', 08:30:50 INFO - 'SHLVL': '1', 08:30:50 INFO - 'TERM': 'linux', 08:30:50 INFO - 'TMOUT': '86400', 08:30:50 INFO - 'USER': 'cltbld', 08:30:50 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', 08:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:50 INFO - '_': '/tools/buildbot/bin/python'} 08:30:52 INFO - Ignoring indexes: http://pypi.python.org/simple/ 08:30:52 INFO - Downloading/unpacking pip>=1.5 08:30:52 INFO - Running setup.py egg_info for package pip 08:30:52 INFO - warning: no files found matching 'pip/cacert.pem' 08:30:52 INFO - warning: no files found matching '*.html' under directory 'docs' 08:30:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:30:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:30:52 INFO - Installing collected packages: pip 08:30:52 INFO - Found existing installation: pip 1.1 08:30:52 INFO - Uninstalling pip: 08:30:52 INFO - Successfully uninstalled pip 08:30:52 INFO - Running setup.py install for pip 08:30:52 INFO - warning: no files found matching 'pip/cacert.pem' 08:30:52 INFO - warning: no files found matching '*.html' under directory 'docs' 08:30:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:30:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:30:52 INFO - Installing pip script to /builds/slave/test/build/venv/bin 08:30:52 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 08:30:52 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 08:30:52 INFO - Successfully installed pip 08:30:52 INFO - Cleaning up... 08:30:52 INFO - Return code: 0 08:30:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cb090>, '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 0x11cbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', '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 08:30:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:30:52 INFO - Using env: {'DISPLAY': ':0', 08:30:52 INFO - 'HOME': '/home/cltbld', 08:30:52 INFO - 'LANG': 'en_US.UTF-8', 08:30:52 INFO - 'LANGUAGE': 'en_US:en', 08:30:52 INFO - 'LOGNAME': 'cltbld', 08:30:52 INFO - 'MAIL': '/var/mail/cltbld', 08:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:52 INFO - 'MOZ_NO_REMOTE': '1', 08:30:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:52 INFO - 'NO_EM_RESTART': '1', 08:30:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:52 INFO - 'PWD': '/builds/slave/test', 08:30:52 INFO - 'SHELL': '/bin/bash', 08:30:52 INFO - 'SHLVL': '1', 08:30:52 INFO - 'TERM': 'linux', 08:30:52 INFO - 'TMOUT': '86400', 08:30:52 INFO - 'USER': 'cltbld', 08:30:52 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', 08:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:52 INFO - '_': '/tools/buildbot/bin/python'} 08:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:52 INFO - Downloading/unpacking psutil>=3.1.1 08:30: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 08:30: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 08:30: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 08:30: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 08:30:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:30:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:53 INFO - Installing collected packages: psutil 08:30:53 INFO - Running setup.py install for psutil 08:30:53 INFO - building 'psutil._psutil_linux' extension 08:30:53 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:30:53 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:30:53 INFO - building 'psutil._psutil_posix' extension 08:30:53 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:30:54 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 08:30:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:54 INFO - Successfully installed psutil 08:30:54 INFO - Cleaning up... 08:30:54 INFO - Return code: 0 08:30:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cb090>, '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 0x11cbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', '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 08:30:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:30:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:30:54 INFO - Using env: {'DISPLAY': ':0', 08:30:54 INFO - 'HOME': '/home/cltbld', 08:30:54 INFO - 'LANG': 'en_US.UTF-8', 08:30:54 INFO - 'LANGUAGE': 'en_US:en', 08:30:54 INFO - 'LOGNAME': 'cltbld', 08:30:54 INFO - 'MAIL': '/var/mail/cltbld', 08:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:54 INFO - 'MOZ_NO_REMOTE': '1', 08:30:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:54 INFO - 'NO_EM_RESTART': '1', 08:30:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:54 INFO - 'PWD': '/builds/slave/test', 08:30:54 INFO - 'SHELL': '/bin/bash', 08:30:54 INFO - 'SHLVL': '1', 08:30:54 INFO - 'TERM': 'linux', 08:30:54 INFO - 'TMOUT': '86400', 08:30:54 INFO - 'USER': 'cltbld', 08:30:54 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', 08:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:54 INFO - '_': '/tools/buildbot/bin/python'} 08:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:30:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:30:55 INFO - Installing collected packages: mozsystemmonitor 08:30:55 INFO - Running setup.py install for mozsystemmonitor 08:30:56 INFO - Successfully installed mozsystemmonitor 08:30:56 INFO - Cleaning up... 08:30:56 INFO - Return code: 0 08:30:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:30: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')]} 08:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30: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')]} 08:30:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xe5c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cb090>, '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 0x11cbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', '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 08:30:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:30:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:30:56 INFO - Using env: {'DISPLAY': ':0', 08:30:56 INFO - 'HOME': '/home/cltbld', 08:30:56 INFO - 'LANG': 'en_US.UTF-8', 08:30:56 INFO - 'LANGUAGE': 'en_US:en', 08:30:56 INFO - 'LOGNAME': 'cltbld', 08:30:57 INFO - 'MAIL': '/var/mail/cltbld', 08:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:57 INFO - 'MOZ_NO_REMOTE': '1', 08:30:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:57 INFO - 'NO_EM_RESTART': '1', 08:30:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:57 INFO - 'PWD': '/builds/slave/test', 08:30:57 INFO - 'SHELL': '/bin/bash', 08:30:57 INFO - 'SHLVL': '1', 08:30:57 INFO - 'TERM': 'linux', 08:30:57 INFO - 'TMOUT': '86400', 08:30:57 INFO - 'USER': 'cltbld', 08:30:57 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', 08:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:57 INFO - '_': '/tools/buildbot/bin/python'} 08:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:57 INFO - Downloading/unpacking jsonschema==2.5.1 08:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:30:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:30: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 08:30: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 08:30: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 08:30: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 08:30:58 INFO - Downloading functools32-3.2.3-2.tar.gz 08:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:30:58 INFO - warning: no files found matching '*.txt' 08:30:58 INFO - no previously-included directories found matching 'build' 08:30:58 INFO - no previously-included directories found matching 'dist' 08:30:58 INFO - no previously-included directories found matching '.git*' 08:30:58 INFO - Installing collected packages: jsonschema, functools32 08:30:58 INFO - Running setup.py install for functools32 08:30:58 INFO - warning: no files found matching '*.txt' 08:30:58 INFO - no previously-included directories found matching 'build' 08:30:58 INFO - no previously-included directories found matching 'dist' 08:30:58 INFO - no previously-included directories found matching '.git*' 08:30:58 INFO - Successfully installed jsonschema functools32 08:30:58 INFO - Cleaning up... 08:30:58 INFO - Return code: 0 08:30:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xe5c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cb090>, '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 0x11cbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', '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 08:30:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:30:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:30:58 INFO - Using env: {'DISPLAY': ':0', 08:30:58 INFO - 'HOME': '/home/cltbld', 08:30:58 INFO - 'LANG': 'en_US.UTF-8', 08:30:58 INFO - 'LANGUAGE': 'en_US:en', 08:30:58 INFO - 'LOGNAME': 'cltbld', 08:30:58 INFO - 'MAIL': '/var/mail/cltbld', 08:30:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:58 INFO - 'MOZ_NO_REMOTE': '1', 08:30:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:58 INFO - 'NO_EM_RESTART': '1', 08:30:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:58 INFO - 'PWD': '/builds/slave/test', 08:30:58 INFO - 'SHELL': '/bin/bash', 08:30:58 INFO - 'SHLVL': '1', 08:30:58 INFO - 'TERM': 'linux', 08:30:58 INFO - 'TMOUT': '86400', 08:30:58 INFO - 'USER': 'cltbld', 08:30:58 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', 08:30:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:58 INFO - '_': '/tools/buildbot/bin/python'} 08:30:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:30:58 INFO - Cleaning up... 08:30:58 INFO - Return code: 0 08:30:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xe5c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cb090>, '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 0x11cbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', '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 08:30:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:30:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:30:58 INFO - Using env: {'DISPLAY': ':0', 08:30:58 INFO - 'HOME': '/home/cltbld', 08:30:58 INFO - 'LANG': 'en_US.UTF-8', 08:30:58 INFO - 'LANGUAGE': 'en_US:en', 08:30:58 INFO - 'LOGNAME': 'cltbld', 08:30:58 INFO - 'MAIL': '/var/mail/cltbld', 08:30:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:58 INFO - 'MOZ_NO_REMOTE': '1', 08:30:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:58 INFO - 'NO_EM_RESTART': '1', 08:30:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:58 INFO - 'PWD': '/builds/slave/test', 08:30:58 INFO - 'SHELL': '/bin/bash', 08:30:58 INFO - 'SHLVL': '1', 08:30:58 INFO - 'TERM': 'linux', 08:30:58 INFO - 'TMOUT': '86400', 08:30:58 INFO - 'USER': 'cltbld', 08:30:58 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', 08:30:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:58 INFO - '_': '/tools/buildbot/bin/python'} 08:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:59 INFO - Downloading/unpacking blobuploader==1.2.4 08:30: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 08:30: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 08:30: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 08:30: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 08:31:00 INFO - Downloading blobuploader-1.2.4.tar.gz 08:31:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:31:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:31:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:00 INFO - Downloading docopt-0.6.1.tar.gz 08:31:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:31:00 INFO - Installing collected packages: blobuploader, requests, docopt 08:31:00 INFO - Running setup.py install for blobuploader 08:31:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:31:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:31:00 INFO - Running setup.py install for requests 08:31:01 INFO - Running setup.py install for docopt 08:31:01 INFO - Successfully installed blobuploader requests docopt 08:31:01 INFO - Cleaning up... 08:31:01 INFO - Return code: 0 08:31:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:01 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 0xe5c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cb090>, '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 0x11cbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', '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 08:31:01 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 08:31:01 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 08:31:01 INFO - Using env: {'DISPLAY': ':0', 08:31:01 INFO - 'HOME': '/home/cltbld', 08:31:01 INFO - 'LANG': 'en_US.UTF-8', 08:31:01 INFO - 'LANGUAGE': 'en_US:en', 08:31:01 INFO - 'LOGNAME': 'cltbld', 08:31:01 INFO - 'MAIL': '/var/mail/cltbld', 08:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:01 INFO - 'MOZ_NO_REMOTE': '1', 08:31:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:01 INFO - 'NO_EM_RESTART': '1', 08:31:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:01 INFO - 'PWD': '/builds/slave/test', 08:31:01 INFO - 'SHELL': '/bin/bash', 08:31:01 INFO - 'SHLVL': '1', 08:31:01 INFO - 'TERM': 'linux', 08:31:01 INFO - 'TMOUT': '86400', 08:31:01 INFO - 'USER': 'cltbld', 08:31:01 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', 08:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:01 INFO - '_': '/tools/buildbot/bin/python'} 08:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:31:01 INFO - Running setup.py (path:/tmp/pip-82msAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:31:01 INFO - Running setup.py (path:/tmp/pip-INVtAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:31:01 INFO - Running setup.py (path:/tmp/pip-DgNwBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:31:01 INFO - Running setup.py (path:/tmp/pip-fJFyGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:31:01 INFO - Running setup.py (path:/tmp/pip-UAfgJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:31:02 INFO - Running setup.py (path:/tmp/pip-x61buz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:31:02 INFO - Running setup.py (path:/tmp/pip-X72CE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:31:02 INFO - Running setup.py (path:/tmp/pip-MjYvGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:31:02 INFO - Running setup.py (path:/tmp/pip-6PcGV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:31:02 INFO - Running setup.py (path:/tmp/pip-1FhCLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:31:02 INFO - Running setup.py (path:/tmp/pip-tSdafQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:31:02 INFO - Running setup.py (path:/tmp/pip-T8mlk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:31:02 INFO - Running setup.py (path:/tmp/pip-b7AaiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:31:02 INFO - Running setup.py (path:/tmp/pip-blucxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:02 INFO - Running setup.py (path:/tmp/pip-kKZsuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:31:02 INFO - Running setup.py (path:/tmp/pip-BwtC6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:31:02 INFO - Running setup.py (path:/tmp/pip-Fm8jzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:31:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:31:02 INFO - Running setup.py install for manifestparser 08:31:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:31:02 INFO - Running setup.py install for mozcrash 08:31:03 INFO - Running setup.py install for mozdebug 08:31:03 INFO - Running setup.py install for mozdevice 08:31:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:31:03 INFO - Running setup.py install for mozfile 08:31:03 INFO - Running setup.py install for mozhttpd 08:31:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:31:03 INFO - Running setup.py install for mozinfo 08:31:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:31:03 INFO - Running setup.py install for mozInstall 08:31:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:31:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:31:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:31:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:31:03 INFO - Running setup.py install for mozleak 08:31:03 INFO - Running setup.py install for mozlog 08:31:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:31:03 INFO - Running setup.py install for moznetwork 08:31:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:31:03 INFO - Running setup.py install for mozprocess 08:31:04 INFO - Running setup.py install for mozprofile 08:31:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:31:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:31:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:31:04 INFO - Running setup.py install for mozrunner 08:31:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:31:04 INFO - Running setup.py install for mozscreenshot 08:31:04 INFO - Running setup.py install for moztest 08:31:04 INFO - Running setup.py install for mozversion 08:31:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:31:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:31:04 INFO - Cleaning up... 08:31:04 INFO - Return code: 0 08:31:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:04 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 0xe5c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cb090>, '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 0x11cbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', '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 08:31:04 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 08:31:04 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 08:31:04 INFO - Using env: {'DISPLAY': ':0', 08:31:04 INFO - 'HOME': '/home/cltbld', 08:31:04 INFO - 'LANG': 'en_US.UTF-8', 08:31:04 INFO - 'LANGUAGE': 'en_US:en', 08:31:04 INFO - 'LOGNAME': 'cltbld', 08:31:04 INFO - 'MAIL': '/var/mail/cltbld', 08:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:04 INFO - 'MOZ_NO_REMOTE': '1', 08:31:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:04 INFO - 'NO_EM_RESTART': '1', 08:31:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:04 INFO - 'PWD': '/builds/slave/test', 08:31:04 INFO - 'SHELL': '/bin/bash', 08:31:04 INFO - 'SHLVL': '1', 08:31:04 INFO - 'TERM': 'linux', 08:31:04 INFO - 'TMOUT': '86400', 08:31:04 INFO - 'USER': 'cltbld', 08:31:04 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', 08:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:04 INFO - '_': '/tools/buildbot/bin/python'} 08:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:31:04 INFO - Running setup.py (path:/tmp/pip-2CMzLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:31:04 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)) 08:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:31:04 INFO - Running setup.py (path:/tmp/pip-KWNNnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:31:04 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)) 08:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:31:05 INFO - Running setup.py (path:/tmp/pip-wv3MLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:31:05 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)) 08:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:31:05 INFO - Running setup.py (path:/tmp/pip-HEKAK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:31:05 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)) 08:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:31:05 INFO - Running setup.py (path:/tmp/pip-8B4VRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:31:05 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)) 08:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:31:05 INFO - Running setup.py (path:/tmp/pip-NWA9Qg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:31:05 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)) 08:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:31:05 INFO - Running setup.py (path:/tmp/pip-s1QxP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:31:05 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)) 08:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:31:05 INFO - Running setup.py (path:/tmp/pip-yU3xVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:31:05 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)) 08:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:31:05 INFO - Running setup.py (path:/tmp/pip-dAwuBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:31:05 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)) 08:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:31:05 INFO - Running setup.py (path:/tmp/pip-1oxtKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:31:05 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)) 08:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:31:05 INFO - Running setup.py (path:/tmp/pip-z4T9GJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:31:05 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)) 08:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:31:05 INFO - Running setup.py (path:/tmp/pip-88hQJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:31:05 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)) 08:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:31:05 INFO - Running setup.py (path:/tmp/pip-VlN8iM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:31:05 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)) 08:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:31:05 INFO - Running setup.py (path:/tmp/pip-HNKRjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:31:05 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)) 08:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:05 INFO - Running setup.py (path:/tmp/pip-RHt5XK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:05 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)) 08:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:31:05 INFO - Running setup.py (path:/tmp/pip-_zHcX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:31:06 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)) 08:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:31:06 INFO - Running setup.py (path:/tmp/pip-RsBdIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:31:06 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)) 08:31:06 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)) 08:31:06 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)) 08:31:06 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)) 08:31:06 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)) 08:31:06 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)) 08:31:06 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)) 08:31:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:07 INFO - Downloading blessings-1.6.tar.gz 08:31:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:31:07 INFO - Installing collected packages: blessings 08:31:07 INFO - Running setup.py install for blessings 08:31:07 INFO - Successfully installed blessings 08:31:07 INFO - Cleaning up... 08:31:07 INFO - Return code: 0 08:31:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:31:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:31:07 INFO - Reading from file tmpfile_stdout 08:31:07 INFO - Current package versions: 08:31:07 INFO - argparse == 1.2.1 08:31:07 INFO - blessings == 1.6 08:31:07 INFO - blobuploader == 1.2.4 08:31:07 INFO - docopt == 0.6.1 08:31:07 INFO - functools32 == 3.2.3-2 08:31:07 INFO - jsonschema == 2.5.1 08:31:07 INFO - manifestparser == 1.1 08:31:07 INFO - mozInstall == 1.13 08:31:07 INFO - mozcrash == 1.0 08:31:07 INFO - mozdebug == 0.1 08:31:07 INFO - mozdevice == 0.51 08:31:07 INFO - mozfile == 1.2 08:31:07 INFO - mozhttpd == 0.7 08:31:07 INFO - mozinfo == 0.10 08:31:07 INFO - mozleak == 0.1 08:31:07 INFO - mozlog == 3.5 08:31:07 INFO - moznetwork == 0.27 08:31:07 INFO - mozprocess == 0.25 08:31:07 INFO - mozprofile == 0.28 08:31:07 INFO - mozrunner == 6.13 08:31:07 INFO - mozscreenshot == 0.1 08:31:07 INFO - mozsystemmonitor == 0.3 08:31:07 INFO - moztest == 0.8 08:31:07 INFO - mozversion == 1.4 08:31:07 INFO - psutil == 3.1.1 08:31:07 INFO - requests == 1.2.3 08:31:07 INFO - wsgiref == 0.1.2 08:31:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:07 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 0xe5c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cb090>, '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 0x11cbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', '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 08:31:07 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 08:31:07 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 08:31:07 INFO - Using env: {'DISPLAY': ':0', 08:31:07 INFO - 'HOME': '/home/cltbld', 08:31:07 INFO - 'LANG': 'en_US.UTF-8', 08:31:07 INFO - 'LANGUAGE': 'en_US:en', 08:31:07 INFO - 'LOGNAME': 'cltbld', 08:31:07 INFO - 'MAIL': '/var/mail/cltbld', 08:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:07 INFO - 'MOZ_NO_REMOTE': '1', 08:31:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:07 INFO - 'NO_EM_RESTART': '1', 08:31:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:07 INFO - 'PWD': '/builds/slave/test', 08:31:07 INFO - 'SHELL': '/bin/bash', 08:31:07 INFO - 'SHLVL': '1', 08:31:07 INFO - 'TERM': 'linux', 08:31:07 INFO - 'TMOUT': '86400', 08:31:07 INFO - 'USER': 'cltbld', 08:31:07 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', 08:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:07 INFO - '_': '/tools/buildbot/bin/python'} 08:31:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:07 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)) 08:31:07 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)) 08:31:07 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)) 08:31:07 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)) 08:31:07 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)) 08:31:07 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)) 08:31:07 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)) 08:31:07 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)) 08:31:07 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)) 08:31:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:31:07 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 08:31:07 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 08:31:07 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 08:31:07 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 08:31:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 08:31:09 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)) 08:31:09 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)) 08:31:09 INFO - Installing collected packages: simplejson 08:31:09 INFO - Running setup.py install for simplejson 08:31:09 INFO - building 'simplejson._speedups' extension 08:31:09 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 08:31:09 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 08:31:10 INFO - Successfully installed simplejson 08:31:10 INFO - Cleaning up... 08:31:10 INFO - Return code: 0 08:31:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:10 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 0xe5c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cb090>, '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 0x11cbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5f90>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', '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 08:31:10 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 08:31:10 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 08:31:10 INFO - Using env: {'DISPLAY': ':0', 08:31:10 INFO - 'HOME': '/home/cltbld', 08:31:10 INFO - 'LANG': 'en_US.UTF-8', 08:31:10 INFO - 'LANGUAGE': 'en_US:en', 08:31:10 INFO - 'LOGNAME': 'cltbld', 08:31:10 INFO - 'MAIL': '/var/mail/cltbld', 08:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:10 INFO - 'MOZ_NO_REMOTE': '1', 08:31:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:10 INFO - 'NO_EM_RESTART': '1', 08:31:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:10 INFO - 'PWD': '/builds/slave/test', 08:31:10 INFO - 'SHELL': '/bin/bash', 08:31:10 INFO - 'SHLVL': '1', 08:31:10 INFO - 'TERM': 'linux', 08:31:10 INFO - 'TMOUT': '86400', 08:31:10 INFO - 'USER': 'cltbld', 08:31:10 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', 08:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:10 INFO - '_': '/tools/buildbot/bin/python'} 08:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:31:10 INFO - Cleaning up... 08:31:10 INFO - Return code: 0 08:31:10 INFO - Running post-action listener: _resource_record_post_action 08:31:10 INFO - Running post-action listener: _start_resource_monitoring 08:31:10 INFO - Starting resource monitoring. 08:31:10 INFO - [mozharness: 2017-10-24 15:31:10.345234Z] Finished create-virtualenv step (success) 08:31:10 INFO - [mozharness: 2017-10-24 15:31:10.345780Z] Running install step. 08:31:10 INFO - Running pre-action listener: _resource_record_pre_action 08:31:10 INFO - Running main action method: install 08:31:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:31:10 INFO - Reading from file tmpfile_stdout 08:31:10 INFO - Detecting whether we're running mozinstall >=1.0... 08:31:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:31:10 INFO - Reading from file tmpfile_stdout 08:31:10 INFO - Output received: 08:31:10 INFO - Usage: mozinstall [options] installer 08:31:10 INFO - Options: 08:31:10 INFO - -h, --help show this help message and exit 08:31:10 INFO - -d DEST, --destination=DEST 08:31:10 INFO - Directory to install application into. [default: 08:31:10 INFO - "/builds/slave/test"] 08:31:10 INFO - --app=APP Application being installed. [default: firefox] 08:31:10 INFO - mkdir: /builds/slave/test/build/application 08:31:10 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'] 08:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:31:25 INFO - Reading from file tmpfile_stdout 08:31:25 INFO - Output received: 08:31:25 INFO - /builds/slave/test/build/application/firefox/firefox 08:31:25 INFO - Running post-action listener: _resource_record_post_action 08:31:25 INFO - [mozharness: 2017-10-24 15:31:25.700508Z] Finished install step (success) 08:31:25 INFO - [mozharness: 2017-10-24 15:31:25.700654Z] Running setup-mitmproxy step. 08:31:25 INFO - Running pre-action listener: _resource_record_pre_action 08:31:25 INFO - Running main action method: setup_mitmproxy 08:31:25 INFO - Skipping: mitmproxy is not required 08:31:25 INFO - Running post-action listener: _resource_record_post_action 08:31:25 INFO - [mozharness: 2017-10-24 15:31:25.701182Z] Finished setup-mitmproxy step (success) 08:31:25 INFO - [mozharness: 2017-10-24 15:31:25.701285Z] Running run-tests step. 08:31:25 INFO - Running pre-action listener: _resource_record_pre_action 08:31:25 INFO - Running pre-action listener: _set_gcov_prefix 08:31:25 INFO - Running main action method: run_tests 08:31:25 WARNING - Try message not found. 08:31:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:31:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:31:25 INFO - Python 2.7.3 08:31:25 INFO - Return code: 0 08:31:25 INFO - grabbing minidump binary from tooltool 08:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:25 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cb090>, '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 0x11cbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c5f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:31:25 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:31:25 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:31:25 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:31:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:31:25 INFO - Return code: 0 08:31:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:31:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:31:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:31:25 INFO - ENV: RUST_BACKTRACE is now full 08:31:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:31:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:31:25 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/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 08:31:25 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/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-019 --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 08:31:25 INFO - Using env: {'DISPLAY': ':0', 08:31:25 INFO - 'HOME': '/home/cltbld', 08:31:25 INFO - 'LANG': 'en_US.UTF-8', 08:31:25 INFO - 'LANGUAGE': 'en_US:en', 08:31:25 INFO - 'LOGNAME': 'cltbld', 08:31:25 INFO - 'MAIL': '/var/mail/cltbld', 08:31:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:31:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:25 INFO - 'MOZ_NO_REMOTE': '1', 08:31:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:31:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:25 INFO - 'NO_EM_RESTART': '1', 08:31:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:25 INFO - 'PWD': '/builds/slave/test', 08:31:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:31:25 INFO - 'RUST_BACKTRACE': 'full', 08:31:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:31:25 INFO - 'SHELL': '/bin/bash', 08:31:25 INFO - 'SHLVL': '1', 08:31:25 INFO - 'TERM': 'linux', 08:31:25 INFO - 'TMOUT': '86400', 08:31:25 INFO - 'USER': 'cltbld', 08:31:25 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', 08:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:25 INFO - '_': '/tools/buildbot/bin/python'} 08:31:25 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/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 08:31:26 INFO - mozversion application_buildid: 20171024141159 08:31:26 INFO - mozversion application_changeset: cae5754b8af50a9b81e92b261365d6a6d4cbffb2 08:31:26 INFO - mozversion application_display_name: Firefox 08:31:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:31:26 INFO - mozversion application_name: Firefox 08:31:26 INFO - mozversion application_remotingname: firefox 08:31:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:31:26 INFO - mozversion application_vendor: Mozilla 08:31:26 INFO - mozversion application_version: 57.0 08:31:26 INFO - mozversion platform_buildid: 20171024141159 08:31:26 INFO - mozversion platform_changeset: cae5754b8af50a9b81e92b261365d6a6d4cbffb2 08:31:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:31:26 INFO - mozversion platform_version: 57.0 08:31:26 INFO - using testdate: 1508859086 08:31:26 INFO - actual date: 1508859086 08:31:26 INFO - starting webserver on 'localhost:58448' 08:31:26 INFO - SUITE-START | Running 2 tests 08:31:26 INFO - TEST-START | dromaeo_css 08:31:26 INFO - Initialising browser for dromaeo_css test... 08:31:26 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58448/getInfo.html -profile /tmp/tmpb75sU7/profile 08:31:26 INFO - TEST-INFO | started process 18060 (/builds/slave/test/build/application/firefox/firefox http://localhost:58448/getInfo.html) 08:31:31 INFO - TEST-INFO | 18060: exit 0 08:31:31 INFO - Browser initialized. 08:31:31 INFO - Running cycle 1/1 for dromaeo_css test... 08:31:31 INFO - Using env: {'DISPLAY': ':0', 08:31:31 INFO - 'HOME': '/home/cltbld', 08:31:31 INFO - 'JSGC_DISABLE_POISONING': '1', 08:31:31 INFO - 'LANG': 'en_US.UTF-8', 08:31:31 INFO - 'LANGUAGE': 'en_US:en', 08:31:31 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 08:31:31 INFO - 'LOGNAME': 'cltbld', 08:31:31 INFO - 'MAIL': '/var/mail/cltbld', 08:31:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:31:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:31:31 INFO - 'MOZ_CRASHREPORTER': '1', 08:31:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:31 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 08:31:31 INFO - 'MOZ_NO_REMOTE': '1', 08:31:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:31:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:31 INFO - 'NO_EM_RESTART': '1', 08:31:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:31 INFO - 'PWD': '/builds/slave/test', 08:31:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:31:31 INFO - 'RUST_BACKTRACE': 'full', 08:31:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:31:31 INFO - 'SHELL': '/bin/bash', 08:31:31 INFO - 'SHLVL': '1', 08:31:31 INFO - 'TERM': 'linux', 08:31:31 INFO - 'TMOUT': '86400', 08:31:31 INFO - 'USER': 'cltbld', 08:31:31 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', 08:31:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:31 INFO - '_': '/tools/buildbot/bin/python'} 08:31:31 INFO - TEST-INFO | started process 18296 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpb75sU7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:31:32 INFO - PID 18296 | 08:31:32 INFO - PID 18296 | (/builds/slave/test/build/application/firefox/firefox:18343): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 08:31:32 INFO - PID 18296 | 08:31:42 INFO - PID 18296 | RSS: Main: 142020608 08:31:42 INFO - PID 18296 | 08:34:40 INFO - PID 18296 | Cycle 1(1): loaded http://localhost:58448/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58448/tests/dromaeo/cssquery-ext.html) 08:34:41 INFO - PID 18296 | RSS: Main: 148086784 08:34:41 INFO - PID 18296 | 08:37:43 INFO - PID 18296 | Cycle 1(1): loaded http://localhost:58448/tests/dromaeo/cssquery-ext.html (next: http://localhost:58448/tests/dromaeo/cssquery-jquery.html) 08:37:43 INFO - PID 18296 | RSS: Main: 144441344 08:37:43 INFO - PID 18296 | 08:40:45 INFO - PID 18296 | Cycle 1(1): loaded http://localhost:58448/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58448/tests/dromaeo/cssquery-mootools.html) 08:40:46 INFO - PID 18296 | RSS: Main: 144457728 08:40:46 INFO - PID 18296 | 08:43:47 INFO - PID 18296 | Cycle 1(1): loaded http://localhost:58448/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58448/tests/dromaeo/cssquery-prototype.html) 08:43:48 INFO - PID 18296 | RSS: Main: 146599936 08:43:48 INFO - PID 18296 | 08:46:51 INFO - PID 18296 | Cycle 1(1): loaded http://localhost:58448/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58448/tests/dromaeo/cssquery-yui.html) 08:46:51 INFO - PID 18296 | RSS: Main: 147554304 08:46:51 INFO - PID 18296 | 08:49:53 INFO - PID 18296 | Cycle 1(1): loaded http://localhost:58448/tests/dromaeo/cssquery-yui.html (next: http://localhost:58448/tests/dromaeo/cssquery-dojo.html) 08:49:53 INFO - PID 18296 | RSS: Main: 149397504 08:49:53 INFO - PID 18296 | 08:49:53 INFO - PID 18296 | __start_tp_report 08:49:53 INFO - PID 18296 | _x_x_mozilla_page_load 08:49:53 INFO - PID 18296 | _x_x_mozilla_page_load_details 08:49:53 INFO - PID 18296 | |i|pagename|runs| 08:49:53 INFO - PID 18296 | |0;dojo.html;3999.300122478566;4007.7595344279334;4060.5330387005483;4062.837486500542;4073.2668119738437;6573.704183311763;6618.205815302164;6643.667816609169;6688.163979502562;6715.764948226807;6622.238442579105;6655.733770649181;6715.798526044215;6742.258351581334;6759.56062855914;5945.137954996523;5965.701714914258;6022;6028.336882942873;6059;5943.465088142067;6027.879442411149;6037.698115094249;6039.788607398742;6140.232470941132;6002.429769171944;6007.399260074001;6043.093535969618;6048.667323297217;6116.877662446748;6069.696515174268;6071.878562428748;6095.329513753506;6110.694465276719;6131.87736245277;5989.401059893976;6013.609115407528;6106.572539922207;6108.297545782203;6130.295016073164;6455;6595.0766892635065;6597.373249541334;6619.602823830537;6709.060731497594;7212.855742885113;7238.0952380952385;7245.203027666952;7262.20115787263;7270.563766173994;7263.34629883313;7284.856571047386;7316.195218525958;7347;7367.926320736778;6695.7656482023385;6769.763058292985;6799.388055075017;6823.420009299216;6836.282190370039;7441.1070671519765;7472.103347598323;7546;7563.357114645317;7588.317051465395;7104.0764700589625;7135.322144396274;7186.820329491805;7192.100987376578;7206.675699593531;7227.241139680363;7227.277272272836;7275.745348912868;7313.085864266966;7328.3404493595835;7185.461090418239;7235.819104522429;7237.095363079615;7263.34629883313;7268.600226987461;7244.985702001724;7251.347378735833;7279.2720727927845;7298.708051677873;7319.963400182965;5400.270963419966;5471.5896307777075;5473.863153421196;5475.753591088411;5477;4868.683535570177;4888.755562221874;4946.678465899705;4959.454460009396;4961.801527938842;6746.898796518056;6787.932120678829;6833.794986150324;6849.075374824434;6863.553868998599;7322.780316590511;7347.448941329421;7371.741989030358;7378.815529611803;7380.889286660705;6802.863942721118;6813;6885.862282754317;6889.621070841152;6904.723811047502;6136.202293701879;6216.7824126155365;6236.750529978841;6312.274088479791;6312.43188113072;7379.483436159525;7534;7596.278353556403;7614;7622;7263.128424589083;7272;7335.596542190017;7345.669444874887;7364.116306043309;7523.059617547807;7534.962325188339;7539.434542409232;7627.466077374531;7641.923580764344;7134.250903655042;7147.249538798559;7193.604351760703;7194.244604316471;7213.062301900929;7293.088363954505;7313.634318284171;7343.669534871051;7350.154732205865;7365.153007404003;6888.311168883272;6910;6993.19578248508;7004.964975175091;7011;6955.23492415844;7011.719531218694;7018.227994920657;7020.613866237406;7028.156621205488;6769.120014398293;6817.284185160487;6851.862962740817;6918.06606108189;6937.098177236927;6979.930200697928;7023.508354415142;7051.894221586783;7088.149422069178;7105.680244388912;6916.585004899722;6919.065926100114;6978.162620485574;6988.196357418758;6999.965000174967 08:49:53 INFO - PID 18296 | |1;ext.html;976.6972238606032;1007.1036777268232;1026.6817286641142;1028.2185538990368;1030.9845352319712;44979.32531012033;45617;45780.54219457805;45805;45828;42917.7854110729;43037.7848110759;43052.784736076275;43220.78389608055;43317.56682433175;8195.877061844078;8254;8258.42191046627;8291.668333266662;8326.417150799447;7837.529748215125;7883.684652613889;7888.56612886291;7898.368130549571;7915.485493442937;8762.737117886474;8953.23897468716;9023.729288121334;9030.232430243437;9032.187103160713;8935.77660558488;8947.463152210823;8984.146506081912;8984.146506081945;9005.054469280696;8871.512066836322;8920.286377089882;8992.8651070234;8998;9024.729258122266;42607.57392426098;42667.57332426667;42931.35602965958;43045.56954430447;43202.351964720554;3858.2862170498547;3887.261420330128;3888.416737489371;3889.6499315061496;3891.591382904811;34304.82847585746;34531.82734086338;34599.13502162441;34638;34653.65346346555;5603.971980140073;5808.273965754281;5832.6792026438525;5834.27071616048;5923.111533270001;231429;233091;233947;235534;235536;55328;55768;55840;55853.44146558564;55922.440775592535;56881.7155914226;57295.713521432124;57323.71338143366;57348.698847586595;57430.425695743346;17548.473545793648;17692.73460898062;17696;17697;17957;14809.407623695148;14842.703145937021;14846.18345990981;14846.331915063847;14896.478623248351;14963.326650300764;15147.697046059238;15261.313240904186;15355.539333820003;15531.378744850306;6007.939920600826;6041.093835924558;6043.576949613484;6139.96930015356;6186.350433204449;1113.5211858900589;1114.3703807348822;1121.097516499226;1122.1864148492277;1126.8253420719639;26090.608640870403;26341.34146646311;26597;26806.06178783733;26880.59679104815;1224.8897599215936;1226.7607816475702;1230.0774419185611;1242.490578862662;1248.8688687687843;14070.71858562823;14184.078034927697;14248;14250.85749142516;14426.927865360605;309044;309165;309624;309673;310579;63878;63937.36062639314;64040.35959640344;64231.35768642254;64489.35510644927;1527.5646440764328;1567.4278888205722;1575.9527214183593;1578.968420631581;1596.7924169857845;25811.225663230132;25843.353916152246;25986.480270394863;26141.60787588226;26211;44139.55860441483;44338;44491.555084448744;44512.777436112614;44593;261060;261120;261928;262772;262893;37777.24445511129;44421.33367999418;44553.331700023875;44868.775656121514;45192.774036130926;56324.155137673784;56646.43353566412;56759.35091034617;57382;57732.71133644305;40729.59270407258;41016.58983410128;41104.58895411008;41364;41755.37366939438;30001.54997674993;30110.397792043597;30214.69785302119;30455.543166852956;31071;47289;47358.52641473679;47720;47728.28407573959;47842.282365763844;39860.402093968034;40081.39877901892;40124.1975160501;40149;40334;28864.42271154607;28978.7102128976;29100.7089929098;29340;29657.555136672534 08:49:53 INFO - PID 18296 | |2;jquery.html;633.2220222686285;643.5848348709463;650.3566072568707;650.6412474908785;654.2260814148012;23290.4177395565;23849.284521464364;24030.279091627235;24059.879700601472;24078.638820417687;26616.201513954566;26623.334416639595;26882.327941801464;26944.326391840215;26997.86501067492;6269.686515674221;6548.214214294291;6561.901571476432;6609.537332386735;6653.467722582194;6530.869382612344;6603.570767900095;6612.206535215781;6614.272430032693;6614.338566143395;6576.835579110512;6590.93409065908;6665.033570132328;6899.758508452205;6913.723451061952;6858.862822743542;6916.930830691729;6921.377076063153;6929.1684997800085;6934.618595977269;6424.164858568403;6476.773312934072;6511;6516.511261655347;6530.608163510157;30419.69580304191;30779.230519236975;31475.52786708201;31527.84236078805;31660.841695791605;2401.7238017627847;2457.778799908017;2459.311392810016;2462.4336402627496;2463.8521688698734;20641.277555285495;21108.788912110835;21113.261035863823;21191;21208.89395553028;2548.3756479662416;2573.6525569048126;2630.06690082789;2659.0693257359962;2667.5331816931957;145492;150366.99266014728;156604;162235;163546.9999999988;16060.357585696675;16701;16777.496675099766;16854.157292135344;17045.573860653334;27598.5860212097;27670.169894903185;27733.30666733348;27747.445051098864;27785.86107069452;11079.50142243601;11315.660530184108;11358.772824543463;11402.087832973342;11469;10149.188064954933;10272.383656980604;10295.279330446947;10296.330738501973;10355.896441035644;9701.787276590427;10066.949665251774;10098.192144628412;10130.442825644514;10217.33587316822;2921.5033444314518;2988.551717242431;3025.803322784056;3032.9241768955953;3082.9383412332077;1056.5034433816095;1068.1935138970184;1070.4594179939236;1085.5874767588266;1096.090245096568;21146;21293.574128517343;21562;21724.674129888066;21763.347099587038;1152.1589239854786;1155.3183621663259;1155.8674246016255;1156.502703837358;1159.3913195572393;11788.115891308185;11820.527178912918;12009.399530023464;12083;12092.093093017882;648478;649466;649576;664735;675047;135184;136995;138474.5509726856;139019;139139.30430347985;1256.1646505074023;1257.5661396818084;1278.3509273037678;1297.4810075969688;1306.9019823513274;18853.909444449648;18960.71558926645;19034.619307614048;19122.713159302344;19550.21799128019;65416.672916635114;65672.34327656853;65714.67142664256;66252.66873665794;66284.33715662782;76760.61619691867;77684;81194;81224;81823.5908820452;15843.128627925575;16125.919370403073;16188.514344569681;16321.18394080315;16343.83656163423;24298.75701242965;24550.877245613658;24705.25884223476;24811.379715507257;24909.501809964062;20151.79848201499;20156;20267.493312666713;20307.390778276676;20329.49176270605;17370.218340174975;17760.3783867564;17920.551986200448;17978.460646180636;18415.171317290486;20373.898130509253;20537.48656283605;20576.588468230253;20816.271430500467;20954.47613809667;15851.207439628202;15883.602909926882;15973.840261597235;15989.360425583312;16000.679986400439;19821;19841.20635174577;19977.800221997593;20026.69959950631;20077.899610501852 08:49:53 INFO - PID 18296 | |3;mootools.html;1403.9227842468663;1473.9041962272452;1509.554681368996;1515.2802418851054;1519.9848001519981;18640.161192746324;19152.13815378308;19161.42515724527;19172.616547669037;19179.328723494662;18399.26402943884;18431.44705658829;18436.72344914824;18525.073746312664;18626.813731862676;3059.8623061962207;3160.6365267994247;3162.4465718499287;3162.905112846618;3171.3657268546267;2991.92520186996;3103.301757104656;3104.58088158098;3104.953425698605;3105.7204851563356;3063.7702172337054;3140.921476963071;3141.921451963696;3151.763617728668;3153.889613863515;3059.6022517072747;3131.8590663419973;3132.9686703133134;3133.5926329577123;3135.858886350108;2989.4768415527196;3035.8937437189593;3037.3469704013523;3063.8008529445738;3130.63997640272;17095;17166.399176028903;17204.91397543017;17273.309067637278;17352.566185845328;8411.537365444898;8641.049484556694;8901.287896968228;8913.732588022369;8918.509481978488;6284.12022316876;6294.118823364733;6316.431521163071;6432.485401167895;6459.5478316517865;2163.5023944492696;2164.101897712447;2166.4150679316654;2192.243675931801;2254.3124347074136;32063.51904721431;32106;32372.352552949043;32396.190095247573;32413.837930810194;7587.203343648947;7635.312821846061;7637;7893.723719669784;7925.484843485155;20644.483887902923;20646.96765161736;20741.896290518453;20885.26901558438;20920.267790627255;8684;8975.50634715097;8992.100789921087;8998.730038098867;9019.503927284062;8106.108328083906;8129.227723366271;8159.755207343789;8343.791405214797;8346.666133354598;6160.599561028519;6230.968845155836;6234.470070044097;6286.4342209201395;6346.682665866688;1674.547872074533;1719.6044909670845;1803.638253118887;1815.8728888977644;1855.8886466812035;930.223263574909;937.2736129499692;938.7606160429184;948.8292107420731;951.5147274889717;8920.776980575407;9293.256539476826;9355.625774969061;9384.24926005918;9437.245020398352;1050.7793363393773;1056.7041228456044;1056.804491169136;1060.1201003167353;1084.924055316136;4930;4948.6783359082265;4954.430240522314;4967.180415231446;5031.345925029723;26086.608700869503;26542.6018609721;26563.468730625278;26776.59835102475;26903;26199.73800262011;26236.86881565618;26689.199324019923;26732.19803405901;26747.592716655596;13037.543685971135;13073.803892941614;13132.671683207805;13162.276074815785;13214.27321497327;8987.595558199766;9120.498372589305;9151.954240228757;9160.633574656938;9192.540372981457;21791.23730669419;21805.56388872182;22015.11939522401;23040.308790736304;23040.617562946278;23479.295621131394;23620.17329393463;23628.17301394443;23787.881060594587;23821.523569528166;12900.483980640669;13064.804027939399;13195.472181113031;13276.402561884544;13339.466421343037;16106.838931610533;16111.355545778037;16310.836891630932;16335.509934701979;16380.508584742476;12997.285149316878;13485.797713034117;13518.39167237457;13520.93239533796;13575.79636305476;12922.289274090015;12931.159474634118;12952.8057079142;13225.140365876186;13299.20204787716;9864.408135511892;9881.802363952824;9961.750956226153;10058.497075146068;10073.949630251802;7885.250901164381;7987.92012079895;8001.359891208573;8022.91977080245;8073.475224110414;7579.1284002340435;7787.29914307715;7794.064712234568;7798.922010779819;7804.595172868938 08:49:53 INFO - PID 18296 | |4;prototype.html;562.9887402251956;587.7061469265367;594.2927915780222;601.6299975515057;605.8909396308666;6429.5820771649815;6435.581687190335;6459.903101453476;6508.674566271679;6551.377619126188;6374.426301632852;6403.359664033606;6414.71133798979;6460.935390646092;6474.838129046764;5243.554297884684;5289.894202115975;5303.416624171338;5305.893882122365;5321.93561287744;5026.246063090547;5166.121759300928;5219.765110570014;5234.476552344773;5319.255304257388;5104.259882317062;5112.079825631385;5123;5134.306868572751;5152.562032227265;5080.49195080489;5082.119615742181;5084.8220312288895;5148.819791307324;5149.45930677281;4610.861674149781;4663.60359369454;4702.459217190033;4713.198756211452;4725;6191.9164146274225;6280.277768056686;6300.3384644612115;6319.33646967071;6347.206599175103;5977.432143946318;6077.787277445313;6081.908771368433;6115.296740874812;6163.907541386883;6853.862922741518;6869.519133660646;6872.010675356186;6945.791626251221;6949.722011119549;5306.2571240026045;5316.867078323073;5334.27987221724;5348.090824559834;5348.91976620351;6889;6962.825929351756;6986.336298051677;7026.683799229047;7237.276272372773;5325.707086110301;5379.112446446371;5388.272583201296;5422.701751403711;5465;5326.9467305327225;5448.645838020516;5452.182172674051;5526.171074338881;5528.198411230349;5997.760089596367;6021.337652858182;6027.9095813562835;6086.93044364918;6159.599626024294;6927.549709268881;6934.8613027740175;6973.198082220508;6985.965070174617;6986.231514533397;1858.2195477899315;1889.2052550077208;1902.4863286912457;1911.1972971352066;1912.4931893048354;1283.3775618824861;1322.0217039390782;1323.5169163255343;1350.844652864933;1354.133354653023;6380.39386258314;6413.274358397663;6448.258450278278;6467.708953097122;6480.837979050467;6761.425278851254;6888.345607167311;6913.827154321081;6915;6956.860862782716;6335.954567496404;6361.141245931833;6399.288412720611;6445.452136568444;6531.8367040823405;3578.624244454347;3704.8888533344043;3716.7955762433326;3724.7020238381406;3729.589745128034;7088.468364872655;7092.9645351772915;7160.462965277521;7174.354308112295;7219.386352160125;7028.191757947901;7031.507794454339;7059.364657180776;7072.681729322193;7231.421486281085;7128.010392725009;7130.358267755927;7150.927360895803;7155.284471552907;7331.340179383881;6789.490788190807;6823.454123669995;6997.965010174917;7023.085419551034;7109;6833.384995350442;6923.792286231421;6950.79147625572;6958.547694399847;7004.509684322048;7059.5058345915295;7152.427805775629;7218.386437152901;7221.566705997657;7299.489035767445;5436.809711660073;5447.311333486632;5462.20797984298;5522.861928451821;5531.363893152178;6744.7976560703255;6815.216250131299;6967.7212911482975;6985.284932670529;7000.579965202104;6695.426574754832;6729.7644582441335;6775.796726098225;6777.864442711019;6831.4876384272375;6630.30381809903;6666.633335166613;6739.460843132636;6765.221999469927;6766.695498702472;6494.577852439576;6550.377714117151;6603.9009414859775;6613.710326486289;6662.700178491883;6689.163854518185;6744.595324280559;6750.966245168743;6798.184217893884;6902.240753517009;6577.736890524326;6631.701573429111;6664.633445160563;6685.866282674417;6691.895837187004 08:49:53 INFO - PID 18296 | |5;yui.html;994.3487016004517;997.5610731278241;1021.8160731068409;1027.5170669785207;1033.204432586908;20157.697634535456;21103.15587376505;21377.358679239627;21404.57190856184;21495.462613434633;19736.802631973675;19850.801491985076;19851.702224466608;19871;19896.20415183391;979.2606582030556;994.41329615527;997.6408492942535;998.6604554451485;1001.6694490818013;1050.495762034224;1055.1611468882256;1057.2968975631195;1061.2253055269618;1061.3843970497094;1040.5473618975732;1084.9023587877089;1089.3082892363327;1089.6240796925088;1112.6495154026495;1058.364981011387;1071.0253669161027;1075.8655168103987;1075.9946200268987;1077.498962982213;803.6102490292203;805.5368163306123;805.6817557064985;807.2532907061004;807.3783186946077;20394.184232630676;20708.171673133056;21036.579268414698;21135.894320528452;21202.469938251514;23430.648540271908;23607.527849443086;23904.76095239043;23927.16254931069;23951.28146155618;18362.449126526226;18395;18554;18591.34930277434;18606.069696515122;4005.819738111792;4160.313548264533;4207.726497777665;4237.216115018731;4239.173361194537;125988.37005815005;134556.3272183633;157273.21363393223;157921;160835;18282;18629.44111676652;18832.435026949213;18852.340168094324;18906.43280701581;34560.82719586386;35388.292234155175;35498.82250588782;35529.64470355315;35657.46513802295;9935.552900119512;9979.401235925867;10004.599816007278;10007.049330313608;10026.548805303779;4712.175369310357;4771.021940502258;4772.665913386029;4783.7129772213775;4793.113274044313;6135.509159267248;6214.067889816563;6246.469050130699;6282.528810339243;6296.748130074746;1319.116192151261;1339.3638021939696;1361.8229630148016;1363.8431580368185;1364.6520137364907;710.989335159973;721.6247551273414;724.8043028382307;736.9631518424058;751.8458715963214;13470.730585388434;13697.452101916011;13763.898888088928;13913.165210087427;13999.580012599637;770.603139383218;774.9108852481924;781.0705260739815;787.4527203593562;788.0071110400936;6330.493560515148;6437.742490300429;6469.126667899867;6469.870602587922;6476.773312934025;139118;142419;143614.28192858968;143631;147393;124627.37686311692;127620.36189819177;129441.35279323731;130878;132516;106617.46691266494;111351;113806;114947.42526287315;117111;14535.200563969083;15015.924920375328;15063.322150503473;15070.924645376703;15225.923870380577;54390;54562.7271863654;54628.726856365465;55850.44149558452;57370.426295736506;72212;72569.91145132933;72953;73560.26439735533;73849;19884.90057549761;20116.798832011493;20237.69643455379;20301.593968120258;20373.57384983037;22582;22742;22772.65841012353;22789.544209115393;22960.19639312616;21515.784842152003;21912.561748765253;21943.451413714785;22148.446288842264;22151.44621384478;20078.297259595845;20337.898310508353;20366.28717994863;20492.38522844317;20494.487637809176;20434.79565204329;20472.795272047686;20486.38540843777;20681;20690.689639655116;15843.920780396023;15875.206239687739;16103.597410064842;16265.918670406572;16353.754693679843;16068.67862642764;16140.435084771976;16251.349946002027;16269.593260168116;16325.102119383073 08:49:53 INFO - PID 18296 | __end_tp_report 08:49:53 INFO - PID 18296 | __start_cc_report 08:49:53 INFO - PID 18296 | _x_x_mozilla_cycle_collect,244 08:49:53 INFO - PID 18296 | __end_cc_report 08:49:53 INFO - PID 18296 | __startTimestamp1508860193500__endTimestamp 08:49:53 INFO - PID 18296 | 08:49:53 INFO - PID 18296 | ------- Summary: start ------- 08:49:53 INFO - PID 18296 | Number of tests: 6 08:49:53 INFO - PID 18296 | 08:49:53 INFO - PID 18296 | [#0] dojo.html Cycles:165 Average:6712.36 Median:6919.07 stddev:781.67 (11.3%) stddev-sans-first:754.53 08:49:53 INFO - PID 18296 | Values: 3999.3 4007.8 4060.5 4062.8 4073.3 6573.7 6618.2 6643.7 6688.2 6715.8 6622.2 6655.7 6715.8 6742.3 6759.6 5945.1 5965.7 6022.0 6028.3 6059.0 5943.5 6027.9 6037.7 6039.8 6140.2 6002.4 6007.4 6043.1 6048.7 6116.9 6069.7 6071.9 6095.3 6110.7 6131.9 5989.4 6013.6 6106.6 6108.3 6130.3 6455.0 6595.1 6597.4 6619.6 6709.1 7212.9 7238.1 7245.2 7262.2 7270.6 7263.3 7284.9 7316.2 7347.0 7367.9 6695.8 6769.8 6799.4 6823.4 6836.3 7441.1 7472.1 7546.0 7563.4 7588.3 7104.1 7135.3 7186.8 7192.1 7206.7 7227.2 7227.3 7275.7 7313.1 7328.3 7185.5 7235.8 7237.1 7263.3 7268.6 7245.0 7251.3 7279.3 7298.7 7320.0 5400.3 5471.6 5473.9 5475.8 5477.0 4868.7 4888.8 4946.7 4959.5 4961.8 6746.9 6787.9 6833.8 6849.1 6863.6 7322.8 7347.4 7371.7 7378.8 7380.9 6802.9 6813.0 6885.9 6889.6 6904.7 6136.2 6216.8 6236.8 6312.3 6312.4 7379.5 7534.0 7596.3 7614.0 7622.0 7263.1 7272.0 7335.6 7345.7 7364.1 7523.1 7535.0 7539.4 7627.5 7641.9 7134.3 7147.2 7193.6 7194.2 7213.1 7293.1 7313.6 7343.7 7350.2 7365.2 6888.3 6910.0 6993.2 7005.0 7011.0 6955.2 7011.7 7018.2 7020.6 7028.2 6769.1 6817.3 6851.9 6918.1 6937.1 6979.9 7023.5 7051.9 7088.1 7105.7 6916.6 6919.1 6978.2 6988.2 7000.0 08:49:53 INFO - PID 18296 | 08:49:53 INFO - PID 18296 | [#1] ext.html Cycles:180 Average:46118.94 Median:40873.09 stddev:70386.34 (172.2%) stddev-sans-first:70502.17 08:49:53 INFO - PID 18296 | Values: 976.7 1007.1 1026.7 1028.2 1031.0 44979.3 45617.0 45780.5 45805.0 45828.0 42917.8 43037.8 43052.8 43220.8 43317.6 8195.9 8254.0 8258.4 8291.7 8326.4 7837.5 7883.7 7888.6 7898.4 7915.5 8762.7 8953.2 9023.7 9030.2 9032.2 8935.8 8947.5 8984.1 8984.1 9005.1 8871.5 8920.3 8992.9 8998.0 9024.7 42607.6 42667.6 42931.4 43045.6 43202.4 3858.3 3887.3 3888.4 3889.6 3891.6 34304.8 34531.8 34599.1 34638.0 34653.7 5604.0 5808.3 5832.7 5834.3 5923.1 231429.0 233091.0 233947.0 235534.0 235536.0 55328.0 55768.0 55840.0 55853.4 55922.4 56881.7 57295.7 57323.7 57348.7 57430.4 17548.5 17692.7 17696.0 17697.0 17957.0 14809.4 14842.7 14846.2 14846.3 14896.5 14963.3 15147.7 15261.3 15355.5 15531.4 6007.9 6041.1 6043.6 6140.0 6186.4 1113.5 1114.4 1121.1 1122.2 1126.8 26090.6 26341.3 26597.0 26806.1 26880.6 1224.9 1226.8 1230.1 1242.5 1248.9 14070.7 14184.1 14248.0 14250.9 14426.9 309044.0 309165.0 309624.0 309673.0 310579.0 63878.0 63937.4 64040.4 64231.4 64489.4 1527.6 1567.4 1576.0 1579.0 1596.8 25811.2 25843.4 25986.5 26141.6 26211.0 44139.6 44338.0 44491.6 44512.8 44593.0 261060.0 261120.0 261928.0 262772.0 262893.0 37777.2 44421.3 44553.3 44868.8 45192.8 56324.2 56646.4 56759.4 57382.0 57732.7 40729.6 41016.6 41104.6 41364.0 41755.4 30001.5 30110.4 30214.7 30455.5 31071.0 47289.0 47358.5 47720.0 47728.3 47842.3 39860.4 40081.4 40124.2 40149.0 40334.0 28864.4 28978.7 29100.7 29340.0 29657.6 08:49:53 INFO - PID 18296 | 08:49:53 INFO - PID 18296 | [#2] jquery.html Cycles:180 Average:41915.41 Median:21427.79 stddev:109729.98 (512.1%) stddev-sans-first:109994.03 08:49:53 INFO - PID 18296 | Values: 633.2 643.6 650.4 650.6 654.2 23290.4 23849.3 24030.3 24059.9 24078.6 26616.2 26623.3 26882.3 26944.3 26997.9 6269.7 6548.2 6561.9 6609.5 6653.5 6530.9 6603.6 6612.2 6614.3 6614.3 6576.8 6590.9 6665.0 6899.8 6913.7 6858.9 6916.9 6921.4 6929.2 6934.6 6424.2 6476.8 6511.0 6516.5 6530.6 30419.7 30779.2 31475.5 31527.8 31660.8 2401.7 2457.8 2459.3 2462.4 2463.9 20641.3 21108.8 21113.3 21191.0 21208.9 2548.4 2573.7 2630.1 2659.1 2667.5 145492.0 150367.0 156604.0 162235.0 163547.0 16060.4 16701.0 16777.5 16854.2 17045.6 27598.6 27670.2 27733.3 27747.4 27785.9 11079.5 11315.7 11358.8 11402.1 11469.0 10149.2 10272.4 10295.3 10296.3 10355.9 9701.8 10066.9 10098.2 10130.4 10217.3 2921.5 2988.6 3025.8 3032.9 3082.9 1056.5 1068.2 1070.5 1085.6 1096.1 21146.0 21293.6 21562.0 21724.7 21763.3 1152.2 1155.3 1155.9 1156.5 1159.4 11788.1 11820.5 12009.4 12083.0 12092.1 648478.0 649466.0 649576.0 664735.0 675047.0 135184.0 136995.0 138474.6 139019.0 139139.3 1256.2 1257.6 1278.4 1297.5 1306.9 18853.9 18960.7 19034.6 19122.7 19550.2 65416.7 65672.3 65714.7 66252.7 66284.3 76760.6 77684.0 81194.0 81224.0 81823.6 15843.1 16125.9 16188.5 16321.2 16343.8 24298.8 24550.9 24705.3 24811.4 24909.5 20151.8 20156.0 20267.5 20307.4 20329.5 17370.2 17760.4 17920.6 17978.5 18415.2 20373.9 20537.5 20576.6 20816.3 20954.5 15851.2 15883.6 15973.8 15989.4 16000.7 19821.0 19841.2 19977.8 20026.7 20077.9 08:49:53 INFO - PID 18296 | 08:49:53 INFO - PID 18296 | [#3] mootools.html Cycles:180 Average:10950.80 Median:3048.47 stddev:8235.10 (270.1%) stddev-sans-first:8226.97 08:49:53 INFO - PID 18296 | Values: 1403.9 1473.9 1509.6 1515.3 1520.0 18640.2 19152.1 19161.4 19172.6 19179.3 18399.3 18431.4 18436.7 18525.1 18626.8 3059.9 3160.6 3162.4 3162.9 3171.4 2991.9 3103.3 3104.6 3105.0 3105.7 3063.8 3140.9 3141.9 3151.8 3153.9 3059.6 3131.9 3133.0 3133.6 3135.9 2989.5 3035.9 3037.3 3063.8 3130.6 17095.0 17166.4 17204.9 17273.3 17352.6 8411.5 8641.0 8901.3 8913.7 8918.5 6284.1 6294.1 6316.4 6432.5 6459.5 2163.5 2164.1 2166.4 2192.2 2254.3 32063.5 32106.0 32372.4 32396.2 32413.8 7587.2 7635.3 7637.0 7893.7 7925.5 20644.5 20647.0 20741.9 20885.3 20920.3 8684.0 8975.5 8992.1 8998.7 9019.5 8106.1 8129.2 8159.8 8343.8 8346.7 6160.6 6231.0 6234.5 6286.4 6346.7 1674.5 1719.6 1803.6 1815.9 1855.9 930.2 937.3 938.8 948.8 951.5 8920.8 9293.3 9355.6 9384.2 9437.2 1050.8 1056.7 1056.8 1060.1 1084.9 4930.0 4948.7 4954.4 4967.2 5031.3 26086.6 26542.6 26563.5 26776.6 26903.0 26199.7 26236.9 26689.2 26732.2 26747.6 13037.5 13073.8 13132.7 13162.3 13214.3 8987.6 9120.5 9152.0 9160.6 9192.5 21791.2 21805.6 22015.1 23040.3 23040.6 23479.3 23620.2 23628.2 23787.9 23821.5 12900.5 13064.8 13195.5 13276.4 13339.5 16106.8 16111.4 16310.8 16335.5 16380.5 12997.3 13485.8 13518.4 13520.9 13575.8 12922.3 12931.2 12952.8 13225.1 13299.2 9864.4 9881.8 9961.8 10058.5 10073.9 7885.3 7987.9 8001.4 8022.9 8073.5 7579.1 7787.3 7794.1 7798.9 7804.6 08:49:53 INFO - PID 18296 | 08:49:53 INFO - PID 18296 | [#4] prototype.html Cycles:180 Average:5816.60 Median:6446.86 stddev:1610.04 (25.0%) stddev-sans-first:1565.52 08:49:53 INFO - PID 18296 | Values: 563.0 587.7 594.3 601.6 605.9 6429.6 6435.6 6459.9 6508.7 6551.4 6374.4 6403.4 6414.7 6460.9 6474.8 5243.6 5289.9 5303.4 5305.9 5321.9 5026.2 5166.1 5219.8 5234.5 5319.3 5104.3 5112.1 5123.0 5134.3 5152.6 5080.5 5082.1 5084.8 5148.8 5149.5 4610.9 4663.6 4702.5 4713.2 4725.0 6191.9 6280.3 6300.3 6319.3 6347.2 5977.4 6077.8 6081.9 6115.3 6163.9 6853.9 6869.5 6872.0 6945.8 6949.7 5306.3 5316.9 5334.3 5348.1 5348.9 6889.0 6962.8 6986.3 7026.7 7237.3 5325.7 5379.1 5388.3 5422.7 5465.0 5326.9 5448.6 5452.2 5526.2 5528.2 5997.8 6021.3 6027.9 6086.9 6159.6 6927.5 6934.9 6973.2 6986.0 6986.2 1858.2 1889.2 1902.5 1911.2 1912.5 1283.4 1322.0 1323.5 1350.8 1354.1 6380.4 6413.3 6448.3 6467.7 6480.8 6761.4 6888.3 6913.8 6915.0 6956.9 6336.0 6361.1 6399.3 6445.5 6531.8 3578.6 3704.9 3716.8 3724.7 3729.6 7088.5 7093.0 7160.5 7174.4 7219.4 7028.2 7031.5 7059.4 7072.7 7231.4 7128.0 7130.4 7150.9 7155.3 7331.3 6789.5 6823.5 6998.0 7023.1 7109.0 6833.4 6923.8 6950.8 6958.5 7004.5 7059.5 7152.4 7218.4 7221.6 7299.5 5436.8 5447.3 5462.2 5522.9 5531.4 6744.8 6815.2 6967.7 6985.3 7000.6 6695.4 6729.8 6775.8 6777.9 6831.5 6630.3 6666.6 6739.5 6765.2 6766.7 6494.6 6550.4 6603.9 6613.7 6662.7 6689.2 6744.6 6751.0 6798.2 6902.2 6577.7 6631.7 6664.6 6685.9 6691.9 08:49:53 INFO - PID 18296 | 08:49:53 INFO - PID 18296 | [#5] yui.html Cycles:180 Average:28505.29 Median:20383.88 stddev:40301.64 (197.7%) stddev-sans-first:40361.76 08:49:53 INFO - PID 18296 | Values: 994.3 997.6 1021.8 1027.5 1033.2 20157.7 21103.2 21377.4 21404.6 21495.5 19736.8 19850.8 19851.7 19871.0 19896.2 979.3 994.4 997.6 998.7 1001.7 1050.5 1055.2 1057.3 1061.2 1061.4 1040.5 1084.9 1089.3 1089.6 1112.6 1058.4 1071.0 1075.9 1076.0 1077.5 803.6 805.5 805.7 807.3 807.4 20394.2 20708.2 21036.6 21135.9 21202.5 23430.6 23607.5 23904.8 23927.2 23951.3 18362.4 18395.0 18554.0 18591.3 18606.1 4005.8 4160.3 4207.7 4237.2 4239.2 125988.4 134556.3 157273.2 157921.0 160835.0 18282.0 18629.4 18832.4 18852.3 18906.4 34560.8 35388.3 35498.8 35529.6 35657.5 9935.6 9979.4 10004.6 10007.0 10026.5 4712.2 4771.0 4772.7 4783.7 4793.1 6135.5 6214.1 6246.5 6282.5 6296.7 1319.1 1339.4 1361.8 1363.8 1364.7 711.0 721.6 724.8 737.0 751.8 13470.7 13697.5 13763.9 13913.2 13999.6 770.6 774.9 781.1 787.5 788.0 6330.5 6437.7 6469.1 6469.9 6476.8 139118.0 142419.0 143614.3 143631.0 147393.0 124627.4 127620.4 129441.4 130878.0 132516.0 106617.5 111351.0 113806.0 114947.4 117111.0 14535.2 15015.9 15063.3 15070.9 15225.9 54390.0 54562.7 54628.7 55850.4 57370.4 72212.0 72569.9 72953.0 73560.3 73849.0 19884.9 20116.8 20237.7 20301.6 20373.6 22582.0 22742.0 22772.7 22789.5 22960.2 21515.8 21912.6 21943.5 22148.4 22151.4 20078.3 20337.9 20366.3 20492.4 20494.5 20434.8 20472.8 20486.4 20681.0 20690.7 15843.9 15875.2 16103.6 16265.9 16353.8 16068.7 16140.4 16251.3 16269.6 16325.1 08:49:53 INFO - PID 18296 | -------- Summary: end -------- 08:49:53 INFO - PID 18296 | 08:49:54 INFO - TEST-INFO | 18296: exit 0 08:49:54 INFO - TEST-OK | dromaeo_css | took 1108202ms 08:49:54 INFO - TEST-START | kraken 08:49:54 INFO - Initialising browser for kraken test... 08:49:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58448/getInfo.html -profile /tmp/tmpsZR6MM/profile 08:49:54 INFO - TEST-INFO | started process 18658 (/builds/slave/test/build/application/firefox/firefox http://localhost:58448/getInfo.html) 08:49:59 INFO - TEST-INFO | 18658: exit 0 08:49:59 INFO - Browser initialized. 08:49:59 INFO - Running cycle 1/1 for kraken test... 08:49:59 INFO - Using env: {'DISPLAY': ':0', 08:49:59 INFO - 'HOME': '/home/cltbld', 08:49:59 INFO - 'JSGC_DISABLE_POISONING': '1', 08:49:59 INFO - 'LANG': 'en_US.UTF-8', 08:49:59 INFO - 'LANGUAGE': 'en_US:en', 08:49:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 08:49:59 INFO - 'LOGNAME': 'cltbld', 08:49:59 INFO - 'MAIL': '/var/mail/cltbld', 08:49:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:49:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:49:59 INFO - 'MOZ_CRASHREPORTER': '1', 08:49:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 08:49:59 INFO - 'MOZ_NO_REMOTE': '1', 08:49:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:49:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:59 INFO - 'NO_EM_RESTART': '1', 08:49:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:59 INFO - 'PWD': '/builds/slave/test', 08:49:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:49:59 INFO - 'RUST_BACKTRACE': 'full', 08:49:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:49:59 INFO - 'SHELL': '/bin/bash', 08:49:59 INFO - 'SHLVL': '1', 08:49:59 INFO - 'TERM': 'linux', 08:49:59 INFO - 'TMOUT': '86400', 08:49:59 INFO - 'USER': 'cltbld', 08:49:59 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404', 08:49:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:59 INFO - '_': '/tools/buildbot/bin/python'} 08:49:59 INFO - TEST-INFO | started process 18896 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsZR6MM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:00 INFO - PID 18896 | 08:50:00 INFO - PID 18896 | (/builds/slave/test/build/application/firefox/firefox:18943): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 08:50:00 INFO - PID 18896 | 08:50:10 INFO - PID 18896 | RSS: Main: 142123008 08:50:10 INFO - PID 18896 | 08:50:13 INFO - PID 18896 | Cycle 1(1): loaded http://localhost:58448/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58448/tests/kraken/driver.html?testName=audio-beat-detection) 08:50:14 INFO - PID 18896 | RSS: Main: 149032960 08:50:14 INFO - PID 18896 | 08:50:16 INFO - PID 18896 | Cycle 1(1): loaded http://localhost:58448/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58448/tests/kraken/driver.html?testName=audio-dft) 08:50:17 INFO - PID 18896 | RSS: Main: 149319680 08:50:17 INFO - PID 18896 | 08:50:20 INFO - PID 18896 | Cycle 1(1): loaded http://localhost:58448/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58448/tests/kraken/driver.html?testName=audio-fft) 08:50:20 INFO - PID 18896 | RSS: Main: 151441408 08:50:20 INFO - PID 18896 | 08:50:22 INFO - PID 18896 | Cycle 1(1): loaded http://localhost:58448/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58448/tests/kraken/driver.html?testName=audio-oscillator) 08:50:23 INFO - PID 18896 | RSS: Main: 151101440 08:50:23 INFO - PID 18896 | 08:50:25 INFO - PID 18896 | Cycle 1(1): loaded http://localhost:58448/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58448/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:50:26 INFO - PID 18896 | RSS: Main: 150974464 08:50:26 INFO - PID 18896 | 08:50:29 INFO - PID 18896 | Cycle 1(1): loaded http://localhost:58448/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58448/tests/kraken/driver.html?testName=imaging-darkroom) 08:50:29 INFO - PID 18896 | RSS: Main: 150667264 08:50:29 INFO - PID 18896 | 08:50:33 INFO - PID 18896 | Cycle 1(1): loaded http://localhost:58448/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58448/tests/kraken/driver.html?testName=imaging-desaturate) 08:50:33 INFO - PID 18896 | RSS: Main: 153051136 08:50:33 INFO - PID 18896 | 08:50:36 INFO - PID 18896 | Cycle 1(1): loaded http://localhost:58448/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58448/tests/kraken/driver.html?testName=json-parse-financial) 08:50:37 INFO - PID 18896 | RSS: Main: 153399296 08:50:37 INFO - PID 18896 | 08:50:38 INFO - PID 18896 | Cycle 1(1): loaded http://localhost:58448/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58448/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:50:39 INFO - PID 18896 | RSS: Main: 153358336 08:50:39 INFO - PID 18896 | 08:50:40 INFO - PID 18896 | Cycle 1(1): loaded http://localhost:58448/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58448/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:50:41 INFO - PID 18896 | RSS: Main: 153899008 08:50:41 INFO - PID 18896 | 08:50:43 INFO - PID 18896 | Cycle 1(1): loaded http://localhost:58448/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58448/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:50:44 INFO - PID 18896 | RSS: Main: 150327296 08:50:44 INFO - PID 18896 | 08:50:46 INFO - PID 18896 | Cycle 1(1): loaded http://localhost:58448/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58448/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:50:47 INFO - PID 18896 | RSS: Main: 150102016 08:50:47 INFO - PID 18896 | 08:50:49 INFO - PID 18896 | Cycle 1(1): loaded http://localhost:58448/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58448/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:50:50 INFO - PID 18896 | RSS: Main: 150679552 08:50:50 INFO - PID 18896 | 08:50:51 INFO - PID 18896 | Cycle 1(1): loaded http://localhost:58448/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58448/tests/kraken/driver.html?testName=ai-astar) 08:50:52 INFO - PID 18896 | RSS: Main: 152276992 08:50:52 INFO - PID 18896 | 08:50:52 INFO - PID 18896 | __start_tp_report 08:50:52 INFO - PID 18896 | _x_x_mozilla_page_load 08:50:52 INFO - PID 18896 | _x_x_mozilla_page_load_details 08:50:52 INFO - PID 18896 | |i|pagename|runs| 08:50:52 INFO - PID 18896 | |0;ai-astar;106;105;104;105;104;105;107;103;106;105 08:50:52 INFO - PID 18896 | |1;audio-beat-detection;109;118;110;111;141;112;110;110;111;111 08:50:52 INFO - PID 18896 | |2;audio-dft;161;163;162;161;161;162;161;162;159;161 08:50:52 INFO - PID 18896 | |3;audio-fft;73;73;75;73;73;76;75;74;77;76 08:50:52 INFO - PID 18896 | |4;audio-oscillator;78;77;77;81;78;77;77;76;77;78 08:50:52 INFO - PID 18896 | |5;imaging-gaussian-blur;113;114;117;114;114;113;114;113;114;113 08:50:52 INFO - PID 18896 | |6;imaging-darkroom;149;148;148;147;149;148;149;148;149;152 08:50:52 INFO - PID 18896 | |7;imaging-desaturate;94;94;96;94;94;98;94;94;95;95 08:50:52 INFO - PID 18896 | |8;json-parse-financial;45;42;42;43;50;44;43;43;42;42 08:50:52 INFO - PID 18896 | |9;json-stringify-tinderbox;35;36;36;35;37;36;36;36;36;37 08:50:52 INFO - PID 18896 | |10;stanford-crypto-aes;68;68;69;70;68;68;70;69;70;71 08:50:52 INFO - PID 18896 | |11;stanford-crypto-ccm;105;108;100;103;103;109;105;102;107;106 08:50:52 INFO - PID 18896 | |12;stanford-crypto-pbkdf2;157;148;148;145;150;146;149;146;146;144 08:50:52 INFO - PID 18896 | |13;stanford-crypto-sha256-iterative;59;59;59;61;61;64;57;57;60;57 08:50:52 INFO - PID 18896 | __end_tp_report 08:50:52 INFO - PID 18896 | __start_cc_report 08:50:52 INFO - PID 18896 | _x_x_mozilla_cycle_collect,698 08:50:52 INFO - PID 18896 | __end_cc_report 08:50:52 INFO - PID 18896 | __startTimestamp1508860252188__endTimestamp 08:50:52 INFO - PID 18896 | 08:50:52 INFO - PID 18896 | ------- Summary: start ------- 08:50:52 INFO - PID 18896 | Number of tests: 14 08:50:52 INFO - PID 18896 | 08:50:52 INFO - PID 18896 | [#0] ai-astar Cycles:10 Average:105.00 Median:105.00 stddev:1.15 (1.1%) stddev-sans-first:1.17 08:50:52 INFO - PID 18896 | Values: 106.0 105.0 104.0 105.0 104.0 105.0 107.0 103.0 106.0 105.0 08:50:52 INFO - PID 18896 | 08:50:52 INFO - PID 18896 | [#1] audio-beat-detection Cycles:10 Average:114.30 Median:111.00 stddev:9.71 (8.7%) stddev-sans-first:10.11 08:50:52 INFO - PID 18896 | Values: 109.0 118.0 110.0 111.0 141.0 112.0 110.0 110.0 111.0 111.0 08:50:52 INFO - PID 18896 | 08:50:52 INFO - PID 18896 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:1.06 (0.7%) stddev-sans-first:1.12 08:50:52 INFO - PID 18896 | Values: 161.0 163.0 162.0 161.0 161.0 162.0 161.0 162.0 159.0 161.0 08:50:52 INFO - PID 18896 | 08:50:52 INFO - PID 18896 | [#3] audio-fft Cycles:10 Average:74.50 Median:75.00 stddev:1.51 (2.0%) stddev-sans-first:1.50 08:50:52 INFO - PID 18896 | Values: 73.0 73.0 75.0 73.0 73.0 76.0 75.0 74.0 77.0 76.0 08:50:52 INFO - PID 18896 | 08:50:52 INFO - PID 18896 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:77.50 stddev:1.35 (1.7%) stddev-sans-first:1.42 08:50:52 INFO - PID 18896 | Values: 78.0 77.0 77.0 81.0 78.0 77.0 77.0 76.0 77.0 78.0 08:50:52 INFO - PID 18896 | 08:50:52 INFO - PID 18896 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:1.20 (1.1%) stddev-sans-first:1.22 08:50:52 INFO - PID 18896 | Values: 113.0 114.0 117.0 114.0 114.0 113.0 114.0 113.0 114.0 113.0 08:50:52 INFO - PID 18896 | 08:50:52 INFO - PID 18896 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:149.00 stddev:1.34 (0.9%) stddev-sans-first:1.41 08:50:52 INFO - PID 18896 | Values: 149.0 148.0 148.0 147.0 149.0 148.0 149.0 148.0 149.0 152.0 08:50:52 INFO - PID 18896 | 08:50:52 INFO - PID 18896 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:94.50 stddev:1.32 (1.4%) stddev-sans-first:1.36 08:50:52 INFO - PID 18896 | Values: 94.0 94.0 96.0 94.0 94.0 98.0 94.0 94.0 95.0 95.0 08:50:52 INFO - PID 18896 | 08:50:52 INFO - PID 18896 | [#8] json-parse-financial Cycles:10 Average:43.60 Median:43.00 stddev:2.46 (5.7%) stddev-sans-first:2.55 08:50:52 INFO - PID 18896 | Values: 45.0 42.0 42.0 43.0 50.0 44.0 43.0 43.0 42.0 42.0 08:50:52 INFO - PID 18896 | 08:50:52 INFO - PID 18896 | [#9] json-stringify-tinderbox Cycles:10 Average:36.00 Median:36.00 stddev:0.67 (1.9%) stddev-sans-first:0.60 08:50:52 INFO - PID 18896 | Values: 35.0 36.0 36.0 35.0 37.0 36.0 36.0 36.0 36.0 37.0 08:50:52 INFO - PID 18896 | 08:50:52 INFO - PID 18896 | [#10] stanford-crypto-aes Cycles:10 Average:69.10 Median:69.50 stddev:1.10 (1.6%) stddev-sans-first:1.09 08:50:52 INFO - PID 18896 | Values: 68.0 68.0 69.0 70.0 68.0 68.0 70.0 69.0 70.0 71.0 08:50:52 INFO - PID 18896 | 08:50:52 INFO - PID 18896 | [#11] stanford-crypto-ccm Cycles:10 Average:104.80 Median:105.50 stddev:2.82 (2.7%) stddev-sans-first:2.99 08:50:52 INFO - PID 18896 | Values: 105.0 108.0 100.0 103.0 103.0 109.0 105.0 102.0 107.0 106.0 08:50:52 INFO - PID 18896 | 08:50:52 INFO - PID 18896 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:147.90 Median:148.00 stddev:3.70 (2.5%) stddev-sans-first:1.96 08:50:52 INFO - PID 18896 | Values: 157.0 148.0 148.0 145.0 150.0 146.0 149.0 146.0 146.0 144.0 08:50:52 INFO - PID 18896 | 08:50:52 INFO - PID 18896 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.40 Median:59.50 stddev:2.22 (3.7%) stddev-sans-first:2.35 08:50:52 INFO - PID 18896 | Values: 59.0 59.0 59.0 61.0 61.0 64.0 57.0 57.0 60.0 57.0 08:50:52 INFO - PID 18896 | -------- Summary: end -------- 08:50:52 INFO - PID 18896 | 08:50:52 INFO - PID 18896 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 08:50:52 INFO - TEST-INFO | 18896: exit 0 08:50:52 INFO - TEST-OK | kraken | took 58530ms 08:50:52 INFO - SUITE-END | took 1166s 08:50:52 INFO - Completed test suite (00:19:26) 08:50:52 INFO - javascript benchmark 08:50:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9513.493460445408, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3999.300122478566, 4007.7595344279334, 4060.5330387005483, 4062.837486500542, 4073.2668119738437, 6573.704183311763, 6618.205815302164, 6643.667816609169, 6688.163979502562, 6715.764948226807, 6622.238442579105, 6655.733770649181, 6715.798526044215, 6742.258351581334, 6759.56062855914, 5945.137954996523, 5965.701714914258, 6022.0, 6028.336882942873, 6059.0, 5943.465088142067, 6027.879442411149, 6037.698115094249, 6039.788607398742, 6140.232470941132, 6002.429769171944, 6007.399260074001, 6043.093535969618, 6048.667323297217, 6116.877662446748, 6069.696515174268, 6071.878562428748, 6095.329513753506, 6110.694465276719, 6131.87736245277, 5989.401059893976, 6013.609115407528, 6106.572539922207, 6108.297545782203, 6130.295016073164, 6455.0, 6595.0766892635065, 6597.373249541334, 6619.602823830537, 6709.060731497594, 7212.855742885113, 7238.0952380952385, 7245.203027666952, 7262.20115787263, 7270.563766173994, 7263.34629883313, 7284.856571047386, 7316.195218525958, 7347.0, 7367.926320736778, 6695.7656482023385, 6769.763058292985, 6799.388055075017, 6823.420009299216, 6836.282190370039, 7441.1070671519765, 7472.103347598323, 7546.0, 7563.357114645317, 7588.317051465395, 7104.0764700589625, 7135.322144396274, 7186.820329491805, 7192.100987376578, 7206.675699593531, 7227.241139680363, 7227.277272272836, 7275.745348912868, 7313.085864266966, 7328.3404493595835, 7185.461090418239, 7235.819104522429, 7237.095363079615, 7263.34629883313, 7268.600226987461, 7244.985702001724, 7251.347378735833, 7279.2720727927845, 7298.708051677873, 7319.963400182965, 5400.270963419966, 5471.5896307777075, 5473.863153421196, 5475.753591088411, 5477.0, 4868.683535570177, 4888.755562221874, 4946.678465899705, 4959.454460009396, 4961.801527938842, 6746.898796518056, 6787.932120678829, 6833.794986150324, 6849.075374824434, 6863.553868998599, 7322.780316590511, 7347.448941329421, 7371.741989030358, 7378.815529611803, 7380.889286660705, 6802.863942721118, 6813.0, 6885.862282754317, 6889.621070841152, 6904.723811047502, 6136.202293701879, 6216.7824126155365, 6236.750529978841, 6312.274088479791, 6312.43188113072, 7379.483436159525, 7534.0, 7596.278353556403, 7614.0, 7622.0, 7263.128424589083, 7272.0, 7335.596542190017, 7345.669444874887, 7364.116306043309, 7523.059617547807, 7534.962325188339, 7539.434542409232, 7627.466077374531, 7641.923580764344, 7134.250903655042, 7147.249538798559, 7193.604351760703, 7194.244604316471, 7213.062301900929, 7293.088363954505, 7313.634318284171, 7343.669534871051, 7350.154732205865, 7365.153007404003, 6888.311168883272, 6910.0, 6993.19578248508, 7004.964975175091, 7011.0, 6955.23492415844, 7011.719531218694, 7018.227994920657, 7020.613866237406, 7028.156621205488, 6769.120014398293, 6817.284185160487, 6851.862962740817, 6918.06606108189, 6937.098177236927, 6979.930200697928, 7023.508354415142, 7051.894221586783, 7088.149422069178, 7105.680244388912, 6916.585004899722, 6919.065926100114, 6978.162620485574, 6988.196357418758, 6999.965000174967], "value": 6659.605786999911, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [976.6972238606032, 1007.1036777268232, 1026.6817286641142, 1028.2185538990368, 1030.9845352319712, 44979.32531012033, 45617.0, 45780.54219457805, 45805.0, 45828.0, 42917.7854110729, 43037.7848110759, 43052.784736076275, 43220.78389608055, 43317.56682433175, 8195.877061844078, 8254.0, 8258.42191046627, 8291.668333266662, 8326.417150799447, 7837.529748215125, 7883.684652613889, 7888.56612886291, 7898.368130549571, 7915.485493442937, 8762.737117886474, 8953.23897468716, 9023.729288121334, 9030.232430243437, 9032.187103160713, 8935.77660558488, 8947.463152210823, 8984.146506081912, 8984.146506081945, 9005.054469280696, 8871.512066836322, 8920.286377089882, 8992.8651070234, 8998.0, 9024.729258122266, 42607.57392426098, 42667.57332426667, 42931.35602965958, 43045.56954430447, 43202.351964720554, 3858.2862170498547, 3887.261420330128, 3888.416737489371, 3889.6499315061496, 3891.591382904811, 34304.82847585746, 34531.82734086338, 34599.13502162441, 34638.0, 34653.65346346555, 5603.971980140073, 5808.273965754281, 5832.6792026438525, 5834.27071616048, 5923.111533270001, 231429.0, 233091.0, 233947.0, 235534.0, 235536.0, 55328.0, 55768.0, 55840.0, 55853.44146558564, 55922.440775592535, 56881.7155914226, 57295.713521432124, 57323.71338143366, 57348.698847586595, 57430.425695743346, 17548.473545793648, 17692.73460898062, 17696.0, 17697.0, 17957.0, 14809.407623695148, 14842.703145937021, 14846.18345990981, 14846.331915063847, 14896.478623248351, 14963.326650300764, 15147.697046059238, 15261.313240904186, 15355.539333820003, 15531.378744850306, 6007.939920600826, 6041.093835924558, 6043.576949613484, 6139.96930015356, 6186.350433204449, 1113.5211858900589, 1114.3703807348822, 1121.097516499226, 1122.1864148492277, 1126.8253420719639, 26090.608640870403, 26341.34146646311, 26597.0, 26806.06178783733, 26880.59679104815, 1224.8897599215936, 1226.7607816475702, 1230.0774419185611, 1242.490578862662, 1248.8688687687843, 14070.71858562823, 14184.078034927697, 14248.0, 14250.85749142516, 14426.927865360605, 309044.0, 309165.0, 309624.0, 309673.0, 310579.0, 63878.0, 63937.36062639314, 64040.35959640344, 64231.35768642254, 64489.35510644927, 1527.5646440764328, 1567.4278888205722, 1575.9527214183593, 1578.968420631581, 1596.7924169857845, 25811.225663230132, 25843.353916152246, 25986.480270394863, 26141.60787588226, 26211.0, 44139.55860441483, 44338.0, 44491.555084448744, 44512.777436112614, 44593.0, 261060.0, 261120.0, 261928.0, 262772.0, 262893.0, 37777.24445511129, 44421.33367999418, 44553.331700023875, 44868.775656121514, 45192.774036130926, 56324.155137673784, 56646.43353566412, 56759.35091034617, 57382.0, 57732.71133644305, 40729.59270407258, 41016.58983410128, 41104.58895411008, 41364.0, 41755.37366939438, 30001.54997674993, 30110.397792043597, 30214.69785302119, 30455.543166852956, 31071.0, 47289.0, 47358.52641473679, 47720.0, 47728.28407573959, 47842.282365763844, 39860.402093968034, 40081.39877901892, 40124.1975160501, 40149.0, 40334.0, 28864.42271154607, 28978.7102128976, 29100.7089929098, 29340.0, 29657.555136672534], "value": 19806.174597687183, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [633.2220222686285, 643.5848348709463, 650.3566072568707, 650.6412474908785, 654.2260814148012, 23290.4177395565, 23849.284521464364, 24030.279091627235, 24059.879700601472, 24078.638820417687, 26616.201513954566, 26623.334416639595, 26882.327941801464, 26944.326391840215, 26997.86501067492, 6269.686515674221, 6548.214214294291, 6561.901571476432, 6609.537332386735, 6653.467722582194, 6530.869382612344, 6603.570767900095, 6612.206535215781, 6614.272430032693, 6614.338566143395, 6576.835579110512, 6590.93409065908, 6665.033570132328, 6899.758508452205, 6913.723451061952, 6858.862822743542, 6916.930830691729, 6921.377076063153, 6929.1684997800085, 6934.618595977269, 6424.164858568403, 6476.773312934072, 6511.0, 6516.511261655347, 6530.608163510157, 30419.69580304191, 30779.230519236975, 31475.52786708201, 31527.84236078805, 31660.841695791605, 2401.7238017627847, 2457.778799908017, 2459.311392810016, 2462.4336402627496, 2463.8521688698734, 20641.277555285495, 21108.788912110835, 21113.261035863823, 21191.0, 21208.89395553028, 2548.3756479662416, 2573.6525569048126, 2630.06690082789, 2659.0693257359962, 2667.5331816931957, 145492.0, 150366.99266014728, 156604.0, 162235.0, 163546.9999999988, 16060.357585696675, 16701.0, 16777.496675099766, 16854.157292135344, 17045.573860653334, 27598.5860212097, 27670.169894903185, 27733.30666733348, 27747.445051098864, 27785.86107069452, 11079.50142243601, 11315.660530184108, 11358.772824543463, 11402.087832973342, 11469.0, 10149.188064954933, 10272.383656980604, 10295.279330446947, 10296.330738501973, 10355.896441035644, 9701.787276590427, 10066.949665251774, 10098.192144628412, 10130.442825644514, 10217.33587316822, 2921.5033444314518, 2988.551717242431, 3025.803322784056, 3032.9241768955953, 3082.9383412332077, 1056.5034433816095, 1068.1935138970184, 1070.4594179939236, 1085.5874767588266, 1096.090245096568, 21146.0, 21293.574128517343, 21562.0, 21724.674129888066, 21763.347099587038, 1152.1589239854786, 1155.3183621663259, 1155.8674246016255, 1156.502703837358, 1159.3913195572393, 11788.115891308185, 11820.527178912918, 12009.399530023464, 12083.0, 12092.093093017882, 648478.0, 649466.0, 649576.0, 664735.0, 675047.0, 135184.0, 136995.0, 138474.5509726856, 139019.0, 139139.30430347985, 1256.1646505074023, 1257.5661396818084, 1278.3509273037678, 1297.4810075969688, 1306.9019823513274, 18853.909444449648, 18960.71558926645, 19034.619307614048, 19122.713159302344, 19550.21799128019, 65416.672916635114, 65672.34327656853, 65714.67142664256, 66252.66873665794, 66284.33715662782, 76760.61619691867, 77684.0, 81194.0, 81224.0, 81823.5908820452, 15843.128627925575, 16125.919370403073, 16188.514344569681, 16321.18394080315, 16343.83656163423, 24298.75701242965, 24550.877245613658, 24705.25884223476, 24811.379715507257, 24909.501809964062, 20151.79848201499, 20156.0, 20267.493312666713, 20307.390778276676, 20329.49176270605, 17370.218340174975, 17760.3783867564, 17920.551986200448, 17978.460646180636, 18415.171317290486, 20373.898130509253, 20537.48656283605, 20576.588468230253, 20816.271430500467, 20954.47613809667, 15851.207439628202, 15883.602909926882, 15973.840261597235, 15989.360425583312, 16000.679986400439, 19821.0, 19841.20635174577, 19977.800221997593, 20026.69959950631, 20077.899610501852], "value": 13170.888836990942, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1403.9227842468663, 1473.9041962272452, 1509.554681368996, 1515.2802418851054, 1519.9848001519981, 18640.161192746324, 19152.13815378308, 19161.42515724527, 19172.616547669037, 19179.328723494662, 18399.26402943884, 18431.44705658829, 18436.72344914824, 18525.073746312664, 18626.813731862676, 3059.8623061962207, 3160.6365267994247, 3162.4465718499287, 3162.905112846618, 3171.3657268546267, 2991.92520186996, 3103.301757104656, 3104.58088158098, 3104.953425698605, 3105.7204851563356, 3063.7702172337054, 3140.921476963071, 3141.921451963696, 3151.763617728668, 3153.889613863515, 3059.6022517072747, 3131.8590663419973, 3132.9686703133134, 3133.5926329577123, 3135.858886350108, 2989.4768415527196, 3035.8937437189593, 3037.3469704013523, 3063.8008529445738, 3130.63997640272, 17095.0, 17166.399176028903, 17204.91397543017, 17273.309067637278, 17352.566185845328, 8411.537365444898, 8641.049484556694, 8901.287896968228, 8913.732588022369, 8918.509481978488, 6284.12022316876, 6294.118823364733, 6316.431521163071, 6432.485401167895, 6459.5478316517865, 2163.5023944492696, 2164.101897712447, 2166.4150679316654, 2192.243675931801, 2254.3124347074136, 32063.51904721431, 32106.0, 32372.352552949043, 32396.190095247573, 32413.837930810194, 7587.203343648947, 7635.312821846061, 7637.0, 7893.723719669784, 7925.484843485155, 20644.483887902923, 20646.96765161736, 20741.896290518453, 20885.26901558438, 20920.267790627255, 8684.0, 8975.50634715097, 8992.100789921087, 8998.730038098867, 9019.503927284062, 8106.108328083906, 8129.227723366271, 8159.755207343789, 8343.791405214797, 8346.666133354598, 6160.599561028519, 6230.968845155836, 6234.470070044097, 6286.4342209201395, 6346.682665866688, 1674.547872074533, 1719.6044909670845, 1803.638253118887, 1815.8728888977644, 1855.8886466812035, 930.223263574909, 937.2736129499692, 938.7606160429184, 948.8292107420731, 951.5147274889717, 8920.776980575407, 9293.256539476826, 9355.625774969061, 9384.24926005918, 9437.245020398352, 1050.7793363393773, 1056.7041228456044, 1056.804491169136, 1060.1201003167353, 1084.924055316136, 4930.0, 4948.6783359082265, 4954.430240522314, 4967.180415231446, 5031.345925029723, 26086.608700869503, 26542.6018609721, 26563.468730625278, 26776.59835102475, 26903.0, 26199.73800262011, 26236.86881565618, 26689.199324019923, 26732.19803405901, 26747.592716655596, 13037.543685971135, 13073.803892941614, 13132.671683207805, 13162.276074815785, 13214.27321497327, 8987.595558199766, 9120.498372589305, 9151.954240228757, 9160.633574656938, 9192.540372981457, 21791.23730669419, 21805.56388872182, 22015.11939522401, 23040.308790736304, 23040.617562946278, 23479.295621131394, 23620.17329393463, 23628.17301394443, 23787.881060594587, 23821.523569528166, 12900.483980640669, 13064.804027939399, 13195.472181113031, 13276.402561884544, 13339.466421343037, 16106.838931610533, 16111.355545778037, 16310.836891630932, 16335.509934701979, 16380.508584742476, 12997.285149316878, 13485.797713034117, 13518.39167237457, 13520.93239533796, 13575.79636305476, 12922.289274090015, 12931.159474634118, 12952.8057079142, 13225.140365876186, 13299.20204787716, 9864.408135511892, 9881.802363952824, 9961.750956226153, 10058.497075146068, 10073.949630251802, 7885.250901164381, 7987.92012079895, 8001.359891208573, 8022.91977080245, 8073.475224110414, 7579.1284002340435, 7787.29914307715, 7794.064712234568, 7798.922010779819, 7804.595172868938], "value": 7646.510695103264, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.9887402251956, 587.7061469265367, 594.2927915780222, 601.6299975515057, 605.8909396308666, 6429.5820771649815, 6435.581687190335, 6459.903101453476, 6508.674566271679, 6551.377619126188, 6374.426301632852, 6403.359664033606, 6414.71133798979, 6460.935390646092, 6474.838129046764, 5243.554297884684, 5289.894202115975, 5303.416624171338, 5305.893882122365, 5321.93561287744, 5026.246063090547, 5166.121759300928, 5219.765110570014, 5234.476552344773, 5319.255304257388, 5104.259882317062, 5112.079825631385, 5123.0, 5134.306868572751, 5152.562032227265, 5080.49195080489, 5082.119615742181, 5084.8220312288895, 5148.819791307324, 5149.45930677281, 4610.861674149781, 4663.60359369454, 4702.459217190033, 4713.198756211452, 4725.0, 6191.9164146274225, 6280.277768056686, 6300.3384644612115, 6319.33646967071, 6347.206599175103, 5977.432143946318, 6077.787277445313, 6081.908771368433, 6115.296740874812, 6163.907541386883, 6853.862922741518, 6869.519133660646, 6872.010675356186, 6945.791626251221, 6949.722011119549, 5306.2571240026045, 5316.867078323073, 5334.27987221724, 5348.090824559834, 5348.91976620351, 6889.0, 6962.825929351756, 6986.336298051677, 7026.683799229047, 7237.276272372773, 5325.707086110301, 5379.112446446371, 5388.272583201296, 5422.701751403711, 5465.0, 5326.9467305327225, 5448.645838020516, 5452.182172674051, 5526.171074338881, 5528.198411230349, 5997.760089596367, 6021.337652858182, 6027.9095813562835, 6086.93044364918, 6159.599626024294, 6927.549709268881, 6934.8613027740175, 6973.198082220508, 6985.965070174617, 6986.231514533397, 1858.2195477899315, 1889.2052550077208, 1902.4863286912457, 1911.1972971352066, 1912.4931893048354, 1283.3775618824861, 1322.0217039390782, 1323.5169163255343, 1350.844652864933, 1354.133354653023, 6380.39386258314, 6413.274358397663, 6448.258450278278, 6467.708953097122, 6480.837979050467, 6761.425278851254, 6888.345607167311, 6913.827154321081, 6915.0, 6956.860862782716, 6335.954567496404, 6361.141245931833, 6399.288412720611, 6445.452136568444, 6531.8367040823405, 3578.624244454347, 3704.8888533344043, 3716.7955762433326, 3724.7020238381406, 3729.589745128034, 7088.468364872655, 7092.9645351772915, 7160.462965277521, 7174.354308112295, 7219.386352160125, 7028.191757947901, 7031.507794454339, 7059.364657180776, 7072.681729322193, 7231.421486281085, 7128.010392725009, 7130.358267755927, 7150.927360895803, 7155.284471552907, 7331.340179383881, 6789.490788190807, 6823.454123669995, 6997.965010174917, 7023.085419551034, 7109.0, 6833.384995350442, 6923.792286231421, 6950.79147625572, 6958.547694399847, 7004.509684322048, 7059.5058345915295, 7152.427805775629, 7218.386437152901, 7221.566705997657, 7299.489035767445, 5436.809711660073, 5447.311333486632, 5462.20797984298, 5522.861928451821, 5531.363893152178, 6744.7976560703255, 6815.216250131299, 6967.7212911482975, 6985.284932670529, 7000.579965202104, 6695.426574754832, 6729.7644582441335, 6775.796726098225, 6777.864442711019, 6831.4876384272375, 6630.30381809903, 6666.633335166613, 6739.460843132636, 6765.221999469927, 6766.695498702472, 6494.577852439576, 6550.377714117151, 6603.9009414859775, 6613.710326486289, 6662.700178491883, 6689.163854518185, 6744.595324280559, 6750.966245168743, 6798.184217893884, 6902.240753517009, 6577.736890524326, 6631.701573429111, 6664.633445160563, 6685.866282674417, 6691.895837187004], "value": 5355.389643862006, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [994.3487016004517, 997.5610731278241, 1021.8160731068409, 1027.5170669785207, 1033.204432586908, 20157.697634535456, 21103.15587376505, 21377.358679239627, 21404.57190856184, 21495.462613434633, 19736.802631973675, 19850.801491985076, 19851.702224466608, 19871.0, 19896.20415183391, 979.2606582030556, 994.41329615527, 997.6408492942535, 998.6604554451485, 1001.6694490818013, 1050.495762034224, 1055.1611468882256, 1057.2968975631195, 1061.2253055269618, 1061.3843970497094, 1040.5473618975732, 1084.9023587877089, 1089.3082892363327, 1089.6240796925088, 1112.6495154026495, 1058.364981011387, 1071.0253669161027, 1075.8655168103987, 1075.9946200268987, 1077.498962982213, 803.6102490292203, 805.5368163306123, 805.6817557064985, 807.2532907061004, 807.3783186946077, 20394.184232630676, 20708.171673133056, 21036.579268414698, 21135.894320528452, 21202.469938251514, 23430.648540271908, 23607.527849443086, 23904.76095239043, 23927.16254931069, 23951.28146155618, 18362.449126526226, 18395.0, 18554.0, 18591.34930277434, 18606.069696515122, 4005.819738111792, 4160.313548264533, 4207.726497777665, 4237.216115018731, 4239.173361194537, 125988.37005815005, 134556.3272183633, 157273.21363393223, 157921.0, 160835.0, 18282.0, 18629.44111676652, 18832.435026949213, 18852.340168094324, 18906.43280701581, 34560.82719586386, 35388.292234155175, 35498.82250588782, 35529.64470355315, 35657.46513802295, 9935.552900119512, 9979.401235925867, 10004.599816007278, 10007.049330313608, 10026.548805303779, 4712.175369310357, 4771.021940502258, 4772.665913386029, 4783.7129772213775, 4793.113274044313, 6135.509159267248, 6214.067889816563, 6246.469050130699, 6282.528810339243, 6296.748130074746, 1319.116192151261, 1339.3638021939696, 1361.8229630148016, 1363.8431580368185, 1364.6520137364907, 710.989335159973, 721.6247551273414, 724.8043028382307, 736.9631518424058, 751.8458715963214, 13470.730585388434, 13697.452101916011, 13763.898888088928, 13913.165210087427, 13999.580012599637, 770.603139383218, 774.9108852481924, 781.0705260739815, 787.4527203593562, 788.0071110400936, 6330.493560515148, 6437.742490300429, 6469.126667899867, 6469.870602587922, 6476.773312934025, 139118.0, 142419.0, 143614.28192858968, 143631.0, 147393.0, 124627.37686311692, 127620.36189819177, 129441.35279323731, 130878.0, 132516.0, 106617.46691266494, 111351.0, 113806.0, 114947.42526287315, 117111.0, 14535.200563969083, 15015.924920375328, 15063.322150503473, 15070.924645376703, 15225.923870380577, 54390.0, 54562.7271863654, 54628.726856365465, 55850.44149558452, 57370.426295736506, 72212.0, 72569.91145132933, 72953.0, 73560.26439735533, 73849.0, 19884.90057549761, 20116.798832011493, 20237.69643455379, 20301.593968120258, 20373.57384983037, 22582.0, 22742.0, 22772.65841012353, 22789.544209115393, 22960.19639312616, 21515.784842152003, 21912.561748765253, 21943.451413714785, 22148.446288842264, 22151.44621384478, 20078.297259595845, 20337.898310508353, 20366.28717994863, 20492.38522844317, 20494.487637809176, 20434.79565204329, 20472.795272047686, 20486.38540843777, 20681.0, 20690.689639655116, 15843.920780396023, 15875.206239687739, 16103.597410064842, 16265.918670406572, 16353.754693679843, 16068.67862642764, 16140.435084771976, 16251.349946002027, 16269.593260168116, 16325.102119383073], "value": 10420.673424439525, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1350.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 105.0, 104.0, 105.0, 104.0, 105.0, 107.0, 103.0, 106.0, 105.0], "value": 105.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 118.0, 110.0, 111.0, 141.0, 112.0, 110.0, 110.0, 111.0, 111.0], "value": 114.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 162.0, 161.0, 161.0, 162.0, 161.0, 162.0, 159.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 75.0, 73.0, 73.0, 76.0, 75.0, 74.0, 77.0, 76.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 77.0, 81.0, 78.0, 77.0, 77.0, 76.0, 77.0, 78.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 117.0, 114.0, 114.0, 113.0, 114.0, 113.0, 114.0, 113.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 148.0, 147.0, 149.0, 148.0, 149.0, 148.0, 149.0, 152.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 94.0, 94.0, 98.0, 94.0, 94.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 42.0, 42.0, 43.0, 50.0, 44.0, 43.0, 43.0, 42.0, 42.0], "value": 43.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [35.0, 36.0, 36.0, 35.0, 37.0, 36.0, 36.0, 36.0, 36.0, 37.0], "value": 36.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 70.0, 68.0, 68.0, 70.0, 69.0, 70.0, 71.0], "value": 69.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 108.0, 100.0, 103.0, 103.0, 109.0, 105.0, 102.0, 107.0, 106.0], "value": 104.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 148.0, 148.0, 145.0, 150.0, 146.0, 149.0, 146.0, 146.0, 144.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 61.0, 61.0, 64.0, 57.0, 57.0, 60.0, 57.0], "value": 59.4, "unit": "score"}]}]} 08:50:52 INFO - Return code: 0 08:50:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:50:52 INFO - # TBPL SUCCESS # 08:50:52 INFO - Running post-action listener: _package_coverage_data 08:50:52 INFO - Running post-action listener: _resource_record_post_action 08:50:52 INFO - [mozharness: 2017-10-24 15:50:52.988473Z] Finished run-tests step (success) 08:50:52 INFO - Running post-run listener: _resource_record_post_run 08:50:53 INFO - Total resource usage - Wall time: 1182s; CPU: 13.0%; Read bytes: 4096; Write bytes: 285806592; Read time: 8; Write time: 140676 08:50:53 INFO - TinderboxPrint: CPU usage
13.0% 08:50:53 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 08:50:53 INFO - TinderboxPrint: I/O write bytes / time
285,806,592 / 140,676 08:50:53 INFO - TinderboxPrint: CPU idle
8,194.9 (86.9%) 08:50:53 INFO - TinderboxPrint: CPU user
1,178.6 (12.5%) 08:50:53 INFO - TinderboxPrint: Swap in / out
0 / 0 08:50:53 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 11923456; Read time: 0; Write time: 1496 08:50:53 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 08:50:53 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 0; Write bytes: 273469440; Read time: 0; Write time: 139156 08:50:53 INFO - Running post-run listener: _upload_blobber_files 08:50:53 INFO - Blob upload gear active. 08:50:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:50:53 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 08:50:53 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'] 08:50:53 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 08:50:54 INFO - (blobuploader) - INFO - Open directory for files ... 08:50:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:50:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:50:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:50:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:50:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:50:55 INFO - (blobuploader) - INFO - Done attempting. 08:50:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:50:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:50:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:50:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:50:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:50:55 INFO - (blobuploader) - INFO - Done attempting. 08:50:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:50:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:50:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:50:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:50:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:50:56 INFO - (blobuploader) - INFO - Done attempting. 08:50:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:50:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:50:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:51:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:00 INFO - (blobuploader) - INFO - Done attempting. 08:51:00 INFO - (blobuploader) - INFO - Iteration through files over. 08:51:00 INFO - Return code: 0 08:51:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:51:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:51:00 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3c34e51fee22cc16a203d779a7edfdd95dd8c965c6c0102d06bf39e610ce4290b2c3df7f18e05742a0c5e233f8cc3c1de80a1f69d01440c297624c4ed2c3b7ce", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d2918a7d6964e7b21fe37eaffc565ed9af3a5d0efc834db45697d0abf8e2cb6711d06e057573012d71d48d2a1f1373e3c51f19b648713efde0885833cf932ef8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d1468f31a182832060dfbc48dca6a5ff05f5d91e674d0b28ddb36592c317c645ea4c3ae705be0fa605530179e0c2fea072348bfb35bdbfc21c15f1079634dc26", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b30e1a97ce2438f0a055f8afe4ef36893e1307fa2c5d89c2ea80a47c5a89821de71533e43bf60e95aa1a068eafffd5b6f205e0cacf7886d8c015651b60dbc9a8"} 08:51:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:51:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:51:00 INFO - Contents: 08:51:00 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3c34e51fee22cc16a203d779a7edfdd95dd8c965c6c0102d06bf39e610ce4290b2c3df7f18e05742a0c5e233f8cc3c1de80a1f69d01440c297624c4ed2c3b7ce", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d2918a7d6964e7b21fe37eaffc565ed9af3a5d0efc834db45697d0abf8e2cb6711d06e057573012d71d48d2a1f1373e3c51f19b648713efde0885833cf932ef8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d1468f31a182832060dfbc48dca6a5ff05f5d91e674d0b28ddb36592c317c645ea4c3ae705be0fa605530179e0c2fea072348bfb35bdbfc21c15f1079634dc26", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b30e1a97ce2438f0a055f8afe4ef36893e1307fa2c5d89c2ea80a47c5a89821de71533e43bf60e95aa1a068eafffd5b6f205e0cacf7886d8c015651b60dbc9a8"} 08:51:00 INFO - Running post-run listener: copy_logs_to_upload_dir 08:51:00 INFO - Copying logs to upload dir... 08:51:00 INFO - mkdir: /builds/slave/test/build/upload/logs 08:51:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.262371 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2017-10-24 08:51:00.203787) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-24 08:51:00.208840) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3c34e51fee22cc16a203d779a7edfdd95dd8c965c6c0102d06bf39e610ce4290b2c3df7f18e05742a0c5e233f8cc3c1de80a1f69d01440c297624c4ed2c3b7ce", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d2918a7d6964e7b21fe37eaffc565ed9af3a5d0efc834db45697d0abf8e2cb6711d06e057573012d71d48d2a1f1373e3c51f19b648713efde0885833cf932ef8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d1468f31a182832060dfbc48dca6a5ff05f5d91e674d0b28ddb36592c317c645ea4c3ae705be0fa605530179e0c2fea072348bfb35bdbfc21c15f1079634dc26", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b30e1a97ce2438f0a055f8afe4ef36893e1307fa2c5d89c2ea80a47c5a89821de71533e43bf60e95aa1a068eafffd5b6f205e0cacf7886d8c015651b60dbc9a8"} build_url:https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011277 build_url: 'https://queue.taskcluster.net/v1/task/HFRzibwGRdanUV-4xuWB2Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3c34e51fee22cc16a203d779a7edfdd95dd8c965c6c0102d06bf39e610ce4290b2c3df7f18e05742a0c5e233f8cc3c1de80a1f69d01440c297624c4ed2c3b7ce", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d2918a7d6964e7b21fe37eaffc565ed9af3a5d0efc834db45697d0abf8e2cb6711d06e057573012d71d48d2a1f1373e3c51f19b648713efde0885833cf932ef8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d1468f31a182832060dfbc48dca6a5ff05f5d91e674d0b28ddb36592c317c645ea4c3ae705be0fa605530179e0c2fea072348bfb35bdbfc21c15f1079634dc26", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b30e1a97ce2438f0a055f8afe4ef36893e1307fa2c5d89c2ea80a47c5a89821de71533e43bf60e95aa1a068eafffd5b6f205e0cacf7886d8c015651b60dbc9a8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-24 08:51:00.241129) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-24 08:51:00.241662) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1508858981.220715-1472382404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004516 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-24 08:51:00.323451) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-24 08:51:00.323795) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-24 08:51:00.324160) ========= ========= Total master_lag: 0.21 =========