builder: mozilla-central_ubuntu64_hw_test-dromaeojs-stylo-disabled-e10s-pgo slave: talos-linux64-ix-051 starttime: 1506647958.34 results: success (0) revision: e6c32278f32cd5f7d159627b2157396b62d0c4a9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-28 18:19:18.341905) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-28 18:19:18.342495) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-28 18:19:18.342810) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-28 18:19:18.448161) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 18:19:18.448450) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-28 18:19:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 233M=0s 2017-09-28 18:19:18 (233 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.189891 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 18:19:18.712041) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 18:19:18.712374) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017823 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 18:19:18.774860) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 18:19:18.779234) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e6c32278f32cd5f7d159627b2157396b62d0c4a9 --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 e6c32278f32cd5f7d159627b2157396b62d0c4a9 --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=5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-28 18:19:18,761 truncating revision to first 12 chars 2017-09-28 18:19:18,761 Setting DEBUG logging. 2017-09-28 18:19:18,761 attempt 1/10 2017-09-28 18:19:18,761 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e6c32278f32c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-28 18:19:19,159 unpacking tar archive at: mozilla-central-e6c32278f32c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.692089 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 18:19:19.491720) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-28 18:19:19.492098) ========= script_repo_revision: e6c32278f32cd5f7d159627b2157396b62d0c4a9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-28 18:19:19.492536) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-28 18:19:19.492840) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-28 18:19:19.510332) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-28 18:19:19.510697) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-stylo-disabled-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:19:19 INFO - MultiFileLogger online at 20170928 18:19:19 in /builds/slave/test 18:19:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:19:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:19:19 INFO - {'append_to_log': False, 18:19:19 INFO - 'base_work_dir': '/builds/slave/test', 18:19:19 INFO - 'blob_upload_branch': 'Firefox', 18:19:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:19:19 INFO - 'branch': 'Firefox', 18:19:19 INFO - 'buildbot_json_path': 'buildprops.json', 18:19:19 INFO - 'code_coverage': False, 18:19:19 INFO - 'config_files': ('talos/linux_config.py',), 18:19:19 INFO - 'default_actions': ('clobber', 18:19:19 INFO - 'read-buildbot-config', 18:19:19 INFO - 'download-and-extract', 18:19:19 INFO - 'populate-webroot', 18:19:19 INFO - 'create-virtualenv', 18:19:19 INFO - 'install', 18:19:19 INFO - 'setup-mitmproxy', 18:19:19 INFO - 'run-tests'), 18:19:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:19:19 INFO - 'disable_ccov_upload': False, 18:19:19 INFO - 'disable_stylo': False, 18:19:19 INFO - 'download_minidump_stackwalk': True, 18:19:19 INFO - 'download_symbols': 'ondemand', 18:19:19 INFO - 'enable_stylo': False, 18:19:19 INFO - 'enable_webrender': False, 18:19:19 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:19:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:19:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:19:19 INFO - 'gecko_profile': False, 18:19:19 INFO - 'gecko_profile_interval': 0, 18:19:19 INFO - 'installer_path': 'installer.exe', 18:19:19 INFO - 'log_level': 'info', 18:19:19 INFO - 'log_name': 'talos', 18:19:19 INFO - 'log_to_console': True, 18:19:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:19:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:19:19 INFO - 'opt_config_files': (), 18:19:19 INFO - 'pip_index': False, 18:19:19 INFO - 'suite': 'dromaeojs-stylo-disabled-e10s', 18:19:19 INFO - 'system_bits': '32', 18:19:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:19:19 INFO - 'title': 'talos-linux64-ix-051', 18:19:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:19:19 INFO - 'use_talos_json': True, 18:19:19 INFO - 'verify': 'False', 18:19:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:19:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:19:19 INFO - 'work_dir': 'build'} 18:19:19 INFO - [mozharness: 2017-09-29 01:19:19.727606Z] Running clobber step. 18:19:19 INFO - Running pre-action listener: _resource_record_pre_action 18:19:19 INFO - Running main action method: clobber 18:19:19 INFO - rmtree: /builds/slave/test/build 18:19:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:19:20 INFO - Running post-action listener: _resource_record_post_action 18:19:20 INFO - [mozharness: 2017-09-29 01:19:20.688684Z] Finished clobber step (success) 18:19:20 INFO - [mozharness: 2017-09-29 01:19:20.688782Z] Running read-buildbot-config step. 18:19:20 INFO - Running pre-action listener: _resource_record_pre_action 18:19:20 INFO - Running main action method: read_buildbot_config 18:19:20 INFO - Using buildbot properties: 18:19:20 INFO - { 18:19:20 INFO - "project": "", 18:19:20 INFO - "product": "firefox", 18:19:20 INFO - "who": "kwierso@gmail.com", 18:19:20 INFO - "installer_path": "public/build/target.tar.bz2", 18:19:20 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-stylo-disabled-e10s", 18:19:20 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:19:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-stylo-disabled-e10s", 18:19:20 INFO - "stage_platform": "linux64-pgo", 18:19:20 INFO - "basedir": "/builds/slave/test", 18:19:20 INFO - "buildnumber": 73, 18:19:20 INFO - "platform": "ubuntu64_hw", 18:19:20 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:19:20 INFO - "slavebuilddir": "test", 18:19:20 INFO - "taskId": "I9Gx7QQRQKSdNCef717ihQ", 18:19:20 INFO - "branch": "mozilla-central", 18:19:20 INFO - "script_repo_revision": "production", 18:19:20 INFO - "revision": "e6c32278f32cd5f7d159627b2157396b62d0c4a9", 18:19:20 INFO - "slavename": "talos-linux64-ix-051", 18:19:20 INFO - "repo_path": "mozilla-central" 18:19:20 INFO - } 18:19:20 INFO - Finding installer, test and symbols from parent task. 18:19:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I9Gx7QQRQKSdNCef717ihQ'}, attempt #1 18:19:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I9Gx7QQRQKSdNCef717ihQ'}, attempt #1 18:19:21 INFO - Task dependencies: LLXKHqi1S7W9dRPfY0Vflg 18:19:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg'}, attempt #1 18:19:22 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.tar.bz2 18:19:22 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.test_packages.json 18:19:22 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.crashreporter-symbols.zip 18:19:22 INFO - Running post-action listener: _resource_record_post_action 18:19:22 INFO - [mozharness: 2017-09-29 01:19:22.163631Z] Finished read-buildbot-config step (success) 18:19:22 INFO - [mozharness: 2017-09-29 01:19:22.163850Z] Running download-and-extract step. 18:19:22 INFO - Running pre-action listener: _resource_record_pre_action 18:19:22 INFO - Running main action method: download_and_extract 18:19:22 INFO - mkdir: /builds/slave/test/build/tests 18:19:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:22 INFO - trying https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.test_packages.json 18:19:22 INFO - Downloading https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:19:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:19:22 INFO - Downloaded 989 bytes. 18:19:22 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:19:22 INFO - Using the following test package requirements: 18:19:22 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:19:22 INFO - u'common': [u'target.common.tests.zip'], 18:19:22 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:19:22 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:19:22 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:19:22 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:19:22 INFO - u'mozbase': [u'target.common.tests.zip'], 18:19:22 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:19:22 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:19:22 INFO - u'web-platform': [u'target.common.tests.zip', 18:19:22 INFO - u'target.web-platform.tests.tar.gz'], 18:19:22 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:19:22 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 18:19:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.common.tests.zip 18:19:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:19:22 INFO - Fetch https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.common.tests.zip into memory 18:19:24 INFO - Content-Length response header: 55912262 18:19:24 INFO - Bytes received: 55912262 18:19:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.talos.tests.zip 18:19:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:19:35 INFO - Fetch https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.talos.tests.zip into memory 18:19:35 INFO - Content-Length response header: 14521355 18:19:35 INFO - Bytes received: 14521355 18:19:36 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')]} 18:19:36 INFO - trying https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.tar.bz2 18:19:36 INFO - Downloading https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:19:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:19:37 INFO - Downloaded 62346591 bytes. 18:19:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.tar.bz2 18:19:37 INFO - mkdir: /builds/slave/test/properties 18:19:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:19:37 INFO - Writing to file /builds/slave/test/properties/build_url 18:19:37 INFO - Contents: 18:19:37 INFO - build_url:https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.tar.bz2 18:19:37 INFO - Running post-action listener: _resource_record_post_action 18:19:37 INFO - Running post-action listener: find_tests_for_verification 18:19:37 INFO - Running post-action listener: set_extra_try_arguments 18:19:37 INFO - [mozharness: 2017-09-29 01:19:37.595163Z] Finished download-and-extract step (success) 18:19:37 INFO - [mozharness: 2017-09-29 01:19:37.595262Z] Running populate-webroot step. 18:19:37 INFO - Running pre-action listener: _resource_record_pre_action 18:19:37 INFO - Running main action method: populate_webroot 18:19:37 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:19:37 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:19:37 INFO - 'tests': ['tresize', 18:19:37 INFO - 'tcanvasmark']}, 18:19:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:19:37 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:19:37 INFO - 'tests': ['dromaeo_css', 18:19:37 INFO - 'kraken']}, 18:19:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:19:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:19:37 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:19:37 INFO - 'talos_options': ['--disable-stylo'], 18:19:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:19:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:19:37 INFO - 'tests': ['damp', 'tps']}, 18:19:37 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:19:37 INFO - 'talos_options': ['--disable-stylo'], 18:19:37 INFO - 'tests': ['damp', 'tps']}, 18:19:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:19:37 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:19:37 INFO - 'tests': ['dromaeo_dom']}, 18:19:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:19:37 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:19:37 INFO - 'tests': ['basic_compositor_video', 18:19:37 INFO - 'glvideo']}, 18:19:37 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:19:37 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:19:37 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:19:37 INFO - 'talos_options': ['--disable-stylo'], 18:19:37 INFO - 'tests': ['ts_paint_webext', 18:19:37 INFO - 'tp5o_webext']}, 18:19:37 INFO - 'other-e10s': {'tests': ['a11yr', 18:19:37 INFO - 'ts_paint', 18:19:37 INFO - 'tpaint', 18:19:37 INFO - 'sessionrestore', 18:19:37 INFO - 'sessionrestore_many_windows', 18:19:37 INFO - 'sessionrestore_no_auto_restore', 18:19:37 INFO - 'tabpaint', 18:19:37 INFO - 'cpstartup']}, 18:19:37 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:19:37 INFO - 'tests': ['a11yr', 18:19:37 INFO - 'ts_paint', 18:19:37 INFO - 'tpaint', 18:19:37 INFO - 'sessionrestore', 18:19:37 INFO - 'sessionrestore_many_windows', 18:19:37 INFO - 'sessionrestore_no_auto_restore', 18:19:37 INFO - 'tabpaint', 18:19:37 INFO - 'cpstartup']}, 18:19:37 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 18:19:37 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 18:19:37 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:19:37 INFO - 'tests': ['perf_reftest_singletons']}, 18:19:37 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:19:37 INFO - 'tests': ['perf_reftest']}, 18:19:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:19:37 INFO - 'tsvgr_opacity', 18:19:37 INFO - 'tart', 18:19:37 INFO - 'tscrollx', 18:19:37 INFO - 'tsvg_static']}, 18:19:37 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:19:37 INFO - 'tests': ['tsvgx', 18:19:37 INFO - 'tsvgr_opacity', 18:19:37 INFO - 'tart', 18:19:37 INFO - 'tscrollx', 18:19:37 INFO - 'tsvg_static']}, 18:19:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:19:37 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:19:37 INFO - 'talos_options': ['--disable-stylo'], 18:19:37 INFO - 'tests': ['tp5o']}, 18:19:37 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:19:37 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 18:19:37 INFO - 'talos_options': ['--mitmproxy', 18:19:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:19:37 INFO - '--firstNonBlankPaint'], 18:19:37 INFO - 'tests': ['tp6_google', 18:19:37 INFO - 'tp6_youtube', 18:19:37 INFO - 'tp6_amazon', 18:19:37 INFO - 'tp6_facebook']}, 18:19:37 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:19:37 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 18:19:37 INFO - 'talos_options': ['--disable-stylo', 18:19:37 INFO - '--mitmproxy', 18:19:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:19:37 INFO - '--firstNonBlankPaint'], 18:19:37 INFO - 'tests': ['tp6_google', 18:19:37 INFO - 'tp6_youtube', 18:19:37 INFO - 'tp6_amazon', 18:19:37 INFO - 'tp6_facebook']}, 18:19:37 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:19:37 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 18:19:37 INFO - 'talos_options': ['--stylo-threads=1', 18:19:37 INFO - '--mitmproxy', 18:19:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:19:37 INFO - '--firstNonBlankPaint'], 18:19:37 INFO - 'tests': ['tp6_google', 18:19:37 INFO - 'tp6_youtube', 18:19:37 INFO - 'tp6_amazon', 18:19:37 INFO - 'tp6_facebook']}, 18:19:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:19:37 INFO - 'talos_options': ['--xperf_path', 18:19:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:19:37 INFO - 'tests': ['tp5n']}, 18:19:37 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:19:37 INFO - 'talos_options': ['--disable-stylo', 18:19:37 INFO - '--xperf_path', 18:19:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:19:37 INFO - 'tests': ['tp5n']}}} 18:19:37 INFO - Running post-action listener: _resource_record_post_action 18:19:37 INFO - [mozharness: 2017-09-29 01:19:37.610886Z] Finished populate-webroot step (success) 18:19:37 INFO - [mozharness: 2017-09-29 01:19:37.610976Z] Running create-virtualenv step. 18:19:37 INFO - Running pre-action listener: _resource_record_pre_action 18:19:37 INFO - Running main action method: create_virtualenv 18:19:37 INFO - Creating virtualenv /builds/slave/test/build/venv 18:19:37 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:19:37 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:19:37 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:19:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:19:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:19:38 INFO - Installing distribute.............................................................................................................................................................................................done. 18:19:39 INFO - Installing pip...............done. 18:19:39 INFO - Return code: 0 18:19:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:19:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:19:40 INFO - Reading from file tmpfile_stdout 18:19:40 INFO - Output received: 18:19:40 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:19:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:19:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', '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 18:19:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:19:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:19:40 INFO - Using env: {'DISPLAY': ':0', 18:19:40 INFO - 'HOME': '/home/cltbld', 18:19:40 INFO - 'LANG': 'en_US.UTF-8', 18:19:40 INFO - 'LANGUAGE': 'en_US:en', 18:19:40 INFO - 'LOGNAME': 'cltbld', 18:19:40 INFO - 'MAIL': '/var/mail/cltbld', 18:19:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:40 INFO - 'MOZ_NO_REMOTE': '1', 18:19:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:19:40 INFO - 'NO_EM_RESTART': '1', 18:19:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:19:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:40 INFO - 'PWD': '/builds/slave/test', 18:19:40 INFO - 'SHELL': '/bin/bash', 18:19:40 INFO - 'SHLVL': '1', 18:19:40 INFO - 'TERM': 'linux', 18:19:40 INFO - 'TMOUT': '86400', 18:19:40 INFO - 'USER': 'cltbld', 18:19:40 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', 18:19:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:40 INFO - '_': '/tools/buildbot/bin/python'} 18:19:41 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:19:41 INFO - Downloading/unpacking pip>=1.5 18:19:41 INFO - Running setup.py egg_info for package pip 18:19:41 INFO - warning: no files found matching 'pip/cacert.pem' 18:19:41 INFO - warning: no files found matching '*.html' under directory 'docs' 18:19:41 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:19:41 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:19:41 INFO - Installing collected packages: pip 18:19:41 INFO - Found existing installation: pip 1.1 18:19:41 INFO - Uninstalling pip: 18:19:41 INFO - Successfully uninstalled pip 18:19:41 INFO - Running setup.py install for pip 18:19:41 INFO - warning: no files found matching 'pip/cacert.pem' 18:19:41 INFO - warning: no files found matching '*.html' under directory 'docs' 18:19:41 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:19:41 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:19:41 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:19:41 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:19:41 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:19:41 INFO - Successfully installed pip 18:19:41 INFO - Cleaning up... 18:19:41 INFO - Return code: 0 18:19:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:19:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', '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 18:19:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:19:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:19:41 INFO - Using env: {'DISPLAY': ':0', 18:19:41 INFO - 'HOME': '/home/cltbld', 18:19:41 INFO - 'LANG': 'en_US.UTF-8', 18:19:41 INFO - 'LANGUAGE': 'en_US:en', 18:19:41 INFO - 'LOGNAME': 'cltbld', 18:19:41 INFO - 'MAIL': '/var/mail/cltbld', 18:19:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:41 INFO - 'MOZ_NO_REMOTE': '1', 18:19:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:19:41 INFO - 'NO_EM_RESTART': '1', 18:19:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:19:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:41 INFO - 'PWD': '/builds/slave/test', 18:19:41 INFO - 'SHELL': '/bin/bash', 18:19:41 INFO - 'SHLVL': '1', 18:19:41 INFO - 'TERM': 'linux', 18:19:41 INFO - 'TMOUT': '86400', 18:19:41 INFO - 'USER': 'cltbld', 18:19:41 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', 18:19:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:41 INFO - '_': '/tools/buildbot/bin/python'} 18:19:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:41 INFO - Downloading/unpacking psutil>=3.1.1 18:19:41 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 18:19:41 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 18:19:41 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 18:19:41 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 18:19:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:19:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:19:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:19:42 INFO - Installing collected packages: psutil 18:19:42 INFO - Running setup.py install for psutil 18:19:42 INFO - building 'psutil._psutil_linux' extension 18:19:42 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 18:19:44 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 18:19:44 INFO - building 'psutil._psutil_posix' extension 18:19:44 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 18:19:44 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 18:19:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:19:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:19:44 INFO - Successfully installed psutil 18:19:44 INFO - Cleaning up... 18:19:44 INFO - Return code: 0 18:19:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:19:44 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')]} 18:19:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:44 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')]} 18:19:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:44 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 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', '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 18:19:44 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 18:19:44 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 18:19:44 INFO - Using env: {'DISPLAY': ':0', 18:19:44 INFO - 'HOME': '/home/cltbld', 18:19:44 INFO - 'LANG': 'en_US.UTF-8', 18:19:44 INFO - 'LANGUAGE': 'en_US:en', 18:19:44 INFO - 'LOGNAME': 'cltbld', 18:19:44 INFO - 'MAIL': '/var/mail/cltbld', 18:19:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:44 INFO - 'MOZ_NO_REMOTE': '1', 18:19:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:19:44 INFO - 'NO_EM_RESTART': '1', 18:19:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:19:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:44 INFO - 'PWD': '/builds/slave/test', 18:19:44 INFO - 'SHELL': '/bin/bash', 18:19:44 INFO - 'SHLVL': '1', 18:19:44 INFO - 'TERM': 'linux', 18:19:44 INFO - 'TMOUT': '86400', 18:19:44 INFO - 'USER': 'cltbld', 18:19:44 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', 18:19:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:44 INFO - '_': '/tools/buildbot/bin/python'} 18:19:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:19:44 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 18:19:44 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 18:19:44 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 18:19:44 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 18:19:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:19:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:19:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:19:46 INFO - Installing collected packages: mozsystemmonitor 18:19:46 INFO - Running setup.py install for mozsystemmonitor 18:19:46 INFO - Successfully installed mozsystemmonitor 18:19:46 INFO - Cleaning up... 18:19:46 INFO - Return code: 0 18:19:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:19:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', '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 18:19:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:19:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:19:46 INFO - Using env: {'DISPLAY': ':0', 18:19:46 INFO - 'HOME': '/home/cltbld', 18:19:46 INFO - 'LANG': 'en_US.UTF-8', 18:19:46 INFO - 'LANGUAGE': 'en_US:en', 18:19:46 INFO - 'LOGNAME': 'cltbld', 18:19:46 INFO - 'MAIL': '/var/mail/cltbld', 18:19:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:46 INFO - 'MOZ_NO_REMOTE': '1', 18:19:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:19:46 INFO - 'NO_EM_RESTART': '1', 18:19:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:19:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:46 INFO - 'PWD': '/builds/slave/test', 18:19:46 INFO - 'SHELL': '/bin/bash', 18:19:46 INFO - 'SHLVL': '1', 18:19:46 INFO - 'TERM': 'linux', 18:19:46 INFO - 'TMOUT': '86400', 18:19:46 INFO - 'USER': 'cltbld', 18:19:46 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', 18:19:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:46 INFO - '_': '/tools/buildbot/bin/python'} 18:19:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:46 INFO - Downloading/unpacking jsonschema==2.5.1 18:19:46 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 18:19:46 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 18:19:46 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 18:19:46 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 18:19:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:19:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:19:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:19:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:19:47 INFO - Downloading functools32-3.2.3-2.tar.gz 18:19:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:19:47 INFO - warning: no files found matching '*.txt' 18:19:47 INFO - no previously-included directories found matching 'build' 18:19:47 INFO - no previously-included directories found matching 'dist' 18:19:47 INFO - no previously-included directories found matching '.git*' 18:19:47 INFO - Installing collected packages: jsonschema, functools32 18:19:47 INFO - Running setup.py install for functools32 18:19:47 INFO - warning: no files found matching '*.txt' 18:19:47 INFO - no previously-included directories found matching 'build' 18:19:47 INFO - no previously-included directories found matching 'dist' 18:19:47 INFO - no previously-included directories found matching '.git*' 18:19:47 INFO - Successfully installed jsonschema functools32 18:19:47 INFO - Cleaning up... 18:19:47 INFO - Return code: 0 18:19:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:19:47 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')]} 18:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:47 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')]} 18:19:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:47 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 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', '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 18:19:47 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 18:19:47 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 18:19:47 INFO - Using env: {'DISPLAY': ':0', 18:19:47 INFO - 'HOME': '/home/cltbld', 18:19:47 INFO - 'LANG': 'en_US.UTF-8', 18:19:47 INFO - 'LANGUAGE': 'en_US:en', 18:19:47 INFO - 'LOGNAME': 'cltbld', 18:19:47 INFO - 'MAIL': '/var/mail/cltbld', 18:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:47 INFO - 'MOZ_NO_REMOTE': '1', 18:19:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:19:47 INFO - 'NO_EM_RESTART': '1', 18:19:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:47 INFO - 'PWD': '/builds/slave/test', 18:19:47 INFO - 'SHELL': '/bin/bash', 18:19:47 INFO - 'SHLVL': '1', 18:19:47 INFO - 'TERM': 'linux', 18:19:47 INFO - 'TMOUT': '86400', 18:19:47 INFO - 'USER': 'cltbld', 18:19:47 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', 18:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:47 INFO - '_': '/tools/buildbot/bin/python'} 18:19:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:19:48 INFO - Cleaning up... 18:19:48 INFO - Return code: 0 18:19:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:19:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', '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 18:19:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:19:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:19:48 INFO - Using env: {'DISPLAY': ':0', 18:19:48 INFO - 'HOME': '/home/cltbld', 18:19:48 INFO - 'LANG': 'en_US.UTF-8', 18:19:48 INFO - 'LANGUAGE': 'en_US:en', 18:19:48 INFO - 'LOGNAME': 'cltbld', 18:19:48 INFO - 'MAIL': '/var/mail/cltbld', 18:19:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:48 INFO - 'MOZ_NO_REMOTE': '1', 18:19:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:19:48 INFO - 'NO_EM_RESTART': '1', 18:19:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:19:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:48 INFO - 'PWD': '/builds/slave/test', 18:19:48 INFO - 'SHELL': '/bin/bash', 18:19:48 INFO - 'SHLVL': '1', 18:19:48 INFO - 'TERM': 'linux', 18:19:48 INFO - 'TMOUT': '86400', 18:19:48 INFO - 'USER': 'cltbld', 18:19:48 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', 18:19:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:48 INFO - '_': '/tools/buildbot/bin/python'} 18:19:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:48 INFO - Downloading/unpacking blobuploader==1.2.4 18:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:19:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:19:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:19:49 INFO - Downloading blobuploader-1.2.4.tar.gz 18:19:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:19:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:19:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:19:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:19:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:19:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:19:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:19:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:19:49 INFO - Downloading docopt-0.6.1.tar.gz 18:19:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:19:50 INFO - Installing collected packages: blobuploader, requests, docopt 18:19:50 INFO - Running setup.py install for blobuploader 18:19:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:19:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:19:50 INFO - Running setup.py install for requests 18:19:50 INFO - Running setup.py install for docopt 18:19:50 INFO - Successfully installed blobuploader requests docopt 18:19:50 INFO - Cleaning up... 18:19:50 INFO - Return code: 0 18:19:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:19: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')]} 18:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19: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')]} 18:19:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:50 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 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', '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 18:19:50 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 18:19:50 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 18:19:50 INFO - Using env: {'DISPLAY': ':0', 18:19:50 INFO - 'HOME': '/home/cltbld', 18:19:50 INFO - 'LANG': 'en_US.UTF-8', 18:19:50 INFO - 'LANGUAGE': 'en_US:en', 18:19:50 INFO - 'LOGNAME': 'cltbld', 18:19:50 INFO - 'MAIL': '/var/mail/cltbld', 18:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:50 INFO - 'MOZ_NO_REMOTE': '1', 18:19:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:19:50 INFO - 'NO_EM_RESTART': '1', 18:19:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:50 INFO - 'PWD': '/builds/slave/test', 18:19:50 INFO - 'SHELL': '/bin/bash', 18:19:50 INFO - 'SHLVL': '1', 18:19:50 INFO - 'TERM': 'linux', 18:19:50 INFO - 'TMOUT': '86400', 18:19:50 INFO - 'USER': 'cltbld', 18:19:50 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', 18:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:50 INFO - '_': '/tools/buildbot/bin/python'} 18:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:19:50 INFO - Running setup.py (path:/tmp/pip-JyG7SE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:19:50 INFO - Running setup.py (path:/tmp/pip-VkuaW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:19:50 INFO - Running setup.py (path:/tmp/pip-PebPNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:19:51 INFO - Running setup.py (path:/tmp/pip-gMYZjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:19:51 INFO - Running setup.py (path:/tmp/pip-4CMZxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:19:51 INFO - Running setup.py (path:/tmp/pip-h6JHbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:19:51 INFO - Running setup.py (path:/tmp/pip-hZgqsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:19:51 INFO - Running setup.py (path:/tmp/pip-6q6VHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:19:51 INFO - Running setup.py (path:/tmp/pip-jVgfi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:19:51 INFO - Running setup.py (path:/tmp/pip-1iRmo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:19:51 INFO - Running setup.py (path:/tmp/pip-hz97CM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:19:51 INFO - Running setup.py (path:/tmp/pip-Fuletw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:19:51 INFO - Running setup.py (path:/tmp/pip-vG_NEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:19:51 INFO - Running setup.py (path:/tmp/pip-HrkLYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:19:51 INFO - Running setup.py (path:/tmp/pip-x46qQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:19:51 INFO - Running setup.py (path:/tmp/pip-M8a4Og-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:19:51 INFO - Running setup.py (path:/tmp/pip-pkAadL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:19:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:19:52 INFO - Running setup.py install for manifestparser 18:19:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:19:52 INFO - Running setup.py install for mozcrash 18:19:52 INFO - Running setup.py install for mozdebug 18:19:52 INFO - Running setup.py install for mozdevice 18:19:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:19:52 INFO - Running setup.py install for mozfile 18:19:52 INFO - Running setup.py install for mozhttpd 18:19:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:19:52 INFO - Running setup.py install for mozinfo 18:19:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:19:52 INFO - Running setup.py install for mozInstall 18:19:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:19:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:19:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:19:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:19:52 INFO - Running setup.py install for mozleak 18:19:52 INFO - Running setup.py install for mozlog 18:19:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:19:53 INFO - Running setup.py install for moznetwork 18:19:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:19:53 INFO - Running setup.py install for mozprocess 18:19:53 INFO - Running setup.py install for mozprofile 18:19:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:19:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:19:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:19:53 INFO - Running setup.py install for mozrunner 18:19:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:19:53 INFO - Running setup.py install for mozscreenshot 18:19:53 INFO - Running setup.py install for moztest 18:19:53 INFO - Running setup.py install for mozversion 18:19:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:19:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:19:53 INFO - Cleaning up... 18:19:53 INFO - Return code: 0 18:19:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:19:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:53 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 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', '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 18:19:53 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 18:19:53 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 18:19:53 INFO - Using env: {'DISPLAY': ':0', 18:19:53 INFO - 'HOME': '/home/cltbld', 18:19:53 INFO - 'LANG': 'en_US.UTF-8', 18:19:53 INFO - 'LANGUAGE': 'en_US:en', 18:19:53 INFO - 'LOGNAME': 'cltbld', 18:19:53 INFO - 'MAIL': '/var/mail/cltbld', 18:19:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:53 INFO - 'MOZ_NO_REMOTE': '1', 18:19:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:19:53 INFO - 'NO_EM_RESTART': '1', 18:19:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:19:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:53 INFO - 'PWD': '/builds/slave/test', 18:19:53 INFO - 'SHELL': '/bin/bash', 18:19:53 INFO - 'SHLVL': '1', 18:19:53 INFO - 'TERM': 'linux', 18:19:53 INFO - 'TMOUT': '86400', 18:19:53 INFO - 'USER': 'cltbld', 18:19:53 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', 18:19:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:53 INFO - '_': '/tools/buildbot/bin/python'} 18:19:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:19:54 INFO - Running setup.py (path:/tmp/pip-WUBSSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:19:54 INFO - Running setup.py (path:/tmp/pip-tepTKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:19:54 INFO - Running setup.py (path:/tmp/pip-NbU7nl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:19:54 INFO - Running setup.py (path:/tmp/pip-ptwmqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:19:54 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)) 18:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:19:54 INFO - Running setup.py (path:/tmp/pip-O2DYpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:19:54 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)) 18:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:19:54 INFO - Running setup.py (path:/tmp/pip-6VPwJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:19:54 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)) 18:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:19:54 INFO - Running setup.py (path:/tmp/pip-2dVeyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:19:54 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)) 18:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:19:54 INFO - Running setup.py (path:/tmp/pip-jTIGSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:19:54 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)) 18:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:19:54 INFO - Running setup.py (path:/tmp/pip-GbajCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:19:54 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)) 18:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:19:54 INFO - Running setup.py (path:/tmp/pip-SRz7gj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:19:54 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)) 18:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:19:54 INFO - Running setup.py (path:/tmp/pip-AtBVxK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:19:54 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)) 18:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:19:54 INFO - Running setup.py (path:/tmp/pip-r1E7qS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:19:54 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)) 18:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:19:54 INFO - Running setup.py (path:/tmp/pip-OgZjPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:19:54 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)) 18:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:19:54 INFO - Running setup.py (path:/tmp/pip-pVlpBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:19:55 INFO - Running setup.py (path:/tmp/pip-Zdox7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:19:55 INFO - Running setup.py (path:/tmp/pip-cbQrXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:19:55 INFO - Running setup.py (path:/tmp/pip-iby10Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:19:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:19: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 18:19: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 18:19: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 18:19: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 18:19:56 INFO - Downloading blessings-1.6.tar.gz 18:19:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:19:56 INFO - Installing collected packages: blessings 18:19:56 INFO - Running setup.py install for blessings 18:19:56 INFO - Successfully installed blessings 18:19:56 INFO - Cleaning up... 18:19:56 INFO - Return code: 0 18:19:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:19:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:19:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:19:56 INFO - Reading from file tmpfile_stdout 18:19:56 INFO - Current package versions: 18:19:56 INFO - argparse == 1.2.1 18:19:56 INFO - blessings == 1.6 18:19:56 INFO - blobuploader == 1.2.4 18:19:56 INFO - docopt == 0.6.1 18:19:56 INFO - functools32 == 3.2.3-2 18:19:56 INFO - jsonschema == 2.5.1 18:19:56 INFO - manifestparser == 1.1 18:19:56 INFO - mozInstall == 1.13 18:19:56 INFO - mozcrash == 1.0 18:19:56 INFO - mozdebug == 0.1 18:19:56 INFO - mozdevice == 0.51 18:19:56 INFO - mozfile == 1.2 18:19:56 INFO - mozhttpd == 0.7 18:19:56 INFO - mozinfo == 0.10 18:19:56 INFO - mozleak == 0.1 18:19:56 INFO - mozlog == 3.5 18:19:56 INFO - moznetwork == 0.27 18:19:56 INFO - mozprocess == 0.25 18:19:56 INFO - mozprofile == 0.28 18:19:56 INFO - mozrunner == 6.13 18:19:56 INFO - mozscreenshot == 0.1 18:19:56 INFO - mozsystemmonitor == 0.3 18:19:56 INFO - moztest == 0.8 18:19:56 INFO - mozversion == 1.4 18:19:56 INFO - psutil == 3.1.1 18:19:56 INFO - requests == 1.2.3 18:19:56 INFO - wsgiref == 0.1.2 18:19:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:19: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')]} 18:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19: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')]} 18:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:56 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 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', '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 18:19:56 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 18:19:56 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 18:19:56 INFO - Using env: {'DISPLAY': ':0', 18:19:56 INFO - 'HOME': '/home/cltbld', 18:19:56 INFO - 'LANG': 'en_US.UTF-8', 18:19:56 INFO - 'LANGUAGE': 'en_US:en', 18:19:56 INFO - 'LOGNAME': 'cltbld', 18:19:56 INFO - 'MAIL': '/var/mail/cltbld', 18:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:56 INFO - 'MOZ_NO_REMOTE': '1', 18:19:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:19:56 INFO - 'NO_EM_RESTART': '1', 18:19:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:56 INFO - 'PWD': '/builds/slave/test', 18:19:56 INFO - 'SHELL': '/bin/bash', 18:19:56 INFO - 'SHLVL': '1', 18:19:56 INFO - 'TERM': 'linux', 18:19:56 INFO - 'TMOUT': '86400', 18:19:56 INFO - 'USER': 'cltbld', 18:19:56 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', 18:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:56 INFO - '_': '/tools/buildbot/bin/python'} 18:19:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:19:57 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:19:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:19:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:19:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:19:58 INFO - Installing collected packages: simplejson 18:19:58 INFO - Running setup.py install for simplejson 18:19:58 INFO - building 'simplejson._speedups' extension 18:19:58 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 18:19:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:19:59 INFO - Successfully installed simplejson 18:19:59 INFO - Cleaning up... 18:19:59 INFO - Return code: 0 18:19:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2958b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2afd9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', '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 18:19:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:19:59 INFO - Using env: {'DISPLAY': ':0', 18:19:59 INFO - 'HOME': '/home/cltbld', 18:19:59 INFO - 'LANG': 'en_US.UTF-8', 18:19:59 INFO - 'LANGUAGE': 'en_US:en', 18:19:59 INFO - 'LOGNAME': 'cltbld', 18:19:59 INFO - 'MAIL': '/var/mail/cltbld', 18:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:59 INFO - 'MOZ_NO_REMOTE': '1', 18:19:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:19:59 INFO - 'NO_EM_RESTART': '1', 18:19:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:59 INFO - 'PWD': '/builds/slave/test', 18:19:59 INFO - 'SHELL': '/bin/bash', 18:19:59 INFO - 'SHLVL': '1', 18:19:59 INFO - 'TERM': 'linux', 18:19:59 INFO - 'TMOUT': '86400', 18:19:59 INFO - 'USER': 'cltbld', 18:19:59 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', 18:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:59 INFO - '_': '/tools/buildbot/bin/python'} 18:19:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:19:59 INFO - Cleaning up... 18:19:59 INFO - Return code: 0 18:19:59 INFO - Running post-action listener: _resource_record_post_action 18:19:59 INFO - Running post-action listener: _start_resource_monitoring 18:19:59 INFO - Starting resource monitoring. 18:19:59 INFO - [mozharness: 2017-09-29 01:19:59.614346Z] Finished create-virtualenv step (success) 18:19:59 INFO - [mozharness: 2017-09-29 01:19:59.615296Z] Running install step. 18:19:59 INFO - Running pre-action listener: _resource_record_pre_action 18:19:59 INFO - Running main action method: install 18:19:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:19:59 INFO - Reading from file tmpfile_stdout 18:19:59 INFO - Detecting whether we're running mozinstall >=1.0... 18:19:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:19:59 INFO - Reading from file tmpfile_stdout 18:19:59 INFO - Output received: 18:19:59 INFO - Usage: mozinstall [options] installer 18:19:59 INFO - Options: 18:19:59 INFO - -h, --help show this help message and exit 18:19:59 INFO - -d DEST, --destination=DEST 18:19:59 INFO - Directory to install application into. [default: 18:19:59 INFO - "/builds/slave/test"] 18:19:59 INFO - --app=APP Application being installed. [default: firefox] 18:19:59 INFO - mkdir: /builds/slave/test/build/application 18:19:59 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'] 18:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:20:17 INFO - Reading from file tmpfile_stdout 18:20:17 INFO - Output received: 18:20:17 INFO - /builds/slave/test/build/application/firefox/firefox 18:20:17 INFO - Running post-action listener: _resource_record_post_action 18:20:17 INFO - [mozharness: 2017-09-29 01:20:17.551211Z] Finished install step (success) 18:20:17 INFO - [mozharness: 2017-09-29 01:20:17.551464Z] Running setup-mitmproxy step. 18:20:17 INFO - Running pre-action listener: _resource_record_pre_action 18:20:17 INFO - Running main action method: setup_mitmproxy 18:20:17 INFO - Skipping: mitmproxy is not required 18:20:17 INFO - Running post-action listener: _resource_record_post_action 18:20:17 INFO - [mozharness: 2017-09-29 01:20:17.552671Z] Finished setup-mitmproxy step (success) 18:20:17 INFO - [mozharness: 2017-09-29 01:20:17.552918Z] Running run-tests step. 18:20:17 INFO - Running pre-action listener: _resource_record_pre_action 18:20:17 INFO - Running pre-action listener: _set_gcov_prefix 18:20:17 INFO - Running main action method: run_tests 18:20:17 WARNING - Try message not found. 18:20:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:20:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:20:17 INFO - Python 2.7.3 18:20:17 INFO - Return code: 0 18:20:17 INFO - grabbing minidump binary from tooltool 18:20:17 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')]} 18:20:17 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 0x2afad60>, '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 0x2afba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2afbef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:20:17 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 18:20:17 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 18:20:17 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 18:20:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:20:17 INFO - Return code: 0 18:20:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:20:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:20:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:20:17 INFO - ENV: RUST_BACKTRACE is now full 18:20:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:20:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:20:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 18:20:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 18:20:17 INFO - Using env: {'DISPLAY': ':0', 18:20:17 INFO - 'HOME': '/home/cltbld', 18:20:17 INFO - 'LANG': 'en_US.UTF-8', 18:20:17 INFO - 'LANGUAGE': 'en_US:en', 18:20:17 INFO - 'LOGNAME': 'cltbld', 18:20:17 INFO - 'MAIL': '/var/mail/cltbld', 18:20:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:20:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:20:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:17 INFO - 'MOZ_NO_REMOTE': '1', 18:20:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:20:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:17 INFO - 'NO_EM_RESTART': '1', 18:20:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:17 INFO - 'PWD': '/builds/slave/test', 18:20:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:20:17 INFO - 'RUST_BACKTRACE': 'full', 18:20:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:20:17 INFO - 'SHELL': '/bin/bash', 18:20:17 INFO - 'SHLVL': '1', 18:20:17 INFO - 'TERM': 'linux', 18:20:17 INFO - 'TMOUT': '86400', 18:20:17 INFO - 'USER': 'cltbld', 18:20:17 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', 18:20:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:17 INFO - '_': '/tools/buildbot/bin/python'} 18:20:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 18:20:18 INFO - mozversion application_buildid: 20170928235740 18:20:18 INFO - mozversion application_changeset: e6c32278f32cd5f7d159627b2157396b62d0c4a9 18:20:18 INFO - mozversion application_display_name: Nightly 18:20:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:20:18 INFO - mozversion application_name: Firefox 18:20:18 INFO - mozversion application_remotingname: firefox 18:20:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:20:18 INFO - mozversion application_vendor: Mozilla 18:20:18 INFO - mozversion application_version: 58.0a1 18:20:18 INFO - mozversion platform_buildid: 20170928235740 18:20:18 INFO - mozversion platform_changeset: e6c32278f32cd5f7d159627b2157396b62d0c4a9 18:20:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:20:18 INFO - mozversion platform_version: 58.0a1 18:20:18 INFO - using testdate: 1506648018 18:20:18 INFO - actual date: 1506648018 18:20:18 INFO - starting webserver on 'localhost:35633' 18:20:18 INFO - SUITE-START | Running 2 tests 18:20:18 INFO - TEST-START | dromaeo_css 18:20:18 INFO - Initialising browser for dromaeo_css test... 18:20:18 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35633/getInfo.html -profile /tmp/tmp9KaDEe/profile 18:20:18 INFO - TEST-INFO | started process 3675 (/builds/slave/test/build/application/firefox/firefox http://localhost:35633/getInfo.html) 18:20:24 INFO - TEST-INFO | 3675: exit 0 18:20:25 INFO - Browser initialized. 18:20:25 INFO - Running cycle 1/1 for dromaeo_css test... 18:20:25 INFO - Using env: {'DISPLAY': ':0', 18:20:25 INFO - 'HOME': '/home/cltbld', 18:20:25 INFO - 'JSGC_DISABLE_POISONING': '1', 18:20:25 INFO - 'LANG': 'en_US.UTF-8', 18:20:25 INFO - 'LANGUAGE': 'en_US:en', 18:20:25 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:20:25 INFO - 'LOGNAME': 'cltbld', 18:20:25 INFO - 'MAIL': '/var/mail/cltbld', 18:20:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:20:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:20:25 INFO - 'MOZ_CRASHREPORTER': '1', 18:20:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:25 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:20:25 INFO - 'MOZ_NO_REMOTE': '1', 18:20:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:20:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:25 INFO - 'NO_EM_RESTART': '1', 18:20:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:25 INFO - 'PWD': '/builds/slave/test', 18:20:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:20:25 INFO - 'RUST_BACKTRACE': 'full', 18:20:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:20:25 INFO - 'SHELL': '/bin/bash', 18:20:25 INFO - 'SHLVL': '1', 18:20:25 INFO - 'STYLO_FORCE_DISABLED': '1', 18:20:25 INFO - 'TERM': 'linux', 18:20:25 INFO - 'TMOUT': '86400', 18:20:25 INFO - 'USER': 'cltbld', 18:20:25 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', 18:20:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:25 INFO - '_': '/tools/buildbot/bin/python'} 18:20:25 INFO - TEST-INFO | started process 3919 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9KaDEe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:20:25 INFO - PID 3919 | 18:20:25 INFO - PID 3919 | (/builds/slave/test/build/application/firefox/firefox:3969): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:20:25 INFO - PID 3919 | 18:20:36 INFO - PID 3919 | RSS: Main: 144846848 18:20:36 INFO - PID 3919 | 18:23:32 INFO - PID 3919 | Cycle 1(1): loaded http://localhost:35633/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35633/tests/dromaeo/cssquery-ext.html) 18:23:33 INFO - PID 3919 | RSS: Main: 142737408 18:23:33 INFO - PID 3919 | 18:26:35 INFO - PID 3919 | Cycle 1(1): loaded http://localhost:35633/tests/dromaeo/cssquery-ext.html (next: http://localhost:35633/tests/dromaeo/cssquery-jquery.html) 18:26:36 INFO - PID 3919 | RSS: Main: 143400960 18:26:36 INFO - PID 3919 | 18:29:37 INFO - PID 3919 | Cycle 1(1): loaded http://localhost:35633/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35633/tests/dromaeo/cssquery-mootools.html) 18:29:38 INFO - PID 3919 | RSS: Main: 144625664 18:29:38 INFO - PID 3919 | 18:32:39 INFO - PID 3919 | Cycle 1(1): loaded http://localhost:35633/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35633/tests/dromaeo/cssquery-prototype.html) 18:32:40 INFO - PID 3919 | RSS: Main: 144609280 18:32:40 INFO - PID 3919 | 18:35:43 INFO - PID 3919 | Cycle 1(1): loaded http://localhost:35633/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35633/tests/dromaeo/cssquery-yui.html) 18:35:44 INFO - PID 3919 | RSS: Main: 147492864 18:35:44 INFO - PID 3919 | 18:38:45 INFO - PID 3919 | Cycle 1(1): loaded http://localhost:35633/tests/dromaeo/cssquery-yui.html (next: http://localhost:35633/tests/dromaeo/cssquery-dojo.html) 18:38:45 INFO - PID 3919 | RSS: Main: 148799488 18:38:45 INFO - PID 3919 | 18:38:45 INFO - PID 3919 | __start_tp_report 18:38:45 INFO - PID 3919 | _x_x_mozilla_page_load 18:38:45 INFO - PID 3919 | _x_x_mozilla_page_load_details 18:38:45 INFO - PID 3919 | |i|pagename|runs| 18:38:45 INFO - PID 3919 | |0;dojo.html;3844.058205739593;3844.7500912440705;3849.807509624519;3870.825812838422;3903.4925459690235;6306.180196574439;6334.936650633493;6335;6371.9681401592925;6416.9358306416925;6388.520860935425;6410.07053977173;6432.421082102622;6481.902771458432;6516.380943810331;5657.632253903483;5712.200291959129;5728.885422291552;5744.569157313197;5803.767849286034;179.49030606023655;5647.068233741427;5727.140928860684;5759.222504961839;5762.9135562966585;5651.039323315046;5652.491275785199;5717.971410142944;5718.456746609087;5747.425257474261;5660.066089095336;5677.744501497443;5729.140628905656;5749.9937510935415;5766.826995190151;5659.40576239493;5682.573806964494;5734.426557344233;5746.080627099644;5759.769609215627;6273.12176295319;6292.402221788922;6324.367563243639;6407.615543067384;6409.455196308273;6848.212455567574;6859.2454829968665;6937.618430986294;6940.30596940307;6953.374196322356;6966.825829354256;7032.156141263081;7041.859162816715;7041.894371584431;7064.505484616079;6438.098666186737;6516.152900123001;6519.119918810948;6529;6564.179477565257;7176.712931482786;7178.892316615255;7192.820179495555;7266.781996540112;7279.67241474135;6748.831279218059;6794.694238759268;6818.147731533559;6829.658517074126;6918.654067296615;6833.077534532874;6911.723531058802;6937.965310173417;6942.132233470817;6944.166699995932;6774.390304872585;6852.965735171292;6866.793996180123;6873.553219040747;6896.931030689629;6738.932610673831;6858.794236172923;6983.8603227935155;6996.545224560387;6999.020137180799;5018.824341148043;5079.492050794891;5085.491450854959;5100.719460429712;5101.744912754421;4629.305604159403;4667.136579732693;4702.741349225757;4706.811727530929;4709.387779588632;6185.381461853869;6346.968265158737;6371.267304259976;6443.96778016107;6455.128557644658;6866.141732283465;6885.036094946711;6908.3437073477935;6910.2053263874295;6913.516053876281;6300.905486417708;6317.842053948596;6330.39861213184;6359.618422894641;6368.681565921685;5602.6918519481005;5652.717364131777;5672.631278966854;5674.035413979551;5700.8859822803315;7016.193137789118;7154.642267886585;7156.141263048364;7259.782206533812;7265.818354541073;6814.93185068143;6830.282820303995;6838.658067096625;6911.7926462206215;6971.755988540377;7179.892301615584;7184.604846733271;7213.495055346285;7265.9273407265255;7288.854222915406;6859.931400686129;6871.450283977169;6972.1284839395075;6974.267701891431;6993.0908981832035;6970.651467426507;6984.196817366068;7030;7067.540609860343;7075.540089894346;6600;6676.799696009128;6721.563098398588;6764;6778;6683.097781799394;6732.124823772878;6742.93257067423;6754.729810807709;6782.932170678428;6604.768833090819;6621.304763000008;6692.83267918306;6694.933050669431;6701.530892837454;6652.201735791736;6760.492963027694;6797.048413222252;6879.965600171967;6881.4494840413645;6526.738930442919;6588.7035083422315;6624.0395142705;6630.403263706289;6672.799816005333 18:38:45 INFO - PID 3919 | |1;ext.html;946.271371044296;963.585658166988;993.3692105513;1014.3102690170681;1014.8731408573929;43406.13187736244;43634.78182609087;44424.11151776963;45207.32189017163;45487.545124548706;44834.7758261209;44936.77531612346;44938;44978.77510612443;45383.54616453835;8477.915220847774;8654.394192406535;8659.653613855438;8661.653533858638;8662.350323725714;8434.072252052269;8609.354298427643;8611.181937715906;8624.956875215616;8643.178898004711;8634.956825215864;8643.740687779346;8657.913420865774;8660.047394786568;8679;8616.138386161365;8647.437916535406;8649.524276164808;8650;8651.480911145289;8387.454815436977;8400.41197116202;8402.201790829862;8430.156984301582;8459.788505287355;40711.59288407108;41118.38322425166;41132.38301425481;41190.588094118975;41219.381709274385;3780.092777733352;3780.206156707095;3790.507234059555;3791.0332865119494;3797.7531460454984;33007.83496082504;33020.669793302;33021.6697833021;33097.50353744696;33169.33661326784;5388.865278368033;5418.8916221675345;5426.050441172819;5449.754761035763;5478.698671573102;229671;229794;230002;230248;230488;52279.477205227464;52436.21345679818;52483.47516524862;52508;52596.21105683418;55540.72229638907;57649.71175144098;58693.41306586879;58889;59016;16886.40897568579;17008.149592520323;17016.744748828776;17031.659366812593;17034.31862725502;13986.510472133426;14103;14184;14185.148891066568;14191;16518;16530.504084877473;16536.421225257058;16606.667866642598;16664.250108745135;6314.431701146918;6408.839779005562;6564.409203171642;6578.243501997236;6618.139641846529;1093.6882988348282;1113.638067628008;1115.827046807746;1132.2470557079614;1140.338603609921;26130.86934565353;26154.215373538824;26179.345516362242;26269.474610508067;26393.868030659723;1232.5377983256278;1233.993830030844;1234.4198226833373;1243.1173866554666;1264.0456455376132;13762.724745505035;13953.651158721113;14206.502772403122;14229.50196743109;14329.283535823375;294146;295131;295792;296866;302283;61258.0811287831;61906.0714089289;61993.38006619876;62103;62122.689386553684;1516.067618414667;1524.695060987807;1533.723929692666;1537.9077255364714;1555.8133024037188;28684.282892927007;28801;28996;29651.110466686034;29745.851270743507;42146.7892660547;42272.788636056626;42306;42347.57652423436;42387.152256955305;259985;260154;261044;261444;261872;36260.63739362573;37062.25875482529;37161.4425783608;37595.81202093972;37715.245695086494;45409.31886021778;45713.77143114263;45737;46033;46417.76791116023;33455.66544334526;33627.66372336343;34178.607497474935;34331.970040898814;34381.82809085939;33574;33907.49138762871;34090;34231.144221394665;34700.82649586736;39501.2099758009;39947.2010559793;39989;40158.59841401548;40452;33270.667293326755;33453.33093338071;33626.159346016546;33767.4934875982;34105.82947085348;25786;25933.351666208495;26445.074422395122;26563.468730625667;26902 18:38:45 INFO - PID 3919 | |2;jquery.html;612.23776398384;613.4632196827775;618.9235116185199;627.2942939193407;632.9968350158249;23600.291991240247;23867.283981480563;24265.27204183873;24346.756532434694;24368.878155609218;25019.37451563712;26041.609375859378;26272.211833644975;26325.473490530225;26340.341491462725;6306.968465157668;6468.094466774655;6468.126802881621;6468.191476065492;6481.805545833632;6295.653739044351;6541.9345806541805;6780.559263647847;6790.592564446124;6792.524523283372;6732.730690772363;6754.290799466059;6772.4582033437455;6775.695093720795;6797.592144471323;6714.395704386604;6757.391834734873;6767.086443330186;6782.253952065269;6783.253842077393;6338.492920566344;6358.586691865014;6371.203599550056;6377.872442551123;6378.4578310843635;29277.85361073202;30033.399332013454;30333.696663033308;30472.8476357619;30653.080407587808;2124.904379302935;2162.870227786322;2165.5993641176274;2171.4354267890462;2173.5109600339956;20451.181952721872;20984.16063357464;20993;21012.474688132766;21080.15679372823;2503.0738626708235;2599.8180127391092;2651.5890037044287;2660.9467810643678;2671.9866400668066;144517;149148;158319;165309;166465.3353466462;15674.608134796721;16134.596635083983;16209.756853647204;16346.264418101215;16952;26092;26211.606825897627;26222.606660900103;26239.606405903927;26265;10734.57061717538;10844.403557804397;10848.67453976382;10993.835092473619;11103.333799972028;9848.852267215998;9944.453055081894;10063.892971773099;10126.189904807745;10126.797464050826;9776.67419562325;9855.162311203629;9951;9977.900220997843;10067.345622534513;3030.5908702325346;3071.616047994001;3074.738647214967;3079.59965204522;3092.876284948622;1060.5704689600725;1068.834330678746;1080.2600218850114;1082.1234799812178;1088.5645741703381;21013.264535741175;21250.89374553117;21510.13959441636;21660.35018949434;21704.89147554252;1129.5425352731993;1138.703936976392;1144.6451600004025;1151.694800877768;1164.0512981919708;11548.307101573933;11709.765804683859;11803.291802491878;11879.40602969848;11949.283043017447;594940;600991;609638;618485;639238;131699;131858.34070829584;132132;134469;136102;1136.4772204785727;1185.9407029648482;1235.4872727818035;1269.7270086931169;1295.1387327427344;21221.89389053045;21974;21986.45033874166;22128.557428851655;22364.88817555902;62237;62466;62858.685706571174;63073;63669;78786.21213787788;78834.21165788268;79056;79729.60135199287;80371;15295;15405.691886162438;15410.075395476308;15496.302666379814;15719.292631831366;23128.306150815504;23163.4209144766;23202;23244.53510929738;23571.41071473227;18878.71681924745;19571.608567828847;19900.50248743793;20026.699599505726;20035.0984205714;17285.481435556954;17537.386191483238;17628.471145865646;17900.820991790435;17921.37275195362;18038.72941905846;18810.24759009624;19060.142293596546;19187.520311992314;19190;15392.384304627689;15519.612009699847;15615.765763513784;15666.686666266383;15680.686386271982;18361.540961475537;18391;18397.172127254038;18640.440786776417;18642.533936651693 18:38:45 INFO - PID 3919 | |3;mootools.html;1426.0801782850062;1513.5761986643743;1514.4018112845426;1523.6419441431258;1526.1682383101208;16898.662026759474;17476.213570389333;17566.560835979108;17575.73636395455;17593.648127037468;17130.31478740849;17153.485395438125;17167.57081072974;17195.914020429882;17240.741388879145;2658.1626787561954;2703.0944633547783;2716.8641567921622;2722.5706504085315;2728.154272175632;2641.709411964682;2716.1579910227892;2719.524083285427;2722.823016503921;2729.686086100104;2628.053900595784;2680.490706765718;2680.6917204521533;2681.168837660322;2681.6781986161686;2512.2337687005456;2543.465872166847;2546.923592292225;2550.9744902551106;2553.323369307135;2469.4567195217023;2530.9493810123695;2537.9111731089506;2540.9872950635126;2541.2376287113793;16704.83295167045;16796.496105116745;16803.91598042014;16830.915845420815;16921.06934118623;8392.20274073962;8647.135286471366;8736.956315218447;8737.956310218407;8753.343499237519;5039.269305950654;5184.403793563751;5241.965513646116;5295.788168473295;5342.786288548454;2149.8925053747407;2150.2474134053114;2188.9890550547148;2204.867707937529;2229.331200639818;27622.585661215096;28405.431891362263;30650.693493065006;31451.685483145106;31478.842605787053;7558.055243094614;7562.735304264324;7627.733029343946;7794.571298578632;7861.331786798072;23252.3024309271;23295.41761455977;23418.53162936766;23431.53136937252;23462.530749384918;8502.787430314167;8611.827763444697;8626.094260102724;8630.309575234092;8639.654413823502;7425.368843648351;7784.104827944746;7820.804479888048;7835.059792824897;7899.960500197462;5688.345840228344;5881.323647780475;5907.793227237112;5943.910841337383;5967.522598192134;1899.8005209453086;1903.4384856467375;1905.2093381246627;1924.6728056230475;1948.2888745608034;959.29971121082;962.1220412508515;963.9396663670047;988.8467287570438;990.980180396388;8519.403641745143;8585.18440748128;8629.43908645936;8652.134786521297;8733.25767309773;790.8655528560158;801.2548330053106;812.463773909217;814.9755507334789;816.7345612675786;5116.258142569381;5140.434552199255;5220.92168617471;5281.656692314988;5313.760880760375;24275;24530.632040519406;25717.742822571534;25728;25889.22332330033;25380.238592842245;25504.74495255061;25633.231003069937;25690;25690.972361105345;11623.941880290713;12401.193922395016;12443.875561244271;12528.123031387715;12609.810852837032;9560.521973901416;9637.662681806103;9685.467299298607;9716.22270218395;9739.61041558358;20531.794682052987;20534.69197962002;20913.477163071046;21318.78681213168;21591.13635454564;22149.778502214773;22161.667574986066;22184.33446996593;22280.888595556917;22609.208677696202;13473.663158421117;13488.932555337553;13536.120152190077;13551.119177253448;13714.314284285547;15207.239638018276;15401.922990384975;15411.691766164839;15413;15414.46049388266;12405.193662412275;12647;12864.871351286367;12925.806112908125;13012;11984.955373578261;12521.686957825763;12526.37368131608;12573.308468034345;12650.936745316214;10857.239993200401;10983.61557345489;11026.117910567296;11042.227044106836;11075.889241107487;8383.664653413794;8481.236688698047;8525.573721314033;8532.573371331284;8562.828743424972;8220;8229.300509456763;8291.08798032228;8314.127016663164;8327.458715183493 18:38:45 INFO - PID 3919 | |4;prototype.html;544.7113030094051;559.8796258804357;562.3280180184676;582.6620560075157;587.588687918457;6286.622802631845;6336.334684858092;6343.175387199669;6348.301686814452;6385.712642931067;6202.937970620293;6267.493002798883;6284.717187726552;6298.449128103126;6301.590396624228;4949.4060712714345;5159.974200129013;5227.607929405291;5248.580113590903;5254.4220135785035;5090.669106508084;5142.511461411179;5153.510416510426;5155.5360017598405;5164.612654050961;5087.974560127194;5093.2105523643895;5102.1836506159025;5123.5901127909865;5141.434442211354;5074.213496907985;5080.41575218849;5083.847484575469;5112.795488180487;5112.872178195537;4684.36761037259;4705.588323502935;4738.455077666078;4744.572988431058;4759.690620109682;6352.682365881734;6381.904271435886;6397.584157029824;6406.93593064068;6425.550211485198;5980.760769569212;6000.459958603703;6029.728662210167;6051.213342265477;6114.144019837227;6683.465322774166;6716.93283067168;6755.493337999621;6772.830679233059;6778.966105169492;5217.295665085203;5235.712035838065;5240.580753539746;5243.711595862226;5248.475152484759;7126.821829454253;7145.4283657307815;7147.463940204453;7151.82120446993;7181.605011724353;5333.493318134849;5345.305110335631;5406.675599464044;5412.377576578665;5416.350037995466;5430.402655707869;5440.721430463838;5462.9513932275395;5470.835874923679;5478.904219156105;5963.5527335450015;6016.819495415145;6018.097285407224;6046.606970546901;6051.939480605226;6332.240131184288;6472.611643301417;6561;6673;6719.798406047827;1839.1999480226145;1844.483544607512;1855.369174480656;1863.2453856188265;1863.2733234038735;1296.6628676543978;1334.652990222549;1336.1448672849385;1346.7273426611757;1366.7403193393422;6079.392060793885;6187.16473276102;6196.81409557714;6228.750849965951;6270.843228919313;6831.4534837212905;6850.417714494224;6860.461629197229;6916.20463646677;6916.757913473004;6077.969610151921;6128.938710612837;6215.533834962396;6229.127922090911;6245.406686364788;3517.278957813642;3608.332458495187;3615.1504396467335;3625.2386998730035;3658.5061016762397;7111.7866464006165;7135.607541585159;7161.856762864818;7166.462515311373;7199.172095209014;7037.261087585832;7051.365377115982;7076.150861896605;7156.570605763671;7173;6983.162020557363;7029.8242543935785;7042.859142817115;7140.286331280456;7151.499395042297;6761.222459417033;6773.525853190229;6793;6795.063406929002;6879.965600171967;6723.899141512783;6759.594424334556;6820.215675197416;6852.828679283058;6893.689783959834;7001.859962800613;7018.964905175441;7172.7089123958185;7178;7212.855742885218;5296.0934063737795;5348.486211480598;5363.249145119608;5377.704226267437;5379.488948549882;6618.867622647616;6868.587884726932;6902.206246281541;6938.202106757585;6987.231404545394;6309.589876658186;6560.539797575909;6570.211574611078;6617.735290588323;6698.66506674674;6542.640154791532;6649.168853893263;6679.56582822115;6680.966595166993;6686.53194276396;6398.328175541687;6454.967205247138;6464.834280515892;6491.993740970157;6557.704903279268;6681.461044802943;6717.664116794043;6720.663966801542;6763.6618169092335;6812.318768123347;6502.057201705821;6512.5766825156215;6514.902276465762;6517.804465866032;6578.407943285128 18:38:45 INFO - PID 3919 | |5;yui.html;945.5697657565809;971.7667759737662;988.545269176179;989.1740396768698;1005.8843233028199;21178;21757.12971481141;21787.782122178774;21793.891030544844;21797.564048719018;19127.904360478176;19526.12132454043;19803.504912377197;19845.20619175235;19947.002649867485;1048.140524769691;1067.0076828549445;1070.2187403195671;1070.5289672544095;1071.3946620159625;1047.6280920273307;1074.382230217628;1075.639660713662;1084.5499117866077;1087.5377964365152;1143.2454579977216;1144.130460849752;1146.7993101207255;1149.4310316393355;1150.8503894493704;1063.0751246415668;1110.7611863449397;1126.6563698072084;1139.4644517077002;1140.0366690146814;814.267159556398;817.2603793566832;820.2371794231362;820.4379999700164;823.074041703084;21502;21675.566488670294;21722.23972160967;21724.348269551938;21728.34814955554;21807.67288490674;22711.09155633789;22751.544969100527;22887;22893.542129157322;18258.269669213216;18501.907490462596;18646.44060678182;18650.720239196424;18655.53361165968;3955.6044395560502;3979.283728928792;3983.083890705154;3984.302747019289;3987.063040185554;160771;162951;163431;163593;165487;17523.12384380776;17589.560260993578;17960.910195448938;18019.459416217534;18153.364632237808;32500.187495312806;33038.66961330404;33282;34617.134571635404;34932;9239;9299.302552308603;9301.34890557668;9324.300677449082;9324.440533568008;4664.533546645376;4725.763711814396;4821.879453013703;4825.5898248649255;4825.686330388584;6071.817845464643;6204.689765511706;6245.594036387621;6246.531510136758;6265.561410701298;1094.190299178618;1105.4693747001484;1111.766529028897;1129.249049382168;1132.2923173016864;698.5983059740568;715.2811424518429;720.5424555407354;734.4895297768106;744.9106107267054;12619.78917105882;12907.612771616867;13187.406566704522;13780;14131.78802317966;698.5121690798567;700.471144286071;710.2258538193394;712.7434123715458;736.8415790605046;6176.722047507873;6210.006398976142;6217.782377616762;6254.562180647402;6310.779122730774;140185;141137.29431352983;143223;147400;148781;130515.34742326228;132363;132370.33814830866;134432;136602;101648.49175754075;103560;104627;105220;106218.99999999846;11329.886701133213;11421;11457.656270311903;11625.302481851117;11628.88371116278;54940.45059549353;55411;56267.43732562622;56480.717596411756;57106;69319.65340173266;71400;72035.27964720286;72243;74426.88359674501;16435;16658.50024499267;16715.91642041782;16727.66544669124;16754.916225418794;17260.82739172592;17723.02523361176;17957.102144892964;18007.81992180114;18199.181036853108;16766.413175538797;16924.153792310583;16970.830291696926;17039.659206815548;17112.229949652046;16741.49775506737;16834.831651683326;16839.15804209809;17076.487705368858;17087.829121708623;16551;16718;16925.238364273882;16948.32206711767;17004.829951700325;13077.803832942704;13128.934355328161;13129.934350328187;13406;13535.864641353854;13503.45986160582;13756.931215343859;13820.37808298649;13827.51603693866;13881.514147004806 18:38:45 INFO - PID 3919 | __end_tp_report 18:38:45 INFO - PID 3919 | __start_cc_report 18:38:45 INFO - PID 3919 | _x_x_mozilla_cycle_collect,274 18:38:45 INFO - PID 3919 | __end_cc_report 18:38:45 INFO - PID 3919 | __startTimestamp1506649125851__endTimestamp 18:38:45 INFO - PID 3919 | 18:38:45 INFO - PID 3919 | ------- Summary: start ------- 18:38:45 INFO - PID 3919 | Number of tests: 6 18:38:45 INFO - PID 3919 | 18:38:45 INFO - PID 3919 | [#0] dojo.html Cycles:165 Average:6345.72 Median:6652.20 stddev:904.30 (13.6%) stddev-sans-first:885.52 18:38:45 INFO - PID 3919 | Values: 3844.1 3844.8 3849.8 3870.8 3903.5 6306.2 6334.9 6335.0 6372.0 6416.9 6388.5 6410.1 6432.4 6481.9 6516.4 5657.6 5712.2 5728.9 5744.6 5803.8 179.5 5647.1 5727.1 5759.2 5762.9 5651.0 5652.5 5718.0 5718.5 5747.4 5660.1 5677.7 5729.1 5750.0 5766.8 5659.4 5682.6 5734.4 5746.1 5759.8 6273.1 6292.4 6324.4 6407.6 6409.5 6848.2 6859.2 6937.6 6940.3 6953.4 6966.8 7032.2 7041.9 7041.9 7064.5 6438.1 6516.2 6519.1 6529.0 6564.2 7176.7 7178.9 7192.8 7266.8 7279.7 6748.8 6794.7 6818.1 6829.7 6918.7 6833.1 6911.7 6938.0 6942.1 6944.2 6774.4 6853.0 6866.8 6873.6 6896.9 6738.9 6858.8 6983.9 6996.5 6999.0 5018.8 5079.5 5085.5 5100.7 5101.7 4629.3 4667.1 4702.7 4706.8 4709.4 6185.4 6347.0 6371.3 6444.0 6455.1 6866.1 6885.0 6908.3 6910.2 6913.5 6300.9 6317.8 6330.4 6359.6 6368.7 5602.7 5652.7 5672.6 5674.0 5700.9 7016.2 7154.6 7156.1 7259.8 7265.8 6814.9 6830.3 6838.7 6911.8 6971.8 7179.9 7184.6 7213.5 7265.9 7288.9 6859.9 6871.5 6972.1 6974.3 6993.1 6970.7 6984.2 7030.0 7067.5 7075.5 6600.0 6676.8 6721.6 6764.0 6778.0 6683.1 6732.1 6742.9 6754.7 6782.9 6604.8 6621.3 6692.8 6694.9 6701.5 6652.2 6760.5 6797.0 6880.0 6881.4 6526.7 6588.7 6624.0 6630.4 6672.8 18:38:45 INFO - PID 3919 | 18:38:45 INFO - PID 3919 | [#1] ext.html Cycles:180 Average:44382.41 Median:37655.53 stddev:68687.36 (182.4%) stddev-sans-first:68802.62 18:38:45 INFO - PID 3919 | Values: 946.3 963.6 993.4 1014.3 1014.9 43406.1 43634.8 44424.1 45207.3 45487.5 44834.8 44936.8 44938.0 44978.8 45383.5 8477.9 8654.4 8659.7 8661.7 8662.4 8434.1 8609.4 8611.2 8625.0 8643.2 8635.0 8643.7 8657.9 8660.0 8679.0 8616.1 8647.4 8649.5 8650.0 8651.5 8387.5 8400.4 8402.2 8430.2 8459.8 40711.6 41118.4 41132.4 41190.6 41219.4 3780.1 3780.2 3790.5 3791.0 3797.8 33007.8 33020.7 33021.7 33097.5 33169.3 5388.9 5418.9 5426.1 5449.8 5478.7 229671.0 229794.0 230002.0 230248.0 230488.0 52279.5 52436.2 52483.5 52508.0 52596.2 55540.7 57649.7 58693.4 58889.0 59016.0 16886.4 17008.1 17016.7 17031.7 17034.3 13986.5 14103.0 14184.0 14185.1 14191.0 16518.0 16530.5 16536.4 16606.7 16664.3 6314.4 6408.8 6564.4 6578.2 6618.1 1093.7 1113.6 1115.8 1132.2 1140.3 26130.9 26154.2 26179.3 26269.5 26393.9 1232.5 1234.0 1234.4 1243.1 1264.0 13762.7 13953.7 14206.5 14229.5 14329.3 294146.0 295131.0 295792.0 296866.0 302283.0 61258.1 61906.1 61993.4 62103.0 62122.7 1516.1 1524.7 1533.7 1537.9 1555.8 28684.3 28801.0 28996.0 29651.1 29745.9 42146.8 42272.8 42306.0 42347.6 42387.2 259985.0 260154.0 261044.0 261444.0 261872.0 36260.6 37062.3 37161.4 37595.8 37715.2 45409.3 45713.8 45737.0 46033.0 46417.8 33455.7 33627.7 34178.6 34332.0 34381.8 33574.0 33907.5 34090.0 34231.1 34700.8 39501.2 39947.2 39989.0 40158.6 40452.0 33270.7 33453.3 33626.2 33767.5 34105.8 25786.0 25933.4 26445.1 26563.5 26902.0 18:38:45 INFO - PID 3919 | 18:38:45 INFO - PID 3919 | [#2] jquery.html Cycles:180 Average:40232.18 Median:21980.23 stddev:102699.50 (467.2%) stddev-sans-first:102944.51 18:38:45 INFO - PID 3919 | Values: 612.2 613.5 618.9 627.3 633.0 23600.3 23867.3 24265.3 24346.8 24368.9 25019.4 26041.6 26272.2 26325.5 26340.3 6307.0 6468.1 6468.1 6468.2 6481.8 6295.7 6541.9 6780.6 6790.6 6792.5 6732.7 6754.3 6772.5 6775.7 6797.6 6714.4 6757.4 6767.1 6782.3 6783.3 6338.5 6358.6 6371.2 6377.9 6378.5 29277.9 30033.4 30333.7 30472.8 30653.1 2124.9 2162.9 2165.6 2171.4 2173.5 20451.2 20984.2 20993.0 21012.5 21080.2 2503.1 2599.8 2651.6 2660.9 2672.0 144517.0 149148.0 158319.0 165309.0 166465.3 15674.6 16134.6 16209.8 16346.3 16952.0 26092.0 26211.6 26222.6 26239.6 26265.0 10734.6 10844.4 10848.7 10993.8 11103.3 9848.9 9944.5 10063.9 10126.2 10126.8 9776.7 9855.2 9951.0 9977.9 10067.3 3030.6 3071.6 3074.7 3079.6 3092.9 1060.6 1068.8 1080.3 1082.1 1088.6 21013.3 21250.9 21510.1 21660.4 21704.9 1129.5 1138.7 1144.6 1151.7 1164.1 11548.3 11709.8 11803.3 11879.4 11949.3 594940.0 600991.0 609638.0 618485.0 639238.0 131699.0 131858.3 132132.0 134469.0 136102.0 1136.5 1185.9 1235.5 1269.7 1295.1 21221.9 21974.0 21986.5 22128.6 22364.9 62237.0 62466.0 62858.7 63073.0 63669.0 78786.2 78834.2 79056.0 79729.6 80371.0 15295.0 15405.7 15410.1 15496.3 15719.3 23128.3 23163.4 23202.0 23244.5 23571.4 18878.7 19571.6 19900.5 20026.7 20035.1 17285.5 17537.4 17628.5 17900.8 17921.4 18038.7 18810.2 19060.1 19187.5 19190.0 15392.4 15519.6 15615.8 15666.7 15680.7 18361.5 18391.0 18397.2 18640.4 18642.5 18:38:45 INFO - PID 3919 | 18:38:45 INFO - PID 3919 | [#3] mootools.html Cycles:180 Average:10578.59 Median:25690.49 stddev:7924.61 (30.8%) stddev-sans-first:7917.00 18:38:45 INFO - PID 3919 | Values: 1426.1 1513.6 1514.4 1523.6 1526.2 16898.7 17476.2 17566.6 17575.7 17593.6 17130.3 17153.5 17167.6 17195.9 17240.7 2658.2 2703.1 2716.9 2722.6 2728.2 2641.7 2716.2 2719.5 2722.8 2729.7 2628.1 2680.5 2680.7 2681.2 2681.7 2512.2 2543.5 2546.9 2551.0 2553.3 2469.5 2530.9 2537.9 2541.0 2541.2 16704.8 16796.5 16803.9 16830.9 16921.1 8392.2 8647.1 8737.0 8738.0 8753.3 5039.3 5184.4 5242.0 5295.8 5342.8 2149.9 2150.2 2189.0 2204.9 2229.3 27622.6 28405.4 30650.7 31451.7 31478.8 7558.1 7562.7 7627.7 7794.6 7861.3 23252.3 23295.4 23418.5 23431.5 23462.5 8502.8 8611.8 8626.1 8630.3 8639.7 7425.4 7784.1 7820.8 7835.1 7900.0 5688.3 5881.3 5907.8 5943.9 5967.5 1899.8 1903.4 1905.2 1924.7 1948.3 959.3 962.1 963.9 988.8 991.0 8519.4 8585.2 8629.4 8652.1 8733.3 790.9 801.3 812.5 815.0 816.7 5116.3 5140.4 5220.9 5281.7 5313.8 24275.0 24530.6 25717.7 25728.0 25889.2 25380.2 25504.7 25633.2 25690.0 25691.0 11623.9 12401.2 12443.9 12528.1 12609.8 9560.5 9637.7 9685.5 9716.2 9739.6 20531.8 20534.7 20913.5 21318.8 21591.1 22149.8 22161.7 22184.3 22280.9 22609.2 13473.7 13488.9 13536.1 13551.1 13714.3 15207.2 15401.9 15411.7 15413.0 15414.5 12405.2 12647.0 12864.9 12925.8 13012.0 11985.0 12521.7 12526.4 12573.3 12650.9 10857.2 10983.6 11026.1 11042.2 11075.9 8383.7 8481.2 8525.6 8532.6 8562.8 8220.0 8229.3 8291.1 8314.1 8327.5 18:38:45 INFO - PID 3919 | 18:38:45 INFO - PID 3919 | [#4] prototype.html Cycles:180 Average:5739.73 Median:6305.59 stddev:1588.42 (25.2%) stddev-sans-first:1544.27 18:38:45 INFO - PID 3919 | Values: 544.7 559.9 562.3 582.7 587.6 6286.6 6336.3 6343.2 6348.3 6385.7 6202.9 6267.5 6284.7 6298.4 6301.6 4949.4 5160.0 5227.6 5248.6 5254.4 5090.7 5142.5 5153.5 5155.5 5164.6 5088.0 5093.2 5102.2 5123.6 5141.4 5074.2 5080.4 5083.8 5112.8 5112.9 4684.4 4705.6 4738.5 4744.6 4759.7 6352.7 6381.9 6397.6 6406.9 6425.6 5980.8 6000.5 6029.7 6051.2 6114.1 6683.5 6716.9 6755.5 6772.8 6779.0 5217.3 5235.7 5240.6 5243.7 5248.5 7126.8 7145.4 7147.5 7151.8 7181.6 5333.5 5345.3 5406.7 5412.4 5416.4 5430.4 5440.7 5463.0 5470.8 5478.9 5963.6 6016.8 6018.1 6046.6 6051.9 6332.2 6472.6 6561.0 6673.0 6719.8 1839.2 1844.5 1855.4 1863.2 1863.3 1296.7 1334.7 1336.1 1346.7 1366.7 6079.4 6187.2 6196.8 6228.8 6270.8 6831.5 6850.4 6860.5 6916.2 6916.8 6078.0 6128.9 6215.5 6229.1 6245.4 3517.3 3608.3 3615.2 3625.2 3658.5 7111.8 7135.6 7161.9 7166.5 7199.2 7037.3 7051.4 7076.2 7156.6 7173.0 6983.2 7029.8 7042.9 7140.3 7151.5 6761.2 6773.5 6793.0 6795.1 6880.0 6723.9 6759.6 6820.2 6852.8 6893.7 7001.9 7019.0 7172.7 7178.0 7212.9 5296.1 5348.5 5363.2 5377.7 5379.5 6618.9 6868.6 6902.2 6938.2 6987.2 6309.6 6560.5 6570.2 6617.7 6698.7 6542.6 6649.2 6679.6 6681.0 6686.5 6398.3 6455.0 6464.8 6492.0 6557.7 6681.5 6717.7 6720.7 6763.7 6812.3 6502.1 6512.6 6514.9 6517.8 6578.4 18:38:45 INFO - PID 3919 | 18:38:45 INFO - PID 3919 | [#5] yui.html Cycles:180 Average:27909.89 Median:17959.01 stddev:41721.27 (232.3%) stddev-sans-first:41789.18 18:38:45 INFO - PID 3919 | Values: 945.6 971.8 988.5 989.2 1005.9 21178.0 21757.1 21787.8 21793.9 21797.6 19127.9 19526.1 19803.5 19845.2 19947.0 1048.1 1067.0 1070.2 1070.5 1071.4 1047.6 1074.4 1075.6 1084.5 1087.5 1143.2 1144.1 1146.8 1149.4 1150.9 1063.1 1110.8 1126.7 1139.5 1140.0 814.3 817.3 820.2 820.4 823.1 21502.0 21675.6 21722.2 21724.3 21728.3 21807.7 22711.1 22751.5 22887.0 22893.5 18258.3 18501.9 18646.4 18650.7 18655.5 3955.6 3979.3 3983.1 3984.3 3987.1 160771.0 162951.0 163431.0 163593.0 165487.0 17523.1 17589.6 17960.9 18019.5 18153.4 32500.2 33038.7 33282.0 34617.1 34932.0 9239.0 9299.3 9301.3 9324.3 9324.4 4664.5 4725.8 4821.9 4825.6 4825.7 6071.8 6204.7 6245.6 6246.5 6265.6 1094.2 1105.5 1111.8 1129.2 1132.3 698.6 715.3 720.5 734.5 744.9 12619.8 12907.6 13187.4 13780.0 14131.8 698.5 700.5 710.2 712.7 736.8 6176.7 6210.0 6217.8 6254.6 6310.8 140185.0 141137.3 143223.0 147400.0 148781.0 130515.3 132363.0 132370.3 134432.0 136602.0 101648.5 103560.0 104627.0 105220.0 106219.0 11329.9 11421.0 11457.7 11625.3 11628.9 54940.5 55411.0 56267.4 56480.7 57106.0 69319.7 71400.0 72035.3 72243.0 74426.9 16435.0 16658.5 16715.9 16727.7 16754.9 17260.8 17723.0 17957.1 18007.8 18199.2 16766.4 16924.2 16970.8 17039.7 17112.2 16741.5 16834.8 16839.2 17076.5 17087.8 16551.0 16718.0 16925.2 16948.3 17004.8 13077.8 13128.9 13129.9 13406.0 13535.9 13503.5 13756.9 13820.4 13827.5 13881.5 18:38:45 INFO - PID 3919 | -------- Summary: end -------- 18:38:45 INFO - PID 3919 | 18:38:46 INFO - TEST-INFO | 3919: exit 0 18:38:46 INFO - TEST-OK | dromaeo_css | took 1108590ms 18:38:46 INFO - TEST-START | kraken 18:38:46 INFO - Initialising browser for kraken test... 18:38:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35633/getInfo.html -profile /tmp/tmpf0euYa/profile 18:38:46 INFO - TEST-INFO | started process 4292 (/builds/slave/test/build/application/firefox/firefox http://localhost:35633/getInfo.html) 18:38:52 INFO - TEST-INFO | 4292: exit 0 18:38:52 INFO - Browser initialized. 18:38:52 INFO - Running cycle 1/1 for kraken test... 18:38:52 INFO - Using env: {'DISPLAY': ':0', 18:38:52 INFO - 'HOME': '/home/cltbld', 18:38:52 INFO - 'JSGC_DISABLE_POISONING': '1', 18:38:52 INFO - 'LANG': 'en_US.UTF-8', 18:38:52 INFO - 'LANGUAGE': 'en_US:en', 18:38:52 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:38:52 INFO - 'LOGNAME': 'cltbld', 18:38:52 INFO - 'MAIL': '/var/mail/cltbld', 18:38:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:38:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:38:52 INFO - 'MOZ_CRASHREPORTER': '1', 18:38:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:52 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:38:52 INFO - 'MOZ_NO_REMOTE': '1', 18:38:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:38:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:38:52 INFO - 'NO_EM_RESTART': '1', 18:38:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:38:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:52 INFO - 'PWD': '/builds/slave/test', 18:38:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:38:52 INFO - 'RUST_BACKTRACE': 'full', 18:38:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:38:52 INFO - 'SHELL': '/bin/bash', 18:38:52 INFO - 'SHLVL': '1', 18:38:52 INFO - 'STYLO_FORCE_DISABLED': '1', 18:38:52 INFO - 'TERM': 'linux', 18:38:52 INFO - 'TMOUT': '86400', 18:38:52 INFO - 'USER': 'cltbld', 18:38:52 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183', 18:38:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:52 INFO - '_': '/tools/buildbot/bin/python'} 18:38:52 INFO - TEST-INFO | started process 4527 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf0euYa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:38:52 INFO - PID 4527 | 18:38:52 INFO - PID 4527 | (/builds/slave/test/build/application/firefox/firefox:4577): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:38:52 INFO - PID 4527 | 18:39:03 INFO - PID 4527 | RSS: Main: 144838656 18:39:03 INFO - PID 4527 | 18:39:05 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:35633/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35633/tests/kraken/driver.html?testName=audio-beat-detection) 18:39:06 INFO - PID 4527 | RSS: Main: 150573056 18:39:06 INFO - PID 4527 | 18:39:09 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:35633/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35633/tests/kraken/driver.html?testName=audio-dft) 18:39:09 INFO - PID 4527 | RSS: Main: 151027712 18:39:09 INFO - PID 4527 | 18:39:12 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:35633/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35633/tests/kraken/driver.html?testName=audio-fft) 18:39:13 INFO - PID 4527 | RSS: Main: 147664896 18:39:13 INFO - PID 4527 | 18:39:15 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:35633/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35633/tests/kraken/driver.html?testName=audio-oscillator) 18:39:15 INFO - PID 4527 | RSS: Main: 148832256 18:39:15 INFO - PID 4527 | 18:39:18 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:35633/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35633/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:39:18 INFO - PID 4527 | RSS: Main: 148443136 18:39:18 INFO - PID 4527 | 18:39:21 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:35633/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35633/tests/kraken/driver.html?testName=imaging-darkroom) 18:39:22 INFO - PID 4527 | RSS: Main: 148332544 18:39:22 INFO - PID 4527 | 18:39:25 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:35633/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35633/tests/kraken/driver.html?testName=imaging-desaturate) 18:39:26 INFO - PID 4527 | RSS: Main: 150474752 18:39:26 INFO - PID 4527 | 18:39:29 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:35633/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35633/tests/kraken/driver.html?testName=json-parse-financial) 18:39:30 INFO - PID 4527 | RSS: Main: 151035904 18:39:30 INFO - PID 4527 | 18:39:31 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:35633/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35633/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:39:32 INFO - PID 4527 | RSS: Main: 151068672 18:39:32 INFO - PID 4527 | 18:39:33 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:35633/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35633/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:39:34 INFO - PID 4527 | RSS: Main: 150679552 18:39:34 INFO - PID 4527 | 18:39:36 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:35633/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35633/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:39:37 INFO - PID 4527 | RSS: Main: 147591168 18:39:37 INFO - PID 4527 | 18:39:39 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:35633/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35633/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:39:40 INFO - PID 4527 | RSS: Main: 147812352 18:39:40 INFO - PID 4527 | 18:39:42 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:35633/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35633/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:39:43 INFO - PID 4527 | RSS: Main: 148426752 18:39:43 INFO - PID 4527 | 18:39:45 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:35633/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35633/tests/kraken/driver.html?testName=ai-astar) 18:39:45 INFO - PID 4527 | RSS: Main: 150155264 18:39:45 INFO - PID 4527 | 18:39:45 INFO - PID 4527 | __start_tp_report 18:39:45 INFO - PID 4527 | _x_x_mozilla_page_load 18:39:45 INFO - PID 4527 | _x_x_mozilla_page_load_details 18:39:45 INFO - PID 4527 | |i|pagename|runs| 18:39:45 INFO - PID 4527 | |0;ai-astar;105;106;105;106;106;107;107;106;107;105 18:39:45 INFO - PID 4527 | |1;audio-beat-detection;114;114;111;115;117;115;117;112;112;138 18:39:45 INFO - PID 4527 | |2;audio-dft;163;164;162;162;163;162;161;162;166;170 18:39:45 INFO - PID 4527 | |3;audio-fft;73;72;72;75;76;76;75;72;72;73 18:39:45 INFO - PID 4527 | |4;audio-oscillator;82;79;78;82;84;81;83;81;82;78 18:39:45 INFO - PID 4527 | |5;imaging-gaussian-blur;118;111;117;111;111;111;111;111;111;117 18:39:45 INFO - PID 4527 | |6;imaging-darkroom;148;151;150;151;153;149;151;150;152;151 18:39:45 INFO - PID 4527 | |7;imaging-desaturate;93;95;95;94;94;94;94;93;94;94 18:39:45 INFO - PID 4527 | |8;json-parse-financial;47;46;42;43;43;45;45;51;44;42 18:39:45 INFO - PID 4527 | |9;json-stringify-tinderbox;39;38;38;38;38;41;39;41;39;39 18:39:45 INFO - PID 4527 | |10;stanford-crypto-aes;76;72;71;71;73;70;71;75;71;73 18:39:45 INFO - PID 4527 | |11;stanford-crypto-ccm;102;114;110;103;104;108;105;102;106;104 18:39:45 INFO - PID 4527 | |12;stanford-crypto-pbkdf2;158;150;147;161;152;150;151;154;157;157 18:39:45 INFO - PID 4527 | |13;stanford-crypto-sha256-iterative;59;59;65;59;60;59;58;64;64;59 18:39:45 INFO - PID 4527 | __end_tp_report 18:39:45 INFO - PID 4527 | __start_cc_report 18:39:45 INFO - PID 4527 | _x_x_mozilla_cycle_collect,700 18:39:45 INFO - PID 4527 | __end_cc_report 18:39:45 INFO - PID 4527 | __startTimestamp1506649185342__endTimestamp 18:39:45 INFO - PID 4527 | 18:39:45 INFO - PID 4527 | ------- Summary: start ------- 18:39:45 INFO - PID 4527 | Number of tests: 14 18:39:45 INFO - PID 4527 | 18:39:45 INFO - PID 4527 | [#0] ai-astar Cycles:10 Average:106.00 Median:106.00 stddev:0.82 (0.8%) stddev-sans-first:0.78 18:39:45 INFO - PID 4527 | Values: 105.0 106.0 105.0 106.0 106.0 107.0 107.0 106.0 107.0 105.0 18:39:45 INFO - PID 4527 | 18:39:45 INFO - PID 4527 | [#1] audio-beat-detection Cycles:10 Average:116.50 Median:115.00 stddev:7.82 (6.8%) stddev-sans-first:8.24 18:39:45 INFO - PID 4527 | Values: 114.0 114.0 111.0 115.0 117.0 115.0 117.0 112.0 112.0 138.0 18:39:45 INFO - PID 4527 | 18:39:45 INFO - PID 4527 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.00 stddev:2.68 (1.6%) stddev-sans-first:2.83 18:39:45 INFO - PID 4527 | Values: 163.0 164.0 162.0 162.0 163.0 162.0 161.0 162.0 166.0 170.0 18:39:45 INFO - PID 4527 | 18:39:45 INFO - PID 4527 | [#3] audio-fft Cycles:10 Average:73.60 Median:74.00 stddev:1.71 (2.3%) stddev-sans-first:1.80 18:39:45 INFO - PID 4527 | Values: 73.0 72.0 72.0 75.0 76.0 76.0 75.0 72.0 72.0 73.0 18:39:45 INFO - PID 4527 | 18:39:45 INFO - PID 4527 | [#4] audio-oscillator Cycles:10 Average:81.00 Median:82.00 stddev:2.05 (2.5%) stddev-sans-first:2.15 18:39:45 INFO - PID 4527 | Values: 82.0 79.0 78.0 82.0 84.0 81.0 83.0 81.0 82.0 78.0 18:39:45 INFO - PID 4527 | 18:39:45 INFO - PID 4527 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:111.00 stddev:3.07 (2.8%) stddev-sans-first:2.65 18:39:45 INFO - PID 4527 | Values: 118.0 111.0 117.0 111.0 111.0 111.0 111.0 111.0 111.0 117.0 18:39:45 INFO - PID 4527 | 18:39:45 INFO - PID 4527 | [#6] imaging-darkroom Cycles:10 Average:150.60 Median:151.00 stddev:1.43 (0.9%) stddev-sans-first:1.17 18:39:45 INFO - PID 4527 | Values: 148.0 151.0 150.0 151.0 153.0 149.0 151.0 150.0 152.0 151.0 18:39:45 INFO - PID 4527 | 18:39:45 INFO - PID 4527 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 18:39:45 INFO - PID 4527 | Values: 93.0 95.0 95.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 18:39:45 INFO - PID 4527 | 18:39:45 INFO - PID 4527 | [#8] json-parse-financial Cycles:10 Average:44.80 Median:45.00 stddev:2.74 (6.1%) stddev-sans-first:2.79 18:39:45 INFO - PID 4527 | Values: 47.0 46.0 42.0 43.0 43.0 45.0 45.0 51.0 44.0 42.0 18:39:45 INFO - PID 4527 | 18:39:45 INFO - PID 4527 | [#9] json-stringify-tinderbox Cycles:10 Average:39.00 Median:39.00 stddev:1.15 (3.0%) stddev-sans-first:1.22 18:39:45 INFO - PID 4527 | Values: 39.0 38.0 38.0 38.0 38.0 41.0 39.0 41.0 39.0 39.0 18:39:45 INFO - PID 4527 | 18:39:45 INFO - PID 4527 | [#10] stanford-crypto-aes Cycles:10 Average:72.30 Median:72.50 stddev:1.95 (2.7%) stddev-sans-first:1.54 18:39:45 INFO - PID 4527 | Values: 76.0 72.0 71.0 71.0 73.0 70.0 71.0 75.0 71.0 73.0 18:39:45 INFO - PID 4527 | 18:39:45 INFO - PID 4527 | [#11] stanford-crypto-ccm Cycles:10 Average:105.80 Median:105.50 stddev:3.85 (3.7%) stddev-sans-first:3.83 18:39:45 INFO - PID 4527 | Values: 102.0 114.0 110.0 103.0 104.0 108.0 105.0 102.0 106.0 104.0 18:39:45 INFO - PID 4527 | 18:39:45 INFO - PID 4527 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.70 Median:155.50 stddev:4.42 (2.8%) stddev-sans-first:4.41 18:39:45 INFO - PID 4527 | Values: 158.0 150.0 147.0 161.0 152.0 150.0 151.0 154.0 157.0 157.0 18:39:45 INFO - PID 4527 | 18:39:45 INFO - PID 4527 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.60 Median:59.50 stddev:2.63 (4.4%) stddev-sans-first:2.73 18:39:45 INFO - PID 4527 | Values: 59.0 59.0 65.0 59.0 60.0 59.0 58.0 64.0 64.0 59.0 18:39:45 INFO - PID 4527 | -------- Summary: end -------- 18:39:45 INFO - PID 4527 | 18:39:45 INFO - PID 4527 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:39:45 INFO - TEST-INFO | 4527: exit 0 18:39:45 INFO - TEST-OK | kraken | took 59393ms 18:39:45 INFO - SUITE-END | took 1167s 18:39:46 INFO - Completed test suite (00:19:27) 18:39:46 INFO - javascript benchmark 18:39:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9157.221500494528, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3844.058205739593, 3844.7500912440705, 3849.807509624519, 3870.825812838422, 3903.4925459690235, 6306.180196574439, 6334.936650633493, 6335.0, 6371.9681401592925, 6416.9358306416925, 6388.520860935425, 6410.07053977173, 6432.421082102622, 6481.902771458432, 6516.380943810331, 5657.632253903483, 5712.200291959129, 5728.885422291552, 5744.569157313197, 5803.767849286034, 179.49030606023655, 5647.068233741427, 5727.140928860684, 5759.222504961839, 5762.9135562966585, 5651.039323315046, 5652.491275785199, 5717.971410142944, 5718.456746609087, 5747.425257474261, 5660.066089095336, 5677.744501497443, 5729.140628905656, 5749.9937510935415, 5766.826995190151, 5659.40576239493, 5682.573806964494, 5734.426557344233, 5746.080627099644, 5759.769609215627, 6273.12176295319, 6292.402221788922, 6324.367563243639, 6407.615543067384, 6409.455196308273, 6848.212455567574, 6859.2454829968665, 6937.618430986294, 6940.30596940307, 6953.374196322356, 6966.825829354256, 7032.156141263081, 7041.859162816715, 7041.894371584431, 7064.505484616079, 6438.098666186737, 6516.152900123001, 6519.119918810948, 6529.0, 6564.179477565257, 7176.712931482786, 7178.892316615255, 7192.820179495555, 7266.781996540112, 7279.67241474135, 6748.831279218059, 6794.694238759268, 6818.147731533559, 6829.658517074126, 6918.654067296615, 6833.077534532874, 6911.723531058802, 6937.965310173417, 6942.132233470817, 6944.166699995932, 6774.390304872585, 6852.965735171292, 6866.793996180123, 6873.553219040747, 6896.931030689629, 6738.932610673831, 6858.794236172923, 6983.8603227935155, 6996.545224560387, 6999.020137180799, 5018.824341148043, 5079.492050794891, 5085.491450854959, 5100.719460429712, 5101.744912754421, 4629.305604159403, 4667.136579732693, 4702.741349225757, 4706.811727530929, 4709.387779588632, 6185.381461853869, 6346.968265158737, 6371.267304259976, 6443.96778016107, 6455.128557644658, 6866.141732283465, 6885.036094946711, 6908.3437073477935, 6910.2053263874295, 6913.516053876281, 6300.905486417708, 6317.842053948596, 6330.39861213184, 6359.618422894641, 6368.681565921685, 5602.6918519481005, 5652.717364131777, 5672.631278966854, 5674.035413979551, 5700.8859822803315, 7016.193137789118, 7154.642267886585, 7156.141263048364, 7259.782206533812, 7265.818354541073, 6814.93185068143, 6830.282820303995, 6838.658067096625, 6911.7926462206215, 6971.755988540377, 7179.892301615584, 7184.604846733271, 7213.495055346285, 7265.9273407265255, 7288.854222915406, 6859.931400686129, 6871.450283977169, 6972.1284839395075, 6974.267701891431, 6993.0908981832035, 6970.651467426507, 6984.196817366068, 7030.0, 7067.540609860343, 7075.540089894346, 6600.0, 6676.799696009128, 6721.563098398588, 6764.0, 6778.0, 6683.097781799394, 6732.124823772878, 6742.93257067423, 6754.729810807709, 6782.932170678428, 6604.768833090819, 6621.304763000008, 6692.83267918306, 6694.933050669431, 6701.530892837454, 6652.201735791736, 6760.492963027694, 6797.048413222252, 6879.965600171967, 6881.4494840413645, 6526.738930442919, 6588.7035083422315, 6624.0395142705, 6630.403263706289, 6672.799816005333], "value": 6285.344055335284, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [946.271371044296, 963.585658166988, 993.3692105513, 1014.3102690170681, 1014.8731408573929, 43406.13187736244, 43634.78182609087, 44424.11151776963, 45207.32189017163, 45487.545124548706, 44834.7758261209, 44936.77531612346, 44938.0, 44978.77510612443, 45383.54616453835, 8477.915220847774, 8654.394192406535, 8659.653613855438, 8661.653533858638, 8662.350323725714, 8434.072252052269, 8609.354298427643, 8611.181937715906, 8624.956875215616, 8643.178898004711, 8634.956825215864, 8643.740687779346, 8657.913420865774, 8660.047394786568, 8679.0, 8616.138386161365, 8647.437916535406, 8649.524276164808, 8650.0, 8651.480911145289, 8387.454815436977, 8400.41197116202, 8402.201790829862, 8430.156984301582, 8459.788505287355, 40711.59288407108, 41118.38322425166, 41132.38301425481, 41190.588094118975, 41219.381709274385, 3780.092777733352, 3780.206156707095, 3790.507234059555, 3791.0332865119494, 3797.7531460454984, 33007.83496082504, 33020.669793302, 33021.6697833021, 33097.50353744696, 33169.33661326784, 5388.865278368033, 5418.8916221675345, 5426.050441172819, 5449.754761035763, 5478.698671573102, 229671.0, 229794.0, 230002.0, 230248.0, 230488.0, 52279.477205227464, 52436.21345679818, 52483.47516524862, 52508.0, 52596.21105683418, 55540.72229638907, 57649.71175144098, 58693.41306586879, 58889.0, 59016.0, 16886.40897568579, 17008.149592520323, 17016.744748828776, 17031.659366812593, 17034.31862725502, 13986.510472133426, 14103.0, 14184.0, 14185.148891066568, 14191.0, 16518.0, 16530.504084877473, 16536.421225257058, 16606.667866642598, 16664.250108745135, 6314.431701146918, 6408.839779005562, 6564.409203171642, 6578.243501997236, 6618.139641846529, 1093.6882988348282, 1113.638067628008, 1115.827046807746, 1132.2470557079614, 1140.338603609921, 26130.86934565353, 26154.215373538824, 26179.345516362242, 26269.474610508067, 26393.868030659723, 1232.5377983256278, 1233.993830030844, 1234.4198226833373, 1243.1173866554666, 1264.0456455376132, 13762.724745505035, 13953.651158721113, 14206.502772403122, 14229.50196743109, 14329.283535823375, 294146.0, 295131.0, 295792.0, 296866.0, 302283.0, 61258.0811287831, 61906.0714089289, 61993.38006619876, 62103.0, 62122.689386553684, 1516.067618414667, 1524.695060987807, 1533.723929692666, 1537.9077255364714, 1555.8133024037188, 28684.282892927007, 28801.0, 28996.0, 29651.110466686034, 29745.851270743507, 42146.7892660547, 42272.788636056626, 42306.0, 42347.57652423436, 42387.152256955305, 259985.0, 260154.0, 261044.0, 261444.0, 261872.0, 36260.63739362573, 37062.25875482529, 37161.4425783608, 37595.81202093972, 37715.245695086494, 45409.31886021778, 45713.77143114263, 45737.0, 46033.0, 46417.76791116023, 33455.66544334526, 33627.66372336343, 34178.607497474935, 34331.970040898814, 34381.82809085939, 33574.0, 33907.49138762871, 34090.0, 34231.144221394665, 34700.82649586736, 39501.2099758009, 39947.2010559793, 39989.0, 40158.59841401548, 40452.0, 33270.667293326755, 33453.33093338071, 33626.159346016546, 33767.4934875982, 34105.82947085348, 25786.0, 25933.351666208495, 26445.074422395122, 26563.468730625667, 26902.0], "value": 19179.3638089627, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [612.23776398384, 613.4632196827775, 618.9235116185199, 627.2942939193407, 632.9968350158249, 23600.291991240247, 23867.283981480563, 24265.27204183873, 24346.756532434694, 24368.878155609218, 25019.37451563712, 26041.609375859378, 26272.211833644975, 26325.473490530225, 26340.341491462725, 6306.968465157668, 6468.094466774655, 6468.126802881621, 6468.191476065492, 6481.805545833632, 6295.653739044351, 6541.9345806541805, 6780.559263647847, 6790.592564446124, 6792.524523283372, 6732.730690772363, 6754.290799466059, 6772.4582033437455, 6775.695093720795, 6797.592144471323, 6714.395704386604, 6757.391834734873, 6767.086443330186, 6782.253952065269, 6783.253842077393, 6338.492920566344, 6358.586691865014, 6371.203599550056, 6377.872442551123, 6378.4578310843635, 29277.85361073202, 30033.399332013454, 30333.696663033308, 30472.8476357619, 30653.080407587808, 2124.904379302935, 2162.870227786322, 2165.5993641176274, 2171.4354267890462, 2173.5109600339956, 20451.181952721872, 20984.16063357464, 20993.0, 21012.474688132766, 21080.15679372823, 2503.0738626708235, 2599.8180127391092, 2651.5890037044287, 2660.9467810643678, 2671.9866400668066, 144517.0, 149148.0, 158319.0, 165309.0, 166465.3353466462, 15674.608134796721, 16134.596635083983, 16209.756853647204, 16346.264418101215, 16952.0, 26092.0, 26211.606825897627, 26222.606660900103, 26239.606405903927, 26265.0, 10734.57061717538, 10844.403557804397, 10848.67453976382, 10993.835092473619, 11103.333799972028, 9848.852267215998, 9944.453055081894, 10063.892971773099, 10126.189904807745, 10126.797464050826, 9776.67419562325, 9855.162311203629, 9951.0, 9977.900220997843, 10067.345622534513, 3030.5908702325346, 3071.616047994001, 3074.738647214967, 3079.59965204522, 3092.876284948622, 1060.5704689600725, 1068.834330678746, 1080.2600218850114, 1082.1234799812178, 1088.5645741703381, 21013.264535741175, 21250.89374553117, 21510.13959441636, 21660.35018949434, 21704.89147554252, 1129.5425352731993, 1138.703936976392, 1144.6451600004025, 1151.694800877768, 1164.0512981919708, 11548.307101573933, 11709.765804683859, 11803.291802491878, 11879.40602969848, 11949.283043017447, 594940.0, 600991.0, 609638.0, 618485.0, 639238.0, 131699.0, 131858.34070829584, 132132.0, 134469.0, 136102.0, 1136.4772204785727, 1185.9407029648482, 1235.4872727818035, 1269.7270086931169, 1295.1387327427344, 21221.89389053045, 21974.0, 21986.45033874166, 22128.557428851655, 22364.88817555902, 62237.0, 62466.0, 62858.685706571174, 63073.0, 63669.0, 78786.21213787788, 78834.21165788268, 79056.0, 79729.60135199287, 80371.0, 15295.0, 15405.691886162438, 15410.075395476308, 15496.302666379814, 15719.292631831366, 23128.306150815504, 23163.4209144766, 23202.0, 23244.53510929738, 23571.41071473227, 18878.71681924745, 19571.608567828847, 19900.50248743793, 20026.699599505726, 20035.0984205714, 17285.481435556954, 17537.386191483238, 17628.471145865646, 17900.820991790435, 17921.37275195362, 18038.72941905846, 18810.24759009624, 19060.142293596546, 19187.520311992314, 19190.0, 15392.384304627689, 15519.612009699847, 15615.765763513784, 15666.686666266383, 15680.686386271982, 18361.540961475537, 18391.0, 18397.172127254038, 18640.440786776417, 18642.533936651693], "value": 12874.073561704101, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1426.0801782850062, 1513.5761986643743, 1514.4018112845426, 1523.6419441431258, 1526.1682383101208, 16898.662026759474, 17476.213570389333, 17566.560835979108, 17575.73636395455, 17593.648127037468, 17130.31478740849, 17153.485395438125, 17167.57081072974, 17195.914020429882, 17240.741388879145, 2658.1626787561954, 2703.0944633547783, 2716.8641567921622, 2722.5706504085315, 2728.154272175632, 2641.709411964682, 2716.1579910227892, 2719.524083285427, 2722.823016503921, 2729.686086100104, 2628.053900595784, 2680.490706765718, 2680.6917204521533, 2681.168837660322, 2681.6781986161686, 2512.2337687005456, 2543.465872166847, 2546.923592292225, 2550.9744902551106, 2553.323369307135, 2469.4567195217023, 2530.9493810123695, 2537.9111731089506, 2540.9872950635126, 2541.2376287113793, 16704.83295167045, 16796.496105116745, 16803.91598042014, 16830.915845420815, 16921.06934118623, 8392.20274073962, 8647.135286471366, 8736.956315218447, 8737.956310218407, 8753.343499237519, 5039.269305950654, 5184.403793563751, 5241.965513646116, 5295.788168473295, 5342.786288548454, 2149.8925053747407, 2150.2474134053114, 2188.9890550547148, 2204.867707937529, 2229.331200639818, 27622.585661215096, 28405.431891362263, 30650.693493065006, 31451.685483145106, 31478.842605787053, 7558.055243094614, 7562.735304264324, 7627.733029343946, 7794.571298578632, 7861.331786798072, 23252.3024309271, 23295.41761455977, 23418.53162936766, 23431.53136937252, 23462.530749384918, 8502.787430314167, 8611.827763444697, 8626.094260102724, 8630.309575234092, 8639.654413823502, 7425.368843648351, 7784.104827944746, 7820.804479888048, 7835.059792824897, 7899.960500197462, 5688.345840228344, 5881.323647780475, 5907.793227237112, 5943.910841337383, 5967.522598192134, 1899.8005209453086, 1903.4384856467375, 1905.2093381246627, 1924.6728056230475, 1948.2888745608034, 959.29971121082, 962.1220412508515, 963.9396663670047, 988.8467287570438, 990.980180396388, 8519.403641745143, 8585.18440748128, 8629.43908645936, 8652.134786521297, 8733.25767309773, 790.8655528560158, 801.2548330053106, 812.463773909217, 814.9755507334789, 816.7345612675786, 5116.258142569381, 5140.434552199255, 5220.92168617471, 5281.656692314988, 5313.760880760375, 24275.0, 24530.632040519406, 25717.742822571534, 25728.0, 25889.22332330033, 25380.238592842245, 25504.74495255061, 25633.231003069937, 25690.0, 25690.972361105345, 11623.941880290713, 12401.193922395016, 12443.875561244271, 12528.123031387715, 12609.810852837032, 9560.521973901416, 9637.662681806103, 9685.467299298607, 9716.22270218395, 9739.61041558358, 20531.794682052987, 20534.69197962002, 20913.477163071046, 21318.78681213168, 21591.13635454564, 22149.778502214773, 22161.667574986066, 22184.33446996593, 22280.888595556917, 22609.208677696202, 13473.663158421117, 13488.932555337553, 13536.120152190077, 13551.119177253448, 13714.314284285547, 15207.239638018276, 15401.922990384975, 15411.691766164839, 15413.0, 15414.46049388266, 12405.193662412275, 12647.0, 12864.871351286367, 12925.806112908125, 13012.0, 11984.955373578261, 12521.686957825763, 12526.37368131608, 12573.308468034345, 12650.936745316214, 10857.239993200401, 10983.61557345489, 11026.117910567296, 11042.227044106836, 11075.889241107487, 8383.664653413794, 8481.236688698047, 8525.573721314033, 8532.573371331284, 8562.828743424972, 8220.0, 8229.300509456763, 8291.08798032228, 8314.127016663164, 8327.458715183493], "value": 7304.820321907056, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.7113030094051, 559.8796258804357, 562.3280180184676, 582.6620560075157, 587.588687918457, 6286.622802631845, 6336.334684858092, 6343.175387199669, 6348.301686814452, 6385.712642931067, 6202.937970620293, 6267.493002798883, 6284.717187726552, 6298.449128103126, 6301.590396624228, 4949.4060712714345, 5159.974200129013, 5227.607929405291, 5248.580113590903, 5254.4220135785035, 5090.669106508084, 5142.511461411179, 5153.510416510426, 5155.5360017598405, 5164.612654050961, 5087.974560127194, 5093.2105523643895, 5102.1836506159025, 5123.5901127909865, 5141.434442211354, 5074.213496907985, 5080.41575218849, 5083.847484575469, 5112.795488180487, 5112.872178195537, 4684.36761037259, 4705.588323502935, 4738.455077666078, 4744.572988431058, 4759.690620109682, 6352.682365881734, 6381.904271435886, 6397.584157029824, 6406.93593064068, 6425.550211485198, 5980.760769569212, 6000.459958603703, 6029.728662210167, 6051.213342265477, 6114.144019837227, 6683.465322774166, 6716.93283067168, 6755.493337999621, 6772.830679233059, 6778.966105169492, 5217.295665085203, 5235.712035838065, 5240.580753539746, 5243.711595862226, 5248.475152484759, 7126.821829454253, 7145.4283657307815, 7147.463940204453, 7151.82120446993, 7181.605011724353, 5333.493318134849, 5345.305110335631, 5406.675599464044, 5412.377576578665, 5416.350037995466, 5430.402655707869, 5440.721430463838, 5462.9513932275395, 5470.835874923679, 5478.904219156105, 5963.5527335450015, 6016.819495415145, 6018.097285407224, 6046.606970546901, 6051.939480605226, 6332.240131184288, 6472.611643301417, 6561.0, 6673.0, 6719.798406047827, 1839.1999480226145, 1844.483544607512, 1855.369174480656, 1863.2453856188265, 1863.2733234038735, 1296.6628676543978, 1334.652990222549, 1336.1448672849385, 1346.7273426611757, 1366.7403193393422, 6079.392060793885, 6187.16473276102, 6196.81409557714, 6228.750849965951, 6270.843228919313, 6831.4534837212905, 6850.417714494224, 6860.461629197229, 6916.20463646677, 6916.757913473004, 6077.969610151921, 6128.938710612837, 6215.533834962396, 6229.127922090911, 6245.406686364788, 3517.278957813642, 3608.332458495187, 3615.1504396467335, 3625.2386998730035, 3658.5061016762397, 7111.7866464006165, 7135.607541585159, 7161.856762864818, 7166.462515311373, 7199.172095209014, 7037.261087585832, 7051.365377115982, 7076.150861896605, 7156.570605763671, 7173.0, 6983.162020557363, 7029.8242543935785, 7042.859142817115, 7140.286331280456, 7151.499395042297, 6761.222459417033, 6773.525853190229, 6793.0, 6795.063406929002, 6879.965600171967, 6723.899141512783, 6759.594424334556, 6820.215675197416, 6852.828679283058, 6893.689783959834, 7001.859962800613, 7018.964905175441, 7172.7089123958185, 7178.0, 7212.855742885218, 5296.0934063737795, 5348.486211480598, 5363.249145119608, 5377.704226267437, 5379.488948549882, 6618.867622647616, 6868.587884726932, 6902.206246281541, 6938.202106757585, 6987.231404545394, 6309.589876658186, 6560.539797575909, 6570.211574611078, 6617.735290588323, 6698.66506674674, 6542.640154791532, 6649.168853893263, 6679.56582822115, 6680.966595166993, 6686.53194276396, 6398.328175541687, 6454.967205247138, 6464.834280515892, 6491.993740970157, 6557.704903279268, 6681.461044802943, 6717.664116794043, 6720.663966801542, 6763.6618169092335, 6812.318768123347, 6502.057201705821, 6512.5766825156215, 6514.902276465762, 6517.804465866032, 6578.407943285128], "value": 5282.840926116105, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [945.5697657565809, 971.7667759737662, 988.545269176179, 989.1740396768698, 1005.8843233028199, 21178.0, 21757.12971481141, 21787.782122178774, 21793.891030544844, 21797.564048719018, 19127.904360478176, 19526.12132454043, 19803.504912377197, 19845.20619175235, 19947.002649867485, 1048.140524769691, 1067.0076828549445, 1070.2187403195671, 1070.5289672544095, 1071.3946620159625, 1047.6280920273307, 1074.382230217628, 1075.639660713662, 1084.5499117866077, 1087.5377964365152, 1143.2454579977216, 1144.130460849752, 1146.7993101207255, 1149.4310316393355, 1150.8503894493704, 1063.0751246415668, 1110.7611863449397, 1126.6563698072084, 1139.4644517077002, 1140.0366690146814, 814.267159556398, 817.2603793566832, 820.2371794231362, 820.4379999700164, 823.074041703084, 21502.0, 21675.566488670294, 21722.23972160967, 21724.348269551938, 21728.34814955554, 21807.67288490674, 22711.09155633789, 22751.544969100527, 22887.0, 22893.542129157322, 18258.269669213216, 18501.907490462596, 18646.44060678182, 18650.720239196424, 18655.53361165968, 3955.6044395560502, 3979.283728928792, 3983.083890705154, 3984.302747019289, 3987.063040185554, 160771.0, 162951.0, 163431.0, 163593.0, 165487.0, 17523.12384380776, 17589.560260993578, 17960.910195448938, 18019.459416217534, 18153.364632237808, 32500.187495312806, 33038.66961330404, 33282.0, 34617.134571635404, 34932.0, 9239.0, 9299.302552308603, 9301.34890557668, 9324.300677449082, 9324.440533568008, 4664.533546645376, 4725.763711814396, 4821.879453013703, 4825.5898248649255, 4825.686330388584, 6071.817845464643, 6204.689765511706, 6245.594036387621, 6246.531510136758, 6265.561410701298, 1094.190299178618, 1105.4693747001484, 1111.766529028897, 1129.249049382168, 1132.2923173016864, 698.5983059740568, 715.2811424518429, 720.5424555407354, 734.4895297768106, 744.9106107267054, 12619.78917105882, 12907.612771616867, 13187.406566704522, 13780.0, 14131.78802317966, 698.5121690798567, 700.471144286071, 710.2258538193394, 712.7434123715458, 736.8415790605046, 6176.722047507873, 6210.006398976142, 6217.782377616762, 6254.562180647402, 6310.779122730774, 140185.0, 141137.29431352983, 143223.0, 147400.0, 148781.0, 130515.34742326228, 132363.0, 132370.33814830866, 134432.0, 136602.0, 101648.49175754075, 103560.0, 104627.0, 105220.0, 106218.99999999846, 11329.886701133213, 11421.0, 11457.656270311903, 11625.302481851117, 11628.88371116278, 54940.45059549353, 55411.0, 56267.43732562622, 56480.717596411756, 57106.0, 69319.65340173266, 71400.0, 72035.27964720286, 72243.0, 74426.88359674501, 16435.0, 16658.50024499267, 16715.91642041782, 16727.66544669124, 16754.916225418794, 17260.82739172592, 17723.02523361176, 17957.102144892964, 18007.81992180114, 18199.181036853108, 16766.413175538797, 16924.153792310583, 16970.830291696926, 17039.659206815548, 17112.229949652046, 16741.49775506737, 16834.831651683326, 16839.15804209809, 17076.487705368858, 17087.829121708623, 16551.0, 16718.0, 16925.238364273882, 16948.32206711767, 17004.829951700325, 13077.803832942704, 13128.934355328161, 13129.934350328187, 13406.0, 13535.864641353854, 13503.45986160582, 13756.931215343859, 13820.37808298649, 13827.51603693866, 13881.514147004806], "value": 9844.628197308775, "unit": "score"}]}, {"extraOptions": ["e10s", "stylo_disabled"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1374.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 106.0, 105.0, 106.0, 106.0, 107.0, 107.0, 106.0, 107.0, 105.0], "value": 106.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 111.0, 115.0, 117.0, 115.0, 117.0, 112.0, 112.0, 138.0], "value": 116.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 162.0, 162.0, 163.0, 162.0, 161.0, 162.0, 166.0, 170.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 72.0, 75.0, 76.0, 76.0, 75.0, 72.0, 72.0, 73.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 79.0, 78.0, 82.0, 84.0, 81.0, 83.0, 81.0, 82.0, 78.0], "value": 81.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 111.0, 117.0, 111.0, 111.0, 111.0, 111.0, 111.0, 111.0, 117.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 151.0, 150.0, 151.0, 153.0, 149.0, 151.0, 150.0, 152.0, 151.0], "value": 150.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 46.0, 42.0, 43.0, 43.0, 45.0, 45.0, 51.0, 44.0, 42.0], "value": 44.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 38.0, 38.0, 38.0, 41.0, 39.0, 41.0, 39.0, 39.0], "value": 39.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 72.0, 71.0, 71.0, 73.0, 70.0, 71.0, 75.0, 71.0, 73.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 110.0, 103.0, 104.0, 108.0, 105.0, 102.0, 106.0, 104.0], "value": 105.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 150.0, 147.0, 161.0, 152.0, 150.0, 151.0, 154.0, 157.0, 157.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 65.0, 59.0, 60.0, 59.0, 58.0, 64.0, 64.0, 59.0], "value": 60.6, "unit": "score"}]}]} 18:39:46 INFO - Return code: 0 18:39:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:39:46 INFO - # TBPL SUCCESS # 18:39:46 INFO - Running post-action listener: _package_coverage_data 18:39:46 INFO - Running post-action listener: _resource_record_post_action 18:39:46 INFO - [mozharness: 2017-09-29 01:39:46.137620Z] Finished run-tests step (success) 18:39:46 INFO - Running post-run listener: _resource_record_post_run 18:39:46 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 9261056; Write bytes: 453410816; Read time: 2140; Write time: 382160 18:39:46 INFO - TinderboxPrint: CPU usage
13.1% 18:39:46 INFO - TinderboxPrint: I/O read bytes / time
9,261,056 / 2,140 18:39:46 INFO - TinderboxPrint: I/O write bytes / time
453,410,816 / 382,160 18:39:46 INFO - TinderboxPrint: CPU idle
8,218.2 (86.9%) 18:39:46 INFO - TinderboxPrint: CPU user
1,186.7 (12.5%) 18:39:46 INFO - TinderboxPrint: Swap in / out
0 / 0 18:39:46 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 109670400; Read time: 60; Write time: 87916 18:39:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:39:46 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 9220096; Write bytes: 306827264; Read time: 2064; Write time: 242756 18:39:46 INFO - Running post-run listener: _upload_blobber_files 18:39:46 INFO - Blob upload gear active. 18:39:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:39:46 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 18:39:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:39:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:39:47 INFO - (blobuploader) - INFO - Open directory for files ... 18:39:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:39:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:39:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:39:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:39:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:39:48 INFO - (blobuploader) - INFO - Done attempting. 18:39:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log ... 18:39:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:39:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:39:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-stylo-disabled-e10s_raw.log: uploaded 18:39:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:39:49 INFO - (blobuploader) - INFO - Done attempting. 18:39:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:39:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:39:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:39:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:39:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:39:57 INFO - (blobuploader) - INFO - Done attempting. 18:39:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log ... 18:39:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:39:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:39:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-stylo-disabled-e10s_errorsummary.log: uploaded 18:39:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:39:58 INFO - (blobuploader) - INFO - Done attempting. 18:39:58 INFO - (blobuploader) - INFO - Iteration through files over. 18:39:58 INFO - Return code: 0 18:39:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:39:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:39:58 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14d7e559e87832ffdedbd5ea8b7598915f13820d80d6699ff5e0fb3284995d457af458583427c7c4cae33b82eee327ce49cc1db376d287654a53c50f6f075a22", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43ed2428a47aefc331bc0b2708fbcb48085559e58fffa7fe46826d779b0b5021dd6de8d720c3e7d3afe9f68aceffc52a2e1efbd373ccfc31cde46a4e0469df81", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86df80b003f63ea5c1489b302fa0a44eec889a3f6daa18e1e30f0c472d3699e4ea9ab941be3babeb5651540af39775674abf82c613cbe1aeb0cb9a2375a4ee4d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3a9cc2f878dedaef6970e11feedf66223612621bed63143a109950ae1caab47c9f4c71a32cba4a4177065b1f3c354402c0c8b72c3a40fe2811d0cd9619f8fb6"} 18:39:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:39:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:39:58 INFO - Contents: 18:39:58 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14d7e559e87832ffdedbd5ea8b7598915f13820d80d6699ff5e0fb3284995d457af458583427c7c4cae33b82eee327ce49cc1db376d287654a53c50f6f075a22", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43ed2428a47aefc331bc0b2708fbcb48085559e58fffa7fe46826d779b0b5021dd6de8d720c3e7d3afe9f68aceffc52a2e1efbd373ccfc31cde46a4e0469df81", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86df80b003f63ea5c1489b302fa0a44eec889a3f6daa18e1e30f0c472d3699e4ea9ab941be3babeb5651540af39775674abf82c613cbe1aeb0cb9a2375a4ee4d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3a9cc2f878dedaef6970e11feedf66223612621bed63143a109950ae1caab47c9f4c71a32cba4a4177065b1f3c354402c0c8b72c3a40fe2811d0cd9619f8fb6"} 18:39:58 INFO - Running post-run listener: copy_logs_to_upload_dir 18:39:58 INFO - Copying logs to upload dir... 18:39:58 INFO - mkdir: /builds/slave/test/build/upload/logs 18:39:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.767802 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-28 18:39:58.359712) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-28 18:39:58.367406) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14d7e559e87832ffdedbd5ea8b7598915f13820d80d6699ff5e0fb3284995d457af458583427c7c4cae33b82eee327ce49cc1db376d287654a53c50f6f075a22", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43ed2428a47aefc331bc0b2708fbcb48085559e58fffa7fe46826d779b0b5021dd6de8d720c3e7d3afe9f68aceffc52a2e1efbd373ccfc31cde46a4e0469df81", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86df80b003f63ea5c1489b302fa0a44eec889a3f6daa18e1e30f0c472d3699e4ea9ab941be3babeb5651540af39775674abf82c613cbe1aeb0cb9a2375a4ee4d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3a9cc2f878dedaef6970e11feedf66223612621bed63143a109950ae1caab47c9f4c71a32cba4a4177065b1f3c354402c0c8b72c3a40fe2811d0cd9619f8fb6"} build_url:https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011255 build_url: 'https://queue.taskcluster.net/v1/task/LLXKHqi1S7W9dRPfY0Vflg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14d7e559e87832ffdedbd5ea8b7598915f13820d80d6699ff5e0fb3284995d457af458583427c7c4cae33b82eee327ce49cc1db376d287654a53c50f6f075a22", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43ed2428a47aefc331bc0b2708fbcb48085559e58fffa7fe46826d779b0b5021dd6de8d720c3e7d3afe9f68aceffc52a2e1efbd373ccfc31cde46a4e0469df81", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86df80b003f63ea5c1489b302fa0a44eec889a3f6daa18e1e30f0c472d3699e4ea9ab941be3babeb5651540af39775674abf82c613cbe1aeb0cb9a2375a4ee4d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3a9cc2f878dedaef6970e11feedf66223612621bed63143a109950ae1caab47c9f4c71a32cba4a4177065b1f3c354402c0c8b72c3a40fe2811d0cd9619f8fb6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-28 18:39:58.395877) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 18:39:58.396190) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1506647231.763013-738927183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004517 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 18:39:58.485204) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-28 18:39:58.485514) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-28 18:39:58.485849) ========= ========= Total master_lag: 0.32 =========