builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-052 starttime: 1506681805.41 results: success (0) revision: 946b9c995ec331f4f96360409fd8d2fc49e46838 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-29 03:43:25.413630) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-29 03:43:25.414107) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-29 03:43:25.414386) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-29 03:43:25.500504) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 03:43:25.500848) ========= 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=826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-29 03:43:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2017-09-29 03:43:25 (221 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111046 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 03:43:25.646461) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 03:43:25.646798) ========= 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=826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019565 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 03:43:25.696447) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 03:43:25.699786) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 946b9c995ec331f4f96360409fd8d2fc49e46838 --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 946b9c995ec331f4f96360409fd8d2fc49e46838 --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=826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-29 03:43:25,782 truncating revision to first 12 chars 2017-09-29 03:43:25,782 Setting DEBUG logging. 2017-09-29 03:43:25,782 attempt 1/10 2017-09-29 03:43:25,782 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/946b9c995ec3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-29 03:43:26,143 unpacking tar archive at: mozilla-central-946b9c995ec3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.672030 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 03:43:26.392000) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-29 03:43:26.392348) ========= script_repo_revision: 946b9c995ec331f4f96360409fd8d2fc49e46838 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-29 03:43:26.392765) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-29 03:43:26.393045) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-29 03:43:26.417053) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-09-29 03:43:26.417370) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:43:26 INFO - MultiFileLogger online at 20170929 03:43:26 in /builds/slave/test 03:43:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 03:43:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:43:26 INFO - {'append_to_log': False, 03:43:26 INFO - 'base_work_dir': '/builds/slave/test', 03:43:26 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:43:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:43:26 INFO - 'branch': 'Firefox-Non-PGO', 03:43:26 INFO - 'buildbot_json_path': 'buildprops.json', 03:43:26 INFO - 'code_coverage': False, 03:43:26 INFO - 'config_files': ('talos/linux_config.py',), 03:43:26 INFO - 'default_actions': ('clobber', 03:43:26 INFO - 'read-buildbot-config', 03:43:26 INFO - 'download-and-extract', 03:43:26 INFO - 'populate-webroot', 03:43:26 INFO - 'create-virtualenv', 03:43:26 INFO - 'install', 03:43:26 INFO - 'setup-mitmproxy', 03:43:26 INFO - 'run-tests'), 03:43:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:43:26 INFO - 'disable_ccov_upload': False, 03:43:26 INFO - 'disable_stylo': False, 03:43:26 INFO - 'download_minidump_stackwalk': True, 03:43:26 INFO - 'download_symbols': 'ondemand', 03:43:26 INFO - 'enable_stylo': False, 03:43:26 INFO - 'enable_webrender': False, 03:43:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:43:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:43:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:43:26 INFO - 'gecko_profile': False, 03:43:26 INFO - 'gecko_profile_interval': 0, 03:43:26 INFO - 'installer_path': 'installer.exe', 03:43:26 INFO - 'log_level': 'info', 03:43:26 INFO - 'log_name': 'talos', 03:43:26 INFO - 'log_to_console': True, 03:43:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:43:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:43:26 INFO - 'opt_config_files': (), 03:43:26 INFO - 'pip_index': False, 03:43:26 INFO - 'suite': 'dromaeojs-e10s', 03:43:26 INFO - 'system_bits': '32', 03:43:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:43:26 INFO - 'title': 'talos-linux64-ix-052', 03:43:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:43:26 INFO - 'use_talos_json': True, 03:43:26 INFO - 'verify': 'False', 03:43:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:43:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:43:26 INFO - 'work_dir': 'build'} 03:43:26 INFO - [mozharness: 2017-09-29 10:43:26.642327Z] Running clobber step. 03:43:26 INFO - Running pre-action listener: _resource_record_pre_action 03:43:26 INFO - Running main action method: clobber 03:43:26 INFO - rmtree: /builds/slave/test/build 03:43:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:43:27 INFO - Running post-action listener: _resource_record_post_action 03:43:27 INFO - [mozharness: 2017-09-29 10:43:27.641585Z] Finished clobber step (success) 03:43:27 INFO - [mozharness: 2017-09-29 10:43:27.641682Z] Running read-buildbot-config step. 03:43:27 INFO - Running pre-action listener: _resource_record_pre_action 03:43:27 INFO - Running main action method: read_buildbot_config 03:43:27 INFO - Using buildbot properties: 03:43:27 INFO - { 03:43:27 INFO - "project": "", 03:43:27 INFO - "product": "firefox", 03:43:27 INFO - "who": "archaeopteryx@coole-files.de", 03:43:27 INFO - "installer_path": "public/build/target.tar.bz2", 03:43:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:43:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:43:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:43:27 INFO - "stage_platform": "linux64", 03:43:27 INFO - "basedir": "/builds/slave/test", 03:43:27 INFO - "buildnumber": 880, 03:43:27 INFO - "platform": "ubuntu64_hw", 03:43:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:43:27 INFO - "slavebuilddir": "test", 03:43:27 INFO - "taskId": "BII68Q7oTkWfbaZfe_h9dg", 03:43:27 INFO - "branch": "mozilla-central", 03:43:27 INFO - "script_repo_revision": "production", 03:43:27 INFO - "revision": "946b9c995ec331f4f96360409fd8d2fc49e46838", 03:43:27 INFO - "slavename": "talos-linux64-ix-052", 03:43:27 INFO - "repo_path": "mozilla-central" 03:43:27 INFO - } 03:43:27 INFO - Finding installer, test and symbols from parent task. 03:43:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BII68Q7oTkWfbaZfe_h9dg'}, attempt #1 03:43:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BII68Q7oTkWfbaZfe_h9dg'}, attempt #1 03:43:28 INFO - Task dependencies: ewqrVSCKSgOWNQzmAaDlXw 03:43:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw'}, attempt #1 03:43:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.tar.bz2 03:43:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.test_packages.json 03:43:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.crashreporter-symbols.zip 03:43:29 INFO - Running post-action listener: _resource_record_post_action 03:43:29 INFO - [mozharness: 2017-09-29 10:43:29.302884Z] Finished read-buildbot-config step (success) 03:43:29 INFO - [mozharness: 2017-09-29 10:43:29.303104Z] Running download-and-extract step. 03:43:29 INFO - Running pre-action listener: _resource_record_pre_action 03:43:29 INFO - Running main action method: download_and_extract 03:43:29 INFO - mkdir: /builds/slave/test/build/tests 03:43:29 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')]} 03:43:29 INFO - trying https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.test_packages.json 03:43:29 INFO - Downloading https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:43:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:43:29 INFO - Downloaded 989 bytes. 03:43:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:43:29 INFO - Using the following test package requirements: 03:43:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:43:29 INFO - u'common': [u'target.common.tests.zip'], 03:43:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:43:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:43:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:43:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:43:29 INFO - u'mozbase': [u'target.common.tests.zip'], 03:43:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:43:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:43:29 INFO - u'web-platform': [u'target.common.tests.zip', 03:43:29 INFO - u'target.web-platform.tests.tar.gz'], 03:43:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:43:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:43:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.common.tests.zip 03:43:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:43:29 INFO - Fetch https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.common.tests.zip into memory 03:43:31 INFO - Content-Length response header: 55366227 03:43:31 INFO - Bytes received: 55366227 03:43:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.talos.tests.zip 03:43:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:43:42 INFO - Fetch https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.talos.tests.zip into memory 03:43:43 INFO - Content-Length response header: 14521355 03:43:43 INFO - Bytes received: 14521355 03:43:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:43 INFO - trying https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.tar.bz2 03:43:43 INFO - Downloading https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:43:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:43:46 INFO - Downloaded 61628365 bytes. 03:43:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.tar.bz2 03:43:46 INFO - mkdir: /builds/slave/test/properties 03:43:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:43:46 INFO - Writing to file /builds/slave/test/properties/build_url 03:43:46 INFO - Contents: 03:43:46 INFO - build_url:https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.tar.bz2 03:43:46 INFO - Running post-action listener: _resource_record_post_action 03:43:46 INFO - Running post-action listener: find_tests_for_verification 03:43:46 INFO - Running post-action listener: set_extra_try_arguments 03:43:46 INFO - [mozharness: 2017-09-29 10:43:46.208474Z] Finished download-and-extract step (success) 03:43:46 INFO - [mozharness: 2017-09-29 10:43:46.208703Z] Running populate-webroot step. 03:43:46 INFO - Running pre-action listener: _resource_record_pre_action 03:43:46 INFO - Running main action method: populate_webroot 03:43:46 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:43:46 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:43:46 INFO - 'tests': ['tresize', 03:43:46 INFO - 'tcanvasmark']}, 03:43:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:43:46 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:43:46 INFO - 'tests': ['dromaeo_css', 03:43:46 INFO - 'kraken']}, 03:43:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:43:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:43:46 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:43:46 INFO - 'talos_options': ['--disable-stylo'], 03:43:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:43:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:43:46 INFO - 'tests': ['damp', 'tps']}, 03:43:46 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:43:46 INFO - 'talos_options': ['--disable-stylo'], 03:43:46 INFO - 'tests': ['damp', 'tps']}, 03:43:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:43:46 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:43:46 INFO - 'tests': ['dromaeo_dom']}, 03:43:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:43:46 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:43:46 INFO - 'tests': ['basic_compositor_video', 03:43:46 INFO - 'glvideo']}, 03:43:46 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:43:46 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:43:46 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:43:46 INFO - 'talos_options': ['--disable-stylo'], 03:43:46 INFO - 'tests': ['ts_paint_webext', 03:43:46 INFO - 'tp5o_webext']}, 03:43:46 INFO - 'other-e10s': {'tests': ['a11yr', 03:43:46 INFO - 'ts_paint', 03:43:46 INFO - 'tpaint', 03:43:46 INFO - 'sessionrestore', 03:43:46 INFO - 'sessionrestore_many_windows', 03:43:46 INFO - 'sessionrestore_no_auto_restore', 03:43:46 INFO - 'tabpaint', 03:43:46 INFO - 'cpstartup']}, 03:43:46 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:43:46 INFO - 'tests': ['a11yr', 03:43:46 INFO - 'ts_paint', 03:43:46 INFO - 'tpaint', 03:43:46 INFO - 'sessionrestore', 03:43:46 INFO - 'sessionrestore_many_windows', 03:43:46 INFO - 'sessionrestore_no_auto_restore', 03:43:46 INFO - 'tabpaint', 03:43:46 INFO - 'cpstartup']}, 03:43:46 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 03:43:46 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 03:43:46 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:43:46 INFO - 'tests': ['perf_reftest_singletons']}, 03:43:46 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:43:46 INFO - 'tests': ['perf_reftest']}, 03:43:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:43:46 INFO - 'tsvgr_opacity', 03:43:46 INFO - 'tart', 03:43:46 INFO - 'tscrollx', 03:43:46 INFO - 'tsvg_static']}, 03:43:46 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:43:46 INFO - 'tests': ['tsvgx', 03:43:46 INFO - 'tsvgr_opacity', 03:43:46 INFO - 'tart', 03:43:46 INFO - 'tscrollx', 03:43:46 INFO - 'tsvg_static']}, 03:43:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:43:46 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:43:46 INFO - 'talos_options': ['--disable-stylo'], 03:43:46 INFO - 'tests': ['tp5o']}, 03:43:46 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:43:46 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:43:46 INFO - 'talos_options': ['--mitmproxy', 03:43:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:43:46 INFO - '--firstNonBlankPaint'], 03:43:46 INFO - 'tests': ['tp6_google', 03:43:46 INFO - 'tp6_youtube', 03:43:46 INFO - 'tp6_amazon', 03:43:46 INFO - 'tp6_facebook']}, 03:43:46 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:43:46 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:43:46 INFO - 'talos_options': ['--disable-stylo', 03:43:46 INFO - '--mitmproxy', 03:43:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:43:46 INFO - '--firstNonBlankPaint'], 03:43:46 INFO - 'tests': ['tp6_google', 03:43:46 INFO - 'tp6_youtube', 03:43:46 INFO - 'tp6_amazon', 03:43:46 INFO - 'tp6_facebook']}, 03:43:46 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:43:46 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:43:46 INFO - 'talos_options': ['--stylo-threads=1', 03:43:46 INFO - '--mitmproxy', 03:43:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:43:46 INFO - '--firstNonBlankPaint'], 03:43:46 INFO - 'tests': ['tp6_google', 03:43:46 INFO - 'tp6_youtube', 03:43:46 INFO - 'tp6_amazon', 03:43:46 INFO - 'tp6_facebook']}, 03:43:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:43:46 INFO - 'talos_options': ['--xperf_path', 03:43:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:43:46 INFO - 'tests': ['tp5n']}, 03:43:46 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:43:46 INFO - 'talos_options': ['--disable-stylo', 03:43:46 INFO - '--xperf_path', 03:43:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:43:46 INFO - 'tests': ['tp5n']}}} 03:43:46 INFO - Running post-action listener: _resource_record_post_action 03:43:46 INFO - [mozharness: 2017-09-29 10:43:46.229506Z] Finished populate-webroot step (success) 03:43:46 INFO - [mozharness: 2017-09-29 10:43:46.229596Z] Running create-virtualenv step. 03:43:46 INFO - Running pre-action listener: _resource_record_pre_action 03:43:46 INFO - Running main action method: create_virtualenv 03:43:46 INFO - Creating virtualenv /builds/slave/test/build/venv 03:43:46 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:43:46 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:43:46 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:43:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:43:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:43:47 INFO - Installing distribute.............................................................................................................................................................................................done. 03:43:47 INFO - Installing pip...............done. 03:43:47 INFO - Return code: 0 03:43:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:43:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:43:48 INFO - Reading from file tmpfile_stdout 03:43:48 INFO - Output received: 03:43:48 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:43:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:43: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')]} 03:43:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43: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')]} 03:43:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21139c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2110d60>, '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 0x2111a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2111ef0>, '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': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', '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 03:43: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', 'pip>=1.5'] in /builds/slave/test/build 03:43: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 pip>=1.5 03:43:48 INFO - Using env: {'DISPLAY': ':0', 03:43:48 INFO - 'HOME': '/home/cltbld', 03:43:48 INFO - 'LANG': 'en_US.UTF-8', 03:43:48 INFO - 'LANGUAGE': 'en_US:en', 03:43:48 INFO - 'LOGNAME': 'cltbld', 03:43:48 INFO - 'MAIL': '/var/mail/cltbld', 03:43:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:48 INFO - 'MOZ_NO_REMOTE': '1', 03:43:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:48 INFO - 'NO_EM_RESTART': '1', 03:43:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:48 INFO - 'PWD': '/builds/slave/test', 03:43:48 INFO - 'SHELL': '/bin/bash', 03:43:48 INFO - 'SHLVL': '1', 03:43:48 INFO - 'TERM': 'linux', 03:43:48 INFO - 'TMOUT': '86400', 03:43:48 INFO - 'USER': 'cltbld', 03:43:48 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', 03:43:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:48 INFO - '_': '/tools/buildbot/bin/python'} 03:43:49 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:43:49 INFO - Downloading/unpacking pip>=1.5 03:43:49 INFO - Running setup.py egg_info for package pip 03:43:49 INFO - warning: no files found matching 'pip/cacert.pem' 03:43:49 INFO - warning: no files found matching '*.html' under directory 'docs' 03:43:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:43:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:43:49 INFO - Installing collected packages: pip 03:43:49 INFO - Found existing installation: pip 1.1 03:43:49 INFO - Uninstalling pip: 03:43:49 INFO - Successfully uninstalled pip 03:43:49 INFO - Running setup.py install for pip 03:43:49 INFO - warning: no files found matching 'pip/cacert.pem' 03:43:49 INFO - warning: no files found matching '*.html' under directory 'docs' 03:43:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:43:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:43:49 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:43:49 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:43:49 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:43:49 INFO - Successfully installed pip 03:43:49 INFO - Cleaning up... 03:43:49 INFO - Return code: 0 03:43:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1da61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21139c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2110d60>, '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 0x2111a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2111ef0>, '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': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', '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 03:43:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:43:49 INFO - Using env: {'DISPLAY': ':0', 03:43:49 INFO - 'HOME': '/home/cltbld', 03:43:49 INFO - 'LANG': 'en_US.UTF-8', 03:43:49 INFO - 'LANGUAGE': 'en_US:en', 03:43:49 INFO - 'LOGNAME': 'cltbld', 03:43:49 INFO - 'MAIL': '/var/mail/cltbld', 03:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:49 INFO - 'MOZ_NO_REMOTE': '1', 03:43:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:49 INFO - 'NO_EM_RESTART': '1', 03:43:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:49 INFO - 'PWD': '/builds/slave/test', 03:43:49 INFO - 'SHELL': '/bin/bash', 03:43:49 INFO - 'SHLVL': '1', 03:43:49 INFO - 'TERM': 'linux', 03:43:49 INFO - 'TMOUT': '86400', 03:43:49 INFO - 'USER': 'cltbld', 03:43:49 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', 03:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:49 INFO - '_': '/tools/buildbot/bin/python'} 03:43:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:49 INFO - Downloading/unpacking psutil>=3.1.1 03:43: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 03:43: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 03:43: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 03:43: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 03:43:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:43:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:43:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:43:50 INFO - Installing collected packages: psutil 03:43:50 INFO - Running setup.py install for psutil 03:43:50 INFO - building 'psutil._psutil_linux' extension 03:43:50 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 03:43:50 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 03:43:50 INFO - building 'psutil._psutil_posix' extension 03:43:50 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 03:43:51 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 03:43:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:43:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:43:51 INFO - Successfully installed psutil 03:43:51 INFO - Cleaning up... 03:43:51 INFO - Return code: 0 03:43:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21139c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2110d60>, '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 0x2111a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2111ef0>, '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': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', '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 03:43:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:43:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:43:51 INFO - Using env: {'DISPLAY': ':0', 03:43:51 INFO - 'HOME': '/home/cltbld', 03:43:51 INFO - 'LANG': 'en_US.UTF-8', 03:43:51 INFO - 'LANGUAGE': 'en_US:en', 03:43:51 INFO - 'LOGNAME': 'cltbld', 03:43:51 INFO - 'MAIL': '/var/mail/cltbld', 03:43:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:51 INFO - 'MOZ_NO_REMOTE': '1', 03:43:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:51 INFO - 'NO_EM_RESTART': '1', 03:43:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:51 INFO - 'PWD': '/builds/slave/test', 03:43:51 INFO - 'SHELL': '/bin/bash', 03:43:51 INFO - 'SHLVL': '1', 03:43:51 INFO - 'TERM': 'linux', 03:43:51 INFO - 'TMOUT': '86400', 03:43:51 INFO - 'USER': 'cltbld', 03:43:51 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', 03:43:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:51 INFO - '_': '/tools/buildbot/bin/python'} 03:43:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:43:51 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 03:43:51 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 03:43:51 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 03:43:51 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 03:43:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:43:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:43:52 INFO - Installing collected packages: mozsystemmonitor 03:43:52 INFO - Running setup.py install for mozsystemmonitor 03:43:52 INFO - Successfully installed mozsystemmonitor 03:43:52 INFO - Cleaning up... 03:43:53 INFO - Return code: 0 03:43:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:43: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')]} 03:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43: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')]} 03:43:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21139c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2110d60>, '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 0x2111a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2111ef0>, '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': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', '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 03:43:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:43:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:43:53 INFO - Using env: {'DISPLAY': ':0', 03:43:53 INFO - 'HOME': '/home/cltbld', 03:43:53 INFO - 'LANG': 'en_US.UTF-8', 03:43:53 INFO - 'LANGUAGE': 'en_US:en', 03:43:53 INFO - 'LOGNAME': 'cltbld', 03:43:53 INFO - 'MAIL': '/var/mail/cltbld', 03:43:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:53 INFO - 'MOZ_NO_REMOTE': '1', 03:43:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:53 INFO - 'NO_EM_RESTART': '1', 03:43:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:53 INFO - 'PWD': '/builds/slave/test', 03:43:53 INFO - 'SHELL': '/bin/bash', 03:43:53 INFO - 'SHLVL': '1', 03:43:53 INFO - 'TERM': 'linux', 03:43:53 INFO - 'TMOUT': '86400', 03:43:53 INFO - 'USER': 'cltbld', 03:43:53 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', 03:43:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:53 INFO - '_': '/tools/buildbot/bin/python'} 03:43:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:54 INFO - Downloading/unpacking jsonschema==2.5.1 03:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:43:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:43: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 03:43: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 03:43: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 03:43: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 03:43:55 INFO - Downloading functools32-3.2.3-2.tar.gz 03:43:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:43:55 INFO - warning: no files found matching '*.txt' 03:43:55 INFO - no previously-included directories found matching 'build' 03:43:55 INFO - no previously-included directories found matching 'dist' 03:43:55 INFO - no previously-included directories found matching '.git*' 03:43:55 INFO - Installing collected packages: jsonschema, functools32 03:43:55 INFO - Running setup.py install for functools32 03:43:55 INFO - warning: no files found matching '*.txt' 03:43:55 INFO - no previously-included directories found matching 'build' 03:43:55 INFO - no previously-included directories found matching 'dist' 03:43:55 INFO - no previously-included directories found matching '.git*' 03:43:55 INFO - Successfully installed jsonschema functools32 03:43:55 INFO - Cleaning up... 03:43:55 INFO - Return code: 0 03:43:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21139c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2110d60>, '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 0x2111a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2111ef0>, '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': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', '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 03:43:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:43:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:43:55 INFO - Using env: {'DISPLAY': ':0', 03:43:55 INFO - 'HOME': '/home/cltbld', 03:43:55 INFO - 'LANG': 'en_US.UTF-8', 03:43:55 INFO - 'LANGUAGE': 'en_US:en', 03:43:55 INFO - 'LOGNAME': 'cltbld', 03:43:55 INFO - 'MAIL': '/var/mail/cltbld', 03:43:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:55 INFO - 'MOZ_NO_REMOTE': '1', 03:43:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:55 INFO - 'NO_EM_RESTART': '1', 03:43:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:55 INFO - 'PWD': '/builds/slave/test', 03:43:55 INFO - 'SHELL': '/bin/bash', 03:43:55 INFO - 'SHLVL': '1', 03:43:55 INFO - 'TERM': 'linux', 03:43:55 INFO - 'TMOUT': '86400', 03:43:55 INFO - 'USER': 'cltbld', 03:43:55 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', 03:43:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:55 INFO - '_': '/tools/buildbot/bin/python'} 03:43:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:43:55 INFO - Cleaning up... 03:43:55 INFO - Return code: 0 03:43:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1da61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21139c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2110d60>, '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 0x2111a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2111ef0>, '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': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', '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 03:43:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:43:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:43:55 INFO - Using env: {'DISPLAY': ':0', 03:43:55 INFO - 'HOME': '/home/cltbld', 03:43:55 INFO - 'LANG': 'en_US.UTF-8', 03:43:55 INFO - 'LANGUAGE': 'en_US:en', 03:43:55 INFO - 'LOGNAME': 'cltbld', 03:43:55 INFO - 'MAIL': '/var/mail/cltbld', 03:43:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:55 INFO - 'MOZ_NO_REMOTE': '1', 03:43:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:55 INFO - 'NO_EM_RESTART': '1', 03:43:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:55 INFO - 'PWD': '/builds/slave/test', 03:43:55 INFO - 'SHELL': '/bin/bash', 03:43:55 INFO - 'SHLVL': '1', 03:43:55 INFO - 'TERM': 'linux', 03:43:55 INFO - 'TMOUT': '86400', 03:43:55 INFO - 'USER': 'cltbld', 03:43:55 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', 03:43:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:55 INFO - '_': '/tools/buildbot/bin/python'} 03:43:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:55 INFO - Downloading/unpacking blobuploader==1.2.4 03:43: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 03:43: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 03:43: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 03:43: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 03:43:57 INFO - Downloading blobuploader-1.2.4.tar.gz 03:43:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:43:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:43: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 03:43: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 03:43: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 03:43: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 03:43:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:43:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:43: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 03:43: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 03:43: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 03:43: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 03:43:57 INFO - Downloading docopt-0.6.1.tar.gz 03:43:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:43:57 INFO - Installing collected packages: blobuploader, requests, docopt 03:43:57 INFO - Running setup.py install for blobuploader 03:43:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:43:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:43:57 INFO - Running setup.py install for requests 03:43:58 INFO - Running setup.py install for docopt 03:43:58 INFO - Successfully installed blobuploader requests docopt 03:43:58 INFO - Cleaning up... 03:43:58 INFO - Return code: 0 03:43:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43:58 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')]} 03:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:58 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')]} 03:43:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:58 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 0x1da61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21139c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2110d60>, '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 0x2111a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2111ef0>, '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': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', '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 03:43:58 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 03:43:58 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 03:43:58 INFO - Using env: {'DISPLAY': ':0', 03:43:58 INFO - 'HOME': '/home/cltbld', 03:43:58 INFO - 'LANG': 'en_US.UTF-8', 03:43:58 INFO - 'LANGUAGE': 'en_US:en', 03:43:58 INFO - 'LOGNAME': 'cltbld', 03:43:58 INFO - 'MAIL': '/var/mail/cltbld', 03:43:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:58 INFO - 'MOZ_NO_REMOTE': '1', 03:43:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:58 INFO - 'NO_EM_RESTART': '1', 03:43:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:58 INFO - 'PWD': '/builds/slave/test', 03:43:58 INFO - 'SHELL': '/bin/bash', 03:43:58 INFO - 'SHLVL': '1', 03:43:58 INFO - 'TERM': 'linux', 03:43:58 INFO - 'TMOUT': '86400', 03:43:58 INFO - 'USER': 'cltbld', 03:43:58 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', 03:43:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:58 INFO - '_': '/tools/buildbot/bin/python'} 03:43:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:43:58 INFO - Running setup.py (path:/tmp/pip-a6ggiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:43:58 INFO - Running setup.py (path:/tmp/pip-DfJRKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:43:58 INFO - Running setup.py (path:/tmp/pip-RZwBZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:43:58 INFO - Running setup.py (path:/tmp/pip-puYZmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:43:58 INFO - Running setup.py (path:/tmp/pip-GB6rAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:43:58 INFO - Running setup.py (path:/tmp/pip-vLIgmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:43:58 INFO - Running setup.py (path:/tmp/pip-GjmSSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:43:58 INFO - Running setup.py (path:/tmp/pip-YQR5or-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:43:59 INFO - Running setup.py (path:/tmp/pip-fo4fmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:43:59 INFO - Running setup.py (path:/tmp/pip-tE097B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:43:59 INFO - Running setup.py (path:/tmp/pip-eyXizd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:43:59 INFO - Running setup.py (path:/tmp/pip-CmTEOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:43:59 INFO - Running setup.py (path:/tmp/pip-JW36Yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:43:59 INFO - Running setup.py (path:/tmp/pip-nbSgrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:59 INFO - Running setup.py (path:/tmp/pip-lSXM7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:43:59 INFO - Running setup.py (path:/tmp/pip-f6HcQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:43:59 INFO - Running setup.py (path:/tmp/pip-kHwFLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:43:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:43:59 INFO - Running setup.py install for manifestparser 03:43:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:43:59 INFO - Running setup.py install for mozcrash 03:43:59 INFO - Running setup.py install for mozdebug 03:44:00 INFO - Running setup.py install for mozdevice 03:44:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:44:00 INFO - Running setup.py install for mozfile 03:44:00 INFO - Running setup.py install for mozhttpd 03:44:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:44:00 INFO - Running setup.py install for mozinfo 03:44:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:44:00 INFO - Running setup.py install for mozInstall 03:44:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:44:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:44:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:44:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:44:00 INFO - Running setup.py install for mozleak 03:44:00 INFO - Running setup.py install for mozlog 03:44:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:44:00 INFO - Running setup.py install for moznetwork 03:44:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:44:00 INFO - Running setup.py install for mozprocess 03:44:00 INFO - Running setup.py install for mozprofile 03:44:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:44:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:44:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:44:01 INFO - Running setup.py install for mozrunner 03:44:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:44:01 INFO - Running setup.py install for mozscreenshot 03:44:01 INFO - Running setup.py install for moztest 03:44:01 INFO - Running setup.py install for mozversion 03:44:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:44:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:44:01 INFO - Cleaning up... 03:44:01 INFO - Return code: 0 03:44:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:44:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:01 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 0x1da61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21139c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2110d60>, '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 0x2111a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2111ef0>, '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': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', '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 03:44:01 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 03:44:01 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 03:44:01 INFO - Using env: {'DISPLAY': ':0', 03:44:01 INFO - 'HOME': '/home/cltbld', 03:44:01 INFO - 'LANG': 'en_US.UTF-8', 03:44:01 INFO - 'LANGUAGE': 'en_US:en', 03:44:01 INFO - 'LOGNAME': 'cltbld', 03:44:01 INFO - 'MAIL': '/var/mail/cltbld', 03:44:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:01 INFO - 'MOZ_NO_REMOTE': '1', 03:44:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:01 INFO - 'NO_EM_RESTART': '1', 03:44:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:01 INFO - 'PWD': '/builds/slave/test', 03:44:01 INFO - 'SHELL': '/bin/bash', 03:44:01 INFO - 'SHLVL': '1', 03:44:01 INFO - 'TERM': 'linux', 03:44:01 INFO - 'TMOUT': '86400', 03:44:01 INFO - 'USER': 'cltbld', 03:44:01 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', 03:44:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:01 INFO - '_': '/tools/buildbot/bin/python'} 03:44:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:44:01 INFO - Running setup.py (path:/tmp/pip-KDWqmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:44:01 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)) 03:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:44:01 INFO - Running setup.py (path:/tmp/pip-qtzyjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:44:01 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)) 03:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:44:01 INFO - Running setup.py (path:/tmp/pip-kvHNCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:44:01 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)) 03:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:44:01 INFO - Running setup.py (path:/tmp/pip-unKoMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:44:01 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)) 03:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:44:01 INFO - Running setup.py (path:/tmp/pip-1q3jpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:44:02 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)) 03:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:44:02 INFO - Running setup.py (path:/tmp/pip-AB4qJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:44:02 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)) 03:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:44:02 INFO - Running setup.py (path:/tmp/pip-d_sSQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:44:02 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)) 03:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:44:02 INFO - Running setup.py (path:/tmp/pip-UbbedN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:44:02 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)) 03:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:44:02 INFO - Running setup.py (path:/tmp/pip-T5xMbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:44:02 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)) 03:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:44:02 INFO - Running setup.py (path:/tmp/pip-oqZ4Bc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:44:02 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)) 03:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:44:02 INFO - Running setup.py (path:/tmp/pip-AVik2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:44:02 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)) 03:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:44:02 INFO - Running setup.py (path:/tmp/pip-YcdAzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:44:02 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)) 03:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:44:02 INFO - Running setup.py (path:/tmp/pip-X0fyWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:44:02 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)) 03:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:44:02 INFO - Running setup.py (path:/tmp/pip-Lm0m0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:44:02 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)) 03:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:44:02 INFO - Running setup.py (path:/tmp/pip-0isaeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:44:02 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)) 03:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:44:02 INFO - Running setup.py (path:/tmp/pip-VddZ7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:44:02 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)) 03:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:44:02 INFO - Running setup.py (path:/tmp/pip-KwlV6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:44:02 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)) 03:44:02 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)) 03:44:02 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)) 03:44:02 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)) 03:44:02 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)) 03:44:02 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)) 03:44:02 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)) 03:44:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:04 INFO - Downloading blessings-1.6.tar.gz 03:44:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:44:04 INFO - Installing collected packages: blessings 03:44:04 INFO - Running setup.py install for blessings 03:44:04 INFO - Successfully installed blessings 03:44:04 INFO - Cleaning up... 03:44:04 INFO - Return code: 0 03:44:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:44:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:44:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:44:04 INFO - Reading from file tmpfile_stdout 03:44:04 INFO - Current package versions: 03:44:04 INFO - argparse == 1.2.1 03:44:04 INFO - blessings == 1.6 03:44:04 INFO - blobuploader == 1.2.4 03:44:04 INFO - docopt == 0.6.1 03:44:04 INFO - functools32 == 3.2.3-2 03:44:04 INFO - jsonschema == 2.5.1 03:44:04 INFO - manifestparser == 1.1 03:44:04 INFO - mozInstall == 1.13 03:44:04 INFO - mozcrash == 1.0 03:44:04 INFO - mozdebug == 0.1 03:44:04 INFO - mozdevice == 0.51 03:44:04 INFO - mozfile == 1.2 03:44:04 INFO - mozhttpd == 0.7 03:44:04 INFO - mozinfo == 0.10 03:44:04 INFO - mozleak == 0.1 03:44:04 INFO - mozlog == 3.5 03:44:04 INFO - moznetwork == 0.27 03:44:04 INFO - mozprocess == 0.25 03:44:04 INFO - mozprofile == 0.28 03:44:04 INFO - mozrunner == 6.13 03:44:04 INFO - mozscreenshot == 0.1 03:44:04 INFO - mozsystemmonitor == 0.3 03:44:04 INFO - moztest == 0.8 03:44:04 INFO - mozversion == 1.4 03:44:04 INFO - psutil == 3.1.1 03:44:04 INFO - requests == 1.2.3 03:44:04 INFO - wsgiref == 0.1.2 03:44:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:44:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:04 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 0x1da61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21139c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2110d60>, '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 0x2111a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2111ef0>, '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': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', '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 03:44:04 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 03:44:04 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 03:44:04 INFO - Using env: {'DISPLAY': ':0', 03:44:04 INFO - 'HOME': '/home/cltbld', 03:44:04 INFO - 'LANG': 'en_US.UTF-8', 03:44:04 INFO - 'LANGUAGE': 'en_US:en', 03:44:04 INFO - 'LOGNAME': 'cltbld', 03:44:04 INFO - 'MAIL': '/var/mail/cltbld', 03:44:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:04 INFO - 'MOZ_NO_REMOTE': '1', 03:44:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:04 INFO - 'NO_EM_RESTART': '1', 03:44:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:04 INFO - 'PWD': '/builds/slave/test', 03:44:04 INFO - 'SHELL': '/bin/bash', 03:44:04 INFO - 'SHLVL': '1', 03:44:04 INFO - 'TERM': 'linux', 03:44:04 INFO - 'TMOUT': '86400', 03:44:04 INFO - 'USER': 'cltbld', 03:44:04 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', 03:44:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:04 INFO - '_': '/tools/buildbot/bin/python'} 03:44:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:04 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)) 03:44:04 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)) 03:44:04 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)) 03:44:04 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)) 03:44:04 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)) 03:44:04 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)) 03:44:04 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)) 03:44:04 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)) 03:44:04 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)) 03:44:04 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:44:04 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 03:44:04 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 03:44:04 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 03:44:04 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 03:44:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:44:05 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)) 03:44:05 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)) 03:44:05 INFO - Installing collected packages: simplejson 03:44:05 INFO - Running setup.py install for simplejson 03:44:06 INFO - building 'simplejson._speedups' extension 03:44:06 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 03:44:06 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 03:44:06 INFO - Successfully installed simplejson 03:44:06 INFO - Cleaning up... 03:44:06 INFO - Return code: 0 03:44:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:44:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:06 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 0x1da61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21139c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2110d60>, '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 0x2111a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2111ef0>, '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': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', '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 03:44:06 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 03:44:06 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 03:44:06 INFO - Using env: {'DISPLAY': ':0', 03:44:06 INFO - 'HOME': '/home/cltbld', 03:44:06 INFO - 'LANG': 'en_US.UTF-8', 03:44:06 INFO - 'LANGUAGE': 'en_US:en', 03:44:06 INFO - 'LOGNAME': 'cltbld', 03:44:06 INFO - 'MAIL': '/var/mail/cltbld', 03:44:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:06 INFO - 'MOZ_NO_REMOTE': '1', 03:44:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:06 INFO - 'NO_EM_RESTART': '1', 03:44:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:06 INFO - 'PWD': '/builds/slave/test', 03:44:06 INFO - 'SHELL': '/bin/bash', 03:44:06 INFO - 'SHLVL': '1', 03:44:06 INFO - 'TERM': 'linux', 03:44:06 INFO - 'TMOUT': '86400', 03:44:06 INFO - 'USER': 'cltbld', 03:44:06 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', 03:44:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:06 INFO - '_': '/tools/buildbot/bin/python'} 03:44:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:44:07 INFO - Cleaning up... 03:44:07 INFO - Return code: 0 03:44:07 INFO - Running post-action listener: _resource_record_post_action 03:44:07 INFO - Running post-action listener: _start_resource_monitoring 03:44:07 INFO - Starting resource monitoring. 03:44:07 INFO - [mozharness: 2017-09-29 10:44:07.245490Z] Finished create-virtualenv step (success) 03:44:07 INFO - [mozharness: 2017-09-29 10:44:07.246006Z] Running install step. 03:44:07 INFO - Running pre-action listener: _resource_record_pre_action 03:44:07 INFO - Running main action method: install 03:44:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:44:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:44:07 INFO - Reading from file tmpfile_stdout 03:44:07 INFO - Detecting whether we're running mozinstall >=1.0... 03:44:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:44:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:44:07 INFO - Reading from file tmpfile_stdout 03:44:07 INFO - Output received: 03:44:07 INFO - Usage: mozinstall [options] installer 03:44:07 INFO - Options: 03:44:07 INFO - -h, --help show this help message and exit 03:44:07 INFO - -d DEST, --destination=DEST 03:44:07 INFO - Directory to install application into. [default: 03:44:07 INFO - "/builds/slave/test"] 03:44:07 INFO - --app=APP Application being installed. [default: firefox] 03:44:07 INFO - mkdir: /builds/slave/test/build/application 03:44:07 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'] 03:44:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:44:25 INFO - Reading from file tmpfile_stdout 03:44:25 INFO - Output received: 03:44:25 INFO - /builds/slave/test/build/application/firefox/firefox 03:44:25 INFO - Running post-action listener: _resource_record_post_action 03:44:25 INFO - [mozharness: 2017-09-29 10:44:25.406219Z] Finished install step (success) 03:44:25 INFO - [mozharness: 2017-09-29 10:44:25.406475Z] Running setup-mitmproxy step. 03:44:25 INFO - Running pre-action listener: _resource_record_pre_action 03:44:25 INFO - Running main action method: setup_mitmproxy 03:44:25 INFO - Skipping: mitmproxy is not required 03:44:25 INFO - Running post-action listener: _resource_record_post_action 03:44:25 INFO - [mozharness: 2017-09-29 10:44:25.407611Z] Finished setup-mitmproxy step (success) 03:44:25 INFO - [mozharness: 2017-09-29 10:44:25.407847Z] Running run-tests step. 03:44:25 INFO - Running pre-action listener: _resource_record_pre_action 03:44:25 INFO - Running pre-action listener: _set_gcov_prefix 03:44:25 INFO - Running main action method: run_tests 03:44:25 WARNING - Try message not found. 03:44:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:44:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:44:25 INFO - Python 2.7.3 03:44:25 INFO - Return code: 0 03:44:25 INFO - grabbing minidump binary from tooltool 03:44:25 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')]} 03:44:25 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 0x2110d60>, '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 0x2111a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2111ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:44:25 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 03:44:25 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 03:44:25 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 03:44:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:44:25 INFO - Return code: 0 03:44:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:44:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:44:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:44:25 INFO - ENV: RUST_BACKTRACE is now full 03:44:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:44:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:44:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:44:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-052 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:44:25 INFO - Using env: {'DISPLAY': ':0', 03:44:25 INFO - 'HOME': '/home/cltbld', 03:44:25 INFO - 'LANG': 'en_US.UTF-8', 03:44:25 INFO - 'LANGUAGE': 'en_US:en', 03:44:25 INFO - 'LOGNAME': 'cltbld', 03:44:25 INFO - 'MAIL': '/var/mail/cltbld', 03:44:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:44:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:44:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:25 INFO - 'MOZ_NO_REMOTE': '1', 03:44:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:44:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:25 INFO - 'NO_EM_RESTART': '1', 03:44:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:25 INFO - 'PWD': '/builds/slave/test', 03:44:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:44:25 INFO - 'RUST_BACKTRACE': 'full', 03:44:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:44:25 INFO - 'SHELL': '/bin/bash', 03:44:25 INFO - 'SHLVL': '1', 03:44:25 INFO - 'TERM': 'linux', 03:44:25 INFO - 'TMOUT': '86400', 03:44:25 INFO - 'USER': 'cltbld', 03:44:25 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', 03:44:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:25 INFO - '_': '/tools/buildbot/bin/python'} 03:44:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:44:25 INFO - mozversion application_buildid: 20170929094722 03:44:25 INFO - mozversion application_changeset: 946b9c995ec331f4f96360409fd8d2fc49e46838 03:44:25 INFO - mozversion application_display_name: Nightly 03:44:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:44:25 INFO - mozversion application_name: Firefox 03:44:25 INFO - mozversion application_remotingname: firefox 03:44:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:44:25 INFO - mozversion application_vendor: Mozilla 03:44:25 INFO - mozversion application_version: 58.0a1 03:44:25 INFO - mozversion platform_buildid: 20170929094722 03:44:25 INFO - mozversion platform_changeset: 946b9c995ec331f4f96360409fd8d2fc49e46838 03:44:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:44:25 INFO - mozversion platform_version: 58.0a1 03:44:25 INFO - using testdate: 1506681865 03:44:25 INFO - actual date: 1506681865 03:44:25 INFO - starting webserver on 'localhost:43881' 03:44:25 INFO - SUITE-START | Running 2 tests 03:44:25 INFO - TEST-START | dromaeo_css 03:44:25 INFO - Initialising browser for dromaeo_css test... 03:44:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43881/getInfo.html -profile /tmp/tmpAtR6VS/profile 03:44:25 INFO - TEST-INFO | started process 30577 (/builds/slave/test/build/application/firefox/firefox http://localhost:43881/getInfo.html) 03:44:30 INFO - TEST-INFO | 30577: exit 0 03:44:30 INFO - Browser initialized. 03:44:30 INFO - Running cycle 1/1 for dromaeo_css test... 03:44:30 INFO - Using env: {'DISPLAY': ':0', 03:44:30 INFO - 'HOME': '/home/cltbld', 03:44:30 INFO - 'JSGC_DISABLE_POISONING': '1', 03:44:30 INFO - 'LANG': 'en_US.UTF-8', 03:44:30 INFO - 'LANGUAGE': 'en_US:en', 03:44:30 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:44:30 INFO - 'LOGNAME': 'cltbld', 03:44:30 INFO - 'MAIL': '/var/mail/cltbld', 03:44:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:44:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:44:30 INFO - 'MOZ_CRASHREPORTER': '1', 03:44:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:30 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:44:30 INFO - 'MOZ_NO_REMOTE': '1', 03:44:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:44:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:30 INFO - 'NO_EM_RESTART': '1', 03:44:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:30 INFO - 'PWD': '/builds/slave/test', 03:44:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:44:30 INFO - 'RUST_BACKTRACE': 'full', 03:44:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:44:30 INFO - 'SHELL': '/bin/bash', 03:44:30 INFO - 'SHLVL': '1', 03:44:30 INFO - 'TERM': 'linux', 03:44:30 INFO - 'TMOUT': '86400', 03:44:30 INFO - 'USER': 'cltbld', 03:44:30 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', 03:44:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:30 INFO - '_': '/tools/buildbot/bin/python'} 03:44:30 INFO - TEST-INFO | started process 30809 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAtR6VS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:44:31 INFO - PID 30809 | 03:44:31 INFO - PID 30809 | (/builds/slave/test/build/application/firefox/firefox:30857): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:44:31 INFO - PID 30809 | 03:44:42 INFO - PID 30809 | RSS: Main: 153006080 03:44:42 INFO - PID 30809 | 03:47:39 INFO - PID 30809 | Cycle 1(1): loaded http://localhost:43881/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43881/tests/dromaeo/cssquery-ext.html) 03:47:40 INFO - PID 30809 | RSS: Main: 150990848 03:47:40 INFO - PID 30809 | 03:50:42 INFO - PID 30809 | Cycle 1(1): loaded http://localhost:43881/tests/dromaeo/cssquery-ext.html (next: http://localhost:43881/tests/dromaeo/cssquery-jquery.html) 03:50:43 INFO - PID 30809 | RSS: Main: 149258240 03:50:43 INFO - PID 30809 | 03:53:44 INFO - PID 30809 | Cycle 1(1): loaded http://localhost:43881/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43881/tests/dromaeo/cssquery-mootools.html) 03:53:45 INFO - PID 30809 | RSS: Main: 152178688 03:53:45 INFO - PID 30809 | 03:56:47 INFO - PID 30809 | Cycle 1(1): loaded http://localhost:43881/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43881/tests/dromaeo/cssquery-prototype.html) 03:56:47 INFO - PID 30809 | RSS: Main: 152616960 03:56:47 INFO - PID 30809 | 03:59:51 INFO - PID 30809 | Cycle 1(1): loaded http://localhost:43881/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43881/tests/dromaeo/cssquery-yui.html) 03:59:51 INFO - PID 30809 | RSS: Main: 154746880 03:59:51 INFO - PID 30809 | 04:02:53 INFO - PID 30809 | Cycle 1(1): loaded http://localhost:43881/tests/dromaeo/cssquery-yui.html (next: http://localhost:43881/tests/dromaeo/cssquery-dojo.html) 04:02:53 INFO - PID 30809 | RSS: Main: 155615232 04:02:53 INFO - PID 30809 | 04:02:53 INFO - PID 30809 | __start_tp_report 04:02:53 INFO - PID 30809 | _x_x_mozilla_page_load 04:02:53 INFO - PID 30809 | _x_x_mozilla_page_load_details 04:02:53 INFO - PID 30809 | |i|pagename|runs| 04:02:53 INFO - PID 30809 | |0;dojo.html;3170.984145079275;3195.2171717929104;3232.773705840589;3248;3248.3990461764574;6213.937860621392;6421.582597131189;6432.5497215194955;6462.806115816521;6578.375054369833;6356.68216589171;6367.458766004884;6381.968090159543;6415.807525774234;6437.806865794022;5832.270966129234;5865.061590145578;5870.207521984542;5910.88178236435;5955.34491205967;5834.883302333951;5873.735681894325;5875;5881.970590147064;5944.2866855977345;5680.715964201773;5893.447423540042;5932;5991.191189189469;6030.125631783389;5801.419858014207;5881.970590147064;5889.823305300848;5968.880622387528;5975.432333928314;5864.794732184396;5872.207252021008;5947.524198064145;5948.672822994734;5973.8805223895715;6210.472109870667;6361.427471527585;6362.809115726535;6504.804855854332;6506.316836732122;6797.898031529531;6810.659467026629;6824.829379265509;6830.282820303846;6834;6906.861862762717;6988.93011069893;7020.192677842062;7025.19210290818;7026.754063607749;6173.567850250484;6314.463270622003;6401.8399540011405;6442.065900444456;6492.902606460907;6848.212455567674;7020.648967551602;7136.286371362822;7189.604571748604;7198.064251647253;6747.156605424322;6779.559328643622;6838.452923766087;6853.314668533207;6861.039454476378;6703.932960670429;6858.348456896587;6903.6548172591165;6978.337057979484;6987.301269872972;6746.156730408699;6768.984652302096;6899.344562266677;6908.6545672716165;6921;6926;6935.861282774316;6939.965300173467;6947.652617369111;6994.020837082812;5351.224072509464;5372.462753724674;5433.945660543423;5440.047991601454;5447.319085114361;4678.461976872635;4690.507496712864;4718.363020992191;4731.95268047322;4767;5719.685417302005;5840.59115861894;5859.414058594107;5923.200367950271;5993.940060599425;6931.549449285882;6963.1296087989;7009.193942696553;7013.088298521159;7014.19336776267;5874.618149820248;5902.085176797603;5906.232189815297;5958.136070269787;5967.433093856077;5816.767329306865;5848.736806843703;5920.407959204045;5920.437558431942;5934.910976335358;6441.5490915635455;6558.213014438298;6582.111414958919;6667.599944003376;6680.766173183915;6912.377885990184;6919.100516932766;6924.4114250289285;6924.58452492852;6951.7914462566205;6532.379423954717;6557.672116394257;6574.178227721535;6649.800505984828;6652.401283884474;6710.127683401127;6721.563098398588;6796.72813087471;6837.213720422215;6857.622830744357;6753.662316884038;6890.31096890307;6895.586264824126;6970.616616086164;6990.26602206761;6657.833554161185;6728.226253980855;6737.393634572912;6763.458923286027;6785.423239024541;6645.102911106938;6690.59856408617;6736.023276624764;6754.054432379569;6764.188297404343;6567.737290508518;6600.60396376219;6672;6673.232578253369;6704;6614.404703576702;6677.43241824431;6724.428423583854;6783.084283621845;6788.626625535643;6496.577722448026;6521.608703477807;6536.117624120873;6537.346265373615;6609.239937407259 04:02:53 INFO - PID 30809 | |1;ext.html;790.9841803163937;794.6225542867143;816.9142240064793;837.9078301386846;841.8232171244039;40096;40855.182896342056;41037.79481102594;41302.586974130325;41408.96477588058;37966.62033379665;38285.808570957175;38315.233695326075;38337.233255334875;38357;6313.4633556147555;6317.52618553608;6330.303666596671;6331.49348052157;6352.9364706353035;6258.280297765747;6266.248050233956;6268.843278918041;6272.090546870701;6325.557210995232;6185.41238582334;6201.875962480748;6213.974694175456;6231.096491008801;6255.499560035186;6211.4409703126485;6214.1921550198185;6224;6234.158388617547;6245.968770156142;6488.545801793876;6549.836254093638;6590.3080176581725;6597.340265973365;6617.040529123298;33381;33611.495827562605;33639.49540756891;33696.49455258173;33708.49437258419;3347.397468455677;3348.698617124446;3360.865565377382;3362.243495213582;3366.6969972702573;28200.717992820013;28233;28319.433611327862;28348.149555513366;28372.574411383845;4484.349769283435;4551.294549344857;4561.996360800652;4580.450345958507;4597.586217240464;190586;191014;192080;192312.0384398083;192438;20725.58548829015;20959.47601309949;21036;21103.784102669306;21157;52625;53457;53460;53556.73221633867;53817;16499.257533411026;16594.170291485378;16636.916815416087;16645.750313745302;16655.6668866622;12930;13113.606591802261;13193.604191874067;13206.075574709677;13210.867891321155;14322.212278324792;14487.565373038826;14522.636934076732;14542.27288635564;14702.338394772261;5758.654480731169;5764.855878602984;5815.41845815415;5880.911786323209;5964.522838172936;991.7124034030195;1001.0690058245901;1010.1565193063727;1011.0041609015115;1027.8869324374314;23061.308160755205;23197.53604927892;23245.070197191922;23260.41848953756;23432;1098.2531878322818;1125.7016890524017;1132.3432409202642;1137.1982752159709;1144.2619510415902;12087.09346798976;12386.938065309796;12390.132690711564;12423.701615201217;12460.065495087723;232025;232111;232333;232603;232777;44449.55550444519;45590.31614525785;52642.47357526376;52992.73503632457;53158.20262696063;1326.0717497751612;1434.2255182201495;1474.8746356559814;1475.2255066090388;1481.6073740458787;26893.193204203904;27151.86424067867;27456.862715686293;27496.450070998068;27914.16257512278;35343.469847952816;35372.29255414929;35398.469022964164;35439.29121417609;35480;176389;176796;176939;177602.22397776373;178229;32987.34025319528;33798.32403351968;34049.82975085109;34050.489242660886;34422.82788586041;42255.366169506866;42371.576284238;42527.787361063;42552;42736;30836;30938.535921960738;31602.683973159976;31608.841955790074;31751.364972700878;30993.69006309998;31024.22439439032;31028.844855775576;31059.844700777256;31151.221219469695;34924;34969.82515087408;34978;34986.82506587451;35001.124971875906;30583.694163058084;30622.234444139074;30681.693183067884;30745.846270768503;30758;22560.323190304316;22739.886300568392;22744.545109098057;23250.76749232486;23364.299071027897 04:02:53 INFO - PID 30809 | |2;jquery.html;545.1686178577669;564.2213745031858;567.4467394290565;595.4373117404052;603.4448307557047;22715.20496782613;23353.883230583822;23459.648105278415;23478.647820282687;23503.529929401444;23429.179978700748;23495.06019759212;23545.882270588667;24795.256142315713;25207.865646045928;6695.3304669533145;6871.415929645961;6874.9656251718925;6893.586384816902;6900.137482814648;6552.639604821733;6761.35767102127;6852.486063545229;6867.450603951672;6879.484038697093;6787.830304242409;6795.354441328066;6798.286179951108;6813.761518346859;6821.3519715626935;6777.661116944158;6781.830454238683;6792.320767923217;6802.4217941475035;6813.4208592269715;6298.716557754912;6828.931710682879;7168.247334029904;7257.45569082321;7268.745593904241;31090;31817.045488635376;31842;31866;31946.361072778414;2111.419359676086;2117.375374264596;2124.0866427436194;2128.255110711254;2138.786121387864;20965.16139354424;21264.574708505897;21790.782092179033;21880.343589692333;22058.007389667502;2576.5233431815172;2613.9085132020286;2678.504476671822;2679.9196024119306;2691.421344410961;127456;132589.33705331507;134004.32997835046;140858;148233;20097.698534521995;21613.243536476148;21822.59951405344;22084.779152208273;23336;44376.11247775026;44680.10639787186;44841;44930.77534612306;44957.55042449533;11552.191346605658;11949.64151075469;11972.401379930969;12079;12122.454489547992;9918.900810991941;9964.451955142391;10048.598056077675;10054.64808731691;10075.09324160829;11210.607628732956;11711.238769479956;11716.882831171579;11720.941395293139;11928.76142477163;3707.8516859325323;3829.751066180745;3895.4351619015083;4045.5954404559307;4098.569650186747;931.4597533430594;962.7978124593914;967.8062394099444;972.747085757694;976.028851292968;19830.90084549568;19904.800951990583;20216.191352346035;20336.389908302775;20646.277380291616;1028.6502589119734;1030.8247597908373;1041.3959903256093;1048.564845589072;1050.2700623066896;10825.729356765987;10866.72833179161;11080.833787493195;11097.945695158947;11265.098792096613;510063;518164;522757;523541;530496;113372;118579;118889;119015.40492297483;119097.40451297861;1064.5316060933164;1102.030213412192;1127.7913585986619;1153.8499995000764;1213.2477863724548;20669.483262918548;20883.582328353044;20915.68626470574;21063.473413164793;21082.894585526974;55466;55836;56548;57609.711951439975;58461.4153858473;68422.65788671224;68526.314736854;68609;68626.65686671535;69627.30372696208;20445.693314599994;20878.37364879056;20913.37259882206;21203.36389908305;21227;23067.1926482579;23493.765062349157;23616.645750314103;23625.173118940755;23649.881750591136;18729.438116856516;18919.054047297854;19021.52446188801;19042.523936901132;19076.1415736295;16470.858788357436;16789.66420671555;17036.31854725797;17210.225539850988;17672;19347.226110955406;19366.903165484084;19442.222311107398;19575.119119639934;19680.21279148818;20732.58534829325;20824.791752082892;20870.68693969622;20935.26726564563;20994;18988.145533450752;19010.904945475184;19027.90486047561;19165.137568809714;19220 04:02:53 INFO - PID 30809 | |3;mootools.html;1330.5609148980836;1382.5714028651116;1387.4519564771908;1398.8531204223557;1406.2757679794902;15150.77273840892;15751.763723544142;15755.291011904463;15760.684786304268;15772.605684857885;15372;15529.844701552982;15539.22303884807;15543;15549.922250388732;2772.6672799264115;2896.362800183956;2898.058131107388;2898.608687827148;2898.6521617405942;2698.2579790557556;2830.4197639483855;2840.2047426720446;2843;2843.0475790610067;2709.945801083977;2836.276749428893;2880.9855950720216;2900.8694608742658;2909.476294267031;2828.9292767680768;2841.6447944007;2854.728800763924;2895.160403482998;2906.3896581717863;2715.5383584790434;2781.8330900146057;2831.1931099636504;2840.076974983139;2841.2186648671573;15208.695826083416;15230.695386092328;15251.694966100617;15311;15370.538883833502;8090.555019473926;8324.209200125979;8385.161483851627;8393.748187554384;8396.580170991487;5537.944620553783;5539.8366343068;5643.379228284885;5810.767569297223;5869.383714709937;1908.1031914999928;1916.492129585661;1932.1498540642071;1934.4680212941414;1963.1264087481077;27687.584686229722;27986.580201296998;27987.300317492227;28015.299617509725;28047.719522804713;7031.08595882532;7091.169376188885;7116.71533138679;7193.100862392201;7257.637118144072;17636.294548217928;17703.911480442515;17721.113944302735;17765.911170444324;17806.109694515224;8057.113717491071;8173.754787356389;8193.8361232775;8205.138460461685;8210.261076503144;7408.296211859864;7430.517016393917;7469.962650186714;7588.392928565701;7643.847123057508;5090.745462726923;5296.947030529723;5382.973085134549;5395.244665746799;5397.7571009304675;1506.5555661080007;1519.7340465418508;1522.4975757999835;1535.1403214200084;1563.4762354611105;885.8139790643908;895.4269267668698;901.8096113130605;901.8151278987792;906.7914379692708;7214.176486462184;7502.17476077631;7618.314351708373;8472.534010629475;8751.693690720795;690.1200968764765;709.2942522190453;709.3332267668478;716.1513606376357;717.9174394944648;4551.840685575989;4954.925676114861;5083.593312534988;5284.682919024871;5492.450754924475;21333;22204.88897555502;22631.20790772348;22954;23010.65484017741;22251;22607.208747693752;22655.660165097535;22722.659160112613;22932.19737309186;11316.490757915913;11619.186656933933;11715.589954351728;11733.35466549348;11761.411929403666;8216.137305582828;8246.752597422086;8263.504189748634;8296.87554686668;8461.32309415233;18468.907655461637;18567.44297671072;18617.441476755717;18781.154848032143;18900.810991889906;19544.315948942287;19659.311924082587;19680.60638787245;19723;19818.306359277358;10555.472226388803;10719.356838589709;10720.195985300978;10721.571137154426;10724.088452481627;13976.790348144583;14055;14272.286385680467;14415.063020903608;14416.495422660577;11349.54601815918;11511.251768635011;11571.710707232387;11724.120690948042;11921.046316294849;11030.117590592894;11035.503402347074;11213.943930280297;11249.100071994384;11374.943125284652;9553.761155971157;9772.804543909224;9987.350822196535;9991.850122248317;10006.949965250127;7122.964385178041;7224.385927196247;7244.4566657499845;7273.854522909618;7292.453066020176;7516.3611093055515;7532.397408207221;7540.283673051052;7616.657250423634;7685.654145563351 04:02:53 INFO - PID 30809 | |4;prototype.html;500.56338309053;502.9739331763203;524.9422563518013;529.9841004769859;533.4052531427457;6166.260048794145;6295.464885484728;6321.747130114795;6340.175777148976;6367;6167.9691601542045;6214.906776398358;6237.469815065725;6300.810975670725;6320.3363646817;5041.697498150104;5042.420121686007;5050.3434553508;5056.646034777567;5109.565686916617;4893.363862697862;5086.720230387327;5122.052420302238;5125.461826508219;5138.144453491452;5065.620078494109;5075.162598171298;5118.667286626376;5129.53834154926;5145.768440420171;5007.399112106534;5023.32185155003;5039.823606173784;5052.863105801202;5052.9747351263;4535.63714902807;4607.424071991001;4613;4613.746243956581;4631.143238500888;6237.257645412761;6280.905786413162;6303;6358.173437453148;6363.300036995926;5772.855678608026;5798.681072541009;5852.76588936442;5856.736446859902;5857.4435428634215;6656.267810540837;6685.832854178684;6696.263411024784;6704.363085506869;6763.381590848872;4973.15456372414;4993.375828021497;4997.950020499785;5006.974965125151;5033.672811267293;6889.896651550231;6980.197277313123;7006.229314775371;7052.153741550996;7072.222055573883;5068.873278168076;5096;5122.1804511278015;5140.717260550631;5152.639315247971;5196.636235463481;5200.531952124327;5210.505002024802;5220.556252718486;5250.711210883438;5768.596198266165;5810.622309549865;5825.378579639179;5829.970850145722;5868.325142608569;6397.296297407282;6697.263301036882;6768.72925083001;6779.254282028973;6801.931980680229;1698.728203487436;1714.9828501715074;1768.9557761056076;1776.4848194023846;1788.1059470264868;1204.795184818583;1226.141700809437;1229.8524177098805;1248.6815861955324;1263.728298415845;5697.430256974352;5751.59738818287;5751.692776653441;5809.564282678816;5812.680302583399;6881.208661004049;6884.896726549106;6917.550359226634;6926.272741362185;6937.340952609494;5766.826995190151;5796.942030579725;5849.853753656107;5867.70661466925;5903.675297858659;3624.909377265537;3631.473436351714;3655.6474104580943;3666.431703086026;3691.169486865434;6419.935800642027;6425.903611445832;6509.381608747161;6516.706748196342;6543.738250470023;6876;6878.518503704793;6949.861002779916;6950.548214366151;6956.617386043715;6410.903836442364;6435.678216089177;6493.383128602775;6519.348065193538;6586;6540.313267107075;6619.735210591522;6703.899441508479;6741.292164322872;6776.661166941534;6681.365270299314;6819.761308354382;6850.349216824394;6874.965625172043;6904.620245886525;6502.967485162544;6527.477801775942;6559.508036897157;6561.770338038145;6612;5097.923531146962;5103.617228707788;5109.795608175631;5120.155174396332;5131.435542090442;6635.734570617121;6816.216135144522;6856.725730970706;6869;6896.379325860697;6346.460550853045;6519.119918810899;6558.1802274715665;6622.543040531075;6675.496688741722;6478.384553467413;6588.901166482602;6600.445692472179;6608.273089960197;6614.801555953329;6288.371162883675;6369.363063693593;6419.26178489461;6435.839104022437;6519.445847103049;6355.586886852339;6428.8071357859335;6494.545381823416;6640.501962352746;6651.301613330531;6278.780242691484;6354.205724284465;6371.55399122057;6472.449841763503;6472.838179045561 04:02:53 INFO - PID 30809 | |5;yui.html;886.2466903132338;901.3780491460892;917.8531434970398;921.0743203080903;932.0772435289066;19957.900210498963;20402.285919992803;20712.585748285026;20756;20782.6882596761;18737.43787686368;18973.90513047433;19065.809341906577;19113.139908704143;19156.521086972796;944.8724422203018;964.5177411294353;967.7338731848728;969.6993931881104;969.781799095205;982.076847763104;985.1133979418508;985.2216748768465;985.2315194148575;985.6500942165535;998.9511013435863;1027.250107421579;1038.8493668418075;1044.5038606661813;1044.8119338519064;1037.1132681557274;1038.4444322287616;1038.875334959806;1038.890916453769;1039.5114296280735;716.3159182980241;735.0811485642946;753.932146106853;755.1504557372955;755.3768141283408;20180.29368972079;20261;20264.79735202644;20354.5929081419;20451.079701413473;21772.237971671075;22054.779452205592;22627.88686056559;23148.53702925949;23151.76848231513;18061.187246573805;18128.365507207316;18258.178381972844;18378.081095945283;18422.63154736911;3847.7883716395586;3865.942010869839;3873.690104791638;3873.9612603873884;3874.5156855393075;108460;141475;144179;144859;145680;17614.823851761575;17937.192826322847;18045.548861278574;18050.548736281697;18059.54851128732;32756;33564;33676.663233367355;33690.663093368756;33743.83128084393;8266.751997440086;8541.273991710774;8543;8595.22642962124;8618.482891026559;3895.5520115187114;3903.2583809076186;3934.429507721364;3947.624975627311;3966.6628336591134;5618.52242559387;5623.10030395143;5647.915281270784;5748.626339288016;5760.913586296125;1083.9945800270948;1098.9395583243;1101.7851518953728;1112.3381587955155;1113.8496302999154;634.9333320001333;652.1326635574675;663.4062514049898;666.4934649666288;671.1476424940298;11153.996140347408;11340.298955156628;12150.817737733942;13226.07417480767;13592.11651242666;703.3142685881224;707.6355676826438;711.5552779512805;723.7249845058949;724.9420046396276;5901.321348044944;5938.61399009063;5951.434613711691;6157.876842463125;6209.348018458088;122730.70442534468;124208;124324.37837810753;124890.37554812168;127511;103570;105954;106512;110233;110473;92111;93905.060949391;95164;95652.0434795657;96444;11088.611898583511;11141.108711302913;11210.55157793679;11334.526511553446;11426.085913127095;46114.30828537642;46589.53410465988;46908.061838763715;47187.292190616485;47221;59984.700076499335;60935;61170.69414653076;61555.38444615497;62477.37522624897;15236.390544378544;15543.84456155424;15558.688826223186;15694.744420446108;15804.60488487751;17667.646647067242;17681.823181768017;17730.468085957444;17877.910610447383;17981.73027404564;16094.839051609802;16113;16212;16268.51194464168;16438.506844794676;14638.707225855636;14849.851501484847;14877.107373557621;14893.925530372278;14897.180655064076;16433.506994790176;16561;16611.41860034893;16688.08215548108;16711.916440418205;11610.535578576762;11627.302361858316;11685.182037257311;11687;11812.232204906653;11582.36297003673;11747.236429632047;11825.172237943605;12006.459709312927;12154.878451215374 04:02:53 INFO - PID 30809 | __end_tp_report 04:02:53 INFO - PID 30809 | __start_cc_report 04:02:53 INFO - PID 30809 | _x_x_mozilla_cycle_collect,259 04:02:53 INFO - PID 30809 | __end_cc_report 04:02:53 INFO - PID 30809 | __startTimestamp1506682973518__endTimestamp 04:02:53 INFO - PID 30809 | 04:02:53 INFO - PID 30809 | ------- Summary: start ------- 04:02:53 INFO - PID 30809 | Number of tests: 6 04:02:53 INFO - PID 30809 | 04:02:53 INFO - PID 30809 | [#0] dojo.html Cycles:165 Average:6321.57 Median:6567.74 stddev:766.93 (11.7%) stddev-sans-first:728.37 04:02:53 INFO - PID 30809 | Values: 3171.0 3195.2 3232.8 3248.0 3248.4 6213.9 6421.6 6432.5 6462.8 6578.4 6356.7 6367.5 6382.0 6415.8 6437.8 5832.3 5865.1 5870.2 5910.9 5955.3 5834.9 5873.7 5875.0 5882.0 5944.3 5680.7 5893.4 5932.0 5991.2 6030.1 5801.4 5882.0 5889.8 5968.9 5975.4 5864.8 5872.2 5947.5 5948.7 5973.9 6210.5 6361.4 6362.8 6504.8 6506.3 6797.9 6810.7 6824.8 6830.3 6834.0 6906.9 6988.9 7020.2 7025.2 7026.8 6173.6 6314.5 6401.8 6442.1 6492.9 6848.2 7020.6 7136.3 7189.6 7198.1 6747.2 6779.6 6838.5 6853.3 6861.0 6703.9 6858.3 6903.7 6978.3 6987.3 6746.2 6769.0 6899.3 6908.7 6921.0 6926.0 6935.9 6940.0 6947.7 6994.0 5351.2 5372.5 5433.9 5440.0 5447.3 4678.5 4690.5 4718.4 4732.0 4767.0 5719.7 5840.6 5859.4 5923.2 5993.9 6931.5 6963.1 7009.2 7013.1 7014.2 5874.6 5902.1 5906.2 5958.1 5967.4 5816.8 5848.7 5920.4 5920.4 5934.9 6441.5 6558.2 6582.1 6667.6 6680.8 6912.4 6919.1 6924.4 6924.6 6951.8 6532.4 6557.7 6574.2 6649.8 6652.4 6710.1 6721.6 6796.7 6837.2 6857.6 6753.7 6890.3 6895.6 6970.6 6990.3 6657.8 6728.2 6737.4 6763.5 6785.4 6645.1 6690.6 6736.0 6754.1 6764.2 6567.7 6600.6 6672.0 6673.2 6704.0 6614.4 6677.4 6724.4 6783.1 6788.6 6496.6 6521.6 6536.1 6537.3 6609.2 04:02:53 INFO - PID 30809 | 04:02:53 INFO - PID 30809 | [#1] ext.html Cycles:180 Average:35728.04 Median:18364.20 stddev:52387.46 (285.3%) stddev-sans-first:52468.74 04:02:53 INFO - PID 30809 | Values: 791.0 794.6 816.9 837.9 841.8 40096.0 40855.2 41037.8 41302.6 41409.0 37966.6 38285.8 38315.2 38337.2 38357.0 6313.5 6317.5 6330.3 6331.5 6352.9 6258.3 6266.2 6268.8 6272.1 6325.6 6185.4 6201.9 6214.0 6231.1 6255.5 6211.4 6214.2 6224.0 6234.2 6246.0 6488.5 6549.8 6590.3 6597.3 6617.0 33381.0 33611.5 33639.5 33696.5 33708.5 3347.4 3348.7 3360.9 3362.2 3366.7 28200.7 28233.0 28319.4 28348.1 28372.6 4484.3 4551.3 4562.0 4580.5 4597.6 190586.0 191014.0 192080.0 192312.0 192438.0 20725.6 20959.5 21036.0 21103.8 21157.0 52625.0 53457.0 53460.0 53556.7 53817.0 16499.3 16594.2 16636.9 16645.8 16655.7 12930.0 13113.6 13193.6 13206.1 13210.9 14322.2 14487.6 14522.6 14542.3 14702.3 5758.7 5764.9 5815.4 5880.9 5964.5 991.7 1001.1 1010.2 1011.0 1027.9 23061.3 23197.5 23245.1 23260.4 23432.0 1098.3 1125.7 1132.3 1137.2 1144.3 12087.1 12386.9 12390.1 12423.7 12460.1 232025.0 232111.0 232333.0 232603.0 232777.0 44449.6 45590.3 52642.5 52992.7 53158.2 1326.1 1434.2 1474.9 1475.2 1481.6 26893.2 27151.9 27456.9 27496.5 27914.2 35343.5 35372.3 35398.5 35439.3 35480.0 176389.0 176796.0 176939.0 177602.2 178229.0 32987.3 33798.3 34049.8 34050.5 34422.8 42255.4 42371.6 42527.8 42552.0 42736.0 30836.0 30938.5 31602.7 31608.8 31751.4 30993.7 31024.2 31028.8 31059.8 31151.2 34924.0 34969.8 34978.0 34986.8 35001.1 30583.7 30622.2 30681.7 30745.8 30758.0 22560.3 22739.9 22744.5 23250.8 23364.3 04:02:53 INFO - PID 30809 | 04:02:53 INFO - PID 30809 | [#2] jquery.html Cycles:180 Average:37233.41 Median:22071.39 stddev:87107.62 (394.7%) stddev-sans-first:87308.42 04:02:53 INFO - PID 30809 | Values: 545.2 564.2 567.4 595.4 603.4 22715.2 23353.9 23459.6 23478.6 23503.5 23429.2 23495.1 23545.9 24795.3 25207.9 6695.3 6871.4 6875.0 6893.6 6900.1 6552.6 6761.4 6852.5 6867.5 6879.5 6787.8 6795.4 6798.3 6813.8 6821.4 6777.7 6781.8 6792.3 6802.4 6813.4 6298.7 6828.9 7168.2 7257.5 7268.7 31090.0 31817.0 31842.0 31866.0 31946.4 2111.4 2117.4 2124.1 2128.3 2138.8 20965.2 21264.6 21790.8 21880.3 22058.0 2576.5 2613.9 2678.5 2679.9 2691.4 127456.0 132589.3 134004.3 140858.0 148233.0 20097.7 21613.2 21822.6 22084.8 23336.0 44376.1 44680.1 44841.0 44930.8 44957.6 11552.2 11949.6 11972.4 12079.0 12122.5 9918.9 9964.5 10048.6 10054.6 10075.1 11210.6 11711.2 11716.9 11720.9 11928.8 3707.9 3829.8 3895.4 4045.6 4098.6 931.5 962.8 967.8 972.7 976.0 19830.9 19904.8 20216.2 20336.4 20646.3 1028.7 1030.8 1041.4 1048.6 1050.3 10825.7 10866.7 11080.8 11097.9 11265.1 510063.0 518164.0 522757.0 523541.0 530496.0 113372.0 118579.0 118889.0 119015.4 119097.4 1064.5 1102.0 1127.8 1153.8 1213.2 20669.5 20883.6 20915.7 21063.5 21082.9 55466.0 55836.0 56548.0 57609.7 58461.4 68422.7 68526.3 68609.0 68626.7 69627.3 20445.7 20878.4 20913.4 21203.4 21227.0 23067.2 23493.8 23616.6 23625.2 23649.9 18729.4 18919.1 19021.5 19042.5 19076.1 16470.9 16789.7 17036.3 17210.2 17672.0 19347.2 19366.9 19442.2 19575.1 19680.2 20732.6 20824.8 20870.7 20935.3 20994.0 18988.1 19010.9 19027.9 19165.1 19220.0 04:02:53 INFO - PID 30809 | 04:02:53 INFO - PID 30809 | [#3] mootools.html Cycles:180 Average:9543.65 Median:15438.32 stddev:6924.02 (44.8%) stddev-sans-first:6915.95 04:02:53 INFO - PID 30809 | Values: 1330.6 1382.6 1387.5 1398.9 1406.3 15150.8 15751.8 15755.3 15760.7 15772.6 15372.0 15529.8 15539.2 15543.0 15549.9 2772.7 2896.4 2898.1 2898.6 2898.7 2698.3 2830.4 2840.2 2843.0 2843.0 2709.9 2836.3 2881.0 2900.9 2909.5 2828.9 2841.6 2854.7 2895.2 2906.4 2715.5 2781.8 2831.2 2840.1 2841.2 15208.7 15230.7 15251.7 15311.0 15370.5 8090.6 8324.2 8385.2 8393.7 8396.6 5537.9 5539.8 5643.4 5810.8 5869.4 1908.1 1916.5 1932.1 1934.5 1963.1 27687.6 27986.6 27987.3 28015.3 28047.7 7031.1 7091.2 7116.7 7193.1 7257.6 17636.3 17703.9 17721.1 17765.9 17806.1 8057.1 8173.8 8193.8 8205.1 8210.3 7408.3 7430.5 7470.0 7588.4 7643.8 5090.7 5296.9 5383.0 5395.2 5397.8 1506.6 1519.7 1522.5 1535.1 1563.5 885.8 895.4 901.8 901.8 906.8 7214.2 7502.2 7618.3 8472.5 8751.7 690.1 709.3 709.3 716.2 717.9 4551.8 4954.9 5083.6 5284.7 5492.5 21333.0 22204.9 22631.2 22954.0 23010.7 22251.0 22607.2 22655.7 22722.7 22932.2 11316.5 11619.2 11715.6 11733.4 11761.4 8216.1 8246.8 8263.5 8296.9 8461.3 18468.9 18567.4 18617.4 18781.2 18900.8 19544.3 19659.3 19680.6 19723.0 19818.3 10555.5 10719.4 10720.2 10721.6 10724.1 13976.8 14055.0 14272.3 14415.1 14416.5 11349.5 11511.3 11571.7 11724.1 11921.0 11030.1 11035.5 11213.9 11249.1 11374.9 9553.8 9772.8 9987.4 9991.9 10006.9 7123.0 7224.4 7244.5 7273.9 7292.5 7516.4 7532.4 7540.3 7616.7 7685.7 04:02:53 INFO - PID 30809 | 04:02:53 INFO - PID 30809 | [#4] prototype.html Cycles:180 Average:5585.55 Median:6279.84 stddev:1556.36 (24.8%) stddev-sans-first:1513.20 04:02:53 INFO - PID 30809 | Values: 500.6 503.0 524.9 530.0 533.4 6166.3 6295.5 6321.7 6340.2 6367.0 6168.0 6214.9 6237.5 6300.8 6320.3 5041.7 5042.4 5050.3 5056.6 5109.6 4893.4 5086.7 5122.1 5125.5 5138.1 5065.6 5075.2 5118.7 5129.5 5145.8 5007.4 5023.3 5039.8 5052.9 5053.0 4535.6 4607.4 4613.0 4613.7 4631.1 6237.3 6280.9 6303.0 6358.2 6363.3 5772.9 5798.7 5852.8 5856.7 5857.4 6656.3 6685.8 6696.3 6704.4 6763.4 4973.2 4993.4 4998.0 5007.0 5033.7 6889.9 6980.2 7006.2 7052.2 7072.2 5068.9 5096.0 5122.2 5140.7 5152.6 5196.6 5200.5 5210.5 5220.6 5250.7 5768.6 5810.6 5825.4 5830.0 5868.3 6397.3 6697.3 6768.7 6779.3 6801.9 1698.7 1715.0 1769.0 1776.5 1788.1 1204.8 1226.1 1229.9 1248.7 1263.7 5697.4 5751.6 5751.7 5809.6 5812.7 6881.2 6884.9 6917.6 6926.3 6937.3 5766.8 5796.9 5849.9 5867.7 5903.7 3624.9 3631.5 3655.6 3666.4 3691.2 6419.9 6425.9 6509.4 6516.7 6543.7 6876.0 6878.5 6949.9 6950.5 6956.6 6410.9 6435.7 6493.4 6519.3 6586.0 6540.3 6619.7 6703.9 6741.3 6776.7 6681.4 6819.8 6850.3 6875.0 6904.6 6503.0 6527.5 6559.5 6561.8 6612.0 5097.9 5103.6 5109.8 5120.2 5131.4 6635.7 6816.2 6856.7 6869.0 6896.4 6346.5 6519.1 6558.2 6622.5 6675.5 6478.4 6588.9 6600.4 6608.3 6614.8 6288.4 6369.4 6419.3 6435.8 6519.4 6355.6 6428.8 6494.5 6640.5 6651.3 6278.8 6354.2 6371.6 6472.4 6472.8 04:02:53 INFO - PID 30809 | 04:02:53 INFO - PID 30809 | [#5] yui.html Cycles:180 Average:24667.22 Median:20220.65 stddev:35286.37 (174.5%) stddev-sans-first:35340.17 04:02:53 INFO - PID 30809 | Values: 886.2 901.4 917.9 921.1 932.1 19957.9 20402.3 20712.6 20756.0 20782.7 18737.4 18973.9 19065.8 19113.1 19156.5 944.9 964.5 967.7 969.7 969.8 982.1 985.1 985.2 985.2 985.7 999.0 1027.3 1038.8 1044.5 1044.8 1037.1 1038.4 1038.9 1038.9 1039.5 716.3 735.1 753.9 755.2 755.4 20180.3 20261.0 20264.8 20354.6 20451.1 21772.2 22054.8 22627.9 23148.5 23151.8 18061.2 18128.4 18258.2 18378.1 18422.6 3847.8 3865.9 3873.7 3874.0 3874.5 108460.0 141475.0 144179.0 144859.0 145680.0 17614.8 17937.2 18045.5 18050.5 18059.5 32756.0 33564.0 33676.7 33690.7 33743.8 8266.8 8541.3 8543.0 8595.2 8618.5 3895.6 3903.3 3934.4 3947.6 3966.7 5618.5 5623.1 5647.9 5748.6 5760.9 1084.0 1098.9 1101.8 1112.3 1113.8 634.9 652.1 663.4 666.5 671.1 11154.0 11340.3 12150.8 13226.1 13592.1 703.3 707.6 711.6 723.7 724.9 5901.3 5938.6 5951.4 6157.9 6209.3 122730.7 124208.0 124324.4 124890.4 127511.0 103570.0 105954.0 106512.0 110233.0 110473.0 92111.0 93905.1 95164.0 95652.0 96444.0 11088.6 11141.1 11210.6 11334.5 11426.1 46114.3 46589.5 46908.1 47187.3 47221.0 59984.7 60935.0 61170.7 61555.4 62477.4 15236.4 15543.8 15558.7 15694.7 15804.6 17667.6 17681.8 17730.5 17877.9 17981.7 16094.8 16113.0 16212.0 16268.5 16438.5 14638.7 14849.9 14877.1 14893.9 14897.2 16433.5 16561.0 16611.4 16688.1 16711.9 11610.5 11627.3 11685.2 11687.0 11812.2 11582.4 11747.2 11825.2 12006.5 12154.9 04:02:53 INFO - PID 30809 | -------- Summary: end -------- 04:02:53 INFO - PID 30809 | 04:02:53 INFO - TEST-INFO | 30809: exit 0 04:02:54 INFO - TEST-OK | dromaeo_css | took 1108240ms 04:02:54 INFO - TEST-START | kraken 04:02:54 INFO - Initialising browser for kraken test... 04:02:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43881/getInfo.html -profile /tmp/tmpVcWWLD/profile 04:02:54 INFO - TEST-INFO | started process 31176 (/builds/slave/test/build/application/firefox/firefox http://localhost:43881/getInfo.html) 04:02:59 INFO - TEST-INFO | 31176: exit 0 04:02:59 INFO - Browser initialized. 04:02:59 INFO - Running cycle 1/1 for kraken test... 04:02:59 INFO - Using env: {'DISPLAY': ':0', 04:02:59 INFO - 'HOME': '/home/cltbld', 04:02:59 INFO - 'JSGC_DISABLE_POISONING': '1', 04:02:59 INFO - 'LANG': 'en_US.UTF-8', 04:02:59 INFO - 'LANGUAGE': 'en_US:en', 04:02:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:02:59 INFO - 'LOGNAME': 'cltbld', 04:02:59 INFO - 'MAIL': '/var/mail/cltbld', 04:02:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:02:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:02:59 INFO - 'MOZ_CRASHREPORTER': '1', 04:02:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:02:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:02:59 INFO - 'MOZ_NO_REMOTE': '1', 04:02:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:02:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:02:59 INFO - 'NO_EM_RESTART': '1', 04:02:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:02:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:02:59 INFO - 'PWD': '/builds/slave/test', 04:02:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:02:59 INFO - 'RUST_BACKTRACE': 'full', 04:02:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:02:59 INFO - 'SHELL': '/bin/bash', 04:02:59 INFO - 'SHLVL': '1', 04:02:59 INFO - 'TERM': 'linux', 04:02:59 INFO - 'TMOUT': '86400', 04:02:59 INFO - 'USER': 'cltbld', 04:02:59 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804', 04:02:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:02:59 INFO - '_': '/tools/buildbot/bin/python'} 04:02:59 INFO - TEST-INFO | started process 31415 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVcWWLD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:03:00 INFO - PID 31415 | 04:03:00 INFO - PID 31415 | (/builds/slave/test/build/application/firefox/firefox:31463): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:03:00 INFO - PID 31415 | 04:03:10 INFO - PID 31415 | RSS: Main: 147935232 04:03:10 INFO - PID 31415 | 04:03:13 INFO - PID 31415 | Cycle 1(1): loaded http://localhost:43881/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43881/tests/kraken/driver.html?testName=audio-beat-detection) 04:03:13 INFO - PID 31415 | RSS: Main: 155111424 04:03:13 INFO - PID 31415 | 04:03:16 INFO - PID 31415 | Cycle 1(1): loaded http://localhost:43881/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43881/tests/kraken/driver.html?testName=audio-dft) 04:03:17 INFO - PID 31415 | RSS: Main: 154877952 04:03:17 INFO - PID 31415 | 04:03:20 INFO - PID 31415 | Cycle 1(1): loaded http://localhost:43881/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43881/tests/kraken/driver.html?testName=audio-fft) 04:03:20 INFO - PID 31415 | RSS: Main: 150786048 04:03:20 INFO - PID 31415 | 04:03:22 INFO - PID 31415 | Cycle 1(1): loaded http://localhost:43881/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43881/tests/kraken/driver.html?testName=audio-oscillator) 04:03:23 INFO - PID 31415 | RSS: Main: 151171072 04:03:23 INFO - PID 31415 | 04:03:25 INFO - PID 31415 | Cycle 1(1): loaded http://localhost:43881/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43881/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:03:26 INFO - PID 31415 | RSS: Main: 151302144 04:03:26 INFO - PID 31415 | 04:03:29 INFO - PID 31415 | Cycle 1(1): loaded http://localhost:43881/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43881/tests/kraken/driver.html?testName=imaging-darkroom) 04:03:30 INFO - PID 31415 | RSS: Main: 153972736 04:03:30 INFO - PID 31415 | 04:03:33 INFO - PID 31415 | Cycle 1(1): loaded http://localhost:43881/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43881/tests/kraken/driver.html?testName=imaging-desaturate) 04:03:34 INFO - PID 31415 | RSS: Main: 154464256 04:03:34 INFO - PID 31415 | 04:03:37 INFO - PID 31415 | Cycle 1(1): loaded http://localhost:43881/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43881/tests/kraken/driver.html?testName=json-parse-financial) 04:03:37 INFO - PID 31415 | RSS: Main: 154361856 04:03:37 INFO - PID 31415 | 04:03:39 INFO - PID 31415 | Cycle 1(1): loaded http://localhost:43881/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43881/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:03:40 INFO - PID 31415 | RSS: Main: 154103808 04:03:40 INFO - PID 31415 | 04:03:41 INFO - PID 31415 | Cycle 1(1): loaded http://localhost:43881/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43881/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:03:42 INFO - PID 31415 | RSS: Main: 154333184 04:03:42 INFO - PID 31415 | 04:03:44 INFO - PID 31415 | Cycle 1(1): loaded http://localhost:43881/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43881/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:03:45 INFO - PID 31415 | RSS: Main: 154562560 04:03:45 INFO - PID 31415 | 04:03:47 INFO - PID 31415 | Cycle 1(1): loaded http://localhost:43881/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43881/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:03:48 INFO - PID 31415 | RSS: Main: 154669056 04:03:48 INFO - PID 31415 | 04:03:51 INFO - PID 31415 | Cycle 1(1): loaded http://localhost:43881/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43881/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:03:51 INFO - PID 31415 | RSS: Main: 154640384 04:03:51 INFO - PID 31415 | 04:03:53 INFO - PID 31415 | Cycle 1(1): loaded http://localhost:43881/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43881/tests/kraken/driver.html?testName=ai-astar) 04:03:53 INFO - PID 31415 | RSS: Main: 155893760 04:03:53 INFO - PID 31415 | 04:03:53 INFO - PID 31415 | __start_tp_report 04:03:53 INFO - PID 31415 | _x_x_mozilla_page_load 04:03:53 INFO - PID 31415 | _x_x_mozilla_page_load_details 04:03:53 INFO - PID 31415 | |i|pagename|runs| 04:03:53 INFO - PID 31415 | |0;ai-astar;107;107;106;106;106;107;107;107;106;106 04:03:53 INFO - PID 31415 | |1;audio-beat-detection;119;115;116;153;117;124;117;117;139;119 04:03:53 INFO - PID 31415 | |2;audio-dft;163;161;162;162;163;162;162;166;165;165 04:03:53 INFO - PID 31415 | |3;audio-fft;74;73;82;77;74;74;74;75;75;76 04:03:53 INFO - PID 31415 | |4;audio-oscillator;81;79;78;78;78;79;126;79;80;81 04:03:53 INFO - PID 31415 | |5;imaging-gaussian-blur;119;112;112;112;111;112;112;113;113;111 04:03:53 INFO - PID 31415 | |6;imaging-darkroom;149;148;148;150;152;148;149;148;148;149 04:03:53 INFO - PID 31415 | |7;imaging-desaturate;97;94;94;94;95;94;96;95;100;94 04:03:53 INFO - PID 31415 | |8;json-parse-financial;54;54;53;51;50;51;53;52;52;51 04:03:53 INFO - PID 31415 | |9;json-stringify-tinderbox;36;37;37;36;37;37;37;37;36;37 04:03:53 INFO - PID 31415 | |10;stanford-crypto-aes;81;77;73;75;76;73;73;74;77;72 04:03:53 INFO - PID 31415 | |11;stanford-crypto-ccm;113;107;105;114;115;108;114;110;111;111 04:03:53 INFO - PID 31415 | |12;stanford-crypto-pbkdf2;165;157;159;161;168;160;170;160;158;165 04:03:53 INFO - PID 31415 | |13;stanford-crypto-sha256-iterative;66;64;64;64;68;70;71;69;67;65 04:03:53 INFO - PID 31415 | __end_tp_report 04:03:53 INFO - PID 31415 | __start_cc_report 04:03:53 INFO - PID 31415 | _x_x_mozilla_cycle_collect,754 04:03:53 INFO - PID 31415 | __end_cc_report 04:03:53 INFO - PID 31415 | __startTimestamp1506683033888__endTimestamp 04:03:53 INFO - PID 31415 | 04:03:53 INFO - PID 31415 | ------- Summary: start ------- 04:03:53 INFO - PID 31415 | Number of tests: 14 04:03:53 INFO - PID 31415 | 04:03:53 INFO - PID 31415 | [#0] ai-astar Cycles:10 Average:106.50 Median:107.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 04:03:53 INFO - PID 31415 | Values: 107.0 107.0 106.0 106.0 106.0 107.0 107.0 107.0 106.0 106.0 04:03:53 INFO - PID 31415 | 04:03:53 INFO - PID 31415 | [#1] audio-beat-detection Cycles:10 Average:123.60 Median:119.00 stddev:12.50 (10.5%) stddev-sans-first:13.15 04:03:53 INFO - PID 31415 | Values: 119.0 115.0 116.0 153.0 117.0 124.0 117.0 117.0 139.0 119.0 04:03:53 INFO - PID 31415 | 04:03:53 INFO - PID 31415 | [#2] audio-dft Cycles:10 Average:163.10 Median:163.00 stddev:1.66 (1.0%) stddev-sans-first:1.76 04:03:53 INFO - PID 31415 | Values: 163.0 161.0 162.0 162.0 163.0 162.0 162.0 166.0 165.0 165.0 04:03:53 INFO - PID 31415 | 04:03:53 INFO - PID 31415 | [#3] audio-fft Cycles:10 Average:75.40 Median:75.00 stddev:2.59 (3.5%) stddev-sans-first:2.70 04:03:53 INFO - PID 31415 | Values: 74.0 73.0 82.0 77.0 74.0 74.0 74.0 75.0 75.0 76.0 04:03:53 INFO - PID 31415 | 04:03:53 INFO - PID 31415 | [#4] audio-oscillator Cycles:10 Average:83.90 Median:79.00 stddev:14.84 (18.8%) stddev-sans-first:15.70 04:03:53 INFO - PID 31415 | Values: 81.0 79.0 78.0 78.0 78.0 79.0 126.0 79.0 80.0 81.0 04:03:53 INFO - PID 31415 | 04:03:53 INFO - PID 31415 | [#5] imaging-gaussian-blur Cycles:10 Average:112.70 Median:112.00 stddev:2.31 (2.1%) stddev-sans-first:0.71 04:03:53 INFO - PID 31415 | Values: 119.0 112.0 112.0 112.0 111.0 112.0 112.0 113.0 113.0 111.0 04:03:53 INFO - PID 31415 | 04:03:53 INFO - PID 31415 | [#6] imaging-darkroom Cycles:10 Average:148.90 Median:149.00 stddev:1.29 (0.9%) stddev-sans-first:1.36 04:03:53 INFO - PID 31415 | Values: 149.0 148.0 148.0 150.0 152.0 148.0 149.0 148.0 148.0 149.0 04:03:53 INFO - PID 31415 | 04:03:53 INFO - PID 31415 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:94.50 stddev:1.95 (2.1%) stddev-sans-first:1.96 04:03:53 INFO - PID 31415 | Values: 97.0 94.0 94.0 94.0 95.0 94.0 96.0 95.0 100.0 94.0 04:03:53 INFO - PID 31415 | 04:03:53 INFO - PID 31415 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:52.50 stddev:1.37 (2.6%) stddev-sans-first:1.27 04:03:53 INFO - PID 31415 | Values: 54.0 54.0 53.0 51.0 50.0 51.0 53.0 52.0 52.0 51.0 04:03:53 INFO - PID 31415 | 04:03:53 INFO - PID 31415 | [#9] json-stringify-tinderbox Cycles:10 Average:36.70 Median:37.00 stddev:0.48 (1.3%) stddev-sans-first:0.44 04:03:53 INFO - PID 31415 | Values: 36.0 37.0 37.0 36.0 37.0 37.0 37.0 37.0 36.0 37.0 04:03:53 INFO - PID 31415 | 04:03:53 INFO - PID 31415 | [#10] stanford-crypto-aes Cycles:10 Average:75.10 Median:75.50 stddev:2.73 (3.6%) stddev-sans-first:1.88 04:03:53 INFO - PID 31415 | Values: 81.0 77.0 73.0 75.0 76.0 73.0 73.0 74.0 77.0 72.0 04:03:53 INFO - PID 31415 | 04:03:53 INFO - PID 31415 | [#11] stanford-crypto-ccm Cycles:10 Average:110.80 Median:112.00 stddev:3.33 (3.0%) stddev-sans-first:3.43 04:03:53 INFO - PID 31415 | Values: 113.0 107.0 105.0 114.0 115.0 108.0 114.0 110.0 111.0 111.0 04:03:53 INFO - PID 31415 | 04:03:53 INFO - PID 31415 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.30 Median:163.00 stddev:4.42 (2.7%) stddev-sans-first:4.58 04:03:53 INFO - PID 31415 | Values: 165.0 157.0 159.0 161.0 168.0 160.0 170.0 160.0 158.0 165.0 04:03:53 INFO - PID 31415 | 04:03:53 INFO - PID 31415 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.50 stddev:2.62 (3.9%) stddev-sans-first:2.76 04:03:53 INFO - PID 31415 | Values: 66.0 64.0 64.0 64.0 68.0 70.0 71.0 69.0 67.0 65.0 04:03:53 INFO - PID 31415 | -------- Summary: end -------- 04:03:53 INFO - PID 31415 | 04:03:54 INFO - PID 31415 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:03:54 INFO - TEST-INFO | 31415: exit 0 04:03:54 INFO - TEST-OK | kraken | took 60544ms 04:03:54 INFO - SUITE-END | took 1168s 04:03:55 INFO - Completed test suite (00:19:29) 04:03:55 INFO - javascript benchmark 04:03:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8644.097052999952, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3170.984145079275, 3195.2171717929104, 3232.773705840589, 3248.0, 3248.3990461764574, 6213.937860621392, 6421.582597131189, 6432.5497215194955, 6462.806115816521, 6578.375054369833, 6356.68216589171, 6367.458766004884, 6381.968090159543, 6415.807525774234, 6437.806865794022, 5832.270966129234, 5865.061590145578, 5870.207521984542, 5910.88178236435, 5955.34491205967, 5834.883302333951, 5873.735681894325, 5875.0, 5881.970590147064, 5944.2866855977345, 5680.715964201773, 5893.447423540042, 5932.0, 5991.191189189469, 6030.125631783389, 5801.419858014207, 5881.970590147064, 5889.823305300848, 5968.880622387528, 5975.432333928314, 5864.794732184396, 5872.207252021008, 5947.524198064145, 5948.672822994734, 5973.8805223895715, 6210.472109870667, 6361.427471527585, 6362.809115726535, 6504.804855854332, 6506.316836732122, 6797.898031529531, 6810.659467026629, 6824.829379265509, 6830.282820303846, 6834.0, 6906.861862762717, 6988.93011069893, 7020.192677842062, 7025.19210290818, 7026.754063607749, 6173.567850250484, 6314.463270622003, 6401.8399540011405, 6442.065900444456, 6492.902606460907, 6848.212455567674, 7020.648967551602, 7136.286371362822, 7189.604571748604, 7198.064251647253, 6747.156605424322, 6779.559328643622, 6838.452923766087, 6853.314668533207, 6861.039454476378, 6703.932960670429, 6858.348456896587, 6903.6548172591165, 6978.337057979484, 6987.301269872972, 6746.156730408699, 6768.984652302096, 6899.344562266677, 6908.6545672716165, 6921.0, 6926.0, 6935.861282774316, 6939.965300173467, 6947.652617369111, 6994.020837082812, 5351.224072509464, 5372.462753724674, 5433.945660543423, 5440.047991601454, 5447.319085114361, 4678.461976872635, 4690.507496712864, 4718.363020992191, 4731.95268047322, 4767.0, 5719.685417302005, 5840.59115861894, 5859.414058594107, 5923.200367950271, 5993.940060599425, 6931.549449285882, 6963.1296087989, 7009.193942696553, 7013.088298521159, 7014.19336776267, 5874.618149820248, 5902.085176797603, 5906.232189815297, 5958.136070269787, 5967.433093856077, 5816.767329306865, 5848.736806843703, 5920.407959204045, 5920.437558431942, 5934.910976335358, 6441.5490915635455, 6558.213014438298, 6582.111414958919, 6667.599944003376, 6680.766173183915, 6912.377885990184, 6919.100516932766, 6924.4114250289285, 6924.58452492852, 6951.7914462566205, 6532.379423954717, 6557.672116394257, 6574.178227721535, 6649.800505984828, 6652.401283884474, 6710.127683401127, 6721.563098398588, 6796.72813087471, 6837.213720422215, 6857.622830744357, 6753.662316884038, 6890.31096890307, 6895.586264824126, 6970.616616086164, 6990.26602206761, 6657.833554161185, 6728.226253980855, 6737.393634572912, 6763.458923286027, 6785.423239024541, 6645.102911106938, 6690.59856408617, 6736.023276624764, 6754.054432379569, 6764.188297404343, 6567.737290508518, 6600.60396376219, 6672.0, 6673.232578253369, 6704.0, 6614.404703576702, 6677.43241824431, 6724.428423583854, 6783.084283621845, 6788.626625535643, 6496.577722448026, 6521.608703477807, 6536.117624120873, 6537.346265373615, 6609.239937407259], "value": 6262.730952510825, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [790.9841803163937, 794.6225542867143, 816.9142240064793, 837.9078301386846, 841.8232171244039, 40096.0, 40855.182896342056, 41037.79481102594, 41302.586974130325, 41408.96477588058, 37966.62033379665, 38285.808570957175, 38315.233695326075, 38337.233255334875, 38357.0, 6313.4633556147555, 6317.52618553608, 6330.303666596671, 6331.49348052157, 6352.9364706353035, 6258.280297765747, 6266.248050233956, 6268.843278918041, 6272.090546870701, 6325.557210995232, 6185.41238582334, 6201.875962480748, 6213.974694175456, 6231.096491008801, 6255.499560035186, 6211.4409703126485, 6214.1921550198185, 6224.0, 6234.158388617547, 6245.968770156142, 6488.545801793876, 6549.836254093638, 6590.3080176581725, 6597.340265973365, 6617.040529123298, 33381.0, 33611.495827562605, 33639.49540756891, 33696.49455258173, 33708.49437258419, 3347.397468455677, 3348.698617124446, 3360.865565377382, 3362.243495213582, 3366.6969972702573, 28200.717992820013, 28233.0, 28319.433611327862, 28348.149555513366, 28372.574411383845, 4484.349769283435, 4551.294549344857, 4561.996360800652, 4580.450345958507, 4597.586217240464, 190586.0, 191014.0, 192080.0, 192312.0384398083, 192438.0, 20725.58548829015, 20959.47601309949, 21036.0, 21103.784102669306, 21157.0, 52625.0, 53457.0, 53460.0, 53556.73221633867, 53817.0, 16499.257533411026, 16594.170291485378, 16636.916815416087, 16645.750313745302, 16655.6668866622, 12930.0, 13113.606591802261, 13193.604191874067, 13206.075574709677, 13210.867891321155, 14322.212278324792, 14487.565373038826, 14522.636934076732, 14542.27288635564, 14702.338394772261, 5758.654480731169, 5764.855878602984, 5815.41845815415, 5880.911786323209, 5964.522838172936, 991.7124034030195, 1001.0690058245901, 1010.1565193063727, 1011.0041609015115, 1027.8869324374314, 23061.308160755205, 23197.53604927892, 23245.070197191922, 23260.41848953756, 23432.0, 1098.2531878322818, 1125.7016890524017, 1132.3432409202642, 1137.1982752159709, 1144.2619510415902, 12087.09346798976, 12386.938065309796, 12390.132690711564, 12423.701615201217, 12460.065495087723, 232025.0, 232111.0, 232333.0, 232603.0, 232777.0, 44449.55550444519, 45590.31614525785, 52642.47357526376, 52992.73503632457, 53158.20262696063, 1326.0717497751612, 1434.2255182201495, 1474.8746356559814, 1475.2255066090388, 1481.6073740458787, 26893.193204203904, 27151.86424067867, 27456.862715686293, 27496.450070998068, 27914.16257512278, 35343.469847952816, 35372.29255414929, 35398.469022964164, 35439.29121417609, 35480.0, 176389.0, 176796.0, 176939.0, 177602.22397776373, 178229.0, 32987.34025319528, 33798.32403351968, 34049.82975085109, 34050.489242660886, 34422.82788586041, 42255.366169506866, 42371.576284238, 42527.787361063, 42552.0, 42736.0, 30836.0, 30938.535921960738, 31602.683973159976, 31608.841955790074, 31751.364972700878, 30993.69006309998, 31024.22439439032, 31028.844855775576, 31059.844700777256, 31151.221219469695, 34924.0, 34969.82515087408, 34978.0, 34986.82506587451, 35001.124971875906, 30583.694163058084, 30622.234444139074, 30681.693183067884, 30745.846270768503, 30758.0, 22560.323190304316, 22739.886300568392, 22744.545109098057, 23250.76749232486, 23364.299071027897], "value": 16033.59827563479, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [545.1686178577669, 564.2213745031858, 567.4467394290565, 595.4373117404052, 603.4448307557047, 22715.20496782613, 23353.883230583822, 23459.648105278415, 23478.647820282687, 23503.529929401444, 23429.179978700748, 23495.06019759212, 23545.882270588667, 24795.256142315713, 25207.865646045928, 6695.3304669533145, 6871.415929645961, 6874.9656251718925, 6893.586384816902, 6900.137482814648, 6552.639604821733, 6761.35767102127, 6852.486063545229, 6867.450603951672, 6879.484038697093, 6787.830304242409, 6795.354441328066, 6798.286179951108, 6813.761518346859, 6821.3519715626935, 6777.661116944158, 6781.830454238683, 6792.320767923217, 6802.4217941475035, 6813.4208592269715, 6298.716557754912, 6828.931710682879, 7168.247334029904, 7257.45569082321, 7268.745593904241, 31090.0, 31817.045488635376, 31842.0, 31866.0, 31946.361072778414, 2111.419359676086, 2117.375374264596, 2124.0866427436194, 2128.255110711254, 2138.786121387864, 20965.16139354424, 21264.574708505897, 21790.782092179033, 21880.343589692333, 22058.007389667502, 2576.5233431815172, 2613.9085132020286, 2678.504476671822, 2679.9196024119306, 2691.421344410961, 127456.0, 132589.33705331507, 134004.32997835046, 140858.0, 148233.0, 20097.698534521995, 21613.243536476148, 21822.59951405344, 22084.779152208273, 23336.0, 44376.11247775026, 44680.10639787186, 44841.0, 44930.77534612306, 44957.55042449533, 11552.191346605658, 11949.64151075469, 11972.401379930969, 12079.0, 12122.454489547992, 9918.900810991941, 9964.451955142391, 10048.598056077675, 10054.64808731691, 10075.09324160829, 11210.607628732956, 11711.238769479956, 11716.882831171579, 11720.941395293139, 11928.76142477163, 3707.8516859325323, 3829.751066180745, 3895.4351619015083, 4045.5954404559307, 4098.569650186747, 931.4597533430594, 962.7978124593914, 967.8062394099444, 972.747085757694, 976.028851292968, 19830.90084549568, 19904.800951990583, 20216.191352346035, 20336.389908302775, 20646.277380291616, 1028.6502589119734, 1030.8247597908373, 1041.3959903256093, 1048.564845589072, 1050.2700623066896, 10825.729356765987, 10866.72833179161, 11080.833787493195, 11097.945695158947, 11265.098792096613, 510063.0, 518164.0, 522757.0, 523541.0, 530496.0, 113372.0, 118579.0, 118889.0, 119015.40492297483, 119097.40451297861, 1064.5316060933164, 1102.030213412192, 1127.7913585986619, 1153.8499995000764, 1213.2477863724548, 20669.483262918548, 20883.582328353044, 20915.68626470574, 21063.473413164793, 21082.894585526974, 55466.0, 55836.0, 56548.0, 57609.711951439975, 58461.4153858473, 68422.65788671224, 68526.314736854, 68609.0, 68626.65686671535, 69627.30372696208, 20445.693314599994, 20878.37364879056, 20913.37259882206, 21203.36389908305, 21227.0, 23067.1926482579, 23493.765062349157, 23616.645750314103, 23625.173118940755, 23649.881750591136, 18729.438116856516, 18919.054047297854, 19021.52446188801, 19042.523936901132, 19076.1415736295, 16470.858788357436, 16789.66420671555, 17036.31854725797, 17210.225539850988, 17672.0, 19347.226110955406, 19366.903165484084, 19442.222311107398, 19575.119119639934, 19680.21279148818, 20732.58534829325, 20824.791752082892, 20870.68693969622, 20935.26726564563, 20994.0, 18988.145533450752, 19010.904945475184, 19027.90486047561, 19165.137568809714, 19220.0], "value": 13187.157872036349, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1330.5609148980836, 1382.5714028651116, 1387.4519564771908, 1398.8531204223557, 1406.2757679794902, 15150.77273840892, 15751.763723544142, 15755.291011904463, 15760.684786304268, 15772.605684857885, 15372.0, 15529.844701552982, 15539.22303884807, 15543.0, 15549.922250388732, 2772.6672799264115, 2896.362800183956, 2898.058131107388, 2898.608687827148, 2898.6521617405942, 2698.2579790557556, 2830.4197639483855, 2840.2047426720446, 2843.0, 2843.0475790610067, 2709.945801083977, 2836.276749428893, 2880.9855950720216, 2900.8694608742658, 2909.476294267031, 2828.9292767680768, 2841.6447944007, 2854.728800763924, 2895.160403482998, 2906.3896581717863, 2715.5383584790434, 2781.8330900146057, 2831.1931099636504, 2840.076974983139, 2841.2186648671573, 15208.695826083416, 15230.695386092328, 15251.694966100617, 15311.0, 15370.538883833502, 8090.555019473926, 8324.209200125979, 8385.161483851627, 8393.748187554384, 8396.580170991487, 5537.944620553783, 5539.8366343068, 5643.379228284885, 5810.767569297223, 5869.383714709937, 1908.1031914999928, 1916.492129585661, 1932.1498540642071, 1934.4680212941414, 1963.1264087481077, 27687.584686229722, 27986.580201296998, 27987.300317492227, 28015.299617509725, 28047.719522804713, 7031.08595882532, 7091.169376188885, 7116.71533138679, 7193.100862392201, 7257.637118144072, 17636.294548217928, 17703.911480442515, 17721.113944302735, 17765.911170444324, 17806.109694515224, 8057.113717491071, 8173.754787356389, 8193.8361232775, 8205.138460461685, 8210.261076503144, 7408.296211859864, 7430.517016393917, 7469.962650186714, 7588.392928565701, 7643.847123057508, 5090.745462726923, 5296.947030529723, 5382.973085134549, 5395.244665746799, 5397.7571009304675, 1506.5555661080007, 1519.7340465418508, 1522.4975757999835, 1535.1403214200084, 1563.4762354611105, 885.8139790643908, 895.4269267668698, 901.8096113130605, 901.8151278987792, 906.7914379692708, 7214.176486462184, 7502.17476077631, 7618.314351708373, 8472.534010629475, 8751.693690720795, 690.1200968764765, 709.2942522190453, 709.3332267668478, 716.1513606376357, 717.9174394944648, 4551.840685575989, 4954.925676114861, 5083.593312534988, 5284.682919024871, 5492.450754924475, 21333.0, 22204.88897555502, 22631.20790772348, 22954.0, 23010.65484017741, 22251.0, 22607.208747693752, 22655.660165097535, 22722.659160112613, 22932.19737309186, 11316.490757915913, 11619.186656933933, 11715.589954351728, 11733.35466549348, 11761.411929403666, 8216.137305582828, 8246.752597422086, 8263.504189748634, 8296.87554686668, 8461.32309415233, 18468.907655461637, 18567.44297671072, 18617.441476755717, 18781.154848032143, 18900.810991889906, 19544.315948942287, 19659.311924082587, 19680.60638787245, 19723.0, 19818.306359277358, 10555.472226388803, 10719.356838589709, 10720.195985300978, 10721.571137154426, 10724.088452481627, 13976.790348144583, 14055.0, 14272.286385680467, 14415.063020903608, 14416.495422660577, 11349.54601815918, 11511.251768635011, 11571.710707232387, 11724.120690948042, 11921.046316294849, 11030.117590592894, 11035.503402347074, 11213.943930280297, 11249.100071994384, 11374.943125284652, 9553.761155971157, 9772.804543909224, 9987.350822196535, 9991.850122248317, 10006.949965250127, 7122.964385178041, 7224.385927196247, 7244.4566657499845, 7273.854522909618, 7292.453066020176, 7516.3611093055515, 7532.397408207221, 7540.283673051052, 7616.657250423634, 7685.654145563351], "value": 6758.142904379397, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.56338309053, 502.9739331763203, 524.9422563518013, 529.9841004769859, 533.4052531427457, 6166.260048794145, 6295.464885484728, 6321.747130114795, 6340.175777148976, 6367.0, 6167.9691601542045, 6214.906776398358, 6237.469815065725, 6300.810975670725, 6320.3363646817, 5041.697498150104, 5042.420121686007, 5050.3434553508, 5056.646034777567, 5109.565686916617, 4893.363862697862, 5086.720230387327, 5122.052420302238, 5125.461826508219, 5138.144453491452, 5065.620078494109, 5075.162598171298, 5118.667286626376, 5129.53834154926, 5145.768440420171, 5007.399112106534, 5023.32185155003, 5039.823606173784, 5052.863105801202, 5052.9747351263, 4535.63714902807, 4607.424071991001, 4613.0, 4613.746243956581, 4631.143238500888, 6237.257645412761, 6280.905786413162, 6303.0, 6358.173437453148, 6363.300036995926, 5772.855678608026, 5798.681072541009, 5852.76588936442, 5856.736446859902, 5857.4435428634215, 6656.267810540837, 6685.832854178684, 6696.263411024784, 6704.363085506869, 6763.381590848872, 4973.15456372414, 4993.375828021497, 4997.950020499785, 5006.974965125151, 5033.672811267293, 6889.896651550231, 6980.197277313123, 7006.229314775371, 7052.153741550996, 7072.222055573883, 5068.873278168076, 5096.0, 5122.1804511278015, 5140.717260550631, 5152.639315247971, 5196.636235463481, 5200.531952124327, 5210.505002024802, 5220.556252718486, 5250.711210883438, 5768.596198266165, 5810.622309549865, 5825.378579639179, 5829.970850145722, 5868.325142608569, 6397.296297407282, 6697.263301036882, 6768.72925083001, 6779.254282028973, 6801.931980680229, 1698.728203487436, 1714.9828501715074, 1768.9557761056076, 1776.4848194023846, 1788.1059470264868, 1204.795184818583, 1226.141700809437, 1229.8524177098805, 1248.6815861955324, 1263.728298415845, 5697.430256974352, 5751.59738818287, 5751.692776653441, 5809.564282678816, 5812.680302583399, 6881.208661004049, 6884.896726549106, 6917.550359226634, 6926.272741362185, 6937.340952609494, 5766.826995190151, 5796.942030579725, 5849.853753656107, 5867.70661466925, 5903.675297858659, 3624.909377265537, 3631.473436351714, 3655.6474104580943, 3666.431703086026, 3691.169486865434, 6419.935800642027, 6425.903611445832, 6509.381608747161, 6516.706748196342, 6543.738250470023, 6876.0, 6878.518503704793, 6949.861002779916, 6950.548214366151, 6956.617386043715, 6410.903836442364, 6435.678216089177, 6493.383128602775, 6519.348065193538, 6586.0, 6540.313267107075, 6619.735210591522, 6703.899441508479, 6741.292164322872, 6776.661166941534, 6681.365270299314, 6819.761308354382, 6850.349216824394, 6874.965625172043, 6904.620245886525, 6502.967485162544, 6527.477801775942, 6559.508036897157, 6561.770338038145, 6612.0, 5097.923531146962, 5103.617228707788, 5109.795608175631, 5120.155174396332, 5131.435542090442, 6635.734570617121, 6816.216135144522, 6856.725730970706, 6869.0, 6896.379325860697, 6346.460550853045, 6519.119918810899, 6558.1802274715665, 6622.543040531075, 6675.496688741722, 6478.384553467413, 6588.901166482602, 6600.445692472179, 6608.273089960197, 6614.801555953329, 6288.371162883675, 6369.363063693593, 6419.26178489461, 6435.839104022437, 6519.445847103049, 6355.586886852339, 6428.8071357859335, 6494.545381823416, 6640.501962352746, 6651.301613330531, 6278.780242691484, 6354.205724284465, 6371.55399122057, 6472.449841763503, 6472.838179045561], "value": 5125.39332018791, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [886.2466903132338, 901.3780491460892, 917.8531434970398, 921.0743203080903, 932.0772435289066, 19957.900210498963, 20402.285919992803, 20712.585748285026, 20756.0, 20782.6882596761, 18737.43787686368, 18973.90513047433, 19065.809341906577, 19113.139908704143, 19156.521086972796, 944.8724422203018, 964.5177411294353, 967.7338731848728, 969.6993931881104, 969.781799095205, 982.076847763104, 985.1133979418508, 985.2216748768465, 985.2315194148575, 985.6500942165535, 998.9511013435863, 1027.250107421579, 1038.8493668418075, 1044.5038606661813, 1044.8119338519064, 1037.1132681557274, 1038.4444322287616, 1038.875334959806, 1038.890916453769, 1039.5114296280735, 716.3159182980241, 735.0811485642946, 753.932146106853, 755.1504557372955, 755.3768141283408, 20180.29368972079, 20261.0, 20264.79735202644, 20354.5929081419, 20451.079701413473, 21772.237971671075, 22054.779452205592, 22627.88686056559, 23148.53702925949, 23151.76848231513, 18061.187246573805, 18128.365507207316, 18258.178381972844, 18378.081095945283, 18422.63154736911, 3847.7883716395586, 3865.942010869839, 3873.690104791638, 3873.9612603873884, 3874.5156855393075, 108460.0, 141475.0, 144179.0, 144859.0, 145680.0, 17614.823851761575, 17937.192826322847, 18045.548861278574, 18050.548736281697, 18059.54851128732, 32756.0, 33564.0, 33676.663233367355, 33690.663093368756, 33743.83128084393, 8266.751997440086, 8541.273991710774, 8543.0, 8595.22642962124, 8618.482891026559, 3895.5520115187114, 3903.2583809076186, 3934.429507721364, 3947.624975627311, 3966.6628336591134, 5618.52242559387, 5623.10030395143, 5647.915281270784, 5748.626339288016, 5760.913586296125, 1083.9945800270948, 1098.9395583243, 1101.7851518953728, 1112.3381587955155, 1113.8496302999154, 634.9333320001333, 652.1326635574675, 663.4062514049898, 666.4934649666288, 671.1476424940298, 11153.996140347408, 11340.298955156628, 12150.817737733942, 13226.07417480767, 13592.11651242666, 703.3142685881224, 707.6355676826438, 711.5552779512805, 723.7249845058949, 724.9420046396276, 5901.321348044944, 5938.61399009063, 5951.434613711691, 6157.876842463125, 6209.348018458088, 122730.70442534468, 124208.0, 124324.37837810753, 124890.37554812168, 127511.0, 103570.0, 105954.0, 106512.0, 110233.0, 110473.0, 92111.0, 93905.060949391, 95164.0, 95652.0434795657, 96444.0, 11088.611898583511, 11141.108711302913, 11210.55157793679, 11334.526511553446, 11426.085913127095, 46114.30828537642, 46589.53410465988, 46908.061838763715, 47187.292190616485, 47221.0, 59984.700076499335, 60935.0, 61170.69414653076, 61555.38444615497, 62477.37522624897, 15236.390544378544, 15543.84456155424, 15558.688826223186, 15694.744420446108, 15804.60488487751, 17667.646647067242, 17681.823181768017, 17730.468085957444, 17877.910610447383, 17981.73027404564, 16094.839051609802, 16113.0, 16212.0, 16268.51194464168, 16438.506844794676, 14638.707225855636, 14849.851501484847, 14877.107373557621, 14893.925530372278, 14897.180655064076, 16433.506994790176, 16561.0, 16611.41860034893, 16688.08215548108, 16711.916440418205, 11610.535578576762, 11627.302361858316, 11685.182037257311, 11687.0, 11812.232204906653, 11582.36297003673, 11747.236429632047, 11825.172237943605, 12006.459709312927, 12154.878451215374], "value": 9094.788852496562, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1413.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 107.0, 106.0, 106.0, 106.0, 107.0, 107.0, 107.0, 106.0, 106.0], "value": 106.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 115.0, 116.0, 153.0, 117.0, 124.0, 117.0, 117.0, 139.0, 119.0], "value": 123.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 162.0, 163.0, 162.0, 162.0, 166.0, 165.0, 165.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 82.0, 77.0, 74.0, 74.0, 74.0, 75.0, 75.0, 76.0], "value": 75.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 78.0, 78.0, 78.0, 79.0, 126.0, 79.0, 80.0, 81.0], "value": 83.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 112.0, 112.0, 112.0, 111.0, 112.0, 112.0, 113.0, 113.0, 111.0], "value": 112.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 148.0, 150.0, 152.0, 148.0, 149.0, 148.0, 148.0, 149.0], "value": 148.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 94.0, 94.0, 94.0, 95.0, 94.0, 96.0, 95.0, 100.0, 94.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 53.0, 51.0, 50.0, 51.0, 53.0, 52.0, 52.0, 51.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 37.0, 37.0, 36.0, 37.0, 37.0, 37.0, 37.0, 36.0, 37.0], "value": 36.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 77.0, 73.0, 75.0, 76.0, 73.0, 73.0, 74.0, 77.0, 72.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 107.0, 105.0, 114.0, 115.0, 108.0, 114.0, 110.0, 111.0, 111.0], "value": 110.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 157.0, 159.0, 161.0, 168.0, 160.0, 170.0, 160.0, 158.0, 165.0], "value": 162.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 64.0, 64.0, 68.0, 70.0, 71.0, 69.0, 67.0, 65.0], "value": 66.8, "unit": "score"}]}]} 04:03:55 INFO - Return code: 0 04:03:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:03:55 INFO - # TBPL SUCCESS # 04:03:55 INFO - Running post-action listener: _package_coverage_data 04:03:55 INFO - Running post-action listener: _resource_record_post_action 04:03:55 INFO - [mozharness: 2017-09-29 11:03:55.187307Z] Finished run-tests step (success) 04:03:55 INFO - Running post-run listener: _resource_record_post_run 04:03:55 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 342405120; Read time: 16; Write time: 225828 04:03:55 INFO - TinderboxPrint: CPU usage
13.1% 04:03:55 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 04:03:55 INFO - TinderboxPrint: I/O write bytes / time
342,405,120 / 225,828 04:03:55 INFO - TinderboxPrint: CPU idle
8,222.6 (86.9%) 04:03:55 INFO - TinderboxPrint: CPU user
1,194.0 (12.6%) 04:03:55 INFO - TinderboxPrint: Swap in / out
0 / 0 04:03:55 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10088448; Read time: 0; Write time: 800 04:03:55 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:03:55 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 0; Write bytes: 332316672; Read time: 0; Write time: 225028 04:03:55 INFO - Running post-run listener: _upload_blobber_files 04:03:55 INFO - Blob upload gear active. 04:03:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:03:55 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 04:03:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:03:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:03:56 INFO - (blobuploader) - INFO - Open directory for files ... 04:03:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:03:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:03:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:57 INFO - (blobuploader) - INFO - Done attempting. 04:03:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:03:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:03:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:58 INFO - (blobuploader) - INFO - Done attempting. 04:03:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:03:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:04:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:04:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:04:01 INFO - (blobuploader) - INFO - Done attempting. 04:04:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:04:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:04:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:04:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:04:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:04:02 INFO - (blobuploader) - INFO - Done attempting. 04:04:02 INFO - (blobuploader) - INFO - Iteration through files over. 04:04:02 INFO - Return code: 0 04:04:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:04:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:04:02 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/23c8a7bf0d9099e83ba2bc8449238fe38bbdfc5f5ee81add9af3085bfb741514bff223a9f042712db912b64b2c9e9320686f6907f3262047ebc7ee94debb64a3", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dcf17ac40af83fcf11d8230361b87938c15b299758edeb17c9a5f2650135a3c1cfca9de796130e26de491431f511c2d0d7ecc30624db7fafb4f73a0282926731", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/35f1421eef89c9e88eeba562958aa6025984b47b2febd81ff0e5eccbb659726a55c49e349aea31bf952b27fc90c73c796e7c3f0f00aa62bd21ee49c33682eb1c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/229237f77f8e8a43a212fa3beea181031056ec9538d59aea1df974e547f7b3f68cf39322aa48df4e89303d3049f9e1bb78c92259e0ab375053f74757c75a7d22"} 04:04:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:04:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:04:02 INFO - Contents: 04:04:02 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/23c8a7bf0d9099e83ba2bc8449238fe38bbdfc5f5ee81add9af3085bfb741514bff223a9f042712db912b64b2c9e9320686f6907f3262047ebc7ee94debb64a3", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dcf17ac40af83fcf11d8230361b87938c15b299758edeb17c9a5f2650135a3c1cfca9de796130e26de491431f511c2d0d7ecc30624db7fafb4f73a0282926731", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/35f1421eef89c9e88eeba562958aa6025984b47b2febd81ff0e5eccbb659726a55c49e349aea31bf952b27fc90c73c796e7c3f0f00aa62bd21ee49c33682eb1c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/229237f77f8e8a43a212fa3beea181031056ec9538d59aea1df974e547f7b3f68cf39322aa48df4e89303d3049f9e1bb78c92259e0ab375053f74757c75a7d22"} 04:04:02 INFO - Running post-run listener: copy_logs_to_upload_dir 04:04:02 INFO - Copying logs to upload dir... 04:04:02 INFO - mkdir: /builds/slave/test/build/upload/logs 04:04:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.166071 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-09-29 04:04:02.669644) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-29 04:04:02.677382) ========= 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=826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804 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-Non-PGO/sha512/23c8a7bf0d9099e83ba2bc8449238fe38bbdfc5f5ee81add9af3085bfb741514bff223a9f042712db912b64b2c9e9320686f6907f3262047ebc7ee94debb64a3", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dcf17ac40af83fcf11d8230361b87938c15b299758edeb17c9a5f2650135a3c1cfca9de796130e26de491431f511c2d0d7ecc30624db7fafb4f73a0282926731", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/35f1421eef89c9e88eeba562958aa6025984b47b2febd81ff0e5eccbb659726a55c49e349aea31bf952b27fc90c73c796e7c3f0f00aa62bd21ee49c33682eb1c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/229237f77f8e8a43a212fa3beea181031056ec9538d59aea1df974e547f7b3f68cf39322aa48df4e89303d3049f9e1bb78c92259e0ab375053f74757c75a7d22"} build_url:https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011319 build_url: 'https://queue.taskcluster.net/v1/task/ewqrVSCKSgOWNQzmAaDlXw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/23c8a7bf0d9099e83ba2bc8449238fe38bbdfc5f5ee81add9af3085bfb741514bff223a9f042712db912b64b2c9e9320686f6907f3262047ebc7ee94debb64a3", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dcf17ac40af83fcf11d8230361b87938c15b299758edeb17c9a5f2650135a3c1cfca9de796130e26de491431f511c2d0d7ecc30624db7fafb4f73a0282926731", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/35f1421eef89c9e88eeba562958aa6025984b47b2febd81ff0e5eccbb659726a55c49e349aea31bf952b27fc90c73c796e7c3f0f00aa62bd21ee49c33682eb1c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/229237f77f8e8a43a212fa3beea181031056ec9538d59aea1df974e547f7b3f68cf39322aa48df4e89303d3049f9e1bb78c92259e0ab375053f74757c75a7d22"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-29 04:04:02.712324) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 04:04:02.712789) ========= 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=826874ccc97bd7bf541b4875000001d5-1506676855.477672-2043760804 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004405 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 04:04:02.791960) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-29 04:04:02.792323) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-29 04:04:02.792716) ========= ========= Total master_lag: 0.27 =========