builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-018 starttime: 1506355314.15 results: success (0) revision: 29da5f3b170d6f5c74733cf93102da76397921c1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-25 09:01:54.147056) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-25 09:01:54.147516) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-25 09:01:54.147786) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-25 09:01:54.167133) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 09:01:54.167472) ========= 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=62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-25 09:01:54-- 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% 46.3M=0s 2017-09-25 09:01:54 (46.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.105607 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 09:01:54.290107) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 09:01:54.290427) ========= 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=62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018378 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 09:01:54.348338) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-09-25 09:01:54.348670) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 29da5f3b170d6f5c74733cf93102da76397921c1 --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 29da5f3b170d6f5c74733cf93102da76397921c1 --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=62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-25 09:01:54,440 truncating revision to first 12 chars 2017-09-25 09:01:54,441 Setting DEBUG logging. 2017-09-25 09:01:54,441 attempt 1/10 2017-09-25 09:01:54,441 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/29da5f3b170d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-25 09:01:55,604 unpacking tar archive at: mozilla-beta-29da5f3b170d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.460045 ========= master_lag: 0.93 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-09-25 09:01:56.736831) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-25 09:01:56.737151) ========= script_repo_revision: 29da5f3b170d6f5c74733cf93102da76397921c1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-25 09:01:56.748358) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-25 09:01:56.748629) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-25 09:01:56.797113) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2017-09-25 09:01:56.797453) ========= /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=62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:01:57 INFO - MultiFileLogger online at 20170925 09:01:57 in /builds/slave/test 09:01:57 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 09:01:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:01:57 INFO - {'append_to_log': False, 09:01:57 INFO - 'base_work_dir': '/builds/slave/test', 09:01:57 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 09:01:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:01:57 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 09:01:57 INFO - 'buildbot_json_path': 'buildprops.json', 09:01:57 INFO - 'code_coverage': False, 09:01:57 INFO - 'config_files': ('talos/linux_config.py',), 09:01:57 INFO - 'default_actions': ('clobber', 09:01:57 INFO - 'read-buildbot-config', 09:01:57 INFO - 'download-and-extract', 09:01:57 INFO - 'populate-webroot', 09:01:57 INFO - 'create-virtualenv', 09:01:57 INFO - 'install', 09:01:57 INFO - 'setup-mitmproxy', 09:01:57 INFO - 'run-tests'), 09:01:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:01:57 INFO - 'disable_ccov_upload': False, 09:01:57 INFO - 'disable_stylo': False, 09:01:57 INFO - 'download_minidump_stackwalk': True, 09:01:57 INFO - 'download_symbols': 'ondemand', 09:01:57 INFO - 'enable_stylo': False, 09:01:57 INFO - 'enable_webrender': False, 09:01:57 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 09:01:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:01:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:01:57 INFO - 'gecko_profile': False, 09:01:57 INFO - 'gecko_profile_interval': 0, 09:01:57 INFO - 'installer_path': 'installer.exe', 09:01:57 INFO - 'log_level': 'info', 09:01:57 INFO - 'log_name': 'talos', 09:01:57 INFO - 'log_to_console': True, 09:01:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:01:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:01:57 INFO - 'opt_config_files': (), 09:01:57 INFO - 'pip_index': False, 09:01:57 INFO - 'suite': 'dromaeojs-e10s', 09:01:57 INFO - 'system_bits': '32', 09:01:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:01:57 INFO - 'title': 'talos-linux64-ix-018', 09:01:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:01:57 INFO - 'use_talos_json': True, 09:01:57 INFO - 'verify': 'False', 09:01:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:01:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:01:57 INFO - 'work_dir': 'build'} 09:01:57 INFO - [mozharness: 2017-09-25 16:01:57.018685Z] Running clobber step. 09:01:57 INFO - Running pre-action listener: _resource_record_pre_action 09:01:57 INFO - Running main action method: clobber 09:01:57 INFO - rmtree: /builds/slave/test/build 09:01:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:01:58 INFO - Running post-action listener: _resource_record_post_action 09:01:58 INFO - [mozharness: 2017-09-25 16:01:58.007269Z] Finished clobber step (success) 09:01:58 INFO - [mozharness: 2017-09-25 16:01:58.007366Z] Running read-buildbot-config step. 09:01:58 INFO - Running pre-action listener: _resource_record_pre_action 09:01:58 INFO - Running main action method: read_buildbot_config 09:01:58 INFO - Using buildbot properties: 09:01:58 INFO - { 09:01:58 INFO - "project": "", 09:01:58 INFO - "product": "firefox", 09:01:58 INFO - "who": "ryanvm@gmail.com", 09:01:58 INFO - "installer_path": "public/build/target.tar.bz2", 09:01:58 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 09:01:58 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 09:01:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 09:01:58 INFO - "stage_platform": "linux64", 09:01:58 INFO - "basedir": "/builds/slave/test", 09:01:58 INFO - "buildnumber": 124, 09:01:58 INFO - "platform": "ubuntu64_hw", 09:01:58 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:01:58 INFO - "slavebuilddir": "test", 09:01:58 INFO - "taskId": "DM8q0O7GSkSkknU4WvYLEg", 09:01:58 INFO - "branch": "mozilla-beta", 09:01:58 INFO - "script_repo_revision": "production", 09:01:58 INFO - "revision": "29da5f3b170d6f5c74733cf93102da76397921c1", 09:01:58 INFO - "slavename": "talos-linux64-ix-018", 09:01:58 INFO - "repo_path": "releases/mozilla-beta" 09:01:58 INFO - } 09:01:58 INFO - Finding installer, test and symbols from parent task. 09:01:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DM8q0O7GSkSkknU4WvYLEg'}, attempt #1 09:01:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DM8q0O7GSkSkknU4WvYLEg'}, attempt #1 09:01:58 INFO - Task dependencies: GRG8MIJyRYWiRI3RY5YvSg 09:01:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg'}, attempt #1 09:01:59 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.tar.bz2 09:01:59 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.test_packages.json 09:01:59 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.crashreporter-symbols.zip 09:01:59 INFO - Running post-action listener: _resource_record_post_action 09:01:59 INFO - [mozharness: 2017-09-25 16:01:59.370546Z] Finished read-buildbot-config step (success) 09:01:59 INFO - [mozharness: 2017-09-25 16:01:59.370767Z] Running download-and-extract step. 09:01:59 INFO - Running pre-action listener: _resource_record_pre_action 09:01:59 INFO - Running main action method: download_and_extract 09:01:59 INFO - mkdir: /builds/slave/test/build/tests 09:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:59 INFO - trying https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.test_packages.json 09:01:59 INFO - Downloading https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:01:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:01:59 INFO - Downloaded 989 bytes. 09:01:59 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:01:59 INFO - Using the following test package requirements: 09:01:59 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:01:59 INFO - u'common': [u'target.common.tests.zip'], 09:01:59 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:01:59 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:01:59 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:01:59 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:01:59 INFO - u'mozbase': [u'target.common.tests.zip'], 09:01:59 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:01:59 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:01:59 INFO - u'web-platform': [u'target.common.tests.zip', 09:01:59 INFO - u'target.web-platform.tests.tar.gz'], 09:01:59 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:01:59 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 09:01:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.common.tests.zip 09:01:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:01:59 INFO - Fetch https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.common.tests.zip into memory 09:02:00 INFO - Content-Length response header: 55178740 09:02:00 INFO - Bytes received: 55178740 09:02:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.talos.tests.zip 09:02:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:02:11 INFO - Fetch https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.talos.tests.zip into memory 09:02:12 INFO - Content-Length response header: 14509184 09:02:12 INFO - Bytes received: 14509184 09:02:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:12 INFO - trying https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.tar.bz2 09:02:12 INFO - Downloading https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:02:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:02:13 INFO - Downloaded 53536511 bytes. 09:02:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.tar.bz2 09:02:13 INFO - mkdir: /builds/slave/test/properties 09:02:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:02:13 INFO - Writing to file /builds/slave/test/properties/build_url 09:02:13 INFO - Contents: 09:02:13 INFO - build_url:https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.tar.bz2 09:02:13 INFO - Running post-action listener: _resource_record_post_action 09:02:13 INFO - Running post-action listener: find_tests_for_verification 09:02:13 INFO - Running post-action listener: set_extra_try_arguments 09:02:13 INFO - [mozharness: 2017-09-25 16:02:13.619871Z] Finished download-and-extract step (success) 09:02:13 INFO - [mozharness: 2017-09-25 16:02:13.619968Z] Running populate-webroot step. 09:02:13 INFO - Running pre-action listener: _resource_record_pre_action 09:02:13 INFO - Running main action method: populate_webroot 09:02:13 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:02:13 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:02:13 INFO - 'tests': ['tresize', 09:02:13 INFO - 'tcanvasmark']}, 09:02:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:02:13 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:02:13 INFO - 'tests': ['dromaeo_css', 09:02:13 INFO - 'kraken']}, 09:02:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:02:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:13 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:02:13 INFO - 'talos_options': ['--disable-stylo'], 09:02:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:02:13 INFO - 'tests': ['damp', 'tps']}, 09:02:13 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:02:13 INFO - 'talos_options': ['--disable-stylo'], 09:02:13 INFO - 'tests': ['damp', 'tps']}, 09:02:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:02:13 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:02:13 INFO - 'tests': ['dromaeo_dom']}, 09:02:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:02:13 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:02:13 INFO - 'tests': ['basic_compositor_video', 09:02:13 INFO - 'glvideo']}, 09:02:13 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:02:13 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:02:13 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:02:13 INFO - 'talos_options': ['--disable-stylo'], 09:02:13 INFO - 'tests': ['ts_paint_webext', 09:02:13 INFO - 'tp5o_webext']}, 09:02:13 INFO - 'other-e10s': {'tests': ['a11yr', 09:02:13 INFO - 'ts_paint', 09:02:13 INFO - 'tpaint', 09:02:13 INFO - 'sessionrestore', 09:02:13 INFO - 'sessionrestore_many_windows', 09:02:13 INFO - 'sessionrestore_no_auto_restore', 09:02:13 INFO - 'tabpaint']}, 09:02:13 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:02:13 INFO - 'tests': ['a11yr', 09:02:13 INFO - 'ts_paint', 09:02:13 INFO - 'tpaint', 09:02:13 INFO - 'sessionrestore', 09:02:13 INFO - 'sessionrestore_many_windows', 09:02:13 INFO - 'sessionrestore_no_auto_restore', 09:02:13 INFO - 'tabpaint']}, 09:02:13 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 09:02:13 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 09:02:13 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:02:13 INFO - 'tests': ['perf_reftest_singletons']}, 09:02:13 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:02:13 INFO - 'tests': ['bloom_basic']}, 09:02:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:02:13 INFO - 'tsvgr_opacity', 09:02:13 INFO - 'tart', 09:02:13 INFO - 'tscrollx', 09:02:13 INFO - 'tsvg_static']}, 09:02:13 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:02:13 INFO - 'tests': ['tsvgx', 09:02:13 INFO - 'tsvgr_opacity', 09:02:13 INFO - 'tart', 09:02:13 INFO - 'tscrollx', 09:02:13 INFO - 'tsvg_static']}, 09:02:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:02:13 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:02:13 INFO - 'talos_options': ['--disable-stylo'], 09:02:13 INFO - 'tests': ['tp5o']}, 09:02:13 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:02:13 INFO - 'talos_options': ['--mitmproxy', 09:02:13 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:02:13 INFO - '--firstNonBlankPaint'], 09:02:13 INFO - 'tests': ['tp6_google', 09:02:13 INFO - 'tp6_youtube', 09:02:13 INFO - 'tp6_amazon', 09:02:13 INFO - 'tp6_facebook']}, 09:02:13 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:02:13 INFO - 'talos_options': ['--disable-stylo', 09:02:13 INFO - '--mitmproxy', 09:02:13 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:02:13 INFO - '--firstNonBlankPaint'], 09:02:13 INFO - 'tests': ['tp6_google', 09:02:13 INFO - 'tp6_youtube', 09:02:13 INFO - 'tp6_amazon', 09:02:13 INFO - 'tp6_facebook']}, 09:02:13 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:02:13 INFO - 'talos_options': ['--stylo-threads=1', 09:02:13 INFO - '--mitmproxy', 09:02:13 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:02:13 INFO - '--firstNonBlankPaint'], 09:02:13 INFO - 'tests': ['tp6_google', 09:02:13 INFO - 'tp6_youtube', 09:02:13 INFO - 'tp6_amazon', 09:02:13 INFO - 'tp6_facebook']}, 09:02:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:02:13 INFO - 'talos_options': ['--xperf_path', 09:02:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:13 INFO - 'tests': ['tp5n']}, 09:02:13 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:02:13 INFO - 'talos_options': ['--disable-stylo', 09:02:13 INFO - '--xperf_path', 09:02:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:13 INFO - 'tests': ['tp5n']}}} 09:02:13 INFO - Running post-action listener: _resource_record_post_action 09:02:13 INFO - [mozharness: 2017-09-25 16:02:13.634900Z] Finished populate-webroot step (success) 09:02:13 INFO - [mozharness: 2017-09-25 16:02:13.634990Z] Running create-virtualenv step. 09:02:13 INFO - Running pre-action listener: _resource_record_pre_action 09:02:13 INFO - Running main action method: create_virtualenv 09:02:13 INFO - Creating virtualenv /builds/slave/test/build/venv 09:02:13 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:02:13 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 09:02:13 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:02:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:02:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:02:14 INFO - Installing distribute.............................................................................................................................................................................................done. 09:02:14 INFO - Installing pip...............done. 09:02:14 INFO - Return code: 0 09:02:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:02:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:02:14 INFO - Reading from file tmpfile_stdout 09:02:14 INFO - Output received: 09:02:14 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 09:02:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:02:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x177a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1942a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae9090>, '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 0x1ae9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3f90>, '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': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', '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 09:02:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:02:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:02:14 INFO - Using env: {'DISPLAY': ':0', 09:02:14 INFO - 'HOME': '/home/cltbld', 09:02:14 INFO - 'LANG': 'en_US.UTF-8', 09:02:14 INFO - 'LANGUAGE': 'en_US:en', 09:02:14 INFO - 'LOGNAME': 'cltbld', 09:02:14 INFO - 'MAIL': '/var/mail/cltbld', 09:02:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:14 INFO - 'MOZ_NO_REMOTE': '1', 09:02:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:14 INFO - 'NO_EM_RESTART': '1', 09:02:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:14 INFO - 'PWD': '/builds/slave/test', 09:02:14 INFO - 'SHELL': '/bin/bash', 09:02:14 INFO - 'SHLVL': '1', 09:02:14 INFO - 'TERM': 'linux', 09:02:14 INFO - 'TMOUT': '86400', 09:02:14 INFO - 'USER': 'cltbld', 09:02:14 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', 09:02:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:14 INFO - '_': '/tools/buildbot/bin/python'} 09:02:15 INFO - Ignoring indexes: http://pypi.python.org/simple/ 09:02:15 INFO - Downloading/unpacking pip>=1.5 09:02:15 INFO - Running setup.py egg_info for package pip 09:02:15 INFO - warning: no files found matching 'pip/cacert.pem' 09:02:15 INFO - warning: no files found matching '*.html' under directory 'docs' 09:02:15 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 09:02:15 INFO - no previously-included directories found matching 'docs/_build/_sources' 09:02:15 INFO - Installing collected packages: pip 09:02:15 INFO - Found existing installation: pip 1.1 09:02:15 INFO - Uninstalling pip: 09:02:15 INFO - Successfully uninstalled pip 09:02:15 INFO - Running setup.py install for pip 09:02:15 INFO - warning: no files found matching 'pip/cacert.pem' 09:02:15 INFO - warning: no files found matching '*.html' under directory 'docs' 09:02:15 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 09:02:15 INFO - no previously-included directories found matching 'docs/_build/_sources' 09:02:15 INFO - Installing pip script to /builds/slave/test/build/venv/bin 09:02:15 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 09:02:15 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 09:02:15 INFO - Successfully installed pip 09:02:15 INFO - Cleaning up... 09:02:15 INFO - Return code: 0 09:02:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:02:15 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')]} 09:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:15 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')]} 09:02:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:15 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 0x177a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1942a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae9090>, '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 0x1ae9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3f90>, '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': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', '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 09:02:15 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 09:02:15 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 09:02:15 INFO - Using env: {'DISPLAY': ':0', 09:02:15 INFO - 'HOME': '/home/cltbld', 09:02:15 INFO - 'LANG': 'en_US.UTF-8', 09:02:15 INFO - 'LANGUAGE': 'en_US:en', 09:02:15 INFO - 'LOGNAME': 'cltbld', 09:02:15 INFO - 'MAIL': '/var/mail/cltbld', 09:02:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:15 INFO - 'MOZ_NO_REMOTE': '1', 09:02:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:15 INFO - 'NO_EM_RESTART': '1', 09:02:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:15 INFO - 'PWD': '/builds/slave/test', 09:02:15 INFO - 'SHELL': '/bin/bash', 09:02:15 INFO - 'SHLVL': '1', 09:02:15 INFO - 'TERM': 'linux', 09:02:15 INFO - 'TMOUT': '86400', 09:02:15 INFO - 'USER': 'cltbld', 09:02:15 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', 09:02:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:15 INFO - '_': '/tools/buildbot/bin/python'} 09:02:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:16 INFO - Downloading/unpacking psutil>=3.1.1 09:02:16 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 09:02:16 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 09:02:16 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 09:02:16 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 09:02:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:02:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:17 INFO - Installing collected packages: psutil 09:02:17 INFO - Running setup.py install for psutil 09:02:17 INFO - building 'psutil._psutil_linux' extension 09:02:17 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 09:02:17 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 09:02:17 INFO - building 'psutil._psutil_posix' extension 09:02:17 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 09:02:17 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 09:02:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:17 INFO - Successfully installed psutil 09:02:17 INFO - Cleaning up... 09:02:17 INFO - Return code: 0 09:02:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:02: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')]} 09:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02: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')]} 09:02:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:17 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 0x177a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1942a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae9090>, '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 0x1ae9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3f90>, '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': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', '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 09:02:17 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 09:02:17 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 09:02:17 INFO - Using env: {'DISPLAY': ':0', 09:02:17 INFO - 'HOME': '/home/cltbld', 09:02:17 INFO - 'LANG': 'en_US.UTF-8', 09:02:17 INFO - 'LANGUAGE': 'en_US:en', 09:02:17 INFO - 'LOGNAME': 'cltbld', 09:02:17 INFO - 'MAIL': '/var/mail/cltbld', 09:02:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:17 INFO - 'MOZ_NO_REMOTE': '1', 09:02:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:17 INFO - 'NO_EM_RESTART': '1', 09:02:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:17 INFO - 'PWD': '/builds/slave/test', 09:02:17 INFO - 'SHELL': '/bin/bash', 09:02:17 INFO - 'SHLVL': '1', 09:02:17 INFO - 'TERM': 'linux', 09:02:17 INFO - 'TMOUT': '86400', 09:02:17 INFO - 'USER': 'cltbld', 09:02:17 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', 09:02:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:17 INFO - '_': '/tools/buildbot/bin/python'} 09:02:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:02:18 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 09:02:18 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 09:02:18 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 09:02:18 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 09:02:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:02:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:02:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:02:19 INFO - Installing collected packages: mozsystemmonitor 09:02:19 INFO - Running setup.py install for mozsystemmonitor 09:02:19 INFO - Successfully installed mozsystemmonitor 09:02:19 INFO - Cleaning up... 09:02:19 INFO - Return code: 0 09:02:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:02:19 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')]} 09:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:19 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')]} 09:02:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:19 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 0x177a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1942a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae9090>, '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 0x1ae9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3f90>, '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': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', '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 09:02:19 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 09:02:19 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 09:02:19 INFO - Using env: {'DISPLAY': ':0', 09:02:19 INFO - 'HOME': '/home/cltbld', 09:02:19 INFO - 'LANG': 'en_US.UTF-8', 09:02:19 INFO - 'LANGUAGE': 'en_US:en', 09:02:19 INFO - 'LOGNAME': 'cltbld', 09:02:19 INFO - 'MAIL': '/var/mail/cltbld', 09:02:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:19 INFO - 'MOZ_NO_REMOTE': '1', 09:02:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:19 INFO - 'NO_EM_RESTART': '1', 09:02:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:19 INFO - 'PWD': '/builds/slave/test', 09:02:19 INFO - 'SHELL': '/bin/bash', 09:02:19 INFO - 'SHLVL': '1', 09:02:19 INFO - 'TERM': 'linux', 09:02:19 INFO - 'TMOUT': '86400', 09:02:19 INFO - 'USER': 'cltbld', 09:02:19 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', 09:02:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:19 INFO - '_': '/tools/buildbot/bin/python'} 09:02:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:19 INFO - Downloading/unpacking jsonschema==2.5.1 09:02:19 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 09:02:19 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 09:02:19 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 09:02:19 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 09:02:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:02:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:02:20 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 09:02:20 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 09:02:20 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 09:02:20 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 09:02:20 INFO - Downloading functools32-3.2.3-2.tar.gz 09:02:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:02:20 INFO - warning: no files found matching '*.txt' 09:02:20 INFO - no previously-included directories found matching 'build' 09:02:20 INFO - no previously-included directories found matching 'dist' 09:02:20 INFO - no previously-included directories found matching '.git*' 09:02:20 INFO - Installing collected packages: jsonschema, functools32 09:02:21 INFO - Running setup.py install for functools32 09:02:21 INFO - warning: no files found matching '*.txt' 09:02:21 INFO - no previously-included directories found matching 'build' 09:02:21 INFO - no previously-included directories found matching 'dist' 09:02:21 INFO - no previously-included directories found matching '.git*' 09:02:21 INFO - Successfully installed jsonschema functools32 09:02:21 INFO - Cleaning up... 09:02:21 INFO - Return code: 0 09:02:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:02:21 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')]} 09:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:21 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')]} 09:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:21 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 0x177a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1942a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae9090>, '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 0x1ae9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3f90>, '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': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', '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 09:02:21 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 09:02:21 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 09:02:21 INFO - Using env: {'DISPLAY': ':0', 09:02:21 INFO - 'HOME': '/home/cltbld', 09:02:21 INFO - 'LANG': 'en_US.UTF-8', 09:02:21 INFO - 'LANGUAGE': 'en_US:en', 09:02:21 INFO - 'LOGNAME': 'cltbld', 09:02:21 INFO - 'MAIL': '/var/mail/cltbld', 09:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:21 INFO - 'MOZ_NO_REMOTE': '1', 09:02:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:21 INFO - 'NO_EM_RESTART': '1', 09:02:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:21 INFO - 'PWD': '/builds/slave/test', 09:02:21 INFO - 'SHELL': '/bin/bash', 09:02:21 INFO - 'SHLVL': '1', 09:02:21 INFO - 'TERM': 'linux', 09:02:21 INFO - 'TMOUT': '86400', 09:02:21 INFO - 'USER': 'cltbld', 09:02:21 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', 09:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:21 INFO - '_': '/tools/buildbot/bin/python'} 09:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:02:21 INFO - Cleaning up... 09:02:21 INFO - Return code: 0 09:02:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:02:21 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')]} 09:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:21 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')]} 09:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:21 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 0x177a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1942a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae9090>, '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 0x1ae9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3f90>, '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': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', '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 09:02:21 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 09:02:21 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 09:02:21 INFO - Using env: {'DISPLAY': ':0', 09:02:21 INFO - 'HOME': '/home/cltbld', 09:02:21 INFO - 'LANG': 'en_US.UTF-8', 09:02:21 INFO - 'LANGUAGE': 'en_US:en', 09:02:21 INFO - 'LOGNAME': 'cltbld', 09:02:21 INFO - 'MAIL': '/var/mail/cltbld', 09:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:21 INFO - 'MOZ_NO_REMOTE': '1', 09:02:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:21 INFO - 'NO_EM_RESTART': '1', 09:02:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:21 INFO - 'PWD': '/builds/slave/test', 09:02:21 INFO - 'SHELL': '/bin/bash', 09:02:21 INFO - 'SHLVL': '1', 09:02:21 INFO - 'TERM': 'linux', 09:02:21 INFO - 'TMOUT': '86400', 09:02:21 INFO - 'USER': 'cltbld', 09:02:21 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', 09:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:21 INFO - '_': '/tools/buildbot/bin/python'} 09:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:21 INFO - Downloading/unpacking blobuploader==1.2.4 09:02:21 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 09:02:21 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 09:02:21 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 09:02:21 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 09:02:22 INFO - Downloading blobuploader-1.2.4.tar.gz 09:02:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:02:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:02:22 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 09:02:22 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 09:02:22 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 09:02:22 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 09:02:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:02:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:02:23 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 09:02:23 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 09:02:23 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 09:02:23 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 09:02:23 INFO - Downloading docopt-0.6.1.tar.gz 09:02:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:02:23 INFO - Installing collected packages: blobuploader, requests, docopt 09:02:23 INFO - Running setup.py install for blobuploader 09:02:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:02:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:02:23 INFO - Running setup.py install for requests 09:02:24 INFO - Running setup.py install for docopt 09:02:25 INFO - Successfully installed blobuploader requests docopt 09:02:25 INFO - Cleaning up... 09:02:25 INFO - Return code: 0 09:02:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:25 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 0x177a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1942a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae9090>, '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 0x1ae9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3f90>, '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': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', '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 09:02:25 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 09:02:25 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 09:02:25 INFO - Using env: {'DISPLAY': ':0', 09:02:25 INFO - 'HOME': '/home/cltbld', 09:02:25 INFO - 'LANG': 'en_US.UTF-8', 09:02:25 INFO - 'LANGUAGE': 'en_US:en', 09:02:25 INFO - 'LOGNAME': 'cltbld', 09:02:25 INFO - 'MAIL': '/var/mail/cltbld', 09:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:25 INFO - 'MOZ_NO_REMOTE': '1', 09:02:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:25 INFO - 'NO_EM_RESTART': '1', 09:02:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:25 INFO - 'PWD': '/builds/slave/test', 09:02:25 INFO - 'SHELL': '/bin/bash', 09:02:25 INFO - 'SHLVL': '1', 09:02:25 INFO - 'TERM': 'linux', 09:02:25 INFO - 'TMOUT': '86400', 09:02:25 INFO - 'USER': 'cltbld', 09:02:25 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', 09:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:25 INFO - '_': '/tools/buildbot/bin/python'} 09:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:25 INFO - Running setup.py (path:/tmp/pip-9hA5GJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:25 INFO - Running setup.py (path:/tmp/pip-rh7jhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:25 INFO - Running setup.py (path:/tmp/pip-btOPV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:26 INFO - Running setup.py (path:/tmp/pip-ebD58M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:26 INFO - Running setup.py (path:/tmp/pip-wplthh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:26 INFO - Running setup.py (path:/tmp/pip-p2jWpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:26 INFO - Running setup.py (path:/tmp/pip-3MwjTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:26 INFO - Running setup.py (path:/tmp/pip-8YLXlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:26 INFO - Running setup.py (path:/tmp/pip-gad8W1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:26 INFO - Running setup.py (path:/tmp/pip-az25K8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:26 INFO - Running setup.py (path:/tmp/pip-3kaxih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:26 INFO - Running setup.py (path:/tmp/pip-4EK77b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:26 INFO - Running setup.py (path:/tmp/pip-QuWT38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:26 INFO - Running setup.py (path:/tmp/pip-50_kpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:26 INFO - Running setup.py (path:/tmp/pip-8ErwKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:26 INFO - Running setup.py (path:/tmp/pip-o_yRdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:26 INFO - Running setup.py (path:/tmp/pip-jXpoPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:02:27 INFO - Running setup.py install for manifestparser 09:02:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:02:27 INFO - Running setup.py install for mozcrash 09:02:27 INFO - Running setup.py install for mozdebug 09:02:27 INFO - Running setup.py install for mozdevice 09:02:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:02:27 INFO - Running setup.py install for mozfile 09:02:27 INFO - Running setup.py install for mozhttpd 09:02:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:02:27 INFO - Running setup.py install for mozinfo 09:02:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:02:27 INFO - Running setup.py install for mozInstall 09:02:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:02:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:02:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:02:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:02:27 INFO - Running setup.py install for mozleak 09:02:27 INFO - Running setup.py install for mozlog 09:02:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:02:28 INFO - Running setup.py install for moznetwork 09:02:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:02:28 INFO - Running setup.py install for mozprocess 09:02:28 INFO - Running setup.py install for mozprofile 09:02:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:02:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:02:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:02:28 INFO - Running setup.py install for mozrunner 09:02:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:02:28 INFO - Running setup.py install for mozscreenshot 09:02:28 INFO - Running setup.py install for moztest 09:02:28 INFO - Running setup.py install for mozversion 09:02:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:02:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:02:28 INFO - Cleaning up... 09:02:28 INFO - Return code: 0 09:02:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:28 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 0x177a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1942a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae9090>, '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 0x1ae9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3f90>, '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': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', '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 09:02:28 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 09:02:28 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 09:02:28 INFO - Using env: {'DISPLAY': ':0', 09:02:28 INFO - 'HOME': '/home/cltbld', 09:02:28 INFO - 'LANG': 'en_US.UTF-8', 09:02:28 INFO - 'LANGUAGE': 'en_US:en', 09:02:28 INFO - 'LOGNAME': 'cltbld', 09:02:28 INFO - 'MAIL': '/var/mail/cltbld', 09:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:28 INFO - 'MOZ_NO_REMOTE': '1', 09:02:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:28 INFO - 'NO_EM_RESTART': '1', 09:02:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:28 INFO - 'PWD': '/builds/slave/test', 09:02:28 INFO - 'SHELL': '/bin/bash', 09:02:28 INFO - 'SHLVL': '1', 09:02:28 INFO - 'TERM': 'linux', 09:02:28 INFO - 'TMOUT': '86400', 09:02:28 INFO - 'USER': 'cltbld', 09:02:28 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', 09:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:28 INFO - '_': '/tools/buildbot/bin/python'} 09:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:28 INFO - Running setup.py (path:/tmp/pip-vSCAai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:29 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)) 09:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:29 INFO - Running setup.py (path:/tmp/pip-jnGl4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:29 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)) 09:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:29 INFO - Running setup.py (path:/tmp/pip-AWrXTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:29 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)) 09:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:29 INFO - Running setup.py (path:/tmp/pip-3eHC3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:29 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)) 09:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:29 INFO - Running setup.py (path:/tmp/pip-ChXpRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:29 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)) 09:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:29 INFO - Running setup.py (path:/tmp/pip-cIBZsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:29 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)) 09:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:29 INFO - Running setup.py (path:/tmp/pip-G69QW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:29 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)) 09:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:29 INFO - Running setup.py (path:/tmp/pip-owijO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:29 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)) 09:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:29 INFO - Running setup.py (path:/tmp/pip-hSJhiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:29 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)) 09:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:29 INFO - Running setup.py (path:/tmp/pip-UMVk7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:29 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)) 09:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:29 INFO - Running setup.py (path:/tmp/pip-x68OTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:29 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)) 09:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:29 INFO - Running setup.py (path:/tmp/pip-HAGfSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:29 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)) 09:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:29 INFO - Running setup.py (path:/tmp/pip-ey3UQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:29 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)) 09:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:29 INFO - Running setup.py (path:/tmp/pip-_Ctc2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:30 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)) 09:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:30 INFO - Running setup.py (path:/tmp/pip-wz4493-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:30 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)) 09:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:30 INFO - Running setup.py (path:/tmp/pip-bQ4HQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:30 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)) 09:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:30 INFO - Running setup.py (path:/tmp/pip-lhjRJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:30 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)) 09:02:30 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)) 09:02:30 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)) 09:02:30 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)) 09:02:30 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)) 09:02:30 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)) 09:02:30 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)) 09:02:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:02:30 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 09:02:30 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 09:02:30 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 09:02:30 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 09:02:31 INFO - Downloading blessings-1.6.tar.gz 09:02:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:02:31 INFO - Installing collected packages: blessings 09:02:31 INFO - Running setup.py install for blessings 09:02:31 INFO - Successfully installed blessings 09:02:31 INFO - Cleaning up... 09:02:31 INFO - Return code: 0 09:02:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:02:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:31 INFO - Reading from file tmpfile_stdout 09:02:31 INFO - Current package versions: 09:02:31 INFO - argparse == 1.2.1 09:02:31 INFO - blessings == 1.6 09:02:31 INFO - blobuploader == 1.2.4 09:02:31 INFO - docopt == 0.6.1 09:02:31 INFO - functools32 == 3.2.3-2 09:02:31 INFO - jsonschema == 2.5.1 09:02:31 INFO - manifestparser == 1.1 09:02:31 INFO - mozInstall == 1.13 09:02:31 INFO - mozcrash == 1.0 09:02:31 INFO - mozdebug == 0.1 09:02:31 INFO - mozdevice == 0.51 09:02:31 INFO - mozfile == 1.2 09:02:31 INFO - mozhttpd == 0.7 09:02:31 INFO - mozinfo == 0.10 09:02:31 INFO - mozleak == 0.1 09:02:31 INFO - mozlog == 3.5 09:02:31 INFO - moznetwork == 0.27 09:02:31 INFO - mozprocess == 0.25 09:02:31 INFO - mozprofile == 0.28 09:02:31 INFO - mozrunner == 6.13 09:02:31 INFO - mozscreenshot == 0.1 09:02:31 INFO - mozsystemmonitor == 0.3 09:02:31 INFO - moztest == 0.8 09:02:31 INFO - mozversion == 1.4 09:02:31 INFO - psutil == 3.1.1 09:02:31 INFO - requests == 1.2.3 09:02:31 INFO - wsgiref == 0.1.2 09:02:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:31 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')]} 09:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:31 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')]} 09:02:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:31 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 0x177a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1942a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae9090>, '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 0x1ae9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3f90>, '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': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', '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 09:02:31 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 09:02:31 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 09:02:31 INFO - Using env: {'DISPLAY': ':0', 09:02:31 INFO - 'HOME': '/home/cltbld', 09:02:31 INFO - 'LANG': 'en_US.UTF-8', 09:02:31 INFO - 'LANGUAGE': 'en_US:en', 09:02:31 INFO - 'LOGNAME': 'cltbld', 09:02:31 INFO - 'MAIL': '/var/mail/cltbld', 09:02:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:31 INFO - 'MOZ_NO_REMOTE': '1', 09:02:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:31 INFO - 'NO_EM_RESTART': '1', 09:02:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:31 INFO - 'PWD': '/builds/slave/test', 09:02:31 INFO - 'SHELL': '/bin/bash', 09:02:31 INFO - 'SHLVL': '1', 09:02:31 INFO - 'TERM': 'linux', 09:02:31 INFO - 'TMOUT': '86400', 09:02:31 INFO - 'USER': 'cltbld', 09:02:31 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', 09:02:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:31 INFO - '_': '/tools/buildbot/bin/python'} 09:02:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:31 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)) 09:02:32 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)) 09:02:32 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)) 09:02:32 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)) 09:02:32 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)) 09:02:32 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)) 09:02:32 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)) 09:02:32 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)) 09:02:32 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)) 09:02:32 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:02:32 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 09:02:32 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 09:02:32 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 09:02:32 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 09:02:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 09:02:33 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)) 09:02:33 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)) 09:02:33 INFO - Installing collected packages: simplejson 09:02:33 INFO - Running setup.py install for simplejson 09:02:33 INFO - building 'simplejson._speedups' extension 09:02:33 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 09:02:34 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 09:02:34 INFO - Successfully installed simplejson 09:02:34 INFO - Cleaning up... 09:02:34 INFO - Return code: 0 09:02:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:02:34 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')]} 09:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:34 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')]} 09:02:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:34 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 0x177a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1942a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae9090>, '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 0x1ae9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3f90>, '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': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', '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 09:02:34 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 09:02:34 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 09:02:34 INFO - Using env: {'DISPLAY': ':0', 09:02:34 INFO - 'HOME': '/home/cltbld', 09:02:34 INFO - 'LANG': 'en_US.UTF-8', 09:02:34 INFO - 'LANGUAGE': 'en_US:en', 09:02:34 INFO - 'LOGNAME': 'cltbld', 09:02:34 INFO - 'MAIL': '/var/mail/cltbld', 09:02:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:34 INFO - 'MOZ_NO_REMOTE': '1', 09:02:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:34 INFO - 'NO_EM_RESTART': '1', 09:02:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:34 INFO - 'PWD': '/builds/slave/test', 09:02:34 INFO - 'SHELL': '/bin/bash', 09:02:34 INFO - 'SHLVL': '1', 09:02:34 INFO - 'TERM': 'linux', 09:02:34 INFO - 'TMOUT': '86400', 09:02:34 INFO - 'USER': 'cltbld', 09:02:34 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', 09:02:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:34 INFO - '_': '/tools/buildbot/bin/python'} 09:02:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:02:34 INFO - Cleaning up... 09:02:34 INFO - Return code: 0 09:02:34 INFO - Running post-action listener: _resource_record_post_action 09:02:34 INFO - Running post-action listener: _start_resource_monitoring 09:02:34 INFO - Starting resource monitoring. 09:02:34 INFO - [mozharness: 2017-09-25 16:02:34.525640Z] Finished create-virtualenv step (success) 09:02:34 INFO - [mozharness: 2017-09-25 16:02:34.526103Z] Running install step. 09:02:34 INFO - Running pre-action listener: _resource_record_pre_action 09:02:34 INFO - Running main action method: install 09:02:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:34 INFO - Reading from file tmpfile_stdout 09:02:34 INFO - Detecting whether we're running mozinstall >=1.0... 09:02:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:02:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:02:34 INFO - Reading from file tmpfile_stdout 09:02:34 INFO - Output received: 09:02:34 INFO - Usage: mozinstall [options] installer 09:02:34 INFO - Options: 09:02:34 INFO - -h, --help show this help message and exit 09:02:34 INFO - -d DEST, --destination=DEST 09:02:34 INFO - Directory to install application into. [default: 09:02:34 INFO - "/builds/slave/test"] 09:02:34 INFO - --app=APP Application being installed. [default: firefox] 09:02:34 INFO - mkdir: /builds/slave/test/build/application 09:02:34 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'] 09:02:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:02:49 INFO - Reading from file tmpfile_stdout 09:02:49 INFO - Output received: 09:02:49 INFO - /builds/slave/test/build/application/firefox/firefox 09:02:49 INFO - Running post-action listener: _resource_record_post_action 09:02:49 INFO - [mozharness: 2017-09-25 16:02:49.916638Z] Finished install step (success) 09:02:49 INFO - [mozharness: 2017-09-25 16:02:49.916784Z] Running setup-mitmproxy step. 09:02:49 INFO - Running pre-action listener: _resource_record_pre_action 09:02:49 INFO - Running main action method: setup_mitmproxy 09:02:49 INFO - Skipping: mitmproxy is not required 09:02:49 INFO - Running post-action listener: _resource_record_post_action 09:02:49 INFO - [mozharness: 2017-09-25 16:02:49.917562Z] Finished setup-mitmproxy step (success) 09:02:49 INFO - [mozharness: 2017-09-25 16:02:49.917783Z] Running run-tests step. 09:02:49 INFO - Running pre-action listener: _resource_record_pre_action 09:02:49 INFO - Running pre-action listener: _set_gcov_prefix 09:02:49 INFO - Running main action method: run_tests 09:02:49 WARNING - Try message not found. 09:02:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:02:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:02:49 INFO - Python 2.7.3 09:02:49 INFO - Return code: 0 09:02:49 INFO - grabbing minidump binary from tooltool 09:02: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')]} 09:02:49 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 0x1ae9090>, '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 0x1ae9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:02:49 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 09:02:49 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 09:02:49 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 09:02:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:02:50 INFO - Return code: 0 09:02:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:02:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:02:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:02:50 INFO - ENV: RUST_BACKTRACE is now full 09:02:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:02:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:02:50 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/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 09:02:50 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/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 09:02:50 INFO - Using env: {'DISPLAY': ':0', 09:02:50 INFO - 'HOME': '/home/cltbld', 09:02:50 INFO - 'LANG': 'en_US.UTF-8', 09:02:50 INFO - 'LANGUAGE': 'en_US:en', 09:02:50 INFO - 'LOGNAME': 'cltbld', 09:02:50 INFO - 'MAIL': '/var/mail/cltbld', 09:02:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:02:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:02:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:50 INFO - 'MOZ_NO_REMOTE': '1', 09:02:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:02:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:50 INFO - 'NO_EM_RESTART': '1', 09:02:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:50 INFO - 'PWD': '/builds/slave/test', 09:02:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:02:50 INFO - 'RUST_BACKTRACE': 'full', 09:02:50 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:02:50 INFO - 'SHELL': '/bin/bash', 09:02:50 INFO - 'SHLVL': '1', 09:02:50 INFO - 'TERM': 'linux', 09:02:50 INFO - 'TMOUT': '86400', 09:02:50 INFO - 'USER': 'cltbld', 09:02:50 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', 09:02:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:50 INFO - '_': '/tools/buildbot/bin/python'} 09:02:50 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/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 09:02:50 INFO - mozversion application_buildid: 20170925144112 09:02:50 INFO - mozversion application_changeset: 29da5f3b170d6f5c74733cf93102da76397921c1 09:02:50 INFO - mozversion application_display_name: Firefox 09:02:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:02:50 INFO - mozversion application_name: Firefox 09:02:50 INFO - mozversion application_remotingname: firefox 09:02:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:02:50 INFO - mozversion application_vendor: Mozilla 09:02:50 INFO - mozversion application_version: 57.0 09:02:50 INFO - mozversion platform_buildid: 20170925144112 09:02:50 INFO - mozversion platform_changeset: 29da5f3b170d6f5c74733cf93102da76397921c1 09:02:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:02:50 INFO - mozversion platform_version: 57.0 09:02:50 INFO - using testdate: 1506355370 09:02:50 INFO - actual date: 1506355370 09:02:50 INFO - starting webserver on 'localhost:48668' 09:02:50 INFO - SUITE-START | Running 2 tests 09:02:50 INFO - TEST-START | dromaeo_css 09:02:50 INFO - Initialising browser for dromaeo_css test... 09:02:50 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48668/getInfo.html -profile /tmp/tmpTV1h0f/profile 09:02:50 INFO - TEST-INFO | started process 3861 (/builds/slave/test/build/application/firefox/firefox http://localhost:48668/getInfo.html) 09:02:54 INFO - TEST-INFO | 3861: exit 0 09:02:54 INFO - Browser initialized. 09:02:54 INFO - Running cycle 1/1 for dromaeo_css test... 09:02:54 INFO - Using env: {'DISPLAY': ':0', 09:02:54 INFO - 'HOME': '/home/cltbld', 09:02:54 INFO - 'JSGC_DISABLE_POISONING': '1', 09:02:54 INFO - 'LANG': 'en_US.UTF-8', 09:02:54 INFO - 'LANGUAGE': 'en_US:en', 09:02:54 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:02:54 INFO - 'LOGNAME': 'cltbld', 09:02:54 INFO - 'MAIL': '/var/mail/cltbld', 09:02:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:02:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:02:54 INFO - 'MOZ_CRASHREPORTER': '1', 09:02:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:54 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:02:54 INFO - 'MOZ_NO_REMOTE': '1', 09:02:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:02:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:54 INFO - 'NO_EM_RESTART': '1', 09:02:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:54 INFO - 'PWD': '/builds/slave/test', 09:02:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:02:54 INFO - 'RUST_BACKTRACE': 'full', 09:02:54 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:02:54 INFO - 'SHELL': '/bin/bash', 09:02:54 INFO - 'SHLVL': '1', 09:02:54 INFO - 'TERM': 'linux', 09:02:54 INFO - 'TMOUT': '86400', 09:02:54 INFO - 'USER': 'cltbld', 09:02:54 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', 09:02:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:54 INFO - '_': '/tools/buildbot/bin/python'} 09:02:54 INFO - TEST-INFO | started process 4049 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTV1h0f/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:02:55 INFO - PID 4049 | 09:02:55 INFO - PID 4049 | (/builds/slave/test/build/application/firefox/firefox:4098): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:02:55 INFO - PID 4049 | 09:03:06 INFO - PID 4049 | RSS: Main: 143400960 09:03:06 INFO - PID 4049 | 09:06:03 INFO - PID 4049 | Cycle 1(1): loaded http://localhost:48668/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48668/tests/dromaeo/cssquery-ext.html) 09:06:04 INFO - PID 4049 | RSS: Main: 145911808 09:06:04 INFO - PID 4049 | 09:09:06 INFO - PID 4049 | Cycle 1(1): loaded http://localhost:48668/tests/dromaeo/cssquery-ext.html (next: http://localhost:48668/tests/dromaeo/cssquery-jquery.html) 09:09:06 INFO - PID 4049 | RSS: Main: 144818176 09:09:06 INFO - PID 4049 | 09:12:08 INFO - PID 4049 | Cycle 1(1): loaded http://localhost:48668/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48668/tests/dromaeo/cssquery-mootools.html) 09:12:08 INFO - PID 4049 | RSS: Main: 144687104 09:12:08 INFO - PID 4049 | 09:15:10 INFO - PID 4049 | Cycle 1(1): loaded http://localhost:48668/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48668/tests/dromaeo/cssquery-prototype.html) 09:15:11 INFO - PID 4049 | RSS: Main: 146104320 09:15:11 INFO - PID 4049 | 09:18:14 INFO - PID 4049 | Cycle 1(1): loaded http://localhost:48668/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48668/tests/dromaeo/cssquery-yui.html) 09:18:14 INFO - PID 4049 | RSS: Main: 147378176 09:18:14 INFO - PID 4049 | 09:21:16 INFO - PID 4049 | Cycle 1(1): loaded http://localhost:48668/tests/dromaeo/cssquery-yui.html (next: http://localhost:48668/tests/dromaeo/cssquery-dojo.html) 09:21:16 INFO - PID 4049 | RSS: Main: 149958656 09:21:16 INFO - PID 4049 | 09:21:16 INFO - PID 4049 | __start_tp_report 09:21:16 INFO - PID 4049 | _x_x_mozilla_page_load 09:21:16 INFO - PID 4049 | _x_x_mozilla_page_load_details 09:21:16 INFO - PID 4049 | |i|pagename|runs| 09:21:16 INFO - PID 4049 | |0;dojo.html;3915.295246855566;3946.0332218606427;3948.0722030322886;3954.327764280073;3960.6633436157927;6376.139221205141;6426.839329016777;6480.481561475082;6520.641364724944;6550.475961923046;6437.8712425751455;6451.25810531789;6509.739610415578;6513.706883190256;6540.934590654092;5653.1237658162845;5715.885682286352;5792.102224155242;5796.275465566804;5805.5065319447895;5700.572457065716;5713.542916566665;5737.770489180449;5742.856428589298;5748.913766293509;5702.059160238577;5753.453421924911;5781.479666829984;5862.413758624146;5877.441643043904;5806.651600903917;5819.272590926134;5862.237909071836;5912.231409916726;5912.852178695545;5732.656040637575;5784.3926387729525;5800.854978625526;5813.389594092601;5866.853328666774;6304.7793327233785;6384.936150638527;6397.9360206397805;6411.967940160316;6452.225732912034;7020.438364930845;7067.646617669096;7081.256468070881;7094.077769889881;7109.9644501777675;7009.193942696605;7034.472414568928;7057.082579264663;7101.573905565682;7131.500794944356;6421;6463.57986730861;6501.967490162566;6518.967405162991;6531.922232831625;7097.751578694721;7183.856322873513;7186.676599553032;7272.60000699956;7287.672054757442;6944.826379340456;6948.096747422803;7027.086478757728;7028.648567571601;7029.683664235019;7092.893606595905;7098.3611474966465;7163.498555101093;7174.820629484305;7179.8564028720175;6801.761938332134;6932.896006559805;6967.198772141167;6991.650417479105;7099.041629380071;6911.550749201387;6938.340857618518;6939.514234003672;6966.303369662993;6983.580985140908;5079.238114282887;5130.820421285237;5136.845894623168;5148.4079330876675;5151.974240128776;4621.838235661736;4658.487566367697;4662.836800711959;4673.626109911199;4698.85903422891;6603.8349041273395;6626.900596491057;6676.833079172962;6677.565958212701;6707.899381509281;7118.039064726299;7146.142462904485;7151.249118842551;7152.570845749272;7158.642067896584;6602;6677.131972843499;6714.966425167843;6787.321267873272;6814.591124532544;5784.71076446176;5873.3245676746865;5883.617564858356;5944;5951.315598706205;7398.297161769623;7486.4759466836795;7493.550386976799;7547.5848828314965;7568.281013303727;7073.045138906182;7078.575285482888;7095.716171353294;7161.606111663908;7166.713331466683;7325.4872158948365;7470.663820128216;7474.551526908403;7550.207228240955;7559.659815308214;6960.895586566105;6988.196357418962;6997.510174287751;7000.089988301488;7005.159380874327;7041.5422997505;7104.751333703295;7138;7179.317964793337;7195.352418282379;6659.667016649245;6742.494312926452;6820.590764554143;6905.482088843457;6911.239763626098;6763.323667633197;6895.034695142785;6895.310468953065;6897.41371983387;6907.551009184588;6659.667016649051;6671.933280667326;6724.663766811737;6762.86474270502;6776.932230677828;6883.655817209019;6947.7220911162985;6952.165740111219;6952.30476952321;6957.269486704025;6672.065910772399;6745.4266387355665;6751.831204219934;6754.932450675627;6755.898661520179 09:21:16 INFO - PID 4049 | |1;ext.html;974.8440249560072;1004.0411407106214;1029.3823705776533;1029.9176065914728;1033.7622346860226;45287.54712452875;46483.07033859321;46486.767566162205;46655.30017049739;46764.53235467644;42800.78599607005;42960.78519607405;43053.35419968695;43122;43151.13697726052;8173.550454725003;8258.174182581724;8263.586820658973;8263.876041859347;8299.29455996241;8094.595270236465;8166.469179503343;8208.302294304991;8211.38414618903;8278.172182781735;8260.628271727786;8837.646494140226;9074.092590740906;9085.364024518287;9136.954315228415;8924.866127008068;9039.774005649846;9061.365704400694;9082.500462474562;9084.40951338161;8833.381663283573;8983.10168983103;8985.640574377017;8993.10068993102;9012.279017678635;42560.3615945758;42696.35955460671;42790;42800.57199427997;42892.3566146508;3931.174453364797;3953.42675312081;3965.6827453803626;3969.186316805048;3969.325214713506;35630.28739425223;35784.28431431383;35839.64160358389;35846.46230306548;35891.28217435637;5657.20799088128;5696.1740547620775;5707.258056452676;5729.656220626775;5806.59353845231;234276;235302;236480;240287;249827;57579;57919.71040144772;58009.709951450815;58081.419185808445;58092.41907580954;59480.405195948355;59914.70042649759;59979.70010149921;60030.69984650049;60053.69973150106;18589.25642974293;18894.338698145773;18907.905460472608;18938.24247030131;19278.421647350602;16073.678526429407;16078.196090195443;16090.919545402197;16114.274857631435;16146.35414583427;16333.101679407746;16358.182090895407;16405.507834764736;16443.177841107896;16489.505314840575;6257.624542527463;6318.652474113968;6320.336364681734;6445.16212892321;6553.049807777846;1098.8516550265772;1107.85044019058;1109.1459576126338;1116.2744216259334;1123.6797512708838;26237.7697895681;26350.736492635213;26606;26840.59739103915;26871.596926046124;1225.4975460061332;1226.5707002549036;1236.5301185549433;1242.2422322383338;1252.8621851596317;13861.306934653428;14080.788788168185;14100.435982560582;14210.502632407815;14226.85773142276;286380;286658;287299;287371;288780;62148.37851621516;62298;62778.37221627816;62795.37204627987;62895.37104628987;1569.4036266218764;1584.8256691763897;1618.4739959513206;1620.311367668736;1620.376155180264;25814.870925646002;26181;26301.34246643849;26353;26436.471270574864;44418.55581444144;44587.77706111578;44648.77675611731;44655;44674;250414;250872;250929;251061;251708;39284.80357598194;45574;45978;46034.76982615065;46292.768536157106;55196;56484.152737708144;56625.71687141538;56745;57061;40554.594454055084;40856;40863;41166;41471.58528414798;39944.60055399409;40844.387334189414;41122;41519.79240103901;41705.165896682505;47517.52482475131;48537.757311213216;48742;48957;49277;39283.607163928;39299;39414.605853941095;39505.604943950195;40384.59615403808;28437.857810710815;28823.85588072046;28859.278518037216;29279.707202927697;29393.85303073471 09:21:16 INFO - PID 4049 | |2;jquery.html;644.8980795603679;645.0000245060795;653.177634400024;655.5512317777711;656.504339223886;23483;24183.274501764954;24380.512389752195;24455.144069957554;26582.468350632975;27612.861935690344;28263;28321;28387.43225135501;28512.144635660912;6848.7260509579555;6969.23338432772;7012.193597736275;7029.402500787439;7034.6834392452465;6826.078479405266;6850.862982740342;6871.587704737707;7074.85850282994;7115.359617634412;7051.330123638272;7064.964675176617;7089.397401220903;7104.893426598605;7109;7118.893216601755;7120.038794762692;7121.750738724171;7123.074000379944;7129;6599.076129341848;6626.271110177877;6635.5023373247195;6643.667816609199;6650.933490665128;31643.050708478553;31652.683473165205;33425.49861752076;33747.32505349879;33753.83123084369;2345.5308938212256;2361.5276944610973;2377.38188071101;2380.571497130516;2383.153980336973;21690.34928952134;21786.237481688066;22119.668204976937;22214.333569992763;22268.777312226834;2558.6801649793774;2571.9099831505996;2595.8053146014126;2613.294410509171;2639.696434909991;167031.16484417501;167240;168565;169587;170103;16629.584260393345;17217.91391043062;17576.648467030587;17580.681448891384;17618.559536011704;27718.5842212367;27770.583441248396;28124;28135;28165.295867603065;11968.401579920968;11970.880291196976;11984.101192410604;12095.697607559705;12205.267683938815;9264.44413335202;11140.108791296676;11171.441427928734;11239.438028098562;11624.593139240085;9016.368854180275;10219.489025548693;10254.846177307347;11724.179307448398;11829.940850295694;2953.704629537029;3056.1290032340676;3080.9075727728205;3118.6494839698494;3123.8906638268004;1082.0856376361962;1086.7391825961713;1102.9448527573588;1107.7950579142885;1110.1895616200059;22085;22108.778912210673;22110.115595376326;22287.777122228894;22301.442463938532;1149.9942500287445;1157.7669781682482;1177.8586569611703;1182.810750279951;1186.3534373766317;12051.51793928233;12295.262284262793;12297.877021229851;12305.876941230652;12311.630651080302;652360;664068;674125;678460;678607;132080;133593.33203334117;134034.32982835022;135097.3245133768;135876;1304.693397051692;1319.551352540141;1325.6752095736413;1337.0840973932886;1339.8124262603048;19435.51411214731;19442;19551.21795128179;19704.113314900576;19778.208871644973;60514;61120.38879611325;61801.690991544754;62957.37042629515;66158.66920665366;77722.6113869427;77970;78636;78705.60647196727;78870.21129788629;13945;14692.41230350774;15772.605684857512;16006;16322.591935201715;24344.391390215387;24346;24482.755172448047;24794.25617231486;24815.751842482066;20146.194152234333;20297.594048119252;20362.18551257933;20392.694109588665;20417.387478375673;16476.42332518356;16905.408310709066;16965.745513817055;17103.48689539316;18046.187921543824;20267.79732202718;20387.59224815525;20602.175912963314;20743;20755.377338679864;15996.280167392262;16129.596760081093;16247.756283655519;16279.51161465158;16320.428784992468;19619.607607848047;19881.900590496956;20032.399028029184;20036.198552057755;20073.498162546053 09:21:16 INFO - PID 4049 | |3;mootools.html;1446.956591302261;1500.7748837674342;1510.5770384292402;1518.6810769738354;1529.8623123918849;16647.583810404733;17364.479065628035;17409.21658525368;17416.738748918775;17435;17103.74344384835;17201.311947522085;17243.31026758928;17253.568660783454;17260.74108888368;2900.8839646414117;2913.9271518212004;2963.7480814130827;2968.7921845470823;2969.881204751807;2918.8248705077763;2936.192547049557;2945.6759756426777;2947.2484516448385;2949.7050294970545;2854.9286267843263;2899.594056832045;2941.279386550298;2942.102658689099;2944.3375240570913;2767.3911739417395;2776.4585905748386;2778.7082356352494;2794.0220922677104;2794.2176190666646;2723.7412445817617;2730.6586676665415;2731.658542682165;2773.875175617082;2867.4121805029918;17397.04316262605;17458.388956386592;17542;17553.385631502962;17561.824381756276;8434.957825210897;8813.030566637664;8817.426867253607;8818.86771698425;8819.250363719091;5330.653507522037;5410.675359478444;5595.524380427669;5598.048331783566;5611.382747897769;1981.6333978214077;1982.0684278389133;1983.3355825798376;2000.8599398042143;2025.8480613954011;31615.52576711351;32001.51997720036;32154.517682234786;32165.356692866244;32203.677963220303;7207.89188162178;7210.423166146696;7403.481756277009;7430.777076687708;7541;21142;21150.47123821886;21150.47123821886;21232;21264.468388290417;8357.164283571594;8610.784730381665;8673.306135509145;8684.522351270742;8686.56567171639;7737.761958086791;7953.2842044216295;7969.442139050211;8002.239787220205;8135.471194372347;6059.151718759377;6296.370362963758;6348.968255158695;6366.5861718989045;6448.419642232222;1648.1017845274114;1670.1064930262494;1778.1109445277361;1823.1431227323137;1838.7058070708622;935.8970513243538;945.0879900895669;954.2652157838426;955.4219697083165;957.5547370472764;7782.533048017137;7810.726624568227;7834.569098699512;7869.095054068741;7922.286994170552;1045.3936716704293;1087.9129669626411;1092.918031147667;1093.8085834978847;1123.5786580032488;4984.601231901439;5054.19132938728;5095.821646242438;5128.358955130609;5176.559992400688;25413;26556.468870622866;26841.328966775596;27083.458330833666;27127.593086103723;26215.606765898527;26543.33641658974;26593;26844.463110738066;27074.1877743668;12772.489100435878;13032.41354139066;13157.815796578354;13260.469581216643;13266.46934122663;9348.95325523368;9362.906370936204;9398.859017114612;9432.292578056537;9439.19766819828;22281.888590556944;22418.103275869435;22561.548769024856;22593.983270753186;23060.654090188324;23269.767302327436;23612.409689757893;23911.760882390954;24065.879670601535;24274;13150.21098734079;13152.802707959198;13174.011949104048;13245.735085298047;13395.464181433023;16244;16255.67488650244;16342.10118443497;16360.100194488941;16501.91749041295;11632.185746997968;11702.35637039971;11780.70548236301;11847.881521184678;11906.166568340133;11564.653060408202;11672.47473863695;11794.764104717686;11862.347570883685;11941.343226122646;11125.610603628835;11152.219344645797;11159.72100697489;11185.32888026721;11193.496292666687;7834.255745704149;7838.255365740246;7851.136374998631;7851.685932562634;7883.84232315339;7810.882836757567;7925.841483170419;7995.400344974034;8048.195180481905;8089.716859909875 09:21:16 INFO - PID 4049 | |4;prototype.html;555.3973938276972;584.5966283264546;593.6200831467864;605.3885575568676;609.7317180440606;6276.403741644548;6313.1477250571215;6357.205349331333;6373.617582945015;6377.6173429594255;6175.691215439221;6192.50459963203;6251.249850017993;6268.310485846553;6298.968505157468;5091.643584949055;5128.71792051437;5136.922946155811;5138.434772175057;5140.614453915971;4900.901981960359;4932.827351042714;5060.418051924039;5134.358205224347;5165.1219292720325;4954.3063971044085;5023.76917655175;5023.824166154185;5046.899062018776;5060.266261392096;4963.975180124076;4965.155923493015;4971.850844474671;4972.975135124338;5020.899582008357;4553.795079221443;4590.196715574761;4596.241620132708;4600.148972440109;4605.700629459075;6200.844978875564;6273.592216505958;6300.653464059475;6318.74725010999;6352.110704501373;5977.2229610150835;5986.4612184903135;6012.75948962041;6016.217891674054;6016.826718789837;6762.797116086525;6784.626845523494;6785.491088168407;6800.863982720367;6805.9659701701175;5185.896282074375;5192.55863251624;5199.14214154664;5199.324087868553;5203.011427828738;6916.167215687847;6976.93023069773;7064;7092.538984966012;7174.3454921678795;5314.441983591744;5322.042032434199;5325.174597937326;5333;5340.946590534123;5338.092524270884;5380.650257733235;5385.3268341457315;5417.214503896913;5452.236686863842;5865.794697185578;5919.171316015675;5919.792807251812;5962.25471816023;5980.491658209101;6809.965950170316;6832.624205668736;6845.828854278583;6856.24581296057;6858.6570671467225;1846.4645252876785;1850;1872.9906350468161;1883.4067268810281;1889.8866068035961;1298.3443361102584;1325.3903204526025;1336.472093523053;1349.2713934475273;1362.557168920105;6339.683015849189;6369.617822930639;6428.614283143026;6496.415322620892;6520.641364724986;6719.395254427125;6727.327267273234;6800.761973330909;6847.726090956406;6934.133233345832;6440.677966101676;6468.8059358219325;6481.546291759626;6524.510661700297;6570.901436478457;3648.179159689049;3660.4143337065943;3661.487391765155;3676.540432445944;3699.8890033299044;7322.524035937647;7325.340719335285;7326.706931722778;7362.153352364547;7372.96313518429;6985.89521157183;7004.509684322048;7084.858302834017;7089.53359383585;7126.35862772353;7160.281532432283;7175.748848790267;7242;7284.5430913817445;7287.870380091094;6750.088738020305;6825.146856642919;6866.793996180123;6882.279430142465;6896.0691006518255;6725.495587830835;6785.185777706707;6791.864162716619;6806.217285012287;6831;7204;7215.206327304098;7219.278072192738;7254.782356529101;7261.346478816932;5209.7395130244095;5274.446183150714;5308;5323.254744335719;5349.625526213128;6717.193936727624;6907.758228461979;6942.444604431735;6960.269171736894;7007.894881576678;6211.3478084802255;6562.704678289393;6591.307912669101;6618.286502533938;6664.133662623828;6590.637514936533;6597.284705976576;6626.111558205888;6690.431313338421;6693.899591506034;6626.602403855784;6646.2356828965285;6694.945316286173;6718.563293385914;6765.830854228684;6720.1263835703;6740.561863478858;6784.388010616047;6785.185777706707;6818.249992500921;6335.936640633535;6445.4199122079235;6516.674166291572;6522.5760325578685;6523.3150519194805 09:21:16 INFO - PID 4049 | |5;yui.html;1014.2241185493097;1022.1924679503193;1028.0952761569818;1032.251617577257;1033.6950599573124;20257.797422025793;20460.28389006385;20754.48113797154;20796.792032079673;20899.164033438665;17090.572735681613;17128.657426851456;17173.141342932864;17173.484795456126;17176;1077.6066735641473;1085.012638498966;1085.1644233939862;1086.8424078508613;1094.03178187304;1060.76132870104;1094.720846184222;1108.927919685222;1109.2235435195355;1109.3898355904262;1174.1956759619688;1180.433391971854;1181.308934273451;1184.97630047399;1194.1640851404009;1170.5259369955181;1174.0431548288202;1174.2778191412262;1176.0180249491684;1183.556166437586;857.9785505362354;858.4720396955889;859.2052351574769;863.9481631102154;869.699953516036;19377.806221937743;19754.80245197544;19913.30303439387;20054.699179512318;20065.29771457992;21895.452613684625;22251.777482225294;22311.776882231134;22312;22652.886735566382;16228.51314460568;16267.186640668038;16434.75347869783;16444.34222631105;16449.917750411172;3928.764274143532;3991.7804520751347;3996.0609256824914;4027.677785777131;4027.8590249341423;155822;159402.20298898433;160600;161712;161792;16883.24025418859;17132.486025419257;17236.913815431093;17299.13504324804;17349.306027759;35111;35171.4724279136;35387.469187962204;35657.46513802295;36136;8433.957830210933;8540.487570745776;8550.828983420297;8560.657573697106;8575.914240857637;4530.501644819067;4562.064776720765;4617.607503362252;4631.258998560214;4633.606143477775;6167.074938759222;6275.34108918557;6280.5603607747935;6313.147725057151;6317.305096439388;1038.081298051215;1085.945702714861;1090.0625462102585;1099.9065079468176;1103.089950790585;762.8893810397461;778.1051790441038;782.7143092771098;791.5290402210791;799.2367289238763;12901.419436125398;13062.869371306355;13224.140430871963;14032;14119.85880141206;753.4386881773114;758.7116895579644;766.375404045701;772.0966469231013;777.1723114882632;6096.329403765582;6139.815805525842;6168.691565421711;6222.62664240147;6225.595336303126;140617;144702;145610;145936.27031864773;149834.25082874516;126666;128865;129160;129750;130083.34958325147;115961.4201928985;116493;117380.41309793397;117452;121151.39424302822;11764.70588235301;11777.234479758788;11878.168528202943;11941.701457463632;12150;55538.444615554945;55585;56336.43663563476;56597.71701141468;57458;69108.6544567274;69672.30327696861;70062.54781082638;71381.64309178421;72442.27557724355;15824.683506329578;16015.8398416019;16035.198240087715;16155.838441615904;16172.433964811175;19750.80249197547;19841.503962401057;19863.801361986196;20055.29806456767;20062.699059513827;16472.42346517866;16500;16611.335546578;16673;16735.74896376531;16243.918780406495;16524.09117498549;16549;16607.66784664276;16721.74917376216;17258.568535786704;17270.827291727426;17325.22036508335;17556.91221543935;17558.648827023644;14002.719945601235;14006.509772157926;14141;14285.57143285703;14306.268859535081;13938.372773225432;13961.581152565439;14018.929905350407;14042.227677477837;14092.718145636825 09:21:16 INFO - PID 4049 | __end_tp_report 09:21:16 INFO - PID 4049 | __start_cc_report 09:21:16 INFO - PID 4049 | _x_x_mozilla_cycle_collect,239 09:21:16 INFO - PID 4049 | __end_cc_report 09:21:16 INFO - PID 4049 | __startTimestamp1506356476320__endTimestamp 09:21:16 INFO - PID 4049 | 09:21:16 INFO - PID 4049 | ------- Summary: start ------- 09:21:16 INFO - PID 4049 | Number of tests: 6 09:21:16 INFO - PID 4049 | 09:21:16 INFO - PID 4049 | [#0] dojo.html Cycles:165 Average:6503.13 Median:6755.90 stddev:800.30 (11.8%) stddev-sans-first:776.58 09:21:16 INFO - PID 4049 | Values: 3915.3 3946.0 3948.1 3954.3 3960.7 6376.1 6426.8 6480.5 6520.6 6550.5 6437.9 6451.3 6509.7 6513.7 6540.9 5653.1 5715.9 5792.1 5796.3 5805.5 5700.6 5713.5 5737.8 5742.9 5748.9 5702.1 5753.5 5781.5 5862.4 5877.4 5806.7 5819.3 5862.2 5912.2 5912.9 5732.7 5784.4 5800.9 5813.4 5866.9 6304.8 6384.9 6397.9 6412.0 6452.2 7020.4 7067.6 7081.3 7094.1 7110.0 7009.2 7034.5 7057.1 7101.6 7131.5 6421.0 6463.6 6502.0 6519.0 6531.9 7097.8 7183.9 7186.7 7272.6 7287.7 6944.8 6948.1 7027.1 7028.6 7029.7 7092.9 7098.4 7163.5 7174.8 7179.9 6801.8 6932.9 6967.2 6991.7 7099.0 6911.6 6938.3 6939.5 6966.3 6983.6 5079.2 5130.8 5136.8 5148.4 5152.0 4621.8 4658.5 4662.8 4673.6 4698.9 6603.8 6626.9 6676.8 6677.6 6707.9 7118.0 7146.1 7151.2 7152.6 7158.6 6602.0 6677.1 6715.0 6787.3 6814.6 5784.7 5873.3 5883.6 5944.0 5951.3 7398.3 7486.5 7493.6 7547.6 7568.3 7073.0 7078.6 7095.7 7161.6 7166.7 7325.5 7470.7 7474.6 7550.2 7559.7 6960.9 6988.2 6997.5 7000.1 7005.2 7041.5 7104.8 7138.0 7179.3 7195.4 6659.7 6742.5 6820.6 6905.5 6911.2 6763.3 6895.0 6895.3 6897.4 6907.6 6659.7 6671.9 6724.7 6762.9 6776.9 6883.7 6947.7 6952.2 6952.3 6957.3 6672.1 6745.4 6751.8 6754.9 6755.9 09:21:16 INFO - PID 4049 | 09:21:16 INFO - PID 4049 | [#1] ext.html Cycles:180 Average:45925.28 Median:41144.00 stddev:67496.80 (164.1%) stddev-sans-first:67601.76 09:21:16 INFO - PID 4049 | Values: 974.8 1004.0 1029.4 1029.9 1033.8 45287.5 46483.1 46486.8 46655.3 46764.5 42800.8 42960.8 43053.4 43122.0 43151.1 8173.6 8258.2 8263.6 8263.9 8299.3 8094.6 8166.5 8208.3 8211.4 8278.2 8260.6 8837.6 9074.1 9085.4 9137.0 8924.9 9039.8 9061.4 9082.5 9084.4 8833.4 8983.1 8985.6 8993.1 9012.3 42560.4 42696.4 42790.0 42800.6 42892.4 3931.2 3953.4 3965.7 3969.2 3969.3 35630.3 35784.3 35839.6 35846.5 35891.3 5657.2 5696.2 5707.3 5729.7 5806.6 234276.0 235302.0 236480.0 240287.0 249827.0 57579.0 57919.7 58009.7 58081.4 58092.4 59480.4 59914.7 59979.7 60030.7 60053.7 18589.3 18894.3 18907.9 18938.2 19278.4 16073.7 16078.2 16090.9 16114.3 16146.4 16333.1 16358.2 16405.5 16443.2 16489.5 6257.6 6318.7 6320.3 6445.2 6553.0 1098.9 1107.9 1109.1 1116.3 1123.7 26237.8 26350.7 26606.0 26840.6 26871.6 1225.5 1226.6 1236.5 1242.2 1252.9 13861.3 14080.8 14100.4 14210.5 14226.9 286380.0 286658.0 287299.0 287371.0 288780.0 62148.4 62298.0 62778.4 62795.4 62895.4 1569.4 1584.8 1618.5 1620.3 1620.4 25814.9 26181.0 26301.3 26353.0 26436.5 44418.6 44587.8 44648.8 44655.0 44674.0 250414.0 250872.0 250929.0 251061.0 251708.0 39284.8 45574.0 45978.0 46034.8 46292.8 55196.0 56484.2 56625.7 56745.0 57061.0 40554.6 40856.0 40863.0 41166.0 41471.6 39944.6 40844.4 41122.0 41519.8 41705.2 47517.5 48537.8 48742.0 48957.0 49277.0 39283.6 39299.0 39414.6 39505.6 40384.6 28437.9 28823.9 28859.3 29279.7 29393.9 09:21:16 INFO - PID 4049 | 09:21:16 INFO - PID 4049 | [#2] jquery.html Cycles:180 Average:42629.42 Median:22278.28 stddev:111833.08 (502.0%) stddev-sans-first:112102.37 09:21:16 INFO - PID 4049 | Values: 644.9 645.0 653.2 655.6 656.5 23483.0 24183.3 24380.5 24455.1 26582.5 27612.9 28263.0 28321.0 28387.4 28512.1 6848.7 6969.2 7012.2 7029.4 7034.7 6826.1 6850.9 6871.6 7074.9 7115.4 7051.3 7065.0 7089.4 7104.9 7109.0 7118.9 7120.0 7121.8 7123.1 7129.0 6599.1 6626.3 6635.5 6643.7 6650.9 31643.1 31652.7 33425.5 33747.3 33753.8 2345.5 2361.5 2377.4 2380.6 2383.2 21690.3 21786.2 22119.7 22214.3 22268.8 2558.7 2571.9 2595.8 2613.3 2639.7 167031.2 167240.0 168565.0 169587.0 170103.0 16629.6 17217.9 17576.6 17580.7 17618.6 27718.6 27770.6 28124.0 28135.0 28165.3 11968.4 11970.9 11984.1 12095.7 12205.3 9264.4 11140.1 11171.4 11239.4 11624.6 9016.4 10219.5 10254.8 11724.2 11829.9 2953.7 3056.1 3080.9 3118.6 3123.9 1082.1 1086.7 1102.9 1107.8 1110.2 22085.0 22108.8 22110.1 22287.8 22301.4 1150.0 1157.8 1177.9 1182.8 1186.4 12051.5 12295.3 12297.9 12305.9 12311.6 652360.0 664068.0 674125.0 678460.0 678607.0 132080.0 133593.3 134034.3 135097.3 135876.0 1304.7 1319.6 1325.7 1337.1 1339.8 19435.5 19442.0 19551.2 19704.1 19778.2 60514.0 61120.4 61801.7 62957.4 66158.7 77722.6 77970.0 78636.0 78705.6 78870.2 13945.0 14692.4 15772.6 16006.0 16322.6 24344.4 24346.0 24482.8 24794.3 24815.8 20146.2 20297.6 20362.2 20392.7 20417.4 16476.4 16905.4 16965.7 17103.5 18046.2 20267.8 20387.6 20602.2 20743.0 20755.4 15996.3 16129.6 16247.8 16279.5 16320.4 19619.6 19881.9 20032.4 20036.2 20073.5 09:21:16 INFO - PID 4049 | 09:21:16 INFO - PID 4049 | [#3] mootools.html Cycles:180 Average:10735.63 Median:2727.20 stddev:8238.06 (302.1%) stddev-sans-first:8231.62 09:21:16 INFO - PID 4049 | Values: 1447.0 1500.8 1510.6 1518.7 1529.9 16647.6 17364.5 17409.2 17416.7 17435.0 17103.7 17201.3 17243.3 17253.6 17260.7 2900.9 2913.9 2963.7 2968.8 2969.9 2918.8 2936.2 2945.7 2947.2 2949.7 2854.9 2899.6 2941.3 2942.1 2944.3 2767.4 2776.5 2778.7 2794.0 2794.2 2723.7 2730.7 2731.7 2773.9 2867.4 17397.0 17458.4 17542.0 17553.4 17561.8 8435.0 8813.0 8817.4 8818.9 8819.3 5330.7 5410.7 5595.5 5598.0 5611.4 1981.6 1982.1 1983.3 2000.9 2025.8 31615.5 32001.5 32154.5 32165.4 32203.7 7207.9 7210.4 7403.5 7430.8 7541.0 21142.0 21150.5 21150.5 21232.0 21264.5 8357.2 8610.8 8673.3 8684.5 8686.6 7737.8 7953.3 7969.4 8002.2 8135.5 6059.2 6296.4 6349.0 6366.6 6448.4 1648.1 1670.1 1778.1 1823.1 1838.7 935.9 945.1 954.3 955.4 957.6 7782.5 7810.7 7834.6 7869.1 7922.3 1045.4 1087.9 1092.9 1093.8 1123.6 4984.6 5054.2 5095.8 5128.4 5176.6 25413.0 26556.5 26841.3 27083.5 27127.6 26215.6 26543.3 26593.0 26844.5 27074.2 12772.5 13032.4 13157.8 13260.5 13266.5 9349.0 9362.9 9398.9 9432.3 9439.2 22281.9 22418.1 22561.5 22594.0 23060.7 23269.8 23612.4 23911.8 24065.9 24274.0 13150.2 13152.8 13174.0 13245.7 13395.5 16244.0 16255.7 16342.1 16360.1 16501.9 11632.2 11702.4 11780.7 11847.9 11906.2 11564.7 11672.5 11794.8 11862.3 11941.3 11125.6 11152.2 11159.7 11185.3 11193.5 7834.3 7838.3 7851.1 7851.7 7883.8 7810.9 7925.8 7995.4 8048.2 8089.7 09:21:16 INFO - PID 4049 | 09:21:16 INFO - PID 4049 | [#4] prototype.html Cycles:180 Average:5753.68 Median:6363.41 stddev:1611.32 (25.3%) stddev-sans-first:1567.89 09:21:16 INFO - PID 4049 | Values: 555.4 584.6 593.6 605.4 609.7 6276.4 6313.1 6357.2 6373.6 6377.6 6175.7 6192.5 6251.2 6268.3 6299.0 5091.6 5128.7 5136.9 5138.4 5140.6 4900.9 4932.8 5060.4 5134.4 5165.1 4954.3 5023.8 5023.8 5046.9 5060.3 4964.0 4965.2 4971.9 4973.0 5020.9 4553.8 4590.2 4596.2 4600.1 4605.7 6200.8 6273.6 6300.7 6318.7 6352.1 5977.2 5986.5 6012.8 6016.2 6016.8 6762.8 6784.6 6785.5 6800.9 6806.0 5185.9 5192.6 5199.1 5199.3 5203.0 6916.2 6976.9 7064.0 7092.5 7174.3 5314.4 5322.0 5325.2 5333.0 5340.9 5338.1 5380.7 5385.3 5417.2 5452.2 5865.8 5919.2 5919.8 5962.3 5980.5 6810.0 6832.6 6845.8 6856.2 6858.7 1846.5 1850.0 1873.0 1883.4 1889.9 1298.3 1325.4 1336.5 1349.3 1362.6 6339.7 6369.6 6428.6 6496.4 6520.6 6719.4 6727.3 6800.8 6847.7 6934.1 6440.7 6468.8 6481.5 6524.5 6570.9 3648.2 3660.4 3661.5 3676.5 3699.9 7322.5 7325.3 7326.7 7362.2 7373.0 6985.9 7004.5 7084.9 7089.5 7126.4 7160.3 7175.7 7242.0 7284.5 7287.9 6750.1 6825.1 6866.8 6882.3 6896.1 6725.5 6785.2 6791.9 6806.2 6831.0 7204.0 7215.2 7219.3 7254.8 7261.3 5209.7 5274.4 5308.0 5323.3 5349.6 6717.2 6907.8 6942.4 6960.3 7007.9 6211.3 6562.7 6591.3 6618.3 6664.1 6590.6 6597.3 6626.1 6690.4 6693.9 6626.6 6646.2 6694.9 6718.6 6765.8 6720.1 6740.6 6784.4 6785.2 6818.2 6335.9 6445.4 6516.7 6522.6 6523.3 09:21:16 INFO - PID 4049 | 09:21:16 INFO - PID 4049 | [#5] yui.html Cycles:180 Average:27950.10 Median:16986.91 stddev:41964.30 (247.0%) stddev-sans-first:42033.28 09:21:16 INFO - PID 4049 | Values: 1014.2 1022.2 1028.1 1032.3 1033.7 20257.8 20460.3 20754.5 20796.8 20899.2 17090.6 17128.7 17173.1 17173.5 17176.0 1077.6 1085.0 1085.2 1086.8 1094.0 1060.8 1094.7 1108.9 1109.2 1109.4 1174.2 1180.4 1181.3 1185.0 1194.2 1170.5 1174.0 1174.3 1176.0 1183.6 858.0 858.5 859.2 863.9 869.7 19377.8 19754.8 19913.3 20054.7 20065.3 21895.5 22251.8 22311.8 22312.0 22652.9 16228.5 16267.2 16434.8 16444.3 16449.9 3928.8 3991.8 3996.1 4027.7 4027.9 155822.0 159402.2 160600.0 161712.0 161792.0 16883.2 17132.5 17236.9 17299.1 17349.3 35111.0 35171.5 35387.5 35657.5 36136.0 8434.0 8540.5 8550.8 8560.7 8575.9 4530.5 4562.1 4617.6 4631.3 4633.6 6167.1 6275.3 6280.6 6313.1 6317.3 1038.1 1085.9 1090.1 1099.9 1103.1 762.9 778.1 782.7 791.5 799.2 12901.4 13062.9 13224.1 14032.0 14119.9 753.4 758.7 766.4 772.1 777.2 6096.3 6139.8 6168.7 6222.6 6225.6 140617.0 144702.0 145610.0 145936.3 149834.3 126666.0 128865.0 129160.0 129750.0 130083.3 115961.4 116493.0 117380.4 117452.0 121151.4 11764.7 11777.2 11878.2 11941.7 12150.0 55538.4 55585.0 56336.4 56597.7 57458.0 69108.7 69672.3 70062.5 71381.6 72442.3 15824.7 16015.8 16035.2 16155.8 16172.4 19750.8 19841.5 19863.8 20055.3 20062.7 16472.4 16500.0 16611.3 16673.0 16735.7 16243.9 16524.1 16549.0 16607.7 16721.7 17258.6 17270.8 17325.2 17556.9 17558.6 14002.7 14006.5 14141.0 14285.6 14306.3 13938.4 13961.6 14018.9 14042.2 14092.7 09:21:16 INFO - PID 4049 | -------- Summary: end -------- 09:21:16 INFO - PID 4049 | 09:21:16 INFO - TEST-INFO | 4049: exit 0 09:21:16 INFO - TEST-OK | dromaeo_css | took 1106626ms 09:21:16 INFO - TEST-START | kraken 09:21:16 INFO - Initialising browser for kraken test... 09:21:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48668/getInfo.html -profile /tmp/tmp_Xecfg/profile 09:21:16 INFO - TEST-INFO | started process 4420 (/builds/slave/test/build/application/firefox/firefox http://localhost:48668/getInfo.html) 09:21:21 INFO - TEST-INFO | 4420: exit 0 09:21:21 INFO - Browser initialized. 09:21:21 INFO - Running cycle 1/1 for kraken test... 09:21:21 INFO - Using env: {'DISPLAY': ':0', 09:21:21 INFO - 'HOME': '/home/cltbld', 09:21:21 INFO - 'JSGC_DISABLE_POISONING': '1', 09:21:21 INFO - 'LANG': 'en_US.UTF-8', 09:21:21 INFO - 'LANGUAGE': 'en_US:en', 09:21:21 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:21:21 INFO - 'LOGNAME': 'cltbld', 09:21:21 INFO - 'MAIL': '/var/mail/cltbld', 09:21:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:21:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:21:21 INFO - 'MOZ_CRASHREPORTER': '1', 09:21:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:21 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:21:21 INFO - 'MOZ_NO_REMOTE': '1', 09:21:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:21:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:21 INFO - 'NO_EM_RESTART': '1', 09:21:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:21 INFO - 'PWD': '/builds/slave/test', 09:21:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:21:21 INFO - 'RUST_BACKTRACE': 'full', 09:21:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:21:21 INFO - 'SHELL': '/bin/bash', 09:21:21 INFO - 'SHLVL': '1', 09:21:21 INFO - 'TERM': 'linux', 09:21:21 INFO - 'TMOUT': '86400', 09:21:21 INFO - 'USER': 'cltbld', 09:21:21 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503', 09:21:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:21 INFO - '_': '/tools/buildbot/bin/python'} 09:21:21 INFO - TEST-INFO | started process 4614 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_Xecfg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:21:22 INFO - PID 4614 | 09:21:22 INFO - PID 4614 | (/builds/slave/test/build/application/firefox/firefox:4663): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:21:22 INFO - PID 4614 | 09:21:33 INFO - PID 4614 | RSS: Main: 141586432 09:21:33 INFO - PID 4614 | 09:21:35 INFO - PID 4614 | Cycle 1(1): loaded http://localhost:48668/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48668/tests/kraken/driver.html?testName=audio-beat-detection) 09:21:36 INFO - PID 4614 | RSS: Main: 148639744 09:21:36 INFO - PID 4614 | 09:21:38 INFO - PID 4614 | Cycle 1(1): loaded http://localhost:48668/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48668/tests/kraken/driver.html?testName=audio-dft) 09:21:39 INFO - PID 4614 | RSS: Main: 149106688 09:21:39 INFO - PID 4614 | 09:21:42 INFO - PID 4614 | Cycle 1(1): loaded http://localhost:48668/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48668/tests/kraken/driver.html?testName=audio-fft) 09:21:43 INFO - PID 4614 | RSS: Main: 150564864 09:21:43 INFO - PID 4614 | 09:21:45 INFO - PID 4614 | Cycle 1(1): loaded http://localhost:48668/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48668/tests/kraken/driver.html?testName=audio-oscillator) 09:21:45 INFO - PID 4614 | RSS: Main: 150708224 09:21:45 INFO - PID 4614 | 09:21:47 INFO - PID 4614 | Cycle 1(1): loaded http://localhost:48668/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48668/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:21:48 INFO - PID 4614 | RSS: Main: 150908928 09:21:48 INFO - PID 4614 | 09:21:51 INFO - PID 4614 | Cycle 1(1): loaded http://localhost:48668/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48668/tests/kraken/driver.html?testName=imaging-darkroom) 09:21:52 INFO - PID 4614 | RSS: Main: 150421504 09:21:52 INFO - PID 4614 | 09:21:55 INFO - PID 4614 | Cycle 1(1): loaded http://localhost:48668/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48668/tests/kraken/driver.html?testName=imaging-desaturate) 09:21:56 INFO - PID 4614 | RSS: Main: 151867392 09:21:56 INFO - PID 4614 | 09:21:58 INFO - PID 4614 | Cycle 1(1): loaded http://localhost:48668/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48668/tests/kraken/driver.html?testName=json-parse-financial) 09:21:59 INFO - PID 4614 | RSS: Main: 152793088 09:21:59 INFO - PID 4614 | 09:22:01 INFO - PID 4614 | Cycle 1(1): loaded http://localhost:48668/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48668/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:22:01 INFO - PID 4614 | RSS: Main: 152842240 09:22:01 INFO - PID 4614 | 09:22:03 INFO - PID 4614 | Cycle 1(1): loaded http://localhost:48668/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48668/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:22:03 INFO - PID 4614 | RSS: Main: 152911872 09:22:03 INFO - PID 4614 | 09:22:05 INFO - PID 4614 | Cycle 1(1): loaded http://localhost:48668/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48668/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:22:06 INFO - PID 4614 | RSS: Main: 149471232 09:22:06 INFO - PID 4614 | 09:22:08 INFO - PID 4614 | Cycle 1(1): loaded http://localhost:48668/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48668/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:22:09 INFO - PID 4614 | RSS: Main: 149823488 09:22:09 INFO - PID 4614 | 09:22:12 INFO - PID 4614 | Cycle 1(1): loaded http://localhost:48668/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48668/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:22:12 INFO - PID 4614 | RSS: Main: 149815296 09:22:12 INFO - PID 4614 | 09:22:14 INFO - PID 4614 | Cycle 1(1): loaded http://localhost:48668/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48668/tests/kraken/driver.html?testName=ai-astar) 09:22:14 INFO - PID 4614 | RSS: Main: 151842816 09:22:14 INFO - PID 4614 | 09:22:14 INFO - PID 4614 | __start_tp_report 09:22:14 INFO - PID 4614 | _x_x_mozilla_page_load 09:22:14 INFO - PID 4614 | _x_x_mozilla_page_load_details 09:22:14 INFO - PID 4614 | |i|pagename|runs| 09:22:14 INFO - PID 4614 | |0;ai-astar;106;104;104;104;104;107;107;106;105;105 09:22:14 INFO - PID 4614 | |1;audio-beat-detection;114;114;114;113;114;110;111;110;113;115 09:22:14 INFO - PID 4614 | |2;audio-dft;163;162;165;162;162;162;163;161;165;162 09:22:14 INFO - PID 4614 | |3;audio-fft;74;74;73;73;72;73;73;75;73;73 09:22:14 INFO - PID 4614 | |4;audio-oscillator;78;77;77;78;78;77;77;78;77;77 09:22:14 INFO - PID 4614 | |5;imaging-gaussian-blur;114;114;114;113;116;115;113;115;114;114 09:22:14 INFO - PID 4614 | |6;imaging-darkroom;147;148;147;148;149;148;149;149;148;148 09:22:14 INFO - PID 4614 | |7;imaging-desaturate;94;94;98;94;94;95;94;94;94;94 09:22:14 INFO - PID 4614 | |8;json-parse-financial;44;43;43;45;47;44;45;44;47;44 09:22:14 INFO - PID 4614 | |9;json-stringify-tinderbox;38;38;40;38;38;38;38;37;38;37 09:22:14 INFO - PID 4614 | |10;stanford-crypto-aes;70;69;71;72;69;70;71;74;70;69 09:22:14 INFO - PID 4614 | |11;stanford-crypto-ccm;105;101;104;103;114;106;101;107;114;105 09:22:14 INFO - PID 4614 | |12;stanford-crypto-pbkdf2;147;147;154;156;148;151;147;155;153;153 09:22:14 INFO - PID 4614 | |13;stanford-crypto-sha256-iterative;58;57;58;62;60;58;57;58;62;57 09:22:14 INFO - PID 4614 | __end_tp_report 09:22:14 INFO - PID 4614 | __start_cc_report 09:22:14 INFO - PID 4614 | _x_x_mozilla_cycle_collect,704 09:22:14 INFO - PID 4614 | __end_cc_report 09:22:14 INFO - PID 4614 | __startTimestamp1506356534663__endTimestamp 09:22:14 INFO - PID 4614 | 09:22:14 INFO - PID 4614 | ------- Summary: start ------- 09:22:14 INFO - PID 4614 | Number of tests: 14 09:22:14 INFO - PID 4614 | 09:22:14 INFO - PID 4614 | [#0] ai-astar Cycles:10 Average:105.20 Median:105.50 stddev:1.23 (1.2%) stddev-sans-first:1.27 09:22:14 INFO - PID 4614 | Values: 106.0 104.0 104.0 104.0 104.0 107.0 107.0 106.0 105.0 105.0 09:22:14 INFO - PID 4614 | 09:22:14 INFO - PID 4614 | [#1] audio-beat-detection Cycles:10 Average:112.80 Median:114.00 stddev:1.81 (1.6%) stddev-sans-first:1.87 09:22:14 INFO - PID 4614 | Values: 114.0 114.0 114.0 113.0 114.0 110.0 111.0 110.0 113.0 115.0 09:22:14 INFO - PID 4614 | 09:22:14 INFO - PID 4614 | [#2] audio-dft Cycles:10 Average:162.70 Median:162.50 stddev:1.34 (0.8%) stddev-sans-first:1.41 09:22:14 INFO - PID 4614 | Values: 163.0 162.0 165.0 162.0 162.0 162.0 163.0 161.0 165.0 162.0 09:22:14 INFO - PID 4614 | 09:22:14 INFO - PID 4614 | [#3] audio-fft Cycles:10 Average:73.30 Median:73.00 stddev:0.82 (1.1%) stddev-sans-first:0.83 09:22:14 INFO - PID 4614 | Values: 74.0 74.0 73.0 73.0 72.0 73.0 73.0 75.0 73.0 73.0 09:22:14 INFO - PID 4614 | 09:22:14 INFO - PID 4614 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:77.50 stddev:0.52 (0.7%) stddev-sans-first:0.50 09:22:14 INFO - PID 4614 | Values: 78.0 77.0 77.0 78.0 78.0 77.0 77.0 78.0 77.0 77.0 09:22:14 INFO - PID 4614 | 09:22:14 INFO - PID 4614 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:0.92 (0.8%) stddev-sans-first:0.97 09:22:14 INFO - PID 4614 | Values: 114.0 114.0 114.0 113.0 116.0 115.0 113.0 115.0 114.0 114.0 09:22:14 INFO - PID 4614 | 09:22:14 INFO - PID 4614 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 09:22:14 INFO - PID 4614 | Values: 147.0 148.0 147.0 148.0 149.0 148.0 149.0 149.0 148.0 148.0 09:22:14 INFO - PID 4614 | 09:22:14 INFO - PID 4614 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.00 stddev:1.27 (1.4%) stddev-sans-first:1.33 09:22:14 INFO - PID 4614 | Values: 94.0 94.0 98.0 94.0 94.0 95.0 94.0 94.0 94.0 94.0 09:22:14 INFO - PID 4614 | 09:22:14 INFO - PID 4614 | [#8] json-parse-financial Cycles:10 Average:44.60 Median:44.50 stddev:1.43 (3.2%) stddev-sans-first:1.50 09:22:14 INFO - PID 4614 | Values: 44.0 43.0 43.0 45.0 47.0 44.0 45.0 44.0 47.0 44.0 09:22:14 INFO - PID 4614 | 09:22:14 INFO - PID 4614 | [#9] json-stringify-tinderbox Cycles:10 Average:38.00 Median:38.00 stddev:0.82 (2.1%) stddev-sans-first:0.87 09:22:14 INFO - PID 4614 | Values: 38.0 38.0 40.0 38.0 38.0 38.0 38.0 37.0 38.0 37.0 09:22:14 INFO - PID 4614 | 09:22:14 INFO - PID 4614 | [#10] stanford-crypto-aes Cycles:10 Average:70.50 Median:70.50 stddev:1.58 (2.2%) stddev-sans-first:1.67 09:22:14 INFO - PID 4614 | Values: 70.0 69.0 71.0 72.0 69.0 70.0 71.0 74.0 70.0 69.0 09:22:14 INFO - PID 4614 | 09:22:14 INFO - PID 4614 | [#11] stanford-crypto-ccm Cycles:10 Average:106.00 Median:105.50 stddev:4.64 (4.4%) stddev-sans-first:4.91 09:22:14 INFO - PID 4614 | Values: 105.0 101.0 104.0 103.0 114.0 106.0 101.0 107.0 114.0 105.0 09:22:14 INFO - PID 4614 | 09:22:14 INFO - PID 4614 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.10 Median:153.00 stddev:3.57 (2.3%) stddev-sans-first:3.47 09:22:14 INFO - PID 4614 | Values: 147.0 147.0 154.0 156.0 148.0 151.0 147.0 155.0 153.0 153.0 09:22:14 INFO - PID 4614 | 09:22:14 INFO - PID 4614 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:58.70 Median:58.00 stddev:1.95 (3.4%) stddev-sans-first:2.05 09:22:14 INFO - PID 4614 | Values: 58.0 57.0 58.0 62.0 60.0 58.0 57.0 58.0 62.0 57.0 09:22:14 INFO - PID 4614 | -------- Summary: end -------- 09:22:14 INFO - PID 4614 | 09:22:14 INFO - PID 4614 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 09:22:15 INFO - TEST-INFO | 4614: exit 0 09:22:15 INFO - TEST-OK | kraken | took 58280ms 09:22:15 INFO - SUITE-END | took 1164s 09:22:15 INFO - Completed test suite (00:19:25) 09:22:15 INFO - javascript benchmark 09:22:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9370.563292536775, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3915.295246855566, 3946.0332218606427, 3948.0722030322886, 3954.327764280073, 3960.6633436157927, 6376.139221205141, 6426.839329016777, 6480.481561475082, 6520.641364724944, 6550.475961923046, 6437.8712425751455, 6451.25810531789, 6509.739610415578, 6513.706883190256, 6540.934590654092, 5653.1237658162845, 5715.885682286352, 5792.102224155242, 5796.275465566804, 5805.5065319447895, 5700.572457065716, 5713.542916566665, 5737.770489180449, 5742.856428589298, 5748.913766293509, 5702.059160238577, 5753.453421924911, 5781.479666829984, 5862.413758624146, 5877.441643043904, 5806.651600903917, 5819.272590926134, 5862.237909071836, 5912.231409916726, 5912.852178695545, 5732.656040637575, 5784.3926387729525, 5800.854978625526, 5813.389594092601, 5866.853328666774, 6304.7793327233785, 6384.936150638527, 6397.9360206397805, 6411.967940160316, 6452.225732912034, 7020.438364930845, 7067.646617669096, 7081.256468070881, 7094.077769889881, 7109.9644501777675, 7009.193942696605, 7034.472414568928, 7057.082579264663, 7101.573905565682, 7131.500794944356, 6421.0, 6463.57986730861, 6501.967490162566, 6518.967405162991, 6531.922232831625, 7097.751578694721, 7183.856322873513, 7186.676599553032, 7272.60000699956, 7287.672054757442, 6944.826379340456, 6948.096747422803, 7027.086478757728, 7028.648567571601, 7029.683664235019, 7092.893606595905, 7098.3611474966465, 7163.498555101093, 7174.820629484305, 7179.8564028720175, 6801.761938332134, 6932.896006559805, 6967.198772141167, 6991.650417479105, 7099.041629380071, 6911.550749201387, 6938.340857618518, 6939.514234003672, 6966.303369662993, 6983.580985140908, 5079.238114282887, 5130.820421285237, 5136.845894623168, 5148.4079330876675, 5151.974240128776, 4621.838235661736, 4658.487566367697, 4662.836800711959, 4673.626109911199, 4698.85903422891, 6603.8349041273395, 6626.900596491057, 6676.833079172962, 6677.565958212701, 6707.899381509281, 7118.039064726299, 7146.142462904485, 7151.249118842551, 7152.570845749272, 7158.642067896584, 6602.0, 6677.131972843499, 6714.966425167843, 6787.321267873272, 6814.591124532544, 5784.71076446176, 5873.3245676746865, 5883.617564858356, 5944.0, 5951.315598706205, 7398.297161769623, 7486.4759466836795, 7493.550386976799, 7547.5848828314965, 7568.281013303727, 7073.045138906182, 7078.575285482888, 7095.716171353294, 7161.606111663908, 7166.713331466683, 7325.4872158948365, 7470.663820128216, 7474.551526908403, 7550.207228240955, 7559.659815308214, 6960.895586566105, 6988.196357418962, 6997.510174287751, 7000.089988301488, 7005.159380874327, 7041.5422997505, 7104.751333703295, 7138.0, 7179.317964793337, 7195.352418282379, 6659.667016649245, 6742.494312926452, 6820.590764554143, 6905.482088843457, 6911.239763626098, 6763.323667633197, 6895.034695142785, 6895.310468953065, 6897.41371983387, 6907.551009184588, 6659.667016649051, 6671.933280667326, 6724.663766811737, 6762.86474270502, 6776.932230677828, 6883.655817209019, 6947.7220911162985, 6952.165740111219, 6952.30476952321, 6957.269486704025, 6672.065910772399, 6745.4266387355665, 6751.831204219934, 6754.932450675627, 6755.898661520179], "value": 6446.66014193636, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [974.8440249560072, 1004.0411407106214, 1029.3823705776533, 1029.9176065914728, 1033.7622346860226, 45287.54712452875, 46483.07033859321, 46486.767566162205, 46655.30017049739, 46764.53235467644, 42800.78599607005, 42960.78519607405, 43053.35419968695, 43122.0, 43151.13697726052, 8173.550454725003, 8258.174182581724, 8263.586820658973, 8263.876041859347, 8299.29455996241, 8094.595270236465, 8166.469179503343, 8208.302294304991, 8211.38414618903, 8278.172182781735, 8260.628271727786, 8837.646494140226, 9074.092590740906, 9085.364024518287, 9136.954315228415, 8924.866127008068, 9039.774005649846, 9061.365704400694, 9082.500462474562, 9084.40951338161, 8833.381663283573, 8983.10168983103, 8985.640574377017, 8993.10068993102, 9012.279017678635, 42560.3615945758, 42696.35955460671, 42790.0, 42800.57199427997, 42892.3566146508, 3931.174453364797, 3953.42675312081, 3965.6827453803626, 3969.186316805048, 3969.325214713506, 35630.28739425223, 35784.28431431383, 35839.64160358389, 35846.46230306548, 35891.28217435637, 5657.20799088128, 5696.1740547620775, 5707.258056452676, 5729.656220626775, 5806.59353845231, 234276.0, 235302.0, 236480.0, 240287.0, 249827.0, 57579.0, 57919.71040144772, 58009.709951450815, 58081.419185808445, 58092.41907580954, 59480.405195948355, 59914.70042649759, 59979.70010149921, 60030.69984650049, 60053.69973150106, 18589.25642974293, 18894.338698145773, 18907.905460472608, 18938.24247030131, 19278.421647350602, 16073.678526429407, 16078.196090195443, 16090.919545402197, 16114.274857631435, 16146.35414583427, 16333.101679407746, 16358.182090895407, 16405.507834764736, 16443.177841107896, 16489.505314840575, 6257.624542527463, 6318.652474113968, 6320.336364681734, 6445.16212892321, 6553.049807777846, 1098.8516550265772, 1107.85044019058, 1109.1459576126338, 1116.2744216259334, 1123.6797512708838, 26237.7697895681, 26350.736492635213, 26606.0, 26840.59739103915, 26871.596926046124, 1225.4975460061332, 1226.5707002549036, 1236.5301185549433, 1242.2422322383338, 1252.8621851596317, 13861.306934653428, 14080.788788168185, 14100.435982560582, 14210.502632407815, 14226.85773142276, 286380.0, 286658.0, 287299.0, 287371.0, 288780.0, 62148.37851621516, 62298.0, 62778.37221627816, 62795.37204627987, 62895.37104628987, 1569.4036266218764, 1584.8256691763897, 1618.4739959513206, 1620.311367668736, 1620.376155180264, 25814.870925646002, 26181.0, 26301.34246643849, 26353.0, 26436.471270574864, 44418.55581444144, 44587.77706111578, 44648.77675611731, 44655.0, 44674.0, 250414.0, 250872.0, 250929.0, 251061.0, 251708.0, 39284.80357598194, 45574.0, 45978.0, 46034.76982615065, 46292.768536157106, 55196.0, 56484.152737708144, 56625.71687141538, 56745.0, 57061.0, 40554.594454055084, 40856.0, 40863.0, 41166.0, 41471.58528414798, 39944.60055399409, 40844.387334189414, 41122.0, 41519.79240103901, 41705.165896682505, 47517.52482475131, 48537.757311213216, 48742.0, 48957.0, 49277.0, 39283.607163928, 39299.0, 39414.605853941095, 39505.604943950195, 40384.59615403808, 28437.857810710815, 28823.85588072046, 28859.278518037216, 29279.707202927697, 29393.85303073471], "value": 20168.026951186384, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [644.8980795603679, 645.0000245060795, 653.177634400024, 655.5512317777711, 656.504339223886, 23483.0, 24183.274501764954, 24380.512389752195, 24455.144069957554, 26582.468350632975, 27612.861935690344, 28263.0, 28321.0, 28387.43225135501, 28512.144635660912, 6848.7260509579555, 6969.23338432772, 7012.193597736275, 7029.402500787439, 7034.6834392452465, 6826.078479405266, 6850.862982740342, 6871.587704737707, 7074.85850282994, 7115.359617634412, 7051.330123638272, 7064.964675176617, 7089.397401220903, 7104.893426598605, 7109.0, 7118.893216601755, 7120.038794762692, 7121.750738724171, 7123.074000379944, 7129.0, 6599.076129341848, 6626.271110177877, 6635.5023373247195, 6643.667816609199, 6650.933490665128, 31643.050708478553, 31652.683473165205, 33425.49861752076, 33747.32505349879, 33753.83123084369, 2345.5308938212256, 2361.5276944610973, 2377.38188071101, 2380.571497130516, 2383.153980336973, 21690.34928952134, 21786.237481688066, 22119.668204976937, 22214.333569992763, 22268.777312226834, 2558.6801649793774, 2571.9099831505996, 2595.8053146014126, 2613.294410509171, 2639.696434909991, 167031.16484417501, 167240.0, 168565.0, 169587.0, 170103.0, 16629.584260393345, 17217.91391043062, 17576.648467030587, 17580.681448891384, 17618.559536011704, 27718.5842212367, 27770.583441248396, 28124.0, 28135.0, 28165.295867603065, 11968.401579920968, 11970.880291196976, 11984.101192410604, 12095.697607559705, 12205.267683938815, 9264.44413335202, 11140.108791296676, 11171.441427928734, 11239.438028098562, 11624.593139240085, 9016.368854180275, 10219.489025548693, 10254.846177307347, 11724.179307448398, 11829.940850295694, 2953.704629537029, 3056.1290032340676, 3080.9075727728205, 3118.6494839698494, 3123.8906638268004, 1082.0856376361962, 1086.7391825961713, 1102.9448527573588, 1107.7950579142885, 1110.1895616200059, 22085.0, 22108.778912210673, 22110.115595376326, 22287.777122228894, 22301.442463938532, 1149.9942500287445, 1157.7669781682482, 1177.8586569611703, 1182.810750279951, 1186.3534373766317, 12051.51793928233, 12295.262284262793, 12297.877021229851, 12305.876941230652, 12311.630651080302, 652360.0, 664068.0, 674125.0, 678460.0, 678607.0, 132080.0, 133593.33203334117, 134034.32982835022, 135097.3245133768, 135876.0, 1304.693397051692, 1319.551352540141, 1325.6752095736413, 1337.0840973932886, 1339.8124262603048, 19435.51411214731, 19442.0, 19551.21795128179, 19704.113314900576, 19778.208871644973, 60514.0, 61120.38879611325, 61801.690991544754, 62957.37042629515, 66158.66920665366, 77722.6113869427, 77970.0, 78636.0, 78705.60647196727, 78870.21129788629, 13945.0, 14692.41230350774, 15772.605684857512, 16006.0, 16322.591935201715, 24344.391390215387, 24346.0, 24482.755172448047, 24794.25617231486, 24815.751842482066, 20146.194152234333, 20297.594048119252, 20362.18551257933, 20392.694109588665, 20417.387478375673, 16476.42332518356, 16905.408310709066, 16965.745513817055, 17103.48689539316, 18046.187921543824, 20267.79732202718, 20387.59224815525, 20602.175912963314, 20743.0, 20755.377338679864, 15996.280167392262, 16129.596760081093, 16247.756283655519, 16279.51161465158, 16320.428784992468, 19619.607607848047, 19881.900590496956, 20032.399028029184, 20036.198552057755, 20073.498162546053], "value": 13418.746681825422, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1446.956591302261, 1500.7748837674342, 1510.5770384292402, 1518.6810769738354, 1529.8623123918849, 16647.583810404733, 17364.479065628035, 17409.21658525368, 17416.738748918775, 17435.0, 17103.74344384835, 17201.311947522085, 17243.31026758928, 17253.568660783454, 17260.74108888368, 2900.8839646414117, 2913.9271518212004, 2963.7480814130827, 2968.7921845470823, 2969.881204751807, 2918.8248705077763, 2936.192547049557, 2945.6759756426777, 2947.2484516448385, 2949.7050294970545, 2854.9286267843263, 2899.594056832045, 2941.279386550298, 2942.102658689099, 2944.3375240570913, 2767.3911739417395, 2776.4585905748386, 2778.7082356352494, 2794.0220922677104, 2794.2176190666646, 2723.7412445817617, 2730.6586676665415, 2731.658542682165, 2773.875175617082, 2867.4121805029918, 17397.04316262605, 17458.388956386592, 17542.0, 17553.385631502962, 17561.824381756276, 8434.957825210897, 8813.030566637664, 8817.426867253607, 8818.86771698425, 8819.250363719091, 5330.653507522037, 5410.675359478444, 5595.524380427669, 5598.048331783566, 5611.382747897769, 1981.6333978214077, 1982.0684278389133, 1983.3355825798376, 2000.8599398042143, 2025.8480613954011, 31615.52576711351, 32001.51997720036, 32154.517682234786, 32165.356692866244, 32203.677963220303, 7207.89188162178, 7210.423166146696, 7403.481756277009, 7430.777076687708, 7541.0, 21142.0, 21150.47123821886, 21150.47123821886, 21232.0, 21264.468388290417, 8357.164283571594, 8610.784730381665, 8673.306135509145, 8684.522351270742, 8686.56567171639, 7737.761958086791, 7953.2842044216295, 7969.442139050211, 8002.239787220205, 8135.471194372347, 6059.151718759377, 6296.370362963758, 6348.968255158695, 6366.5861718989045, 6448.419642232222, 1648.1017845274114, 1670.1064930262494, 1778.1109445277361, 1823.1431227323137, 1838.7058070708622, 935.8970513243538, 945.0879900895669, 954.2652157838426, 955.4219697083165, 957.5547370472764, 7782.533048017137, 7810.726624568227, 7834.569098699512, 7869.095054068741, 7922.286994170552, 1045.3936716704293, 1087.9129669626411, 1092.918031147667, 1093.8085834978847, 1123.5786580032488, 4984.601231901439, 5054.19132938728, 5095.821646242438, 5128.358955130609, 5176.559992400688, 25413.0, 26556.468870622866, 26841.328966775596, 27083.458330833666, 27127.593086103723, 26215.606765898527, 26543.33641658974, 26593.0, 26844.463110738066, 27074.1877743668, 12772.489100435878, 13032.41354139066, 13157.815796578354, 13260.469581216643, 13266.46934122663, 9348.95325523368, 9362.906370936204, 9398.859017114612, 9432.292578056537, 9439.19766819828, 22281.888590556944, 22418.103275869435, 22561.548769024856, 22593.983270753186, 23060.654090188324, 23269.767302327436, 23612.409689757893, 23911.760882390954, 24065.879670601535, 24274.0, 13150.21098734079, 13152.802707959198, 13174.011949104048, 13245.735085298047, 13395.464181433023, 16244.0, 16255.67488650244, 16342.10118443497, 16360.100194488941, 16501.91749041295, 11632.185746997968, 11702.35637039971, 11780.70548236301, 11847.881521184678, 11906.166568340133, 11564.653060408202, 11672.47473863695, 11794.764104717686, 11862.347570883685, 11941.343226122646, 11125.610603628835, 11152.219344645797, 11159.72100697489, 11185.32888026721, 11193.496292666687, 7834.255745704149, 7838.255365740246, 7851.136374998631, 7851.685932562634, 7883.84232315339, 7810.882836757567, 7925.841483170419, 7995.400344974034, 8048.195180481905, 8089.716859909875], "value": 7426.976558516467, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.3973938276972, 584.5966283264546, 593.6200831467864, 605.3885575568676, 609.7317180440606, 6276.403741644548, 6313.1477250571215, 6357.205349331333, 6373.617582945015, 6377.6173429594255, 6175.691215439221, 6192.50459963203, 6251.249850017993, 6268.310485846553, 6298.968505157468, 5091.643584949055, 5128.71792051437, 5136.922946155811, 5138.434772175057, 5140.614453915971, 4900.901981960359, 4932.827351042714, 5060.418051924039, 5134.358205224347, 5165.1219292720325, 4954.3063971044085, 5023.76917655175, 5023.824166154185, 5046.899062018776, 5060.266261392096, 4963.975180124076, 4965.155923493015, 4971.850844474671, 4972.975135124338, 5020.899582008357, 4553.795079221443, 4590.196715574761, 4596.241620132708, 4600.148972440109, 4605.700629459075, 6200.844978875564, 6273.592216505958, 6300.653464059475, 6318.74725010999, 6352.110704501373, 5977.2229610150835, 5986.4612184903135, 6012.75948962041, 6016.217891674054, 6016.826718789837, 6762.797116086525, 6784.626845523494, 6785.491088168407, 6800.863982720367, 6805.9659701701175, 5185.896282074375, 5192.55863251624, 5199.14214154664, 5199.324087868553, 5203.011427828738, 6916.167215687847, 6976.93023069773, 7064.0, 7092.538984966012, 7174.3454921678795, 5314.441983591744, 5322.042032434199, 5325.174597937326, 5333.0, 5340.946590534123, 5338.092524270884, 5380.650257733235, 5385.3268341457315, 5417.214503896913, 5452.236686863842, 5865.794697185578, 5919.171316015675, 5919.792807251812, 5962.25471816023, 5980.491658209101, 6809.965950170316, 6832.624205668736, 6845.828854278583, 6856.24581296057, 6858.6570671467225, 1846.4645252876785, 1850.0, 1872.9906350468161, 1883.4067268810281, 1889.8866068035961, 1298.3443361102584, 1325.3903204526025, 1336.472093523053, 1349.2713934475273, 1362.557168920105, 6339.683015849189, 6369.617822930639, 6428.614283143026, 6496.415322620892, 6520.641364724986, 6719.395254427125, 6727.327267273234, 6800.761973330909, 6847.726090956406, 6934.133233345832, 6440.677966101676, 6468.8059358219325, 6481.546291759626, 6524.510661700297, 6570.901436478457, 3648.179159689049, 3660.4143337065943, 3661.487391765155, 3676.540432445944, 3699.8890033299044, 7322.524035937647, 7325.340719335285, 7326.706931722778, 7362.153352364547, 7372.96313518429, 6985.89521157183, 7004.509684322048, 7084.858302834017, 7089.53359383585, 7126.35862772353, 7160.281532432283, 7175.748848790267, 7242.0, 7284.5430913817445, 7287.870380091094, 6750.088738020305, 6825.146856642919, 6866.793996180123, 6882.279430142465, 6896.0691006518255, 6725.495587830835, 6785.185777706707, 6791.864162716619, 6806.217285012287, 6831.0, 7204.0, 7215.206327304098, 7219.278072192738, 7254.782356529101, 7261.346478816932, 5209.7395130244095, 5274.446183150714, 5308.0, 5323.254744335719, 5349.625526213128, 6717.193936727624, 6907.758228461979, 6942.444604431735, 6960.269171736894, 7007.894881576678, 6211.3478084802255, 6562.704678289393, 6591.307912669101, 6618.286502533938, 6664.133662623828, 6590.637514936533, 6597.284705976576, 6626.111558205888, 6690.431313338421, 6693.899591506034, 6626.602403855784, 6646.2356828965285, 6694.945316286173, 6718.563293385914, 6765.830854228684, 6720.1263835703, 6740.561863478858, 6784.388010616047, 6785.185777706707, 6818.249992500921, 6335.936640633535, 6445.4199122079235, 6516.674166291572, 6522.5760325578685, 6523.3150519194805], "value": 5295.678336989988, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1014.2241185493097, 1022.1924679503193, 1028.0952761569818, 1032.251617577257, 1033.6950599573124, 20257.797422025793, 20460.28389006385, 20754.48113797154, 20796.792032079673, 20899.164033438665, 17090.572735681613, 17128.657426851456, 17173.141342932864, 17173.484795456126, 17176.0, 1077.6066735641473, 1085.012638498966, 1085.1644233939862, 1086.8424078508613, 1094.03178187304, 1060.76132870104, 1094.720846184222, 1108.927919685222, 1109.2235435195355, 1109.3898355904262, 1174.1956759619688, 1180.433391971854, 1181.308934273451, 1184.97630047399, 1194.1640851404009, 1170.5259369955181, 1174.0431548288202, 1174.2778191412262, 1176.0180249491684, 1183.556166437586, 857.9785505362354, 858.4720396955889, 859.2052351574769, 863.9481631102154, 869.699953516036, 19377.806221937743, 19754.80245197544, 19913.30303439387, 20054.699179512318, 20065.29771457992, 21895.452613684625, 22251.777482225294, 22311.776882231134, 22312.0, 22652.886735566382, 16228.51314460568, 16267.186640668038, 16434.75347869783, 16444.34222631105, 16449.917750411172, 3928.764274143532, 3991.7804520751347, 3996.0609256824914, 4027.677785777131, 4027.8590249341423, 155822.0, 159402.20298898433, 160600.0, 161712.0, 161792.0, 16883.24025418859, 17132.486025419257, 17236.913815431093, 17299.13504324804, 17349.306027759, 35111.0, 35171.4724279136, 35387.469187962204, 35657.46513802295, 36136.0, 8433.957830210933, 8540.487570745776, 8550.828983420297, 8560.657573697106, 8575.914240857637, 4530.501644819067, 4562.064776720765, 4617.607503362252, 4631.258998560214, 4633.606143477775, 6167.074938759222, 6275.34108918557, 6280.5603607747935, 6313.147725057151, 6317.305096439388, 1038.081298051215, 1085.945702714861, 1090.0625462102585, 1099.9065079468176, 1103.089950790585, 762.8893810397461, 778.1051790441038, 782.7143092771098, 791.5290402210791, 799.2367289238763, 12901.419436125398, 13062.869371306355, 13224.140430871963, 14032.0, 14119.85880141206, 753.4386881773114, 758.7116895579644, 766.375404045701, 772.0966469231013, 777.1723114882632, 6096.329403765582, 6139.815805525842, 6168.691565421711, 6222.62664240147, 6225.595336303126, 140617.0, 144702.0, 145610.0, 145936.27031864773, 149834.25082874516, 126666.0, 128865.0, 129160.0, 129750.0, 130083.34958325147, 115961.4201928985, 116493.0, 117380.41309793397, 117452.0, 121151.39424302822, 11764.70588235301, 11777.234479758788, 11878.168528202943, 11941.701457463632, 12150.0, 55538.444615554945, 55585.0, 56336.43663563476, 56597.71701141468, 57458.0, 69108.6544567274, 69672.30327696861, 70062.54781082638, 71381.64309178421, 72442.27557724355, 15824.683506329578, 16015.8398416019, 16035.198240087715, 16155.838441615904, 16172.433964811175, 19750.80249197547, 19841.503962401057, 19863.801361986196, 20055.29806456767, 20062.699059513827, 16472.42346517866, 16500.0, 16611.335546578, 16673.0, 16735.74896376531, 16243.918780406495, 16524.09117498549, 16549.0, 16607.66784664276, 16721.74917376216, 17258.568535786704, 17270.827291727426, 17325.22036508335, 17556.91221543935, 17558.648827023644, 14002.719945601235, 14006.509772157926, 14141.0, 14285.57143285703, 14306.268859535081, 13938.372773225432, 13961.581152565439, 14018.929905350407, 14042.227677477837, 14092.718145636825], "value": 9865.58200773456, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1357.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 104.0, 104.0, 104.0, 104.0, 107.0, 107.0, 106.0, 105.0, 105.0], "value": 105.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 113.0, 114.0, 110.0, 111.0, 110.0, 113.0, 115.0], "value": 112.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 165.0, 162.0, 162.0, 162.0, 163.0, 161.0, 165.0, 162.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 73.0, 72.0, 73.0, 73.0, 75.0, 73.0, 73.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 77.0, 78.0, 78.0, 77.0, 77.0, 78.0, 77.0, 77.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 113.0, 116.0, 115.0, 113.0, 115.0, 114.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 148.0, 149.0, 148.0, 149.0, 149.0, 148.0, 148.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 98.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 43.0, 43.0, 45.0, 47.0, 44.0, 45.0, 44.0, 47.0, 44.0], "value": 44.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 40.0, 38.0, 38.0, 38.0, 38.0, 37.0, 38.0, 37.0], "value": 38.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 71.0, 72.0, 69.0, 70.0, 71.0, 74.0, 70.0, 69.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 101.0, 104.0, 103.0, 114.0, 106.0, 101.0, 107.0, 114.0, 105.0], "value": 106.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 154.0, 156.0, 148.0, 151.0, 147.0, 155.0, 153.0, 153.0], "value": 151.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 58.0, 62.0, 60.0, 58.0, 57.0, 58.0, 62.0, 57.0], "value": 58.7, "unit": "score"}]}]} 09:22:15 INFO - Return code: 0 09:22:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:22:15 INFO - # TBPL SUCCESS # 09:22:15 INFO - Running post-action listener: _package_coverage_data 09:22:15 INFO - Running post-action listener: _resource_record_post_action 09:22:15 INFO - [mozharness: 2017-09-25 16:22:15.435178Z] Finished run-tests step (success) 09:22:15 INFO - Running post-run listener: _resource_record_post_run 09:22:15 INFO - Total resource usage - Wall time: 1180s; CPU: 13.0%; Read bytes: 4096; Write bytes: 287842304; Read time: 8; Write time: 144664 09:22:15 INFO - TinderboxPrint: CPU usage
13.0% 09:22:15 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 09:22:15 INFO - TinderboxPrint: I/O write bytes / time
287,842,304 / 144,664 09:22:15 INFO - TinderboxPrint: CPU idle
8,182.1 (87.0%) 09:22:15 INFO - TinderboxPrint: CPU user
1,178.8 (12.5%) 09:22:15 INFO - TinderboxPrint: Swap in / out
0 / 0 09:22:15 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 8318976; Read time: 0; Write time: 1228 09:22:15 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:22:15 INFO - run-tests - Wall time: 1166s; CPU: 13.0%; Read bytes: 0; Write bytes: 279142400; Read time: 0; Write time: 143412 09:22:16 INFO - Running post-run listener: _upload_blobber_files 09:22:16 INFO - Blob upload gear active. 09:22:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:22:16 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 09:22:16 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'] 09:22:16 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 09:22:16 INFO - (blobuploader) - INFO - Open directory for files ... 09:22:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:22:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:22:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:17 INFO - (blobuploader) - INFO - Done attempting. 09:22:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:22:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:22:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:18 INFO - (blobuploader) - INFO - Done attempting. 09:22:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:22:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:22:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:25 INFO - (blobuploader) - INFO - Done attempting. 09:22:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:22:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:22:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:26 INFO - (blobuploader) - INFO - Done attempting. 09:22:26 INFO - (blobuploader) - INFO - Iteration through files over. 09:22:26 INFO - Return code: 0 09:22:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:22:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:22:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5649ffe831798cbf5c2dbd05da8f11668339dc9e89806f4581ad3e94161cbd6ce87ccfbd0bc4c3d3c4bab54be26a3a6b4f2db21ce87b14c3f28972facd4cc9c8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/01c35918fab74cc07b284b4814e859e366a6d861947b709f305dcd300c91de592632a1a5b08f0a462f6783841af83f7dee3d233ebdb525f8f857e3dc11b3a1f1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/40ca3babc607e82c4da3c70f86a6dfdbb1a13547894e4201b4d2174bbd78ba7fb994c57cb034f4d9825c2ba797affc8ab0cff86483355959d10c3723c4520727", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2da96dda0fd1ae68ca264d1335cbc73b3ec2ec02545adbbcc5031b1bed5616b3c3638ebacc39bd94254f3839eeba206eeba705b88c67b7e935053819023c15d4"} 09:22:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:22:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:22:26 INFO - Contents: 09:22:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5649ffe831798cbf5c2dbd05da8f11668339dc9e89806f4581ad3e94161cbd6ce87ccfbd0bc4c3d3c4bab54be26a3a6b4f2db21ce87b14c3f28972facd4cc9c8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/01c35918fab74cc07b284b4814e859e366a6d861947b709f305dcd300c91de592632a1a5b08f0a462f6783841af83f7dee3d233ebdb525f8f857e3dc11b3a1f1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/40ca3babc607e82c4da3c70f86a6dfdbb1a13547894e4201b4d2174bbd78ba7fb994c57cb034f4d9825c2ba797affc8ab0cff86483355959d10c3723c4520727", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2da96dda0fd1ae68ca264d1335cbc73b3ec2ec02545adbbcc5031b1bed5616b3c3638ebacc39bd94254f3839eeba206eeba705b88c67b7e935053819023c15d4"} 09:22:26 INFO - Running post-run listener: copy_logs_to_upload_dir 09:22:26 INFO - Copying logs to upload dir... 09:22:26 INFO - mkdir: /builds/slave/test/build/upload/logs 09:22:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1229.321613 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2017-09-25 09:22:26.172077) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-25 09:22:26.176612) ========= 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=62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503 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/5649ffe831798cbf5c2dbd05da8f11668339dc9e89806f4581ad3e94161cbd6ce87ccfbd0bc4c3d3c4bab54be26a3a6b4f2db21ce87b14c3f28972facd4cc9c8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/01c35918fab74cc07b284b4814e859e366a6d861947b709f305dcd300c91de592632a1a5b08f0a462f6783841af83f7dee3d233ebdb525f8f857e3dc11b3a1f1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/40ca3babc607e82c4da3c70f86a6dfdbb1a13547894e4201b4d2174bbd78ba7fb994c57cb034f4d9825c2ba797affc8ab0cff86483355959d10c3723c4520727", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2da96dda0fd1ae68ca264d1335cbc73b3ec2ec02545adbbcc5031b1bed5616b3c3638ebacc39bd94254f3839eeba206eeba705b88c67b7e935053819023c15d4"} build_url:https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011512 build_url: 'https://queue.taskcluster.net/v1/task/GRG8MIJyRYWiRI3RY5YvSg/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/5649ffe831798cbf5c2dbd05da8f11668339dc9e89806f4581ad3e94161cbd6ce87ccfbd0bc4c3d3c4bab54be26a3a6b4f2db21ce87b14c3f28972facd4cc9c8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/01c35918fab74cc07b284b4814e859e366a6d861947b709f305dcd300c91de592632a1a5b08f0a462f6783841af83f7dee3d233ebdb525f8f857e3dc11b3a1f1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/40ca3babc607e82c4da3c70f86a6dfdbb1a13547894e4201b4d2174bbd78ba7fb994c57cb034f4d9825c2ba797affc8ab0cff86483355959d10c3723c4520727", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2da96dda0fd1ae68ca264d1335cbc73b3ec2ec02545adbbcc5031b1bed5616b3c3638ebacc39bd94254f3839eeba206eeba705b88c67b7e935053819023c15d4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-25 09:22:26.207709) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 09:22:26.207997) ========= 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=62c568580acbfdaac3eec7be000001dc-1506355310.973609-1496303503 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004501 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 09:22:26.292976) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-25 09:22:26.296295) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-25 09:22:26.296602) ========= ========= Total master_lag: 1.14 =========