builder: mozilla-central_ubuntu64_hw_test-dromaeojs-stylo-disabled-e10s slave: talos-linux64-ix-011 starttime: 1506884004.97 results: success (0) revision: 3cfe8f48847512909238760bcf8410115cb55ad6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:24.971144) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:24.971577) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:24.971854) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:25.036346) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:25.036639) ========= 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=5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-01 11:53:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.9M=0s 2017-10-01 11:53:25 (45.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.149280 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:25.230979) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:25.231576) ========= 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=5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019149 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:25.282020) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:25.282308) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3cfe8f48847512909238760bcf8410115cb55ad6 --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 3cfe8f48847512909238760bcf8410115cb55ad6 --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=5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-01 11:53:25,508 truncating revision to first 12 chars 2017-10-01 11:53:25,509 Setting DEBUG logging. 2017-10-01 11:53:25,509 attempt 1/10 2017-10-01 11:53:25,509 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3cfe8f488475?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-01 11:53:25,927 unpacking tar archive at: mozilla-central-3cfe8f488475/testing/mozharness/ program finished with exit code 0 elapsedTime=0.783850 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:26.091582) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:26.093964) ========= script_repo_revision: 3cfe8f48847512909238760bcf8410115cb55ad6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:26.094326) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:26.094627) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-01 11:53:26.136011) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2017-10-01 11:53:26.136302) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-stylo-disabled-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-stylo-disabled-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=5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:53:26 INFO - MultiFileLogger online at 20171001 11:53:26 in /builds/slave/test 11:53:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 11:53:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:53:26 INFO - {'append_to_log': False, 11:53:26 INFO - 'base_work_dir': '/builds/slave/test', 11:53:26 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:53:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:53:26 INFO - 'branch': 'Firefox-Non-PGO', 11:53:26 INFO - 'buildbot_json_path': 'buildprops.json', 11:53:26 INFO - 'code_coverage': False, 11:53:26 INFO - 'config_files': ('talos/linux_config.py',), 11:53:26 INFO - 'default_actions': ('clobber', 11:53:26 INFO - 'read-buildbot-config', 11:53:26 INFO - 'download-and-extract', 11:53:26 INFO - 'populate-webroot', 11:53:26 INFO - 'create-virtualenv', 11:53:26 INFO - 'install', 11:53:26 INFO - 'setup-mitmproxy', 11:53:26 INFO - 'run-tests'), 11:53:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:53:26 INFO - 'disable_ccov_upload': False, 11:53:26 INFO - 'disable_stylo': False, 11:53:26 INFO - 'download_minidump_stackwalk': True, 11:53:26 INFO - 'download_symbols': 'ondemand', 11:53:26 INFO - 'enable_stylo': False, 11:53:26 INFO - 'enable_webrender': False, 11:53:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:53:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:53:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:53:26 INFO - 'gecko_profile': False, 11:53:26 INFO - 'gecko_profile_interval': 0, 11:53:26 INFO - 'installer_path': 'installer.exe', 11:53:26 INFO - 'log_level': 'info', 11:53:26 INFO - 'log_name': 'talos', 11:53:26 INFO - 'log_to_console': True, 11:53:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:53:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:53:26 INFO - 'opt_config_files': (), 11:53:26 INFO - 'pip_index': False, 11:53:26 INFO - 'suite': 'dromaeojs-stylo-disabled-e10s', 11:53:26 INFO - 'system_bits': '32', 11:53:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:53:26 INFO - 'title': 'talos-linux64-ix-011', 11:53:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:53:26 INFO - 'use_talos_json': True, 11:53:26 INFO - 'verify': 'False', 11:53:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:53:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:53:26 INFO - 'work_dir': 'build'} 11:53:26 INFO - [mozharness: 2017-10-01 18:53:26.498437Z] Running clobber step. 11:53:26 INFO - Running pre-action listener: _resource_record_pre_action 11:53:26 INFO - Running main action method: clobber 11:53:26 INFO - rmtree: /builds/slave/test/build 11:53:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:53:27 INFO - Running post-action listener: _resource_record_post_action 11:53:27 INFO - [mozharness: 2017-10-01 18:53:27.506675Z] Finished clobber step (success) 11:53:27 INFO - [mozharness: 2017-10-01 18:53:27.506774Z] Running read-buildbot-config step. 11:53:27 INFO - Running pre-action listener: _resource_record_pre_action 11:53:27 INFO - Running main action method: read_buildbot_config 11:53:27 INFO - Using buildbot properties: 11:53:27 INFO - { 11:53:27 INFO - "project": "", 11:53:27 INFO - "product": "firefox", 11:53:27 INFO - "who": "ffxbld@noreply.mozilla.org", 11:53:27 INFO - "installer_path": "public/build/target.tar.bz2", 11:53:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-stylo-disabled-e10s", 11:53:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:53:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-stylo-disabled-e10s", 11:53:27 INFO - "stage_platform": "linux64", 11:53:27 INFO - "basedir": "/builds/slave/test", 11:53:27 INFO - "buildnumber": 87, 11:53:27 INFO - "platform": "ubuntu64_hw", 11:53:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:53:27 INFO - "slavebuilddir": "test", 11:53:27 INFO - "taskId": "TRPb_B0VRfWlSjcpGZ0w_Q", 11:53:27 INFO - "branch": "mozilla-central", 11:53:27 INFO - "script_repo_revision": "production", 11:53:27 INFO - "revision": "3cfe8f48847512909238760bcf8410115cb55ad6", 11:53:27 INFO - "slavename": "talos-linux64-ix-011", 11:53:27 INFO - "repo_path": "mozilla-central" 11:53:27 INFO - } 11:53:27 INFO - Finding installer, test and symbols from parent task. 11:53:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TRPb_B0VRfWlSjcpGZ0w_Q'}, attempt #1 11:53:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TRPb_B0VRfWlSjcpGZ0w_Q'}, attempt #1 11:53:28 INFO - Task dependencies: aaFg7CtMSLCpUxmqnoapeA 11:53:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA'}, attempt #1 11:53:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.tar.bz2 11:53:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.test_packages.json 11:53:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.crashreporter-symbols.zip 11:53:28 INFO - Running post-action listener: _resource_record_post_action 11:53:28 INFO - [mozharness: 2017-10-01 18:53:28.876245Z] Finished read-buildbot-config step (success) 11:53:28 INFO - [mozharness: 2017-10-01 18:53:28.876470Z] Running download-and-extract step. 11:53:28 INFO - Running pre-action listener: _resource_record_pre_action 11:53:28 INFO - Running main action method: download_and_extract 11:53:28 INFO - mkdir: /builds/slave/test/build/tests 11:53:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:28 INFO - trying https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.test_packages.json 11:53:28 INFO - Downloading https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:53:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:53:29 INFO - Downloaded 989 bytes. 11:53:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:53:29 INFO - Using the following test package requirements: 11:53:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:53:29 INFO - u'common': [u'target.common.tests.zip'], 11:53:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:53:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:53:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:53:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:53:29 INFO - u'mozbase': [u'target.common.tests.zip'], 11:53:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:53:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:53:29 INFO - u'web-platform': [u'target.common.tests.zip', 11:53:29 INFO - u'target.web-platform.tests.tar.gz'], 11:53:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:53:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 11:53:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.common.tests.zip 11:53:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:53:29 INFO - Fetch https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.common.tests.zip into memory 11:53:32 INFO - Content-Length response header: 55366754 11:53:32 INFO - Bytes received: 55366754 11:53:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.talos.tests.zip 11:53:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:53:43 INFO - Fetch https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.talos.tests.zip into memory 11:53:44 INFO - Content-Length response header: 14522385 11:53:44 INFO - Bytes received: 14522385 11:53:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:45 INFO - trying https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.tar.bz2 11:53:45 INFO - Downloading https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:53:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:53:48 INFO - Downloaded 61656394 bytes. 11:53:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.tar.bz2 11:53:48 INFO - mkdir: /builds/slave/test/properties 11:53:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:53:48 INFO - Writing to file /builds/slave/test/properties/build_url 11:53:48 INFO - Contents: 11:53:48 INFO - build_url:https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.tar.bz2 11:53:48 INFO - Running post-action listener: _resource_record_post_action 11:53:48 INFO - Running post-action listener: find_tests_for_verification 11:53:48 INFO - Running post-action listener: set_extra_try_arguments 11:53:48 INFO - [mozharness: 2017-10-01 18:53:48.014830Z] Finished download-and-extract step (success) 11:53:48 INFO - [mozharness: 2017-10-01 18:53:48.014992Z] Running populate-webroot step. 11:53:48 INFO - Running pre-action listener: _resource_record_pre_action 11:53:48 INFO - Running main action method: populate_webroot 11:53:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:53:48 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['tresize', 11:53:48 INFO - 'tcanvasmark']}, 11:53:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:53:48 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['dromaeo_css', 11:53:48 INFO - 'kraken']}, 11:53:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:53:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:53:48 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:53:48 INFO - 'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:53:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:53:48 INFO - 'tests': ['damp', 'tps']}, 11:53:48 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:53:48 INFO - 'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['damp', 'tps']}, 11:53:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:53:48 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['dromaeo_dom']}, 11:53:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:53:48 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['basic_compositor_video', 11:53:48 INFO - 'glvideo']}, 11:53:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:53:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:53:48 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:53:48 INFO - 'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['ts_paint_webext', 11:53:48 INFO - 'tp5o_webext']}, 11:53:48 INFO - 'other-e10s': {'tests': ['a11yr', 11:53:48 INFO - 'ts_paint', 11:53:48 INFO - 'tpaint', 11:53:48 INFO - 'sessionrestore', 11:53:48 INFO - 'sessionrestore_many_windows', 11:53:48 INFO - 'sessionrestore_no_auto_restore', 11:53:48 INFO - 'tabpaint', 11:53:48 INFO - 'cpstartup']}, 11:53:48 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['a11yr', 11:53:48 INFO - 'ts_paint', 11:53:48 INFO - 'tpaint', 11:53:48 INFO - 'sessionrestore', 11:53:48 INFO - 'sessionrestore_many_windows', 11:53:48 INFO - 'sessionrestore_no_auto_restore', 11:53:48 INFO - 'tabpaint', 11:53:48 INFO - 'cpstartup']}, 11:53:48 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 11:53:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 11:53:48 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['perf_reftest_singletons']}, 11:53:48 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['perf_reftest']}, 11:53:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:53:48 INFO - 'tsvgr_opacity', 11:53:48 INFO - 'tart', 11:53:48 INFO - 'tscrollx', 11:53:48 INFO - 'tsvg_static']}, 11:53:48 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['tsvgx', 11:53:48 INFO - 'tsvgr_opacity', 11:53:48 INFO - 'tart', 11:53:48 INFO - 'tscrollx', 11:53:48 INFO - 'tsvg_static']}, 11:53:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:53:48 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:53:48 INFO - 'talos_options': ['--disable-stylo'], 11:53:48 INFO - 'tests': ['tp5o']}, 11:53:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:53:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:53:48 INFO - 'talos_options': ['--mitmproxy', 11:53:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:53:48 INFO - '--firstNonBlankPaint'], 11:53:48 INFO - 'tests': ['tp6_google', 11:53:48 INFO - 'tp6_youtube', 11:53:48 INFO - 'tp6_amazon', 11:53:48 INFO - 'tp6_facebook']}, 11:53:48 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:53:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:53:48 INFO - 'talos_options': ['--disable-stylo', 11:53:48 INFO - '--mitmproxy', 11:53:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:53:48 INFO - '--firstNonBlankPaint'], 11:53:48 INFO - 'tests': ['tp6_google', 11:53:48 INFO - 'tp6_youtube', 11:53:48 INFO - 'tp6_amazon', 11:53:48 INFO - 'tp6_facebook']}, 11:53:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:53:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:53:48 INFO - 'talos_options': ['--stylo-threads=1', 11:53:48 INFO - '--mitmproxy', 11:53:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:53:48 INFO - '--firstNonBlankPaint'], 11:53:48 INFO - 'tests': ['tp6_google', 11:53:48 INFO - 'tp6_youtube', 11:53:48 INFO - 'tp6_amazon', 11:53:48 INFO - 'tp6_facebook']}, 11:53:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:53:48 INFO - 'talos_options': ['--xperf_path', 11:53:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:53:48 INFO - 'tests': ['tp5n']}, 11:53:48 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:53:48 INFO - 'talos_options': ['--disable-stylo', 11:53:48 INFO - '--xperf_path', 11:53:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:53:48 INFO - 'tests': ['tp5n']}}} 11:53:48 INFO - Running post-action listener: _resource_record_post_action 11:53:48 INFO - [mozharness: 2017-10-01 18:53:48.208790Z] Finished populate-webroot step (success) 11:53:48 INFO - [mozharness: 2017-10-01 18:53:48.208925Z] Running create-virtualenv step. 11:53:48 INFO - Running pre-action listener: _resource_record_pre_action 11:53:48 INFO - Running main action method: create_virtualenv 11:53:48 INFO - Creating virtualenv /builds/slave/test/build/venv 11:53:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:53:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:53:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:53:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:53:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:53:48 INFO - Installing distribute.............................................................................................................................................................................................done. 11:53:49 INFO - Installing pip...............done. 11:53:49 INFO - Return code: 0 11:53:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:53:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:53:49 INFO - Reading from file tmpfile_stdout 11:53:49 INFO - Output received: 11:53:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:53:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:53: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')]} 11:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53: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')]} 11:53:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:49 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 0x1b231f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cebb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e909c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8dd60>, '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 0x1e8ea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8eef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:53:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:53:49 INFO - Using env: {'DISPLAY': ':0', 11:53:49 INFO - 'HOME': '/home/cltbld', 11:53:49 INFO - 'LANG': 'en_US.UTF-8', 11:53:49 INFO - 'LANGUAGE': 'en_US:en', 11:53:49 INFO - 'LOGNAME': 'cltbld', 11:53:49 INFO - 'MAIL': '/var/mail/cltbld', 11:53:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:49 INFO - 'MOZ_NO_REMOTE': '1', 11:53:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:49 INFO - 'NO_EM_RESTART': '1', 11:53:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:49 INFO - 'PWD': '/builds/slave/test', 11:53:49 INFO - 'SHELL': '/bin/bash', 11:53:49 INFO - 'SHLVL': '1', 11:53:49 INFO - 'TERM': 'linux', 11:53:49 INFO - 'TMOUT': '86400', 11:53:49 INFO - 'USER': 'cltbld', 11:53:49 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 11:53:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:49 INFO - '_': '/tools/buildbot/bin/python'} 11:53:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:53:50 INFO - Downloading/unpacking pip>=1.5 11:53:50 INFO - Running setup.py egg_info for package pip 11:53:50 INFO - warning: no files found matching 'pip/cacert.pem' 11:53:50 INFO - warning: no files found matching '*.html' under directory 'docs' 11:53:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:53:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:53:50 INFO - Installing collected packages: pip 11:53:50 INFO - Found existing installation: pip 1.1 11:53:50 INFO - Uninstalling pip: 11:53:50 INFO - Successfully uninstalled pip 11:53:50 INFO - Running setup.py install for pip 11:53:50 INFO - warning: no files found matching 'pip/cacert.pem' 11:53:50 INFO - warning: no files found matching '*.html' under directory 'docs' 11:53:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:53:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:53:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:53:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:53:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:53:50 INFO - Successfully installed pip 11:53:50 INFO - Cleaning up... 11:53:50 INFO - Return code: 0 11:53:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:50 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 0x1b231f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cebb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e909c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8dd60>, '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 0x1e8ea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8eef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:53:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:53:50 INFO - Using env: {'DISPLAY': ':0', 11:53:50 INFO - 'HOME': '/home/cltbld', 11:53:50 INFO - 'LANG': 'en_US.UTF-8', 11:53:50 INFO - 'LANGUAGE': 'en_US:en', 11:53:50 INFO - 'LOGNAME': 'cltbld', 11:53:50 INFO - 'MAIL': '/var/mail/cltbld', 11:53:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:50 INFO - 'MOZ_NO_REMOTE': '1', 11:53:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:50 INFO - 'NO_EM_RESTART': '1', 11:53:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:50 INFO - 'PWD': '/builds/slave/test', 11:53:50 INFO - 'SHELL': '/bin/bash', 11:53:50 INFO - 'SHLVL': '1', 11:53:50 INFO - 'TERM': 'linux', 11:53:50 INFO - 'TMOUT': '86400', 11:53:50 INFO - 'USER': 'cltbld', 11:53:50 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 11:53:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:50 INFO - '_': '/tools/buildbot/bin/python'} 11:53:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:50 INFO - Downloading/unpacking psutil>=3.1.1 11:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:53:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:53:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:53:51 INFO - Installing collected packages: psutil 11:53:51 INFO - Running setup.py install for psutil 11:53:51 INFO - building 'psutil._psutil_linux' extension 11:53:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 11:53:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:53:52 INFO - building 'psutil._psutil_posix' extension 11:53:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 11:53:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 11:53:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:53:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:53:52 INFO - Successfully installed psutil 11:53:52 INFO - Cleaning up... 11:53:52 INFO - Return code: 0 11:53:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b231f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cebb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e909c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8dd60>, '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 0x1e8ea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8eef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:53:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:53:52 INFO - Using env: {'DISPLAY': ':0', 11:53:52 INFO - 'HOME': '/home/cltbld', 11:53:52 INFO - 'LANG': 'en_US.UTF-8', 11:53:52 INFO - 'LANGUAGE': 'en_US:en', 11:53:52 INFO - 'LOGNAME': 'cltbld', 11:53:52 INFO - 'MAIL': '/var/mail/cltbld', 11:53:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:52 INFO - 'MOZ_NO_REMOTE': '1', 11:53:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:52 INFO - 'NO_EM_RESTART': '1', 11:53:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:52 INFO - 'PWD': '/builds/slave/test', 11:53:52 INFO - 'SHELL': '/bin/bash', 11:53:52 INFO - 'SHLVL': '1', 11:53:52 INFO - 'TERM': 'linux', 11:53:52 INFO - 'TMOUT': '86400', 11:53:52 INFO - 'USER': 'cltbld', 11:53:52 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 11:53:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:52 INFO - '_': '/tools/buildbot/bin/python'} 11:53:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:53:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:53:53 INFO - Installing collected packages: mozsystemmonitor 11:53:53 INFO - Running setup.py install for mozsystemmonitor 11:53:53 INFO - Successfully installed mozsystemmonitor 11:53:53 INFO - Cleaning up... 11:53:53 INFO - Return code: 0 11:53:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:53:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1b231f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cebb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e909c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8dd60>, '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 0x1e8ea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8eef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:53:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:53:53 INFO - Using env: {'DISPLAY': ':0', 11:53:53 INFO - 'HOME': '/home/cltbld', 11:53:53 INFO - 'LANG': 'en_US.UTF-8', 11:53:53 INFO - 'LANGUAGE': 'en_US:en', 11:53:53 INFO - 'LOGNAME': 'cltbld', 11:53:53 INFO - 'MAIL': '/var/mail/cltbld', 11:53:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:53 INFO - 'MOZ_NO_REMOTE': '1', 11:53:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:53 INFO - 'NO_EM_RESTART': '1', 11:53:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:53 INFO - 'PWD': '/builds/slave/test', 11:53:53 INFO - 'SHELL': '/bin/bash', 11:53:53 INFO - 'SHLVL': '1', 11:53:53 INFO - 'TERM': 'linux', 11:53:53 INFO - 'TMOUT': '86400', 11:53:53 INFO - 'USER': 'cltbld', 11:53:53 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 11:53:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:53 INFO - '_': '/tools/buildbot/bin/python'} 11:53:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:54 INFO - Downloading/unpacking jsonschema==2.5.1 11:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:53:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:55 INFO - Downloading functools32-3.2.3-2.tar.gz 11:53:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:53:55 INFO - warning: no files found matching '*.txt' 11:53:55 INFO - no previously-included directories found matching 'build' 11:53:55 INFO - no previously-included directories found matching 'dist' 11:53:55 INFO - no previously-included directories found matching '.git*' 11:53:55 INFO - Installing collected packages: jsonschema, functools32 11:53:55 INFO - Running setup.py install for functools32 11:53:55 INFO - warning: no files found matching '*.txt' 11:53:55 INFO - no previously-included directories found matching 'build' 11:53:55 INFO - no previously-included directories found matching 'dist' 11:53:55 INFO - no previously-included directories found matching '.git*' 11:53:55 INFO - Successfully installed jsonschema functools32 11:53:55 INFO - Cleaning up... 11:53:55 INFO - Return code: 0 11:53:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:55 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 0x1b231f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cebb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e909c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8dd60>, '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 0x1e8ea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8eef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:53:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:53:55 INFO - Using env: {'DISPLAY': ':0', 11:53:55 INFO - 'HOME': '/home/cltbld', 11:53:55 INFO - 'LANG': 'en_US.UTF-8', 11:53:55 INFO - 'LANGUAGE': 'en_US:en', 11:53:55 INFO - 'LOGNAME': 'cltbld', 11:53:55 INFO - 'MAIL': '/var/mail/cltbld', 11:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:55 INFO - 'MOZ_NO_REMOTE': '1', 11:53:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:55 INFO - 'NO_EM_RESTART': '1', 11:53:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:55 INFO - 'PWD': '/builds/slave/test', 11:53:55 INFO - 'SHELL': '/bin/bash', 11:53:55 INFO - 'SHLVL': '1', 11:53:55 INFO - 'TERM': 'linux', 11:53:55 INFO - 'TMOUT': '86400', 11:53:55 INFO - 'USER': 'cltbld', 11:53:55 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 11:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:55 INFO - '_': '/tools/buildbot/bin/python'} 11:53:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:53:56 INFO - Cleaning up... 11:53:56 INFO - Return code: 0 11:53:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:53:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:56 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 0x1b231f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cebb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e909c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8dd60>, '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 0x1e8ea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8eef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:53:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:53:56 INFO - Using env: {'DISPLAY': ':0', 11:53:56 INFO - 'HOME': '/home/cltbld', 11:53:56 INFO - 'LANG': 'en_US.UTF-8', 11:53:56 INFO - 'LANGUAGE': 'en_US:en', 11:53:56 INFO - 'LOGNAME': 'cltbld', 11:53:56 INFO - 'MAIL': '/var/mail/cltbld', 11:53:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:56 INFO - 'MOZ_NO_REMOTE': '1', 11:53:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:56 INFO - 'NO_EM_RESTART': '1', 11:53:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:56 INFO - 'PWD': '/builds/slave/test', 11:53:56 INFO - 'SHELL': '/bin/bash', 11:53:56 INFO - 'SHLVL': '1', 11:53:56 INFO - 'TERM': 'linux', 11:53:56 INFO - 'TMOUT': '86400', 11:53:56 INFO - 'USER': 'cltbld', 11:53:56 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 11:53:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:56 INFO - '_': '/tools/buildbot/bin/python'} 11:53:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:56 INFO - Downloading/unpacking blobuploader==1.2.4 11:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:57 INFO - Downloading blobuploader-1.2.4.tar.gz 11:53:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:53:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:53:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:59 INFO - Downloading docopt-0.6.1.tar.gz 11:53:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:54:00 INFO - Installing collected packages: blobuploader, requests, docopt 11:54:00 INFO - Running setup.py install for blobuploader 11:54:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:54:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:54:00 INFO - Running setup.py install for requests 11:54:00 INFO - Running setup.py install for docopt 11:54:00 INFO - Successfully installed blobuploader requests docopt 11:54:00 INFO - Cleaning up... 11:54:00 INFO - Return code: 0 11:54:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:54:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:00 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 0x1b231f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cebb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e909c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8dd60>, '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 0x1e8ea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8eef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:54:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:54:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:54:00 INFO - Using env: {'DISPLAY': ':0', 11:54:00 INFO - 'HOME': '/home/cltbld', 11:54:00 INFO - 'LANG': 'en_US.UTF-8', 11:54:00 INFO - 'LANGUAGE': 'en_US:en', 11:54:00 INFO - 'LOGNAME': 'cltbld', 11:54:00 INFO - 'MAIL': '/var/mail/cltbld', 11:54:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:00 INFO - 'MOZ_NO_REMOTE': '1', 11:54:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:00 INFO - 'NO_EM_RESTART': '1', 11:54:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:00 INFO - 'PWD': '/builds/slave/test', 11:54:00 INFO - 'SHELL': '/bin/bash', 11:54:00 INFO - 'SHLVL': '1', 11:54:00 INFO - 'TERM': 'linux', 11:54:00 INFO - 'TMOUT': '86400', 11:54:00 INFO - 'USER': 'cltbld', 11:54:00 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 11:54:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:00 INFO - '_': '/tools/buildbot/bin/python'} 11:54:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:54:00 INFO - Running setup.py (path:/tmp/pip-qLM2TK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:54:00 INFO - Running setup.py (path:/tmp/pip-DbS7W5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:54:00 INFO - Running setup.py (path:/tmp/pip-wGcgKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:54:01 INFO - Running setup.py (path:/tmp/pip-gfa3r8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:54:01 INFO - Running setup.py (path:/tmp/pip-R_V6EP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:54:01 INFO - Running setup.py (path:/tmp/pip-n05C4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:54:01 INFO - Running setup.py (path:/tmp/pip-9Gohcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:54:01 INFO - Running setup.py (path:/tmp/pip-I4NAIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:54:01 INFO - Running setup.py (path:/tmp/pip-c6V6dQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:54:01 INFO - Running setup.py (path:/tmp/pip-HrIDBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:54:01 INFO - Running setup.py (path:/tmp/pip-YcU5hW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:54:01 INFO - Running setup.py (path:/tmp/pip-A3NyVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:54:01 INFO - Running setup.py (path:/tmp/pip-YuxVSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:54:01 INFO - Running setup.py (path:/tmp/pip-Zk5yaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:54:01 INFO - Running setup.py (path:/tmp/pip-5tmdLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:54:01 INFO - Running setup.py (path:/tmp/pip-fGiyps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:54:01 INFO - Running setup.py (path:/tmp/pip-73PDjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:54:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:54:02 INFO - Running setup.py install for manifestparser 11:54:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:54:02 INFO - Running setup.py install for mozcrash 11:54:02 INFO - Running setup.py install for mozdebug 11:54:02 INFO - Running setup.py install for mozdevice 11:54:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:54:02 INFO - Running setup.py install for mozfile 11:54:02 INFO - Running setup.py install for mozhttpd 11:54:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:54:02 INFO - Running setup.py install for mozinfo 11:54:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:54:02 INFO - Running setup.py install for mozInstall 11:54:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:54:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:54:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:54:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:54:02 INFO - Running setup.py install for mozleak 11:54:02 INFO - Running setup.py install for mozlog 11:54:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:54:03 INFO - Running setup.py install for moznetwork 11:54:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:54:03 INFO - Running setup.py install for mozprocess 11:54:03 INFO - Running setup.py install for mozprofile 11:54:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:54:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:54:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:54:03 INFO - Running setup.py install for mozrunner 11:54:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:54:03 INFO - Running setup.py install for mozscreenshot 11:54:03 INFO - Running setup.py install for moztest 11:54:03 INFO - Running setup.py install for mozversion 11:54:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:54:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:54:03 INFO - Cleaning up... 11:54:03 INFO - Return code: 0 11:54:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:54:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:03 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 0x1b231f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cebb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e909c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8dd60>, '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 0x1e8ea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8eef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:54:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:54:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:54:03 INFO - Using env: {'DISPLAY': ':0', 11:54:03 INFO - 'HOME': '/home/cltbld', 11:54:03 INFO - 'LANG': 'en_US.UTF-8', 11:54:03 INFO - 'LANGUAGE': 'en_US:en', 11:54:03 INFO - 'LOGNAME': 'cltbld', 11:54:03 INFO - 'MAIL': '/var/mail/cltbld', 11:54:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:03 INFO - 'MOZ_NO_REMOTE': '1', 11:54:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:03 INFO - 'NO_EM_RESTART': '1', 11:54:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:03 INFO - 'PWD': '/builds/slave/test', 11:54:03 INFO - 'SHELL': '/bin/bash', 11:54:03 INFO - 'SHLVL': '1', 11:54:03 INFO - 'TERM': 'linux', 11:54:03 INFO - 'TMOUT': '86400', 11:54:03 INFO - 'USER': 'cltbld', 11:54:03 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 11:54:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:03 INFO - '_': '/tools/buildbot/bin/python'} 11:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:54:04 INFO - Running setup.py (path:/tmp/pip-Oqy7zO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:54:04 INFO - Running setup.py (path:/tmp/pip-foMLUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:54:04 INFO - Running setup.py (path:/tmp/pip-eObbT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:54:04 INFO - Running setup.py (path:/tmp/pip-VVxKd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:54:04 INFO - Running setup.py (path:/tmp/pip-svrP7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:54:04 INFO - Running setup.py (path:/tmp/pip-ItWVdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:54:04 INFO - Running setup.py (path:/tmp/pip-3dIYb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:54:04 INFO - Running setup.py (path:/tmp/pip-US9eKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:54:04 INFO - Running setup.py (path:/tmp/pip-S3wXdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:54:04 INFO - Running setup.py (path:/tmp/pip-plNYUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:54:04 INFO - Running setup.py (path:/tmp/pip-Morq4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:54:04 INFO - Running setup.py (path:/tmp/pip-23HTRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:54:04 INFO - Running setup.py (path:/tmp/pip-s68RMw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:54:04 INFO - Running setup.py (path:/tmp/pip-_LjNRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:54:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:54:05 INFO - Running setup.py (path:/tmp/pip-o3MbDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:54:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:54:05 INFO - Running setup.py (path:/tmp/pip-3xtoXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:54:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:54:05 INFO - Running setup.py (path:/tmp/pip-OKHKXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:54:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:06 INFO - Downloading blessings-1.6.tar.gz 11:54:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:54:06 INFO - Installing collected packages: blessings 11:54:06 INFO - Running setup.py install for blessings 11:54:06 INFO - Successfully installed blessings 11:54:06 INFO - Cleaning up... 11:54:06 INFO - Return code: 0 11:54:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:54:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:54:06 INFO - Reading from file tmpfile_stdout 11:54:06 INFO - Current package versions: 11:54:06 INFO - argparse == 1.2.1 11:54:06 INFO - blessings == 1.6 11:54:06 INFO - blobuploader == 1.2.4 11:54:06 INFO - docopt == 0.6.1 11:54:06 INFO - functools32 == 3.2.3-2 11:54:06 INFO - jsonschema == 2.5.1 11:54:06 INFO - manifestparser == 1.1 11:54:06 INFO - mozInstall == 1.13 11:54:06 INFO - mozcrash == 1.0 11:54:06 INFO - mozdebug == 0.1 11:54:06 INFO - mozdevice == 0.51 11:54:06 INFO - mozfile == 1.2 11:54:06 INFO - mozhttpd == 0.7 11:54:06 INFO - mozinfo == 0.10 11:54:06 INFO - mozleak == 0.1 11:54:06 INFO - mozlog == 3.5 11:54:06 INFO - moznetwork == 0.27 11:54:06 INFO - mozprocess == 0.25 11:54:06 INFO - mozprofile == 0.28 11:54:06 INFO - mozrunner == 6.13 11:54:06 INFO - mozscreenshot == 0.1 11:54:06 INFO - mozsystemmonitor == 0.3 11:54:06 INFO - moztest == 0.8 11:54:06 INFO - mozversion == 1.4 11:54:06 INFO - psutil == 3.1.1 11:54:06 INFO - requests == 1.2.3 11:54:06 INFO - wsgiref == 0.1.2 11:54:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:06 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 0x1b231f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cebb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e909c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8dd60>, '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 0x1e8ea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8eef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:54:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:54:06 INFO - Using env: {'DISPLAY': ':0', 11:54:06 INFO - 'HOME': '/home/cltbld', 11:54:06 INFO - 'LANG': 'en_US.UTF-8', 11:54:06 INFO - 'LANGUAGE': 'en_US:en', 11:54:06 INFO - 'LOGNAME': 'cltbld', 11:54:06 INFO - 'MAIL': '/var/mail/cltbld', 11:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:06 INFO - 'MOZ_NO_REMOTE': '1', 11:54:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:06 INFO - 'NO_EM_RESTART': '1', 11:54:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:06 INFO - 'PWD': '/builds/slave/test', 11:54:06 INFO - 'SHELL': '/bin/bash', 11:54:06 INFO - 'SHLVL': '1', 11:54:06 INFO - 'TERM': 'linux', 11:54:06 INFO - 'TMOUT': '86400', 11:54:06 INFO - 'USER': 'cltbld', 11:54:06 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 11:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:06 INFO - '_': '/tools/buildbot/bin/python'} 11:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:54:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:54:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:54:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:54:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:54:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:54:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:54:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:54:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:54:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:54:08 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:54:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:54:08 INFO - Installing collected packages: simplejson 11:54:08 INFO - Running setup.py install for simplejson 11:54:08 INFO - building 'simplejson._speedups' extension 11:54:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 11:54:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 11:54:09 INFO - Successfully installed simplejson 11:54:09 INFO - Cleaning up... 11:54:09 INFO - Return code: 0 11:54:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:54:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:09 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 0x1b231f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cebb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e909c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8dd60>, '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 0x1e8ea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8eef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:54:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:54:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:54:09 INFO - Using env: {'DISPLAY': ':0', 11:54:09 INFO - 'HOME': '/home/cltbld', 11:54:09 INFO - 'LANG': 'en_US.UTF-8', 11:54:09 INFO - 'LANGUAGE': 'en_US:en', 11:54:09 INFO - 'LOGNAME': 'cltbld', 11:54:09 INFO - 'MAIL': '/var/mail/cltbld', 11:54:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:09 INFO - 'MOZ_NO_REMOTE': '1', 11:54:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:09 INFO - 'NO_EM_RESTART': '1', 11:54:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:09 INFO - 'PWD': '/builds/slave/test', 11:54:09 INFO - 'SHELL': '/bin/bash', 11:54:09 INFO - 'SHLVL': '1', 11:54:09 INFO - 'TERM': 'linux', 11:54:09 INFO - 'TMOUT': '86400', 11:54:09 INFO - 'USER': 'cltbld', 11:54:09 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 11:54:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:09 INFO - '_': '/tools/buildbot/bin/python'} 11:54:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:54:09 INFO - Cleaning up... 11:54:09 INFO - Return code: 0 11:54:09 INFO - Running post-action listener: _resource_record_post_action 11:54:09 INFO - Running post-action listener: _start_resource_monitoring 11:54:09 INFO - Starting resource monitoring. 11:54:09 INFO - [mozharness: 2017-10-01 18:54:09.526883Z] Finished create-virtualenv step (success) 11:54:09 INFO - [mozharness: 2017-10-01 18:54:09.527367Z] Running install step. 11:54:09 INFO - Running pre-action listener: _resource_record_pre_action 11:54:09 INFO - Running main action method: install 11:54:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:54:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:54:09 INFO - Reading from file tmpfile_stdout 11:54:09 INFO - Detecting whether we're running mozinstall >=1.0... 11:54:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:54:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:54:09 INFO - Reading from file tmpfile_stdout 11:54:09 INFO - Output received: 11:54:09 INFO - Usage: mozinstall [options] installer 11:54:09 INFO - Options: 11:54:09 INFO - -h, --help show this help message and exit 11:54:09 INFO - -d DEST, --destination=DEST 11:54:09 INFO - Directory to install application into. [default: 11:54:09 INFO - "/builds/slave/test"] 11:54:09 INFO - --app=APP Application being installed. [default: firefox] 11:54:09 INFO - mkdir: /builds/slave/test/build/application 11:54:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:54:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:54:27 INFO - Reading from file tmpfile_stdout 11:54:27 INFO - Output received: 11:54:27 INFO - /builds/slave/test/build/application/firefox/firefox 11:54:27 INFO - Running post-action listener: _resource_record_post_action 11:54:27 INFO - [mozharness: 2017-10-01 18:54:27.694317Z] Finished install step (success) 11:54:27 INFO - [mozharness: 2017-10-01 18:54:27.694664Z] Running setup-mitmproxy step. 11:54:27 INFO - Running pre-action listener: _resource_record_pre_action 11:54:27 INFO - Running main action method: setup_mitmproxy 11:54:27 INFO - Skipping: mitmproxy is not required 11:54:27 INFO - Running post-action listener: _resource_record_post_action 11:54:27 INFO - [mozharness: 2017-10-01 18:54:27.696370Z] Finished setup-mitmproxy step (success) 11:54:27 INFO - [mozharness: 2017-10-01 18:54:27.696684Z] Running run-tests step. 11:54:27 INFO - Running pre-action listener: _resource_record_pre_action 11:54:27 INFO - Running pre-action listener: _set_gcov_prefix 11:54:27 INFO - Running main action method: run_tests 11:54:27 WARNING - Try message not found. 11:54:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:54:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:54:27 INFO - Python 2.7.3 11:54:27 INFO - Return code: 0 11:54:27 INFO - grabbing minidump binary from tooltool 11:54:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:27 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 0x1e8dd60>, '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 0x1e8ea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8eef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:54:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:54:27 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 11:54:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:54:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:54:27 INFO - Return code: 0 11:54:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:54:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:54:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:54:27 INFO - ENV: RUST_BACKTRACE is now full 11:54:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:54:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:54:27 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-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log'] in /builds/slave/test/build 11:54:27 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-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log 11:54:27 INFO - Using env: {'DISPLAY': ':0', 11:54:27 INFO - 'HOME': '/home/cltbld', 11:54:27 INFO - 'LANG': 'en_US.UTF-8', 11:54:27 INFO - 'LANGUAGE': 'en_US:en', 11:54:27 INFO - 'LOGNAME': 'cltbld', 11:54:27 INFO - 'MAIL': '/var/mail/cltbld', 11:54:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:54:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:54:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:27 INFO - 'MOZ_NO_REMOTE': '1', 11:54:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:54:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:27 INFO - 'NO_EM_RESTART': '1', 11:54:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:27 INFO - 'PWD': '/builds/slave/test', 11:54:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:54:27 INFO - 'RUST_BACKTRACE': 'full', 11:54:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:54:27 INFO - 'SHELL': '/bin/bash', 11:54:27 INFO - 'SHLVL': '1', 11:54:27 INFO - 'TERM': 'linux', 11:54:27 INFO - 'TMOUT': '86400', 11:54:27 INFO - 'USER': 'cltbld', 11:54:27 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 11:54:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:27 INFO - '_': '/tools/buildbot/bin/python'} 11:54:27 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-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log'] with output_timeout 3600 11:54:28 INFO - mozversion application_buildid: 20171001175841 11:54:28 INFO - mozversion application_changeset: 3cfe8f48847512909238760bcf8410115cb55ad6 11:54:28 INFO - mozversion application_display_name: FirefoxNightly 11:54:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:54:28 INFO - mozversion application_name: Firefox 11:54:28 INFO - mozversion application_remotingname: firefox 11:54:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:54:28 INFO - mozversion application_vendor: Mozilla 11:54:28 INFO - mozversion application_version: 58.0a1 11:54:28 INFO - mozversion platform_buildid: 20171001175841 11:54:28 INFO - mozversion platform_changeset: 3cfe8f48847512909238760bcf8410115cb55ad6 11:54:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:54:28 INFO - mozversion platform_version: 58.0a1 11:54:28 INFO - using testdate: 1506884068 11:54:28 INFO - actual date: 1506884068 11:54:28 INFO - starting webserver on 'localhost:44220' 11:54:28 INFO - SUITE-START | Running 2 tests 11:54:28 INFO - TEST-START | dromaeo_css 11:54:28 INFO - Initialising browser for dromaeo_css test... 11:54:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44220/getInfo.html -profile /tmp/tmpYXHaWm/profile 11:54:28 INFO - TEST-INFO | started process 6928 (/builds/slave/test/build/application/firefox/firefox http://localhost:44220/getInfo.html) 11:54:34 INFO - TEST-INFO | 6928: exit 0 11:54:34 INFO - Browser initialized. 11:54:34 INFO - Running cycle 1/1 for dromaeo_css test... 11:54:34 INFO - Using env: {'DISPLAY': ':0', 11:54:34 INFO - 'HOME': '/home/cltbld', 11:54:34 INFO - 'JSGC_DISABLE_POISONING': '1', 11:54:34 INFO - 'LANG': 'en_US.UTF-8', 11:54:34 INFO - 'LANGUAGE': 'en_US:en', 11:54:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:54:34 INFO - 'LOGNAME': 'cltbld', 11:54:34 INFO - 'MAIL': '/var/mail/cltbld', 11:54:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:54:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:54:34 INFO - 'MOZ_CRASHREPORTER': '1', 11:54:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:54:34 INFO - 'MOZ_NO_REMOTE': '1', 11:54:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:54:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:34 INFO - 'NO_EM_RESTART': '1', 11:54:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:34 INFO - 'PWD': '/builds/slave/test', 11:54:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:54:34 INFO - 'RUST_BACKTRACE': 'full', 11:54:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:54:34 INFO - 'SHELL': '/bin/bash', 11:54:34 INFO - 'SHLVL': '1', 11:54:34 INFO - 'STYLO_FORCE_DISABLED': '1', 11:54:34 INFO - 'TERM': 'linux', 11:54:34 INFO - 'TMOUT': '86400', 11:54:34 INFO - 'USER': 'cltbld', 11:54:34 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 11:54:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:34 INFO - '_': '/tools/buildbot/bin/python'} 11:54:34 INFO - TEST-INFO | started process 7163 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYXHaWm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:54:35 INFO - PID 7163 | 11:54:35 INFO - PID 7163 | (/builds/slave/test/build/application/firefox/firefox:7212): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:54:35 INFO - PID 7163 | 11:54:45 INFO - PID 7163 | RSS: Main: 151805952 11:54:45 INFO - PID 7163 | 11:57:42 INFO - PID 7163 | Cycle 1(1): loaded http://localhost:44220/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44220/tests/dromaeo/cssquery-ext.html) 11:57:44 INFO - PID 7163 | RSS: Main: 150216704 11:57:44 INFO - PID 7163 | 12:00:45 INFO - PID 7163 | Cycle 1(1): loaded http://localhost:44220/tests/dromaeo/cssquery-ext.html (next: http://localhost:44220/tests/dromaeo/cssquery-jquery.html) 12:00:46 INFO - PID 7163 | RSS: Main: 151322624 12:00:46 INFO - PID 7163 | 12:03:48 INFO - PID 7163 | Cycle 1(1): loaded http://localhost:44220/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44220/tests/dromaeo/cssquery-mootools.html) 12:03:48 INFO - PID 7163 | RSS: Main: 152236032 12:03:48 INFO - PID 7163 | 12:06:50 INFO - PID 7163 | Cycle 1(1): loaded http://localhost:44220/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44220/tests/dromaeo/cssquery-prototype.html) 12:06:51 INFO - PID 7163 | RSS: Main: 151638016 12:06:51 INFO - PID 7163 | 12:09:54 INFO - PID 7163 | Cycle 1(1): loaded http://localhost:44220/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44220/tests/dromaeo/cssquery-yui.html) 12:09:55 INFO - PID 7163 | RSS: Main: 154943488 12:09:55 INFO - PID 7163 | 12:12:56 INFO - PID 7163 | Cycle 1(1): loaded http://localhost:44220/tests/dromaeo/cssquery-yui.html (next: http://localhost:44220/tests/dromaeo/cssquery-dojo.html) 12:12:57 INFO - PID 7163 | RSS: Main: 154411008 12:12:57 INFO - PID 7163 | 12:12:57 INFO - PID 7163 | __start_tp_report 12:12:57 INFO - PID 7163 | _x_x_mozilla_page_load 12:12:57 INFO - PID 7163 | _x_x_mozilla_page_load_details 12:12:57 INFO - PID 7163 | |i|pagename|runs| 12:12:57 INFO - PID 7163 | |0;dojo.html;3186.4264432402174;3194.1056504178823;3205.0545089198677;3219.533167690685;3224.3712476067176;6132.141500189977;6250.8124756257275;6251.187345645071;6317.715702793374;6343.207099112617;6177.536684748639;6241.344658810816;6253.656048917308;6282.3717628237155;6295.811125666238;5607;5844.64932104073;5888.470037696607;5890.882182356372;5891.617044892068;1259.007721215666;5802.245708057946;5861.413858614147;5875.706214689249;5894.115882617599;5283.55089817364;5573.8606534836545;5643.830685079454;5660.688662123582;5804.390538993408;5671.376148623689;5731.885362292731;5736.168255602956;5769.798057068025;5787.594868359217;5725.885482290372;5760.222369980041;5797.391273916221;5802.448767367094;5818.941810581925;6126.479249263818;6239.447846989462;6263.6868156592445;6289.591176573508;6332.4934005279465;6552.901706474407;6608.86782264352;6615.239247486505;6631.602103873783;6765.05289259504;6826.795196144124;6828.52200345976;6833.965830170867;6842.828929276759;6847.520673552854;6242.500599951993;6263.123162757218;6266.279377871512;6318.4313411793155;6324.209473815773;6910.7581234656545;6927.93072069283;6996.580205187705;6997.965010174917;7001.089858318386;6377.394147555975;6455.935440645628;6471.320511346241;6482.448991835747;6657.600543967377;6603.834904127435;6614.404703576605;6631.23740769808;6632.966835165793;6639.834004149839;6504.60972341661;6591.472682185509;6601.933980660228;6609.339066093352;6621.33786621334;6632.933670663231;6724.966375168093;6776.864462710817;6778.7288508459105;6782.050512928194;5247.895042099137;5277.26837824908;5338.54622357104;5342.0384330820825;5357.11607584752;4512.2554778461845;4597.103564804898;4597.678162528591;4670.089332580114;4672.649551283667;5690.658560486384;5809.244798176294;5810.88378232433;5813.970930145322;5815;6779.898301525481;6830;6863.965680171567;6865.107536020286;6869.553479023947;5772.942270577324;5785.103308987113;5869.119632055226;5872.853178670482;5886.293644762571;5697.772089116389;5724.942750572441;5749.051406517961;5763.711814409263;5770.394108618619;6458.192725909262;6527.445167160845;6548.541602087904;6548.70530826114;6587.242467116343;6722.764703235461;6723.5293529452465;6730.32696730323;6823.249442561215;6826.41975432074;6272.09054687077;6466.612003279818;6466.838329041623;6474.741010359533;6476.6114033158165;6533.41199292066;6580.934190658032;6612.5040621952585;6623.503237257321;6627.039079333566;6776.186857577122;6868.381845633917;6873.243943166148;6873.5875847449315;6877.415419689182;6451.580647257913;6506.154199953976;6584.637844918576;6585.6377899216;6597;6475.967620161869;6486.2216534016225;6494.0258961156205;6496.7726129585235;6497.057926600522;6360.332165122781;6442.581232219891;6493.805185844432;6510.316416776171;6522.34776522363;6575.079488871657;6578.80263592093;6629.03878937561;6667.599944003376;6672.966635166793;6275.497960163085;6340.478116516737;6382.234131904202;6387.105805187371;6427.1002059710745 12:12:57 INFO - PID 7163 | |1;ext.html;869.3219288954616;883.3992884838308;889.7997950461147;890.6036813617939;896.3814967672307;38146.237075258476;41454.792726036365;41529.16941661165;41564.37653435197;41570.16859662805;38334.80832595833;38476;38556;38692.61307386925;38786.418203726964;7106.431485481149;7298.343149116578;7298.927010729878;7299.270072992685;7309.488335816495;7129.500934934556;7240.601766902818;7240.674169662351;7240.710371585156;7247.275272472763;7991.120976692559;8118.14759450258;8118.228768267005;8121.066077401086;8121.30968867647;7990.560519171443;8059.717909873185;8060.596970151499;8061.596920153998;8061.959690201512;7833.099193592753;7872.763817085496;7992.200779922019;8009.719659811937;8011.639476223584;38968.22063558713;38997.41503877444;39206.607933920866;39247.21505569901;39275.803620982;3419.64093770153;3452.3267962747273;3480.9651903480894;3481.8781342653137;3485.041613556267;31683.366332673217;31900.840495797605;32009.83995080033;32052;32080.197995050075;5104.2088476286235;5107.387113546361;5117.948820511784;5141.948580514222;5144.922826157574;187114;189529;189541;190180;190440;43962.78018609886;44562.99999999935;48796.5120348792;48931.510684892695;49151.26273105906;52631;52877.735611321696;53071.28501004892;53116;53141.20288195603;16382.098984555963;16529.256183471774;16538.50384488467;16539.751903721455;16540.83459165417;13504.122232054886;13524.18854868711;13527.86472135266;13534.323283835769;13627;14312;14350.928245358915;14477.565673029825;14512.346944387527;14678.825693944458;5693.345265294544;5699.658020518782;5758.337791153987;5777.884442311214;5794;981.9754506137403;1002.3835341265069;1004.5680357446222;1012.2610494339174;1024.155072065549;22243.44391390196;22376.55246895053;22647.433814154778;22657;22664.320070397913;1106.3085571517802;1111.4387234446554;1119.160629527854;1119.3787447966456;1132.6885106595587;11926.165168438127;11936.820947685792;11956.52173913051;11996.340201289013;12036.87963120375;214280;215305;216251;216387;216631;52518.21222681663;52663;52735.736321318145;52789.47210527846;52864.471355286725;1417.0505761139855;1465.0623600895435;1467.6771110355705;1477.0325436838887;1478.5564330700747;21633.891830540746;22162.556748865256;22294.554108918055;22541.774582254624;22682.43293917665;36630.45054324134;36647;36908.44637330389;36928.26143477169;36982.44526332161;185955;188712;189004;189079;189573;33787.3242535143;35608.46587301244;35863.46204806982;36156.819215904805;36170;44092.77953610211;45509.31736023896;45618.31572526481;45871;46082.539174607824;32322.35355292834;32387.028389148363;32625.836870815496;32640;33385.66614333923;31966.52050219202;32248.83875580701;32299.838500807346;32536.186595335304;32814.17964550905;35703.46444803382;37330.62669373272;37378.43932340963;37510.8124459376;37576.2484750298;32097.679023209468;32299.677003229666;32346.191345216557;32405.18987025343;32468.188295292806;23499.41251468727;23544.17595384153;23715.05139794458;23841.880790595937;24254.393640159138 12:12:57 INFO - PID 7163 | |2;jquery.html;559.56074481532;578.869754305281;582.3186871360508;600.2227115884929;613.2181468627499;22229.77770222297;22778;22834.086636534517;22917.42706432338;22985.770142298592;24187.27438176853;25120;25258.621120683205;25312;25362.239132826046;6575.4410875075555;6688.297728738486;6721.428678562328;6723;6734.360235777618;6500.902486462707;6604.372584604479;6715.9328406715795;6721.697523611425;6772.153480814898;6732.091167692372;6751.898721519181;6759.155105611799;6765.188177418692;6767.154105736783;6739.326067393271;6756.695948682272;6758.932410675929;6765.424938880201;6767.05261263421;6858.142732158481;6879.071325371111;6903.585784852925;6961.338672826124;6963.617001064939;30194.54708179357;30508.389832203233;30606.846965765253;30819;30929.536056959165;2014.5970805838745;2026.371824734337;2034.471037530238;2037.3887833649846;2041.1937284772432;20459.48851278715;20907.68638470424;21570.78429215719;21594.244201452875;21775.782242177534;2544.745525447459;2599.9870000649876;2626.3434141464636;2671.545837207679;2677.973220267792;116597;122767.3861630695;134986.325068375;141602.99999999898;142359;20101.698474522895;21082.472938176667;22043;22186.445338866335;23609.173678921154;42674;43159.35260971088;43311.56688433138;43464.78267608705;43635.12729745387;11418.771624567627;11859.70350741221;11860;11927.270545770814;12038.699032524082;9946.154576861049;9961.551730172016;9972;9991.900080999243;10067.798644027078;11188.608398706006;11219.607313744144;11351.205415620827;11720.706982325339;11741.06071514294;3895.383415882434;3933.4296527003426;4117.97941010299;4129.814158362881;4264.275073237557;937.2595649436869;954.7947191353753;973.5375696544085;977.3354119199013;983.2871168402851;19357.51606209856;19691.212351506067;19757;20085.49786255355;20183.192672293237;1018.1244130048152;1031.4275577054657;1034.3690348887174;1044.3525014490913;1051.4269723000982;10809.945950270197;10877.619283325204;10878.619248326271;10911.672649820519;11023.324311415468;421811;422225;426597;429680;436056;112142.43928780303;115430.1115519942;116864;117707;118704.40647796706;1065.4459680966008;1105.9004689577976;1169.6841852699893;1184.3663639952413;1195.0797885628192;19793.50516237048;19918.90040549788;20003.499912502302;20214.292499762436;20395.490112746706;55181;55502.72248638731;56271.43728562662;56772.716136419054;58612.706936465045;66575;66768;66878.66560667165;67288.32711673016;68505.31494684989;18878;19335;19487.610247795248;19520.219191232758;19539.023048847786;21596;22080;22196.77803221947;22214;22264.554708906056;16526.09106499106;16748.916255418644;16846.74729879077;16849.2417841195;16929.238184281985;15430.768538471708;15489.922550387177;15742.842571574138;15784.842151578796;15866.04803711781;16845.15774211309;16854.915725421295;18523.536911577317;18594.907025464785;18700.064996749836;18047.90976045164;18060.819391805915;18065.187066582297;18213.90893045526;18351.357702480884;17217.31130754756;17394.565135871704;17560.473185803934;17645.558861028065;17649.117544122484 12:12:57 INFO - PID 7163 | |3;mootools.html;1291.3091496049615;1366.064245991496;1367.5828872193983;1378.800073989271;1380.3236414157054;13879.236641984688;14551.981361304714;14563.708725825478;14574.635634109152;14582.343794529232;14119;14255.144691318528;14256.643583910381;14271.072380295274;14296.213708246043;2521.949561008779;2541.364658835296;2576.948461030778;2592.1057235253807;2594.182832407795;2747.3269049083;2779.749822515973;2780.2771279467383;2782.860856957144;2787.02454141051;2715.0768738628863;2738.561830107183;2788.3029242689327;2789;2789.790765692571;2728.5497892847657;2787.0663327785114;2801.929951751202;2806.5088609493255;2806.536921407965;2580.5742052561304;2604.5702459094227;2639.0895141176265;2656.9601455978177;2727.2909043648597;14050.648733781634;14196.290185490787;14197.219152946584;14218.431262749478;14220.71558568823;7568.356689681384;7885.645145968445;7893.565853878034;7896.407769417317;7900.367970562399;5142.3829140503;5348.465153484659;5428.321459817523;5445;5496.285482887199;1925.1625542888887;1959.9020048997493;1960.4804726747423;1994.1425187169511;2019.2629690163133;24061.27816165518;24068;24210.39474013146;24245.515089698107;24337;6666.200055993215;6918.827029324307;7062.081929349151;7079.1858936223;7098.751543695946;16363.427280045142;16379.344826206818;16417.343306267856;16442.424515141913;16515.504534863972;7202.4238060954995;7798.376129909594;7801.180876008051;7813.882791758128;7817;6996.300369962963;7174.641267936582;7231.855362892713;7253.528520646141;7264.564126152342;4961.3798275215595;5084.6440749147205;5110.48895110486;5112.948870511322;5117.590592752571;1531.3491765999406;1541.0676540692718;1541.6300087978805;1557.2058250292432;1558.8363221861769;888.7022847346083;901.1799262671069;904.5251243097426;905.6467977488785;917.6742256498981;7655.387568994467;7708.152103268636;7717.112532058773;7730.497517661334;7757.573333466601;700.1213477086225;739.3604532079712;742.4876834983844;743.5761615879005;752.8381397999457;4976.305671736655;5333.386660534011;5383.246345511631;5458.372287186945;5484.012877681976;19225.903870480557;19447.902760486108;20142.19431222764;20176.596468070555;20327.695084573745;19956.401307960783;20182.89908550448;20192.798072019385;20293.28973485921;20424;10313.896861031444;10560.52477638508;10608.363498190134;10643.095336896451;10700.357978521159;8281.83436331282;8396.622152003052;8440.198181172778;8447.95776021116;8466.915330846612;17104.828951710322;17181.828181718523;17198.22607982619;17406;17634;17619.559511011816;17668.116594169984;17771.466855994342;17863.28546858111;18158.636827263115;10744.51649675751;10977.3962432067;10989.120870330515;10994.230403871652;10996.890031099587;13155.868441315464;13162.934185329395;13233.669158271121;13304.733905321646;13376;10048.246381521269;10123.291369604058;10477.633282835064;10561.788764224826;10662.573497060032;10631.096356809758;10669.46652667348;10775.784484310427;10845.457727113771;10980;9606.375585586915;9821.410715357102;9821.85267220978;9833.213342932406;9880.20958323318;7321.633918303957;7325.7435989742235;7369.926300737138;7429.257074292744;7431.516951398142;7374.926250737424;7564.697412103455;7603.315701586663;7606.923930760621;7639.350655194371 12:12:57 INFO - PID 7163 | |4;prototype.html;516.2282387830193;520.9453007434221;535.2319421629961;548.106586264389;551.8978988887054;6136.23297087864;6344.3338449462835;6345.048242763588;6396.744130234791;6494.577852439588;6243.656598887071;6285.277193122793;6326.367363263671;6344.175257216555;6413.236360001002;5248.475152484759;5267.23625074364;5300.602454815885;5376.569874410038;5377.838664840061;5192.948070519303;5273.762680679379;5286.3392075990505;5337.386200586924;5348.866278343034;5305.787768489276;5313.787448502056;5317.304473390995;5366.6779993200535;5370.973145134269;5244.423113457517;5266.789328426858;5293.735313234342;5319;5414.133738601825;4962.255661650781;5008.7996480140755;5015.774290156952;5020.1636075617525;5030;6376.33048529907;6403.7438502459845;6425.550211485198;6427.228732552124;6459.192600924885;6212.782552610682;6252.74989000444;6261.405166509175;6267.62394256346;6272.651379953332;6563.671816409161;6667.733290668368;6672.69972851218;6708.899366509506;6798.150231221098;5349.973250133763;5367.5169234769055;5425.565954723613;5444.6188766785945;5446.863828404322;6952.860942781167;7113.395361394288;7151.177614574339;7162.606056666933;7219.278072192791;5513.393526712059;5607.130894711327;5611.466910643482;5666.773329066791;5702.45826646468;5614.691191984399;5677.37548869624;5725.77096916127;5754.539636829044;5762.394948530344;6166.568340216142;6169.598976066542;6177.660228687376;6186.381361863778;6202.503799696104;6806.251312355637;6820.454363650896;6846.726130954706;6891.827704307432;6897.379235868796;1830.4691639424418;1874.297138573035;1932.391296741522;1960.2746983616153;2005.4785755703622;1452.404514149215;1461.335092532895;1467.8605532474398;1486.197453375187;1497.9325930333162;5771.220885180531;5793.102069179284;5814.651120932757;5817.545613596601;5823.5923485355615;6690;6756.797296081126;6784.76253331131;6800.149981252343;6936.237013928464;5783.219265399117;5796.82609521715;5824.3302020267365;5827.650340979555;5862.443067908542;3589.210373717778;3606.1525541497726;3637.054365864841;3650.032741323498;3655.9634403656155;6584.703688334036;6597.505187110986;6616.801495955129;6674.83312917181;6677.1319728435965;6792.320767923168;6861.176658800976;6924.549904256207;6929.896051559231;7007.299270072952;6325.070853389669;6327.430531252209;6412.358764123644;6457.160569125889;6550.443212327005;6772.25505194438;6787.89818152738;6837.726490940307;6840.897386539106;6879.552829066095;6675.899861502178;6780.627065511444;6816.522843401112;6818.556793808387;6852.349026842442;6573.211214654272;6587.176602924635;6601.339866013553;6613.603183808987;6660.0675905374255;5086.160783470686;5100.52084895391;5108.386993560796;5149.124648809637;5169.560587349967;6529.51028672861;6865.588064715933;6870.793876183722;6943.444524437932;6976.7558135465015;6390.329015453311;6468.773592924414;6469.191351081115;6516.934830651633;6567.770128045496;6368.617882927039;6379.96810015947;6518.315576864551;6559.311272316338;6570.868582628225;6415.422611964946;6448.421620649391;6458.22501299847;6458.54790164684;6507.739690412331;6594.142761440982;6621.073049772939;6689.26418094019;6699.29657385987;6738.124043874265;6350.206224221973;6417.518686098655;6441.806745797634;6449.419552240321;6480.70836812354 12:12:57 INFO - PID 7163 | |5;yui.html;893.6648756716231;896.166565094462;909.7634615000106;913.7167478081793;915.7115508614786;17463.65072698547;18011.639767204648;18040.909795451003;18068.819311806878;18080.36718714845;18043.90978045108;18193.545161370974;18261.086945652696;18290.542736431595;18308.633827323447;948.7438391634272;952.6713283917038;957.1194501059042;959.9088086631759;962.4898803634085;980.5195454227448;985.9260555458334;986.1765425869407;986.3982970388057;986.5609803637384;991.9752006199831;1030.1912998296339;1030.855680204772;1044.4568824211406;1045.038564520639;1035.30634474902;1038.48075962019;1038.5793753529833;1038.7558923652973;1039.153090231459;724.3734169942961;724.4023680463639;724.5326764237037;735.46311192829;756.8032311598969;18800.34198803035;19254.61490770177;19385.03074846266;19641;19655.410337689893;21977.67033494499;22093.116275348966;22249.110035598558;22381.10475580975;22475.988580513917;16140.83859161405;16364.099974501398;16407.343706251853;16443.09562974048;16458.75311870323;3568.4468907319406;3576.6065732769375;3577.499150118985;3578.982105089458;3630.0198946284354;121002.39498802538;136043;137664.31167844098;137792;138964;17559.20983555743;17749.7337539937;17779.644407111784;17852.375166869097;17912.641747165246;30546;30873.073807785804;31267.37465250728;31285.687143128733;31324.06027819169;8689;8929.419587726778;8945;8987.505687187268;9018.909810901807;4425.048614547888;4516.796744146522;4521.434820647419;4540.50054494012;4546.886327841764;5318.97340513295;5389.622726409114;5396.055690254189;5427.3215848019;5446.455354464521;1027.5016616940777;1051.3008849115406;1056.2078441169124;1065.2436769893307;1076.1390887290136;652.393274254938;664.7440735316841;676.1176664452838;680.6936878404738;683.5215349255541;12100.455479503444;12143.635690929286;12184.390780461119;12265.632031039084;12421.944134748648;662.7845950066247;663.349917081263;668.2815972829228;684.9897251541231;691.1498856406648;5309.256704061435;5334.306540149833;5392.73036348181;5427.972860135674;5436.53789427903;123089;124677;124685.37657311655;127360;127589;103630.48184759029;104953;107260;107576.4621176889;108112.45943770389;88998.55500722455;90887.54556227177;91137.54431227801;91392.54303728572;93407.53296233612;11729.296242225153;12059.276443413422;12128.272303661808;12201.328926909106;12283.57007504733;41695.58304416917;41833.37249941314;42689;43186.136277274905;43450;56509.43490565206;57067;57633.42366576448;57731.71134144302;58349.4165058344;18068.728969065738;18625.068746562345;18645.81354186495;18671.692981491164;18714.438566842473;20982.895085524473;21019;21128.15487380549;21222.787772122698;21285.46786330354;17856.107194640477;17967.5508112293;18179.272829086687;18375;18486.168122434254;17567.121643917497;17648.382306619205;17721.645567088843;17870.642587147926;17929.103544822967;17635.55911102233;18078.5480362992;18153.818461815743;18156.182971766037;18227.453176404728;13794.862051379358;13828.723425531232;13900.860991390362;13990.720185596434;14156.009079364758;12224.38878056076;12302.507899683913;12377.690557736129;12465.376731163224;12508.374581270718 12:12:57 INFO - PID 7163 | __end_tp_report 12:12:57 INFO - PID 7163 | __start_cc_report 12:12:57 INFO - PID 7163 | _x_x_mozilla_cycle_collect,265 12:12:57 INFO - PID 7163 | __end_cc_report 12:12:57 INFO - PID 7163 | __startTimestamp1506885177016__endTimestamp 12:12:57 INFO - PID 7163 | 12:12:57 INFO - PID 7163 | ------- Summary: start ------- 12:12:57 INFO - PID 7163 | Number of tests: 6 12:12:57 INFO - PID 7163 | 12:12:57 INFO - PID 7163 | [#0] dojo.html Cycles:165 Average:6145.43 Median:6427.10 stddev:829.02 (12.9%) stddev-sans-first:798.41 12:12:57 INFO - PID 7163 | Values: 3186.4 3194.1 3205.1 3219.5 3224.4 6132.1 6250.8 6251.2 6317.7 6343.2 6177.5 6241.3 6253.7 6282.4 6295.8 5607.0 5844.6 5888.5 5890.9 5891.6 1259.0 5802.2 5861.4 5875.7 5894.1 5283.6 5573.9 5643.8 5660.7 5804.4 5671.4 5731.9 5736.2 5769.8 5787.6 5725.9 5760.2 5797.4 5802.4 5818.9 6126.5 6239.4 6263.7 6289.6 6332.5 6552.9 6608.9 6615.2 6631.6 6765.1 6826.8 6828.5 6834.0 6842.8 6847.5 6242.5 6263.1 6266.3 6318.4 6324.2 6910.8 6927.9 6996.6 6998.0 7001.1 6377.4 6455.9 6471.3 6482.4 6657.6 6603.8 6614.4 6631.2 6633.0 6639.8 6504.6 6591.5 6601.9 6609.3 6621.3 6632.9 6725.0 6776.9 6778.7 6782.1 5247.9 5277.3 5338.5 5342.0 5357.1 4512.3 4597.1 4597.7 4670.1 4672.6 5690.7 5809.2 5810.9 5814.0 5815.0 6779.9 6830.0 6864.0 6865.1 6869.6 5772.9 5785.1 5869.1 5872.9 5886.3 5697.8 5724.9 5749.1 5763.7 5770.4 6458.2 6527.4 6548.5 6548.7 6587.2 6722.8 6723.5 6730.3 6823.2 6826.4 6272.1 6466.6 6466.8 6474.7 6476.6 6533.4 6580.9 6612.5 6623.5 6627.0 6776.2 6868.4 6873.2 6873.6 6877.4 6451.6 6506.2 6584.6 6585.6 6597.0 6476.0 6486.2 6494.0 6496.8 6497.1 6360.3 6442.6 6493.8 6510.3 6522.3 6575.1 6578.8 6629.0 6667.6 6673.0 6275.5 6340.5 6382.2 6387.1 6427.1 12:12:57 INFO - PID 7163 | 12:12:57 INFO - PID 7163 | [#1] ext.html Cycles:180 Average:36986.45 Median:35655.97 stddev:51290.02 (143.8%) stddev-sans-first:51362.20 12:12:57 INFO - PID 7163 | Values: 869.3 883.4 889.8 890.6 896.4 38146.2 41454.8 41529.2 41564.4 41570.2 38334.8 38476.0 38556.0 38692.6 38786.4 7106.4 7298.3 7298.9 7299.3 7309.5 7129.5 7240.6 7240.7 7240.7 7247.3 7991.1 8118.1 8118.2 8121.1 8121.3 7990.6 8059.7 8060.6 8061.6 8062.0 7833.1 7872.8 7992.2 8009.7 8011.6 38968.2 38997.4 39206.6 39247.2 39275.8 3419.6 3452.3 3481.0 3481.9 3485.0 31683.4 31900.8 32009.8 32052.0 32080.2 5104.2 5107.4 5117.9 5141.9 5144.9 187114.0 189529.0 189541.0 190180.0 190440.0 43962.8 44563.0 48796.5 48931.5 49151.3 52631.0 52877.7 53071.3 53116.0 53141.2 16382.1 16529.3 16538.5 16539.8 16540.8 13504.1 13524.2 13527.9 13534.3 13627.0 14312.0 14350.9 14477.6 14512.3 14678.8 5693.3 5699.7 5758.3 5777.9 5794.0 982.0 1002.4 1004.6 1012.3 1024.2 22243.4 22376.6 22647.4 22657.0 22664.3 1106.3 1111.4 1119.2 1119.4 1132.7 11926.2 11936.8 11956.5 11996.3 12036.9 214280.0 215305.0 216251.0 216387.0 216631.0 52518.2 52663.0 52735.7 52789.5 52864.5 1417.1 1465.1 1467.7 1477.0 1478.6 21633.9 22162.6 22294.6 22541.8 22682.4 36630.5 36647.0 36908.4 36928.3 36982.4 185955.0 188712.0 189004.0 189079.0 189573.0 33787.3 35608.5 35863.5 36156.8 36170.0 44092.8 45509.3 45618.3 45871.0 46082.5 32322.4 32387.0 32625.8 32640.0 33385.7 31966.5 32248.8 32299.8 32536.2 32814.2 35703.5 37330.6 37378.4 37510.8 37576.2 32097.7 32299.7 32346.2 32405.2 32468.2 23499.4 23544.2 23715.1 23841.9 24254.4 12:12:57 INFO - PID 7163 | 12:12:57 INFO - PID 7163 | [#2] jquery.html Cycles:180 Average:33921.58 Median:22133.22 stddev:72579.48 (327.9%) stddev-sans-first:72739.86 12:12:57 INFO - PID 7163 | Values: 559.6 578.9 582.3 600.2 613.2 22229.8 22778.0 22834.1 22917.4 22985.8 24187.3 25120.0 25258.6 25312.0 25362.2 6575.4 6688.3 6721.4 6723.0 6734.4 6500.9 6604.4 6715.9 6721.7 6772.2 6732.1 6751.9 6759.2 6765.2 6767.2 6739.3 6756.7 6758.9 6765.4 6767.1 6858.1 6879.1 6903.6 6961.3 6963.6 30194.5 30508.4 30606.8 30819.0 30929.5 2014.6 2026.4 2034.5 2037.4 2041.2 20459.5 20907.7 21570.8 21594.2 21775.8 2544.7 2600.0 2626.3 2671.5 2678.0 116597.0 122767.4 134986.3 141603.0 142359.0 20101.7 21082.5 22043.0 22186.4 23609.2 42674.0 43159.4 43311.6 43464.8 43635.1 11418.8 11859.7 11860.0 11927.3 12038.7 9946.2 9961.6 9972.0 9991.9 10067.8 11188.6 11219.6 11351.2 11720.7 11741.1 3895.4 3933.4 4118.0 4129.8 4264.3 937.3 954.8 973.5 977.3 983.3 19357.5 19691.2 19757.0 20085.5 20183.2 1018.1 1031.4 1034.4 1044.4 1051.4 10809.9 10877.6 10878.6 10911.7 11023.3 421811.0 422225.0 426597.0 429680.0 436056.0 112142.4 115430.1 116864.0 117707.0 118704.4 1065.4 1105.9 1169.7 1184.4 1195.1 19793.5 19918.9 20003.5 20214.3 20395.5 55181.0 55502.7 56271.4 56772.7 58612.7 66575.0 66768.0 66878.7 67288.3 68505.3 18878.0 19335.0 19487.6 19520.2 19539.0 21596.0 22080.0 22196.8 22214.0 22264.6 16526.1 16748.9 16846.7 16849.2 16929.2 15430.8 15489.9 15742.8 15784.8 15866.0 16845.2 16854.9 18523.5 18594.9 18700.1 18047.9 18060.8 18065.2 18213.9 18351.4 17217.3 17394.6 17560.5 17645.6 17649.1 12:12:57 INFO - PID 7163 | 12:12:57 INFO - PID 7163 | [#3] mootools.html Cycles:180 Average:8913.65 Median:2599.38 stddev:6128.91 (235.8%) stddev-sans-first:6119.34 12:12:57 INFO - PID 7163 | Values: 1291.3 1366.1 1367.6 1378.8 1380.3 13879.2 14552.0 14563.7 14574.6 14582.3 14119.0 14255.1 14256.6 14271.1 14296.2 2521.9 2541.4 2576.9 2592.1 2594.2 2747.3 2779.7 2780.3 2782.9 2787.0 2715.1 2738.6 2788.3 2789.0 2789.8 2728.5 2787.1 2801.9 2806.5 2806.5 2580.6 2604.6 2639.1 2657.0 2727.3 14050.6 14196.3 14197.2 14218.4 14220.7 7568.4 7885.6 7893.6 7896.4 7900.4 5142.4 5348.5 5428.3 5445.0 5496.3 1925.2 1959.9 1960.5 1994.1 2019.3 24061.3 24068.0 24210.4 24245.5 24337.0 6666.2 6918.8 7062.1 7079.2 7098.8 16363.4 16379.3 16417.3 16442.4 16515.5 7202.4 7798.4 7801.2 7813.9 7817.0 6996.3 7174.6 7231.9 7253.5 7264.6 4961.4 5084.6 5110.5 5112.9 5117.6 1531.3 1541.1 1541.6 1557.2 1558.8 888.7 901.2 904.5 905.6 917.7 7655.4 7708.2 7717.1 7730.5 7757.6 700.1 739.4 742.5 743.6 752.8 4976.3 5333.4 5383.2 5458.4 5484.0 19225.9 19447.9 20142.2 20176.6 20327.7 19956.4 20182.9 20192.8 20293.3 20424.0 10313.9 10560.5 10608.4 10643.1 10700.4 8281.8 8396.6 8440.2 8448.0 8466.9 17104.8 17181.8 17198.2 17406.0 17634.0 17619.6 17668.1 17771.5 17863.3 18158.6 10744.5 10977.4 10989.1 10994.2 10996.9 13155.9 13162.9 13233.7 13304.7 13376.0 10048.2 10123.3 10477.6 10561.8 10662.6 10631.1 10669.5 10775.8 10845.5 10980.0 9606.4 9821.4 9821.9 9833.2 9880.2 7321.6 7325.7 7369.9 7429.3 7431.5 7374.9 7564.7 7603.3 7606.9 7639.4 12:12:57 INFO - PID 7163 | 12:12:57 INFO - PID 7163 | [#4] prototype.html Cycles:180 Average:5715.96 Median:6335.80 stddev:1520.73 (24.0%) stddev-sans-first:1474.07 12:12:57 INFO - PID 7163 | Values: 516.2 520.9 535.2 548.1 551.9 6136.2 6344.3 6345.0 6396.7 6494.6 6243.7 6285.3 6326.4 6344.2 6413.2 5248.5 5267.2 5300.6 5376.6 5377.8 5192.9 5273.8 5286.3 5337.4 5348.9 5305.8 5313.8 5317.3 5366.7 5371.0 5244.4 5266.8 5293.7 5319.0 5414.1 4962.3 5008.8 5015.8 5020.2 5030.0 6376.3 6403.7 6425.6 6427.2 6459.2 6212.8 6252.7 6261.4 6267.6 6272.7 6563.7 6667.7 6672.7 6708.9 6798.2 5350.0 5367.5 5425.6 5444.6 5446.9 6952.9 7113.4 7151.2 7162.6 7219.3 5513.4 5607.1 5611.5 5666.8 5702.5 5614.7 5677.4 5725.8 5754.5 5762.4 6166.6 6169.6 6177.7 6186.4 6202.5 6806.3 6820.5 6846.7 6891.8 6897.4 1830.5 1874.3 1932.4 1960.3 2005.5 1452.4 1461.3 1467.9 1486.2 1497.9 5771.2 5793.1 5814.7 5817.5 5823.6 6690.0 6756.8 6784.8 6800.1 6936.2 5783.2 5796.8 5824.3 5827.7 5862.4 3589.2 3606.2 3637.1 3650.0 3656.0 6584.7 6597.5 6616.8 6674.8 6677.1 6792.3 6861.2 6924.5 6929.9 7007.3 6325.1 6327.4 6412.4 6457.2 6550.4 6772.3 6787.9 6837.7 6840.9 6879.6 6675.9 6780.6 6816.5 6818.6 6852.3 6573.2 6587.2 6601.3 6613.6 6660.1 5086.2 5100.5 5108.4 5149.1 5169.6 6529.5 6865.6 6870.8 6943.4 6976.8 6390.3 6468.8 6469.2 6516.9 6567.8 6368.6 6380.0 6518.3 6559.3 6570.9 6415.4 6448.4 6458.2 6458.5 6507.7 6594.1 6621.1 6689.3 6699.3 6738.1 6350.2 6417.5 6441.8 6449.4 6480.7 12:12:57 INFO - PID 7163 | 12:12:57 INFO - PID 7163 | [#5] yui.html Cycles:180 Average:24452.56 Median:19319.82 stddev:34530.91 (178.7%) stddev-sans-first:34582.47 12:12:57 INFO - PID 7163 | Values: 893.7 896.2 909.8 913.7 915.7 17463.7 18011.6 18040.9 18068.8 18080.4 18043.9 18193.5 18261.1 18290.5 18308.6 948.7 952.7 957.1 959.9 962.5 980.5 985.9 986.2 986.4 986.6 992.0 1030.2 1030.9 1044.5 1045.0 1035.3 1038.5 1038.6 1038.8 1039.2 724.4 724.4 724.5 735.5 756.8 18800.3 19254.6 19385.0 19641.0 19655.4 21977.7 22093.1 22249.1 22381.1 22476.0 16140.8 16364.1 16407.3 16443.1 16458.8 3568.4 3576.6 3577.5 3579.0 3630.0 121002.4 136043.0 137664.3 137792.0 138964.0 17559.2 17749.7 17779.6 17852.4 17912.6 30546.0 30873.1 31267.4 31285.7 31324.1 8689.0 8929.4 8945.0 8987.5 9018.9 4425.0 4516.8 4521.4 4540.5 4546.9 5319.0 5389.6 5396.1 5427.3 5446.5 1027.5 1051.3 1056.2 1065.2 1076.1 652.4 664.7 676.1 680.7 683.5 12100.5 12143.6 12184.4 12265.6 12421.9 662.8 663.3 668.3 685.0 691.1 5309.3 5334.3 5392.7 5428.0 5436.5 123089.0 124677.0 124685.4 127360.0 127589.0 103630.5 104953.0 107260.0 107576.5 108112.5 88998.6 90887.5 91137.5 91392.5 93407.5 11729.3 12059.3 12128.3 12201.3 12283.6 41695.6 41833.4 42689.0 43186.1 43450.0 56509.4 57067.0 57633.4 57731.7 58349.4 18068.7 18625.1 18645.8 18671.7 18714.4 20982.9 21019.0 21128.2 21222.8 21285.5 17856.1 17967.6 18179.3 18375.0 18486.2 17567.1 17648.4 17721.6 17870.6 17929.1 17635.6 18078.5 18153.8 18156.2 18227.5 13794.9 13828.7 13900.9 13990.7 14156.0 12224.4 12302.5 12377.7 12465.4 12508.4 12:12:57 INFO - PID 7163 | -------- Summary: end -------- 12:12:57 INFO - PID 7163 | 12:12:57 INFO - TEST-INFO | 7163: exit 0 12:12:57 INFO - TEST-OK | dromaeo_css | took 1109535ms 12:12:57 INFO - TEST-START | kraken 12:12:57 INFO - Initialising browser for kraken test... 12:12:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44220/getInfo.html -profile /tmp/tmp8vxCdx/profile 12:12:57 INFO - TEST-INFO | started process 7523 (/builds/slave/test/build/application/firefox/firefox http://localhost:44220/getInfo.html) 12:13:03 INFO - TEST-INFO | 7523: exit 0 12:13:03 INFO - Browser initialized. 12:13:03 INFO - Running cycle 1/1 for kraken test... 12:13:03 INFO - Using env: {'DISPLAY': ':0', 12:13:03 INFO - 'HOME': '/home/cltbld', 12:13:03 INFO - 'JSGC_DISABLE_POISONING': '1', 12:13:03 INFO - 'LANG': 'en_US.UTF-8', 12:13:03 INFO - 'LANGUAGE': 'en_US:en', 12:13:03 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:13:03 INFO - 'LOGNAME': 'cltbld', 12:13:03 INFO - 'MAIL': '/var/mail/cltbld', 12:13:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:13:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:13:03 INFO - 'MOZ_CRASHREPORTER': '1', 12:13:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:03 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:13:03 INFO - 'MOZ_NO_REMOTE': '1', 12:13:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:13:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:13:03 INFO - 'NO_EM_RESTART': '1', 12:13:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:13:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:03 INFO - 'PWD': '/builds/slave/test', 12:13:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:13:03 INFO - 'RUST_BACKTRACE': 'full', 12:13:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:13:03 INFO - 'SHELL': '/bin/bash', 12:13:03 INFO - 'SHLVL': '1', 12:13:03 INFO - 'STYLO_FORCE_DISABLED': '1', 12:13:03 INFO - 'TERM': 'linux', 12:13:03 INFO - 'TMOUT': '86400', 12:13:03 INFO - 'USER': 'cltbld', 12:13:03 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964', 12:13:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:03 INFO - '_': '/tools/buildbot/bin/python'} 12:13:03 INFO - TEST-INFO | started process 7756 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8vxCdx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:13:04 INFO - PID 7756 | 12:13:04 INFO - PID 7756 | (/builds/slave/test/build/application/firefox/firefox:7806): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:13:04 INFO - PID 7756 | 12:13:14 INFO - PID 7756 | RSS: Main: 150945792 12:13:14 INFO - PID 7756 | 12:13:17 INFO - PID 7756 | Cycle 1(1): loaded http://localhost:44220/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44220/tests/kraken/driver.html?testName=audio-beat-detection) 12:13:18 INFO - PID 7756 | RSS: Main: 157081600 12:13:18 INFO - PID 7756 | 12:13:20 INFO - PID 7756 | Cycle 1(1): loaded http://localhost:44220/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44220/tests/kraken/driver.html?testName=audio-dft) 12:13:21 INFO - PID 7756 | RSS: Main: 157306880 12:13:21 INFO - PID 7756 | 12:13:24 INFO - PID 7756 | Cycle 1(1): loaded http://localhost:44220/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44220/tests/kraken/driver.html?testName=audio-fft) 12:13:24 INFO - PID 7756 | RSS: Main: 154529792 12:13:24 INFO - PID 7756 | 12:13:27 INFO - PID 7756 | Cycle 1(1): loaded http://localhost:44220/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44220/tests/kraken/driver.html?testName=audio-oscillator) 12:13:27 INFO - PID 7756 | RSS: Main: 155062272 12:13:27 INFO - PID 7756 | 12:13:29 INFO - PID 7756 | Cycle 1(1): loaded http://localhost:44220/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44220/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:13:30 INFO - PID 7756 | RSS: Main: 154931200 12:13:30 INFO - PID 7756 | 12:13:33 INFO - PID 7756 | Cycle 1(1): loaded http://localhost:44220/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44220/tests/kraken/driver.html?testName=imaging-darkroom) 12:13:34 INFO - PID 7756 | RSS: Main: 157364224 12:13:34 INFO - PID 7756 | 12:13:37 INFO - PID 7756 | Cycle 1(1): loaded http://localhost:44220/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44220/tests/kraken/driver.html?testName=imaging-desaturate) 12:13:38 INFO - PID 7756 | RSS: Main: 157683712 12:13:38 INFO - PID 7756 | 12:13:41 INFO - PID 7756 | Cycle 1(1): loaded http://localhost:44220/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44220/tests/kraken/driver.html?testName=json-parse-financial) 12:13:42 INFO - PID 7756 | RSS: Main: 157986816 12:13:42 INFO - PID 7756 | 12:13:43 INFO - PID 7756 | Cycle 1(1): loaded http://localhost:44220/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44220/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:13:44 INFO - PID 7756 | RSS: Main: 158130176 12:13:44 INFO - PID 7756 | 12:13:45 INFO - PID 7756 | Cycle 1(1): loaded http://localhost:44220/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44220/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:13:46 INFO - PID 7756 | RSS: Main: 158105600 12:13:46 INFO - PID 7756 | 12:13:48 INFO - PID 7756 | Cycle 1(1): loaded http://localhost:44220/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44220/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:13:49 INFO - PID 7756 | RSS: Main: 155451392 12:13:49 INFO - PID 7756 | 12:13:51 INFO - PID 7756 | Cycle 1(1): loaded http://localhost:44220/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44220/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:13:52 INFO - PID 7756 | RSS: Main: 154750976 12:13:52 INFO - PID 7756 | 12:13:55 INFO - PID 7756 | Cycle 1(1): loaded http://localhost:44220/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44220/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:13:55 INFO - PID 7756 | RSS: Main: 155299840 12:13:55 INFO - PID 7756 | 12:13:57 INFO - PID 7756 | Cycle 1(1): loaded http://localhost:44220/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44220/tests/kraken/driver.html?testName=ai-astar) 12:13:57 INFO - PID 7756 | RSS: Main: 156278784 12:13:57 INFO - PID 7756 | 12:13:57 INFO - PID 7756 | __start_tp_report 12:13:57 INFO - PID 7756 | _x_x_mozilla_page_load 12:13:57 INFO - PID 7756 | _x_x_mozilla_page_load_details 12:13:57 INFO - PID 7756 | |i|pagename|runs| 12:13:57 INFO - PID 7756 | |0;ai-astar;108;106;108;107;106;109;107;107;106;109 12:13:57 INFO - PID 7756 | |1;audio-beat-detection;120;119;115;119;115;147;120;122;116;151 12:13:57 INFO - PID 7756 | |2;audio-dft;163;161;161;162;162;161;165;163;170;169 12:13:57 INFO - PID 7756 | |3;audio-fft;74;74;72;76;76;77;75;77;80;75 12:13:57 INFO - PID 7756 | |4;audio-oscillator;79;79;80;79;80;78;79;80;80;78 12:13:57 INFO - PID 7756 | |5;imaging-gaussian-blur;116;111;111;111;111;111;111;111;112;111 12:13:57 INFO - PID 7756 | |6;imaging-darkroom;148;148;148;147;149;148;148;148;148;149 12:13:57 INFO - PID 7756 | |7;imaging-desaturate;93;93;94;94;93;93;94;94;95;94 12:13:57 INFO - PID 7756 | |8;json-parse-financial;53;56;50;53;52;51;51;51;51;51 12:13:57 INFO - PID 7756 | |9;json-stringify-tinderbox;37;37;37;37;38;37;37;37;38;37 12:13:57 INFO - PID 7756 | |10;stanford-crypto-aes;75;74;76;74;80;75;74;75;78;73 12:13:57 INFO - PID 7756 | |11;stanford-crypto-ccm;108;109;112;109;110;117;119;124;109;114 12:13:57 INFO - PID 7756 | |12;stanford-crypto-pbkdf2;162;165;160;164;165;162;166;169;161;162 12:13:57 INFO - PID 7756 | |13;stanford-crypto-sha256-iterative;75;67;63;64;64;68;73;64;63;64 12:13:57 INFO - PID 7756 | __end_tp_report 12:13:57 INFO - PID 7756 | __start_cc_report 12:13:57 INFO - PID 7756 | _x_x_mozilla_cycle_collect,728 12:13:57 INFO - PID 7756 | __end_cc_report 12:13:57 INFO - PID 7756 | __startTimestamp1506885237843__endTimestamp 12:13:57 INFO - PID 7756 | 12:13:57 INFO - PID 7756 | ------- Summary: start ------- 12:13:57 INFO - PID 7756 | Number of tests: 14 12:13:57 INFO - PID 7756 | 12:13:57 INFO - PID 7756 | [#0] ai-astar Cycles:10 Average:107.30 Median:107.50 stddev:1.16 (1.1%) stddev-sans-first:1.20 12:13:57 INFO - PID 7756 | Values: 108.0 106.0 108.0 107.0 106.0 109.0 107.0 107.0 106.0 109.0 12:13:57 INFO - PID 7756 | 12:13:57 INFO - PID 7756 | [#1] audio-beat-detection Cycles:10 Average:124.40 Median:120.00 stddev:13.20 (11.0%) stddev-sans-first:13.91 12:13:57 INFO - PID 7756 | Values: 120.0 119.0 115.0 119.0 115.0 147.0 120.0 122.0 116.0 151.0 12:13:57 INFO - PID 7756 | 12:13:57 INFO - PID 7756 | [#2] audio-dft Cycles:10 Average:163.70 Median:163.00 stddev:3.30 (2.0%) stddev-sans-first:3.49 12:13:57 INFO - PID 7756 | Values: 163.0 161.0 161.0 162.0 162.0 161.0 165.0 163.0 170.0 169.0 12:13:57 INFO - PID 7756 | 12:13:57 INFO - PID 7756 | [#3] audio-fft Cycles:10 Average:75.60 Median:76.00 stddev:2.17 (2.9%) stddev-sans-first:2.22 12:13:57 INFO - PID 7756 | Values: 74.0 74.0 72.0 76.0 76.0 77.0 75.0 77.0 80.0 75.0 12:13:57 INFO - PID 7756 | 12:13:57 INFO - PID 7756 | [#4] audio-oscillator Cycles:10 Average:79.20 Median:79.50 stddev:0.79 (1.0%) stddev-sans-first:0.83 12:13:57 INFO - PID 7756 | Values: 79.0 79.0 80.0 79.0 80.0 78.0 79.0 80.0 80.0 78.0 12:13:57 INFO - PID 7756 | 12:13:57 INFO - PID 7756 | [#5] imaging-gaussian-blur Cycles:10 Average:111.60 Median:111.00 stddev:1.58 (1.4%) stddev-sans-first:0.33 12:13:57 INFO - PID 7756 | Values: 116.0 111.0 111.0 111.0 111.0 111.0 111.0 111.0 112.0 111.0 12:13:57 INFO - PID 7756 | 12:13:57 INFO - PID 7756 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 12:13:57 INFO - PID 7756 | Values: 148.0 148.0 148.0 147.0 149.0 148.0 148.0 148.0 148.0 149.0 12:13:57 INFO - PID 7756 | 12:13:57 INFO - PID 7756 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 12:13:57 INFO - PID 7756 | Values: 93.0 93.0 94.0 94.0 93.0 93.0 94.0 94.0 95.0 94.0 12:13:57 INFO - PID 7756 | 12:13:57 INFO - PID 7756 | [#8] json-parse-financial Cycles:10 Average:51.90 Median:51.50 stddev:1.73 (3.4%) stddev-sans-first:1.79 12:13:57 INFO - PID 7756 | Values: 53.0 56.0 50.0 53.0 52.0 51.0 51.0 51.0 51.0 51.0 12:13:57 INFO - PID 7756 | 12:13:57 INFO - PID 7756 | [#9] json-stringify-tinderbox Cycles:10 Average:37.20 Median:37.00 stddev:0.42 (1.1%) stddev-sans-first:0.44 12:13:57 INFO - PID 7756 | Values: 37.0 37.0 37.0 37.0 38.0 37.0 37.0 37.0 38.0 37.0 12:13:57 INFO - PID 7756 | 12:13:57 INFO - PID 7756 | [#10] stanford-crypto-aes Cycles:10 Average:75.40 Median:75.00 stddev:2.12 (2.8%) stddev-sans-first:2.24 12:13:57 INFO - PID 7756 | Values: 75.0 74.0 76.0 74.0 80.0 75.0 74.0 75.0 78.0 73.0 12:13:57 INFO - PID 7756 | 12:13:57 INFO - PID 7756 | [#11] stanford-crypto-ccm Cycles:10 Average:113.10 Median:113.00 stddev:5.34 (4.7%) stddev-sans-first:5.34 12:13:57 INFO - PID 7756 | Values: 108.0 109.0 112.0 109.0 110.0 117.0 119.0 124.0 109.0 114.0 12:13:57 INFO - PID 7756 | 12:13:57 INFO - PID 7756 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.60 Median:164.50 stddev:2.72 (1.7%) stddev-sans-first:2.82 12:13:57 INFO - PID 7756 | Values: 162.0 165.0 160.0 164.0 165.0 162.0 166.0 169.0 161.0 162.0 12:13:57 INFO - PID 7756 | 12:13:57 INFO - PID 7756 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:65.50 stddev:4.30 (6.6%) stddev-sans-first:3.28 12:13:57 INFO - PID 7756 | Values: 75.0 67.0 63.0 64.0 64.0 68.0 73.0 64.0 63.0 64.0 12:13:57 INFO - PID 7756 | -------- Summary: end -------- 12:13:57 INFO - PID 7756 | 12:13:58 INFO - PID 7756 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:13:58 INFO - TEST-INFO | 7756: exit 0 12:13:58 INFO - TEST-OK | kraken | took 60894ms 12:13:58 INFO - SUITE-END | took 1170s 12:13:59 INFO - Completed test suite (00:19:30) 12:13:59 INFO - javascript benchmark 12:13:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8641.508319474826, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3186.4264432402174, 3194.1056504178823, 3205.0545089198677, 3219.533167690685, 3224.3712476067176, 6132.141500189977, 6250.8124756257275, 6251.187345645071, 6317.715702793374, 6343.207099112617, 6177.536684748639, 6241.344658810816, 6253.656048917308, 6282.3717628237155, 6295.811125666238, 5607.0, 5844.64932104073, 5888.470037696607, 5890.882182356372, 5891.617044892068, 1259.007721215666, 5802.245708057946, 5861.413858614147, 5875.706214689249, 5894.115882617599, 5283.55089817364, 5573.8606534836545, 5643.830685079454, 5660.688662123582, 5804.390538993408, 5671.376148623689, 5731.885362292731, 5736.168255602956, 5769.798057068025, 5787.594868359217, 5725.885482290372, 5760.222369980041, 5797.391273916221, 5802.448767367094, 5818.941810581925, 6126.479249263818, 6239.447846989462, 6263.6868156592445, 6289.591176573508, 6332.4934005279465, 6552.901706474407, 6608.86782264352, 6615.239247486505, 6631.602103873783, 6765.05289259504, 6826.795196144124, 6828.52200345976, 6833.965830170867, 6842.828929276759, 6847.520673552854, 6242.500599951993, 6263.123162757218, 6266.279377871512, 6318.4313411793155, 6324.209473815773, 6910.7581234656545, 6927.93072069283, 6996.580205187705, 6997.965010174917, 7001.089858318386, 6377.394147555975, 6455.935440645628, 6471.320511346241, 6482.448991835747, 6657.600543967377, 6603.834904127435, 6614.404703576605, 6631.23740769808, 6632.966835165793, 6639.834004149839, 6504.60972341661, 6591.472682185509, 6601.933980660228, 6609.339066093352, 6621.33786621334, 6632.933670663231, 6724.966375168093, 6776.864462710817, 6778.7288508459105, 6782.050512928194, 5247.895042099137, 5277.26837824908, 5338.54622357104, 5342.0384330820825, 5357.11607584752, 4512.2554778461845, 4597.103564804898, 4597.678162528591, 4670.089332580114, 4672.649551283667, 5690.658560486384, 5809.244798176294, 5810.88378232433, 5813.970930145322, 5815.0, 6779.898301525481, 6830.0, 6863.965680171567, 6865.107536020286, 6869.553479023947, 5772.942270577324, 5785.103308987113, 5869.119632055226, 5872.853178670482, 5886.293644762571, 5697.772089116389, 5724.942750572441, 5749.051406517961, 5763.711814409263, 5770.394108618619, 6458.192725909262, 6527.445167160845, 6548.541602087904, 6548.70530826114, 6587.242467116343, 6722.764703235461, 6723.5293529452465, 6730.32696730323, 6823.249442561215, 6826.41975432074, 6272.09054687077, 6466.612003279818, 6466.838329041623, 6474.741010359533, 6476.6114033158165, 6533.41199292066, 6580.934190658032, 6612.5040621952585, 6623.503237257321, 6627.039079333566, 6776.186857577122, 6868.381845633917, 6873.243943166148, 6873.5875847449315, 6877.415419689182, 6451.580647257913, 6506.154199953976, 6584.637844918576, 6585.6377899216, 6597.0, 6475.967620161869, 6486.2216534016225, 6494.0258961156205, 6496.7726129585235, 6497.057926600522, 6360.332165122781, 6442.581232219891, 6493.805185844432, 6510.316416776171, 6522.34776522363, 6575.079488871657, 6578.80263592093, 6629.03878937561, 6667.599944003376, 6672.966635166793, 6275.497960163085, 6340.478116516737, 6382.234131904202, 6387.105805187371, 6427.1002059710745], "value": 6086.628881519655, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [869.3219288954616, 883.3992884838308, 889.7997950461147, 890.6036813617939, 896.3814967672307, 38146.237075258476, 41454.792726036365, 41529.16941661165, 41564.37653435197, 41570.16859662805, 38334.80832595833, 38476.0, 38556.0, 38692.61307386925, 38786.418203726964, 7106.431485481149, 7298.343149116578, 7298.927010729878, 7299.270072992685, 7309.488335816495, 7129.500934934556, 7240.601766902818, 7240.674169662351, 7240.710371585156, 7247.275272472763, 7991.120976692559, 8118.14759450258, 8118.228768267005, 8121.066077401086, 8121.30968867647, 7990.560519171443, 8059.717909873185, 8060.596970151499, 8061.596920153998, 8061.959690201512, 7833.099193592753, 7872.763817085496, 7992.200779922019, 8009.719659811937, 8011.639476223584, 38968.22063558713, 38997.41503877444, 39206.607933920866, 39247.21505569901, 39275.803620982, 3419.64093770153, 3452.3267962747273, 3480.9651903480894, 3481.8781342653137, 3485.041613556267, 31683.366332673217, 31900.840495797605, 32009.83995080033, 32052.0, 32080.197995050075, 5104.2088476286235, 5107.387113546361, 5117.948820511784, 5141.948580514222, 5144.922826157574, 187114.0, 189529.0, 189541.0, 190180.0, 190440.0, 43962.78018609886, 44562.99999999935, 48796.5120348792, 48931.510684892695, 49151.26273105906, 52631.0, 52877.735611321696, 53071.28501004892, 53116.0, 53141.20288195603, 16382.098984555963, 16529.256183471774, 16538.50384488467, 16539.751903721455, 16540.83459165417, 13504.122232054886, 13524.18854868711, 13527.86472135266, 13534.323283835769, 13627.0, 14312.0, 14350.928245358915, 14477.565673029825, 14512.346944387527, 14678.825693944458, 5693.345265294544, 5699.658020518782, 5758.337791153987, 5777.884442311214, 5794.0, 981.9754506137403, 1002.3835341265069, 1004.5680357446222, 1012.2610494339174, 1024.155072065549, 22243.44391390196, 22376.55246895053, 22647.433814154778, 22657.0, 22664.320070397913, 1106.3085571517802, 1111.4387234446554, 1119.160629527854, 1119.3787447966456, 1132.6885106595587, 11926.165168438127, 11936.820947685792, 11956.52173913051, 11996.340201289013, 12036.87963120375, 214280.0, 215305.0, 216251.0, 216387.0, 216631.0, 52518.21222681663, 52663.0, 52735.736321318145, 52789.47210527846, 52864.471355286725, 1417.0505761139855, 1465.0623600895435, 1467.6771110355705, 1477.0325436838887, 1478.5564330700747, 21633.891830540746, 22162.556748865256, 22294.554108918055, 22541.774582254624, 22682.43293917665, 36630.45054324134, 36647.0, 36908.44637330389, 36928.26143477169, 36982.44526332161, 185955.0, 188712.0, 189004.0, 189079.0, 189573.0, 33787.3242535143, 35608.46587301244, 35863.46204806982, 36156.819215904805, 36170.0, 44092.77953610211, 45509.31736023896, 45618.31572526481, 45871.0, 46082.539174607824, 32322.35355292834, 32387.028389148363, 32625.836870815496, 32640.0, 33385.66614333923, 31966.52050219202, 32248.83875580701, 32299.838500807346, 32536.186595335304, 32814.17964550905, 35703.46444803382, 37330.62669373272, 37378.43932340963, 37510.8124459376, 37576.2484750298, 32097.679023209468, 32299.677003229666, 32346.191345216557, 32405.18987025343, 32468.188295292806, 23499.41251468727, 23544.17595384153, 23715.05139794458, 23841.880790595937, 24254.393640159138], "value": 17164.780909660876, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.56074481532, 578.869754305281, 582.3186871360508, 600.2227115884929, 613.2181468627499, 22229.77770222297, 22778.0, 22834.086636534517, 22917.42706432338, 22985.770142298592, 24187.27438176853, 25120.0, 25258.621120683205, 25312.0, 25362.239132826046, 6575.4410875075555, 6688.297728738486, 6721.428678562328, 6723.0, 6734.360235777618, 6500.902486462707, 6604.372584604479, 6715.9328406715795, 6721.697523611425, 6772.153480814898, 6732.091167692372, 6751.898721519181, 6759.155105611799, 6765.188177418692, 6767.154105736783, 6739.326067393271, 6756.695948682272, 6758.932410675929, 6765.424938880201, 6767.05261263421, 6858.142732158481, 6879.071325371111, 6903.585784852925, 6961.338672826124, 6963.617001064939, 30194.54708179357, 30508.389832203233, 30606.846965765253, 30819.0, 30929.536056959165, 2014.5970805838745, 2026.371824734337, 2034.471037530238, 2037.3887833649846, 2041.1937284772432, 20459.48851278715, 20907.68638470424, 21570.78429215719, 21594.244201452875, 21775.782242177534, 2544.745525447459, 2599.9870000649876, 2626.3434141464636, 2671.545837207679, 2677.973220267792, 116597.0, 122767.3861630695, 134986.325068375, 141602.99999999898, 142359.0, 20101.698474522895, 21082.472938176667, 22043.0, 22186.445338866335, 23609.173678921154, 42674.0, 43159.35260971088, 43311.56688433138, 43464.78267608705, 43635.12729745387, 11418.771624567627, 11859.70350741221, 11860.0, 11927.270545770814, 12038.699032524082, 9946.154576861049, 9961.551730172016, 9972.0, 9991.900080999243, 10067.798644027078, 11188.608398706006, 11219.607313744144, 11351.205415620827, 11720.706982325339, 11741.06071514294, 3895.383415882434, 3933.4296527003426, 4117.97941010299, 4129.814158362881, 4264.275073237557, 937.2595649436869, 954.7947191353753, 973.5375696544085, 977.3354119199013, 983.2871168402851, 19357.51606209856, 19691.212351506067, 19757.0, 20085.49786255355, 20183.192672293237, 1018.1244130048152, 1031.4275577054657, 1034.3690348887174, 1044.3525014490913, 1051.4269723000982, 10809.945950270197, 10877.619283325204, 10878.619248326271, 10911.672649820519, 11023.324311415468, 421811.0, 422225.0, 426597.0, 429680.0, 436056.0, 112142.43928780303, 115430.1115519942, 116864.0, 117707.0, 118704.40647796706, 1065.4459680966008, 1105.9004689577976, 1169.6841852699893, 1184.3663639952413, 1195.0797885628192, 19793.50516237048, 19918.90040549788, 20003.499912502302, 20214.292499762436, 20395.490112746706, 55181.0, 55502.72248638731, 56271.43728562662, 56772.716136419054, 58612.706936465045, 66575.0, 66768.0, 66878.66560667165, 67288.32711673016, 68505.31494684989, 18878.0, 19335.0, 19487.610247795248, 19520.219191232758, 19539.023048847786, 21596.0, 22080.0, 22196.77803221947, 22214.0, 22264.554708906056, 16526.09106499106, 16748.916255418644, 16846.74729879077, 16849.2417841195, 16929.238184281985, 15430.768538471708, 15489.922550387177, 15742.842571574138, 15784.842151578796, 15866.04803711781, 16845.15774211309, 16854.915725421295, 18523.536911577317, 18594.907025464785, 18700.064996749836, 18047.90976045164, 18060.819391805915, 18065.187066582297, 18213.90893045526, 18351.357702480884, 17217.31130754756, 17394.565135871704, 17560.473185803934, 17645.558861028065, 17649.117544122484], "value": 12770.737566747346, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1291.3091496049615, 1366.064245991496, 1367.5828872193983, 1378.800073989271, 1380.3236414157054, 13879.236641984688, 14551.981361304714, 14563.708725825478, 14574.635634109152, 14582.343794529232, 14119.0, 14255.144691318528, 14256.643583910381, 14271.072380295274, 14296.213708246043, 2521.949561008779, 2541.364658835296, 2576.948461030778, 2592.1057235253807, 2594.182832407795, 2747.3269049083, 2779.749822515973, 2780.2771279467383, 2782.860856957144, 2787.02454141051, 2715.0768738628863, 2738.561830107183, 2788.3029242689327, 2789.0, 2789.790765692571, 2728.5497892847657, 2787.0663327785114, 2801.929951751202, 2806.5088609493255, 2806.536921407965, 2580.5742052561304, 2604.5702459094227, 2639.0895141176265, 2656.9601455978177, 2727.2909043648597, 14050.648733781634, 14196.290185490787, 14197.219152946584, 14218.431262749478, 14220.71558568823, 7568.356689681384, 7885.645145968445, 7893.565853878034, 7896.407769417317, 7900.367970562399, 5142.3829140503, 5348.465153484659, 5428.321459817523, 5445.0, 5496.285482887199, 1925.1625542888887, 1959.9020048997493, 1960.4804726747423, 1994.1425187169511, 2019.2629690163133, 24061.27816165518, 24068.0, 24210.39474013146, 24245.515089698107, 24337.0, 6666.200055993215, 6918.827029324307, 7062.081929349151, 7079.1858936223, 7098.751543695946, 16363.427280045142, 16379.344826206818, 16417.343306267856, 16442.424515141913, 16515.504534863972, 7202.4238060954995, 7798.376129909594, 7801.180876008051, 7813.882791758128, 7817.0, 6996.300369962963, 7174.641267936582, 7231.855362892713, 7253.528520646141, 7264.564126152342, 4961.3798275215595, 5084.6440749147205, 5110.48895110486, 5112.948870511322, 5117.590592752571, 1531.3491765999406, 1541.0676540692718, 1541.6300087978805, 1557.2058250292432, 1558.8363221861769, 888.7022847346083, 901.1799262671069, 904.5251243097426, 905.6467977488785, 917.6742256498981, 7655.387568994467, 7708.152103268636, 7717.112532058773, 7730.497517661334, 7757.573333466601, 700.1213477086225, 739.3604532079712, 742.4876834983844, 743.5761615879005, 752.8381397999457, 4976.305671736655, 5333.386660534011, 5383.246345511631, 5458.372287186945, 5484.012877681976, 19225.903870480557, 19447.902760486108, 20142.19431222764, 20176.596468070555, 20327.695084573745, 19956.401307960783, 20182.89908550448, 20192.798072019385, 20293.28973485921, 20424.0, 10313.896861031444, 10560.52477638508, 10608.363498190134, 10643.095336896451, 10700.357978521159, 8281.83436331282, 8396.622152003052, 8440.198181172778, 8447.95776021116, 8466.915330846612, 17104.828951710322, 17181.828181718523, 17198.22607982619, 17406.0, 17634.0, 17619.559511011816, 17668.116594169984, 17771.466855994342, 17863.28546858111, 18158.636827263115, 10744.51649675751, 10977.3962432067, 10989.120870330515, 10994.230403871652, 10996.890031099587, 13155.868441315464, 13162.934185329395, 13233.669158271121, 13304.733905321646, 13376.0, 10048.246381521269, 10123.291369604058, 10477.633282835064, 10561.788764224826, 10662.573497060032, 10631.096356809758, 10669.46652667348, 10775.784484310427, 10845.457727113771, 10980.0, 9606.375585586915, 9821.410715357102, 9821.85267220978, 9833.213342932406, 9880.20958323318, 7321.633918303957, 7325.7435989742235, 7369.926300737138, 7429.257074292744, 7431.516951398142, 7374.926250737424, 7564.697412103455, 7603.315701586663, 7606.923930760621, 7639.350655194371], "value": 6465.300275298356, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.2282387830193, 520.9453007434221, 535.2319421629961, 548.106586264389, 551.8978988887054, 6136.23297087864, 6344.3338449462835, 6345.048242763588, 6396.744130234791, 6494.577852439588, 6243.656598887071, 6285.277193122793, 6326.367363263671, 6344.175257216555, 6413.236360001002, 5248.475152484759, 5267.23625074364, 5300.602454815885, 5376.569874410038, 5377.838664840061, 5192.948070519303, 5273.762680679379, 5286.3392075990505, 5337.386200586924, 5348.866278343034, 5305.787768489276, 5313.787448502056, 5317.304473390995, 5366.6779993200535, 5370.973145134269, 5244.423113457517, 5266.789328426858, 5293.735313234342, 5319.0, 5414.133738601825, 4962.255661650781, 5008.7996480140755, 5015.774290156952, 5020.1636075617525, 5030.0, 6376.33048529907, 6403.7438502459845, 6425.550211485198, 6427.228732552124, 6459.192600924885, 6212.782552610682, 6252.74989000444, 6261.405166509175, 6267.62394256346, 6272.651379953332, 6563.671816409161, 6667.733290668368, 6672.69972851218, 6708.899366509506, 6798.150231221098, 5349.973250133763, 5367.5169234769055, 5425.565954723613, 5444.6188766785945, 5446.863828404322, 6952.860942781167, 7113.395361394288, 7151.177614574339, 7162.606056666933, 7219.278072192791, 5513.393526712059, 5607.130894711327, 5611.466910643482, 5666.773329066791, 5702.45826646468, 5614.691191984399, 5677.37548869624, 5725.77096916127, 5754.539636829044, 5762.394948530344, 6166.568340216142, 6169.598976066542, 6177.660228687376, 6186.381361863778, 6202.503799696104, 6806.251312355637, 6820.454363650896, 6846.726130954706, 6891.827704307432, 6897.379235868796, 1830.4691639424418, 1874.297138573035, 1932.391296741522, 1960.2746983616153, 2005.4785755703622, 1452.404514149215, 1461.335092532895, 1467.8605532474398, 1486.197453375187, 1497.9325930333162, 5771.220885180531, 5793.102069179284, 5814.651120932757, 5817.545613596601, 5823.5923485355615, 6690.0, 6756.797296081126, 6784.76253331131, 6800.149981252343, 6936.237013928464, 5783.219265399117, 5796.82609521715, 5824.3302020267365, 5827.650340979555, 5862.443067908542, 3589.210373717778, 3606.1525541497726, 3637.054365864841, 3650.032741323498, 3655.9634403656155, 6584.703688334036, 6597.505187110986, 6616.801495955129, 6674.83312917181, 6677.1319728435965, 6792.320767923168, 6861.176658800976, 6924.549904256207, 6929.896051559231, 7007.299270072952, 6325.070853389669, 6327.430531252209, 6412.358764123644, 6457.160569125889, 6550.443212327005, 6772.25505194438, 6787.89818152738, 6837.726490940307, 6840.897386539106, 6879.552829066095, 6675.899861502178, 6780.627065511444, 6816.522843401112, 6818.556793808387, 6852.349026842442, 6573.211214654272, 6587.176602924635, 6601.339866013553, 6613.603183808987, 6660.0675905374255, 5086.160783470686, 5100.52084895391, 5108.386993560796, 5149.124648809637, 5169.560587349967, 6529.51028672861, 6865.588064715933, 6870.793876183722, 6943.444524437932, 6976.7558135465015, 6390.329015453311, 6468.773592924414, 6469.191351081115, 6516.934830651633, 6567.770128045496, 6368.617882927039, 6379.96810015947, 6518.315576864551, 6559.311272316338, 6570.868582628225, 6415.422611964946, 6448.421620649391, 6458.22501299847, 6458.54790164684, 6507.739690412331, 6594.142761440982, 6621.073049772939, 6689.26418094019, 6699.29657385987, 6738.124043874265, 6350.206224221973, 6417.518686098655, 6441.806745797634, 6449.419552240321, 6480.70836812354], "value": 5279.905970819837, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [893.6648756716231, 896.166565094462, 909.7634615000106, 913.7167478081793, 915.7115508614786, 17463.65072698547, 18011.639767204648, 18040.909795451003, 18068.819311806878, 18080.36718714845, 18043.90978045108, 18193.545161370974, 18261.086945652696, 18290.542736431595, 18308.633827323447, 948.7438391634272, 952.6713283917038, 957.1194501059042, 959.9088086631759, 962.4898803634085, 980.5195454227448, 985.9260555458334, 986.1765425869407, 986.3982970388057, 986.5609803637384, 991.9752006199831, 1030.1912998296339, 1030.855680204772, 1044.4568824211406, 1045.038564520639, 1035.30634474902, 1038.48075962019, 1038.5793753529833, 1038.7558923652973, 1039.153090231459, 724.3734169942961, 724.4023680463639, 724.5326764237037, 735.46311192829, 756.8032311598969, 18800.34198803035, 19254.61490770177, 19385.03074846266, 19641.0, 19655.410337689893, 21977.67033494499, 22093.116275348966, 22249.110035598558, 22381.10475580975, 22475.988580513917, 16140.83859161405, 16364.099974501398, 16407.343706251853, 16443.09562974048, 16458.75311870323, 3568.4468907319406, 3576.6065732769375, 3577.499150118985, 3578.982105089458, 3630.0198946284354, 121002.39498802538, 136043.0, 137664.31167844098, 137792.0, 138964.0, 17559.20983555743, 17749.7337539937, 17779.644407111784, 17852.375166869097, 17912.641747165246, 30546.0, 30873.073807785804, 31267.37465250728, 31285.687143128733, 31324.06027819169, 8689.0, 8929.419587726778, 8945.0, 8987.505687187268, 9018.909810901807, 4425.048614547888, 4516.796744146522, 4521.434820647419, 4540.50054494012, 4546.886327841764, 5318.97340513295, 5389.622726409114, 5396.055690254189, 5427.3215848019, 5446.455354464521, 1027.5016616940777, 1051.3008849115406, 1056.2078441169124, 1065.2436769893307, 1076.1390887290136, 652.393274254938, 664.7440735316841, 676.1176664452838, 680.6936878404738, 683.5215349255541, 12100.455479503444, 12143.635690929286, 12184.390780461119, 12265.632031039084, 12421.944134748648, 662.7845950066247, 663.349917081263, 668.2815972829228, 684.9897251541231, 691.1498856406648, 5309.256704061435, 5334.306540149833, 5392.73036348181, 5427.972860135674, 5436.53789427903, 123089.0, 124677.0, 124685.37657311655, 127360.0, 127589.0, 103630.48184759029, 104953.0, 107260.0, 107576.4621176889, 108112.45943770389, 88998.55500722455, 90887.54556227177, 91137.54431227801, 91392.54303728572, 93407.53296233612, 11729.296242225153, 12059.276443413422, 12128.272303661808, 12201.328926909106, 12283.57007504733, 41695.58304416917, 41833.37249941314, 42689.0, 43186.136277274905, 43450.0, 56509.43490565206, 57067.0, 57633.42366576448, 57731.71134144302, 58349.4165058344, 18068.728969065738, 18625.068746562345, 18645.81354186495, 18671.692981491164, 18714.438566842473, 20982.895085524473, 21019.0, 21128.15487380549, 21222.787772122698, 21285.46786330354, 17856.107194640477, 17967.5508112293, 18179.272829086687, 18375.0, 18486.168122434254, 17567.121643917497, 17648.382306619205, 17721.645567088843, 17870.642587147926, 17929.103544822967, 17635.55911102233, 18078.5480362992, 18153.818461815743, 18156.182971766037, 18227.453176404728, 13794.862051379358, 13828.723425531232, 13900.860991390362, 13990.720185596434, 14156.009079364758, 12224.38878056076, 12302.507899683913, 12377.690557736129, 12465.376731163224, 12508.374581270718], "value": 9142.507274683278, "unit": "score"}]}, {"extraOptions": ["e10s", "stylo_disabled"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1411.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 106.0, 108.0, 107.0, 106.0, 109.0, 107.0, 107.0, 106.0, 109.0], "value": 107.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 119.0, 115.0, 119.0, 115.0, 147.0, 120.0, 122.0, 116.0, 151.0], "value": 124.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 161.0, 162.0, 162.0, 161.0, 165.0, 163.0, 170.0, 169.0], "value": 163.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 72.0, 76.0, 76.0, 77.0, 75.0, 77.0, 80.0, 75.0], "value": 75.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 80.0, 79.0, 80.0, 78.0, 79.0, 80.0, 80.0, 78.0], "value": 79.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 111.0, 111.0, 111.0, 111.0, 111.0, 111.0, 111.0, 112.0, 111.0], "value": 111.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 147.0, 149.0, 148.0, 148.0, 148.0, 148.0, 149.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 95.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 56.0, 50.0, 53.0, 52.0, 51.0, 51.0, 51.0, 51.0, 51.0], "value": 51.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 37.0, 37.0, 38.0, 37.0, 37.0, 37.0, 38.0, 37.0], "value": 37.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 76.0, 74.0, 80.0, 75.0, 74.0, 75.0, 78.0, 73.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 109.0, 112.0, 109.0, 110.0, 117.0, 119.0, 124.0, 109.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 165.0, 160.0, 164.0, 165.0, 162.0, 166.0, 169.0, 161.0, 162.0], "value": 163.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 67.0, 63.0, 64.0, 64.0, 68.0, 73.0, 64.0, 63.0, 64.0], "value": 66.5, "unit": "score"}]}]} 12:13:59 INFO - Return code: 0 12:13:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:13:59 INFO - # TBPL SUCCESS # 12:13:59 INFO - Running post-action listener: _package_coverage_data 12:13:59 INFO - Running post-action listener: _resource_record_post_action 12:13:59 INFO - [mozharness: 2017-10-01 19:13:59.125120Z] Finished run-tests step (success) 12:13:59 INFO - Running post-run listener: _resource_record_post_run 12:13:59 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 151552; Write bytes: 343896064; Read time: 852; Write time: 265196 12:13:59 INFO - TinderboxPrint: CPU usage
13.1% 12:13:59 INFO - TinderboxPrint: I/O read bytes / time
151,552 / 852 12:13:59 INFO - TinderboxPrint: I/O write bytes / time
343,896,064 / 265,196 12:13:59 INFO - TinderboxPrint: CPU idle
8,235.5 (86.9%) 12:13:59 INFO - TinderboxPrint: CPU user
1,192.8 (12.6%) 12:13:59 INFO - TinderboxPrint: Swap in / out
8,192 / 0 12:13:59 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12943360; Read time: 0; Write time: 3076 12:13:59 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:13:59 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 147456; Write bytes: 329781248; Read time: 832; Write time: 261828 12:13:59 INFO - Running post-run listener: _upload_blobber_files 12:13:59 INFO - Blob upload gear active. 12:13:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:13:59 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 12:13:59 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'] 12:13:59 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 12:14:00 INFO - Traceback (most recent call last): 12:14:00 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 12:14:00 INFO - main() 12:14:00 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 12:14:00 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 12:14:00 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 12:14:00 INFO - return set(response.json().get('whitelist', [])) 12:14:00 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 12:14:00 INFO - return json.loads(self.text or self.content, **kwargs) 12:14:00 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/__init__.py", line 488, in loads 12:14:00 INFO - return _default_decoder.decode(s) 12:14:00 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 370, in decode 12:14:00 INFO - obj, end = self.raw_decode(s) 12:14:00 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 389, in raw_decode 12:14:00 INFO - return self.scan_once(s, idx=_w(s, idx).end()) 12:14:00 INFO - simplejson.scanner.JSONDecodeError: Expecting value: line 1 column 1 (char 0) 12:14:00 ERROR - Return code: 1 12:14:00 INFO - Setting buildbot property blobber_files to {} 12:14:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:14:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:14:00 INFO - Contents: 12:14:00 INFO - blobber_files:{} 12:14:00 INFO - Running post-run listener: copy_logs_to_upload_dir 12:14:00 INFO - Copying logs to upload dir... 12:14:00 INFO - mkdir: /builds/slave/test/build/upload/logs 12:14:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.887068 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2017-10-01 12:14:00.072184) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-01 12:14:00.076444) ========= 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=5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011470 build_url: 'https://queue.taskcluster.net/v1/task/aaFg7CtMSLCpUxmqnoapeA/artifacts/public/build/target.tar.bz2' blobber_files: '{}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-01 12:14:00.108425) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 12:14:00.108752) ========= 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=5cf47e208febbcd81da23b2e0000026f-1506876209.320960-1173589964 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004540 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 12:14:00.158925) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-01 12:14:00.162271) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-01 12:14:00.162565) ========= ========= Total master_lag: 0.22 =========