builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-030 starttime: 1504823939.71 results: success (0) revision: 64bf417d1bdf9bb8b562d73cc0742b1ec60a8d0e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 15:38:59.705844) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 15:38:59.706330) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 15:38:59.706604) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 15:38:59.722112) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:38:59.722553) ========= 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=3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-07 15:38:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 109M=0s 2017-09-07 15:38:59 (109 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120316 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:38:59.860211) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:38:59.860524) ========= 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=3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018534 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:38:59.910292) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:38:59.910629) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 64bf417d1bdf9bb8b562d73cc0742b1ec60a8d0e --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 64bf417d1bdf9bb8b562d73cc0742b1ec60a8d0e --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=3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-07 15:38:59,990 truncating revision to first 12 chars 2017-09-07 15:38:59,990 Setting DEBUG logging. 2017-09-07 15:38:59,990 attempt 1/10 2017-09-07 15:38:59,990 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/64bf417d1bdf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-07 15:39:00,368 unpacking tar archive at: mozilla-central-64bf417d1bdf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.650910 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:39:00.577903) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 15:39:00.578252) ========= script_repo_revision: 64bf417d1bdf9bb8b562d73cc0742b1ec60a8d0e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 15:39:00.578635) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 15:39:00.578898) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 15:39:00.591855) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-07 15:39:00.592256) ========= /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=3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:39:00 INFO - MultiFileLogger online at 20170907 15:39:00 in /builds/slave/test 15:39:00 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 15:39:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:39:00 INFO - {'append_to_log': False, 15:39:00 INFO - 'base_work_dir': '/builds/slave/test', 15:39:00 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:39:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:39:00 INFO - 'branch': 'Firefox-Non-PGO', 15:39:00 INFO - 'buildbot_json_path': 'buildprops.json', 15:39:00 INFO - 'code_coverage': False, 15:39:00 INFO - 'config_files': ('talos/linux_config.py',), 15:39:00 INFO - 'default_actions': ('clobber', 15:39:00 INFO - 'read-buildbot-config', 15:39:00 INFO - 'download-and-extract', 15:39:00 INFO - 'populate-webroot', 15:39:00 INFO - 'create-virtualenv', 15:39:00 INFO - 'install', 15:39:00 INFO - 'setup-mitmproxy', 15:39:00 INFO - 'run-tests'), 15:39:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:39:00 INFO - 'disable_ccov_upload': False, 15:39:00 INFO - 'disable_stylo': False, 15:39:00 INFO - 'download_minidump_stackwalk': True, 15:39:00 INFO - 'download_symbols': 'ondemand', 15:39:00 INFO - 'enable_stylo': False, 15:39:00 INFO - 'enable_webrender': False, 15:39:00 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:39:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:39:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:39:00 INFO - 'gecko_profile': False, 15:39:00 INFO - 'gecko_profile_interval': 0, 15:39:00 INFO - 'installer_path': 'installer.exe', 15:39:00 INFO - 'log_level': 'info', 15:39:00 INFO - 'log_name': 'talos', 15:39:00 INFO - 'log_to_console': True, 15:39:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:39:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:39:00 INFO - 'opt_config_files': (), 15:39:00 INFO - 'pip_index': False, 15:39:00 INFO - 'suite': 'dromaeojs-e10s', 15:39:00 INFO - 'system_bits': '32', 15:39:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:39:00 INFO - 'title': 'talos-linux64-ix-030', 15:39:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:39:00 INFO - 'use_talos_json': True, 15:39:00 INFO - 'verify': 'False', 15:39:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:39:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:39:00 INFO - 'work_dir': 'build'} 15:39:00 INFO - [mozharness: 2017-09-07 22:39:00.804370Z] Running clobber step. 15:39:00 INFO - Running pre-action listener: _resource_record_pre_action 15:39:00 INFO - Running main action method: clobber 15:39:00 INFO - rmtree: /builds/slave/test/build 15:39:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:39:02 INFO - Running post-action listener: _resource_record_post_action 15:39:02 INFO - [mozharness: 2017-09-07 22:39:02.487529Z] Finished clobber step (success) 15:39:02 INFO - [mozharness: 2017-09-07 22:39:02.487627Z] Running read-buildbot-config step. 15:39:02 INFO - Running pre-action listener: _resource_record_pre_action 15:39:02 INFO - Running main action method: read_buildbot_config 15:39:02 INFO - Using buildbot properties: 15:39:02 INFO - { 15:39:02 INFO - "project": "", 15:39:02 INFO - "product": "firefox", 15:39:02 INFO - "who": "archaeopteryx@coole-files.de", 15:39:02 INFO - "installer_path": "public/build/target.tar.bz2", 15:39:02 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:39:02 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:39:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:39:02 INFO - "stage_platform": "linux64", 15:39:02 INFO - "basedir": "/builds/slave/test", 15:39:02 INFO - "buildnumber": 1123, 15:39:02 INFO - "platform": "ubuntu64_hw", 15:39:02 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:39:02 INFO - "slavebuilddir": "test", 15:39:02 INFO - "taskId": "RlLXc9qJQKm57SxZjPlDGg", 15:39:02 INFO - "branch": "mozilla-central", 15:39:02 INFO - "script_repo_revision": "production", 15:39:02 INFO - "revision": "64bf417d1bdf9bb8b562d73cc0742b1ec60a8d0e", 15:39:02 INFO - "slavename": "talos-linux64-ix-030", 15:39:02 INFO - "repo_path": "mozilla-central" 15:39:02 INFO - } 15:39:02 INFO - Finding installer, test and symbols from parent task. 15:39:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RlLXc9qJQKm57SxZjPlDGg'}, attempt #1 15:39:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RlLXc9qJQKm57SxZjPlDGg'}, attempt #1 15:39:03 INFO - Task dependencies: HU_acp97TAesHcVo7pnBow 15:39:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow'}, attempt #1 15:39:03 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.tar.bz2 15:39:03 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.test_packages.json 15:39:03 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.crashreporter-symbols.zip 15:39:03 INFO - Running post-action listener: _resource_record_post_action 15:39:03 INFO - [mozharness: 2017-09-07 22:39:03.827038Z] Finished read-buildbot-config step (success) 15:39:03 INFO - [mozharness: 2017-09-07 22:39:03.827257Z] Running download-and-extract step. 15:39:03 INFO - Running pre-action listener: _resource_record_pre_action 15:39:03 INFO - Running main action method: download_and_extract 15:39:03 INFO - mkdir: /builds/slave/test/build/tests 15:39:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:03 INFO - trying https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.test_packages.json 15:39:03 INFO - Downloading https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:39:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:39:04 INFO - Downloaded 989 bytes. 15:39:04 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:39:04 INFO - Using the following test package requirements: 15:39:04 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:39:04 INFO - u'common': [u'target.common.tests.zip'], 15:39:04 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:39:04 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:39:04 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:39:04 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:39:04 INFO - u'mozbase': [u'target.common.tests.zip'], 15:39:04 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:39:04 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:39:04 INFO - u'web-platform': [u'target.common.tests.zip', 15:39:04 INFO - u'target.web-platform.tests.tar.gz'], 15:39:04 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:39:04 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:39:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.common.tests.zip 15:39:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:39:04 INFO - Fetch https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.common.tests.zip into memory 15:39:06 INFO - Content-Length response header: 55021152 15:39:06 INFO - Bytes received: 55021152 15:39:17 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:39:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.common.tests.zip 15:39:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:39:17 INFO - Fetch https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.common.tests.zip into memory 15:39:18 INFO - Content-Length response header: 55021152 15:39:18 INFO - Bytes received: 55021152 15:39:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.talos.tests.zip 15:39:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:39:30 INFO - Fetch https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.talos.tests.zip into memory 15:39:31 INFO - Content-Length response header: 14496665 15:39:31 INFO - Bytes received: 14496665 15:39:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:31 INFO - trying https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.tar.bz2 15:39:31 INFO - Downloading https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:39:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:39:33 INFO - Downloaded 62707151 bytes. 15:39:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.tar.bz2 15:39:33 INFO - mkdir: /builds/slave/test/properties 15:39:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:39:33 INFO - Writing to file /builds/slave/test/properties/build_url 15:39:33 INFO - Contents: 15:39:33 INFO - build_url:https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.tar.bz2 15:39:33 INFO - Running post-action listener: _resource_record_post_action 15:39:33 INFO - Running post-action listener: find_tests_for_verification 15:39:33 INFO - Running post-action listener: set_extra_try_arguments 15:39:33 INFO - [mozharness: 2017-09-07 22:39:33.136834Z] Finished download-and-extract step (success) 15:39:33 INFO - [mozharness: 2017-09-07 22:39:33.136931Z] Running populate-webroot step. 15:39:33 INFO - Running pre-action listener: _resource_record_pre_action 15:39:33 INFO - Running main action method: populate_webroot 15:39:33 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:39:33 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:39:33 INFO - 'tests': ['tresize', 15:39:33 INFO - 'tcanvasmark']}, 15:39:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:39:33 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:39:33 INFO - 'tests': ['dromaeo_css', 15:39:33 INFO - 'kraken']}, 15:39:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:39:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:39:33 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:39:33 INFO - 'talos_options': ['--disable-stylo'], 15:39:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:39:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:39:33 INFO - 'tests': ['damp', 'tps']}, 15:39:33 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:39:33 INFO - 'talos_options': ['--disable-stylo'], 15:39:33 INFO - 'tests': ['damp', 'tps']}, 15:39:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:39:33 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:39:33 INFO - 'tests': ['dromaeo_dom']}, 15:39:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:39:33 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:39:33 INFO - 'tests': ['basic_compositor_video', 15:39:33 INFO - 'glvideo']}, 15:39:33 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:39:33 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:39:33 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:39:33 INFO - 'talos_options': ['--disable-stylo'], 15:39:33 INFO - 'tests': ['ts_paint_webext', 15:39:33 INFO - 'tp5o_webext']}, 15:39:33 INFO - 'other-e10s': {'tests': ['a11yr', 15:39:33 INFO - 'ts_paint', 15:39:33 INFO - 'tpaint', 15:39:33 INFO - 'sessionrestore', 15:39:33 INFO - 'sessionrestore_many_windows', 15:39:33 INFO - 'sessionrestore_no_auto_restore', 15:39:33 INFO - 'tabpaint']}, 15:39:33 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:39:33 INFO - 'tests': ['a11yr', 15:39:33 INFO - 'ts_paint', 15:39:33 INFO - 'tpaint', 15:39:33 INFO - 'sessionrestore', 15:39:33 INFO - 'sessionrestore_many_windows', 15:39:33 INFO - 'sessionrestore_no_auto_restore', 15:39:33 INFO - 'tabpaint']}, 15:39:33 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:39:33 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:39:33 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:39:33 INFO - 'tests': ['bloom_basic_singleton']}, 15:39:33 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:39:33 INFO - 'tests': ['bloom_basic']}, 15:39:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:39:33 INFO - 'tsvgr_opacity', 15:39:33 INFO - 'tart', 15:39:33 INFO - 'tscrollx', 15:39:33 INFO - 'tsvg_static']}, 15:39:33 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:39:33 INFO - 'tests': ['tsvgx', 15:39:33 INFO - 'tsvgr_opacity', 15:39:33 INFO - 'tart', 15:39:33 INFO - 'tscrollx', 15:39:33 INFO - 'tsvg_static']}, 15:39:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:39:33 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:39:33 INFO - 'talos_options': ['--disable-stylo'], 15:39:33 INFO - 'tests': ['tp5o']}, 15:39:33 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:39:33 INFO - 'talos_options': ['--mitmproxy', 15:39:33 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:39:33 INFO - '--firstNonBlankPaint'], 15:39:33 INFO - 'tests': ['tp6_google', 15:39:33 INFO - 'tp6_youtube', 15:39:33 INFO - 'tp6_amazon', 15:39:33 INFO - 'tp6_facebook']}, 15:39:33 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:39:33 INFO - 'talos_options': ['--disable-stylo', 15:39:33 INFO - '--mitmproxy', 15:39:33 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:39:33 INFO - '--firstNonBlankPaint'], 15:39:33 INFO - 'tests': ['tp6_google', 15:39:33 INFO - 'tp6_youtube', 15:39:33 INFO - 'tp6_amazon', 15:39:33 INFO - 'tp6_facebook']}, 15:39:33 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:39:33 INFO - 'talos_options': ['--stylo-threads=1', 15:39:33 INFO - '--mitmproxy', 15:39:33 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:39:33 INFO - '--firstNonBlankPaint'], 15:39:33 INFO - 'tests': ['tp6_google', 15:39:33 INFO - 'tp6_youtube', 15:39:33 INFO - 'tp6_amazon', 15:39:33 INFO - 'tp6_facebook']}, 15:39:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:39:33 INFO - 'talos_options': ['--xperf_path', 15:39:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:39:33 INFO - 'tests': ['tp5n']}, 15:39:33 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:39:33 INFO - 'talos_options': ['--disable-stylo', 15:39:33 INFO - '--xperf_path', 15:39:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:39:33 INFO - 'tests': ['tp5n']}}} 15:39:33 INFO - Running post-action listener: _resource_record_post_action 15:39:33 INFO - [mozharness: 2017-09-07 22:39:33.151812Z] Finished populate-webroot step (success) 15:39:33 INFO - [mozharness: 2017-09-07 22:39:33.151901Z] Running create-virtualenv step. 15:39:33 INFO - Running pre-action listener: _resource_record_pre_action 15:39:33 INFO - Running main action method: create_virtualenv 15:39:33 INFO - Creating virtualenv /builds/slave/test/build/venv 15:39:33 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:39:33 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:39:33 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:39:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:39:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:39:33 INFO - Installing distribute.............................................................................................................................................................................................done. 15:39:34 INFO - Installing pip...............done. 15:39:34 INFO - Return code: 0 15:39:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:39:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:39:34 INFO - Reading from file tmpfile_stdout 15:39:34 INFO - Output received: 15:39:34 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:39:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:39:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d77a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e090>, '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 0x1f1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f18f90>, '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': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', '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 15:39:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:39:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:39:34 INFO - Using env: {'DISPLAY': ':0', 15:39:34 INFO - 'HOME': '/home/cltbld', 15:39:34 INFO - 'LANG': 'en_US.UTF-8', 15:39:34 INFO - 'LANGUAGE': 'en_US:en', 15:39:34 INFO - 'LOGNAME': 'cltbld', 15:39:34 INFO - 'MAIL': '/var/mail/cltbld', 15:39:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:34 INFO - 'MOZ_NO_REMOTE': '1', 15:39:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:34 INFO - 'NO_EM_RESTART': '1', 15:39:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:34 INFO - 'PWD': '/builds/slave/test', 15:39:34 INFO - 'SHELL': '/bin/bash', 15:39:34 INFO - 'SHLVL': '1', 15:39:34 INFO - 'TERM': 'linux', 15:39:34 INFO - 'TMOUT': '86400', 15:39:34 INFO - 'USER': 'cltbld', 15:39:34 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', 15:39:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:34 INFO - '_': '/tools/buildbot/bin/python'} 15:39:35 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:39:35 INFO - Downloading/unpacking pip>=1.5 15:39:35 INFO - Running setup.py egg_info for package pip 15:39:35 INFO - warning: no files found matching 'pip/cacert.pem' 15:39:35 INFO - warning: no files found matching '*.html' under directory 'docs' 15:39:35 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:39:35 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:39:35 INFO - Installing collected packages: pip 15:39:35 INFO - Found existing installation: pip 1.1 15:39:35 INFO - Uninstalling pip: 15:39:35 INFO - Successfully uninstalled pip 15:39:35 INFO - Running setup.py install for pip 15:39:35 INFO - warning: no files found matching 'pip/cacert.pem' 15:39:35 INFO - warning: no files found matching '*.html' under directory 'docs' 15:39:35 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:39:35 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:39:35 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:39:35 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:39:35 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:39:35 INFO - Successfully installed pip 15:39:35 INFO - Cleaning up... 15:39:35 INFO - Return code: 0 15:39:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:39:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:35 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 0x1bae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d77a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e090>, '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 0x1f1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f18f90>, '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': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', '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 15:39:35 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 15:39:35 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 15:39:35 INFO - Using env: {'DISPLAY': ':0', 15:39:35 INFO - 'HOME': '/home/cltbld', 15:39:35 INFO - 'LANG': 'en_US.UTF-8', 15:39:35 INFO - 'LANGUAGE': 'en_US:en', 15:39:35 INFO - 'LOGNAME': 'cltbld', 15:39:35 INFO - 'MAIL': '/var/mail/cltbld', 15:39:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:35 INFO - 'MOZ_NO_REMOTE': '1', 15:39:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:35 INFO - 'NO_EM_RESTART': '1', 15:39:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:35 INFO - 'PWD': '/builds/slave/test', 15:39:35 INFO - 'SHELL': '/bin/bash', 15:39:35 INFO - 'SHLVL': '1', 15:39:35 INFO - 'TERM': 'linux', 15:39:35 INFO - 'TMOUT': '86400', 15:39:35 INFO - 'USER': 'cltbld', 15:39:35 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', 15:39:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:35 INFO - '_': '/tools/buildbot/bin/python'} 15:39:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:35 INFO - Downloading/unpacking psutil>=3.1.1 15:39:35 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 15:39:35 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 15:39:35 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 15:39:35 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 15:39:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:39:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:36 INFO - Installing collected packages: psutil 15:39:36 INFO - Running setup.py install for psutil 15:39:36 INFO - building 'psutil._psutil_linux' extension 15:39:36 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 15:39:37 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 15:39:37 INFO - building 'psutil._psutil_posix' extension 15:39:37 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 15:39:37 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 15:39:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:37 INFO - Successfully installed psutil 15:39:37 INFO - Cleaning up... 15:39:37 INFO - Return code: 0 15:39:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:39:37 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')]} 15:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:37 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')]} 15:39:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:37 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 0x1bae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d77a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e090>, '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 0x1f1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f18f90>, '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': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', '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 15:39:37 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 15:39:37 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 15:39:37 INFO - Using env: {'DISPLAY': ':0', 15:39:37 INFO - 'HOME': '/home/cltbld', 15:39:37 INFO - 'LANG': 'en_US.UTF-8', 15:39:37 INFO - 'LANGUAGE': 'en_US:en', 15:39:37 INFO - 'LOGNAME': 'cltbld', 15:39:37 INFO - 'MAIL': '/var/mail/cltbld', 15:39:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:37 INFO - 'MOZ_NO_REMOTE': '1', 15:39:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:37 INFO - 'NO_EM_RESTART': '1', 15:39:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:37 INFO - 'PWD': '/builds/slave/test', 15:39:37 INFO - 'SHELL': '/bin/bash', 15:39:37 INFO - 'SHLVL': '1', 15:39:37 INFO - 'TERM': 'linux', 15:39:37 INFO - 'TMOUT': '86400', 15:39:37 INFO - 'USER': 'cltbld', 15:39:37 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', 15:39:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:37 INFO - '_': '/tools/buildbot/bin/python'} 15:39:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:39:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:39:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:39:38 INFO - Installing collected packages: mozsystemmonitor 15:39:38 INFO - Running setup.py install for mozsystemmonitor 15:39:38 INFO - Successfully installed mozsystemmonitor 15:39:38 INFO - Cleaning up... 15:39:38 INFO - Return code: 0 15:39:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:38 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 0x1bae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d77a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e090>, '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 0x1f1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f18f90>, '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': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', '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 15:39:38 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 15:39:38 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 15:39:38 INFO - Using env: {'DISPLAY': ':0', 15:39:38 INFO - 'HOME': '/home/cltbld', 15:39:38 INFO - 'LANG': 'en_US.UTF-8', 15:39:38 INFO - 'LANGUAGE': 'en_US:en', 15:39:38 INFO - 'LOGNAME': 'cltbld', 15:39:38 INFO - 'MAIL': '/var/mail/cltbld', 15:39:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:38 INFO - 'MOZ_NO_REMOTE': '1', 15:39:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:38 INFO - 'NO_EM_RESTART': '1', 15:39:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:38 INFO - 'PWD': '/builds/slave/test', 15:39:38 INFO - 'SHELL': '/bin/bash', 15:39:38 INFO - 'SHLVL': '1', 15:39:38 INFO - 'TERM': 'linux', 15:39:38 INFO - 'TMOUT': '86400', 15:39:38 INFO - 'USER': 'cltbld', 15:39:38 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', 15:39:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:38 INFO - '_': '/tools/buildbot/bin/python'} 15:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:39 INFO - Downloading/unpacking jsonschema==2.5.1 15:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:39:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:39:40 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 15:39:40 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 15:39:40 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 15:39:40 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 15:39:40 INFO - Downloading functools32-3.2.3-2.tar.gz 15:39:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:39:40 INFO - warning: no files found matching '*.txt' 15:39:40 INFO - no previously-included directories found matching 'build' 15:39:40 INFO - no previously-included directories found matching 'dist' 15:39:40 INFO - no previously-included directories found matching '.git*' 15:39:40 INFO - Installing collected packages: jsonschema, functools32 15:39:40 INFO - Running setup.py install for functools32 15:39:40 INFO - warning: no files found matching '*.txt' 15:39:40 INFO - no previously-included directories found matching 'build' 15:39:40 INFO - no previously-included directories found matching 'dist' 15:39:40 INFO - no previously-included directories found matching '.git*' 15:39:40 INFO - Successfully installed jsonschema functools32 15:39:40 INFO - Cleaning up... 15:39:40 INFO - Return code: 0 15:39:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:39: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')]} 15:39:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39: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')]} 15:39:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39: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', '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 0x1bae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d77a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e090>, '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 0x1f1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f18f90>, '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': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', '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 15:39: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:39: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 functools32==3.2.3-2 15:39:40 INFO - Using env: {'DISPLAY': ':0', 15:39:40 INFO - 'HOME': '/home/cltbld', 15:39:40 INFO - 'LANG': 'en_US.UTF-8', 15:39:40 INFO - 'LANGUAGE': 'en_US:en', 15:39:40 INFO - 'LOGNAME': 'cltbld', 15:39:40 INFO - 'MAIL': '/var/mail/cltbld', 15:39:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:40 INFO - 'MOZ_NO_REMOTE': '1', 15:39:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:40 INFO - 'NO_EM_RESTART': '1', 15:39:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:40 INFO - 'PWD': '/builds/slave/test', 15:39:40 INFO - 'SHELL': '/bin/bash', 15:39:40 INFO - 'SHLVL': '1', 15:39:40 INFO - 'TERM': 'linux', 15:39:40 INFO - 'TMOUT': '86400', 15:39:40 INFO - 'USER': 'cltbld', 15:39:40 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', 15:39:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:40 INFO - '_': '/tools/buildbot/bin/python'} 15:39:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:39:40 INFO - Cleaning up... 15:39:40 INFO - Return code: 0 15:39:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:39: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')]} 15:39:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39: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')]} 15:39:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39: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', '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 0x1bae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d77a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e090>, '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 0x1f1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f18f90>, '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': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', '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 15:39: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:39: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 blobuploader==1.2.4 15:39:40 INFO - Using env: {'DISPLAY': ':0', 15:39:40 INFO - 'HOME': '/home/cltbld', 15:39:40 INFO - 'LANG': 'en_US.UTF-8', 15:39:40 INFO - 'LANGUAGE': 'en_US:en', 15:39:40 INFO - 'LOGNAME': 'cltbld', 15:39:40 INFO - 'MAIL': '/var/mail/cltbld', 15:39:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:40 INFO - 'MOZ_NO_REMOTE': '1', 15:39:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:40 INFO - 'NO_EM_RESTART': '1', 15:39:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:40 INFO - 'PWD': '/builds/slave/test', 15:39:40 INFO - 'SHELL': '/bin/bash', 15:39:40 INFO - 'SHLVL': '1', 15:39:40 INFO - 'TERM': 'linux', 15:39:40 INFO - 'TMOUT': '86400', 15:39:40 INFO - 'USER': 'cltbld', 15:39:40 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', 15:39:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:40 INFO - '_': '/tools/buildbot/bin/python'} 15:39:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:40 INFO - Downloading/unpacking blobuploader==1.2.4 15:39:40 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 15:39:40 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 15:39:40 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 15:39:40 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 15:39:42 INFO - Downloading blobuploader-1.2.4.tar.gz 15:39:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:39:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:39:42 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 15:39:42 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 15:39:42 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 15:39:42 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 15:39:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:39:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:39:42 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 15:39:42 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 15:39:42 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 15:39:42 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 15:39:42 INFO - Downloading docopt-0.6.1.tar.gz 15:39:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:39:42 INFO - Installing collected packages: blobuploader, requests, docopt 15:39:42 INFO - Running setup.py install for blobuploader 15:39:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:39:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:39:42 INFO - Running setup.py install for requests 15:39:43 INFO - Running setup.py install for docopt 15:39:43 INFO - Successfully installed blobuploader requests docopt 15:39:43 INFO - Cleaning up... 15:39:43 INFO - Return code: 0 15:39:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39: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')]} 15:39:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39: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')]} 15:39:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:43 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 0x1bae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d77a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e090>, '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 0x1f1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f18f90>, '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': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', '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 15:39:43 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 15:39:43 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 15:39:43 INFO - Using env: {'DISPLAY': ':0', 15:39:43 INFO - 'HOME': '/home/cltbld', 15:39:43 INFO - 'LANG': 'en_US.UTF-8', 15:39:43 INFO - 'LANGUAGE': 'en_US:en', 15:39:43 INFO - 'LOGNAME': 'cltbld', 15:39:43 INFO - 'MAIL': '/var/mail/cltbld', 15:39:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:43 INFO - 'MOZ_NO_REMOTE': '1', 15:39:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:43 INFO - 'NO_EM_RESTART': '1', 15:39:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:43 INFO - 'PWD': '/builds/slave/test', 15:39:43 INFO - 'SHELL': '/bin/bash', 15:39:43 INFO - 'SHLVL': '1', 15:39:43 INFO - 'TERM': 'linux', 15:39:43 INFO - 'TMOUT': '86400', 15:39:43 INFO - 'USER': 'cltbld', 15:39:43 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', 15:39:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:43 INFO - '_': '/tools/buildbot/bin/python'} 15:39:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:43 INFO - Running setup.py (path:/tmp/pip-vgi6Do-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:43 INFO - Running setup.py (path:/tmp/pip-_VmHg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:39:43 INFO - Running setup.py (path:/tmp/pip-drouuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:39:43 INFO - Running setup.py (path:/tmp/pip-3R8Pky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:39:43 INFO - Running setup.py (path:/tmp/pip-HR3wMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:39:43 INFO - Running setup.py (path:/tmp/pip-rucw6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:39:43 INFO - Running setup.py (path:/tmp/pip-mfYXOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:39:43 INFO - Running setup.py (path:/tmp/pip-MFKOCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:39:44 INFO - Running setup.py (path:/tmp/pip-Ovpm5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:39:44 INFO - Running setup.py (path:/tmp/pip-PLx2Ak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:39:44 INFO - Running setup.py (path:/tmp/pip-El6PyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:39:44 INFO - Running setup.py (path:/tmp/pip-1zHijf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:39:44 INFO - Running setup.py (path:/tmp/pip-udJbMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:39:44 INFO - Running setup.py (path:/tmp/pip-JqJk7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:44 INFO - Running setup.py (path:/tmp/pip-jWJOv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:39:44 INFO - Running setup.py (path:/tmp/pip-U7_0xt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:39:44 INFO - Running setup.py (path:/tmp/pip-mbMDh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:39:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:39:44 INFO - Running setup.py install for manifestparser 15:39:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:39:44 INFO - Running setup.py install for mozcrash 15:39:44 INFO - Running setup.py install for mozdebug 15:39:44 INFO - Running setup.py install for mozdevice 15:39:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:39:45 INFO - Running setup.py install for mozfile 15:39:45 INFO - Running setup.py install for mozhttpd 15:39:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:39:45 INFO - Running setup.py install for mozinfo 15:39:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:39:45 INFO - Running setup.py install for mozInstall 15:39:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:39:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:39:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:39:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:39:45 INFO - Running setup.py install for mozleak 15:39:45 INFO - Running setup.py install for mozlog 15:39:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:39:45 INFO - Running setup.py install for moznetwork 15:39:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:39:45 INFO - Running setup.py install for mozprocess 15:39:45 INFO - Running setup.py install for mozprofile 15:39:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:39:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:39:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:39:45 INFO - Running setup.py install for mozrunner 15:39:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:39:46 INFO - Running setup.py install for mozscreenshot 15:39:46 INFO - Running setup.py install for moztest 15:39:46 INFO - Running setup.py install for mozversion 15:39:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:39:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:39:46 INFO - Cleaning up... 15:39:46 INFO - Return code: 0 15:39:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39: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')]} 15:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39: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')]} 15:39:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:46 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 0x1bae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d77a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e090>, '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 0x1f1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f18f90>, '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': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', '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 15:39:46 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 15:39:46 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 15:39:46 INFO - Using env: {'DISPLAY': ':0', 15:39:46 INFO - 'HOME': '/home/cltbld', 15:39:46 INFO - 'LANG': 'en_US.UTF-8', 15:39:46 INFO - 'LANGUAGE': 'en_US:en', 15:39:46 INFO - 'LOGNAME': 'cltbld', 15:39:46 INFO - 'MAIL': '/var/mail/cltbld', 15:39:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:46 INFO - 'MOZ_NO_REMOTE': '1', 15:39:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:46 INFO - 'NO_EM_RESTART': '1', 15:39:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:46 INFO - 'PWD': '/builds/slave/test', 15:39:46 INFO - 'SHELL': '/bin/bash', 15:39:46 INFO - 'SHLVL': '1', 15:39:46 INFO - 'TERM': 'linux', 15:39:46 INFO - 'TMOUT': '86400', 15:39:46 INFO - 'USER': 'cltbld', 15:39:46 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', 15:39:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:46 INFO - '_': '/tools/buildbot/bin/python'} 15:39:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:46 INFO - Running setup.py (path:/tmp/pip-sk1mqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:46 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)) 15:39:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:46 INFO - Running setup.py (path:/tmp/pip-_F3ZsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:39:46 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)) 15:39:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:39:46 INFO - Running setup.py (path:/tmp/pip-0YY8n7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:39:46 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)) 15:39:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:39:46 INFO - Running setup.py (path:/tmp/pip-ePvoDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:39:46 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)) 15:39:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:39:46 INFO - Running setup.py (path:/tmp/pip-V4XLOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:39:46 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)) 15:39:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:39:46 INFO - Running setup.py (path:/tmp/pip-VqwwFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:39:47 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)) 15:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:39:47 INFO - Running setup.py (path:/tmp/pip-eMcd6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:39:47 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)) 15:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:39:47 INFO - Running setup.py (path:/tmp/pip-Nh5anQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:39:47 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)) 15:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:39:47 INFO - Running setup.py (path:/tmp/pip-mum8UB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:39:47 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)) 15:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:39:47 INFO - Running setup.py (path:/tmp/pip-biCXfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:39:47 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)) 15:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:39:47 INFO - Running setup.py (path:/tmp/pip-nfLGxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:39:47 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)) 15:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:39:47 INFO - Running setup.py (path:/tmp/pip-mf15sN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:39:47 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)) 15:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:39:47 INFO - Running setup.py (path:/tmp/pip-T9LRnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:39:47 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)) 15:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:39:47 INFO - Running setup.py (path:/tmp/pip-vRdos4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:39:47 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)) 15:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:47 INFO - Running setup.py (path:/tmp/pip-pTnyMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:47 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)) 15:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:39:47 INFO - Running setup.py (path:/tmp/pip-193IUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:39:47 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)) 15:39:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:39:47 INFO - Running setup.py (path:/tmp/pip-gKp_U8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:39:47 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)) 15:39:47 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)) 15:39:47 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)) 15:39:47 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)) 15:39:47 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)) 15:39:47 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)) 15:39:47 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)) 15:39:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:39: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 15:39: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 15:39: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 15:39: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 15:39:48 INFO - Downloading blessings-1.6.tar.gz 15:39:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:39:49 INFO - Installing collected packages: blessings 15:39:49 INFO - Running setup.py install for blessings 15:39:49 INFO - Successfully installed blessings 15:39:49 INFO - Cleaning up... 15:39:49 INFO - Return code: 0 15:39:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:39:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:39:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:39:49 INFO - Reading from file tmpfile_stdout 15:39:49 INFO - Current package versions: 15:39:49 INFO - argparse == 1.2.1 15:39:49 INFO - blessings == 1.6 15:39:49 INFO - blobuploader == 1.2.4 15:39:49 INFO - docopt == 0.6.1 15:39:49 INFO - functools32 == 3.2.3-2 15:39:49 INFO - jsonschema == 2.5.1 15:39:49 INFO - manifestparser == 1.1 15:39:49 INFO - mozInstall == 1.13 15:39:49 INFO - mozcrash == 1.0 15:39:49 INFO - mozdebug == 0.1 15:39:49 INFO - mozdevice == 0.51 15:39:49 INFO - mozfile == 1.2 15:39:49 INFO - mozhttpd == 0.7 15:39:49 INFO - mozinfo == 0.10 15:39:49 INFO - mozleak == 0.1 15:39:49 INFO - mozlog == 3.5 15:39:49 INFO - moznetwork == 0.27 15:39:49 INFO - mozprocess == 0.25 15:39:49 INFO - mozprofile == 0.28 15:39:49 INFO - mozrunner == 6.13 15:39:49 INFO - mozscreenshot == 0.1 15:39:49 INFO - mozsystemmonitor == 0.3 15:39:49 INFO - moztest == 0.8 15:39:49 INFO - mozversion == 1.4 15:39:49 INFO - psutil == 3.1.1 15:39:49 INFO - requests == 1.2.3 15:39:49 INFO - wsgiref == 0.1.2 15:39:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:49 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 0x1bae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d77a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e090>, '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 0x1f1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f18f90>, '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': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', '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 15:39:49 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 15:39:49 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 15:39:49 INFO - Using env: {'DISPLAY': ':0', 15:39:49 INFO - 'HOME': '/home/cltbld', 15:39:49 INFO - 'LANG': 'en_US.UTF-8', 15:39:49 INFO - 'LANGUAGE': 'en_US:en', 15:39:49 INFO - 'LOGNAME': 'cltbld', 15:39:49 INFO - 'MAIL': '/var/mail/cltbld', 15:39:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:49 INFO - 'MOZ_NO_REMOTE': '1', 15:39:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:49 INFO - 'NO_EM_RESTART': '1', 15:39:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:49 INFO - 'PWD': '/builds/slave/test', 15:39:49 INFO - 'SHELL': '/bin/bash', 15:39:49 INFO - 'SHLVL': '1', 15:39:49 INFO - 'TERM': 'linux', 15:39:49 INFO - 'TMOUT': '86400', 15:39:49 INFO - 'USER': 'cltbld', 15:39:49 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', 15:39:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:49 INFO - '_': '/tools/buildbot/bin/python'} 15:39:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:49 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)) 15:39:49 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)) 15:39:49 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)) 15:39:49 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)) 15:39:49 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)) 15:39:49 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)) 15:39:49 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)) 15:39:49 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)) 15:39:49 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)) 15:39:49 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:39: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 15:39: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 15:39: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 15:39: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 15:39:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:39:50 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)) 15:39:50 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)) 15:39:50 INFO - Installing collected packages: simplejson 15:39:50 INFO - Running setup.py install for simplejson 15:39:50 INFO - building 'simplejson._speedups' extension 15:39:50 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 15:39:51 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 15:39:51 INFO - Successfully installed simplejson 15:39:51 INFO - Cleaning up... 15:39:51 INFO - Return code: 0 15:39:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:39: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')]} 15:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39: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')]} 15:39:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:51 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 0x1bae1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d77a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e090>, '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 0x1f1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f18f90>, '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': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', '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 15:39:51 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 15:39:51 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 15:39:51 INFO - Using env: {'DISPLAY': ':0', 15:39:51 INFO - 'HOME': '/home/cltbld', 15:39:51 INFO - 'LANG': 'en_US.UTF-8', 15:39:51 INFO - 'LANGUAGE': 'en_US:en', 15:39:51 INFO - 'LOGNAME': 'cltbld', 15:39:51 INFO - 'MAIL': '/var/mail/cltbld', 15:39:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:51 INFO - 'MOZ_NO_REMOTE': '1', 15:39:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:51 INFO - 'NO_EM_RESTART': '1', 15:39:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:51 INFO - 'PWD': '/builds/slave/test', 15:39:51 INFO - 'SHELL': '/bin/bash', 15:39:51 INFO - 'SHLVL': '1', 15:39:51 INFO - 'TERM': 'linux', 15:39:51 INFO - 'TMOUT': '86400', 15:39:51 INFO - 'USER': 'cltbld', 15:39:51 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', 15:39:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:51 INFO - '_': '/tools/buildbot/bin/python'} 15:39:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:39:52 INFO - Cleaning up... 15:39:52 INFO - Return code: 0 15:39:52 INFO - Running post-action listener: _resource_record_post_action 15:39:52 INFO - Running post-action listener: _start_resource_monitoring 15:39:52 INFO - Starting resource monitoring. 15:39:52 INFO - [mozharness: 2017-09-07 22:39:52.102796Z] Finished create-virtualenv step (success) 15:39:52 INFO - [mozharness: 2017-09-07 22:39:52.103260Z] Running install step. 15:39:52 INFO - Running pre-action listener: _resource_record_pre_action 15:39:52 INFO - Running main action method: install 15:39:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:39:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:39:52 INFO - Reading from file tmpfile_stdout 15:39:52 INFO - Detecting whether we're running mozinstall >=1.0... 15:39:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:39:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:39:52 INFO - Reading from file tmpfile_stdout 15:39:52 INFO - Output received: 15:39:52 INFO - Usage: mozinstall [options] installer 15:39:52 INFO - Options: 15:39:52 INFO - -h, --help show this help message and exit 15:39:52 INFO - -d DEST, --destination=DEST 15:39:52 INFO - Directory to install application into. [default: 15:39:52 INFO - "/builds/slave/test"] 15:39:52 INFO - --app=APP Application being installed. [default: firefox] 15:39:52 INFO - mkdir: /builds/slave/test/build/application 15:39:52 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'] 15:39:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:40:08 INFO - Reading from file tmpfile_stdout 15:40:08 INFO - Output received: 15:40:08 INFO - /builds/slave/test/build/application/firefox/firefox 15:40:08 INFO - Running post-action listener: _resource_record_post_action 15:40:08 INFO - [mozharness: 2017-09-07 22:40:08.977616Z] Finished install step (success) 15:40:08 INFO - [mozharness: 2017-09-07 22:40:08.977760Z] Running setup-mitmproxy step. 15:40:08 INFO - Running pre-action listener: _resource_record_pre_action 15:40:08 INFO - Running main action method: setup_mitmproxy 15:40:08 INFO - Skipping: mitmproxy is not required 15:40:08 INFO - Running post-action listener: _resource_record_post_action 15:40:08 INFO - [mozharness: 2017-09-07 22:40:08.978645Z] Finished setup-mitmproxy step (success) 15:40:08 INFO - [mozharness: 2017-09-07 22:40:08.978865Z] Running run-tests step. 15:40:08 INFO - Running pre-action listener: _resource_record_pre_action 15:40:08 INFO - Running pre-action listener: _set_gcov_prefix 15:40:08 INFO - Running main action method: run_tests 15:40:08 WARNING - Try message not found. 15:40:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:40:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:40:08 INFO - Python 2.7.3 15:40:08 INFO - Return code: 0 15:40:08 INFO - grabbing minidump binary from tooltool 15:40:08 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')]} 15:40:08 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 0x1f1e090>, '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 0x1f1ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f18f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:40:08 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 15:40:08 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 15:40:08 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 15:40:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:40:09 INFO - Return code: 0 15:40:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:40:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:40:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:40:09 INFO - ENV: RUST_BACKTRACE is now full 15:40:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:40:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:40:09 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/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 15:40:09 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/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-030 --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 15:40:09 INFO - Using env: {'DISPLAY': ':0', 15:40:09 INFO - 'HOME': '/home/cltbld', 15:40:09 INFO - 'LANG': 'en_US.UTF-8', 15:40:09 INFO - 'LANGUAGE': 'en_US:en', 15:40:09 INFO - 'LOGNAME': 'cltbld', 15:40:09 INFO - 'MAIL': '/var/mail/cltbld', 15:40:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:40:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:40:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:09 INFO - 'MOZ_NO_REMOTE': '1', 15:40:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:40:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:09 INFO - 'NO_EM_RESTART': '1', 15:40:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:09 INFO - 'PWD': '/builds/slave/test', 15:40:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:40:09 INFO - 'RUST_BACKTRACE': 'full', 15:40:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:40:09 INFO - 'SHELL': '/bin/bash', 15:40:09 INFO - 'SHLVL': '1', 15:40:09 INFO - 'TERM': 'linux', 15:40:09 INFO - 'TMOUT': '86400', 15:40:09 INFO - 'USER': 'cltbld', 15:40:09 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', 15:40:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:09 INFO - '_': '/tools/buildbot/bin/python'} 15:40:09 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/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 15:40:09 INFO - mozversion application_buildid: 20170907215054 15:40:09 INFO - mozversion application_changeset: 64bf417d1bdf9bb8b562d73cc0742b1ec60a8d0e 15:40:09 INFO - mozversion application_display_name: Nightly 15:40:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:40:09 INFO - mozversion application_name: Firefox 15:40:09 INFO - mozversion application_remotingname: firefox 15:40:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:40:09 INFO - mozversion application_vendor: Mozilla 15:40:09 INFO - mozversion application_version: 57.0a1 15:40:09 INFO - mozversion platform_buildid: 20170907215054 15:40:09 INFO - mozversion platform_changeset: 64bf417d1bdf9bb8b562d73cc0742b1ec60a8d0e 15:40:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:40:09 INFO - mozversion platform_version: 57.0a1 15:40:09 INFO - using testdate: 1504824009 15:40:09 INFO - actual date: 1504824009 15:40:09 INFO - starting webserver on 'localhost:44403' 15:40:09 INFO - SUITE-START | Running 2 tests 15:40:09 INFO - TEST-START | dromaeo_css 15:40:09 INFO - Initialising browser for dromaeo_css test... 15:40:09 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44403/getInfo.html -profile /tmp/tmp_LkLPT/profile 15:40:09 INFO - TEST-INFO | started process 24987 (/builds/slave/test/build/application/firefox/firefox http://localhost:44403/getInfo.html) 15:40:14 INFO - TEST-INFO | 24987: exit 0 15:40:14 INFO - Browser initialized. 15:40:14 INFO - Running cycle 1/1 for dromaeo_css test... 15:40:14 INFO - Using env: {'DISPLAY': ':0', 15:40:14 INFO - 'HOME': '/home/cltbld', 15:40:14 INFO - 'JSGC_DISABLE_POISONING': '1', 15:40:14 INFO - 'LANG': 'en_US.UTF-8', 15:40:14 INFO - 'LANGUAGE': 'en_US:en', 15:40:14 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:40:14 INFO - 'LOGNAME': 'cltbld', 15:40:14 INFO - 'MAIL': '/var/mail/cltbld', 15:40:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:40:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:40:15 INFO - 'MOZ_CRASHREPORTER': '1', 15:40:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:15 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:40:15 INFO - 'MOZ_NO_REMOTE': '1', 15:40:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:40:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:15 INFO - 'NO_EM_RESTART': '1', 15:40:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:15 INFO - 'PWD': '/builds/slave/test', 15:40:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:40:15 INFO - 'RUST_BACKTRACE': 'full', 15:40:15 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:40:15 INFO - 'SHELL': '/bin/bash', 15:40:15 INFO - 'SHLVL': '1', 15:40:15 INFO - 'TERM': 'linux', 15:40:15 INFO - 'TMOUT': '86400', 15:40:15 INFO - 'USER': 'cltbld', 15:40:15 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', 15:40:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:15 INFO - '_': '/tools/buildbot/bin/python'} 15:40:15 INFO - TEST-INFO | started process 25185 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_LkLPT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:40:15 INFO - PID 25185 | 15:40:15 INFO - PID 25185 | (/builds/slave/test/build/application/firefox/firefox:25236): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:40:15 INFO - PID 25185 | 15:40:26 INFO - PID 25185 | RSS: Main: 171790336 15:40:26 INFO - PID 25185 | 15:43:23 INFO - PID 25185 | Cycle 1(1): loaded http://localhost:44403/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44403/tests/dromaeo/cssquery-ext.html) 15:43:24 INFO - PID 25185 | RSS: Main: 173985792 15:43:24 INFO - PID 25185 | 15:46:26 INFO - PID 25185 | Cycle 1(1): loaded http://localhost:44403/tests/dromaeo/cssquery-ext.html (next: http://localhost:44403/tests/dromaeo/cssquery-jquery.html) 15:46:27 INFO - PID 25185 | RSS: Main: 170311680 15:46:27 INFO - PID 25185 | 15:49:29 INFO - PID 25185 | Cycle 1(1): loaded http://localhost:44403/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44403/tests/dromaeo/cssquery-mootools.html) 15:49:29 INFO - PID 25185 | RSS: Main: 172630016 15:49:29 INFO - PID 25185 | 15:52:31 INFO - PID 25185 | Cycle 1(1): loaded http://localhost:44403/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44403/tests/dromaeo/cssquery-prototype.html) 15:52:31 INFO - PID 25185 | RSS: Main: 171962368 15:52:31 INFO - PID 25185 | 15:55:35 INFO - PID 25185 | Cycle 1(1): loaded http://localhost:44403/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44403/tests/dromaeo/cssquery-yui.html) 15:55:35 INFO - PID 25185 | RSS: Main: 172916736 15:55:35 INFO - PID 25185 | 15:58:37 INFO - PID 25185 | Cycle 1(1): loaded http://localhost:44403/tests/dromaeo/cssquery-yui.html (next: http://localhost:44403/tests/dromaeo/cssquery-dojo.html) 15:58:37 INFO - PID 25185 | RSS: Main: 175599616 15:58:37 INFO - PID 25185 | 15:58:37 INFO - PID 25185 | __start_tp_report 15:58:37 INFO - PID 25185 | _x_x_mozilla_page_load 15:58:37 INFO - PID 25185 | _x_x_mozilla_page_load_details 15:58:37 INFO - PID 25185 | |i|pagename|runs| 15:58:37 INFO - PID 25185 | |0;dojo.html;3099.845007749612;3106.860191291392;3120.297932965083;3122.1258047746637;3148.5277208418734;6066.696665166735;6243.219597550306;6253.906191407133;6262.624242545445;6303.653299068561;6192.628442293454;6209.441150296472;6323.810285691437;6353.872922541558;6389.744410223597;5757.510611598019;5805.216295800055;5855.853603659911;5904.143899134623;5906.26171728534;5583.218349431083;5780.421957804228;5814.273215848019;5824.941750582503;5927.081302398135;5646.18130371096;5707.71461426927;5741.483266506035;5742.3396309424115;5785.392533783935;5691.686957217351;5756.539476841843;5773.3072031356505;5785.074388097884;5816.1275808628625;5689.288838895138;5721.742521586539;5740.454656807597;5768.3654797972185;5779.2775903012125;6095.939040609582;6184.659843708594;6190.3190649028575;6266.373362663743;6293.496520278367;6673.666316684146;6747.730090796363;6769.22153952292;6772.830679233059;6773.491988100863;6683.331666833278;6694.263631000586;6711.362420570038;6788.1175447191545;6793.184817821893;6085.3914608539235;6121.66330851803;6128.785492507785;6136.96931515344;6150.04674275488;6724.361185687352;6730.629815360202;6777.525573209927;6839.487038472134;6905.413039891565;6527.281998980108;6557.983512555466;6637.137172167684;6638.535302528777;6641.169853768279;6662.600243985376;6665.633390163587;6704.765333213411;6741.325867413219;6745.763898263537;6421.614703117828;6540.50946179029;6545.509086818508;6658.633775142317;6742.292059333798;6542;6556.475481961432;6604.537682362285;6658.134442522538;6708.865822683617;5230;5274.156135018378;5295.761690723926;5297.390800058042;5297.655652382583;4562.657800664897;4574.4739354973935;4634.675572709878;4641.86074417768;4658.254679251304;5596.300462442195;5680.062789639664;5689.487946084872;5803.912941305884;5805.651660900359;6759.3578610032955;6773.559718618372;6776.9322306777285;6778.389944904982;6780.525363224626;5685.147227915846;5825.679587622722;5829.125631155361;5829.854253643693;5831.679257640785;5774.133879917962;5779.826605201851;5837.153612726131;5842.941570584325;5848.327442344185;6326.240851097898;6423.871522569522;6456.451201647818;6559.639219842859;6595.109660195908;6750.088738020402;6756.594604323756;6756.966215168893;6768.661566921536;6800.659967001531;6428.6785660717715;6466.741330346734;6584.967075164594;6587.143671322698;6602.240742314695;6574.0796288520605;6582.012698095324;6595.571287866273;6672.299408562225;6705.899411508929;6769.255381907888;6774.254831968578;6779.35596118368;6787.93212067873;6788.796336109925;6328.746850125944;6406.263279722892;6579.671016449062;6602.240742314503;6610.900836487553;6257.061440783918;6297.748090076346;6303.243610766737;6310.242770867341;6321.747130114744;6252.687365631791;6253.593516421418;6260.154879091447;6268.843278918063;6282.905756413749;6302.212223472066;6339.904901426575;6358.2687990879795;6363.936360636334;6575.210974683068;6152.630842149485;6327.936720632735;6354.555181137276;6415.9358406417205;6419.101325814483 15:58:37 INFO - PID 25185 | |1;ext.html;853.7481442974322;868.7871471489486;871.020102384817;872.5986046418647;874.3092956564315;39040.60959390405;40129;40348.39477407838;40377.79811100948;40378.19243615126;36857;36878;36975.63024369755;37071.44392834103;37101;6922.999999999987;7051.400630946376;7053.294670532957;7055.15338159419;7056.717731290768;6918.411934985532;7016.508844380895;7019.087518622598;7022.36798688118;7023.227444981048;7558;7764.456488045839;7768.689252429896;7771.8057048573955;7781.22187781223;7649.196834332399;7722.729704460373;7723;7724.536527808322;7724.884126738103;7424.665890034961;7593.240675932418;7648.082230132364;7671.654775535114;7676.46264761467;37595.62404375948;37730.24539509222;37895.431568526496;37898.810505947295;37981;3578.212793185521;3626.909327266813;3629.056445324208;3629.6007439181667;3630.8729194478065;31383.372332553223;31405.37189256202;31610;31664.366712665847;31780.682193178003;5272.340957380327;5297.6821390716295;5300.257963885059;5332.3601167859715;5353.866153346197;173697;175467;175573;175859;176574;40187.39718904219;41352.586474135474;46734.76632616883;49469.50530494721;50007;52687.20969185465;52911;52952.47047529475;53106.203406948924;53160.73419632955;15764.290606922716;15790.36838526446;15795.605109872115;15827.68344633101;15910.443134490237;12920.806187907188;12990.805137922938;13016.088873778934;13116.344182791014;13160.868391316155;14270.785938210935;14289.64275893111;14426.917981151246;14441.277936103153;14475.131492110508;5792.189093526909;5800.216970708984;5867.530597552186;5873.471387575139;6026.758929642853;1003.3478239144468;1013.6705570689555;1031.6028329093203;1032.5972870580479;1035.5909415780725;23126;23294.3011709649;23337.53324933492;23361.883190584278;23388.64917026246;1072.8980746829038;1113.33200079953;1117.8816143408196;1126.4536699700639;1129.5820546397722;12402.81395779064;12421.565245216554;12434.81347779784;12443.502259909683;12526.624201273977;224257;224581;224634;224777;226019;53569.1964620531;53594.73202633962;53613.46386536163;53680.46319536833;53747;1428.2358937968138;1440.992795036039;1455.825300963891;1477.3942683499713;1484.9703005940037;23127.07491700313;23159.536809264057;23216.651750224097;23221.187258445872;23876.52246955086;37432.25135497329;37557;37724.24551509009;37729.43405848969;37742.62257377501;184953;185180;185424;185764;185857;30714.539281911235;31428.52857207098;31429.685703142677;35093;35348.646513534535;42242.15515689608;43568.34647480354;44224.55775442204;44231.55768442275;44427.33358999553;31866.36267274688;32081.518777218826;32202.83898580492;32217.35565288728;32707.34585308328;31541;31737.20656983594;31742;32181.195470113435;32401.837990809894;35053.47419788756;35201.29597408089;35453.46819797757;35652.108697282776;35674;31218.375632486768;31278.53082203814;31567.84216078905;31570.842145789124;31916.52125218077;22764.178865691036;23317.085364329458;23831.880840595684;24242;24347.26958191257 15:58:37 INFO - PID 25185 | |2;jquery.html;555.3641080962299;567.2059117235867;568.2527476368575;568.6957477749403;584.4535359438925;22021.11915523379;22900;22937.77062229377;22957.19649812257;22958.885205573948;23374.532509349803;24275.150369737064;24504.87747561264;24609.26172214832;24632.630510542356;6427.4215320621015;6649.468042556584;6649.468042556608;6651;6655;6377.33038031007;6527.5757075789925;6581.703823327938;6608.603483790988;6610.272869984298;6591.571547849375;6592.17597800275;6593.703283352261;6595.175603049619;6606.768763093292;6579.407853293203;6580.967095164541;6611.636360000198;6612.173478315211;6613.834654133661;5709.172170035322;5906.320773111104;5934.258217722785;6133.325334213233;6145.3547377525165;27163.185104446897;27845;27863.303417414525;27875.721242787517;27903.302417439525;2090.1743811194647;2123.4054464749893;2128.1487405037956;2131.6589345704615;2132.17911104226;18808.529786755305;19300;19635.31276405333;19644.50888727779;19689.80310196894;2432.5499782540287;2440.04838113136;2465.5438743832447;2475.405902583367;2490.9128180513776;117716.41141794236;123639;133053;139710.30144849312;139834;14526.418943242363;14546.709065818624;14614.269286535631;14650.487232946796;15064.802643088116;22698.886505567367;22731.204407845646;22901.541969160524;23005.194818181284;23048.4237894054;9895.158911492605;10028.247881408777;10100.646477373404;10143.340682855738;10223.64856083105;9250.121238482334;9266.814663706687;9299.674511392204;9308.301877359225;9316.813663726687;8858.955705221433;8976.506292153863;9068.954655226682;9279.62881484733;9389.671361502315;2615.686117665892;2620.135355332736;2674.8395096294284;2704.5807899775173;2712.22701530063;950.235060776068;965.5317171171976;967.0136460809935;982.6167794560128;985.7092157813319;18489.81510184881;18748.718769218474;22575.887120564294;23968.760312397;24322.513549728905;1001.5943542865151;1049.7428130108171;1050.6270274052754;1058.0583280880023;1059.4014381874217;10545.156387488982;10726.785464290671;10829.241953063367;11115.83326250107;12739.872601274054;488646;505624;505917;517568;521121;113414;113940.43029784964;116190.4190479059;116544;120634.39682801529;1093.0708897437273;1133.8809425010254;1210.2315029955864;1211.733418647896;1271.739293444823;19495.610087797882;20120.29578964729;20166.395008149193;20264.08811603511;20265.29071482491;57218.7139064302;59156.40843591681;59410.99999999827;60126;60214.09678854908;69749;70645.64677176581;70899.29100709133;71298.64350678213;71617.64191179011;13964.930175349058;14534.636634084232;14548.199849008406;14551.85448145505;14591.124532528082;22841.88579057094;23555.41111472227;23570.410739731644;24008.63987040231;24018.39954001094;18851.90574047121;18951.0524473773;18960.620787583895;18966.905165474083;19030.14364353635;16528.83471165273;16961.321547137977;17396.130193490528;17630.471085867444;17768.555786104935;19348.129334179714;19427.70858437153;19456.902715486332;19565.60868782588;19603.901980490005;14290.142591444546;14416.351264192926;14417.207053611732;14594.562163135122;14605.780913286097;18216.635667286846;18432.07839608041;18620.813791861907;19106.23575056982;19247.711284331028 15:58:37 INFO - PID 25185 | |3;mootools.html;1298.279454902529;1394.5188909826106;1410.6825964158065;1423.0110073498915;1424.1668623855044;14414.135151890894;15293.541193764177;15310;15328.540143795704;15336.38654453822;14973.727233185193;15107.546773596781;15108.395664173448;15126.546203613882;15128.470503532379;2751.2296556964034;2752.0642981386322;2821.7742580593504;2822.647169103862;2827.7737780977577;2754.8898044078214;2787.512185367563;2792.3437992071845;2794.2315863137596;2794.6786119596304;2656.0969270448045;2761.765249953756;2858.885644574225;2860.5709143628414;2860.599516067752;2772;2777.0974433309257;2798.38435544181;2804.9158525244275;2815.9859200703863;2697.8381297122237;2744.423671029086;2747.5191841427672;2767.5433553463854;2770.2381844992588;15003.474878379313;15217.086974781549;15225.847741522553;15230.923845380814;15232.847671523365;7896.881546776803;8165.265126138676;8195.918040819575;8197.877031844528;8205.876911846328;5087.23691446286;5242.239875218072;5312.601554883399;5344.064788661969;5448.45515448456;1923.6248931458372;1930.276146445083;1931.9806801932082;1960.7549056367955;1974.7926467720827;25696.48607027849;25981;25988.610170847263;26075.869620651774;26076.478470430677;6676.632785196861;6736.326367363323;6802.081718967975;6911.2397636259975;6970.477214208853;16917.661646766996;17016.23426945766;17041.14794260282;17070.402535911184;17074.743878841826;7416.22129676387;7698.538087714755;7706.113796913314;7712.421568382394;7720.922790772132;7051.29487051301;7109.040279562297;7174.892376614355;7180.425565954711;7191.244919283505;5005.3242812220615;5159.148740457783;5174.197999310113;5207.297014903015;5217.634765566374;1256.447163248168;1286.922784632925;1409.4150927365029;1417.6739349949569;1421.9431222750975;904.9819003619891;934.45334479329;936.0545848692869;938.7183844846519;952.3571589177416;8684.696035638848;8795.428297160664;8826.86759698605;8864.73405797827;8885.0670679579;728.9271072892668;732.7801659502128;737.5943231222806;739.5562662402623;821.3839620284787;5031.23906632683;5320.733963301819;5479.616426850162;5625.578081643893;5634.661920284714;20166.09252583637;20510.692339614918;21091.15635374568;21468.785312146993;21537.461563461038;21033.78966210349;21173.063141969877;21215.15139394438;21301.254456093964;21427.250046248308;10814.837777433344;10978.286411383235;11100.167487438475;11122.666320010412;11123.16576256784;8140.308073813792;8344.499330040217;8417.07412184647;8457.281131103678;8464.66141354339;17853.553661158574;18352.265909363476;18440;18544.165512552237;18713.15790789445;18876.528086797938;18877.528061798566;18929.242830287185;19213.519662008563;19698.21207151755;12061.51753929833;12527.498900043896;12560.937195313965;12576;12615.621531354074;13655.112417692819;14054.648633784238;14081.718365632425;14126.223057731513;14178.716425671635;11522.251053681484;11604.6518604442;11637.650870473899;11861.703457413634;11998.100142489173;11080.667579972614;11092.611758588411;11223.270487418291;11393.487293071998;11465.19743617972;9902.653407130716;9978.850317245102;10042.397456152654;10088.142507886912;10131.240156988108;7745.496542724705;7747.535147890919;7835.764927052198;7854.72508462201;7871;7604.8479030420185;7788.88316675239;7807.414443916616;7908.130105688485;7973.401994850294 15:58:37 INFO - PID 25185 | |4;prototype.html;498.24267113986764;504.4652668171738;513.8689634143293;523.3189123292152;533.8078291814946;6103.389661033894;6163.260408750956;6181.536384771149;6233.470155036827;6272.404121608445;6082.178905847709;6111.205543279379;6113.541484388677;6158.815235542941;6161;4953.826616068438;5074.720890351029;5084.44071152173;5123.436421993578;5200.193969934648;5087.982403519292;5109.642325037249;5111.923321150186;5115.897682046357;5159.458256883029;4947.752612369385;4974.651774375795;4980;5033.597312215014;5056.8786439052055;5017.84946451607;5050.797968081272;5056.3173971513925;5063.329101396567;5071.797128114871;4532.4787649420405;4551.112533056055;4555.931661025087;4577.702449340783;4598.195315819718;6174.691265436755;6181.320054793969;6194.163787888622;6210.472109870621;6308.148399965991;5706.343770466408;5722.227499287626;5730.36965933747;5742.674216942145;5758.683272420015;6595.175603049619;6604.745098431283;6615.239247486553;6619.609881924754;6627.536072474929;4903.362562866841;4903.8774030649165;4914.483979182206;4919.483454237289;4923.744445166495;6918.584884906872;6997.370236678723;6999.12510936133;7014.368706816411;7025.402840758542;4959.776810043557;4980.9252861207115;4994.725290109006;5014.648974571818;5047.369078865142;5067.138586440314;5073.16292811682;5081.644284900095;5083.525777524476;5107.642465027412;5436.864078397993;5635.408282130399;5664.943350566524;5728.971355143198;5766.855828604318;6428.646424446607;6471.838204044937;6482.384173503509;6517.51118666102;6529.2817790043055;1729.9394521191698;1736.461696873973;1741.3295881085874;1750.8949463032259;1755.7893052833742;1190.7201807575213;1192.5349113845607;1203.8194270859442;1217.9269243845397;1222.2422098298935;5518.365387980433;5719.8570035749435;5720.570957178228;5729.398413166641;5744.425557444306;6616.86766264672;6668.399844013965;6673.632950187689;6709.899351509731;6755.648870225876;5629.746661400247;5635.408282130399;5673.205751194836;5710.628809127394;5733.88532229353;3533.964660353415;3574.463830425405;3580.230250496156;3612.8735494257576;3619.7828130312264;6471.9029214561815;6505.41451269388;6573.441257493071;6605.207375115017;6672.599644021374;6841.863162736718;6936.34104760047;6968.79093627192;7009.193942696553;7054.153501579741;6571.342865713486;6610.8016759497295;6621.046791196609;6671.299513551007;6697.9953007049335;6784.287649796897;6793.66031698423;6804;6841.828954275984;6847.21257055445;6647.268800432045;6713.56361836479;6713.899291510534;6717.798466046026;6767.1879374475375;6555.639439830665;6570.474362050929;6585.670716464253;6630.9336906630315;6641.280882705701;4937.851864444073;4973.701577905337;5004.345958258708;5042.520960508746;5066.721330326867;6750.379908821946;6766.289539598271;6774.525783195129;6776.881814500749;6789.253182150058;6447.290798012121;6500.349965003462;6550.3471094206925;6566.358410397401;6574.967125164344;6464.612123272619;6508.479321654161;6543.345665433419;6599.769008084694;6609.504287178577;6479.611223326616;6487.9619260918025;6496.285408604956;6535.803925882231;6544.607323560602;5968.940310597012;6592.967035164794;6609.149438157324;6630.143559803263;6672.132622759011;6318.810435686937;6331.6517591531065;6376.713047912763;6400.071989561395;6429.903551446639 15:58:37 INFO - PID 25185 | |5;yui.html;870.2776695342866;899.8200359928014;920.5765347939947;921.2860033474061;924.0436148586215;19287.517812054706;19932;19940.90029549852;19975.600487990232;20000.699989500168;19073.046347682593;19229.42311730647;19242.13410396532;19242.80757192431;19256.614867702636;943.5565284316394;970;976.3897564022486;979.4612962870428;981.1856159387727;969.6218474794836;981.8772653418323;982.459647194044;988.4168340678998;989.3865803201988;945.3288165402572;1017.277732809706;1028.1671845804885;1030.938143711376;1031.4275577054732;1003.372891942536;1007.1187710753054;1007.5214273220236;1007.7329507680471;1008.7982403519326;731.1409094314159;747.2266204478339;747.4618274842105;748.1919526910923;749.407967705512;19994.400167994983;20144.697829532568;20177.596448070955;20213.292534761214;20214.595708085904;22038.559228815495;22214.444638883997;22821.88589057044;22872.542549148922;22922;17409.21658525357;17558.38545650896;18309.45071647853;18883.433496995112;19016.193461621126;3712.350338690718;3728.12389088564;3730.0674831292176;3730.198007428416;3731.6828069614116;105439.47280263626;137351.31324343313;137613;138912;139012;15481.303341349667;15671.294791734399;15772.211389430482;15811.841881581036;15900.363985440685;30785.2303692405;32168.83915580407;32415.51376729351;32428.83785581057;32486.512702309483;9299.67451139207;9364.906350936404;9422.293328000427;9426.811463770824;9467.857982130274;4476.082403107355;4565.315202719618;4578.198815207325;4579.335996280521;4588.632909367243;5331.840044798662;5474.671519708831;5482.424345443751;5492.972535137299;5497.36780270274;1066.4454483668603;1077.7844431113808;1080.7946490166844;1084.5715942202785;1085.7177133945231;669.5012215899186;671.1610486891386;685.4893104636977;691.5401258163365;696.8745625787305;10888.183386246063;10972.23194376402;11187.272827266203;11319.830202546967;11499.367534785668;667.8363800868818;674.1068084787578;674.4536925090692;681.3629256645053;690.520088538461;5133.948660513422;5173.146430838945;5220.164773636276;5399.973000135053;5405.864853378697;111751.44124279327;115938;118181;120145.39927300307;120494;100615;100942;104456.47771761092;105786;107836.4608176954;92284;92782.53608732048;94603.52698236465;94610.99999999862;95462;12854.357282135668;13253.933730331672;13290.534831280858;13304.733905322033;13419.597412077654;43473;44611;44806.77596611996;44825.77587112044;45246.321305179794;78361.60819195867;79105;80071.59964200141;81192.18807811846;85937.57031214803;19294.13176407093;19534.413967580997;19723.11245993905;19747.605047899247;20025.39923802288;18730.531736706693;18783.812161878206;18977.52556186051;20605.17579296811;21398.358049258546;18931.43205703831;19335.613287734446;19481;19932.601347972668;20187.39437816868;18911.90544047271;19295.807041929962;19588.510287242934;19989.500262493555;20246.493837654176;17443.738343924597;17946;18050.548736281173;18105.90947045309;18312.90843545774;13412.262325571797;13871.167729936236;14109.365078571283;14188.645283867985;14237.572872813833;14321.14073155614;14996.550103496913;15503.68218701423;15800.209989500709;15849 15:58:37 INFO - PID 25185 | __end_tp_report 15:58:37 INFO - PID 25185 | __start_cc_report 15:58:37 INFO - PID 25185 | _x_x_mozilla_cycle_collect,371 15:58:37 INFO - PID 25185 | __end_cc_report 15:58:37 INFO - PID 25185 | __startTimestamp1504825117978__endTimestamp 15:58:37 INFO - PID 25185 | 15:58:37 INFO - PID 25185 | ------- Summary: start ------- 15:58:37 INFO - PID 25185 | Number of tests: 6 15:58:37 INFO - PID 25185 | 15:58:37 INFO - PID 25185 | [#0] dojo.html Cycles:165 Average:6138.63 Median:6310.24 stddev:730.95 (11.6%) stddev-sans-first:693.23 15:58:37 INFO - PID 25185 | Values: 3099.8 3106.9 3120.3 3122.1 3148.5 6066.7 6243.2 6253.9 6262.6 6303.7 6192.6 6209.4 6323.8 6353.9 6389.7 5757.5 5805.2 5855.9 5904.1 5906.3 5583.2 5780.4 5814.3 5824.9 5927.1 5646.2 5707.7 5741.5 5742.3 5785.4 5691.7 5756.5 5773.3 5785.1 5816.1 5689.3 5721.7 5740.5 5768.4 5779.3 6095.9 6184.7 6190.3 6266.4 6293.5 6673.7 6747.7 6769.2 6772.8 6773.5 6683.3 6694.3 6711.4 6788.1 6793.2 6085.4 6121.7 6128.8 6137.0 6150.0 6724.4 6730.6 6777.5 6839.5 6905.4 6527.3 6558.0 6637.1 6638.5 6641.2 6662.6 6665.6 6704.8 6741.3 6745.8 6421.6 6540.5 6545.5 6658.6 6742.3 6542.0 6556.5 6604.5 6658.1 6708.9 5230.0 5274.2 5295.8 5297.4 5297.7 4562.7 4574.5 4634.7 4641.9 4658.3 5596.3 5680.1 5689.5 5803.9 5805.7 6759.4 6773.6 6776.9 6778.4 6780.5 5685.1 5825.7 5829.1 5829.9 5831.7 5774.1 5779.8 5837.2 5842.9 5848.3 6326.2 6423.9 6456.5 6559.6 6595.1 6750.1 6756.6 6757.0 6768.7 6800.7 6428.7 6466.7 6585.0 6587.1 6602.2 6574.1 6582.0 6595.6 6672.3 6705.9 6769.3 6774.3 6779.4 6787.9 6788.8 6328.7 6406.3 6579.7 6602.2 6610.9 6257.1 6297.7 6303.2 6310.2 6321.7 6252.7 6253.6 6260.2 6268.8 6282.9 6302.2 6339.9 6358.3 6363.9 6575.2 6152.6 6327.9 6354.6 6415.9 6419.1 15:58:37 INFO - PID 25185 | 15:58:37 INFO - PID 25185 | [#1] ext.html Cycles:180 Average:36440.82 Median:35274.97 stddev:50856.47 (144.2%) stddev-sans-first:50928.94 15:58:37 INFO - PID 25185 | Values: 853.7 868.8 871.0 872.6 874.3 39040.6 40129.0 40348.4 40377.8 40378.2 36857.0 36878.0 36975.6 37071.4 37101.0 6923.0 7051.4 7053.3 7055.2 7056.7 6918.4 7016.5 7019.1 7022.4 7023.2 7558.0 7764.5 7768.7 7771.8 7781.2 7649.2 7722.7 7723.0 7724.5 7724.9 7424.7 7593.2 7648.1 7671.7 7676.5 37595.6 37730.2 37895.4 37898.8 37981.0 3578.2 3626.9 3629.1 3629.6 3630.9 31383.4 31405.4 31610.0 31664.4 31780.7 5272.3 5297.7 5300.3 5332.4 5353.9 173697.0 175467.0 175573.0 175859.0 176574.0 40187.4 41352.6 46734.8 49469.5 50007.0 52687.2 52911.0 52952.5 53106.2 53160.7 15764.3 15790.4 15795.6 15827.7 15910.4 12920.8 12990.8 13016.1 13116.3 13160.9 14270.8 14289.6 14426.9 14441.3 14475.1 5792.2 5800.2 5867.5 5873.5 6026.8 1003.3 1013.7 1031.6 1032.6 1035.6 23126.0 23294.3 23337.5 23361.9 23388.6 1072.9 1113.3 1117.9 1126.5 1129.6 12402.8 12421.6 12434.8 12443.5 12526.6 224257.0 224581.0 224634.0 224777.0 226019.0 53569.2 53594.7 53613.5 53680.5 53747.0 1428.2 1441.0 1455.8 1477.4 1485.0 23127.1 23159.5 23216.7 23221.2 23876.5 37432.3 37557.0 37724.2 37729.4 37742.6 184953.0 185180.0 185424.0 185764.0 185857.0 30714.5 31428.5 31429.7 35093.0 35348.6 42242.2 43568.3 44224.6 44231.6 44427.3 31866.4 32081.5 32202.8 32217.4 32707.3 31541.0 31737.2 31742.0 32181.2 32401.8 35053.5 35201.3 35453.5 35652.1 35674.0 31218.4 31278.5 31567.8 31570.8 31916.5 22764.2 23317.1 23831.9 24242.0 24347.3 15:58:37 INFO - PID 25185 | 15:58:37 INFO - PID 25185 | [#2] jquery.html Cycles:180 Average:35225.30 Median:22982.04 stddev:85116.13 (370.4%) stddev-sans-first:85315.10 15:58:37 INFO - PID 25185 | Values: 555.4 567.2 568.3 568.7 584.5 22021.1 22900.0 22937.8 22957.2 22958.9 23374.5 24275.2 24504.9 24609.3 24632.6 6427.4 6649.5 6649.5 6651.0 6655.0 6377.3 6527.6 6581.7 6608.6 6610.3 6591.6 6592.2 6593.7 6595.2 6606.8 6579.4 6581.0 6611.6 6612.2 6613.8 5709.2 5906.3 5934.3 6133.3 6145.4 27163.2 27845.0 27863.3 27875.7 27903.3 2090.2 2123.4 2128.1 2131.7 2132.2 18808.5 19300.0 19635.3 19644.5 19689.8 2432.5 2440.0 2465.5 2475.4 2490.9 117716.4 123639.0 133053.0 139710.3 139834.0 14526.4 14546.7 14614.3 14650.5 15064.8 22698.9 22731.2 22901.5 23005.2 23048.4 9895.2 10028.2 10100.6 10143.3 10223.6 9250.1 9266.8 9299.7 9308.3 9316.8 8859.0 8976.5 9069.0 9279.6 9389.7 2615.7 2620.1 2674.8 2704.6 2712.2 950.2 965.5 967.0 982.6 985.7 18489.8 18748.7 22575.9 23968.8 24322.5 1001.6 1049.7 1050.6 1058.1 1059.4 10545.2 10726.8 10829.2 11115.8 12739.9 488646.0 505624.0 505917.0 517568.0 521121.0 113414.0 113940.4 116190.4 116544.0 120634.4 1093.1 1133.9 1210.2 1211.7 1271.7 19495.6 20120.3 20166.4 20264.1 20265.3 57218.7 59156.4 59411.0 60126.0 60214.1 69749.0 70645.6 70899.3 71298.6 71617.6 13964.9 14534.6 14548.2 14551.9 14591.1 22841.9 23555.4 23570.4 24008.6 24018.4 18851.9 18951.1 18960.6 18966.9 19030.1 16528.8 16961.3 17396.1 17630.5 17768.6 19348.1 19427.7 19456.9 19565.6 19603.9 14290.1 14416.4 14417.2 14594.6 14605.8 18216.6 18432.1 18620.8 19106.2 19247.7 15:58:37 INFO - PID 25185 | 15:58:37 INFO - PID 25185 | [#3] mootools.html Cycles:180 Average:9369.50 Median:2745.97 stddev:6573.15 (239.4%) stddev-sans-first:6563.61 15:58:37 INFO - PID 25185 | Values: 1298.3 1394.5 1410.7 1423.0 1424.2 14414.1 15293.5 15310.0 15328.5 15336.4 14973.7 15107.5 15108.4 15126.5 15128.5 2751.2 2752.1 2821.8 2822.6 2827.8 2754.9 2787.5 2792.3 2794.2 2794.7 2656.1 2761.8 2858.9 2860.6 2860.6 2772.0 2777.1 2798.4 2804.9 2816.0 2697.8 2744.4 2747.5 2767.5 2770.2 15003.5 15217.1 15225.8 15230.9 15232.8 7896.9 8165.3 8195.9 8197.9 8205.9 5087.2 5242.2 5312.6 5344.1 5448.5 1923.6 1930.3 1932.0 1960.8 1974.8 25696.5 25981.0 25988.6 26075.9 26076.5 6676.6 6736.3 6802.1 6911.2 6970.5 16917.7 17016.2 17041.1 17070.4 17074.7 7416.2 7698.5 7706.1 7712.4 7720.9 7051.3 7109.0 7174.9 7180.4 7191.2 5005.3 5159.1 5174.2 5207.3 5217.6 1256.4 1286.9 1409.4 1417.7 1421.9 905.0 934.5 936.1 938.7 952.4 8684.7 8795.4 8826.9 8864.7 8885.1 728.9 732.8 737.6 739.6 821.4 5031.2 5320.7 5479.6 5625.6 5634.7 20166.1 20510.7 21091.2 21468.8 21537.5 21033.8 21173.1 21215.2 21301.3 21427.3 10814.8 10978.3 11100.2 11122.7 11123.2 8140.3 8344.5 8417.1 8457.3 8464.7 17853.6 18352.3 18440.0 18544.2 18713.2 18876.5 18877.5 18929.2 19213.5 19698.2 12061.5 12527.5 12560.9 12576.0 12615.6 13655.1 14054.6 14081.7 14126.2 14178.7 11522.3 11604.7 11637.7 11861.7 11998.1 11080.7 11092.6 11223.3 11393.5 11465.2 9902.7 9978.9 10042.4 10088.1 10131.2 7745.5 7747.5 7835.8 7854.7 7871.0 7604.8 7788.9 7807.4 7908.1 7973.4 15:58:37 INFO - PID 25185 | 15:58:37 INFO - PID 25185 | [#4] prototype.html Cycles:180 Average:5539.33 Median:6159.91 stddev:1555.99 (25.3%) stddev-sans-first:1513.65 15:58:37 INFO - PID 25185 | Values: 498.2 504.5 513.9 523.3 533.8 6103.4 6163.3 6181.5 6233.5 6272.4 6082.2 6111.2 6113.5 6158.8 6161.0 4953.8 5074.7 5084.4 5123.4 5200.2 5088.0 5109.6 5111.9 5115.9 5159.5 4947.8 4974.7 4980.0 5033.6 5056.9 5017.8 5050.8 5056.3 5063.3 5071.8 4532.5 4551.1 4555.9 4577.7 4598.2 6174.7 6181.3 6194.2 6210.5 6308.1 5706.3 5722.2 5730.4 5742.7 5758.7 6595.2 6604.7 6615.2 6619.6 6627.5 4903.4 4903.9 4914.5 4919.5 4923.7 6918.6 6997.4 6999.1 7014.4 7025.4 4959.8 4980.9 4994.7 5014.6 5047.4 5067.1 5073.2 5081.6 5083.5 5107.6 5436.9 5635.4 5664.9 5729.0 5766.9 6428.6 6471.8 6482.4 6517.5 6529.3 1729.9 1736.5 1741.3 1750.9 1755.8 1190.7 1192.5 1203.8 1217.9 1222.2 5518.4 5719.9 5720.6 5729.4 5744.4 6616.9 6668.4 6673.6 6709.9 6755.6 5629.7 5635.4 5673.2 5710.6 5733.9 3534.0 3574.5 3580.2 3612.9 3619.8 6471.9 6505.4 6573.4 6605.2 6672.6 6841.9 6936.3 6968.8 7009.2 7054.2 6571.3 6610.8 6621.0 6671.3 6698.0 6784.3 6793.7 6804.0 6841.8 6847.2 6647.3 6713.6 6713.9 6717.8 6767.2 6555.6 6570.5 6585.7 6630.9 6641.3 4937.9 4973.7 5004.3 5042.5 5066.7 6750.4 6766.3 6774.5 6776.9 6789.3 6447.3 6500.3 6550.3 6566.4 6575.0 6464.6 6508.5 6543.3 6599.8 6609.5 6479.6 6488.0 6496.3 6535.8 6544.6 5968.9 6593.0 6609.1 6630.1 6672.1 6318.8 6331.7 6376.7 6400.1 6429.9 15:58:37 INFO - PID 25185 | 15:58:37 INFO - PID 25185 | [#5] yui.html Cycles:180 Average:25188.45 Median:20085.05 stddev:34532.35 (171.9%) stddev-sans-first:34580.94 15:58:37 INFO - PID 25185 | Values: 870.3 899.8 920.6 921.3 924.0 19287.5 19932.0 19940.9 19975.6 20000.7 19073.0 19229.4 19242.1 19242.8 19256.6 943.6 970.0 976.4 979.5 981.2 969.6 981.9 982.5 988.4 989.4 945.3 1017.3 1028.2 1030.9 1031.4 1003.4 1007.1 1007.5 1007.7 1008.8 731.1 747.2 747.5 748.2 749.4 19994.4 20144.7 20177.6 20213.3 20214.6 22038.6 22214.4 22821.9 22872.5 22922.0 17409.2 17558.4 18309.5 18883.4 19016.2 3712.4 3728.1 3730.1 3730.2 3731.7 105439.5 137351.3 137613.0 138912.0 139012.0 15481.3 15671.3 15772.2 15811.8 15900.4 30785.2 32168.8 32415.5 32428.8 32486.5 9299.7 9364.9 9422.3 9426.8 9467.9 4476.1 4565.3 4578.2 4579.3 4588.6 5331.8 5474.7 5482.4 5493.0 5497.4 1066.4 1077.8 1080.8 1084.6 1085.7 669.5 671.2 685.5 691.5 696.9 10888.2 10972.2 11187.3 11319.8 11499.4 667.8 674.1 674.5 681.4 690.5 5133.9 5173.1 5220.2 5400.0 5405.9 111751.4 115938.0 118181.0 120145.4 120494.0 100615.0 100942.0 104456.5 105786.0 107836.5 92284.0 92782.5 94603.5 94611.0 95462.0 12854.4 13253.9 13290.5 13304.7 13419.6 43473.0 44611.0 44806.8 44825.8 45246.3 78361.6 79105.0 80071.6 81192.2 85937.6 19294.1 19534.4 19723.1 19747.6 20025.4 18730.5 18783.8 18977.5 20605.2 21398.4 18931.4 19335.6 19481.0 19932.6 20187.4 18911.9 19295.8 19588.5 19989.5 20246.5 17443.7 17946.0 18050.5 18105.9 18312.9 13412.3 13871.2 14109.4 14188.6 14237.6 14321.1 14996.6 15503.7 15800.2 15849.0 15:58:37 INFO - PID 25185 | -------- Summary: end -------- 15:58:38 INFO - PID 25185 | 15:58:38 INFO - TEST-INFO | 25185: exit 0 15:58:38 INFO - TEST-OK | dromaeo_css | took 1109327ms 15:58:38 INFO - TEST-START | kraken 15:58:38 INFO - Initialising browser for kraken test... 15:58:38 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44403/getInfo.html -profile /tmp/tmpybYIVx/profile 15:58:38 INFO - TEST-INFO | started process 25562 (/builds/slave/test/build/application/firefox/firefox http://localhost:44403/getInfo.html) 15:58:44 INFO - TEST-INFO | 25562: exit 0 15:58:44 INFO - Browser initialized. 15:58:44 INFO - Running cycle 1/1 for kraken test... 15:58:44 INFO - Using env: {'DISPLAY': ':0', 15:58:44 INFO - 'HOME': '/home/cltbld', 15:58:44 INFO - 'JSGC_DISABLE_POISONING': '1', 15:58:44 INFO - 'LANG': 'en_US.UTF-8', 15:58:44 INFO - 'LANGUAGE': 'en_US:en', 15:58:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:58:44 INFO - 'LOGNAME': 'cltbld', 15:58:44 INFO - 'MAIL': '/var/mail/cltbld', 15:58:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:58:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:58:44 INFO - 'MOZ_CRASHREPORTER': '1', 15:58:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:58:44 INFO - 'MOZ_NO_REMOTE': '1', 15:58:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:58:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:58:44 INFO - 'NO_EM_RESTART': '1', 15:58:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:58:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:44 INFO - 'PWD': '/builds/slave/test', 15:58:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:58:44 INFO - 'RUST_BACKTRACE': 'full', 15:58:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:58:44 INFO - 'SHELL': '/bin/bash', 15:58:44 INFO - 'SHLVL': '1', 15:58:44 INFO - 'TERM': 'linux', 15:58:44 INFO - 'TMOUT': '86400', 15:58:44 INFO - 'USER': 'cltbld', 15:58:44 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142', 15:58:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:44 INFO - '_': '/tools/buildbot/bin/python'} 15:58:44 INFO - TEST-INFO | started process 25756 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpybYIVx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:58:45 INFO - PID 25756 | 15:58:45 INFO - PID 25756 | (/builds/slave/test/build/application/firefox/firefox:25807): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:58:45 INFO - PID 25756 | 15:58:55 INFO - PID 25756 | RSS: Main: 172204032 15:58:55 INFO - PID 25756 | 15:58:58 INFO - PID 25756 | Cycle 1(1): loaded http://localhost:44403/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44403/tests/kraken/driver.html?testName=audio-beat-detection) 15:58:59 INFO - PID 25756 | RSS: Main: 178335744 15:58:59 INFO - PID 25756 | 15:59:01 INFO - PID 25756 | Cycle 1(1): loaded http://localhost:44403/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44403/tests/kraken/driver.html?testName=audio-dft) 15:59:02 INFO - PID 25756 | RSS: Main: 179245056 15:59:02 INFO - PID 25756 | 15:59:05 INFO - PID 25756 | Cycle 1(1): loaded http://localhost:44403/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44403/tests/kraken/driver.html?testName=audio-fft) 15:59:05 INFO - PID 25756 | RSS: Main: 173273088 15:59:05 INFO - PID 25756 | 15:59:08 INFO - PID 25756 | Cycle 1(1): loaded http://localhost:44403/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44403/tests/kraken/driver.html?testName=audio-oscillator) 15:59:08 INFO - PID 25756 | RSS: Main: 174063616 15:59:08 INFO - PID 25756 | 15:59:10 INFO - PID 25756 | Cycle 1(1): loaded http://localhost:44403/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44403/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:59:11 INFO - PID 25756 | RSS: Main: 174252032 15:59:11 INFO - PID 25756 | 15:59:14 INFO - PID 25756 | Cycle 1(1): loaded http://localhost:44403/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44403/tests/kraken/driver.html?testName=imaging-darkroom) 15:59:15 INFO - PID 25756 | RSS: Main: 176799744 15:59:15 INFO - PID 25756 | 15:59:18 INFO - PID 25756 | Cycle 1(1): loaded http://localhost:44403/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44403/tests/kraken/driver.html?testName=imaging-desaturate) 15:59:19 INFO - PID 25756 | RSS: Main: 176513024 15:59:19 INFO - PID 25756 | 15:59:22 INFO - PID 25756 | Cycle 1(1): loaded http://localhost:44403/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44403/tests/kraken/driver.html?testName=json-parse-financial) 15:59:22 INFO - PID 25756 | RSS: Main: 176984064 15:59:22 INFO - PID 25756 | 15:59:24 INFO - PID 25756 | Cycle 1(1): loaded http://localhost:44403/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44403/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:59:25 INFO - PID 25756 | RSS: Main: 176873472 15:59:25 INFO - PID 25756 | 15:59:26 INFO - PID 25756 | Cycle 1(1): loaded http://localhost:44403/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44403/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:59:27 INFO - PID 25756 | RSS: Main: 176877568 15:59:27 INFO - PID 25756 | 15:59:29 INFO - PID 25756 | Cycle 1(1): loaded http://localhost:44403/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44403/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:59:30 INFO - PID 25756 | RSS: Main: 173608960 15:59:30 INFO - PID 25756 | 15:59:32 INFO - PID 25756 | Cycle 1(1): loaded http://localhost:44403/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44403/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:59:33 INFO - PID 25756 | RSS: Main: 173871104 15:59:33 INFO - PID 25756 | 15:59:36 INFO - PID 25756 | Cycle 1(1): loaded http://localhost:44403/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44403/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:59:37 INFO - PID 25756 | RSS: Main: 174239744 15:59:37 INFO - PID 25756 | 15:59:38 INFO - PID 25756 | Cycle 1(1): loaded http://localhost:44403/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44403/tests/kraken/driver.html?testName=ai-astar) 15:59:39 INFO - PID 25756 | RSS: Main: 175734784 15:59:39 INFO - PID 25756 | 15:59:39 INFO - PID 25756 | __start_tp_report 15:59:39 INFO - PID 25756 | _x_x_mozilla_page_load 15:59:39 INFO - PID 25756 | _x_x_mozilla_page_load_details 15:59:39 INFO - PID 25756 | |i|pagename|runs| 15:59:39 INFO - PID 25756 | |0;ai-astar;108;107;107;107;108;108;106;106;108;97 15:59:39 INFO - PID 25756 | |1;audio-beat-detection;116;116;115;116;144;115;142;120;118;134 15:59:39 INFO - PID 25756 | |2;audio-dft;162;161;163;163;162;164;169;165;171;162 15:59:39 INFO - PID 25756 | |3;audio-fft;73;79;76;73;75;74;74;74;82;77 15:59:39 INFO - PID 25756 | |4;audio-oscillator;80;80;79;78;79;78;78;79;80;79 15:59:39 INFO - PID 25756 | |5;imaging-gaussian-blur;117;115;116;116;116;117;115;114;113;111 15:59:39 INFO - PID 25756 | |6;imaging-darkroom;147;148;147;147;148;148;148;147;148;148 15:59:39 INFO - PID 25756 | |7;imaging-desaturate;94;100;93;94;94;94;93;94;94;94 15:59:39 INFO - PID 25756 | |8;json-parse-financial;54;54;51;52;51;52;54;50;51;52 15:59:39 INFO - PID 25756 | |9;json-stringify-tinderbox;38;39;42;38;38;38;38;38;38;38 15:59:39 INFO - PID 25756 | |10;stanford-crypto-aes;75;75;73;73;78;78;73;77;74;74 15:59:39 INFO - PID 25756 | |11;stanford-crypto-ccm;113;117;118;131;125;120;119;117;130;114 15:59:39 INFO - PID 25756 | |12;stanford-crypto-pbkdf2;170;167;170;175;168;168;180;173;171;174 15:59:39 INFO - PID 25756 | |13;stanford-crypto-sha256-iterative;67;64;63;66;67;67;66;78;67;63 15:59:39 INFO - PID 25756 | __end_tp_report 15:59:39 INFO - PID 25756 | __start_cc_report 15:59:39 INFO - PID 25756 | _x_x_mozilla_cycle_collect,959 15:59:39 INFO - PID 25756 | __end_cc_report 15:59:39 INFO - PID 25756 | __startTimestamp1504825179133__endTimestamp 15:59:39 INFO - PID 25756 | 15:59:39 INFO - PID 25756 | ------- Summary: start ------- 15:59:39 INFO - PID 25756 | Number of tests: 14 15:59:39 INFO - PID 25756 | 15:59:39 INFO - PID 25756 | [#0] ai-astar Cycles:10 Average:106.20 Median:108.00 stddev:3.33 (3.1%) stddev-sans-first:3.46 15:59:39 INFO - PID 25756 | Values: 108.0 107.0 107.0 107.0 108.0 108.0 106.0 106.0 108.0 97.0 15:59:39 INFO - PID 25756 | 15:59:39 INFO - PID 25756 | [#1] audio-beat-detection Cycles:10 Average:123.60 Median:119.00 stddev:11.68 (9.8%) stddev-sans-first:12.06 15:59:39 INFO - PID 25756 | Values: 116.0 116.0 115.0 116.0 144.0 115.0 142.0 120.0 118.0 134.0 15:59:39 INFO - PID 25756 | 15:59:39 INFO - PID 25756 | [#2] audio-dft Cycles:10 Average:164.20 Median:163.50 stddev:3.29 (2.0%) stddev-sans-first:3.40 15:59:39 INFO - PID 25756 | Values: 162.0 161.0 163.0 163.0 162.0 164.0 169.0 165.0 171.0 162.0 15:59:39 INFO - PID 25756 | 15:59:39 INFO - PID 25756 | [#3] audio-fft Cycles:10 Average:75.70 Median:75.50 stddev:2.91 (3.9%) stddev-sans-first:2.92 15:59:39 INFO - PID 25756 | Values: 73.0 79.0 76.0 73.0 75.0 74.0 74.0 74.0 82.0 77.0 15:59:39 INFO - PID 25756 | 15:59:39 INFO - PID 25756 | [#4] audio-oscillator Cycles:10 Average:79.00 Median:79.00 stddev:0.82 (1.0%) stddev-sans-first:0.78 15:59:39 INFO - PID 25756 | Values: 80.0 80.0 79.0 78.0 79.0 78.0 78.0 79.0 80.0 79.0 15:59:39 INFO - PID 25756 | 15:59:39 INFO - PID 25756 | [#5] imaging-gaussian-blur Cycles:10 Average:115.00 Median:116.00 stddev:1.89 (1.6%) stddev-sans-first:1.86 15:59:39 INFO - PID 25756 | Values: 117.0 115.0 116.0 116.0 116.0 117.0 115.0 114.0 113.0 111.0 15:59:39 INFO - PID 25756 | 15:59:39 INFO - PID 25756 | [#6] imaging-darkroom Cycles:10 Average:147.60 Median:148.00 stddev:0.52 (0.3%) stddev-sans-first:0.50 15:59:39 INFO - PID 25756 | Values: 147.0 148.0 147.0 147.0 148.0 148.0 148.0 147.0 148.0 148.0 15:59:39 INFO - PID 25756 | 15:59:39 INFO - PID 25756 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.00 stddev:2.01 (2.1%) stddev-sans-first:2.13 15:59:39 INFO - PID 25756 | Values: 94.0 100.0 93.0 94.0 94.0 94.0 93.0 94.0 94.0 94.0 15:59:39 INFO - PID 25756 | 15:59:39 INFO - PID 25756 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:52.00 stddev:1.45 (2.8%) stddev-sans-first:1.36 15:59:39 INFO - PID 25756 | Values: 54.0 54.0 51.0 52.0 51.0 52.0 54.0 50.0 51.0 52.0 15:59:39 INFO - PID 25756 | 15:59:39 INFO - PID 25756 | [#9] json-stringify-tinderbox Cycles:10 Average:38.50 Median:38.00 stddev:1.27 (3.3%) stddev-sans-first:1.33 15:59:39 INFO - PID 25756 | Values: 38.0 39.0 42.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 15:59:39 INFO - PID 25756 | 15:59:39 INFO - PID 25756 | [#10] stanford-crypto-aes Cycles:10 Average:75.00 Median:75.00 stddev:2.00 (2.7%) stddev-sans-first:2.12 15:59:39 INFO - PID 25756 | Values: 75.0 75.0 73.0 73.0 78.0 78.0 73.0 77.0 74.0 74.0 15:59:39 INFO - PID 25756 | 15:59:39 INFO - PID 25756 | [#11] stanford-crypto-ccm Cycles:10 Average:120.40 Median:119.50 stddev:6.26 (5.2%) stddev-sans-first:6.04 15:59:39 INFO - PID 25756 | Values: 113.0 117.0 118.0 131.0 125.0 120.0 119.0 117.0 130.0 114.0 15:59:39 INFO - PID 25756 | 15:59:39 INFO - PID 25756 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.60 Median:172.00 stddev:3.98 (2.3%) stddev-sans-first:4.18 15:59:39 INFO - PID 25756 | Values: 170.0 167.0 170.0 175.0 168.0 168.0 180.0 173.0 171.0 174.0 15:59:39 INFO - PID 25756 | 15:59:39 INFO - PID 25756 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:4.26 (6.4%) stddev-sans-first:4.52 15:59:39 INFO - PID 25756 | Values: 67.0 64.0 63.0 66.0 67.0 67.0 66.0 78.0 67.0 63.0 15:59:39 INFO - PID 25756 | -------- Summary: end -------- 15:59:39 INFO - PID 25756 | 15:59:39 INFO - PID 25756 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:59:39 INFO - TEST-INFO | 25756: exit 0 15:59:39 INFO - TEST-OK | kraken | took 61235ms 15:59:39 INFO - SUITE-END | took 1170s 15:59:40 INFO - Completed test suite (00:19:30) 15:59:40 INFO - javascript benchmark 15:59:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8575.693228454951, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3099.845007749612, 3106.860191291392, 3120.297932965083, 3122.1258047746637, 3148.5277208418734, 6066.696665166735, 6243.219597550306, 6253.906191407133, 6262.624242545445, 6303.653299068561, 6192.628442293454, 6209.441150296472, 6323.810285691437, 6353.872922541558, 6389.744410223597, 5757.510611598019, 5805.216295800055, 5855.853603659911, 5904.143899134623, 5906.26171728534, 5583.218349431083, 5780.421957804228, 5814.273215848019, 5824.941750582503, 5927.081302398135, 5646.18130371096, 5707.71461426927, 5741.483266506035, 5742.3396309424115, 5785.392533783935, 5691.686957217351, 5756.539476841843, 5773.3072031356505, 5785.074388097884, 5816.1275808628625, 5689.288838895138, 5721.742521586539, 5740.454656807597, 5768.3654797972185, 5779.2775903012125, 6095.939040609582, 6184.659843708594, 6190.3190649028575, 6266.373362663743, 6293.496520278367, 6673.666316684146, 6747.730090796363, 6769.22153952292, 6772.830679233059, 6773.491988100863, 6683.331666833278, 6694.263631000586, 6711.362420570038, 6788.1175447191545, 6793.184817821893, 6085.3914608539235, 6121.66330851803, 6128.785492507785, 6136.96931515344, 6150.04674275488, 6724.361185687352, 6730.629815360202, 6777.525573209927, 6839.487038472134, 6905.413039891565, 6527.281998980108, 6557.983512555466, 6637.137172167684, 6638.535302528777, 6641.169853768279, 6662.600243985376, 6665.633390163587, 6704.765333213411, 6741.325867413219, 6745.763898263537, 6421.614703117828, 6540.50946179029, 6545.509086818508, 6658.633775142317, 6742.292059333798, 6542.0, 6556.475481961432, 6604.537682362285, 6658.134442522538, 6708.865822683617, 5230.0, 5274.156135018378, 5295.761690723926, 5297.390800058042, 5297.655652382583, 4562.657800664897, 4574.4739354973935, 4634.675572709878, 4641.86074417768, 4658.254679251304, 5596.300462442195, 5680.062789639664, 5689.487946084872, 5803.912941305884, 5805.651660900359, 6759.3578610032955, 6773.559718618372, 6776.9322306777285, 6778.389944904982, 6780.525363224626, 5685.147227915846, 5825.679587622722, 5829.125631155361, 5829.854253643693, 5831.679257640785, 5774.133879917962, 5779.826605201851, 5837.153612726131, 5842.941570584325, 5848.327442344185, 6326.240851097898, 6423.871522569522, 6456.451201647818, 6559.639219842859, 6595.109660195908, 6750.088738020402, 6756.594604323756, 6756.966215168893, 6768.661566921536, 6800.659967001531, 6428.6785660717715, 6466.741330346734, 6584.967075164594, 6587.143671322698, 6602.240742314695, 6574.0796288520605, 6582.012698095324, 6595.571287866273, 6672.299408562225, 6705.899411508929, 6769.255381907888, 6774.254831968578, 6779.35596118368, 6787.93212067873, 6788.796336109925, 6328.746850125944, 6406.263279722892, 6579.671016449062, 6602.240742314503, 6610.900836487553, 6257.061440783918, 6297.748090076346, 6303.243610766737, 6310.242770867341, 6321.747130114744, 6252.687365631791, 6253.593516421418, 6260.154879091447, 6268.843278918063, 6282.905756413749, 6302.212223472066, 6339.904901426575, 6358.2687990879795, 6363.936360636334, 6575.210974683068, 6152.630842149485, 6327.936720632735, 6354.555181137276, 6415.9358406417205, 6419.101325814483], "value": 6083.123482568506, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [853.7481442974322, 868.7871471489486, 871.020102384817, 872.5986046418647, 874.3092956564315, 39040.60959390405, 40129.0, 40348.39477407838, 40377.79811100948, 40378.19243615126, 36857.0, 36878.0, 36975.63024369755, 37071.44392834103, 37101.0, 6922.999999999987, 7051.400630946376, 7053.294670532957, 7055.15338159419, 7056.717731290768, 6918.411934985532, 7016.508844380895, 7019.087518622598, 7022.36798688118, 7023.227444981048, 7558.0, 7764.456488045839, 7768.689252429896, 7771.8057048573955, 7781.22187781223, 7649.196834332399, 7722.729704460373, 7723.0, 7724.536527808322, 7724.884126738103, 7424.665890034961, 7593.240675932418, 7648.082230132364, 7671.654775535114, 7676.46264761467, 37595.62404375948, 37730.24539509222, 37895.431568526496, 37898.810505947295, 37981.0, 3578.212793185521, 3626.909327266813, 3629.056445324208, 3629.6007439181667, 3630.8729194478065, 31383.372332553223, 31405.37189256202, 31610.0, 31664.366712665847, 31780.682193178003, 5272.340957380327, 5297.6821390716295, 5300.257963885059, 5332.3601167859715, 5353.866153346197, 173697.0, 175467.0, 175573.0, 175859.0, 176574.0, 40187.39718904219, 41352.586474135474, 46734.76632616883, 49469.50530494721, 50007.0, 52687.20969185465, 52911.0, 52952.47047529475, 53106.203406948924, 53160.73419632955, 15764.290606922716, 15790.36838526446, 15795.605109872115, 15827.68344633101, 15910.443134490237, 12920.806187907188, 12990.805137922938, 13016.088873778934, 13116.344182791014, 13160.868391316155, 14270.785938210935, 14289.64275893111, 14426.917981151246, 14441.277936103153, 14475.131492110508, 5792.189093526909, 5800.216970708984, 5867.530597552186, 5873.471387575139, 6026.758929642853, 1003.3478239144468, 1013.6705570689555, 1031.6028329093203, 1032.5972870580479, 1035.5909415780725, 23126.0, 23294.3011709649, 23337.53324933492, 23361.883190584278, 23388.64917026246, 1072.8980746829038, 1113.33200079953, 1117.8816143408196, 1126.4536699700639, 1129.5820546397722, 12402.81395779064, 12421.565245216554, 12434.81347779784, 12443.502259909683, 12526.624201273977, 224257.0, 224581.0, 224634.0, 224777.0, 226019.0, 53569.1964620531, 53594.73202633962, 53613.46386536163, 53680.46319536833, 53747.0, 1428.2358937968138, 1440.992795036039, 1455.825300963891, 1477.3942683499713, 1484.9703005940037, 23127.07491700313, 23159.536809264057, 23216.651750224097, 23221.187258445872, 23876.52246955086, 37432.25135497329, 37557.0, 37724.24551509009, 37729.43405848969, 37742.62257377501, 184953.0, 185180.0, 185424.0, 185764.0, 185857.0, 30714.539281911235, 31428.52857207098, 31429.685703142677, 35093.0, 35348.646513534535, 42242.15515689608, 43568.34647480354, 44224.55775442204, 44231.55768442275, 44427.33358999553, 31866.36267274688, 32081.518777218826, 32202.83898580492, 32217.35565288728, 32707.34585308328, 31541.0, 31737.20656983594, 31742.0, 32181.195470113435, 32401.837990809894, 35053.47419788756, 35201.29597408089, 35453.46819797757, 35652.108697282776, 35674.0, 31218.375632486768, 31278.53082203814, 31567.84216078905, 31570.842145789124, 31916.52125218077, 22764.178865691036, 23317.085364329458, 23831.880840595684, 24242.0, 24347.26958191257], "value": 16953.601898253026, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.3641080962299, 567.2059117235867, 568.2527476368575, 568.6957477749403, 584.4535359438925, 22021.11915523379, 22900.0, 22937.77062229377, 22957.19649812257, 22958.885205573948, 23374.532509349803, 24275.150369737064, 24504.87747561264, 24609.26172214832, 24632.630510542356, 6427.4215320621015, 6649.468042556584, 6649.468042556608, 6651.0, 6655.0, 6377.33038031007, 6527.5757075789925, 6581.703823327938, 6608.603483790988, 6610.272869984298, 6591.571547849375, 6592.17597800275, 6593.703283352261, 6595.175603049619, 6606.768763093292, 6579.407853293203, 6580.967095164541, 6611.636360000198, 6612.173478315211, 6613.834654133661, 5709.172170035322, 5906.320773111104, 5934.258217722785, 6133.325334213233, 6145.3547377525165, 27163.185104446897, 27845.0, 27863.303417414525, 27875.721242787517, 27903.302417439525, 2090.1743811194647, 2123.4054464749893, 2128.1487405037956, 2131.6589345704615, 2132.17911104226, 18808.529786755305, 19300.0, 19635.31276405333, 19644.50888727779, 19689.80310196894, 2432.5499782540287, 2440.04838113136, 2465.5438743832447, 2475.405902583367, 2490.9128180513776, 117716.41141794236, 123639.0, 133053.0, 139710.30144849312, 139834.0, 14526.418943242363, 14546.709065818624, 14614.269286535631, 14650.487232946796, 15064.802643088116, 22698.886505567367, 22731.204407845646, 22901.541969160524, 23005.194818181284, 23048.4237894054, 9895.158911492605, 10028.247881408777, 10100.646477373404, 10143.340682855738, 10223.64856083105, 9250.121238482334, 9266.814663706687, 9299.674511392204, 9308.301877359225, 9316.813663726687, 8858.955705221433, 8976.506292153863, 9068.954655226682, 9279.62881484733, 9389.671361502315, 2615.686117665892, 2620.135355332736, 2674.8395096294284, 2704.5807899775173, 2712.22701530063, 950.235060776068, 965.5317171171976, 967.0136460809935, 982.6167794560128, 985.7092157813319, 18489.81510184881, 18748.718769218474, 22575.887120564294, 23968.760312397, 24322.513549728905, 1001.5943542865151, 1049.7428130108171, 1050.6270274052754, 1058.0583280880023, 1059.4014381874217, 10545.156387488982, 10726.785464290671, 10829.241953063367, 11115.83326250107, 12739.872601274054, 488646.0, 505624.0, 505917.0, 517568.0, 521121.0, 113414.0, 113940.43029784964, 116190.4190479059, 116544.0, 120634.39682801529, 1093.0708897437273, 1133.8809425010254, 1210.2315029955864, 1211.733418647896, 1271.739293444823, 19495.610087797882, 20120.29578964729, 20166.395008149193, 20264.08811603511, 20265.29071482491, 57218.7139064302, 59156.40843591681, 59410.99999999827, 60126.0, 60214.09678854908, 69749.0, 70645.64677176581, 70899.29100709133, 71298.64350678213, 71617.64191179011, 13964.930175349058, 14534.636634084232, 14548.199849008406, 14551.85448145505, 14591.124532528082, 22841.88579057094, 23555.41111472227, 23570.410739731644, 24008.63987040231, 24018.39954001094, 18851.90574047121, 18951.0524473773, 18960.620787583895, 18966.905165474083, 19030.14364353635, 16528.83471165273, 16961.321547137977, 17396.130193490528, 17630.471085867444, 17768.555786104935, 19348.129334179714, 19427.70858437153, 19456.902715486332, 19565.60868782588, 19603.901980490005, 14290.142591444546, 14416.351264192926, 14417.207053611732, 14594.562163135122, 14605.780913286097, 18216.635667286846, 18432.07839608041, 18620.813791861907, 19106.23575056982, 19247.711284331028], "value": 12057.809765454887, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1298.279454902529, 1394.5188909826106, 1410.6825964158065, 1423.0110073498915, 1424.1668623855044, 14414.135151890894, 15293.541193764177, 15310.0, 15328.540143795704, 15336.38654453822, 14973.727233185193, 15107.546773596781, 15108.395664173448, 15126.546203613882, 15128.470503532379, 2751.2296556964034, 2752.0642981386322, 2821.7742580593504, 2822.647169103862, 2827.7737780977577, 2754.8898044078214, 2787.512185367563, 2792.3437992071845, 2794.2315863137596, 2794.6786119596304, 2656.0969270448045, 2761.765249953756, 2858.885644574225, 2860.5709143628414, 2860.599516067752, 2772.0, 2777.0974433309257, 2798.38435544181, 2804.9158525244275, 2815.9859200703863, 2697.8381297122237, 2744.423671029086, 2747.5191841427672, 2767.5433553463854, 2770.2381844992588, 15003.474878379313, 15217.086974781549, 15225.847741522553, 15230.923845380814, 15232.847671523365, 7896.881546776803, 8165.265126138676, 8195.918040819575, 8197.877031844528, 8205.876911846328, 5087.23691446286, 5242.239875218072, 5312.601554883399, 5344.064788661969, 5448.45515448456, 1923.6248931458372, 1930.276146445083, 1931.9806801932082, 1960.7549056367955, 1974.7926467720827, 25696.48607027849, 25981.0, 25988.610170847263, 26075.869620651774, 26076.478470430677, 6676.632785196861, 6736.326367363323, 6802.081718967975, 6911.2397636259975, 6970.477214208853, 16917.661646766996, 17016.23426945766, 17041.14794260282, 17070.402535911184, 17074.743878841826, 7416.22129676387, 7698.538087714755, 7706.113796913314, 7712.421568382394, 7720.922790772132, 7051.29487051301, 7109.040279562297, 7174.892376614355, 7180.425565954711, 7191.244919283505, 5005.3242812220615, 5159.148740457783, 5174.197999310113, 5207.297014903015, 5217.634765566374, 1256.447163248168, 1286.922784632925, 1409.4150927365029, 1417.6739349949569, 1421.9431222750975, 904.9819003619891, 934.45334479329, 936.0545848692869, 938.7183844846519, 952.3571589177416, 8684.696035638848, 8795.428297160664, 8826.86759698605, 8864.73405797827, 8885.0670679579, 728.9271072892668, 732.7801659502128, 737.5943231222806, 739.5562662402623, 821.3839620284787, 5031.23906632683, 5320.733963301819, 5479.616426850162, 5625.578081643893, 5634.661920284714, 20166.09252583637, 20510.692339614918, 21091.15635374568, 21468.785312146993, 21537.461563461038, 21033.78966210349, 21173.063141969877, 21215.15139394438, 21301.254456093964, 21427.250046248308, 10814.837777433344, 10978.286411383235, 11100.167487438475, 11122.666320010412, 11123.16576256784, 8140.308073813792, 8344.499330040217, 8417.07412184647, 8457.281131103678, 8464.66141354339, 17853.553661158574, 18352.265909363476, 18440.0, 18544.165512552237, 18713.15790789445, 18876.528086797938, 18877.528061798566, 18929.242830287185, 19213.519662008563, 19698.21207151755, 12061.51753929833, 12527.498900043896, 12560.937195313965, 12576.0, 12615.621531354074, 13655.112417692819, 14054.648633784238, 14081.718365632425, 14126.223057731513, 14178.716425671635, 11522.251053681484, 11604.6518604442, 11637.650870473899, 11861.703457413634, 11998.100142489173, 11080.667579972614, 11092.611758588411, 11223.270487418291, 11393.487293071998, 11465.19743617972, 9902.653407130716, 9978.850317245102, 10042.397456152654, 10088.142507886912, 10131.240156988108, 7745.496542724705, 7747.535147890919, 7835.764927052198, 7854.72508462201, 7871.0, 7604.8479030420185, 7788.88316675239, 7807.414443916616, 7908.130105688485, 7973.401994850294], "value": 6694.363998404613, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.24267113986764, 504.4652668171738, 513.8689634143293, 523.3189123292152, 533.8078291814946, 6103.389661033894, 6163.260408750956, 6181.536384771149, 6233.470155036827, 6272.404121608445, 6082.178905847709, 6111.205543279379, 6113.541484388677, 6158.815235542941, 6161.0, 4953.826616068438, 5074.720890351029, 5084.44071152173, 5123.436421993578, 5200.193969934648, 5087.982403519292, 5109.642325037249, 5111.923321150186, 5115.897682046357, 5159.458256883029, 4947.752612369385, 4974.651774375795, 4980.0, 5033.597312215014, 5056.8786439052055, 5017.84946451607, 5050.797968081272, 5056.3173971513925, 5063.329101396567, 5071.797128114871, 4532.4787649420405, 4551.112533056055, 4555.931661025087, 4577.702449340783, 4598.195315819718, 6174.691265436755, 6181.320054793969, 6194.163787888622, 6210.472109870621, 6308.148399965991, 5706.343770466408, 5722.227499287626, 5730.36965933747, 5742.674216942145, 5758.683272420015, 6595.175603049619, 6604.745098431283, 6615.239247486553, 6619.609881924754, 6627.536072474929, 4903.362562866841, 4903.8774030649165, 4914.483979182206, 4919.483454237289, 4923.744445166495, 6918.584884906872, 6997.370236678723, 6999.12510936133, 7014.368706816411, 7025.402840758542, 4959.776810043557, 4980.9252861207115, 4994.725290109006, 5014.648974571818, 5047.369078865142, 5067.138586440314, 5073.16292811682, 5081.644284900095, 5083.525777524476, 5107.642465027412, 5436.864078397993, 5635.408282130399, 5664.943350566524, 5728.971355143198, 5766.855828604318, 6428.646424446607, 6471.838204044937, 6482.384173503509, 6517.51118666102, 6529.2817790043055, 1729.9394521191698, 1736.461696873973, 1741.3295881085874, 1750.8949463032259, 1755.7893052833742, 1190.7201807575213, 1192.5349113845607, 1203.8194270859442, 1217.9269243845397, 1222.2422098298935, 5518.365387980433, 5719.8570035749435, 5720.570957178228, 5729.398413166641, 5744.425557444306, 6616.86766264672, 6668.399844013965, 6673.632950187689, 6709.899351509731, 6755.648870225876, 5629.746661400247, 5635.408282130399, 5673.205751194836, 5710.628809127394, 5733.88532229353, 3533.964660353415, 3574.463830425405, 3580.230250496156, 3612.8735494257576, 3619.7828130312264, 6471.9029214561815, 6505.41451269388, 6573.441257493071, 6605.207375115017, 6672.599644021374, 6841.863162736718, 6936.34104760047, 6968.79093627192, 7009.193942696553, 7054.153501579741, 6571.342865713486, 6610.8016759497295, 6621.046791196609, 6671.299513551007, 6697.9953007049335, 6784.287649796897, 6793.66031698423, 6804.0, 6841.828954275984, 6847.21257055445, 6647.268800432045, 6713.56361836479, 6713.899291510534, 6717.798466046026, 6767.1879374475375, 6555.639439830665, 6570.474362050929, 6585.670716464253, 6630.9336906630315, 6641.280882705701, 4937.851864444073, 4973.701577905337, 5004.345958258708, 5042.520960508746, 5066.721330326867, 6750.379908821946, 6766.289539598271, 6774.525783195129, 6776.881814500749, 6789.253182150058, 6447.290798012121, 6500.349965003462, 6550.3471094206925, 6566.358410397401, 6574.967125164344, 6464.612123272619, 6508.479321654161, 6543.345665433419, 6599.769008084694, 6609.504287178577, 6479.611223326616, 6487.9619260918025, 6496.285408604956, 6535.803925882231, 6544.607323560602, 5968.940310597012, 6592.967035164794, 6609.149438157324, 6630.143559803263, 6672.132622759011, 6318.810435686937, 6331.6517591531065, 6376.713047912763, 6400.071989561395, 6429.903551446639], "value": 5079.129969104012, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [870.2776695342866, 899.8200359928014, 920.5765347939947, 921.2860033474061, 924.0436148586215, 19287.517812054706, 19932.0, 19940.90029549852, 19975.600487990232, 20000.699989500168, 19073.046347682593, 19229.42311730647, 19242.13410396532, 19242.80757192431, 19256.614867702636, 943.5565284316394, 970.0, 976.3897564022486, 979.4612962870428, 981.1856159387727, 969.6218474794836, 981.8772653418323, 982.459647194044, 988.4168340678998, 989.3865803201988, 945.3288165402572, 1017.277732809706, 1028.1671845804885, 1030.938143711376, 1031.4275577054732, 1003.372891942536, 1007.1187710753054, 1007.5214273220236, 1007.7329507680471, 1008.7982403519326, 731.1409094314159, 747.2266204478339, 747.4618274842105, 748.1919526910923, 749.407967705512, 19994.400167994983, 20144.697829532568, 20177.596448070955, 20213.292534761214, 20214.595708085904, 22038.559228815495, 22214.444638883997, 22821.88589057044, 22872.542549148922, 22922.0, 17409.21658525357, 17558.38545650896, 18309.45071647853, 18883.433496995112, 19016.193461621126, 3712.350338690718, 3728.12389088564, 3730.0674831292176, 3730.198007428416, 3731.6828069614116, 105439.47280263626, 137351.31324343313, 137613.0, 138912.0, 139012.0, 15481.303341349667, 15671.294791734399, 15772.211389430482, 15811.841881581036, 15900.363985440685, 30785.2303692405, 32168.83915580407, 32415.51376729351, 32428.83785581057, 32486.512702309483, 9299.67451139207, 9364.906350936404, 9422.293328000427, 9426.811463770824, 9467.857982130274, 4476.082403107355, 4565.315202719618, 4578.198815207325, 4579.335996280521, 4588.632909367243, 5331.840044798662, 5474.671519708831, 5482.424345443751, 5492.972535137299, 5497.36780270274, 1066.4454483668603, 1077.7844431113808, 1080.7946490166844, 1084.5715942202785, 1085.7177133945231, 669.5012215899186, 671.1610486891386, 685.4893104636977, 691.5401258163365, 696.8745625787305, 10888.183386246063, 10972.23194376402, 11187.272827266203, 11319.830202546967, 11499.367534785668, 667.8363800868818, 674.1068084787578, 674.4536925090692, 681.3629256645053, 690.520088538461, 5133.948660513422, 5173.146430838945, 5220.164773636276, 5399.973000135053, 5405.864853378697, 111751.44124279327, 115938.0, 118181.0, 120145.39927300307, 120494.0, 100615.0, 100942.0, 104456.47771761092, 105786.0, 107836.4608176954, 92284.0, 92782.53608732048, 94603.52698236465, 94610.99999999862, 95462.0, 12854.357282135668, 13253.933730331672, 13290.534831280858, 13304.733905322033, 13419.597412077654, 43473.0, 44611.0, 44806.77596611996, 44825.77587112044, 45246.321305179794, 78361.60819195867, 79105.0, 80071.59964200141, 81192.18807811846, 85937.57031214803, 19294.13176407093, 19534.413967580997, 19723.11245993905, 19747.605047899247, 20025.39923802288, 18730.531736706693, 18783.812161878206, 18977.52556186051, 20605.17579296811, 21398.358049258546, 18931.43205703831, 19335.613287734446, 19481.0, 19932.601347972668, 20187.39437816868, 18911.90544047271, 19295.807041929962, 19588.510287242934, 19989.500262493555, 20246.493837654176, 17443.738343924597, 17946.0, 18050.548736281173, 18105.90947045309, 18312.90843545774, 13412.262325571797, 13871.167729936236, 14109.365078571283, 14188.645283867985, 14237.572872813833, 14321.14073155614, 14996.550103496913, 15503.68218701423, 15800.209989500709, 15849.0], "value": 9406.629001161786, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1430.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 107.0, 107.0, 107.0, 108.0, 108.0, 106.0, 106.0, 108.0, 97.0], "value": 106.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 115.0, 116.0, 144.0, 115.0, 142.0, 120.0, 118.0, 134.0], "value": 123.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 163.0, 163.0, 162.0, 164.0, 169.0, 165.0, 171.0, 162.0], "value": 164.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 79.0, 76.0, 73.0, 75.0, 74.0, 74.0, 74.0, 82.0, 77.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 78.0, 79.0, 78.0, 78.0, 79.0, 80.0, 79.0], "value": 79.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 115.0, 116.0, 116.0, 116.0, 117.0, 115.0, 114.0, 113.0, 111.0], "value": 115.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 147.0, 148.0, 148.0, 148.0, 147.0, 148.0, 148.0], "value": 147.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 100.0, 93.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 51.0, 52.0, 51.0, 52.0, 54.0, 50.0, 51.0, 52.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 42.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0], "value": 38.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 73.0, 73.0, 78.0, 78.0, 73.0, 77.0, 74.0, 74.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 117.0, 118.0, 131.0, 125.0, 120.0, 119.0, 117.0, 130.0, 114.0], "value": 120.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 167.0, 170.0, 175.0, 168.0, 168.0, 180.0, 173.0, 171.0, 174.0], "value": 171.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 63.0, 66.0, 67.0, 67.0, 66.0, 78.0, 67.0, 63.0], "value": 66.8, "unit": "score"}]}]} 15:59:40 INFO - Return code: 0 15:59:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:59:40 INFO - # TBPL SUCCESS # 15:59:40 INFO - Running post-action listener: _package_coverage_data 15:59:40 INFO - Running post-action listener: _resource_record_post_action 15:59:40 INFO - [mozharness: 2017-09-07 22:59:40.401710Z] Finished run-tests step (success) 15:59:40 INFO - Running post-run listener: _resource_record_post_run 15:59:40 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 392957952; Read time: 12; Write time: 217200 15:59:40 INFO - TinderboxPrint: CPU usage
13.1% 15:59:40 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 15:59:40 INFO - TinderboxPrint: I/O write bytes / time
392,957,952 / 217,200 15:59:40 INFO - TinderboxPrint: CPU idle
8,230.4 (86.9%) 15:59:40 INFO - TinderboxPrint: CPU user
1,191.9 (12.6%) 15:59:40 INFO - TinderboxPrint: Swap in / out
0 / 0 15:59:40 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 98926592; Read time: 0; Write time: 73092 15:59:40 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:59:40 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 4096; Write bytes: 284147712; Read time: 12; Write time: 142872 15:59:41 INFO - Running post-run listener: _upload_blobber_files 15:59:41 INFO - Blob upload gear active. 15:59:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:59:41 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 15:59:41 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'] 15:59:41 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 15:59:41 INFO - (blobuploader) - INFO - Open directory for files ... 15:59:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:59:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:59:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:44 INFO - (blobuploader) - INFO - Done attempting. 15:59:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:59:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:59:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:45 INFO - (blobuploader) - INFO - Done attempting. 15:59:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:59:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:59:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:46 INFO - (blobuploader) - INFO - Done attempting. 15:59:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:59:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:59:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:47 INFO - (blobuploader) - INFO - Done attempting. 15:59:47 INFO - (blobuploader) - INFO - Iteration through files over. 15:59:47 INFO - Return code: 0 15:59:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:59:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:59:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ee09faaab83d6e2b89d8c783d88a561362ac730f6fd57f0eb2795e7cddfa2014cdfe116e5ffc7816a1c9193c53f046078db1f45e129d994f2aa0049d37bfa086", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/493701ea497e61add49747fd338f9aa88036d0ef0485a2c66403445177e49d5d086fb7eac6d48302e517b0b79fc05943248db41eb27e298a0251bd9fc8e487fc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/61f360e6ed5a60cb70aabdcad6cfeb5730d5c2a23dc6cb6827eef87c9d34c07e61a804d2a209133d7a708e34133f879e8d92e5585d385713f1d64c6a8b0bbc86", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff30c819b1eb06dba563112331610d7679f3a9e807c2b8b7d73d39518054c3d3e9ddbc1c3d85ad02dc7841c1c90e45ffbaaa99a1e3fff5966a21da5bc811b080"} 15:59:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:59:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:59:47 INFO - Contents: 15:59:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ee09faaab83d6e2b89d8c783d88a561362ac730f6fd57f0eb2795e7cddfa2014cdfe116e5ffc7816a1c9193c53f046078db1f45e129d994f2aa0049d37bfa086", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/493701ea497e61add49747fd338f9aa88036d0ef0485a2c66403445177e49d5d086fb7eac6d48302e517b0b79fc05943248db41eb27e298a0251bd9fc8e487fc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/61f360e6ed5a60cb70aabdcad6cfeb5730d5c2a23dc6cb6827eef87c9d34c07e61a804d2a209133d7a708e34133f879e8d92e5585d385713f1d64c6a8b0bbc86", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff30c819b1eb06dba563112331610d7679f3a9e807c2b8b7d73d39518054c3d3e9ddbc1c3d85ad02dc7841c1c90e45ffbaaa99a1e3fff5966a21da5bc811b080"} 15:59:47 INFO - Running post-run listener: copy_logs_to_upload_dir 15:59:47 INFO - Copying logs to upload dir... 15:59:47 INFO - mkdir: /builds/slave/test/build/upload/logs 15:59:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.149906 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-07 15:59:47.776550) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 15:59:47.780496) ========= 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=3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142 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/ee09faaab83d6e2b89d8c783d88a561362ac730f6fd57f0eb2795e7cddfa2014cdfe116e5ffc7816a1c9193c53f046078db1f45e129d994f2aa0049d37bfa086", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/493701ea497e61add49747fd338f9aa88036d0ef0485a2c66403445177e49d5d086fb7eac6d48302e517b0b79fc05943248db41eb27e298a0251bd9fc8e487fc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/61f360e6ed5a60cb70aabdcad6cfeb5730d5c2a23dc6cb6827eef87c9d34c07e61a804d2a209133d7a708e34133f879e8d92e5585d385713f1d64c6a8b0bbc86", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff30c819b1eb06dba563112331610d7679f3a9e807c2b8b7d73d39518054c3d3e9ddbc1c3d85ad02dc7841c1c90e45ffbaaa99a1e3fff5966a21da5bc811b080"} build_url:https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011193 build_url: 'https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ee09faaab83d6e2b89d8c783d88a561362ac730f6fd57f0eb2795e7cddfa2014cdfe116e5ffc7816a1c9193c53f046078db1f45e129d994f2aa0049d37bfa086", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/493701ea497e61add49747fd338f9aa88036d0ef0485a2c66403445177e49d5d086fb7eac6d48302e517b0b79fc05943248db41eb27e298a0251bd9fc8e487fc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/61f360e6ed5a60cb70aabdcad6cfeb5730d5c2a23dc6cb6827eef87c9d34c07e61a804d2a209133d7a708e34133f879e8d92e5585d385713f1d64c6a8b0bbc86", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff30c819b1eb06dba563112331610d7679f3a9e807c2b8b7d73d39518054c3d3e9ddbc1c3d85ad02dc7841c1c90e45ffbaaa99a1e3fff5966a21da5bc811b080"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 15:59:47.808736) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:59:47.809159) ========= 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=3836458cccd887f2d2de3cf1000001de-1504823938.904946-178547142 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004516 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:59:47.893208) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 15:59:47.893528) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 15:59:47.893868) ========= ========= Total master_lag: 0.20 =========