builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-015 starttime: 1509029816.17 results: success (0) revision: 44dfa8d7a2340fc1ef89da31f8ebb04c34b31a41 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-26 07:56:56.171308) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-26 07:56:56.171851) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-26 07:56:56.172141) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-26 07:56:56.191522) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-26 07:56:56.191844) ========= 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=20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-26 07:56:56-- 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% 222M=0s 2017-10-26 07:56:56 (222 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111524 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-26 07:56:56.321535) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-26 07:56:56.321844) ========= 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=20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022646 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-26 07:56:56.372699) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-26 07:56:56.373038) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 44dfa8d7a2340fc1ef89da31f8ebb04c34b31a41 --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 44dfa8d7a2340fc1ef89da31f8ebb04c34b31a41 --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=20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-26 07:56:56,423 truncating revision to first 12 chars 2017-10-26 07:56:56,423 Setting DEBUG logging. 2017-10-26 07:56:56,424 attempt 1/10 2017-10-26 07:56:56,424 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/44dfa8d7a234?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-26 07:56:57,212 unpacking tar archive at: mozilla-beta-44dfa8d7a234/testing/mozharness/ program finished with exit code 0 elapsedTime=1.067652 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-26 07:56:57.458511) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-26 07:56:57.458840) ========= script_repo_revision: 44dfa8d7a2340fc1ef89da31f8ebb04c34b31a41 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-26 07:56:57.459208) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-26 07:56:57.459492) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-26 07:56:57.474098) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2017-10-26 07:56:57.474397) ========= /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=20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:56:57 INFO - MultiFileLogger online at 20171026 07:56:57 in /builds/slave/test 07:56: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 07:56:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:56:57 INFO - {'append_to_log': False, 07:56:57 INFO - 'base_work_dir': '/builds/slave/test', 07:56:57 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 07:56:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:56:57 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 07:56:57 INFO - 'buildbot_json_path': 'buildprops.json', 07:56:57 INFO - 'code_coverage': False, 07:56:57 INFO - 'config_files': ('talos/linux_config.py',), 07:56:57 INFO - 'default_actions': ('clobber', 07:56:57 INFO - 'read-buildbot-config', 07:56:57 INFO - 'download-and-extract', 07:56:57 INFO - 'populate-webroot', 07:56:57 INFO - 'create-virtualenv', 07:56:57 INFO - 'install', 07:56:57 INFO - 'setup-mitmproxy', 07:56:57 INFO - 'run-tests'), 07:56:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:56:57 INFO - 'disable_ccov_upload': False, 07:56:57 INFO - 'disable_stylo': False, 07:56:57 INFO - 'download_minidump_stackwalk': True, 07:56:57 INFO - 'download_symbols': 'ondemand', 07:56:57 INFO - 'enable_stylo': False, 07:56:57 INFO - 'enable_webrender': False, 07:56:57 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 07:56:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:56:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:56:57 INFO - 'gecko_profile': False, 07:56:57 INFO - 'gecko_profile_interval': 0, 07:56:57 INFO - 'installer_path': 'installer.exe', 07:56:57 INFO - 'log_level': 'info', 07:56:57 INFO - 'log_name': 'talos', 07:56:57 INFO - 'log_to_console': True, 07:56:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:56:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:56:57 INFO - 'opt_config_files': (), 07:56:57 INFO - 'pip_index': False, 07:56:57 INFO - 'suite': 'dromaeojs-e10s', 07:56:57 INFO - 'system_bits': '32', 07:56:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:56:57 INFO - 'title': 'talos-linux64-ix-015', 07:56:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:56:57 INFO - 'use_talos_json': True, 07:56:57 INFO - 'verify': 'False', 07:56:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:56:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:56:57 INFO - 'work_dir': 'build'} 07:56:57 INFO - [mozharness: 2017-10-26 14:56:57.667340Z] Running clobber step. 07:56:57 INFO - Running pre-action listener: _resource_record_pre_action 07:56:57 INFO - Running main action method: clobber 07:56:57 INFO - rmtree: /builds/slave/test/build 07:56:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:56:59 INFO - Running post-action listener: _resource_record_post_action 07:56:59 INFO - [mozharness: 2017-10-26 14:56:59.287992Z] Finished clobber step (success) 07:56:59 INFO - [mozharness: 2017-10-26 14:56:59.288089Z] Running read-buildbot-config step. 07:56:59 INFO - Running pre-action listener: _resource_record_pre_action 07:56:59 INFO - Running main action method: read_buildbot_config 07:56:59 INFO - Using buildbot properties: 07:56:59 INFO - { 07:56:59 INFO - "project": "", 07:56:59 INFO - "product": "firefox", 07:56:59 INFO - "who": "ryanvm@gmail.com", 07:56:59 INFO - "installer_path": "public/build/target.tar.bz2", 07:56:59 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 07:56:59 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 07:56:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 07:56:59 INFO - "stage_platform": "linux64", 07:56:59 INFO - "basedir": "/builds/slave/test", 07:56:59 INFO - "buildnumber": 42, 07:56:59 INFO - "platform": "ubuntu64_hw", 07:56:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:56:59 INFO - "slavebuilddir": "test", 07:56:59 INFO - "taskId": "RIKLdLBbSjmC8WQwxtLcuA", 07:56:59 INFO - "branch": "mozilla-beta", 07:56:59 INFO - "script_repo_revision": "production", 07:56:59 INFO - "revision": "44dfa8d7a2340fc1ef89da31f8ebb04c34b31a41", 07:56:59 INFO - "slavename": "talos-linux64-ix-015", 07:56:59 INFO - "repo_path": "releases/mozilla-beta" 07:56:59 INFO - } 07:56:59 INFO - Finding installer, test and symbols from parent task. 07:56:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RIKLdLBbSjmC8WQwxtLcuA'}, attempt #1 07:56:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RIKLdLBbSjmC8WQwxtLcuA'}, attempt #1 07:57:00 INFO - Task dependencies: GbsAtyrAQa2vNGzBeex94g 07:57:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g'}, attempt #1 07:57:00 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.tar.bz2 07:57:00 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.test_packages.json 07:57:00 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.crashreporter-symbols.zip 07:57:00 INFO - Running post-action listener: _resource_record_post_action 07:57:00 INFO - [mozharness: 2017-10-26 14:57:00.645097Z] Finished read-buildbot-config step (success) 07:57:00 INFO - [mozharness: 2017-10-26 14:57:00.645315Z] Running download-and-extract step. 07:57:00 INFO - Running pre-action listener: _resource_record_pre_action 07:57:00 INFO - Running main action method: download_and_extract 07:57:00 INFO - mkdir: /builds/slave/test/build/tests 07:57:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:00 INFO - trying https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.test_packages.json 07:57:00 INFO - Downloading https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 07:57:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 07:57:01 INFO - Downloaded 989 bytes. 07:57:01 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 07:57:01 INFO - Using the following test package requirements: 07:57:01 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 07:57:01 INFO - u'common': [u'target.common.tests.zip'], 07:57:01 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 07:57:01 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 07:57:01 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 07:57:01 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 07:57:01 INFO - u'mozbase': [u'target.common.tests.zip'], 07:57:01 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 07:57:01 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 07:57:01 INFO - u'web-platform': [u'target.common.tests.zip', 07:57:01 INFO - u'target.web-platform.tests.tar.gz'], 07:57:01 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 07:57:01 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 07:57:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.common.tests.zip 07:57:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:57:01 INFO - Fetch https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.common.tests.zip into memory 07:57:04 INFO - Content-Length response header: 55178356 07:57:04 INFO - Bytes received: 55178356 07:57:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.talos.tests.zip 07:57:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 07:57:14 INFO - Fetch https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.talos.tests.zip into memory 07:57:16 INFO - Content-Length response header: 14509096 07:57:16 INFO - Bytes received: 14509096 07:57:16 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')]} 07:57:16 INFO - trying https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.tar.bz2 07:57:16 INFO - Downloading https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 07:57:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 07:57:19 INFO - Downloaded 53480522 bytes. 07:57:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.tar.bz2 07:57:19 INFO - mkdir: /builds/slave/test/properties 07:57:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:57:19 INFO - Writing to file /builds/slave/test/properties/build_url 07:57:19 INFO - Contents: 07:57:19 INFO - build_url:https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.tar.bz2 07:57:19 INFO - Running post-action listener: _resource_record_post_action 07:57:19 INFO - Running post-action listener: find_tests_for_verification 07:57:19 INFO - Running post-action listener: set_extra_try_arguments 07:57:19 INFO - [mozharness: 2017-10-26 14:57:19.595213Z] Finished download-and-extract step (success) 07:57:19 INFO - [mozharness: 2017-10-26 14:57:19.595440Z] Running populate-webroot step. 07:57:19 INFO - Running pre-action listener: _resource_record_pre_action 07:57:19 INFO - Running main action method: populate_webroot 07:57:19 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:57:19 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:57:19 INFO - 'tests': ['tresize', 07:57:19 INFO - 'tcanvasmark']}, 07:57:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:57:19 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:57:19 INFO - 'tests': ['dromaeo_css', 07:57:19 INFO - 'kraken']}, 07:57:19 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:57:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:57:19 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:57:19 INFO - 'talos_options': ['--disable-stylo'], 07:57:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:57:19 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:57:19 INFO - 'tests': ['damp', 'tps']}, 07:57:19 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:57:19 INFO - 'talos_options': ['--disable-stylo'], 07:57:19 INFO - 'tests': ['damp', 'tps']}, 07:57:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:57:19 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:57:19 INFO - 'tests': ['dromaeo_dom']}, 07:57:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:57:19 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:57:19 INFO - 'tests': ['basic_compositor_video', 07:57:19 INFO - 'glvideo']}, 07:57:19 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 07:57:19 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 07:57:19 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:57:19 INFO - 'talos_options': ['--disable-stylo'], 07:57:19 INFO - 'tests': ['ts_paint_webext', 07:57:19 INFO - 'tp5o_webext']}, 07:57:19 INFO - 'other-e10s': {'tests': ['a11yr', 07:57:19 INFO - 'ts_paint', 07:57:19 INFO - 'tpaint', 07:57:19 INFO - 'sessionrestore', 07:57:19 INFO - 'sessionrestore_many_windows', 07:57:19 INFO - 'sessionrestore_no_auto_restore', 07:57:19 INFO - 'tabpaint']}, 07:57:19 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:57:19 INFO - 'tests': ['a11yr', 07:57:19 INFO - 'ts_paint', 07:57:19 INFO - 'tpaint', 07:57:19 INFO - 'sessionrestore', 07:57:19 INFO - 'sessionrestore_many_windows', 07:57:19 INFO - 'sessionrestore_no_auto_restore', 07:57:19 INFO - 'tabpaint']}, 07:57:19 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 07:57:19 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 07:57:19 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:57:19 INFO - 'tests': ['perf_reftest_singletons']}, 07:57:19 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:57:19 INFO - 'tests': ['bloom_basic']}, 07:57:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:57:19 INFO - 'tsvgr_opacity', 07:57:19 INFO - 'tart', 07:57:19 INFO - 'tscrollx', 07:57:19 INFO - 'tsvg_static']}, 07:57:19 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:57:19 INFO - 'tests': ['tsvgx', 07:57:19 INFO - 'tsvgr_opacity', 07:57:19 INFO - 'tart', 07:57:19 INFO - 'tscrollx', 07:57:19 INFO - 'tsvg_static']}, 07:57:19 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:57:19 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:57:19 INFO - 'talos_options': ['--disable-stylo'], 07:57:19 INFO - 'tests': ['tp5o']}, 07:57:19 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:57:19 INFO - 'talos_options': ['--mitmproxy', 07:57:19 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:57:19 INFO - '--firstNonBlankPaint'], 07:57:19 INFO - 'tests': ['tp6_google', 07:57:19 INFO - 'tp6_youtube', 07:57:19 INFO - 'tp6_amazon', 07:57:19 INFO - 'tp6_facebook']}, 07:57:19 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:57:19 INFO - 'talos_options': ['--disable-stylo', 07:57:19 INFO - '--mitmproxy', 07:57:19 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:57:19 INFO - '--firstNonBlankPaint'], 07:57:19 INFO - 'tests': ['tp6_google', 07:57:19 INFO - 'tp6_youtube', 07:57:19 INFO - 'tp6_amazon', 07:57:19 INFO - 'tp6_facebook']}, 07:57:19 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:57:19 INFO - 'talos_options': ['--stylo-threads=1', 07:57:19 INFO - '--mitmproxy', 07:57:19 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:57:19 INFO - '--firstNonBlankPaint'], 07:57:19 INFO - 'tests': ['tp6_google', 07:57:19 INFO - 'tp6_youtube', 07:57:19 INFO - 'tp6_amazon', 07:57:19 INFO - 'tp6_facebook']}, 07:57:19 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:57:19 INFO - 'talos_options': ['--xperf_path', 07:57:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:57:19 INFO - 'tests': ['tp5n']}, 07:57:19 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:57:19 INFO - 'talos_options': ['--disable-stylo', 07:57:19 INFO - '--xperf_path', 07:57:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:57:19 INFO - 'tests': ['tp5n']}}} 07:57:19 INFO - Running post-action listener: _resource_record_post_action 07:57:19 INFO - [mozharness: 2017-10-26 14:57:19.619447Z] Finished populate-webroot step (success) 07:57:19 INFO - [mozharness: 2017-10-26 14:57:19.619553Z] Running create-virtualenv step. 07:57:19 INFO - Running pre-action listener: _resource_record_pre_action 07:57:19 INFO - Running main action method: create_virtualenv 07:57:19 INFO - Creating virtualenv /builds/slave/test/build/venv 07:57:19 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:57:19 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 07:57:19 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 07:57:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:57:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:57:20 INFO - Installing distribute.............................................................................................................................................................................................done. 07:57:20 INFO - Installing pip...............done. 07:57:20 INFO - Return code: 0 07:57:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:57:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:57:20 INFO - Reading from file tmpfile_stdout 07:57:20 INFO - Output received: 07:57:20 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 07:57:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:57:20 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')]} 07:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:20 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')]} 07:57:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:20 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 0x17471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab6090>, '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 0x1ab6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab0f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', '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 07:57:20 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 07:57:20 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 07:57:20 INFO - Using env: {'DISPLAY': ':0', 07:57:20 INFO - 'HOME': '/home/cltbld', 07:57:20 INFO - 'LANG': 'en_US.UTF-8', 07:57:20 INFO - 'LANGUAGE': 'en_US:en', 07:57:20 INFO - 'LOGNAME': 'cltbld', 07:57:20 INFO - 'MAIL': '/var/mail/cltbld', 07:57:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:20 INFO - 'MOZ_NO_REMOTE': '1', 07:57:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:20 INFO - 'NO_EM_RESTART': '1', 07:57:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:20 INFO - 'PWD': '/builds/slave/test', 07:57:20 INFO - 'SHELL': '/bin/bash', 07:57:20 INFO - 'SHLVL': '1', 07:57:20 INFO - 'TERM': 'linux', 07:57:20 INFO - 'TMOUT': '86400', 07:57:20 INFO - 'USER': 'cltbld', 07:57:20 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', 07:57:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:20 INFO - '_': '/tools/buildbot/bin/python'} 07:57:22 INFO - Ignoring indexes: http://pypi.python.org/simple/ 07:57:22 INFO - Downloading/unpacking pip>=1.5 07:57:22 INFO - Running setup.py egg_info for package pip 07:57:22 INFO - warning: no files found matching 'pip/cacert.pem' 07:57:22 INFO - warning: no files found matching '*.html' under directory 'docs' 07:57:22 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 07:57:22 INFO - no previously-included directories found matching 'docs/_build/_sources' 07:57:22 INFO - Installing collected packages: pip 07:57:22 INFO - Found existing installation: pip 1.1 07:57:22 INFO - Uninstalling pip: 07:57:22 INFO - Successfully uninstalled pip 07:57:22 INFO - Running setup.py install for pip 07:57:22 INFO - warning: no files found matching 'pip/cacert.pem' 07:57:22 INFO - warning: no files found matching '*.html' under directory 'docs' 07:57:22 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 07:57:22 INFO - no previously-included directories found matching 'docs/_build/_sources' 07:57:22 INFO - Installing pip script to /builds/slave/test/build/venv/bin 07:57:22 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 07:57:22 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 07:57:22 INFO - Successfully installed pip 07:57:22 INFO - Cleaning up... 07:57:22 INFO - Return code: 0 07:57:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:57:22 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')]} 07:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:22 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')]} 07:57:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:22 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 0x17471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab6090>, '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 0x1ab6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab0f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', '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 07:57:22 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 07:57:22 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 07:57:22 INFO - Using env: {'DISPLAY': ':0', 07:57:22 INFO - 'HOME': '/home/cltbld', 07:57:22 INFO - 'LANG': 'en_US.UTF-8', 07:57:22 INFO - 'LANGUAGE': 'en_US:en', 07:57:22 INFO - 'LOGNAME': 'cltbld', 07:57:22 INFO - 'MAIL': '/var/mail/cltbld', 07:57:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:22 INFO - 'MOZ_NO_REMOTE': '1', 07:57:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:22 INFO - 'NO_EM_RESTART': '1', 07:57:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:22 INFO - 'PWD': '/builds/slave/test', 07:57:22 INFO - 'SHELL': '/bin/bash', 07:57:22 INFO - 'SHLVL': '1', 07:57:22 INFO - 'TERM': 'linux', 07:57:22 INFO - 'TMOUT': '86400', 07:57:22 INFO - 'USER': 'cltbld', 07:57:22 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', 07:57:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:22 INFO - '_': '/tools/buildbot/bin/python'} 07:57:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:22 INFO - Downloading/unpacking psutil>=3.1.1 07:57: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 07:57: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 07:57: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 07:57: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 07:57:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:57:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:57:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:57:23 INFO - Installing collected packages: psutil 07:57:23 INFO - Running setup.py install for psutil 07:57:23 INFO - building 'psutil._psutil_linux' extension 07:57:23 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 07:57:25 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 07:57:25 INFO - building 'psutil._psutil_posix' extension 07:57:25 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 07:57:25 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 07:57:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:57:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:57:25 INFO - Successfully installed psutil 07:57:25 INFO - Cleaning up... 07:57:26 INFO - Return code: 0 07:57:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:57:26 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')]} 07:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:26 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')]} 07:57:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:26 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 0x17471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab6090>, '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 0x1ab6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab0f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', '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 07:57:26 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 07:57:26 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 07:57:26 INFO - Using env: {'DISPLAY': ':0', 07:57:26 INFO - 'HOME': '/home/cltbld', 07:57:26 INFO - 'LANG': 'en_US.UTF-8', 07:57:26 INFO - 'LANGUAGE': 'en_US:en', 07:57:26 INFO - 'LOGNAME': 'cltbld', 07:57:26 INFO - 'MAIL': '/var/mail/cltbld', 07:57:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:26 INFO - 'MOZ_NO_REMOTE': '1', 07:57:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:26 INFO - 'NO_EM_RESTART': '1', 07:57:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:26 INFO - 'PWD': '/builds/slave/test', 07:57:26 INFO - 'SHELL': '/bin/bash', 07:57:26 INFO - 'SHLVL': '1', 07:57:26 INFO - 'TERM': 'linux', 07:57:26 INFO - 'TMOUT': '86400', 07:57:26 INFO - 'USER': 'cltbld', 07:57:26 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', 07:57:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:26 INFO - '_': '/tools/buildbot/bin/python'} 07:57:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:57:26 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 07:57:26 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 07:57:26 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 07:57:26 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 07:57:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:57:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:57:27 INFO - Installing collected packages: mozsystemmonitor 07:57:27 INFO - Running setup.py install for mozsystemmonitor 07:57:27 INFO - Successfully installed mozsystemmonitor 07:57:27 INFO - Cleaning up... 07:57:28 INFO - Return code: 0 07:57:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:57: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')]} 07:57:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57: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')]} 07:57:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:28 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 0x17471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab6090>, '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 0x1ab6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab0f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', '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 07:57:28 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 07:57:28 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 07:57:28 INFO - Using env: {'DISPLAY': ':0', 07:57:28 INFO - 'HOME': '/home/cltbld', 07:57:28 INFO - 'LANG': 'en_US.UTF-8', 07:57:28 INFO - 'LANGUAGE': 'en_US:en', 07:57:28 INFO - 'LOGNAME': 'cltbld', 07:57:28 INFO - 'MAIL': '/var/mail/cltbld', 07:57:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:28 INFO - 'MOZ_NO_REMOTE': '1', 07:57:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:28 INFO - 'NO_EM_RESTART': '1', 07:57:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:28 INFO - 'PWD': '/builds/slave/test', 07:57:28 INFO - 'SHELL': '/bin/bash', 07:57:28 INFO - 'SHLVL': '1', 07:57:28 INFO - 'TERM': 'linux', 07:57:28 INFO - 'TMOUT': '86400', 07:57:28 INFO - 'USER': 'cltbld', 07:57:28 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', 07:57:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:28 INFO - '_': '/tools/buildbot/bin/python'} 07:57:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:28 INFO - Downloading/unpacking jsonschema==2.5.1 07:57:28 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 07:57:28 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 07:57:28 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 07:57:28 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 07:57:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:57:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:57: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 07:57: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 07:57: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 07:57: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 07:57:30 INFO - Downloading functools32-3.2.3-2.tar.gz 07:57:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:57:30 INFO - warning: no files found matching '*.txt' 07:57:30 INFO - no previously-included directories found matching 'build' 07:57:30 INFO - no previously-included directories found matching 'dist' 07:57:30 INFO - no previously-included directories found matching '.git*' 07:57:30 INFO - Installing collected packages: jsonschema, functools32 07:57:30 INFO - Running setup.py install for functools32 07:57:30 INFO - warning: no files found matching '*.txt' 07:57:30 INFO - no previously-included directories found matching 'build' 07:57:30 INFO - no previously-included directories found matching 'dist' 07:57:30 INFO - no previously-included directories found matching '.git*' 07:57:30 INFO - Successfully installed jsonschema functools32 07:57:30 INFO - Cleaning up... 07:57:30 INFO - Return code: 0 07:57:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:57:30 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')]} 07:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:30 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')]} 07:57:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:30 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 0x17471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab6090>, '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 0x1ab6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab0f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', '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 07:57:30 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 07:57:30 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 07:57:30 INFO - Using env: {'DISPLAY': ':0', 07:57:30 INFO - 'HOME': '/home/cltbld', 07:57:30 INFO - 'LANG': 'en_US.UTF-8', 07:57:30 INFO - 'LANGUAGE': 'en_US:en', 07:57:30 INFO - 'LOGNAME': 'cltbld', 07:57:30 INFO - 'MAIL': '/var/mail/cltbld', 07:57:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:30 INFO - 'MOZ_NO_REMOTE': '1', 07:57:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:30 INFO - 'NO_EM_RESTART': '1', 07:57:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:30 INFO - 'PWD': '/builds/slave/test', 07:57:30 INFO - 'SHELL': '/bin/bash', 07:57:30 INFO - 'SHLVL': '1', 07:57:30 INFO - 'TERM': 'linux', 07:57:30 INFO - 'TMOUT': '86400', 07:57:30 INFO - 'USER': 'cltbld', 07:57:30 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', 07:57:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:30 INFO - '_': '/tools/buildbot/bin/python'} 07:57:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:57:30 INFO - Cleaning up... 07:57:30 INFO - Return code: 0 07:57:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:57:30 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')]} 07:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:30 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')]} 07:57:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:30 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 0x17471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab6090>, '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 0x1ab6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab0f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', '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 07:57:30 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 07:57:30 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 07:57:30 INFO - Using env: {'DISPLAY': ':0', 07:57:30 INFO - 'HOME': '/home/cltbld', 07:57:30 INFO - 'LANG': 'en_US.UTF-8', 07:57:30 INFO - 'LANGUAGE': 'en_US:en', 07:57:30 INFO - 'LOGNAME': 'cltbld', 07:57:30 INFO - 'MAIL': '/var/mail/cltbld', 07:57:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:30 INFO - 'MOZ_NO_REMOTE': '1', 07:57:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:30 INFO - 'NO_EM_RESTART': '1', 07:57:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:30 INFO - 'PWD': '/builds/slave/test', 07:57:30 INFO - 'SHELL': '/bin/bash', 07:57:30 INFO - 'SHLVL': '1', 07:57:30 INFO - 'TERM': 'linux', 07:57:30 INFO - 'TMOUT': '86400', 07:57:30 INFO - 'USER': 'cltbld', 07:57:30 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', 07:57:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:30 INFO - '_': '/tools/buildbot/bin/python'} 07:57:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:30 INFO - Downloading/unpacking blobuploader==1.2.4 07:57: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 07:57: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 07:57: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 07:57: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 07:57:31 INFO - Downloading blobuploader-1.2.4.tar.gz 07:57:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:57:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:57: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 07:57: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 07:57: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 07:57: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 07:57:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:57:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:57: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 07:57: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 07:57: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 07:57: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 07:57:32 INFO - Downloading docopt-0.6.1.tar.gz 07:57:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:57:32 INFO - Installing collected packages: blobuploader, requests, docopt 07:57:32 INFO - Running setup.py install for blobuploader 07:57:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:57:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:57:32 INFO - Running setup.py install for requests 07:57:33 INFO - Running setup.py install for docopt 07:57:33 INFO - Successfully installed blobuploader requests docopt 07:57:33 INFO - Cleaning up... 07:57:33 INFO - Return code: 0 07:57:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57:33 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')]} 07:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:33 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')]} 07:57:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:33 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 0x17471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab6090>, '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 0x1ab6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab0f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', '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 07:57:33 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 07:57:33 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 07:57:33 INFO - Using env: {'DISPLAY': ':0', 07:57:33 INFO - 'HOME': '/home/cltbld', 07:57:33 INFO - 'LANG': 'en_US.UTF-8', 07:57:33 INFO - 'LANGUAGE': 'en_US:en', 07:57:33 INFO - 'LOGNAME': 'cltbld', 07:57:33 INFO - 'MAIL': '/var/mail/cltbld', 07:57:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:33 INFO - 'MOZ_NO_REMOTE': '1', 07:57:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:33 INFO - 'NO_EM_RESTART': '1', 07:57:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:33 INFO - 'PWD': '/builds/slave/test', 07:57:33 INFO - 'SHELL': '/bin/bash', 07:57:33 INFO - 'SHLVL': '1', 07:57:33 INFO - 'TERM': 'linux', 07:57:33 INFO - 'TMOUT': '86400', 07:57:33 INFO - 'USER': 'cltbld', 07:57:33 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', 07:57:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:33 INFO - '_': '/tools/buildbot/bin/python'} 07:57:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:57:33 INFO - Running setup.py (path:/tmp/pip-bsQUEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:57:33 INFO - Running setup.py (path:/tmp/pip-RmCVVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:57:33 INFO - Running setup.py (path:/tmp/pip-EMFQla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:57:33 INFO - Running setup.py (path:/tmp/pip-PDng98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:57:33 INFO - Running setup.py (path:/tmp/pip-zEzlFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:57:33 INFO - Running setup.py (path:/tmp/pip-tUNxHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:57:33 INFO - Running setup.py (path:/tmp/pip-NNksZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:57:33 INFO - Running setup.py (path:/tmp/pip-eVr9If-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:57:33 INFO - Running setup.py (path:/tmp/pip-QMmgEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:57:34 INFO - Running setup.py (path:/tmp/pip-S45K2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:57:34 INFO - Running setup.py (path:/tmp/pip-sRtxD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:57:34 INFO - Running setup.py (path:/tmp/pip-mInX3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:57:34 INFO - Running setup.py (path:/tmp/pip-L_U7UZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:57:34 INFO - Running setup.py (path:/tmp/pip-2wCTRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:34 INFO - Running setup.py (path:/tmp/pip-2_WxXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:57:34 INFO - Running setup.py (path:/tmp/pip-fcoSKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:57:34 INFO - Running setup.py (path:/tmp/pip-5NGT1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:57:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:57:34 INFO - Running setup.py install for manifestparser 07:57:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:57:34 INFO - Running setup.py install for mozcrash 07:57:34 INFO - Running setup.py install for mozdebug 07:57:34 INFO - Running setup.py install for mozdevice 07:57:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:57:35 INFO - Running setup.py install for mozfile 07:57:35 INFO - Running setup.py install for mozhttpd 07:57:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:57:35 INFO - Running setup.py install for mozinfo 07:57:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:57:35 INFO - Running setup.py install for mozInstall 07:57:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:57:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:57:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:57:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:57:35 INFO - Running setup.py install for mozleak 07:57:35 INFO - Running setup.py install for mozlog 07:57:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:57:35 INFO - Running setup.py install for moznetwork 07:57:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:57:35 INFO - Running setup.py install for mozprocess 07:57:35 INFO - Running setup.py install for mozprofile 07:57:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:57:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:57:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:57:35 INFO - Running setup.py install for mozrunner 07:57:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:57:36 INFO - Running setup.py install for mozscreenshot 07:57:36 INFO - Running setup.py install for moztest 07:57:36 INFO - Running setup.py install for mozversion 07:57:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:57:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:57:36 INFO - Cleaning up... 07:57:36 INFO - Return code: 0 07:57:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57:36 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')]} 07:57:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:36 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')]} 07:57:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:36 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 0x17471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab6090>, '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 0x1ab6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab0f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', '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 07:57:36 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 07:57:36 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 07:57:36 INFO - Using env: {'DISPLAY': ':0', 07:57:36 INFO - 'HOME': '/home/cltbld', 07:57:36 INFO - 'LANG': 'en_US.UTF-8', 07:57:36 INFO - 'LANGUAGE': 'en_US:en', 07:57:36 INFO - 'LOGNAME': 'cltbld', 07:57:36 INFO - 'MAIL': '/var/mail/cltbld', 07:57:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:36 INFO - 'MOZ_NO_REMOTE': '1', 07:57:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:36 INFO - 'NO_EM_RESTART': '1', 07:57:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:36 INFO - 'PWD': '/builds/slave/test', 07:57:36 INFO - 'SHELL': '/bin/bash', 07:57:36 INFO - 'SHLVL': '1', 07:57:36 INFO - 'TERM': 'linux', 07:57:36 INFO - 'TMOUT': '86400', 07:57:36 INFO - 'USER': 'cltbld', 07:57:36 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', 07:57:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:36 INFO - '_': '/tools/buildbot/bin/python'} 07:57:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:57:36 INFO - Running setup.py (path:/tmp/pip-clWOFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:57:36 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)) 07:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:57:36 INFO - Running setup.py (path:/tmp/pip-z3xeuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:57:36 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)) 07:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:57:36 INFO - Running setup.py (path:/tmp/pip-av5QeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:57:36 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)) 07:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:57:36 INFO - Running setup.py (path:/tmp/pip-tVUSQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:57:36 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)) 07:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:57:36 INFO - Running setup.py (path:/tmp/pip-TChmPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:57:36 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)) 07:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:57:36 INFO - Running setup.py (path:/tmp/pip-xiwnWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:57:37 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)) 07:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:57:37 INFO - Running setup.py (path:/tmp/pip-kg6WxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:57:37 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)) 07:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:57:37 INFO - Running setup.py (path:/tmp/pip-f1pUH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:57:37 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)) 07:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:57:37 INFO - Running setup.py (path:/tmp/pip-t_fc0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:57:37 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)) 07:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:57:37 INFO - Running setup.py (path:/tmp/pip-YkY7tl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:57:37 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)) 07:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:57:37 INFO - Running setup.py (path:/tmp/pip-YL9Mfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:57:37 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)) 07:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:57:37 INFO - Running setup.py (path:/tmp/pip-YXIiVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:57:37 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)) 07:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:57:37 INFO - Running setup.py (path:/tmp/pip-VtsC_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:57:37 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)) 07:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:57:37 INFO - Running setup.py (path:/tmp/pip-zSp8AX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:57:37 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)) 07:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:37 INFO - Running setup.py (path:/tmp/pip-fN2oWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:37 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)) 07:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:57:37 INFO - Running setup.py (path:/tmp/pip-qhhhag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:57:37 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)) 07:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:57:37 INFO - Running setup.py (path:/tmp/pip-MKgASd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:57:37 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)) 07:57:37 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)) 07:57:37 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)) 07:57:37 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)) 07:57:37 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)) 07:57:37 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)) 07:57:37 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)) 07:57:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:57:37 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 07:57:37 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 07:57:37 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 07:57:37 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 07:57:38 INFO - Downloading blessings-1.6.tar.gz 07:57:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:57:38 INFO - Installing collected packages: blessings 07:57:38 INFO - Running setup.py install for blessings 07:57:39 INFO - Successfully installed blessings 07:57:39 INFO - Cleaning up... 07:57:39 INFO - Return code: 0 07:57:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:57:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:57:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:57:39 INFO - Reading from file tmpfile_stdout 07:57:39 INFO - Current package versions: 07:57:39 INFO - argparse == 1.2.1 07:57:39 INFO - blessings == 1.6 07:57:39 INFO - blobuploader == 1.2.4 07:57:39 INFO - docopt == 0.6.1 07:57:39 INFO - functools32 == 3.2.3-2 07:57:39 INFO - jsonschema == 2.5.1 07:57:39 INFO - manifestparser == 1.1 07:57:39 INFO - mozInstall == 1.13 07:57:39 INFO - mozcrash == 1.0 07:57:39 INFO - mozdebug == 0.1 07:57:39 INFO - mozdevice == 0.51 07:57:39 INFO - mozfile == 1.2 07:57:39 INFO - mozhttpd == 0.7 07:57:39 INFO - mozinfo == 0.10 07:57:39 INFO - mozleak == 0.1 07:57:39 INFO - mozlog == 3.5 07:57:39 INFO - moznetwork == 0.27 07:57:39 INFO - mozprocess == 0.25 07:57:39 INFO - mozprofile == 0.28 07:57:39 INFO - mozrunner == 6.13 07:57:39 INFO - mozscreenshot == 0.1 07:57:39 INFO - mozsystemmonitor == 0.3 07:57:39 INFO - moztest == 0.8 07:57:39 INFO - mozversion == 1.4 07:57:39 INFO - psutil == 3.1.1 07:57:39 INFO - requests == 1.2.3 07:57:39 INFO - wsgiref == 0.1.2 07:57:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57:39 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')]} 07:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:39 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')]} 07:57:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:39 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 0x17471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab6090>, '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 0x1ab6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab0f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', '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 07:57:39 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 07:57:39 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 07:57:39 INFO - Using env: {'DISPLAY': ':0', 07:57:39 INFO - 'HOME': '/home/cltbld', 07:57:39 INFO - 'LANG': 'en_US.UTF-8', 07:57:39 INFO - 'LANGUAGE': 'en_US:en', 07:57:39 INFO - 'LOGNAME': 'cltbld', 07:57:39 INFO - 'MAIL': '/var/mail/cltbld', 07:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:39 INFO - 'MOZ_NO_REMOTE': '1', 07:57:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:39 INFO - 'NO_EM_RESTART': '1', 07:57:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:39 INFO - 'PWD': '/builds/slave/test', 07:57:39 INFO - 'SHELL': '/bin/bash', 07:57:39 INFO - 'SHLVL': '1', 07:57:39 INFO - 'TERM': 'linux', 07:57:39 INFO - 'TMOUT': '86400', 07:57:39 INFO - 'USER': 'cltbld', 07:57:39 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', 07:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:39 INFO - '_': '/tools/buildbot/bin/python'} 07:57:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:39 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)) 07:57:39 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)) 07:57:39 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)) 07:57:39 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)) 07:57:39 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)) 07:57:39 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)) 07:57:39 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)) 07:57:39 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)) 07:57:39 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)) 07:57:39 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:57:39 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 07:57:39 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 07:57:39 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 07:57:39 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 07:57:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 07:57:40 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)) 07:57:40 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)) 07:57:40 INFO - Installing collected packages: simplejson 07:57:40 INFO - Running setup.py install for simplejson 07:57:40 INFO - building 'simplejson._speedups' extension 07:57:40 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 07:57:41 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 07:57:41 INFO - Successfully installed simplejson 07:57:41 INFO - Cleaning up... 07:57:41 INFO - Return code: 0 07:57:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x190fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab6090>, '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 0x1ab6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab0f90>, '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': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', '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 07:57:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:57:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:57:41 INFO - Using env: {'DISPLAY': ':0', 07:57:41 INFO - 'HOME': '/home/cltbld', 07:57:41 INFO - 'LANG': 'en_US.UTF-8', 07:57:41 INFO - 'LANGUAGE': 'en_US:en', 07:57:41 INFO - 'LOGNAME': 'cltbld', 07:57:41 INFO - 'MAIL': '/var/mail/cltbld', 07:57:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:41 INFO - 'MOZ_NO_REMOTE': '1', 07:57:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:41 INFO - 'NO_EM_RESTART': '1', 07:57:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:41 INFO - 'PWD': '/builds/slave/test', 07:57:41 INFO - 'SHELL': '/bin/bash', 07:57:41 INFO - 'SHLVL': '1', 07:57:41 INFO - 'TERM': 'linux', 07:57:41 INFO - 'TMOUT': '86400', 07:57:41 INFO - 'USER': 'cltbld', 07:57:41 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', 07:57:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:41 INFO - '_': '/tools/buildbot/bin/python'} 07:57:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:57:42 INFO - Cleaning up... 07:57:42 INFO - Return code: 0 07:57:42 INFO - Running post-action listener: _resource_record_post_action 07:57:42 INFO - Running post-action listener: _start_resource_monitoring 07:57:42 INFO - Starting resource monitoring. 07:57:42 INFO - [mozharness: 2017-10-26 14:57:42.054932Z] Finished create-virtualenv step (success) 07:57:42 INFO - [mozharness: 2017-10-26 14:57:42.055442Z] Running install step. 07:57:42 INFO - Running pre-action listener: _resource_record_pre_action 07:57:42 INFO - Running main action method: install 07:57:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:57:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:57:42 INFO - Reading from file tmpfile_stdout 07:57:42 INFO - Detecting whether we're running mozinstall >=1.0... 07:57:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:57:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:57:42 INFO - Reading from file tmpfile_stdout 07:57:42 INFO - Output received: 07:57:42 INFO - Usage: mozinstall [options] installer 07:57:42 INFO - Options: 07:57:42 INFO - -h, --help show this help message and exit 07:57:42 INFO - -d DEST, --destination=DEST 07:57:42 INFO - Directory to install application into. [default: 07:57:42 INFO - "/builds/slave/test"] 07:57:42 INFO - --app=APP Application being installed. [default: firefox] 07:57:42 INFO - mkdir: /builds/slave/test/build/application 07:57:42 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'] 07:57:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 07:57:57 INFO - Reading from file tmpfile_stdout 07:57:57 INFO - Output received: 07:57:57 INFO - /builds/slave/test/build/application/firefox/firefox 07:57:57 INFO - Running post-action listener: _resource_record_post_action 07:57:57 INFO - [mozharness: 2017-10-26 14:57:57.026658Z] Finished install step (success) 07:57:57 INFO - [mozharness: 2017-10-26 14:57:57.026910Z] Running setup-mitmproxy step. 07:57:57 INFO - Running pre-action listener: _resource_record_pre_action 07:57:57 INFO - Running main action method: setup_mitmproxy 07:57:57 INFO - Skipping: mitmproxy is not required 07:57:57 INFO - Running post-action listener: _resource_record_post_action 07:57:57 INFO - [mozharness: 2017-10-26 14:57:57.028033Z] Finished setup-mitmproxy step (success) 07:57:57 INFO - [mozharness: 2017-10-26 14:57:57.028254Z] Running run-tests step. 07:57:57 INFO - Running pre-action listener: _resource_record_pre_action 07:57:57 INFO - Running pre-action listener: _set_gcov_prefix 07:57:57 INFO - Running main action method: run_tests 07:57:57 WARNING - Try message not found. 07:57:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:57:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:57:57 INFO - Python 2.7.3 07:57:57 INFO - Return code: 0 07:57:57 INFO - grabbing minidump binary from tooltool 07:57:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:57 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 0x1ab6090>, '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 0x1ab6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab0f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:57:57 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 07:57:57 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 07:57:57 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 07:57:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:57:57 INFO - Return code: 0 07:57:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:57:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:57:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:57:57 INFO - ENV: RUST_BACKTRACE is now full 07:57:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:57:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:57:57 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/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 07:57:57 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/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-015 --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 07:57:57 INFO - Using env: {'DISPLAY': ':0', 07:57:57 INFO - 'HOME': '/home/cltbld', 07:57:57 INFO - 'LANG': 'en_US.UTF-8', 07:57:57 INFO - 'LANGUAGE': 'en_US:en', 07:57:57 INFO - 'LOGNAME': 'cltbld', 07:57:57 INFO - 'MAIL': '/var/mail/cltbld', 07:57:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:57:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:57:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:57 INFO - 'MOZ_NO_REMOTE': '1', 07:57:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:57:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:57:57 INFO - 'NO_EM_RESTART': '1', 07:57:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:57:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:57 INFO - 'PWD': '/builds/slave/test', 07:57:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:57:57 INFO - 'RUST_BACKTRACE': 'full', 07:57:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:57:57 INFO - 'SHELL': '/bin/bash', 07:57:57 INFO - 'SHLVL': '1', 07:57:57 INFO - 'TERM': 'linux', 07:57:57 INFO - 'TMOUT': '86400', 07:57:57 INFO - 'USER': 'cltbld', 07:57:57 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', 07:57:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:57 INFO - '_': '/tools/buildbot/bin/python'} 07:57:57 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/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 07:57:57 INFO - mozversion application_buildid: 20171026133649 07:57:57 INFO - mozversion application_changeset: 44dfa8d7a2340fc1ef89da31f8ebb04c34b31a41 07:57:57 INFO - mozversion application_display_name: Firefox 07:57:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:57:57 INFO - mozversion application_name: Firefox 07:57:57 INFO - mozversion application_remotingname: firefox 07:57:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 07:57:57 INFO - mozversion application_vendor: Mozilla 07:57:57 INFO - mozversion application_version: 57.0 07:57:57 INFO - mozversion platform_buildid: 20171026133649 07:57:57 INFO - mozversion platform_changeset: 44dfa8d7a2340fc1ef89da31f8ebb04c34b31a41 07:57:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 07:57:57 INFO - mozversion platform_version: 57.0 07:57:57 INFO - using testdate: 1509029877 07:57:57 INFO - actual date: 1509029877 07:57:57 INFO - starting webserver on 'localhost:56567' 07:57:57 INFO - SUITE-START | Running 2 tests 07:57:57 INFO - TEST-START | dromaeo_css 07:57:57 INFO - Initialising browser for dromaeo_css test... 07:57:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56567/getInfo.html -profile /tmp/tmpSzGowm/profile 07:57:57 INFO - TEST-INFO | started process 722 (/builds/slave/test/build/application/firefox/firefox http://localhost:56567/getInfo.html) 07:58:02 INFO - TEST-INFO | 722: exit 0 07:58:02 INFO - Browser initialized. 07:58:02 INFO - Running cycle 1/1 for dromaeo_css test... 07:58:02 INFO - Using env: {'DISPLAY': ':0', 07:58:02 INFO - 'HOME': '/home/cltbld', 07:58:02 INFO - 'JSGC_DISABLE_POISONING': '1', 07:58:02 INFO - 'LANG': 'en_US.UTF-8', 07:58:02 INFO - 'LANGUAGE': 'en_US:en', 07:58:02 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 07:58:02 INFO - 'LOGNAME': 'cltbld', 07:58:02 INFO - 'MAIL': '/var/mail/cltbld', 07:58:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:58:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:58:02 INFO - 'MOZ_CRASHREPORTER': '1', 07:58:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:58:02 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 07:58:02 INFO - 'MOZ_NO_REMOTE': '1', 07:58:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:58:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:58:02 INFO - 'NO_EM_RESTART': '1', 07:58:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:58:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:58:02 INFO - 'PWD': '/builds/slave/test', 07:58:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:58:02 INFO - 'RUST_BACKTRACE': 'full', 07:58:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:58:02 INFO - 'SHELL': '/bin/bash', 07:58:02 INFO - 'SHLVL': '1', 07:58:02 INFO - 'TERM': 'linux', 07:58:02 INFO - 'TMOUT': '86400', 07:58:02 INFO - 'USER': 'cltbld', 07:58:02 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', 07:58:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:58:02 INFO - '_': '/tools/buildbot/bin/python'} 07:58:02 INFO - TEST-INFO | started process 963 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSzGowm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 07:58:03 INFO - PID 963 | 07:58:03 INFO - PID 963 | (/builds/slave/test/build/application/firefox/firefox:1011): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 07:58:03 INFO - PID 963 | 07:58:13 INFO - PID 963 | RSS: Main: 143577088 07:58:13 INFO - PID 963 | 08:01:10 INFO - PID 963 | Cycle 1(1): loaded http://localhost:56567/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56567/tests/dromaeo/cssquery-ext.html) 08:01:11 INFO - PID 963 | RSS: Main: 146718720 08:01:11 INFO - PID 963 | 08:04:13 INFO - PID 963 | Cycle 1(1): loaded http://localhost:56567/tests/dromaeo/cssquery-ext.html (next: http://localhost:56567/tests/dromaeo/cssquery-jquery.html) 08:04:13 INFO - PID 963 | RSS: Main: 144633856 08:04:13 INFO - PID 963 | 08:07:15 INFO - PID 963 | Cycle 1(1): loaded http://localhost:56567/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56567/tests/dromaeo/cssquery-mootools.html) 08:07:16 INFO - PID 963 | RSS: Main: 146391040 08:07:16 INFO - PID 963 | 08:10:17 INFO - PID 963 | Cycle 1(1): loaded http://localhost:56567/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56567/tests/dromaeo/cssquery-prototype.html) 08:10:18 INFO - PID 963 | RSS: Main: 145960960 08:10:18 INFO - PID 963 | 08:13:21 INFO - PID 963 | Cycle 1(1): loaded http://localhost:56567/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56567/tests/dromaeo/cssquery-yui.html) 08:13:21 INFO - PID 963 | RSS: Main: 146993152 08:13:21 INFO - PID 963 | 08:16:23 INFO - PID 963 | Cycle 1(1): loaded http://localhost:56567/tests/dromaeo/cssquery-yui.html (next: http://localhost:56567/tests/dromaeo/cssquery-dojo.html) 08:16:23 INFO - PID 963 | RSS: Main: 149291008 08:16:23 INFO - PID 963 | 08:16:23 INFO - PID 963 | __start_tp_report 08:16:23 INFO - PID 963 | _x_x_mozilla_page_load 08:16:23 INFO - PID 963 | _x_x_mozilla_page_load_details 08:16:23 INFO - PID 963 | |i|pagename|runs| 08:16:23 INFO - PID 963 | |0;dojo.html;4004.199160167967;4026.3155263605186;4043.878683639492;4059.695522835788;4066.3697126945317;6508;6518.348165183485;6544.116544266522;6560.803175904719;6609.272979972199;6497.6101433914;6533.411992920636;6579.111819904311;6605.933940660592;6616.503762217829;5819.214406055192;5849.561282903788;5899.557533185007;5927.822165335026;5943.316518600352;5807.361190269067;5809.128630705386;5810.9418905811035;5874.353821079678;5917.260342457193;5860.355360910296;5972.522198224154;5976.8505787355225;5988.041913293874;5998.520118390518;5850.502707269887;5856.76572937082;5878.29460464747;5940.524758019348;5967.850803729898;5627.80302689408;5751.1085781703905;5762.135679648086;5809.070548712228;5811.244538210048;6377.872442551169;6409.743610255631;6423.582467139668;6425;6460.93539064608;7033.648317584151;7042.225355210922;7080.468964827607;7087.503874728771;7137.643117844087;6939.340762627542;7021.648917554102;7074.257202993715;7139.8929016064285;7147.49967502275;6500.219973603198;6500.837479063014;6503.382178693017;6508.479321654257;6516.9674151629415;7045;7117.75087871922;7118;7274.4907856449545;7279.199288078413;6953.860922781516;7020.052292940388;7038;7056.54132481387;7064.646767661699;7049.9647501762165;7104.43164546835;7104.964475177591;7160.498765086498;7197.244289349543;6940.271271516519;6974.79075627732;7051.153861536648;7066.646667666596;7092.893606595905;7074.151101867706;7117.608531530712;7120.394766444807;7152.606606636685;7154;5152.252923326097;5155.896882062338;5192.7403629818355;5214.530692237717;5220.791168353223;4642.141203877226;4716.457607375127;4738.957429365534;4769.976150119227;4789.44921334051;6631.038499417654;6639.468842492589;6724.226713927863;6732.629705366154;6752.257251702309;6935.167779866348;6961.756338528228;7023.89464158038;7034.8944765828555;7051.153861536648;6466.450351720063;6559.409653131241;6642.036904648799;6646.634435106019;6651.068850360954;5766.3368712598585;5782.479576838105;5877.676727780014;5896.115582662549;5914.231149950565;7413.258674132544;7420.406367490587;7485.887711684329;7511.51175173612;7520.21037791035;6865.5537390069485;7020.333068358395;7038;7130.073090498202;7133.464990125657;7306.561606303639;7346.412287017132;7380.0590922907095;7380.5940673263485;7452.776416707507;6931.89602155958;7084.929150708427;7101.964490177516;7103.360697537247;7104;7095.467839911924;7099.609521476162;7114.217436081924;7202.991581178534;7205.171405288249;6634.436072933855;6786.355296246848;6786.524943253925;6787.321267873173;6836.077129587442;6819.147606549182;6821.317868213139;6869.003994420681;6886.208086070166;6901.551399159039;6753;6837.6581170942245;6850.554713943578;6850.760223392157;6866.279040700854;6807.489438292247;6854.554453960476;6867.793966181022;6889.965550172217;6949.583025018313;6519.445847103049;6595.8021259362295;6612.107365505787;6684.231313398827;6771.085903402977 08:16:23 INFO - PID 963 | |1;ext.html;975.7853272280105;981.3032746749811;994.3487016004516;1025.1491262252327;1041.115052205625;45515;46596;46662.76668616652;46702.532974670285;46726.53273467264;43374.13251734971;43531.78234108825;43697.34453983193;43784.343234851425;43908.78045609767;7977.521348719081;8077.515349079074;8088.231617996281;8122.553259570721;8126.878096828552;8171.264586187242;8174.754757357259;8242.423030387876;8246.257836794688;8253.628586713612;8746.737597872074;9016.8647470288;9019.54902254888;9025.95487022564;9027.503487308195;8871.911280887172;8922.866157007682;8925.73222803317;8945;8971.551422428885;8824.382293239476;8848.734537963806;8888.822223555493;8907;8911.376203665746;40849.59150408488;40864.18271634551;40994.59005409967;41008.794956025326;41153.1769364614;3901.9804900975596;3944.1914407546383;3945.3884647879627;3950.4271880577444;3957.841686332572;32998.17504562381;33076.6692333076;33120.834395827864;34116.658833411595;34124.14689632754;5092.236164575306;5092.9236061459105;5329.2006199070065;5376.301080859503;5393.919091213635;227144;228036;228449;228836.99999999834;229450;53362;53519;53527;53537.1969420459;53573.46426535763;55829.72085139548;56028.43971560314;56481;56520.43479565234;56559.43440565624;18133.637327253644;18441.07794610264;18593.34923277679;18610;18667;15562.766558501631;15605;15620.531384058497;15629.843701563066;15632.531024069296;15213.467528636445;15364;15456.92271538635;15526.378944842305;15587;6026.487748541335;6059.939400606026;6085.4523092921845;6230.314665386803;6359.427651511386;1138.772245550893;1139.0294790013324;1143.2968724234536;1144.1933436926947;1145.169751929844;26367.736322636527;26573.33566660849;26576.867115664685;26871.193864184104;27129.728702713113;1233.2353940556911;1249.5813902342636;1252.6680429686141;1258.0250306012913;1281.5450515067203;13916.165030098227;14306.213158276394;14392.496262630757;14411.207383593794;14627.122372657675;282722;326129;330319;334394;334637;64062.67968660127;64337.35662643314;64452.35547644558;64607.67696161489;64889.67555162194;1542.2520077762276;1575.9054456732404;1582.208895552247;1599.392230952254;1599.5201439568084;31306.530402044442;31352.529712054795;31716.524252136696;32204.677953221104;32494;44388.33417498805;44575.33137003012;44700.10599787921;44711;44717.55282447134;281165;283663;286062;286698;287296;36616.45075323819;37713.24573508569;38428.42357364698;38442.615573843905;38453.80773096117;46100.76949615365;47806;48135.27797082977;48140;48401.757991209815;35161.648383515836;35240;35480;35574;35655.28689426145;34795.65204348025;35108;35282.294354113284;35745;36077.27845443129;39865.601343986185;40067.599324007555;40093.198136037696;40321.1935761289;40390.79804600958;33909.830450848574;34174.82912585421;34406.82796586001;34900.301993960486;34944.30111397707;25448;25595.872020639777;25818.741812581633;26089;26318.21045368642 08:16:23 INFO - PID 963 | |2;jquery.html;622.9563930524865;631.9462845658119;635.1013316157637;647.1005302629346;653.493542504559;17960.905442350264;17964.730529042074;18448.538786530327;18502.259909603617;18514.629707405842;25700.742992570067;26473;26617.600735988977;26782.464350712973;26786.598201027;6774.220964589062;6906.274841141683;6917.0661960635425;6937.063496427968;6961.23426423093;6661.26726060133;6834.829129271759;6840.41856442203;6904.482163837707;6908.06741089954;6885.724571017154;6887.139107611549;6889.517733758639;6901.585904845725;6904.10246667935;6858.177018757731;6888.345607167311;6897.172339319314;6946.548474349152;6953.269906659779;6434.195725534309;6456.128422662928;6460.095586617877;6471.83820404489;6473.190851143607;25787.871060644764;26768.196954091407;26855.32861678454;26990.595141072703;27174.320641984108;2292.3008482412856;2318.0959425823944;2324.140068174769;2340.9180678676335;2345.2729653807205;20488.897555512278;21080.89459552708;21083.789162108336;21084.683729744065;21118.3664490064;2518.1312447205682;2539.263613552086;2601.843889366644;2614.3202767280454;2625;136977;143849.28075359555;144984.27507862498;146051;150466;16114.67770644604;16459.91770041166;16683.833161668226;16932.91533542349;17054.232559534852;26808.59787103195;26849;26915.596266056025;26970.325741856217;26974.5953810693;11318.943405283086;11492;11575.305481671126;11650.76698466043;11702.648920532398;10471.790564188826;10510.684679459628;10565.947170264253;10662.253642245123;10805.83791243132;9808.509574521388;9895.158911492605;10020.394697378733;10123.03831136041;10307.433091179908;2804.298925268683;2880.2079428157426;2955.7930944833656;2959.792814502964;2988.475877302548;1017.1862509991977;1068.8302745426797;1073.6886302972207;1076.4563895233014;1081.4971038467152;20990.580188396452;21013.78986210149;21238.25666101679;21360.572788544454;21445.892770536047;1134.2683968840051;1137.9943100284445;1138.4706111658118;1140.8516892803882;1150.1201580790703;11353.659390218307;11479.827802582968;11553.191276610725;11589.24669896454;11810.52757889692;608768;608811;618918;649276;665178;138538;139499;141842;142155.28922355323;142448;1138.6486904542094;1197.4611424858674;1233.2662066070673;1264.1277518512197;1297.772889744291;21763.891180543997;22064.007119679973;22220;22309.888450557642;22473.10107595743;62529;62623.37376626176;62995.6850215746;64204.35795641984;64921.35078649342;80540.59729701314;80963;81174.59412702899;81814.18185818066;84243.5787821057;15783.131927744083;15881.444149454714;16045.037297762172;16117.113558754381;16224.918875405547;24407.75592244055;24619.63070553979;24665.876670616533;24683;24767.50464990726;19106.93876736149;19165.233390664216;19168.424947251606;19259.22963081461;19431.514212144244;16050.357985680443;16589.834101658827;17926.372576959744;18217.271309148015;18417.079146042914;20156.395308140778;20234.291799786937;20259.898700506405;20328.59342813106;20502.69245961282;14822.40710371573;15758.054516728575;15766.605834854221;15771.68456630838;15798.684026319179;19852.801471985094;19854.90072549628;19903.60192796165;20253;20272 08:16:23 INFO - PID 963 | |3;mootools.html;1426.201327256736;1477.290900367823;1482.4292647330774;1492.0749135535968;1503.1507198432885;17599.73600395995;18084.186211620476;18154.364597239066;18185.272589096454;18199.090045497724;17675.469735907944;17862.910685446586;17867.821321786778;17899;17909.194086266118;3103.2707313781243;3143.544186093015;3177.841107944605;3179.650238473766;3181.6182058153054;2685.8254213476157;2727.8772455239464;2752.6008728734323;2753.4768394005073;2754.325190328362;2637.4197676511135;2728.086091159464;2728.6179934809143;2745.6156138140577;2748.560230363142;2735.7811375089946;2737.657792775903;2760.30992251937;2768.5985532097834;2770.252031951382;3042.3915216956475;3081.0910781319562;3108.8911888084035;3124.390743804959;3133.1697100268448;17205.655886882316;17238.827611723973;17352.305907763675;17460.388886388915;17515.64968700619;8458.154184581554;8765.430247033986;8855.291576673852;8877.023527411979;8887.911120888837;5948.256467941507;6128.816135515897;6157.907631385533;6184.381561843825;6225.875482490324;2185.5519618478334;2187.58435897179;2210.7347118345742;2251.909923603054;2254.8308876834303;32561.348773024645;32786.50820237699;32909.17727056819;32978.83510582456;33021.33957320864;7827.804304892423;7851.646675899598;7917.129115797257;8071.354291656653;8078.8788168177525;21241.575168496543;21282;21290.467738306357;21308.254211102536;21320.573588528143;8872.8669069964;9032.548372581345;9057.275417966546;9067.410618309788;9071.138241867013;8157.796055098552;8222.753317400488;8250.174982501701;8295;8300.377471689648;6002.6698531580305;6278.121063051177;6373.267074286424;6381.627949990681;6449.871002580016;1733.5037545933815;1771.8936863787956;1806.3858288182082;1863.087087327227;1880.6802843516634;943.306669597845;976.7558110472382;980.127686359141;987.9950600246952;998.4508520313798;9745;10125.797484050425;10126.341787783771;10129.493525323851;10368.274220804622;806.5281810141016;815.351795322708;821.6836517940636;835.8662613981734;870.4342177584494;5990.101484777319;6175.289841668266;6225.657588832658;6227.2140124783455;6261.24865016201;25983.61024584633;27183.32041698934;27201.86399067992;27306.45387092287;27421.314467138083;25870.223893283233;26711.732882670924;26827.329316766845;26856.194314170607;26927.057552985552;13002.349882505838;13355.666108347368;13444.932775336061;13450.26023568713;13522.864771352357;9806.362586431858;9953.153981911619;9985.450800205768;10019.048190421898;10135.138513226459;22384;22600.886995564917;22867.656985144902;22955.88522057379;23479;23623.64564531499;23646.645300320164;23848.523029539658;23916.28251152468;24391.390215244763;12846.357682116044;12865.16376435566;13002.414891330101;13048.934755326542;13080.476780928657;16187.59531011687;16282.755758663867;16401.67196656084;16434.096124712778;16490.917545412198;13067;13318.400671969592;13381.732365352833;13602.931985340407;13789.862101378858;12981.675458113623;12981.935090324489;13033.804492932424;13092.149010314299;13459.798103028266;11206.719832004264;11281.71795705114;11321.433928303717;11343.773124537298;11354.886451135713;7844.9607751960875;7945.920540794518;7996.080450748006;8006.479578827358;8129.065157506962;8281;8461.069282379056;8510.787230319043;8512.489250644981;8639.049704532623 08:16:23 INFO - PID 963 | |4;prototype.html;573.8909607174636;593.3888095261882;596.7374355283672;619.4332186049764;620.5159975219328;6253.248570628925;6308.810735677931;6337.588056776306;6360.346309959409;6467.320931302216;6219.906701399471;6227.813165605028;6281.968590157055;6318.557700960934;6348.714307856146;5214.817481388142;5244.160934250521;5263.657862238962;5275.208718692188;5289.603279754034;4963.925541116868;5181.455947125554;5193.221016847484;5224.425313215544;5226.843194704165;4997.350344455216;5018.84943451697;5046.24306354048;5085.516875896784;5092.465291144432;5051.924221136685;5053.166227572447;5060.721660308682;5063.56926703085;5085.440601533828;4610.815567377301;4611.608013318838;4631.8378856739855;4643.9767801161115;4644.349791029259;6165.290991535986;6196.180737764352;6221.222347206599;6228.314885362652;6302.676437948036;5932.614380065325;5973.820785376446;5978.312494063195;5992.700364981733;6023.69881505923;6674.766383176614;6693.397594216543;6695.765648202289;6695.899561506581;6703.798886033427;5141.357330333708;5166.793328266865;5178.611604129667;5191.948080519184;5200.24596433515;6772.390484856386;6906.06768086307;7001.754938577176;7005.579665220052;7037.366637002694;5338.706371149624;5343.385510666245;5351.732413379315;5353.812616558402;5365.248865158881;5422.295101636762;5451.672899626035;5453.972730136324;5456.208849716848;5461.563074953994;5881.588288819741;5889.293284805851;5982.880342393128;5995.61028533144;6014.639121652627;6727.996841879074;6769.42459890905;6780.525363224527;6786.524943254023;6798.864022719518;1848.454705861774;1863.226760894241;1871.6911709568042;1891.5460289530413;1896.2130715753083;1313.9211647301192;1320.5972178485463;1329.2622594459974;1338.4044100375434;1352.6956434802287;6294.622322660655;6351.872962540723;6367.872642547123;6380.170577824853;6394.744210231632;6690.498212634072;6704.564203326768;6751.493637977172;6761.29006454325;6804.863902722017;6228.532860035515;6263.592866463757;6334.113224148623;6373.426391624776;6400.135981642418;3499.1077275295147;3560.483729859156;3571.5714114306447;3571.6785489305566;3614.2771445710964;7132.465065120033;7144.1784194818265;7154.676384868816;7196.96401517989;7213.8557228855125;6942.687579058955;6993.160820701447;6998.790036298919;7019.964900175466;7061.717531298793;6982.930170698228;7002.299770022957;7039.859202815915;7107.005019297302;7119.67961441726;6666.533342665967;6719.857624203994;6738.663066846736;6780.627065511444;6786.932130678828;6758.695858686273;6768.4585233182215;6793.694283757144;6801.217859945971;6819.761308354184;7088.503804733826;7135.429165666838;7153.928460715326;7207.459440541876;7264.382527485223;5295.682259064469;5303.257543943928;5335.519803217729;5361.303030605996;5405.378381486179;6403.423691867754;6687.297833727389;6733.663316834237;6810.965945170243;6839.726410943506;6459.257185423737;6548.476121910331;6578.210614726264;6653.268140504638;6707.5304728668525;6543.836404089745;6595.934040659532;6642.800715978528;6667.733290668319;6696.296888826603;6538.934610654022;6595.703193356215;6599.175103112111;6622.966885165543;6640.50196235294;6553.344665533408;6649.767258146136;6705.798826035227;6726.443951298475;6737.966310168418;6334.239891213084;6468.838279043061;6529.151210342625;6573.737050517926;6581.539292249497 08:16:23 INFO - PID 963 | |5;yui.html;983.7540614846289;1014.2899970020985;1025.2669341420883;1026.1482969135614;1038.9324693894896;20587.691184632244;21034.263800766937;21061.26285580005;21063;21149.894250528745;17422.82577174228;17520.12399380029;17645.647087058253;18378.356757513455;18609.44171674852;1053.752368193474;1068.3750006246355;1069.7004838645153;1070.2668671959693;1074.511097450658;1088.9782204355909;1093.7101668057971;1103.6854496468509;1105.4141305108305;1118.4799068433176;1149.5746573767717;1149.999500434623;1150.1086657840158;1150.551284998847;1161.1233518693416;1140.4069883660534;1142.1205671632838;1144.8339990701343;1150.2235990706324;1164.889335513129;841.9896124650444;845.2012847858776;847.8389106069889;852.0627309958995;858.2533196119354;20443.59112817735;21230.5753884923;21375.893120534452;21522.677159842617;22201.11195552176;20296.391108266627;20461.18155273802;20659.070341834653;20666.48333791652;20670.17319307226;18476.260949562;18513.62972740551;18705.53236169093;18706.719399208887;18770.249190032384;3676.246369494274;3761.54861416629;3771.302309072803;3825.61743825618;3836.482074919878;153261;161704.99999999884;162503;167522;170450;17203.65592688164;17819.554511137325;17824.821751782576;17833;17835.643287134186;34319.31361372759;35271.64728352735;35772.46341304883;35788.28423431569;35878.64121358805;10228.437435940947;10289.588416463257;10322.432266225429;10354.896451035544;10469.162467002621;4636.652251081183;4703.035877645075;4708.4820669726305;4723.740194289278;4733.029728905566;6282.748690052438;6337.461315788118;6364.936350636527;6400.103985442041;6403.391677790602;1304.2565737529708;1305.9804102938463;1308.718625495504;1318.123447907131;1340.0150889096506;741.4550305525435;763.6449051191223;767.1484652036236;780.0834020026447;784.5410434895535;13065.673358166121;13244.404001819941;13509.256990865597;13943.302834858216;13994.020418570806;770.2490072179692;774.6785084190111;777.782220978127;788.1724189600897;793.7777422321759;6109.969450152721;6112.113743507255;6175.752969881244;6273.560850740404;6293.465055470245;157950;159518;160341.58719397045;160953;162195;134084;134283;137664.16791604197;137998.3100084493;144932;120033.39983300028;121215;123196.38401808112;125097;125269.37365313114;13397.12440258384;13568.86431135676;13711.382987765375;13733.725325493633;13956.855364123565;54361;54395;54783.726081369336;54824.725876370365;55531.16703249535;89629;97424.512877438;97524.51237743766;97538;99826;19490;19885.801141988395;19979.800201998376;20066.19735210575;20398.28605998783;20728.896355518125;21033.579328413653;21290.254841081107;22219.666704999116;22569;19751.703724444433;19993.300234491722;20092.296769612993;20121.798782011992;20178.899105504377;18492.260309588004;18671.066446677884;18777.624447511247;19134;20447.59104817925;19622.411327660193;19753.209871604973;20099.698504522737;20142.194312227934;20436.182552697726;15011.099334039993;15058.096514209183;15183.620409489851;15332.770008450105;15373.077615343114;14303.427862885368;14407.495737649551;14640.414383424544;15072;15075.924620376827 08:16:23 INFO - PID 963 | __end_tp_report 08:16:23 INFO - PID 963 | __start_cc_report 08:16:23 INFO - PID 963 | _x_x_mozilla_cycle_collect,259 08:16:23 INFO - PID 963 | __end_cc_report 08:16:23 INFO - PID 963 | __startTimestamp1509030983518__endTimestamp 08:16:23 INFO - PID 963 | 08:16:23 INFO - PID 963 | ------- Summary: start ------- 08:16:23 INFO - PID 963 | Number of tests: 6 08:16:23 INFO - PID 963 | 08:16:23 INFO - PID 963 | [#0] dojo.html Cycles:165 Average:6519.47 Median:6786.36 stddev:768.36 (11.3%) stddev-sans-first:744.94 08:16:23 INFO - PID 963 | Values: 4004.2 4026.3 4043.9 4059.7 4066.4 6508.0 6518.3 6544.1 6560.8 6609.3 6497.6 6533.4 6579.1 6605.9 6616.5 5819.2 5849.6 5899.6 5927.8 5943.3 5807.4 5809.1 5810.9 5874.4 5917.3 5860.4 5972.5 5976.9 5988.0 5998.5 5850.5 5856.8 5878.3 5940.5 5967.9 5627.8 5751.1 5762.1 5809.1 5811.2 6377.9 6409.7 6423.6 6425.0 6460.9 7033.6 7042.2 7080.5 7087.5 7137.6 6939.3 7021.6 7074.3 7139.9 7147.5 6500.2 6500.8 6503.4 6508.5 6517.0 7045.0 7117.8 7118.0 7274.5 7279.2 6953.9 7020.1 7038.0 7056.5 7064.6 7050.0 7104.4 7105.0 7160.5 7197.2 6940.3 6974.8 7051.2 7066.6 7092.9 7074.2 7117.6 7120.4 7152.6 7154.0 5152.3 5155.9 5192.7 5214.5 5220.8 4642.1 4716.5 4739.0 4770.0 4789.4 6631.0 6639.5 6724.2 6732.6 6752.3 6935.2 6961.8 7023.9 7034.9 7051.2 6466.5 6559.4 6642.0 6646.6 6651.1 5766.3 5782.5 5877.7 5896.1 5914.2 7413.3 7420.4 7485.9 7511.5 7520.2 6865.6 7020.3 7038.0 7130.1 7133.5 7306.6 7346.4 7380.1 7380.6 7452.8 6931.9 7084.9 7102.0 7103.4 7104.0 7095.5 7099.6 7114.2 7203.0 7205.2 6634.4 6786.4 6786.5 6787.3 6836.1 6819.1 6821.3 6869.0 6886.2 6901.6 6753.0 6837.7 6850.6 6850.8 6866.3 6807.5 6854.6 6867.8 6890.0 6949.6 6519.4 6595.8 6612.1 6684.2 6771.1 08:16:23 INFO - PID 963 | 08:16:23 INFO - PID 963 | [#1] ext.html Cycles:180 Average:46143.12 Median:38448.21 stddev:73331.61 (190.7%) stddev-sans-first:73458.90 08:16:23 INFO - PID 963 | Values: 975.8 981.3 994.3 1025.1 1041.1 45515.0 46596.0 46662.8 46702.5 46726.5 43374.1 43531.8 43697.3 43784.3 43908.8 7977.5 8077.5 8088.2 8122.6 8126.9 8171.3 8174.8 8242.4 8246.3 8253.6 8746.7 9016.9 9019.5 9026.0 9027.5 8871.9 8922.9 8925.7 8945.0 8971.6 8824.4 8848.7 8888.8 8907.0 8911.4 40849.6 40864.2 40994.6 41008.8 41153.2 3902.0 3944.2 3945.4 3950.4 3957.8 32998.2 33076.7 33120.8 34116.7 34124.1 5092.2 5092.9 5329.2 5376.3 5393.9 227144.0 228036.0 228449.0 228837.0 229450.0 53362.0 53519.0 53527.0 53537.2 53573.5 55829.7 56028.4 56481.0 56520.4 56559.4 18133.6 18441.1 18593.3 18610.0 18667.0 15562.8 15605.0 15620.5 15629.8 15632.5 15213.5 15364.0 15456.9 15526.4 15587.0 6026.5 6059.9 6085.5 6230.3 6359.4 1138.8 1139.0 1143.3 1144.2 1145.2 26367.7 26573.3 26576.9 26871.2 27129.7 1233.2 1249.6 1252.7 1258.0 1281.5 13916.2 14306.2 14392.5 14411.2 14627.1 282722.0 326129.0 330319.0 334394.0 334637.0 64062.7 64337.4 64452.4 64607.7 64889.7 1542.3 1575.9 1582.2 1599.4 1599.5 31306.5 31352.5 31716.5 32204.7 32494.0 44388.3 44575.3 44700.1 44711.0 44717.6 281165.0 283663.0 286062.0 286698.0 287296.0 36616.5 37713.2 38428.4 38442.6 38453.8 46100.8 47806.0 48135.3 48140.0 48401.8 35161.6 35240.0 35480.0 35574.0 35655.3 34795.7 35108.0 35282.3 35745.0 36077.3 39865.6 40067.6 40093.2 40321.2 40390.8 33909.8 34174.8 34406.8 34900.3 34944.3 25448.0 25595.9 25818.7 26089.0 26318.2 08:16:23 INFO - PID 963 | 08:16:23 INFO - PID 963 | [#2] jquery.html Cycles:180 Average:40637.56 Median:21299.41 stddev:105323.63 (494.5%) stddev-sans-first:105576.23 08:16:23 INFO - PID 963 | Values: 623.0 631.9 635.1 647.1 653.5 17960.9 17964.7 18448.5 18502.3 18514.6 25700.7 26473.0 26617.6 26782.5 26786.6 6774.2 6906.3 6917.1 6937.1 6961.2 6661.3 6834.8 6840.4 6904.5 6908.1 6885.7 6887.1 6889.5 6901.6 6904.1 6858.2 6888.3 6897.2 6946.5 6953.3 6434.2 6456.1 6460.1 6471.8 6473.2 25787.9 26768.2 26855.3 26990.6 27174.3 2292.3 2318.1 2324.1 2340.9 2345.3 20488.9 21080.9 21083.8 21084.7 21118.4 2518.1 2539.3 2601.8 2614.3 2625.0 136977.0 143849.3 144984.3 146051.0 150466.0 16114.7 16459.9 16683.8 16932.9 17054.2 26808.6 26849.0 26915.6 26970.3 26974.6 11318.9 11492.0 11575.3 11650.8 11702.6 10471.8 10510.7 10565.9 10662.3 10805.8 9808.5 9895.2 10020.4 10123.0 10307.4 2804.3 2880.2 2955.8 2959.8 2988.5 1017.2 1068.8 1073.7 1076.5 1081.5 20990.6 21013.8 21238.3 21360.6 21445.9 1134.3 1138.0 1138.5 1140.9 1150.1 11353.7 11479.8 11553.2 11589.2 11810.5 608768.0 608811.0 618918.0 649276.0 665178.0 138538.0 139499.0 141842.0 142155.3 142448.0 1138.6 1197.5 1233.3 1264.1 1297.8 21763.9 22064.0 22220.0 22309.9 22473.1 62529.0 62623.4 62995.7 64204.4 64921.4 80540.6 80963.0 81174.6 81814.2 84243.6 15783.1 15881.4 16045.0 16117.1 16224.9 24407.8 24619.6 24665.9 24683.0 24767.5 19106.9 19165.2 19168.4 19259.2 19431.5 16050.4 16589.8 17926.4 18217.3 18417.1 20156.4 20234.3 20259.9 20328.6 20502.7 14822.4 15758.1 15766.6 15771.7 15798.7 19852.8 19854.9 19903.6 20253.0 20272.0 08:16:23 INFO - PID 963 | 08:16:23 INFO - PID 963 | [#3] mootools.html Cycles:180 Average:11089.28 Median:14964.87 stddev:8313.95 (55.6%) stddev-sans-first:8305.57 08:16:23 INFO - PID 963 | Values: 1426.2 1477.3 1482.4 1492.1 1503.2 17599.7 18084.2 18154.4 18185.3 18199.1 17675.5 17862.9 17867.8 17899.0 17909.2 3103.3 3143.5 3177.8 3179.7 3181.6 2685.8 2727.9 2752.6 2753.5 2754.3 2637.4 2728.1 2728.6 2745.6 2748.6 2735.8 2737.7 2760.3 2768.6 2770.3 3042.4 3081.1 3108.9 3124.4 3133.2 17205.7 17238.8 17352.3 17460.4 17515.6 8458.2 8765.4 8855.3 8877.0 8887.9 5948.3 6128.8 6157.9 6184.4 6225.9 2185.6 2187.6 2210.7 2251.9 2254.8 32561.3 32786.5 32909.2 32978.8 33021.3 7827.8 7851.6 7917.1 8071.4 8078.9 21241.6 21282.0 21290.5 21308.3 21320.6 8872.9 9032.5 9057.3 9067.4 9071.1 8157.8 8222.8 8250.2 8295.0 8300.4 6002.7 6278.1 6373.3 6381.6 6449.9 1733.5 1771.9 1806.4 1863.1 1880.7 943.3 976.8 980.1 988.0 998.5 9745.0 10125.8 10126.3 10129.5 10368.3 806.5 815.4 821.7 835.9 870.4 5990.1 6175.3 6225.7 6227.2 6261.2 25983.6 27183.3 27201.9 27306.5 27421.3 25870.2 26711.7 26827.3 26856.2 26927.1 13002.3 13355.7 13444.9 13450.3 13522.9 9806.4 9953.2 9985.5 10019.0 10135.1 22384.0 22600.9 22867.7 22955.9 23479.0 23623.6 23646.6 23848.5 23916.3 24391.4 12846.4 12865.2 13002.4 13048.9 13080.5 16187.6 16282.8 16401.7 16434.1 16490.9 13067.0 13318.4 13381.7 13602.9 13789.9 12981.7 12981.9 13033.8 13092.1 13459.8 11206.7 11281.7 11321.4 11343.8 11354.9 7845.0 7945.9 7996.1 8006.5 8129.1 8281.0 8461.1 8510.8 8512.5 8639.0 08:16:23 INFO - PID 963 | 08:16:23 INFO - PID 963 | [#4] prototype.html Cycles:180 Average:5723.76 Median:6335.91 stddev:1579.69 (24.9%) stddev-sans-first:1536.10 08:16:23 INFO - PID 963 | Values: 573.9 593.4 596.7 619.4 620.5 6253.2 6308.8 6337.6 6360.3 6467.3 6219.9 6227.8 6282.0 6318.6 6348.7 5214.8 5244.2 5263.7 5275.2 5289.6 4963.9 5181.5 5193.2 5224.4 5226.8 4997.4 5018.8 5046.2 5085.5 5092.5 5051.9 5053.2 5060.7 5063.6 5085.4 4610.8 4611.6 4631.8 4644.0 4644.3 6165.3 6196.2 6221.2 6228.3 6302.7 5932.6 5973.8 5978.3 5992.7 6023.7 6674.8 6693.4 6695.8 6695.9 6703.8 5141.4 5166.8 5178.6 5191.9 5200.2 6772.4 6906.1 7001.8 7005.6 7037.4 5338.7 5343.4 5351.7 5353.8 5365.2 5422.3 5451.7 5454.0 5456.2 5461.6 5881.6 5889.3 5982.9 5995.6 6014.6 6728.0 6769.4 6780.5 6786.5 6798.9 1848.5 1863.2 1871.7 1891.5 1896.2 1313.9 1320.6 1329.3 1338.4 1352.7 6294.6 6351.9 6367.9 6380.2 6394.7 6690.5 6704.6 6751.5 6761.3 6804.9 6228.5 6263.6 6334.1 6373.4 6400.1 3499.1 3560.5 3571.6 3571.7 3614.3 7132.5 7144.2 7154.7 7197.0 7213.9 6942.7 6993.2 6998.8 7020.0 7061.7 6982.9 7002.3 7039.9 7107.0 7119.7 6666.5 6719.9 6738.7 6780.6 6786.9 6758.7 6768.5 6793.7 6801.2 6819.8 7088.5 7135.4 7153.9 7207.5 7264.4 5295.7 5303.3 5335.5 5361.3 5405.4 6403.4 6687.3 6733.7 6811.0 6839.7 6459.3 6548.5 6578.2 6653.3 6707.5 6543.8 6595.9 6642.8 6667.7 6696.3 6538.9 6595.7 6599.2 6623.0 6640.5 6553.3 6649.8 6705.8 6726.4 6738.0 6334.2 6468.8 6529.2 6573.7 6581.5 08:16:23 INFO - PID 963 | 08:16:23 INFO - PID 963 | [#5] yui.html Cycles:180 Average:30169.35 Median:18773.94 stddev:45030.84 (239.9%) stddev-sans-first:45103.84 08:16:23 INFO - PID 963 | Values: 983.8 1014.3 1025.3 1026.1 1038.9 20587.7 21034.3 21061.3 21063.0 21149.9 17422.8 17520.1 17645.6 18378.4 18609.4 1053.8 1068.4 1069.7 1070.3 1074.5 1089.0 1093.7 1103.7 1105.4 1118.5 1149.6 1150.0 1150.1 1150.6 1161.1 1140.4 1142.1 1144.8 1150.2 1164.9 842.0 845.2 847.8 852.1 858.3 20443.6 21230.6 21375.9 21522.7 22201.1 20296.4 20461.2 20659.1 20666.5 20670.2 18476.3 18513.6 18705.5 18706.7 18770.2 3676.2 3761.5 3771.3 3825.6 3836.5 153261.0 161705.0 162503.0 167522.0 170450.0 17203.7 17819.6 17824.8 17833.0 17835.6 34319.3 35271.6 35772.5 35788.3 35878.6 10228.4 10289.6 10322.4 10354.9 10469.2 4636.7 4703.0 4708.5 4723.7 4733.0 6282.7 6337.5 6364.9 6400.1 6403.4 1304.3 1306.0 1308.7 1318.1 1340.0 741.5 763.6 767.1 780.1 784.5 13065.7 13244.4 13509.3 13943.3 13994.0 770.2 774.7 777.8 788.2 793.8 6110.0 6112.1 6175.8 6273.6 6293.5 157950.0 159518.0 160341.6 160953.0 162195.0 134084.0 134283.0 137664.2 137998.3 144932.0 120033.4 121215.0 123196.4 125097.0 125269.4 13397.1 13568.9 13711.4 13733.7 13956.9 54361.0 54395.0 54783.7 54824.7 55531.2 89629.0 97424.5 97524.5 97538.0 99826.0 19490.0 19885.8 19979.8 20066.2 20398.3 20728.9 21033.6 21290.3 22219.7 22569.0 19751.7 19993.3 20092.3 20121.8 20178.9 18492.3 18671.1 18777.6 19134.0 20447.6 19622.4 19753.2 20099.7 20142.2 20436.2 15011.1 15058.1 15183.6 15332.8 15373.1 14303.4 14407.5 14640.4 15072.0 15075.9 08:16:23 INFO - PID 963 | -------- Summary: end -------- 08:16:23 INFO - PID 963 | 08:16:24 INFO - TEST-INFO | 963: exit 0 08:16:24 INFO - TEST-OK | dromaeo_css | took 1106763ms 08:16:24 INFO - TEST-START | kraken 08:16:24 INFO - Initialising browser for kraken test... 08:16:24 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56567/getInfo.html -profile /tmp/tmp1C8BjW/profile 08:16:24 INFO - TEST-INFO | started process 1405 (/builds/slave/test/build/application/firefox/firefox http://localhost:56567/getInfo.html) 08:16:28 INFO - TEST-INFO | 1405: exit 0 08:16:29 INFO - Browser initialized. 08:16:29 INFO - Running cycle 1/1 for kraken test... 08:16:29 INFO - Using env: {'DISPLAY': ':0', 08:16:29 INFO - 'HOME': '/home/cltbld', 08:16:29 INFO - 'JSGC_DISABLE_POISONING': '1', 08:16:29 INFO - 'LANG': 'en_US.UTF-8', 08:16:29 INFO - 'LANGUAGE': 'en_US:en', 08:16:29 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 08:16:29 INFO - 'LOGNAME': 'cltbld', 08:16:29 INFO - 'MAIL': '/var/mail/cltbld', 08:16:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:16:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:16:29 INFO - 'MOZ_CRASHREPORTER': '1', 08:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:29 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 08:16:29 INFO - 'MOZ_NO_REMOTE': '1', 08:16:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:16:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:16:29 INFO - 'NO_EM_RESTART': '1', 08:16:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:29 INFO - 'PWD': '/builds/slave/test', 08:16:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:16:29 INFO - 'RUST_BACKTRACE': 'full', 08:16:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:16:29 INFO - 'SHELL': '/bin/bash', 08:16:29 INFO - 'SHLVL': '1', 08:16:29 INFO - 'TERM': 'linux', 08:16:29 INFO - 'TMOUT': '86400', 08:16:29 INFO - 'USER': 'cltbld', 08:16:29 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969', 08:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:29 INFO - '_': '/tools/buildbot/bin/python'} 08:16:29 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1C8BjW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:16:29 INFO - PID 1638 | 08:16:29 INFO - PID 1638 | (/builds/slave/test/build/application/firefox/firefox:1686): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 08:16:29 INFO - PID 1638 | 08:16:40 INFO - PID 1638 | RSS: Main: 143708160 08:16:40 INFO - PID 1638 | 08:16:42 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:56567/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56567/tests/kraken/driver.html?testName=audio-beat-detection) 08:16:43 INFO - PID 1638 | RSS: Main: 149647360 08:16:43 INFO - PID 1638 | 08:16:46 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:56567/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56567/tests/kraken/driver.html?testName=audio-dft) 08:16:46 INFO - PID 1638 | RSS: Main: 149946368 08:16:46 INFO - PID 1638 | 08:16:49 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:56567/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56567/tests/kraken/driver.html?testName=audio-fft) 08:16:50 INFO - PID 1638 | RSS: Main: 151584768 08:16:50 INFO - PID 1638 | 08:16:52 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:56567/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56567/tests/kraken/driver.html?testName=audio-oscillator) 08:16:52 INFO - PID 1638 | RSS: Main: 151699456 08:16:52 INFO - PID 1638 | 08:16:54 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:56567/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56567/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:16:55 INFO - PID 1638 | RSS: Main: 151310336 08:16:55 INFO - PID 1638 | 08:16:58 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:56567/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56567/tests/kraken/driver.html?testName=imaging-darkroom) 08:16:58 INFO - PID 1638 | RSS: Main: 151584768 08:16:58 INFO - PID 1638 | 08:17:02 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:56567/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56567/tests/kraken/driver.html?testName=imaging-desaturate) 08:17:02 INFO - PID 1638 | RSS: Main: 153309184 08:17:02 INFO - PID 1638 | 08:17:05 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:56567/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56567/tests/kraken/driver.html?testName=json-parse-financial) 08:17:06 INFO - PID 1638 | RSS: Main: 154312704 08:17:06 INFO - PID 1638 | 08:17:07 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:56567/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56567/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:17:08 INFO - PID 1638 | RSS: Main: 154279936 08:17:08 INFO - PID 1638 | 08:17:10 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:56567/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56567/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:17:10 INFO - PID 1638 | RSS: Main: 154562560 08:17:10 INFO - PID 1638 | 08:17:12 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:56567/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56567/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:17:13 INFO - PID 1638 | RSS: Main: 151465984 08:17:13 INFO - PID 1638 | 08:17:15 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:56567/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56567/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:17:16 INFO - PID 1638 | RSS: Main: 151101440 08:17:16 INFO - PID 1638 | 08:17:18 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:56567/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56567/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:17:19 INFO - PID 1638 | RSS: Main: 151781376 08:17:19 INFO - PID 1638 | 08:17:21 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:56567/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56567/tests/kraken/driver.html?testName=ai-astar) 08:17:21 INFO - PID 1638 | RSS: Main: 153358336 08:17:21 INFO - PID 1638 | 08:17:21 INFO - PID 1638 | __start_tp_report 08:17:21 INFO - PID 1638 | _x_x_mozilla_page_load 08:17:21 INFO - PID 1638 | _x_x_mozilla_page_load_details 08:17:21 INFO - PID 1638 | |i|pagename|runs| 08:17:21 INFO - PID 1638 | |0;ai-astar;106;105;105;105;106;107;108;107;105;106 08:17:21 INFO - PID 1638 | |1;audio-beat-detection;121;112;111;112;139;109;112;110;111;134 08:17:21 INFO - PID 1638 | |2;audio-dft;162;163;162;160;162;162;160;162;162;163 08:17:21 INFO - PID 1638 | |3;audio-fft;72;72;73;73;74;73;76;74;75;74 08:17:21 INFO - PID 1638 | |4;audio-oscillator;78;77;77;77;76;77;76;76;77;77 08:17:21 INFO - PID 1638 | |5;imaging-gaussian-blur;114;114;114;114;114;114;114;114;114;111 08:17:21 INFO - PID 1638 | |6;imaging-darkroom;149;149;148;149;149;147;147;151;157;150 08:17:21 INFO - PID 1638 | |7;imaging-desaturate;94;94;94;94;95;94;94;94;96;94 08:17:21 INFO - PID 1638 | |8;json-parse-financial;46;43;42;42;46;44;43;42;42;43 08:17:21 INFO - PID 1638 | |9;json-stringify-tinderbox;37;38;37;38;37;38;37;39;38;38 08:17:21 INFO - PID 1638 | |10;stanford-crypto-aes;72;71;69;70;70;70;70;72;70;71 08:17:21 INFO - PID 1638 | |11;stanford-crypto-ccm;108;107;102;106;102;109;104;107;107;107 08:17:21 INFO - PID 1638 | |12;stanford-crypto-pbkdf2;154;148;150;150;149;149;148;148;152;154 08:17:21 INFO - PID 1638 | |13;stanford-crypto-sha256-iterative;66;59;58;66;59;67;64;60;60;60 08:17:21 INFO - PID 1638 | __end_tp_report 08:17:21 INFO - PID 1638 | __start_cc_report 08:17:21 INFO - PID 1638 | _x_x_mozilla_cycle_collect,666 08:17:21 INFO - PID 1638 | __end_cc_report 08:17:21 INFO - PID 1638 | __startTimestamp1509031041434__endTimestamp 08:17:21 INFO - PID 1638 | 08:17:21 INFO - PID 1638 | ------- Summary: start ------- 08:17:21 INFO - PID 1638 | Number of tests: 14 08:17:21 INFO - PID 1638 | 08:17:21 INFO - PID 1638 | [#0] ai-astar Cycles:10 Average:106.00 Median:106.00 stddev:1.05 (1.0%) stddev-sans-first:1.12 08:17:21 INFO - PID 1638 | Values: 106.0 105.0 105.0 105.0 106.0 107.0 108.0 107.0 105.0 106.0 08:17:21 INFO - PID 1638 | 08:17:21 INFO - PID 1638 | [#1] audio-beat-detection Cycles:10 Average:117.10 Median:112.00 stddev:10.80 (9.6%) stddev-sans-first:11.36 08:17:21 INFO - PID 1638 | Values: 121.0 112.0 111.0 112.0 139.0 109.0 112.0 110.0 111.0 134.0 08:17:21 INFO - PID 1638 | 08:17:21 INFO - PID 1638 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.03 (0.6%) stddev-sans-first:1.09 08:17:21 INFO - PID 1638 | Values: 162.0 163.0 162.0 160.0 162.0 162.0 160.0 162.0 162.0 163.0 08:17:21 INFO - PID 1638 | 08:17:21 INFO - PID 1638 | [#3] audio-fft Cycles:10 Average:73.60 Median:74.00 stddev:1.26 (1.7%) stddev-sans-first:1.20 08:17:21 INFO - PID 1638 | Values: 72.0 72.0 73.0 73.0 74.0 73.0 76.0 74.0 75.0 74.0 08:17:21 INFO - PID 1638 | 08:17:21 INFO - PID 1638 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:77.00 stddev:0.63 (0.8%) stddev-sans-first:0.50 08:17:21 INFO - PID 1638 | Values: 78.0 77.0 77.0 77.0 76.0 77.0 76.0 76.0 77.0 77.0 08:17:21 INFO - PID 1638 | 08:17:21 INFO - PID 1638 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:114.00 stddev:0.95 (0.8%) stddev-sans-first:1.00 08:17:21 INFO - PID 1638 | Values: 114.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 111.0 08:17:21 INFO - PID 1638 | 08:17:21 INFO - PID 1638 | [#6] imaging-darkroom Cycles:10 Average:149.60 Median:149.00 stddev:2.88 (1.9%) stddev-sans-first:3.04 08:17:21 INFO - PID 1638 | Values: 149.0 149.0 148.0 149.0 149.0 147.0 147.0 151.0 157.0 150.0 08:17:21 INFO - PID 1638 | 08:17:21 INFO - PID 1638 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 08:17:21 INFO - PID 1638 | Values: 94.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 96.0 94.0 08:17:21 INFO - PID 1638 | 08:17:21 INFO - PID 1638 | [#8] json-parse-financial Cycles:10 Average:43.30 Median:43.00 stddev:1.57 (3.6%) stddev-sans-first:1.32 08:17:21 INFO - PID 1638 | Values: 46.0 43.0 42.0 42.0 46.0 44.0 43.0 42.0 42.0 43.0 08:17:21 INFO - PID 1638 | 08:17:21 INFO - PID 1638 | [#9] json-stringify-tinderbox Cycles:10 Average:37.70 Median:38.00 stddev:0.67 (1.8%) stddev-sans-first:0.67 08:17:21 INFO - PID 1638 | Values: 37.0 38.0 37.0 38.0 37.0 38.0 37.0 39.0 38.0 38.0 08:17:21 INFO - PID 1638 | 08:17:21 INFO - PID 1638 | [#10] stanford-crypto-aes Cycles:10 Average:70.50 Median:70.50 stddev:0.97 (1.4%) stddev-sans-first:0.87 08:17:21 INFO - PID 1638 | Values: 72.0 71.0 69.0 70.0 70.0 70.0 70.0 72.0 70.0 71.0 08:17:21 INFO - PID 1638 | 08:17:21 INFO - PID 1638 | [#11] stanford-crypto-ccm Cycles:10 Average:105.90 Median:107.00 stddev:2.42 (2.3%) stddev-sans-first:2.45 08:17:21 INFO - PID 1638 | Values: 108.0 107.0 102.0 106.0 102.0 109.0 104.0 107.0 107.0 107.0 08:17:21 INFO - PID 1638 | 08:17:21 INFO - PID 1638 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.20 Median:150.00 stddev:2.35 (1.6%) stddev-sans-first:2.05 08:17:21 INFO - PID 1638 | Values: 154.0 148.0 150.0 150.0 149.0 149.0 148.0 148.0 152.0 154.0 08:17:21 INFO - PID 1638 | 08:17:21 INFO - PID 1638 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:3.45 (5.6%) stddev-sans-first:3.32 08:17:21 INFO - PID 1638 | Values: 66.0 59.0 58.0 66.0 59.0 67.0 64.0 60.0 60.0 60.0 08:17:21 INFO - PID 1638 | -------- Summary: end -------- 08:17:21 INFO - PID 1638 | 08:17:21 INFO - PID 1638 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 08:17:22 INFO - TEST-INFO | 1638: exit 0 08:17:22 INFO - TEST-OK | kraken | took 57891ms 08:17:22 INFO - SUITE-END | took 1164s 08:17:22 INFO - Completed test suite (00:19:24) 08:17:22 INFO - javascript benchmark 08:17:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9404.310614179158, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [4004.199160167967, 4026.3155263605186, 4043.878683639492, 4059.695522835788, 4066.3697126945317, 6508.0, 6518.348165183485, 6544.116544266522, 6560.803175904719, 6609.272979972199, 6497.6101433914, 6533.411992920636, 6579.111819904311, 6605.933940660592, 6616.503762217829, 5819.214406055192, 5849.561282903788, 5899.557533185007, 5927.822165335026, 5943.316518600352, 5807.361190269067, 5809.128630705386, 5810.9418905811035, 5874.353821079678, 5917.260342457193, 5860.355360910296, 5972.522198224154, 5976.8505787355225, 5988.041913293874, 5998.520118390518, 5850.502707269887, 5856.76572937082, 5878.29460464747, 5940.524758019348, 5967.850803729898, 5627.80302689408, 5751.1085781703905, 5762.135679648086, 5809.070548712228, 5811.244538210048, 6377.872442551169, 6409.743610255631, 6423.582467139668, 6425.0, 6460.93539064608, 7033.648317584151, 7042.225355210922, 7080.468964827607, 7087.503874728771, 7137.643117844087, 6939.340762627542, 7021.648917554102, 7074.257202993715, 7139.8929016064285, 7147.49967502275, 6500.219973603198, 6500.837479063014, 6503.382178693017, 6508.479321654257, 6516.9674151629415, 7045.0, 7117.75087871922, 7118.0, 7274.4907856449545, 7279.199288078413, 6953.860922781516, 7020.052292940388, 7038.0, 7056.54132481387, 7064.646767661699, 7049.9647501762165, 7104.43164546835, 7104.964475177591, 7160.498765086498, 7197.244289349543, 6940.271271516519, 6974.79075627732, 7051.153861536648, 7066.646667666596, 7092.893606595905, 7074.151101867706, 7117.608531530712, 7120.394766444807, 7152.606606636685, 7154.0, 5152.252923326097, 5155.896882062338, 5192.7403629818355, 5214.530692237717, 5220.791168353223, 4642.141203877226, 4716.457607375127, 4738.957429365534, 4769.976150119227, 4789.44921334051, 6631.038499417654, 6639.468842492589, 6724.226713927863, 6732.629705366154, 6752.257251702309, 6935.167779866348, 6961.756338528228, 7023.89464158038, 7034.8944765828555, 7051.153861536648, 6466.450351720063, 6559.409653131241, 6642.036904648799, 6646.634435106019, 6651.068850360954, 5766.3368712598585, 5782.479576838105, 5877.676727780014, 5896.115582662549, 5914.231149950565, 7413.258674132544, 7420.406367490587, 7485.887711684329, 7511.51175173612, 7520.21037791035, 6865.5537390069485, 7020.333068358395, 7038.0, 7130.073090498202, 7133.464990125657, 7306.561606303639, 7346.412287017132, 7380.0590922907095, 7380.5940673263485, 7452.776416707507, 6931.89602155958, 7084.929150708427, 7101.964490177516, 7103.360697537247, 7104.0, 7095.467839911924, 7099.609521476162, 7114.217436081924, 7202.991581178534, 7205.171405288249, 6634.436072933855, 6786.355296246848, 6786.524943253925, 6787.321267873173, 6836.077129587442, 6819.147606549182, 6821.317868213139, 6869.003994420681, 6886.208086070166, 6901.551399159039, 6753.0, 6837.6581170942245, 6850.554713943578, 6850.760223392157, 6866.279040700854, 6807.489438292247, 6854.554453960476, 6867.793966181022, 6889.965550172217, 6949.583025018313, 6519.445847103049, 6595.8021259362295, 6612.107365505787, 6684.231313398827, 6771.085903402977], "value": 6467.752521426845, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [975.7853272280105, 981.3032746749811, 994.3487016004516, 1025.1491262252327, 1041.115052205625, 45515.0, 46596.0, 46662.76668616652, 46702.532974670285, 46726.53273467264, 43374.13251734971, 43531.78234108825, 43697.34453983193, 43784.343234851425, 43908.78045609767, 7977.521348719081, 8077.515349079074, 8088.231617996281, 8122.553259570721, 8126.878096828552, 8171.264586187242, 8174.754757357259, 8242.423030387876, 8246.257836794688, 8253.628586713612, 8746.737597872074, 9016.8647470288, 9019.54902254888, 9025.95487022564, 9027.503487308195, 8871.911280887172, 8922.866157007682, 8925.73222803317, 8945.0, 8971.551422428885, 8824.382293239476, 8848.734537963806, 8888.822223555493, 8907.0, 8911.376203665746, 40849.59150408488, 40864.18271634551, 40994.59005409967, 41008.794956025326, 41153.1769364614, 3901.9804900975596, 3944.1914407546383, 3945.3884647879627, 3950.4271880577444, 3957.841686332572, 32998.17504562381, 33076.6692333076, 33120.834395827864, 34116.658833411595, 34124.14689632754, 5092.236164575306, 5092.9236061459105, 5329.2006199070065, 5376.301080859503, 5393.919091213635, 227144.0, 228036.0, 228449.0, 228836.99999999834, 229450.0, 53362.0, 53519.0, 53527.0, 53537.1969420459, 53573.46426535763, 55829.72085139548, 56028.43971560314, 56481.0, 56520.43479565234, 56559.43440565624, 18133.637327253644, 18441.07794610264, 18593.34923277679, 18610.0, 18667.0, 15562.766558501631, 15605.0, 15620.531384058497, 15629.843701563066, 15632.531024069296, 15213.467528636445, 15364.0, 15456.92271538635, 15526.378944842305, 15587.0, 6026.487748541335, 6059.939400606026, 6085.4523092921845, 6230.314665386803, 6359.427651511386, 1138.772245550893, 1139.0294790013324, 1143.2968724234536, 1144.1933436926947, 1145.169751929844, 26367.736322636527, 26573.33566660849, 26576.867115664685, 26871.193864184104, 27129.728702713113, 1233.2353940556911, 1249.5813902342636, 1252.6680429686141, 1258.0250306012913, 1281.5450515067203, 13916.165030098227, 14306.213158276394, 14392.496262630757, 14411.207383593794, 14627.122372657675, 282722.0, 326129.0, 330319.0, 334394.0, 334637.0, 64062.67968660127, 64337.35662643314, 64452.35547644558, 64607.67696161489, 64889.67555162194, 1542.2520077762276, 1575.9054456732404, 1582.208895552247, 1599.392230952254, 1599.5201439568084, 31306.530402044442, 31352.529712054795, 31716.524252136696, 32204.677953221104, 32494.0, 44388.33417498805, 44575.33137003012, 44700.10599787921, 44711.0, 44717.55282447134, 281165.0, 283663.0, 286062.0, 286698.0, 287296.0, 36616.45075323819, 37713.24573508569, 38428.42357364698, 38442.615573843905, 38453.80773096117, 46100.76949615365, 47806.0, 48135.27797082977, 48140.0, 48401.757991209815, 35161.648383515836, 35240.0, 35480.0, 35574.0, 35655.28689426145, 34795.65204348025, 35108.0, 35282.294354113284, 35745.0, 36077.27845443129, 39865.601343986185, 40067.599324007555, 40093.198136037696, 40321.1935761289, 40390.79804600958, 33909.830450848574, 34174.82912585421, 34406.82796586001, 34900.301993960486, 34944.30111397707, 25448.0, 25595.872020639777, 25818.741812581633, 26089.0, 26318.21045368642], "value": 19552.71420522602, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.9563930524865, 631.9462845658119, 635.1013316157637, 647.1005302629346, 653.493542504559, 17960.905442350264, 17964.730529042074, 18448.538786530327, 18502.259909603617, 18514.629707405842, 25700.742992570067, 26473.0, 26617.600735988977, 26782.464350712973, 26786.598201027, 6774.220964589062, 6906.274841141683, 6917.0661960635425, 6937.063496427968, 6961.23426423093, 6661.26726060133, 6834.829129271759, 6840.41856442203, 6904.482163837707, 6908.06741089954, 6885.724571017154, 6887.139107611549, 6889.517733758639, 6901.585904845725, 6904.10246667935, 6858.177018757731, 6888.345607167311, 6897.172339319314, 6946.548474349152, 6953.269906659779, 6434.195725534309, 6456.128422662928, 6460.095586617877, 6471.83820404489, 6473.190851143607, 25787.871060644764, 26768.196954091407, 26855.32861678454, 26990.595141072703, 27174.320641984108, 2292.3008482412856, 2318.0959425823944, 2324.140068174769, 2340.9180678676335, 2345.2729653807205, 20488.897555512278, 21080.89459552708, 21083.789162108336, 21084.683729744065, 21118.3664490064, 2518.1312447205682, 2539.263613552086, 2601.843889366644, 2614.3202767280454, 2625.0, 136977.0, 143849.28075359555, 144984.27507862498, 146051.0, 150466.0, 16114.67770644604, 16459.91770041166, 16683.833161668226, 16932.91533542349, 17054.232559534852, 26808.59787103195, 26849.0, 26915.596266056025, 26970.325741856217, 26974.5953810693, 11318.943405283086, 11492.0, 11575.305481671126, 11650.76698466043, 11702.648920532398, 10471.790564188826, 10510.684679459628, 10565.947170264253, 10662.253642245123, 10805.83791243132, 9808.509574521388, 9895.158911492605, 10020.394697378733, 10123.03831136041, 10307.433091179908, 2804.298925268683, 2880.2079428157426, 2955.7930944833656, 2959.792814502964, 2988.475877302548, 1017.1862509991977, 1068.8302745426797, 1073.6886302972207, 1076.4563895233014, 1081.4971038467152, 20990.580188396452, 21013.78986210149, 21238.25666101679, 21360.572788544454, 21445.892770536047, 1134.2683968840051, 1137.9943100284445, 1138.4706111658118, 1140.8516892803882, 1150.1201580790703, 11353.659390218307, 11479.827802582968, 11553.191276610725, 11589.24669896454, 11810.52757889692, 608768.0, 608811.0, 618918.0, 649276.0, 665178.0, 138538.0, 139499.0, 141842.0, 142155.28922355323, 142448.0, 1138.6486904542094, 1197.4611424858674, 1233.2662066070673, 1264.1277518512197, 1297.772889744291, 21763.891180543997, 22064.007119679973, 22220.0, 22309.888450557642, 22473.10107595743, 62529.0, 62623.37376626176, 62995.6850215746, 64204.35795641984, 64921.35078649342, 80540.59729701314, 80963.0, 81174.59412702899, 81814.18185818066, 84243.5787821057, 15783.131927744083, 15881.444149454714, 16045.037297762172, 16117.113558754381, 16224.918875405547, 24407.75592244055, 24619.63070553979, 24665.876670616533, 24683.0, 24767.50464990726, 19106.93876736149, 19165.233390664216, 19168.424947251606, 19259.22963081461, 19431.514212144244, 16050.357985680443, 16589.834101658827, 17926.372576959744, 18217.271309148015, 18417.079146042914, 20156.395308140778, 20234.291799786937, 20259.898700506405, 20328.59342813106, 20502.69245961282, 14822.40710371573, 15758.054516728575, 15766.605834854221, 15771.68456630838, 15798.684026319179, 19852.801471985094, 19854.90072549628, 19903.60192796165, 20253.0, 20272.0], "value": 12946.968000222865, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1426.201327256736, 1477.290900367823, 1482.4292647330774, 1492.0749135535968, 1503.1507198432885, 17599.73600395995, 18084.186211620476, 18154.364597239066, 18185.272589096454, 18199.090045497724, 17675.469735907944, 17862.910685446586, 17867.821321786778, 17899.0, 17909.194086266118, 3103.2707313781243, 3143.544186093015, 3177.841107944605, 3179.650238473766, 3181.6182058153054, 2685.8254213476157, 2727.8772455239464, 2752.6008728734323, 2753.4768394005073, 2754.325190328362, 2637.4197676511135, 2728.086091159464, 2728.6179934809143, 2745.6156138140577, 2748.560230363142, 2735.7811375089946, 2737.657792775903, 2760.30992251937, 2768.5985532097834, 2770.252031951382, 3042.3915216956475, 3081.0910781319562, 3108.8911888084035, 3124.390743804959, 3133.1697100268448, 17205.655886882316, 17238.827611723973, 17352.305907763675, 17460.388886388915, 17515.64968700619, 8458.154184581554, 8765.430247033986, 8855.291576673852, 8877.023527411979, 8887.911120888837, 5948.256467941507, 6128.816135515897, 6157.907631385533, 6184.381561843825, 6225.875482490324, 2185.5519618478334, 2187.58435897179, 2210.7347118345742, 2251.909923603054, 2254.8308876834303, 32561.348773024645, 32786.50820237699, 32909.17727056819, 32978.83510582456, 33021.33957320864, 7827.804304892423, 7851.646675899598, 7917.129115797257, 8071.354291656653, 8078.8788168177525, 21241.575168496543, 21282.0, 21290.467738306357, 21308.254211102536, 21320.573588528143, 8872.8669069964, 9032.548372581345, 9057.275417966546, 9067.410618309788, 9071.138241867013, 8157.796055098552, 8222.753317400488, 8250.174982501701, 8295.0, 8300.377471689648, 6002.6698531580305, 6278.121063051177, 6373.267074286424, 6381.627949990681, 6449.871002580016, 1733.5037545933815, 1771.8936863787956, 1806.3858288182082, 1863.087087327227, 1880.6802843516634, 943.306669597845, 976.7558110472382, 980.127686359141, 987.9950600246952, 998.4508520313798, 9745.0, 10125.797484050425, 10126.341787783771, 10129.493525323851, 10368.274220804622, 806.5281810141016, 815.351795322708, 821.6836517940636, 835.8662613981734, 870.4342177584494, 5990.101484777319, 6175.289841668266, 6225.657588832658, 6227.2140124783455, 6261.24865016201, 25983.61024584633, 27183.32041698934, 27201.86399067992, 27306.45387092287, 27421.314467138083, 25870.223893283233, 26711.732882670924, 26827.329316766845, 26856.194314170607, 26927.057552985552, 13002.349882505838, 13355.666108347368, 13444.932775336061, 13450.26023568713, 13522.864771352357, 9806.362586431858, 9953.153981911619, 9985.450800205768, 10019.048190421898, 10135.138513226459, 22384.0, 22600.886995564917, 22867.656985144902, 22955.88522057379, 23479.0, 23623.64564531499, 23646.645300320164, 23848.523029539658, 23916.28251152468, 24391.390215244763, 12846.357682116044, 12865.16376435566, 13002.414891330101, 13048.934755326542, 13080.476780928657, 16187.59531011687, 16282.755758663867, 16401.67196656084, 16434.096124712778, 16490.917545412198, 13067.0, 13318.400671969592, 13381.732365352833, 13602.931985340407, 13789.862101378858, 12981.675458113623, 12981.935090324489, 13033.804492932424, 13092.149010314299, 13459.798103028266, 11206.719832004264, 11281.71795705114, 11321.433928303717, 11343.773124537298, 11354.886451135713, 7844.9607751960875, 7945.920540794518, 7996.080450748006, 8006.479578827358, 8129.065157506962, 8281.0, 8461.069282379056, 8510.787230319043, 8512.489250644981, 8639.049704532623], "value": 7668.026737868836, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.8909607174636, 593.3888095261882, 596.7374355283672, 619.4332186049764, 620.5159975219328, 6253.248570628925, 6308.810735677931, 6337.588056776306, 6360.346309959409, 6467.320931302216, 6219.906701399471, 6227.813165605028, 6281.968590157055, 6318.557700960934, 6348.714307856146, 5214.817481388142, 5244.160934250521, 5263.657862238962, 5275.208718692188, 5289.603279754034, 4963.925541116868, 5181.455947125554, 5193.221016847484, 5224.425313215544, 5226.843194704165, 4997.350344455216, 5018.84943451697, 5046.24306354048, 5085.516875896784, 5092.465291144432, 5051.924221136685, 5053.166227572447, 5060.721660308682, 5063.56926703085, 5085.440601533828, 4610.815567377301, 4611.608013318838, 4631.8378856739855, 4643.9767801161115, 4644.349791029259, 6165.290991535986, 6196.180737764352, 6221.222347206599, 6228.314885362652, 6302.676437948036, 5932.614380065325, 5973.820785376446, 5978.312494063195, 5992.700364981733, 6023.69881505923, 6674.766383176614, 6693.397594216543, 6695.765648202289, 6695.899561506581, 6703.798886033427, 5141.357330333708, 5166.793328266865, 5178.611604129667, 5191.948080519184, 5200.24596433515, 6772.390484856386, 6906.06768086307, 7001.754938577176, 7005.579665220052, 7037.366637002694, 5338.706371149624, 5343.385510666245, 5351.732413379315, 5353.812616558402, 5365.248865158881, 5422.295101636762, 5451.672899626035, 5453.972730136324, 5456.208849716848, 5461.563074953994, 5881.588288819741, 5889.293284805851, 5982.880342393128, 5995.61028533144, 6014.639121652627, 6727.996841879074, 6769.42459890905, 6780.525363224527, 6786.524943254023, 6798.864022719518, 1848.454705861774, 1863.226760894241, 1871.6911709568042, 1891.5460289530413, 1896.2130715753083, 1313.9211647301192, 1320.5972178485463, 1329.2622594459974, 1338.4044100375434, 1352.6956434802287, 6294.622322660655, 6351.872962540723, 6367.872642547123, 6380.170577824853, 6394.744210231632, 6690.498212634072, 6704.564203326768, 6751.493637977172, 6761.29006454325, 6804.863902722017, 6228.532860035515, 6263.592866463757, 6334.113224148623, 6373.426391624776, 6400.135981642418, 3499.1077275295147, 3560.483729859156, 3571.5714114306447, 3571.6785489305566, 3614.2771445710964, 7132.465065120033, 7144.1784194818265, 7154.676384868816, 7196.96401517989, 7213.8557228855125, 6942.687579058955, 6993.160820701447, 6998.790036298919, 7019.964900175466, 7061.717531298793, 6982.930170698228, 7002.299770022957, 7039.859202815915, 7107.005019297302, 7119.67961441726, 6666.533342665967, 6719.857624203994, 6738.663066846736, 6780.627065511444, 6786.932130678828, 6758.695858686273, 6768.4585233182215, 6793.694283757144, 6801.217859945971, 6819.761308354184, 7088.503804733826, 7135.429165666838, 7153.928460715326, 7207.459440541876, 7264.382527485223, 5295.682259064469, 5303.257543943928, 5335.519803217729, 5361.303030605996, 5405.378381486179, 6403.423691867754, 6687.297833727389, 6733.663316834237, 6810.965945170243, 6839.726410943506, 6459.257185423737, 6548.476121910331, 6578.210614726264, 6653.268140504638, 6707.5304728668525, 6543.836404089745, 6595.934040659532, 6642.800715978528, 6667.733290668319, 6696.296888826603, 6538.934610654022, 6595.703193356215, 6599.175103112111, 6622.966885165543, 6640.50196235294, 6553.344665533408, 6649.767258146136, 6705.798826035227, 6726.443951298475, 6737.966310168418, 6334.239891213084, 6468.838279043061, 6529.151210342625, 6573.737050517926, 6581.539292249497], "value": 5276.678326493267, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [983.7540614846289, 1014.2899970020985, 1025.2669341420883, 1026.1482969135614, 1038.9324693894896, 20587.691184632244, 21034.263800766937, 21061.26285580005, 21063.0, 21149.894250528745, 17422.82577174228, 17520.12399380029, 17645.647087058253, 18378.356757513455, 18609.44171674852, 1053.752368193474, 1068.3750006246355, 1069.7004838645153, 1070.2668671959693, 1074.511097450658, 1088.9782204355909, 1093.7101668057971, 1103.6854496468509, 1105.4141305108305, 1118.4799068433176, 1149.5746573767717, 1149.999500434623, 1150.1086657840158, 1150.551284998847, 1161.1233518693416, 1140.4069883660534, 1142.1205671632838, 1144.8339990701343, 1150.2235990706324, 1164.889335513129, 841.9896124650444, 845.2012847858776, 847.8389106069889, 852.0627309958995, 858.2533196119354, 20443.59112817735, 21230.5753884923, 21375.893120534452, 21522.677159842617, 22201.11195552176, 20296.391108266627, 20461.18155273802, 20659.070341834653, 20666.48333791652, 20670.17319307226, 18476.260949562, 18513.62972740551, 18705.53236169093, 18706.719399208887, 18770.249190032384, 3676.246369494274, 3761.54861416629, 3771.302309072803, 3825.61743825618, 3836.482074919878, 153261.0, 161704.99999999884, 162503.0, 167522.0, 170450.0, 17203.65592688164, 17819.554511137325, 17824.821751782576, 17833.0, 17835.643287134186, 34319.31361372759, 35271.64728352735, 35772.46341304883, 35788.28423431569, 35878.64121358805, 10228.437435940947, 10289.588416463257, 10322.432266225429, 10354.896451035544, 10469.162467002621, 4636.652251081183, 4703.035877645075, 4708.4820669726305, 4723.740194289278, 4733.029728905566, 6282.748690052438, 6337.461315788118, 6364.936350636527, 6400.103985442041, 6403.391677790602, 1304.2565737529708, 1305.9804102938463, 1308.718625495504, 1318.123447907131, 1340.0150889096506, 741.4550305525435, 763.6449051191223, 767.1484652036236, 780.0834020026447, 784.5410434895535, 13065.673358166121, 13244.404001819941, 13509.256990865597, 13943.302834858216, 13994.020418570806, 770.2490072179692, 774.6785084190111, 777.782220978127, 788.1724189600897, 793.7777422321759, 6109.969450152721, 6112.113743507255, 6175.752969881244, 6273.560850740404, 6293.465055470245, 157950.0, 159518.0, 160341.58719397045, 160953.0, 162195.0, 134084.0, 134283.0, 137664.16791604197, 137998.3100084493, 144932.0, 120033.39983300028, 121215.0, 123196.38401808112, 125097.0, 125269.37365313114, 13397.12440258384, 13568.86431135676, 13711.382987765375, 13733.725325493633, 13956.855364123565, 54361.0, 54395.0, 54783.726081369336, 54824.725876370365, 55531.16703249535, 89629.0, 97424.512877438, 97524.51237743766, 97538.0, 99826.0, 19490.0, 19885.801141988395, 19979.800201998376, 20066.19735210575, 20398.28605998783, 20728.896355518125, 21033.579328413653, 21290.254841081107, 22219.666704999116, 22569.0, 19751.703724444433, 19993.300234491722, 20092.296769612993, 20121.798782011992, 20178.899105504377, 18492.260309588004, 18671.066446677884, 18777.624447511247, 19134.0, 20447.59104817925, 19622.411327660193, 19753.209871604973, 20099.698504522737, 20142.194312227934, 20436.182552697726, 15011.099334039993, 15058.096514209183, 15183.620409489851, 15332.770008450105, 15373.077615343114, 14303.427862885368, 14407.495737649551, 14640.414383424544, 15072.0, 15075.924620376827], "value": 10441.494895910058, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1362.4000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 105.0, 105.0, 105.0, 106.0, 107.0, 108.0, 107.0, 105.0, 106.0], "value": 106.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 112.0, 111.0, 112.0, 139.0, 109.0, 112.0, 110.0, 111.0, 134.0], "value": 117.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 162.0, 160.0, 162.0, 162.0, 160.0, 162.0, 162.0, 163.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 73.0, 73.0, 74.0, 73.0, 76.0, 74.0, 75.0, 74.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 77.0, 77.0, 76.0, 77.0, 76.0, 76.0, 77.0, 77.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 111.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 148.0, 149.0, 149.0, 147.0, 147.0, 151.0, 157.0, 150.0], "value": 149.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 96.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 43.0, 42.0, 42.0, 46.0, 44.0, 43.0, 42.0, 42.0, 43.0], "value": 43.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 38.0, 37.0, 38.0, 37.0, 38.0, 37.0, 39.0, 38.0, 38.0], "value": 37.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 69.0, 70.0, 70.0, 70.0, 70.0, 72.0, 70.0, 71.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 107.0, 102.0, 106.0, 102.0, 109.0, 104.0, 107.0, 107.0, 107.0], "value": 105.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 148.0, 150.0, 150.0, 149.0, 149.0, 148.0, 148.0, 152.0, 154.0], "value": 150.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 59.0, 58.0, 66.0, 59.0, 67.0, 64.0, 60.0, 60.0, 60.0], "value": 61.9, "unit": "score"}]}]} 08:17:22 INFO - Return code: 0 08:17:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:17:22 INFO - # TBPL SUCCESS # 08:17:22 INFO - Running post-action listener: _package_coverage_data 08:17:22 INFO - Running post-action listener: _resource_record_post_action 08:17:22 INFO - [mozharness: 2017-10-26 15:17:22.215137Z] Finished run-tests step (success) 08:17:22 INFO - Running post-run listener: _resource_record_post_run 08:17:22 INFO - Total resource usage - Wall time: 1180s; CPU: 13.0%; Read bytes: 4096; Write bytes: 295395328; Read time: 16; Write time: 236392 08:17:22 INFO - TinderboxPrint: CPU usage
13.1% 08:17:22 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 08:17:22 INFO - TinderboxPrint: I/O write bytes / time
295,395,328 / 236,392 08:17:22 INFO - TinderboxPrint: CPU idle
8,178.6 (86.9%) 08:17:22 INFO - TinderboxPrint: CPU user
1,179.2 (12.5%) 08:17:22 INFO - TinderboxPrint: Swap in / out
0 / 0 08:17:22 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 12558336; Read time: 0; Write time: 3052 08:17:22 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 08:17:22 INFO - run-tests - Wall time: 1165s; CPU: 13.0%; Read bytes: 4096; Write bytes: 282836992; Read time: 16; Write time: 233340 08:17:22 INFO - Running post-run listener: _upload_blobber_files 08:17:22 INFO - Blob upload gear active. 08:17:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:17:22 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:17:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:17:23 INFO - (blobuploader) - INFO - Open directory for files ... 08:17:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:17:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:17:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:17:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:17:24 INFO - (blobuploader) - INFO - Done attempting. 08:17:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:17:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:17:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:17:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:17:27 INFO - (blobuploader) - INFO - Done attempting. 08:17:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:17:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:17:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:17:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:17:28 INFO - (blobuploader) - INFO - Done attempting. 08:17:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:17:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:17:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:17:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:17:29 INFO - (blobuploader) - INFO - Done attempting. 08:17:29 INFO - (blobuploader) - INFO - Iteration through files over. 08:17:29 INFO - Return code: 0 08:17:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:17:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:17:29 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3089974c9d02f88929d2bd6389240a471cdf8d21ddf020c8679b26f1723e10905c7b32f47bc3187199c5ccfcc8607f6cdea6320e2b46307d857600b45aa5ec78", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/78779bf757d65ab96ed01e02e4d7b72e1d44bdf05c6152fde2ccb8c8634f1059f9d2a3434bd0913efdbf7e4cded014c896bf4c7ed428692baa19a1c97a0e3164", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5f7c9aadfd8ca431f2d72b980699faa8e0fb3c5ca7af481f651e1012dfc8ac3606ae027292d8d2d2c4d7ee8209c8e2b431e1c6bf042bbb1726dfad28e0faba8b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fc421e38d81cd2825e02787af1ff227ab14b349bd56401ac1fc6ed443d09002d7fec23d55f2509fc5cc297400017eac29d53ed919f038d860dadb56b2584f29b"} 08:17:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:17:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:17:29 INFO - Contents: 08:17:29 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3089974c9d02f88929d2bd6389240a471cdf8d21ddf020c8679b26f1723e10905c7b32f47bc3187199c5ccfcc8607f6cdea6320e2b46307d857600b45aa5ec78", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/78779bf757d65ab96ed01e02e4d7b72e1d44bdf05c6152fde2ccb8c8634f1059f9d2a3434bd0913efdbf7e4cded014c896bf4c7ed428692baa19a1c97a0e3164", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5f7c9aadfd8ca431f2d72b980699faa8e0fb3c5ca7af481f651e1012dfc8ac3606ae027292d8d2d2c4d7ee8209c8e2b431e1c6bf042bbb1726dfad28e0faba8b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fc421e38d81cd2825e02787af1ff227ab14b349bd56401ac1fc6ed443d09002d7fec23d55f2509fc5cc297400017eac29d53ed919f038d860dadb56b2584f29b"} 08:17:29 INFO - Running post-run listener: copy_logs_to_upload_dir 08:17:29 INFO - Copying logs to upload dir... 08:17:29 INFO - mkdir: /builds/slave/test/build/upload/logs 08:17:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.283980 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2017-10-26 08:17:29.799601) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-26 08:17:29.802971) ========= 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=20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3089974c9d02f88929d2bd6389240a471cdf8d21ddf020c8679b26f1723e10905c7b32f47bc3187199c5ccfcc8607f6cdea6320e2b46307d857600b45aa5ec78", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/78779bf757d65ab96ed01e02e4d7b72e1d44bdf05c6152fde2ccb8c8634f1059f9d2a3434bd0913efdbf7e4cded014c896bf4c7ed428692baa19a1c97a0e3164", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5f7c9aadfd8ca431f2d72b980699faa8e0fb3c5ca7af481f651e1012dfc8ac3606ae027292d8d2d2c4d7ee8209c8e2b431e1c6bf042bbb1726dfad28e0faba8b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fc421e38d81cd2825e02787af1ff227ab14b349bd56401ac1fc6ed443d09002d7fec23d55f2509fc5cc297400017eac29d53ed919f038d860dadb56b2584f29b"} build_url:https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011248 build_url: 'https://queue.taskcluster.net/v1/task/GbsAtyrAQa2vNGzBeex94g/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3089974c9d02f88929d2bd6389240a471cdf8d21ddf020c8679b26f1723e10905c7b32f47bc3187199c5ccfcc8607f6cdea6320e2b46307d857600b45aa5ec78", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/78779bf757d65ab96ed01e02e4d7b72e1d44bdf05c6152fde2ccb8c8634f1059f9d2a3434bd0913efdbf7e4cded014c896bf4c7ed428692baa19a1c97a0e3164", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5f7c9aadfd8ca431f2d72b980699faa8e0fb3c5ca7af481f651e1012dfc8ac3606ae027292d8d2d2c4d7ee8209c8e2b431e1c6bf042bbb1726dfad28e0faba8b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fc421e38d81cd2825e02787af1ff227ab14b349bd56401ac1fc6ed443d09002d7fec23d55f2509fc5cc297400017eac29d53ed919f038d860dadb56b2584f29b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-26 08:17:29.834661) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-26 08:17:29.835120) ========= 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=20e666a3d4cfbb71505a330e000001b4-1509029815.264165-1498870969 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004517 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-26 08:17:29.918597) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-26 08:17:29.918959) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-26 08:17:29.919279) ========= ========= Total master_lag: 0.20 =========