builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-047 starttime: 1507663339.72 results: success (0) revision: ed53ec40faf53fe22e65df3fc47135cdbb1ae030 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-10 12:22:19.718235) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-10 12:22:19.718866) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-10 12:22:19.719144) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-10 12:22:19.765126) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-10 12:22:19.765490) ========= 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=03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-10 12:22:19-- 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% 226M=0s 2017-10-10 12:22:19 (226 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.238401 ========= master_lag: 0.26 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-10 12:22:20.265577) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-10 12:22:20.265936) ========= 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=03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016219 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-10 12:22:20.334127) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-10 12:22:20.334523) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ed53ec40faf53fe22e65df3fc47135cdbb1ae030 --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 ed53ec40faf53fe22e65df3fc47135cdbb1ae030 --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=03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-10 12:22:20,268 truncating revision to first 12 chars 2017-10-10 12:22:20,268 Setting DEBUG logging. 2017-10-10 12:22:20,268 attempt 1/10 2017-10-10 12:22:20,268 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ed53ec40faf5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-10 12:22:21,635 unpacking tar archive at: mozilla-beta-ed53ec40faf5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.635682 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-10 12:22:22.002598) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-10 12:22:22.005997) ========= script_repo_revision: ed53ec40faf53fe22e65df3fc47135cdbb1ae030 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-10 12:22:22.006430) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-10 12:22:22.006736) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-10 12:22:22.023103) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-10-10 12:22:22.023478) ========= /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=03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:22:22 INFO - MultiFileLogger online at 20171010 12:22:22 in /builds/slave/test 12:22:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 12:22:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:22:22 INFO - {'append_to_log': False, 12:22:22 INFO - 'base_work_dir': '/builds/slave/test', 12:22:22 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 12:22:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:22:22 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 12:22:22 INFO - 'buildbot_json_path': 'buildprops.json', 12:22:22 INFO - 'code_coverage': False, 12:22:22 INFO - 'config_files': ('talos/linux_config.py',), 12:22:22 INFO - 'default_actions': ('clobber', 12:22:22 INFO - 'read-buildbot-config', 12:22:22 INFO - 'download-and-extract', 12:22:22 INFO - 'populate-webroot', 12:22:22 INFO - 'create-virtualenv', 12:22:22 INFO - 'install', 12:22:22 INFO - 'setup-mitmproxy', 12:22:22 INFO - 'run-tests'), 12:22:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:22:22 INFO - 'disable_ccov_upload': False, 12:22:22 INFO - 'disable_stylo': False, 12:22:22 INFO - 'download_minidump_stackwalk': True, 12:22:22 INFO - 'download_symbols': 'ondemand', 12:22:22 INFO - 'enable_stylo': False, 12:22:22 INFO - 'enable_webrender': False, 12:22:22 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:22:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:22:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:22:22 INFO - 'gecko_profile': False, 12:22:22 INFO - 'gecko_profile_interval': 0, 12:22:22 INFO - 'installer_path': 'installer.exe', 12:22:22 INFO - 'log_level': 'info', 12:22:22 INFO - 'log_name': 'talos', 12:22:22 INFO - 'log_to_console': True, 12:22:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:22:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:22:22 INFO - 'opt_config_files': (), 12:22:22 INFO - 'pip_index': False, 12:22:22 INFO - 'suite': 'dromaeojs-e10s', 12:22:22 INFO - 'system_bits': '32', 12:22:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:22:22 INFO - 'title': 'talos-linux64-ix-047', 12:22:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:22:22 INFO - 'use_talos_json': True, 12:22:22 INFO - 'verify': 'False', 12:22:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:22:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:22:22 INFO - 'work_dir': 'build'} 12:22:22 INFO - [mozharness: 2017-10-10 19:22:22.189870Z] Running clobber step. 12:22:22 INFO - Running pre-action listener: _resource_record_pre_action 12:22:22 INFO - Running main action method: clobber 12:22:22 INFO - rmtree: /builds/slave/test/build 12:22:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:22:23 INFO - Running post-action listener: _resource_record_post_action 12:22:23 INFO - [mozharness: 2017-10-10 19:22:23.163570Z] Finished clobber step (success) 12:22:23 INFO - [mozharness: 2017-10-10 19:22:23.163667Z] Running read-buildbot-config step. 12:22:23 INFO - Running pre-action listener: _resource_record_pre_action 12:22:23 INFO - Running main action method: read_buildbot_config 12:22:23 INFO - Using buildbot properties: 12:22:23 INFO - { 12:22:23 INFO - "project": "", 12:22:23 INFO - "product": "firefox", 12:22:23 INFO - "who": "ffxbld@noreply.mozilla.org", 12:22:23 INFO - "installer_path": "public/build/target.tar.bz2", 12:22:23 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 12:22:23 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 12:22:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 12:22:23 INFO - "stage_platform": "linux64", 12:22:23 INFO - "basedir": "/builds/slave/test", 12:22:23 INFO - "buildnumber": 162, 12:22:23 INFO - "platform": "ubuntu64_hw", 12:22:23 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:22:23 INFO - "slavebuilddir": "test", 12:22:23 INFO - "taskId": "Up3RT_TOTmaZDuJvynr2EA", 12:22:23 INFO - "branch": "mozilla-beta", 12:22:23 INFO - "script_repo_revision": "production", 12:22:23 INFO - "revision": "ed53ec40faf53fe22e65df3fc47135cdbb1ae030", 12:22:23 INFO - "slavename": "talos-linux64-ix-047", 12:22:23 INFO - "repo_path": "releases/mozilla-beta" 12:22:23 INFO - } 12:22:23 INFO - Finding installer, test and symbols from parent task. 12:22:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Up3RT_TOTmaZDuJvynr2EA'}, attempt #1 12:22:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Up3RT_TOTmaZDuJvynr2EA'}, attempt #1 12:22:24 INFO - Task dependencies: TSIKWs0gS4aJYxqYDOhsYw 12:22:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw'}, attempt #1 12:22:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.tar.bz2 12:22:24 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.test_packages.json 12:22:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.crashreporter-symbols.zip 12:22:24 INFO - Running post-action listener: _resource_record_post_action 12:22:24 INFO - [mozharness: 2017-10-10 19:22:24.707856Z] Finished read-buildbot-config step (success) 12:22:24 INFO - [mozharness: 2017-10-10 19:22:24.708173Z] Running download-and-extract step. 12:22:24 INFO - Running pre-action listener: _resource_record_pre_action 12:22:24 INFO - Running main action method: download_and_extract 12:22:24 INFO - mkdir: /builds/slave/test/build/tests 12:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:24 INFO - trying https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.test_packages.json 12:22:24 INFO - Downloading https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:22:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:22:25 INFO - Downloaded 989 bytes. 12:22:25 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:22:25 INFO - Using the following test package requirements: 12:22:25 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:22:25 INFO - u'common': [u'target.common.tests.zip'], 12:22:25 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:22:25 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:22:25 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:22:25 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:22:25 INFO - u'mozbase': [u'target.common.tests.zip'], 12:22:25 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:22:25 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:22:25 INFO - u'web-platform': [u'target.common.tests.zip', 12:22:25 INFO - u'target.web-platform.tests.tar.gz'], 12:22:25 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:22:25 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 12:22:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.common.tests.zip 12:22:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:22:25 INFO - Fetch https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.common.tests.zip into memory 12:22:27 INFO - Content-Length response header: 55184289 12:22:27 INFO - Bytes received: 55184289 12:22:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.talos.tests.zip 12:22:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:22:38 INFO - Fetch https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.talos.tests.zip into memory 12:22:39 INFO - Content-Length response header: 14509251 12:22:39 INFO - Bytes received: 14509251 12:22:40 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')]} 12:22:40 INFO - trying https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.tar.bz2 12:22:40 INFO - Downloading https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:22:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:22:41 INFO - Downloaded 53699002 bytes. 12:22:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.tar.bz2 12:22:41 INFO - mkdir: /builds/slave/test/properties 12:22:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:22:41 INFO - Writing to file /builds/slave/test/properties/build_url 12:22:41 INFO - Contents: 12:22:41 INFO - build_url:https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.tar.bz2 12:22:41 INFO - Running post-action listener: _resource_record_post_action 12:22:41 INFO - Running post-action listener: find_tests_for_verification 12:22:41 INFO - Running post-action listener: set_extra_try_arguments 12:22:41 INFO - [mozharness: 2017-10-10 19:22:41.849569Z] Finished download-and-extract step (success) 12:22:41 INFO - [mozharness: 2017-10-10 19:22:41.849673Z] Running populate-webroot step. 12:22:41 INFO - Running pre-action listener: _resource_record_pre_action 12:22:41 INFO - Running main action method: populate_webroot 12:22:41 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:22:41 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:22:41 INFO - 'tests': ['tresize', 12:22:41 INFO - 'tcanvasmark']}, 12:22:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:22:41 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:22:41 INFO - 'tests': ['dromaeo_css', 12:22:41 INFO - 'kraken']}, 12:22:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:22:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:22:41 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:22:41 INFO - 'talos_options': ['--disable-stylo'], 12:22:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:22:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:22:41 INFO - 'tests': ['damp', 'tps']}, 12:22:41 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:22:41 INFO - 'talos_options': ['--disable-stylo'], 12:22:41 INFO - 'tests': ['damp', 'tps']}, 12:22:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:22:41 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:22:41 INFO - 'tests': ['dromaeo_dom']}, 12:22:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:22:41 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:22:41 INFO - 'tests': ['basic_compositor_video', 12:22:41 INFO - 'glvideo']}, 12:22:41 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:22:41 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:22:41 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:22:41 INFO - 'talos_options': ['--disable-stylo'], 12:22:41 INFO - 'tests': ['ts_paint_webext', 12:22:41 INFO - 'tp5o_webext']}, 12:22:41 INFO - 'other-e10s': {'tests': ['a11yr', 12:22:41 INFO - 'ts_paint', 12:22:41 INFO - 'tpaint', 12:22:41 INFO - 'sessionrestore', 12:22:41 INFO - 'sessionrestore_many_windows', 12:22:41 INFO - 'sessionrestore_no_auto_restore', 12:22:41 INFO - 'tabpaint']}, 12:22:41 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:22:41 INFO - 'tests': ['a11yr', 12:22:41 INFO - 'ts_paint', 12:22:41 INFO - 'tpaint', 12:22:41 INFO - 'sessionrestore', 12:22:41 INFO - 'sessionrestore_many_windows', 12:22:41 INFO - 'sessionrestore_no_auto_restore', 12:22:41 INFO - 'tabpaint']}, 12:22:41 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 12:22:41 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 12:22:41 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:22:41 INFO - 'tests': ['perf_reftest_singletons']}, 12:22:41 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:22:41 INFO - 'tests': ['bloom_basic']}, 12:22:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:22:41 INFO - 'tsvgr_opacity', 12:22:41 INFO - 'tart', 12:22:41 INFO - 'tscrollx', 12:22:41 INFO - 'tsvg_static']}, 12:22:41 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:22:41 INFO - 'tests': ['tsvgx', 12:22:41 INFO - 'tsvgr_opacity', 12:22:41 INFO - 'tart', 12:22:41 INFO - 'tscrollx', 12:22:41 INFO - 'tsvg_static']}, 12:22:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:22:41 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:22:41 INFO - 'talos_options': ['--disable-stylo'], 12:22:41 INFO - 'tests': ['tp5o']}, 12:22:41 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:22:41 INFO - 'talos_options': ['--mitmproxy', 12:22:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:22:41 INFO - '--firstNonBlankPaint'], 12:22:41 INFO - 'tests': ['tp6_google', 12:22:41 INFO - 'tp6_youtube', 12:22:41 INFO - 'tp6_amazon', 12:22:41 INFO - 'tp6_facebook']}, 12:22:41 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:22:41 INFO - 'talos_options': ['--disable-stylo', 12:22:41 INFO - '--mitmproxy', 12:22:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:22:41 INFO - '--firstNonBlankPaint'], 12:22:41 INFO - 'tests': ['tp6_google', 12:22:41 INFO - 'tp6_youtube', 12:22:41 INFO - 'tp6_amazon', 12:22:41 INFO - 'tp6_facebook']}, 12:22:41 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:22:41 INFO - 'talos_options': ['--stylo-threads=1', 12:22:41 INFO - '--mitmproxy', 12:22:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:22:41 INFO - '--firstNonBlankPaint'], 12:22:41 INFO - 'tests': ['tp6_google', 12:22:41 INFO - 'tp6_youtube', 12:22:41 INFO - 'tp6_amazon', 12:22:41 INFO - 'tp6_facebook']}, 12:22:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:22:41 INFO - 'talos_options': ['--xperf_path', 12:22:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:22:41 INFO - 'tests': ['tp5n']}, 12:22:41 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:22:41 INFO - 'talos_options': ['--disable-stylo', 12:22:41 INFO - '--xperf_path', 12:22:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:22:41 INFO - 'tests': ['tp5n']}}} 12:22:41 INFO - Running post-action listener: _resource_record_post_action 12:22:41 INFO - [mozharness: 2017-10-10 19:22:41.864645Z] Finished populate-webroot step (success) 12:22:41 INFO - [mozharness: 2017-10-10 19:22:41.864735Z] Running create-virtualenv step. 12:22:41 INFO - Running pre-action listener: _resource_record_pre_action 12:22:41 INFO - Running main action method: create_virtualenv 12:22:41 INFO - Creating virtualenv /builds/slave/test/build/venv 12:22:41 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:22:41 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:22:41 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:22:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:22:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:22:42 INFO - Installing distribute.............................................................................................................................................................................................done. 12:22:42 INFO - Installing pip...............done. 12:22:42 INFO - Return code: 0 12:22:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:22:43 INFO - Reading from file tmpfile_stdout 12:22:43 INFO - Output received: 12:22:43 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:22:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:22:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a7090>, '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 0x26a7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a1f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', '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 12:22:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:22:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:22:43 INFO - Using env: {'DISPLAY': ':0', 12:22:43 INFO - 'HOME': '/home/cltbld', 12:22:43 INFO - 'LANG': 'en_US.UTF-8', 12:22:43 INFO - 'LANGUAGE': 'en_US:en', 12:22:43 INFO - 'LOGNAME': 'cltbld', 12:22:43 INFO - 'MAIL': '/var/mail/cltbld', 12:22:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:43 INFO - 'MOZ_NO_REMOTE': '1', 12:22:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:22:43 INFO - 'NO_EM_RESTART': '1', 12:22:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:22:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:43 INFO - 'PWD': '/builds/slave/test', 12:22:43 INFO - 'SHELL': '/bin/bash', 12:22:43 INFO - 'SHLVL': '1', 12:22:43 INFO - 'TERM': 'linux', 12:22:43 INFO - 'TMOUT': '86400', 12:22:43 INFO - 'USER': 'cltbld', 12:22:43 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', 12:22:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:43 INFO - '_': '/tools/buildbot/bin/python'} 12:22:44 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:22:44 INFO - Downloading/unpacking pip>=1.5 12:22:44 INFO - Running setup.py egg_info for package pip 12:22:44 INFO - warning: no files found matching 'pip/cacert.pem' 12:22:44 INFO - warning: no files found matching '*.html' under directory 'docs' 12:22:44 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:22:44 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:22:44 INFO - Installing collected packages: pip 12:22:44 INFO - Found existing installation: pip 1.1 12:22:44 INFO - Uninstalling pip: 12:22:44 INFO - Successfully uninstalled pip 12:22:44 INFO - Running setup.py install for pip 12:22:44 INFO - warning: no files found matching 'pip/cacert.pem' 12:22:44 INFO - warning: no files found matching '*.html' under directory 'docs' 12:22:44 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:22:44 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:22:44 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:22:44 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:22:44 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:22:44 INFO - Successfully installed pip 12:22:44 INFO - Cleaning up... 12:22:44 INFO - Return code: 0 12:22:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:22:44 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')]} 12:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:44 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')]} 12:22:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:44 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 0x23381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a7090>, '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 0x26a7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a1f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', '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 12:22:44 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 12:22:44 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 12:22:44 INFO - Using env: {'DISPLAY': ':0', 12:22:44 INFO - 'HOME': '/home/cltbld', 12:22:44 INFO - 'LANG': 'en_US.UTF-8', 12:22:44 INFO - 'LANGUAGE': 'en_US:en', 12:22:44 INFO - 'LOGNAME': 'cltbld', 12:22:44 INFO - 'MAIL': '/var/mail/cltbld', 12:22:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:44 INFO - 'MOZ_NO_REMOTE': '1', 12:22:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:22:44 INFO - 'NO_EM_RESTART': '1', 12:22:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:22:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:44 INFO - 'PWD': '/builds/slave/test', 12:22:44 INFO - 'SHELL': '/bin/bash', 12:22:44 INFO - 'SHLVL': '1', 12:22:44 INFO - 'TERM': 'linux', 12:22:44 INFO - 'TMOUT': '86400', 12:22:44 INFO - 'USER': 'cltbld', 12:22:44 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', 12:22:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:44 INFO - '_': '/tools/buildbot/bin/python'} 12:22:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:44 INFO - Downloading/unpacking psutil>=3.1.1 12:22:44 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 12:22:44 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 12:22:44 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 12:22:44 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 12:22:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:22:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:22:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:22:46 INFO - Installing collected packages: psutil 12:22:46 INFO - Running setup.py install for psutil 12:22:46 INFO - building 'psutil._psutil_linux' extension 12:22:46 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 12:22:47 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 12:22:47 INFO - building 'psutil._psutil_posix' extension 12:22:47 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 12:22:47 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 12:22:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:22:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:22:47 INFO - Successfully installed psutil 12:22:47 INFO - Cleaning up... 12:22:48 INFO - Return code: 0 12:22:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:22:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a7090>, '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 0x26a7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a1f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', '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 12:22:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:22:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:22:48 INFO - Using env: {'DISPLAY': ':0', 12:22:48 INFO - 'HOME': '/home/cltbld', 12:22:48 INFO - 'LANG': 'en_US.UTF-8', 12:22:48 INFO - 'LANGUAGE': 'en_US:en', 12:22:48 INFO - 'LOGNAME': 'cltbld', 12:22:48 INFO - 'MAIL': '/var/mail/cltbld', 12:22:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:48 INFO - 'MOZ_NO_REMOTE': '1', 12:22:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:22:48 INFO - 'NO_EM_RESTART': '1', 12:22:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:22:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:48 INFO - 'PWD': '/builds/slave/test', 12:22:48 INFO - 'SHELL': '/bin/bash', 12:22:48 INFO - 'SHLVL': '1', 12:22:48 INFO - 'TERM': 'linux', 12:22:48 INFO - 'TMOUT': '86400', 12:22:48 INFO - 'USER': 'cltbld', 12:22:48 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', 12:22:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:48 INFO - '_': '/tools/buildbot/bin/python'} 12:22:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:22:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:22:49 INFO - Installing collected packages: mozsystemmonitor 12:22:49 INFO - Running setup.py install for mozsystemmonitor 12:22:49 INFO - Successfully installed mozsystemmonitor 12:22:49 INFO - Cleaning up... 12:22:49 INFO - Return code: 0 12:22:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:22:49 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')]} 12:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:49 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')]} 12:22:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:49 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 0x23381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a7090>, '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 0x26a7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a1f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', '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 12:22:49 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 12:22:49 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 12:22:49 INFO - Using env: {'DISPLAY': ':0', 12:22:49 INFO - 'HOME': '/home/cltbld', 12:22:49 INFO - 'LANG': 'en_US.UTF-8', 12:22:49 INFO - 'LANGUAGE': 'en_US:en', 12:22:49 INFO - 'LOGNAME': 'cltbld', 12:22:49 INFO - 'MAIL': '/var/mail/cltbld', 12:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:49 INFO - 'MOZ_NO_REMOTE': '1', 12:22:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:22:49 INFO - 'NO_EM_RESTART': '1', 12:22:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:49 INFO - 'PWD': '/builds/slave/test', 12:22:49 INFO - 'SHELL': '/bin/bash', 12:22:49 INFO - 'SHLVL': '1', 12:22:49 INFO - 'TERM': 'linux', 12:22:49 INFO - 'TMOUT': '86400', 12:22:49 INFO - 'USER': 'cltbld', 12:22:49 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', 12:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:49 INFO - '_': '/tools/buildbot/bin/python'} 12:22:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:49 INFO - Downloading/unpacking jsonschema==2.5.1 12:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:22:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:22:50 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 12:22:50 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 12:22:50 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 12:22:50 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 12:22:50 INFO - Downloading functools32-3.2.3-2.tar.gz 12:22:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:22:51 INFO - warning: no files found matching '*.txt' 12:22:51 INFO - no previously-included directories found matching 'build' 12:22:51 INFO - no previously-included directories found matching 'dist' 12:22:51 INFO - no previously-included directories found matching '.git*' 12:22:51 INFO - Installing collected packages: jsonschema, functools32 12:22:51 INFO - Running setup.py install for functools32 12:22:51 INFO - warning: no files found matching '*.txt' 12:22:51 INFO - no previously-included directories found matching 'build' 12:22:51 INFO - no previously-included directories found matching 'dist' 12:22:51 INFO - no previously-included directories found matching '.git*' 12:22:51 INFO - Successfully installed jsonschema functools32 12:22:51 INFO - Cleaning up... 12:22:51 INFO - Return code: 0 12:22:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a7090>, '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 0x26a7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a1f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', '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 12:22:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:22:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:22:51 INFO - Using env: {'DISPLAY': ':0', 12:22:51 INFO - 'HOME': '/home/cltbld', 12:22:51 INFO - 'LANG': 'en_US.UTF-8', 12:22:51 INFO - 'LANGUAGE': 'en_US:en', 12:22:51 INFO - 'LOGNAME': 'cltbld', 12:22:51 INFO - 'MAIL': '/var/mail/cltbld', 12:22:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:51 INFO - 'MOZ_NO_REMOTE': '1', 12:22:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:22:51 INFO - 'NO_EM_RESTART': '1', 12:22:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:22:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:51 INFO - 'PWD': '/builds/slave/test', 12:22:51 INFO - 'SHELL': '/bin/bash', 12:22:51 INFO - 'SHLVL': '1', 12:22:51 INFO - 'TERM': 'linux', 12:22:51 INFO - 'TMOUT': '86400', 12:22:51 INFO - 'USER': 'cltbld', 12:22:51 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', 12:22:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:51 INFO - '_': '/tools/buildbot/bin/python'} 12:22:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:22:51 INFO - Cleaning up... 12:22:51 INFO - Return code: 0 12:22:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a7090>, '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 0x26a7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a1f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', '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 12:22:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:22:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:22:51 INFO - Using env: {'DISPLAY': ':0', 12:22:51 INFO - 'HOME': '/home/cltbld', 12:22:51 INFO - 'LANG': 'en_US.UTF-8', 12:22:51 INFO - 'LANGUAGE': 'en_US:en', 12:22:51 INFO - 'LOGNAME': 'cltbld', 12:22:51 INFO - 'MAIL': '/var/mail/cltbld', 12:22:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:51 INFO - 'MOZ_NO_REMOTE': '1', 12:22:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:22:51 INFO - 'NO_EM_RESTART': '1', 12:22:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:22:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:51 INFO - 'PWD': '/builds/slave/test', 12:22:51 INFO - 'SHELL': '/bin/bash', 12:22:51 INFO - 'SHLVL': '1', 12:22:51 INFO - 'TERM': 'linux', 12:22:51 INFO - 'TMOUT': '86400', 12:22:51 INFO - 'USER': 'cltbld', 12:22:51 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', 12:22:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:51 INFO - '_': '/tools/buildbot/bin/python'} 12:22:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:51 INFO - Downloading/unpacking blobuploader==1.2.4 12:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:52 INFO - Downloading blobuploader-1.2.4.tar.gz 12:22:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:22:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:22: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 12:22: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 12:22: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 12:22: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 12:22:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:22:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:22:53 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 12:22:53 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 12:22:53 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 12:22:53 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 12:22:53 INFO - Downloading docopt-0.6.1.tar.gz 12:22:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:22:53 INFO - Installing collected packages: blobuploader, requests, docopt 12:22:53 INFO - Running setup.py install for blobuploader 12:22:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:22:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:22:53 INFO - Running setup.py install for requests 12:22:53 INFO - Running setup.py install for docopt 12:22:54 INFO - Successfully installed blobuploader requests docopt 12:22:54 INFO - Cleaning up... 12:22:54 INFO - Return code: 0 12:22:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:22: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')]} 12:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22: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')]} 12:22:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:54 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 0x23381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a7090>, '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 0x26a7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a1f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', '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 12:22:54 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 12:22:54 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 12:22:54 INFO - Using env: {'DISPLAY': ':0', 12:22:54 INFO - 'HOME': '/home/cltbld', 12:22:54 INFO - 'LANG': 'en_US.UTF-8', 12:22:54 INFO - 'LANGUAGE': 'en_US:en', 12:22:54 INFO - 'LOGNAME': 'cltbld', 12:22:54 INFO - 'MAIL': '/var/mail/cltbld', 12:22:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:54 INFO - 'MOZ_NO_REMOTE': '1', 12:22:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:22:54 INFO - 'NO_EM_RESTART': '1', 12:22:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:22:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:54 INFO - 'PWD': '/builds/slave/test', 12:22:54 INFO - 'SHELL': '/bin/bash', 12:22:54 INFO - 'SHLVL': '1', 12:22:54 INFO - 'TERM': 'linux', 12:22:54 INFO - 'TMOUT': '86400', 12:22:54 INFO - 'USER': 'cltbld', 12:22:54 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', 12:22:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:54 INFO - '_': '/tools/buildbot/bin/python'} 12:22:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:22:54 INFO - Running setup.py (path:/tmp/pip-yiOkI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:22:54 INFO - Running setup.py (path:/tmp/pip-XLxfYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:22:54 INFO - Running setup.py (path:/tmp/pip-B9xov9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:22:54 INFO - Running setup.py (path:/tmp/pip-IKfKH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:22:54 INFO - Running setup.py (path:/tmp/pip-nlLTtP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:22:54 INFO - Running setup.py (path:/tmp/pip-XNKDGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:22:54 INFO - Running setup.py (path:/tmp/pip-vxjbAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:22:54 INFO - Running setup.py (path:/tmp/pip-QkiPzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:22:54 INFO - Running setup.py (path:/tmp/pip-r7ruEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:22:54 INFO - Running setup.py (path:/tmp/pip-l9cSCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:22:54 INFO - Running setup.py (path:/tmp/pip-7R77RX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:22:55 INFO - Running setup.py (path:/tmp/pip-isVdmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:22:55 INFO - Running setup.py (path:/tmp/pip-DSZbNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:22:55 INFO - Running setup.py (path:/tmp/pip-CvQ3Ud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:55 INFO - Running setup.py (path:/tmp/pip-ALI5H0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:22:55 INFO - Running setup.py (path:/tmp/pip-IFa2Fk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:22:55 INFO - Running setup.py (path:/tmp/pip-7qmYiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:22:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:22:55 INFO - Running setup.py install for manifestparser 12:22:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:22:55 INFO - Running setup.py install for mozcrash 12:22:55 INFO - Running setup.py install for mozdebug 12:22:55 INFO - Running setup.py install for mozdevice 12:22:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:22:55 INFO - Running setup.py install for mozfile 12:22:55 INFO - Running setup.py install for mozhttpd 12:22:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:22:56 INFO - Running setup.py install for mozinfo 12:22:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:22:56 INFO - Running setup.py install for mozInstall 12:22:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:22:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:22:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:22:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:22:56 INFO - Running setup.py install for mozleak 12:22:56 INFO - Running setup.py install for mozlog 12:22:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:22:56 INFO - Running setup.py install for moznetwork 12:22:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:22:56 INFO - Running setup.py install for mozprocess 12:22:56 INFO - Running setup.py install for mozprofile 12:22:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:22:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:22:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:22:56 INFO - Running setup.py install for mozrunner 12:22:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:22:56 INFO - Running setup.py install for mozscreenshot 12:22:57 INFO - Running setup.py install for moztest 12:22:57 INFO - Running setup.py install for mozversion 12:22:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:22:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:22:57 INFO - Cleaning up... 12:22:57 INFO - Return code: 0 12:22:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:57 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 0x23381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a7090>, '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 0x26a7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a1f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', '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 12:22:57 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 12:22:57 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 12:22:57 INFO - Using env: {'DISPLAY': ':0', 12:22:57 INFO - 'HOME': '/home/cltbld', 12:22:57 INFO - 'LANG': 'en_US.UTF-8', 12:22:57 INFO - 'LANGUAGE': 'en_US:en', 12:22:57 INFO - 'LOGNAME': 'cltbld', 12:22:57 INFO - 'MAIL': '/var/mail/cltbld', 12:22:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:57 INFO - 'MOZ_NO_REMOTE': '1', 12:22:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:22:57 INFO - 'NO_EM_RESTART': '1', 12:22:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:22:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:57 INFO - 'PWD': '/builds/slave/test', 12:22:57 INFO - 'SHELL': '/bin/bash', 12:22:57 INFO - 'SHLVL': '1', 12:22:57 INFO - 'TERM': 'linux', 12:22:57 INFO - 'TMOUT': '86400', 12:22:57 INFO - 'USER': 'cltbld', 12:22:57 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', 12:22:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:57 INFO - '_': '/tools/buildbot/bin/python'} 12:22:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:22:57 INFO - Running setup.py (path:/tmp/pip-Zcnm4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:22:57 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:22:57 INFO - Running setup.py (path:/tmp/pip-38wpwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:22:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:22:57 INFO - Running setup.py (path:/tmp/pip-tFZaMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:22:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:22:57 INFO - Running setup.py (path:/tmp/pip-sgaaKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:22:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:22:57 INFO - Running setup.py (path:/tmp/pip-RHDrmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:22:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:22:57 INFO - Running setup.py (path:/tmp/pip-y3uh6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:22:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:22:57 INFO - Running setup.py (path:/tmp/pip-ceT0Ne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:22:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:22:57 INFO - Running setup.py (path:/tmp/pip-lP359T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:22:58 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)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:22:58 INFO - Running setup.py (path:/tmp/pip-XyeNqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:22:58 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)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:22:58 INFO - Running setup.py (path:/tmp/pip-LWZYlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:22:58 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)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:22:58 INFO - Running setup.py (path:/tmp/pip-p1hADL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:22:58 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)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:22:58 INFO - Running setup.py (path:/tmp/pip-Zn5ifE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:22:58 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)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:22:58 INFO - Running setup.py (path:/tmp/pip-eSIvsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:22:58 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)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:22:58 INFO - Running setup.py (path:/tmp/pip-dVs0em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:22:58 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)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:58 INFO - Running setup.py (path:/tmp/pip-GUOdyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:22:58 INFO - Running setup.py (path:/tmp/pip-Q2ZUns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:22:58 INFO - Running setup.py (path:/tmp/pip-s_tn_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:22:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:22: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 12:22: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 12:22: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 12:22: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 12:22:59 INFO - Downloading blessings-1.6.tar.gz 12:22:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:22:59 INFO - Installing collected packages: blessings 12:22:59 INFO - Running setup.py install for blessings 12:23:00 INFO - Successfully installed blessings 12:23:00 INFO - Cleaning up... 12:23:00 INFO - Return code: 0 12:23:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:23:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:23:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:23:00 INFO - Reading from file tmpfile_stdout 12:23:00 INFO - Current package versions: 12:23:00 INFO - argparse == 1.2.1 12:23:00 INFO - blessings == 1.6 12:23:00 INFO - blobuploader == 1.2.4 12:23:00 INFO - docopt == 0.6.1 12:23:00 INFO - functools32 == 3.2.3-2 12:23:00 INFO - jsonschema == 2.5.1 12:23:00 INFO - manifestparser == 1.1 12:23:00 INFO - mozInstall == 1.13 12:23:00 INFO - mozcrash == 1.0 12:23:00 INFO - mozdebug == 0.1 12:23:00 INFO - mozdevice == 0.51 12:23:00 INFO - mozfile == 1.2 12:23:00 INFO - mozhttpd == 0.7 12:23:00 INFO - mozinfo == 0.10 12:23:00 INFO - mozleak == 0.1 12:23:00 INFO - mozlog == 3.5 12:23:00 INFO - moznetwork == 0.27 12:23:00 INFO - mozprocess == 0.25 12:23:00 INFO - mozprofile == 0.28 12:23:00 INFO - mozrunner == 6.13 12:23:00 INFO - mozscreenshot == 0.1 12:23:00 INFO - mozsystemmonitor == 0.3 12:23:00 INFO - moztest == 0.8 12:23:00 INFO - mozversion == 1.4 12:23:00 INFO - psutil == 3.1.1 12:23:00 INFO - requests == 1.2.3 12:23:00 INFO - wsgiref == 0.1.2 12:23:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:00 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 0x23381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a7090>, '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 0x26a7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a1f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', '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 12:23:00 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 12:23:00 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 12:23:00 INFO - Using env: {'DISPLAY': ':0', 12:23:00 INFO - 'HOME': '/home/cltbld', 12:23:00 INFO - 'LANG': 'en_US.UTF-8', 12:23:00 INFO - 'LANGUAGE': 'en_US:en', 12:23:00 INFO - 'LOGNAME': 'cltbld', 12:23:00 INFO - 'MAIL': '/var/mail/cltbld', 12:23:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:00 INFO - 'MOZ_NO_REMOTE': '1', 12:23:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:00 INFO - 'NO_EM_RESTART': '1', 12:23:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:00 INFO - 'PWD': '/builds/slave/test', 12:23:00 INFO - 'SHELL': '/bin/bash', 12:23:00 INFO - 'SHLVL': '1', 12:23:00 INFO - 'TERM': 'linux', 12:23:00 INFO - 'TMOUT': '86400', 12:23:00 INFO - 'USER': 'cltbld', 12:23:00 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', 12:23:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:00 INFO - '_': '/tools/buildbot/bin/python'} 12:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:00 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)) 12:23:00 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)) 12:23:00 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)) 12:23:00 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)) 12:23:00 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)) 12:23:00 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)) 12:23:00 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)) 12:23:00 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)) 12:23:00 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)) 12:23:00 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:23: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 12:23: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 12:23: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 12:23: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 12:23:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:23:01 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:23:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:23:01 INFO - Installing collected packages: simplejson 12:23:01 INFO - Running setup.py install for simplejson 12:23:01 INFO - building 'simplejson._speedups' extension 12:23:01 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 12:23:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 12:23:02 INFO - Successfully installed simplejson 12:23:02 INFO - Cleaning up... 12:23:02 INFO - Return code: 0 12:23:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:23:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23381f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2500a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a7090>, '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 0x26a7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a1f90>, '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': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', '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 12:23:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:23:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:23:02 INFO - Using env: {'DISPLAY': ':0', 12:23:02 INFO - 'HOME': '/home/cltbld', 12:23:02 INFO - 'LANG': 'en_US.UTF-8', 12:23:02 INFO - 'LANGUAGE': 'en_US:en', 12:23:02 INFO - 'LOGNAME': 'cltbld', 12:23:02 INFO - 'MAIL': '/var/mail/cltbld', 12:23:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:02 INFO - 'MOZ_NO_REMOTE': '1', 12:23:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:02 INFO - 'NO_EM_RESTART': '1', 12:23:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:02 INFO - 'PWD': '/builds/slave/test', 12:23:02 INFO - 'SHELL': '/bin/bash', 12:23:02 INFO - 'SHLVL': '1', 12:23:02 INFO - 'TERM': 'linux', 12:23:02 INFO - 'TMOUT': '86400', 12:23:02 INFO - 'USER': 'cltbld', 12:23:02 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', 12:23:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:02 INFO - '_': '/tools/buildbot/bin/python'} 12:23:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:23:02 INFO - Cleaning up... 12:23:02 INFO - Return code: 0 12:23:02 INFO - Running post-action listener: _resource_record_post_action 12:23:02 INFO - Running post-action listener: _start_resource_monitoring 12:23:02 INFO - Starting resource monitoring. 12:23:02 INFO - [mozharness: 2017-10-10 19:23:02.982607Z] Finished create-virtualenv step (success) 12:23:02 INFO - [mozharness: 2017-10-10 19:23:02.983544Z] Running install step. 12:23:02 INFO - Running pre-action listener: _resource_record_pre_action 12:23:02 INFO - Running main action method: install 12:23:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:23:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:23:03 INFO - Reading from file tmpfile_stdout 12:23:03 INFO - Detecting whether we're running mozinstall >=1.0... 12:23:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:23:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:23:03 INFO - Reading from file tmpfile_stdout 12:23:03 INFO - Output received: 12:23:03 INFO - Usage: mozinstall [options] installer 12:23:03 INFO - Options: 12:23:03 INFO - -h, --help show this help message and exit 12:23:03 INFO - -d DEST, --destination=DEST 12:23:03 INFO - Directory to install application into. [default: 12:23:03 INFO - "/builds/slave/test"] 12:23:03 INFO - --app=APP Application being installed. [default: firefox] 12:23:03 INFO - mkdir: /builds/slave/test/build/application 12:23:03 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'] 12:23:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:23:17 INFO - Reading from file tmpfile_stdout 12:23:17 INFO - Output received: 12:23:17 INFO - /builds/slave/test/build/application/firefox/firefox 12:23:17 INFO - Running post-action listener: _resource_record_post_action 12:23:17 INFO - [mozharness: 2017-10-10 19:23:17.941137Z] Finished install step (success) 12:23:17 INFO - [mozharness: 2017-10-10 19:23:17.941283Z] Running setup-mitmproxy step. 12:23:17 INFO - Running pre-action listener: _resource_record_pre_action 12:23:17 INFO - Running main action method: setup_mitmproxy 12:23:17 INFO - Skipping: mitmproxy is not required 12:23:17 INFO - Running post-action listener: _resource_record_post_action 12:23:17 INFO - [mozharness: 2017-10-10 19:23:17.941815Z] Finished setup-mitmproxy step (success) 12:23:17 INFO - [mozharness: 2017-10-10 19:23:17.941919Z] Running run-tests step. 12:23:17 INFO - Running pre-action listener: _resource_record_pre_action 12:23:17 INFO - Running pre-action listener: _set_gcov_prefix 12:23:17 INFO - Running main action method: run_tests 12:23:17 WARNING - Try message not found. 12:23:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:23:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:23:17 INFO - Python 2.7.3 12:23:17 INFO - Return code: 0 12:23:17 INFO - grabbing minidump binary from tooltool 12:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:17 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a7090>, '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 0x26a7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a1f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:23:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:23:17 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 12:23:17 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:23:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:23:18 INFO - Return code: 0 12:23:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:23:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:23:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:23:18 INFO - ENV: RUST_BACKTRACE is now full 12:23:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:23:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:23:18 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/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 12:23:18 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/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-047 --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 12:23:18 INFO - Using env: {'DISPLAY': ':0', 12:23:18 INFO - 'HOME': '/home/cltbld', 12:23:18 INFO - 'LANG': 'en_US.UTF-8', 12:23:18 INFO - 'LANGUAGE': 'en_US:en', 12:23:18 INFO - 'LOGNAME': 'cltbld', 12:23:18 INFO - 'MAIL': '/var/mail/cltbld', 12:23:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:23:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:23:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:18 INFO - 'MOZ_NO_REMOTE': '1', 12:23:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:23:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:18 INFO - 'NO_EM_RESTART': '1', 12:23:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:18 INFO - 'PWD': '/builds/slave/test', 12:23:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:23:18 INFO - 'RUST_BACKTRACE': 'full', 12:23:18 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:23:18 INFO - 'SHELL': '/bin/bash', 12:23:18 INFO - 'SHLVL': '1', 12:23:18 INFO - 'TERM': 'linux', 12:23:18 INFO - 'TMOUT': '86400', 12:23:18 INFO - 'USER': 'cltbld', 12:23:18 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', 12:23:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:18 INFO - '_': '/tools/buildbot/bin/python'} 12:23:18 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/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 12:23:18 INFO - mozversion application_buildid: 20171010175158 12:23:18 INFO - mozversion application_changeset: ed53ec40faf53fe22e65df3fc47135cdbb1ae030 12:23:18 INFO - mozversion application_display_name: Firefox 12:23:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:23:18 INFO - mozversion application_name: Firefox 12:23:18 INFO - mozversion application_remotingname: firefox 12:23:18 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:23:18 INFO - mozversion application_vendor: Mozilla 12:23:18 INFO - mozversion application_version: 57.0 12:23:18 INFO - mozversion platform_buildid: 20171010175158 12:23:18 INFO - mozversion platform_changeset: ed53ec40faf53fe22e65df3fc47135cdbb1ae030 12:23:18 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:23:18 INFO - mozversion platform_version: 57.0 12:23:18 INFO - using testdate: 1507663398 12:23:18 INFO - actual date: 1507663398 12:23:18 INFO - starting webserver on 'localhost:59820' 12:23:18 INFO - SUITE-START | Running 2 tests 12:23:18 INFO - TEST-START | dromaeo_css 12:23:18 INFO - Initialising browser for dromaeo_css test... 12:23:18 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59820/getInfo.html -profile /tmp/tmp9fayl7/profile 12:23:18 INFO - TEST-INFO | started process 3717 (/builds/slave/test/build/application/firefox/firefox http://localhost:59820/getInfo.html) 12:23:26 INFO - TEST-INFO | 3717: exit 0 12:23:26 INFO - Browser initialized. 12:23:26 INFO - Running cycle 1/1 for dromaeo_css test... 12:23:26 INFO - Using env: {'DISPLAY': ':0', 12:23:26 INFO - 'HOME': '/home/cltbld', 12:23:26 INFO - 'JSGC_DISABLE_POISONING': '1', 12:23:26 INFO - 'LANG': 'en_US.UTF-8', 12:23:26 INFO - 'LANGUAGE': 'en_US:en', 12:23:26 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:23:26 INFO - 'LOGNAME': 'cltbld', 12:23:26 INFO - 'MAIL': '/var/mail/cltbld', 12:23:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:23:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:23:26 INFO - 'MOZ_CRASHREPORTER': '1', 12:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:26 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:23:26 INFO - 'MOZ_NO_REMOTE': '1', 12:23:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:23:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:26 INFO - 'NO_EM_RESTART': '1', 12:23:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:26 INFO - 'PWD': '/builds/slave/test', 12:23:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:23:26 INFO - 'RUST_BACKTRACE': 'full', 12:23:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:23:26 INFO - 'SHELL': '/bin/bash', 12:23:26 INFO - 'SHLVL': '1', 12:23:26 INFO - 'TERM': 'linux', 12:23:26 INFO - 'TMOUT': '86400', 12:23:26 INFO - 'USER': 'cltbld', 12:23:26 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', 12:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:26 INFO - '_': '/tools/buildbot/bin/python'} 12:23:26 INFO - TEST-INFO | started process 3951 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9fayl7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:23:27 INFO - PID 3951 | 12:23:27 INFO - PID 3951 | (/builds/slave/test/build/application/firefox/firefox:3998): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:23:27 INFO - PID 3951 | 12:23:37 INFO - PID 3951 | RSS: Main: 141910016 12:23:37 INFO - PID 3951 | 12:26:34 INFO - PID 3951 | Cycle 1(1): loaded http://localhost:59820/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59820/tests/dromaeo/cssquery-ext.html) 12:26:35 INFO - PID 3951 | RSS: Main: 145473536 12:26:35 INFO - PID 3951 | 12:29:37 INFO - PID 3951 | Cycle 1(1): loaded http://localhost:59820/tests/dromaeo/cssquery-ext.html (next: http://localhost:59820/tests/dromaeo/cssquery-jquery.html) 12:29:37 INFO - PID 3951 | RSS: Main: 144400384 12:29:37 INFO - PID 3951 | 12:32:39 INFO - PID 3951 | Cycle 1(1): loaded http://localhost:59820/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59820/tests/dromaeo/cssquery-mootools.html) 12:32:39 INFO - PID 3951 | RSS: Main: 143663104 12:32:39 INFO - PID 3951 | 12:35:41 INFO - PID 3951 | Cycle 1(1): loaded http://localhost:59820/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59820/tests/dromaeo/cssquery-prototype.html) 12:35:42 INFO - PID 3951 | RSS: Main: 143790080 12:35:42 INFO - PID 3951 | 12:38:44 INFO - PID 3951 | Cycle 1(1): loaded http://localhost:59820/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59820/tests/dromaeo/cssquery-yui.html) 12:38:45 INFO - PID 3951 | RSS: Main: 146849792 12:38:45 INFO - PID 3951 | 12:41:46 INFO - PID 3951 | Cycle 1(1): loaded http://localhost:59820/tests/dromaeo/cssquery-yui.html (next: http://localhost:59820/tests/dromaeo/cssquery-dojo.html) 12:41:47 INFO - PID 3951 | RSS: Main: 149237760 12:41:47 INFO - PID 3951 | 12:41:47 INFO - PID 3951 | __start_tp_report 12:41:47 INFO - PID 3951 | _x_x_mozilla_page_load 12:41:47 INFO - PID 3951 | _x_x_mozilla_page_load_details 12:41:47 INFO - PID 3951 | |i|pagename|runs| 12:41:47 INFO - PID 3951 | |0;dojo.html;3978.1248125412417;4041.9595804041987;4052.979735101324;4055.797210139494;4095.344744840826;6522.510811689119;6539.476841852652;6595.076689263501;6615.966920165404;6644.269130395653;6589.637569933651;6631.403173714365;6644;6692;6721.596704197739;5881.823545293627;5891.55813314001;5977.342492325863;5979.970100149493;6011.338752737195;5784.826455206329;5848.327442344143;5862.765489380419;5961.970190149043;5976;5827.562932780037;5918.1418694289305;5938.88122237555;5942.702864856761;5952.136940143677;5878.059510478303;5880.441358070976;5952.494038006775;5956.910646340309;5959.553033522503;5767.33675627304;5796.101604251347;5838.591298609099;5885.440883116097;5920.378360272195;6403.295637479873;6431.324710905381;6439;6482.189726284215;6532.542722009462;7094.964525177393;7126.964365178141;7145;7170.641467926635;7182.42540596751;7137.357637812622;7137.928620713778;7160.319769621878;7222.205557388683;7225.132984041896;6515.924872396049;6526.869462610721;6574.76988305407;6620.8013759587775;6661.866762664719;7243.710251589878;7336.633168341562;7337.816554586071;7338.339549440469;7373.115226172894;6963.09479767627;7029.191642961125;7093.716251349992;7100.68046937889;7145.857082858313;7145.249748776408;7173.6771845267085;7185.964070179615;7234.348908598251;7280;6912.723491060301;7063.505554611128;7086.574805511686;7103.82240443993;7116.893246601305;7050.506464547535;7067.646617669096;7097.432205423554;7128.180259270251;7137.714491420285;5189.714565698923;5263.7894484220205;5331.067063263991;5339.092354299778;5339.626226164209;4771.713697178111;4804.95195048045;4817.108834865561;4841.95158048415;4847.466778654345;6498.317676643979;6537.542372033912;6570.605763654196;6615.338466153443;6637.601743895381;7134.143902731706;7206.135263768381;7214.891776623354;7215.927840721525;7223.9638801806705;6589.209294884645;6601.570897891622;6605.141331626858;6677.933220667731;6682.431993280528;5924.140999555127;5941.257342832146;6010.759569617254;6017.669028203403;6021.819345419644;7396.55620662762;7403;7479.663415146331;7501.624918754041;7508.324250817453;6999.880019196904;7097.9290207097265;7106.467014973898;7187.460940429593;7200.963995179991;7281.635918204174;7342.8898566520475;7351.117865856131;7424.591647459441;7440.181580026301;6979.616121113388;7111.644417779194;7123.323284287984;7124.750633727795;7140.357367836919;7033.824154396181;7136.250693677089;7150.606716630427;7198.244184360567;7218.711251549879;6789.660516974231;6887.311268873073;6888.41448476885;6928.515003949877;6996.125484314461;6809;6817.113775209191;6827.795166145023;6829.385355317847;6833.726650933907;6814.318568143344;6875.690593923386;6901.482388820758;6906.758263460755;6910.965445172742;6918.308169183041;6962.1993470749485;6987.965060174666;7059.364657180879;7059.71761129549;6756.256811750605;6763.52655314143;6840.0765896603325;6852.211995620568;6861.039454476278 12:41:47 INFO - PID 3951 | |1;ext.html;938.2822141062085;999.5452069308463;1021.6475316015974;1021.754778853075;1039.5893622019298;44265.33601995969;46113.538864611386;46160;46277.76861115698;46305.07389852205;44555.55444445555;44884.326735099;45067;45076.77461612695;45091.54908450923;8197.83604327913;8297.626606802709;8319.792005199857;8322.126176751444;8323.084460709317;8083.878741818878;8125.918740812575;8156.959215203916;8162.755117346489;8172.959135204315;8315.792105197357;8519.74440766778;8533.914660853374;8547.10255423181;8550.657973681045;8455.281301089415;8456.57717114148;8493.36299777513;8495.235428811437;8524.445911015795;8793.56032198394;8795.560221988875;8834.249088827457;8848.51333176675;8877.644894204224;41957;42002.78998605018;42256;42323.57676423258;42340.57659423397;3799.3351163546545;3826.4069069294305;3845.8846234613006;3846.3268927937497;3850.114473671071;34788.478172827425;34875;34919;34934;35061.64938350635;5580.497755202011;5626.493615574577;5628.915566266509;5639;5675.063614503602;213830;214485;214735;215873;225288;49050.28324008643;54347.184792228145;55723.16415253774;55987.72006140025;56040;56556.71721641366;57011;57151.714241428526;57284.71357643185;57353.713231433576;17334.566635834206;17442.563935901704;17502.91248543749;17529.912350438422;17548.29806807739;14476.493322733864;14526.418943242363;14687.92656036713;14850.628734281729;14876.330565124596;15274.618134546503;15363.692726145415;15433.151176685391;15501.837362197655;15661.921690391475;6064.787732429432;6157.599756015845;6166.352532984062;6189.257289125244;6241.157443745036;1119.675294164683;1122.8877112288706;1130.2879186112696;1131.9773604527863;1133.2690414682484;25821;26314.6052809208;26417.339566510607;26549.3362665931;26634.06780762664;1232.7534493101416;1249.8500179978462;1250.993745031269;1258.0501721200435;1262.1858901008798;13839.169649821042;13890.166590004632;13931.65170870716;14076.71846563063;14188.85811141896;285073;317854;335044;337513;338520;65045.349546504876;65369;65444;65681.67159164173;65848.34151658422;1535.1019653502826;1560.602046478141;1564.4889951627474;1576.5664442278464;1580.178307280231;30615.846920765252;30951.76192952347;31083.378332432774;31179.07549138532;31293.217669558442;42648;42783.57216427796;42856.78571607122;42935.355969661105;42947.57052429436;266374;267456;267496;267613;269127;37623;37979.81010094932;38392.424113638874;38417.80791096027;38506.80746596249;47738;47961.5203847957;48236.27645585249;48254.276185856535;48406.27390589073;35215.295694085464;35544.28911421809;35848.82075589606;35957.28085438224;36506.634933650326;35096.29807403786;35177.64822351744;35366.82316588401;35712;35730.28539429249;41709;41825.581744183386;41828;42069;42288.15423691447;34004.65995340015;34324.656753432144;34436.82781586076;34526.309473810885;34535.13662158365;25295.494090117725;25430.49139017246;25719.228423147335;25841;25860.353491162117 12:41:47 INFO - PID 3951 | |2;jquery.html;620.4075108271601;629.4680994559595;631.4254028833763;648.4293821437135;648.4488185042715;24595.13917012905;24753.13364032259;25415.364615884613;25589.616155757656;25648.10231641893;23244;24013.279601611936;24092.87953560234;24101.75898241017;24117.397065073383;6644.900326495106;6792.694328755206;6922.307769223063;6926.099607051076;6959.4432445404245;6690.76582319619;6768.627725475097;6773.254941956381;6776.525643204977;6796.626185559792;6747.898781518256;6748.696308666122;6772.559783614074;6775.2208496023095;6781.593104413726;6741.595504269735;6750;6772.72909083636;6773.322667733237;6775.695093720795;6321.146645202884;6327.145835312266;6355;6387.073874288249;6394.296627370985;28323.291917702016;31275.37449251025;32073.51889721656;32319.83840080808;32396.514052289236;2393.222202784102;2411.5297516984197;2425.7331693513697;2431.8419302745265;2433.209207007712;20652.380428587167;20878.791212087835;21246;21255.256066037615;21256.893715531478;2630.631711560383;2650.4169082801945;2662.267876334004;2673.4118493931305;2676.879540420666;151890.24054879765;157333.21333393373;164833.17583412005;167173;171864.14067929707;15614.453494127878;16628.667426651642;17129.571760706083;17282.740758888627;17640.55898602545;27914.581281280796;28026.159215223168;28027.719722802918;28051;28153;10737.194710396752;10783.78432431347;10929;11012.614558490575;11040.668779936615;9895.752606184902;9921.355111917703;9955.701328960144;10012.098911098035;10162.288639795144;9870.753231169278;10144.898551014543;10298.227632927561;10332.948335258276;10464.895351046544;2932.621667816123;2954.1432984434273;2995.236214765221;2997.2656699108857;3062.586550815611;1062.311322866041;1069.7325668582855;1079.6653037558226;1080.1574771678104;1082.6373164989816;21324.466888327606;21596.244131455325;21883.234086807202;22004.229851955104;22432.66351004736;1147.1568397228032;1154.7748189103213;1166.9066474682006;1170.6839153428698;1170.988290117105;11672.649820505398;12009.159358844798;12196.268223906594;12205.511779528719;12236.75526489483;664323;679846;693384;699290;705991;131835.34082329526;133528.3323583376;135620.32189838987;138103.30948345395;139148;1296.1056870759146;1309.9476020959246;1326.840779106494;1334.5595953335376;1339.216558313378;19205;19381.321653742616;19440.61118777645;19643.410697679094;20097;62709;64689.67655161694;64885.67557162184;65440;65630;82091.5895420543;82851;83318.58340708258;83712.58143709242;84215;15846.841531584998;16323.673526529165;16353.18234088267;16372.426965056642;16376.426825061542;24004.51990960206;24263.393415164763;24380.26859194227;24710.629340560266;24951.87524062368;19909.900450497655;20044.198232070554;20047.498812529804;20127.094280757694;20389.79610203879;16156;16654.41709540209;17970.460886173434;18099.72850407271;18177.545561361072;19645.410637680892;20361.49096272605;20403.285884993955;20499.59000820005;20536.897315513328;15837.841621583637;15919.363225470852;16007.599810004378;16091.919540402223;16099.75850362269;19633.60732785365;19717.802821971596;19817.20731170737;19925.402237932885;20051.799482004993 12:41:47 INFO - PID 3951 | |3;mootools.html;1430.6137342917414;1485.1460410264094;1497.2813049736133;1499.6625759204178;1505.3666771552857;16777.24502397392;17405.21676524556;17423.128843557835;17432.302707891668;17454.738178927328;16954.23705933233;16977.915110424463;17014.149292535385;17058.23237954292;17070.402535911246;3009.8043627164166;3050.1916991997136;3107.4406606810767;3108.4560201964678;3113.5018397056474;2949.9852500737466;2999.9100026999226;3009.1574359179463;3013.668496465387;3015;2988.775841811862;3071.554624579435;3174.936501269973;3175.8253296068706;3176.888808891689;3146.7797254192214;3169.5879535660333;3190.56927314813;3190.840457977115;3191.5850939377733;2960.185948864058;2970.2722832906074;3008.42839860428;3026.092172348309;3026.5157574787927;16386.426475073316;16618;16657.50027499177;16760.329586816635;16845.32618695251;8412.579371031485;8770.824583508294;8788.472691638523;8797.912020879774;8799.516026618534;5868.853278668024;6112.205413296243;6242.750289988395;6246.843828904268;6258.87482250357;2091.801278878504;2160.0711693971584;2160.751513575943;2215.789499997498;2218.0351547077075;31780;32059.35881282361;32115.678843211736;32124.678753212404;32280.031599052065;7464.253574642492;7499.137599176055;7548.131964824006;7673.501222420525;7823.491473054233;20714.067866946025;20744.377668669964;20749.377518674162;20810;20813.1674733009;8399.37004724648;8553.44402613828;8564.229219370161;8574.056853746082;8578.528180949983;7679.462437629312;7746.264104910025;7877.724279650185;7881.881771773428;7893.13175550689;5993.700314984233;6041;6204.81385558434;6271.404216599416;6351.2060992375955;1577.1798664694295;1612.129450096958;1704.5994191365019;1764.3912850066706;1769.133124768854;1016.8169729448771;1019.261035749076;1020.8366661334151;1037.766502536923;1047.7956798424386;9445.811083778424;9593.904060959441;9707.757306067264;9812.067853553901;9932;1082.9133669306436;1090.1987039525943;1126.7577470843808;1128.4696192789377;1133.1048471707425;5809.141044947318;5905.557083218776;5928.703564821742;6402.647854368054;6490.221173459121;25255.242342729747;27267.590986135223;27492.725072749017;27650.723492765217;27707.722922770514;25500;26367;26511.46977060448;26756.732432675813;26766.330841728723;13173.802392964113;13186.604401867768;13275.734485310433;13312.334383280797;13348.132371395828;9680.467574283482;9765.951170244103;9816.705498835046;9826.606935722777;9960.203183745138;22249.44376390603;22339.77660223377;23105.537889241783;23176.188833390752;23450.53098938046;23007.309780706604;23583.41041473977;23616.409589760395;23795.643065353688;23871.283861484182;12737.617871463872;12953.805692914426;13152.408141633841;13157.210567365988;13176.60470185896;15928.761068584212;16188.543031127256;16203.108829014518;16221.59446013859;16222.675546488768;13124.475020999053;13186.538471153464;13232.933835330761;13344.199348039148;13400.731985360433;12385.814212786996;12608.36958152107;12716.554920577735;12836;12903.548375806802;11549.711257218636;11685.883141168819;11694.824577631513;11696.590619328283;11712.238704484182;8263.380246481418;8302.584870756558;8329.95835020821;8429.283510901396;8466.407351485337;8010.799730006564;8012.599370031592;8063.83872322562;8203.630836612247;8273.751787446387 12:41:47 INFO - PID 3951 | |4;prototype.html;570.0878594249202;573.6080544130973;585.7744768264218;587.7061469265367;599.8860216558853;6260.436560709535;6335.402484981153;6357.491400687951;6369.554131210817;6430.392401899525;6273.619803643202;6292.528060395477;6294.685265736718;6299;6302.495800335973;5036.438704001771;5122.436531981479;5231.768231768232;5240.8689782755355;5287.920681189776;5109.489051094879;5122.513361230677;5134.127198376285;5151.4333423323405;5182.4299327073995;5030.773615187325;5043.092243396187;5064.1390963536105;5101.336826212606;5105.668131571436;5105.294194077145;5114.948850511484;5126.154184559543;5155.252488389163;5158.638895277332;4650.697704649187;4675.251959686434;4694.08465349257;4697.929531057037;4724.645651576146;6113.205283313186;6138.355472675349;6200.162977997958;6291.842703932392;6316.652584107872;5916.911246331266;5953.523718102585;5984.311804142536;6030.246219222598;6042.093685947099;6760;6792.592444453348;6810.523263371566;6812.420944219747;6842.6578671066745;4965.329680493123;5136.922946155811;5174.120399532088;5209.218617207412;5210.296609957683;6896.137982752156;7005.719771209146;7024.5082844200415;7080.575165490087;7109.6445177741425;5290.603204759582;5355.839324820262;5362.812301569505;5373.220882972026;5381.139017757218;5408.8377348679605;5414.07960646691;5467.835964921059;5469.917951230735;5484.643498172606;5952.434518720715;5997.160397544348;6007.3992600739575;6028.90956635642;6077.878442431127;6806.727730890808;6829.726810927507;6843.657817109125;6855.760048398282;6881.690323935435;1818.127298896537;1867.738516607676;1878.3238034307637;1892.7413270175455;1900.9334673286646;1275.8213850061;1319.9868001320056;1328.2296268164441;1354.214555557774;1370.1299674706434;6180.47465965389;6254.468370188585;6279.340669229757;6311;6388.456981156561;6774.525783195228;6784.830379240559;6798.422134118655;6874.278200788845;6887.173539175331;6293.280039970023;6299.685015749286;6321.8735625288155;6340.841478963062;6365.777197798055;3641.9453708194396;3651.8721844735305;3669.4679271505483;3672.797996110186;3675.61406052366;7243.804772212631;7252.311030452099;7256.419486441072;7268.200497945222;7285.907113932953;7026.508144429841;7084.504084714021;7116.5018448709125;7117.679704413314;7164.391026762679;7134.7859564214195;7151.2121969507625;7217.278272172845;7225.602591857393;7259.963700181465;6825.805484040372;6847.726090956306;6858.759943402157;6942.51402401827;6973.58158510491;6687.264400915993;6971.930280697128;6974.546654467444;6975.162980442379;6979.441644668516;7031.191412987368;7051.471139664443;7096.396806271318;7101.8579628406105;7186.892196617159;5312.62811603184;5317.627766056339;5343.812966546152;5344.545713614386;5390.460953904578;6605.768798092236;6837.384635382839;6866.690998905161;6917.155004021593;6994.405475534637;6239.656818875005;6522.119513865758;6532.41208291256;6714.52998290115;6745.831354216184;6507.446867016357;6586.868262634816;6616.206055273398;6634.2848005117285;6659.733610655519;6540.640264785482;6595.2852258412195;6596.736130554916;6610.272869984202;6623.536352455475;6720.85745423289;6729.798106056826;6742.4943129266485;6772.187337519529;6773.254941956282;6550.148480697479;6575.145231119924;6579.276279609144;6592.934070659232;6594.538382313192 12:41:47 INFO - PID 3951 | |5;yui.html;975.1857199238635;994.8857279846571;1015.9949200253998;1019.5361110694635;1033.514248303297;22158.556828863435;23915;23951.76048239517;23985.5202895942;24054.398640033985;20749.170033198654;22386.21648242312;22604.54790904181;22695.432614184614;22703.432414189654;1039.2777019971122;1085.018058656913;1086.0822604898888;1086.315621158673;1087.211771465689;1072.4155335342257;1073.8281874900017;1081.9188560857967;1082.0369870814982;1082.2640604389012;1121.9158563107758;1124.246754674366;1127.7349822791637;1129.1926272715007;1136.4033882211863;1122.186414849236;1134.1493879590307;1134.4271143072779;1134.6199023327194;1134.7787181499612;814.450246083891;816.481534225765;817.6729308276737;824.0688022534498;828.2793969246734;19196.520086997796;19291.710624340645;19323.227070917146;19417.80582194174;19424.90287548553;22775.544489110292;23399.53200935972;23573.17493887723;23776.167834125896;23869.28392148221;19037.14332855011;19159.23363065476;19186.424407267805;19256.903715481472;19421.126049327813;3996.9200615987806;4041;4053.5135783706146;4065.3698676705158;4073.083556199861;166985;167335;168187;168730;170408;17657.911710441367;17793.466196014142;18038.36865709694;18076.819231807778;18150.45548633543;33633;34658.133546661535;34690.30619387598;34732.13169670779;35306.64693353033;9635.373700709431;9664.855027174597;9736.610535578639;9797.510124493747;9832.115109640166;4706.011737535087;4723.504032076583;4759.619230461555;4819.445763737145;4845.733484658378;6170.845728856725;6269.96865015672;6274.121622972787;6282.654454005074;6284.717187726564;1145.3643228008534;1169.6666450061691;1192.5110704611068;1192.797224471842;1197.3953153657521;745.3962290544677;752.6650640464941;757.1935888278965;766.5170942306312;768.7271018788362;12740.617781466572;12929;13654.726905462034;13698;13881.375338109809;728.4427413029038;731.5318196354337;735.312482828557;738.0810890441435;747.7046566606269;6213.1922850029205;6262.968685156545;6340.048992651139;6342.619442833445;6422.100905873182;150802;152101;152364;154030.22984885005;157663;136501;136588;137728;143211;143760.28119859335;120916;121288;121859.78140218662;122931;124059;11620.302781833117;11644.592439264585;11702.414879256174;11773.411329433666;11866.0421030529;57087.42912570821;57151.714241428526;57454;58334.4166558329;58604.70697646485;71774.28225717676;73384;73993.63003184949;74045;74839;17070.48788536346;17228;17231.483055508354;17331.826681733022;17459.738103928197;18618.906905465385;19390.612187755883;19442.805571944664;19499.317523886595;19597.51006224856;17533.649327013645;17639.294428223242;17663.116844157485;17709.822901770818;17827.910860445616;17499;17768.200430980378;17792.02143882099;17815.46553603394;17824.732629010836;16057.67884642324;16166.67666646684;16326.265318060954;16398.426055088014;16403.17984100814;13205.405756740778;13319.400626971616;13560.59318220455;13563.321833908463;13739.24434156131;12849;12903.41934612926;12959.224465320733;12992.935035325141;13097.279649618982 12:41:47 INFO - PID 3951 | __end_tp_report 12:41:47 INFO - PID 3951 | __start_cc_report 12:41:47 INFO - PID 3951 | _x_x_mozilla_cycle_collect,248 12:41:47 INFO - PID 3951 | __end_cc_report 12:41:47 INFO - PID 3951 | __startTimestamp1507664507113__endTimestamp 12:41:47 INFO - PID 3951 | 12:41:47 INFO - PID 3951 | ------- Summary: start ------- 12:41:47 INFO - PID 3951 | Number of tests: 6 12:41:47 INFO - PID 3951 | 12:41:47 INFO - PID 3951 | [#0] dojo.html Cycles:165 Average:6575.99 Median:6829.39 stddev:769.75 (11.3%) stddev-sans-first:744.64 12:41:47 INFO - PID 3951 | Values: 3978.1 4042.0 4053.0 4055.8 4095.3 6522.5 6539.5 6595.1 6616.0 6644.3 6589.6 6631.4 6644.0 6692.0 6721.6 5881.8 5891.6 5977.3 5980.0 6011.3 5784.8 5848.3 5862.8 5962.0 5976.0 5827.6 5918.1 5938.9 5942.7 5952.1 5878.1 5880.4 5952.5 5956.9 5959.6 5767.3 5796.1 5838.6 5885.4 5920.4 6403.3 6431.3 6439.0 6482.2 6532.5 7095.0 7127.0 7145.0 7170.6 7182.4 7137.4 7137.9 7160.3 7222.2 7225.1 6515.9 6526.9 6574.8 6620.8 6661.9 7243.7 7336.6 7337.8 7338.3 7373.1 6963.1 7029.2 7093.7 7100.7 7145.9 7145.2 7173.7 7186.0 7234.3 7280.0 6912.7 7063.5 7086.6 7103.8 7116.9 7050.5 7067.6 7097.4 7128.2 7137.7 5189.7 5263.8 5331.1 5339.1 5339.6 4771.7 4805.0 4817.1 4842.0 4847.5 6498.3 6537.5 6570.6 6615.3 6637.6 7134.1 7206.1 7214.9 7215.9 7224.0 6589.2 6601.6 6605.1 6677.9 6682.4 5924.1 5941.3 6010.8 6017.7 6021.8 7396.6 7403.0 7479.7 7501.6 7508.3 6999.9 7097.9 7106.5 7187.5 7201.0 7281.6 7342.9 7351.1 7424.6 7440.2 6979.6 7111.6 7123.3 7124.8 7140.4 7033.8 7136.3 7150.6 7198.2 7218.7 6789.7 6887.3 6888.4 6928.5 6996.1 6809.0 6817.1 6827.8 6829.4 6833.7 6814.3 6875.7 6901.5 6906.8 6911.0 6918.3 6962.2 6988.0 7059.4 7059.7 6756.3 6763.5 6840.1 6852.2 6861.0 12:41:47 INFO - PID 3951 | 12:41:47 INFO - PID 3951 | [#1] ext.html Cycles:180 Average:45443.53 Median:3812.87 stddev:71170.95 (1866.6%) stddev-sans-first:71292.15 12:41:47 INFO - PID 3951 | Values: 938.3 999.5 1021.6 1021.8 1039.6 44265.3 46113.5 46160.0 46277.8 46305.1 44555.6 44884.3 45067.0 45076.8 45091.5 8197.8 8297.6 8319.8 8322.1 8323.1 8083.9 8125.9 8157.0 8162.8 8173.0 8315.8 8519.7 8533.9 8547.1 8550.7 8455.3 8456.6 8493.4 8495.2 8524.4 8793.6 8795.6 8834.2 8848.5 8877.6 41957.0 42002.8 42256.0 42323.6 42340.6 3799.3 3826.4 3845.9 3846.3 3850.1 34788.5 34875.0 34919.0 34934.0 35061.6 5580.5 5626.5 5628.9 5639.0 5675.1 213830.0 214485.0 214735.0 215873.0 225288.0 49050.3 54347.2 55723.2 55987.7 56040.0 56556.7 57011.0 57151.7 57284.7 57353.7 17334.6 17442.6 17502.9 17529.9 17548.3 14476.5 14526.4 14687.9 14850.6 14876.3 15274.6 15363.7 15433.2 15501.8 15661.9 6064.8 6157.6 6166.4 6189.3 6241.2 1119.7 1122.9 1130.3 1132.0 1133.3 25821.0 26314.6 26417.3 26549.3 26634.1 1232.8 1249.9 1251.0 1258.1 1262.2 13839.2 13890.2 13931.7 14076.7 14188.9 285073.0 317854.0 335044.0 337513.0 338520.0 65045.3 65369.0 65444.0 65681.7 65848.3 1535.1 1560.6 1564.5 1576.6 1580.2 30615.8 30951.8 31083.4 31179.1 31293.2 42648.0 42783.6 42856.8 42935.4 42947.6 266374.0 267456.0 267496.0 267613.0 269127.0 37623.0 37979.8 38392.4 38417.8 38506.8 47738.0 47961.5 48236.3 48254.3 48406.3 35215.3 35544.3 35848.8 35957.3 36506.6 35096.3 35177.6 35366.8 35712.0 35730.3 41709.0 41825.6 41828.0 42069.0 42288.2 34004.7 34324.7 34436.8 34526.3 34535.1 25295.5 25430.5 25719.2 25841.0 25860.4 12:41:47 INFO - PID 3951 | 12:41:47 INFO - PID 3951 | [#2] jquery.html Cycles:180 Average:42937.77 Median:22838.33 stddev:114811.35 (502.7%) stddev-sans-first:115089.46 12:41:47 INFO - PID 3951 | Values: 620.4 629.5 631.4 648.4 648.4 24595.1 24753.1 25415.4 25589.6 25648.1 23244.0 24013.3 24092.9 24101.8 24117.4 6644.9 6792.7 6922.3 6926.1 6959.4 6690.8 6768.6 6773.3 6776.5 6796.6 6747.9 6748.7 6772.6 6775.2 6781.6 6741.6 6750.0 6772.7 6773.3 6775.7 6321.1 6327.1 6355.0 6387.1 6394.3 28323.3 31275.4 32073.5 32319.8 32396.5 2393.2 2411.5 2425.7 2431.8 2433.2 20652.4 20878.8 21246.0 21255.3 21256.9 2630.6 2650.4 2662.3 2673.4 2676.9 151890.2 157333.2 164833.2 167173.0 171864.1 15614.5 16628.7 17129.6 17282.7 17640.6 27914.6 28026.2 28027.7 28051.0 28153.0 10737.2 10783.8 10929.0 11012.6 11040.7 9895.8 9921.4 9955.7 10012.1 10162.3 9870.8 10144.9 10298.2 10332.9 10464.9 2932.6 2954.1 2995.2 2997.3 3062.6 1062.3 1069.7 1079.7 1080.2 1082.6 21324.5 21596.2 21883.2 22004.2 22432.7 1147.2 1154.8 1166.9 1170.7 1171.0 11672.6 12009.2 12196.3 12205.5 12236.8 664323.0 679846.0 693384.0 699290.0 705991.0 131835.3 133528.3 135620.3 138103.3 139148.0 1296.1 1309.9 1326.8 1334.6 1339.2 19205.0 19381.3 19440.6 19643.4 20097.0 62709.0 64689.7 64885.7 65440.0 65630.0 82091.6 82851.0 83318.6 83712.6 84215.0 15846.8 16323.7 16353.2 16372.4 16376.4 24004.5 24263.4 24380.3 24710.6 24951.9 19909.9 20044.2 20047.5 20127.1 20389.8 16156.0 16654.4 17970.5 18099.7 18177.5 19645.4 20361.5 20403.3 20499.6 20536.9 15837.8 15919.4 16007.6 16091.9 16099.8 19633.6 19717.8 19817.2 19925.4 20051.8 12:41:47 INFO - PID 3951 | 12:41:47 INFO - PID 3951 | [#3] mootools.html Cycles:180 Average:10935.60 Median:27016.96 stddev:8146.42 (30.2%) stddev-sans-first:8137.97 12:41:47 INFO - PID 3951 | Values: 1430.6 1485.1 1497.3 1499.7 1505.4 16777.2 17405.2 17423.1 17432.3 17454.7 16954.2 16977.9 17014.1 17058.2 17070.4 3009.8 3050.2 3107.4 3108.5 3113.5 2950.0 2999.9 3009.2 3013.7 3015.0 2988.8 3071.6 3174.9 3175.8 3176.9 3146.8 3169.6 3190.6 3190.8 3191.6 2960.2 2970.3 3008.4 3026.1 3026.5 16386.4 16618.0 16657.5 16760.3 16845.3 8412.6 8770.8 8788.5 8797.9 8799.5 5868.9 6112.2 6242.8 6246.8 6258.9 2091.8 2160.1 2160.8 2215.8 2218.0 31780.0 32059.4 32115.7 32124.7 32280.0 7464.3 7499.1 7548.1 7673.5 7823.5 20714.1 20744.4 20749.4 20810.0 20813.2 8399.4 8553.4 8564.2 8574.1 8578.5 7679.5 7746.3 7877.7 7881.9 7893.1 5993.7 6041.0 6204.8 6271.4 6351.2 1577.2 1612.1 1704.6 1764.4 1769.1 1016.8 1019.3 1020.8 1037.8 1047.8 9445.8 9593.9 9707.8 9812.1 9932.0 1082.9 1090.2 1126.8 1128.5 1133.1 5809.1 5905.6 5928.7 6402.6 6490.2 25255.2 27267.6 27492.7 27650.7 27707.7 25500.0 26367.0 26511.5 26756.7 26766.3 13173.8 13186.6 13275.7 13312.3 13348.1 9680.5 9766.0 9816.7 9826.6 9960.2 22249.4 22339.8 23105.5 23176.2 23450.5 23007.3 23583.4 23616.4 23795.6 23871.3 12737.6 12953.8 13152.4 13157.2 13176.6 15928.8 16188.5 16203.1 16221.6 16222.7 13124.5 13186.5 13232.9 13344.2 13400.7 12385.8 12608.4 12716.6 12836.0 12903.5 11549.7 11685.9 11694.8 11696.6 11712.2 8263.4 8302.6 8330.0 8429.3 8466.4 8010.8 8012.6 8063.8 8203.6 8273.8 12:41:47 INFO - PID 3951 | 12:41:47 INFO - PID 3951 | [#4] prototype.html Cycles:180 Average:5757.65 Median:6306.75 stddev:1598.99 (25.4%) stddev-sans-first:1555.35 12:41:47 INFO - PID 3951 | Values: 570.1 573.6 585.8 587.7 599.9 6260.4 6335.4 6357.5 6369.6 6430.4 6273.6 6292.5 6294.7 6299.0 6302.5 5036.4 5122.4 5231.8 5240.9 5287.9 5109.5 5122.5 5134.1 5151.4 5182.4 5030.8 5043.1 5064.1 5101.3 5105.7 5105.3 5114.9 5126.2 5155.3 5158.6 4650.7 4675.3 4694.1 4697.9 4724.6 6113.2 6138.4 6200.2 6291.8 6316.7 5916.9 5953.5 5984.3 6030.2 6042.1 6760.0 6792.6 6810.5 6812.4 6842.7 4965.3 5136.9 5174.1 5209.2 5210.3 6896.1 7005.7 7024.5 7080.6 7109.6 5290.6 5355.8 5362.8 5373.2 5381.1 5408.8 5414.1 5467.8 5469.9 5484.6 5952.4 5997.2 6007.4 6028.9 6077.9 6806.7 6829.7 6843.7 6855.8 6881.7 1818.1 1867.7 1878.3 1892.7 1900.9 1275.8 1320.0 1328.2 1354.2 1370.1 6180.5 6254.5 6279.3 6311.0 6388.5 6774.5 6784.8 6798.4 6874.3 6887.2 6293.3 6299.7 6321.9 6340.8 6365.8 3641.9 3651.9 3669.5 3672.8 3675.6 7243.8 7252.3 7256.4 7268.2 7285.9 7026.5 7084.5 7116.5 7117.7 7164.4 7134.8 7151.2 7217.3 7225.6 7260.0 6825.8 6847.7 6858.8 6942.5 6973.6 6687.3 6971.9 6974.5 6975.2 6979.4 7031.2 7051.5 7096.4 7101.9 7186.9 5312.6 5317.6 5343.8 5344.5 5390.5 6605.8 6837.4 6866.7 6917.2 6994.4 6239.7 6522.1 6532.4 6714.5 6745.8 6507.4 6586.9 6616.2 6634.3 6659.7 6540.6 6595.3 6596.7 6610.3 6623.5 6720.9 6729.8 6742.5 6772.2 6773.3 6550.1 6575.1 6579.3 6592.9 6594.5 12:41:47 INFO - PID 3951 | 12:41:47 INFO - PID 3951 | [#5] yui.html Cycles:180 Average:29307.56 Median:18057.59 stddev:44358.44 (245.6%) stddev-sans-first:44431.86 12:41:47 INFO - PID 3951 | Values: 975.2 994.9 1016.0 1019.5 1033.5 22158.6 23915.0 23951.8 23985.5 24054.4 20749.2 22386.2 22604.5 22695.4 22703.4 1039.3 1085.0 1086.1 1086.3 1087.2 1072.4 1073.8 1081.9 1082.0 1082.3 1121.9 1124.2 1127.7 1129.2 1136.4 1122.2 1134.1 1134.4 1134.6 1134.8 814.5 816.5 817.7 824.1 828.3 19196.5 19291.7 19323.2 19417.8 19424.9 22775.5 23399.5 23573.2 23776.2 23869.3 19037.1 19159.2 19186.4 19256.9 19421.1 3996.9 4041.0 4053.5 4065.4 4073.1 166985.0 167335.0 168187.0 168730.0 170408.0 17657.9 17793.5 18038.4 18076.8 18150.5 33633.0 34658.1 34690.3 34732.1 35306.6 9635.4 9664.9 9736.6 9797.5 9832.1 4706.0 4723.5 4759.6 4819.4 4845.7 6170.8 6270.0 6274.1 6282.7 6284.7 1145.4 1169.7 1192.5 1192.8 1197.4 745.4 752.7 757.2 766.5 768.7 12740.6 12929.0 13654.7 13698.0 13881.4 728.4 731.5 735.3 738.1 747.7 6213.2 6263.0 6340.0 6342.6 6422.1 150802.0 152101.0 152364.0 154030.2 157663.0 136501.0 136588.0 137728.0 143211.0 143760.3 120916.0 121288.0 121859.8 122931.0 124059.0 11620.3 11644.6 11702.4 11773.4 11866.0 57087.4 57151.7 57454.0 58334.4 58604.7 71774.3 73384.0 73993.6 74045.0 74839.0 17070.5 17228.0 17231.5 17331.8 17459.7 18618.9 19390.6 19442.8 19499.3 19597.5 17533.6 17639.3 17663.1 17709.8 17827.9 17499.0 17768.2 17792.0 17815.5 17824.7 16057.7 16166.7 16326.3 16398.4 16403.2 13205.4 13319.4 13560.6 13563.3 13739.2 12849.0 12903.4 12959.2 12992.9 13097.3 12:41:47 INFO - PID 3951 | -------- Summary: end -------- 12:41:47 INFO - PID 3951 | 12:41:47 INFO - TEST-INFO | 3951: exit 0 12:41:47 INFO - TEST-OK | dromaeo_css | took 1109437ms 12:41:47 INFO - TEST-START | kraken 12:41:47 INFO - Initialising browser for kraken test... 12:41:47 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59820/getInfo.html -profile /tmp/tmppmSpgG/profile 12:41:47 INFO - TEST-INFO | started process 4333 (/builds/slave/test/build/application/firefox/firefox http://localhost:59820/getInfo.html) 12:41:52 INFO - TEST-INFO | 4333: exit 0 12:41:52 INFO - Browser initialized. 12:41:52 INFO - Running cycle 1/1 for kraken test... 12:41:52 INFO - Using env: {'DISPLAY': ':0', 12:41:52 INFO - 'HOME': '/home/cltbld', 12:41:52 INFO - 'JSGC_DISABLE_POISONING': '1', 12:41:52 INFO - 'LANG': 'en_US.UTF-8', 12:41:52 INFO - 'LANGUAGE': 'en_US:en', 12:41:52 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:41:52 INFO - 'LOGNAME': 'cltbld', 12:41:52 INFO - 'MAIL': '/var/mail/cltbld', 12:41:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:41:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:41:52 INFO - 'MOZ_CRASHREPORTER': '1', 12:41:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:52 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:41:52 INFO - 'MOZ_NO_REMOTE': '1', 12:41:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:41:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:41:52 INFO - 'NO_EM_RESTART': '1', 12:41:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:41:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:52 INFO - 'PWD': '/builds/slave/test', 12:41:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:41:52 INFO - 'RUST_BACKTRACE': 'full', 12:41:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:41:52 INFO - 'SHELL': '/bin/bash', 12:41:52 INFO - 'SHLVL': '1', 12:41:52 INFO - 'TERM': 'linux', 12:41:52 INFO - 'TMOUT': '86400', 12:41:52 INFO - 'USER': 'cltbld', 12:41:52 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965', 12:41:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:52 INFO - '_': '/tools/buildbot/bin/python'} 12:41:52 INFO - TEST-INFO | started process 4564 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppmSpgG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:41:53 INFO - PID 4564 | 12:41:53 INFO - PID 4564 | (/builds/slave/test/build/application/firefox/firefox:4614): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:41:53 INFO - PID 4564 | 12:42:03 INFO - PID 4564 | RSS: Main: 143564800 12:42:03 INFO - PID 4564 | 12:42:06 INFO - PID 4564 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59820/tests/kraken/driver.html?testName=audio-beat-detection) 12:42:07 INFO - PID 4564 | RSS: Main: 149966848 12:42:07 INFO - PID 4564 | 12:42:09 INFO - PID 4564 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59820/tests/kraken/driver.html?testName=audio-dft) 12:42:10 INFO - PID 4564 | RSS: Main: 150310912 12:42:10 INFO - PID 4564 | 12:42:13 INFO - PID 4564 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59820/tests/kraken/driver.html?testName=audio-fft) 12:42:13 INFO - PID 4564 | RSS: Main: 151658496 12:42:13 INFO - PID 4564 | 12:42:15 INFO - PID 4564 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59820/tests/kraken/driver.html?testName=audio-oscillator) 12:42:16 INFO - PID 4564 | RSS: Main: 151572480 12:42:16 INFO - PID 4564 | 12:42:18 INFO - PID 4564 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59820/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:42:18 INFO - PID 4564 | RSS: Main: 150777856 12:42:18 INFO - PID 4564 | 12:42:22 INFO - PID 4564 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59820/tests/kraken/driver.html?testName=imaging-darkroom) 12:42:22 INFO - PID 4564 | RSS: Main: 151326720 12:42:22 INFO - PID 4564 | 12:42:26 INFO - PID 4564 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59820/tests/kraken/driver.html?testName=imaging-desaturate) 12:42:26 INFO - PID 4564 | RSS: Main: 152129536 12:42:26 INFO - PID 4564 | 12:42:29 INFO - PID 4564 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59820/tests/kraken/driver.html?testName=json-parse-financial) 12:42:30 INFO - PID 4564 | RSS: Main: 154046464 12:42:30 INFO - PID 4564 | 12:42:31 INFO - PID 4564 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59820/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:42:32 INFO - PID 4564 | RSS: Main: 153280512 12:42:32 INFO - PID 4564 | 12:42:33 INFO - PID 4564 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59820/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:42:34 INFO - PID 4564 | RSS: Main: 153538560 12:42:34 INFO - PID 4564 | 12:42:36 INFO - PID 4564 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59820/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:42:37 INFO - PID 4564 | RSS: Main: 150544384 12:42:37 INFO - PID 4564 | 12:42:39 INFO - PID 4564 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59820/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:42:40 INFO - PID 4564 | RSS: Main: 150134784 12:42:40 INFO - PID 4564 | 12:42:42 INFO - PID 4564 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59820/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:42:43 INFO - PID 4564 | RSS: Main: 151425024 12:42:43 INFO - PID 4564 | 12:42:44 INFO - PID 4564 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59820/tests/kraken/driver.html?testName=ai-astar) 12:42:45 INFO - PID 4564 | RSS: Main: 152866816 12:42:45 INFO - PID 4564 | 12:42:45 INFO - PID 4564 | __start_tp_report 12:42:45 INFO - PID 4564 | _x_x_mozilla_page_load 12:42:45 INFO - PID 4564 | _x_x_mozilla_page_load_details 12:42:45 INFO - PID 4564 | |i|pagename|runs| 12:42:45 INFO - PID 4564 | |0;ai-astar;104;104;105;104;105;109;105;105;104;104 12:42:45 INFO - PID 4564 | |1;audio-beat-detection;110;113;147;116;111;114;111;114;115;112 12:42:45 INFO - PID 4564 | |2;audio-dft;162;160;162;161;162;162;161;162;162;163 12:42:45 INFO - PID 4564 | |3;audio-fft;73;74;72;72;74;73;72;72;73;73 12:42:45 INFO - PID 4564 | |4;audio-oscillator;78;79;78;77;77;77;78;77;77;84 12:42:45 INFO - PID 4564 | |5;imaging-gaussian-blur;114;113;116;113;113;114;114;113;114;114 12:42:45 INFO - PID 4564 | |6;imaging-darkroom;151;148;148;147;148;149;149;154;149;148 12:42:45 INFO - PID 4564 | |7;imaging-desaturate;95;94;95;93;94;93;94;94;93;94 12:42:45 INFO - PID 4564 | |8;json-parse-financial;44;44;43;44;44;44;46;43;42;43 12:42:45 INFO - PID 4564 | |9;json-stringify-tinderbox;40;39;38;37;39;38;38;39;38;37 12:42:45 INFO - PID 4564 | |10;stanford-crypto-aes;68;71;68;69;72;71;74;71;70;70 12:42:45 INFO - PID 4564 | |11;stanford-crypto-ccm;102;101;101;104;102;106;98;102;102;104 12:42:45 INFO - PID 4564 | |12;stanford-crypto-pbkdf2;181;151;159;149;152;150;149;154;149;145 12:42:45 INFO - PID 4564 | |13;stanford-crypto-sha256-iterative;58;58;60;57;57;58;62;61;58;60 12:42:45 INFO - PID 4564 | __end_tp_report 12:42:45 INFO - PID 4564 | __start_cc_report 12:42:45 INFO - PID 4564 | _x_x_mozilla_cycle_collect,684 12:42:45 INFO - PID 4564 | __end_cc_report 12:42:45 INFO - PID 4564 | __startTimestamp1507664565219__endTimestamp 12:42:45 INFO - PID 4564 | 12:42:45 INFO - PID 4564 | ------- Summary: start ------- 12:42:45 INFO - PID 4564 | Number of tests: 14 12:42:45 INFO - PID 4564 | 12:42:45 INFO - PID 4564 | [#0] ai-astar Cycles:10 Average:104.90 Median:105.00 stddev:1.52 (1.5%) stddev-sans-first:1.58 12:42:45 INFO - PID 4564 | Values: 104.0 104.0 105.0 104.0 105.0 109.0 105.0 105.0 104.0 104.0 12:42:45 INFO - PID 4564 | 12:42:45 INFO - PID 4564 | [#1] audio-beat-detection Cycles:10 Average:116.30 Median:114.00 stddev:10.95 (9.6%) stddev-sans-first:11.38 12:42:45 INFO - PID 4564 | Values: 110.0 113.0 147.0 116.0 111.0 114.0 111.0 114.0 115.0 112.0 12:42:45 INFO - PID 4564 | 12:42:45 INFO - PID 4564 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 12:42:45 INFO - PID 4564 | Values: 162.0 160.0 162.0 161.0 162.0 162.0 161.0 162.0 162.0 163.0 12:42:45 INFO - PID 4564 | 12:42:45 INFO - PID 4564 | [#3] audio-fft Cycles:10 Average:72.80 Median:73.00 stddev:0.79 (1.1%) stddev-sans-first:0.83 12:42:45 INFO - PID 4564 | Values: 73.0 74.0 72.0 72.0 74.0 73.0 72.0 72.0 73.0 73.0 12:42:45 INFO - PID 4564 | 12:42:45 INFO - PID 4564 | [#4] audio-oscillator Cycles:10 Average:78.20 Median:78.00 stddev:2.15 (2.8%) stddev-sans-first:2.28 12:42:45 INFO - PID 4564 | Values: 78.0 79.0 78.0 77.0 77.0 77.0 78.0 77.0 77.0 84.0 12:42:45 INFO - PID 4564 | 12:42:45 INFO - PID 4564 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.92 (0.8%) stddev-sans-first:0.97 12:42:45 INFO - PID 4564 | Values: 114.0 113.0 116.0 113.0 113.0 114.0 114.0 113.0 114.0 114.0 12:42:45 INFO - PID 4564 | 12:42:45 INFO - PID 4564 | [#6] imaging-darkroom Cycles:10 Average:149.10 Median:149.00 stddev:2.02 (1.4%) stddev-sans-first:2.03 12:42:45 INFO - PID 4564 | Values: 151.0 148.0 148.0 147.0 148.0 149.0 149.0 154.0 149.0 148.0 12:42:45 INFO - PID 4564 | 12:42:45 INFO - PID 4564 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.74 (0.8%) stddev-sans-first:0.67 12:42:45 INFO - PID 4564 | Values: 95.0 94.0 95.0 93.0 94.0 93.0 94.0 94.0 93.0 94.0 12:42:45 INFO - PID 4564 | 12:42:45 INFO - PID 4564 | [#8] json-parse-financial Cycles:10 Average:43.70 Median:44.00 stddev:1.06 (2.4%) stddev-sans-first:1.12 12:42:45 INFO - PID 4564 | Values: 44.0 44.0 43.0 44.0 44.0 44.0 46.0 43.0 42.0 43.0 12:42:45 INFO - PID 4564 | 12:42:45 INFO - PID 4564 | [#9] json-stringify-tinderbox Cycles:10 Average:38.30 Median:38.50 stddev:0.95 (2.5%) stddev-sans-first:0.78 12:42:45 INFO - PID 4564 | Values: 40.0 39.0 38.0 37.0 39.0 38.0 38.0 39.0 38.0 37.0 12:42:45 INFO - PID 4564 | 12:42:45 INFO - PID 4564 | [#10] stanford-crypto-aes Cycles:10 Average:70.40 Median:71.00 stddev:1.84 (2.6%) stddev-sans-first:1.73 12:42:45 INFO - PID 4564 | Values: 68.0 71.0 68.0 69.0 72.0 71.0 74.0 71.0 70.0 70.0 12:42:45 INFO - PID 4564 | 12:42:45 INFO - PID 4564 | [#11] stanford-crypto-ccm Cycles:10 Average:102.20 Median:103.00 stddev:2.15 (2.1%) stddev-sans-first:2.28 12:42:45 INFO - PID 4564 | Values: 102.0 101.0 101.0 104.0 102.0 106.0 98.0 102.0 102.0 104.0 12:42:45 INFO - PID 4564 | 12:42:45 INFO - PID 4564 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.90 Median:151.50 stddev:10.21 (6.7%) stddev-sans-first:3.92 12:42:45 INFO - PID 4564 | Values: 181.0 151.0 159.0 149.0 152.0 150.0 149.0 154.0 149.0 145.0 12:42:45 INFO - PID 4564 | 12:42:45 INFO - PID 4564 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:58.90 Median:59.00 stddev:1.73 (2.9%) stddev-sans-first:1.80 12:42:45 INFO - PID 4564 | Values: 58.0 58.0 60.0 57.0 57.0 58.0 62.0 61.0 58.0 60.0 12:42:45 INFO - PID 4564 | -------- Summary: end -------- 12:42:45 INFO - PID 4564 | 12:42:45 INFO - PID 4564 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:42:45 INFO - TEST-INFO | 4564: exit 0 12:42:45 INFO - TEST-OK | kraken | took 58096ms 12:42:45 INFO - SUITE-END | took 1167s 12:42:46 INFO - Completed test suite (00:19:28) 12:42:46 INFO - javascript benchmark 12:42:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9399.005815748429, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3978.1248125412417, 4041.9595804041987, 4052.979735101324, 4055.797210139494, 4095.344744840826, 6522.510811689119, 6539.476841852652, 6595.076689263501, 6615.966920165404, 6644.269130395653, 6589.637569933651, 6631.403173714365, 6644.0, 6692.0, 6721.596704197739, 5881.823545293627, 5891.55813314001, 5977.342492325863, 5979.970100149493, 6011.338752737195, 5784.826455206329, 5848.327442344143, 5862.765489380419, 5961.970190149043, 5976.0, 5827.562932780037, 5918.1418694289305, 5938.88122237555, 5942.702864856761, 5952.136940143677, 5878.059510478303, 5880.441358070976, 5952.494038006775, 5956.910646340309, 5959.553033522503, 5767.33675627304, 5796.101604251347, 5838.591298609099, 5885.440883116097, 5920.378360272195, 6403.295637479873, 6431.324710905381, 6439.0, 6482.189726284215, 6532.542722009462, 7094.964525177393, 7126.964365178141, 7145.0, 7170.641467926635, 7182.42540596751, 7137.357637812622, 7137.928620713778, 7160.319769621878, 7222.205557388683, 7225.132984041896, 6515.924872396049, 6526.869462610721, 6574.76988305407, 6620.8013759587775, 6661.866762664719, 7243.710251589878, 7336.633168341562, 7337.816554586071, 7338.339549440469, 7373.115226172894, 6963.09479767627, 7029.191642961125, 7093.716251349992, 7100.68046937889, 7145.857082858313, 7145.249748776408, 7173.6771845267085, 7185.964070179615, 7234.348908598251, 7280.0, 6912.723491060301, 7063.505554611128, 7086.574805511686, 7103.82240443993, 7116.893246601305, 7050.506464547535, 7067.646617669096, 7097.432205423554, 7128.180259270251, 7137.714491420285, 5189.714565698923, 5263.7894484220205, 5331.067063263991, 5339.092354299778, 5339.626226164209, 4771.713697178111, 4804.95195048045, 4817.108834865561, 4841.95158048415, 4847.466778654345, 6498.317676643979, 6537.542372033912, 6570.605763654196, 6615.338466153443, 6637.601743895381, 7134.143902731706, 7206.135263768381, 7214.891776623354, 7215.927840721525, 7223.9638801806705, 6589.209294884645, 6601.570897891622, 6605.141331626858, 6677.933220667731, 6682.431993280528, 5924.140999555127, 5941.257342832146, 6010.759569617254, 6017.669028203403, 6021.819345419644, 7396.55620662762, 7403.0, 7479.663415146331, 7501.624918754041, 7508.324250817453, 6999.880019196904, 7097.9290207097265, 7106.467014973898, 7187.460940429593, 7200.963995179991, 7281.635918204174, 7342.8898566520475, 7351.117865856131, 7424.591647459441, 7440.181580026301, 6979.616121113388, 7111.644417779194, 7123.323284287984, 7124.750633727795, 7140.357367836919, 7033.824154396181, 7136.250693677089, 7150.606716630427, 7198.244184360567, 7218.711251549879, 6789.660516974231, 6887.311268873073, 6888.41448476885, 6928.515003949877, 6996.125484314461, 6809.0, 6817.113775209191, 6827.795166145023, 6829.385355317847, 6833.726650933907, 6814.318568143344, 6875.690593923386, 6901.482388820758, 6906.758263460755, 6910.965445172742, 6918.308169183041, 6962.1993470749485, 6987.965060174666, 7059.364657180879, 7059.71761129549, 6756.256811750605, 6763.52655314143, 6840.0765896603325, 6852.211995620568, 6861.039454476278], "value": 6524.380716938193, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [938.2822141062085, 999.5452069308463, 1021.6475316015974, 1021.754778853075, 1039.5893622019298, 44265.33601995969, 46113.538864611386, 46160.0, 46277.76861115698, 46305.07389852205, 44555.55444445555, 44884.326735099, 45067.0, 45076.77461612695, 45091.54908450923, 8197.83604327913, 8297.626606802709, 8319.792005199857, 8322.126176751444, 8323.084460709317, 8083.878741818878, 8125.918740812575, 8156.959215203916, 8162.755117346489, 8172.959135204315, 8315.792105197357, 8519.74440766778, 8533.914660853374, 8547.10255423181, 8550.657973681045, 8455.281301089415, 8456.57717114148, 8493.36299777513, 8495.235428811437, 8524.445911015795, 8793.56032198394, 8795.560221988875, 8834.249088827457, 8848.51333176675, 8877.644894204224, 41957.0, 42002.78998605018, 42256.0, 42323.57676423258, 42340.57659423397, 3799.3351163546545, 3826.4069069294305, 3845.8846234613006, 3846.3268927937497, 3850.114473671071, 34788.478172827425, 34875.0, 34919.0, 34934.0, 35061.64938350635, 5580.497755202011, 5626.493615574577, 5628.915566266509, 5639.0, 5675.063614503602, 213830.0, 214485.0, 214735.0, 215873.0, 225288.0, 49050.28324008643, 54347.184792228145, 55723.16415253774, 55987.72006140025, 56040.0, 56556.71721641366, 57011.0, 57151.714241428526, 57284.71357643185, 57353.713231433576, 17334.566635834206, 17442.563935901704, 17502.91248543749, 17529.912350438422, 17548.29806807739, 14476.493322733864, 14526.418943242363, 14687.92656036713, 14850.628734281729, 14876.330565124596, 15274.618134546503, 15363.692726145415, 15433.151176685391, 15501.837362197655, 15661.921690391475, 6064.787732429432, 6157.599756015845, 6166.352532984062, 6189.257289125244, 6241.157443745036, 1119.675294164683, 1122.8877112288706, 1130.2879186112696, 1131.9773604527863, 1133.2690414682484, 25821.0, 26314.6052809208, 26417.339566510607, 26549.3362665931, 26634.06780762664, 1232.7534493101416, 1249.8500179978462, 1250.993745031269, 1258.0501721200435, 1262.1858901008798, 13839.169649821042, 13890.166590004632, 13931.65170870716, 14076.71846563063, 14188.85811141896, 285073.0, 317854.0, 335044.0, 337513.0, 338520.0, 65045.349546504876, 65369.0, 65444.0, 65681.67159164173, 65848.34151658422, 1535.1019653502826, 1560.602046478141, 1564.4889951627474, 1576.5664442278464, 1580.178307280231, 30615.846920765252, 30951.76192952347, 31083.378332432774, 31179.07549138532, 31293.217669558442, 42648.0, 42783.57216427796, 42856.78571607122, 42935.355969661105, 42947.57052429436, 266374.0, 267456.0, 267496.0, 267613.0, 269127.0, 37623.0, 37979.81010094932, 38392.424113638874, 38417.80791096027, 38506.80746596249, 47738.0, 47961.5203847957, 48236.27645585249, 48254.276185856535, 48406.27390589073, 35215.295694085464, 35544.28911421809, 35848.82075589606, 35957.28085438224, 36506.634933650326, 35096.29807403786, 35177.64822351744, 35366.82316588401, 35712.0, 35730.28539429249, 41709.0, 41825.581744183386, 41828.0, 42069.0, 42288.15423691447, 34004.65995340015, 34324.656753432144, 34436.82781586076, 34526.309473810885, 34535.13662158365, 25295.494090117725, 25430.49139017246, 25719.228423147335, 25841.0, 25860.353491162117], "value": 19433.280376106148, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [620.4075108271601, 629.4680994559595, 631.4254028833763, 648.4293821437135, 648.4488185042715, 24595.13917012905, 24753.13364032259, 25415.364615884613, 25589.616155757656, 25648.10231641893, 23244.0, 24013.279601611936, 24092.87953560234, 24101.75898241017, 24117.397065073383, 6644.900326495106, 6792.694328755206, 6922.307769223063, 6926.099607051076, 6959.4432445404245, 6690.76582319619, 6768.627725475097, 6773.254941956381, 6776.525643204977, 6796.626185559792, 6747.898781518256, 6748.696308666122, 6772.559783614074, 6775.2208496023095, 6781.593104413726, 6741.595504269735, 6750.0, 6772.72909083636, 6773.322667733237, 6775.695093720795, 6321.146645202884, 6327.145835312266, 6355.0, 6387.073874288249, 6394.296627370985, 28323.291917702016, 31275.37449251025, 32073.51889721656, 32319.83840080808, 32396.514052289236, 2393.222202784102, 2411.5297516984197, 2425.7331693513697, 2431.8419302745265, 2433.209207007712, 20652.380428587167, 20878.791212087835, 21246.0, 21255.256066037615, 21256.893715531478, 2630.631711560383, 2650.4169082801945, 2662.267876334004, 2673.4118493931305, 2676.879540420666, 151890.24054879765, 157333.21333393373, 164833.17583412005, 167173.0, 171864.14067929707, 15614.453494127878, 16628.667426651642, 17129.571760706083, 17282.740758888627, 17640.55898602545, 27914.581281280796, 28026.159215223168, 28027.719722802918, 28051.0, 28153.0, 10737.194710396752, 10783.78432431347, 10929.0, 11012.614558490575, 11040.668779936615, 9895.752606184902, 9921.355111917703, 9955.701328960144, 10012.098911098035, 10162.288639795144, 9870.753231169278, 10144.898551014543, 10298.227632927561, 10332.948335258276, 10464.895351046544, 2932.621667816123, 2954.1432984434273, 2995.236214765221, 2997.2656699108857, 3062.586550815611, 1062.311322866041, 1069.7325668582855, 1079.6653037558226, 1080.1574771678104, 1082.6373164989816, 21324.466888327606, 21596.244131455325, 21883.234086807202, 22004.229851955104, 22432.66351004736, 1147.1568397228032, 1154.7748189103213, 1166.9066474682006, 1170.6839153428698, 1170.988290117105, 11672.649820505398, 12009.159358844798, 12196.268223906594, 12205.511779528719, 12236.75526489483, 664323.0, 679846.0, 693384.0, 699290.0, 705991.0, 131835.34082329526, 133528.3323583376, 135620.32189838987, 138103.30948345395, 139148.0, 1296.1056870759146, 1309.9476020959246, 1326.840779106494, 1334.5595953335376, 1339.216558313378, 19205.0, 19381.321653742616, 19440.61118777645, 19643.410697679094, 20097.0, 62709.0, 64689.67655161694, 64885.67557162184, 65440.0, 65630.0, 82091.5895420543, 82851.0, 83318.58340708258, 83712.58143709242, 84215.0, 15846.841531584998, 16323.673526529165, 16353.18234088267, 16372.426965056642, 16376.426825061542, 24004.51990960206, 24263.393415164763, 24380.26859194227, 24710.629340560266, 24951.87524062368, 19909.900450497655, 20044.198232070554, 20047.498812529804, 20127.094280757694, 20389.79610203879, 16156.0, 16654.41709540209, 17970.460886173434, 18099.72850407271, 18177.545561361072, 19645.410637680892, 20361.49096272605, 20403.285884993955, 20499.59000820005, 20536.897315513328, 15837.841621583637, 15919.363225470852, 16007.599810004378, 16091.919540402223, 16099.75850362269, 19633.60732785365, 19717.802821971596, 19817.20731170737, 19925.402237932885, 20051.799482004993], "value": 13192.953726464213, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1430.6137342917414, 1485.1460410264094, 1497.2813049736133, 1499.6625759204178, 1505.3666771552857, 16777.24502397392, 17405.21676524556, 17423.128843557835, 17432.302707891668, 17454.738178927328, 16954.23705933233, 16977.915110424463, 17014.149292535385, 17058.23237954292, 17070.402535911246, 3009.8043627164166, 3050.1916991997136, 3107.4406606810767, 3108.4560201964678, 3113.5018397056474, 2949.9852500737466, 2999.9100026999226, 3009.1574359179463, 3013.668496465387, 3015.0, 2988.775841811862, 3071.554624579435, 3174.936501269973, 3175.8253296068706, 3176.888808891689, 3146.7797254192214, 3169.5879535660333, 3190.56927314813, 3190.840457977115, 3191.5850939377733, 2960.185948864058, 2970.2722832906074, 3008.42839860428, 3026.092172348309, 3026.5157574787927, 16386.426475073316, 16618.0, 16657.50027499177, 16760.329586816635, 16845.32618695251, 8412.579371031485, 8770.824583508294, 8788.472691638523, 8797.912020879774, 8799.516026618534, 5868.853278668024, 6112.205413296243, 6242.750289988395, 6246.843828904268, 6258.87482250357, 2091.801278878504, 2160.0711693971584, 2160.751513575943, 2215.789499997498, 2218.0351547077075, 31780.0, 32059.35881282361, 32115.678843211736, 32124.678753212404, 32280.031599052065, 7464.253574642492, 7499.137599176055, 7548.131964824006, 7673.501222420525, 7823.491473054233, 20714.067866946025, 20744.377668669964, 20749.377518674162, 20810.0, 20813.1674733009, 8399.37004724648, 8553.44402613828, 8564.229219370161, 8574.056853746082, 8578.528180949983, 7679.462437629312, 7746.264104910025, 7877.724279650185, 7881.881771773428, 7893.13175550689, 5993.700314984233, 6041.0, 6204.81385558434, 6271.404216599416, 6351.2060992375955, 1577.1798664694295, 1612.129450096958, 1704.5994191365019, 1764.3912850066706, 1769.133124768854, 1016.8169729448771, 1019.261035749076, 1020.8366661334151, 1037.766502536923, 1047.7956798424386, 9445.811083778424, 9593.904060959441, 9707.757306067264, 9812.067853553901, 9932.0, 1082.9133669306436, 1090.1987039525943, 1126.7577470843808, 1128.4696192789377, 1133.1048471707425, 5809.141044947318, 5905.557083218776, 5928.703564821742, 6402.647854368054, 6490.221173459121, 25255.242342729747, 27267.590986135223, 27492.725072749017, 27650.723492765217, 27707.722922770514, 25500.0, 26367.0, 26511.46977060448, 26756.732432675813, 26766.330841728723, 13173.802392964113, 13186.604401867768, 13275.734485310433, 13312.334383280797, 13348.132371395828, 9680.467574283482, 9765.951170244103, 9816.705498835046, 9826.606935722777, 9960.203183745138, 22249.44376390603, 22339.77660223377, 23105.537889241783, 23176.188833390752, 23450.53098938046, 23007.309780706604, 23583.41041473977, 23616.409589760395, 23795.643065353688, 23871.283861484182, 12737.617871463872, 12953.805692914426, 13152.408141633841, 13157.210567365988, 13176.60470185896, 15928.761068584212, 16188.543031127256, 16203.108829014518, 16221.59446013859, 16222.675546488768, 13124.475020999053, 13186.538471153464, 13232.933835330761, 13344.199348039148, 13400.731985360433, 12385.814212786996, 12608.36958152107, 12716.554920577735, 12836.0, 12903.548375806802, 11549.711257218636, 11685.883141168819, 11694.824577631513, 11696.590619328283, 11712.238704484182, 8263.380246481418, 8302.584870756558, 8329.95835020821, 8429.283510901396, 8466.407351485337, 8010.799730006564, 8012.599370031592, 8063.83872322562, 8203.630836612247, 8273.751787446387], "value": 7688.235127427247, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.0878594249202, 573.6080544130973, 585.7744768264218, 587.7061469265367, 599.8860216558853, 6260.436560709535, 6335.402484981153, 6357.491400687951, 6369.554131210817, 6430.392401899525, 6273.619803643202, 6292.528060395477, 6294.685265736718, 6299.0, 6302.495800335973, 5036.438704001771, 5122.436531981479, 5231.768231768232, 5240.8689782755355, 5287.920681189776, 5109.489051094879, 5122.513361230677, 5134.127198376285, 5151.4333423323405, 5182.4299327073995, 5030.773615187325, 5043.092243396187, 5064.1390963536105, 5101.336826212606, 5105.668131571436, 5105.294194077145, 5114.948850511484, 5126.154184559543, 5155.252488389163, 5158.638895277332, 4650.697704649187, 4675.251959686434, 4694.08465349257, 4697.929531057037, 4724.645651576146, 6113.205283313186, 6138.355472675349, 6200.162977997958, 6291.842703932392, 6316.652584107872, 5916.911246331266, 5953.523718102585, 5984.311804142536, 6030.246219222598, 6042.093685947099, 6760.0, 6792.592444453348, 6810.523263371566, 6812.420944219747, 6842.6578671066745, 4965.329680493123, 5136.922946155811, 5174.120399532088, 5209.218617207412, 5210.296609957683, 6896.137982752156, 7005.719771209146, 7024.5082844200415, 7080.575165490087, 7109.6445177741425, 5290.603204759582, 5355.839324820262, 5362.812301569505, 5373.220882972026, 5381.139017757218, 5408.8377348679605, 5414.07960646691, 5467.835964921059, 5469.917951230735, 5484.643498172606, 5952.434518720715, 5997.160397544348, 6007.3992600739575, 6028.90956635642, 6077.878442431127, 6806.727730890808, 6829.726810927507, 6843.657817109125, 6855.760048398282, 6881.690323935435, 1818.127298896537, 1867.738516607676, 1878.3238034307637, 1892.7413270175455, 1900.9334673286646, 1275.8213850061, 1319.9868001320056, 1328.2296268164441, 1354.214555557774, 1370.1299674706434, 6180.47465965389, 6254.468370188585, 6279.340669229757, 6311.0, 6388.456981156561, 6774.525783195228, 6784.830379240559, 6798.422134118655, 6874.278200788845, 6887.173539175331, 6293.280039970023, 6299.685015749286, 6321.8735625288155, 6340.841478963062, 6365.777197798055, 3641.9453708194396, 3651.8721844735305, 3669.4679271505483, 3672.797996110186, 3675.61406052366, 7243.804772212631, 7252.311030452099, 7256.419486441072, 7268.200497945222, 7285.907113932953, 7026.508144429841, 7084.504084714021, 7116.5018448709125, 7117.679704413314, 7164.391026762679, 7134.7859564214195, 7151.2121969507625, 7217.278272172845, 7225.602591857393, 7259.963700181465, 6825.805484040372, 6847.726090956306, 6858.759943402157, 6942.51402401827, 6973.58158510491, 6687.264400915993, 6971.930280697128, 6974.546654467444, 6975.162980442379, 6979.441644668516, 7031.191412987368, 7051.471139664443, 7096.396806271318, 7101.8579628406105, 7186.892196617159, 5312.62811603184, 5317.627766056339, 5343.812966546152, 5344.545713614386, 5390.460953904578, 6605.768798092236, 6837.384635382839, 6866.690998905161, 6917.155004021593, 6994.405475534637, 6239.656818875005, 6522.119513865758, 6532.41208291256, 6714.52998290115, 6745.831354216184, 6507.446867016357, 6586.868262634816, 6616.206055273398, 6634.2848005117285, 6659.733610655519, 6540.640264785482, 6595.2852258412195, 6596.736130554916, 6610.272869984202, 6623.536352455475, 6720.85745423289, 6729.798106056826, 6742.4943129266485, 6772.187337519529, 6773.254941956282, 6550.148480697479, 6575.145231119924, 6579.276279609144, 6592.934070659232, 6594.538382313192], "value": 5300.968495070985, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [975.1857199238635, 994.8857279846571, 1015.9949200253998, 1019.5361110694635, 1033.514248303297, 22158.556828863435, 23915.0, 23951.76048239517, 23985.5202895942, 24054.398640033985, 20749.170033198654, 22386.21648242312, 22604.54790904181, 22695.432614184614, 22703.432414189654, 1039.2777019971122, 1085.018058656913, 1086.0822604898888, 1086.315621158673, 1087.211771465689, 1072.4155335342257, 1073.8281874900017, 1081.9188560857967, 1082.0369870814982, 1082.2640604389012, 1121.9158563107758, 1124.246754674366, 1127.7349822791637, 1129.1926272715007, 1136.4033882211863, 1122.186414849236, 1134.1493879590307, 1134.4271143072779, 1134.6199023327194, 1134.7787181499612, 814.450246083891, 816.481534225765, 817.6729308276737, 824.0688022534498, 828.2793969246734, 19196.520086997796, 19291.710624340645, 19323.227070917146, 19417.80582194174, 19424.90287548553, 22775.544489110292, 23399.53200935972, 23573.17493887723, 23776.167834125896, 23869.28392148221, 19037.14332855011, 19159.23363065476, 19186.424407267805, 19256.903715481472, 19421.126049327813, 3996.9200615987806, 4041.0, 4053.5135783706146, 4065.3698676705158, 4073.083556199861, 166985.0, 167335.0, 168187.0, 168730.0, 170408.0, 17657.911710441367, 17793.466196014142, 18038.36865709694, 18076.819231807778, 18150.45548633543, 33633.0, 34658.133546661535, 34690.30619387598, 34732.13169670779, 35306.64693353033, 9635.373700709431, 9664.855027174597, 9736.610535578639, 9797.510124493747, 9832.115109640166, 4706.011737535087, 4723.504032076583, 4759.619230461555, 4819.445763737145, 4845.733484658378, 6170.845728856725, 6269.96865015672, 6274.121622972787, 6282.654454005074, 6284.717187726564, 1145.3643228008534, 1169.6666450061691, 1192.5110704611068, 1192.797224471842, 1197.3953153657521, 745.3962290544677, 752.6650640464941, 757.1935888278965, 766.5170942306312, 768.7271018788362, 12740.617781466572, 12929.0, 13654.726905462034, 13698.0, 13881.375338109809, 728.4427413029038, 731.5318196354337, 735.312482828557, 738.0810890441435, 747.7046566606269, 6213.1922850029205, 6262.968685156545, 6340.048992651139, 6342.619442833445, 6422.100905873182, 150802.0, 152101.0, 152364.0, 154030.22984885005, 157663.0, 136501.0, 136588.0, 137728.0, 143211.0, 143760.28119859335, 120916.0, 121288.0, 121859.78140218662, 122931.0, 124059.0, 11620.302781833117, 11644.592439264585, 11702.414879256174, 11773.411329433666, 11866.0421030529, 57087.42912570821, 57151.714241428526, 57454.0, 58334.4166558329, 58604.70697646485, 71774.28225717676, 73384.0, 73993.63003184949, 74045.0, 74839.0, 17070.48788536346, 17228.0, 17231.483055508354, 17331.826681733022, 17459.738103928197, 18618.906905465385, 19390.612187755883, 19442.805571944664, 19499.317523886595, 19597.51006224856, 17533.649327013645, 17639.294428223242, 17663.116844157485, 17709.822901770818, 17827.910860445616, 17499.0, 17768.200430980378, 17792.02143882099, 17815.46553603394, 17824.732629010836, 16057.67884642324, 16166.67666646684, 16326.265318060954, 16398.426055088014, 16403.17984100814, 13205.405756740778, 13319.400626971616, 13560.59318220455, 13563.321833908463, 13739.24434156131, 12849.0, 12903.41934612926, 12959.224465320733, 12992.935035325141, 13097.279649618982], "value": 10112.446325736153, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1358.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 104.0, 105.0, 104.0, 105.0, 109.0, 105.0, 105.0, 104.0, 104.0], "value": 104.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 147.0, 116.0, 111.0, 114.0, 111.0, 114.0, 115.0, 112.0], "value": 116.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 162.0, 161.0, 162.0, 162.0, 161.0, 162.0, 162.0, 163.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 72.0, 72.0, 74.0, 73.0, 72.0, 72.0, 73.0, 73.0], "value": 72.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 78.0, 77.0, 77.0, 77.0, 78.0, 77.0, 77.0, 84.0], "value": 78.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 116.0, 113.0, 113.0, 114.0, 114.0, 113.0, 114.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 148.0, 148.0, 147.0, 148.0, 149.0, 149.0, 154.0, 149.0, 148.0], "value": 149.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 93.0, 94.0, 93.0, 94.0, 94.0, 93.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 44.0, 43.0, 44.0, 44.0, 44.0, 46.0, 43.0, 42.0, 43.0], "value": 43.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [40.0, 39.0, 38.0, 37.0, 39.0, 38.0, 38.0, 39.0, 38.0, 37.0], "value": 38.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 68.0, 69.0, 72.0, 71.0, 74.0, 71.0, 70.0, 70.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 101.0, 101.0, 104.0, 102.0, 106.0, 98.0, 102.0, 102.0, 104.0], "value": 102.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 151.0, 159.0, 149.0, 152.0, 150.0, 149.0, 154.0, 149.0, 145.0], "value": 153.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 60.0, 57.0, 57.0, 58.0, 62.0, 61.0, 58.0, 60.0], "value": 58.9, "unit": "score"}]}]} 12:42:46 INFO - Return code: 0 12:42:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:42:46 INFO - # TBPL SUCCESS # 12:42:46 INFO - Running post-action listener: _package_coverage_data 12:42:46 INFO - Running post-action listener: _resource_record_post_action 12:42:46 INFO - [mozharness: 2017-10-10 19:42:46.520774Z] Finished run-tests step (success) 12:42:46 INFO - Running post-run listener: _resource_record_post_run 12:42:46 INFO - Total resource usage - Wall time: 1183s; CPU: 13.0%; Read bytes: 9150464; Write bytes: 480415744; Read time: 3028; Write time: 480380 12:42:46 INFO - TinderboxPrint: CPU usage
13.0% 12:42:46 INFO - TinderboxPrint: I/O read bytes / time
9,150,464 / 3,028 12:42:46 INFO - TinderboxPrint: I/O write bytes / time
480,415,744 / 480,380 12:42:46 INFO - TinderboxPrint: CPU idle
8,199.9 (86.9%) 12:42:46 INFO - TinderboxPrint: CPU user
1,178.3 (12.5%) 12:42:46 INFO - TinderboxPrint: Swap in / out
0 / 0 12:42:46 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 79314944; Read time: 0; Write time: 71728 12:42:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:42:46 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 9150464; Write bytes: 401100800; Read time: 3028; Write time: 408652 12:42:47 INFO - Running post-run listener: _upload_blobber_files 12:42:47 INFO - Blob upload gear active. 12:42:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:42:47 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 12:42:47 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'] 12:42:47 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 12:42:47 INFO - (blobuploader) - INFO - Open directory for files ... 12:42:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:42:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:42:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:54 INFO - (blobuploader) - INFO - Done attempting. 12:42:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:42:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:42:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:56 INFO - (blobuploader) - INFO - Done attempting. 12:42:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:42:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:42:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:57 INFO - (blobuploader) - INFO - Done attempting. 12:42:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:42:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:42:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:58 INFO - (blobuploader) - INFO - Done attempting. 12:42:58 INFO - (blobuploader) - INFO - Iteration through files over. 12:42:58 INFO - Return code: 0 12:42:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:42:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:42:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4c250600d2fb66478c2097e37d295d4437f0b2039f0017f06b518e590aa91b4c8087123987ee43cfcf13342c72458e0f49188d048dad8f9c5286e46c7dbf9f83", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4462c862e74d6292fc7f4e871d056361a7d3bd51ad36fdab68b86d1e7fc0b772863cce9a06ab108ed7ca882d07a90dcd433019325e7739fdeaab5d45304774e1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d0b7b17b468b994943acabcba2a3d6adf17aab01f41aa2fc51f761cc6a09bd8336e970502658311878b547c28b267a079ab691a0c0c4d7cfc8de68656fc66227", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b978f484b0b888c2d68ac36b990dcea9254b4f128cb480f3f8dffc2efc98ab212c3685723ccfdd9fc6588e4946669c3bb83fdb854b94d55c45ca670eeac6e00a"} 12:42:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:42:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:42:58 INFO - Contents: 12:42:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4c250600d2fb66478c2097e37d295d4437f0b2039f0017f06b518e590aa91b4c8087123987ee43cfcf13342c72458e0f49188d048dad8f9c5286e46c7dbf9f83", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4462c862e74d6292fc7f4e871d056361a7d3bd51ad36fdab68b86d1e7fc0b772863cce9a06ab108ed7ca882d07a90dcd433019325e7739fdeaab5d45304774e1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d0b7b17b468b994943acabcba2a3d6adf17aab01f41aa2fc51f761cc6a09bd8336e970502658311878b547c28b267a079ab691a0c0c4d7cfc8de68656fc66227", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b978f484b0b888c2d68ac36b990dcea9254b4f128cb480f3f8dffc2efc98ab212c3685723ccfdd9fc6588e4946669c3bb83fdb854b94d55c45ca670eeac6e00a"} 12:42:58 INFO - Running post-run listener: copy_logs_to_upload_dir 12:42:58 INFO - Copying logs to upload dir... 12:42:58 INFO - mkdir: /builds/slave/test/build/upload/logs 12:42:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.813747 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-10-10 12:42:58.891819) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-10 12:42:58.899383) ========= 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=03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4c250600d2fb66478c2097e37d295d4437f0b2039f0017f06b518e590aa91b4c8087123987ee43cfcf13342c72458e0f49188d048dad8f9c5286e46c7dbf9f83", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4462c862e74d6292fc7f4e871d056361a7d3bd51ad36fdab68b86d1e7fc0b772863cce9a06ab108ed7ca882d07a90dcd433019325e7739fdeaab5d45304774e1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d0b7b17b468b994943acabcba2a3d6adf17aab01f41aa2fc51f761cc6a09bd8336e970502658311878b547c28b267a079ab691a0c0c4d7cfc8de68656fc66227", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b978f484b0b888c2d68ac36b990dcea9254b4f128cb480f3f8dffc2efc98ab212c3685723ccfdd9fc6588e4946669c3bb83fdb854b94d55c45ca670eeac6e00a"} build_url:https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011189 build_url: 'https://queue.taskcluster.net/v1/task/TSIKWs0gS4aJYxqYDOhsYw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4c250600d2fb66478c2097e37d295d4437f0b2039f0017f06b518e590aa91b4c8087123987ee43cfcf13342c72458e0f49188d048dad8f9c5286e46c7dbf9f83", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4462c862e74d6292fc7f4e871d056361a7d3bd51ad36fdab68b86d1e7fc0b772863cce9a06ab108ed7ca882d07a90dcd433019325e7739fdeaab5d45304774e1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d0b7b17b468b994943acabcba2a3d6adf17aab01f41aa2fc51f761cc6a09bd8336e970502658311878b547c28b267a079ab691a0c0c4d7cfc8de68656fc66227", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b978f484b0b888c2d68ac36b990dcea9254b4f128cb480f3f8dffc2efc98ab212c3685723ccfdd9fc6588e4946669c3bb83fdb854b94d55c45ca670eeac6e00a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-10 12:42:58.933676) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-10 12:42:58.934017) ========= 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=03627c79f6bb3d1578c4a402000001ba-1507659028.744510-445250965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004479 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-10 12:42:59.015780) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-10 12:42:59.019114) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-10 12:42:59.019432) ========= ========= Total master_lag: 0.50 =========