builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-058 starttime: 1507055819.62 results: success (0) revision: bb2a1f41d82a1fc2414c6f0d435fa6c054244d62 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 11:36:59.619657) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 11:36:59.620107) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 11:36:59.620379) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 11:36:59.633255) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 11:36:59.633620) ========= 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=0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-03 11:36:59-- 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% 41.8M=0s 2017-10-03 11:36:59 (41.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132857 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 11:36:59.782070) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 11:36:59.782417) ========= 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=0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016705 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 11:36:59.832091) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-03 11:36:59.832435) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bb2a1f41d82a1fc2414c6f0d435fa6c054244d62 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev bb2a1f41d82a1fc2414c6f0d435fa6c054244d62 --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=0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-03 11:36:59,806 truncating revision to first 12 chars 2017-10-03 11:36:59,806 Setting DEBUG logging. 2017-10-03 11:36:59,806 attempt 1/10 2017-10-03 11:36:59,806 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bb2a1f41d82a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-03 11:37:00,946 unpacking tar archive at: mozilla-central-bb2a1f41d82a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.450962 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-03 11:37:01.300071) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 11:37:01.300425) ========= script_repo_revision: bb2a1f41d82a1fc2414c6f0d435fa6c054244d62 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 11:37:01.300808) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 11:37:01.301087) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 11:37:01.314467) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-10-03 11:37:01.314866) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:37:01 INFO - MultiFileLogger online at 20171003 11:37:01 in /builds/slave/test 11:37:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 11:37:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:37:01 INFO - {'append_to_log': False, 11:37:01 INFO - 'base_work_dir': '/builds/slave/test', 11:37:01 INFO - 'blob_upload_branch': 'Firefox', 11:37:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:37:01 INFO - 'branch': 'Firefox', 11:37:01 INFO - 'buildbot_json_path': 'buildprops.json', 11:37:01 INFO - 'code_coverage': False, 11:37:01 INFO - 'config_files': ('talos/linux_config.py',), 11:37:01 INFO - 'default_actions': ('clobber', 11:37:01 INFO - 'read-buildbot-config', 11:37:01 INFO - 'download-and-extract', 11:37:01 INFO - 'populate-webroot', 11:37:01 INFO - 'create-virtualenv', 11:37:01 INFO - 'install', 11:37:01 INFO - 'setup-mitmproxy', 11:37:01 INFO - 'run-tests'), 11:37:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:37:01 INFO - 'disable_ccov_upload': False, 11:37:01 INFO - 'disable_stylo': False, 11:37:01 INFO - 'download_minidump_stackwalk': True, 11:37:01 INFO - 'download_symbols': 'ondemand', 11:37:01 INFO - 'enable_stylo': False, 11:37:01 INFO - 'enable_webrender': False, 11:37:01 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:37:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:37:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:37:01 INFO - 'gecko_profile': False, 11:37:01 INFO - 'gecko_profile_interval': 0, 11:37:01 INFO - 'installer_path': 'installer.exe', 11:37:01 INFO - 'log_level': 'info', 11:37:01 INFO - 'log_name': 'talos', 11:37:01 INFO - 'log_to_console': True, 11:37:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:37:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:37:01 INFO - 'opt_config_files': (), 11:37:01 INFO - 'pip_index': False, 11:37:01 INFO - 'suite': 'dromaeojs-e10s', 11:37:01 INFO - 'system_bits': '32', 11:37:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:37:01 INFO - 'title': 'talos-linux64-ix-058', 11:37:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:37:01 INFO - 'use_talos_json': True, 11:37:01 INFO - 'verify': 'False', 11:37:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:37:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:37:01 INFO - 'work_dir': 'build'} 11:37:01 INFO - [mozharness: 2017-10-03 18:37:01.434486Z] Running clobber step. 11:37:01 INFO - Running pre-action listener: _resource_record_pre_action 11:37:01 INFO - Running main action method: clobber 11:37:01 INFO - rmtree: /builds/slave/test/build 11:37:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:37:02 INFO - Running post-action listener: _resource_record_post_action 11:37:02 INFO - [mozharness: 2017-10-03 18:37:02.481030Z] Finished clobber step (success) 11:37:02 INFO - [mozharness: 2017-10-03 18:37:02.481128Z] Running read-buildbot-config step. 11:37:02 INFO - Running pre-action listener: _resource_record_pre_action 11:37:02 INFO - Running main action method: read_buildbot_config 11:37:02 INFO - Using buildbot properties: 11:37:02 INFO - { 11:37:02 INFO - "project": "", 11:37:02 INFO - "product": "firefox", 11:37:02 INFO - "who": "ffxbld@noreply.mozilla.org", 11:37:02 INFO - "installer_path": "public/build/target.tar.bz2", 11:37:02 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 11:37:02 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:37:02 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 11:37:02 INFO - "stage_platform": "linux64-qr", 11:37:02 INFO - "basedir": "/builds/slave/test", 11:37:02 INFO - "buildnumber": 97, 11:37:02 INFO - "platform": "ubuntu64_hw_qr", 11:37:02 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:37:02 INFO - "slavebuilddir": "test", 11:37:02 INFO - "taskId": "MeE2OzNbSoWSY78NJG6bKA", 11:37:02 INFO - "branch": "mozilla-central", 11:37:02 INFO - "script_repo_revision": "production", 11:37:02 INFO - "revision": "bb2a1f41d82a1fc2414c6f0d435fa6c054244d62", 11:37:02 INFO - "slavename": "talos-linux64-ix-058", 11:37:02 INFO - "repo_path": "mozilla-central" 11:37:02 INFO - } 11:37:02 INFO - Finding installer, test and symbols from parent task. 11:37:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MeE2OzNbSoWSY78NJG6bKA'}, attempt #1 11:37:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MeE2OzNbSoWSY78NJG6bKA'}, attempt #1 11:37:03 INFO - Task dependencies: IhxenNHPQ7mEyjmdOY6GYQ 11:37:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ'}, attempt #1 11:37:03 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.tar.bz2 11:37:03 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.test_packages.json 11:37:03 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.crashreporter-symbols.zip 11:37:03 INFO - Running post-action listener: _resource_record_post_action 11:37:03 INFO - [mozharness: 2017-10-03 18:37:03.840744Z] Finished read-buildbot-config step (success) 11:37:03 INFO - [mozharness: 2017-10-03 18:37:03.840971Z] Running download-and-extract step. 11:37:03 INFO - Running pre-action listener: _resource_record_pre_action 11:37:03 INFO - Running main action method: download_and_extract 11:37:03 INFO - mkdir: /builds/slave/test/build/tests 11:37:03 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')]} 11:37:03 INFO - trying https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.test_packages.json 11:37:03 INFO - Downloading https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:37:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:37:04 INFO - Downloaded 989 bytes. 11:37:04 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:37:04 INFO - Using the following test package requirements: 11:37:04 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:37:04 INFO - u'common': [u'target.common.tests.zip'], 11:37:04 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:37:04 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:37:04 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:37:04 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:37:04 INFO - u'mozbase': [u'target.common.tests.zip'], 11:37:04 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:37:04 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:37:04 INFO - u'web-platform': [u'target.common.tests.zip', 11:37:04 INFO - u'target.web-platform.tests.tar.gz'], 11:37:04 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:37:04 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 11:37:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.common.tests.zip 11:37:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:37:04 INFO - Fetch https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.common.tests.zip into memory 11:37:06 INFO - Content-Length response header: 55304242 11:37:06 INFO - Bytes received: 55304242 11:37:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.talos.tests.zip 11:37:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:37:17 INFO - Fetch https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.talos.tests.zip into memory 11:37:18 INFO - Content-Length response header: 14522427 11:37:18 INFO - Bytes received: 14522427 11:37:18 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')]} 11:37:18 INFO - trying https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.tar.bz2 11:37:18 INFO - Downloading https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:37:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:37:20 INFO - Downloaded 61690418 bytes. 11:37:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.tar.bz2 11:37:20 INFO - mkdir: /builds/slave/test/properties 11:37:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:37:20 INFO - Writing to file /builds/slave/test/properties/build_url 11:37:20 INFO - Contents: 11:37:20 INFO - build_url:https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.tar.bz2 11:37:20 INFO - Running post-action listener: _resource_record_post_action 11:37:20 INFO - Running post-action listener: find_tests_for_verification 11:37:20 INFO - Running post-action listener: set_extra_try_arguments 11:37:20 INFO - [mozharness: 2017-10-03 18:37:20.697478Z] Finished download-and-extract step (success) 11:37:20 INFO - [mozharness: 2017-10-03 18:37:20.697707Z] Running populate-webroot step. 11:37:20 INFO - Running pre-action listener: _resource_record_pre_action 11:37:20 INFO - Running main action method: populate_webroot 11:37:20 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:37:20 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:37:20 INFO - 'tests': ['tresize', 11:37:20 INFO - 'tcanvasmark']}, 11:37:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:37:20 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:37:20 INFO - 'tests': ['dromaeo_css', 11:37:20 INFO - 'kraken']}, 11:37:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:37:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:37:20 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:37:20 INFO - 'talos_options': ['--disable-stylo'], 11:37:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:37:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:37:20 INFO - 'tests': ['damp', 'tps']}, 11:37:20 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:37:20 INFO - 'talos_options': ['--disable-stylo'], 11:37:20 INFO - 'tests': ['damp', 'tps']}, 11:37:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:37:20 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:37:20 INFO - 'tests': ['dromaeo_dom']}, 11:37:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:37:20 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:37:20 INFO - 'tests': ['basic_compositor_video', 11:37:20 INFO - 'glvideo']}, 11:37:20 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:37:20 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:37:20 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:37:20 INFO - 'talos_options': ['--disable-stylo'], 11:37:20 INFO - 'tests': ['ts_paint_webext', 11:37:20 INFO - 'tp5o_webext']}, 11:37:20 INFO - 'other-e10s': {'tests': ['a11yr', 11:37:20 INFO - 'ts_paint', 11:37:20 INFO - 'tpaint', 11:37:20 INFO - 'sessionrestore', 11:37:20 INFO - 'sessionrestore_many_windows', 11:37:20 INFO - 'sessionrestore_no_auto_restore', 11:37:20 INFO - 'tabpaint', 11:37:20 INFO - 'cpstartup']}, 11:37:20 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:37:20 INFO - 'tests': ['a11yr', 11:37:20 INFO - 'ts_paint', 11:37:20 INFO - 'tpaint', 11:37:20 INFO - 'sessionrestore', 11:37:20 INFO - 'sessionrestore_many_windows', 11:37:20 INFO - 'sessionrestore_no_auto_restore', 11:37:20 INFO - 'tabpaint', 11:37:20 INFO - 'cpstartup']}, 11:37:20 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 11:37:20 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 11:37:20 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:37:20 INFO - 'tests': ['perf_reftest_singletons']}, 11:37:20 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:37:20 INFO - 'tests': ['perf_reftest']}, 11:37:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:37:20 INFO - 'tsvgr_opacity', 11:37:20 INFO - 'tart', 11:37:20 INFO - 'tscrollx', 11:37:20 INFO - 'tsvg_static']}, 11:37:20 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:37:20 INFO - 'tests': ['tsvgx', 11:37:20 INFO - 'tsvgr_opacity', 11:37:20 INFO - 'tart', 11:37:20 INFO - 'tscrollx', 11:37:20 INFO - 'tsvg_static']}, 11:37:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:37:20 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:37:20 INFO - 'talos_options': ['--disable-stylo'], 11:37:20 INFO - 'tests': ['tp5o']}, 11:37:20 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:37:20 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:37:20 INFO - 'talos_options': ['--mitmproxy', 11:37:20 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:37:20 INFO - '--firstNonBlankPaint'], 11:37:20 INFO - 'tests': ['tp6_google', 11:37:20 INFO - 'tp6_youtube', 11:37:20 INFO - 'tp6_amazon', 11:37:20 INFO - 'tp6_facebook']}, 11:37:20 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:37:20 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:37:20 INFO - 'talos_options': ['--disable-stylo', 11:37:20 INFO - '--mitmproxy', 11:37:20 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:37:20 INFO - '--firstNonBlankPaint'], 11:37:20 INFO - 'tests': ['tp6_google', 11:37:20 INFO - 'tp6_youtube', 11:37:20 INFO - 'tp6_amazon', 11:37:20 INFO - 'tp6_facebook']}, 11:37:20 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:37:20 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:37:20 INFO - 'talos_options': ['--stylo-threads=1', 11:37:20 INFO - '--mitmproxy', 11:37:20 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:37:20 INFO - '--firstNonBlankPaint'], 11:37:20 INFO - 'tests': ['tp6_google', 11:37:20 INFO - 'tp6_youtube', 11:37:20 INFO - 'tp6_amazon', 11:37:20 INFO - 'tp6_facebook']}, 11:37:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:37:20 INFO - 'talos_options': ['--xperf_path', 11:37:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:37:20 INFO - 'tests': ['tp5n']}, 11:37:20 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:37:20 INFO - 'talos_options': ['--disable-stylo', 11:37:20 INFO - '--xperf_path', 11:37:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:37:20 INFO - 'tests': ['tp5n']}}} 11:37:20 INFO - Running post-action listener: _resource_record_post_action 11:37:20 INFO - [mozharness: 2017-10-03 18:37:20.716945Z] Finished populate-webroot step (success) 11:37:20 INFO - [mozharness: 2017-10-03 18:37:20.717054Z] Running create-virtualenv step. 11:37:20 INFO - Running pre-action listener: _resource_record_pre_action 11:37:20 INFO - Running main action method: create_virtualenv 11:37:20 INFO - Creating virtualenv /builds/slave/test/build/venv 11:37:20 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:37:20 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:37:20 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:37:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:37:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:37:21 INFO - Installing distribute.............................................................................................................................................................................................done. 11:37:21 INFO - Installing pip...............done. 11:37:21 INFO - Return code: 0 11:37:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:37:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:37:21 INFO - Reading from file tmpfile_stdout 11:37:21 INFO - Output received: 11:37:21 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:37:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:37:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:37:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1238d60>, '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 0x1239a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1239ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', '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 11:37:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:37:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:37:21 INFO - Using env: {'DISPLAY': ':0', 11:37:21 INFO - 'HOME': '/home/cltbld', 11:37:21 INFO - 'LANG': 'en_US.UTF-8', 11:37:21 INFO - 'LANGUAGE': 'en_US:en', 11:37:21 INFO - 'LOGNAME': 'cltbld', 11:37:21 INFO - 'MAIL': '/var/mail/cltbld', 11:37:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:37:21 INFO - 'MOZ_NO_REMOTE': '1', 11:37:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:37:21 INFO - 'NO_EM_RESTART': '1', 11:37:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:37:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:37:21 INFO - 'PWD': '/builds/slave/test', 11:37:21 INFO - 'SHELL': '/bin/bash', 11:37:21 INFO - 'SHLVL': '1', 11:37:21 INFO - 'TERM': 'linux', 11:37:21 INFO - 'TMOUT': '86400', 11:37:21 INFO - 'USER': 'cltbld', 11:37:21 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', 11:37:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:37:21 INFO - '_': '/tools/buildbot/bin/python'} 11:37:22 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:37:22 INFO - Downloading/unpacking pip>=1.5 11:37:22 INFO - Running setup.py egg_info for package pip 11:37:22 INFO - warning: no files found matching 'pip/cacert.pem' 11:37:22 INFO - warning: no files found matching '*.html' under directory 'docs' 11:37:22 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:37:22 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:37:22 INFO - Installing collected packages: pip 11:37:22 INFO - Found existing installation: pip 1.1 11:37:22 INFO - Uninstalling pip: 11:37:22 INFO - Successfully uninstalled pip 11:37:22 INFO - Running setup.py install for pip 11:37:22 INFO - warning: no files found matching 'pip/cacert.pem' 11:37:22 INFO - warning: no files found matching '*.html' under directory 'docs' 11:37:22 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:37:22 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:37:22 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:37:22 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:37:22 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:37:22 INFO - Successfully installed pip 11:37:22 INFO - Cleaning up... 11:37:22 INFO - Return code: 0 11:37:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:37: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')]} 11:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:37: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')]} 11:37:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:37: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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1238d60>, '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 0x1239a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1239ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', '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 11:37: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 11:37: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 11:37:22 INFO - Using env: {'DISPLAY': ':0', 11:37:22 INFO - 'HOME': '/home/cltbld', 11:37:22 INFO - 'LANG': 'en_US.UTF-8', 11:37:22 INFO - 'LANGUAGE': 'en_US:en', 11:37:22 INFO - 'LOGNAME': 'cltbld', 11:37:22 INFO - 'MAIL': '/var/mail/cltbld', 11:37:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:37:22 INFO - 'MOZ_NO_REMOTE': '1', 11:37:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:37:22 INFO - 'NO_EM_RESTART': '1', 11:37:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:37:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:37:22 INFO - 'PWD': '/builds/slave/test', 11:37:22 INFO - 'SHELL': '/bin/bash', 11:37:22 INFO - 'SHLVL': '1', 11:37:22 INFO - 'TERM': 'linux', 11:37:22 INFO - 'TMOUT': '86400', 11:37:22 INFO - 'USER': 'cltbld', 11:37:22 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', 11:37:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:37:22 INFO - '_': '/tools/buildbot/bin/python'} 11:37:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:37:23 INFO - Downloading/unpacking psutil>=3.1.1 11:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:37:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:37:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:37:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:37:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:37:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:37:24 INFO - Installing collected packages: psutil 11:37:24 INFO - Running setup.py install for psutil 11:37:24 INFO - building 'psutil._psutil_linux' extension 11:37:24 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 11:37:24 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 11:37:24 INFO - building 'psutil._psutil_posix' extension 11:37:24 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 11:37:24 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 11:37:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:37:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:37:24 INFO - Successfully installed psutil 11:37:24 INFO - Cleaning up... 11:37:24 INFO - Return code: 0 11:37:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:37:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:37:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:37:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:37:24 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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1238d60>, '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 0x1239a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1239ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', '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 11:37:24 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 11:37:24 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 11:37:24 INFO - Using env: {'DISPLAY': ':0', 11:37:24 INFO - 'HOME': '/home/cltbld', 11:37:24 INFO - 'LANG': 'en_US.UTF-8', 11:37:24 INFO - 'LANGUAGE': 'en_US:en', 11:37:24 INFO - 'LOGNAME': 'cltbld', 11:37:24 INFO - 'MAIL': '/var/mail/cltbld', 11:37:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:37:24 INFO - 'MOZ_NO_REMOTE': '1', 11:37:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:37:24 INFO - 'NO_EM_RESTART': '1', 11:37:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:37:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:37:24 INFO - 'PWD': '/builds/slave/test', 11:37:24 INFO - 'SHELL': '/bin/bash', 11:37:24 INFO - 'SHLVL': '1', 11:37:24 INFO - 'TERM': 'linux', 11:37:24 INFO - 'TMOUT': '86400', 11:37:24 INFO - 'USER': 'cltbld', 11:37:24 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', 11:37:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:37:24 INFO - '_': '/tools/buildbot/bin/python'} 11:37:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:37:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:37:25 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 11:37:25 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 11:37:25 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 11:37:25 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 11:37:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:37:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:37:26 INFO - Installing collected packages: mozsystemmonitor 11:37:26 INFO - Running setup.py install for mozsystemmonitor 11:37:26 INFO - Successfully installed mozsystemmonitor 11:37:26 INFO - Cleaning up... 11:37:27 INFO - Return code: 0 11:37:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:37:27 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')]} 11:37:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:37:27 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')]} 11:37:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:37:27 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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1238d60>, '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 0x1239a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1239ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', '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 11:37:27 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 11:37:27 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 11:37:27 INFO - Using env: {'DISPLAY': ':0', 11:37:27 INFO - 'HOME': '/home/cltbld', 11:37:27 INFO - 'LANG': 'en_US.UTF-8', 11:37:27 INFO - 'LANGUAGE': 'en_US:en', 11:37:27 INFO - 'LOGNAME': 'cltbld', 11:37:27 INFO - 'MAIL': '/var/mail/cltbld', 11:37:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:37:27 INFO - 'MOZ_NO_REMOTE': '1', 11:37:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:37:27 INFO - 'NO_EM_RESTART': '1', 11:37:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:37:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:37:27 INFO - 'PWD': '/builds/slave/test', 11:37:27 INFO - 'SHELL': '/bin/bash', 11:37:27 INFO - 'SHLVL': '1', 11:37:27 INFO - 'TERM': 'linux', 11:37:27 INFO - 'TMOUT': '86400', 11:37:27 INFO - 'USER': 'cltbld', 11:37:27 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', 11:37:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:37:27 INFO - '_': '/tools/buildbot/bin/python'} 11:37:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:37:27 INFO - Downloading/unpacking jsonschema==2.5.1 11:37:27 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 11:37:27 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 11:37:27 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 11:37:27 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 11:37:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:37:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:37: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 11:37: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 11:37: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 11:37: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 11:37:29 INFO - Downloading functools32-3.2.3-2.tar.gz 11:37:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:37:29 INFO - warning: no files found matching '*.txt' 11:37:29 INFO - no previously-included directories found matching 'build' 11:37:29 INFO - no previously-included directories found matching 'dist' 11:37:29 INFO - no previously-included directories found matching '.git*' 11:37:29 INFO - Installing collected packages: jsonschema, functools32 11:37:29 INFO - Running setup.py install for functools32 11:37:29 INFO - warning: no files found matching '*.txt' 11:37:29 INFO - no previously-included directories found matching 'build' 11:37:29 INFO - no previously-included directories found matching 'dist' 11:37:29 INFO - no previously-included directories found matching '.git*' 11:37:29 INFO - Successfully installed jsonschema functools32 11:37:29 INFO - Cleaning up... 11:37:29 INFO - Return code: 0 11:37:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:37:29 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')]} 11:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:37:29 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')]} 11:37:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:37:29 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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1238d60>, '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 0x1239a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1239ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', '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 11:37:29 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 11:37:29 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 11:37:29 INFO - Using env: {'DISPLAY': ':0', 11:37:29 INFO - 'HOME': '/home/cltbld', 11:37:29 INFO - 'LANG': 'en_US.UTF-8', 11:37:29 INFO - 'LANGUAGE': 'en_US:en', 11:37:29 INFO - 'LOGNAME': 'cltbld', 11:37:29 INFO - 'MAIL': '/var/mail/cltbld', 11:37:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:37:29 INFO - 'MOZ_NO_REMOTE': '1', 11:37:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:37:29 INFO - 'NO_EM_RESTART': '1', 11:37:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:37:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:37:29 INFO - 'PWD': '/builds/slave/test', 11:37:29 INFO - 'SHELL': '/bin/bash', 11:37:29 INFO - 'SHLVL': '1', 11:37:29 INFO - 'TERM': 'linux', 11:37:29 INFO - 'TMOUT': '86400', 11:37:29 INFO - 'USER': 'cltbld', 11:37:29 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', 11:37:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:37:29 INFO - '_': '/tools/buildbot/bin/python'} 11:37:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:37:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:37:30 INFO - Cleaning up... 11:37:30 INFO - Return code: 0 11:37:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:37: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')]} 11:37:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:37: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')]} 11:37:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:37: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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1238d60>, '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 0x1239a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1239ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', '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 11:37: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 11:37: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 11:37:30 INFO - Using env: {'DISPLAY': ':0', 11:37:30 INFO - 'HOME': '/home/cltbld', 11:37:30 INFO - 'LANG': 'en_US.UTF-8', 11:37:30 INFO - 'LANGUAGE': 'en_US:en', 11:37:30 INFO - 'LOGNAME': 'cltbld', 11:37:30 INFO - 'MAIL': '/var/mail/cltbld', 11:37:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:37:30 INFO - 'MOZ_NO_REMOTE': '1', 11:37:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:37:30 INFO - 'NO_EM_RESTART': '1', 11:37:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:37:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:37:30 INFO - 'PWD': '/builds/slave/test', 11:37:30 INFO - 'SHELL': '/bin/bash', 11:37:30 INFO - 'SHLVL': '1', 11:37:30 INFO - 'TERM': 'linux', 11:37:30 INFO - 'TMOUT': '86400', 11:37:30 INFO - 'USER': 'cltbld', 11:37:30 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', 11:37:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:37:30 INFO - '_': '/tools/buildbot/bin/python'} 11:37:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:37:30 INFO - Downloading/unpacking blobuploader==1.2.4 11:37: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 11:37: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 11:37: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 11:37: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 11:37:31 INFO - Downloading blobuploader-1.2.4.tar.gz 11:37:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:37:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:37:31 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 11:37:31 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 11:37:31 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 11:37:31 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 11:37:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:37:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:37:31 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 11:37:31 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 11:37:31 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 11:37:31 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 11:37:31 INFO - Downloading docopt-0.6.1.tar.gz 11:37:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:37:31 INFO - Installing collected packages: blobuploader, requests, docopt 11:37:31 INFO - Running setup.py install for blobuploader 11:37:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:37:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:37:32 INFO - Running setup.py install for requests 11:37:32 INFO - Running setup.py install for docopt 11:37:32 INFO - Successfully installed blobuploader requests docopt 11:37:32 INFO - Cleaning up... 11:37:32 INFO - Return code: 0 11:37:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:37:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:37:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:37:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:37:32 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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1238d60>, '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 0x1239a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1239ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', '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 11:37:32 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 11:37:32 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 11:37:32 INFO - Using env: {'DISPLAY': ':0', 11:37:32 INFO - 'HOME': '/home/cltbld', 11:37:32 INFO - 'LANG': 'en_US.UTF-8', 11:37:32 INFO - 'LANGUAGE': 'en_US:en', 11:37:32 INFO - 'LOGNAME': 'cltbld', 11:37:32 INFO - 'MAIL': '/var/mail/cltbld', 11:37:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:37:32 INFO - 'MOZ_NO_REMOTE': '1', 11:37:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:37:32 INFO - 'NO_EM_RESTART': '1', 11:37:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:37:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:37:32 INFO - 'PWD': '/builds/slave/test', 11:37:32 INFO - 'SHELL': '/bin/bash', 11:37:32 INFO - 'SHLVL': '1', 11:37:32 INFO - 'TERM': 'linux', 11:37:32 INFO - 'TMOUT': '86400', 11:37:32 INFO - 'USER': 'cltbld', 11:37:32 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', 11:37:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:37:32 INFO - '_': '/tools/buildbot/bin/python'} 11:37:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:37:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:37:32 INFO - Running setup.py (path:/tmp/pip-OvTXno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:37:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:37:32 INFO - Running setup.py (path:/tmp/pip-dgeO5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:37:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:37:32 INFO - Running setup.py (path:/tmp/pip-_nvYa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:37:33 INFO - Running setup.py (path:/tmp/pip-l7K_QP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:37:33 INFO - Running setup.py (path:/tmp/pip-6HoskL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:37:33 INFO - Running setup.py (path:/tmp/pip-GOswqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:37:33 INFO - Running setup.py (path:/tmp/pip-7aJ0KA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:37:33 INFO - Running setup.py (path:/tmp/pip-wb6cna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:37:33 INFO - Running setup.py (path:/tmp/pip-HtpW98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:37:33 INFO - Running setup.py (path:/tmp/pip-8RDLXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:37:33 INFO - Running setup.py (path:/tmp/pip-Tk5BCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:37:33 INFO - Running setup.py (path:/tmp/pip-LzErGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:37:33 INFO - Running setup.py (path:/tmp/pip-RSI09a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:37:33 INFO - Running setup.py (path:/tmp/pip-jeXLYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:37:33 INFO - Running setup.py (path:/tmp/pip-hacoqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:37:33 INFO - Running setup.py (path:/tmp/pip-wS0J7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:37:33 INFO - Running setup.py (path:/tmp/pip-P0i_Gs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:37:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:37:34 INFO - Running setup.py install for manifestparser 11:37:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:37:34 INFO - Running setup.py install for mozcrash 11:37:34 INFO - Running setup.py install for mozdebug 11:37:34 INFO - Running setup.py install for mozdevice 11:37:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:37:34 INFO - Running setup.py install for mozfile 11:37:34 INFO - Running setup.py install for mozhttpd 11:37:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:37:34 INFO - Running setup.py install for mozinfo 11:37:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:37:34 INFO - Running setup.py install for mozInstall 11:37:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:37:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:37:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:37:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:37:34 INFO - Running setup.py install for mozleak 11:37:34 INFO - Running setup.py install for mozlog 11:37:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:37:35 INFO - Running setup.py install for moznetwork 11:37:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:37:35 INFO - Running setup.py install for mozprocess 11:37:35 INFO - Running setup.py install for mozprofile 11:37:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:37:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:37:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:37:35 INFO - Running setup.py install for mozrunner 11:37:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:37:35 INFO - Running setup.py install for mozscreenshot 11:37:35 INFO - Running setup.py install for moztest 11:37:35 INFO - Running setup.py install for mozversion 11:37:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:37:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:37:35 INFO - Cleaning up... 11:37:35 INFO - Return code: 0 11:37:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:37:35 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')]} 11:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:37:35 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')]} 11:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:37:35 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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1238d60>, '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 0x1239a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1239ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', '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 11:37:35 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 11:37:35 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 11:37:35 INFO - Using env: {'DISPLAY': ':0', 11:37:35 INFO - 'HOME': '/home/cltbld', 11:37:35 INFO - 'LANG': 'en_US.UTF-8', 11:37:35 INFO - 'LANGUAGE': 'en_US:en', 11:37:35 INFO - 'LOGNAME': 'cltbld', 11:37:35 INFO - 'MAIL': '/var/mail/cltbld', 11:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:37:35 INFO - 'MOZ_NO_REMOTE': '1', 11:37:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:37:35 INFO - 'NO_EM_RESTART': '1', 11:37:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:37:35 INFO - 'PWD': '/builds/slave/test', 11:37:35 INFO - 'SHELL': '/bin/bash', 11:37:35 INFO - 'SHLVL': '1', 11:37:35 INFO - 'TERM': 'linux', 11:37:35 INFO - 'TMOUT': '86400', 11:37:35 INFO - 'USER': 'cltbld', 11:37:35 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', 11:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:37:35 INFO - '_': '/tools/buildbot/bin/python'} 11:37:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:37:36 INFO - Running setup.py (path:/tmp/pip-hPgFtO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:37: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)) 11:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:37:36 INFO - Running setup.py (path:/tmp/pip-cUn7ba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:37: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)) 11:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:37:36 INFO - Running setup.py (path:/tmp/pip-TezERB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:37: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)) 11:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:37:36 INFO - Running setup.py (path:/tmp/pip-kSmcAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:37: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)) 11:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:37:36 INFO - Running setup.py (path:/tmp/pip-FqAlQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:37: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)) 11:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:37:36 INFO - Running setup.py (path:/tmp/pip-_fBtSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:37:36 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)) 11:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:37:36 INFO - Running setup.py (path:/tmp/pip-lwgttB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:37:36 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)) 11:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:37:36 INFO - Running setup.py (path:/tmp/pip-7PPLBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:37:36 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)) 11:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:37:36 INFO - Running setup.py (path:/tmp/pip-oQ2qrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:37:36 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)) 11:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:37:36 INFO - Running setup.py (path:/tmp/pip-2QEH7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:37:36 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)) 11:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:37:36 INFO - Running setup.py (path:/tmp/pip-QaHMlo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:37:36 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)) 11:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:37:36 INFO - Running setup.py (path:/tmp/pip-8SQsyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:37:36 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)) 11:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:37:36 INFO - Running setup.py (path:/tmp/pip-BismD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:37:36 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)) 11:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:37:36 INFO - Running setup.py (path:/tmp/pip-QJ15wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:37: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)) 11:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:37:37 INFO - Running setup.py (path:/tmp/pip-KqKTrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:37: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)) 11:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:37:37 INFO - Running setup.py (path:/tmp/pip-hBRhDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:37: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)) 11:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:37:37 INFO - Running setup.py (path:/tmp/pip-mYXLkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:37: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)) 11:37: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)) 11:37: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)) 11:37: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)) 11:37: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)) 11:37: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)) 11:37: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)) 11:37:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:37: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 11:37: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 11:37: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 11:37: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 11:37:38 INFO - Downloading blessings-1.6.tar.gz 11:37:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:37:38 INFO - Installing collected packages: blessings 11:37:38 INFO - Running setup.py install for blessings 11:37:38 INFO - Successfully installed blessings 11:37:38 INFO - Cleaning up... 11:37:38 INFO - Return code: 0 11:37:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:37:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:37:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:37:38 INFO - Reading from file tmpfile_stdout 11:37:38 INFO - Current package versions: 11:37:38 INFO - argparse == 1.2.1 11:37:38 INFO - blessings == 1.6 11:37:38 INFO - blobuploader == 1.2.4 11:37:38 INFO - docopt == 0.6.1 11:37:38 INFO - functools32 == 3.2.3-2 11:37:38 INFO - jsonschema == 2.5.1 11:37:38 INFO - manifestparser == 1.1 11:37:38 INFO - mozInstall == 1.13 11:37:38 INFO - mozcrash == 1.0 11:37:38 INFO - mozdebug == 0.1 11:37:38 INFO - mozdevice == 0.51 11:37:38 INFO - mozfile == 1.2 11:37:38 INFO - mozhttpd == 0.7 11:37:38 INFO - mozinfo == 0.10 11:37:38 INFO - mozleak == 0.1 11:37:38 INFO - mozlog == 3.5 11:37:38 INFO - moznetwork == 0.27 11:37:38 INFO - mozprocess == 0.25 11:37:38 INFO - mozprofile == 0.28 11:37:38 INFO - mozrunner == 6.13 11:37:38 INFO - mozscreenshot == 0.1 11:37:38 INFO - mozsystemmonitor == 0.3 11:37:38 INFO - moztest == 0.8 11:37:38 INFO - mozversion == 1.4 11:37:38 INFO - psutil == 3.1.1 11:37:38 INFO - requests == 1.2.3 11:37:38 INFO - wsgiref == 0.1.2 11:37:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:37:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:37:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:37:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:37:38 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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1238d60>, '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 0x1239a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1239ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', '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 11:37:38 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 11:37:38 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 11:37:38 INFO - Using env: {'DISPLAY': ':0', 11:37:38 INFO - 'HOME': '/home/cltbld', 11:37:38 INFO - 'LANG': 'en_US.UTF-8', 11:37:38 INFO - 'LANGUAGE': 'en_US:en', 11:37:38 INFO - 'LOGNAME': 'cltbld', 11:37:38 INFO - 'MAIL': '/var/mail/cltbld', 11:37:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:37:38 INFO - 'MOZ_NO_REMOTE': '1', 11:37:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:37:38 INFO - 'NO_EM_RESTART': '1', 11:37:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:37:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:37:38 INFO - 'PWD': '/builds/slave/test', 11:37:38 INFO - 'SHELL': '/bin/bash', 11:37:38 INFO - 'SHLVL': '1', 11:37:38 INFO - 'TERM': 'linux', 11:37:38 INFO - 'TMOUT': '86400', 11:37:38 INFO - 'USER': 'cltbld', 11:37:38 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', 11:37:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:37:38 INFO - '_': '/tools/buildbot/bin/python'} 11:37:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:37: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)) 11:37: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)) 11:37: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)) 11:37: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)) 11:37: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)) 11:37: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)) 11:37: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)) 11:37: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)) 11:37: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)) 11:37:39 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:37: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 11:37: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 11:37: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 11:37: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 11:37:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:37: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)) 11:37: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)) 11:37:40 INFO - Installing collected packages: simplejson 11:37:40 INFO - Running setup.py install for simplejson 11:37:40 INFO - building 'simplejson._speedups' extension 11:37: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 11:37: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 11:37:41 INFO - Successfully installed simplejson 11:37:41 INFO - Cleaning up... 11:37:41 INFO - Return code: 0 11:37:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:37: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')]} 11:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:37: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')]} 11:37:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:37: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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1238d60>, '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 0x1239a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1239ef0>, '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': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', '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 11:37: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 11:37: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 11:37:41 INFO - Using env: {'DISPLAY': ':0', 11:37:41 INFO - 'HOME': '/home/cltbld', 11:37:41 INFO - 'LANG': 'en_US.UTF-8', 11:37:41 INFO - 'LANGUAGE': 'en_US:en', 11:37:41 INFO - 'LOGNAME': 'cltbld', 11:37:41 INFO - 'MAIL': '/var/mail/cltbld', 11:37:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:37:41 INFO - 'MOZ_NO_REMOTE': '1', 11:37:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:37:41 INFO - 'NO_EM_RESTART': '1', 11:37:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:37:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:37:41 INFO - 'PWD': '/builds/slave/test', 11:37:41 INFO - 'SHELL': '/bin/bash', 11:37:41 INFO - 'SHLVL': '1', 11:37:41 INFO - 'TERM': 'linux', 11:37:41 INFO - 'TMOUT': '86400', 11:37:41 INFO - 'USER': 'cltbld', 11:37:41 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', 11:37:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:37:41 INFO - '_': '/tools/buildbot/bin/python'} 11:37:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:37:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:37:41 INFO - Cleaning up... 11:37:41 INFO - Return code: 0 11:37:41 INFO - Running post-action listener: _resource_record_post_action 11:37:41 INFO - Running post-action listener: _start_resource_monitoring 11:37:41 INFO - Starting resource monitoring. 11:37:41 INFO - [mozharness: 2017-10-03 18:37:41.543798Z] Finished create-virtualenv step (success) 11:37:41 INFO - [mozharness: 2017-10-03 18:37:41.544278Z] Running install step. 11:37:41 INFO - Running pre-action listener: _resource_record_pre_action 11:37:41 INFO - Running main action method: install 11:37:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:37:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:37:41 INFO - Reading from file tmpfile_stdout 11:37:41 INFO - Detecting whether we're running mozinstall >=1.0... 11:37:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:37:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:37:41 INFO - Reading from file tmpfile_stdout 11:37:41 INFO - Output received: 11:37:41 INFO - Usage: mozinstall [options] installer 11:37:41 INFO - Options: 11:37:41 INFO - -h, --help show this help message and exit 11:37:41 INFO - -d DEST, --destination=DEST 11:37:41 INFO - Directory to install application into. [default: 11:37:41 INFO - "/builds/slave/test"] 11:37:41 INFO - --app=APP Application being installed. [default: firefox] 11:37:41 INFO - mkdir: /builds/slave/test/build/application 11:37:41 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'] 11:37:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:37:59 INFO - Reading from file tmpfile_stdout 11:37:59 INFO - Output received: 11:37:59 INFO - /builds/slave/test/build/application/firefox/firefox 11:37:59 INFO - Running post-action listener: _resource_record_post_action 11:37:59 INFO - [mozharness: 2017-10-03 18:37:59.731887Z] Finished install step (success) 11:37:59 INFO - [mozharness: 2017-10-03 18:37:59.732009Z] Running setup-mitmproxy step. 11:37:59 INFO - Running pre-action listener: _resource_record_pre_action 11:37:59 INFO - Running main action method: setup_mitmproxy 11:37:59 INFO - Skipping: mitmproxy is not required 11:37:59 INFO - Running post-action listener: _resource_record_post_action 11:37:59 INFO - [mozharness: 2017-10-03 18:37:59.732537Z] Finished setup-mitmproxy step (success) 11:37:59 INFO - [mozharness: 2017-10-03 18:37:59.732678Z] Running run-tests step. 11:37:59 INFO - Running pre-action listener: _resource_record_pre_action 11:37:59 INFO - Running pre-action listener: _set_gcov_prefix 11:37:59 INFO - Running main action method: run_tests 11:37:59 WARNING - Try message not found. 11:37:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:37:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:37:59 INFO - Python 2.7.3 11:37:59 INFO - Return code: 0 11:37:59 INFO - grabbing minidump binary from tooltool 11:37:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:37:59 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 0x1238d60>, '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 0x1239a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1239ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:37:59 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 11:37:59 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 11:37:59 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 11:37:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:37:59 INFO - Return code: 0 11:37:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:37:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:37:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:37:59 INFO - ENV: RUST_BACKTRACE is now full 11:37:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:37:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:37:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 11:37:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-058 --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 11:37:59 INFO - Using env: {'DISPLAY': ':0', 11:37:59 INFO - 'HOME': '/home/cltbld', 11:37:59 INFO - 'LANG': 'en_US.UTF-8', 11:37:59 INFO - 'LANGUAGE': 'en_US:en', 11:37:59 INFO - 'LOGNAME': 'cltbld', 11:37:59 INFO - 'MAIL': '/var/mail/cltbld', 11:37:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:37:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:37:59 INFO - 'MOZ_ACCELERATED': '1', 11:37:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:37:59 INFO - 'MOZ_NO_REMOTE': '1', 11:37:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:37:59 INFO - 'MOZ_WEBRENDER': '1', 11:37:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:37:59 INFO - 'NO_EM_RESTART': '1', 11:37:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:37:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:37:59 INFO - 'PWD': '/builds/slave/test', 11:37:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:37:59 INFO - 'RUST_BACKTRACE': 'full', 11:37:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:37:59 INFO - 'SHELL': '/bin/bash', 11:37:59 INFO - 'SHLVL': '1', 11:37:59 INFO - 'TERM': 'linux', 11:37:59 INFO - 'TMOUT': '86400', 11:37:59 INFO - 'USER': 'cltbld', 11:37:59 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', 11:37:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:37:59 INFO - '_': '/tools/buildbot/bin/python'} 11:37:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 11:38:00 INFO - mozversion application_buildid: 20171003174132 11:38:00 INFO - mozversion application_changeset: bb2a1f41d82a1fc2414c6f0d435fa6c054244d62 11:38:00 INFO - mozversion application_display_name: Nightly 11:38:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:38:00 INFO - mozversion application_name: Firefox 11:38:00 INFO - mozversion application_remotingname: firefox 11:38:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:38:00 INFO - mozversion application_vendor: Mozilla 11:38:00 INFO - mozversion application_version: 58.0a1 11:38:00 INFO - mozversion platform_buildid: 20171003174132 11:38:00 INFO - mozversion platform_changeset: bb2a1f41d82a1fc2414c6f0d435fa6c054244d62 11:38:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:38:00 INFO - mozversion platform_version: 58.0a1 11:38:00 INFO - using testdate: 1507055880 11:38:00 INFO - actual date: 1507055880 11:38:00 INFO - starting webserver on 'localhost:47751' 11:38:00 INFO - SUITE-START | Running 2 tests 11:38:00 INFO - TEST-START | dromaeo_css 11:38:00 INFO - Initialising browser for dromaeo_css test... 11:38:00 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47751/getInfo.html -profile /tmp/tmp33HLHA/profile 11:38:00 INFO - TEST-INFO | started process 3487 (/builds/slave/test/build/application/firefox/firefox http://localhost:47751/getInfo.html) 11:38:05 INFO - TEST-INFO | 3487: exit 0 11:38:06 INFO - Browser initialized. 11:38:06 INFO - Running cycle 1/1 for dromaeo_css test... 11:38:06 INFO - Using env: {'DISPLAY': ':0', 11:38:06 INFO - 'HOME': '/home/cltbld', 11:38:06 INFO - 'JSGC_DISABLE_POISONING': '1', 11:38:06 INFO - 'LANG': 'en_US.UTF-8', 11:38:06 INFO - 'LANGUAGE': 'en_US:en', 11:38:06 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:38:06 INFO - 'LOGNAME': 'cltbld', 11:38:06 INFO - 'MAIL': '/var/mail/cltbld', 11:38:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:38:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:38:06 INFO - 'MOZ_ACCELERATED': '1', 11:38:06 INFO - 'MOZ_CRASHREPORTER': '1', 11:38:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:06 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:38:06 INFO - 'MOZ_NO_REMOTE': '1', 11:38:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:38:06 INFO - 'MOZ_WEBRENDER': '1', 11:38:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:38:06 INFO - 'NO_EM_RESTART': '1', 11:38:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:38:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:06 INFO - 'PWD': '/builds/slave/test', 11:38:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:38:06 INFO - 'RUST_BACKTRACE': 'full', 11:38:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:38:06 INFO - 'SHELL': '/bin/bash', 11:38:06 INFO - 'SHLVL': '1', 11:38:06 INFO - 'TERM': 'linux', 11:38:06 INFO - 'TMOUT': '86400', 11:38:06 INFO - 'USER': 'cltbld', 11:38:06 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', 11:38:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:06 INFO - '_': '/tools/buildbot/bin/python'} 11:38:06 INFO - TEST-INFO | started process 3770 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp33HLHA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:38:06 INFO - PID 3770 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:38:07 INFO - PID 3770 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:38:07 INFO - PID 3770 | 11:38:07 INFO - PID 3770 | (/builds/slave/test/build/application/firefox/firefox:3830): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:38:07 INFO - PID 3770 | 11:38:17 INFO - PID 3770 | RSS: Main: 196894720 11:38:17 INFO - PID 3770 | 11:41:15 INFO - PID 3770 | Cycle 1(1): loaded http://localhost:47751/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47751/tests/dromaeo/cssquery-ext.html) 11:41:16 INFO - PID 3770 | RSS: Main: 188534784 11:41:16 INFO - PID 3770 | 11:44:18 INFO - PID 3770 | Cycle 1(1): loaded http://localhost:47751/tests/dromaeo/cssquery-ext.html (next: http://localhost:47751/tests/dromaeo/cssquery-jquery.html) 11:44:19 INFO - PID 3770 | RSS: Main: 188956672 11:44:19 INFO - PID 3770 | 11:47:21 INFO - PID 3770 | Cycle 1(1): loaded http://localhost:47751/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47751/tests/dromaeo/cssquery-mootools.html) 11:47:22 INFO - PID 3770 | RSS: Main: 189448192 11:47:22 INFO - PID 3770 | 11:50:24 INFO - PID 3770 | Cycle 1(1): loaded http://localhost:47751/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47751/tests/dromaeo/cssquery-prototype.html) 11:50:24 INFO - PID 3770 | RSS: Main: 189579264 11:50:24 INFO - PID 3770 | 11:53:28 INFO - PID 3770 | Cycle 1(1): loaded http://localhost:47751/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47751/tests/dromaeo/cssquery-yui.html) 11:53:28 INFO - PID 3770 | RSS: Main: 192745472 11:53:28 INFO - PID 3770 | 11:56:30 INFO - PID 3770 | Cycle 1(1): loaded http://localhost:47751/tests/dromaeo/cssquery-yui.html (next: http://localhost:47751/tests/dromaeo/cssquery-dojo.html) 11:56:31 INFO - PID 3770 | RSS: Main: 193593344 11:56:31 INFO - PID 3770 | 11:56:31 INFO - PID 3770 | __start_tp_report 11:56:31 INFO - PID 3770 | _x_x_mozilla_page_load 11:56:31 INFO - PID 3770 | _x_x_mozilla_page_load_details 11:56:31 INFO - PID 3770 | |i|pagename|runs| 11:56:31 INFO - PID 3770 | |0;dojo.html;3072.2472994116442;3117.236277112108;3135.890243841466;3139.4035133324664;3159.194405426616;6110.541709371792;6175;6258.374162583745;6322.494200463963;6389.201349831271;6159.846003849906;6182.5981311214855;6203.441690247877;6221.906671399933;6232.971559692645;5689.829305120853;5764.16419619156;5776.855578610526;5782.930157920798;5803.448672376117;5750.137479378085;5853.707314634273;5870.20752198452;5887.116932460122;5944.286685597713;5736.6558006519535;5837.50381217597;5876.764929402819;5886.411358864122;5897.292324920994;1281.9142768143597;5350.652207606532;5618.747156377973;5647.548196144298;5701.344345400291;5683.147527870811;5753.050746626802;5780.653160810322;5829.562782791308;5829.679367634779;6188.566800323979;6227.813165605039;6254.437100660963;6272;6363.204599425072;6712.697928593225;6724.4956628252585;6792.524523283323;6809.5233333666665;6826.897596536056;6706.061151438803;6736.2253340865445;6844.965775171142;6849.760258390932;6849.863002739967;6074.179985701918;6163.2604087509335;6175.567710260284;6230.5638605297645;6313.494920406357;6863.519553631298;6877.828054298682;6883.862322753517;6906.792796216121;6919.654017299215;6616;6616.702248398834;6642.269350371455;6644.767433139817;6648;6654.966725166343;6681.265060843303;6683.565568238049;6777.35615116573;6797.2523022467485;6495.382938620824;6643.60138391698;6651.833704157338;6745.190577130776;6754.662266886636;6513.511486638521;6538.803835884931;6551.934480655228;6564.901526477107;6572.671366431659;4860.6111511079025;4872.317875497433;4920;4958.9504104959215;4960.677555958851;4227.682923780667;4272.6154646082;4291.3777502262;4348.5216626171095;4394.450693663292;5778.682172480554;5808.854778630568;5821.592488525847;5865.88268234633;5870.119482077723;6682.799516014527;6747.831304217434;6821.829454263584;6824.863502729918;6842.04211410403;5610.102383618601;5789;5789.8263052108505;5846.298444186725;5871.530277577869;5599.496045355938;5680.829575112753;5717.685527295956;5763.510101641324;5809.506191973645;6445.129907462527;6524.510661700391;6542.084108224853;6563.573367731082;6571.145751052428;6710.060591517094;6729.259781424137;6734.865302693918;6797.592144471348;6798.320167983162;6423.903641445476;6507.707153178213;6591.538592298685;6604.042413850061;6613.702383392662;6431.292557818542;6595.736170553124;6606.669666516751;6614;6694.230163531256;6663.700133493908;6673.265940746611;6817.625030623165;6821.488388370793;6837.89743153863;6520.543561950808;6548.279689234276;6562.146920900444;6591.604503729792;6616.7022483987375;6476.417122459001;6512.05575191604;6541.836454088686;6581;6596.142501474777;6216.875662486816;6438.67806609677;6447.838804029937;6525.184351956005;6535.3464653534265;6338.668879535349;6432.6462044588;6449;6460.741570337133;6478.093066970534;6264.68676566179;6314;6357.841053973502;6404.519661025535;6411.679416029273 11:56:31 INFO - PID 3770 | |1;ext.html;774.608822544615;813.9511629302241;820.4093053001836;825.4923222218334;825.896762904637;38055.23889522208;38831.41752873709;40910.79544602276;41477;41655;38409.231815363746;38811.22377552447;38912.80543597285;39065.80467097668;39087.80456097716;7122.501424900259;7155.642217889111;7227.34953854153;7235.855282894339;7252.0572325597595;7748.263914928073;7829.960850195741;7884;7933.206679332049;7938.325242354407;7864.213578642147;7872.5670088145125;7942.602869856484;7951.880721789149;7952.761417157494;7833.490823096509;7882.684692612317;7890.368770498347;7898.565578893159;7912.208779122099;7758.418118641124;7824.843503129906;7833.608319583998;7862.960685196537;7886.093099293597;39606.20787584232;39740.20519589592;39795.21433499656;39906.40140397896;39980.40029399561;3595.676389124965;3630.3646861799343;3633.7638053526703;3634.56385233771;3637.4907512947943;30940.535891961637;31122.066338009896;31205;31339.216519586964;31386.52920206199;4939.259111133359;5132.897342053138;5133.178691409394;5167.844964651066;5280.947190528084;194576;194868.02565987222;195346;195740;196023;37694.24611507754;38398.232035359135;47266.5273347269;47412.5258747415;47769;50861.491385085676;52806.20790688143;53152.73423632934;53568.73215633897;53819.73090134524;16292;16315.755263671055;16514.091724955244;16524.586885327724;16529.421470248722;13278.601641950758;13301.334933253298;13368.331583420984;13417.329133543284;13419.93290033563;14416.855831441551;14421.134731916118;14480.85519144795;14624.68377845999;14717.484888028868;5802.854928626733;5857.531397488191;5893.5579831512805;5911;6072.937235983685;994.174834887042;1007.4610083605236;1014.974625634365;1015.6851376073441;1021.7037059252733;22840.200592979167;23120.959556819646;23265.418364541023;23290.3012909613;23385.532289354458;1100.6918062942389;1126.4142645824124;1130.2766229613055;1131.1120770195357;1142.2860712054965;12083.57707480234;12107.213031152947;12251.509939602494;12309.50761969529;12326.81509777354;225667;226318;226548;228652;229139;54840.45159548433;55034.44965550373;55061.174082387995;55235;55283;1368.3021658953805;1456.1044957351364;1490.1059364381501;1491.9627009324854;1499.197929107922;22561.210357637403;22815;22906.65640015368;23162.53674926526;23460;37702.057448564;37741.62258377491;37940.810295948344;38202.23595528129;38215;195511;197453;197716;198731;198767;27994.300142496602;32661.510077348383;34118;34840;35043.546951713135;40243.597564025156;42784;43450.782746086064;43741.78129109334;43925.78037109794;31232.843835781583;31300.843495782374;31402.21494462657;31452;31887.202819929687;31148.844255778575;31558;31695.36609267848;31954.20114497156;31999.520007200375;33984;35960.8201958999;36196.81901590475;36252;36392.09019774527;29872;30002.94989675438;30247.395052098396;30568;31537;22823;23020.884895575415;23660.17189398363;23722.525549488568;23894.52210955806 11:56:31 INFO - PID 3770 | |2;jquery.html;577.3245302995495;583.5657157627364;589.3929252869546;589.5283772981614;591.5770224289633;21412.57174856504;22239.44401389964;22366;22402.43993900153;22408.887955560218;21531.784682153175;21592.35222943314;21759.8032108234;24502.632460513105;24722.876385618092;6466.579672321296;6663.4669226461765;6670.466362690973;6672.199336079677;6678.56589321695;6572.047053177311;6626.271110177877;6649.368310010541;6651.8337041574105;6651.866962660744;6396.776112836052;6604.900926486107;6609.107770451;6614.206295244578;6635.071090047397;6638.336166383371;6669.733210671591;6674.733010679567;6681.732730690766;6688.699008544579;5885.176075349455;6083.817485475399;6106.694665266719;6163.907541386883;6211.751529938797;27535.173944781487;27960.30099247535;28057.438851223065;28120.578191327146;28212.858935705397;2056.28030189434;2074.460640233535;2082.073477302601;2085.270155445597;2088.571842772228;18818.90590547052;18833.24667013318;18839.340623078264;19166.329178478685;19213.32753353626;1858.3960212930717;1897.5351038995395;1976.6343226503143;1989.950251243716;1995.5709522452598;118176.40911795472;121910;121986;142893;147179.26410367986;14353.122771367993;14579.68782809531;14842.356186563713;14888.660020598198;15009.699806003819;23353.182638607566;23456.7654323458;23520.529589408117;23640.645390319158;23647.763522364556;9938;9988.551660009258;10044.698659040241;10266.794664106825;10362.222833287382;9284.210842078364;9308.488033158106;9413.388129771543;9550.331476796557;9582.808343833085;8828.911710882809;8853.848999630007;8987.865182022275;9085.318601104811;9143.359964802534;2645.722199169098;2660.7472290132405;2740.1231605885923;2770.612114303999;2810.480061188646;962.9133377996014;973.0415540692412;973.7906350134755;980.9019098090276;989.980200395988;18264.908675456536;18457.53856153607;18660.906695466434;18667.253309867454;18983.620327593646;1026.6765968719828;1027.8817935937464;1036.9429681367596;1039.2725092435326;1045.1690905729963;10055.849162262572;10479.94760026195;10498.422586757802;10597.15222782191;10709.518071686793;469312;484267;487544;488958;494392;100255.49872250592;106622;107559;108757;113581.43209284067;952.7713348796377;975.4829940131741;995.1789773436943;1141.7545227776234;1167.7547714979949;19493;19703.605927881075;19827.504312392306;20000.599987999867;20172.899135504227;53668.463315366345;55537.722311388185;56892.71553642205;57327.71336143293;57582.136267955175;73469.26530734624;73522;73831;74531;74717.62641186759;14192.858071419154;14436.783448248494;14465.566033019026;14469.421223150955;14543.41826326935;22274.77725222727;22349.553008940056;22393.44016399603;22551.661725074464;22963.88518057399;17475.737863932307;18122.54693632617;18220.544486387946;18231.635367292845;18375.26498940081;15757.842421575637;16184.271707773414;16184.757228641814;16245.67508649844;16655.75016374731;16840.73694472944;17919.37282195117;17944;17980.55048623795;18359;14090.295485225493;14171.787423188867;14295;14306.284685765462;14332.928335358256;17025.233864475882;17237.827621724125;17327.91336043312;17638.823611763717;17753.201105950004 11:56:31 INFO - PID 3770 | |3;mootools.html;1266.1453518874757;1350.3383342162344;1373.629120137563;1374.29223949666;1377.0567161494378;14740.11559306441;15489.84510154898;15537.067775933452;15546.844531554681;15550.222488875568;15177.924110379432;15211.315490802912;15346.00250983688;15347.76978345323;15361;2722.469118521879;2775.139706690927;2806.2984253936515;2813.4935711571907;2818.168640251131;2628.316637674209;2710.864456777163;2718.3340081680017;2750.188694335176;2778.4304217635336;2687.7446642568925;2715.1447294102395;2725.1415804021767;2728.0451841855293;2800.6079148919166;2773.9306517337027;2817.521021426352;2838.120182743356;2841.687414384416;2845.4309138172343;2696.3393968477653;2718.8096833221684;2737.0420352876336;2766.14249582628;2768.584712293152;15050.096994180385;15300;15310.157941313224;15350.539483815503;15374.846251537454;8079.95960020202;8489.066202717697;8490.10853860342;8525.104863989254;8545.487270763711;5405.162199859028;5426.186072089179;5529.170624406331;5546.889062218733;5657;1863.7577114974968;1906.8474522038205;1915.9329423470112;1921.788603253641;1951.2000079967288;26994.32514187161;27052.188434347;27194.728052719416;27293.044743434082;27567.862160689067;6798.456123510107;6899.344562266577;6944.583325000516;7093.92906070943;7127.10911136108;17830.375936842407;17840.46478605644;17915.73126403105;17928.282868685106;17930.641387172185;7697.65360558765;7726.845463090706;7824.530528168328;7838.686452541949;7882.684692612346;7058;7302.0142280791415;7417;7652.311291983749;7652.426068044919;5109;5328;5348.224507446398;5364.30264065669;5493.560515158778;1289.8323217981601;1509.3434356055152;1526.2750193658146;1537;1557.8208506021726;679.5956405938458;892.2237653241722;893.3344658229661;911.589784596934;929.6746138851348;8105.189481051848;8207.548584827773;8308.12764659714;8322.20939010793;8510.106438823957;993.9751506212402;999.550202408919;1000.9199264058858;1007.1640538353296;1022.7136401807505;5257.027449921777;5315.04329220744;5379.408265090837;5406.999705054499;5416.972915135478;19864.404067877986;22376.776232237797;22795.88602056979;22863;22888;20423.897880510503;21618.351449456542;21778.673319900216;22079.33761987143;22430.214942476934;11502.309861408175;11851.70370740721;11852.644420667393;11864.644060678194;11876.643700688994;8088.272055515032;8226.62980165882;8291;8419.747407577783;8556.486610803371;18146.274149033892;18634.97507637093;18993.525161871064;19040.61918761645;19119.61760764749;18228.544286392946;18934.43196704101;19635.920024398794;19693.70459443081;20024.49938751543;10807.891921080689;11507.252028618113;11708.88291117112;11720.355380454157;11722.296662200295;13333.133346332457;13535.323233838466;13663.931680341535;13698;13732;10536.473176340998;10629.309094908805;10833.458327083457;11035.117190624891;11057.778844423228;11189.832152517556;11233.887661123284;11320.886791131983;11345;11412.885871141183;9019.909800901907;9232.630694772133;9248.398854074463;9263.212626926786;9332.580033898355;7291.744788932362;7452.956586078062;7527.209642987407;7545.886811697938;7683.846323073619;7128.643567821692;7291.38023268007;7306.232845551141;7336.559806411632;7338.889916651361 11:56:31 INFO - PID 3770 | |4;prototype.html;504.750148676405;513.2916575126325;523.1446584833797;536.8469986053975;539.8353502181835;5987.760489580417;5998.910016349747;6021.9397806021925;6089.5432842536875;6119.694015299234;5903.704814759256;5974.611650242731;5992.16109744635;6018.578699491038;6043.939560604404;4974.303597496344;5005;5029.698218106925;5032.245163225509;5046.571041461461;5046.671966322196;5061.822836200734;5071.56891664209;5110.182370820669;5119.923201151985;4909.607231421478;5003.724795136266;5017.297578339036;5026.748662566875;5073.162928116874;5060.84817455477;5063;5115.667481613683;5117.744112794346;5137.51193636604;4635.165670179366;4733.9763301183275;4753.904921901577;4759;4767.42790865095;5934.1692163097205;5975.462208401222;5984.132300816357;5986.431289027535;6014;5855.8536036599;5880.97059514704;5886.970565147147;5891.403166991376;5892.587518873681;6351.587146835441;6363.204599425072;6426.003969384706;6444.162258906359;6485.124508191381;4827.203511420611;5164;5227.973860130713;5239.646626159261;5247.842564723064;6475.3200913903365;6535.967320163416;6540.247871494744;6600.471962243009;6609.711106334266;5297.5232229099565;5336.279602253724;5371.516563509303;5372.62391632582;5417.322834645669;5368.221607866837;5393.541548968382;5398.163284690879;5456.399796022434;5463.153211252262;5761;5766.826995190151;5780;5794.797182098607;5822.679752613562;6420;6422.197225346832;6438.291787903327;6454.450931776434;6466.127072845105;1880.407671583447;1893.981060189408;1908.0269062778084;1910.9044547772555;1925.653382391155;1051.4374809476892;1391.958241252764;1394.5746547302967;1429.6425893526618;1441.6251774538487;5429.972850135803;5468.28912241406;5469.667074602231;5475.835724928259;5513.9448605514235;6280.497560195174;6300.527460440485;6325.620462772248;6395.872082558323;6446.806595802133;5471.37079235893;5501;5523.558115350762;5537.224788529609;5557.138643510262;3410.4031794435873;3429.4341433663667;3437.645213029083;3439.5872495300223;3440.673136052071;6116.908246376308;6201.379862013762;6237.438630523275;6260.686965651699;6265.619064755364;6595.868082638321;6646.652446913186;6665.566738162004;6720.051185156348;6754.716603845253;6262;6316.30520642729;6325.462335701517;6328.683565821782;6342.334054924258;6327.651979141191;6351.71417286214;6510.837229069121;6514.4788416925585;6542.607443553402;6281.403266689657;6369.20384951881;6397.776077837253;6426.164598602152;6475.838104047437;6255.749770009149;6263.530235232284;6273.71768270438;6284.151639528788;6304.275008374096;5210.426853046238;5320.600954928474;5323.1749078892835;5326.09456392422;5333.7066461345;6252.562320637512;6305.243370795534;6352.237731472253;6440.871182576228;6442.581232219891;6115.785947491994;6251.312355640966;6317;6361.236651601837;6396.58422202574;6183.876322473615;6231.4391704746795;6264.59280146789;6275.937240627535;6301;6128.816135515764;6152.938470615416;6154.323024467216;6158.476529495043;6180.783672571439;6276.246850377984;6327.462165715781;6339.619622822645;6354.5869518483;6379.298277189413;6001.879962400641;6057.606255593372;6073.362296958932;6079.848003799763;6133.3866613338305 11:56:31 INFO - PID 3770 | |5;yui.html;891.189017993626;896.0591379051993;901.531203774038;905.2395987370608;922.8615707643854;16958.83041169588;17876.374326898527;17891.552711182227;17920.910395448005;18004.189811458484;18444.262229510805;18617.627647447043;18688.813111868876;18823.43529694108;18897.81102188981;948.6395169835453;974.8050389922008;980.5538479991623;981.6318880419842;984.9212063034976;979.6130528441297;987.249690235419;989.4904124375952;993.8310487217191;998.8951160128191;1011.2264117949775;1041.656253436364;1041.8697662792151;1045.8012977534245;1054.2146101154613;1040.427764729396;1042.338115296792;1048.2138396202847;1048.2505008918583;1051.7318083888636;742.4246209187922;743.6690672650673;747.0736287004075;749.7038669725428;755.9357454616364;17186.140692965302;17496.387626433014;17557.473275801618;17575.121243937752;17620.20709068095;21857.453563660878;22161;22258.554828903332;22623.095076196932;22817.88591057051;17190.570235744082;17648.911755441142;17709.29162833498;17710.91144544282;17874.731879021823;3685.1339935115225;3697.8890633281044;3708.258348330318;3713.57293911201;3727.273181729564;100592;103684;136668;144661.27669361586;146109;16976.660466790596;17170.39903603368;17482.737758933625;17513.649727005388;17553.912230438767;30948.22629434282;31872;32431.837840810644;32503.674963250065;32504;9262.073792620818;9324.1608255256;9347.345685802065;9351.859722104175;9373.625054997723;4622.168009758208;4680.953190468052;4681.67228294023;4695.483496815347;4697.412823397075;5254.97372513135;5360.3031605891765;5373.35519737634;5383.946160538423;5410.972945135328;1075.7095584192225;1100.4772732951783;1105.6959336182615;1124.0445621221895;1125.3810404277615;581.520245797219;593.9851503712442;614.0574218574467;620.7237779188308;623.0560700538688;11804.822927656092;12220.3278819664;12369.93815030919;12459.252444853339;12724.745505089846;643.9581427207311;645.9709313080923;651.0592194279221;654.5908806995627;665.4210836572116;5740.54075673945;5831.825045248649;5847.708451672689;5863.413658634187;5875.148103524965;121582;126665.36667316605;131048;133191;134944.32527837297;108127;108579.45710271556;109473;111443;112731;92060.53969730108;92550;92828;93284;96186;10896.61861834832;10926.781464370828;11015.83476247873;11262.09903207725;11388.373639449579;43448.78275608602;43658.56341436545;43673.78163109164;45325.77337113425;45369;56657;56774.43225567691;57053;57179;57192.428075720374;15463.768043479131;15556.222188890737;15601.453949111727;15608.609784755472;15965;18181;18395.08024598738;18474.353397631017;18610.44168674942;18874.33939812155;15389.538313850602;15412.922935385252;15472.613184670474;15498.690026199187;15739.763903541227;14833.184174870054;14895.702085958437;14971.475998340005;15086.321115549606;15104.622384440478;14728.779068314198;14807.963442558917;14852.777208341668;14952.551423457313;15146.924265378602;11929.164958452826;12124.33316167584;12199.756004879675;12562.937185314015;12622.495100195889;11608.941955290507;11926.821097683714;12060;12081.939590301992;12189.939050304693 11:56:31 INFO - PID 3770 | __end_tp_report 11:56:31 INFO - PID 3770 | __start_cc_report 11:56:31 INFO - PID 3770 | _x_x_mozilla_cycle_collect,288 11:56:31 INFO - PID 3770 | __end_cc_report 11:56:31 INFO - PID 3770 | __startTimestamp1507056991128__endTimestamp 11:56:31 INFO - PID 3770 | 11:56:31 INFO - PID 3770 | ------- Summary: start ------- 11:56:31 INFO - PID 3770 | Number of tests: 6 11:56:31 INFO - PID 3770 | 11:56:31 INFO - PID 3770 | [#0] dojo.html Cycles:165 Average:6116.74 Median:6423.90 stddev:865.81 (13.5%) stddev-sans-first:834.88 11:56:31 INFO - PID 3770 | Values: 3072.2 3117.2 3135.9 3139.4 3159.2 6110.5 6175.0 6258.4 6322.5 6389.2 6159.8 6182.6 6203.4 6221.9 6233.0 5689.8 5764.2 5776.9 5782.9 5803.4 5750.1 5853.7 5870.2 5887.1 5944.3 5736.7 5837.5 5876.8 5886.4 5897.3 1281.9 5350.7 5618.7 5647.5 5701.3 5683.1 5753.1 5780.7 5829.6 5829.7 6188.6 6227.8 6254.4 6272.0 6363.2 6712.7 6724.5 6792.5 6809.5 6826.9 6706.1 6736.2 6845.0 6849.8 6849.9 6074.2 6163.3 6175.6 6230.6 6313.5 6863.5 6877.8 6883.9 6906.8 6919.7 6616.0 6616.7 6642.3 6644.8 6648.0 6655.0 6681.3 6683.6 6777.4 6797.3 6495.4 6643.6 6651.8 6745.2 6754.7 6513.5 6538.8 6551.9 6564.9 6572.7 4860.6 4872.3 4920.0 4959.0 4960.7 4227.7 4272.6 4291.4 4348.5 4394.5 5778.7 5808.9 5821.6 5865.9 5870.1 6682.8 6747.8 6821.8 6824.9 6842.0 5610.1 5789.0 5789.8 5846.3 5871.5 5599.5 5680.8 5717.7 5763.5 5809.5 6445.1 6524.5 6542.1 6563.6 6571.1 6710.1 6729.3 6734.9 6797.6 6798.3 6423.9 6507.7 6591.5 6604.0 6613.7 6431.3 6595.7 6606.7 6614.0 6694.2 6663.7 6673.3 6817.6 6821.5 6837.9 6520.5 6548.3 6562.1 6591.6 6616.7 6476.4 6512.1 6541.8 6581.0 6596.1 6216.9 6438.7 6447.8 6525.2 6535.3 6338.7 6432.6 6449.0 6460.7 6478.1 6264.7 6314.0 6357.8 6404.5 6411.7 11:56:31 INFO - PID 3770 | 11:56:31 INFO - PID 3770 | [#1] ext.html Cycles:180 Average:37468.03 Median:19778.25 stddev:53673.94 (271.4%) stddev-sans-first:53753.79 11:56:31 INFO - PID 3770 | Values: 774.6 814.0 820.4 825.5 825.9 38055.2 38831.4 40910.8 41477.0 41655.0 38409.2 38811.2 38912.8 39065.8 39087.8 7122.5 7155.6 7227.3 7235.9 7252.1 7748.3 7830.0 7884.0 7933.2 7938.3 7864.2 7872.6 7942.6 7951.9 7952.8 7833.5 7882.7 7890.4 7898.6 7912.2 7758.4 7824.8 7833.6 7863.0 7886.1 39606.2 39740.2 39795.2 39906.4 39980.4 3595.7 3630.4 3633.8 3634.6 3637.5 30940.5 31122.1 31205.0 31339.2 31386.5 4939.3 5132.9 5133.2 5167.8 5280.9 194576.0 194868.0 195346.0 195740.0 196023.0 37694.2 38398.2 47266.5 47412.5 47769.0 50861.5 52806.2 53152.7 53568.7 53819.7 16292.0 16315.8 16514.1 16524.6 16529.4 13278.6 13301.3 13368.3 13417.3 13419.9 14416.9 14421.1 14480.9 14624.7 14717.5 5802.9 5857.5 5893.6 5911.0 6072.9 994.2 1007.5 1015.0 1015.7 1021.7 22840.2 23121.0 23265.4 23290.3 23385.5 1100.7 1126.4 1130.3 1131.1 1142.3 12083.6 12107.2 12251.5 12309.5 12326.8 225667.0 226318.0 226548.0 228652.0 229139.0 54840.5 55034.4 55061.2 55235.0 55283.0 1368.3 1456.1 1490.1 1492.0 1499.2 22561.2 22815.0 22906.7 23162.5 23460.0 37702.1 37741.6 37940.8 38202.2 38215.0 195511.0 197453.0 197716.0 198731.0 198767.0 27994.3 32661.5 34118.0 34840.0 35043.5 40243.6 42784.0 43450.8 43741.8 43925.8 31232.8 31300.8 31402.2 31452.0 31887.2 31148.8 31558.0 31695.4 31954.2 31999.5 33984.0 35960.8 36196.8 36252.0 36392.1 29872.0 30002.9 30247.4 30568.0 31537.0 22823.0 23020.9 23660.2 23722.5 23894.5 11:56:31 INFO - PID 3770 | 11:56:31 INFO - PID 3770 | [#2] jquery.html Cycles:180 Average:34005.06 Median:22312.17 stddev:81418.04 (364.9%) stddev-sans-first:81607.75 11:56:31 INFO - PID 3770 | Values: 577.3 583.6 589.4 589.5 591.6 21412.6 22239.4 22366.0 22402.4 22408.9 21531.8 21592.4 21759.8 24502.6 24722.9 6466.6 6663.5 6670.5 6672.2 6678.6 6572.0 6626.3 6649.4 6651.8 6651.9 6396.8 6604.9 6609.1 6614.2 6635.1 6638.3 6669.7 6674.7 6681.7 6688.7 5885.2 6083.8 6106.7 6163.9 6211.8 27535.2 27960.3 28057.4 28120.6 28212.9 2056.3 2074.5 2082.1 2085.3 2088.6 18818.9 18833.2 18839.3 19166.3 19213.3 1858.4 1897.5 1976.6 1990.0 1995.6 118176.4 121910.0 121986.0 142893.0 147179.3 14353.1 14579.7 14842.4 14888.7 15009.7 23353.2 23456.8 23520.5 23640.6 23647.8 9938.0 9988.6 10044.7 10266.8 10362.2 9284.2 9308.5 9413.4 9550.3 9582.8 8828.9 8853.8 8987.9 9085.3 9143.4 2645.7 2660.7 2740.1 2770.6 2810.5 962.9 973.0 973.8 980.9 990.0 18264.9 18457.5 18660.9 18667.3 18983.6 1026.7 1027.9 1036.9 1039.3 1045.2 10055.8 10479.9 10498.4 10597.2 10709.5 469312.0 484267.0 487544.0 488958.0 494392.0 100255.5 106622.0 107559.0 108757.0 113581.4 952.8 975.5 995.2 1141.8 1167.8 19493.0 19703.6 19827.5 20000.6 20172.9 53668.5 55537.7 56892.7 57327.7 57582.1 73469.3 73522.0 73831.0 74531.0 74717.6 14192.9 14436.8 14465.6 14469.4 14543.4 22274.8 22349.6 22393.4 22551.7 22963.9 17475.7 18122.5 18220.5 18231.6 18375.3 15757.8 16184.3 16184.8 16245.7 16655.8 16840.7 17919.4 17944.0 17980.6 18359.0 14090.3 14171.8 14295.0 14306.3 14332.9 17025.2 17237.8 17327.9 17638.8 17753.2 11:56:31 INFO - PID 3770 | 11:56:31 INFO - PID 3770 | [#3] mootools.html Cycles:180 Average:9438.23 Median:2718.57 stddev:6787.97 (249.7%) stddev-sans-first:6779.24 11:56:31 INFO - PID 3770 | Values: 1266.1 1350.3 1373.6 1374.3 1377.1 14740.1 15489.8 15537.1 15546.8 15550.2 15177.9 15211.3 15346.0 15347.8 15361.0 2722.5 2775.1 2806.3 2813.5 2818.2 2628.3 2710.9 2718.3 2750.2 2778.4 2687.7 2715.1 2725.1 2728.0 2800.6 2773.9 2817.5 2838.1 2841.7 2845.4 2696.3 2718.8 2737.0 2766.1 2768.6 15050.1 15300.0 15310.2 15350.5 15374.8 8080.0 8489.1 8490.1 8525.1 8545.5 5405.2 5426.2 5529.2 5546.9 5657.0 1863.8 1906.8 1915.9 1921.8 1951.2 26994.3 27052.2 27194.7 27293.0 27567.9 6798.5 6899.3 6944.6 7093.9 7127.1 17830.4 17840.5 17915.7 17928.3 17930.6 7697.7 7726.8 7824.5 7838.7 7882.7 7058.0 7302.0 7417.0 7652.3 7652.4 5109.0 5328.0 5348.2 5364.3 5493.6 1289.8 1509.3 1526.3 1537.0 1557.8 679.6 892.2 893.3 911.6 929.7 8105.2 8207.5 8308.1 8322.2 8510.1 994.0 999.6 1000.9 1007.2 1022.7 5257.0 5315.0 5379.4 5407.0 5417.0 19864.4 22376.8 22795.9 22863.0 22888.0 20423.9 21618.4 21778.7 22079.3 22430.2 11502.3 11851.7 11852.6 11864.6 11876.6 8088.3 8226.6 8291.0 8419.7 8556.5 18146.3 18635.0 18993.5 19040.6 19119.6 18228.5 18934.4 19635.9 19693.7 20024.5 10807.9 11507.3 11708.9 11720.4 11722.3 13333.1 13535.3 13663.9 13698.0 13732.0 10536.5 10629.3 10833.5 11035.1 11057.8 11189.8 11233.9 11320.9 11345.0 11412.9 9019.9 9232.6 9248.4 9263.2 9332.6 7291.7 7453.0 7527.2 7545.9 7683.8 7128.6 7291.4 7306.2 7336.6 7338.9 11:56:31 INFO - PID 3770 | 11:56:31 INFO - PID 3770 | [#4] prototype.html Cycles:180 Average:5432.17 Median:5987.10 stddev:1433.72 (23.9%) stddev-sans-first:1389.22 11:56:31 INFO - PID 3770 | Values: 504.8 513.3 523.1 536.8 539.8 5987.8 5998.9 6021.9 6089.5 6119.7 5903.7 5974.6 5992.2 6018.6 6043.9 4974.3 5005.0 5029.7 5032.2 5046.6 5046.7 5061.8 5071.6 5110.2 5119.9 4909.6 5003.7 5017.3 5026.7 5073.2 5060.8 5063.0 5115.7 5117.7 5137.5 4635.2 4734.0 4753.9 4759.0 4767.4 5934.2 5975.5 5984.1 5986.4 6014.0 5855.9 5881.0 5887.0 5891.4 5892.6 6351.6 6363.2 6426.0 6444.2 6485.1 4827.2 5164.0 5228.0 5239.6 5247.8 6475.3 6536.0 6540.2 6600.5 6609.7 5297.5 5336.3 5371.5 5372.6 5417.3 5368.2 5393.5 5398.2 5456.4 5463.2 5761.0 5766.8 5780.0 5794.8 5822.7 6420.0 6422.2 6438.3 6454.5 6466.1 1880.4 1894.0 1908.0 1910.9 1925.7 1051.4 1392.0 1394.6 1429.6 1441.6 5430.0 5468.3 5469.7 5475.8 5513.9 6280.5 6300.5 6325.6 6395.9 6446.8 5471.4 5501.0 5523.6 5537.2 5557.1 3410.4 3429.4 3437.6 3439.6 3440.7 6116.9 6201.4 6237.4 6260.7 6265.6 6595.9 6646.7 6665.6 6720.1 6754.7 6262.0 6316.3 6325.5 6328.7 6342.3 6327.7 6351.7 6510.8 6514.5 6542.6 6281.4 6369.2 6397.8 6426.2 6475.8 6255.7 6263.5 6273.7 6284.2 6304.3 5210.4 5320.6 5323.2 5326.1 5333.7 6252.6 6305.2 6352.2 6440.9 6442.6 6115.8 6251.3 6317.0 6361.2 6396.6 6183.9 6231.4 6264.6 6275.9 6301.0 6128.8 6152.9 6154.3 6158.5 6180.8 6276.2 6327.5 6339.6 6354.6 6379.3 6001.9 6057.6 6073.4 6079.8 6133.4 11:56:31 INFO - PID 3770 | 11:56:31 INFO - PID 3770 | [#5] yui.html Cycles:180 Average:24110.80 Median:18288.04 stddev:34826.15 (190.4%) stddev-sans-first:34880.20 11:56:31 INFO - PID 3770 | Values: 891.2 896.1 901.5 905.2 922.9 16958.8 17876.4 17891.6 17920.9 18004.2 18444.3 18617.6 18688.8 18823.4 18897.8 948.6 974.8 980.6 981.6 984.9 979.6 987.2 989.5 993.8 998.9 1011.2 1041.7 1041.9 1045.8 1054.2 1040.4 1042.3 1048.2 1048.3 1051.7 742.4 743.7 747.1 749.7 755.9 17186.1 17496.4 17557.5 17575.1 17620.2 21857.5 22161.0 22258.6 22623.1 22817.9 17190.6 17648.9 17709.3 17710.9 17874.7 3685.1 3697.9 3708.3 3713.6 3727.3 100592.0 103684.0 136668.0 144661.3 146109.0 16976.7 17170.4 17482.7 17513.6 17553.9 30948.2 31872.0 32431.8 32503.7 32504.0 9262.1 9324.2 9347.3 9351.9 9373.6 4622.2 4681.0 4681.7 4695.5 4697.4 5255.0 5360.3 5373.4 5383.9 5411.0 1075.7 1100.5 1105.7 1124.0 1125.4 581.5 594.0 614.1 620.7 623.1 11804.8 12220.3 12369.9 12459.3 12724.7 644.0 646.0 651.1 654.6 665.4 5740.5 5831.8 5847.7 5863.4 5875.1 121582.0 126665.4 131048.0 133191.0 134944.3 108127.0 108579.5 109473.0 111443.0 112731.0 92060.5 92550.0 92828.0 93284.0 96186.0 10896.6 10926.8 11015.8 11262.1 11388.4 43448.8 43658.6 43673.8 45325.8 45369.0 56657.0 56774.4 57053.0 57179.0 57192.4 15463.8 15556.2 15601.5 15608.6 15965.0 18181.0 18395.1 18474.4 18610.4 18874.3 15389.5 15412.9 15472.6 15498.7 15739.8 14833.2 14895.7 14971.5 15086.3 15104.6 14728.8 14808.0 14852.8 14952.6 15146.9 11929.2 12124.3 12199.8 12562.9 12622.5 11608.9 11926.8 12060.0 12081.9 12189.9 11:56:31 INFO - PID 3770 | -------- Summary: end -------- 11:56:31 INFO - PID 3770 | 11:56:31 INFO - TEST-INFO | 3770: exit 0 11:56:31 INFO - TEST-OK | dromaeo_css | took 1111800ms 11:56:31 INFO - TEST-START | kraken 11:56:31 INFO - Initialising browser for kraken test... 11:56:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47751/getInfo.html -profile /tmp/tmpAo4UYz/profile 11:56:31 INFO - TEST-INFO | started process 4185 (/builds/slave/test/build/application/firefox/firefox http://localhost:47751/getInfo.html) 11:56:37 INFO - TEST-INFO | 4185: exit 0 11:56:37 INFO - Browser initialized. 11:56:37 INFO - Running cycle 1/1 for kraken test... 11:56:37 INFO - Using env: {'DISPLAY': ':0', 11:56:37 INFO - 'HOME': '/home/cltbld', 11:56:37 INFO - 'JSGC_DISABLE_POISONING': '1', 11:56:37 INFO - 'LANG': 'en_US.UTF-8', 11:56:37 INFO - 'LANGUAGE': 'en_US:en', 11:56:37 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:56:37 INFO - 'LOGNAME': 'cltbld', 11:56:37 INFO - 'MAIL': '/var/mail/cltbld', 11:56:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:56:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:56:37 INFO - 'MOZ_ACCELERATED': '1', 11:56:37 INFO - 'MOZ_CRASHREPORTER': '1', 11:56:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:37 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:56:37 INFO - 'MOZ_NO_REMOTE': '1', 11:56:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:56:37 INFO - 'MOZ_WEBRENDER': '1', 11:56:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:56:37 INFO - 'NO_EM_RESTART': '1', 11:56:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:56:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:37 INFO - 'PWD': '/builds/slave/test', 11:56:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:56:37 INFO - 'RUST_BACKTRACE': 'full', 11:56:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:56:37 INFO - 'SHELL': '/bin/bash', 11:56:37 INFO - 'SHLVL': '1', 11:56:37 INFO - 'TERM': 'linux', 11:56:37 INFO - 'TMOUT': '86400', 11:56:37 INFO - 'USER': 'cltbld', 11:56:37 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709', 11:56:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:37 INFO - '_': '/tools/buildbot/bin/python'} 11:56:37 INFO - TEST-INFO | started process 4470 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAo4UYz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:56:38 INFO - PID 4470 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:56:38 INFO - PID 4470 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:56:38 INFO - PID 4470 | 11:56:38 INFO - PID 4470 | (/builds/slave/test/build/application/firefox/firefox:4529): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:56:38 INFO - PID 4470 | 11:56:49 INFO - PID 4470 | RSS: Main: 190169088 11:56:49 INFO - PID 4470 | 11:56:51 INFO - PID 4470 | Cycle 1(1): loaded http://localhost:47751/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47751/tests/kraken/driver.html?testName=audio-beat-detection) 11:56:52 INFO - PID 4470 | RSS: Main: 191344640 11:56:52 INFO - PID 4470 | 11:56:55 INFO - PID 4470 | Cycle 1(1): loaded http://localhost:47751/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47751/tests/kraken/driver.html?testName=audio-dft) 11:56:56 INFO - PID 4470 | RSS: Main: 191483904 11:56:56 INFO - PID 4470 | 11:56:59 INFO - PID 4470 | Cycle 1(1): loaded http://localhost:47751/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47751/tests/kraken/driver.html?testName=audio-fft) 11:56:59 INFO - PID 4470 | RSS: Main: 188428288 11:56:59 INFO - PID 4470 | 11:57:01 INFO - PID 4470 | Cycle 1(1): loaded http://localhost:47751/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47751/tests/kraken/driver.html?testName=audio-oscillator) 11:57:02 INFO - PID 4470 | RSS: Main: 188837888 11:57:02 INFO - PID 4470 | 11:57:04 INFO - PID 4470 | Cycle 1(1): loaded http://localhost:47751/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47751/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:57:05 INFO - PID 4470 | RSS: Main: 188805120 11:57:05 INFO - PID 4470 | 11:57:08 INFO - PID 4470 | Cycle 1(1): loaded http://localhost:47751/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47751/tests/kraken/driver.html?testName=imaging-darkroom) 11:57:09 INFO - PID 4470 | RSS: Main: 191229952 11:57:09 INFO - PID 4470 | 11:57:12 INFO - PID 4470 | Cycle 1(1): loaded http://localhost:47751/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47751/tests/kraken/driver.html?testName=imaging-desaturate) 11:57:13 INFO - PID 4470 | RSS: Main: 191098880 11:57:13 INFO - PID 4470 | 11:57:16 INFO - PID 4470 | Cycle 1(1): loaded http://localhost:47751/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47751/tests/kraken/driver.html?testName=json-parse-financial) 11:57:17 INFO - PID 4470 | RSS: Main: 191664128 11:57:17 INFO - PID 4470 | 11:57:18 INFO - PID 4470 | Cycle 1(1): loaded http://localhost:47751/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47751/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:57:19 INFO - PID 4470 | RSS: Main: 191586304 11:57:19 INFO - PID 4470 | 11:57:21 INFO - PID 4470 | Cycle 1(1): loaded http://localhost:47751/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47751/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:57:21 INFO - PID 4470 | RSS: Main: 191504384 11:57:21 INFO - PID 4470 | 11:57:23 INFO - PID 4470 | Cycle 1(1): loaded http://localhost:47751/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47751/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:57:24 INFO - PID 4470 | RSS: Main: 191918080 11:57:24 INFO - PID 4470 | 11:57:27 INFO - PID 4470 | Cycle 1(1): loaded http://localhost:47751/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47751/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:57:27 INFO - PID 4470 | RSS: Main: 192319488 11:57:27 INFO - PID 4470 | 11:57:30 INFO - PID 4470 | Cycle 1(1): loaded http://localhost:47751/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47751/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:57:31 INFO - PID 4470 | RSS: Main: 192196608 11:57:31 INFO - PID 4470 | 11:57:33 INFO - PID 4470 | Cycle 1(1): loaded http://localhost:47751/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47751/tests/kraken/driver.html?testName=ai-astar) 11:57:33 INFO - PID 4470 | RSS: Main: 193200128 11:57:33 INFO - PID 4470 | 11:57:33 INFO - PID 4470 | __start_tp_report 11:57:33 INFO - PID 4470 | _x_x_mozilla_page_load 11:57:33 INFO - PID 4470 | _x_x_mozilla_page_load_details 11:57:33 INFO - PID 4470 | |i|pagename|runs| 11:57:33 INFO - PID 4470 | |0;ai-astar;112;114;107;108;111;109;117;117;111;113 11:57:33 INFO - PID 4470 | |1;audio-beat-detection;118;119;124;124;116;122;123;121;116;124 11:57:33 INFO - PID 4470 | |2;audio-dft;163;163;167;163;164;168;177;168;174;170 11:57:33 INFO - PID 4470 | |3;audio-fft;83;75;74;74;78;75;78;79;74;79 11:57:33 INFO - PID 4470 | |4;audio-oscillator;80;80;83;82;86;82;86;79;79;83 11:57:33 INFO - PID 4470 | |5;imaging-gaussian-blur;114;113;110;114;113;114;115;119;118;114 11:57:33 INFO - PID 4470 | |6;imaging-darkroom;149;154;158;148;148;150;149;149;155;151 11:57:33 INFO - PID 4470 | |7;imaging-desaturate;101;94;95;97;95;94;95;104;95;96 11:57:33 INFO - PID 4470 | |8;json-parse-financial;51;54;51;53;51;59;50;51;51;52 11:57:33 INFO - PID 4470 | |9;json-stringify-tinderbox;46;43;43;44;38;45;37;40;40;37 11:57:33 INFO - PID 4470 | |10;stanford-crypto-aes;74;76;81;75;75;71;77;74;73;73 11:57:33 INFO - PID 4470 | |11;stanford-crypto-ccm;125;117;122;126;119;122;107;115;117;113 11:57:33 INFO - PID 4470 | |12;stanford-crypto-pbkdf2;164;161;160;166;167;170;170;179;165;165 11:57:33 INFO - PID 4470 | |13;stanford-crypto-sha256-iterative;71;70;70;75;64;65;69;72;65;63 11:57:33 INFO - PID 4470 | __end_tp_report 11:57:33 INFO - PID 4470 | __start_cc_report 11:57:33 INFO - PID 4470 | _x_x_mozilla_cycle_collect,732 11:57:33 INFO - PID 4470 | __end_cc_report 11:57:33 INFO - PID 4470 | __startTimestamp1507057053474__endTimestamp 11:57:33 INFO - PID 4470 | 11:57:33 INFO - PID 4470 | ------- Summary: start ------- 11:57:33 INFO - PID 4470 | Number of tests: 14 11:57:33 INFO - PID 4470 | 11:57:33 INFO - PID 4470 | [#0] ai-astar Cycles:10 Average:111.90 Median:112.50 stddev:3.45 (3.1%) stddev-sans-first:3.66 11:57:33 INFO - PID 4470 | Values: 112.0 114.0 107.0 108.0 111.0 109.0 117.0 117.0 111.0 113.0 11:57:33 INFO - PID 4470 | 11:57:33 INFO - PID 4470 | [#1] audio-beat-detection Cycles:10 Average:120.70 Median:122.50 stddev:3.23 (2.6%) stddev-sans-first:3.28 11:57:33 INFO - PID 4470 | Values: 118.0 119.0 124.0 124.0 116.0 122.0 123.0 121.0 116.0 124.0 11:57:33 INFO - PID 4470 | 11:57:33 INFO - PID 4470 | [#2] audio-dft Cycles:10 Average:167.70 Median:168.00 stddev:4.85 (2.9%) stddev-sans-first:4.84 11:57:33 INFO - PID 4470 | Values: 163.0 163.0 167.0 163.0 164.0 168.0 177.0 168.0 174.0 170.0 11:57:33 INFO - PID 4470 | 11:57:33 INFO - PID 4470 | [#3] audio-fft Cycles:10 Average:76.90 Median:78.00 stddev:3.00 (3.8%) stddev-sans-first:2.22 11:57:33 INFO - PID 4470 | Values: 83.0 75.0 74.0 74.0 78.0 75.0 78.0 79.0 74.0 79.0 11:57:33 INFO - PID 4470 | 11:57:33 INFO - PID 4470 | [#4] audio-oscillator Cycles:10 Average:82.00 Median:82.50 stddev:2.58 (3.1%) stddev-sans-first:2.64 11:57:33 INFO - PID 4470 | Values: 80.0 80.0 83.0 82.0 86.0 82.0 86.0 79.0 79.0 83.0 11:57:33 INFO - PID 4470 | 11:57:33 INFO - PID 4470 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.00 stddev:2.55 (2.2%) stddev-sans-first:2.70 11:57:33 INFO - PID 4470 | Values: 114.0 113.0 110.0 114.0 113.0 114.0 115.0 119.0 118.0 114.0 11:57:33 INFO - PID 4470 | 11:57:33 INFO - PID 4470 | [#6] imaging-darkroom Cycles:10 Average:151.10 Median:150.50 stddev:3.41 (2.3%) stddev-sans-first:3.54 11:57:33 INFO - PID 4470 | Values: 149.0 154.0 158.0 148.0 148.0 150.0 149.0 149.0 155.0 151.0 11:57:33 INFO - PID 4470 | 11:57:33 INFO - PID 4470 | [#7] imaging-desaturate Cycles:10 Average:96.60 Median:95.00 stddev:3.31 (3.5%) stddev-sans-first:3.10 11:57:33 INFO - PID 4470 | Values: 101.0 94.0 95.0 97.0 95.0 94.0 95.0 104.0 95.0 96.0 11:57:33 INFO - PID 4470 | 11:57:33 INFO - PID 4470 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:51.50 stddev:2.63 (5.1%) stddev-sans-first:2.74 11:57:33 INFO - PID 4470 | Values: 51.0 54.0 51.0 53.0 51.0 59.0 50.0 51.0 51.0 52.0 11:57:33 INFO - PID 4470 | 11:57:33 INFO - PID 4470 | [#9] json-stringify-tinderbox Cycles:10 Average:41.30 Median:43.00 stddev:3.33 (7.8%) stddev-sans-first:3.07 11:57:33 INFO - PID 4470 | Values: 46.0 43.0 43.0 44.0 38.0 45.0 37.0 40.0 40.0 37.0 11:57:33 INFO - PID 4470 | 11:57:33 INFO - PID 4470 | [#10] stanford-crypto-aes Cycles:10 Average:74.90 Median:75.00 stddev:2.73 (3.6%) stddev-sans-first:2.87 11:57:33 INFO - PID 4470 | Values: 74.0 76.0 81.0 75.0 75.0 71.0 77.0 74.0 73.0 73.0 11:57:33 INFO - PID 4470 | 11:57:33 INFO - PID 4470 | [#11] stanford-crypto-ccm Cycles:10 Average:118.30 Median:120.50 stddev:5.79 (4.8%) stddev-sans-first:5.61 11:57:33 INFO - PID 4470 | Values: 125.0 117.0 122.0 126.0 119.0 122.0 107.0 115.0 117.0 113.0 11:57:33 INFO - PID 4470 | 11:57:33 INFO - PID 4470 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.70 Median:166.50 stddev:5.42 (3.3%) stddev-sans-first:5.66 11:57:33 INFO - PID 4470 | Values: 164.0 161.0 160.0 166.0 167.0 170.0 170.0 179.0 165.0 165.0 11:57:33 INFO - PID 4470 | 11:57:33 INFO - PID 4470 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:70.00 stddev:3.95 (5.6%) stddev-sans-first:4.08 11:57:33 INFO - PID 4470 | Values: 71.0 70.0 70.0 75.0 64.0 65.0 69.0 72.0 65.0 63.0 11:57:33 INFO - PID 4470 | -------- Summary: end -------- 11:57:33 INFO - PID 4470 | 11:57:33 INFO - PID 4470 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:57:34 INFO - TEST-INFO | 4470: exit 0 11:57:34 INFO - TEST-OK | kraken | took 62531ms 11:57:34 INFO - SUITE-END | took 1174s 11:57:34 INFO - Completed test suite (00:19:34) 11:57:34 INFO - javascript benchmark 11:57:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8459.782077050611, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3072.2472994116442, 3117.236277112108, 3135.890243841466, 3139.4035133324664, 3159.194405426616, 6110.541709371792, 6175.0, 6258.374162583745, 6322.494200463963, 6389.201349831271, 6159.846003849906, 6182.5981311214855, 6203.441690247877, 6221.906671399933, 6232.971559692645, 5689.829305120853, 5764.16419619156, 5776.855578610526, 5782.930157920798, 5803.448672376117, 5750.137479378085, 5853.707314634273, 5870.20752198452, 5887.116932460122, 5944.286685597713, 5736.6558006519535, 5837.50381217597, 5876.764929402819, 5886.411358864122, 5897.292324920994, 1281.9142768143597, 5350.652207606532, 5618.747156377973, 5647.548196144298, 5701.344345400291, 5683.147527870811, 5753.050746626802, 5780.653160810322, 5829.562782791308, 5829.679367634779, 6188.566800323979, 6227.813165605039, 6254.437100660963, 6272.0, 6363.204599425072, 6712.697928593225, 6724.4956628252585, 6792.524523283323, 6809.5233333666665, 6826.897596536056, 6706.061151438803, 6736.2253340865445, 6844.965775171142, 6849.760258390932, 6849.863002739967, 6074.179985701918, 6163.2604087509335, 6175.567710260284, 6230.5638605297645, 6313.494920406357, 6863.519553631298, 6877.828054298682, 6883.862322753517, 6906.792796216121, 6919.654017299215, 6616.0, 6616.702248398834, 6642.269350371455, 6644.767433139817, 6648.0, 6654.966725166343, 6681.265060843303, 6683.565568238049, 6777.35615116573, 6797.2523022467485, 6495.382938620824, 6643.60138391698, 6651.833704157338, 6745.190577130776, 6754.662266886636, 6513.511486638521, 6538.803835884931, 6551.934480655228, 6564.901526477107, 6572.671366431659, 4860.6111511079025, 4872.317875497433, 4920.0, 4958.9504104959215, 4960.677555958851, 4227.682923780667, 4272.6154646082, 4291.3777502262, 4348.5216626171095, 4394.450693663292, 5778.682172480554, 5808.854778630568, 5821.592488525847, 5865.88268234633, 5870.119482077723, 6682.799516014527, 6747.831304217434, 6821.829454263584, 6824.863502729918, 6842.04211410403, 5610.102383618601, 5789.0, 5789.8263052108505, 5846.298444186725, 5871.530277577869, 5599.496045355938, 5680.829575112753, 5717.685527295956, 5763.510101641324, 5809.506191973645, 6445.129907462527, 6524.510661700391, 6542.084108224853, 6563.573367731082, 6571.145751052428, 6710.060591517094, 6729.259781424137, 6734.865302693918, 6797.592144471348, 6798.320167983162, 6423.903641445476, 6507.707153178213, 6591.538592298685, 6604.042413850061, 6613.702383392662, 6431.292557818542, 6595.736170553124, 6606.669666516751, 6614.0, 6694.230163531256, 6663.700133493908, 6673.265940746611, 6817.625030623165, 6821.488388370793, 6837.89743153863, 6520.543561950808, 6548.279689234276, 6562.146920900444, 6591.604503729792, 6616.7022483987375, 6476.417122459001, 6512.05575191604, 6541.836454088686, 6581.0, 6596.142501474777, 6216.875662486816, 6438.67806609677, 6447.838804029937, 6525.184351956005, 6535.3464653534265, 6338.668879535349, 6432.6462044588, 6449.0, 6460.741570337133, 6478.093066970534, 6264.68676566179, 6314.0, 6357.841053973502, 6404.519661025535, 6411.679416029273], "value": 6049.388095992385, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.608822544615, 813.9511629302241, 820.4093053001836, 825.4923222218334, 825.896762904637, 38055.23889522208, 38831.41752873709, 40910.79544602276, 41477.0, 41655.0, 38409.231815363746, 38811.22377552447, 38912.80543597285, 39065.80467097668, 39087.80456097716, 7122.501424900259, 7155.642217889111, 7227.34953854153, 7235.855282894339, 7252.0572325597595, 7748.263914928073, 7829.960850195741, 7884.0, 7933.206679332049, 7938.325242354407, 7864.213578642147, 7872.5670088145125, 7942.602869856484, 7951.880721789149, 7952.761417157494, 7833.490823096509, 7882.684692612317, 7890.368770498347, 7898.565578893159, 7912.208779122099, 7758.418118641124, 7824.843503129906, 7833.608319583998, 7862.960685196537, 7886.093099293597, 39606.20787584232, 39740.20519589592, 39795.21433499656, 39906.40140397896, 39980.40029399561, 3595.676389124965, 3630.3646861799343, 3633.7638053526703, 3634.56385233771, 3637.4907512947943, 30940.535891961637, 31122.066338009896, 31205.0, 31339.216519586964, 31386.52920206199, 4939.259111133359, 5132.897342053138, 5133.178691409394, 5167.844964651066, 5280.947190528084, 194576.0, 194868.02565987222, 195346.0, 195740.0, 196023.0, 37694.24611507754, 38398.232035359135, 47266.5273347269, 47412.5258747415, 47769.0, 50861.491385085676, 52806.20790688143, 53152.73423632934, 53568.73215633897, 53819.73090134524, 16292.0, 16315.755263671055, 16514.091724955244, 16524.586885327724, 16529.421470248722, 13278.601641950758, 13301.334933253298, 13368.331583420984, 13417.329133543284, 13419.93290033563, 14416.855831441551, 14421.134731916118, 14480.85519144795, 14624.68377845999, 14717.484888028868, 5802.854928626733, 5857.531397488191, 5893.5579831512805, 5911.0, 6072.937235983685, 994.174834887042, 1007.4610083605236, 1014.974625634365, 1015.6851376073441, 1021.7037059252733, 22840.200592979167, 23120.959556819646, 23265.418364541023, 23290.3012909613, 23385.532289354458, 1100.6918062942389, 1126.4142645824124, 1130.2766229613055, 1131.1120770195357, 1142.2860712054965, 12083.57707480234, 12107.213031152947, 12251.509939602494, 12309.50761969529, 12326.81509777354, 225667.0, 226318.0, 226548.0, 228652.0, 229139.0, 54840.45159548433, 55034.44965550373, 55061.174082387995, 55235.0, 55283.0, 1368.3021658953805, 1456.1044957351364, 1490.1059364381501, 1491.9627009324854, 1499.197929107922, 22561.210357637403, 22815.0, 22906.65640015368, 23162.53674926526, 23460.0, 37702.057448564, 37741.62258377491, 37940.810295948344, 38202.23595528129, 38215.0, 195511.0, 197453.0, 197716.0, 198731.0, 198767.0, 27994.300142496602, 32661.510077348383, 34118.0, 34840.0, 35043.546951713135, 40243.597564025156, 42784.0, 43450.782746086064, 43741.78129109334, 43925.78037109794, 31232.843835781583, 31300.843495782374, 31402.21494462657, 31452.0, 31887.202819929687, 31148.844255778575, 31558.0, 31695.36609267848, 31954.20114497156, 31999.520007200375, 33984.0, 35960.8201958999, 36196.81901590475, 36252.0, 36392.09019774527, 29872.0, 30002.94989675438, 30247.395052098396, 30568.0, 31537.0, 22823.0, 23020.884895575415, 23660.17189398363, 23722.525549488568, 23894.52210955806], "value": 17115.14139044478, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.3245302995495, 583.5657157627364, 589.3929252869546, 589.5283772981614, 591.5770224289633, 21412.57174856504, 22239.44401389964, 22366.0, 22402.43993900153, 22408.887955560218, 21531.784682153175, 21592.35222943314, 21759.8032108234, 24502.632460513105, 24722.876385618092, 6466.579672321296, 6663.4669226461765, 6670.466362690973, 6672.199336079677, 6678.56589321695, 6572.047053177311, 6626.271110177877, 6649.368310010541, 6651.8337041574105, 6651.866962660744, 6396.776112836052, 6604.900926486107, 6609.107770451, 6614.206295244578, 6635.071090047397, 6638.336166383371, 6669.733210671591, 6674.733010679567, 6681.732730690766, 6688.699008544579, 5885.176075349455, 6083.817485475399, 6106.694665266719, 6163.907541386883, 6211.751529938797, 27535.173944781487, 27960.30099247535, 28057.438851223065, 28120.578191327146, 28212.858935705397, 2056.28030189434, 2074.460640233535, 2082.073477302601, 2085.270155445597, 2088.571842772228, 18818.90590547052, 18833.24667013318, 18839.340623078264, 19166.329178478685, 19213.32753353626, 1858.3960212930717, 1897.5351038995395, 1976.6343226503143, 1989.950251243716, 1995.5709522452598, 118176.40911795472, 121910.0, 121986.0, 142893.0, 147179.26410367986, 14353.122771367993, 14579.68782809531, 14842.356186563713, 14888.660020598198, 15009.699806003819, 23353.182638607566, 23456.7654323458, 23520.529589408117, 23640.645390319158, 23647.763522364556, 9938.0, 9988.551660009258, 10044.698659040241, 10266.794664106825, 10362.222833287382, 9284.210842078364, 9308.488033158106, 9413.388129771543, 9550.331476796557, 9582.808343833085, 8828.911710882809, 8853.848999630007, 8987.865182022275, 9085.318601104811, 9143.359964802534, 2645.722199169098, 2660.7472290132405, 2740.1231605885923, 2770.612114303999, 2810.480061188646, 962.9133377996014, 973.0415540692412, 973.7906350134755, 980.9019098090276, 989.980200395988, 18264.908675456536, 18457.53856153607, 18660.906695466434, 18667.253309867454, 18983.620327593646, 1026.6765968719828, 1027.8817935937464, 1036.9429681367596, 1039.2725092435326, 1045.1690905729963, 10055.849162262572, 10479.94760026195, 10498.422586757802, 10597.15222782191, 10709.518071686793, 469312.0, 484267.0, 487544.0, 488958.0, 494392.0, 100255.49872250592, 106622.0, 107559.0, 108757.0, 113581.43209284067, 952.7713348796377, 975.4829940131741, 995.1789773436943, 1141.7545227776234, 1167.7547714979949, 19493.0, 19703.605927881075, 19827.504312392306, 20000.599987999867, 20172.899135504227, 53668.463315366345, 55537.722311388185, 56892.71553642205, 57327.71336143293, 57582.136267955175, 73469.26530734624, 73522.0, 73831.0, 74531.0, 74717.62641186759, 14192.858071419154, 14436.783448248494, 14465.566033019026, 14469.421223150955, 14543.41826326935, 22274.77725222727, 22349.553008940056, 22393.44016399603, 22551.661725074464, 22963.88518057399, 17475.737863932307, 18122.54693632617, 18220.544486387946, 18231.635367292845, 18375.26498940081, 15757.842421575637, 16184.271707773414, 16184.757228641814, 16245.67508649844, 16655.75016374731, 16840.73694472944, 17919.37282195117, 17944.0, 17980.55048623795, 18359.0, 14090.295485225493, 14171.787423188867, 14295.0, 14306.284685765462, 14332.928335358256, 17025.233864475882, 17237.827621724125, 17327.91336043312, 17638.823611763717, 17753.201105950004], "value": 11710.187681314781, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1266.1453518874757, 1350.3383342162344, 1373.629120137563, 1374.29223949666, 1377.0567161494378, 14740.11559306441, 15489.84510154898, 15537.067775933452, 15546.844531554681, 15550.222488875568, 15177.924110379432, 15211.315490802912, 15346.00250983688, 15347.76978345323, 15361.0, 2722.469118521879, 2775.139706690927, 2806.2984253936515, 2813.4935711571907, 2818.168640251131, 2628.316637674209, 2710.864456777163, 2718.3340081680017, 2750.188694335176, 2778.4304217635336, 2687.7446642568925, 2715.1447294102395, 2725.1415804021767, 2728.0451841855293, 2800.6079148919166, 2773.9306517337027, 2817.521021426352, 2838.120182743356, 2841.687414384416, 2845.4309138172343, 2696.3393968477653, 2718.8096833221684, 2737.0420352876336, 2766.14249582628, 2768.584712293152, 15050.096994180385, 15300.0, 15310.157941313224, 15350.539483815503, 15374.846251537454, 8079.95960020202, 8489.066202717697, 8490.10853860342, 8525.104863989254, 8545.487270763711, 5405.162199859028, 5426.186072089179, 5529.170624406331, 5546.889062218733, 5657.0, 1863.7577114974968, 1906.8474522038205, 1915.9329423470112, 1921.788603253641, 1951.2000079967288, 26994.32514187161, 27052.188434347, 27194.728052719416, 27293.044743434082, 27567.862160689067, 6798.456123510107, 6899.344562266577, 6944.583325000516, 7093.92906070943, 7127.10911136108, 17830.375936842407, 17840.46478605644, 17915.73126403105, 17928.282868685106, 17930.641387172185, 7697.65360558765, 7726.845463090706, 7824.530528168328, 7838.686452541949, 7882.684692612346, 7058.0, 7302.0142280791415, 7417.0, 7652.311291983749, 7652.426068044919, 5109.0, 5328.0, 5348.224507446398, 5364.30264065669, 5493.560515158778, 1289.8323217981601, 1509.3434356055152, 1526.2750193658146, 1537.0, 1557.8208506021726, 679.5956405938458, 892.2237653241722, 893.3344658229661, 911.589784596934, 929.6746138851348, 8105.189481051848, 8207.548584827773, 8308.12764659714, 8322.20939010793, 8510.106438823957, 993.9751506212402, 999.550202408919, 1000.9199264058858, 1007.1640538353296, 1022.7136401807505, 5257.027449921777, 5315.04329220744, 5379.408265090837, 5406.999705054499, 5416.972915135478, 19864.404067877986, 22376.776232237797, 22795.88602056979, 22863.0, 22888.0, 20423.897880510503, 21618.351449456542, 21778.673319900216, 22079.33761987143, 22430.214942476934, 11502.309861408175, 11851.70370740721, 11852.644420667393, 11864.644060678194, 11876.643700688994, 8088.272055515032, 8226.62980165882, 8291.0, 8419.747407577783, 8556.486610803371, 18146.274149033892, 18634.97507637093, 18993.525161871064, 19040.61918761645, 19119.61760764749, 18228.544286392946, 18934.43196704101, 19635.920024398794, 19693.70459443081, 20024.49938751543, 10807.891921080689, 11507.252028618113, 11708.88291117112, 11720.355380454157, 11722.296662200295, 13333.133346332457, 13535.323233838466, 13663.931680341535, 13698.0, 13732.0, 10536.473176340998, 10629.309094908805, 10833.458327083457, 11035.117190624891, 11057.778844423228, 11189.832152517556, 11233.887661123284, 11320.886791131983, 11345.0, 11412.885871141183, 9019.909800901907, 9232.630694772133, 9248.398854074463, 9263.212626926786, 9332.580033898355, 7291.744788932362, 7452.956586078062, 7527.209642987407, 7545.886811697938, 7683.846323073619, 7128.643567821692, 7291.38023268007, 7306.232845551141, 7336.559806411632, 7338.889916651361], "value": 6738.911346236217, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.750148676405, 513.2916575126325, 523.1446584833797, 536.8469986053975, 539.8353502181835, 5987.760489580417, 5998.910016349747, 6021.9397806021925, 6089.5432842536875, 6119.694015299234, 5903.704814759256, 5974.611650242731, 5992.16109744635, 6018.578699491038, 6043.939560604404, 4974.303597496344, 5005.0, 5029.698218106925, 5032.245163225509, 5046.571041461461, 5046.671966322196, 5061.822836200734, 5071.56891664209, 5110.182370820669, 5119.923201151985, 4909.607231421478, 5003.724795136266, 5017.297578339036, 5026.748662566875, 5073.162928116874, 5060.84817455477, 5063.0, 5115.667481613683, 5117.744112794346, 5137.51193636604, 4635.165670179366, 4733.9763301183275, 4753.904921901577, 4759.0, 4767.42790865095, 5934.1692163097205, 5975.462208401222, 5984.132300816357, 5986.431289027535, 6014.0, 5855.8536036599, 5880.97059514704, 5886.970565147147, 5891.403166991376, 5892.587518873681, 6351.587146835441, 6363.204599425072, 6426.003969384706, 6444.162258906359, 6485.124508191381, 4827.203511420611, 5164.0, 5227.973860130713, 5239.646626159261, 5247.842564723064, 6475.3200913903365, 6535.967320163416, 6540.247871494744, 6600.471962243009, 6609.711106334266, 5297.5232229099565, 5336.279602253724, 5371.516563509303, 5372.62391632582, 5417.322834645669, 5368.221607866837, 5393.541548968382, 5398.163284690879, 5456.399796022434, 5463.153211252262, 5761.0, 5766.826995190151, 5780.0, 5794.797182098607, 5822.679752613562, 6420.0, 6422.197225346832, 6438.291787903327, 6454.450931776434, 6466.127072845105, 1880.407671583447, 1893.981060189408, 1908.0269062778084, 1910.9044547772555, 1925.653382391155, 1051.4374809476892, 1391.958241252764, 1394.5746547302967, 1429.6425893526618, 1441.6251774538487, 5429.972850135803, 5468.28912241406, 5469.667074602231, 5475.835724928259, 5513.9448605514235, 6280.497560195174, 6300.527460440485, 6325.620462772248, 6395.872082558323, 6446.806595802133, 5471.37079235893, 5501.0, 5523.558115350762, 5537.224788529609, 5557.138643510262, 3410.4031794435873, 3429.4341433663667, 3437.645213029083, 3439.5872495300223, 3440.673136052071, 6116.908246376308, 6201.379862013762, 6237.438630523275, 6260.686965651699, 6265.619064755364, 6595.868082638321, 6646.652446913186, 6665.566738162004, 6720.051185156348, 6754.716603845253, 6262.0, 6316.30520642729, 6325.462335701517, 6328.683565821782, 6342.334054924258, 6327.651979141191, 6351.71417286214, 6510.837229069121, 6514.4788416925585, 6542.607443553402, 6281.403266689657, 6369.20384951881, 6397.776077837253, 6426.164598602152, 6475.838104047437, 6255.749770009149, 6263.530235232284, 6273.71768270438, 6284.151639528788, 6304.275008374096, 5210.426853046238, 5320.600954928474, 5323.1749078892835, 5326.09456392422, 5333.7066461345, 6252.562320637512, 6305.243370795534, 6352.237731472253, 6440.871182576228, 6442.581232219891, 6115.785947491994, 6251.312355640966, 6317.0, 6361.236651601837, 6396.58422202574, 6183.876322473615, 6231.4391704746795, 6264.59280146789, 6275.937240627535, 6301.0, 6128.816135515764, 6152.938470615416, 6154.323024467216, 6158.476529495043, 6180.783672571439, 6276.246850377984, 6327.462165715781, 6339.619622822645, 6354.5869518483, 6379.298277189413, 6001.879962400641, 6057.606255593372, 6073.362296958932, 6079.848003799763, 6133.3866613338305], "value": 5022.93423893492, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [891.189017993626, 896.0591379051993, 901.531203774038, 905.2395987370608, 922.8615707643854, 16958.83041169588, 17876.374326898527, 17891.552711182227, 17920.910395448005, 18004.189811458484, 18444.262229510805, 18617.627647447043, 18688.813111868876, 18823.43529694108, 18897.81102188981, 948.6395169835453, 974.8050389922008, 980.5538479991623, 981.6318880419842, 984.9212063034976, 979.6130528441297, 987.249690235419, 989.4904124375952, 993.8310487217191, 998.8951160128191, 1011.2264117949775, 1041.656253436364, 1041.8697662792151, 1045.8012977534245, 1054.2146101154613, 1040.427764729396, 1042.338115296792, 1048.2138396202847, 1048.2505008918583, 1051.7318083888636, 742.4246209187922, 743.6690672650673, 747.0736287004075, 749.7038669725428, 755.9357454616364, 17186.140692965302, 17496.387626433014, 17557.473275801618, 17575.121243937752, 17620.20709068095, 21857.453563660878, 22161.0, 22258.554828903332, 22623.095076196932, 22817.88591057051, 17190.570235744082, 17648.911755441142, 17709.29162833498, 17710.91144544282, 17874.731879021823, 3685.1339935115225, 3697.8890633281044, 3708.258348330318, 3713.57293911201, 3727.273181729564, 100592.0, 103684.0, 136668.0, 144661.27669361586, 146109.0, 16976.660466790596, 17170.39903603368, 17482.737758933625, 17513.649727005388, 17553.912230438767, 30948.22629434282, 31872.0, 32431.837840810644, 32503.674963250065, 32504.0, 9262.073792620818, 9324.1608255256, 9347.345685802065, 9351.859722104175, 9373.625054997723, 4622.168009758208, 4680.953190468052, 4681.67228294023, 4695.483496815347, 4697.412823397075, 5254.97372513135, 5360.3031605891765, 5373.35519737634, 5383.946160538423, 5410.972945135328, 1075.7095584192225, 1100.4772732951783, 1105.6959336182615, 1124.0445621221895, 1125.3810404277615, 581.520245797219, 593.9851503712442, 614.0574218574467, 620.7237779188308, 623.0560700538688, 11804.822927656092, 12220.3278819664, 12369.93815030919, 12459.252444853339, 12724.745505089846, 643.9581427207311, 645.9709313080923, 651.0592194279221, 654.5908806995627, 665.4210836572116, 5740.54075673945, 5831.825045248649, 5847.708451672689, 5863.413658634187, 5875.148103524965, 121582.0, 126665.36667316605, 131048.0, 133191.0, 134944.32527837297, 108127.0, 108579.45710271556, 109473.0, 111443.0, 112731.0, 92060.53969730108, 92550.0, 92828.0, 93284.0, 96186.0, 10896.61861834832, 10926.781464370828, 11015.83476247873, 11262.09903207725, 11388.373639449579, 43448.78275608602, 43658.56341436545, 43673.78163109164, 45325.77337113425, 45369.0, 56657.0, 56774.43225567691, 57053.0, 57179.0, 57192.428075720374, 15463.768043479131, 15556.222188890737, 15601.453949111727, 15608.609784755472, 15965.0, 18181.0, 18395.08024598738, 18474.353397631017, 18610.44168674942, 18874.33939812155, 15389.538313850602, 15412.922935385252, 15472.613184670474, 15498.690026199187, 15739.763903541227, 14833.184174870054, 14895.702085958437, 14971.475998340005, 15086.321115549606, 15104.622384440478, 14728.779068314198, 14807.963442558917, 14852.777208341668, 14952.551423457313, 15146.924265378602, 11929.164958452826, 12124.33316167584, 12199.756004879675, 12562.937185314015, 12622.495100195889, 11608.941955290507, 11926.821097683714, 12060.0, 12081.939590301992, 12189.939050304693], "value": 8931.538269160828, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1443.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 107.0, 108.0, 111.0, 109.0, 117.0, 117.0, 111.0, 113.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 119.0, 124.0, 124.0, 116.0, 122.0, 123.0, 121.0, 116.0, 124.0], "value": 120.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 167.0, 163.0, 164.0, 168.0, 177.0, 168.0, 174.0, 170.0], "value": 167.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 75.0, 74.0, 74.0, 78.0, 75.0, 78.0, 79.0, 74.0, 79.0], "value": 76.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 83.0, 82.0, 86.0, 82.0, 86.0, 79.0, 79.0, 83.0], "value": 82.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 110.0, 114.0, 113.0, 114.0, 115.0, 119.0, 118.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 154.0, 158.0, 148.0, 148.0, 150.0, 149.0, 149.0, 155.0, 151.0], "value": 151.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 94.0, 95.0, 97.0, 95.0, 94.0, 95.0, 104.0, 95.0, 96.0], "value": 96.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 54.0, 51.0, 53.0, 51.0, 59.0, 50.0, 51.0, 51.0, 52.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 43.0, 43.0, 44.0, 38.0, 45.0, 37.0, 40.0, 40.0, 37.0], "value": 41.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 81.0, 75.0, 75.0, 71.0, 77.0, 74.0, 73.0, 73.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 117.0, 122.0, 126.0, 119.0, 122.0, 107.0, 115.0, 117.0, 113.0], "value": 118.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 160.0, 166.0, 167.0, 170.0, 170.0, 179.0, 165.0, 165.0], "value": 166.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 70.0, 75.0, 64.0, 65.0, 69.0, 72.0, 65.0, 63.0], "value": 68.4, "unit": "score"}]}]} 11:57:34 INFO - Return code: 0 11:57:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:57:34 INFO - # TBPL SUCCESS # 11:57:34 INFO - Running post-action listener: _package_coverage_data 11:57:34 INFO - Running post-action listener: _resource_record_post_action 11:57:34 INFO - [mozharness: 2017-10-03 18:57:34.773327Z] Finished run-tests step (success) 11:57:34 INFO - Running post-run listener: _resource_record_post_run 11:57:34 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 335659008; Read time: 8; Write time: 178348 11:57:34 INFO - TinderboxPrint: CPU usage
13.2% 11:57:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 11:57:34 INFO - TinderboxPrint: I/O write bytes / time
335,659,008 / 178,348 11:57:34 INFO - TinderboxPrint: CPU idle
8,248.1 (86.8%) 11:57:34 INFO - TinderboxPrint: CPU user
1,199.9 (12.6%) 11:57:34 INFO - TinderboxPrint: Swap in / out
0 / 0 11:57:34 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12939264; Read time: 0; Write time: 2440 11:57:34 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:57:34 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 322281472; Read time: 0; Write time: 175880 11:57:35 INFO - Running post-run listener: _upload_blobber_files 11:57:35 INFO - Blob upload gear active. 11:57:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:57:35 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 11:57:35 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:57:35 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:57:35 INFO - (blobuploader) - INFO - Open directory for files ... 11:57:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:57:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:57:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:57:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:57:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:57:37 INFO - (blobuploader) - INFO - Done attempting. 11:57:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:57:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:57:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:57:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:57:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:57:44 INFO - (blobuploader) - INFO - Done attempting. 11:57:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:57:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:57:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:57:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:57:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:57:45 INFO - (blobuploader) - INFO - Done attempting. 11:57:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:57:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:57:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:57:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:57:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:57:46 INFO - (blobuploader) - INFO - Done attempting. 11:57:46 INFO - (blobuploader) - INFO - Iteration through files over. 11:57:46 INFO - Return code: 0 11:57:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:57:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:57:46 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07f50a46ed08ed0130ce0cde8878d65eb252fb1d443b1622aca04c2e7841288d2f8d0fae4119bdd15bdffa8b3f93d78ccf1ec5ba8318dc307e3519eb258dfadf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3b35c45f3a4bacc10319162e5983883f376ca0271991e2bf3dd7cba2c099e8db4ffdd7b83c44b8ad4a292e5d266102b070756f3fc5e84d09234699be02e8d77", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d902c735be6053904e7a53b7eea7740bdd78f34c49804278bb3be0d801da507d5f00048606f2cd03b4a7bb081a61f6e25fcf9abc1b8083b5be2d4e0a3d13fd0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9943bb4104cd6c6c86d568a02a74975b640bc426ed8aeb64f6596431105de24b5aaf3d82055bdbb29bbec104070bd0abc2cc31b5f120085e836b1b1eab556199"} 11:57:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:57:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:57:46 INFO - Contents: 11:57:46 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07f50a46ed08ed0130ce0cde8878d65eb252fb1d443b1622aca04c2e7841288d2f8d0fae4119bdd15bdffa8b3f93d78ccf1ec5ba8318dc307e3519eb258dfadf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3b35c45f3a4bacc10319162e5983883f376ca0271991e2bf3dd7cba2c099e8db4ffdd7b83c44b8ad4a292e5d266102b070756f3fc5e84d09234699be02e8d77", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d902c735be6053904e7a53b7eea7740bdd78f34c49804278bb3be0d801da507d5f00048606f2cd03b4a7bb081a61f6e25fcf9abc1b8083b5be2d4e0a3d13fd0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9943bb4104cd6c6c86d568a02a74975b640bc426ed8aeb64f6596431105de24b5aaf3d82055bdbb29bbec104070bd0abc2cc31b5f120085e836b1b1eab556199"} 11:57:46 INFO - Running post-run listener: copy_logs_to_upload_dir 11:57:46 INFO - Copying logs to upload dir... 11:57:46 INFO - mkdir: /builds/slave/test/build/upload/logs 11:57:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.525064 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-10-03 11:57:46.884135) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 11:57:46.887339) ========= 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=0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07f50a46ed08ed0130ce0cde8878d65eb252fb1d443b1622aca04c2e7841288d2f8d0fae4119bdd15bdffa8b3f93d78ccf1ec5ba8318dc307e3519eb258dfadf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3b35c45f3a4bacc10319162e5983883f376ca0271991e2bf3dd7cba2c099e8db4ffdd7b83c44b8ad4a292e5d266102b070756f3fc5e84d09234699be02e8d77", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d902c735be6053904e7a53b7eea7740bdd78f34c49804278bb3be0d801da507d5f00048606f2cd03b4a7bb081a61f6e25fcf9abc1b8083b5be2d4e0a3d13fd0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9943bb4104cd6c6c86d568a02a74975b640bc426ed8aeb64f6596431105de24b5aaf3d82055bdbb29bbec104070bd0abc2cc31b5f120085e836b1b1eab556199"} build_url:https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011355 build_url: 'https://queue.taskcluster.net/v1/task/IhxenNHPQ7mEyjmdOY6GYQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07f50a46ed08ed0130ce0cde8878d65eb252fb1d443b1622aca04c2e7841288d2f8d0fae4119bdd15bdffa8b3f93d78ccf1ec5ba8318dc307e3519eb258dfadf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3b35c45f3a4bacc10319162e5983883f376ca0271991e2bf3dd7cba2c099e8db4ffdd7b83c44b8ad4a292e5d266102b070756f3fc5e84d09234699be02e8d77", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d902c735be6053904e7a53b7eea7740bdd78f34c49804278bb3be0d801da507d5f00048606f2cd03b4a7bb081a61f6e25fcf9abc1b8083b5be2d4e0a3d13fd0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9943bb4104cd6c6c86d568a02a74975b640bc426ed8aeb64f6596431105de24b5aaf3d82055bdbb29bbec104070bd0abc2cc31b5f120085e836b1b1eab556199"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 11:57:46.919465) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 11:57:46.920087) ========= 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=0e2a256762fed810e2f0f810000001b3-1507055818.703066-320100709 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004607 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 11:57:47.001229) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 11:57:47.001572) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 11:57:47.001899) ========= ========= Total master_lag: 0.21 =========