builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-068 starttime: 1505305170.53 results: success (0) revision: 1888ec2f277f6bb26271b8808e08914a21db9efe ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 05:19:30.533875) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 05:19:30.534241) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 05:19:30.534503) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 05:19:30.610796) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 05:19:30.611031) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-13 05:19:30-- 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% 220M=0s 2017-09-13 05:19:30 (220 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132529 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 05:19:30.761370) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 05:19:30.761648) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023926 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 05:19:30.810546) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 05:19:30.810832) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1888ec2f277f6bb26271b8808e08914a21db9efe --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 1888ec2f277f6bb26271b8808e08914a21db9efe --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=97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-13 05:19:30,861 truncating revision to first 12 chars 2017-09-13 05:19:30,862 Setting DEBUG logging. 2017-09-13 05:19:30,862 attempt 1/10 2017-09-13 05:19:30,862 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1888ec2f277f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-13 05:19:31,227 unpacking tar archive at: mozilla-central-1888ec2f277f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.666426 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 05:19:31.493913) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 05:19:31.494276) ========= script_repo_revision: 1888ec2f277f6bb26271b8808e08914a21db9efe ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 05:19:31.494683) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 05:19:31.494977) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 05:19:31.507814) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-09-13 05:19:31.508123) ========= /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=97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:19:31 INFO - MultiFileLogger online at 20170913 05:19:31 in /builds/slave/test 05:19:31 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 05:19:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:19:31 INFO - {'append_to_log': False, 05:19:31 INFO - 'base_work_dir': '/builds/slave/test', 05:19:31 INFO - 'blob_upload_branch': 'Firefox', 05:19:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:19:31 INFO - 'branch': 'Firefox', 05:19:31 INFO - 'buildbot_json_path': 'buildprops.json', 05:19:31 INFO - 'code_coverage': False, 05:19:31 INFO - 'config_files': ('talos/linux_config.py',), 05:19:31 INFO - 'default_actions': ('clobber', 05:19:31 INFO - 'read-buildbot-config', 05:19:31 INFO - 'download-and-extract', 05:19:31 INFO - 'populate-webroot', 05:19:31 INFO - 'create-virtualenv', 05:19:31 INFO - 'install', 05:19:31 INFO - 'setup-mitmproxy', 05:19:31 INFO - 'run-tests'), 05:19:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:19:31 INFO - 'disable_ccov_upload': False, 05:19:31 INFO - 'disable_stylo': False, 05:19:31 INFO - 'download_minidump_stackwalk': True, 05:19:31 INFO - 'download_symbols': 'ondemand', 05:19:31 INFO - 'enable_stylo': False, 05:19:31 INFO - 'enable_webrender': False, 05:19:31 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 05:19:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:19:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:19:31 INFO - 'gecko_profile': False, 05:19:31 INFO - 'gecko_profile_interval': 0, 05:19:31 INFO - 'installer_path': 'installer.exe', 05:19:31 INFO - 'log_level': 'info', 05:19:31 INFO - 'log_name': 'talos', 05:19:31 INFO - 'log_to_console': True, 05:19:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:19:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:19:31 INFO - 'opt_config_files': (), 05:19:31 INFO - 'pip_index': False, 05:19:31 INFO - 'suite': 'dromaeojs-e10s', 05:19:31 INFO - 'system_bits': '32', 05:19:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:19:31 INFO - 'title': 'talos-linux64-ix-068', 05:19:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:19:31 INFO - 'use_talos_json': True, 05:19:31 INFO - 'verify': 'False', 05:19:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:19:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:19:31 INFO - 'work_dir': 'build'} 05:19:31 INFO - [mozharness: 2017-09-13 12:19:31.705782Z] Running clobber step. 05:19:31 INFO - Running pre-action listener: _resource_record_pre_action 05:19:31 INFO - Running main action method: clobber 05:19:31 INFO - rmtree: /builds/slave/test/build 05:19:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:19:32 INFO - Running post-action listener: _resource_record_post_action 05:19:32 INFO - [mozharness: 2017-09-13 12:19:32.701758Z] Finished clobber step (success) 05:19:32 INFO - [mozharness: 2017-09-13 12:19:32.701855Z] Running read-buildbot-config step. 05:19:32 INFO - Running pre-action listener: _resource_record_pre_action 05:19:32 INFO - Running main action method: read_buildbot_config 05:19:32 INFO - Using buildbot properties: 05:19:32 INFO - { 05:19:32 INFO - "project": "", 05:19:32 INFO - "product": "firefox", 05:19:32 INFO - "who": "archaeopteryx@coole-files.de", 05:19:32 INFO - "installer_path": "public/build/target.tar.bz2", 05:19:32 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 05:19:32 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:19:32 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 05:19:32 INFO - "stage_platform": "linux64-qr", 05:19:32 INFO - "basedir": "/builds/slave/test", 05:19:32 INFO - "buildnumber": 50, 05:19:32 INFO - "platform": "ubuntu64_hw_qr", 05:19:32 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:19:32 INFO - "slavebuilddir": "test", 05:19:32 INFO - "taskId": "F1QaXuiIQz-TZFQ-8NVMiw", 05:19:32 INFO - "branch": "mozilla-central", 05:19:32 INFO - "script_repo_revision": "production", 05:19:32 INFO - "revision": "1888ec2f277f6bb26271b8808e08914a21db9efe", 05:19:32 INFO - "slavename": "talos-linux64-ix-068", 05:19:32 INFO - "repo_path": "mozilla-central" 05:19:32 INFO - } 05:19:32 INFO - Finding installer, test and symbols from parent task. 05:19:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F1QaXuiIQz-TZFQ-8NVMiw'}, attempt #1 05:19:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F1QaXuiIQz-TZFQ-8NVMiw'}, attempt #1 05:19:33 INFO - Task dependencies: DkoRCDt5TmCGOf_xf-Pg9A 05:19:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A'}, attempt #1 05:19:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.tar.bz2 05:19:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.test_packages.json 05:19:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.crashreporter-symbols.zip 05:19:34 INFO - Running post-action listener: _resource_record_post_action 05:19:34 INFO - [mozharness: 2017-09-13 12:19:34.035750Z] Finished read-buildbot-config step (success) 05:19:34 INFO - [mozharness: 2017-09-13 12:19:34.035967Z] Running download-and-extract step. 05:19:34 INFO - Running pre-action listener: _resource_record_pre_action 05:19:34 INFO - Running main action method: download_and_extract 05:19:34 INFO - mkdir: /builds/slave/test/build/tests 05:19:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:34 INFO - trying https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.test_packages.json 05:19:34 INFO - Downloading https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:19:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:19:34 INFO - Downloaded 989 bytes. 05:19:34 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:19:34 INFO - Using the following test package requirements: 05:19:34 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:19:34 INFO - u'common': [u'target.common.tests.zip'], 05:19:34 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:19:34 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:19:34 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:19:34 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:19:34 INFO - u'mozbase': [u'target.common.tests.zip'], 05:19:34 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:19:34 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:19:34 INFO - u'web-platform': [u'target.common.tests.zip', 05:19:34 INFO - u'target.web-platform.tests.tar.gz'], 05:19:34 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:19:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 05:19:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.common.tests.zip 05:19:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:19:34 INFO - Fetch https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.common.tests.zip into memory 05:19:36 INFO - Content-Length response header: 54566962 05:19:36 INFO - Bytes received: 54566962 05:19:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.talos.tests.zip 05:19:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:19:47 INFO - Fetch https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.talos.tests.zip into memory 05:19:48 INFO - Content-Length response header: 14499226 05:19:48 INFO - Bytes received: 14499226 05:19:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:48 INFO - trying https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.tar.bz2 05:19:48 INFO - Downloading https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:19:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:19:50 INFO - Downloaded 61377180 bytes. 05:19:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.tar.bz2 05:19:51 INFO - mkdir: /builds/slave/test/properties 05:19:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:19:51 INFO - Writing to file /builds/slave/test/properties/build_url 05:19:51 INFO - Contents: 05:19:51 INFO - build_url:https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.tar.bz2 05:19:51 INFO - Running post-action listener: _resource_record_post_action 05:19:51 INFO - Running post-action listener: find_tests_for_verification 05:19:51 INFO - Running post-action listener: set_extra_try_arguments 05:19:51 INFO - [mozharness: 2017-09-13 12:19:51.076651Z] Finished download-and-extract step (success) 05:19:51 INFO - [mozharness: 2017-09-13 12:19:51.076752Z] Running populate-webroot step. 05:19:51 INFO - Running pre-action listener: _resource_record_pre_action 05:19:51 INFO - Running main action method: populate_webroot 05:19:51 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:19:51 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:19:51 INFO - 'tests': ['tresize', 05:19:51 INFO - 'tcanvasmark']}, 05:19:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:19:51 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:19:51 INFO - 'tests': ['dromaeo_css', 05:19:51 INFO - 'kraken']}, 05:19:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:19:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:19:51 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:19:51 INFO - 'talos_options': ['--disable-stylo'], 05:19:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:19:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:19:51 INFO - 'tests': ['damp', 'tps']}, 05:19:51 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:19:51 INFO - 'talos_options': ['--disable-stylo'], 05:19:51 INFO - 'tests': ['damp', 'tps']}, 05:19:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:19:51 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:19:51 INFO - 'tests': ['dromaeo_dom']}, 05:19:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:19:51 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:19:51 INFO - 'tests': ['basic_compositor_video', 05:19:51 INFO - 'glvideo']}, 05:19:51 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:19:51 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:19:51 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:19:51 INFO - 'talos_options': ['--disable-stylo'], 05:19:51 INFO - 'tests': ['ts_paint_webext', 05:19:51 INFO - 'tp5o_webext']}, 05:19:51 INFO - 'other-e10s': {'tests': ['a11yr', 05:19:51 INFO - 'ts_paint', 05:19:51 INFO - 'tpaint', 05:19:51 INFO - 'sessionrestore', 05:19:51 INFO - 'sessionrestore_many_windows', 05:19:51 INFO - 'sessionrestore_no_auto_restore', 05:19:51 INFO - 'tabpaint']}, 05:19:51 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:19:51 INFO - 'tests': ['a11yr', 05:19:51 INFO - 'ts_paint', 05:19:51 INFO - 'tpaint', 05:19:51 INFO - 'sessionrestore', 05:19:51 INFO - 'sessionrestore_many_windows', 05:19:51 INFO - 'sessionrestore_no_auto_restore', 05:19:51 INFO - 'tabpaint']}, 05:19:51 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:19:51 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:19:51 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:19:51 INFO - 'tests': ['bloom_basic_singleton']}, 05:19:51 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:19:51 INFO - 'tests': ['bloom_basic']}, 05:19:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:19:51 INFO - 'tsvgr_opacity', 05:19:51 INFO - 'tart', 05:19:51 INFO - 'tscrollx', 05:19:51 INFO - 'tsvg_static']}, 05:19:51 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:19:51 INFO - 'tests': ['tsvgx', 05:19:51 INFO - 'tsvgr_opacity', 05:19:51 INFO - 'tart', 05:19:51 INFO - 'tscrollx', 05:19:51 INFO - 'tsvg_static']}, 05:19:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:19:51 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:19:51 INFO - 'talos_options': ['--disable-stylo'], 05:19:51 INFO - 'tests': ['tp5o']}, 05:19:51 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:19:51 INFO - 'talos_options': ['--mitmproxy', 05:19:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:19:51 INFO - '--firstNonBlankPaint'], 05:19:51 INFO - 'tests': ['tp6_google', 05:19:51 INFO - 'tp6_youtube', 05:19:51 INFO - 'tp6_amazon', 05:19:51 INFO - 'tp6_facebook']}, 05:19:51 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:19:51 INFO - 'talos_options': ['--disable-stylo', 05:19:51 INFO - '--mitmproxy', 05:19:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:19:51 INFO - '--firstNonBlankPaint'], 05:19:51 INFO - 'tests': ['tp6_google', 05:19:51 INFO - 'tp6_youtube', 05:19:51 INFO - 'tp6_amazon', 05:19:51 INFO - 'tp6_facebook']}, 05:19:51 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:19:51 INFO - 'talos_options': ['--stylo-threads=1', 05:19:51 INFO - '--mitmproxy', 05:19:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:19:51 INFO - '--firstNonBlankPaint'], 05:19:51 INFO - 'tests': ['tp6_google', 05:19:51 INFO - 'tp6_youtube', 05:19:51 INFO - 'tp6_amazon', 05:19:51 INFO - 'tp6_facebook']}, 05:19:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:19:51 INFO - 'talos_options': ['--xperf_path', 05:19:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:19:51 INFO - 'tests': ['tp5n']}, 05:19:51 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:19:51 INFO - 'talos_options': ['--disable-stylo', 05:19:51 INFO - '--xperf_path', 05:19:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:19:51 INFO - 'tests': ['tp5n']}}} 05:19:51 INFO - Running post-action listener: _resource_record_post_action 05:19:51 INFO - [mozharness: 2017-09-13 12:19:51.099911Z] Finished populate-webroot step (success) 05:19:51 INFO - [mozharness: 2017-09-13 12:19:51.100002Z] Running create-virtualenv step. 05:19:51 INFO - Running pre-action listener: _resource_record_pre_action 05:19:51 INFO - Running main action method: create_virtualenv 05:19:51 INFO - Creating virtualenv /builds/slave/test/build/venv 05:19:51 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:19:51 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 05:19:51 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:19:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:19:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:19:51 INFO - Installing distribute.............................................................................................................................................................................................done. 05:19:52 INFO - Installing pip...............done. 05:19:52 INFO - Return code: 0 05:19:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:19:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:19:52 INFO - Reading from file tmpfile_stdout 05:19:52 INFO - Output received: 05:19:52 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 05:19:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:19:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2195090>, '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 0x2195d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218ff90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', '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 05:19:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:19:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:19:52 INFO - Using env: {'DISPLAY': ':0', 05:19:52 INFO - 'HOME': '/home/cltbld', 05:19:52 INFO - 'LANG': 'en_US.UTF-8', 05:19:52 INFO - 'LANGUAGE': 'en_US:en', 05:19:52 INFO - 'LOGNAME': 'cltbld', 05:19:52 INFO - 'MAIL': '/var/mail/cltbld', 05:19:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:52 INFO - 'MOZ_NO_REMOTE': '1', 05:19:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:19:52 INFO - 'NO_EM_RESTART': '1', 05:19:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:19:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:52 INFO - 'PWD': '/builds/slave/test', 05:19:52 INFO - 'SHELL': '/bin/bash', 05:19:52 INFO - 'SHLVL': '1', 05:19:52 INFO - 'TERM': 'linux', 05:19:52 INFO - 'TMOUT': '86400', 05:19:52 INFO - 'USER': 'cltbld', 05:19:52 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', 05:19:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:52 INFO - '_': '/tools/buildbot/bin/python'} 05:19:53 INFO - Ignoring indexes: http://pypi.python.org/simple/ 05:19:53 INFO - Downloading/unpacking pip>=1.5 05:19:53 INFO - Running setup.py egg_info for package pip 05:19:53 INFO - warning: no files found matching 'pip/cacert.pem' 05:19:53 INFO - warning: no files found matching '*.html' under directory 'docs' 05:19:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:19:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:19:53 INFO - Installing collected packages: pip 05:19:53 INFO - Found existing installation: pip 1.1 05:19:53 INFO - Uninstalling pip: 05:19:53 INFO - Successfully uninstalled pip 05:19:53 INFO - Running setup.py install for pip 05:19:53 INFO - warning: no files found matching 'pip/cacert.pem' 05:19:53 INFO - warning: no files found matching '*.html' under directory 'docs' 05:19:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:19:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:19:53 INFO - Installing pip script to /builds/slave/test/build/venv/bin 05:19:53 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 05:19:53 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 05:19:53 INFO - Successfully installed pip 05:19:53 INFO - Cleaning up... 05:19:53 INFO - Return code: 0 05:19:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:19:53 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')]} 05:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:53 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')]} 05:19:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:53 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 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2195090>, '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 0x2195d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218ff90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', '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 05:19:53 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 05:19:53 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 05:19:53 INFO - Using env: {'DISPLAY': ':0', 05:19:53 INFO - 'HOME': '/home/cltbld', 05:19:53 INFO - 'LANG': 'en_US.UTF-8', 05:19:53 INFO - 'LANGUAGE': 'en_US:en', 05:19:53 INFO - 'LOGNAME': 'cltbld', 05:19:53 INFO - 'MAIL': '/var/mail/cltbld', 05:19:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:53 INFO - 'MOZ_NO_REMOTE': '1', 05:19:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:19:53 INFO - 'NO_EM_RESTART': '1', 05:19:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:19:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:53 INFO - 'PWD': '/builds/slave/test', 05:19:53 INFO - 'SHELL': '/bin/bash', 05:19:53 INFO - 'SHLVL': '1', 05:19:53 INFO - 'TERM': 'linux', 05:19:53 INFO - 'TMOUT': '86400', 05:19:53 INFO - 'USER': 'cltbld', 05:19:53 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', 05:19:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:53 INFO - '_': '/tools/buildbot/bin/python'} 05:19:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:54 INFO - Downloading/unpacking psutil>=3.1.1 05:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:19:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:19:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:19:56 INFO - Installing collected packages: psutil 05:19:56 INFO - Running setup.py install for psutil 05:19:56 INFO - building 'psutil._psutil_linux' extension 05:19:56 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 05:19:56 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 05:19:56 INFO - building 'psutil._psutil_posix' extension 05:19:56 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 05:19:56 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 05:19:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:19:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:19:57 INFO - Successfully installed psutil 05:19:57 INFO - Cleaning up... 05:19:57 INFO - Return code: 0 05:19:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:19:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:57 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 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2195090>, '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 0x2195d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218ff90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', '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 05:19:57 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 05:19:57 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 05:19:57 INFO - Using env: {'DISPLAY': ':0', 05:19:57 INFO - 'HOME': '/home/cltbld', 05:19:57 INFO - 'LANG': 'en_US.UTF-8', 05:19:57 INFO - 'LANGUAGE': 'en_US:en', 05:19:57 INFO - 'LOGNAME': 'cltbld', 05:19:57 INFO - 'MAIL': '/var/mail/cltbld', 05:19:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:57 INFO - 'MOZ_NO_REMOTE': '1', 05:19:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:19:57 INFO - 'NO_EM_RESTART': '1', 05:19:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:19:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:57 INFO - 'PWD': '/builds/slave/test', 05:19:57 INFO - 'SHELL': '/bin/bash', 05:19:57 INFO - 'SHLVL': '1', 05:19:57 INFO - 'TERM': 'linux', 05:19:57 INFO - 'TMOUT': '86400', 05:19:57 INFO - 'USER': 'cltbld', 05:19:57 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', 05:19:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:57 INFO - '_': '/tools/buildbot/bin/python'} 05:19:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:19:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:19:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:19:59 INFO - Installing collected packages: mozsystemmonitor 05:19:59 INFO - Running setup.py install for mozsystemmonitor 05:19:59 INFO - Successfully installed mozsystemmonitor 05:19:59 INFO - Cleaning up... 05:20:00 INFO - Return code: 0 05:20:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:20:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:00 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 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2195090>, '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 0x2195d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218ff90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', '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 05:20:00 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 05:20:00 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 05:20:00 INFO - Using env: {'DISPLAY': ':0', 05:20:00 INFO - 'HOME': '/home/cltbld', 05:20:00 INFO - 'LANG': 'en_US.UTF-8', 05:20:00 INFO - 'LANGUAGE': 'en_US:en', 05:20:00 INFO - 'LOGNAME': 'cltbld', 05:20:00 INFO - 'MAIL': '/var/mail/cltbld', 05:20:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:00 INFO - 'MOZ_NO_REMOTE': '1', 05:20:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:00 INFO - 'NO_EM_RESTART': '1', 05:20:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:00 INFO - 'PWD': '/builds/slave/test', 05:20:00 INFO - 'SHELL': '/bin/bash', 05:20:00 INFO - 'SHLVL': '1', 05:20:00 INFO - 'TERM': 'linux', 05:20:00 INFO - 'TMOUT': '86400', 05:20:00 INFO - 'USER': 'cltbld', 05:20:00 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', 05:20:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:00 INFO - '_': '/tools/buildbot/bin/python'} 05:20:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:00 INFO - Downloading/unpacking jsonschema==2.5.1 05:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:20:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:20:01 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 05:20:01 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 05:20:01 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 05:20:01 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 05:20:01 INFO - Downloading functools32-3.2.3-2.tar.gz 05:20:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:20:01 INFO - warning: no files found matching '*.txt' 05:20:01 INFO - no previously-included directories found matching 'build' 05:20:01 INFO - no previously-included directories found matching 'dist' 05:20:01 INFO - no previously-included directories found matching '.git*' 05:20:01 INFO - Installing collected packages: jsonschema, functools32 05:20:01 INFO - Running setup.py install for functools32 05:20:01 INFO - warning: no files found matching '*.txt' 05:20:01 INFO - no previously-included directories found matching 'build' 05:20:01 INFO - no previously-included directories found matching 'dist' 05:20:01 INFO - no previously-included directories found matching '.git*' 05:20:01 INFO - Successfully installed jsonschema functools32 05:20:01 INFO - Cleaning up... 05:20:01 INFO - Return code: 0 05:20:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:01 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 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2195090>, '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 0x2195d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218ff90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', '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 05:20:01 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 05:20:01 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 05:20:01 INFO - Using env: {'DISPLAY': ':0', 05:20:01 INFO - 'HOME': '/home/cltbld', 05:20:01 INFO - 'LANG': 'en_US.UTF-8', 05:20:01 INFO - 'LANGUAGE': 'en_US:en', 05:20:01 INFO - 'LOGNAME': 'cltbld', 05:20:01 INFO - 'MAIL': '/var/mail/cltbld', 05:20:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:01 INFO - 'MOZ_NO_REMOTE': '1', 05:20:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:01 INFO - 'NO_EM_RESTART': '1', 05:20:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:01 INFO - 'PWD': '/builds/slave/test', 05:20:01 INFO - 'SHELL': '/bin/bash', 05:20:01 INFO - 'SHLVL': '1', 05:20:01 INFO - 'TERM': 'linux', 05:20:01 INFO - 'TMOUT': '86400', 05:20:01 INFO - 'USER': 'cltbld', 05:20:01 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', 05:20:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:01 INFO - '_': '/tools/buildbot/bin/python'} 05:20:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:20:01 INFO - Cleaning up... 05:20:01 INFO - Return code: 0 05:20:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:01 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 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2195090>, '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 0x2195d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218ff90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', '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 05:20:01 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 05:20:01 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 05:20:01 INFO - Using env: {'DISPLAY': ':0', 05:20:01 INFO - 'HOME': '/home/cltbld', 05:20:01 INFO - 'LANG': 'en_US.UTF-8', 05:20:01 INFO - 'LANGUAGE': 'en_US:en', 05:20:01 INFO - 'LOGNAME': 'cltbld', 05:20:01 INFO - 'MAIL': '/var/mail/cltbld', 05:20:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:01 INFO - 'MOZ_NO_REMOTE': '1', 05:20:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:01 INFO - 'NO_EM_RESTART': '1', 05:20:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:01 INFO - 'PWD': '/builds/slave/test', 05:20:01 INFO - 'SHELL': '/bin/bash', 05:20:01 INFO - 'SHLVL': '1', 05:20:01 INFO - 'TERM': 'linux', 05:20:01 INFO - 'TMOUT': '86400', 05:20:01 INFO - 'USER': 'cltbld', 05:20:01 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', 05:20:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:01 INFO - '_': '/tools/buildbot/bin/python'} 05:20:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:02 INFO - Downloading/unpacking blobuploader==1.2.4 05:20:02 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 05:20:02 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 05:20:02 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 05:20:02 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 05:20:03 INFO - Downloading blobuploader-1.2.4.tar.gz 05:20:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:20:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:20:03 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 05:20:03 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 05:20:03 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 05:20:03 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 05:20:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:20:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:20:03 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 05:20:03 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 05:20:03 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 05:20:03 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 05:20:03 INFO - Downloading docopt-0.6.1.tar.gz 05:20:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:20:03 INFO - Installing collected packages: blobuploader, requests, docopt 05:20:03 INFO - Running setup.py install for blobuploader 05:20:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:20:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:20:03 INFO - Running setup.py install for requests 05:20:04 INFO - Running setup.py install for docopt 05:20:04 INFO - Successfully installed blobuploader requests docopt 05:20:04 INFO - Cleaning up... 05:20:04 INFO - Return code: 0 05:20:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:04 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 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2195090>, '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 0x2195d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218ff90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', '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 05:20:04 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 05:20:04 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 05:20:04 INFO - Using env: {'DISPLAY': ':0', 05:20:04 INFO - 'HOME': '/home/cltbld', 05:20:04 INFO - 'LANG': 'en_US.UTF-8', 05:20:04 INFO - 'LANGUAGE': 'en_US:en', 05:20:04 INFO - 'LOGNAME': 'cltbld', 05:20:04 INFO - 'MAIL': '/var/mail/cltbld', 05:20:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:04 INFO - 'MOZ_NO_REMOTE': '1', 05:20:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:04 INFO - 'NO_EM_RESTART': '1', 05:20:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:04 INFO - 'PWD': '/builds/slave/test', 05:20:04 INFO - 'SHELL': '/bin/bash', 05:20:04 INFO - 'SHLVL': '1', 05:20:04 INFO - 'TERM': 'linux', 05:20:04 INFO - 'TMOUT': '86400', 05:20:04 INFO - 'USER': 'cltbld', 05:20:04 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', 05:20:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:04 INFO - '_': '/tools/buildbot/bin/python'} 05:20:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:04 INFO - Running setup.py (path:/tmp/pip-dO8Ljr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:04 INFO - Running setup.py (path:/tmp/pip-WyETyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:04 INFO - Running setup.py (path:/tmp/pip-9xm_f6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:04 INFO - Running setup.py (path:/tmp/pip-5vMDZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:04 INFO - Running setup.py (path:/tmp/pip-shjGcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:05 INFO - Running setup.py (path:/tmp/pip-_Jz3Fx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:05 INFO - Running setup.py (path:/tmp/pip-SDRl1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:05 INFO - Running setup.py (path:/tmp/pip-dY4W_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:05 INFO - Running setup.py (path:/tmp/pip-sq8gQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:05 INFO - Running setup.py (path:/tmp/pip-2VqVGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:05 INFO - Running setup.py (path:/tmp/pip-XEmnEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:05 INFO - Running setup.py (path:/tmp/pip-Moaxby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:05 INFO - Running setup.py (path:/tmp/pip-DWMNYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:05 INFO - Running setup.py (path:/tmp/pip-Hbq4E0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:05 INFO - Running setup.py (path:/tmp/pip-QJ_aBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:05 INFO - Running setup.py (path:/tmp/pip-HQSdez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:05 INFO - Running setup.py (path:/tmp/pip-35eKam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:20:05 INFO - Running setup.py install for manifestparser 05:20:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:20:06 INFO - Running setup.py install for mozcrash 05:20:06 INFO - Running setup.py install for mozdebug 05:20:06 INFO - Running setup.py install for mozdevice 05:20:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:20:06 INFO - Running setup.py install for mozfile 05:20:06 INFO - Running setup.py install for mozhttpd 05:20:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:20:06 INFO - Running setup.py install for mozinfo 05:20:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:20:06 INFO - Running setup.py install for mozInstall 05:20:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:20:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:20:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:20:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:20:06 INFO - Running setup.py install for mozleak 05:20:06 INFO - Running setup.py install for mozlog 05:20:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:20:06 INFO - Running setup.py install for moznetwork 05:20:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:20:07 INFO - Running setup.py install for mozprocess 05:20:07 INFO - Running setup.py install for mozprofile 05:20:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:20:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:20:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:20:07 INFO - Running setup.py install for mozrunner 05:20:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:20:07 INFO - Running setup.py install for mozscreenshot 05:20:07 INFO - Running setup.py install for moztest 05:20:07 INFO - Running setup.py install for mozversion 05:20:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:20:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:20:07 INFO - Cleaning up... 05:20:07 INFO - Return code: 0 05:20:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:07 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 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2195090>, '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 0x2195d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218ff90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', '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 05:20:07 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 05:20:07 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 05:20:07 INFO - Using env: {'DISPLAY': ':0', 05:20:07 INFO - 'HOME': '/home/cltbld', 05:20:07 INFO - 'LANG': 'en_US.UTF-8', 05:20:07 INFO - 'LANGUAGE': 'en_US:en', 05:20:07 INFO - 'LOGNAME': 'cltbld', 05:20:07 INFO - 'MAIL': '/var/mail/cltbld', 05:20:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:07 INFO - 'MOZ_NO_REMOTE': '1', 05:20:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:07 INFO - 'NO_EM_RESTART': '1', 05:20:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:07 INFO - 'PWD': '/builds/slave/test', 05:20:07 INFO - 'SHELL': '/bin/bash', 05:20:07 INFO - 'SHLVL': '1', 05:20:07 INFO - 'TERM': 'linux', 05:20:07 INFO - 'TMOUT': '86400', 05:20:07 INFO - 'USER': 'cltbld', 05:20:07 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', 05:20:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:07 INFO - '_': '/tools/buildbot/bin/python'} 05:20:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:07 INFO - Running setup.py (path:/tmp/pip-cmHIh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:08 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)) 05:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:08 INFO - Running setup.py (path:/tmp/pip-3ICHV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:08 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)) 05:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:08 INFO - Running setup.py (path:/tmp/pip-JTfor7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:08 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)) 05:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:08 INFO - Running setup.py (path:/tmp/pip-3H79Dr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:08 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)) 05:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:08 INFO - Running setup.py (path:/tmp/pip-C8dCaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:08 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)) 05:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:08 INFO - Running setup.py (path:/tmp/pip-TgqE9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:08 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)) 05:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:08 INFO - Running setup.py (path:/tmp/pip-SArXoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:08 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)) 05:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:08 INFO - Running setup.py (path:/tmp/pip-rIoPa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:08 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)) 05:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:08 INFO - Running setup.py (path:/tmp/pip-Y8tcxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:08 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)) 05:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:08 INFO - Running setup.py (path:/tmp/pip-U3Dtmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:08 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)) 05:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:08 INFO - Running setup.py (path:/tmp/pip-FtWQ2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:08 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)) 05:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:08 INFO - Running setup.py (path:/tmp/pip-bRTVbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:08 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)) 05:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:08 INFO - Running setup.py (path:/tmp/pip-9xRaTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:08 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)) 05:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:08 INFO - Running setup.py (path:/tmp/pip-8FkztP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:08 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)) 05:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:08 INFO - Running setup.py (path:/tmp/pip-W1yRHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:09 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)) 05:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:09 INFO - Running setup.py (path:/tmp/pip-9NWhQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:09 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)) 05:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:09 INFO - Running setup.py (path:/tmp/pip-BfFdIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:09 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)) 05:20:09 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)) 05:20:09 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)) 05:20:09 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)) 05:20:09 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)) 05:20:09 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)) 05:20:09 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)) 05:20:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:20:09 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 05:20:09 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 05:20:09 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 05:20:09 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 05:20:10 INFO - Downloading blessings-1.6.tar.gz 05:20:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:20:10 INFO - Installing collected packages: blessings 05:20:10 INFO - Running setup.py install for blessings 05:20:10 INFO - Successfully installed blessings 05:20:10 INFO - Cleaning up... 05:20:10 INFO - Return code: 0 05:20:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:20:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:10 INFO - Reading from file tmpfile_stdout 05:20:10 INFO - Current package versions: 05:20:10 INFO - argparse == 1.2.1 05:20:10 INFO - blessings == 1.6 05:20:10 INFO - blobuploader == 1.2.4 05:20:10 INFO - docopt == 0.6.1 05:20:10 INFO - functools32 == 3.2.3-2 05:20:10 INFO - jsonschema == 2.5.1 05:20:10 INFO - manifestparser == 1.1 05:20:10 INFO - mozInstall == 1.13 05:20:10 INFO - mozcrash == 1.0 05:20:10 INFO - mozdebug == 0.1 05:20:10 INFO - mozdevice == 0.51 05:20:10 INFO - mozfile == 1.2 05:20:10 INFO - mozhttpd == 0.7 05:20:10 INFO - mozinfo == 0.10 05:20:10 INFO - mozleak == 0.1 05:20:10 INFO - mozlog == 3.5 05:20:10 INFO - moznetwork == 0.27 05:20:10 INFO - mozprocess == 0.25 05:20:10 INFO - mozprofile == 0.28 05:20:10 INFO - mozrunner == 6.13 05:20:10 INFO - mozscreenshot == 0.1 05:20:10 INFO - mozsystemmonitor == 0.3 05:20:10 INFO - moztest == 0.8 05:20:10 INFO - mozversion == 1.4 05:20:10 INFO - psutil == 3.1.1 05:20:10 INFO - requests == 1.2.3 05:20:10 INFO - wsgiref == 0.1.2 05:20:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:10 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 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2195090>, '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 0x2195d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218ff90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', '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 05:20:10 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 05:20:10 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 05:20:10 INFO - Using env: {'DISPLAY': ':0', 05:20:10 INFO - 'HOME': '/home/cltbld', 05:20:10 INFO - 'LANG': 'en_US.UTF-8', 05:20:10 INFO - 'LANGUAGE': 'en_US:en', 05:20:10 INFO - 'LOGNAME': 'cltbld', 05:20:10 INFO - 'MAIL': '/var/mail/cltbld', 05:20:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:10 INFO - 'MOZ_NO_REMOTE': '1', 05:20:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:10 INFO - 'NO_EM_RESTART': '1', 05:20:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:10 INFO - 'PWD': '/builds/slave/test', 05:20:10 INFO - 'SHELL': '/bin/bash', 05:20:10 INFO - 'SHLVL': '1', 05:20:10 INFO - 'TERM': 'linux', 05:20:10 INFO - 'TMOUT': '86400', 05:20:10 INFO - 'USER': 'cltbld', 05:20:10 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', 05:20:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:10 INFO - '_': '/tools/buildbot/bin/python'} 05:20:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:10 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)) 05:20:10 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)) 05:20:10 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)) 05:20:10 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)) 05:20:10 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)) 05:20:10 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)) 05:20:10 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)) 05:20:10 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)) 05:20:10 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)) 05:20:10 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:20:10 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 05:20:10 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 05:20:10 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 05:20:10 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 05:20:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:20:12 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)) 05:20:12 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)) 05:20:12 INFO - Installing collected packages: simplejson 05:20:12 INFO - Running setup.py install for simplejson 05:20:12 INFO - building 'simplejson._speedups' extension 05:20:12 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 05:20:13 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 05:20:13 INFO - Successfully installed simplejson 05:20:13 INFO - Cleaning up... 05:20:13 INFO - Return code: 0 05:20:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:20:13 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')]} 05:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:13 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')]} 05:20:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:13 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 0x1e251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2191a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2195090>, '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 0x2195d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218ff90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', '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 05:20:13 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 05:20:13 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 05:20:13 INFO - Using env: {'DISPLAY': ':0', 05:20:13 INFO - 'HOME': '/home/cltbld', 05:20:13 INFO - 'LANG': 'en_US.UTF-8', 05:20:13 INFO - 'LANGUAGE': 'en_US:en', 05:20:13 INFO - 'LOGNAME': 'cltbld', 05:20:13 INFO - 'MAIL': '/var/mail/cltbld', 05:20:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:13 INFO - 'MOZ_NO_REMOTE': '1', 05:20:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:13 INFO - 'NO_EM_RESTART': '1', 05:20:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:13 INFO - 'PWD': '/builds/slave/test', 05:20:13 INFO - 'SHELL': '/bin/bash', 05:20:13 INFO - 'SHLVL': '1', 05:20:13 INFO - 'TERM': 'linux', 05:20:13 INFO - 'TMOUT': '86400', 05:20:13 INFO - 'USER': 'cltbld', 05:20:13 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', 05:20:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:13 INFO - '_': '/tools/buildbot/bin/python'} 05:20:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:20:13 INFO - Cleaning up... 05:20:13 INFO - Return code: 0 05:20:13 INFO - Running post-action listener: _resource_record_post_action 05:20:13 INFO - Running post-action listener: _start_resource_monitoring 05:20:13 INFO - Starting resource monitoring. 05:20:13 INFO - [mozharness: 2017-09-13 12:20:13.414534Z] Finished create-virtualenv step (success) 05:20:13 INFO - [mozharness: 2017-09-13 12:20:13.414996Z] Running install step. 05:20:13 INFO - Running pre-action listener: _resource_record_pre_action 05:20:13 INFO - Running main action method: install 05:20:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:13 INFO - Reading from file tmpfile_stdout 05:20:13 INFO - Detecting whether we're running mozinstall >=1.0... 05:20:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:20:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:20:13 INFO - Reading from file tmpfile_stdout 05:20:13 INFO - Output received: 05:20:13 INFO - Usage: mozinstall [options] installer 05:20:13 INFO - Options: 05:20:13 INFO - -h, --help show this help message and exit 05:20:13 INFO - -d DEST, --destination=DEST 05:20:13 INFO - Directory to install application into. [default: 05:20:13 INFO - "/builds/slave/test"] 05:20:13 INFO - --app=APP Application being installed. [default: firefox] 05:20:13 INFO - mkdir: /builds/slave/test/build/application 05:20:13 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'] 05:20:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:20:31 INFO - Reading from file tmpfile_stdout 05:20:31 INFO - Output received: 05:20:31 INFO - /builds/slave/test/build/application/firefox/firefox 05:20:31 INFO - Running post-action listener: _resource_record_post_action 05:20:31 INFO - [mozharness: 2017-09-13 12:20:31.645154Z] Finished install step (success) 05:20:31 INFO - [mozharness: 2017-09-13 12:20:31.645411Z] Running setup-mitmproxy step. 05:20:31 INFO - Running pre-action listener: _resource_record_pre_action 05:20:31 INFO - Running main action method: setup_mitmproxy 05:20:31 INFO - Skipping: mitmproxy is not required 05:20:31 INFO - Running post-action listener: _resource_record_post_action 05:20:31 INFO - [mozharness: 2017-09-13 12:20:31.646537Z] Finished setup-mitmproxy step (success) 05:20:31 INFO - [mozharness: 2017-09-13 12:20:31.646755Z] Running run-tests step. 05:20:31 INFO - Running pre-action listener: _resource_record_pre_action 05:20:31 INFO - Running pre-action listener: _set_gcov_prefix 05:20:31 INFO - Running main action method: run_tests 05:20:31 WARNING - Try message not found. 05:20:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:20:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:20:31 INFO - Python 2.7.3 05:20:31 INFO - Return code: 0 05:20:31 INFO - grabbing minidump binary from tooltool 05:20:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:31 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 0x2195090>, '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 0x2195d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218ff90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:20:31 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 05:20:31 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 05:20:31 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 05:20:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:20:31 INFO - Return code: 0 05:20:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:20:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:20:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:20:31 INFO - ENV: RUST_BACKTRACE is now full 05:20:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:20:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:20:31 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/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 05:20:31 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/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-068 --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 05:20:31 INFO - Using env: {'DISPLAY': ':0', 05:20:31 INFO - 'HOME': '/home/cltbld', 05:20:31 INFO - 'LANG': 'en_US.UTF-8', 05:20:31 INFO - 'LANGUAGE': 'en_US:en', 05:20:31 INFO - 'LOGNAME': 'cltbld', 05:20:31 INFO - 'MAIL': '/var/mail/cltbld', 05:20:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:20:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:20:31 INFO - 'MOZ_ACCELERATED': '1', 05:20:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:31 INFO - 'MOZ_NO_REMOTE': '1', 05:20:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:20:31 INFO - 'MOZ_WEBRENDER': '1', 05:20:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:31 INFO - 'NO_EM_RESTART': '1', 05:20:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:31 INFO - 'PWD': '/builds/slave/test', 05:20:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:20:31 INFO - 'RUST_BACKTRACE': 'full', 05:20:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:20:31 INFO - 'SHELL': '/bin/bash', 05:20:31 INFO - 'SHLVL': '1', 05:20:31 INFO - 'TERM': 'linux', 05:20:31 INFO - 'TMOUT': '86400', 05:20:31 INFO - 'USER': 'cltbld', 05:20:31 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', 05:20:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:31 INFO - '_': '/tools/buildbot/bin/python'} 05:20:31 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/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 05:20:32 INFO - mozversion application_buildid: 20170913113324 05:20:32 INFO - mozversion application_changeset: 1888ec2f277f6bb26271b8808e08914a21db9efe 05:20:32 INFO - mozversion application_display_name: Nightly 05:20:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:20:32 INFO - mozversion application_name: Firefox 05:20:32 INFO - mozversion application_remotingname: firefox 05:20:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:20:32 INFO - mozversion application_vendor: Mozilla 05:20:32 INFO - mozversion application_version: 57.0a1 05:20:32 INFO - mozversion platform_buildid: 20170913113324 05:20:32 INFO - mozversion platform_changeset: 1888ec2f277f6bb26271b8808e08914a21db9efe 05:20:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:20:32 INFO - mozversion platform_version: 57.0a1 05:20:32 INFO - using testdate: 1505305232 05:20:32 INFO - actual date: 1505305232 05:20:32 INFO - starting webserver on 'localhost:48108' 05:20:32 INFO - SUITE-START | Running 2 tests 05:20:32 INFO - TEST-START | dromaeo_css 05:20:32 INFO - Initialising browser for dromaeo_css test... 05:20:32 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48108/getInfo.html -profile /tmp/tmpOTuA5T/profile 05:20:32 INFO - TEST-INFO | started process 24789 (/builds/slave/test/build/application/firefox/firefox http://localhost:48108/getInfo.html) 05:20:39 INFO - TEST-INFO | 24789: exit 0 05:20:39 INFO - Browser initialized. 05:20:39 INFO - Running cycle 1/1 for dromaeo_css test... 05:20:39 INFO - Using env: {'DISPLAY': ':0', 05:20:39 INFO - 'HOME': '/home/cltbld', 05:20:39 INFO - 'JSGC_DISABLE_POISONING': '1', 05:20:39 INFO - 'LANG': 'en_US.UTF-8', 05:20:39 INFO - 'LANGUAGE': 'en_US:en', 05:20:39 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 05:20:39 INFO - 'LOGNAME': 'cltbld', 05:20:39 INFO - 'MAIL': '/var/mail/cltbld', 05:20:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:20:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:20:39 INFO - 'MOZ_ACCELERATED': '1', 05:20:39 INFO - 'MOZ_CRASHREPORTER': '1', 05:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:39 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 05:20:39 INFO - 'MOZ_NO_REMOTE': '1', 05:20:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:20:39 INFO - 'MOZ_WEBRENDER': '1', 05:20:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:39 INFO - 'NO_EM_RESTART': '1', 05:20:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:39 INFO - 'PWD': '/builds/slave/test', 05:20:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:20:39 INFO - 'RUST_BACKTRACE': 'full', 05:20:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:20:39 INFO - 'SHELL': '/bin/bash', 05:20:39 INFO - 'SHLVL': '1', 05:20:39 INFO - 'TERM': 'linux', 05:20:39 INFO - 'TMOUT': '86400', 05:20:39 INFO - 'USER': 'cltbld', 05:20:39 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', 05:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:39 INFO - '_': '/tools/buildbot/bin/python'} 05:20:39 INFO - TEST-INFO | started process 24986 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOTuA5T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:20:40 INFO - PID 24986 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 05:20:40 INFO - PID 24986 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 05:20:40 INFO - PID 24986 | 05:20:40 INFO - PID 24986 | (/builds/slave/test/build/application/firefox/firefox:25045): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:20:40 INFO - PID 24986 | 05:20:51 INFO - PID 24986 | RSS: Main: 219983872 05:20:51 INFO - PID 24986 | 05:23:49 INFO - PID 24986 | Cycle 1(1): loaded http://localhost:48108/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48108/tests/dromaeo/cssquery-ext.html) 05:23:50 INFO - PID 24986 | RSS: Main: 226267136 05:23:50 INFO - PID 24986 | 05:26:52 INFO - PID 24986 | Cycle 1(1): loaded http://localhost:48108/tests/dromaeo/cssquery-ext.html (next: http://localhost:48108/tests/dromaeo/cssquery-jquery.html) 05:26:53 INFO - PID 24986 | RSS: Main: 225705984 05:26:53 INFO - PID 24986 | 05:29:55 INFO - PID 24986 | Cycle 1(1): loaded http://localhost:48108/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48108/tests/dromaeo/cssquery-mootools.html) 05:29:56 INFO - PID 24986 | RSS: Main: 230363136 05:29:56 INFO - PID 24986 | 05:32:58 INFO - PID 24986 | Cycle 1(1): loaded http://localhost:48108/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48108/tests/dromaeo/cssquery-prototype.html) 05:32:58 INFO - PID 24986 | RSS: Main: 225173504 05:32:58 INFO - PID 24986 | 05:36:02 INFO - PID 24986 | Cycle 1(1): loaded http://localhost:48108/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48108/tests/dromaeo/cssquery-yui.html) 05:36:02 INFO - PID 24986 | RSS: Main: 230150144 05:36:02 INFO - PID 24986 | 05:39:04 INFO - PID 24986 | Cycle 1(1): loaded http://localhost:48108/tests/dromaeo/cssquery-yui.html (next: http://localhost:48108/tests/dromaeo/cssquery-dojo.html) 05:39:05 INFO - PID 24986 | RSS: Main: 230883328 05:39:05 INFO - PID 24986 | 05:39:05 INFO - PID 24986 | __start_tp_report 05:39:05 INFO - PID 24986 | _x_x_mozilla_page_load 05:39:05 INFO - PID 24986 | _x_x_mozilla_page_load_details 05:39:05 INFO - PID 24986 | |i|pagename|runs| 05:39:05 INFO - PID 24986 | |0;dojo.html;3019.109362737992;3064.4943584308585;3100.767442441816;3129.906102816915;3134.5141503067002;6115.633062016271;6329.525285603575;6360.204974378203;6396.136521569587;6415.06981487684;6228.595141315823;6274.686265686709;6465.70904309306;6505.57713748606;6511.3814187652215;5781.855453613673;5877.3241077276125;5946.821595352147;5960.463558279754;6011.939880601203;5835.737391817378;5930.940690593104;5983;5989.760409583611;6006.609570377933;5803.883922321572;5854.648721076728;5860.120981852713;5918.733656985446;5947.107933809942;5616.466435688603;5734.39788822176;5804.042333015047;5812.622179558315;5885.5585831062845;5753.568482363839;5796.594238403313;5803.65178089316;5815.73829177688;5902.055671092605;6301.968490157566;6322.968385158091;6379.234491861006;6404.359564043559;6439.323870993525;6778.22050464198;6778.423833974118;6810.387065164159;6868.278830722752;6919.273476285019;6618.238902526223;6740.966295168541;6779.28817474163;6838.7264509419565;6859.176898772179;6277.937220627827;6343.333949935237;6366.204224471941;6408.67956602168;6421.711023003976;6823.1812182538415;6872.896906546406;6874.072000279998;6874.9656251718425;6936.861262774716;6613.140291762136;6647.86704265912;6781.966090169518;6786.117804685458;6831.4534837212905;6778;6797.762078327235;6855.760048398282;6873.621950792654;6875.449964002868;6735.326467353323;6745.763898263635;6770.052192693027;6840.931590684029;6844.1444819397575;6559.6064236145985;6698.5980841149685;6699.899501507482;6703.229128650268;6710.865782684319;5139.331886854759;5285.1543753000105;5301.893962120736;5303.893922121536;5310.814121505806;4707.78814953321;4723.173444647242;4724.291356296583;4737.763111844463;4760.714357138583;5834.795782147605;5835.912461313083;5887.4406931341455;5901.645901245853;5914.852128696817;6656.800295991128;6810.931890681129;6827.419669328163;6889.173299204128;6903.792886213321;5637.9718101409235;5641.915371269434;5649.971750141223;5657.632253903483;5664.26364572603;5637.3235211774845;5672.064109421899;5685.60200785941;5698.059820129715;5701.372848986608;6535.5425120241125;6640.369164929324;6674.199096108498;6674.199096108498;6678.599284042973;6845.828854278683;6849.075374824335;6930.896036559456;6938.27148149437;6946.79159625212;6433.420992110545;6597.175353080865;6599.109120268702;6670.299618539983;6694.564853284521;6685.699143538455;6718.7312507499155;6788.626625535643;6804.76183333581;6818.8636227276165;6887.173539175331;6928.757493487704;6930.306969303231;6976.162860456778;6982.057422248135;6612.537122401386;6646.933530664632;6712.362325579063;6720.126383570105;6721.731130754715;6399.392057754692;6520.250171230369;6625;6625.635590042594;6731.865362692621;6485.156929599122;6595.934040659532;6602.240742314695;6623.072769812134;6627.834304142435;6599.5050371223315;6611.371919667624;6665.266820649822;6762.628055456997;6774.051632771515;6437.774677886251;6492.188476440445;6512.934870651233;6528.118703974903;6531.216254049545 05:39:05 INFO - PID 24986 | |1;ext.html;847.7202523167354;873.6199753107398;883.5802993578047;884.7168905950099;895.0557162193887;40969.18061638769;42358.788206059005;42733.57266427335;42752.786236068816;42784;38740.225195496074;38952.61047389532;39105.60894391055;39128;39152.216955660944;6989.881618940958;7099;7102.786916392516;7115.5730656160795;7146.606936618484;7049.647517624124;7102.218755936869;7152.249013853549;7154.642267886611;7156.284371562854;7091.042709234264;7113.85772284554;7134.46491513136;7758.806029849243;7862.528248305091;7821.843563128734;7825.256600622989;7831.295183433518;7860.0960889497865;7880.4089693272945;7704.46068775186;7805.219478052206;7806.453548251625;7817.726379576688;7821.960890195513;38776.80611596952;39651.801740991395;39692;39708.60291397078;39729.20541589181;3562.1806984393475;3607.1703508193264;3610.675039246454;3616.150204701893;3635.909102272464;31618.841905790323;32010.519842202153;32069.358612827848;32074;32119.03642890717;5083.669561478492;5122.436531981479;5122.641415100945;5129.717865517395;5141.38303403594;177351;177934;177986;178970;180147.09926450285;46911.5308846914;47126.29310560344;47401.76299118482;47413.525864740914;47464.5253547467;49236.015279694206;49321.753391232814;49556.25665615019;49743;49784.751076244385;15588;15669.843301567067;15872.365105395655;15928.601784955468;16112.355505779638;13029.413676384585;13222.933885330513;13249.536266230636;13269.402876870565;13308.93345533266;14503.347349369304;14542.200178990046;14547.418103275962;14702.26488675562;14795.482158124414;5707.258056452718;5860.589758716934;5868.178455016301;5892.587518873637;5940.970295148583;1010.3230835340339;1023.6314926626409;1033.9844902326472;1041.2555023158477;1044.8380501022355;22913.312600622008;22938.65592016121;22961.425964351023;23158.53682926332;23469.647955280685;1129.7062763681502;1135.28477059452;1140.3556990300453;1141.8401423800674;1147.5008371358485;11948.342841143647;12059.57791477294;12204.267743935392;12291;12303.939699899012;214425;214612;215516;215552;215978;51736.74131629318;52099.47900520946;52122.73938630283;52183;52184;1287.7295767888847;1311.3377744239099;1451.4411951398577;1475.1960181701;1488.8362280149174;26903.730962690122;27058.864705676344;27782;27860.44279114447;28206.85896570504;36031.63968360283;36192;36265.27469450543;36552.63447365492;36683.63316366802;176389.1180544089;176487;178267;178644;178959;32959.176020599676;34064.31871362608;34108.10081143031;34503.4824477628;34555.30889382248;42523.574764251964;42537.78731106325;42891.57108428876;43158.05788739451;43547.56452435435;30781.538276926312;31023.68976310208;31095;31210.375792484476;31545.8422707885;31121.377572448877;31156;31218;31501.8424907874;31687;34946;35558.28883422265;35787.64212357947;35927.46108808422;36053.45919811152;30241.697583023888;30429.391412172074;30586.694133059274;30712.84643576768;30881.382372351978;22401.887990559942;22889.542209156058;23208.76791232066;23226.419339516648;23564.882175589013 05:39:05 INFO - PID 24986 | |2;jquery.html;544.9182622606611;554.3237250554322;557.684908026965;572.7680289482759;581.5928849805136;22614.660780088292;22688.886555567242;23207.535849283006;23288.88355558222;23314.4171395715;24109.915053822577;24385.63421548678;24456.26631201067;24761;24891.62662560063;6487.675616219194;6591.110200122994;6667.133272674546;6669.0996715443525;6672.332766723337;6549.312322206171;6604.141461609984;6627.966860165717;6633.4693224541925;6636.469082473391;6238.376162383771;6486.967565162167;6491.642959637218;6504.609723416586;6560.573562718408;6399.168108145957;6440.420362167404;6491.350864913518;6492.7402903883785;6495.415412612841;5807.390224026459;5941.494972927307;5952.404759524056;6014.097885317194;6017.699115044274;28365.290867728265;28607.713922860712;28612.284692882844;28621;29028.27429314284;2010.5978804239064;2037.6128535578341;2041.1631231195288;2044.948876278105;2046.2019812273224;18647.53381165468;18982;19307.80692193074;19370;19463.416097517096;2446.2294377271282;2488.776010159076;2520.206135067466;2525.368657835541;2526.431552900601;119496;123968;124385;136954.6304536952;137697;14274.643133921734;14338.928305358406;14488.179657372897;14585.854141458662;14662.575156550201;21984.780152198593;22087.66868496974;22194.334169974605;22247.221347253093;22372.328830134797;9954;10160.898391015995;10270.845937310947;10291.125254353314;10309.793804124025;9646.855297170549;9664.516774161404;9666.613335466644;9742.512874356255;9803.803923921625;8706.259967902799;8769.824603507894;8857.778555536035;8930.955345223232;8968.417052891542;2451.656768052474;2475.344033831036;2542.084849454195;2547.312225699051;2641.6433781439646;950.9476978766235;951.695457453622;966.970990870275;969.1471505075489;969.9854502182473;18203.362882299058;18264;18425.447236582924;18576.25694972186;18580.721289180674;1007.1841808135433;1017.2014968250036;1029.53156313877;1031.8761748590066;1034.8085604163255;10232.846507302396;10247.846282305622;10348.32735872181;10357.585696572203;10390.324628899098;411745;433379;439692;442943;443471.5377401828;99907.50046249868;105873.47063264635;108783;110358;111284;1166.2128063557066;1171.2386948483554;1183.195427109591;1186.5135294529202;1186.9228500147462;16166.515004549883;16231.94492357993;16602;16807.41174058902;16852.15739213059;53799.73100134474;54048;54821;54908.450915490335;54978.45021549893;67407;67505;67892.66053669898;68424.3157568418;68437.31562684508;13510.932445338103;13642.2496762679;13864.722705546033;13924.651883702583;13966.720665586834;20422.795772042682;21027;21698;21794.128234870426;21897.671534927307;17208.569785755455;17252.223649936033;17254.396096136574;17471.38850140239;18079.728804068214;14700.632484187981;15130.848691512943;16024.599385015468;16163.43427980062;16682.416115435903;17722.822771772117;17760.822391775917;17789.911050444665;17891.821081789014;18071.638567228845;13642.181469111885;13672.794908076587;13688.452461901412;13700.65748356299;13701.520446784314;17612.559686007953;17659.735103973708;17771;17790.110494474964;18020.729689054413 05:39:05 INFO - PID 24986 | |3;mootools.html;1339.3236415610118;1390.888728901688;1403.7192561487698;1413.392241336225;1414.6887684709363;14891.776623350659;15462.536123916272;15488.380464781396;15600.453984110558;15703.685926281481;15245.847541524581;15398.30707618157;15406.61483462914;15418.30617622207;15451.84548154518;2431.416460049584;2523.3565440812567;2527.810414218932;2531.6708827852444;2536.606825941982;2637.7494138056854;2760.3789147441867;2763.72362763723;2764.156932135698;2778.7082356352594;2640.8811603477793;2641.128427618891;2769.667639883217;2781.9443611127767;2787.428577141681;2769.639946806912;2791.4138031013613;2802.2574017885277;2805.6212411324414;2813.7467627913484;2588.4564241509306;2654.8407095574325;2675.4916565852427;2677.4243537639304;2682.12830829981;12159.817602735966;12511.812322815165;12599.244045357216;14632.268386580736;15244.39022439101;7617.733379331697;7785.766427007142;7846.489978151459;7858.37133029362;7926.612842752495;4724.5039270876405;4998.325226094467;5035.924461133086;5077.644564880423;5091.541761241469;1906.3327835257687;1972.960540789176;1986.2253721048803;1996.5308152584132;2023.3323003408839;26177.345566360804;26283.999999999807;26429.3392665183;26653.46693066147;26661.46677066448;6558.278589355167;6602.2737498875085;6615.669216539154;6740.932590674129;6815.556988795713;17078.658426831393;17086.231119599648;17107;17195.05427201516;17259.22333494996;7277.9636101820215;7379.040724705861;7392.667329970164;7399.8150046249275;7414.073240844895;6783.219929708147;6855.520113592101;7234.927650723531;7339.412846972229;7352.926470735224;5112.488751124858;5344.839654810362;5348.277982472317;5352.705601191894;5393.946060539344;1579.6998570271612;1603.6071162565013;1605.4793973461553;1606.309287006575;1615.4345978907288;902.9187373136449;907.3693782821008;910.485575649769;922.9077092290718;931.4085555672197;7481.102267727799;8524.31805455562;8601.397902146915;8670.002949660744;8718.084601116918;686.8488932434856;716.4698123388655;718.2601920022386;733.8478588615822;739.7004213293625;4493.370928070073;4573.153966516205;5108.080545501845;5205.531502164823;5312.229726689686;20536.281230156874;21501.247456339264;21551.78448215529;21951.780482195292;21956.341309760734;20862.687059694115;21017.68473472899;21506.46233844135;21565;21584.136634534443;10094.19246460296;10319.96800319977;10542.262041657164;10551.20865935058;10559.577616895393;8281.964754405699;8401.915980840358;8406.28546573548;8417.915820841714;8540.274076703301;18109.728354074963;18200.908995454938;18262.452126436227;18508;18722.906385467984;18699.06504674788;18878.905605471886;18925.716114258572;19037.90481047586;19062.714059288843;10881.673549793519;10991.230613856953;11050.171237157085;11073.833892491459;11083.778324433628;13466.730665386833;13589.32053397346;13666.385012674255;13697;13735;10634.627788027381;10924.617638382619;11120.666380008613;11211.887881121085;11224.270422422516;11008.834867476835;11102.111831053659;11193.608223712132;11205.943970280423;11273.887261127284;9831.262655301025;9991.700248992542;10035.24735644815;10138.290319677551;10150.49247537635;6834.453243740588;6984.126984126984;7024.824379390556;7052.365287124184;7070.964645176741;6737.3599508046655;6847.691853866489;6856.554323968926;6968.860622787617;7004.299570042955 05:39:05 INFO - PID 24986 | |4;prototype.html;504.38978287362886;504.69756968708754;506.66560070353563;514.1234195696338;524.260792282881;6136.355682653324;6201.565890387669;6201.906971395432;6277.1839660844025;6318.368163183685;6051.969740151293;6053.334133245339;6071.483923866476;6144.754209831602;6170.845728856781;4915.139850526161;5080.517350851682;5104.183330667085;5134.358205224347;5136.743162841843;5073.036123136592;5091.389033316007;5155.432902380735;5188.16989281715;5216.800475617127;5182.352205974254;5187.118189907725;5193.221016847466;5222.63441559091;5235.5549778268705;5039.639297389703;5070.315507406508;5112.514311140436;5158.793648254065;5174.43081261061;4758.762061896891;4779.55501383713;4782.928256076161;4794.976025119887;4811.9037619247765;6243.313235544087;6245.3442388549;6248.28144763349;6267.609855524245;6286.874262514724;5884.293884733859;6005.489533389624;6017.14185761764;6026.819195424145;6054.394560543955;6552.901706474407;6560.278369379364;6660.23407308156;6664.966675166642;6681.432078273353;5193.376794784613;5214.582833373359;5216.643672645102;5233;5242.501962313574;6861.588304701734;6925.549839260482;6949;6954;6973.267806880255;5367.704776237267;5396.379416367089;5409.864753381197;5437.700926449083;5494.637305958397;5426.782928682887;5461.15352120422;5469.917951230735;5492.862678433071;5504.284443022461;5976.970115149396;5988.981873081587;5995.910061349084;6033.18551995475;6034.547408944347;6431.614103153825;6622;6698;6717.749553899401;6742.932570674328;1834.8899066056078;1856.2482194710894;1862.733341327883;1868.6542989546977;1895.5734959634249;1391.8100024479127;1406.360106151719;1418.8084608577917;1426.6148140002142;1427.700182961569;5709.600327977002;5761.596688231783;5763.510101641324;5779.450952159538;5815.069588865846;6472.773452929125;6664.566803157779;6713.462922966151;6759.194934102293;6793.422559082434;5654.74553645087;5655.321361436654;5739.827805165852;5748.770049197985;5750.5112065474905;3492.650734926487;3512.3502152101432;3548.8947171225177;3554.804485753308;3569.214772749991;6370.554061215763;6372.203474565679;6382.936170638327;6394.488440924715;6406.935930640727;6697.330266973264;6781.728730850809;6843.7604683835825;6868.553544019623;6893.517453778188;6369.05743748152;6448.322926092693;6457.741690332428;6472.385123413268;6538.836529086716;6660.234073081658;6723.630200339028;6728.663566821737;6758.715843989627;6796.76211332601;6635.701393437404;6650.933490665031;6695.464362851058;6706.865862682816;6785.830354241183;6417.679116044273;6437.195350581177;6469.902951455638;6497.642629655225;6510.576812507172;4886.998165376161;4905.411350637946;4918.336024636771;4938.283948827472;4949.653524253412;6669;6736.797896063126;6778.983352408131;6794;6825.35159159879;6155.815325540241;6394.328595497405;6403.8719225614295;6413.615183089029;6422.807315780534;6391.488680905423;6427.196600424947;6468.15913931186;6499.642519661464;6546.738130474918;5923.970380148072;6245.125682404558;6322.652254126067;6345.397187267202;6395.97664373698;6561.031690492929;6571.737130514917;6572.14562106923;6573.3426657333885;6643.60138391698;6183.288921774054;6229.096780966644;6289.685515724104;6338.841528961812;6366.9681651591445 05:39:05 INFO - PID 24986 | |5;yui.html;890.8975467821194;891.9331050171237;906.7551761024528;916.8028873792133;928.2434815625265;16854.83145168548;17583.208755605996;17845.55386115348;17882.55293617657;17885.64228715425;16566.420175293868;16982.915085424556;17323.91338043311;17364.913175434136;17371;945.0738276489045;962.5379817687514;973.737090985437;973.8539219117154;976.1556253840448;983.7442265011113;985.0494273026528;987.0573602209909;987.244757760314;990.6285143071349;978.8189185000762;1021.9897801021968;1023.6621914768145;1026.3687831983314;1027.0653705128298;1033.2044325869076;1033.3231733214757;1034.1519953637944;1035.094292494064;1035.5236591168073;713.3437237741291;748.5134662469438;748.8839229919371;750.2085300008489;752.4883079505934;17504.562385940328;17677.558061048447;17694.734578981326;17709.468715938543;17750.11249437536;21390.14439422421;21560.568788624296;21870.562588748136;22005.449863753372;22241;17081.231344589523;17206.569835754082;17222.569435764206;17314;17556;3657.4148136298063;3674.3202507536193;3699.4820725098507;3715.8699445519546;3720.8511659533588;132685.33657331747;133460;134601;137256;137678.999999999;15982.920085399499;16373.181340933144;16833.91583042077;16850.32598696063;16939.830601694073;29383.853080734887;29699.703002970127;30183.396332073673;30766.53850192249;30862.845685771877;7967.163447838009;8051.114377418479;8054.476459030145;8060.596970151469;8072.556009419421;3970.781607011584;4023.8591649292134;4061.3907913812573;4069.9389509157386;4078.5105787305715;5175.9741201293755;5181.637285389987;5210.374755029421;5224.451432599599;5248.9737551312;958.8993155718689;979.4368238263028;989.6833013435776;992.1417973452911;1002.9448380339005;692.4495026453981;695.4123765418213;713.4363852556526;718.2601920022386;719.2843121094544;10475.319104258351;11200.183920914085;11411.885881141248;11885.643430697093;12704.555340563036;558.4694540186839;563.613924461745;565.5023579250234;565.5334349161958;575.8128608202351;5392.703401312965;5407.215953686693;5480.862978425492;5486.286782718221;5588.273524441796;118574;125944.37027814802;126321.36839315745;127618;128565.35717321353;100864.49567752262;104523;105646;106370;107290;88433.5578322117;92715.99999999865;93915.53042234745;93973;94394.0560594399;10118.49407529606;10167.884850450093;10168.847467288144;10198.235132365251;10307.433091180057;40998;42326.576734232265;43655.78172109119;44283.89290267769;44851;57614.42385576258;58009.70995145166;59088;59344.406555933885;59346;14497.85502144965;14696.926515367355;14751;14835.703285934436;14959.17724525118;17124.31502739926;17305.480835574952;17504.387346442814;17606.647867042844;17811.376601818873;14366.497172598909;14468.204248765998;14565.92717036408;14708;14731.337089831197;14168;14353;14370.281485925452;14452.710945781237;14575.562733118024;14767.261636918325;14883.702325953203;14999.475018374305;15135.848641513885;15168.696626067638;11896.881031189578;11944.940275298568;11993.64019079429;12291.692707682021;12351.505939762308;11132.721681958015;11382.317060976367;11483.770324593293;11545.450914609914;11613.070954323803 05:39:05 INFO - PID 24986 | __end_tp_report 05:39:05 INFO - PID 24986 | __start_cc_report 05:39:05 INFO - PID 24986 | _x_x_mozilla_cycle_collect,374 05:39:05 INFO - PID 24986 | __end_cc_report 05:39:05 INFO - PID 24986 | __startTimestamp1505306345101__endTimestamp 05:39:05 INFO - PID 24986 | 05:39:05 INFO - PID 24986 | ------- Summary: start ------- 05:39:05 INFO - PID 24986 | Number of tests: 6 05:39:05 INFO - PID 24986 | 05:39:05 INFO - PID 24986 | [#0] dojo.html Cycles:165 Average:6250.74 Median:6528.12 stddev:771.11 (11.8%) stddev-sans-first:730.62 05:39:05 INFO - PID 24986 | Values: 3019.1 3064.5 3100.8 3129.9 3134.5 6115.6 6329.5 6360.2 6396.1 6415.1 6228.6 6274.7 6465.7 6505.6 6511.4 5781.9 5877.3 5946.8 5960.5 6011.9 5835.7 5930.9 5983.0 5989.8 6006.6 5803.9 5854.6 5860.1 5918.7 5947.1 5616.5 5734.4 5804.0 5812.6 5885.6 5753.6 5796.6 5803.7 5815.7 5902.1 6302.0 6323.0 6379.2 6404.4 6439.3 6778.2 6778.4 6810.4 6868.3 6919.3 6618.2 6741.0 6779.3 6838.7 6859.2 6277.9 6343.3 6366.2 6408.7 6421.7 6823.2 6872.9 6874.1 6875.0 6936.9 6613.1 6647.9 6782.0 6786.1 6831.5 6778.0 6797.8 6855.8 6873.6 6875.4 6735.3 6745.8 6770.1 6840.9 6844.1 6559.6 6698.6 6699.9 6703.2 6710.9 5139.3 5285.2 5301.9 5303.9 5310.8 4707.8 4723.2 4724.3 4737.8 4760.7 5834.8 5835.9 5887.4 5901.6 5914.9 6656.8 6810.9 6827.4 6889.2 6903.8 5638.0 5641.9 5650.0 5657.6 5664.3 5637.3 5672.1 5685.6 5698.1 5701.4 6535.5 6640.4 6674.2 6674.2 6678.6 6845.8 6849.1 6930.9 6938.3 6946.8 6433.4 6597.2 6599.1 6670.3 6694.6 6685.7 6718.7 6788.6 6804.8 6818.9 6887.2 6928.8 6930.3 6976.2 6982.1 6612.5 6646.9 6712.4 6720.1 6721.7 6399.4 6520.3 6625.0 6625.6 6731.9 6485.2 6595.9 6602.2 6623.1 6627.8 6599.5 6611.4 6665.3 6762.6 6774.1 6437.8 6492.2 6512.9 6528.1 6531.2 05:39:05 INFO - PID 24986 | 05:39:05 INFO - PID 24986 | [#1] ext.html Cycles:180 Average:36137.32 Median:35252.14 stddev:49438.64 (140.2%) stddev-sans-first:49506.32 05:39:05 INFO - PID 24986 | Values: 847.7 873.6 883.6 884.7 895.1 40969.2 42358.8 42733.6 42752.8 42784.0 38740.2 38952.6 39105.6 39128.0 39152.2 6989.9 7099.0 7102.8 7115.6 7146.6 7049.6 7102.2 7152.2 7154.6 7156.3 7091.0 7113.9 7134.5 7758.8 7862.5 7821.8 7825.3 7831.3 7860.1 7880.4 7704.5 7805.2 7806.5 7817.7 7822.0 38776.8 39651.8 39692.0 39708.6 39729.2 3562.2 3607.2 3610.7 3616.2 3635.9 31618.8 32010.5 32069.4 32074.0 32119.0 5083.7 5122.4 5122.6 5129.7 5141.4 177351.0 177934.0 177986.0 178970.0 180147.1 46911.5 47126.3 47401.8 47413.5 47464.5 49236.0 49321.8 49556.3 49743.0 49784.8 15588.0 15669.8 15872.4 15928.6 16112.4 13029.4 13222.9 13249.5 13269.4 13308.9 14503.3 14542.2 14547.4 14702.3 14795.5 5707.3 5860.6 5868.2 5892.6 5941.0 1010.3 1023.6 1034.0 1041.3 1044.8 22913.3 22938.7 22961.4 23158.5 23469.6 1129.7 1135.3 1140.4 1141.8 1147.5 11948.3 12059.6 12204.3 12291.0 12303.9 214425.0 214612.0 215516.0 215552.0 215978.0 51736.7 52099.5 52122.7 52183.0 52184.0 1287.7 1311.3 1451.4 1475.2 1488.8 26903.7 27058.9 27782.0 27860.4 28206.9 36031.6 36192.0 36265.3 36552.6 36683.6 176389.1 176487.0 178267.0 178644.0 178959.0 32959.2 34064.3 34108.1 34503.5 34555.3 42523.6 42537.8 42891.6 43158.1 43547.6 30781.5 31023.7 31095.0 31210.4 31545.8 31121.4 31156.0 31218.0 31501.8 31687.0 34946.0 35558.3 35787.6 35927.5 36053.5 30241.7 30429.4 30586.7 30712.8 30881.4 22401.9 22889.5 23208.8 23226.4 23564.9 05:39:05 INFO - PID 24986 | 05:39:05 INFO - PID 24986 | [#2] jquery.html Cycles:180 Average:32193.43 Median:22220.78 stddev:73487.27 (330.7%) stddev-sans-first:73655.00 05:39:05 INFO - PID 24986 | Values: 544.9 554.3 557.7 572.8 581.6 22614.7 22688.9 23207.5 23288.9 23314.4 24109.9 24385.6 24456.3 24761.0 24891.6 6487.7 6591.1 6667.1 6669.1 6672.3 6549.3 6604.1 6628.0 6633.5 6636.5 6238.4 6487.0 6491.6 6504.6 6560.6 6399.2 6440.4 6491.4 6492.7 6495.4 5807.4 5941.5 5952.4 6014.1 6017.7 28365.3 28607.7 28612.3 28621.0 29028.3 2010.6 2037.6 2041.2 2044.9 2046.2 18647.5 18982.0 19307.8 19370.0 19463.4 2446.2 2488.8 2520.2 2525.4 2526.4 119496.0 123968.0 124385.0 136954.6 137697.0 14274.6 14338.9 14488.2 14585.9 14662.6 21984.8 22087.7 22194.3 22247.2 22372.3 9954.0 10160.9 10270.8 10291.1 10309.8 9646.9 9664.5 9666.6 9742.5 9803.8 8706.3 8769.8 8857.8 8931.0 8968.4 2451.7 2475.3 2542.1 2547.3 2641.6 950.9 951.7 967.0 969.1 970.0 18203.4 18264.0 18425.4 18576.3 18580.7 1007.2 1017.2 1029.5 1031.9 1034.8 10232.8 10247.8 10348.3 10357.6 10390.3 411745.0 433379.0 439692.0 442943.0 443471.5 99907.5 105873.5 108783.0 110358.0 111284.0 1166.2 1171.2 1183.2 1186.5 1186.9 16166.5 16231.9 16602.0 16807.4 16852.2 53799.7 54048.0 54821.0 54908.5 54978.5 67407.0 67505.0 67892.7 68424.3 68437.3 13510.9 13642.2 13864.7 13924.7 13966.7 20422.8 21027.0 21698.0 21794.1 21897.7 17208.6 17252.2 17254.4 17471.4 18079.7 14700.6 15130.8 16024.6 16163.4 16682.4 17722.8 17760.8 17789.9 17891.8 18071.6 13642.2 13672.8 13688.5 13700.7 13701.5 17612.6 17659.7 17771.0 17790.1 18020.7 05:39:05 INFO - PID 24986 | 05:39:05 INFO - PID 24986 | [#3] mootools.html Cycles:180 Average:9169.44 Median:14460.87 stddev:6619.78 (45.8%) stddev-sans-first:6612.21 05:39:05 INFO - PID 24986 | Values: 1339.3 1390.9 1403.7 1413.4 1414.7 14891.8 15462.5 15488.4 15600.5 15703.7 15245.8 15398.3 15406.6 15418.3 15451.8 2431.4 2523.4 2527.8 2531.7 2536.6 2637.7 2760.4 2763.7 2764.2 2778.7 2640.9 2641.1 2769.7 2781.9 2787.4 2769.6 2791.4 2802.3 2805.6 2813.7 2588.5 2654.8 2675.5 2677.4 2682.1 12159.8 12511.8 12599.2 14632.3 15244.4 7617.7 7785.8 7846.5 7858.4 7926.6 4724.5 4998.3 5035.9 5077.6 5091.5 1906.3 1973.0 1986.2 1996.5 2023.3 26177.3 26284.0 26429.3 26653.5 26661.5 6558.3 6602.3 6615.7 6740.9 6815.6 17078.7 17086.2 17107.0 17195.1 17259.2 7278.0 7379.0 7392.7 7399.8 7414.1 6783.2 6855.5 7234.9 7339.4 7352.9 5112.5 5344.8 5348.3 5352.7 5393.9 1579.7 1603.6 1605.5 1606.3 1615.4 902.9 907.4 910.5 922.9 931.4 7481.1 8524.3 8601.4 8670.0 8718.1 686.8 716.5 718.3 733.8 739.7 4493.4 4573.2 5108.1 5205.5 5312.2 20536.3 21501.2 21551.8 21951.8 21956.3 20862.7 21017.7 21506.5 21565.0 21584.1 10094.2 10320.0 10542.3 10551.2 10559.6 8282.0 8401.9 8406.3 8417.9 8540.3 18109.7 18200.9 18262.5 18508.0 18722.9 18699.1 18878.9 18925.7 19037.9 19062.7 10881.7 10991.2 11050.2 11073.8 11083.8 13466.7 13589.3 13666.4 13697.0 13735.0 10634.6 10924.6 11120.7 11211.9 11224.3 11008.8 11102.1 11193.6 11205.9 11273.9 9831.3 9991.7 10035.2 10138.3 10150.5 6834.5 6984.1 7024.8 7052.4 7071.0 6737.4 6847.7 6856.6 6968.9 7004.3 05:39:05 INFO - PID 24986 | 05:39:05 INFO - PID 24986 | [#4] prototype.html Cycles:180 Average:5579.78 Median:6150.28 stddev:1504.75 (24.5%) stddev-sans-first:1459.96 05:39:05 INFO - PID 24986 | Values: 504.4 504.7 506.7 514.1 524.3 6136.4 6201.6 6201.9 6277.2 6318.4 6052.0 6053.3 6071.5 6144.8 6170.8 4915.1 5080.5 5104.2 5134.4 5136.7 5073.0 5091.4 5155.4 5188.2 5216.8 5182.4 5187.1 5193.2 5222.6 5235.6 5039.6 5070.3 5112.5 5158.8 5174.4 4758.8 4779.6 4782.9 4795.0 4811.9 6243.3 6245.3 6248.3 6267.6 6286.9 5884.3 6005.5 6017.1 6026.8 6054.4 6552.9 6560.3 6660.2 6665.0 6681.4 5193.4 5214.6 5216.6 5233.0 5242.5 6861.6 6925.5 6949.0 6954.0 6973.3 5367.7 5396.4 5409.9 5437.7 5494.6 5426.8 5461.2 5469.9 5492.9 5504.3 5977.0 5989.0 5995.9 6033.2 6034.5 6431.6 6622.0 6698.0 6717.7 6742.9 1834.9 1856.2 1862.7 1868.7 1895.6 1391.8 1406.4 1418.8 1426.6 1427.7 5709.6 5761.6 5763.5 5779.5 5815.1 6472.8 6664.6 6713.5 6759.2 6793.4 5654.7 5655.3 5739.8 5748.8 5750.5 3492.7 3512.4 3548.9 3554.8 3569.2 6370.6 6372.2 6382.9 6394.5 6406.9 6697.3 6781.7 6843.8 6868.6 6893.5 6369.1 6448.3 6457.7 6472.4 6538.8 6660.2 6723.6 6728.7 6758.7 6796.8 6635.7 6650.9 6695.5 6706.9 6785.8 6417.7 6437.2 6469.9 6497.6 6510.6 4887.0 4905.4 4918.3 4938.3 4949.7 6669.0 6736.8 6779.0 6794.0 6825.4 6155.8 6394.3 6403.9 6413.6 6422.8 6391.5 6427.2 6468.2 6499.6 6546.7 5924.0 6245.1 6322.7 6345.4 6396.0 6561.0 6571.7 6572.1 6573.3 6643.6 6183.3 6229.1 6289.7 6338.8 6367.0 05:39:05 INFO - PID 24986 | 05:39:05 INFO - PID 24986 | [#5] yui.html Cycles:180 Average:23725.44 Median:17780.74 stddev:34811.59 (195.8%) stddev-sans-first:34867.02 05:39:05 INFO - PID 24986 | Values: 890.9 891.9 906.8 916.8 928.2 16854.8 17583.2 17845.6 17882.6 17885.6 16566.4 16982.9 17323.9 17364.9 17371.0 945.1 962.5 973.7 973.9 976.2 983.7 985.0 987.1 987.2 990.6 978.8 1022.0 1023.7 1026.4 1027.1 1033.2 1033.3 1034.2 1035.1 1035.5 713.3 748.5 748.9 750.2 752.5 17504.6 17677.6 17694.7 17709.5 17750.1 21390.1 21560.6 21870.6 22005.4 22241.0 17081.2 17206.6 17222.6 17314.0 17556.0 3657.4 3674.3 3699.5 3715.9 3720.9 132685.3 133460.0 134601.0 137256.0 137679.0 15982.9 16373.2 16833.9 16850.3 16939.8 29383.9 29699.7 30183.4 30766.5 30862.8 7967.2 8051.1 8054.5 8060.6 8072.6 3970.8 4023.9 4061.4 4069.9 4078.5 5176.0 5181.6 5210.4 5224.5 5249.0 958.9 979.4 989.7 992.1 1002.9 692.4 695.4 713.4 718.3 719.3 10475.3 11200.2 11411.9 11885.6 12704.6 558.5 563.6 565.5 565.5 575.8 5392.7 5407.2 5480.9 5486.3 5588.3 118574.0 125944.4 126321.4 127618.0 128565.4 100864.5 104523.0 105646.0 106370.0 107290.0 88433.6 92716.0 93915.5 93973.0 94394.1 10118.5 10167.9 10168.8 10198.2 10307.4 40998.0 42326.6 43655.8 44283.9 44851.0 57614.4 58009.7 59088.0 59344.4 59346.0 14497.9 14696.9 14751.0 14835.7 14959.2 17124.3 17305.5 17504.4 17606.6 17811.4 14366.5 14468.2 14565.9 14708.0 14731.3 14168.0 14353.0 14370.3 14452.7 14575.6 14767.3 14883.7 14999.5 15135.8 15168.7 11896.9 11944.9 11993.6 12291.7 12351.5 11132.7 11382.3 11483.8 11545.5 11613.1 05:39:05 INFO - PID 24986 | -------- Summary: end -------- 05:39:05 INFO - PID 24986 | 05:39:05 INFO - PID 24986 | 05:39:05 INFO - PID 24986 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 05:39:05 INFO - PID 24986 | 05:39:06 INFO - TEST-INFO | 24986: exit 0 05:39:06 INFO - TEST-OK | dromaeo_css | took 1114043ms 05:39:06 INFO - TEST-START | kraken 05:39:06 INFO - Initialising browser for kraken test... 05:39:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48108/getInfo.html -profile /tmp/tmpZfzirA/profile 05:39:06 INFO - TEST-INFO | started process 25372 (/builds/slave/test/build/application/firefox/firefox http://localhost:48108/getInfo.html) 05:39:11 INFO - TEST-INFO | 25372: exit 0 05:39:12 INFO - Browser initialized. 05:39:12 INFO - Running cycle 1/1 for kraken test... 05:39:12 INFO - Using env: {'DISPLAY': ':0', 05:39:12 INFO - 'HOME': '/home/cltbld', 05:39:12 INFO - 'JSGC_DISABLE_POISONING': '1', 05:39:12 INFO - 'LANG': 'en_US.UTF-8', 05:39:12 INFO - 'LANGUAGE': 'en_US:en', 05:39:12 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 05:39:12 INFO - 'LOGNAME': 'cltbld', 05:39:12 INFO - 'MAIL': '/var/mail/cltbld', 05:39:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:39:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:39:12 INFO - 'MOZ_ACCELERATED': '1', 05:39:12 INFO - 'MOZ_CRASHREPORTER': '1', 05:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:12 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 05:39:12 INFO - 'MOZ_NO_REMOTE': '1', 05:39:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:39:12 INFO - 'MOZ_WEBRENDER': '1', 05:39:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:39:12 INFO - 'NO_EM_RESTART': '1', 05:39:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:12 INFO - 'PWD': '/builds/slave/test', 05:39:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:39:12 INFO - 'RUST_BACKTRACE': 'full', 05:39:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:39:12 INFO - 'SHELL': '/bin/bash', 05:39:12 INFO - 'SHLVL': '1', 05:39:12 INFO - 'TERM': 'linux', 05:39:12 INFO - 'TMOUT': '86400', 05:39:12 INFO - 'USER': 'cltbld', 05:39:12 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367', 05:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:12 INFO - '_': '/tools/buildbot/bin/python'} 05:39:12 INFO - TEST-INFO | started process 25565 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZfzirA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:39:12 INFO - PID 25565 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 05:39:13 INFO - PID 25565 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 05:39:13 INFO - PID 25565 | 05:39:13 INFO - PID 25565 | (/builds/slave/test/build/application/firefox/firefox:25624): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:39:13 INFO - PID 25565 | 05:39:23 INFO - PID 25565 | RSS: Main: 218652672 05:39:23 INFO - PID 25565 | 05:39:26 INFO - PID 25565 | Cycle 1(1): loaded http://localhost:48108/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48108/tests/kraken/driver.html?testName=audio-beat-detection) 05:39:27 INFO - PID 25565 | RSS: Main: 222969856 05:39:27 INFO - PID 25565 | 05:39:30 INFO - PID 25565 | Cycle 1(1): loaded http://localhost:48108/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48108/tests/kraken/driver.html?testName=audio-dft) 05:39:30 INFO - PID 25565 | RSS: Main: 225239040 05:39:30 INFO - PID 25565 | 05:39:34 INFO - PID 25565 | Cycle 1(1): loaded http://localhost:48108/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48108/tests/kraken/driver.html?testName=audio-fft) 05:39:34 INFO - PID 25565 | RSS: Main: 221179904 05:39:34 INFO - PID 25565 | 05:39:36 INFO - PID 25565 | Cycle 1(1): loaded http://localhost:48108/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48108/tests/kraken/driver.html?testName=audio-oscillator) 05:39:37 INFO - PID 25565 | RSS: Main: 222048256 05:39:37 INFO - PID 25565 | 05:39:39 INFO - PID 25565 | Cycle 1(1): loaded http://localhost:48108/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48108/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:39:40 INFO - PID 25565 | RSS: Main: 222142464 05:39:40 INFO - PID 25565 | 05:39:43 INFO - PID 25565 | Cycle 1(1): loaded http://localhost:48108/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48108/tests/kraken/driver.html?testName=imaging-darkroom) 05:39:44 INFO - PID 25565 | RSS: Main: 224657408 05:39:44 INFO - PID 25565 | 05:39:47 INFO - PID 25565 | Cycle 1(1): loaded http://localhost:48108/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48108/tests/kraken/driver.html?testName=imaging-desaturate) 05:39:48 INFO - PID 25565 | RSS: Main: 222732288 05:39:48 INFO - PID 25565 | 05:39:51 INFO - PID 25565 | Cycle 1(1): loaded http://localhost:48108/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48108/tests/kraken/driver.html?testName=json-parse-financial) 05:39:52 INFO - PID 25565 | RSS: Main: 223571968 05:39:52 INFO - PID 25565 | 05:39:53 INFO - PID 25565 | Cycle 1(1): loaded http://localhost:48108/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48108/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:39:54 INFO - PID 25565 | RSS: Main: 223387648 05:39:54 INFO - PID 25565 | 05:39:56 INFO - PID 25565 | Cycle 1(1): loaded http://localhost:48108/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48108/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:39:56 INFO - PID 25565 | RSS: Main: 221442048 05:39:56 INFO - PID 25565 | 05:39:59 INFO - PID 25565 | Cycle 1(1): loaded http://localhost:48108/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48108/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:39:59 INFO - PID 25565 | RSS: Main: 221310976 05:39:59 INFO - PID 25565 | 05:40:02 INFO - PID 25565 | Cycle 1(1): loaded http://localhost:48108/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48108/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:40:03 INFO - PID 25565 | RSS: Main: 222224384 05:40:03 INFO - PID 25565 | 05:40:06 INFO - PID 25565 | Cycle 1(1): loaded http://localhost:48108/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48108/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:40:06 INFO - PID 25565 | RSS: Main: 221724672 05:40:06 INFO - PID 25565 | 05:40:08 INFO - PID 25565 | Cycle 1(1): loaded http://localhost:48108/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48108/tests/kraken/driver.html?testName=ai-astar) 05:40:08 INFO - PID 25565 | RSS: Main: 224223232 05:40:08 INFO - PID 25565 | 05:40:08 INFO - PID 25565 | __start_tp_report 05:40:08 INFO - PID 25565 | _x_x_mozilla_page_load 05:40:08 INFO - PID 25565 | _x_x_mozilla_page_load_details 05:40:08 INFO - PID 25565 | |i|pagename|runs| 05:40:08 INFO - PID 25565 | |0;ai-astar;113;106;121;119;114;115;111;109;108;109 05:40:08 INFO - PID 25565 | |1;audio-beat-detection;134;118;122;120;117;116;119;118;119;122 05:40:08 INFO - PID 25565 | |2;audio-dft;166;168;167;175;168;164;173;162;165;163 05:40:08 INFO - PID 25565 | |3;audio-fft;77;83;80;80;75;74;76;82;75;76 05:40:08 INFO - PID 25565 | |4;audio-oscillator;82;83;84;80;79;78;81;78;79;85 05:40:08 INFO - PID 25565 | |5;imaging-gaussian-blur;114;114;114;114;113;114;115;112;124;115 05:40:08 INFO - PID 25565 | |6;imaging-darkroom;153;153;150;154;158;150;147;169;153;150 05:40:08 INFO - PID 25565 | |7;imaging-desaturate;99;104;95;97;94;94;95;104;96;95 05:40:08 INFO - PID 25565 | |8;json-parse-financial;56;55;53;57;59;55;55;54;56;52 05:40:08 INFO - PID 25565 | |9;json-stringify-tinderbox;41;39;40;45;39;43;43;45;48;38 05:40:08 INFO - PID 25565 | |10;stanford-crypto-aes;79;77;75;74;76;75;76;73;76;73 05:40:08 INFO - PID 25565 | |11;stanford-crypto-ccm;118;112;110;118;117;119;124;116;120;110 05:40:08 INFO - PID 25565 | |12;stanford-crypto-pbkdf2;176;168;176;169;171;174;171;172;186;168 05:40:08 INFO - PID 25565 | |13;stanford-crypto-sha256-iterative;68;67;65;65;66;68;64;66;68;68 05:40:08 INFO - PID 25565 | __end_tp_report 05:40:08 INFO - PID 25565 | __start_cc_report 05:40:08 INFO - PID 25565 | _x_x_mozilla_cycle_collect,943 05:40:08 INFO - PID 25565 | __end_cc_report 05:40:08 INFO - PID 25565 | __startTimestamp1505306408873__endTimestamp 05:40:08 INFO - PID 25565 | 05:40:08 INFO - PID 25565 | ------- Summary: start ------- 05:40:08 INFO - PID 25565 | Number of tests: 14 05:40:08 INFO - PID 25565 | 05:40:08 INFO - PID 25565 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.50 stddev:4.86 (4.3%) stddev-sans-first:5.15 05:40:08 INFO - PID 25565 | Values: 113.0 106.0 121.0 119.0 114.0 115.0 111.0 109.0 108.0 109.0 05:40:08 INFO - PID 25565 | 05:40:08 INFO - PID 25565 | [#1] audio-beat-detection Cycles:10 Average:120.50 Median:119.50 stddev:5.13 (4.3%) stddev-sans-first:2.06 05:40:08 INFO - PID 25565 | Values: 134.0 118.0 122.0 120.0 117.0 116.0 119.0 118.0 119.0 122.0 05:40:08 INFO - PID 25565 | 05:40:08 INFO - PID 25565 | [#2] audio-dft Cycles:10 Average:167.10 Median:167.50 stddev:4.18 (2.5%) stddev-sans-first:4.41 05:40:08 INFO - PID 25565 | Values: 166.0 168.0 167.0 175.0 168.0 164.0 173.0 162.0 165.0 163.0 05:40:08 INFO - PID 25565 | 05:40:08 INFO - PID 25565 | [#3] audio-fft Cycles:10 Average:77.80 Median:78.50 stddev:3.19 (4.1%) stddev-sans-first:3.37 05:40:08 INFO - PID 25565 | Values: 77.0 83.0 80.0 80.0 75.0 74.0 76.0 82.0 75.0 76.0 05:40:08 INFO - PID 25565 | 05:40:08 INFO - PID 25565 | [#4] audio-oscillator Cycles:10 Average:80.90 Median:81.50 stddev:2.51 (3.1%) stddev-sans-first:2.64 05:40:08 INFO - PID 25565 | Values: 82.0 83.0 84.0 80.0 79.0 78.0 81.0 78.0 79.0 85.0 05:40:08 INFO - PID 25565 | 05:40:08 INFO - PID 25565 | [#5] imaging-gaussian-blur Cycles:10 Average:114.90 Median:114.00 stddev:3.31 (2.9%) stddev-sans-first:3.50 05:40:08 INFO - PID 25565 | Values: 114.0 114.0 114.0 114.0 113.0 114.0 115.0 112.0 124.0 115.0 05:40:08 INFO - PID 25565 | 05:40:08 INFO - PID 25565 | [#6] imaging-darkroom Cycles:10 Average:153.70 Median:153.00 stddev:6.15 (4.0%) stddev-sans-first:6.51 05:40:08 INFO - PID 25565 | Values: 153.0 153.0 150.0 154.0 158.0 150.0 147.0 169.0 153.0 150.0 05:40:08 INFO - PID 25565 | 05:40:08 INFO - PID 25565 | [#7] imaging-desaturate Cycles:10 Average:97.30 Median:95.00 stddev:3.83 (4.0%) stddev-sans-first:4.01 05:40:08 INFO - PID 25565 | Values: 99.0 104.0 95.0 97.0 94.0 94.0 95.0 104.0 96.0 95.0 05:40:08 INFO - PID 25565 | 05:40:08 INFO - PID 25565 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.50 stddev:1.99 (3.6%) stddev-sans-first:2.09 05:40:08 INFO - PID 25565 | Values: 56.0 55.0 53.0 57.0 59.0 55.0 55.0 54.0 56.0 52.0 05:40:08 INFO - PID 25565 | 05:40:08 INFO - PID 25565 | [#9] json-stringify-tinderbox Cycles:10 Average:42.10 Median:43.00 stddev:3.25 (7.6%) stddev-sans-first:3.42 05:40:08 INFO - PID 25565 | Values: 41.0 39.0 40.0 45.0 39.0 43.0 43.0 45.0 48.0 38.0 05:40:08 INFO - PID 25565 | 05:40:08 INFO - PID 25565 | [#10] stanford-crypto-aes Cycles:10 Average:75.40 Median:76.00 stddev:1.84 (2.4%) stddev-sans-first:1.41 05:40:08 INFO - PID 25565 | Values: 79.0 77.0 75.0 74.0 76.0 75.0 76.0 73.0 76.0 73.0 05:40:08 INFO - PID 25565 | 05:40:08 INFO - PID 25565 | [#11] stanford-crypto-ccm Cycles:10 Average:116.40 Median:118.00 stddev:4.53 (3.8%) stddev-sans-first:4.76 05:40:08 INFO - PID 25565 | Values: 118.0 112.0 110.0 118.0 117.0 119.0 124.0 116.0 120.0 110.0 05:40:08 INFO - PID 25565 | 05:40:08 INFO - PID 25565 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.10 Median:173.00 stddev:5.40 (3.1%) stddev-sans-first:5.63 05:40:08 INFO - PID 25565 | Values: 176.0 168.0 176.0 169.0 171.0 174.0 171.0 172.0 186.0 168.0 05:40:08 INFO - PID 25565 | 05:40:08 INFO - PID 25565 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.50 stddev:1.51 (2.2%) stddev-sans-first:1.50 05:40:08 INFO - PID 25565 | Values: 68.0 67.0 65.0 65.0 66.0 68.0 64.0 66.0 68.0 68.0 05:40:08 INFO - PID 25565 | -------- Summary: end -------- 05:40:08 INFO - PID 25565 | 05:40:09 INFO - PID 25565 | 05:40:09 INFO - PID 25565 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 05:40:09 INFO - PID 25565 | 05:40:09 INFO - PID 25565 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 05:40:09 INFO - TEST-INFO | 25565: exit 0 05:40:09 INFO - TEST-OK | kraken | took 63803ms 05:40:09 INFO - SUITE-END | took 1177s 05:40:10 INFO - Completed test suite (00:19:38) 05:40:10 INFO - javascript benchmark 05:40:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8407.485062128737, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3019.109362737992, 3064.4943584308585, 3100.767442441816, 3129.906102816915, 3134.5141503067002, 6115.633062016271, 6329.525285603575, 6360.204974378203, 6396.136521569587, 6415.06981487684, 6228.595141315823, 6274.686265686709, 6465.70904309306, 6505.57713748606, 6511.3814187652215, 5781.855453613673, 5877.3241077276125, 5946.821595352147, 5960.463558279754, 6011.939880601203, 5835.737391817378, 5930.940690593104, 5983.0, 5989.760409583611, 6006.609570377933, 5803.883922321572, 5854.648721076728, 5860.120981852713, 5918.733656985446, 5947.107933809942, 5616.466435688603, 5734.39788822176, 5804.042333015047, 5812.622179558315, 5885.5585831062845, 5753.568482363839, 5796.594238403313, 5803.65178089316, 5815.73829177688, 5902.055671092605, 6301.968490157566, 6322.968385158091, 6379.234491861006, 6404.359564043559, 6439.323870993525, 6778.22050464198, 6778.423833974118, 6810.387065164159, 6868.278830722752, 6919.273476285019, 6618.238902526223, 6740.966295168541, 6779.28817474163, 6838.7264509419565, 6859.176898772179, 6277.937220627827, 6343.333949935237, 6366.204224471941, 6408.67956602168, 6421.711023003976, 6823.1812182538415, 6872.896906546406, 6874.072000279998, 6874.9656251718425, 6936.861262774716, 6613.140291762136, 6647.86704265912, 6781.966090169518, 6786.117804685458, 6831.4534837212905, 6778.0, 6797.762078327235, 6855.760048398282, 6873.621950792654, 6875.449964002868, 6735.326467353323, 6745.763898263635, 6770.052192693027, 6840.931590684029, 6844.1444819397575, 6559.6064236145985, 6698.5980841149685, 6699.899501507482, 6703.229128650268, 6710.865782684319, 5139.331886854759, 5285.1543753000105, 5301.893962120736, 5303.893922121536, 5310.814121505806, 4707.78814953321, 4723.173444647242, 4724.291356296583, 4737.763111844463, 4760.714357138583, 5834.795782147605, 5835.912461313083, 5887.4406931341455, 5901.645901245853, 5914.852128696817, 6656.800295991128, 6810.931890681129, 6827.419669328163, 6889.173299204128, 6903.792886213321, 5637.9718101409235, 5641.915371269434, 5649.971750141223, 5657.632253903483, 5664.26364572603, 5637.3235211774845, 5672.064109421899, 5685.60200785941, 5698.059820129715, 5701.372848986608, 6535.5425120241125, 6640.369164929324, 6674.199096108498, 6674.199096108498, 6678.599284042973, 6845.828854278683, 6849.075374824335, 6930.896036559456, 6938.27148149437, 6946.79159625212, 6433.420992110545, 6597.175353080865, 6599.109120268702, 6670.299618539983, 6694.564853284521, 6685.699143538455, 6718.7312507499155, 6788.626625535643, 6804.76183333581, 6818.8636227276165, 6887.173539175331, 6928.757493487704, 6930.306969303231, 6976.162860456778, 6982.057422248135, 6612.537122401386, 6646.933530664632, 6712.362325579063, 6720.126383570105, 6721.731130754715, 6399.392057754692, 6520.250171230369, 6625.0, 6625.635590042594, 6731.865362692621, 6485.156929599122, 6595.934040659532, 6602.240742314695, 6623.072769812134, 6627.834304142435, 6599.5050371223315, 6611.371919667624, 6665.266820649822, 6762.628055456997, 6774.051632771515, 6437.774677886251, 6492.188476440445, 6512.934870651233, 6528.118703974903, 6531.216254049545], "value": 6189.631509648216, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [847.7202523167354, 873.6199753107398, 883.5802993578047, 884.7168905950099, 895.0557162193887, 40969.18061638769, 42358.788206059005, 42733.57266427335, 42752.786236068816, 42784.0, 38740.225195496074, 38952.61047389532, 39105.60894391055, 39128.0, 39152.216955660944, 6989.881618940958, 7099.0, 7102.786916392516, 7115.5730656160795, 7146.606936618484, 7049.647517624124, 7102.218755936869, 7152.249013853549, 7154.642267886611, 7156.284371562854, 7091.042709234264, 7113.85772284554, 7134.46491513136, 7758.806029849243, 7862.528248305091, 7821.843563128734, 7825.256600622989, 7831.295183433518, 7860.0960889497865, 7880.4089693272945, 7704.46068775186, 7805.219478052206, 7806.453548251625, 7817.726379576688, 7821.960890195513, 38776.80611596952, 39651.801740991395, 39692.0, 39708.60291397078, 39729.20541589181, 3562.1806984393475, 3607.1703508193264, 3610.675039246454, 3616.150204701893, 3635.909102272464, 31618.841905790323, 32010.519842202153, 32069.358612827848, 32074.0, 32119.03642890717, 5083.669561478492, 5122.436531981479, 5122.641415100945, 5129.717865517395, 5141.38303403594, 177351.0, 177934.0, 177986.0, 178970.0, 180147.09926450285, 46911.5308846914, 47126.29310560344, 47401.76299118482, 47413.525864740914, 47464.5253547467, 49236.015279694206, 49321.753391232814, 49556.25665615019, 49743.0, 49784.751076244385, 15588.0, 15669.843301567067, 15872.365105395655, 15928.601784955468, 16112.355505779638, 13029.413676384585, 13222.933885330513, 13249.536266230636, 13269.402876870565, 13308.93345533266, 14503.347349369304, 14542.200178990046, 14547.418103275962, 14702.26488675562, 14795.482158124414, 5707.258056452718, 5860.589758716934, 5868.178455016301, 5892.587518873637, 5940.970295148583, 1010.3230835340339, 1023.6314926626409, 1033.9844902326472, 1041.2555023158477, 1044.8380501022355, 22913.312600622008, 22938.65592016121, 22961.425964351023, 23158.53682926332, 23469.647955280685, 1129.7062763681502, 1135.28477059452, 1140.3556990300453, 1141.8401423800674, 1147.5008371358485, 11948.342841143647, 12059.57791477294, 12204.267743935392, 12291.0, 12303.939699899012, 214425.0, 214612.0, 215516.0, 215552.0, 215978.0, 51736.74131629318, 52099.47900520946, 52122.73938630283, 52183.0, 52184.0, 1287.7295767888847, 1311.3377744239099, 1451.4411951398577, 1475.1960181701, 1488.8362280149174, 26903.730962690122, 27058.864705676344, 27782.0, 27860.44279114447, 28206.85896570504, 36031.63968360283, 36192.0, 36265.27469450543, 36552.63447365492, 36683.63316366802, 176389.1180544089, 176487.0, 178267.0, 178644.0, 178959.0, 32959.176020599676, 34064.31871362608, 34108.10081143031, 34503.4824477628, 34555.30889382248, 42523.574764251964, 42537.78731106325, 42891.57108428876, 43158.05788739451, 43547.56452435435, 30781.538276926312, 31023.68976310208, 31095.0, 31210.375792484476, 31545.8422707885, 31121.377572448877, 31156.0, 31218.0, 31501.8424907874, 31687.0, 34946.0, 35558.28883422265, 35787.64212357947, 35927.46108808422, 36053.45919811152, 30241.697583023888, 30429.391412172074, 30586.694133059274, 30712.84643576768, 30881.382372351978, 22401.887990559942, 22889.542209156058, 23208.76791232066, 23226.419339516648, 23564.882175589013], "value": 16984.44823487473, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.9182622606611, 554.3237250554322, 557.684908026965, 572.7680289482759, 581.5928849805136, 22614.660780088292, 22688.886555567242, 23207.535849283006, 23288.88355558222, 23314.4171395715, 24109.915053822577, 24385.63421548678, 24456.26631201067, 24761.0, 24891.62662560063, 6487.675616219194, 6591.110200122994, 6667.133272674546, 6669.0996715443525, 6672.332766723337, 6549.312322206171, 6604.141461609984, 6627.966860165717, 6633.4693224541925, 6636.469082473391, 6238.376162383771, 6486.967565162167, 6491.642959637218, 6504.609723416586, 6560.573562718408, 6399.168108145957, 6440.420362167404, 6491.350864913518, 6492.7402903883785, 6495.415412612841, 5807.390224026459, 5941.494972927307, 5952.404759524056, 6014.097885317194, 6017.699115044274, 28365.290867728265, 28607.713922860712, 28612.284692882844, 28621.0, 29028.27429314284, 2010.5978804239064, 2037.6128535578341, 2041.1631231195288, 2044.948876278105, 2046.2019812273224, 18647.53381165468, 18982.0, 19307.80692193074, 19370.0, 19463.416097517096, 2446.2294377271282, 2488.776010159076, 2520.206135067466, 2525.368657835541, 2526.431552900601, 119496.0, 123968.0, 124385.0, 136954.6304536952, 137697.0, 14274.643133921734, 14338.928305358406, 14488.179657372897, 14585.854141458662, 14662.575156550201, 21984.780152198593, 22087.66868496974, 22194.334169974605, 22247.221347253093, 22372.328830134797, 9954.0, 10160.898391015995, 10270.845937310947, 10291.125254353314, 10309.793804124025, 9646.855297170549, 9664.516774161404, 9666.613335466644, 9742.512874356255, 9803.803923921625, 8706.259967902799, 8769.824603507894, 8857.778555536035, 8930.955345223232, 8968.417052891542, 2451.656768052474, 2475.344033831036, 2542.084849454195, 2547.312225699051, 2641.6433781439646, 950.9476978766235, 951.695457453622, 966.970990870275, 969.1471505075489, 969.9854502182473, 18203.362882299058, 18264.0, 18425.447236582924, 18576.25694972186, 18580.721289180674, 1007.1841808135433, 1017.2014968250036, 1029.53156313877, 1031.8761748590066, 1034.8085604163255, 10232.846507302396, 10247.846282305622, 10348.32735872181, 10357.585696572203, 10390.324628899098, 411745.0, 433379.0, 439692.0, 442943.0, 443471.5377401828, 99907.50046249868, 105873.47063264635, 108783.0, 110358.0, 111284.0, 1166.2128063557066, 1171.2386948483554, 1183.195427109591, 1186.5135294529202, 1186.9228500147462, 16166.515004549883, 16231.94492357993, 16602.0, 16807.41174058902, 16852.15739213059, 53799.73100134474, 54048.0, 54821.0, 54908.450915490335, 54978.45021549893, 67407.0, 67505.0, 67892.66053669898, 68424.3157568418, 68437.31562684508, 13510.932445338103, 13642.2496762679, 13864.722705546033, 13924.651883702583, 13966.720665586834, 20422.795772042682, 21027.0, 21698.0, 21794.128234870426, 21897.671534927307, 17208.569785755455, 17252.223649936033, 17254.396096136574, 17471.38850140239, 18079.728804068214, 14700.632484187981, 15130.848691512943, 16024.599385015468, 16163.43427980062, 16682.416115435903, 17722.822771772117, 17760.822391775917, 17789.911050444665, 17891.821081789014, 18071.638567228845, 13642.181469111885, 13672.794908076587, 13688.452461901412, 13700.65748356299, 13701.520446784314, 17612.559686007953, 17659.735103973708, 17771.0, 17790.110494474964, 18020.729689054413], "value": 11577.718470700009, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1339.3236415610118, 1390.888728901688, 1403.7192561487698, 1413.392241336225, 1414.6887684709363, 14891.776623350659, 15462.536123916272, 15488.380464781396, 15600.453984110558, 15703.685926281481, 15245.847541524581, 15398.30707618157, 15406.61483462914, 15418.30617622207, 15451.84548154518, 2431.416460049584, 2523.3565440812567, 2527.810414218932, 2531.6708827852444, 2536.606825941982, 2637.7494138056854, 2760.3789147441867, 2763.72362763723, 2764.156932135698, 2778.7082356352594, 2640.8811603477793, 2641.128427618891, 2769.667639883217, 2781.9443611127767, 2787.428577141681, 2769.639946806912, 2791.4138031013613, 2802.2574017885277, 2805.6212411324414, 2813.7467627913484, 2588.4564241509306, 2654.8407095574325, 2675.4916565852427, 2677.4243537639304, 2682.12830829981, 12159.817602735966, 12511.812322815165, 12599.244045357216, 14632.268386580736, 15244.39022439101, 7617.733379331697, 7785.766427007142, 7846.489978151459, 7858.37133029362, 7926.612842752495, 4724.5039270876405, 4998.325226094467, 5035.924461133086, 5077.644564880423, 5091.541761241469, 1906.3327835257687, 1972.960540789176, 1986.2253721048803, 1996.5308152584132, 2023.3323003408839, 26177.345566360804, 26283.999999999807, 26429.3392665183, 26653.46693066147, 26661.46677066448, 6558.278589355167, 6602.2737498875085, 6615.669216539154, 6740.932590674129, 6815.556988795713, 17078.658426831393, 17086.231119599648, 17107.0, 17195.05427201516, 17259.22333494996, 7277.9636101820215, 7379.040724705861, 7392.667329970164, 7399.8150046249275, 7414.073240844895, 6783.219929708147, 6855.520113592101, 7234.927650723531, 7339.412846972229, 7352.926470735224, 5112.488751124858, 5344.839654810362, 5348.277982472317, 5352.705601191894, 5393.946060539344, 1579.6998570271612, 1603.6071162565013, 1605.4793973461553, 1606.309287006575, 1615.4345978907288, 902.9187373136449, 907.3693782821008, 910.485575649769, 922.9077092290718, 931.4085555672197, 7481.102267727799, 8524.31805455562, 8601.397902146915, 8670.002949660744, 8718.084601116918, 686.8488932434856, 716.4698123388655, 718.2601920022386, 733.8478588615822, 739.7004213293625, 4493.370928070073, 4573.153966516205, 5108.080545501845, 5205.531502164823, 5312.229726689686, 20536.281230156874, 21501.247456339264, 21551.78448215529, 21951.780482195292, 21956.341309760734, 20862.687059694115, 21017.68473472899, 21506.46233844135, 21565.0, 21584.136634534443, 10094.19246460296, 10319.96800319977, 10542.262041657164, 10551.20865935058, 10559.577616895393, 8281.964754405699, 8401.915980840358, 8406.28546573548, 8417.915820841714, 8540.274076703301, 18109.728354074963, 18200.908995454938, 18262.452126436227, 18508.0, 18722.906385467984, 18699.06504674788, 18878.905605471886, 18925.716114258572, 19037.90481047586, 19062.714059288843, 10881.673549793519, 10991.230613856953, 11050.171237157085, 11073.833892491459, 11083.778324433628, 13466.730665386833, 13589.32053397346, 13666.385012674255, 13697.0, 13735.0, 10634.627788027381, 10924.617638382619, 11120.666380008613, 11211.887881121085, 11224.270422422516, 11008.834867476835, 11102.111831053659, 11193.608223712132, 11205.943970280423, 11273.887261127284, 9831.262655301025, 9991.700248992542, 10035.24735644815, 10138.290319677551, 10150.49247537635, 6834.453243740588, 6984.126984126984, 7024.824379390556, 7052.365287124184, 7070.964645176741, 6737.3599508046655, 6847.691853866489, 6856.554323968926, 6968.860622787617, 7004.299570042955], "value": 6530.768955343783, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.38978287362886, 504.69756968708754, 506.66560070353563, 514.1234195696338, 524.260792282881, 6136.355682653324, 6201.565890387669, 6201.906971395432, 6277.1839660844025, 6318.368163183685, 6051.969740151293, 6053.334133245339, 6071.483923866476, 6144.754209831602, 6170.845728856781, 4915.139850526161, 5080.517350851682, 5104.183330667085, 5134.358205224347, 5136.743162841843, 5073.036123136592, 5091.389033316007, 5155.432902380735, 5188.16989281715, 5216.800475617127, 5182.352205974254, 5187.118189907725, 5193.221016847466, 5222.63441559091, 5235.5549778268705, 5039.639297389703, 5070.315507406508, 5112.514311140436, 5158.793648254065, 5174.43081261061, 4758.762061896891, 4779.55501383713, 4782.928256076161, 4794.976025119887, 4811.9037619247765, 6243.313235544087, 6245.3442388549, 6248.28144763349, 6267.609855524245, 6286.874262514724, 5884.293884733859, 6005.489533389624, 6017.14185761764, 6026.819195424145, 6054.394560543955, 6552.901706474407, 6560.278369379364, 6660.23407308156, 6664.966675166642, 6681.432078273353, 5193.376794784613, 5214.582833373359, 5216.643672645102, 5233.0, 5242.501962313574, 6861.588304701734, 6925.549839260482, 6949.0, 6954.0, 6973.267806880255, 5367.704776237267, 5396.379416367089, 5409.864753381197, 5437.700926449083, 5494.637305958397, 5426.782928682887, 5461.15352120422, 5469.917951230735, 5492.862678433071, 5504.284443022461, 5976.970115149396, 5988.981873081587, 5995.910061349084, 6033.18551995475, 6034.547408944347, 6431.614103153825, 6622.0, 6698.0, 6717.749553899401, 6742.932570674328, 1834.8899066056078, 1856.2482194710894, 1862.733341327883, 1868.6542989546977, 1895.5734959634249, 1391.8100024479127, 1406.360106151719, 1418.8084608577917, 1426.6148140002142, 1427.700182961569, 5709.600327977002, 5761.596688231783, 5763.510101641324, 5779.450952159538, 5815.069588865846, 6472.773452929125, 6664.566803157779, 6713.462922966151, 6759.194934102293, 6793.422559082434, 5654.74553645087, 5655.321361436654, 5739.827805165852, 5748.770049197985, 5750.5112065474905, 3492.650734926487, 3512.3502152101432, 3548.8947171225177, 3554.804485753308, 3569.214772749991, 6370.554061215763, 6372.203474565679, 6382.936170638327, 6394.488440924715, 6406.935930640727, 6697.330266973264, 6781.728730850809, 6843.7604683835825, 6868.553544019623, 6893.517453778188, 6369.05743748152, 6448.322926092693, 6457.741690332428, 6472.385123413268, 6538.836529086716, 6660.234073081658, 6723.630200339028, 6728.663566821737, 6758.715843989627, 6796.76211332601, 6635.701393437404, 6650.933490665031, 6695.464362851058, 6706.865862682816, 6785.830354241183, 6417.679116044273, 6437.195350581177, 6469.902951455638, 6497.642629655225, 6510.576812507172, 4886.998165376161, 4905.411350637946, 4918.336024636771, 4938.283948827472, 4949.653524253412, 6669.0, 6736.797896063126, 6778.983352408131, 6794.0, 6825.35159159879, 6155.815325540241, 6394.328595497405, 6403.8719225614295, 6413.615183089029, 6422.807315780534, 6391.488680905423, 6427.196600424947, 6468.15913931186, 6499.642519661464, 6546.738130474918, 5923.970380148072, 6245.125682404558, 6322.652254126067, 6345.397187267202, 6395.97664373698, 6561.031690492929, 6571.737130514917, 6572.14562106923, 6573.3426657333885, 6643.60138391698, 6183.288921774054, 6229.096780966644, 6289.685515724104, 6338.841528961812, 6366.9681651591445], "value": 5145.26178751027, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [890.8975467821194, 891.9331050171237, 906.7551761024528, 916.8028873792133, 928.2434815625265, 16854.83145168548, 17583.208755605996, 17845.55386115348, 17882.55293617657, 17885.64228715425, 16566.420175293868, 16982.915085424556, 17323.91338043311, 17364.913175434136, 17371.0, 945.0738276489045, 962.5379817687514, 973.737090985437, 973.8539219117154, 976.1556253840448, 983.7442265011113, 985.0494273026528, 987.0573602209909, 987.244757760314, 990.6285143071349, 978.8189185000762, 1021.9897801021968, 1023.6621914768145, 1026.3687831983314, 1027.0653705128298, 1033.2044325869076, 1033.3231733214757, 1034.1519953637944, 1035.094292494064, 1035.5236591168073, 713.3437237741291, 748.5134662469438, 748.8839229919371, 750.2085300008489, 752.4883079505934, 17504.562385940328, 17677.558061048447, 17694.734578981326, 17709.468715938543, 17750.11249437536, 21390.14439422421, 21560.568788624296, 21870.562588748136, 22005.449863753372, 22241.0, 17081.231344589523, 17206.569835754082, 17222.569435764206, 17314.0, 17556.0, 3657.4148136298063, 3674.3202507536193, 3699.4820725098507, 3715.8699445519546, 3720.8511659533588, 132685.33657331747, 133460.0, 134601.0, 137256.0, 137678.999999999, 15982.920085399499, 16373.181340933144, 16833.91583042077, 16850.32598696063, 16939.830601694073, 29383.853080734887, 29699.703002970127, 30183.396332073673, 30766.53850192249, 30862.845685771877, 7967.163447838009, 8051.114377418479, 8054.476459030145, 8060.596970151469, 8072.556009419421, 3970.781607011584, 4023.8591649292134, 4061.3907913812573, 4069.9389509157386, 4078.5105787305715, 5175.9741201293755, 5181.637285389987, 5210.374755029421, 5224.451432599599, 5248.9737551312, 958.8993155718689, 979.4368238263028, 989.6833013435776, 992.1417973452911, 1002.9448380339005, 692.4495026453981, 695.4123765418213, 713.4363852556526, 718.2601920022386, 719.2843121094544, 10475.319104258351, 11200.183920914085, 11411.885881141248, 11885.643430697093, 12704.555340563036, 558.4694540186839, 563.613924461745, 565.5023579250234, 565.5334349161958, 575.8128608202351, 5392.703401312965, 5407.215953686693, 5480.862978425492, 5486.286782718221, 5588.273524441796, 118574.0, 125944.37027814802, 126321.36839315745, 127618.0, 128565.35717321353, 100864.49567752262, 104523.0, 105646.0, 106370.0, 107290.0, 88433.5578322117, 92715.99999999865, 93915.53042234745, 93973.0, 94394.0560594399, 10118.49407529606, 10167.884850450093, 10168.847467288144, 10198.235132365251, 10307.433091180057, 40998.0, 42326.576734232265, 43655.78172109119, 44283.89290267769, 44851.0, 57614.42385576258, 58009.70995145166, 59088.0, 59344.406555933885, 59346.0, 14497.85502144965, 14696.926515367355, 14751.0, 14835.703285934436, 14959.17724525118, 17124.31502739926, 17305.480835574952, 17504.387346442814, 17606.647867042844, 17811.376601818873, 14366.497172598909, 14468.204248765998, 14565.92717036408, 14708.0, 14731.337089831197, 14168.0, 14353.0, 14370.281485925452, 14452.710945781237, 14575.562733118024, 14767.261636918325, 14883.702325953203, 14999.475018374305, 15135.848641513885, 15168.696626067638, 11896.881031189578, 11944.940275298568, 11993.64019079429, 12291.692707682021, 12351.505939762308, 11132.721681958015, 11382.317060976367, 11483.770324593293, 11545.450914609914, 11613.070954323803], "value": 8634.97531760694, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1453.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 106.0, 121.0, 119.0, 114.0, 115.0, 111.0, 109.0, 108.0, 109.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 118.0, 122.0, 120.0, 117.0, 116.0, 119.0, 118.0, 119.0, 122.0], "value": 120.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 168.0, 167.0, 175.0, 168.0, 164.0, 173.0, 162.0, 165.0, 163.0], "value": 167.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 83.0, 80.0, 80.0, 75.0, 74.0, 76.0, 82.0, 75.0, 76.0], "value": 77.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 84.0, 80.0, 79.0, 78.0, 81.0, 78.0, 79.0, 85.0], "value": 80.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 115.0, 112.0, 124.0, 115.0], "value": 114.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 153.0, 150.0, 154.0, 158.0, 150.0, 147.0, 169.0, 153.0, 150.0], "value": 153.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 104.0, 95.0, 97.0, 94.0, 94.0, 95.0, 104.0, 96.0, 95.0], "value": 97.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 53.0, 57.0, 59.0, 55.0, 55.0, 54.0, 56.0, 52.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [41.0, 39.0, 40.0, 45.0, 39.0, 43.0, 43.0, 45.0, 48.0, 38.0], "value": 42.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 77.0, 75.0, 74.0, 76.0, 75.0, 76.0, 73.0, 76.0, 73.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 112.0, 110.0, 118.0, 117.0, 119.0, 124.0, 116.0, 120.0, 110.0], "value": 116.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 168.0, 176.0, 169.0, 171.0, 174.0, 171.0, 172.0, 186.0, 168.0], "value": 173.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 65.0, 65.0, 66.0, 68.0, 64.0, 66.0, 68.0, 68.0], "value": 66.5, "unit": "score"}]}]} 05:40:10 INFO - Return code: 0 05:40:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:40:10 INFO - # TBPL SUCCESS # 05:40:10 INFO - Running post-action listener: _package_coverage_data 05:40:10 INFO - Running post-action listener: _resource_record_post_action 05:40:10 INFO - [mozharness: 2017-09-13 12:40:10.142485Z] Finished run-tests step (success) 05:40:10 INFO - Running post-run listener: _resource_record_post_run 05:40:10 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 9670656; Write bytes: 333082624; Read time: 6628; Write time: 187848 05:40:10 INFO - TinderboxPrint: CPU usage
13.3% 05:40:10 INFO - TinderboxPrint: I/O read bytes / time
9,670,656 / 6,628 05:40:10 INFO - TinderboxPrint: I/O write bytes / time
333,082,624 / 187,848 05:40:10 INFO - TinderboxPrint: CPU idle
8,262.8 (86.7%) 05:40:10 INFO - TinderboxPrint: CPU user
1,202.6 (12.6%) 05:40:10 INFO - TinderboxPrint: Swap in / out
0 / 0 05:40:10 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13176832; Read time: 0; Write time: 1760 05:40:10 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:40:10 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 9666560; Write bytes: 319905792; Read time: 6612; Write time: 186088 05:40:10 INFO - Running post-run listener: _upload_blobber_files 05:40:10 INFO - Blob upload gear active. 05:40:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:40:10 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 05:40:10 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'] 05:40:10 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 05:40:11 INFO - (blobuploader) - INFO - Open directory for files ... 05:40:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:40:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:40:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:12 INFO - (blobuploader) - INFO - Done attempting. 05:40:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:40:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:40:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:13 INFO - (blobuploader) - INFO - Done attempting. 05:40:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:40:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:40:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:20 INFO - (blobuploader) - INFO - Done attempting. 05:40:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:40:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:40:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:21 INFO - (blobuploader) - INFO - Done attempting. 05:40:21 INFO - (blobuploader) - INFO - Iteration through files over. 05:40:21 INFO - Return code: 0 05:40:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:40:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:40:21 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c7d798064b6c0bde5269a2aef76522e29d99bb9054d3f0d84335135db795c10725555675b5f6477e08888353eee327ab1a5467f1141add059469689b3b2c5b9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55b3bcb7717918a4a45640cf620e75d6c02a9848804b7b136d9e31caaa670f9d9d146949159f18d05e4174c027b7387413f664c9a02490e5fd7e301ca3456c0b", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb860bac9edf4070ac7f37fff76f8bcb49b5370c2ff99f7dc747f9b325df8710b26769459dee1fd3684ae4970e4ade4215639af7f2f0f44388caf2d1baf52c34", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eccfdcb92d26c96e7f3d7e5a14af3919694ab3f7689ba76842ae63598831c4e356f7f3e0c9cb7c193858b02696aca00c7212a52b7173e0b58e5fbf33f6badee5"} 05:40:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:40:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:40:21 INFO - Contents: 05:40:21 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c7d798064b6c0bde5269a2aef76522e29d99bb9054d3f0d84335135db795c10725555675b5f6477e08888353eee327ab1a5467f1141add059469689b3b2c5b9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55b3bcb7717918a4a45640cf620e75d6c02a9848804b7b136d9e31caaa670f9d9d146949159f18d05e4174c027b7387413f664c9a02490e5fd7e301ca3456c0b", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb860bac9edf4070ac7f37fff76f8bcb49b5370c2ff99f7dc747f9b325df8710b26769459dee1fd3684ae4970e4ade4215639af7f2f0f44388caf2d1baf52c34", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eccfdcb92d26c96e7f3d7e5a14af3919694ab3f7689ba76842ae63598831c4e356f7f3e0c9cb7c193858b02696aca00c7212a52b7173e0b58e5fbf33f6badee5"} 05:40:21 INFO - Running post-run listener: copy_logs_to_upload_dir 05:40:21 INFO - Copying logs to upload dir... 05:40:21 INFO - mkdir: /builds/slave/test/build/upload/logs 05:40:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.749234 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-09-13 05:40:21.291850) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 05:40:21.295525) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367 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/8c7d798064b6c0bde5269a2aef76522e29d99bb9054d3f0d84335135db795c10725555675b5f6477e08888353eee327ab1a5467f1141add059469689b3b2c5b9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55b3bcb7717918a4a45640cf620e75d6c02a9848804b7b136d9e31caaa670f9d9d146949159f18d05e4174c027b7387413f664c9a02490e5fd7e301ca3456c0b", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb860bac9edf4070ac7f37fff76f8bcb49b5370c2ff99f7dc747f9b325df8710b26769459dee1fd3684ae4970e4ade4215639af7f2f0f44388caf2d1baf52c34", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eccfdcb92d26c96e7f3d7e5a14af3919694ab3f7689ba76842ae63598831c4e356f7f3e0c9cb7c193858b02696aca00c7212a52b7173e0b58e5fbf33f6badee5"} build_url:https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011497 build_url: 'https://queue.taskcluster.net/v1/task/DkoRCDt5TmCGOf_xf-Pg9A/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c7d798064b6c0bde5269a2aef76522e29d99bb9054d3f0d84335135db795c10725555675b5f6477e08888353eee327ab1a5467f1141add059469689b3b2c5b9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55b3bcb7717918a4a45640cf620e75d6c02a9848804b7b136d9e31caaa670f9d9d146949159f18d05e4174c027b7387413f664c9a02490e5fd7e301ca3456c0b", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb860bac9edf4070ac7f37fff76f8bcb49b5370c2ff99f7dc747f9b325df8710b26769459dee1fd3684ae4970e4ade4215639af7f2f0f44388caf2d1baf52c34", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eccfdcb92d26c96e7f3d7e5a14af3919694ab3f7689ba76842ae63598831c4e356f7f3e0c9cb7c193858b02696aca00c7212a52b7173e0b58e5fbf33f6badee5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 05:40:21.324738) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 05:40:21.325149) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1505299307.447881-1392107367 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004627 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 05:40:21.410519) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 05:40:21.410868) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 05:40:21.411235) ========= ========= Total master_lag: 0.19 =========