builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-004 starttime: 1506191173.81 results: success (0) revision: 2d9d0bebb5c66ba3dfdffd713a888a8c49730631 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:13.810733) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:13.811190) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:13.811493) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:13.829408) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:13.829718) ========= 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=c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-23 11:26:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 94.6M=0s 2017-09-23 11:26:13 (94.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.143928 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:13.992740) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:13.993066) ========= 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=c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016996 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:14.062889) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:14.063207) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2d9d0bebb5c66ba3dfdffd713a888a8c49730631 --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 2d9d0bebb5c66ba3dfdffd713a888a8c49730631 --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=c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-23 11:26:14,131 truncating revision to first 12 chars 2017-09-23 11:26:14,131 Setting DEBUG logging. 2017-09-23 11:26:14,131 attempt 1/10 2017-09-23 11:26:14,131 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2d9d0bebb5c6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-23 11:26:14,507 unpacking tar archive at: mozilla-central-2d9d0bebb5c6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.719662 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:14.824557) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:14.824837) ========= script_repo_revision: 2d9d0bebb5c66ba3dfdffd713a888a8c49730631 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:14.828279) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:14.828550) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:14.918459) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-09-23 11:26:14.918722) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:26:15 INFO - MultiFileLogger online at 20170923 11:26:15 in /builds/slave/test 11:26:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 11:26:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:26:15 INFO - {'append_to_log': False, 11:26:15 INFO - 'base_work_dir': '/builds/slave/test', 11:26:15 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:26:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:26:15 INFO - 'branch': 'Firefox-Non-PGO', 11:26:15 INFO - 'buildbot_json_path': 'buildprops.json', 11:26:15 INFO - 'code_coverage': False, 11:26:15 INFO - 'config_files': ('talos/linux_config.py',), 11:26:15 INFO - 'default_actions': ('clobber', 11:26:15 INFO - 'read-buildbot-config', 11:26:15 INFO - 'download-and-extract', 11:26:15 INFO - 'populate-webroot', 11:26:15 INFO - 'create-virtualenv', 11:26:15 INFO - 'install', 11:26:15 INFO - 'setup-mitmproxy', 11:26:15 INFO - 'run-tests'), 11:26:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:26:15 INFO - 'disable_ccov_upload': False, 11:26:15 INFO - 'disable_stylo': False, 11:26:15 INFO - 'download_minidump_stackwalk': True, 11:26:15 INFO - 'download_symbols': 'ondemand', 11:26:15 INFO - 'enable_stylo': False, 11:26:15 INFO - 'enable_webrender': False, 11:26:15 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:26:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:26:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:26:15 INFO - 'gecko_profile': False, 11:26:15 INFO - 'gecko_profile_interval': 0, 11:26:15 INFO - 'installer_path': 'installer.exe', 11:26:15 INFO - 'log_level': 'info', 11:26:15 INFO - 'log_name': 'talos', 11:26:15 INFO - 'log_to_console': True, 11:26:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:26:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:26:15 INFO - 'opt_config_files': (), 11:26:15 INFO - 'pip_index': False, 11:26:15 INFO - 'suite': 'dromaeojs-e10s', 11:26:15 INFO - 'system_bits': '32', 11:26:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:26:15 INFO - 'title': 'talos-linux64-ix-004', 11:26:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:26:15 INFO - 'use_talos_json': True, 11:26:15 INFO - 'verify': 'False', 11:26:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:26:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:26:15 INFO - 'work_dir': 'build'} 11:26:15 INFO - [mozharness: 2017-09-23 18:26:15.128004Z] Running clobber step. 11:26:15 INFO - Running pre-action listener: _resource_record_pre_action 11:26:15 INFO - Running main action method: clobber 11:26:15 INFO - rmtree: /builds/slave/test/build 11:26:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:26:16 INFO - Running post-action listener: _resource_record_post_action 11:26:16 INFO - [mozharness: 2017-09-23 18:26:16.641956Z] Finished clobber step (success) 11:26:16 INFO - [mozharness: 2017-09-23 18:26:16.642054Z] Running read-buildbot-config step. 11:26:16 INFO - Running pre-action listener: _resource_record_pre_action 11:26:16 INFO - Running main action method: read_buildbot_config 11:26:16 INFO - Using buildbot properties: 11:26:16 INFO - { 11:26:16 INFO - "project": "", 11:26:16 INFO - "product": "firefox", 11:26:16 INFO - "who": "ffxbld@noreply.mozilla.org", 11:26:16 INFO - "installer_path": "public/build/target.tar.bz2", 11:26:16 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:26:16 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:26:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 11:26:16 INFO - "stage_platform": "linux64", 11:26:16 INFO - "basedir": "/builds/slave/test", 11:26:16 INFO - "buildnumber": 859, 11:26:16 INFO - "platform": "ubuntu64_hw", 11:26:16 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:26:16 INFO - "slavebuilddir": "test", 11:26:16 INFO - "taskId": "HTgiwydqTmWmnSMtAtvmCQ", 11:26:16 INFO - "branch": "mozilla-central", 11:26:16 INFO - "script_repo_revision": "production", 11:26:16 INFO - "revision": "2d9d0bebb5c66ba3dfdffd713a888a8c49730631", 11:26:16 INFO - "slavename": "talos-linux64-ix-004", 11:26:16 INFO - "repo_path": "mozilla-central" 11:26:16 INFO - } 11:26:16 INFO - Finding installer, test and symbols from parent task. 11:26:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HTgiwydqTmWmnSMtAtvmCQ'}, attempt #1 11:26:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HTgiwydqTmWmnSMtAtvmCQ'}, attempt #1 11:26:17 INFO - Task dependencies: DNUXLEF0TY6Rkrc0rZZnLg 11:26:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg'}, attempt #1 11:26:18 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.tar.bz2 11:26:18 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.test_packages.json 11:26:18 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.crashreporter-symbols.zip 11:26:18 INFO - Running post-action listener: _resource_record_post_action 11:26:18 INFO - [mozharness: 2017-09-23 18:26:18.025083Z] Finished read-buildbot-config step (success) 11:26:18 INFO - [mozharness: 2017-09-23 18:26:18.025301Z] Running download-and-extract step. 11:26:18 INFO - Running pre-action listener: _resource_record_pre_action 11:26:18 INFO - Running main action method: download_and_extract 11:26:18 INFO - mkdir: /builds/slave/test/build/tests 11:26:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:18 INFO - trying https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.test_packages.json 11:26:18 INFO - Downloading https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:26:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:26:18 INFO - Downloaded 989 bytes. 11:26:18 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:26:18 INFO - Using the following test package requirements: 11:26:18 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:26:18 INFO - u'common': [u'target.common.tests.zip'], 11:26:18 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:26:18 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:26:18 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:26:18 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:26:18 INFO - u'mozbase': [u'target.common.tests.zip'], 11:26:18 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:26:18 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:26:18 INFO - u'web-platform': [u'target.common.tests.zip', 11:26:18 INFO - u'target.web-platform.tests.tar.gz'], 11:26:18 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:26:18 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 11:26:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.common.tests.zip 11:26:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:26:18 INFO - Fetch https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.common.tests.zip into memory 11:26:20 INFO - Content-Length response header: 54943881 11:26:20 INFO - Bytes received: 54943881 11:26:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.talos.tests.zip 11:26:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:26:31 INFO - Fetch https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.talos.tests.zip into memory 11:26:32 INFO - Content-Length response header: 14513823 11:26:32 INFO - Bytes received: 14513823 11:26:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:33 INFO - trying https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.tar.bz2 11:26:33 INFO - Downloading https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:26:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:26:38 INFO - Downloaded 61524879 bytes. 11:26:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.tar.bz2 11:26:38 INFO - mkdir: /builds/slave/test/properties 11:26:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:26:38 INFO - Writing to file /builds/slave/test/properties/build_url 11:26:38 INFO - Contents: 11:26:38 INFO - build_url:https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.tar.bz2 11:26:38 INFO - Running post-action listener: _resource_record_post_action 11:26:38 INFO - Running post-action listener: find_tests_for_verification 11:26:38 INFO - Running post-action listener: set_extra_try_arguments 11:26:38 INFO - [mozharness: 2017-09-23 18:26:38.538418Z] Finished download-and-extract step (success) 11:26:38 INFO - [mozharness: 2017-09-23 18:26:38.538647Z] Running populate-webroot step. 11:26:38 INFO - Running pre-action listener: _resource_record_pre_action 11:26:38 INFO - Running main action method: populate_webroot 11:26:38 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:26:38 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['tresize', 11:26:38 INFO - 'tcanvasmark']}, 11:26:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:26:38 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['dromaeo_css', 11:26:38 INFO - 'kraken']}, 11:26:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:26:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:26:38 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:26:38 INFO - 'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:26:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:26:38 INFO - 'tests': ['damp', 'tps']}, 11:26:38 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:26:38 INFO - 'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['damp', 'tps']}, 11:26:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:26:38 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['dromaeo_dom']}, 11:26:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:26:38 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['basic_compositor_video', 11:26:38 INFO - 'glvideo']}, 11:26:38 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:26:38 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:26:38 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:26:38 INFO - 'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['ts_paint_webext', 11:26:38 INFO - 'tp5o_webext']}, 11:26:38 INFO - 'other-e10s': {'tests': ['a11yr', 11:26:38 INFO - 'ts_paint', 11:26:38 INFO - 'tpaint', 11:26:38 INFO - 'sessionrestore', 11:26:38 INFO - 'sessionrestore_many_windows', 11:26:38 INFO - 'sessionrestore_no_auto_restore', 11:26:38 INFO - 'tabpaint']}, 11:26:38 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['a11yr', 11:26:38 INFO - 'ts_paint', 11:26:38 INFO - 'tpaint', 11:26:38 INFO - 'sessionrestore', 11:26:38 INFO - 'sessionrestore_many_windows', 11:26:38 INFO - 'sessionrestore_no_auto_restore', 11:26:38 INFO - 'tabpaint']}, 11:26:38 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 11:26:38 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 11:26:38 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['perf_reftest_singletons']}, 11:26:38 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['perf_reftest']}, 11:26:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:26:38 INFO - 'tsvgr_opacity', 11:26:38 INFO - 'tart', 11:26:38 INFO - 'tscrollx', 11:26:38 INFO - 'tsvg_static']}, 11:26:38 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['tsvgx', 11:26:38 INFO - 'tsvgr_opacity', 11:26:38 INFO - 'tart', 11:26:38 INFO - 'tscrollx', 11:26:38 INFO - 'tsvg_static']}, 11:26:38 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:26:38 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:26:38 INFO - 'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['tp5o']}, 11:26:38 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:26:38 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:26:38 INFO - 'talos_options': ['--mitmproxy', 11:26:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:26:38 INFO - '--firstNonBlankPaint'], 11:26:38 INFO - 'tests': ['tp6_google', 11:26:38 INFO - 'tp6_youtube', 11:26:38 INFO - 'tp6_amazon', 11:26:38 INFO - 'tp6_facebook']}, 11:26:38 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:26:38 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:26:38 INFO - 'talos_options': ['--disable-stylo', 11:26:38 INFO - '--mitmproxy', 11:26:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:26:38 INFO - '--firstNonBlankPaint'], 11:26:38 INFO - 'tests': ['tp6_google', 11:26:38 INFO - 'tp6_youtube', 11:26:38 INFO - 'tp6_amazon', 11:26:38 INFO - 'tp6_facebook']}, 11:26:38 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:26:38 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:26:38 INFO - 'talos_options': ['--stylo-threads=1', 11:26:38 INFO - '--mitmproxy', 11:26:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:26:38 INFO - '--firstNonBlankPaint'], 11:26:38 INFO - 'tests': ['tp6_google', 11:26:38 INFO - 'tp6_youtube', 11:26:38 INFO - 'tp6_amazon', 11:26:38 INFO - 'tp6_facebook']}, 11:26:38 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:26:38 INFO - 'talos_options': ['--xperf_path', 11:26:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:26:38 INFO - 'tests': ['tp5n']}, 11:26:38 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:26:38 INFO - 'talos_options': ['--disable-stylo', 11:26:38 INFO - '--xperf_path', 11:26:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:26:38 INFO - 'tests': ['tp5n']}}} 11:26:38 INFO - Running post-action listener: _resource_record_post_action 11:26:38 INFO - [mozharness: 2017-09-23 18:26:38.562813Z] Finished populate-webroot step (success) 11:26:38 INFO - [mozharness: 2017-09-23 18:26:38.562903Z] Running create-virtualenv step. 11:26:38 INFO - Running pre-action listener: _resource_record_pre_action 11:26:38 INFO - Running main action method: create_virtualenv 11:26:38 INFO - Creating virtualenv /builds/slave/test/build/venv 11:26:38 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:26:38 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:26:38 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:26:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:26:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:26:39 INFO - Installing distribute.............................................................................................................................................................................................done. 11:26:40 INFO - Installing pip...............done. 11:26:40 INFO - Return code: 0 11:26:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:26:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:26:40 INFO - Reading from file tmpfile_stdout 11:26:40 INFO - Output received: 11:26:40 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:26:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:40 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 0x22961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26039c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2600d60>, '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 0x2601a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2601ef0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:26:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:26:40 INFO - Using env: {'DISPLAY': ':0', 11:26:40 INFO - 'HOME': '/home/cltbld', 11:26:40 INFO - 'LANG': 'en_US.UTF-8', 11:26:40 INFO - 'LANGUAGE': 'en_US:en', 11:26:40 INFO - 'LOGNAME': 'cltbld', 11:26:40 INFO - 'MAIL': '/var/mail/cltbld', 11:26:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:40 INFO - 'MOZ_NO_REMOTE': '1', 11:26:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:40 INFO - 'NO_EM_RESTART': '1', 11:26:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:40 INFO - 'PWD': '/builds/slave/test', 11:26:40 INFO - 'SHELL': '/bin/bash', 11:26:40 INFO - 'SHLVL': '1', 11:26:40 INFO - 'TERM': 'linux', 11:26:40 INFO - 'TMOUT': '86400', 11:26:40 INFO - 'USER': 'cltbld', 11:26:40 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 11:26:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:40 INFO - '_': '/tools/buildbot/bin/python'} 11:26:43 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:26:43 INFO - Downloading/unpacking pip>=1.5 11:26:43 INFO - Running setup.py egg_info for package pip 11:26:43 INFO - warning: no files found matching 'pip/cacert.pem' 11:26:43 INFO - warning: no files found matching '*.html' under directory 'docs' 11:26:43 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:26:43 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:26:43 INFO - Installing collected packages: pip 11:26:43 INFO - Found existing installation: pip 1.1 11:26:43 INFO - Uninstalling pip: 11:26:43 INFO - Successfully uninstalled pip 11:26:43 INFO - Running setup.py install for pip 11:26:43 INFO - warning: no files found matching 'pip/cacert.pem' 11:26:43 INFO - warning: no files found matching '*.html' under directory 'docs' 11:26:43 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:26:43 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:26:43 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:26:43 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:26:43 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:26:43 INFO - Successfully installed pip 11:26:43 INFO - Cleaning up... 11:26:43 INFO - Return code: 0 11:26:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x22961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26039c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2600d60>, '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 0x2601a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2601ef0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:26:43 INFO - Using env: {'DISPLAY': ':0', 11:26:43 INFO - 'HOME': '/home/cltbld', 11:26:43 INFO - 'LANG': 'en_US.UTF-8', 11:26:43 INFO - 'LANGUAGE': 'en_US:en', 11:26:43 INFO - 'LOGNAME': 'cltbld', 11:26:43 INFO - 'MAIL': '/var/mail/cltbld', 11:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:43 INFO - 'MOZ_NO_REMOTE': '1', 11:26:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:43 INFO - 'NO_EM_RESTART': '1', 11:26:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:43 INFO - 'PWD': '/builds/slave/test', 11:26:43 INFO - 'SHELL': '/bin/bash', 11:26:43 INFO - 'SHLVL': '1', 11:26:43 INFO - 'TERM': 'linux', 11:26:43 INFO - 'TMOUT': '86400', 11:26:43 INFO - 'USER': 'cltbld', 11:26:43 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 11:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:43 INFO - '_': '/tools/buildbot/bin/python'} 11:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:43 INFO - Downloading/unpacking psutil>=3.1.1 11:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:26:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:26:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:26:44 INFO - Installing collected packages: psutil 11:26:44 INFO - Running setup.py install for psutil 11:26:44 INFO - building 'psutil._psutil_linux' extension 11:26:44 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 11:26:45 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:26:45 INFO - building 'psutil._psutil_posix' extension 11:26:45 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 11:26:45 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 11:26:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:26:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:26:45 INFO - Successfully installed psutil 11:26:45 INFO - Cleaning up... 11:26:45 INFO - Return code: 0 11:26:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:45 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 0x22961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26039c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2600d60>, '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 0x2601a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2601ef0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:26:45 INFO - Using env: {'DISPLAY': ':0', 11:26:45 INFO - 'HOME': '/home/cltbld', 11:26:45 INFO - 'LANG': 'en_US.UTF-8', 11:26:45 INFO - 'LANGUAGE': 'en_US:en', 11:26:45 INFO - 'LOGNAME': 'cltbld', 11:26:45 INFO - 'MAIL': '/var/mail/cltbld', 11:26:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:45 INFO - 'MOZ_NO_REMOTE': '1', 11:26:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:45 INFO - 'NO_EM_RESTART': '1', 11:26:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:45 INFO - 'PWD': '/builds/slave/test', 11:26:45 INFO - 'SHELL': '/bin/bash', 11:26:45 INFO - 'SHLVL': '1', 11:26:45 INFO - 'TERM': 'linux', 11:26:45 INFO - 'TMOUT': '86400', 11:26:45 INFO - 'USER': 'cltbld', 11:26:45 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 11:26:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:45 INFO - '_': '/tools/buildbot/bin/python'} 11:26:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:26:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:26:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:26:46 INFO - Installing collected packages: mozsystemmonitor 11:26:46 INFO - Running setup.py install for mozsystemmonitor 11:26:46 INFO - Successfully installed mozsystemmonitor 11:26:46 INFO - Cleaning up... 11:26:46 INFO - Return code: 0 11:26:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:26:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:46 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 0x22961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26039c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2600d60>, '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 0x2601a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2601ef0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:26:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:26:46 INFO - Using env: {'DISPLAY': ':0', 11:26:46 INFO - 'HOME': '/home/cltbld', 11:26:46 INFO - 'LANG': 'en_US.UTF-8', 11:26:46 INFO - 'LANGUAGE': 'en_US:en', 11:26:46 INFO - 'LOGNAME': 'cltbld', 11:26:46 INFO - 'MAIL': '/var/mail/cltbld', 11:26:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:46 INFO - 'MOZ_NO_REMOTE': '1', 11:26:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:46 INFO - 'NO_EM_RESTART': '1', 11:26:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:46 INFO - 'PWD': '/builds/slave/test', 11:26:46 INFO - 'SHELL': '/bin/bash', 11:26:46 INFO - 'SHLVL': '1', 11:26:46 INFO - 'TERM': 'linux', 11:26:46 INFO - 'TMOUT': '86400', 11:26:46 INFO - 'USER': 'cltbld', 11:26:46 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 11:26:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:46 INFO - '_': '/tools/buildbot/bin/python'} 11:26:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:47 INFO - Downloading/unpacking jsonschema==2.5.1 11:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:26:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:48 INFO - Downloading functools32-3.2.3-2.tar.gz 11:26:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:26:48 INFO - warning: no files found matching '*.txt' 11:26:48 INFO - no previously-included directories found matching 'build' 11:26:48 INFO - no previously-included directories found matching 'dist' 11:26:48 INFO - no previously-included directories found matching '.git*' 11:26:48 INFO - Installing collected packages: jsonschema, functools32 11:26:48 INFO - Running setup.py install for functools32 11:26:48 INFO - warning: no files found matching '*.txt' 11:26:48 INFO - no previously-included directories found matching 'build' 11:26:48 INFO - no previously-included directories found matching 'dist' 11:26:48 INFO - no previously-included directories found matching '.git*' 11:26:48 INFO - Successfully installed jsonschema functools32 11:26:48 INFO - Cleaning up... 11:26:48 INFO - Return code: 0 11:26:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:26: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')]} 11:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26: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')]} 11:26:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x22961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26039c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2600d60>, '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 0x2601a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2601ef0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:26:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:26:48 INFO - Using env: {'DISPLAY': ':0', 11:26:48 INFO - 'HOME': '/home/cltbld', 11:26:48 INFO - 'LANG': 'en_US.UTF-8', 11:26:48 INFO - 'LANGUAGE': 'en_US:en', 11:26:48 INFO - 'LOGNAME': 'cltbld', 11:26:48 INFO - 'MAIL': '/var/mail/cltbld', 11:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:48 INFO - 'MOZ_NO_REMOTE': '1', 11:26:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:48 INFO - 'NO_EM_RESTART': '1', 11:26:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:48 INFO - 'PWD': '/builds/slave/test', 11:26:48 INFO - 'SHELL': '/bin/bash', 11:26:48 INFO - 'SHLVL': '1', 11:26:48 INFO - 'TERM': 'linux', 11:26:48 INFO - 'TMOUT': '86400', 11:26:48 INFO - 'USER': 'cltbld', 11:26:48 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 11:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:48 INFO - '_': '/tools/buildbot/bin/python'} 11:26:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:26:48 INFO - Cleaning up... 11:26:48 INFO - Return code: 0 11:26:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:26: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')]} 11:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26: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')]} 11:26:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x22961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26039c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2600d60>, '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 0x2601a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2601ef0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:26:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:26:48 INFO - Using env: {'DISPLAY': ':0', 11:26:48 INFO - 'HOME': '/home/cltbld', 11:26:48 INFO - 'LANG': 'en_US.UTF-8', 11:26:48 INFO - 'LANGUAGE': 'en_US:en', 11:26:48 INFO - 'LOGNAME': 'cltbld', 11:26:48 INFO - 'MAIL': '/var/mail/cltbld', 11:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:48 INFO - 'MOZ_NO_REMOTE': '1', 11:26:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:48 INFO - 'NO_EM_RESTART': '1', 11:26:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:48 INFO - 'PWD': '/builds/slave/test', 11:26:48 INFO - 'SHELL': '/bin/bash', 11:26:48 INFO - 'SHLVL': '1', 11:26:48 INFO - 'TERM': 'linux', 11:26:48 INFO - 'TMOUT': '86400', 11:26:48 INFO - 'USER': 'cltbld', 11:26:48 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 11:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:48 INFO - '_': '/tools/buildbot/bin/python'} 11:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:49 INFO - Downloading/unpacking blobuploader==1.2.4 11:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:50 INFO - Downloading blobuploader-1.2.4.tar.gz 11:26:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:26:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:26:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:50 INFO - Downloading docopt-0.6.1.tar.gz 11:26:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:26:50 INFO - Installing collected packages: blobuploader, requests, docopt 11:26:50 INFO - Running setup.py install for blobuploader 11:26:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:26:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:26:50 INFO - Running setup.py install for requests 11:26:51 INFO - Running setup.py install for docopt 11:26:51 INFO - Successfully installed blobuploader requests docopt 11:26:51 INFO - Cleaning up... 11:26:51 INFO - Return code: 0 11:26:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:51 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 0x22961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26039c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2600d60>, '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 0x2601a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2601ef0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:26:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:26:51 INFO - Using env: {'DISPLAY': ':0', 11:26:51 INFO - 'HOME': '/home/cltbld', 11:26:51 INFO - 'LANG': 'en_US.UTF-8', 11:26:51 INFO - 'LANGUAGE': 'en_US:en', 11:26:51 INFO - 'LOGNAME': 'cltbld', 11:26:51 INFO - 'MAIL': '/var/mail/cltbld', 11:26:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:51 INFO - 'MOZ_NO_REMOTE': '1', 11:26:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:51 INFO - 'NO_EM_RESTART': '1', 11:26:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:51 INFO - 'PWD': '/builds/slave/test', 11:26:51 INFO - 'SHELL': '/bin/bash', 11:26:51 INFO - 'SHLVL': '1', 11:26:51 INFO - 'TERM': 'linux', 11:26:51 INFO - 'TMOUT': '86400', 11:26:51 INFO - 'USER': 'cltbld', 11:26:51 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 11:26:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:51 INFO - '_': '/tools/buildbot/bin/python'} 11:26:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:26:51 INFO - Running setup.py (path:/tmp/pip-jnH6rA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:26:51 INFO - Running setup.py (path:/tmp/pip-AlsH5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:26:51 INFO - Running setup.py (path:/tmp/pip-EMoqsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:26:51 INFO - Running setup.py (path:/tmp/pip-bNfm1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:26:51 INFO - Running setup.py (path:/tmp/pip-J4wE4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:26:51 INFO - Running setup.py (path:/tmp/pip-xLGlpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:26:51 INFO - Running setup.py (path:/tmp/pip-y_rE01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:26:52 INFO - Running setup.py (path:/tmp/pip-2VkrUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:26:52 INFO - Running setup.py (path:/tmp/pip-CcsiYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:26:52 INFO - Running setup.py (path:/tmp/pip-g77HaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:26:52 INFO - Running setup.py (path:/tmp/pip-REyAvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:26:52 INFO - Running setup.py (path:/tmp/pip-0HfZ72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:26:52 INFO - Running setup.py (path:/tmp/pip-W9Dn0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:26:52 INFO - Running setup.py (path:/tmp/pip-aZIahE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:52 INFO - Running setup.py (path:/tmp/pip-m4Gn3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:26:52 INFO - Running setup.py (path:/tmp/pip-D4llYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:26:52 INFO - Running setup.py (path:/tmp/pip-KZ0uPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:26:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:26:52 INFO - Running setup.py install for manifestparser 11:26:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:26:52 INFO - Running setup.py install for mozcrash 11:26:52 INFO - Running setup.py install for mozdebug 11:26:53 INFO - Running setup.py install for mozdevice 11:26:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:26:53 INFO - Running setup.py install for mozfile 11:26:53 INFO - Running setup.py install for mozhttpd 11:26:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:26:53 INFO - Running setup.py install for mozinfo 11:26:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:26:53 INFO - Running setup.py install for mozInstall 11:26:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:26:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:26:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:26:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:26:53 INFO - Running setup.py install for mozleak 11:26:53 INFO - Running setup.py install for mozlog 11:26:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:26:53 INFO - Running setup.py install for moznetwork 11:26:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:26:53 INFO - Running setup.py install for mozprocess 11:26:53 INFO - Running setup.py install for mozprofile 11:26:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:26:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:26:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:26:54 INFO - Running setup.py install for mozrunner 11:26:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:26:54 INFO - Running setup.py install for mozscreenshot 11:26:54 INFO - Running setup.py install for moztest 11:26:54 INFO - Running setup.py install for mozversion 11:26:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:26:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:26:54 INFO - Cleaning up... 11:26:54 INFO - Return code: 0 11:26:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:54 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 0x22961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26039c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2600d60>, '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 0x2601a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2601ef0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:26:54 INFO - Using env: {'DISPLAY': ':0', 11:26:54 INFO - 'HOME': '/home/cltbld', 11:26:54 INFO - 'LANG': 'en_US.UTF-8', 11:26:54 INFO - 'LANGUAGE': 'en_US:en', 11:26:54 INFO - 'LOGNAME': 'cltbld', 11:26:54 INFO - 'MAIL': '/var/mail/cltbld', 11:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:54 INFO - 'MOZ_NO_REMOTE': '1', 11:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:54 INFO - 'NO_EM_RESTART': '1', 11:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:54 INFO - 'PWD': '/builds/slave/test', 11:26:54 INFO - 'SHELL': '/bin/bash', 11:26:54 INFO - 'SHLVL': '1', 11:26:54 INFO - 'TERM': 'linux', 11:26:54 INFO - 'TMOUT': '86400', 11:26:54 INFO - 'USER': 'cltbld', 11:26:54 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 11:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:54 INFO - '_': '/tools/buildbot/bin/python'} 11:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:26:54 INFO - Running setup.py (path:/tmp/pip-zyyi41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:26:54 INFO - Running setup.py (path:/tmp/pip-KXaXI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:26:54 INFO - Running setup.py (path:/tmp/pip-5tYhWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:26:54 INFO - Running setup.py (path:/tmp/pip-g_1YLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:26:55 INFO - Running setup.py (path:/tmp/pip-nH4ubI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:26:55 INFO - Running setup.py (path:/tmp/pip-nKc9_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:26:55 INFO - Running setup.py (path:/tmp/pip-Gdc0c_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:26:55 INFO - Running setup.py (path:/tmp/pip-Imu0Eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:26:55 INFO - Running setup.py (path:/tmp/pip-VltORk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:26:55 INFO - Running setup.py (path:/tmp/pip-Ze9KI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:26:55 INFO - Running setup.py (path:/tmp/pip-gPEc0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:26:55 INFO - Running setup.py (path:/tmp/pip-6TJoXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:26:55 INFO - Running setup.py (path:/tmp/pip-bAXCcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:26:55 INFO - Running setup.py (path:/tmp/pip-jqjqw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:55 INFO - Running setup.py (path:/tmp/pip-XWQa1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:26:55 INFO - Running setup.py (path:/tmp/pip-m0mxY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:26:55 INFO - Running setup.py (path:/tmp/pip-G9_1_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:26:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:57 INFO - Downloading blessings-1.6.tar.gz 11:26:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:26:57 INFO - Installing collected packages: blessings 11:26:57 INFO - Running setup.py install for blessings 11:26:57 INFO - Successfully installed blessings 11:26:57 INFO - Cleaning up... 11:26:57 INFO - Return code: 0 11:26:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:26:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:26:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:26:57 INFO - Reading from file tmpfile_stdout 11:26:57 INFO - Current package versions: 11:26:57 INFO - argparse == 1.2.1 11:26:57 INFO - blessings == 1.6 11:26:57 INFO - blobuploader == 1.2.4 11:26:57 INFO - docopt == 0.6.1 11:26:57 INFO - functools32 == 3.2.3-2 11:26:57 INFO - jsonschema == 2.5.1 11:26:57 INFO - manifestparser == 1.1 11:26:57 INFO - mozInstall == 1.13 11:26:57 INFO - mozcrash == 1.0 11:26:57 INFO - mozdebug == 0.1 11:26:57 INFO - mozdevice == 0.51 11:26:57 INFO - mozfile == 1.2 11:26:57 INFO - mozhttpd == 0.7 11:26:57 INFO - mozinfo == 0.10 11:26:57 INFO - mozleak == 0.1 11:26:57 INFO - mozlog == 3.5 11:26:57 INFO - moznetwork == 0.27 11:26:57 INFO - mozprocess == 0.25 11:26:57 INFO - mozprofile == 0.28 11:26:57 INFO - mozrunner == 6.13 11:26:57 INFO - mozscreenshot == 0.1 11:26:57 INFO - mozsystemmonitor == 0.3 11:26:57 INFO - moztest == 0.8 11:26:57 INFO - mozversion == 1.4 11:26:57 INFO - psutil == 3.1.1 11:26:57 INFO - requests == 1.2.3 11:26:57 INFO - wsgiref == 0.1.2 11:26:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26: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')]} 11:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26: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')]} 11:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:57 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 0x22961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26039c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2600d60>, '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 0x2601a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2601ef0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:26:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:26:57 INFO - Using env: {'DISPLAY': ':0', 11:26:57 INFO - 'HOME': '/home/cltbld', 11:26:57 INFO - 'LANG': 'en_US.UTF-8', 11:26:57 INFO - 'LANGUAGE': 'en_US:en', 11:26:57 INFO - 'LOGNAME': 'cltbld', 11:26:57 INFO - 'MAIL': '/var/mail/cltbld', 11:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:57 INFO - 'MOZ_NO_REMOTE': '1', 11:26:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:57 INFO - 'NO_EM_RESTART': '1', 11:26:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:57 INFO - 'PWD': '/builds/slave/test', 11:26:57 INFO - 'SHELL': '/bin/bash', 11:26:57 INFO - 'SHLVL': '1', 11:26:57 INFO - 'TERM': 'linux', 11:26:57 INFO - 'TMOUT': '86400', 11:26:57 INFO - 'USER': 'cltbld', 11:26:57 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 11:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:57 INFO - '_': '/tools/buildbot/bin/python'} 11:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:26:57 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:26: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 11:26: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 11:26: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 11:26: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 11:26:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:26:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:26:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:26:58 INFO - Installing collected packages: simplejson 11:26:58 INFO - Running setup.py install for simplejson 11:26:59 INFO - building 'simplejson._speedups' extension 11:26:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 11:26:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 11:26:59 INFO - Successfully installed simplejson 11:26:59 INFO - Cleaning up... 11:26:59 INFO - Return code: 0 11:26:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:59 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 0x22961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26039c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2600d60>, '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 0x2601a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2601ef0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:26:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:26:59 INFO - Using env: {'DISPLAY': ':0', 11:26:59 INFO - 'HOME': '/home/cltbld', 11:26:59 INFO - 'LANG': 'en_US.UTF-8', 11:26:59 INFO - 'LANGUAGE': 'en_US:en', 11:26:59 INFO - 'LOGNAME': 'cltbld', 11:26:59 INFO - 'MAIL': '/var/mail/cltbld', 11:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:59 INFO - 'MOZ_NO_REMOTE': '1', 11:26:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:59 INFO - 'NO_EM_RESTART': '1', 11:26:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:59 INFO - 'PWD': '/builds/slave/test', 11:26:59 INFO - 'SHELL': '/bin/bash', 11:26:59 INFO - 'SHLVL': '1', 11:26:59 INFO - 'TERM': 'linux', 11:26:59 INFO - 'TMOUT': '86400', 11:26:59 INFO - 'USER': 'cltbld', 11:26:59 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 11:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:59 INFO - '_': '/tools/buildbot/bin/python'} 11:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:27:00 INFO - Cleaning up... 11:27:00 INFO - Return code: 0 11:27:00 INFO - Running post-action listener: _resource_record_post_action 11:27:00 INFO - Running post-action listener: _start_resource_monitoring 11:27:00 INFO - Starting resource monitoring. 11:27:00 INFO - [mozharness: 2017-09-23 18:27:00.229239Z] Finished create-virtualenv step (success) 11:27:00 INFO - [mozharness: 2017-09-23 18:27:00.229752Z] Running install step. 11:27:00 INFO - Running pre-action listener: _resource_record_pre_action 11:27:00 INFO - Running main action method: install 11:27:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:27:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:27:00 INFO - Reading from file tmpfile_stdout 11:27:00 INFO - Detecting whether we're running mozinstall >=1.0... 11:27:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:27:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:27:00 INFO - Reading from file tmpfile_stdout 11:27:00 INFO - Output received: 11:27:00 INFO - Usage: mozinstall [options] installer 11:27:00 INFO - Options: 11:27:00 INFO - -h, --help show this help message and exit 11:27:00 INFO - -d DEST, --destination=DEST 11:27:00 INFO - Directory to install application into. [default: 11:27:00 INFO - "/builds/slave/test"] 11:27:00 INFO - --app=APP Application being installed. [default: firefox] 11:27:00 INFO - mkdir: /builds/slave/test/build/application 11:27:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:27:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:27:18 INFO - Reading from file tmpfile_stdout 11:27:18 INFO - Output received: 11:27:18 INFO - /builds/slave/test/build/application/firefox/firefox 11:27:18 INFO - Running post-action listener: _resource_record_post_action 11:27:18 INFO - [mozharness: 2017-09-23 18:27:18.374230Z] Finished install step (success) 11:27:18 INFO - [mozharness: 2017-09-23 18:27:18.374482Z] Running setup-mitmproxy step. 11:27:18 INFO - Running pre-action listener: _resource_record_pre_action 11:27:18 INFO - Running main action method: setup_mitmproxy 11:27:18 INFO - Skipping: mitmproxy is not required 11:27:18 INFO - Running post-action listener: _resource_record_post_action 11:27:18 INFO - [mozharness: 2017-09-23 18:27:18.375611Z] Finished setup-mitmproxy step (success) 11:27:18 INFO - [mozharness: 2017-09-23 18:27:18.375832Z] Running run-tests step. 11:27:18 INFO - Running pre-action listener: _resource_record_pre_action 11:27:18 INFO - Running pre-action listener: _set_gcov_prefix 11:27:18 INFO - Running main action method: run_tests 11:27:18 WARNING - Try message not found. 11:27:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:27:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:27:18 INFO - Python 2.7.3 11:27:18 INFO - Return code: 0 11:27:18 INFO - grabbing minidump binary from tooltool 11:27:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:18 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 0x2600d60>, '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 0x2601a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2601ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:27:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:27:18 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 11:27:18 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:27:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:27:18 INFO - Return code: 0 11:27:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:27:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:27:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:27:18 INFO - ENV: RUST_BACKTRACE is now full 11:27:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:27:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:27:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:27:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-004 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:27:18 INFO - Using env: {'DISPLAY': ':0', 11:27:18 INFO - 'HOME': '/home/cltbld', 11:27:18 INFO - 'LANG': 'en_US.UTF-8', 11:27:18 INFO - 'LANGUAGE': 'en_US:en', 11:27:18 INFO - 'LOGNAME': 'cltbld', 11:27:18 INFO - 'MAIL': '/var/mail/cltbld', 11:27:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:27:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:27:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:18 INFO - 'MOZ_NO_REMOTE': '1', 11:27:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:27:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:18 INFO - 'NO_EM_RESTART': '1', 11:27:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:18 INFO - 'PWD': '/builds/slave/test', 11:27:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:27:18 INFO - 'RUST_BACKTRACE': 'full', 11:27:18 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:27:18 INFO - 'SHELL': '/bin/bash', 11:27:18 INFO - 'SHLVL': '1', 11:27:18 INFO - 'TERM': 'linux', 11:27:18 INFO - 'TMOUT': '86400', 11:27:18 INFO - 'USER': 'cltbld', 11:27:18 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 11:27:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:18 INFO - '_': '/tools/buildbot/bin/python'} 11:27:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:27:18 INFO - mozversion application_buildid: 20170923173344 11:27:18 INFO - mozversion application_changeset: 2d9d0bebb5c66ba3dfdffd713a888a8c49730631 11:27:18 INFO - mozversion application_display_name: Nightly 11:27:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:27:18 INFO - mozversion application_name: Firefox 11:27:18 INFO - mozversion application_remotingname: firefox 11:27:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:27:18 INFO - mozversion application_vendor: Mozilla 11:27:18 INFO - mozversion application_version: 58.0a1 11:27:18 INFO - mozversion platform_buildid: 20170923173344 11:27:18 INFO - mozversion platform_changeset: 2d9d0bebb5c66ba3dfdffd713a888a8c49730631 11:27:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:27:18 INFO - mozversion platform_version: 58.0a1 11:27:18 INFO - using testdate: 1506191238 11:27:18 INFO - actual date: 1506191238 11:27:18 INFO - starting webserver on 'localhost:54226' 11:27:18 INFO - SUITE-START | Running 2 tests 11:27:18 INFO - TEST-START | dromaeo_css 11:27:18 INFO - Initialising browser for dromaeo_css test... 11:27:18 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54226/getInfo.html -profile /tmp/tmpLT5IKJ/profile 11:27:18 INFO - TEST-INFO | started process 17573 (/builds/slave/test/build/application/firefox/firefox http://localhost:54226/getInfo.html) 11:27:24 INFO - TEST-INFO | 17573: exit 0 11:27:24 INFO - Browser initialized. 11:27:24 INFO - Running cycle 1/1 for dromaeo_css test... 11:27:24 INFO - Using env: {'DISPLAY': ':0', 11:27:24 INFO - 'HOME': '/home/cltbld', 11:27:24 INFO - 'JSGC_DISABLE_POISONING': '1', 11:27:24 INFO - 'LANG': 'en_US.UTF-8', 11:27:24 INFO - 'LANGUAGE': 'en_US:en', 11:27:24 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:27:24 INFO - 'LOGNAME': 'cltbld', 11:27:24 INFO - 'MAIL': '/var/mail/cltbld', 11:27:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:27:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:27:24 INFO - 'MOZ_CRASHREPORTER': '1', 11:27:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:24 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:27:24 INFO - 'MOZ_NO_REMOTE': '1', 11:27:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:27:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:24 INFO - 'NO_EM_RESTART': '1', 11:27:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:24 INFO - 'PWD': '/builds/slave/test', 11:27:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:27:24 INFO - 'RUST_BACKTRACE': 'full', 11:27:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:27:24 INFO - 'SHELL': '/bin/bash', 11:27:24 INFO - 'SHLVL': '1', 11:27:24 INFO - 'TERM': 'linux', 11:27:24 INFO - 'TMOUT': '86400', 11:27:24 INFO - 'USER': 'cltbld', 11:27:24 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 11:27:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:24 INFO - '_': '/tools/buildbot/bin/python'} 11:27:24 INFO - TEST-INFO | started process 17808 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLT5IKJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:27:25 INFO - PID 17808 | 11:27:25 INFO - PID 17808 | (/builds/slave/test/build/application/firefox/firefox:17857): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:27:25 INFO - PID 17808 | 11:27:35 INFO - PID 17808 | RSS: Main: 153149440 11:27:35 INFO - PID 17808 | 11:30:32 INFO - PID 17808 | Cycle 1(1): loaded http://localhost:54226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54226/tests/dromaeo/cssquery-ext.html) 11:30:32 INFO - PID 17808 | RSS: Main: 154021888 11:30:32 INFO - PID 17808 | 11:33:34 INFO - PID 17808 | Cycle 1(1): loaded http://localhost:54226/tests/dromaeo/cssquery-ext.html (next: http://localhost:54226/tests/dromaeo/cssquery-jquery.html) 11:33:35 INFO - PID 17808 | RSS: Main: 154116096 11:33:35 INFO - PID 17808 | 11:36:37 INFO - PID 17808 | Cycle 1(1): loaded http://localhost:54226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54226/tests/dromaeo/cssquery-mootools.html) 11:36:37 INFO - PID 17808 | RSS: Main: 154353664 11:36:37 INFO - PID 17808 | 11:39:39 INFO - PID 17808 | Cycle 1(1): loaded http://localhost:54226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54226/tests/dromaeo/cssquery-prototype.html) 11:39:39 INFO - PID 17808 | RSS: Main: 154550272 11:39:39 INFO - PID 17808 | 11:42:43 INFO - PID 17808 | Cycle 1(1): loaded http://localhost:54226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54226/tests/dromaeo/cssquery-yui.html) 11:42:43 INFO - PID 17808 | RSS: Main: 155820032 11:42:43 INFO - PID 17808 | 11:45:45 INFO - PID 17808 | Cycle 1(1): loaded http://localhost:54226/tests/dromaeo/cssquery-yui.html (next: http://localhost:54226/tests/dromaeo/cssquery-dojo.html) 11:45:45 INFO - PID 17808 | RSS: Main: 156495872 11:45:45 INFO - PID 17808 | 11:45:45 INFO - PID 17808 | __start_tp_report 11:45:45 INFO - PID 17808 | _x_x_mozilla_page_load 11:45:45 INFO - PID 17808 | _x_x_mozilla_page_load_details 11:45:45 INFO - PID 17808 | |i|pagename|runs| 11:45:45 INFO - PID 17808 | |0;dojo.html;3105.130563442236;3114.719675229229;3117.5947126873502;3121.812691238526;3130.5617213590085;6185.505159587234;6275.090111933772;6312.368763123685;6335.429811316981;6447.613143211404;6202.875942481148;6295.43341099301;6304.684765761705;6359.3958573935515;6363.331850155736;5830.329512106098;5879.265091863517;5931.762729490815;5932.11018347247;5938.52491800657;5814.098814683731;5875.559333050039;5886.499647529965;5901.557383196256;5921.703914804242;11.80621460973649;5429.40276569579;5781.4507621775865;5876.97061514694;5912.9704351478185;5766.0486019806685;5818.621789583706;5874.060150375919;5885.764569417261;5888.4700376966275;5744.770209191627;5750.6262092963825;5827.009408400582;5835.649861008311;5835.79574714883;6153.692315384213;6333.936660633381;6376.362363763633;6390.840228994266;6403.807885763388;6727.66361681914;6750.257471678112;6792;6799.048133261347;6838.2819803920365;6607.075009498674;6735.865282694368;6759.121314229168;6765.154355705537;6881.8967715484305;6256.15541901842;6319.210098737658;6325.430711236011;6332.176817013805;6357.11000459936;6941.375276225164;6942.757003504853;7029.191642961074;7032.964835175893;7034.40207582351;6447.806565803034;6588.077669126326;6594.439472644879;6619.073329733841;6680.365365290289;6702.128723265944;6707.859663857156;6714.664266786641;6714.798556043229;6768.255491895888;6672.799816005528;6734.629595372204;6749.898751518731;6756.831079223059;6757.3242675732035;6753.662316884136;6756.391924726799;6813.420859226922;6850.862982740317;6855.348741869514;5295.788168473218;5297.443768404262;5372.543333816591;5373.301470808848;5387.057264978613;4496.752678602643;4547.931781023287;4591.1965406053805;4609.1703493371515;4653.325267836212;5730.914036289459;5803.912941305884;5806.825795226066;5814.854628634233;5838.299404071538;6793.728250870009;6838.794836154923;6933.583984960817;6937.3062693731235;6948.478864085214;5789.942100579025;5799.159121927297;5821.126830975303;5868.354481007086;5886.23478947734;5661.716914154275;5799.1011393233275;5800.071988481823;5807.332156801937;5855.824325270249;6472.126262954441;6544.869102617921;6637.701303441357;6640.634765087966;6642.501812364092;6828.487863410363;6894.896576551356;6914.688839002257;6944.097267355211;6945.791626251221;6574.638394888327;6584.670766461753;6590.868182636416;6682.498812588978;6683.131192944886;6534.575252608565;6638.90041649366;6722.2605513394465;6796.048553202457;6807.795766127024;6749.628770417477;6806.455483561204;6813.8637227254185;6825.419839313714;6849.315068493111;6590.176227971504;6652;6681.69932353055;6689.929611262175;6693.598384096969;6582.868342633216;6584;6596.637184954681;6676.265610782712;6687.398134167948;6417.711202995783;6523.967380163069;6531.836704082436;6617.172853393326;6622.205335359788;6644.136262285871;6657.267700553032;6708.295628959084;6716.5634233774645;6745.5952642841585;6362.109304697438;6391.392817682313;6491.772787952399;6495.123158373559;6506.804795856132 11:45:45 INFO - PID 17808 | |1;ext.html;795.7732046366783;824.6742536698005;831.3640065350008;832.3632421197789;848.7793173774819;41286.17427651445;41824.790876045576;41933.58066419335;42097.368539471936;42294.15411691768;39202.41196382057;39223.80388098056;39475.40786888192;39513.20973580526;39579.802100989524;7120.85758284834;7215.170255420641;7241.7688992871335;7255.600941948191;7265.982762413267;7087.57474551526;7180.28197180283;7183.640817959081;7184.748533801318;7188.209296977328;8026.157253488387;8206.302464290542;8268.751937441886;8275;8282.047564530096;8162.347012239036;8196;8206.179382061777;8217.30152937001;8249.546274954875;7872.3308518775975;8076.636551355203;8077.8788318175275;8082.43422960393;8093.069297030858;38761.806190969146;39076.804615977024;39133;39205.607943920484;39257;3433;3455.7926524408617;3458.5503884494856;3458.9308213835834;3459.7924124552605;32120.518192227137;32243.19392015195;32299.354012919845;32407.351852963046;32454.35091298161;5183.196604526304;5188;5226.242194881721;5227.137522308814;5241.580673546107;192771.03614481978;192918;193399;194125.02937485362;194942;48465;48830.755846221255;48967;48983.75508122508;49216.753916230904;51658.74170629198;51750;51797.223041654404;51829.7408512955;51866.22200666993;14633.780493292608;14648.12111273327;14683.265836708122;14717.705645887023;14733.484328048467;11740.178187526963;11874.1094417919;11876.228045177037;11891.583794567148;11925.582604608797;14385.208813515357;14511.492097776527;14526.201058941648;14560.41758329655;14652.780208296885;5815.563832712563;5823.970880145657;5852.502537284294;5951.9107213391835;6060.424259695322;972.9951350243346;987.1806400687412;994.9154321882721;1004.1013293102778;1004.3371374892536;22223.333300001024;22412.88793556022;22660.433489162904;22756.20353287627;22922.885385572965;1098.1544210957677;1098.2916019167592;1101.2731597146005;1102.404701461202;1104.6133853151493;11928.582499612645;11957.402129893471;12126.57556985519;12200.877991220152;12210.328431936152;225697;225748;226372;226737;227102;46188.307175392394;46796.298055529194;53089.734551327;53251;53679.731601341744;1397.5597686728647;1424.287856071964;1447.6525633848012;1451.4847229233892;1452.644102194969;26492.735072649797;27266;27695.58456623112;27963.58054629223;28114.578281325386;36953.26093478169;36963;37063.25873482569;37068.44397334096;37124.443133352484;184085;184677;184901;185202;186037;32362.676373236907;33653.66346336603;33706.66293337035;33771.66228337685;33780.15549611279;42890.92772680708;43003.35494967515;43080.56919430891;43219.13561728685;43332;31740.365192696478;32114.19714507156;32132.83933580317;32255.51616725704;32555.34889302248;31555.52666709955;31679.84160079185;31985.200369990936;32061;32451.513227302083;34394.65605344014;35748.64251357453;35820.462693059104;36288.81855590705;36419.27161456809;31069;31213.531797023516;31561.8421907889;31622.683773161974;32147.35705285928;22374.888125559268;22772.845457856936;22924.4268893279;23177.88411057934;23213.535729285657 11:45:45 INFO - PID 17808 | |2;jquery.html;592.2478452365496;592.9911051334228;599.9100134979756;608.7047781825815;614.3825455417305;21776.564468710618;22867.085316587338;22871.542569148605;22905;22908;22465.438364040907;23820.523589528242;24449.633255501136;24522.509549808994;24578.877105614447;6300.086487459315;6430.356964303579;6475.320091390407;6483.578567393104;6486.286508484063;6347.682615869211;6433.195850518685;6532.6733663316645;6534.346565343451;6623.23832759234;6588.538802283842;6594.703238354262;6595.736170553196;6596.43930265928;6596.80209593713;6360.650164240965;6505.447037001861;6556.082148499214;6566.244881838602;6572.441342485894;6166.044263139221;6176.4749996250375;6180.165677633507;6185.783497577609;6193.194884664965;28197.29506762327;28715.71284287151;28803.42393152146;28827.27931801701;28834.711652883623;2023.585165041163;2051.0872661665567;2060.629086764382;2062.2885104638876;2062.886541240231;18947.715784263248;19355.225790968343;19701.704474432896;19733.506662333413;19782.802171978383;2403.315055209257;2435.9391015224583;2463.3718401807605;2466.260121963404;2493.5636263653787;125687.3715631416;130350;130697;136870;144020.27989859984;14930.730887874652;15093.622659433382;15217.31522081509;15335.309911053806;15524.456644017631;23404.765952340258;23536.7646323538;23539.0584376623;23541.293761187193;23557.646635300483;9693.369930954465;9879.901200988043;9886.752831179278;9906.108450239513;10012.599496020077;8829.38194326391;8874;8895.955520222358;8910.732678019669;9001.909980900238;8900.821983560292;9121.543922803834;9124.58939347731;9256.259499240045;9258.537073146315;2804.831710097401;2813.31073886899;2816.943661126766;2830.264131325829;2889.2487953132336;926.5321012888448;957.3107362698846;965.5461932891529;967.9128878400978;982.7936133412063;18571.071446427624;18658.160382782808;18914.527136821413;19052.71420928687;19221.13504892283;1011.7318910488726;1029.8661174047327;1030.5002073994108;1031.8297480915564;1034.632705389591;10583.470826458646;10735.141188704885;10776.892231077745;10789.946050269698;10839.83740243897;429665;457982;465392;475318;480506;111629;112645;112722.43638781754;114600.42699786615;115743;1108.7837871614959;1166.55087791201;1237.2576454127452;1244.3715923458776;1251.6557946564767;20160.29438969629;20169.882162372738;20478.385648430572;20574.794252057287;20949.266775662778;56733.71633141808;58057.4194258052;58761.706191468766;58769.412305876394;59822.70088649529;72168.27831721616;72787;73457.63271183823;73472.63263683647;74241;14103.576892693236;14318.28408579546;14550.563483095524;14636.487722929647;14668.193249371387;21754.238601648867;21772.237971670915;22350.441238969157;22360.440988975406;22440.77559224387;17684.29262829524;17742;17755.733663994793;17972.19125139346;18066.819331806513;15996.440124595583;16247.51257462278;16339.836601634306;16390.83609163941;16415.835841641907;18070.5482362942;18184.727229091837;18304.542386439913;18447.53881152982;18463.63072738511;14190.787138193142;14306.785398219243;14329.78505322442;14336.784948225994;14345.497907573603;16363.181840908144;16615.750763738313;16677.082760447807;16682.916585416995;16683.91658041702 11:45:45 INFO - PID 17808 | |3;mootools.html;1351.2162945491616;1399.657084014416;1416.0017187882545;1417.5605562275703;1421.1899217446057;12992.090553661248;14893.553193404201;15655.843441565596;15706.764398534002;15725.60685982851;13027.674308142301;13198.274094924775;15056.247187640602;15371.692566148671;15382.384704611803;2826.660800703913;2875.539913613822;2875.784316176285;2876.8705408256574;2877.352595665979;2730.945381092377;2784.2343355577277;2806.480801051802;2811;2817.3238422778486;2782.5687018512062;2819.3092692290415;2819.915402537927;2820;2825.5902894080346;2640.815142939995;2653.986730066347;2655.429082747199;2673.1980405878257;2674.3848914749524;2615.1500762252153;2654.057809477627;2659.574468085116;2660.4945060438454;2669.279294590469;15011.549653510303;15403.614909627237;15432;15488.148151851643;15501.612459688598;8122.75631731049;8365.539895305756;8389.412741108124;8390.454620449651;8442.78893027673;5386.569074474032;5684.37471878093;5697.487226149667;5756.7985120521;5857.912131318034;1911.2164012754843;1946.9318573849848;1953.4042117154263;1999.2302963359211;2001.8098280663314;27797.305067373276;27974.300642483897;28032.579511307347;28085.578716319273;28115.85942070297;6917.342852429011;6991.580505169706;7041.366277035091;7079.539829911039;7282.70869165238;17256.309747610205;17287.481375558753;17322.653546928992;17350.3059877606;17383.217755201047;8072.3138533224155;8082.797930051678;8096.028476582847;8100.8379832403025;8102.837943241102;7164.391026762783;7235.746748863765;7256.782296531007;7262.382697470668;7310.634468276565;5222.660527065728;5381.27352807366;5436.945630543723;5483.287172667528;5485.4788795064405;1418.822647169104;1456.7253653053579;1470.624990627383;1617.9191040447931;1621.894576852501;851.9531425771642;857.1899554920665;862.4480332587149;872.6291326186346;885.8051228729669;8193.959030204811;8383.203595658402;8398.622062007224;8406.747797566082;8411.116832732476;977.3940157102642;989.569537251298;991.8462638291077;995.8107959487675;1011.2163073618005;4590.609798167126;4824.324594556765;4827.25177597473;4881.145799485076;4990.201567749143;21528.354149375544;22000.669989950162;22023.889880550814;22170.002349894294;22225.333240002827;20955.790442095386;21319;21538.13847446116;21622.567548648938;21953.121875125136;11389.259698119597;11588.246763960316;11714.121440891968;11721.4139293035;11834.822477662841;7788.7171114849825;8076.798080048045;8091.3817236554105;8121.918780812116;8235.38234632393;18349.266029358678;18653.81346186575;18933.810661893203;19133.425997220107;19404.8059519403;19513.609727805648;19543.60912781765;19548.12033458469;19581.70627440561;19771.802281977572;11081.612143574936;11560.595379161688;11589.6523104307;11618.186726929034;11618.244814087057;14299.57101286963;14304.356303966139;14364.28178591087;14507.637309067357;14510.492132775302;11642.6507204784;11760.529578816751;11805.527778888749;11806.822897656713;12209.145359825083;11447.02700270453;11474.196806223485;11659.242149260272;11677.591284305348;11892.227005244631;10401.27191096616;10430.947845260725;10450.895491044992;10520.210984176065;10558.260921735711;6603.075569420189;6677.03183038447;6682.231543372574;6709.396154346132;6768.289329620319;6547.803565893031;6599.835004124935;6603.273639899703;6683.8997415039785;6770.492213084137 11:45:45 INFO - PID 17808 | |4;prototype.html;512.5105524224365;522.380620077759;534.2733881920587;535.6491498068765;549.4423160492099;6335.398137176972;6360.300366959631;6368.745250189987;6434.195725534309;6441.452476539494;6287.339829317924;6361.840953976153;6370.840728981778;6375.330590288023;6389.616623002623;5254.8686282842855;5289.709066001368;5306.628536002481;5322.520973112419;5346.358436987567;4868.172410690191;4980.626453016038;5162.174052151657;5187;5273.57811375091;4964.131277026524;4986.601071914238;4989.203886600821;5015.348004759376;5057.5701065409485;4990.556516003946;4998.1260775054125;5041.143005689041;5042.268871013719;5071.7210553419545;4359.6076353128055;4525.570070843265;4526.4794548627;4537.024539723975;4555.794989225493;5995.490383317379;6059.515238780931;6071.846349193639;6108.511319094462;6182.474489668383;5691.463304907693;5702.629329093637;5708.943845388575;5728.656280623134;5748.913766293509;6431.302001349243;6469.191351081115;6501.6749162542155;6526.445252153572;6529;4830.782614782308;4879.6580940241365;4911.729854857981;4928.43323017854;4943.752812359404;6612.471002319803;6703.530752847254;6795.626240556768;6809.582598177575;6849.828754281133;4982.626303027288;5038.546530812245;5059.87350316245;5060.544550990428;5063.822766203165;5076.670016448919;5093.185090385595;5121.641485096007;5134.666246694028;5141.203113517411;5735.713214339266;5753;5767.163761254595;5774.364819869811;5776.624519406225;6554.4756419487285;6583.308752581006;6638.966805165944;6659.667016649245;6664.133662623828;1768.982834869955;1774.086345532052;1779.67076090921;1784.1525275494037;1794.4347530527841;1140.1733743036232;1223.253815172744;1225.8966929763285;1244.6950497128262;1246.1588427811373;5636.689982050944;5683.573731970118;5692.943070569324;5693.60144789869;5693.629914055573;6602.40578347951;6651.235107962646;6686.765963191265;6798.430823817436;6807.387335139762;5620.651043749471;5703.657780533182;5712.971435142797;5730.426957304237;5760.798372056958;3488.89533314001;3508.929821403609;3518.3666939951054;3538.6461353864925;3564.4475106358555;6381.3937675920715;6442.133250703486;6530.934690653127;6555.541112122201;6562.606243625398;6238.844028899314;6711.281930818027;6882.346177113166;6898.586084834926;6963.303669632996;6408.455281301049;6436.3563643637135;6438.555372875489;6441.64570948593;6442.420182183626;6702.296258892746;6712.664366781739;6756.054152418567;6795.864082718219;6803.693833777591;6633.9336606633315;6670.73256081343;6675.295211157854;6690.190120149451;6695.4308883745425;6414.679266036773;6493.935060649522;6526;6527.2167339919515;6588.604683718992;5037.6927726793065;5046.268291097844;5074.644774865724;5095.41402738675;5106.642535022513;6443.548951573533;6474.708638111202;6575.408213260638;6606.6036037837885;6702.698378572878;6280.152179455715;6444.8388790280615;6454.515911306765;6497.967510162419;6562.606243625398;6394.808155755335;6451.3226111257645;6451.903221451588;6493.045664222862;6519.869602607826;6382.712777924911;6445.25879523842;6453.483721302191;6501;6513.478921686348;6619.238787539494;6619.312075420737;6624.9337506624315;6658.201015878126;6676.232233293234;6352.650604216812;6366.427021568081;6370.267419246846;6394.488440924808;6428.260750013808 11:45:45 INFO - PID 17808 | |5;yui.html;894.7718331825383;899.0874262623437;904.8009437923655;910.7950711090006;918.0865039285911;19671;20561.897190514064;20724.27465038724;20761.792382076175;20846.166153353868;17321.307147714077;17511;17517.386891458802;17559.29762809486;17881.731774023367;978;991.1575161113068;994.2791476179784;995.0894931138;995.8854731705837;926.1386910173536;990.5047476261869;991.2714155095998;993.7217579077869;994.3387647214594;998.4059484606647;1028.2596530498029;1034.2966782587837;1034.5137785240925;1039.1842403713088;964.5948701545368;1014.2494554030038;1016.4104819204844;1018.2973748113815;1019.6838979916249;732.3957734868754;742.6435311050726;749.4603885202699;749.8425330680564;750.9436792240604;18673.906630466896;18880.150393232336;19018.714719279084;19033.714494282598;19044;21877.562448751094;21963.780362196332;22860.428489287733;22876.771232287796;22978.885105574533;17185.48443546683;17286.13569321529;17349.913250433794;17601.7359739604;17687.646247075114;3664.633536646341;3736.6823819975334;3737.121776382548;3738.4392341148773;3740.3828368319196;108414.45792771064;132821.33589331992;133100;133886;135234;17448.912755436395;18065.09674516269;18152.546186345182;18157.45527634173;18204.271829126952;31429.371412572076;32696.182595435304;32718;32895.342093158004;33064.173395665304;9117.772055698528;9263.629454821867;9265;9291.163795258459;9329.11373419524;4247.362895565628;4360.585744354281;4379.890502737457;4380.956190438119;4391.802368893407;5430;5524.281843360337;5555.50000499957;5567.387587365387;5583.441655834466;1045.3048722599538;1097.13874608432;1103.1946678924269;1115.219346457483;1123.8988491035846;665.3246954341347;679.1273213920089;686.7905288886838;695.1623293930801;702.1048163591462;12113.212641178296;12252.693682658004;12374.071944604191;12498.812517812241;12832.037597180062;615.5229696984873;626.1765778001912;629.7134803664321;631.43486579512;641.0896526931768;5405.648632838853;5433.022056029874;5487.396386397493;5537.58468114893;5613.326400831845;120974;123090;124403;125600;127358.36320818523;107821;108287;109523.45238273758;110031;110709;90187.54906225427;90564;93680.53159734157;94510.5274473637;95852.0414795857;10301.433421161608;10356.844647330145;10521.526531306263;10592.046715795308;10624.150067994387;43532.564674354115;43975.560244397144;44353;44533.33200001938;45895;56627.71686141543;56746.14880776874;57997;58754.706226468596;59475;15764.369425222863;15899.523014309589;16101.919490402941;16176.757348640016;16194.757078643594;18094.5476363092;18356;18460.538486537946;18497.260109595467;18685.532861678024;15600.609984750472;15873.841261587237;15939.521814345588;16068.276927538524;16134.838651613334;15206.847931520986;15445.382184712485;15502.5349239523;15660.530184094496;15686.843131568538;15317.540473785804;15452.690946181237;15574.610634734223;15740.92129539345;15773.842261577238;11607.36159511235;11757.823632645346;11859.940700296444;11974.22167559106;12065.758684826429;11975.341356225492;12012.459439325427;12364.258144511357;12495.500179992698;12500.124991250525 11:45:45 INFO - PID 17808 | __end_tp_report 11:45:45 INFO - PID 17808 | __start_cc_report 11:45:45 INFO - PID 17808 | _x_x_mozilla_cycle_collect,287 11:45:45 INFO - PID 17808 | __end_cc_report 11:45:45 INFO - PID 17808 | __startTimestamp1506192345794__endTimestamp 11:45:45 INFO - PID 17808 | 11:45:45 INFO - PID 17808 | ------- Summary: start ------- 11:45:45 INFO - PID 17808 | Number of tests: 6 11:45:45 INFO - PID 17808 | 11:45:45 INFO - PID 17808 | [#0] dojo.html Cycles:165 Average:6200.60 Median:6506.80 stddev:905.48 (13.9%) stddev-sans-first:875.09 11:45:45 INFO - PID 17808 | Values: 3105.1 3114.7 3117.6 3121.8 3130.6 6185.5 6275.1 6312.4 6335.4 6447.6 6202.9 6295.4 6304.7 6359.4 6363.3 5830.3 5879.3 5931.8 5932.1 5938.5 5814.1 5875.6 5886.5 5901.6 5921.7 11.8 5429.4 5781.5 5877.0 5913.0 5766.0 5818.6 5874.1 5885.8 5888.5 5744.8 5750.6 5827.0 5835.6 5835.8 6153.7 6333.9 6376.4 6390.8 6403.8 6727.7 6750.3 6792.0 6799.0 6838.3 6607.1 6735.9 6759.1 6765.2 6881.9 6256.2 6319.2 6325.4 6332.2 6357.1 6941.4 6942.8 7029.2 7033.0 7034.4 6447.8 6588.1 6594.4 6619.1 6680.4 6702.1 6707.9 6714.7 6714.8 6768.3 6672.8 6734.6 6749.9 6756.8 6757.3 6753.7 6756.4 6813.4 6850.9 6855.3 5295.8 5297.4 5372.5 5373.3 5387.1 4496.8 4547.9 4591.2 4609.2 4653.3 5730.9 5803.9 5806.8 5814.9 5838.3 6793.7 6838.8 6933.6 6937.3 6948.5 5789.9 5799.2 5821.1 5868.4 5886.2 5661.7 5799.1 5800.1 5807.3 5855.8 6472.1 6544.9 6637.7 6640.6 6642.5 6828.5 6894.9 6914.7 6944.1 6945.8 6574.6 6584.7 6590.9 6682.5 6683.1 6534.6 6638.9 6722.3 6796.0 6807.8 6749.6 6806.5 6813.9 6825.4 6849.3 6590.2 6652.0 6681.7 6689.9 6693.6 6582.9 6584.0 6596.6 6676.3 6687.4 6417.7 6524.0 6531.8 6617.2 6622.2 6644.1 6657.3 6708.3 6716.6 6745.6 6362.1 6391.4 6491.8 6495.1 6506.8 11:45:45 INFO - PID 17808 | 11:45:45 INFO - PID 17808 | [#1] ext.html Cycles:180 Average:37160.26 Median:3458.74 stddev:52403.24 (1515.1%) stddev-sans-first:52479.10 11:45:45 INFO - PID 17808 | Values: 795.8 824.7 831.4 832.4 848.8 41286.2 41824.8 41933.6 42097.4 42294.2 39202.4 39223.8 39475.4 39513.2 39579.8 7120.9 7215.2 7241.8 7255.6 7266.0 7087.6 7180.3 7183.6 7184.7 7188.2 8026.2 8206.3 8268.8 8275.0 8282.0 8162.3 8196.0 8206.2 8217.3 8249.5 7872.3 8076.6 8077.9 8082.4 8093.1 38761.8 39076.8 39133.0 39205.6 39257.0 3433.0 3455.8 3458.6 3458.9 3459.8 32120.5 32243.2 32299.4 32407.4 32454.4 5183.2 5188.0 5226.2 5227.1 5241.6 192771.0 192918.0 193399.0 194125.0 194942.0 48465.0 48830.8 48967.0 48983.8 49216.8 51658.7 51750.0 51797.2 51829.7 51866.2 14633.8 14648.1 14683.3 14717.7 14733.5 11740.2 11874.1 11876.2 11891.6 11925.6 14385.2 14511.5 14526.2 14560.4 14652.8 5815.6 5824.0 5852.5 5951.9 6060.4 973.0 987.2 994.9 1004.1 1004.3 22223.3 22412.9 22660.4 22756.2 22922.9 1098.2 1098.3 1101.3 1102.4 1104.6 11928.6 11957.4 12126.6 12200.9 12210.3 225697.0 225748.0 226372.0 226737.0 227102.0 46188.3 46796.3 53089.7 53251.0 53679.7 1397.6 1424.3 1447.7 1451.5 1452.6 26492.7 27266.0 27695.6 27963.6 28114.6 36953.3 36963.0 37063.3 37068.4 37124.4 184085.0 184677.0 184901.0 185202.0 186037.0 32362.7 33653.7 33706.7 33771.7 33780.2 42890.9 43003.4 43080.6 43219.1 43332.0 31740.4 32114.2 32132.8 32255.5 32555.3 31555.5 31679.8 31985.2 32061.0 32451.5 34394.7 35748.6 35820.5 36288.8 36419.3 31069.0 31213.5 31561.8 31622.7 32147.4 22374.9 22772.8 22924.4 23177.9 23213.5 11:45:45 INFO - PID 17808 | 11:45:45 INFO - PID 17808 | [#2] jquery.html Cycles:180 Average:33756.43 Median:22906.50 stddev:78143.51 (341.1%) stddev-sans-first:78323.05 11:45:45 INFO - PID 17808 | Values: 592.2 593.0 599.9 608.7 614.4 21776.6 22867.1 22871.5 22905.0 22908.0 22465.4 23820.5 24449.6 24522.5 24578.9 6300.1 6430.4 6475.3 6483.6 6486.3 6347.7 6433.2 6532.7 6534.3 6623.2 6588.5 6594.7 6595.7 6596.4 6596.8 6360.7 6505.4 6556.1 6566.2 6572.4 6166.0 6176.5 6180.2 6185.8 6193.2 28197.3 28715.7 28803.4 28827.3 28834.7 2023.6 2051.1 2060.6 2062.3 2062.9 18947.7 19355.2 19701.7 19733.5 19782.8 2403.3 2435.9 2463.4 2466.3 2493.6 125687.4 130350.0 130697.0 136870.0 144020.3 14930.7 15093.6 15217.3 15335.3 15524.5 23404.8 23536.8 23539.1 23541.3 23557.6 9693.4 9879.9 9886.8 9906.1 10012.6 8829.4 8874.0 8896.0 8910.7 9001.9 8900.8 9121.5 9124.6 9256.3 9258.5 2804.8 2813.3 2816.9 2830.3 2889.2 926.5 957.3 965.5 967.9 982.8 18571.1 18658.2 18914.5 19052.7 19221.1 1011.7 1029.9 1030.5 1031.8 1034.6 10583.5 10735.1 10776.9 10789.9 10839.8 429665.0 457982.0 465392.0 475318.0 480506.0 111629.0 112645.0 112722.4 114600.4 115743.0 1108.8 1166.6 1237.3 1244.4 1251.7 20160.3 20169.9 20478.4 20574.8 20949.3 56733.7 58057.4 58761.7 58769.4 59822.7 72168.3 72787.0 73457.6 73472.6 74241.0 14103.6 14318.3 14550.6 14636.5 14668.2 21754.2 21772.2 22350.4 22360.4 22440.8 17684.3 17742.0 17755.7 17972.2 18066.8 15996.4 16247.5 16339.8 16390.8 16415.8 18070.5 18184.7 18304.5 18447.5 18463.6 14190.8 14306.8 14329.8 14336.8 14345.5 16363.2 16615.8 16677.1 16682.9 16683.9 11:45:45 INFO - PID 17808 | 11:45:45 INFO - PID 17808 | [#3] mootools.html Cycles:180 Average:9456.87 Median:2657.50 stddev:6841.43 (257.4%) stddev-sans-first:6833.52 11:45:45 INFO - PID 17808 | Values: 1351.2 1399.7 1416.0 1417.6 1421.2 12992.1 14893.6 15655.8 15706.8 15725.6 13027.7 13198.3 15056.2 15371.7 15382.4 2826.7 2875.5 2875.8 2876.9 2877.4 2730.9 2784.2 2806.5 2811.0 2817.3 2782.6 2819.3 2819.9 2820.0 2825.6 2640.8 2654.0 2655.4 2673.2 2674.4 2615.2 2654.1 2659.6 2660.5 2669.3 15011.5 15403.6 15432.0 15488.1 15501.6 8122.8 8365.5 8389.4 8390.5 8442.8 5386.6 5684.4 5697.5 5756.8 5857.9 1911.2 1946.9 1953.4 1999.2 2001.8 27797.3 27974.3 28032.6 28085.6 28115.9 6917.3 6991.6 7041.4 7079.5 7282.7 17256.3 17287.5 17322.7 17350.3 17383.2 8072.3 8082.8 8096.0 8100.8 8102.8 7164.4 7235.7 7256.8 7262.4 7310.6 5222.7 5381.3 5436.9 5483.3 5485.5 1418.8 1456.7 1470.6 1617.9 1621.9 852.0 857.2 862.4 872.6 885.8 8194.0 8383.2 8398.6 8406.7 8411.1 977.4 989.6 991.8 995.8 1011.2 4590.6 4824.3 4827.3 4881.1 4990.2 21528.4 22000.7 22023.9 22170.0 22225.3 20955.8 21319.0 21538.1 21622.6 21953.1 11389.3 11588.2 11714.1 11721.4 11834.8 7788.7 8076.8 8091.4 8121.9 8235.4 18349.3 18653.8 18933.8 19133.4 19404.8 19513.6 19543.6 19548.1 19581.7 19771.8 11081.6 11560.6 11589.7 11618.2 11618.2 14299.6 14304.4 14364.3 14507.6 14510.5 11642.7 11760.5 11805.5 11806.8 12209.1 11447.0 11474.2 11659.2 11677.6 11892.2 10401.3 10430.9 10450.9 10520.2 10558.3 6603.1 6677.0 6682.2 6709.4 6768.3 6547.8 6599.8 6603.3 6683.9 6770.5 11:45:45 INFO - PID 17808 | 11:45:45 INFO - PID 17808 | [#4] prototype.html Cycles:180 Average:5525.84 Median:6259.50 stddev:1533.08 (24.5%) stddev-sans-first:1490.48 11:45:45 INFO - PID 17808 | Values: 512.5 522.4 534.3 535.6 549.4 6335.4 6360.3 6368.7 6434.2 6441.5 6287.3 6361.8 6370.8 6375.3 6389.6 5254.9 5289.7 5306.6 5322.5 5346.4 4868.2 4980.6 5162.2 5187.0 5273.6 4964.1 4986.6 4989.2 5015.3 5057.6 4990.6 4998.1 5041.1 5042.3 5071.7 4359.6 4525.6 4526.5 4537.0 4555.8 5995.5 6059.5 6071.8 6108.5 6182.5 5691.5 5702.6 5708.9 5728.7 5748.9 6431.3 6469.2 6501.7 6526.4 6529.0 4830.8 4879.7 4911.7 4928.4 4943.8 6612.5 6703.5 6795.6 6809.6 6849.8 4982.6 5038.5 5059.9 5060.5 5063.8 5076.7 5093.2 5121.6 5134.7 5141.2 5735.7 5753.0 5767.2 5774.4 5776.6 6554.5 6583.3 6639.0 6659.7 6664.1 1769.0 1774.1 1779.7 1784.2 1794.4 1140.2 1223.3 1225.9 1244.7 1246.2 5636.7 5683.6 5692.9 5693.6 5693.6 6602.4 6651.2 6686.8 6798.4 6807.4 5620.7 5703.7 5713.0 5730.4 5760.8 3488.9 3508.9 3518.4 3538.6 3564.4 6381.4 6442.1 6530.9 6555.5 6562.6 6238.8 6711.3 6882.3 6898.6 6963.3 6408.5 6436.4 6438.6 6441.6 6442.4 6702.3 6712.7 6756.1 6795.9 6803.7 6633.9 6670.7 6675.3 6690.2 6695.4 6414.7 6493.9 6526.0 6527.2 6588.6 5037.7 5046.3 5074.6 5095.4 5106.6 6443.5 6474.7 6575.4 6606.6 6702.7 6280.2 6444.8 6454.5 6498.0 6562.6 6394.8 6451.3 6451.9 6493.0 6519.9 6382.7 6445.3 6453.5 6501.0 6513.5 6619.2 6619.3 6624.9 6658.2 6676.2 6352.7 6366.4 6370.3 6394.5 6428.3 11:45:45 INFO - PID 17808 | 11:45:45 INFO - PID 17808 | [#5] yui.html Cycles:180 Average:24165.80 Median:19026.21 stddev:34403.06 (180.8%) stddev-sans-first:34455.19 11:45:45 INFO - PID 17808 | Values: 894.8 899.1 904.8 910.8 918.1 19671.0 20561.9 20724.3 20761.8 20846.2 17321.3 17511.0 17517.4 17559.3 17881.7 978.0 991.2 994.3 995.1 995.9 926.1 990.5 991.3 993.7 994.3 998.4 1028.3 1034.3 1034.5 1039.2 964.6 1014.2 1016.4 1018.3 1019.7 732.4 742.6 749.5 749.8 750.9 18673.9 18880.2 19018.7 19033.7 19044.0 21877.6 21963.8 22860.4 22876.8 22978.9 17185.5 17286.1 17349.9 17601.7 17687.6 3664.6 3736.7 3737.1 3738.4 3740.4 108414.5 132821.3 133100.0 133886.0 135234.0 17448.9 18065.1 18152.5 18157.5 18204.3 31429.4 32696.2 32718.0 32895.3 33064.2 9117.8 9263.6 9265.0 9291.2 9329.1 4247.4 4360.6 4379.9 4381.0 4391.8 5430.0 5524.3 5555.5 5567.4 5583.4 1045.3 1097.1 1103.2 1115.2 1123.9 665.3 679.1 686.8 695.2 702.1 12113.2 12252.7 12374.1 12498.8 12832.0 615.5 626.2 629.7 631.4 641.1 5405.6 5433.0 5487.4 5537.6 5613.3 120974.0 123090.0 124403.0 125600.0 127358.4 107821.0 108287.0 109523.5 110031.0 110709.0 90187.5 90564.0 93680.5 94510.5 95852.0 10301.4 10356.8 10521.5 10592.0 10624.2 43532.6 43975.6 44353.0 44533.3 45895.0 56627.7 56746.1 57997.0 58754.7 59475.0 15764.4 15899.5 16101.9 16176.8 16194.8 18094.5 18356.0 18460.5 18497.3 18685.5 15600.6 15873.8 15939.5 16068.3 16134.8 15206.8 15445.4 15502.5 15660.5 15686.8 15317.5 15452.7 15574.6 15740.9 15773.8 11607.4 11757.8 11859.9 11974.2 12065.8 11975.3 12012.5 12364.3 12495.5 12500.1 11:45:45 INFO - PID 17808 | -------- Summary: end -------- 11:45:45 INFO - PID 17808 | 11:45:46 INFO - TEST-INFO | 17808: exit 0 11:45:46 INFO - TEST-OK | dromaeo_css | took 1107714ms 11:45:46 INFO - TEST-START | kraken 11:45:46 INFO - Initialising browser for kraken test... 11:45:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54226/getInfo.html -profile /tmp/tmpPZx7zn/profile 11:45:46 INFO - TEST-INFO | started process 18177 (/builds/slave/test/build/application/firefox/firefox http://localhost:54226/getInfo.html) 11:45:51 INFO - TEST-INFO | 18177: exit 0 11:45:52 INFO - Browser initialized. 11:45:52 INFO - Running cycle 1/1 for kraken test... 11:45:52 INFO - Using env: {'DISPLAY': ':0', 11:45:52 INFO - 'HOME': '/home/cltbld', 11:45:52 INFO - 'JSGC_DISABLE_POISONING': '1', 11:45:52 INFO - 'LANG': 'en_US.UTF-8', 11:45:52 INFO - 'LANGUAGE': 'en_US:en', 11:45:52 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:45:52 INFO - 'LOGNAME': 'cltbld', 11:45:52 INFO - 'MAIL': '/var/mail/cltbld', 11:45:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:45:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:45:52 INFO - 'MOZ_CRASHREPORTER': '1', 11:45:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:52 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:45:52 INFO - 'MOZ_NO_REMOTE': '1', 11:45:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:45:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:45:52 INFO - 'NO_EM_RESTART': '1', 11:45:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:45:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:52 INFO - 'PWD': '/builds/slave/test', 11:45:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:45:52 INFO - 'RUST_BACKTRACE': 'full', 11:45:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:45:52 INFO - 'SHELL': '/bin/bash', 11:45:52 INFO - 'SHLVL': '1', 11:45:52 INFO - 'TERM': 'linux', 11:45:52 INFO - 'TMOUT': '86400', 11:45:52 INFO - 'USER': 'cltbld', 11:45:52 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629', 11:45:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:52 INFO - '_': '/tools/buildbot/bin/python'} 11:45:52 INFO - TEST-INFO | started process 18415 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPZx7zn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:45:52 INFO - PID 18415 | 11:45:52 INFO - PID 18415 | (/builds/slave/test/build/application/firefox/firefox:18465): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:45:52 INFO - PID 18415 | 11:46:03 INFO - PID 18415 | RSS: Main: 151318528 11:46:03 INFO - PID 18415 | 11:46:05 INFO - PID 18415 | Cycle 1(1): loaded http://localhost:54226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54226/tests/kraken/driver.html?testName=audio-beat-detection) 11:46:06 INFO - PID 18415 | RSS: Main: 158461952 11:46:06 INFO - PID 18415 | 11:46:09 INFO - PID 18415 | Cycle 1(1): loaded http://localhost:54226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54226/tests/kraken/driver.html?testName=audio-dft) 11:46:10 INFO - PID 18415 | RSS: Main: 158638080 11:46:10 INFO - PID 18415 | 11:46:13 INFO - PID 18415 | Cycle 1(1): loaded http://localhost:54226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54226/tests/kraken/driver.html?testName=audio-fft) 11:46:13 INFO - PID 18415 | RSS: Main: 154075136 11:46:13 INFO - PID 18415 | 11:46:15 INFO - PID 18415 | Cycle 1(1): loaded http://localhost:54226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54226/tests/kraken/driver.html?testName=audio-oscillator) 11:46:16 INFO - PID 18415 | RSS: Main: 154599424 11:46:16 INFO - PID 18415 | 11:46:18 INFO - PID 18415 | Cycle 1(1): loaded http://localhost:54226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:46:19 INFO - PID 18415 | RSS: Main: 154931200 11:46:19 INFO - PID 18415 | 11:46:22 INFO - PID 18415 | Cycle 1(1): loaded http://localhost:54226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54226/tests/kraken/driver.html?testName=imaging-darkroom) 11:46:23 INFO - PID 18415 | RSS: Main: 157048832 11:46:23 INFO - PID 18415 | 11:46:26 INFO - PID 18415 | Cycle 1(1): loaded http://localhost:54226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54226/tests/kraken/driver.html?testName=imaging-desaturate) 11:46:27 INFO - PID 18415 | RSS: Main: 157585408 11:46:27 INFO - PID 18415 | 11:46:30 INFO - PID 18415 | Cycle 1(1): loaded http://localhost:54226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54226/tests/kraken/driver.html?testName=json-parse-financial) 11:46:30 INFO - PID 18415 | RSS: Main: 157687808 11:46:30 INFO - PID 18415 | 11:46:32 INFO - PID 18415 | Cycle 1(1): loaded http://localhost:54226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:46:33 INFO - PID 18415 | RSS: Main: 157495296 11:46:33 INFO - PID 18415 | 11:46:34 INFO - PID 18415 | Cycle 1(1): loaded http://localhost:54226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54226/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:46:35 INFO - PID 18415 | RSS: Main: 154750976 11:46:35 INFO - PID 18415 | 11:46:37 INFO - PID 18415 | Cycle 1(1): loaded http://localhost:54226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:46:38 INFO - PID 18415 | RSS: Main: 155336704 11:46:38 INFO - PID 18415 | 11:46:40 INFO - PID 18415 | Cycle 1(1): loaded http://localhost:54226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:46:41 INFO - PID 18415 | RSS: Main: 154697728 11:46:41 INFO - PID 18415 | 11:46:44 INFO - PID 18415 | Cycle 1(1): loaded http://localhost:54226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:46:44 INFO - PID 18415 | RSS: Main: 155312128 11:46:44 INFO - PID 18415 | 11:46:46 INFO - PID 18415 | Cycle 1(1): loaded http://localhost:54226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54226/tests/kraken/driver.html?testName=ai-astar) 11:46:46 INFO - PID 18415 | RSS: Main: 156319744 11:46:46 INFO - PID 18415 | 11:46:46 INFO - PID 18415 | __start_tp_report 11:46:46 INFO - PID 18415 | _x_x_mozilla_page_load 11:46:46 INFO - PID 18415 | _x_x_mozilla_page_load_details 11:46:46 INFO - PID 18415 | |i|pagename|runs| 11:46:46 INFO - PID 18415 | |0;ai-astar;109;108;107;107;109;108;108;107;107;108 11:46:46 INFO - PID 18415 | |1;audio-beat-detection;120;116;154;118;118;117;115;116;116;117 11:46:46 INFO - PID 18415 | |2;audio-dft;165;163;161;161;165;163;164;164;165;168 11:46:46 INFO - PID 18415 | |3;audio-fft;73;73;74;75;83;74;74;74;76;74 11:46:46 INFO - PID 18415 | |4;audio-oscillator;80;80;80;79;85;78;80;81;79;79 11:46:46 INFO - PID 18415 | |5;imaging-gaussian-blur;111;113;114;113;115;113;115;116;115;115 11:46:46 INFO - PID 18415 | |6;imaging-darkroom;150;148;147;147;149;147;147;149;149;148 11:46:46 INFO - PID 18415 | |7;imaging-desaturate;99;95;95;94;94;95;94;94;95;95 11:46:46 INFO - PID 18415 | |8;json-parse-financial;56;58;53;55;56;54;52;51;52;52 11:46:46 INFO - PID 18415 | |9;json-stringify-tinderbox;39;38;39;38;38;38;38;38;38;38 11:46:46 INFO - PID 18415 | |10;stanford-crypto-aes;83;73;73;73;73;75;74;73;74;73 11:46:46 INFO - PID 18415 | |11;stanford-crypto-ccm;117;111;115;112;115;109;115;111;117;117 11:46:46 INFO - PID 18415 | |12;stanford-crypto-pbkdf2;171;174;173;166;169;169;175;170;165;173 11:46:46 INFO - PID 18415 | |13;stanford-crypto-sha256-iterative;69;66;67;63;65;64;65;64;64;66 11:46:46 INFO - PID 18415 | __end_tp_report 11:46:46 INFO - PID 18415 | __start_cc_report 11:46:46 INFO - PID 18415 | _x_x_mozilla_cycle_collect,745 11:46:46 INFO - PID 18415 | __end_cc_report 11:46:46 INFO - PID 18415 | __startTimestamp1506192406875__endTimestamp 11:46:46 INFO - PID 18415 | 11:46:46 INFO - PID 18415 | ------- Summary: start ------- 11:46:46 INFO - PID 18415 | Number of tests: 14 11:46:46 INFO - PID 18415 | 11:46:46 INFO - PID 18415 | [#0] ai-astar Cycles:10 Average:107.80 Median:108.00 stddev:0.79 (0.7%) stddev-sans-first:0.71 11:46:46 INFO - PID 18415 | Values: 109.0 108.0 107.0 107.0 109.0 108.0 108.0 107.0 107.0 108.0 11:46:46 INFO - PID 18415 | 11:46:46 INFO - PID 18415 | [#1] audio-beat-detection Cycles:10 Average:120.70 Median:117.50 stddev:11.79 (10.0%) stddev-sans-first:12.50 11:46:46 INFO - PID 18415 | Values: 120.0 116.0 154.0 118.0 118.0 117.0 115.0 116.0 116.0 117.0 11:46:46 INFO - PID 18415 | 11:46:46 INFO - PID 18415 | [#2] audio-dft Cycles:10 Average:163.90 Median:164.50 stddev:2.08 (1.3%) stddev-sans-first:2.17 11:46:46 INFO - PID 18415 | Values: 165.0 163.0 161.0 161.0 165.0 163.0 164.0 164.0 165.0 168.0 11:46:46 INFO - PID 18415 | 11:46:46 INFO - PID 18415 | [#3] audio-fft Cycles:10 Average:75.00 Median:74.00 stddev:2.94 (4.0%) stddev-sans-first:3.03 11:46:46 INFO - PID 18415 | Values: 73.0 73.0 74.0 75.0 83.0 74.0 74.0 74.0 76.0 74.0 11:46:46 INFO - PID 18415 | 11:46:46 INFO - PID 18415 | [#4] audio-oscillator Cycles:10 Average:80.10 Median:80.00 stddev:1.91 (2.4%) stddev-sans-first:2.03 11:46:46 INFO - PID 18415 | Values: 80.0 80.0 80.0 79.0 85.0 78.0 80.0 81.0 79.0 79.0 11:46:46 INFO - PID 18415 | 11:46:46 INFO - PID 18415 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:115.00 stddev:1.49 (1.3%) stddev-sans-first:1.12 11:46:46 INFO - PID 18415 | Values: 111.0 113.0 114.0 113.0 115.0 113.0 115.0 116.0 115.0 115.0 11:46:46 INFO - PID 18415 | 11:46:46 INFO - PID 18415 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.50 stddev:1.10 (0.7%) stddev-sans-first:0.93 11:46:46 INFO - PID 18415 | Values: 150.0 148.0 147.0 147.0 149.0 147.0 147.0 149.0 149.0 148.0 11:46:46 INFO - PID 18415 | 11:46:46 INFO - PID 18415 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:1.49 (1.6%) stddev-sans-first:0.53 11:46:46 INFO - PID 18415 | Values: 99.0 95.0 95.0 94.0 94.0 95.0 94.0 94.0 95.0 95.0 11:46:46 INFO - PID 18415 | 11:46:46 INFO - PID 18415 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.50 stddev:2.28 (4.2%) stddev-sans-first:2.29 11:46:46 INFO - PID 18415 | Values: 56.0 58.0 53.0 55.0 56.0 54.0 52.0 51.0 52.0 52.0 11:46:46 INFO - PID 18415 | 11:46:46 INFO - PID 18415 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:0.42 (1.1%) stddev-sans-first:0.33 11:46:46 INFO - PID 18415 | Values: 39.0 38.0 39.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 11:46:46 INFO - PID 18415 | 11:46:46 INFO - PID 18415 | [#10] stanford-crypto-aes Cycles:10 Average:74.40 Median:73.50 stddev:3.10 (4.2%) stddev-sans-first:0.73 11:46:46 INFO - PID 18415 | Values: 83.0 73.0 73.0 73.0 73.0 75.0 74.0 73.0 74.0 73.0 11:46:46 INFO - PID 18415 | 11:46:46 INFO - PID 18415 | [#11] stanford-crypto-ccm Cycles:10 Average:113.90 Median:115.00 stddev:2.92 (2.5%) stddev-sans-first:2.88 11:46:46 INFO - PID 18415 | Values: 117.0 111.0 115.0 112.0 115.0 109.0 115.0 111.0 117.0 117.0 11:46:46 INFO - PID 18415 | 11:46:46 INFO - PID 18415 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.50 Median:172.00 stddev:3.34 (1.9%) stddev-sans-first:3.54 11:46:46 INFO - PID 18415 | Values: 171.0 174.0 173.0 166.0 169.0 169.0 175.0 170.0 165.0 173.0 11:46:46 INFO - PID 18415 | 11:46:46 INFO - PID 18415 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.50 stddev:1.77 (2.7%) stddev-sans-first:1.27 11:46:46 INFO - PID 18415 | Values: 69.0 66.0 67.0 63.0 65.0 64.0 65.0 64.0 64.0 66.0 11:46:46 INFO - PID 18415 | -------- Summary: end -------- 11:46:46 INFO - PID 18415 | 11:46:47 INFO - PID 18415 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:46:47 INFO - TEST-INFO | 18415: exit 0 11:46:47 INFO - TEST-OK | kraken | took 61054ms 11:46:47 INFO - SUITE-END | took 1168s 11:46:47 INFO - Completed test suite (00:19:28) 11:46:47 INFO - javascript benchmark 11:46:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8518.712382046993, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3105.130563442236, 3114.719675229229, 3117.5947126873502, 3121.812691238526, 3130.5617213590085, 6185.505159587234, 6275.090111933772, 6312.368763123685, 6335.429811316981, 6447.613143211404, 6202.875942481148, 6295.43341099301, 6304.684765761705, 6359.3958573935515, 6363.331850155736, 5830.329512106098, 5879.265091863517, 5931.762729490815, 5932.11018347247, 5938.52491800657, 5814.098814683731, 5875.559333050039, 5886.499647529965, 5901.557383196256, 5921.703914804242, 11.80621460973649, 5429.40276569579, 5781.4507621775865, 5876.97061514694, 5912.9704351478185, 5766.0486019806685, 5818.621789583706, 5874.060150375919, 5885.764569417261, 5888.4700376966275, 5744.770209191627, 5750.6262092963825, 5827.009408400582, 5835.649861008311, 5835.79574714883, 6153.692315384213, 6333.936660633381, 6376.362363763633, 6390.840228994266, 6403.807885763388, 6727.66361681914, 6750.257471678112, 6792.0, 6799.048133261347, 6838.2819803920365, 6607.075009498674, 6735.865282694368, 6759.121314229168, 6765.154355705537, 6881.8967715484305, 6256.15541901842, 6319.210098737658, 6325.430711236011, 6332.176817013805, 6357.11000459936, 6941.375276225164, 6942.757003504853, 7029.191642961074, 7032.964835175893, 7034.40207582351, 6447.806565803034, 6588.077669126326, 6594.439472644879, 6619.073329733841, 6680.365365290289, 6702.128723265944, 6707.859663857156, 6714.664266786641, 6714.798556043229, 6768.255491895888, 6672.799816005528, 6734.629595372204, 6749.898751518731, 6756.831079223059, 6757.3242675732035, 6753.662316884136, 6756.391924726799, 6813.420859226922, 6850.862982740317, 6855.348741869514, 5295.788168473218, 5297.443768404262, 5372.543333816591, 5373.301470808848, 5387.057264978613, 4496.752678602643, 4547.931781023287, 4591.1965406053805, 4609.1703493371515, 4653.325267836212, 5730.914036289459, 5803.912941305884, 5806.825795226066, 5814.854628634233, 5838.299404071538, 6793.728250870009, 6838.794836154923, 6933.583984960817, 6937.3062693731235, 6948.478864085214, 5789.942100579025, 5799.159121927297, 5821.126830975303, 5868.354481007086, 5886.23478947734, 5661.716914154275, 5799.1011393233275, 5800.071988481823, 5807.332156801937, 5855.824325270249, 6472.126262954441, 6544.869102617921, 6637.701303441357, 6640.634765087966, 6642.501812364092, 6828.487863410363, 6894.896576551356, 6914.688839002257, 6944.097267355211, 6945.791626251221, 6574.638394888327, 6584.670766461753, 6590.868182636416, 6682.498812588978, 6683.131192944886, 6534.575252608565, 6638.90041649366, 6722.2605513394465, 6796.048553202457, 6807.795766127024, 6749.628770417477, 6806.455483561204, 6813.8637227254185, 6825.419839313714, 6849.315068493111, 6590.176227971504, 6652.0, 6681.69932353055, 6689.929611262175, 6693.598384096969, 6582.868342633216, 6584.0, 6596.637184954681, 6676.265610782712, 6687.398134167948, 6417.711202995783, 6523.967380163069, 6531.836704082436, 6617.172853393326, 6622.205335359788, 6644.136262285871, 6657.267700553032, 6708.295628959084, 6716.5634233774645, 6745.5952642841585, 6362.109304697438, 6391.392817682313, 6491.772787952399, 6495.123158373559, 6506.804795856132], "value": 6133.624044219272, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.7732046366783, 824.6742536698005, 831.3640065350008, 832.3632421197789, 848.7793173774819, 41286.17427651445, 41824.790876045576, 41933.58066419335, 42097.368539471936, 42294.15411691768, 39202.41196382057, 39223.80388098056, 39475.40786888192, 39513.20973580526, 39579.802100989524, 7120.85758284834, 7215.170255420641, 7241.7688992871335, 7255.600941948191, 7265.982762413267, 7087.57474551526, 7180.28197180283, 7183.640817959081, 7184.748533801318, 7188.209296977328, 8026.157253488387, 8206.302464290542, 8268.751937441886, 8275.0, 8282.047564530096, 8162.347012239036, 8196.0, 8206.179382061777, 8217.30152937001, 8249.546274954875, 7872.3308518775975, 8076.636551355203, 8077.8788318175275, 8082.43422960393, 8093.069297030858, 38761.806190969146, 39076.804615977024, 39133.0, 39205.607943920484, 39257.0, 3433.0, 3455.7926524408617, 3458.5503884494856, 3458.9308213835834, 3459.7924124552605, 32120.518192227137, 32243.19392015195, 32299.354012919845, 32407.351852963046, 32454.35091298161, 5183.196604526304, 5188.0, 5226.242194881721, 5227.137522308814, 5241.580673546107, 192771.03614481978, 192918.0, 193399.0, 194125.02937485362, 194942.0, 48465.0, 48830.755846221255, 48967.0, 48983.75508122508, 49216.753916230904, 51658.74170629198, 51750.0, 51797.223041654404, 51829.7408512955, 51866.22200666993, 14633.780493292608, 14648.12111273327, 14683.265836708122, 14717.705645887023, 14733.484328048467, 11740.178187526963, 11874.1094417919, 11876.228045177037, 11891.583794567148, 11925.582604608797, 14385.208813515357, 14511.492097776527, 14526.201058941648, 14560.41758329655, 14652.780208296885, 5815.563832712563, 5823.970880145657, 5852.502537284294, 5951.9107213391835, 6060.424259695322, 972.9951350243346, 987.1806400687412, 994.9154321882721, 1004.1013293102778, 1004.3371374892536, 22223.333300001024, 22412.88793556022, 22660.433489162904, 22756.20353287627, 22922.885385572965, 1098.1544210957677, 1098.2916019167592, 1101.2731597146005, 1102.404701461202, 1104.6133853151493, 11928.582499612645, 11957.402129893471, 12126.57556985519, 12200.877991220152, 12210.328431936152, 225697.0, 225748.0, 226372.0, 226737.0, 227102.0, 46188.307175392394, 46796.298055529194, 53089.734551327, 53251.0, 53679.731601341744, 1397.5597686728647, 1424.287856071964, 1447.6525633848012, 1451.4847229233892, 1452.644102194969, 26492.735072649797, 27266.0, 27695.58456623112, 27963.58054629223, 28114.578281325386, 36953.26093478169, 36963.0, 37063.25873482569, 37068.44397334096, 37124.443133352484, 184085.0, 184677.0, 184901.0, 185202.0, 186037.0, 32362.676373236907, 33653.66346336603, 33706.66293337035, 33771.66228337685, 33780.15549611279, 42890.92772680708, 43003.35494967515, 43080.56919430891, 43219.13561728685, 43332.0, 31740.365192696478, 32114.19714507156, 32132.83933580317, 32255.51616725704, 32555.34889302248, 31555.52666709955, 31679.84160079185, 31985.200369990936, 32061.0, 32451.513227302083, 34394.65605344014, 35748.64251357453, 35820.462693059104, 36288.81855590705, 36419.27161456809, 31069.0, 31213.531797023516, 31561.8421907889, 31622.683773161974, 32147.35705285928, 22374.888125559268, 22772.845457856936, 22924.4268893279, 23177.88411057934, 23213.535729285657], "value": 17061.92001400921, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.2478452365496, 592.9911051334228, 599.9100134979756, 608.7047781825815, 614.3825455417305, 21776.564468710618, 22867.085316587338, 22871.542569148605, 22905.0, 22908.0, 22465.438364040907, 23820.523589528242, 24449.633255501136, 24522.509549808994, 24578.877105614447, 6300.086487459315, 6430.356964303579, 6475.320091390407, 6483.578567393104, 6486.286508484063, 6347.682615869211, 6433.195850518685, 6532.6733663316645, 6534.346565343451, 6623.23832759234, 6588.538802283842, 6594.703238354262, 6595.736170553196, 6596.43930265928, 6596.80209593713, 6360.650164240965, 6505.447037001861, 6556.082148499214, 6566.244881838602, 6572.441342485894, 6166.044263139221, 6176.4749996250375, 6180.165677633507, 6185.783497577609, 6193.194884664965, 28197.29506762327, 28715.71284287151, 28803.42393152146, 28827.27931801701, 28834.711652883623, 2023.585165041163, 2051.0872661665567, 2060.629086764382, 2062.2885104638876, 2062.886541240231, 18947.715784263248, 19355.225790968343, 19701.704474432896, 19733.506662333413, 19782.802171978383, 2403.315055209257, 2435.9391015224583, 2463.3718401807605, 2466.260121963404, 2493.5636263653787, 125687.3715631416, 130350.0, 130697.0, 136870.0, 144020.27989859984, 14930.730887874652, 15093.622659433382, 15217.31522081509, 15335.309911053806, 15524.456644017631, 23404.765952340258, 23536.7646323538, 23539.0584376623, 23541.293761187193, 23557.646635300483, 9693.369930954465, 9879.901200988043, 9886.752831179278, 9906.108450239513, 10012.599496020077, 8829.38194326391, 8874.0, 8895.955520222358, 8910.732678019669, 9001.909980900238, 8900.821983560292, 9121.543922803834, 9124.58939347731, 9256.259499240045, 9258.537073146315, 2804.831710097401, 2813.31073886899, 2816.943661126766, 2830.264131325829, 2889.2487953132336, 926.5321012888448, 957.3107362698846, 965.5461932891529, 967.9128878400978, 982.7936133412063, 18571.071446427624, 18658.160382782808, 18914.527136821413, 19052.71420928687, 19221.13504892283, 1011.7318910488726, 1029.8661174047327, 1030.5002073994108, 1031.8297480915564, 1034.632705389591, 10583.470826458646, 10735.141188704885, 10776.892231077745, 10789.946050269698, 10839.83740243897, 429665.0, 457982.0, 465392.0, 475318.0, 480506.0, 111629.0, 112645.0, 112722.43638781754, 114600.42699786615, 115743.0, 1108.7837871614959, 1166.55087791201, 1237.2576454127452, 1244.3715923458776, 1251.6557946564767, 20160.29438969629, 20169.882162372738, 20478.385648430572, 20574.794252057287, 20949.266775662778, 56733.71633141808, 58057.4194258052, 58761.706191468766, 58769.412305876394, 59822.70088649529, 72168.27831721616, 72787.0, 73457.63271183823, 73472.63263683647, 74241.0, 14103.576892693236, 14318.28408579546, 14550.563483095524, 14636.487722929647, 14668.193249371387, 21754.238601648867, 21772.237971670915, 22350.441238969157, 22360.440988975406, 22440.77559224387, 17684.29262829524, 17742.0, 17755.733663994793, 17972.19125139346, 18066.819331806513, 15996.440124595583, 16247.51257462278, 16339.836601634306, 16390.83609163941, 16415.835841641907, 18070.5482362942, 18184.727229091837, 18304.542386439913, 18447.53881152982, 18463.63072738511, 14190.787138193142, 14306.785398219243, 14329.78505322442, 14336.784948225994, 14345.497907573603, 16363.181840908144, 16615.750763738313, 16677.082760447807, 16682.916585416995, 16683.91658041702], "value": 11881.847350764574, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1351.2162945491616, 1399.657084014416, 1416.0017187882545, 1417.5605562275703, 1421.1899217446057, 12992.090553661248, 14893.553193404201, 15655.843441565596, 15706.764398534002, 15725.60685982851, 13027.674308142301, 13198.274094924775, 15056.247187640602, 15371.692566148671, 15382.384704611803, 2826.660800703913, 2875.539913613822, 2875.784316176285, 2876.8705408256574, 2877.352595665979, 2730.945381092377, 2784.2343355577277, 2806.480801051802, 2811.0, 2817.3238422778486, 2782.5687018512062, 2819.3092692290415, 2819.915402537927, 2820.0, 2825.5902894080346, 2640.815142939995, 2653.986730066347, 2655.429082747199, 2673.1980405878257, 2674.3848914749524, 2615.1500762252153, 2654.057809477627, 2659.574468085116, 2660.4945060438454, 2669.279294590469, 15011.549653510303, 15403.614909627237, 15432.0, 15488.148151851643, 15501.612459688598, 8122.75631731049, 8365.539895305756, 8389.412741108124, 8390.454620449651, 8442.78893027673, 5386.569074474032, 5684.37471878093, 5697.487226149667, 5756.7985120521, 5857.912131318034, 1911.2164012754843, 1946.9318573849848, 1953.4042117154263, 1999.2302963359211, 2001.8098280663314, 27797.305067373276, 27974.300642483897, 28032.579511307347, 28085.578716319273, 28115.85942070297, 6917.342852429011, 6991.580505169706, 7041.366277035091, 7079.539829911039, 7282.70869165238, 17256.309747610205, 17287.481375558753, 17322.653546928992, 17350.3059877606, 17383.217755201047, 8072.3138533224155, 8082.797930051678, 8096.028476582847, 8100.8379832403025, 8102.837943241102, 7164.391026762783, 7235.746748863765, 7256.782296531007, 7262.382697470668, 7310.634468276565, 5222.660527065728, 5381.27352807366, 5436.945630543723, 5483.287172667528, 5485.4788795064405, 1418.822647169104, 1456.7253653053579, 1470.624990627383, 1617.9191040447931, 1621.894576852501, 851.9531425771642, 857.1899554920665, 862.4480332587149, 872.6291326186346, 885.8051228729669, 8193.959030204811, 8383.203595658402, 8398.622062007224, 8406.747797566082, 8411.116832732476, 977.3940157102642, 989.569537251298, 991.8462638291077, 995.8107959487675, 1011.2163073618005, 4590.609798167126, 4824.324594556765, 4827.25177597473, 4881.145799485076, 4990.201567749143, 21528.354149375544, 22000.669989950162, 22023.889880550814, 22170.002349894294, 22225.333240002827, 20955.790442095386, 21319.0, 21538.13847446116, 21622.567548648938, 21953.121875125136, 11389.259698119597, 11588.246763960316, 11714.121440891968, 11721.4139293035, 11834.822477662841, 7788.7171114849825, 8076.798080048045, 8091.3817236554105, 8121.918780812116, 8235.38234632393, 18349.266029358678, 18653.81346186575, 18933.810661893203, 19133.425997220107, 19404.8059519403, 19513.609727805648, 19543.60912781765, 19548.12033458469, 19581.70627440561, 19771.802281977572, 11081.612143574936, 11560.595379161688, 11589.6523104307, 11618.186726929034, 11618.244814087057, 14299.57101286963, 14304.356303966139, 14364.28178591087, 14507.637309067357, 14510.492132775302, 11642.6507204784, 11760.529578816751, 11805.527778888749, 11806.822897656713, 12209.145359825083, 11447.02700270453, 11474.196806223485, 11659.242149260272, 11677.591284305348, 11892.227005244631, 10401.27191096616, 10430.947845260725, 10450.895491044992, 10520.210984176065, 10558.260921735711, 6603.075569420189, 6677.03183038447, 6682.231543372574, 6709.396154346132, 6768.289329620319, 6547.803565893031, 6599.835004124935, 6603.273639899703, 6683.8997415039785, 6770.492213084137], "value": 6741.34598349574, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.5105524224365, 522.380620077759, 534.2733881920587, 535.6491498068765, 549.4423160492099, 6335.398137176972, 6360.300366959631, 6368.745250189987, 6434.195725534309, 6441.452476539494, 6287.339829317924, 6361.840953976153, 6370.840728981778, 6375.330590288023, 6389.616623002623, 5254.8686282842855, 5289.709066001368, 5306.628536002481, 5322.520973112419, 5346.358436987567, 4868.172410690191, 4980.626453016038, 5162.174052151657, 5187.0, 5273.57811375091, 4964.131277026524, 4986.601071914238, 4989.203886600821, 5015.348004759376, 5057.5701065409485, 4990.556516003946, 4998.1260775054125, 5041.143005689041, 5042.268871013719, 5071.7210553419545, 4359.6076353128055, 4525.570070843265, 4526.4794548627, 4537.024539723975, 4555.794989225493, 5995.490383317379, 6059.515238780931, 6071.846349193639, 6108.511319094462, 6182.474489668383, 5691.463304907693, 5702.629329093637, 5708.943845388575, 5728.656280623134, 5748.913766293509, 6431.302001349243, 6469.191351081115, 6501.6749162542155, 6526.445252153572, 6529.0, 4830.782614782308, 4879.6580940241365, 4911.729854857981, 4928.43323017854, 4943.752812359404, 6612.471002319803, 6703.530752847254, 6795.626240556768, 6809.582598177575, 6849.828754281133, 4982.626303027288, 5038.546530812245, 5059.87350316245, 5060.544550990428, 5063.822766203165, 5076.670016448919, 5093.185090385595, 5121.641485096007, 5134.666246694028, 5141.203113517411, 5735.713214339266, 5753.0, 5767.163761254595, 5774.364819869811, 5776.624519406225, 6554.4756419487285, 6583.308752581006, 6638.966805165944, 6659.667016649245, 6664.133662623828, 1768.982834869955, 1774.086345532052, 1779.67076090921, 1784.1525275494037, 1794.4347530527841, 1140.1733743036232, 1223.253815172744, 1225.8966929763285, 1244.6950497128262, 1246.1588427811373, 5636.689982050944, 5683.573731970118, 5692.943070569324, 5693.60144789869, 5693.629914055573, 6602.40578347951, 6651.235107962646, 6686.765963191265, 6798.430823817436, 6807.387335139762, 5620.651043749471, 5703.657780533182, 5712.971435142797, 5730.426957304237, 5760.798372056958, 3488.89533314001, 3508.929821403609, 3518.3666939951054, 3538.6461353864925, 3564.4475106358555, 6381.3937675920715, 6442.133250703486, 6530.934690653127, 6555.541112122201, 6562.606243625398, 6238.844028899314, 6711.281930818027, 6882.346177113166, 6898.586084834926, 6963.303669632996, 6408.455281301049, 6436.3563643637135, 6438.555372875489, 6441.64570948593, 6442.420182183626, 6702.296258892746, 6712.664366781739, 6756.054152418567, 6795.864082718219, 6803.693833777591, 6633.9336606633315, 6670.73256081343, 6675.295211157854, 6690.190120149451, 6695.4308883745425, 6414.679266036773, 6493.935060649522, 6526.0, 6527.2167339919515, 6588.604683718992, 5037.6927726793065, 5046.268291097844, 5074.644774865724, 5095.41402738675, 5106.642535022513, 6443.548951573533, 6474.708638111202, 6575.408213260638, 6606.6036037837885, 6702.698378572878, 6280.152179455715, 6444.8388790280615, 6454.515911306765, 6497.967510162419, 6562.606243625398, 6394.808155755335, 6451.3226111257645, 6451.903221451588, 6493.045664222862, 6519.869602607826, 6382.712777924911, 6445.25879523842, 6453.483721302191, 6501.0, 6513.478921686348, 6619.238787539494, 6619.312075420737, 6624.9337506624315, 6658.201015878126, 6676.232233293234, 6352.650604216812, 6366.427021568081, 6370.267419246846, 6394.488440924808, 6428.260750013808], "value": 5077.062817236589, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [894.7718331825383, 899.0874262623437, 904.8009437923655, 910.7950711090006, 918.0865039285911, 19671.0, 20561.897190514064, 20724.27465038724, 20761.792382076175, 20846.166153353868, 17321.307147714077, 17511.0, 17517.386891458802, 17559.29762809486, 17881.731774023367, 978.0, 991.1575161113068, 994.2791476179784, 995.0894931138, 995.8854731705837, 926.1386910173536, 990.5047476261869, 991.2714155095998, 993.7217579077869, 994.3387647214594, 998.4059484606647, 1028.2596530498029, 1034.2966782587837, 1034.5137785240925, 1039.1842403713088, 964.5948701545368, 1014.2494554030038, 1016.4104819204844, 1018.2973748113815, 1019.6838979916249, 732.3957734868754, 742.6435311050726, 749.4603885202699, 749.8425330680564, 750.9436792240604, 18673.906630466896, 18880.150393232336, 19018.714719279084, 19033.714494282598, 19044.0, 21877.562448751094, 21963.780362196332, 22860.428489287733, 22876.771232287796, 22978.885105574533, 17185.48443546683, 17286.13569321529, 17349.913250433794, 17601.7359739604, 17687.646247075114, 3664.633536646341, 3736.6823819975334, 3737.121776382548, 3738.4392341148773, 3740.3828368319196, 108414.45792771064, 132821.33589331992, 133100.0, 133886.0, 135234.0, 17448.912755436395, 18065.09674516269, 18152.546186345182, 18157.45527634173, 18204.271829126952, 31429.371412572076, 32696.182595435304, 32718.0, 32895.342093158004, 33064.173395665304, 9117.772055698528, 9263.629454821867, 9265.0, 9291.163795258459, 9329.11373419524, 4247.362895565628, 4360.585744354281, 4379.890502737457, 4380.956190438119, 4391.802368893407, 5430.0, 5524.281843360337, 5555.50000499957, 5567.387587365387, 5583.441655834466, 1045.3048722599538, 1097.13874608432, 1103.1946678924269, 1115.219346457483, 1123.8988491035846, 665.3246954341347, 679.1273213920089, 686.7905288886838, 695.1623293930801, 702.1048163591462, 12113.212641178296, 12252.693682658004, 12374.071944604191, 12498.812517812241, 12832.037597180062, 615.5229696984873, 626.1765778001912, 629.7134803664321, 631.43486579512, 641.0896526931768, 5405.648632838853, 5433.022056029874, 5487.396386397493, 5537.58468114893, 5613.326400831845, 120974.0, 123090.0, 124403.0, 125600.0, 127358.36320818523, 107821.0, 108287.0, 109523.45238273758, 110031.0, 110709.0, 90187.54906225427, 90564.0, 93680.53159734157, 94510.5274473637, 95852.0414795857, 10301.433421161608, 10356.844647330145, 10521.526531306263, 10592.046715795308, 10624.150067994387, 43532.564674354115, 43975.560244397144, 44353.0, 44533.33200001938, 45895.0, 56627.71686141543, 56746.14880776874, 57997.0, 58754.706226468596, 59475.0, 15764.369425222863, 15899.523014309589, 16101.919490402941, 16176.757348640016, 16194.757078643594, 18094.5476363092, 18356.0, 18460.538486537946, 18497.260109595467, 18685.532861678024, 15600.609984750472, 15873.841261587237, 15939.521814345588, 16068.276927538524, 16134.838651613334, 15206.847931520986, 15445.382184712485, 15502.5349239523, 15660.530184094496, 15686.843131568538, 15317.540473785804, 15452.690946181237, 15574.610634734223, 15740.92129539345, 15773.842261577238, 11607.36159511235, 11757.823632645346, 11859.940700296444, 11974.22167559106, 12065.758684826429, 11975.341356225492, 12012.459439325427, 12364.258144511357, 12495.500179992698, 12500.124991250525], "value": 8979.048301768687, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1420.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 108.0, 107.0, 107.0, 109.0, 108.0, 108.0, 107.0, 107.0, 108.0], "value": 107.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 116.0, 154.0, 118.0, 118.0, 117.0, 115.0, 116.0, 116.0, 117.0], "value": 120.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 161.0, 161.0, 165.0, 163.0, 164.0, 164.0, 165.0, 168.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 74.0, 75.0, 83.0, 74.0, 74.0, 74.0, 76.0, 74.0], "value": 75.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 80.0, 79.0, 85.0, 78.0, 80.0, 81.0, 79.0, 79.0], "value": 80.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 114.0, 113.0, 115.0, 113.0, 115.0, 116.0, 115.0, 115.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 147.0, 147.0, 149.0, 147.0, 147.0, 149.0, 149.0, 148.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 53.0, 55.0, 56.0, 54.0, 52.0, 51.0, 52.0, 52.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 39.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 73.0, 73.0, 73.0, 73.0, 75.0, 74.0, 73.0, 74.0, 73.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 111.0, 115.0, 112.0, 115.0, 109.0, 115.0, 111.0, 117.0, 117.0], "value": 113.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 174.0, 173.0, 166.0, 169.0, 169.0, 175.0, 170.0, 165.0, 173.0], "value": 170.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 67.0, 63.0, 65.0, 64.0, 65.0, 64.0, 64.0, 66.0], "value": 65.3, "unit": "score"}]}]} 11:46:47 INFO - Return code: 0 11:46:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:46:47 INFO - # TBPL SUCCESS # 11:46:47 INFO - Running post-action listener: _package_coverage_data 11:46:47 INFO - Running post-action listener: _resource_record_post_action 11:46:47 INFO - [mozharness: 2017-09-23 18:46:47.667174Z] Finished run-tests step (success) 11:46:47 INFO - Running post-run listener: _resource_record_post_run 11:46:47 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 8192; Write bytes: 414126080; Read time: 20; Write time: 244608 11:46:47 INFO - TinderboxPrint: CPU usage
13.1% 11:46:47 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 20 11:46:47 INFO - TinderboxPrint: I/O write bytes / time
414,126,080 / 244,608 11:46:47 INFO - TinderboxPrint: CPU idle
8,220.8 (86.9%) 11:46:47 INFO - TinderboxPrint: CPU user
1,192.3 (12.6%) 11:46:47 INFO - TinderboxPrint: Swap in / out
0 / 0 11:46:47 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 85725184; Read time: 0; Write time: 52184 11:46:47 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:46:47 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 4096; Write bytes: 328400896; Read time: 8; Write time: 192424 11:46:48 INFO - Running post-run listener: _upload_blobber_files 11:46:48 INFO - Blob upload gear active. 11:46:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:46:48 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:46:48 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:46:48 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:46:48 INFO - (blobuploader) - INFO - Open directory for files ... 11:46:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:46:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:46:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:49 INFO - (blobuploader) - INFO - Done attempting. 11:46:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:46:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:46:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:50 INFO - (blobuploader) - INFO - Done attempting. 11:46:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:46:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:46:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:51 INFO - (blobuploader) - INFO - Done attempting. 11:46:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:46:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:46:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:58 INFO - (blobuploader) - INFO - Done attempting. 11:46:58 INFO - (blobuploader) - INFO - Iteration through files over. 11:46:58 INFO - Return code: 0 11:46:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:46:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:46:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3d20658bf3546a714b657d470100a0a7ef9bf8332a4424f3db936bafa25be4ec95a477b055d81ac94bf4b86473bfd63bd30a3a40f68c949310921b98dae0916a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/afa9fc5fd96bfb5956eefaf99abcc6b834eea90556d7de0a391fabbf09c3f529d3fbab0b6bd085d08261520a030938c1cf764021b97dc2dbbda8ed088beeeefb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/08995ebd6d5728485354517acc9d04a423378aadb7998c3b3ce765bd2d07e982ca9a939ea3360f3e7ed626bfad03ee95e4acf6635e272ccc9d43ab26f771f1b5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1aa146735a8c9ea9279ca14851ac9cfb9320afd560a4073237ae8c683d6dae095f52744fc7f95e480c51335120c2d945df8bdaf4a6ab13300dd992dd98f99c3b"} 11:46:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:46:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:46:58 INFO - Contents: 11:46:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3d20658bf3546a714b657d470100a0a7ef9bf8332a4424f3db936bafa25be4ec95a477b055d81ac94bf4b86473bfd63bd30a3a40f68c949310921b98dae0916a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/afa9fc5fd96bfb5956eefaf99abcc6b834eea90556d7de0a391fabbf09c3f529d3fbab0b6bd085d08261520a030938c1cf764021b97dc2dbbda8ed088beeeefb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/08995ebd6d5728485354517acc9d04a423378aadb7998c3b3ce765bd2d07e982ca9a939ea3360f3e7ed626bfad03ee95e4acf6635e272ccc9d43ab26f771f1b5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1aa146735a8c9ea9279ca14851ac9cfb9320afd560a4073237ae8c683d6dae095f52744fc7f95e480c51335120c2d945df8bdaf4a6ab13300dd992dd98f99c3b"} 11:46:58 INFO - Running post-run listener: copy_logs_to_upload_dir 11:46:58 INFO - Copying logs to upload dir... 11:46:58 INFO - mkdir: /builds/slave/test/build/upload/logs 11:46:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.683075 ========= master_lag: -0.10 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-09-23 11:46:58.503964) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 11:46:58.508325) ========= 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=c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3d20658bf3546a714b657d470100a0a7ef9bf8332a4424f3db936bafa25be4ec95a477b055d81ac94bf4b86473bfd63bd30a3a40f68c949310921b98dae0916a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/afa9fc5fd96bfb5956eefaf99abcc6b834eea90556d7de0a391fabbf09c3f529d3fbab0b6bd085d08261520a030938c1cf764021b97dc2dbbda8ed088beeeefb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/08995ebd6d5728485354517acc9d04a423378aadb7998c3b3ce765bd2d07e982ca9a939ea3360f3e7ed626bfad03ee95e4acf6635e272ccc9d43ab26f771f1b5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1aa146735a8c9ea9279ca14851ac9cfb9320afd560a4073237ae8c683d6dae095f52744fc7f95e480c51335120c2d945df8bdaf4a6ab13300dd992dd98f99c3b"} build_url:https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011390 build_url: 'https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3d20658bf3546a714b657d470100a0a7ef9bf8332a4424f3db936bafa25be4ec95a477b055d81ac94bf4b86473bfd63bd30a3a40f68c949310921b98dae0916a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/afa9fc5fd96bfb5956eefaf99abcc6b834eea90556d7de0a391fabbf09c3f529d3fbab0b6bd085d08261520a030938c1cf764021b97dc2dbbda8ed088beeeefb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/08995ebd6d5728485354517acc9d04a423378aadb7998c3b3ce765bd2d07e982ca9a939ea3360f3e7ed626bfad03ee95e4acf6635e272ccc9d43ab26f771f1b5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1aa146735a8c9ea9279ca14851ac9cfb9320afd560a4073237ae8c683d6dae095f52744fc7f95e480c51335120c2d945df8bdaf4a6ab13300dd992dd98f99c3b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 11:46:58.539403) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:46:58.539748) ========= 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=c6927cf182745ad25b1d38b9000001fe-1506182146.365549-1501719629 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004606 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:46:58.621867) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 11:46:58.622180) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 11:46:58.622484) ========= ========= Total master_lag: 0.11 =========