builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-069 starttime: 1506849924.96 results: success (0) revision: 969b202c949f549d1f015d1308c7aaf8c6e0328b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-01 02:25:24.962775) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-01 02:25:24.963260) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-01 02:25:24.963532) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-01 02:25:25.103553) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 02:25:25.103839) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-01 02:25: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.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 228M=0s 2017-10-01 02:25:25 (228 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.185427 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 02:25:25.342580) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 02:25:25.342943) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014875 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 02:25:25.394976) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 02:25:25.395295) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 969b202c949f549d1f015d1308c7aaf8c6e0328b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 969b202c949f549d1f015d1308c7aaf8c6e0328b --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=05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-01 02:25:25,421 truncating revision to first 12 chars 2017-10-01 02:25:25,421 Setting DEBUG logging. 2017-10-01 02:25:25,421 attempt 1/10 2017-10-01 02:25:25,421 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/969b202c949f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-01 02:25:26,004 unpacking tar archive at: mozilla-beta-969b202c949f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.880001 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 02:25:26.291361) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-01 02:25:26.291692) ========= script_repo_revision: 969b202c949f549d1f015d1308c7aaf8c6e0328b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-01 02:25:26.295129) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-01 02:25:26.295415) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-01 02:25:26.310919) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-10-01 02:25:26.311200) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-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', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-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=05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:25:26 INFO - MultiFileLogger online at 20171001 02:25:26 in /builds/slave/test 02:25:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 02:25:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:25:26 INFO - {'append_to_log': False, 02:25:26 INFO - 'base_work_dir': '/builds/slave/test', 02:25:26 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 02:25:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:25:26 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 02:25:26 INFO - 'buildbot_json_path': 'buildprops.json', 02:25:26 INFO - 'code_coverage': False, 02:25:26 INFO - 'config_files': ('talos/linux_config.py',), 02:25:26 INFO - 'default_actions': ('clobber', 02:25:26 INFO - 'read-buildbot-config', 02:25:26 INFO - 'download-and-extract', 02:25:26 INFO - 'populate-webroot', 02:25:26 INFO - 'create-virtualenv', 02:25:26 INFO - 'install', 02:25:26 INFO - 'setup-mitmproxy', 02:25:26 INFO - 'run-tests'), 02:25:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:25:26 INFO - 'disable_ccov_upload': False, 02:25:26 INFO - 'disable_stylo': False, 02:25:26 INFO - 'download_minidump_stackwalk': True, 02:25:26 INFO - 'download_symbols': 'ondemand', 02:25:26 INFO - 'enable_stylo': False, 02:25:26 INFO - 'enable_webrender': False, 02:25:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:25:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:25:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:25:26 INFO - 'gecko_profile': False, 02:25:26 INFO - 'gecko_profile_interval': 0, 02:25:26 INFO - 'installer_path': 'installer.exe', 02:25:26 INFO - 'log_level': 'info', 02:25:26 INFO - 'log_name': 'talos', 02:25:26 INFO - 'log_to_console': True, 02:25:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:25:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:25:26 INFO - 'opt_config_files': (), 02:25:26 INFO - 'pip_index': False, 02:25:26 INFO - 'suite': 'dromaeojs-e10s', 02:25:26 INFO - 'system_bits': '32', 02:25:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:25:26 INFO - 'title': 'talos-linux64-ix-069', 02:25:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:25:26 INFO - 'use_talos_json': True, 02:25:26 INFO - 'verify': 'False', 02:25:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:25:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:25:26 INFO - 'work_dir': 'build'} 02:25:26 INFO - [mozharness: 2017-10-01 09:25:26.605827Z] Running clobber step. 02:25:26 INFO - Running pre-action listener: _resource_record_pre_action 02:25:26 INFO - Running main action method: clobber 02:25:26 INFO - rmtree: /builds/slave/test/build 02:25:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:25:27 INFO - Running post-action listener: _resource_record_post_action 02:25:27 INFO - [mozharness: 2017-10-01 09:25:27.547655Z] Finished clobber step (success) 02:25:27 INFO - [mozharness: 2017-10-01 09:25:27.547752Z] Running read-buildbot-config step. 02:25:27 INFO - Running pre-action listener: _resource_record_pre_action 02:25:27 INFO - Running main action method: read_buildbot_config 02:25:27 INFO - Using buildbot properties: 02:25:27 INFO - { 02:25:27 INFO - "project": "", 02:25:27 INFO - "product": "firefox", 02:25:27 INFO - "who": "ffxbld@noreply.mozilla.org", 02:25:27 INFO - "installer_path": "public/build/target.tar.bz2", 02:25:27 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 02:25:27 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 02:25:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 02:25:27 INFO - "stage_platform": "linux64", 02:25:27 INFO - "basedir": "/builds/slave/test", 02:25:27 INFO - "buildnumber": 138, 02:25:27 INFO - "platform": "ubuntu64_hw", 02:25:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 02:25:27 INFO - "slavebuilddir": "test", 02:25:27 INFO - "taskId": "DdlHwtnRTzioKJf6aYcOOQ", 02:25:27 INFO - "branch": "mozilla-beta", 02:25:27 INFO - "script_repo_revision": "production", 02:25:27 INFO - "revision": "969b202c949f549d1f015d1308c7aaf8c6e0328b", 02:25:27 INFO - "slavename": "talos-linux64-ix-069", 02:25:27 INFO - "repo_path": "releases/mozilla-beta" 02:25:27 INFO - } 02:25:27 INFO - Finding installer, test and symbols from parent task. 02:25:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DdlHwtnRTzioKJf6aYcOOQ'}, attempt #1 02:25:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DdlHwtnRTzioKJf6aYcOOQ'}, attempt #1 02:25:28 INFO - Task dependencies: dMjXDFRQS4WLa_eQowLuJQ 02:25:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ'}, attempt #1 02:25:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.tar.bz2 02:25:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.test_packages.json 02:25:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.crashreporter-symbols.zip 02:25:28 INFO - Running post-action listener: _resource_record_post_action 02:25:28 INFO - [mozharness: 2017-10-01 09:25:28.916484Z] Finished read-buildbot-config step (success) 02:25:28 INFO - [mozharness: 2017-10-01 09:25:28.916701Z] Running download-and-extract step. 02:25:28 INFO - Running pre-action listener: _resource_record_pre_action 02:25:28 INFO - Running main action method: download_and_extract 02:25:28 INFO - mkdir: /builds/slave/test/build/tests 02:25:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:28 INFO - trying https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.test_packages.json 02:25:28 INFO - Downloading https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:25:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:25:29 INFO - Downloaded 989 bytes. 02:25:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:25:29 INFO - Using the following test package requirements: 02:25:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:25:29 INFO - u'common': [u'target.common.tests.zip'], 02:25:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:25:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:25:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:25:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:25:29 INFO - u'mozbase': [u'target.common.tests.zip'], 02:25:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:25:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:25:29 INFO - u'web-platform': [u'target.common.tests.zip', 02:25:29 INFO - u'target.web-platform.tests.tar.gz'], 02:25:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:25:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 02:25:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.common.tests.zip 02:25:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:25:29 INFO - Fetch https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.common.tests.zip into memory 02:25:31 INFO - Content-Length response header: 55177072 02:25:31 INFO - Bytes received: 55177072 02:25:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.talos.tests.zip 02:25:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:25:42 INFO - Fetch https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.talos.tests.zip into memory 02:25:43 INFO - Content-Length response header: 14509184 02:25:43 INFO - Bytes received: 14509184 02:25:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:44 INFO - trying https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.tar.bz2 02:25:44 INFO - Downloading https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:25:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:25:46 INFO - Downloaded 53535161 bytes. 02:25:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.tar.bz2 02:25:46 INFO - mkdir: /builds/slave/test/properties 02:25:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:25:46 INFO - Writing to file /builds/slave/test/properties/build_url 02:25:46 INFO - Contents: 02:25:46 INFO - build_url:https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.tar.bz2 02:25:46 INFO - Running post-action listener: _resource_record_post_action 02:25:46 INFO - Running post-action listener: find_tests_for_verification 02:25:46 INFO - Running post-action listener: set_extra_try_arguments 02:25:46 INFO - [mozharness: 2017-10-01 09:25:46.669026Z] Finished download-and-extract step (success) 02:25:46 INFO - [mozharness: 2017-10-01 09:25:46.669253Z] Running populate-webroot step. 02:25:46 INFO - Running pre-action listener: _resource_record_pre_action 02:25:46 INFO - Running main action method: populate_webroot 02:25:46 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:25:46 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:25:46 INFO - 'tests': ['tresize', 02:25:46 INFO - 'tcanvasmark']}, 02:25:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:25:46 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:25:46 INFO - 'tests': ['dromaeo_css', 02:25:46 INFO - 'kraken']}, 02:25:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:25:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:25:46 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:25:46 INFO - 'talos_options': ['--disable-stylo'], 02:25:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:25:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:25:46 INFO - 'tests': ['damp', 'tps']}, 02:25:46 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:25:46 INFO - 'talos_options': ['--disable-stylo'], 02:25:46 INFO - 'tests': ['damp', 'tps']}, 02:25:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:25:46 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:25:46 INFO - 'tests': ['dromaeo_dom']}, 02:25:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:25:46 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:25:46 INFO - 'tests': ['basic_compositor_video', 02:25:46 INFO - 'glvideo']}, 02:25:46 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:25:46 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:25:46 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:25:46 INFO - 'talos_options': ['--disable-stylo'], 02:25:46 INFO - 'tests': ['ts_paint_webext', 02:25:46 INFO - 'tp5o_webext']}, 02:25:46 INFO - 'other-e10s': {'tests': ['a11yr', 02:25:46 INFO - 'ts_paint', 02:25:46 INFO - 'tpaint', 02:25:46 INFO - 'sessionrestore', 02:25:46 INFO - 'sessionrestore_many_windows', 02:25:46 INFO - 'sessionrestore_no_auto_restore', 02:25:46 INFO - 'tabpaint']}, 02:25:46 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:25:46 INFO - 'tests': ['a11yr', 02:25:46 INFO - 'ts_paint', 02:25:46 INFO - 'tpaint', 02:25:46 INFO - 'sessionrestore', 02:25:46 INFO - 'sessionrestore_many_windows', 02:25:46 INFO - 'sessionrestore_no_auto_restore', 02:25:46 INFO - 'tabpaint']}, 02:25:46 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:25:46 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 02:25:46 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:25:46 INFO - 'tests': ['perf_reftest_singletons']}, 02:25:46 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:25:46 INFO - 'tests': ['bloom_basic']}, 02:25:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:25:46 INFO - 'tsvgr_opacity', 02:25:46 INFO - 'tart', 02:25:46 INFO - 'tscrollx', 02:25:46 INFO - 'tsvg_static']}, 02:25:46 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:25:46 INFO - 'tests': ['tsvgx', 02:25:46 INFO - 'tsvgr_opacity', 02:25:46 INFO - 'tart', 02:25:46 INFO - 'tscrollx', 02:25:46 INFO - 'tsvg_static']}, 02:25:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:25:46 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:25:46 INFO - 'talos_options': ['--disable-stylo'], 02:25:46 INFO - 'tests': ['tp5o']}, 02:25:46 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:25:46 INFO - 'talos_options': ['--mitmproxy', 02:25:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:25:46 INFO - '--firstNonBlankPaint'], 02:25:46 INFO - 'tests': ['tp6_google', 02:25:46 INFO - 'tp6_youtube', 02:25:46 INFO - 'tp6_amazon', 02:25:46 INFO - 'tp6_facebook']}, 02:25:46 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:25:46 INFO - 'talos_options': ['--disable-stylo', 02:25:46 INFO - '--mitmproxy', 02:25:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:25:46 INFO - '--firstNonBlankPaint'], 02:25:46 INFO - 'tests': ['tp6_google', 02:25:46 INFO - 'tp6_youtube', 02:25:46 INFO - 'tp6_amazon', 02:25:46 INFO - 'tp6_facebook']}, 02:25:46 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:25:46 INFO - 'talos_options': ['--stylo-threads=1', 02:25:46 INFO - '--mitmproxy', 02:25:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:25:46 INFO - '--firstNonBlankPaint'], 02:25:46 INFO - 'tests': ['tp6_google', 02:25:46 INFO - 'tp6_youtube', 02:25:46 INFO - 'tp6_amazon', 02:25:46 INFO - 'tp6_facebook']}, 02:25:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:25:46 INFO - 'talos_options': ['--xperf_path', 02:25:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:25:46 INFO - 'tests': ['tp5n']}, 02:25:46 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:25:46 INFO - 'talos_options': ['--disable-stylo', 02:25:46 INFO - '--xperf_path', 02:25:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:25:46 INFO - 'tests': ['tp5n']}}} 02:25:46 INFO - Running post-action listener: _resource_record_post_action 02:25:46 INFO - [mozharness: 2017-10-01 09:25:46.687246Z] Finished populate-webroot step (success) 02:25:46 INFO - [mozharness: 2017-10-01 09:25:46.687336Z] Running create-virtualenv step. 02:25:46 INFO - Running pre-action listener: _resource_record_pre_action 02:25:46 INFO - Running main action method: create_virtualenv 02:25:46 INFO - Creating virtualenv /builds/slave/test/build/venv 02:25:46 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:25:46 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:25:46 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:25:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:25:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:25:49 INFO - Installing distribute.............................................................................................................................................................................................done. 02:25:49 INFO - Installing pip...............done. 02:25:49 INFO - Return code: 0 02:25:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:25:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:25:49 INFO - Reading from file tmpfile_stdout 02:25:49 INFO - Output received: 02:25:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:25:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:25: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')]} 02:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25: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')]} 02:25:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022090>, '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 0x1022d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', '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 02:25:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:25:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:25:49 INFO - Using env: {'DISPLAY': ':0', 02:25:49 INFO - 'HOME': '/home/cltbld', 02:25:49 INFO - 'LANG': 'en_US.UTF-8', 02:25:49 INFO - 'LANGUAGE': 'en_US:en', 02:25:49 INFO - 'LOGNAME': 'cltbld', 02:25:49 INFO - 'MAIL': '/var/mail/cltbld', 02:25:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:49 INFO - 'MOZ_NO_REMOTE': '1', 02:25:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:25:49 INFO - 'NO_EM_RESTART': '1', 02:25:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:25:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:49 INFO - 'PWD': '/builds/slave/test', 02:25:49 INFO - 'SHELL': '/bin/bash', 02:25:49 INFO - 'SHLVL': '1', 02:25:49 INFO - 'TERM': 'linux', 02:25:49 INFO - 'TMOUT': '86400', 02:25:49 INFO - 'USER': 'cltbld', 02:25:49 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', 02:25:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:49 INFO - '_': '/tools/buildbot/bin/python'} 02:25:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:25:50 INFO - Downloading/unpacking pip>=1.5 02:25:50 INFO - Running setup.py egg_info for package pip 02:25:50 INFO - warning: no files found matching 'pip/cacert.pem' 02:25:50 INFO - warning: no files found matching '*.html' under directory 'docs' 02:25:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:25:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:25:50 INFO - Installing collected packages: pip 02:25:50 INFO - Found existing installation: pip 1.1 02:25:50 INFO - Uninstalling pip: 02:25:50 INFO - Successfully uninstalled pip 02:25:50 INFO - Running setup.py install for pip 02:25:50 INFO - warning: no files found matching 'pip/cacert.pem' 02:25:50 INFO - warning: no files found matching '*.html' under directory 'docs' 02:25:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:25:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:25:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:25:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:25:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:25:50 INFO - Successfully installed pip 02:25:50 INFO - Cleaning up... 02:25:50 INFO - Return code: 0 02:25:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022090>, '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 0x1022d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', '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 02:25:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:25:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:25:50 INFO - Using env: {'DISPLAY': ':0', 02:25:50 INFO - 'HOME': '/home/cltbld', 02:25:50 INFO - 'LANG': 'en_US.UTF-8', 02:25:50 INFO - 'LANGUAGE': 'en_US:en', 02:25:50 INFO - 'LOGNAME': 'cltbld', 02:25:50 INFO - 'MAIL': '/var/mail/cltbld', 02:25:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:50 INFO - 'MOZ_NO_REMOTE': '1', 02:25:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:25:50 INFO - 'NO_EM_RESTART': '1', 02:25:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:25:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:50 INFO - 'PWD': '/builds/slave/test', 02:25:50 INFO - 'SHELL': '/bin/bash', 02:25:50 INFO - 'SHLVL': '1', 02:25:50 INFO - 'TERM': 'linux', 02:25:50 INFO - 'TMOUT': '86400', 02:25:50 INFO - 'USER': 'cltbld', 02:25:50 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', 02:25:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:50 INFO - '_': '/tools/buildbot/bin/python'} 02:25:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:51 INFO - Downloading/unpacking psutil>=3.1.1 02:25: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 02:25: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 02:25: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 02:25: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 02:25:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:25:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:25:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:25:52 INFO - Installing collected packages: psutil 02:25:52 INFO - Running setup.py install for psutil 02:25:52 INFO - building 'psutil._psutil_linux' extension 02:25:52 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 02:25:53 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 02:25:54 INFO - building 'psutil._psutil_posix' extension 02:25:54 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 02:25:54 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 02:25:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:25:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:25:54 INFO - Successfully installed psutil 02:25:54 INFO - Cleaning up... 02:25:54 INFO - Return code: 0 02:25:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:54 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 0xcb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022090>, '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 0x1022d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', '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 02:25:54 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 02:25:54 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 02:25:54 INFO - Using env: {'DISPLAY': ':0', 02:25:54 INFO - 'HOME': '/home/cltbld', 02:25:54 INFO - 'LANG': 'en_US.UTF-8', 02:25:54 INFO - 'LANGUAGE': 'en_US:en', 02:25:54 INFO - 'LOGNAME': 'cltbld', 02:25:54 INFO - 'MAIL': '/var/mail/cltbld', 02:25:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:54 INFO - 'MOZ_NO_REMOTE': '1', 02:25:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:25:54 INFO - 'NO_EM_RESTART': '1', 02:25:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:25:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:54 INFO - 'PWD': '/builds/slave/test', 02:25:54 INFO - 'SHELL': '/bin/bash', 02:25:54 INFO - 'SHLVL': '1', 02:25:54 INFO - 'TERM': 'linux', 02:25:54 INFO - 'TMOUT': '86400', 02:25:54 INFO - 'USER': 'cltbld', 02:25:54 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', 02:25:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:54 INFO - '_': '/tools/buildbot/bin/python'} 02:25:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:25: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 02:25: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 02:25: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 02:25: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 02:25:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:25:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:25:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:25:56 INFO - Installing collected packages: mozsystemmonitor 02:25:56 INFO - Running setup.py install for mozsystemmonitor 02:25:56 INFO - Successfully installed mozsystemmonitor 02:25:56 INFO - Cleaning up... 02:25:56 INFO - Return code: 0 02:25:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:25:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xcb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022090>, '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 0x1022d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', '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 02:25:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:25:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:25:56 INFO - Using env: {'DISPLAY': ':0', 02:25:56 INFO - 'HOME': '/home/cltbld', 02:25:56 INFO - 'LANG': 'en_US.UTF-8', 02:25:56 INFO - 'LANGUAGE': 'en_US:en', 02:25:56 INFO - 'LOGNAME': 'cltbld', 02:25:56 INFO - 'MAIL': '/var/mail/cltbld', 02:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:56 INFO - 'MOZ_NO_REMOTE': '1', 02:25:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:25:56 INFO - 'NO_EM_RESTART': '1', 02:25:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:56 INFO - 'PWD': '/builds/slave/test', 02:25:56 INFO - 'SHELL': '/bin/bash', 02:25:56 INFO - 'SHLVL': '1', 02:25:56 INFO - 'TERM': 'linux', 02:25:56 INFO - 'TMOUT': '86400', 02:25:56 INFO - 'USER': 'cltbld', 02:25:56 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', 02:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:56 INFO - '_': '/tools/buildbot/bin/python'} 02:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:56 INFO - Downloading/unpacking jsonschema==2.5.1 02:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:25:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:25:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:25:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:25:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:25: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 02:25: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 02:25: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 02:25: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 02:25:57 INFO - Downloading functools32-3.2.3-2.tar.gz 02:25:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:25:57 INFO - warning: no files found matching '*.txt' 02:25:57 INFO - no previously-included directories found matching 'build' 02:25:57 INFO - no previously-included directories found matching 'dist' 02:25:57 INFO - no previously-included directories found matching '.git*' 02:25:57 INFO - Installing collected packages: jsonschema, functools32 02:25:57 INFO - Running setup.py install for functools32 02:25:58 INFO - warning: no files found matching '*.txt' 02:25:58 INFO - no previously-included directories found matching 'build' 02:25:58 INFO - no previously-included directories found matching 'dist' 02:25:58 INFO - no previously-included directories found matching '.git*' 02:25:58 INFO - Successfully installed jsonschema functools32 02:25:58 INFO - Cleaning up... 02:25:58 INFO - Return code: 0 02:25:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:25: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')]} 02:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25: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')]} 02:25:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:58 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 0xcb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022090>, '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 0x1022d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', '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 02:25:58 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 02:25:58 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 02:25:58 INFO - Using env: {'DISPLAY': ':0', 02:25:58 INFO - 'HOME': '/home/cltbld', 02:25:58 INFO - 'LANG': 'en_US.UTF-8', 02:25:58 INFO - 'LANGUAGE': 'en_US:en', 02:25:58 INFO - 'LOGNAME': 'cltbld', 02:25:58 INFO - 'MAIL': '/var/mail/cltbld', 02:25:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:58 INFO - 'MOZ_NO_REMOTE': '1', 02:25:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:25:58 INFO - 'NO_EM_RESTART': '1', 02:25:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:58 INFO - 'PWD': '/builds/slave/test', 02:25:58 INFO - 'SHELL': '/bin/bash', 02:25:58 INFO - 'SHLVL': '1', 02:25:58 INFO - 'TERM': 'linux', 02:25:58 INFO - 'TMOUT': '86400', 02:25:58 INFO - 'USER': 'cltbld', 02:25:58 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', 02:25:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:58 INFO - '_': '/tools/buildbot/bin/python'} 02:25:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:25:58 INFO - Cleaning up... 02:25:58 INFO - Return code: 0 02:25:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:25: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')]} 02:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25: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')]} 02:25:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:58 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 0xcb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022090>, '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 0x1022d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', '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 02:25:58 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 02:25:58 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 02:25:58 INFO - Using env: {'DISPLAY': ':0', 02:25:58 INFO - 'HOME': '/home/cltbld', 02:25:58 INFO - 'LANG': 'en_US.UTF-8', 02:25:58 INFO - 'LANGUAGE': 'en_US:en', 02:25:58 INFO - 'LOGNAME': 'cltbld', 02:25:58 INFO - 'MAIL': '/var/mail/cltbld', 02:25:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:58 INFO - 'MOZ_NO_REMOTE': '1', 02:25:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:25:58 INFO - 'NO_EM_RESTART': '1', 02:25:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:58 INFO - 'PWD': '/builds/slave/test', 02:25:58 INFO - 'SHELL': '/bin/bash', 02:25:58 INFO - 'SHLVL': '1', 02:25:58 INFO - 'TERM': 'linux', 02:25:58 INFO - 'TMOUT': '86400', 02:25:58 INFO - 'USER': 'cltbld', 02:25:58 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', 02:25:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:58 INFO - '_': '/tools/buildbot/bin/python'} 02:25:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:58 INFO - Downloading/unpacking blobuploader==1.2.4 02:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:25:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:25:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:25:59 INFO - Downloading blobuploader-1.2.4.tar.gz 02:25:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:25:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:25:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:25:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:25:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:26:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:26:00 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 02:26:00 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 02:26:00 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 02:26:00 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 02:26:00 INFO - Downloading docopt-0.6.1.tar.gz 02:26:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:26:00 INFO - Installing collected packages: blobuploader, requests, docopt 02:26:00 INFO - Running setup.py install for blobuploader 02:26:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:26:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:26:00 INFO - Running setup.py install for requests 02:26:00 INFO - Running setup.py install for docopt 02:26:00 INFO - Successfully installed blobuploader requests docopt 02:26:00 INFO - Cleaning up... 02:26:00 INFO - Return code: 0 02:26:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:26:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:26:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022090>, '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 0x1022d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', '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 02:26:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:26:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:26:00 INFO - Using env: {'DISPLAY': ':0', 02:26:00 INFO - 'HOME': '/home/cltbld', 02:26:00 INFO - 'LANG': 'en_US.UTF-8', 02:26:00 INFO - 'LANGUAGE': 'en_US:en', 02:26:00 INFO - 'LOGNAME': 'cltbld', 02:26:00 INFO - 'MAIL': '/var/mail/cltbld', 02:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:00 INFO - 'MOZ_NO_REMOTE': '1', 02:26:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:26:00 INFO - 'NO_EM_RESTART': '1', 02:26:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:00 INFO - 'PWD': '/builds/slave/test', 02:26:00 INFO - 'SHELL': '/bin/bash', 02:26:00 INFO - 'SHLVL': '1', 02:26:00 INFO - 'TERM': 'linux', 02:26:00 INFO - 'TMOUT': '86400', 02:26:00 INFO - 'USER': 'cltbld', 02:26:00 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', 02:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:00 INFO - '_': '/tools/buildbot/bin/python'} 02:26:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:26:01 INFO - Running setup.py (path:/tmp/pip-o8iasl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:26:01 INFO - Running setup.py (path:/tmp/pip-udSVOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:26:01 INFO - Running setup.py (path:/tmp/pip-K8VLYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:26:01 INFO - Running setup.py (path:/tmp/pip-YfYqbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:26:01 INFO - Running setup.py (path:/tmp/pip-Q_S8OU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:26:01 INFO - Running setup.py (path:/tmp/pip-mhWwMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:26:01 INFO - Running setup.py (path:/tmp/pip-mH4Krf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:26:01 INFO - Running setup.py (path:/tmp/pip-UtTPF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:26:01 INFO - Running setup.py (path:/tmp/pip-PFU_xU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:26:01 INFO - Running setup.py (path:/tmp/pip-4h1dwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:26:01 INFO - Running setup.py (path:/tmp/pip-AOuSOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:26:01 INFO - Running setup.py (path:/tmp/pip-6Tp5Jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:26:01 INFO - Running setup.py (path:/tmp/pip-Ydb_CB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:26:01 INFO - Running setup.py (path:/tmp/pip-MtoEVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:26:02 INFO - Running setup.py (path:/tmp/pip-qQPOhg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:26:02 INFO - Running setup.py (path:/tmp/pip-I6S49P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:26:02 INFO - Running setup.py (path:/tmp/pip-e_RsmZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:26:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:26:02 INFO - Running setup.py install for manifestparser 02:26:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:26:02 INFO - Running setup.py install for mozcrash 02:26:02 INFO - Running setup.py install for mozdebug 02:26:02 INFO - Running setup.py install for mozdevice 02:26:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:26:02 INFO - Running setup.py install for mozfile 02:26:02 INFO - Running setup.py install for mozhttpd 02:26:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:26:02 INFO - Running setup.py install for mozinfo 02:26:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:26:02 INFO - Running setup.py install for mozInstall 02:26:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:26:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:26:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:26:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:26:03 INFO - Running setup.py install for mozleak 02:26:03 INFO - Running setup.py install for mozlog 02:26:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:26:03 INFO - Running setup.py install for moznetwork 02:26:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:26:03 INFO - Running setup.py install for mozprocess 02:26:03 INFO - Running setup.py install for mozprofile 02:26:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:26:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:26:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:26:03 INFO - Running setup.py install for mozrunner 02:26:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:26:03 INFO - Running setup.py install for mozscreenshot 02:26:03 INFO - Running setup.py install for moztest 02:26:03 INFO - Running setup.py install for mozversion 02:26:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:26:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:26:04 INFO - Cleaning up... 02:26:04 INFO - Return code: 0 02:26:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:26: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')]} 02:26:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:26: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')]} 02:26:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:26:04 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 0xcb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022090>, '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 0x1022d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', '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 02:26:04 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 02:26:04 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 02:26:04 INFO - Using env: {'DISPLAY': ':0', 02:26:04 INFO - 'HOME': '/home/cltbld', 02:26:04 INFO - 'LANG': 'en_US.UTF-8', 02:26:04 INFO - 'LANGUAGE': 'en_US:en', 02:26:04 INFO - 'LOGNAME': 'cltbld', 02:26:04 INFO - 'MAIL': '/var/mail/cltbld', 02:26:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:04 INFO - 'MOZ_NO_REMOTE': '1', 02:26:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:26:04 INFO - 'NO_EM_RESTART': '1', 02:26:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:26:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:04 INFO - 'PWD': '/builds/slave/test', 02:26:04 INFO - 'SHELL': '/bin/bash', 02:26:04 INFO - 'SHLVL': '1', 02:26:04 INFO - 'TERM': 'linux', 02:26:04 INFO - 'TMOUT': '86400', 02:26:04 INFO - 'USER': 'cltbld', 02:26:04 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', 02:26:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:04 INFO - '_': '/tools/buildbot/bin/python'} 02:26:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:26:04 INFO - Running setup.py (path:/tmp/pip-lSvRHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:26:04 INFO - Running setup.py (path:/tmp/pip-furnPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:26:04 INFO - Running setup.py (path:/tmp/pip-C5AuVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:26:04 INFO - Running setup.py (path:/tmp/pip-S3GGoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:26:04 INFO - Running setup.py (path:/tmp/pip-086KKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:26:04 INFO - Running setup.py (path:/tmp/pip-SI7sKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:26:04 INFO - Running setup.py (path:/tmp/pip-jxTCco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:26:04 INFO - Running setup.py (path:/tmp/pip-9wyUch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:26:04 INFO - Running setup.py (path:/tmp/pip-dwudl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:26:04 INFO - Running setup.py (path:/tmp/pip-JJOCWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:26:04 INFO - Running setup.py (path:/tmp/pip-rzm1Rq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:26:05 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)) 02:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:26:05 INFO - Running setup.py (path:/tmp/pip-C2Gfy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:26:05 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)) 02:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:26:05 INFO - Running setup.py (path:/tmp/pip-XKqfyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:26:05 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)) 02:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:26:05 INFO - Running setup.py (path:/tmp/pip-6oqt1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:26:05 INFO - Running setup.py (path:/tmp/pip-LsFH9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:26:05 INFO - Running setup.py (path:/tmp/pip-Vq4K2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:26:05 INFO - Running setup.py (path:/tmp/pip-HicCiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:26:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:26:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:26:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:26:06 INFO - Downloading blessings-1.6.tar.gz 02:26:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:26:06 INFO - Installing collected packages: blessings 02:26:06 INFO - Running setup.py install for blessings 02:26:06 INFO - Successfully installed blessings 02:26:06 INFO - Cleaning up... 02:26:06 INFO - Return code: 0 02:26:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:26:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:26:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:26:07 INFO - Reading from file tmpfile_stdout 02:26:07 INFO - Current package versions: 02:26:07 INFO - argparse == 1.2.1 02:26:07 INFO - blessings == 1.6 02:26:07 INFO - blobuploader == 1.2.4 02:26:07 INFO - docopt == 0.6.1 02:26:07 INFO - functools32 == 3.2.3-2 02:26:07 INFO - jsonschema == 2.5.1 02:26:07 INFO - manifestparser == 1.1 02:26:07 INFO - mozInstall == 1.13 02:26:07 INFO - mozcrash == 1.0 02:26:07 INFO - mozdebug == 0.1 02:26:07 INFO - mozdevice == 0.51 02:26:07 INFO - mozfile == 1.2 02:26:07 INFO - mozhttpd == 0.7 02:26:07 INFO - mozinfo == 0.10 02:26:07 INFO - mozleak == 0.1 02:26:07 INFO - mozlog == 3.5 02:26:07 INFO - moznetwork == 0.27 02:26:07 INFO - mozprocess == 0.25 02:26:07 INFO - mozprofile == 0.28 02:26:07 INFO - mozrunner == 6.13 02:26:07 INFO - mozscreenshot == 0.1 02:26:07 INFO - mozsystemmonitor == 0.3 02:26:07 INFO - moztest == 0.8 02:26:07 INFO - mozversion == 1.4 02:26:07 INFO - psutil == 3.1.1 02:26:07 INFO - requests == 1.2.3 02:26:07 INFO - wsgiref == 0.1.2 02:26:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:26:07 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')]} 02:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:26:07 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')]} 02:26:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:26:07 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 0xcb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022090>, '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 0x1022d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', '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 02:26:07 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 02:26:07 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 02:26:07 INFO - Using env: {'DISPLAY': ':0', 02:26:07 INFO - 'HOME': '/home/cltbld', 02:26:07 INFO - 'LANG': 'en_US.UTF-8', 02:26:07 INFO - 'LANGUAGE': 'en_US:en', 02:26:07 INFO - 'LOGNAME': 'cltbld', 02:26:07 INFO - 'MAIL': '/var/mail/cltbld', 02:26:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:07 INFO - 'MOZ_NO_REMOTE': '1', 02:26:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:26:07 INFO - 'NO_EM_RESTART': '1', 02:26:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:26:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:07 INFO - 'PWD': '/builds/slave/test', 02:26:07 INFO - 'SHELL': '/bin/bash', 02:26:07 INFO - 'SHLVL': '1', 02:26:07 INFO - 'TERM': 'linux', 02:26:07 INFO - 'TMOUT': '86400', 02:26:07 INFO - 'USER': 'cltbld', 02:26:07 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', 02:26:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:07 INFO - '_': '/tools/buildbot/bin/python'} 02:26:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:26:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:26:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:26:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:26:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:26:08 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:26:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:26:08 INFO - Installing collected packages: simplejson 02:26:08 INFO - Running setup.py install for simplejson 02:26:08 INFO - building 'simplejson._speedups' extension 02:26:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 02:26:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 02:26:09 INFO - Successfully installed simplejson 02:26:09 INFO - Cleaning up... 02:26:09 INFO - Return code: 0 02:26:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:26:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:26:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:26:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:26:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcb31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe7ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022090>, '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 0x1022d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cf90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', '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 02:26:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:26:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:26:09 INFO - Using env: {'DISPLAY': ':0', 02:26:09 INFO - 'HOME': '/home/cltbld', 02:26:09 INFO - 'LANG': 'en_US.UTF-8', 02:26:09 INFO - 'LANGUAGE': 'en_US:en', 02:26:09 INFO - 'LOGNAME': 'cltbld', 02:26:09 INFO - 'MAIL': '/var/mail/cltbld', 02:26:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:09 INFO - 'MOZ_NO_REMOTE': '1', 02:26:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:26:09 INFO - 'NO_EM_RESTART': '1', 02:26:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:26:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:09 INFO - 'PWD': '/builds/slave/test', 02:26:09 INFO - 'SHELL': '/bin/bash', 02:26:09 INFO - 'SHLVL': '1', 02:26:09 INFO - 'TERM': 'linux', 02:26:09 INFO - 'TMOUT': '86400', 02:26:09 INFO - 'USER': 'cltbld', 02:26:09 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', 02:26:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:09 INFO - '_': '/tools/buildbot/bin/python'} 02:26:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:26:09 INFO - Cleaning up... 02:26:09 INFO - Return code: 0 02:26:09 INFO - Running post-action listener: _resource_record_post_action 02:26:09 INFO - Running post-action listener: _start_resource_monitoring 02:26:09 INFO - Starting resource monitoring. 02:26:09 INFO - [mozharness: 2017-10-01 09:26:09.791696Z] Finished create-virtualenv step (success) 02:26:09 INFO - [mozharness: 2017-10-01 09:26:09.792634Z] Running install step. 02:26:09 INFO - Running pre-action listener: _resource_record_pre_action 02:26:09 INFO - Running main action method: install 02:26:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:26:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:26:10 INFO - Reading from file tmpfile_stdout 02:26:10 INFO - Detecting whether we're running mozinstall >=1.0... 02:26:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:26:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:26:10 INFO - Reading from file tmpfile_stdout 02:26:10 INFO - Output received: 02:26:10 INFO - Usage: mozinstall [options] installer 02:26:10 INFO - Options: 02:26:10 INFO - -h, --help show this help message and exit 02:26:10 INFO - -d DEST, --destination=DEST 02:26:10 INFO - Directory to install application into. [default: 02:26:10 INFO - "/builds/slave/test"] 02:26:10 INFO - --app=APP Application being installed. [default: firefox] 02:26:10 INFO - mkdir: /builds/slave/test/build/application 02:26:10 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'] 02:26:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:26:24 INFO - Reading from file tmpfile_stdout 02:26:24 INFO - Output received: 02:26:24 INFO - /builds/slave/test/build/application/firefox/firefox 02:26:24 INFO - Running post-action listener: _resource_record_post_action 02:26:24 INFO - [mozharness: 2017-10-01 09:26:24.715980Z] Finished install step (success) 02:26:24 INFO - [mozharness: 2017-10-01 09:26:24.716231Z] Running setup-mitmproxy step. 02:26:24 INFO - Running pre-action listener: _resource_record_pre_action 02:26:24 INFO - Running main action method: setup_mitmproxy 02:26:24 INFO - Skipping: mitmproxy is not required 02:26:24 INFO - Running post-action listener: _resource_record_post_action 02:26:24 INFO - [mozharness: 2017-10-01 09:26:24.717359Z] Finished setup-mitmproxy step (success) 02:26:24 INFO - [mozharness: 2017-10-01 09:26:24.717581Z] Running run-tests step. 02:26:24 INFO - Running pre-action listener: _resource_record_pre_action 02:26:24 INFO - Running pre-action listener: _set_gcov_prefix 02:26:24 INFO - Running main action method: run_tests 02:26:24 WARNING - Try message not found. 02:26:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:26:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:26:24 INFO - Python 2.7.3 02:26:24 INFO - Return code: 0 02:26:24 INFO - grabbing minidump binary from tooltool 02:26:24 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')]} 02:26:24 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 0x1022090>, '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 0x1022d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:26:24 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 02:26:24 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 02:26:24 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 02:26:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:26:25 INFO - Return code: 0 02:26:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:26:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:26:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:26:25 INFO - ENV: RUST_BACKTRACE is now full 02:26:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:26:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:26:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 02:26:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 02:26:25 INFO - Using env: {'DISPLAY': ':0', 02:26:25 INFO - 'HOME': '/home/cltbld', 02:26:25 INFO - 'LANG': 'en_US.UTF-8', 02:26:25 INFO - 'LANGUAGE': 'en_US:en', 02:26:25 INFO - 'LOGNAME': 'cltbld', 02:26:25 INFO - 'MAIL': '/var/mail/cltbld', 02:26:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:26:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:25 INFO - 'MOZ_NO_REMOTE': '1', 02:26:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:26:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:26:25 INFO - 'NO_EM_RESTART': '1', 02:26:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:26:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:25 INFO - 'PWD': '/builds/slave/test', 02:26:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:26:25 INFO - 'RUST_BACKTRACE': 'full', 02:26:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:26:25 INFO - 'SHELL': '/bin/bash', 02:26:25 INFO - 'SHLVL': '1', 02:26:25 INFO - 'TERM': 'linux', 02:26:25 INFO - 'TMOUT': '86400', 02:26:25 INFO - 'USER': 'cltbld', 02:26:25 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', 02:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:25 INFO - '_': '/tools/buildbot/bin/python'} 02:26:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 02:26:25 INFO - mozversion application_buildid: 20171001080022 02:26:25 INFO - mozversion application_changeset: 969b202c949f549d1f015d1308c7aaf8c6e0328b 02:26:25 INFO - mozversion application_display_name: Firefox 02:26:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:26:25 INFO - mozversion application_name: Firefox 02:26:25 INFO - mozversion application_remotingname: firefox 02:26:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 02:26:25 INFO - mozversion application_vendor: Mozilla 02:26:25 INFO - mozversion application_version: 57.0 02:26:25 INFO - mozversion platform_buildid: 20171001080022 02:26:25 INFO - mozversion platform_changeset: 969b202c949f549d1f015d1308c7aaf8c6e0328b 02:26:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 02:26:25 INFO - mozversion platform_version: 57.0 02:26:25 INFO - using testdate: 1506849985 02:26:25 INFO - actual date: 1506849985 02:26:25 INFO - starting webserver on 'localhost:44330' 02:26:25 INFO - SUITE-START | Running 2 tests 02:26:25 INFO - TEST-START | dromaeo_css 02:26:25 INFO - Initialising browser for dromaeo_css test... 02:26:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44330/getInfo.html -profile /tmp/tmpqXW1g6/profile 02:26:25 INFO - TEST-INFO | started process 3761 (/builds/slave/test/build/application/firefox/firefox http://localhost:44330/getInfo.html) 02:26:33 INFO - TEST-INFO | 3761: exit 0 02:26:34 INFO - Browser initialized. 02:26:34 INFO - Running cycle 1/1 for dromaeo_css test... 02:26:34 INFO - Using env: {'DISPLAY': ':0', 02:26:34 INFO - 'HOME': '/home/cltbld', 02:26:34 INFO - 'JSGC_DISABLE_POISONING': '1', 02:26:34 INFO - 'LANG': 'en_US.UTF-8', 02:26:34 INFO - 'LANGUAGE': 'en_US:en', 02:26:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:26:34 INFO - 'LOGNAME': 'cltbld', 02:26:34 INFO - 'MAIL': '/var/mail/cltbld', 02:26:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:26:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:26:34 INFO - 'MOZ_CRASHREPORTER': '1', 02:26:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:26:34 INFO - 'MOZ_NO_REMOTE': '1', 02:26:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:26:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:26:34 INFO - 'NO_EM_RESTART': '1', 02:26:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:26:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:34 INFO - 'PWD': '/builds/slave/test', 02:26:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:26:34 INFO - 'RUST_BACKTRACE': 'full', 02:26:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:26:34 INFO - 'SHELL': '/bin/bash', 02:26:34 INFO - 'SHLVL': '1', 02:26:34 INFO - 'TERM': 'linux', 02:26:34 INFO - 'TMOUT': '86400', 02:26:34 INFO - 'USER': 'cltbld', 02:26:34 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', 02:26:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:34 INFO - '_': '/tools/buildbot/bin/python'} 02:26:34 INFO - TEST-INFO | started process 3952 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqXW1g6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:26:34 INFO - PID 3952 | 02:26:34 INFO - PID 3952 | (/builds/slave/test/build/application/firefox/firefox:4001): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:26:34 INFO - PID 3952 | 02:26:45 INFO - PID 3952 | RSS: Main: 142114816 02:26:45 INFO - PID 3952 | 02:29:42 INFO - PID 3952 | Cycle 1(1): loaded http://localhost:44330/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44330/tests/dromaeo/cssquery-ext.html) 02:29:43 INFO - PID 3952 | RSS: Main: 145461248 02:29:43 INFO - PID 3952 | 02:32:45 INFO - PID 3952 | Cycle 1(1): loaded http://localhost:44330/tests/dromaeo/cssquery-ext.html (next: http://localhost:44330/tests/dromaeo/cssquery-jquery.html) 02:32:45 INFO - PID 3952 | RSS: Main: 143810560 02:32:45 INFO - PID 3952 | 02:35:47 INFO - PID 3952 | Cycle 1(1): loaded http://localhost:44330/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44330/tests/dromaeo/cssquery-mootools.html) 02:35:47 INFO - PID 3952 | RSS: Main: 145629184 02:35:47 INFO - PID 3952 | 02:38:49 INFO - PID 3952 | Cycle 1(1): loaded http://localhost:44330/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44330/tests/dromaeo/cssquery-prototype.html) 02:38:49 INFO - PID 3952 | RSS: Main: 145059840 02:38:49 INFO - PID 3952 | 02:41:52 INFO - PID 3952 | Cycle 1(1): loaded http://localhost:44330/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44330/tests/dromaeo/cssquery-yui.html) 02:41:53 INFO - PID 3952 | RSS: Main: 144519168 02:41:53 INFO - PID 3952 | 02:44:54 INFO - PID 3952 | Cycle 1(1): loaded http://localhost:44330/tests/dromaeo/cssquery-yui.html (next: http://localhost:44330/tests/dromaeo/cssquery-dojo.html) 02:44:55 INFO - PID 3952 | RSS: Main: 148860928 02:44:55 INFO - PID 3952 | 02:44:55 INFO - PID 3952 | __start_tp_report 02:44:55 INFO - PID 3952 | _x_x_mozilla_page_load 02:44:55 INFO - PID 3952 | _x_x_mozilla_page_load_details 02:44:55 INFO - PID 3952 | |i|pagename|runs| 02:44:55 INFO - PID 3952 | |0;dojo.html;3988.780617066062;4001.579834117416;4016.839326426944;4016.9957510622344;4037.2329257441083;6424.229092508894;6511.348865113487;6533.575317604358;6551.541392102555;6570.342965703439;6498.545101842873;6500.154979852624;6593.736250549972;6605.603663780176;6608.96695516523;5720.141978703207;5740.913886291709;5827.213326200972;5834.766609335621;5860.150278209657;5731.856703582423;5793.073108302672;5811.27359080115;5853.502452291539;5882.499987501046;5860.062390017598;5864.882702345951;5869.06095024794;5877.8824423511505;5883.794067207648;5749.856253593652;5817.534597232254;5870.3836097209605;5872.794452194195;5885.087811389241;5800.158976948361;5810.302763668386;5868.295804503444;5898.14476900847;5922.7927022554;6264.436200741955;6408.391202835724;6421.101045853584;6428.100065990719;6502.479801615859;7040.225575186725;7044.647767611599;7052.4005459536;7056.788296351117;7063.04648872406;7075.681594328216;7138.321859423347;7158.570485770871;7161.069061022086;7167.4266058715175;6527.902081468782;6554.770583029619;6598.373154550262;6606.537542372036;6625.635590042546;7208.891866622005;7270.818229544304;7316.817079573053;7336.816579585553;7342.5227360221415;6992.5105242633545;7004.15950085993;7054.047703559953;7058.258882817333;7084.822879428056;6945.305469453116;7054.400375967997;7091.290870912971;7117.964410177916;7156.069710937545;6968.407685346701;6975.581465112109;6983.685734141975;6983.93016069843;7002;6956.269591692901;6961.617111058839;7043.788686339418;7049.9647501762165;7062.823429414306;5101.903090835488;5116.488351164854;5169.198774190007;5175.223716442564;5188.714620695822;4655.790489427984;4672.719636821802;4710.105080034782;4748.358971538798;4809.927851082237;6716.966415167893;6792.150981127359;6798.456123510107;6799.1840979081835;6801.319868013159;6989.091418115612;7095.645217739196;7167.068281123525;7179.89230161548;7190.676419561132;6689.76585819494;6707.429868461236;6752.864942701119;6786.8303292417095;6793.354631310018;5840.970795145997;5879.029960056629;5928.911066334009;5941.1385349125;5943.167956486095;7379.483436159418;7381.261873812683;7479.364254038359;7484.476086673881;7495.812604684927;6968.268331825187;7010.544314619533;7055.3650171484815;7091.255418181223;7126.893096603451;7266.164391095092;7348.926510734825;7436.330730234088;7438.256174382518;7462.403007759258;6979.162500499972;6979.755708550177;7058.400035996998;7074.469414793808;7076.716931322895;6989.615571143636;7042.260562640849;7081.575105493687;7103.2186459488385;7154.2488038757265;6764.830879228059;6781.050652908498;6785.830354241183;6802.557833740791;6826.317368263134;6642.767503137367;6736.629485378156;6803.115594972622;6811.625360605214;6832.795016149524;6668.133142691419;6675.23234828001;6774.898376524455;6775.593464392152;6779.15260592426;6787.287334829969;6866.519343645897;6898.689558969758;6942.8611427772175;7004.089468369283;6645.036469711961;6714.764983225564;6715.395614394728;6792.830179245559;6822.658867056528 02:44:55 INFO - PID 3952 | |1;ext.html;982.9705108846734;987.9209663226943;1005.919526437885;1014.6753039027509;1045.5765415006924;46028.309575356354;46805.7659711701;46840.29739553909;46902.53097469024;46957.76521117394;42370.57629423705;42435.787821060854;42567.361489577685;42631.573684263145;42758.14483710324;8147.592620368988;8201.7129400471;8269.710560130397;8270.793230169234;8301.83396332073;8067.556284404384;8104.3921705872;8125.390595705317;8133.715319963802;8159.755207343789;8790.868136977919;8987.775305617379;9024.413413128159;9028.68399606014;9034.412763170372;8909.420887642314;8927.553622318923;8950.283977281866;8972.147645973622;8975.775605609846;8748.081451447633;8782;8786.253168480687;8875.378723489359;8883.822323553557;42583.78708106471;42680.57319426797;42710.78644606788;42825.785871070446;42858.35712464315;3895.7078219133678;3902.356111241642;3930.4300876372768;3930.803459827026;3935.9212815743526;34513;34555.445004974834;34800;34836;34991.825040874886;5578.135389014709;5710.65736055838;5727.770889164429;5752.856178595527;5893.469587737081;229021;229908;231159;231262;231379;54901.1764823528;54941;55059.17411238835;55119;55185.72407138019;65974;66192.69747466399;66348.6682566584;66736.66631666811;67443.66278168578;18618.720719189223;18683.252669893325;18691.439256822316;18747.437576872715;18752.906235469007;15010.399584016735;15127.621809454851;15266.542003739905;15283.770743438858;15287;16617.41839035628;16666.83333166677;16743.413980510624;16769.664606707796;16791.2443940023;6505.967470162619;6539.6403197824575;6557.311482294386;6564.310747371553;6584.604923704593;1110.3393480878865;1147.6499667601295;1166.3293606176483;1167.7255844876447;1171.1860257121218;27201.591976120373;27666.308342291602;27695.723042769314;27942.161735147976;28002.719972800012;1234.327291626048;1242.6520574239228;1257.6227131860587;1279.0662816144268;1291.9547815826402;14511.274436278143;14594.197319147548;14607.78088328676;14836.554903352917;14923.328450219766;292226;300277;300484;303197;306519;66275.33724662692;66346;66529;66603.33396665972;67034.32965670379;1524.6950609877847;1540.6302487402943;1540.6302487403166;1548.3110016042754;1558.5480210738986;31689.366212675155;31747.84126079355;32088.5186722204;32531.18672033218;32643.673563265012;44091.338629919934;44144.11711765811;44157.33763993607;44742;44773.55226447694;262226;262371;264613;264680;264721;39197.216055679295;40665.39001914914;41243.58756412518;41313.1737365245;41408.37887431631;49595.25607115968;50878.74560627173;51289.48710512847;51472.74263628658;51500.742496287276;36982.26035479329;37670.62329376671;37785.433218501195;37807;38481.23037539177;36479.27041459103;36590.63409365872;36993.26013479661;37091.44362834613;37095.62904370922;43744.78127609342;43752;43912.560874390845;44197.11605767931;44296.11407771762;35439;35515.82242088773;35545.93362199138;35744.463833042006;36260;25859.741402585732;25977.610335845355;26199.738002620492;26618.600720989587;26621.7337826627 02:44:55 INFO - PID 3952 | |2;jquery.html;633.0027224889128;645.670707938951;648.4520580109805;651.0139269417705;652.654093330535;23936.1622343218;24046.038158473682;24548.87725561372;25717.48565028703;25942.351441213978;27200.727992720065;28247.29381765457;28282.858585707094;28303;28317.00890468834;6929.203141638701;7004.4396448284015;7185.137783465965;7194.4963852530345;7220.8194795130275;7011.368976792088;7101.609411482366;7117.501774875735;7130.821729456753;7133.607651579161;6998.4751143664425;7059.576425414466;7078.256783037784;7086.433085353186;7090.29097090292;6996.510244282902;7000.754973575926;7005.369516743492;7040.190378106532;7050.259722729116;6561.475081993477;6573.079768832367;6582.407583317525;6615.867682646368;6633.436157926582;30520.236994075105;32463.350732985207;32700.509492357633;32825.34349313001;32829.671703282904;2302.1482051640833;2331.5336932613377;2335.976640233593;2342.390978345625;2353.623420252751;21372;21643.35069947904;22209.666854997187;22211.333659990225;22224.77775222243;2624.4357463145325;2641.511320405731;2702.2974026753186;2706.6210730497746;2710.6476158099517;148051;153414;166668;175116;175233;16912.915435422743;17236.741448878278;17656.20547075385;17701.911490442464;17905.910470447565;28190;28209.15372538786;28228.576571351445;28277.71722282792;28406.857965710453;11690.70773230676;11777.175597708077;11818.409079545989;11826.919712699106;11917.880821191851;10743.355398676105;10843.728906777236;10890.509927053301;10992.615258465916;11146.721331966766;10363.585456581803;10467;10477.161827053817;10620;10676.51955661997;3015.8492075396143;3042.7717921156;3048.3750831079574;3121.438141134572;3133.7022982816598;1062.166199533378;1062.8830828608848;1080.4597701149426;1080.7514271717548;1085.913126949842;22688.65967010496;23166.76833231646;23357.2992810216;23555.646665300035;23678.408039799142;1148.104478506766;1161.7908776420325;1169.502961241469;1170.9180357374903;1186.2704436771496;12460.376981150906;12583.370831458391;12637.43131559082;12895.742085158245;12914.612561623166;602070;604585;608522;619419;620664;136565.31717341547;141109;141458;142706;143111;1278.859325474197;1288;1300.843898732158;1317.6705823544114;1326.191023475679;20536.691949621065;20695.482612934797;20730.585388292453;20753.169873204904;21101.155953761678;62432.68783656053;63123.36876631175;64124.358756413705;64567.354326458015;64603;83558.58220708858;84089;84431.57784211039;85036;85072;16917.577060573585;17155.742663860303;17211.91394043022;17225.224864881362;17271.136443178042;25959.610605841306;26161.345966350993;26308.342291442103;26467.7353226473;26635.33411664724;19958.900205498878;19968.400947971582;20044.098015589043;20083.297084601967;20308.79691203128;18836;19318.227270909567;19493.220271188995;19629.018549072774;19881.204751810343;20933;21822.890885545472;22157.778422216215;22298.21956231524;22306;16911.91544042272;16994.235259413603;17148.142592870558;17159.227834747715;17305.13474326304;18687.345942891934;19548.902255488632;19599.608007839477;19641.901790490956;19684.409467715992 02:44:55 INFO - PID 3952 | |3;mootools.html;1373.7183877305151;1516.2115699836088;1529.3270960777263;1540.1606124662057;1543.228385807097;17991;18535.073246337663;18541.814581854178;18544.536386590342;18546.165422556;17953.192106355214;18000.54998625035;18012;18047;18089.7286540702;3114.548390483384;3192.9840350798213;3194.105650417887;3196.6643502432257;3197.280611862324;2835.248659105339;2917.9270518237004;2937.7943543951933;2938.8971386001494;2955.940881182375;3133.2950086230644;3226.322472280824;3230.789998650092;3234.5471633971265;3235.9838200808963;3084.4293805645916;3125.1718294651937;3150.5116706910467;3159.08386567895;3163.3040731039137;3108.875644974198;3153;3154.542591324245;3159.462891308483;3167.144870884871;17200.741988870177;17361.131943402906;17432;17496.12519374039;17525.47423577295;8684.088170742107;9017.278617710568;9021.36850420471;9028.909710902939;9042.18620324174;5533.142362933751;5697.401772813836;5860.765569377219;5881.70591470429;5901;2100.159936025602;2140.4969832089446;2144;2203.5482726041123;2214.4242496950746;31260.218494537818;31574.526382104286;31576.052718418712;31646.683533164603;31682.524762128585;7871.291583757489;7923.247291507298;7923.801904952307;7937.801554961172;7948;20520.179192832416;20553.48616284605;20554;20577;20583.794162058188;8884;9119.270458363315;9145.451272923647;9161;9161.725148245563;8255.504669719836;8296.626651800683;8321;8383.622736976851;8393.454425462325;6250.062490626442;6301.023341382093;6332.240131184288;6356.777512787123;6392;1693.3734518228089;1746.502246859639;1837.200817644329;1850.2136591948367;1876.7091100879386;983.0808194338316;999.9900001000043;1007.5163921317809;1010.5957616953131;1018.3940555369692;9942.453165075991;9956.850647240297;10124.190064794799;10142.44216568081;10196.235282353853;778.8052986753312;781.249999999995;790.1032328307298;794.2732399854069;865.057087774329;5871.060630299217;5892.528597712173;6185.567010309325;6193.938060619426;6407.711652975628;24731.5053698925;25754.356141096247;26029.479410411685;26513.33716657099;26525.204243872715;25590.488190236465;25640.74359256421;25904;26247.21258362252;26311.73688263131;12902.870971290355;12931.159474634307;12947.287899165449;12965.546205882749;13121.409536570878;9456.858147127661;9697.563609637442;9734.561944712646;9753.122219000325;9860.408375497493;21485.355439336843;21599.568008639424;21778.237761678265;22561;22811.429714257276;22649.094036239025;22883.427914302276;22915.656265155703;22962.655560166277;23384.415389615395;12493.75012499763;12695.301758403008;12770.361481926053;12782;12831.230126192459;16077.19614019271;16106.516804495885;16122.596935076717;16159.67680646404;16344;13078.411471483616;13236.933815330862;13533.593992180251;13649.658758530719;13720.382582783599;12492.875071249171;12604.243745375306;12606.49574017029;12764.42560084817;13129.934350328187;11067.335959842436;11176.497057632589;11184.94407527957;11290.15323850698;11302;8548.273396761346;8574.09971952936;8614.78463038429;8733.297007083558;8793.956030219808;8554.743357699279;8641.8703719443;8662.740117796477;8695.695650652196;8827.558622069 02:44:55 INFO - PID 3952 | |4;prototype.html;573.416149068323;591.4144996453513;601.4947444146918;608.8782243551291;610.886985907607;6380.29816720161;6400.167873255691;6421.839454013652;6470.870582588345;6502.634446766179;6337.144485494457;6378.107065010902;6384.585001974869;6392.817328794176;6462.321456247097;5209.86975325618;5211.687298762067;5228.660137091077;5230.529252367277;5236.8690782730355;4914.606831453493;5071.746412679369;5175.585953123741;5200.843974680765;5233.011455646849;5018.974905125469;5033.7734801934;5044.873878153039;5058.367704036996;5101.60743105836;5018.774155163026;5022.397312322526;5029.874253143664;5045;5064.974675126601;4625.606823420013;4653.348531205634;4658.440987081572;4667.089917466095;4685.367475390813;6316.883843912295;6345.968270158666;6350.523710721714;6354;6357.936420635827;6035.969820150915;6057.151998720183;6061.757529698807;6100.908486372708;6110.602810817282;6671.532992690513;6683.365080317362;6703.832404189886;6790.932090679129;6818.181818181801;5243.056249875021;5255.395629502618;5259.342582177228;5260.210968354739;5273.393559740641;6789.355011273921;6803.217629972518;6893.448524118108;7065.293470652945;7084.752033678797;5337.62636615441;5349.277847490617;5354.705491198021;5416.8104116355735;5420.915816836569;5448.155535891943;5453.03690671358;5475.260839786658;5483.506484416422;5488.17677348401;6035.064564992345;6039.8792024159275;6071.119687645267;6074.665893375818;6078.665673388006;6767;6824.556403833735;6836.384725374641;6852.881938717871;6883.346082122191;1875.1936667233222;1880.407671583447;1883.2372888979985;1883.6720112320781;1893.1575448925096;1334.8798608125314;1340.8468716903456;1346.582559406587;1348.4740951029107;1361.7957306404119;6393.9360606394275;6465.579737317059;6484.935150648433;6533.281339052701;6571.408573228432;6843.520953533205;6860.142482189726;6886.311368863074;6887.278180454886;6925.722971081101;6508;6538.313477084933;6545.4436372907885;6550.181227346582;6574.506911981621;3660.3894286514383;3685.4471829225295;3686.4838922550866;3688.911771027553;3697.352963231424;7234.927650723425;7266.854662906712;7268.96365518169;7282.725523033448;7284.70861165558;7051.6377013700985;7060.376113493925;7061.82345441368;7144.749139141782;7176.569405835562;7186.92813071873;7205;7205.711771529185;7217.6391180441815;7219.566825990458;6682.799516014527;6726.091977583159;6737.427318677967;6777.966110169417;6814.897776533455;6646.667666616747;6752.358525940028;6885.726140663994;6886.139232595926;6925.342092501204;7100;7171.820704482429;7215.963920180366;7256.238094999949;7268.164161121539;5266.631335806457;5266.86832829167;5302.443243459381;5374.167004114369;5389.730513474389;6574.572652777554;6805.3875151236625;6895.482993741369;6898.413634841095;6935.133108361455;6493.480521558169;6541.542092053511;6654.866902662016;6676.232233293039;6764.2559318475915;6517.446017088411;6542.967285163544;6618.536702430783;6674.199096108304;6751.358620931003;6639.6680165992475;6645.435138013323;6667;6685.298043705341;6702.832429189114;6704.06143139951;6758.648270346049;6782.491313151632;6819.5908245505425;6826.624535650587;6536.738530458919;6550.181227346582;6569.408753212234;6587.80236592903;6638.701258443285 02:44:55 INFO - PID 3952 | |5;yui.html;922.8154369126177;927.1053433436734;937.1565590968128;939.38939689202;942.7030485397104;23432.414189645267;24839.254822355313;24889.50220995579;24938.12716554923;25048.123315683955;20533.794662053377;20598.38204853857;20702.48243793906;20736.274230401938;21025.369238922856;1062.6546372428952;1075.763332066944;1085.3704851186333;1087.7008822573816;1088.8530048443438;1123.3147779854282;1130.508228920418;1130.666453396246;1130.9434528273594;1133.5069244878464;1170.7073231692077;1171.8652354979201;1172.2790483852455;1172.953081876724;1173.636172786439;1160.1878684920548;1165.6036947437826;1173.5716463490808;1174.9412529373583;1175.0012489384042;859.1408591408591;859.7592674051276;860.3590325207698;861.3367706865741;863.3783675753448;19009.809901900942;19466.70799938002;19576;19579.11893964775;19966.500837479034;22626.434339141488;23036.65445018326;23043.423914402105;23058.308250752503;23183.188588399324;18446.90776546122;18475.445736627924;18510.444686659423;18572.814271857245;18647.90676046611;3901.0637447012514;4003.1393250451;4004.4994375703036;4009.3184158693093;4032.4556184914954;166233;169654;170787;171497;172955;17121.400750973655;17254.654906901793;17304.480865574053;17455.38906138279;17456.825431745772;29559.408811823643;30384;30451.847740761154;30559.541606875915;30726.38547229043;8960.327975401871;9329.673461428818;9330.533473326306;9331.72004839856;9348.392354496937;4769.976150119297;4827.927581086286;4903.289023091703;4916.655834091651;4936.703797772145;6082.543809214327;6220.937790622126;6242.063690446378;6250.624962502265;6291.653959032205;1297.9545715899899;1322.1406086043955;1334.8598397168353;1335.6460537957448;1344.2203521957242;678.3081257117713;687.9277675844064;698.4203111417511;703.8592281543712;707.2326525719652;13618.319084045757;13768.586942391745;13974.790378144337;14040.789388159186;14291;708.4226355520235;708.9259771446264;715.4026387966106;730.0800990751691;731.4769939493287;6150.600210986271;6245.156903818017;6270.529710271657;6364.427201551883;6377.5535712500605;153391;154756;155306.22346888419;156001;162035;130847;133999;135181;136554.31722841322;137175.31412342875;121321;122192;123318.38340808239;123540.38229808974;125151.3742431282;11738.70653233676;11814.527418903148;12187.87812121903;12222.515264459518;12304.446299916704;56657.433425665215;57998.7100064497;58050.41949580619;58952.70523647354;59868.10197846949;74463.25536744563;74708.62645686737;74733;75341;77793;16982.745258821375;17430.128493575015;17823.643527129127;19648.803511964696;19807.801921980597;21549.569008619426;21685.56628867382;21813.781862181175;21837.126514939224;21865.125394984658;20119.497012574804;20131;20135.59728805445;20205.191792328143;20213.191472340943;21206.681899771207;21314.147434102462;21331.8933405332;21397.572048559254;21463.678044829026;19360.322388716326;19397.127129278935;19418.90290548538;19487.415377538695;19612.117454714287;14846.109233446028;14850.554483365517;14991.325390357242;15049.699006020037;15059.849401505844;15981.760273595673;16005.519834404986;16114.27485763167;16272.349106035626;16313.265903034155 02:44:55 INFO - PID 3952 | __end_tp_report 02:44:55 INFO - PID 3952 | __start_cc_report 02:44:55 INFO - PID 3952 | _x_x_mozilla_cycle_collect,252 02:44:55 INFO - PID 3952 | __end_cc_report 02:44:55 INFO - PID 3952 | __startTimestamp1506851095137__endTimestamp 02:44:55 INFO - PID 3952 | 02:44:55 INFO - PID 3952 | ------- Summary: start ------- 02:44:55 INFO - PID 3952 | Number of tests: 6 02:44:55 INFO - PID 3952 | 02:44:55 INFO - PID 3952 | [#0] dojo.html Cycles:165 Average:6521.47 Median:6786.83 stddev:777.93 (11.5%) stddev-sans-first:754.52 02:44:55 INFO - PID 3952 | Values: 3988.8 4001.6 4016.8 4017.0 4037.2 6424.2 6511.3 6533.6 6551.5 6570.3 6498.5 6500.2 6593.7 6605.6 6609.0 5720.1 5740.9 5827.2 5834.8 5860.2 5731.9 5793.1 5811.3 5853.5 5882.5 5860.1 5864.9 5869.1 5877.9 5883.8 5749.9 5817.5 5870.4 5872.8 5885.1 5800.2 5810.3 5868.3 5898.1 5922.8 6264.4 6408.4 6421.1 6428.1 6502.5 7040.2 7044.6 7052.4 7056.8 7063.0 7075.7 7138.3 7158.6 7161.1 7167.4 6527.9 6554.8 6598.4 6606.5 6625.6 7208.9 7270.8 7316.8 7336.8 7342.5 6992.5 7004.2 7054.0 7058.3 7084.8 6945.3 7054.4 7091.3 7118.0 7156.1 6968.4 6975.6 6983.7 6983.9 7002.0 6956.3 6961.6 7043.8 7050.0 7062.8 5101.9 5116.5 5169.2 5175.2 5188.7 4655.8 4672.7 4710.1 4748.4 4809.9 6717.0 6792.2 6798.5 6799.2 6801.3 6989.1 7095.6 7167.1 7179.9 7190.7 6689.8 6707.4 6752.9 6786.8 6793.4 5841.0 5879.0 5928.9 5941.1 5943.2 7379.5 7381.3 7479.4 7484.5 7495.8 6968.3 7010.5 7055.4 7091.3 7126.9 7266.2 7348.9 7436.3 7438.3 7462.4 6979.2 6979.8 7058.4 7074.5 7076.7 6989.6 7042.3 7081.6 7103.2 7154.2 6764.8 6781.1 6785.8 6802.6 6826.3 6642.8 6736.6 6803.1 6811.6 6832.8 6668.1 6675.2 6774.9 6775.6 6779.2 6787.3 6866.5 6898.7 6942.9 7004.1 6645.0 6714.8 6715.4 6792.8 6822.7 02:44:55 INFO - PID 3952 | 02:44:55 INFO - PID 3952 | [#1] ext.html Cycles:180 Average:45991.94 Median:21563.82 stddev:69328.23 (321.5%) stddev-sans-first:69440.34 02:44:55 INFO - PID 3952 | Values: 983.0 987.9 1005.9 1014.7 1045.6 46028.3 46805.8 46840.3 46902.5 46957.8 42370.6 42435.8 42567.4 42631.6 42758.1 8147.6 8201.7 8269.7 8270.8 8301.8 8067.6 8104.4 8125.4 8133.7 8159.8 8790.9 8987.8 9024.4 9028.7 9034.4 8909.4 8927.6 8950.3 8972.1 8975.8 8748.1 8782.0 8786.3 8875.4 8883.8 42583.8 42680.6 42710.8 42825.8 42858.4 3895.7 3902.4 3930.4 3930.8 3935.9 34513.0 34555.4 34800.0 34836.0 34991.8 5578.1 5710.7 5727.8 5752.9 5893.5 229021.0 229908.0 231159.0 231262.0 231379.0 54901.2 54941.0 55059.2 55119.0 55185.7 65974.0 66192.7 66348.7 66736.7 67443.7 18618.7 18683.3 18691.4 18747.4 18752.9 15010.4 15127.6 15266.5 15283.8 15287.0 16617.4 16666.8 16743.4 16769.7 16791.2 6506.0 6539.6 6557.3 6564.3 6584.6 1110.3 1147.6 1166.3 1167.7 1171.2 27201.6 27666.3 27695.7 27942.2 28002.7 1234.3 1242.7 1257.6 1279.1 1292.0 14511.3 14594.2 14607.8 14836.6 14923.3 292226.0 300277.0 300484.0 303197.0 306519.0 66275.3 66346.0 66529.0 66603.3 67034.3 1524.7 1540.6 1540.6 1548.3 1558.5 31689.4 31747.8 32088.5 32531.2 32643.7 44091.3 44144.1 44157.3 44742.0 44773.6 262226.0 262371.0 264613.0 264680.0 264721.0 39197.2 40665.4 41243.6 41313.2 41408.4 49595.3 50878.7 51289.5 51472.7 51500.7 36982.3 37670.6 37785.4 37807.0 38481.2 36479.3 36590.6 36993.3 37091.4 37095.6 43744.8 43752.0 43912.6 44197.1 44296.1 35439.0 35515.8 35545.9 35744.5 36260.0 25859.7 25977.6 26199.7 26618.6 26621.7 02:44:55 INFO - PID 3952 | 02:44:55 INFO - PID 3952 | [#2] jquery.html Cycles:180 Average:41537.80 Median:12495.40 stddev:102762.99 (822.4%) stddev-sans-first:103005.37 02:44:55 INFO - PID 3952 | Values: 633.0 645.7 648.5 651.0 652.7 23936.2 24046.0 24548.9 25717.5 25942.4 27200.7 28247.3 28282.9 28303.0 28317.0 6929.2 7004.4 7185.1 7194.5 7220.8 7011.4 7101.6 7117.5 7130.8 7133.6 6998.5 7059.6 7078.3 7086.4 7090.3 6996.5 7000.8 7005.4 7040.2 7050.3 6561.5 6573.1 6582.4 6615.9 6633.4 30520.2 32463.4 32700.5 32825.3 32829.7 2302.1 2331.5 2336.0 2342.4 2353.6 21372.0 21643.4 22209.7 22211.3 22224.8 2624.4 2641.5 2702.3 2706.6 2710.6 148051.0 153414.0 166668.0 175116.0 175233.0 16912.9 17236.7 17656.2 17701.9 17905.9 28190.0 28209.2 28228.6 28277.7 28406.9 11690.7 11777.2 11818.4 11826.9 11917.9 10743.4 10843.7 10890.5 10992.6 11146.7 10363.6 10467.0 10477.2 10620.0 10676.5 3015.8 3042.8 3048.4 3121.4 3133.7 1062.2 1062.9 1080.5 1080.8 1085.9 22688.7 23166.8 23357.3 23555.6 23678.4 1148.1 1161.8 1169.5 1170.9 1186.3 12460.4 12583.4 12637.4 12895.7 12914.6 602070.0 604585.0 608522.0 619419.0 620664.0 136565.3 141109.0 141458.0 142706.0 143111.0 1278.9 1288.0 1300.8 1317.7 1326.2 20536.7 20695.5 20730.6 20753.2 21101.2 62432.7 63123.4 64124.4 64567.4 64603.0 83558.6 84089.0 84431.6 85036.0 85072.0 16917.6 17155.7 17211.9 17225.2 17271.1 25959.6 26161.3 26308.3 26467.7 26635.3 19958.9 19968.4 20044.1 20083.3 20308.8 18836.0 19318.2 19493.2 19629.0 19881.2 20933.0 21822.9 22157.8 22298.2 22306.0 16911.9 16994.2 17148.1 17159.2 17305.1 18687.3 19548.9 19599.6 19641.9 19684.4 02:44:55 INFO - PID 3952 | 02:44:55 INFO - PID 3952 | [#3] mootools.html Cycles:180 Average:10958.71 Median:14680.23 stddev:8000.99 (54.5%) stddev-sans-first:7991.03 02:44:55 INFO - PID 3952 | Values: 1373.7 1516.2 1529.3 1540.2 1543.2 17991.0 18535.1 18541.8 18544.5 18546.2 17953.2 18000.5 18012.0 18047.0 18089.7 3114.5 3193.0 3194.1 3196.7 3197.3 2835.2 2917.9 2937.8 2938.9 2955.9 3133.3 3226.3 3230.8 3234.5 3236.0 3084.4 3125.2 3150.5 3159.1 3163.3 3108.9 3153.0 3154.5 3159.5 3167.1 17200.7 17361.1 17432.0 17496.1 17525.5 8684.1 9017.3 9021.4 9028.9 9042.2 5533.1 5697.4 5860.8 5881.7 5901.0 2100.2 2140.5 2144.0 2203.5 2214.4 31260.2 31574.5 31576.1 31646.7 31682.5 7871.3 7923.2 7923.8 7937.8 7948.0 20520.2 20553.5 20554.0 20577.0 20583.8 8884.0 9119.3 9145.5 9161.0 9161.7 8255.5 8296.6 8321.0 8383.6 8393.5 6250.1 6301.0 6332.2 6356.8 6392.0 1693.4 1746.5 1837.2 1850.2 1876.7 983.1 1000.0 1007.5 1010.6 1018.4 9942.5 9956.9 10124.2 10142.4 10196.2 778.8 781.2 790.1 794.3 865.1 5871.1 5892.5 6185.6 6193.9 6407.7 24731.5 25754.4 26029.5 26513.3 26525.2 25590.5 25640.7 25904.0 26247.2 26311.7 12902.9 12931.2 12947.3 12965.5 13121.4 9456.9 9697.6 9734.6 9753.1 9860.4 21485.4 21599.6 21778.2 22561.0 22811.4 22649.1 22883.4 22915.7 22962.7 23384.4 12493.8 12695.3 12770.4 12782.0 12831.2 16077.2 16106.5 16122.6 16159.7 16344.0 13078.4 13236.9 13533.6 13649.7 13720.4 12492.9 12604.2 12606.5 12764.4 13129.9 11067.3 11176.5 11184.9 11290.2 11302.0 8548.3 8574.1 8614.8 8733.3 8794.0 8554.7 8641.9 8662.7 8695.7 8827.6 02:44:55 INFO - PID 3952 | 02:44:55 INFO - PID 3952 | [#4] prototype.html Cycles:180 Average:5782.48 Median:6477.90 stddev:1602.31 (24.7%) stddev-sans-first:1558.37 02:44:55 INFO - PID 3952 | Values: 573.4 591.4 601.5 608.9 610.9 6380.3 6400.2 6421.8 6470.9 6502.6 6337.1 6378.1 6384.6 6392.8 6462.3 5209.9 5211.7 5228.7 5230.5 5236.9 4914.6 5071.7 5175.6 5200.8 5233.0 5019.0 5033.8 5044.9 5058.4 5101.6 5018.8 5022.4 5029.9 5045.0 5065.0 4625.6 4653.3 4658.4 4667.1 4685.4 6316.9 6346.0 6350.5 6354.0 6357.9 6036.0 6057.2 6061.8 6100.9 6110.6 6671.5 6683.4 6703.8 6790.9 6818.2 5243.1 5255.4 5259.3 5260.2 5273.4 6789.4 6803.2 6893.4 7065.3 7084.8 5337.6 5349.3 5354.7 5416.8 5420.9 5448.2 5453.0 5475.3 5483.5 5488.2 6035.1 6039.9 6071.1 6074.7 6078.7 6767.0 6824.6 6836.4 6852.9 6883.3 1875.2 1880.4 1883.2 1883.7 1893.2 1334.9 1340.8 1346.6 1348.5 1361.8 6393.9 6465.6 6484.9 6533.3 6571.4 6843.5 6860.1 6886.3 6887.3 6925.7 6508.0 6538.3 6545.4 6550.2 6574.5 3660.4 3685.4 3686.5 3688.9 3697.4 7234.9 7266.9 7269.0 7282.7 7284.7 7051.6 7060.4 7061.8 7144.7 7176.6 7186.9 7205.0 7205.7 7217.6 7219.6 6682.8 6726.1 6737.4 6778.0 6814.9 6646.7 6752.4 6885.7 6886.1 6925.3 7100.0 7171.8 7216.0 7256.2 7268.2 5266.6 5266.9 5302.4 5374.2 5389.7 6574.6 6805.4 6895.5 6898.4 6935.1 6493.5 6541.5 6654.9 6676.2 6764.3 6517.4 6543.0 6618.5 6674.2 6751.4 6639.7 6645.4 6667.0 6685.3 6702.8 6704.1 6758.6 6782.5 6819.6 6826.6 6536.7 6550.2 6569.4 6587.8 6638.7 02:44:55 INFO - PID 3952 | 02:44:55 INFO - PID 3952 | [#5] yui.html Cycles:180 Average:29721.68 Median:20125.25 stddev:44415.52 (220.7%) stddev-sans-first:44487.48 02:44:55 INFO - PID 3952 | Values: 922.8 927.1 937.2 939.4 942.7 23432.4 24839.3 24889.5 24938.1 25048.1 20533.8 20598.4 20702.5 20736.3 21025.4 1062.7 1075.8 1085.4 1087.7 1088.9 1123.3 1130.5 1130.7 1130.9 1133.5 1170.7 1171.9 1172.3 1173.0 1173.6 1160.2 1165.6 1173.6 1174.9 1175.0 859.1 859.8 860.4 861.3 863.4 19009.8 19466.7 19576.0 19579.1 19966.5 22626.4 23036.7 23043.4 23058.3 23183.2 18446.9 18475.4 18510.4 18572.8 18647.9 3901.1 4003.1 4004.5 4009.3 4032.5 166233.0 169654.0 170787.0 171497.0 172955.0 17121.4 17254.7 17304.5 17455.4 17456.8 29559.4 30384.0 30451.8 30559.5 30726.4 8960.3 9329.7 9330.5 9331.7 9348.4 4770.0 4827.9 4903.3 4916.7 4936.7 6082.5 6220.9 6242.1 6250.6 6291.7 1298.0 1322.1 1334.9 1335.6 1344.2 678.3 687.9 698.4 703.9 707.2 13618.3 13768.6 13974.8 14040.8 14291.0 708.4 708.9 715.4 730.1 731.5 6150.6 6245.2 6270.5 6364.4 6377.6 153391.0 154756.0 155306.2 156001.0 162035.0 130847.0 133999.0 135181.0 136554.3 137175.3 121321.0 122192.0 123318.4 123540.4 125151.4 11738.7 11814.5 12187.9 12222.5 12304.4 56657.4 57998.7 58050.4 58952.7 59868.1 74463.3 74708.6 74733.0 75341.0 77793.0 16982.7 17430.1 17823.6 19648.8 19807.8 21549.6 21685.6 21813.8 21837.1 21865.1 20119.5 20131.0 20135.6 20205.2 20213.2 21206.7 21314.1 21331.9 21397.6 21463.7 19360.3 19397.1 19418.9 19487.4 19612.1 14846.1 14850.6 14991.3 15049.7 15059.8 15981.8 16005.5 16114.3 16272.3 16313.3 02:44:55 INFO - PID 3952 | -------- Summary: end -------- 02:44:55 INFO - PID 3952 | 02:44:55 INFO - TEST-INFO | 3952: exit 0 02:44:55 INFO - TEST-OK | dromaeo_css | took 1110057ms 02:44:55 INFO - TEST-START | kraken 02:44:55 INFO - Initialising browser for kraken test... 02:44:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44330/getInfo.html -profile /tmp/tmpzdOJsP/profile 02:44:55 INFO - TEST-INFO | started process 4334 (/builds/slave/test/build/application/firefox/firefox http://localhost:44330/getInfo.html) 02:45:00 INFO - TEST-INFO | 4334: exit 0 02:45:01 INFO - Browser initialized. 02:45:01 INFO - Running cycle 1/1 for kraken test... 02:45:01 INFO - Using env: {'DISPLAY': ':0', 02:45:01 INFO - 'HOME': '/home/cltbld', 02:45:01 INFO - 'JSGC_DISABLE_POISONING': '1', 02:45:01 INFO - 'LANG': 'en_US.UTF-8', 02:45:01 INFO - 'LANGUAGE': 'en_US:en', 02:45:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:45:01 INFO - 'LOGNAME': 'cltbld', 02:45:01 INFO - 'MAIL': '/var/mail/cltbld', 02:45:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:45:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:45:01 INFO - 'MOZ_CRASHREPORTER': '1', 02:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:45:01 INFO - 'MOZ_NO_REMOTE': '1', 02:45:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:45:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:01 INFO - 'NO_EM_RESTART': '1', 02:45:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:01 INFO - 'PWD': '/builds/slave/test', 02:45:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:45:01 INFO - 'RUST_BACKTRACE': 'full', 02:45:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:45:01 INFO - 'SHELL': '/bin/bash', 02:45:01 INFO - 'SHLVL': '1', 02:45:01 INFO - 'TERM': 'linux', 02:45:01 INFO - 'TMOUT': '86400', 02:45:01 INFO - 'USER': 'cltbld', 02:45:01 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789', 02:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:01 INFO - '_': '/tools/buildbot/bin/python'} 02:45:01 INFO - TEST-INFO | started process 4527 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzdOJsP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:45:01 INFO - PID 4527 | 02:45:01 INFO - PID 4527 | (/builds/slave/test/build/application/firefox/firefox:4576): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:45:01 INFO - PID 4527 | 02:45:12 INFO - PID 4527 | RSS: Main: 141524992 02:45:12 INFO - PID 4527 | 02:45:14 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:44330/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44330/tests/kraken/driver.html?testName=audio-beat-detection) 02:45:15 INFO - PID 4527 | RSS: Main: 147632128 02:45:15 INFO - PID 4527 | 02:45:18 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:44330/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44330/tests/kraken/driver.html?testName=audio-dft) 02:45:18 INFO - PID 4527 | RSS: Main: 148008960 02:45:18 INFO - PID 4527 | 02:45:21 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:44330/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44330/tests/kraken/driver.html?testName=audio-fft) 02:45:22 INFO - PID 4527 | RSS: Main: 149483520 02:45:22 INFO - PID 4527 | 02:45:24 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:44330/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44330/tests/kraken/driver.html?testName=audio-oscillator) 02:45:24 INFO - PID 4527 | RSS: Main: 149516288 02:45:24 INFO - PID 4527 | 02:45:26 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:44330/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44330/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:45:27 INFO - PID 4527 | RSS: Main: 149446656 02:45:27 INFO - PID 4527 | 02:45:30 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:44330/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44330/tests/kraken/driver.html?testName=imaging-darkroom) 02:45:31 INFO - PID 4527 | RSS: Main: 149274624 02:45:31 INFO - PID 4527 | 02:45:34 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:44330/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44330/tests/kraken/driver.html?testName=imaging-desaturate) 02:45:35 INFO - PID 4527 | RSS: Main: 149823488 02:45:35 INFO - PID 4527 | 02:45:38 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:44330/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44330/tests/kraken/driver.html?testName=json-parse-financial) 02:45:38 INFO - PID 4527 | RSS: Main: 151580672 02:45:38 INFO - PID 4527 | 02:45:40 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:44330/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44330/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:45:40 INFO - PID 4527 | RSS: Main: 151568384 02:45:40 INFO - PID 4527 | 02:45:42 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:44330/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44330/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:45:42 INFO - PID 4527 | RSS: Main: 151654400 02:45:42 INFO - PID 4527 | 02:45:44 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:44330/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44330/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:45:45 INFO - PID 4527 | RSS: Main: 148733952 02:45:45 INFO - PID 4527 | 02:45:48 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:44330/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44330/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:45:49 INFO - PID 4527 | RSS: Main: 148561920 02:45:49 INFO - PID 4527 | 02:45:51 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:44330/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44330/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:45:52 INFO - PID 4527 | RSS: Main: 149098496 02:45:52 INFO - PID 4527 | 02:45:53 INFO - PID 4527 | Cycle 1(1): loaded http://localhost:44330/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44330/tests/kraken/driver.html?testName=ai-astar) 02:45:54 INFO - PID 4527 | RSS: Main: 150589440 02:45:54 INFO - PID 4527 | 02:45:54 INFO - PID 4527 | __start_tp_report 02:45:54 INFO - PID 4527 | _x_x_mozilla_page_load 02:45:54 INFO - PID 4527 | _x_x_mozilla_page_load_details 02:45:54 INFO - PID 4527 | |i|pagename|runs| 02:45:54 INFO - PID 4527 | |0;ai-astar;109;105;105;104;106;95;104;106;105;105 02:45:54 INFO - PID 4527 | |1;audio-beat-detection;109;116;110;113;113;111;112;111;111;110 02:45:54 INFO - PID 4527 | |2;audio-dft;162;163;161;161;164;164;164;164;165;163 02:45:54 INFO - PID 4527 | |3;audio-fft;73;74;72;73;73;73;73;85;75;74 02:45:54 INFO - PID 4527 | |4;audio-oscillator;78;78;78;78;77;77;83;77;77;77 02:45:54 INFO - PID 4527 | |5;imaging-gaussian-blur;113;113;115;113;115;114;114;114;114;113 02:45:54 INFO - PID 4527 | |6;imaging-darkroom;154;149;149;148;150;147;148;149;148;149 02:45:54 INFO - PID 4527 | |7;imaging-desaturate;93;94;94;100;94;95;94;94;95;94 02:45:54 INFO - PID 4527 | |8;json-parse-financial;43;43;43;43;43;42;44;44;43;43 02:45:54 INFO - PID 4527 | |9;json-stringify-tinderbox;38;38;39;39;38;38;39;39;38;39 02:45:54 INFO - PID 4527 | |10;stanford-crypto-aes;77;76;71;72;74;69;76;72;70;69 02:45:54 INFO - PID 4527 | |11;stanford-crypto-ccm;105;102;105;114;106;110;109;109;101;105 02:45:54 INFO - PID 4527 | |12;stanford-crypto-pbkdf2;149;147;152;156;148;151;148;148;152;156 02:45:54 INFO - PID 4527 | |13;stanford-crypto-sha256-iterative;60;58;61;61;59;59;58;63;64;59 02:45:54 INFO - PID 4527 | __end_tp_report 02:45:54 INFO - PID 4527 | __start_cc_report 02:45:54 INFO - PID 4527 | _x_x_mozilla_cycle_collect,678 02:45:54 INFO - PID 4527 | __end_cc_report 02:45:54 INFO - PID 4527 | __startTimestamp1506851154214__endTimestamp 02:45:54 INFO - PID 4527 | 02:45:54 INFO - PID 4527 | ------- Summary: start ------- 02:45:54 INFO - PID 4527 | Number of tests: 14 02:45:54 INFO - PID 4527 | 02:45:54 INFO - PID 4527 | [#0] ai-astar Cycles:10 Average:104.40 Median:105.50 stddev:3.60 (3.4%) stddev-sans-first:3.41 02:45:54 INFO - PID 4527 | Values: 109.0 105.0 105.0 104.0 106.0 95.0 104.0 106.0 105.0 105.0 02:45:54 INFO - PID 4527 | 02:45:54 INFO - PID 4527 | [#1] audio-beat-detection Cycles:10 Average:111.60 Median:111.50 stddev:2.01 (1.8%) stddev-sans-first:1.90 02:45:54 INFO - PID 4527 | Values: 109.0 116.0 110.0 113.0 113.0 111.0 112.0 111.0 111.0 110.0 02:45:54 INFO - PID 4527 | 02:45:54 INFO - PID 4527 | [#2] audio-dft Cycles:10 Average:163.10 Median:164.00 stddev:1.37 (0.8%) stddev-sans-first:1.39 02:45:54 INFO - PID 4527 | Values: 162.0 163.0 161.0 161.0 164.0 164.0 164.0 164.0 165.0 163.0 02:45:54 INFO - PID 4527 | 02:45:54 INFO - PID 4527 | [#3] audio-fft Cycles:10 Average:74.50 Median:73.50 stddev:3.78 (5.1%) stddev-sans-first:3.97 02:45:54 INFO - PID 4527 | Values: 73.0 74.0 72.0 73.0 73.0 73.0 73.0 85.0 75.0 74.0 02:45:54 INFO - PID 4527 | 02:45:54 INFO - PID 4527 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:78.00 stddev:1.83 (2.3%) stddev-sans-first:1.94 02:45:54 INFO - PID 4527 | Values: 78.0 78.0 78.0 78.0 77.0 77.0 83.0 77.0 77.0 77.0 02:45:54 INFO - PID 4527 | 02:45:54 INFO - PID 4527 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.79 (0.7%) stddev-sans-first:0.78 02:45:54 INFO - PID 4527 | Values: 113.0 113.0 115.0 113.0 115.0 114.0 114.0 114.0 114.0 113.0 02:45:54 INFO - PID 4527 | 02:45:54 INFO - PID 4527 | [#6] imaging-darkroom Cycles:10 Average:149.10 Median:149.00 stddev:1.91 (1.3%) stddev-sans-first:0.88 02:45:54 INFO - PID 4527 | Values: 154.0 149.0 149.0 148.0 150.0 147.0 148.0 149.0 148.0 149.0 02:45:54 INFO - PID 4527 | 02:45:54 INFO - PID 4527 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.00 stddev:1.95 (2.1%) stddev-sans-first:1.96 02:45:54 INFO - PID 4527 | Values: 93.0 94.0 94.0 100.0 94.0 95.0 94.0 94.0 95.0 94.0 02:45:54 INFO - PID 4527 | 02:45:54 INFO - PID 4527 | [#8] json-parse-financial Cycles:10 Average:43.10 Median:43.00 stddev:0.57 (1.3%) stddev-sans-first:0.60 02:45:54 INFO - PID 4527 | Values: 43.0 43.0 43.0 43.0 43.0 42.0 44.0 44.0 43.0 43.0 02:45:54 INFO - PID 4527 | 02:45:54 INFO - PID 4527 | [#9] json-stringify-tinderbox Cycles:10 Average:38.50 Median:39.00 stddev:0.53 (1.4%) stddev-sans-first:0.53 02:45:54 INFO - PID 4527 | Values: 38.0 38.0 39.0 39.0 38.0 38.0 39.0 39.0 38.0 39.0 02:45:54 INFO - PID 4527 | 02:45:54 INFO - PID 4527 | [#10] stanford-crypto-aes Cycles:10 Average:72.60 Median:73.00 stddev:2.99 (4.1%) stddev-sans-first:2.71 02:45:54 INFO - PID 4527 | Values: 77.0 76.0 71.0 72.0 74.0 69.0 76.0 72.0 70.0 69.0 02:45:54 INFO - PID 4527 | 02:45:54 INFO - PID 4527 | [#11] stanford-crypto-ccm Cycles:10 Average:106.60 Median:107.50 stddev:3.92 (3.6%) stddev-sans-first:4.12 02:45:54 INFO - PID 4527 | Values: 105.0 102.0 105.0 114.0 106.0 110.0 109.0 109.0 101.0 105.0 02:45:54 INFO - PID 4527 | 02:45:54 INFO - PID 4527 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.70 Median:151.50 stddev:3.30 (2.2%) stddev-sans-first:3.44 02:45:54 INFO - PID 4527 | Values: 149.0 147.0 152.0 156.0 148.0 151.0 148.0 148.0 152.0 156.0 02:45:54 INFO - PID 4527 | 02:45:54 INFO - PID 4527 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.20 Median:60.50 stddev:2.04 (3.4%) stddev-sans-first:2.17 02:45:54 INFO - PID 4527 | Values: 60.0 58.0 61.0 61.0 59.0 59.0 58.0 63.0 64.0 59.0 02:45:54 INFO - PID 4527 | -------- Summary: end -------- 02:45:54 INFO - PID 4527 | 02:45:54 INFO - PID 4527 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 02:45:54 INFO - TEST-INFO | 4527: exit 0 02:45:54 INFO - TEST-OK | kraken | took 59108ms 02:45:54 INFO - SUITE-END | took 1169s 02:45:54 INFO - Completed test suite (00:19:29) 02:45:54 INFO - javascript benchmark 02:45:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9528.371062705173, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3988.780617066062, 4001.579834117416, 4016.839326426944, 4016.9957510622344, 4037.2329257441083, 6424.229092508894, 6511.348865113487, 6533.575317604358, 6551.541392102555, 6570.342965703439, 6498.545101842873, 6500.154979852624, 6593.736250549972, 6605.603663780176, 6608.96695516523, 5720.141978703207, 5740.913886291709, 5827.213326200972, 5834.766609335621, 5860.150278209657, 5731.856703582423, 5793.073108302672, 5811.27359080115, 5853.502452291539, 5882.499987501046, 5860.062390017598, 5864.882702345951, 5869.06095024794, 5877.8824423511505, 5883.794067207648, 5749.856253593652, 5817.534597232254, 5870.3836097209605, 5872.794452194195, 5885.087811389241, 5800.158976948361, 5810.302763668386, 5868.295804503444, 5898.14476900847, 5922.7927022554, 6264.436200741955, 6408.391202835724, 6421.101045853584, 6428.100065990719, 6502.479801615859, 7040.225575186725, 7044.647767611599, 7052.4005459536, 7056.788296351117, 7063.04648872406, 7075.681594328216, 7138.321859423347, 7158.570485770871, 7161.069061022086, 7167.4266058715175, 6527.902081468782, 6554.770583029619, 6598.373154550262, 6606.537542372036, 6625.635590042546, 7208.891866622005, 7270.818229544304, 7316.817079573053, 7336.816579585553, 7342.5227360221415, 6992.5105242633545, 7004.15950085993, 7054.047703559953, 7058.258882817333, 7084.822879428056, 6945.305469453116, 7054.400375967997, 7091.290870912971, 7117.964410177916, 7156.069710937545, 6968.407685346701, 6975.581465112109, 6983.685734141975, 6983.93016069843, 7002.0, 6956.269591692901, 6961.617111058839, 7043.788686339418, 7049.9647501762165, 7062.823429414306, 5101.903090835488, 5116.488351164854, 5169.198774190007, 5175.223716442564, 5188.714620695822, 4655.790489427984, 4672.719636821802, 4710.105080034782, 4748.358971538798, 4809.927851082237, 6716.966415167893, 6792.150981127359, 6798.456123510107, 6799.1840979081835, 6801.319868013159, 6989.091418115612, 7095.645217739196, 7167.068281123525, 7179.89230161548, 7190.676419561132, 6689.76585819494, 6707.429868461236, 6752.864942701119, 6786.8303292417095, 6793.354631310018, 5840.970795145997, 5879.029960056629, 5928.911066334009, 5941.1385349125, 5943.167956486095, 7379.483436159418, 7381.261873812683, 7479.364254038359, 7484.476086673881, 7495.812604684927, 6968.268331825187, 7010.544314619533, 7055.3650171484815, 7091.255418181223, 7126.893096603451, 7266.164391095092, 7348.926510734825, 7436.330730234088, 7438.256174382518, 7462.403007759258, 6979.162500499972, 6979.755708550177, 7058.400035996998, 7074.469414793808, 7076.716931322895, 6989.615571143636, 7042.260562640849, 7081.575105493687, 7103.2186459488385, 7154.2488038757265, 6764.830879228059, 6781.050652908498, 6785.830354241183, 6802.557833740791, 6826.317368263134, 6642.767503137367, 6736.629485378156, 6803.115594972622, 6811.625360605214, 6832.795016149524, 6668.133142691419, 6675.23234828001, 6774.898376524455, 6775.593464392152, 6779.15260592426, 6787.287334829969, 6866.519343645897, 6898.689558969758, 6942.8611427772175, 7004.089468369283, 6645.036469711961, 6714.764983225564, 6715.395614394728, 6792.830179245559, 6822.658867056528], "value": 6468.271478777797, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [982.9705108846734, 987.9209663226943, 1005.919526437885, 1014.6753039027509, 1045.5765415006924, 46028.309575356354, 46805.7659711701, 46840.29739553909, 46902.53097469024, 46957.76521117394, 42370.57629423705, 42435.787821060854, 42567.361489577685, 42631.573684263145, 42758.14483710324, 8147.592620368988, 8201.7129400471, 8269.710560130397, 8270.793230169234, 8301.83396332073, 8067.556284404384, 8104.3921705872, 8125.390595705317, 8133.715319963802, 8159.755207343789, 8790.868136977919, 8987.775305617379, 9024.413413128159, 9028.68399606014, 9034.412763170372, 8909.420887642314, 8927.553622318923, 8950.283977281866, 8972.147645973622, 8975.775605609846, 8748.081451447633, 8782.0, 8786.253168480687, 8875.378723489359, 8883.822323553557, 42583.78708106471, 42680.57319426797, 42710.78644606788, 42825.785871070446, 42858.35712464315, 3895.7078219133678, 3902.356111241642, 3930.4300876372768, 3930.803459827026, 3935.9212815743526, 34513.0, 34555.445004974834, 34800.0, 34836.0, 34991.825040874886, 5578.135389014709, 5710.65736055838, 5727.770889164429, 5752.856178595527, 5893.469587737081, 229021.0, 229908.0, 231159.0, 231262.0, 231379.0, 54901.1764823528, 54941.0, 55059.17411238835, 55119.0, 55185.72407138019, 65974.0, 66192.69747466399, 66348.6682566584, 66736.66631666811, 67443.66278168578, 18618.720719189223, 18683.252669893325, 18691.439256822316, 18747.437576872715, 18752.906235469007, 15010.399584016735, 15127.621809454851, 15266.542003739905, 15283.770743438858, 15287.0, 16617.41839035628, 16666.83333166677, 16743.413980510624, 16769.664606707796, 16791.2443940023, 6505.967470162619, 6539.6403197824575, 6557.311482294386, 6564.310747371553, 6584.604923704593, 1110.3393480878865, 1147.6499667601295, 1166.3293606176483, 1167.7255844876447, 1171.1860257121218, 27201.591976120373, 27666.308342291602, 27695.723042769314, 27942.161735147976, 28002.719972800012, 1234.327291626048, 1242.6520574239228, 1257.6227131860587, 1279.0662816144268, 1291.9547815826402, 14511.274436278143, 14594.197319147548, 14607.78088328676, 14836.554903352917, 14923.328450219766, 292226.0, 300277.0, 300484.0, 303197.0, 306519.0, 66275.33724662692, 66346.0, 66529.0, 66603.33396665972, 67034.32965670379, 1524.6950609877847, 1540.6302487402943, 1540.6302487403166, 1548.3110016042754, 1558.5480210738986, 31689.366212675155, 31747.84126079355, 32088.5186722204, 32531.18672033218, 32643.673563265012, 44091.338629919934, 44144.11711765811, 44157.33763993607, 44742.0, 44773.55226447694, 262226.0, 262371.0, 264613.0, 264680.0, 264721.0, 39197.216055679295, 40665.39001914914, 41243.58756412518, 41313.1737365245, 41408.37887431631, 49595.25607115968, 50878.74560627173, 51289.48710512847, 51472.74263628658, 51500.742496287276, 36982.26035479329, 37670.62329376671, 37785.433218501195, 37807.0, 38481.23037539177, 36479.27041459103, 36590.63409365872, 36993.26013479661, 37091.44362834613, 37095.62904370922, 43744.78127609342, 43752.0, 43912.560874390845, 44197.11605767931, 44296.11407771762, 35439.0, 35515.82242088773, 35545.93362199138, 35744.463833042006, 36260.0, 25859.741402585732, 25977.610335845355, 26199.738002620492, 26618.600720989587, 26621.7337826627], "value": 19995.345335888574, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [633.0027224889128, 645.670707938951, 648.4520580109805, 651.0139269417705, 652.654093330535, 23936.1622343218, 24046.038158473682, 24548.87725561372, 25717.48565028703, 25942.351441213978, 27200.727992720065, 28247.29381765457, 28282.858585707094, 28303.0, 28317.00890468834, 6929.203141638701, 7004.4396448284015, 7185.137783465965, 7194.4963852530345, 7220.8194795130275, 7011.368976792088, 7101.609411482366, 7117.501774875735, 7130.821729456753, 7133.607651579161, 6998.4751143664425, 7059.576425414466, 7078.256783037784, 7086.433085353186, 7090.29097090292, 6996.510244282902, 7000.754973575926, 7005.369516743492, 7040.190378106532, 7050.259722729116, 6561.475081993477, 6573.079768832367, 6582.407583317525, 6615.867682646368, 6633.436157926582, 30520.236994075105, 32463.350732985207, 32700.509492357633, 32825.34349313001, 32829.671703282904, 2302.1482051640833, 2331.5336932613377, 2335.976640233593, 2342.390978345625, 2353.623420252751, 21372.0, 21643.35069947904, 22209.666854997187, 22211.333659990225, 22224.77775222243, 2624.4357463145325, 2641.511320405731, 2702.2974026753186, 2706.6210730497746, 2710.6476158099517, 148051.0, 153414.0, 166668.0, 175116.0, 175233.0, 16912.915435422743, 17236.741448878278, 17656.20547075385, 17701.911490442464, 17905.910470447565, 28190.0, 28209.15372538786, 28228.576571351445, 28277.71722282792, 28406.857965710453, 11690.70773230676, 11777.175597708077, 11818.409079545989, 11826.919712699106, 11917.880821191851, 10743.355398676105, 10843.728906777236, 10890.509927053301, 10992.615258465916, 11146.721331966766, 10363.585456581803, 10467.0, 10477.161827053817, 10620.0, 10676.51955661997, 3015.8492075396143, 3042.7717921156, 3048.3750831079574, 3121.438141134572, 3133.7022982816598, 1062.166199533378, 1062.8830828608848, 1080.4597701149426, 1080.7514271717548, 1085.913126949842, 22688.65967010496, 23166.76833231646, 23357.2992810216, 23555.646665300035, 23678.408039799142, 1148.104478506766, 1161.7908776420325, 1169.502961241469, 1170.9180357374903, 1186.2704436771496, 12460.376981150906, 12583.370831458391, 12637.43131559082, 12895.742085158245, 12914.612561623166, 602070.0, 604585.0, 608522.0, 619419.0, 620664.0, 136565.31717341547, 141109.0, 141458.0, 142706.0, 143111.0, 1278.859325474197, 1288.0, 1300.843898732158, 1317.6705823544114, 1326.191023475679, 20536.691949621065, 20695.482612934797, 20730.585388292453, 20753.169873204904, 21101.155953761678, 62432.68783656053, 63123.36876631175, 64124.358756413705, 64567.354326458015, 64603.0, 83558.58220708858, 84089.0, 84431.57784211039, 85036.0, 85072.0, 16917.577060573585, 17155.742663860303, 17211.91394043022, 17225.224864881362, 17271.136443178042, 25959.610605841306, 26161.345966350993, 26308.342291442103, 26467.7353226473, 26635.33411664724, 19958.900205498878, 19968.400947971582, 20044.098015589043, 20083.297084601967, 20308.79691203128, 18836.0, 19318.227270909567, 19493.220271188995, 19629.018549072774, 19881.204751810343, 20933.0, 21822.890885545472, 22157.778422216215, 22298.21956231524, 22306.0, 16911.91544042272, 16994.235259413603, 17148.142592870558, 17159.227834747715, 17305.13474326304, 18687.345942891934, 19548.902255488632, 19599.608007839477, 19641.901790490956, 19684.409467715992], "value": 13612.31285956814, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1373.7183877305151, 1516.2115699836088, 1529.3270960777263, 1540.1606124662057, 1543.228385807097, 17991.0, 18535.073246337663, 18541.814581854178, 18544.536386590342, 18546.165422556, 17953.192106355214, 18000.54998625035, 18012.0, 18047.0, 18089.7286540702, 3114.548390483384, 3192.9840350798213, 3194.105650417887, 3196.6643502432257, 3197.280611862324, 2835.248659105339, 2917.9270518237004, 2937.7943543951933, 2938.8971386001494, 2955.940881182375, 3133.2950086230644, 3226.322472280824, 3230.789998650092, 3234.5471633971265, 3235.9838200808963, 3084.4293805645916, 3125.1718294651937, 3150.5116706910467, 3159.08386567895, 3163.3040731039137, 3108.875644974198, 3153.0, 3154.542591324245, 3159.462891308483, 3167.144870884871, 17200.741988870177, 17361.131943402906, 17432.0, 17496.12519374039, 17525.47423577295, 8684.088170742107, 9017.278617710568, 9021.36850420471, 9028.909710902939, 9042.18620324174, 5533.142362933751, 5697.401772813836, 5860.765569377219, 5881.70591470429, 5901.0, 2100.159936025602, 2140.4969832089446, 2144.0, 2203.5482726041123, 2214.4242496950746, 31260.218494537818, 31574.526382104286, 31576.052718418712, 31646.683533164603, 31682.524762128585, 7871.291583757489, 7923.247291507298, 7923.801904952307, 7937.801554961172, 7948.0, 20520.179192832416, 20553.48616284605, 20554.0, 20577.0, 20583.794162058188, 8884.0, 9119.270458363315, 9145.451272923647, 9161.0, 9161.725148245563, 8255.504669719836, 8296.626651800683, 8321.0, 8383.622736976851, 8393.454425462325, 6250.062490626442, 6301.023341382093, 6332.240131184288, 6356.777512787123, 6392.0, 1693.3734518228089, 1746.502246859639, 1837.200817644329, 1850.2136591948367, 1876.7091100879386, 983.0808194338316, 999.9900001000043, 1007.5163921317809, 1010.5957616953131, 1018.3940555369692, 9942.453165075991, 9956.850647240297, 10124.190064794799, 10142.44216568081, 10196.235282353853, 778.8052986753312, 781.249999999995, 790.1032328307298, 794.2732399854069, 865.057087774329, 5871.060630299217, 5892.528597712173, 6185.567010309325, 6193.938060619426, 6407.711652975628, 24731.5053698925, 25754.356141096247, 26029.479410411685, 26513.33716657099, 26525.204243872715, 25590.488190236465, 25640.74359256421, 25904.0, 26247.21258362252, 26311.73688263131, 12902.870971290355, 12931.159474634307, 12947.287899165449, 12965.546205882749, 13121.409536570878, 9456.858147127661, 9697.563609637442, 9734.561944712646, 9753.122219000325, 9860.408375497493, 21485.355439336843, 21599.568008639424, 21778.237761678265, 22561.0, 22811.429714257276, 22649.094036239025, 22883.427914302276, 22915.656265155703, 22962.655560166277, 23384.415389615395, 12493.75012499763, 12695.301758403008, 12770.361481926053, 12782.0, 12831.230126192459, 16077.19614019271, 16106.516804495885, 16122.596935076717, 16159.67680646404, 16344.0, 13078.411471483616, 13236.933815330862, 13533.593992180251, 13649.658758530719, 13720.382582783599, 12492.875071249171, 12604.243745375306, 12606.49574017029, 12764.42560084817, 13129.934350328187, 11067.335959842436, 11176.497057632589, 11184.94407527957, 11290.15323850698, 11302.0, 8548.273396761346, 8574.09971952936, 8614.78463038429, 8733.297007083558, 8793.956030219808, 8554.743357699279, 8641.8703719443, 8662.740117796477, 8695.695650652196, 8827.558622069], "value": 7693.550543475475, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.416149068323, 591.4144996453513, 601.4947444146918, 608.8782243551291, 610.886985907607, 6380.29816720161, 6400.167873255691, 6421.839454013652, 6470.870582588345, 6502.634446766179, 6337.144485494457, 6378.107065010902, 6384.585001974869, 6392.817328794176, 6462.321456247097, 5209.86975325618, 5211.687298762067, 5228.660137091077, 5230.529252367277, 5236.8690782730355, 4914.606831453493, 5071.746412679369, 5175.585953123741, 5200.843974680765, 5233.011455646849, 5018.974905125469, 5033.7734801934, 5044.873878153039, 5058.367704036996, 5101.60743105836, 5018.774155163026, 5022.397312322526, 5029.874253143664, 5045.0, 5064.974675126601, 4625.606823420013, 4653.348531205634, 4658.440987081572, 4667.089917466095, 4685.367475390813, 6316.883843912295, 6345.968270158666, 6350.523710721714, 6354.0, 6357.936420635827, 6035.969820150915, 6057.151998720183, 6061.757529698807, 6100.908486372708, 6110.602810817282, 6671.532992690513, 6683.365080317362, 6703.832404189886, 6790.932090679129, 6818.181818181801, 5243.056249875021, 5255.395629502618, 5259.342582177228, 5260.210968354739, 5273.393559740641, 6789.355011273921, 6803.217629972518, 6893.448524118108, 7065.293470652945, 7084.752033678797, 5337.62636615441, 5349.277847490617, 5354.705491198021, 5416.8104116355735, 5420.915816836569, 5448.155535891943, 5453.03690671358, 5475.260839786658, 5483.506484416422, 5488.17677348401, 6035.064564992345, 6039.8792024159275, 6071.119687645267, 6074.665893375818, 6078.665673388006, 6767.0, 6824.556403833735, 6836.384725374641, 6852.881938717871, 6883.346082122191, 1875.1936667233222, 1880.407671583447, 1883.2372888979985, 1883.6720112320781, 1893.1575448925096, 1334.8798608125314, 1340.8468716903456, 1346.582559406587, 1348.4740951029107, 1361.7957306404119, 6393.9360606394275, 6465.579737317059, 6484.935150648433, 6533.281339052701, 6571.408573228432, 6843.520953533205, 6860.142482189726, 6886.311368863074, 6887.278180454886, 6925.722971081101, 6508.0, 6538.313477084933, 6545.4436372907885, 6550.181227346582, 6574.506911981621, 3660.3894286514383, 3685.4471829225295, 3686.4838922550866, 3688.911771027553, 3697.352963231424, 7234.927650723425, 7266.854662906712, 7268.96365518169, 7282.725523033448, 7284.70861165558, 7051.6377013700985, 7060.376113493925, 7061.82345441368, 7144.749139141782, 7176.569405835562, 7186.92813071873, 7205.0, 7205.711771529185, 7217.6391180441815, 7219.566825990458, 6682.799516014527, 6726.091977583159, 6737.427318677967, 6777.966110169417, 6814.897776533455, 6646.667666616747, 6752.358525940028, 6885.726140663994, 6886.139232595926, 6925.342092501204, 7100.0, 7171.820704482429, 7215.963920180366, 7256.238094999949, 7268.164161121539, 5266.631335806457, 5266.86832829167, 5302.443243459381, 5374.167004114369, 5389.730513474389, 6574.572652777554, 6805.3875151236625, 6895.482993741369, 6898.413634841095, 6935.133108361455, 6493.480521558169, 6541.542092053511, 6654.866902662016, 6676.232233293039, 6764.2559318475915, 6517.446017088411, 6542.967285163544, 6618.536702430783, 6674.199096108304, 6751.358620931003, 6639.6680165992475, 6645.435138013323, 6667.0, 6685.298043705341, 6702.832429189114, 6704.06143139951, 6758.648270346049, 6782.491313151632, 6819.5908245505425, 6826.624535650587, 6536.738530458919, 6550.181227346582, 6569.408753212234, 6587.80236592903, 6638.701258443285], "value": 5327.449345743924, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [922.8154369126177, 927.1053433436734, 937.1565590968128, 939.38939689202, 942.7030485397104, 23432.414189645267, 24839.254822355313, 24889.50220995579, 24938.12716554923, 25048.123315683955, 20533.794662053377, 20598.38204853857, 20702.48243793906, 20736.274230401938, 21025.369238922856, 1062.6546372428952, 1075.763332066944, 1085.3704851186333, 1087.7008822573816, 1088.8530048443438, 1123.3147779854282, 1130.508228920418, 1130.666453396246, 1130.9434528273594, 1133.5069244878464, 1170.7073231692077, 1171.8652354979201, 1172.2790483852455, 1172.953081876724, 1173.636172786439, 1160.1878684920548, 1165.6036947437826, 1173.5716463490808, 1174.9412529373583, 1175.0012489384042, 859.1408591408591, 859.7592674051276, 860.3590325207698, 861.3367706865741, 863.3783675753448, 19009.809901900942, 19466.70799938002, 19576.0, 19579.11893964775, 19966.500837479034, 22626.434339141488, 23036.65445018326, 23043.423914402105, 23058.308250752503, 23183.188588399324, 18446.90776546122, 18475.445736627924, 18510.444686659423, 18572.814271857245, 18647.90676046611, 3901.0637447012514, 4003.1393250451, 4004.4994375703036, 4009.3184158693093, 4032.4556184914954, 166233.0, 169654.0, 170787.0, 171497.0, 172955.0, 17121.400750973655, 17254.654906901793, 17304.480865574053, 17455.38906138279, 17456.825431745772, 29559.408811823643, 30384.0, 30451.847740761154, 30559.541606875915, 30726.38547229043, 8960.327975401871, 9329.673461428818, 9330.533473326306, 9331.72004839856, 9348.392354496937, 4769.976150119297, 4827.927581086286, 4903.289023091703, 4916.655834091651, 4936.703797772145, 6082.543809214327, 6220.937790622126, 6242.063690446378, 6250.624962502265, 6291.653959032205, 1297.9545715899899, 1322.1406086043955, 1334.8598397168353, 1335.6460537957448, 1344.2203521957242, 678.3081257117713, 687.9277675844064, 698.4203111417511, 703.8592281543712, 707.2326525719652, 13618.319084045757, 13768.586942391745, 13974.790378144337, 14040.789388159186, 14291.0, 708.4226355520235, 708.9259771446264, 715.4026387966106, 730.0800990751691, 731.4769939493287, 6150.600210986271, 6245.156903818017, 6270.529710271657, 6364.427201551883, 6377.5535712500605, 153391.0, 154756.0, 155306.22346888419, 156001.0, 162035.0, 130847.0, 133999.0, 135181.0, 136554.31722841322, 137175.31412342875, 121321.0, 122192.0, 123318.38340808239, 123540.38229808974, 125151.3742431282, 11738.70653233676, 11814.527418903148, 12187.87812121903, 12222.515264459518, 12304.446299916704, 56657.433425665215, 57998.7100064497, 58050.41949580619, 58952.70523647354, 59868.10197846949, 74463.25536744563, 74708.62645686737, 74733.0, 75341.0, 77793.0, 16982.745258821375, 17430.128493575015, 17823.643527129127, 19648.803511964696, 19807.801921980597, 21549.569008619426, 21685.56628867382, 21813.781862181175, 21837.126514939224, 21865.125394984658, 20119.497012574804, 20131.0, 20135.59728805445, 20205.191792328143, 20213.191472340943, 21206.681899771207, 21314.147434102462, 21331.8933405332, 21397.572048559254, 21463.678044829026, 19360.322388716326, 19397.127129278935, 19418.90290548538, 19487.415377538695, 19612.117454714287, 14846.109233446028, 14850.554483365517, 14991.325390357242, 15049.699006020037, 15059.849401505844, 15981.760273595673, 16005.519834404986, 16114.27485763167, 16272.349106035626, 16313.265903034155], "value": 10370.259491493902, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1360.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 105.0, 105.0, 104.0, 106.0, 95.0, 104.0, 106.0, 105.0, 105.0], "value": 104.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 116.0, 110.0, 113.0, 113.0, 111.0, 112.0, 111.0, 111.0, 110.0], "value": 111.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 161.0, 161.0, 164.0, 164.0, 164.0, 164.0, 165.0, 163.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 72.0, 73.0, 73.0, 73.0, 73.0, 85.0, 75.0, 74.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 78.0, 78.0, 77.0, 77.0, 83.0, 77.0, 77.0, 77.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 115.0, 113.0, 115.0, 114.0, 114.0, 114.0, 114.0, 113.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 149.0, 149.0, 148.0, 150.0, 147.0, 148.0, 149.0, 148.0, 149.0], "value": 149.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 100.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 43.0, 43.0, 43.0, 43.0, 42.0, 44.0, 44.0, 43.0, 43.0], "value": 43.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 39.0, 39.0, 38.0, 38.0, 39.0, 39.0, 38.0, 39.0], "value": 38.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 71.0, 72.0, 74.0, 69.0, 76.0, 72.0, 70.0, 69.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 102.0, 105.0, 114.0, 106.0, 110.0, 109.0, 109.0, 101.0, 105.0], "value": 106.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 152.0, 156.0, 148.0, 151.0, 148.0, 148.0, 152.0, 156.0], "value": 150.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 61.0, 61.0, 59.0, 59.0, 58.0, 63.0, 64.0, 59.0], "value": 60.2, "unit": "score"}]}]} 02:45:54 INFO - Return code: 0 02:45:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:45:55 INFO - # TBPL SUCCESS # 02:45:55 INFO - Running post-action listener: _package_coverage_data 02:45:55 INFO - Running post-action listener: _resource_record_post_action 02:45:55 INFO - [mozharness: 2017-10-01 09:45:55.006152Z] Finished run-tests step (success) 02:45:55 INFO - Running post-run listener: _resource_record_post_run 02:45:55 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 10743808; Write bytes: 420495360; Read time: 3816; Write time: 441388 02:45:55 INFO - TinderboxPrint: CPU usage
13.1% 02:45:55 INFO - TinderboxPrint: I/O read bytes / time
10,743,808 / 3,816 02:45:55 INFO - TinderboxPrint: I/O write bytes / time
420,495,360 / 441,388 02:45:55 INFO - TinderboxPrint: CPU idle
8,211.8 (86.9%) 02:45:55 INFO - TinderboxPrint: CPU user
1,180.5 (12.5%) 02:45:55 INFO - TinderboxPrint: Swap in / out
0 / 0 02:45:55 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 3809280; Read time: 0; Write time: 700 02:45:55 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 02:45:55 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 10743808; Write bytes: 366764032; Read time: 3816; Write time: 400080 02:45:55 INFO - Running post-run listener: _upload_blobber_files 02:45:55 INFO - Blob upload gear active. 02:45:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:45: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 02:45: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', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:45: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 Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:45:56 INFO - (blobuploader) - INFO - Open directory for files ... 02:45:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:45:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:45:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:45:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:45:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:45:57 INFO - (blobuploader) - INFO - Done attempting. 02:45:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:45:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:45:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:46:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:46:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:46:04 INFO - (blobuploader) - INFO - Done attempting. 02:46:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:46:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:46:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:46:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:46:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:46:04 INFO - (blobuploader) - INFO - Done attempting. 02:46:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:46:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:46:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:46:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:46:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:46:06 INFO - (blobuploader) - INFO - Done attempting. 02:46:06 INFO - (blobuploader) - INFO - Iteration through files over. 02:46:06 INFO - Return code: 0 02:46:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:46:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:46:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5fe729ee802e00870d5710aa88e00dbc482720ef1c44e930626158c965e9012c1fbb297743f5f29b3c36ace08985b3ecb81f756c2fed4c66b44025d7691a15db", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e2e3554bc5b590cbdf63b226a80c48d8bdf8aa8510cc67a334001b4339c01a30b5ea31b5da5e601a4e504afb687f37fb30ce1a9e2c5a0b77bd9f41ac7f91d4d2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8af6a3f8ff7f5d3c50669e7be50f8bc41d55cdef1783a7ec0f61afa5b6bd553721498a4f73be450da221cc5ba6876dd6ccb1eb75fb64fdf3145359492d66b210", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5dbdf1811f1de74a99881a4a6000430da98254e6e5ca87285a5612d9a0f0626f07ff08c23f4f42edbf6fa6b2b13b41efdc78256ebb03c468346b245160f3b82a"} 02:46:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:46:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:46:06 INFO - Contents: 02:46:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5fe729ee802e00870d5710aa88e00dbc482720ef1c44e930626158c965e9012c1fbb297743f5f29b3c36ace08985b3ecb81f756c2fed4c66b44025d7691a15db", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e2e3554bc5b590cbdf63b226a80c48d8bdf8aa8510cc67a334001b4339c01a30b5ea31b5da5e601a4e504afb687f37fb30ce1a9e2c5a0b77bd9f41ac7f91d4d2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8af6a3f8ff7f5d3c50669e7be50f8bc41d55cdef1783a7ec0f61afa5b6bd553721498a4f73be450da221cc5ba6876dd6ccb1eb75fb64fdf3145359492d66b210", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5dbdf1811f1de74a99881a4a6000430da98254e6e5ca87285a5612d9a0f0626f07ff08c23f4f42edbf6fa6b2b13b41efdc78256ebb03c468346b245160f3b82a"} 02:46:06 INFO - Running post-run listener: copy_logs_to_upload_dir 02:46:06 INFO - Copying logs to upload dir... 02:46:06 INFO - mkdir: /builds/slave/test/build/upload/logs 02:46:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.764916 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-10-01 02:46:06.121384) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-01 02:46:06.129336) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5fe729ee802e00870d5710aa88e00dbc482720ef1c44e930626158c965e9012c1fbb297743f5f29b3c36ace08985b3ecb81f756c2fed4c66b44025d7691a15db", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e2e3554bc5b590cbdf63b226a80c48d8bdf8aa8510cc67a334001b4339c01a30b5ea31b5da5e601a4e504afb687f37fb30ce1a9e2c5a0b77bd9f41ac7f91d4d2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8af6a3f8ff7f5d3c50669e7be50f8bc41d55cdef1783a7ec0f61afa5b6bd553721498a4f73be450da221cc5ba6876dd6ccb1eb75fb64fdf3145359492d66b210", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5dbdf1811f1de74a99881a4a6000430da98254e6e5ca87285a5612d9a0f0626f07ff08c23f4f42edbf6fa6b2b13b41efdc78256ebb03c468346b245160f3b82a"} build_url:https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011166 build_url: 'https://queue.taskcluster.net/v1/task/dMjXDFRQS4WLa_eQowLuJQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5fe729ee802e00870d5710aa88e00dbc482720ef1c44e930626158c965e9012c1fbb297743f5f29b3c36ace08985b3ecb81f756c2fed4c66b44025d7691a15db", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e2e3554bc5b590cbdf63b226a80c48d8bdf8aa8510cc67a334001b4339c01a30b5ea31b5da5e601a4e504afb687f37fb30ce1a9e2c5a0b77bd9f41ac7f91d4d2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8af6a3f8ff7f5d3c50669e7be50f8bc41d55cdef1783a7ec0f61afa5b6bd553721498a4f73be450da221cc5ba6876dd6ccb1eb75fb64fdf3145359492d66b210", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/5dbdf1811f1de74a99881a4a6000430da98254e6e5ca87285a5612d9a0f0626f07ff08c23f4f42edbf6fa6b2b13b41efdc78256ebb03c468346b245160f3b82a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-01 02:46:06.165692) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 02:46:06.166086) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506847169.358051-427017789 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004437 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 02:46:06.247003) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-01 02:46:06.247311) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-01 02:46:06.247622) ========= ========= Total master_lag: 0.25 =========