builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-045 starttime: 1504281211.69 results: success (0) revision: cc85091ab81c7eb865d2cfb31539702c1bb7a18d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:31.694019) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:31.694502) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:31.694773) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:31.707890) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:31.708281) ========= 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=4300f881736b422476174874000001bc-1504281210.647201-1575338579 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-01 08:53:31-- 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% 47.2M=0s 2017-09-01 08:53:31 (47.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126613 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:31.850327) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:31.850678) ========= 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=4300f881736b422476174874000001bc-1504281210.647201-1575338579 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020835 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:31.900951) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-01 08:53:31.901241) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cc85091ab81c7eb865d2cfb31539702c1bb7a18d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev cc85091ab81c7eb865d2cfb31539702c1bb7a18d --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=4300f881736b422476174874000001bc-1504281210.647201-1575338579 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-01 08:53:31,953 truncating revision to first 12 chars 2017-09-01 08:53:31,954 Setting DEBUG logging. 2017-09-01 08:53:31,954 attempt 1/10 2017-09-01 08:53:31,954 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cc85091ab81c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-01 08:53:33,125 unpacking tar archive at: mozilla-central-cc85091ab81c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.581221 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-01 08:53:33.498603) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:33.498951) ========= script_repo_revision: cc85091ab81c7eb865d2cfb31539702c1bb7a18d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:33.499351) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:33.499630) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:33.511074) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-01 08:53:33.511497) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1504281210.647201-1575338579 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:53:33 INFO - MultiFileLogger online at 20170901 08:53:33 in /builds/slave/test 08:53:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 08:53:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:53:33 INFO - {'append_to_log': False, 08:53:33 INFO - 'base_work_dir': '/builds/slave/test', 08:53:33 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:53:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:53:33 INFO - 'branch': 'Firefox-Non-PGO', 08:53:33 INFO - 'buildbot_json_path': 'buildprops.json', 08:53:33 INFO - 'code_coverage': False, 08:53:33 INFO - 'config_files': ('talos/linux_config.py',), 08:53:33 INFO - 'default_actions': ('clobber', 08:53:33 INFO - 'read-buildbot-config', 08:53:33 INFO - 'download-and-extract', 08:53:33 INFO - 'populate-webroot', 08:53:33 INFO - 'create-virtualenv', 08:53:33 INFO - 'install', 08:53:33 INFO - 'setup-mitmproxy', 08:53:33 INFO - 'run-tests'), 08:53:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:53:33 INFO - 'disable_ccov_upload': False, 08:53:33 INFO - 'disable_stylo': False, 08:53:33 INFO - 'download_minidump_stackwalk': True, 08:53:33 INFO - 'download_symbols': 'ondemand', 08:53:33 INFO - 'enable_stylo': False, 08:53:33 INFO - 'enable_webrender': False, 08:53:33 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 08:53:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:53:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:53:33 INFO - 'gecko_profile': False, 08:53:33 INFO - 'gecko_profile_interval': 0, 08:53:33 INFO - 'installer_path': 'installer.exe', 08:53:33 INFO - 'log_level': 'info', 08:53:33 INFO - 'log_name': 'talos', 08:53:33 INFO - 'log_to_console': True, 08:53:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:53:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:53:33 INFO - 'opt_config_files': (), 08:53:33 INFO - 'pip_index': False, 08:53:33 INFO - 'suite': 'dromaeojs-e10s', 08:53:33 INFO - 'system_bits': '32', 08:53:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:53:33 INFO - 'title': 'talos-linux64-ix-045', 08:53:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:53:33 INFO - 'use_talos_json': True, 08:53:33 INFO - 'verify': 'False', 08:53:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:53:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:53:33 INFO - 'work_dir': 'build'} 08:53:33 INFO - [mozharness: 2017-09-01 15:53:33.708960Z] Running clobber step. 08:53:33 INFO - Running pre-action listener: _resource_record_pre_action 08:53:33 INFO - Running main action method: clobber 08:53:33 INFO - rmtree: /builds/slave/test/build 08:53:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:53:34 INFO - Running post-action listener: _resource_record_post_action 08:53:34 INFO - [mozharness: 2017-09-01 15:53:34.734210Z] Finished clobber step (success) 08:53:34 INFO - [mozharness: 2017-09-01 15:53:34.734307Z] Running read-buildbot-config step. 08:53:34 INFO - Running pre-action listener: _resource_record_pre_action 08:53:34 INFO - Running main action method: read_buildbot_config 08:53:34 INFO - Using buildbot properties: 08:53:34 INFO - { 08:53:34 INFO - "project": "", 08:53:34 INFO - "product": "firefox", 08:53:34 INFO - "who": "archaeopteryx@coole-files.de", 08:53:34 INFO - "installer_path": "public/build/target.tar.bz2", 08:53:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 08:53:34 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 08:53:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 08:53:34 INFO - "stage_platform": "linux64", 08:53:34 INFO - "basedir": "/builds/slave/test", 08:53:34 INFO - "buildnumber": 1108, 08:53:34 INFO - "platform": "ubuntu64_hw", 08:53:34 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:53:34 INFO - "slavebuilddir": "test", 08:53:34 INFO - "taskId": "I7xDpz9WRTetOGL24a43TQ", 08:53:34 INFO - "branch": "mozilla-central", 08:53:34 INFO - "script_repo_revision": "production", 08:53:34 INFO - "revision": "cc85091ab81c7eb865d2cfb31539702c1bb7a18d", 08:53:34 INFO - "slavename": "talos-linux64-ix-045", 08:53:34 INFO - "repo_path": "mozilla-central" 08:53:34 INFO - } 08:53:34 INFO - Finding installer, test and symbols from parent task. 08:53:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I7xDpz9WRTetOGL24a43TQ'}, attempt #1 08:53:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I7xDpz9WRTetOGL24a43TQ'}, attempt #1 08:53:35 INFO - Task dependencies: P0kX4SRdS6ifTGg50JASWg 08:53:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg'}, attempt #1 08:53:36 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2 08:53:36 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.test_packages.json 08:53:36 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.crashreporter-symbols.zip 08:53:36 INFO - Running post-action listener: _resource_record_post_action 08:53:36 INFO - [mozharness: 2017-09-01 15:53:36.193970Z] Finished read-buildbot-config step (success) 08:53:36 INFO - [mozharness: 2017-09-01 15:53:36.194188Z] Running download-and-extract step. 08:53:36 INFO - Running pre-action listener: _resource_record_pre_action 08:53:36 INFO - Running main action method: download_and_extract 08:53:36 INFO - mkdir: /builds/slave/test/build/tests 08:53:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:36 INFO - trying https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.test_packages.json 08:53:36 INFO - Downloading https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:53:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:53:36 INFO - Downloaded 989 bytes. 08:53:36 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:53:36 INFO - Using the following test package requirements: 08:53:36 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:53:36 INFO - u'common': [u'target.common.tests.zip'], 08:53:36 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:53:36 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:53:36 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:53:36 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:53:36 INFO - u'mozbase': [u'target.common.tests.zip'], 08:53:36 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:53:36 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:53:36 INFO - u'web-platform': [u'target.common.tests.zip', 08:53:36 INFO - u'target.web-platform.tests.tar.gz'], 08:53:36 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:53:36 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:53:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.common.tests.zip 08:53:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:53:36 INFO - Fetch https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.common.tests.zip into memory 08:53:37 INFO - Content-Length response header: 54957468 08:53:37 INFO - Bytes received: 54957468 08:53:48 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:53:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.common.tests.zip 08:53:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:53:48 INFO - Fetch https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.common.tests.zip into memory 08:53:50 INFO - Content-Length response header: 54957468 08:53:50 INFO - Bytes received: 54957468 08:54:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.talos.tests.zip 08:54:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:54:02 INFO - Fetch https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.talos.tests.zip into memory 08:54:02 INFO - Content-Length response header: 14496650 08:54:02 INFO - Bytes received: 14496650 08:54:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:03 INFO - trying https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2 08:54:03 INFO - Downloading https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:54:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:54:04 INFO - Downloaded 62657924 bytes. 08:54:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2 08:54:04 INFO - mkdir: /builds/slave/test/properties 08:54:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:54:04 INFO - Writing to file /builds/slave/test/properties/build_url 08:54:04 INFO - Contents: 08:54:04 INFO - build_url:https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2 08:54:04 INFO - Running post-action listener: _resource_record_post_action 08:54:04 INFO - Running post-action listener: find_tests_for_verification 08:54:04 INFO - Running post-action listener: set_extra_try_arguments 08:54:04 INFO - [mozharness: 2017-09-01 15:54:04.533727Z] Finished download-and-extract step (success) 08:54:04 INFO - [mozharness: 2017-09-01 15:54:04.533823Z] Running populate-webroot step. 08:54:04 INFO - Running pre-action listener: _resource_record_pre_action 08:54:04 INFO - Running main action method: populate_webroot 08:54:04 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:54:04 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 08:54:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:54:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:54:04 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 08:54:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:54:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:54:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:54:04 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:54:04 INFO - 'talos_options': ['--enable-stylo'], 08:54:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:54:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:54:04 INFO - 'tests': ['damp', 'tps']}, 08:54:04 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:54:04 INFO - 'talos_options': ['--enable-stylo'], 08:54:04 INFO - 'tests': ['damp', 'tps']}, 08:54:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:54:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:54:04 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 08:54:04 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:54:04 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:54:04 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:54:04 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:54:04 INFO - 'talos_options': ['--enable-stylo'], 08:54:04 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:54:04 INFO - 'other-e10s': {'tests': ['a11yr', 08:54:04 INFO - 'ts_paint', 08:54:04 INFO - 'tpaint', 08:54:04 INFO - 'sessionrestore', 08:54:04 INFO - 'sessionrestore_many_windows', 08:54:04 INFO - 'sessionrestore_no_auto_restore', 08:54:04 INFO - 'tabpaint']}, 08:54:04 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 08:54:04 INFO - 'tests': ['a11yr', 08:54:04 INFO - 'ts_paint', 08:54:04 INFO - 'tpaint', 08:54:04 INFO - 'sessionrestore', 08:54:04 INFO - 'sessionrestore_many_windows', 08:54:04 INFO - 'sessionrestore_no_auto_restore', 08:54:04 INFO - 'tabpaint']}, 08:54:04 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 08:54:04 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 08:54:04 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 08:54:04 INFO - 'tests': ['bloom_basic_singleton']}, 08:54:04 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 08:54:04 INFO - 'tests': ['bloom_basic']}, 08:54:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:54:04 INFO - 'tsvgr_opacity', 08:54:04 INFO - 'tart', 08:54:04 INFO - 'tscrollx', 08:54:04 INFO - 'tsvg_static']}, 08:54:04 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 08:54:04 INFO - 'tests': ['tsvgx', 08:54:04 INFO - 'tsvgr_opacity', 08:54:04 INFO - 'tart', 08:54:04 INFO - 'tscrollx', 08:54:04 INFO - 'tsvg_static']}, 08:54:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:54:04 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:54:04 INFO - 'talos_options': ['--enable-stylo'], 08:54:04 INFO - 'tests': ['tp5o']}, 08:54:04 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:54:04 INFO - 'talos_options': ['--mitmproxy', 08:54:04 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:54:04 INFO - '--firstNonBlankPaint'], 08:54:04 INFO - 'tests': ['tp6_google', 08:54:04 INFO - 'tp6_youtube', 08:54:04 INFO - 'tp6_amazon', 08:54:04 INFO - 'tp6_facebook']}, 08:54:04 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:54:04 INFO - 'talos_options': ['--enable-stylo', 08:54:04 INFO - '--mitmproxy', 08:54:04 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:54:04 INFO - '--firstNonBlankPaint'], 08:54:04 INFO - 'tests': ['tp6_google', 08:54:04 INFO - 'tp6_youtube', 08:54:04 INFO - 'tp6_amazon', 08:54:04 INFO - 'tp6_facebook']}, 08:54:04 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:54:04 INFO - 'talos_options': ['--enable-stylo', 08:54:04 INFO - '--stylo-threads=1', 08:54:04 INFO - '--mitmproxy', 08:54:04 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:54:04 INFO - '--firstNonBlankPaint'], 08:54:04 INFO - 'tests': ['tp6_google', 08:54:04 INFO - 'tp6_youtube', 08:54:04 INFO - 'tp6_amazon', 08:54:04 INFO - 'tp6_facebook']}, 08:54:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:54:04 INFO - 'talos_options': ['--xperf_path', 08:54:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:54:04 INFO - 'tests': ['tp5n']}, 08:54:04 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:54:04 INFO - 'talos_options': ['--enable-stylo', 08:54:04 INFO - '--xperf_path', 08:54:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:54:04 INFO - 'tests': ['tp5n']}}} 08:54:04 INFO - Running post-action listener: _resource_record_post_action 08:54:04 INFO - [mozharness: 2017-09-01 15:54:04.548231Z] Finished populate-webroot step (success) 08:54:04 INFO - [mozharness: 2017-09-01 15:54:04.548320Z] Running create-virtualenv step. 08:54:04 INFO - Running pre-action listener: _resource_record_pre_action 08:54:04 INFO - Running main action method: create_virtualenv 08:54:04 INFO - Creating virtualenv /builds/slave/test/build/venv 08:54:04 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:54:04 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 08:54:04 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:54:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:54:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:54:05 INFO - Installing distribute.............................................................................................................................................................................................done. 08:54:05 INFO - Installing pip...............done. 08:54:05 INFO - Return code: 0 08:54:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:54:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:54:05 INFO - Reading from file tmpfile_stdout 08:54:05 INFO - Output received: 08:54:05 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 08:54:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:54:05 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')]} 08:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:05 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')]} 08:54:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:05 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 0x24571f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c7090>, '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 0x27c7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c1f90>, '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': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', '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 08:54:05 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 08:54:05 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 08:54:05 INFO - Using env: {'DISPLAY': ':0', 08:54:05 INFO - 'HOME': '/home/cltbld', 08:54:05 INFO - 'LANG': 'en_US.UTF-8', 08:54:05 INFO - 'LANGUAGE': 'en_US:en', 08:54:05 INFO - 'LOGNAME': 'cltbld', 08:54:05 INFO - 'MAIL': '/var/mail/cltbld', 08:54:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:05 INFO - 'MOZ_NO_REMOTE': '1', 08:54:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:05 INFO - 'NO_EM_RESTART': '1', 08:54:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:05 INFO - 'PWD': '/builds/slave/test', 08:54:05 INFO - 'SHELL': '/bin/bash', 08:54:05 INFO - 'SHLVL': '1', 08:54:05 INFO - 'TERM': 'linux', 08:54:05 INFO - 'TMOUT': '86400', 08:54:05 INFO - 'USER': 'cltbld', 08:54:05 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', 08:54:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:05 INFO - '_': '/tools/buildbot/bin/python'} 08:54:06 INFO - Ignoring indexes: http://pypi.python.org/simple/ 08:54:06 INFO - Downloading/unpacking pip>=1.5 08:54:06 INFO - Running setup.py egg_info for package pip 08:54:06 INFO - warning: no files found matching 'pip/cacert.pem' 08:54:06 INFO - warning: no files found matching '*.html' under directory 'docs' 08:54:06 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:54:06 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:54:06 INFO - Installing collected packages: pip 08:54:06 INFO - Found existing installation: pip 1.1 08:54:06 INFO - Uninstalling pip: 08:54:06 INFO - Successfully uninstalled pip 08:54:06 INFO - Running setup.py install for pip 08:54:06 INFO - warning: no files found matching 'pip/cacert.pem' 08:54:06 INFO - warning: no files found matching '*.html' under directory 'docs' 08:54:06 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:54:06 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:54:06 INFO - Installing pip script to /builds/slave/test/build/venv/bin 08:54:06 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 08:54:06 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 08:54:06 INFO - Successfully installed pip 08:54:06 INFO - Cleaning up... 08:54:06 INFO - Return code: 0 08:54:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x24571f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c7090>, '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 0x27c7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c1f90>, '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': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', '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 08:54:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:54:06 INFO - Using env: {'DISPLAY': ':0', 08:54:06 INFO - 'HOME': '/home/cltbld', 08:54:06 INFO - 'LANG': 'en_US.UTF-8', 08:54:06 INFO - 'LANGUAGE': 'en_US:en', 08:54:06 INFO - 'LOGNAME': 'cltbld', 08:54:06 INFO - 'MAIL': '/var/mail/cltbld', 08:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:06 INFO - 'MOZ_NO_REMOTE': '1', 08:54:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:06 INFO - 'NO_EM_RESTART': '1', 08:54:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:06 INFO - 'PWD': '/builds/slave/test', 08:54:06 INFO - 'SHELL': '/bin/bash', 08:54:06 INFO - 'SHLVL': '1', 08:54:06 INFO - 'TERM': 'linux', 08:54:06 INFO - 'TMOUT': '86400', 08:54:06 INFO - 'USER': 'cltbld', 08:54:06 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', 08:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:06 INFO - '_': '/tools/buildbot/bin/python'} 08:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:06 INFO - Downloading/unpacking psutil>=3.1.1 08:54:06 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 08:54:06 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 08:54:06 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 08:54:06 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 08:54:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:54:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:54:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:54:08 INFO - Installing collected packages: psutil 08:54:08 INFO - Running setup.py install for psutil 08:54:08 INFO - building 'psutil._psutil_linux' extension 08:54:08 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 08:54:08 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 08:54:08 INFO - building 'psutil._psutil_posix' extension 08:54:08 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 08:54:08 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 08:54:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:54:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:54:08 INFO - Successfully installed psutil 08:54:08 INFO - Cleaning up... 08:54:08 INFO - Return code: 0 08:54:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:54:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:08 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 0x24571f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c7090>, '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 0x27c7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c1f90>, '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': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', '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 08:54:08 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 08:54:08 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 08:54:08 INFO - Using env: {'DISPLAY': ':0', 08:54:08 INFO - 'HOME': '/home/cltbld', 08:54:08 INFO - 'LANG': 'en_US.UTF-8', 08:54:08 INFO - 'LANGUAGE': 'en_US:en', 08:54:08 INFO - 'LOGNAME': 'cltbld', 08:54:08 INFO - 'MAIL': '/var/mail/cltbld', 08:54:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:08 INFO - 'MOZ_NO_REMOTE': '1', 08:54:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:08 INFO - 'NO_EM_RESTART': '1', 08:54:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:08 INFO - 'PWD': '/builds/slave/test', 08:54:08 INFO - 'SHELL': '/bin/bash', 08:54:08 INFO - 'SHLVL': '1', 08:54:08 INFO - 'TERM': 'linux', 08:54:08 INFO - 'TMOUT': '86400', 08:54:08 INFO - 'USER': 'cltbld', 08:54:08 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', 08:54:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:08 INFO - '_': '/tools/buildbot/bin/python'} 08:54:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:54:08 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 08:54:08 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 08:54:08 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 08:54:08 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 08:54:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:54:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:54:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:54:10 INFO - Installing collected packages: mozsystemmonitor 08:54:10 INFO - Running setup.py install for mozsystemmonitor 08:54:10 INFO - Successfully installed mozsystemmonitor 08:54:10 INFO - Cleaning up... 08:54:10 INFO - Return code: 0 08:54:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:54:10 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')]} 08:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:10 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')]} 08:54:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:10 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 0x24571f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c7090>, '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 0x27c7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c1f90>, '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': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', '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 08:54:10 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 08:54:10 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 08:54:10 INFO - Using env: {'DISPLAY': ':0', 08:54:10 INFO - 'HOME': '/home/cltbld', 08:54:10 INFO - 'LANG': 'en_US.UTF-8', 08:54:10 INFO - 'LANGUAGE': 'en_US:en', 08:54:10 INFO - 'LOGNAME': 'cltbld', 08:54:10 INFO - 'MAIL': '/var/mail/cltbld', 08:54:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:10 INFO - 'MOZ_NO_REMOTE': '1', 08:54:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:10 INFO - 'NO_EM_RESTART': '1', 08:54:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:10 INFO - 'PWD': '/builds/slave/test', 08:54:10 INFO - 'SHELL': '/bin/bash', 08:54:10 INFO - 'SHLVL': '1', 08:54:10 INFO - 'TERM': 'linux', 08:54:10 INFO - 'TMOUT': '86400', 08:54:10 INFO - 'USER': 'cltbld', 08:54:10 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', 08:54:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:10 INFO - '_': '/tools/buildbot/bin/python'} 08:54:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:10 INFO - Downloading/unpacking jsonschema==2.5.1 08:54:10 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 08:54:10 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 08:54:10 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 08:54:10 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 08:54:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:54:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:54:11 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 08:54:11 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 08:54:11 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 08:54:11 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 08:54:11 INFO - Downloading functools32-3.2.3-2.tar.gz 08:54:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:54:11 INFO - warning: no files found matching '*.txt' 08:54:11 INFO - no previously-included directories found matching 'build' 08:54:11 INFO - no previously-included directories found matching 'dist' 08:54:11 INFO - no previously-included directories found matching '.git*' 08:54:11 INFO - Installing collected packages: jsonschema, functools32 08:54:11 INFO - Running setup.py install for functools32 08:54:11 INFO - warning: no files found matching '*.txt' 08:54:11 INFO - no previously-included directories found matching 'build' 08:54:11 INFO - no previously-included directories found matching 'dist' 08:54:11 INFO - no previously-included directories found matching '.git*' 08:54:11 INFO - Successfully installed jsonschema functools32 08:54:11 INFO - Cleaning up... 08:54:11 INFO - Return code: 0 08:54:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:54:11 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')]} 08:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:11 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')]} 08:54:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:11 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 0x24571f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c7090>, '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 0x27c7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c1f90>, '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': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', '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 08:54:11 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 08:54:11 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 08:54:11 INFO - Using env: {'DISPLAY': ':0', 08:54:11 INFO - 'HOME': '/home/cltbld', 08:54:11 INFO - 'LANG': 'en_US.UTF-8', 08:54:11 INFO - 'LANGUAGE': 'en_US:en', 08:54:11 INFO - 'LOGNAME': 'cltbld', 08:54:11 INFO - 'MAIL': '/var/mail/cltbld', 08:54:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:11 INFO - 'MOZ_NO_REMOTE': '1', 08:54:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:11 INFO - 'NO_EM_RESTART': '1', 08:54:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:11 INFO - 'PWD': '/builds/slave/test', 08:54:11 INFO - 'SHELL': '/bin/bash', 08:54:11 INFO - 'SHLVL': '1', 08:54:11 INFO - 'TERM': 'linux', 08:54:11 INFO - 'TMOUT': '86400', 08:54:11 INFO - 'USER': 'cltbld', 08:54:11 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', 08:54:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:11 INFO - '_': '/tools/buildbot/bin/python'} 08:54:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:54:12 INFO - Cleaning up... 08:54:12 INFO - Return code: 0 08:54:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:54:12 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')]} 08:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:12 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')]} 08:54:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:12 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 0x24571f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c7090>, '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 0x27c7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c1f90>, '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': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', '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 08:54:12 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 08:54:12 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 08:54:12 INFO - Using env: {'DISPLAY': ':0', 08:54:12 INFO - 'HOME': '/home/cltbld', 08:54:12 INFO - 'LANG': 'en_US.UTF-8', 08:54:12 INFO - 'LANGUAGE': 'en_US:en', 08:54:12 INFO - 'LOGNAME': 'cltbld', 08:54:12 INFO - 'MAIL': '/var/mail/cltbld', 08:54:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:12 INFO - 'MOZ_NO_REMOTE': '1', 08:54:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:12 INFO - 'NO_EM_RESTART': '1', 08:54:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:12 INFO - 'PWD': '/builds/slave/test', 08:54:12 INFO - 'SHELL': '/bin/bash', 08:54:12 INFO - 'SHLVL': '1', 08:54:12 INFO - 'TERM': 'linux', 08:54:12 INFO - 'TMOUT': '86400', 08:54:12 INFO - 'USER': 'cltbld', 08:54:12 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', 08:54:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:12 INFO - '_': '/tools/buildbot/bin/python'} 08:54:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:12 INFO - Downloading/unpacking blobuploader==1.2.4 08:54:12 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 08:54:12 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 08:54:12 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 08:54:12 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 08:54:13 INFO - Downloading blobuploader-1.2.4.tar.gz 08:54:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:54:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:54:13 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 08:54:13 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 08:54:13 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 08:54:13 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 08:54:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:54:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:54:13 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 08:54:13 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 08:54:13 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 08:54:13 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 08:54:13 INFO - Downloading docopt-0.6.1.tar.gz 08:54:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:54:14 INFO - Installing collected packages: blobuploader, requests, docopt 08:54:14 INFO - Running setup.py install for blobuploader 08:54:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:54:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:54:14 INFO - Running setup.py install for requests 08:54:14 INFO - Running setup.py install for docopt 08:54:14 INFO - Successfully installed blobuploader requests docopt 08:54:14 INFO - Cleaning up... 08:54:14 INFO - Return code: 0 08:54:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:54:14 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')]} 08:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:14 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')]} 08:54:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:14 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 0x24571f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c7090>, '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 0x27c7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c1f90>, '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': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', '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 08:54:14 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 08:54:14 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 08:54:14 INFO - Using env: {'DISPLAY': ':0', 08:54:14 INFO - 'HOME': '/home/cltbld', 08:54:14 INFO - 'LANG': 'en_US.UTF-8', 08:54:14 INFO - 'LANGUAGE': 'en_US:en', 08:54:14 INFO - 'LOGNAME': 'cltbld', 08:54:14 INFO - 'MAIL': '/var/mail/cltbld', 08:54:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:14 INFO - 'MOZ_NO_REMOTE': '1', 08:54:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:14 INFO - 'NO_EM_RESTART': '1', 08:54:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:14 INFO - 'PWD': '/builds/slave/test', 08:54:14 INFO - 'SHELL': '/bin/bash', 08:54:14 INFO - 'SHLVL': '1', 08:54:14 INFO - 'TERM': 'linux', 08:54:14 INFO - 'TMOUT': '86400', 08:54:14 INFO - 'USER': 'cltbld', 08:54:14 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', 08:54:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:14 INFO - '_': '/tools/buildbot/bin/python'} 08:54:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:54:14 INFO - Running setup.py (path:/tmp/pip-eEJJfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:54:14 INFO - Running setup.py (path:/tmp/pip-lcvIMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:54:14 INFO - Running setup.py (path:/tmp/pip-qyTrvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:54:15 INFO - Running setup.py (path:/tmp/pip-g_bNhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:54:15 INFO - Running setup.py (path:/tmp/pip-j556BG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:54:15 INFO - Running setup.py (path:/tmp/pip-4qwPMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:54:15 INFO - Running setup.py (path:/tmp/pip-CDKZTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:54:15 INFO - Running setup.py (path:/tmp/pip-STvAjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:54:15 INFO - Running setup.py (path:/tmp/pip-ldOQg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:54:15 INFO - Running setup.py (path:/tmp/pip-zszL8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:54:15 INFO - Running setup.py (path:/tmp/pip-B9Pvt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:54:15 INFO - Running setup.py (path:/tmp/pip-wE4PGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:54:15 INFO - Running setup.py (path:/tmp/pip-sjFANX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:54:15 INFO - Running setup.py (path:/tmp/pip-_E9VGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:15 INFO - Running setup.py (path:/tmp/pip-_Ogdbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:54:15 INFO - Running setup.py (path:/tmp/pip-NI5kOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:54:15 INFO - Running setup.py (path:/tmp/pip-d8oIIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:54:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:54:16 INFO - Running setup.py install for manifestparser 08:54:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:54:16 INFO - Running setup.py install for mozcrash 08:54:16 INFO - Running setup.py install for mozdebug 08:54:16 INFO - Running setup.py install for mozdevice 08:54:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:54:16 INFO - Running setup.py install for mozfile 08:54:16 INFO - Running setup.py install for mozhttpd 08:54:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:54:16 INFO - Running setup.py install for mozinfo 08:54:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:54:16 INFO - Running setup.py install for mozInstall 08:54:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:54:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:54:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:54:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:54:16 INFO - Running setup.py install for mozleak 08:54:16 INFO - Running setup.py install for mozlog 08:54:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:54:17 INFO - Running setup.py install for moznetwork 08:54:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:54:17 INFO - Running setup.py install for mozprocess 08:54:17 INFO - Running setup.py install for mozprofile 08:54:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:54:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:54:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:54:17 INFO - Running setup.py install for mozrunner 08:54:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:54:17 INFO - Running setup.py install for mozscreenshot 08:54:17 INFO - Running setup.py install for moztest 08:54:17 INFO - Running setup.py install for mozversion 08:54:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:54:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:54:17 INFO - Cleaning up... 08:54:17 INFO - Return code: 0 08:54:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:17 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 0x24571f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c7090>, '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 0x27c7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c1f90>, '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': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', '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 08:54:17 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 08:54:17 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 08:54:17 INFO - Using env: {'DISPLAY': ':0', 08:54:17 INFO - 'HOME': '/home/cltbld', 08:54:17 INFO - 'LANG': 'en_US.UTF-8', 08:54:17 INFO - 'LANGUAGE': 'en_US:en', 08:54:17 INFO - 'LOGNAME': 'cltbld', 08:54:17 INFO - 'MAIL': '/var/mail/cltbld', 08:54:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:17 INFO - 'MOZ_NO_REMOTE': '1', 08:54:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:17 INFO - 'NO_EM_RESTART': '1', 08:54:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:17 INFO - 'PWD': '/builds/slave/test', 08:54:17 INFO - 'SHELL': '/bin/bash', 08:54:17 INFO - 'SHLVL': '1', 08:54:17 INFO - 'TERM': 'linux', 08:54:17 INFO - 'TMOUT': '86400', 08:54:17 INFO - 'USER': 'cltbld', 08:54:17 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', 08:54:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:17 INFO - '_': '/tools/buildbot/bin/python'} 08:54:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:54:17 INFO - Running setup.py (path:/tmp/pip-YP_O5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:54:18 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)) 08:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:54:18 INFO - Running setup.py (path:/tmp/pip-tC3Ua4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:54:18 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)) 08:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:54:18 INFO - Running setup.py (path:/tmp/pip-vy1dPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:54:18 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)) 08:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:54:18 INFO - Running setup.py (path:/tmp/pip-FKARHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:54:18 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)) 08:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:54:18 INFO - Running setup.py (path:/tmp/pip-VWkCaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:54:18 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)) 08:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:54:18 INFO - Running setup.py (path:/tmp/pip-rP9L39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:54:18 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)) 08:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:54:18 INFO - Running setup.py (path:/tmp/pip-wZiAsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:54:18 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)) 08:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:54:18 INFO - Running setup.py (path:/tmp/pip-XknBjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:54:18 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)) 08:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:54:18 INFO - Running setup.py (path:/tmp/pip-ZicLbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:54:18 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)) 08:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:54:18 INFO - Running setup.py (path:/tmp/pip-o6kbYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:54:18 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)) 08:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:54:18 INFO - Running setup.py (path:/tmp/pip-xuKx_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:54:18 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)) 08:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:54:18 INFO - Running setup.py (path:/tmp/pip-R4Yd0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:54:18 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)) 08:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:54:18 INFO - Running setup.py (path:/tmp/pip-RX7Vp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:54:18 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)) 08:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:54:18 INFO - Running setup.py (path:/tmp/pip-at4eei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:54:18 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)) 08:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:18 INFO - Running setup.py (path:/tmp/pip-bJ2o26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:19 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)) 08:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:54:19 INFO - Running setup.py (path:/tmp/pip-68gSOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:54:19 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)) 08:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:54:19 INFO - Running setup.py (path:/tmp/pip-vfir4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:54:19 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)) 08:54:19 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)) 08:54:19 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)) 08:54:19 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)) 08:54:19 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)) 08:54:19 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)) 08:54:19 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)) 08:54:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:54:19 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 08:54:19 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 08:54:19 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 08:54:19 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 08:54:20 INFO - Downloading blessings-1.6.tar.gz 08:54:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:54:20 INFO - Installing collected packages: blessings 08:54:20 INFO - Running setup.py install for blessings 08:54:20 INFO - Successfully installed blessings 08:54:20 INFO - Cleaning up... 08:54:20 INFO - Return code: 0 08:54:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:54:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:54:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:54:20 INFO - Reading from file tmpfile_stdout 08:54:20 INFO - Current package versions: 08:54:20 INFO - argparse == 1.2.1 08:54:20 INFO - blessings == 1.6 08:54:20 INFO - blobuploader == 1.2.4 08:54:20 INFO - docopt == 0.6.1 08:54:20 INFO - functools32 == 3.2.3-2 08:54:20 INFO - jsonschema == 2.5.1 08:54:20 INFO - manifestparser == 1.1 08:54:20 INFO - mozInstall == 1.13 08:54:20 INFO - mozcrash == 1.0 08:54:20 INFO - mozdebug == 0.1 08:54:20 INFO - mozdevice == 0.51 08:54:20 INFO - mozfile == 1.2 08:54:20 INFO - mozhttpd == 0.7 08:54:20 INFO - mozinfo == 0.10 08:54:20 INFO - mozleak == 0.1 08:54:20 INFO - mozlog == 3.5 08:54:20 INFO - moznetwork == 0.27 08:54:20 INFO - mozprocess == 0.25 08:54:20 INFO - mozprofile == 0.28 08:54:20 INFO - mozrunner == 6.13 08:54:20 INFO - mozscreenshot == 0.1 08:54:20 INFO - mozsystemmonitor == 0.3 08:54:20 INFO - moztest == 0.8 08:54:20 INFO - mozversion == 1.4 08:54:20 INFO - psutil == 3.1.1 08:54:20 INFO - requests == 1.2.3 08:54:20 INFO - wsgiref == 0.1.2 08:54:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:54:20 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')]} 08:54:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:20 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')]} 08:54:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:20 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 0x24571f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c7090>, '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 0x27c7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c1f90>, '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': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', '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 08:54:20 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 08:54:20 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 08:54:20 INFO - Using env: {'DISPLAY': ':0', 08:54:20 INFO - 'HOME': '/home/cltbld', 08:54:20 INFO - 'LANG': 'en_US.UTF-8', 08:54:20 INFO - 'LANGUAGE': 'en_US:en', 08:54:20 INFO - 'LOGNAME': 'cltbld', 08:54:20 INFO - 'MAIL': '/var/mail/cltbld', 08:54:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:20 INFO - 'MOZ_NO_REMOTE': '1', 08:54:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:20 INFO - 'NO_EM_RESTART': '1', 08:54:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:20 INFO - 'PWD': '/builds/slave/test', 08:54:20 INFO - 'SHELL': '/bin/bash', 08:54:20 INFO - 'SHLVL': '1', 08:54:20 INFO - 'TERM': 'linux', 08:54:20 INFO - 'TMOUT': '86400', 08:54:20 INFO - 'USER': 'cltbld', 08:54:20 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', 08:54:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:20 INFO - '_': '/tools/buildbot/bin/python'} 08:54:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:20 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)) 08:54:20 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)) 08:54:20 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)) 08:54:20 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)) 08:54:20 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)) 08:54:20 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)) 08:54:20 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)) 08:54:20 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)) 08:54:20 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)) 08:54:20 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:54:20 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 08:54:20 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 08:54:20 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 08:54:20 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 08:54:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 08:54:22 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)) 08:54:22 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)) 08:54:22 INFO - Installing collected packages: simplejson 08:54:22 INFO - Running setup.py install for simplejson 08:54:22 INFO - building 'simplejson._speedups' extension 08:54:22 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 08:54:22 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 08:54:23 INFO - Successfully installed simplejson 08:54:23 INFO - Cleaning up... 08:54:23 INFO - Return code: 0 08:54:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:54:23 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')]} 08:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:23 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')]} 08:54:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:23 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 0x24571f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2620a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c7090>, '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 0x27c7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c1f90>, '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': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', '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 08:54:23 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 08:54:23 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 08:54:23 INFO - Using env: {'DISPLAY': ':0', 08:54:23 INFO - 'HOME': '/home/cltbld', 08:54:23 INFO - 'LANG': 'en_US.UTF-8', 08:54:23 INFO - 'LANGUAGE': 'en_US:en', 08:54:23 INFO - 'LOGNAME': 'cltbld', 08:54:23 INFO - 'MAIL': '/var/mail/cltbld', 08:54:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:23 INFO - 'MOZ_NO_REMOTE': '1', 08:54:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:23 INFO - 'NO_EM_RESTART': '1', 08:54:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:23 INFO - 'PWD': '/builds/slave/test', 08:54:23 INFO - 'SHELL': '/bin/bash', 08:54:23 INFO - 'SHLVL': '1', 08:54:23 INFO - 'TERM': 'linux', 08:54:23 INFO - 'TMOUT': '86400', 08:54:23 INFO - 'USER': 'cltbld', 08:54:23 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', 08:54:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:23 INFO - '_': '/tools/buildbot/bin/python'} 08:54:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:54:23 INFO - Cleaning up... 08:54:23 INFO - Return code: 0 08:54:23 INFO - Running post-action listener: _resource_record_post_action 08:54:23 INFO - Running post-action listener: _start_resource_monitoring 08:54:23 INFO - Starting resource monitoring. 08:54:23 INFO - [mozharness: 2017-09-01 15:54:23.378795Z] Finished create-virtualenv step (success) 08:54:23 INFO - [mozharness: 2017-09-01 15:54:23.379260Z] Running install step. 08:54:23 INFO - Running pre-action listener: _resource_record_pre_action 08:54:23 INFO - Running main action method: install 08:54:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:54:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:54:23 INFO - Reading from file tmpfile_stdout 08:54:23 INFO - Detecting whether we're running mozinstall >=1.0... 08:54:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:54:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:54:23 INFO - Reading from file tmpfile_stdout 08:54:23 INFO - Output received: 08:54:23 INFO - Usage: mozinstall [options] installer 08:54:23 INFO - Options: 08:54:23 INFO - -h, --help show this help message and exit 08:54:23 INFO - -d DEST, --destination=DEST 08:54:23 INFO - Directory to install application into. [default: 08:54:23 INFO - "/builds/slave/test"] 08:54:23 INFO - --app=APP Application being installed. [default: firefox] 08:54:23 INFO - mkdir: /builds/slave/test/build/application 08:54:23 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'] 08:54:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:54:40 INFO - Reading from file tmpfile_stdout 08:54:40 INFO - Output received: 08:54:40 INFO - /builds/slave/test/build/application/firefox/firefox 08:54:40 INFO - Running post-action listener: _resource_record_post_action 08:54:40 INFO - [mozharness: 2017-09-01 15:54:40.195239Z] Finished install step (success) 08:54:40 INFO - [mozharness: 2017-09-01 15:54:40.195486Z] Running setup-mitmproxy step. 08:54:40 INFO - Running pre-action listener: _resource_record_pre_action 08:54:40 INFO - Running main action method: setup_mitmproxy 08:54:40 INFO - Skipping: mitmproxy is not required 08:54:40 INFO - Running post-action listener: _resource_record_post_action 08:54:40 INFO - [mozharness: 2017-09-01 15:54:40.196606Z] Finished setup-mitmproxy step (success) 08:54:40 INFO - [mozharness: 2017-09-01 15:54:40.196824Z] Running run-tests step. 08:54:40 INFO - Running pre-action listener: _resource_record_pre_action 08:54:40 INFO - Running pre-action listener: _set_gcov_prefix 08:54:40 INFO - Running main action method: run_tests 08:54:40 WARNING - Try message not found. 08:54:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:54:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:54:40 INFO - Python 2.7.3 08:54:40 INFO - Return code: 0 08:54:40 INFO - grabbing minidump binary from tooltool 08:54:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:40 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 0x27c7090>, '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 0x27c7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c1f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:54:40 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 08:54:40 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 08:54:40 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 08:54:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:54:40 INFO - Return code: 0 08:54:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:54:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:54:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:54:40 INFO - ENV: RUST_BACKTRACE is now full 08:54:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:54:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:54:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 08:54:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-045 --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 08:54:40 INFO - Using env: {'DISPLAY': ':0', 08:54:40 INFO - 'HOME': '/home/cltbld', 08:54:40 INFO - 'LANG': 'en_US.UTF-8', 08:54:40 INFO - 'LANGUAGE': 'en_US:en', 08:54:40 INFO - 'LOGNAME': 'cltbld', 08:54:40 INFO - 'MAIL': '/var/mail/cltbld', 08:54:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:54:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:54:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:40 INFO - 'MOZ_NO_REMOTE': '1', 08:54:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:54:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:40 INFO - 'NO_EM_RESTART': '1', 08:54:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:40 INFO - 'PWD': '/builds/slave/test', 08:54:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:54:40 INFO - 'RUST_BACKTRACE': 'full', 08:54:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:54:40 INFO - 'SHELL': '/bin/bash', 08:54:40 INFO - 'SHLVL': '1', 08:54:40 INFO - 'TERM': 'linux', 08:54:40 INFO - 'TMOUT': '86400', 08:54:40 INFO - 'USER': 'cltbld', 08:54:40 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', 08:54:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:40 INFO - '_': '/tools/buildbot/bin/python'} 08:54:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 08:54:40 INFO - mozversion application_buildid: 20170901150340 08:54:40 INFO - mozversion application_changeset: cc85091ab81c7eb865d2cfb31539702c1bb7a18d 08:54:40 INFO - mozversion application_display_name: Nightly 08:54:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:54:40 INFO - mozversion application_name: Firefox 08:54:40 INFO - mozversion application_remotingname: firefox 08:54:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:54:40 INFO - mozversion application_vendor: Mozilla 08:54:40 INFO - mozversion application_version: 57.0a1 08:54:40 INFO - mozversion platform_buildid: 20170901150340 08:54:40 INFO - mozversion platform_changeset: cc85091ab81c7eb865d2cfb31539702c1bb7a18d 08:54:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:54:40 INFO - mozversion platform_version: 57.0a1 08:54:40 INFO - using testdate: 1504281280 08:54:40 INFO - actual date: 1504281280 08:54:40 INFO - starting webserver on 'localhost:49226' 08:54:40 INFO - SUITE-START | Running 2 tests 08:54:40 INFO - TEST-START | dromaeo_css 08:54:40 INFO - Initialising browser for dromaeo_css test... 08:54:40 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49226/getInfo.html -profile /tmp/tmpEU_8Cb/profile 08:54:40 INFO - TEST-INFO | started process 30757 (/builds/slave/test/build/application/firefox/firefox http://localhost:49226/getInfo.html) 08:54:46 INFO - TEST-INFO | 30757: exit 0 08:54:46 INFO - Browser initialized. 08:54:46 INFO - Running cycle 1/1 for dromaeo_css test... 08:54:46 INFO - Using env: {'DISPLAY': ':0', 08:54:46 INFO - 'HOME': '/home/cltbld', 08:54:46 INFO - 'JSGC_DISABLE_POISONING': '1', 08:54:46 INFO - 'LANG': 'en_US.UTF-8', 08:54:46 INFO - 'LANGUAGE': 'en_US:en', 08:54:46 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 08:54:46 INFO - 'LOGNAME': 'cltbld', 08:54:46 INFO - 'MAIL': '/var/mail/cltbld', 08:54:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:54:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:54:46 INFO - 'MOZ_CRASHREPORTER': '1', 08:54:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:46 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 08:54:46 INFO - 'MOZ_NO_REMOTE': '1', 08:54:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:54:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:46 INFO - 'NO_EM_RESTART': '1', 08:54:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:46 INFO - 'PWD': '/builds/slave/test', 08:54:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:54:46 INFO - 'RUST_BACKTRACE': 'full', 08:54:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:54:46 INFO - 'SHELL': '/bin/bash', 08:54:46 INFO - 'SHLVL': '1', 08:54:46 INFO - 'TERM': 'linux', 08:54:46 INFO - 'TMOUT': '86400', 08:54:46 INFO - 'USER': 'cltbld', 08:54:46 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', 08:54:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:46 INFO - '_': '/tools/buildbot/bin/python'} 08:54:46 INFO - TEST-INFO | started process 30938 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEU_8Cb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:54:47 INFO - PID 30938 | 08:54:47 INFO - PID 30938 | (/builds/slave/test/build/application/firefox/firefox:30988): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 08:54:47 INFO - PID 30938 | 08:54:57 INFO - PID 30938 | RSS: Main: 169558016 08:54:57 INFO - PID 30938 | 08:57:56 INFO - PID 30938 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 08:57:57 INFO - PID 30938 | RSS: Main: 170008576 08:57:57 INFO - PID 30938 | 09:00:59 INFO - PID 30938 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 09:00:59 INFO - PID 30938 | RSS: Main: 168587264 09:00:59 INFO - PID 30938 | 09:04:01 INFO - PID 30938 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 09:04:02 INFO - PID 30938 | RSS: Main: 171077632 09:04:02 INFO - PID 30938 | 09:07:03 INFO - PID 30938 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 09:07:04 INFO - PID 30938 | RSS: Main: 167763968 09:07:04 INFO - PID 30938 | 09:10:08 INFO - PID 30938 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 09:10:08 INFO - PID 30938 | RSS: Main: 169558016 09:10:08 INFO - PID 30938 | 09:13:10 INFO - PID 30938 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 09:13:10 INFO - PID 30938 | RSS: Main: 173654016 09:13:10 INFO - PID 30938 | 09:13:10 INFO - PID 30938 | __start_tp_report 09:13:10 INFO - PID 30938 | _x_x_mozilla_page_load 09:13:10 INFO - PID 30938 | _x_x_mozilla_page_load_details 09:13:10 INFO - PID 30938 | |i|pagename|runs| 09:13:10 INFO - PID 30938 | |0;dojo.html;3300.438925382685;3303.9339213215744;3312.817795021274;3335.5663763710745;3340.7661463697536;6340.873182536352;6355.618662880219;6385.9999999999945;6392.520560957929;6461.353864613537;6291.591046581969;6390.201224846895;6396.744130234785;6405.807825765234;6431.678416079189;5771.855703607412;5788.5369170466265;5790.363060063411;5800.506956908668;5813.6221145625395;5818.185454036438;5819.7672093116225;5829.9708501457435;5834.854128646796;5924.97037514814;5982.132590774335;5995.100734889779;6005.159277701126;6016.518678505731;6024.156618073452;5949.762009519614;5960.254968128984;5989.460948514655;5993.430624090726;6050.394960503936;5732.971335143339;5797.043487824504;5820.708964551798;5895.64626122434;5920.2895652521975;6197.225346831646;6339.58792678479;6363.7772677956045;6460.709268082947;6535.183102112236;6873.072135261776;6889.689963951634;6895.689693963784;6982.1272340957385;6997.090378250795;6714.227863795677;6832.795016149524;6836.077129587541;7027.437804975641;7027.82430439238;6291.748330066792;6297;6310.5267104967315;6404.583702059304;6488.15653964991;7112.110986126734;7118.679659415338;7123.608201548861;7125.216226215112;7143.7142514299885;6759.2226893906845;6818.079559259536;6851.9657401712675;6854.6229957351825;6868.484863635247;6870.553414028072;6881.862362752717;6896.689648965808;6959.0257363969085;6970.756023539152;6671.132752742113;6757.560758550679;6777.8644427111185;6835.4189893859575;6915.239323674392;6933;6961.060256865258;6975.232724400312;6980.127484064492;6983.511154219156;5432.375276843213;5494.80768173112;5504.862378440571;5514.889702205934;5515.61390702655;4711.528847115261;4743.288506724019;4751.762411879392;4778.976105119452;4812.25410061434;5913.438223368773;6002.789902353396;6027.336992930774;6034.336223015464;6096.329403765582;6993.930060699327;6998.790036298919;7008.264132266038;7010.964945175241;7022.4733145014325;6025.0962355646125;6070.2108725865355;6088.2694076710195;6097.481714054266;6106.5114790817515;5928.436798504135;5940.6435613863305;6009.098635204754;6011.5190784737115;6013.368596297413;6453.290138084901;6733.629650369178;6745.898811517831;6804.455643548505;6831.521793474409;6761.560498567577;6774.187097548326;6778.254392016973;6779.423748981195;6779.830504237236;6483.481321494175;6556.672166391757;6558.934410655832;6567.671616419065;6571.638559879061;6652.301508341748;6654.367835055662;6673.998900165014;6675.732970681119;6678.699458524281;6920.723171073302;6934.375906168469;7015.228324884157;7018.298170183145;7049.823754406181;6708.597484150967;6729.899051514133;6789.388954994074;6829;6832.965835170792;6597.637129957899;6678.799636010927;6704.530682852153;6723.193216814014;6819.727210911508;6596.0435736816935;6626.734930602722;6646.767363142267;6658.667066646552;6671.166104236971;6759.290274521104;6762.323767623198;6785.389314961677;6791.932080679328;6863.691133898887;6548.017797330439;6561.475081993524;6624.735010599522;6636.7013484392355;6647.567908085959 09:13:10 INFO - PID 30938 | |1;ext.html;805.8710606302989;851.1148405658116;883.796726752847;887.8712586674928;1100.2023532938615;29397.85301073497;30138.698613013865;39340.99999999996;40189.39715904264;40359.19281614366;40526;40746;40856.79571602145;40931.386029209585;40944.79527602358;7454.142773581027;7579.1662917079075;7646.464747467679;7648.0822301323915;7655.464117511777;7468.701251949916;7562.810929726746;7569.167391586921;7570.924290757104;7584.5070070445245;7499.850002999937;7556.584387858666;7573.318401343878;7578.317951384374;7585.7344992925255;7530.924690753104;7539.019927409457;7557.168711441792;7567.167611562723;7574.772756817304;7478.813029674247;7524.3228109469865;7529.698812047512;7530.623468826537;7538.73614423498;36598.63401365979;36624.26751464982;36823.815880920694;36881.26237475262;36885.631143688486;3493.458513930345;3520.2431477232517;3523.0487768302414;3523.3834079036064;3525.2068284636007;28950.131496055154;30116.24709382261;30228.09315720532;30293.848530757426;30397.088087357413;5079.187330027215;5087.56755675768;5145.125328694094;5168.793248270064;5183.403908550527;181419;181491;181668;181692.09153954277;182050;46171.845703857;46217.7689111559;46337.536624634;46422;46714;48427;48442;48538.27192592043;48587.271190932166;48762.268565971535;12915;13196.340182990813;14510.347034383478;14613.342399592044;14784.11295322284;12001.339926304137;12063.276203427822;12063.819042714365;12064.457099430547;12069.637910862688;14068.155910645393;14079.577612671636;14100.294985250901;14146;14183.219922904133;5586.162075688598;5602.607817452739;5700.3444603871085;5711;5821.505172060422;1001.104011909337;1013.9340942838692;1022.7749895023084;1031.504877658728;1047.0628787235535;22309.665355019686;22943.541129177323;22958.540829183323;23136.884315578653;23267;1104.7956128116325;1106.6956586938657;1109.4286442482128;1121.6747143328344;1130.559081958037;11943.283402995849;12114.81827772584;12252.196454704856;12275.57035503771;12301.938490307492;224894;225792;227324;228101;228810;55160.44839551633;55687.72156139275;55859.162112568345;55927;56082;1472;1477;1480.822301323848;1481.1779462398472;1485.9034162779385;26713.19860404191;27441.451170976867;27456.725432745417;27492.45015099647;27787.16638500848;37613.623863761015;37675.43486847645;37715.245695085396;37768;37820.24359512849;201425;203239;203400;203423;203424;35139.29721405609;36193.27613447663;36673.08317292089;36721.44917826288;36750.8162459186;43329.13341733211;44334;44814;44849;44943.77528112339;33157.83421082976;33253;33319.33361332808;33404.33191336208;34121;32635.510467343483;32744.50883236801;32812.67187328191;32822.343553128325;34036.65963340434;37594.43608345932;37853.62146378611;37898;37928.620713793614;38053.61946380501;32261;32414.189645259055;32450.188745281557;33491.49762753512;33903.660963391034;24579.631305530787;24930.75069249357;25250.242492725247;25645;25995.61006584865 09:13:10 INFO - PID 30938 | |2;jquery.html;551.2337850387959;558.5308340993564;559.9440055994401;563.3493315220921;572.2589246925232;21602.783972160272;22355.552888942213;22412.88793556032;22459.887700561514;22468.77531224687;25334.619980700303;25955.610665840028;25999.220023399328;26050.73949260507;26052.73947260527;6686.063951046857;6845.760398386057;6853.7258509659805;6856;6857.245702972669;5827.475527202551;6090.543209259301;6538.248101468321;6545.9018114728315;6551.57414768041;6489.967550162266;6494.025896115573;6508.967455162741;6511.4465270452065;6514.641694706789;6482.967585162091;6492.123563318963;6517.837054073639;6518.28298887122;6521.967390163042;6039.758409663608;6052.515798736091;6064.029755239185;6066.817995460143;6067.423594758491;29174.85412572945;29463.558046629318;29678.55482167769;29996.250093747614;30421;2041.612093702192;2062.61841559312;2064.215598072723;2064.597403506317;2067.6174907642135;19630;20254.291099811584;20305.898470507553;20307.79692203074;20325;2414.7343792183024;2480.8015358771254;2510.221831232324;2517.987410062938;2530.03858533756;131366;137520;138260.30869845688;138536.30731846378;138656;14013.789793153112;14491.347889345005;14507.419703211965;15191.924040379949;15215.695686086437;22555.323340299816;23321.183758568368;23332.7666723334;23455.296341109795;23483.7651623485;9708.854367184498;9771.51142442875;9774.853377199348;9870.94212250568;10004.149647280063;8888.866667;8923.643054277756;8960.073584179432;9018.729438116867;9031;8978.86531702025;9236;9425.670101546413;9439.292053096045;9613.134817866425;2612.8301660392012;2740.1365572976288;2747.848868312222;2799.762020228263;2819.3515491437083;969.9951500242454;979.9461029643438;999.0858364596313;1007.8034783217203;1013.2451323763696;23817;25550.488990220092;25580.616290755654;25624.74375256261;25848.74151258501;1050.8003479338902;1065.1585247654266;1070.1599244593112;1079.794838980591;1081.567373050786;11226.382548959886;11515.76968460626;11520.827187592193;11536.76926461466;11898.46456909441;500935;501652;507243;507451;507564;124179.37910310572;127138;127142.36428817797;127355;127876.36061819817;1260.0612543655036;1278.3991523983714;1282.9679258018436;1286.5111257722006;1291.4963164365904;18663.16015779266;18870;18887;18890.622187556448;19395.418137455898;52064.739676301375;60800.391996079474;65661;65851.34148658453;67661.3233867655;79660;80398.5980070096;82257;83179.5841020791;84102.57948710218;21090.683639745723;21158.365249042552;21387.0375833091;21553.676694849277;21957.231496897533;19608.80391196127;21225.787742122382;24069.157579485334;24127.638085429084;24298;18031.729524057413;18806.153723082767;18825.77632453886;18866.905665471586;19210;16732.247048883073;17219.22513486921;17287.7406838895;17376.218070187126;17764.022978735775;18686.252549898396;19136.23455061782;19360.903195483934;19373.51566210856;19623.11695973656;20994.790052099284;21135.8943205283;21398.144074236858;21468.892655536623;21477.89261053685;16276.10481423533;16440.095794731405;16444.917775411046;16445.424410145588;16543.586410339838 09:13:10 INFO - PID 30938 | |3;mootools.html;1333.1667707682698;1378.111118328678;1386.3484162443654;1392.3595146232744;1405.7750759878422;14307.213103279315;14801.555953321391;14815.629609259788;14921.179335136563;15152.621184470394;15652.452164174256;15914.283857226425;15927.761083583755;15929.283182256797;15933.20333983302;2585.1598230575046;2701.2706569226298;2760.944781104387;2765.612814206013;2767.3081729567607;2759.365345970428;2787.818791778528;2791.860406979653;2791.9860400697967;2793.064323451646;2633.5917932720363;2678.651775269212;2817.8872845086275;2838.5600231964077;2852.87162077707;2608.804339674532;2621.986890065547;2635.235781623327;2639.4325220077776;2641.828281161718;2573.5882258838496;2602.2323414592743;2613.1899111275375;2614.830036047651;2617.5419301622333;14940;15279.159646219454;15286.694266114615;15301.846981530154;15301.923490382476;7859;7963.761087167394;8198.057223419324;8244.876326855101;8247.092819789817;5559.082751346023;5676.7183686323815;5755.856103597402;5828.271466066742;5838.941610583882;1828.0859570214893;1841.300305883769;1852.9629407411776;1875.9906200469047;1885.9339923102625;26735.19794406171;26943.595846062326;26948.326291842666;26962.730372696216;26992.1902342932;6723.226828914738;6729.384947612635;6908.792736217922;6941.68762405693;6947.930520694728;17053.31786728542;17070.658586828442;17075.48773536796;17157.57106072333;17176.31294748221;7997.800054998672;8142.389320800964;8150.470219435718;8154.062282837431;8160.714374996846;7331.193568707438;7501.024866767285;7513.39892808574;7517.511361761468;7542.132654744665;5104.744762761847;5342.519173274346;5354;5383.327084114486;5386.380566234933;1532.578540901261;1542.1826431991062;1551.2786554252327;1566.0525382143867;1589.6105454163803;881.9162179592928;911.093462005309;927.7541451515353;936.784539555906;940.8776859008285;7481.364084052808;7677.846443071107;7707.190744971697;7724.188960159215;7855.214478552099;719.4927576058866;726.5241266970143;732.9322261448937;739.3235189801379;744.9590272534954;4605.42407739267;4678.345031695565;5457.372402173801;5649.519790817826;5704.657720536781;21443.356699299045;21497.67753483699;21675.132994680036;21833.12667493314;22012.889935550542;22108;22417;22694.205702800322;22727.772722272566;22752.20367287137;10725.83911241332;10741.785164296827;11016.779664406668;11027.779444411068;11113.444327783578;8137.7965550859335;8622.439541429787;8640.481571105754;8695.652173913226;8793.560321984003;18808.52978675544;18835.90582047081;18865.434036978913;18866.622667546846;18942.52643683919;19136.425907222805;19187.424377268704;19190;19211.231550737815;19324.13041413168;11984.041276698017;12368.690782730144;12378.133530652767;12386.62840114798;12399.132060756021;13589.388477518338;13825.10136841102;13964.4414223434;13969.231692257023;13980;10607.893921060691;10713;10744.247902646739;10774.353538787698;10960.12319014461;11144.60993865211;11278.154138439813;11455.484503197542;11468.254563453349;11501.137414693763;9165.587548560197;9237.722868313962;9250.167484926389;9251.629934802533;9306.627734890528;6840.110785597841;6966.3730264276455;6974.965125174342;6988.79033628992;7106.111736032996;6875.896861547181;6915.308469153045;6964.721411143498;7019.824504387227;7060.117485314336 09:13:10 INFO - PID 30938 | |4;prototype.html;493.644575905348;500.04740968455104;525.4797750227275;532.5526557691538;535.6197100058957;6467.353264673537;6485.286618471964;6516.315786842384;6527.2167339919215;6562.506247625902;6116.847078823032;6248.468880145193;6292.905606415896;6312.842178945529;6330.525210609199;5290.338707661543;5325.147976323789;5355.758990845402;5364.168553874156;5367.382750983628;5269.8946021079555;5293.179557168645;5296.8146114885985;5302.469753024705;5310.203469479589;5125.641205115643;5263.394709608387;5277.815276465324;5289.8942021159555;5315.601329900253;5324.787008519655;5337.5996800239745;5343.145096784535;5356.812511562096;5370.812021579226;4943.406791185081;4977.328060711794;4999.550040496373;5003.774830132616;5012.1980483122525;6328.702940574158;6336.556441049129;6367.376319038615;6368.872622547523;6405.135306733578;6142.96928515359;6167.7532898684;6173.660448675321;6176.320604733521;6197.16338294329;6747.426468750162;6767.661616919184;6775.864482710367;6785.355391237825;6843.213030501506;5338.866528336784;5371.301730775013;5379.515843574058;5379.9731001345135;5408.432114627986;6970.651467426608;7043.859122817515;7129.928700712979;7159.319864612853;7171.89242161368;5610.803621873215;5640.8154287599245;5644.463775941279;5645.830625081172;5649.971750141223;5579.637323574036;5582.888342233133;5584.329880414295;5667.716614169275;5694.686792226467;6033.758649653964;6049.340149209753;6052.485538729169;6099.634021958696;6144.969275153596;6758.68824276111;6765.323467653294;6784.966075169593;6810.693518791666;6832.624205668637;1813.8820976636475;1856.5544269375252;1950.5123719070232;1960.6764883794015;1975.2691504143559;1366.3509832829525;1419.8012278281049;1461.5457619668498;1463.6706740983407;1476.60131764423;5764.798232061858;5777.7977770777825;5842.766289348463;5951.761929522857;5978.459154859254;6626.680661768342;6648.800535983928;6730.360615741497;6771.762988295386;6776.864462710718;5788.408187748319;5802.709864506842;5841.357450680422;5909.188573370152;6024.819255422344;3591.964080359163;3606.2246616977477;3627.981860090683;3643.961470980758;3658.3963645998642;6487.599588152414;6566.967165164144;6655.334466553403;6670.799876003728;6692.631905245258;6913.930860691429;6951.165860096719;6960;6973.755918542826;7050.15398152225;6573.868522629521;6594.175728033996;6596.901046484307;6680.331966803281;6682.69927853238;6770.627615480998;6771.356721111487;6783.966080169567;6850.9314906850295;6883.89857622778;6628.5028622852515;6641.933580664325;6680.331966803281;6704.66476676174;6771.28901465339;6554.180727409074;6592;6658.300878407697;6676.9332306676315;6718.294579069127;5478.986387518322;5498.45015498463;5532.253145825423;5600;5615.2700148980375;6757.560758550679;6843.247242803387;6843.897341539781;6877.724891004304;6885.724571017103;6590.604563726191;6591.703373348115;6606.207255129223;6606.339366063356;6639.900401494078;6375.426211640789;6437.613743175424;6537.836554085996;6574.539782215199;6657.234418041987;6301.873962520815;6551.639659818756;6581.473482121514;6608.471322294301;6626.421732264524;6697.430718388991;6702.162229721285;6711.295313991961;6711.765088221889;6712.463002959849;6420.2937676856445;6481.902771458337;6506.284308725942;6526.543141980016;6566.606003639797 09:13:10 INFO - PID 30938 | |5;yui.html;899.4693131052682;909.9044600316967;924.2375040591505;927.0636656976452;930.0838673906201;16926.576835579115;17151.914240428796;17152.313907443688;17204.139793010345;18589.721154182665;18656.533586660342;18658;18699.532511687215;18737;18779;958.6644674363987;977.4917043137564;979.3144798640944;981.8281800684887;983.5868935047297;941.5339406993513;977.569869257528;985.9112679858847;986.058314309836;986.3145114145672;1054.4833031814433;1056.1128651932374;1057.2757661002238;1057.376148072637;1057.60868478663;1050.5482642463737;1050.6112738286884;1051.9000694934007;1051.9368837869752;1052.2266134391216;771.6026246483066;772.0272456704537;772.142921357293;772.3512249710215;774.5159275452842;16960.40638577656;17265.13674316279;18110.63778724431;18213.99823009734;18280.994545300004;18859.057047147726;19113.139908704143;19376.80623193764;19407.51481212967;19557.80442195574;15088;15331.923340383226;15495.302711377903;15620.76568851468;15625.296861641253;3752.6810221131236;3802.1445174835717;3807.447920051577;3808.542974843009;3818.0264032671766;126327;127479.36260318731;128122.35938820339;129005.35497322453;130146.34926825305;15290.770638440432;15310.234488275542;15424.228788560527;15618.531444056696;15700.528984130495;30895.536566951512;31142.84428577888;32031.67968320287;32038.83980580082;32045;8659;8782.648694052166;8787.077356877564;8787.209151176425;8801.603927823264;3468.236987862666;3654.9634503655157;4052.1287923096675;4062.1875624875142;4063.817128229237;5150.381954165449;5277.551408130352;5277.920831187458;5297.020051290524;5297.231901374279;1038.6416686243233;1052.2634156090767;1059.2320567588438;1073.3238060022136;1076.235872530512;691.6196092149398;705.4109818301673;709.8854797967142;715.3704739828918;718.9352958233679;10149.898501015043;10190.796184076276;10311.175105991502;10342.224333174892;10553.419561924167;678.375894177358;699.8075529229502;702.1433850702135;715.677944924786;716.3338095571169;5124.897502049938;5187.662801917863;5256.211568264791;5264.710440925785;5416.322959630046;106331.32521100264;107295.4635226819;109425.45287273671;113256;113415.43292283651;93501.53249233711;96814;97743;99529.50235248922;102418;85489.57255213684;85870.57064714762;86247.13752862517;88611.55694221487;90352;10077.546510407195;10079.596816127272;10257.538410771682;10340.482975851028;10409.895901041196;41761.79119104385;41781.37327940023;42271.365929511696;43023.56976430196;43131.568684312755;70296.6485167571;72933.27066729264;73537;73572;77152.61423692845;16659.416920407726;16694.916525417295;16726.916365418096;16925.66148676995;17140.742888856428;15171.24143792828;21205.78794212038;22021.33935981923;22113.115475380804;22117.115315387204;13962.720745585235;14364.425422982964;14724;15617.375304987674;15650.765238521659;15854.44509442164;15979.680406392039;15985.200739962722;16103.516894493185;16138.112403817433;14077.366518506897;14187.14877107377;14188.361523731663;14299.356528956014;14351.354189061725;11717.76564468723;11920.403979801149;11968.700782480159;12384.81422778641;12462.252264864137;11507.252028618113;11559.537618495166;11596.36220007874;11644.91409776427;11736.589219377282 09:13:10 INFO - PID 30938 | __end_tp_report 09:13:10 INFO - PID 30938 | __start_cc_report 09:13:10 INFO - PID 30938 | _x_x_mozilla_cycle_collect,361 09:13:10 INFO - PID 30938 | __end_cc_report 09:13:10 INFO - PID 30938 | __startTimestamp1504282390720__endTimestamp 09:13:10 INFO - PID 30938 | 09:13:10 INFO - PID 30938 | ------- Summary: start ------- 09:13:10 INFO - PID 30938 | Number of tests: 6 09:13:10 INFO - PID 30938 | 09:13:10 INFO - PID 30938 | [#0] dojo.html Cycles:165 Average:6341.75 Median:6571.64 stddev:745.97 (11.4%) stddev-sans-first:709.08 09:13:10 INFO - PID 30938 | Values: 3300.4 3303.9 3312.8 3335.6 3340.8 6340.9 6355.6 6386.0 6392.5 6461.4 6291.6 6390.2 6396.7 6405.8 6431.7 5771.9 5788.5 5790.4 5800.5 5813.6 5818.2 5819.8 5830.0 5834.9 5925.0 5982.1 5995.1 6005.2 6016.5 6024.2 5949.8 5960.3 5989.5 5993.4 6050.4 5733.0 5797.0 5820.7 5895.6 5920.3 6197.2 6339.6 6363.8 6460.7 6535.2 6873.1 6889.7 6895.7 6982.1 6997.1 6714.2 6832.8 6836.1 7027.4 7027.8 6291.7 6297.0 6310.5 6404.6 6488.2 7112.1 7118.7 7123.6 7125.2 7143.7 6759.2 6818.1 6852.0 6854.6 6868.5 6870.6 6881.9 6896.7 6959.0 6970.8 6671.1 6757.6 6777.9 6835.4 6915.2 6933.0 6961.1 6975.2 6980.1 6983.5 5432.4 5494.8 5504.9 5514.9 5515.6 4711.5 4743.3 4751.8 4779.0 4812.3 5913.4 6002.8 6027.3 6034.3 6096.3 6993.9 6998.8 7008.3 7011.0 7022.5 6025.1 6070.2 6088.3 6097.5 6106.5 5928.4 5940.6 6009.1 6011.5 6013.4 6453.3 6733.6 6745.9 6804.5 6831.5 6761.6 6774.2 6778.3 6779.4 6779.8 6483.5 6556.7 6558.9 6567.7 6571.6 6652.3 6654.4 6674.0 6675.7 6678.7 6920.7 6934.4 7015.2 7018.3 7049.8 6708.6 6729.9 6789.4 6829.0 6833.0 6597.6 6678.8 6704.5 6723.2 6819.7 6596.0 6626.7 6646.8 6658.7 6671.2 6759.3 6762.3 6785.4 6791.9 6863.7 6548.0 6561.5 6624.7 6636.7 6647.6 09:13:10 INFO - PID 30938 | 09:13:10 INFO - PID 30938 | [#1] ext.html Cycles:180 Average:37389.22 Median:3521.65 stddev:53024.48 (1505.7%) stddev-sans-first:53102.07 09:13:10 INFO - PID 30938 | Values: 805.9 851.1 883.8 887.9 1100.2 29397.9 30138.7 39341.0 40189.4 40359.2 40526.0 40746.0 40856.8 40931.4 40944.8 7454.1 7579.2 7646.5 7648.1 7655.5 7468.7 7562.8 7569.2 7570.9 7584.5 7499.9 7556.6 7573.3 7578.3 7585.7 7530.9 7539.0 7557.2 7567.2 7574.8 7478.8 7524.3 7529.7 7530.6 7538.7 36598.6 36624.3 36823.8 36881.3 36885.6 3493.5 3520.2 3523.0 3523.4 3525.2 28950.1 30116.2 30228.1 30293.8 30397.1 5079.2 5087.6 5145.1 5168.8 5183.4 181419.0 181491.0 181668.0 181692.1 182050.0 46171.8 46217.8 46337.5 46422.0 46714.0 48427.0 48442.0 48538.3 48587.3 48762.3 12915.0 13196.3 14510.3 14613.3 14784.1 12001.3 12063.3 12063.8 12064.5 12069.6 14068.2 14079.6 14100.3 14146.0 14183.2 5586.2 5602.6 5700.3 5711.0 5821.5 1001.1 1013.9 1022.8 1031.5 1047.1 22309.7 22943.5 22958.5 23136.9 23267.0 1104.8 1106.7 1109.4 1121.7 1130.6 11943.3 12114.8 12252.2 12275.6 12301.9 224894.0 225792.0 227324.0 228101.0 228810.0 55160.4 55687.7 55859.2 55927.0 56082.0 1472.0 1477.0 1480.8 1481.2 1485.9 26713.2 27441.5 27456.7 27492.5 27787.2 37613.6 37675.4 37715.2 37768.0 37820.2 201425.0 203239.0 203400.0 203423.0 203424.0 35139.3 36193.3 36673.1 36721.4 36750.8 43329.1 44334.0 44814.0 44849.0 44943.8 33157.8 33253.0 33319.3 33404.3 34121.0 32635.5 32744.5 32812.7 32822.3 34036.7 37594.4 37853.6 37898.0 37928.6 38053.6 32261.0 32414.2 32450.2 33491.5 33903.7 24579.6 24930.8 25250.2 25645.0 25995.6 09:13:10 INFO - PID 30938 | 09:13:10 INFO - PID 30938 | [#2] jquery.html Cycles:180 Average:36478.86 Median:23943.08 stddev:85237.33 (356.0%) stddev-sans-first:85433.76 09:13:10 INFO - PID 30938 | Values: 551.2 558.5 559.9 563.3 572.3 21602.8 22355.6 22412.9 22459.9 22468.8 25334.6 25955.6 25999.2 26050.7 26052.7 6686.1 6845.8 6853.7 6856.0 6857.2 5827.5 6090.5 6538.2 6545.9 6551.6 6490.0 6494.0 6509.0 6511.4 6514.6 6483.0 6492.1 6517.8 6518.3 6522.0 6039.8 6052.5 6064.0 6066.8 6067.4 29174.9 29463.6 29678.6 29996.3 30421.0 2041.6 2062.6 2064.2 2064.6 2067.6 19630.0 20254.3 20305.9 20307.8 20325.0 2414.7 2480.8 2510.2 2518.0 2530.0 131366.0 137520.0 138260.3 138536.3 138656.0 14013.8 14491.3 14507.4 15191.9 15215.7 22555.3 23321.2 23332.8 23455.3 23483.8 9708.9 9771.5 9774.9 9870.9 10004.1 8888.9 8923.6 8960.1 9018.7 9031.0 8978.9 9236.0 9425.7 9439.3 9613.1 2612.8 2740.1 2747.8 2799.8 2819.4 970.0 979.9 999.1 1007.8 1013.2 23817.0 25550.5 25580.6 25624.7 25848.7 1050.8 1065.2 1070.2 1079.8 1081.6 11226.4 11515.8 11520.8 11536.8 11898.5 500935.0 501652.0 507243.0 507451.0 507564.0 124179.4 127138.0 127142.4 127355.0 127876.4 1260.1 1278.4 1283.0 1286.5 1291.5 18663.2 18870.0 18887.0 18890.6 19395.4 52064.7 60800.4 65661.0 65851.3 67661.3 79660.0 80398.6 82257.0 83179.6 84102.6 21090.7 21158.4 21387.0 21553.7 21957.2 19608.8 21225.8 24069.2 24127.6 24298.0 18031.7 18806.2 18825.8 18866.9 19210.0 16732.2 17219.2 17287.7 17376.2 17764.0 18686.3 19136.2 19360.9 19373.5 19623.1 20994.8 21135.9 21398.1 21468.9 21477.9 16276.1 16440.1 16444.9 16445.4 16543.6 09:13:10 INFO - PID 30938 | 09:13:10 INFO - PID 30938 | [#3] mootools.html Cycles:180 Average:9379.47 Median:2640.63 stddev:6786.21 (257.0%) stddev-sans-first:6778.32 09:13:10 INFO - PID 30938 | Values: 1333.2 1378.1 1386.3 1392.4 1405.8 14307.2 14801.6 14815.6 14921.2 15152.6 15652.5 15914.3 15927.8 15929.3 15933.2 2585.2 2701.3 2760.9 2765.6 2767.3 2759.4 2787.8 2791.9 2792.0 2793.1 2633.6 2678.7 2817.9 2838.6 2852.9 2608.8 2622.0 2635.2 2639.4 2641.8 2573.6 2602.2 2613.2 2614.8 2617.5 14940.0 15279.2 15286.7 15301.8 15301.9 7859.0 7963.8 8198.1 8244.9 8247.1 5559.1 5676.7 5755.9 5828.3 5838.9 1828.1 1841.3 1853.0 1876.0 1885.9 26735.2 26943.6 26948.3 26962.7 26992.2 6723.2 6729.4 6908.8 6941.7 6947.9 17053.3 17070.7 17075.5 17157.6 17176.3 7997.8 8142.4 8150.5 8154.1 8160.7 7331.2 7501.0 7513.4 7517.5 7542.1 5104.7 5342.5 5354.0 5383.3 5386.4 1532.6 1542.2 1551.3 1566.1 1589.6 881.9 911.1 927.8 936.8 940.9 7481.4 7677.8 7707.2 7724.2 7855.2 719.5 726.5 732.9 739.3 745.0 4605.4 4678.3 5457.4 5649.5 5704.7 21443.4 21497.7 21675.1 21833.1 22012.9 22108.0 22417.0 22694.2 22727.8 22752.2 10725.8 10741.8 11016.8 11027.8 11113.4 8137.8 8622.4 8640.5 8695.7 8793.6 18808.5 18835.9 18865.4 18866.6 18942.5 19136.4 19187.4 19190.0 19211.2 19324.1 11984.0 12368.7 12378.1 12386.6 12399.1 13589.4 13825.1 13964.4 13969.2 13980.0 10607.9 10713.0 10744.2 10774.4 10960.1 11144.6 11278.2 11455.5 11468.3 11501.1 9165.6 9237.7 9250.2 9251.6 9306.6 6840.1 6966.4 6975.0 6988.8 7106.1 6875.9 6915.3 6964.7 7019.8 7060.1 09:13:10 INFO - PID 30938 | 09:13:10 INFO - PID 30938 | [#4] prototype.html Cycles:180 Average:5734.67 Median:6329.61 stddev:1529.13 (24.2%) stddev-sans-first:1481.96 09:13:10 INFO - PID 30938 | Values: 493.6 500.0 525.5 532.6 535.6 6467.4 6485.3 6516.3 6527.2 6562.5 6116.8 6248.5 6292.9 6312.8 6330.5 5290.3 5325.1 5355.8 5364.2 5367.4 5269.9 5293.2 5296.8 5302.5 5310.2 5125.6 5263.4 5277.8 5289.9 5315.6 5324.8 5337.6 5343.1 5356.8 5370.8 4943.4 4977.3 4999.6 5003.8 5012.2 6328.7 6336.6 6367.4 6368.9 6405.1 6143.0 6167.8 6173.7 6176.3 6197.2 6747.4 6767.7 6775.9 6785.4 6843.2 5338.9 5371.3 5379.5 5380.0 5408.4 6970.7 7043.9 7129.9 7159.3 7171.9 5610.8 5640.8 5644.5 5645.8 5650.0 5579.6 5582.9 5584.3 5667.7 5694.7 6033.8 6049.3 6052.5 6099.6 6145.0 6758.7 6765.3 6785.0 6810.7 6832.6 1813.9 1856.6 1950.5 1960.7 1975.3 1366.4 1419.8 1461.5 1463.7 1476.6 5764.8 5777.8 5842.8 5951.8 5978.5 6626.7 6648.8 6730.4 6771.8 6776.9 5788.4 5802.7 5841.4 5909.2 6024.8 3592.0 3606.2 3628.0 3644.0 3658.4 6487.6 6567.0 6655.3 6670.8 6692.6 6913.9 6951.2 6960.0 6973.8 7050.2 6573.9 6594.2 6596.9 6680.3 6682.7 6770.6 6771.4 6784.0 6850.9 6883.9 6628.5 6641.9 6680.3 6704.7 6771.3 6554.2 6592.0 6658.3 6676.9 6718.3 5479.0 5498.5 5532.3 5600.0 5615.3 6757.6 6843.2 6843.9 6877.7 6885.7 6590.6 6591.7 6606.2 6606.3 6639.9 6375.4 6437.6 6537.8 6574.5 6657.2 6301.9 6551.6 6581.5 6608.5 6626.4 6697.4 6702.2 6711.3 6711.8 6712.5 6420.3 6481.9 6506.3 6526.5 6566.6 09:13:10 INFO - PID 30938 | 09:13:10 INFO - PID 30938 | [#5] yui.html Cycles:180 Average:23246.87 Median:18758.00 stddev:32750.44 (174.6%) stddev-sans-first:32799.33 09:13:10 INFO - PID 30938 | Values: 899.5 909.9 924.2 927.1 930.1 16926.6 17151.9 17152.3 17204.1 18589.7 18656.5 18658.0 18699.5 18737.0 18779.0 958.7 977.5 979.3 981.8 983.6 941.5 977.6 985.9 986.1 986.3 1054.5 1056.1 1057.3 1057.4 1057.6 1050.5 1050.6 1051.9 1051.9 1052.2 771.6 772.0 772.1 772.4 774.5 16960.4 17265.1 18110.6 18214.0 18281.0 18859.1 19113.1 19376.8 19407.5 19557.8 15088.0 15331.9 15495.3 15620.8 15625.3 3752.7 3802.1 3807.4 3808.5 3818.0 126327.0 127479.4 128122.4 129005.4 130146.3 15290.8 15310.2 15424.2 15618.5 15700.5 30895.5 31142.8 32031.7 32038.8 32045.0 8659.0 8782.6 8787.1 8787.2 8801.6 3468.2 3655.0 4052.1 4062.2 4063.8 5150.4 5277.6 5277.9 5297.0 5297.2 1038.6 1052.3 1059.2 1073.3 1076.2 691.6 705.4 709.9 715.4 718.9 10149.9 10190.8 10311.2 10342.2 10553.4 678.4 699.8 702.1 715.7 716.3 5124.9 5187.7 5256.2 5264.7 5416.3 106331.3 107295.5 109425.5 113256.0 113415.4 93501.5 96814.0 97743.0 99529.5 102418.0 85489.6 85870.6 86247.1 88611.6 90352.0 10077.5 10079.6 10257.5 10340.5 10409.9 41761.8 41781.4 42271.4 43023.6 43131.6 70296.6 72933.3 73537.0 73572.0 77152.6 16659.4 16694.9 16726.9 16925.7 17140.7 15171.2 21205.8 22021.3 22113.1 22117.1 13962.7 14364.4 14724.0 15617.4 15650.8 15854.4 15979.7 15985.2 16103.5 16138.1 14077.4 14187.1 14188.4 14299.4 14351.4 11717.8 11920.4 11968.7 12384.8 12462.3 11507.3 11559.5 11596.4 11644.9 11736.6 09:13:10 INFO - PID 30938 | -------- Summary: end -------- 09:13:10 INFO - PID 30938 | 09:13:11 INFO - TEST-INFO | 30938: exit 0 09:13:11 INFO - TEST-OK | dromaeo_css | took 1110948ms 09:13:11 INFO - TEST-START | kraken 09:13:11 INFO - Initialising browser for kraken test... 09:13:11 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49226/getInfo.html -profile /tmp/tmpQ0Oooj/profile 09:13:11 INFO - TEST-INFO | started process 31307 (/builds/slave/test/build/application/firefox/firefox http://localhost:49226/getInfo.html) 09:13:16 INFO - TEST-INFO | 31307: exit 0 09:13:16 INFO - Browser initialized. 09:13:16 INFO - Running cycle 1/1 for kraken test... 09:13:16 INFO - Using env: {'DISPLAY': ':0', 09:13:16 INFO - 'HOME': '/home/cltbld', 09:13:16 INFO - 'JSGC_DISABLE_POISONING': '1', 09:13:16 INFO - 'LANG': 'en_US.UTF-8', 09:13:16 INFO - 'LANGUAGE': 'en_US:en', 09:13:16 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:13:16 INFO - 'LOGNAME': 'cltbld', 09:13:16 INFO - 'MAIL': '/var/mail/cltbld', 09:13:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:13:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:13:16 INFO - 'MOZ_CRASHREPORTER': '1', 09:13:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:16 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:13:16 INFO - 'MOZ_NO_REMOTE': '1', 09:13:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:13:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:16 INFO - 'NO_EM_RESTART': '1', 09:13:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:16 INFO - 'PWD': '/builds/slave/test', 09:13:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:13:16 INFO - 'RUST_BACKTRACE': 'full', 09:13:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:13:16 INFO - 'SHELL': '/bin/bash', 09:13:16 INFO - 'SHLVL': '1', 09:13:16 INFO - 'TERM': 'linux', 09:13:16 INFO - 'TMOUT': '86400', 09:13:16 INFO - 'USER': 'cltbld', 09:13:16 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504281210.647201-1575338579', 09:13:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:16 INFO - '_': '/tools/buildbot/bin/python'} 09:13:16 INFO - TEST-INFO | started process 31490 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQ0Oooj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:13:17 INFO - PID 31490 | 09:13:17 INFO - PID 31490 | (/builds/slave/test/build/application/firefox/firefox:31541): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:13:17 INFO - PID 31490 | 09:13:28 INFO - PID 31490 | RSS: Main: 169951232 09:13:28 INFO - PID 31490 | 09:13:30 INFO - PID 31490 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 09:13:31 INFO - PID 31490 | RSS: Main: 174489600 09:13:31 INFO - PID 31490 | 09:13:34 INFO - PID 31490 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 09:13:35 INFO - PID 31490 | RSS: Main: 174895104 09:13:35 INFO - PID 31490 | 09:13:38 INFO - PID 31490 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 09:13:38 INFO - PID 31490 | RSS: Main: 168923136 09:13:38 INFO - PID 31490 | 09:13:40 INFO - PID 31490 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 09:13:41 INFO - PID 31490 | RSS: Main: 170004480 09:13:41 INFO - PID 31490 | 09:13:43 INFO - PID 31490 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:13:44 INFO - PID 31490 | RSS: Main: 170172416 09:13:44 INFO - PID 31490 | 09:13:47 INFO - PID 31490 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 09:13:47 INFO - PID 31490 | RSS: Main: 172912640 09:13:47 INFO - PID 31490 | 09:13:51 INFO - PID 31490 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 09:13:51 INFO - PID 31490 | RSS: Main: 173297664 09:13:51 INFO - PID 31490 | 09:13:54 INFO - PID 31490 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 09:13:55 INFO - PID 31490 | RSS: Main: 173744128 09:13:55 INFO - PID 31490 | 09:13:57 INFO - PID 31490 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:13:57 INFO - PID 31490 | RSS: Main: 173187072 09:13:57 INFO - PID 31490 | 09:13:59 INFO - PID 31490 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:14:00 INFO - PID 31490 | RSS: Main: 173248512 09:14:00 INFO - PID 31490 | 09:14:02 INFO - PID 31490 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:14:02 INFO - PID 31490 | RSS: Main: 170340352 09:14:02 INFO - PID 31490 | 09:14:05 INFO - PID 31490 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:14:06 INFO - PID 31490 | RSS: Main: 170622976 09:14:06 INFO - PID 31490 | 09:14:09 INFO - PID 31490 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:14:09 INFO - PID 31490 | RSS: Main: 171061248 09:14:09 INFO - PID 31490 | 09:14:11 INFO - PID 31490 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 09:14:11 INFO - PID 31490 | RSS: Main: 172515328 09:14:11 INFO - PID 31490 | 09:14:11 INFO - PID 31490 | __start_tp_report 09:14:11 INFO - PID 31490 | _x_x_mozilla_page_load 09:14:11 INFO - PID 31490 | _x_x_mozilla_page_load_details 09:14:11 INFO - PID 31490 | |i|pagename|runs| 09:14:11 INFO - PID 31490 | |0;ai-astar;99;107;99;111;107;113;108;98;106;109 09:14:11 INFO - PID 31490 | |1;audio-beat-detection;149;115;152;168;116;141;148;118;167;118 09:14:11 INFO - PID 31490 | |2;audio-dft;165;163;161;162;162;161;162;165;168;169 09:14:11 INFO - PID 31490 | |3;audio-fft;75;75;74;176;74;75;74;74;154;75 09:14:11 INFO - PID 31490 | |4;audio-oscillator;79;78;131;80;79;79;129;79;82;128 09:14:11 INFO - PID 31490 | |5;imaging-gaussian-blur;114;115;114;114;114;115;114;115;114;114 09:14:11 INFO - PID 31490 | |6;imaging-darkroom;147;147;157;148;147;150;150;147;149;147 09:14:11 INFO - PID 31490 | |7;imaging-desaturate;94;94;94;94;93;94;94;94;93;94 09:14:11 INFO - PID 31490 | |8;json-parse-financial;53;53;55;55;54;56;54;54;54;54 09:14:11 INFO - PID 31490 | |9;json-stringify-tinderbox;38;39;38;39;38;38;37;38;38;38 09:14:11 INFO - PID 31490 | |10;stanford-crypto-aes;74;75;79;77;80;74;75;75;76;76 09:14:11 INFO - PID 31490 | |11;stanford-crypto-ccm;122;128;119;129;118;118;125;118;126;170 09:14:11 INFO - PID 31490 | |12;stanford-crypto-pbkdf2;178;179;175;172;171;172;173;171;168;169 09:14:11 INFO - PID 31490 | |13;stanford-crypto-sha256-iterative;68;69;66;65;65;64;64;66;63;65 09:14:11 INFO - PID 31490 | __end_tp_report 09:14:11 INFO - PID 31490 | __start_cc_report 09:14:11 INFO - PID 31490 | _x_x_mozilla_cycle_collect,963 09:14:11 INFO - PID 31490 | __end_cc_report 09:14:11 INFO - PID 31490 | __startTimestamp1504282451662__endTimestamp 09:14:11 INFO - PID 31490 | 09:14:11 INFO - PID 31490 | ------- Summary: start ------- 09:14:11 INFO - PID 31490 | Number of tests: 14 09:14:11 INFO - PID 31490 | 09:14:11 INFO - PID 31490 | [#0] ai-astar Cycles:10 Average:105.70 Median:112.00 stddev:5.27 (4.7%) stddev-sans-first:5.00 09:14:11 INFO - PID 31490 | Values: 99.0 107.0 99.0 111.0 107.0 113.0 108.0 98.0 106.0 109.0 09:14:11 INFO - PID 31490 | 09:14:11 INFO - PID 31490 | [#1] audio-beat-detection Cycles:10 Average:139.20 Median:148.50 stddev:20.99 (14.1%) stddev-sans-first:21.96 09:14:11 INFO - PID 31490 | Values: 149.0 115.0 152.0 168.0 116.0 141.0 148.0 118.0 167.0 118.0 09:14:11 INFO - PID 31490 | 09:14:11 INFO - PID 31490 | [#2] audio-dft Cycles:10 Average:163.80 Median:164.00 stddev:2.86 (1.7%) stddev-sans-first:3.00 09:14:11 INFO - PID 31490 | Values: 165.0 163.0 161.0 162.0 162.0 161.0 162.0 165.0 168.0 169.0 09:14:11 INFO - PID 31490 | 09:14:11 INFO - PID 31490 | [#3] audio-fft Cycles:10 Average:92.60 Median:74.50 stddev:38.51 (51.7%) stddev-sans-first:40.32 09:14:11 INFO - PID 31490 | Values: 75.0 75.0 74.0 176.0 74.0 75.0 74.0 74.0 154.0 75.0 09:14:11 INFO - PID 31490 | 09:14:11 INFO - PID 31490 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:79.00 stddev:24.14 (30.6%) stddev-sans-first:24.95 09:14:11 INFO - PID 31490 | Values: 79.0 78.0 131.0 80.0 79.0 79.0 129.0 79.0 82.0 128.0 09:14:11 INFO - PID 31490 | 09:14:11 INFO - PID 31490 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 09:14:11 INFO - PID 31490 | Values: 114.0 115.0 114.0 114.0 114.0 115.0 114.0 115.0 114.0 114.0 09:14:11 INFO - PID 31490 | 09:14:11 INFO - PID 31490 | [#6] imaging-darkroom Cycles:10 Average:148.90 Median:148.50 stddev:3.11 (2.1%) stddev-sans-first:3.22 09:14:11 INFO - PID 31490 | Values: 147.0 147.0 157.0 148.0 147.0 150.0 150.0 147.0 149.0 147.0 09:14:11 INFO - PID 31490 | 09:14:11 INFO - PID 31490 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:14:11 INFO - PID 31490 | Values: 94.0 94.0 94.0 94.0 93.0 94.0 94.0 94.0 93.0 94.0 09:14:11 INFO - PID 31490 | 09:14:11 INFO - PID 31490 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:0.92 (1.7%) stddev-sans-first:0.87 09:14:11 INFO - PID 31490 | Values: 53.0 53.0 55.0 55.0 54.0 56.0 54.0 54.0 54.0 54.0 09:14:11 INFO - PID 31490 | 09:14:11 INFO - PID 31490 | [#9] json-stringify-tinderbox Cycles:10 Average:38.10 Median:38.00 stddev:0.57 (1.5%) stddev-sans-first:0.60 09:14:11 INFO - PID 31490 | Values: 38.0 39.0 38.0 39.0 38.0 38.0 37.0 38.0 38.0 38.0 09:14:11 INFO - PID 31490 | 09:14:11 INFO - PID 31490 | [#10] stanford-crypto-aes Cycles:10 Average:76.10 Median:76.00 stddev:2.02 (2.7%) stddev-sans-first:2.00 09:14:11 INFO - PID 31490 | Values: 74.0 75.0 79.0 77.0 80.0 74.0 75.0 75.0 76.0 76.0 09:14:11 INFO - PID 31490 | 09:14:11 INFO - PID 31490 | [#11] stanford-crypto-ccm Cycles:10 Average:127.30 Median:125.50 stddev:15.60 (12.4%) stddev-sans-first:16.43 09:14:11 INFO - PID 31490 | Values: 122.0 128.0 119.0 129.0 118.0 118.0 125.0 118.0 126.0 170.0 09:14:11 INFO - PID 31490 | 09:14:11 INFO - PID 31490 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:172.50 stddev:3.58 (2.1%) stddev-sans-first:3.27 09:14:11 INFO - PID 31490 | Values: 178.0 179.0 175.0 172.0 171.0 172.0 173.0 171.0 168.0 169.0 09:14:11 INFO - PID 31490 | 09:14:11 INFO - PID 31490 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:65.50 stddev:1.84 (2.8%) stddev-sans-first:1.72 09:14:11 INFO - PID 31490 | Values: 68.0 69.0 66.0 65.0 65.0 64.0 64.0 66.0 63.0 65.0 09:14:11 INFO - PID 31490 | -------- Summary: end -------- 09:14:11 INFO - PID 31490 | 09:14:12 INFO - PID 31490 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 09:14:12 INFO - TEST-INFO | 31490: exit 0 09:14:12 INFO - TEST-OK | kraken | took 60894ms 09:14:12 INFO - SUITE-END | took 1171s 09:14:12 INFO - Completed test suite (00:19:32) 09:14:12 INFO - javascript benchmark 09:14:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8629.229908903613, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3300.438925382685, 3303.9339213215744, 3312.817795021274, 3335.5663763710745, 3340.7661463697536, 6340.873182536352, 6355.618662880219, 6385.9999999999945, 6392.520560957929, 6461.353864613537, 6291.591046581969, 6390.201224846895, 6396.744130234785, 6405.807825765234, 6431.678416079189, 5771.855703607412, 5788.5369170466265, 5790.363060063411, 5800.506956908668, 5813.6221145625395, 5818.185454036438, 5819.7672093116225, 5829.9708501457435, 5834.854128646796, 5924.97037514814, 5982.132590774335, 5995.100734889779, 6005.159277701126, 6016.518678505731, 6024.156618073452, 5949.762009519614, 5960.254968128984, 5989.460948514655, 5993.430624090726, 6050.394960503936, 5732.971335143339, 5797.043487824504, 5820.708964551798, 5895.64626122434, 5920.2895652521975, 6197.225346831646, 6339.58792678479, 6363.7772677956045, 6460.709268082947, 6535.183102112236, 6873.072135261776, 6889.689963951634, 6895.689693963784, 6982.1272340957385, 6997.090378250795, 6714.227863795677, 6832.795016149524, 6836.077129587541, 7027.437804975641, 7027.82430439238, 6291.748330066792, 6297.0, 6310.5267104967315, 6404.583702059304, 6488.15653964991, 7112.110986126734, 7118.679659415338, 7123.608201548861, 7125.216226215112, 7143.7142514299885, 6759.2226893906845, 6818.079559259536, 6851.9657401712675, 6854.6229957351825, 6868.484863635247, 6870.553414028072, 6881.862362752717, 6896.689648965808, 6959.0257363969085, 6970.756023539152, 6671.132752742113, 6757.560758550679, 6777.8644427111185, 6835.4189893859575, 6915.239323674392, 6933.0, 6961.060256865258, 6975.232724400312, 6980.127484064492, 6983.511154219156, 5432.375276843213, 5494.80768173112, 5504.862378440571, 5514.889702205934, 5515.61390702655, 4711.528847115261, 4743.288506724019, 4751.762411879392, 4778.976105119452, 4812.25410061434, 5913.438223368773, 6002.789902353396, 6027.336992930774, 6034.336223015464, 6096.329403765582, 6993.930060699327, 6998.790036298919, 7008.264132266038, 7010.964945175241, 7022.4733145014325, 6025.0962355646125, 6070.2108725865355, 6088.2694076710195, 6097.481714054266, 6106.5114790817515, 5928.436798504135, 5940.6435613863305, 6009.098635204754, 6011.5190784737115, 6013.368596297413, 6453.290138084901, 6733.629650369178, 6745.898811517831, 6804.455643548505, 6831.521793474409, 6761.560498567577, 6774.187097548326, 6778.254392016973, 6779.423748981195, 6779.830504237236, 6483.481321494175, 6556.672166391757, 6558.934410655832, 6567.671616419065, 6571.638559879061, 6652.301508341748, 6654.367835055662, 6673.998900165014, 6675.732970681119, 6678.699458524281, 6920.723171073302, 6934.375906168469, 7015.228324884157, 7018.298170183145, 7049.823754406181, 6708.597484150967, 6729.899051514133, 6789.388954994074, 6829.0, 6832.965835170792, 6597.637129957899, 6678.799636010927, 6704.530682852153, 6723.193216814014, 6819.727210911508, 6596.0435736816935, 6626.734930602722, 6646.767363142267, 6658.667066646552, 6671.166104236971, 6759.290274521104, 6762.323767623198, 6785.389314961677, 6791.932080679328, 6863.691133898887, 6548.017797330439, 6561.475081993524, 6624.735010599522, 6636.7013484392355, 6647.567908085959], "value": 6286.654023246655, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [805.8710606302989, 851.1148405658116, 883.796726752847, 887.8712586674928, 1100.2023532938615, 29397.85301073497, 30138.698613013865, 39340.99999999996, 40189.39715904264, 40359.19281614366, 40526.0, 40746.0, 40856.79571602145, 40931.386029209585, 40944.79527602358, 7454.142773581027, 7579.1662917079075, 7646.464747467679, 7648.0822301323915, 7655.464117511777, 7468.701251949916, 7562.810929726746, 7569.167391586921, 7570.924290757104, 7584.5070070445245, 7499.850002999937, 7556.584387858666, 7573.318401343878, 7578.317951384374, 7585.7344992925255, 7530.924690753104, 7539.019927409457, 7557.168711441792, 7567.167611562723, 7574.772756817304, 7478.813029674247, 7524.3228109469865, 7529.698812047512, 7530.623468826537, 7538.73614423498, 36598.63401365979, 36624.26751464982, 36823.815880920694, 36881.26237475262, 36885.631143688486, 3493.458513930345, 3520.2431477232517, 3523.0487768302414, 3523.3834079036064, 3525.2068284636007, 28950.131496055154, 30116.24709382261, 30228.09315720532, 30293.848530757426, 30397.088087357413, 5079.187330027215, 5087.56755675768, 5145.125328694094, 5168.793248270064, 5183.403908550527, 181419.0, 181491.0, 181668.0, 181692.09153954277, 182050.0, 46171.845703857, 46217.7689111559, 46337.536624634, 46422.0, 46714.0, 48427.0, 48442.0, 48538.27192592043, 48587.271190932166, 48762.268565971535, 12915.0, 13196.340182990813, 14510.347034383478, 14613.342399592044, 14784.11295322284, 12001.339926304137, 12063.276203427822, 12063.819042714365, 12064.457099430547, 12069.637910862688, 14068.155910645393, 14079.577612671636, 14100.294985250901, 14146.0, 14183.219922904133, 5586.162075688598, 5602.607817452739, 5700.3444603871085, 5711.0, 5821.505172060422, 1001.104011909337, 1013.9340942838692, 1022.7749895023084, 1031.504877658728, 1047.0628787235535, 22309.665355019686, 22943.541129177323, 22958.540829183323, 23136.884315578653, 23267.0, 1104.7956128116325, 1106.6956586938657, 1109.4286442482128, 1121.6747143328344, 1130.559081958037, 11943.283402995849, 12114.81827772584, 12252.196454704856, 12275.57035503771, 12301.938490307492, 224894.0, 225792.0, 227324.0, 228101.0, 228810.0, 55160.44839551633, 55687.72156139275, 55859.162112568345, 55927.0, 56082.0, 1472.0, 1477.0, 1480.822301323848, 1481.1779462398472, 1485.9034162779385, 26713.19860404191, 27441.451170976867, 27456.725432745417, 27492.45015099647, 27787.16638500848, 37613.623863761015, 37675.43486847645, 37715.245695085396, 37768.0, 37820.24359512849, 201425.0, 203239.0, 203400.0, 203423.0, 203424.0, 35139.29721405609, 36193.27613447663, 36673.08317292089, 36721.44917826288, 36750.8162459186, 43329.13341733211, 44334.0, 44814.0, 44849.0, 44943.77528112339, 33157.83421082976, 33253.0, 33319.33361332808, 33404.33191336208, 34121.0, 32635.510467343483, 32744.50883236801, 32812.67187328191, 32822.343553128325, 34036.65963340434, 37594.43608345932, 37853.62146378611, 37898.0, 37928.620713793614, 38053.61946380501, 32261.0, 32414.189645259055, 32450.188745281557, 33491.49762753512, 33903.660963391034, 24579.631305530787, 24930.75069249357, 25250.242492725247, 25645.0, 25995.61006584865], "value": 17117.551365321488, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.2337850387959, 558.5308340993564, 559.9440055994401, 563.3493315220921, 572.2589246925232, 21602.783972160272, 22355.552888942213, 22412.88793556032, 22459.887700561514, 22468.77531224687, 25334.619980700303, 25955.610665840028, 25999.220023399328, 26050.73949260507, 26052.73947260527, 6686.063951046857, 6845.760398386057, 6853.7258509659805, 6856.0, 6857.245702972669, 5827.475527202551, 6090.543209259301, 6538.248101468321, 6545.9018114728315, 6551.57414768041, 6489.967550162266, 6494.025896115573, 6508.967455162741, 6511.4465270452065, 6514.641694706789, 6482.967585162091, 6492.123563318963, 6517.837054073639, 6518.28298887122, 6521.967390163042, 6039.758409663608, 6052.515798736091, 6064.029755239185, 6066.817995460143, 6067.423594758491, 29174.85412572945, 29463.558046629318, 29678.55482167769, 29996.250093747614, 30421.0, 2041.612093702192, 2062.61841559312, 2064.215598072723, 2064.597403506317, 2067.6174907642135, 19630.0, 20254.291099811584, 20305.898470507553, 20307.79692203074, 20325.0, 2414.7343792183024, 2480.8015358771254, 2510.221831232324, 2517.987410062938, 2530.03858533756, 131366.0, 137520.0, 138260.30869845688, 138536.30731846378, 138656.0, 14013.789793153112, 14491.347889345005, 14507.419703211965, 15191.924040379949, 15215.695686086437, 22555.323340299816, 23321.183758568368, 23332.7666723334, 23455.296341109795, 23483.7651623485, 9708.854367184498, 9771.51142442875, 9774.853377199348, 9870.94212250568, 10004.149647280063, 8888.866667, 8923.643054277756, 8960.073584179432, 9018.729438116867, 9031.0, 8978.86531702025, 9236.0, 9425.670101546413, 9439.292053096045, 9613.134817866425, 2612.8301660392012, 2740.1365572976288, 2747.848868312222, 2799.762020228263, 2819.3515491437083, 969.9951500242454, 979.9461029643438, 999.0858364596313, 1007.8034783217203, 1013.2451323763696, 23817.0, 25550.488990220092, 25580.616290755654, 25624.74375256261, 25848.74151258501, 1050.8003479338902, 1065.1585247654266, 1070.1599244593112, 1079.794838980591, 1081.567373050786, 11226.382548959886, 11515.76968460626, 11520.827187592193, 11536.76926461466, 11898.46456909441, 500935.0, 501652.0, 507243.0, 507451.0, 507564.0, 124179.37910310572, 127138.0, 127142.36428817797, 127355.0, 127876.36061819817, 1260.0612543655036, 1278.3991523983714, 1282.9679258018436, 1286.5111257722006, 1291.4963164365904, 18663.16015779266, 18870.0, 18887.0, 18890.622187556448, 19395.418137455898, 52064.739676301375, 60800.391996079474, 65661.0, 65851.34148658453, 67661.3233867655, 79660.0, 80398.5980070096, 82257.0, 83179.5841020791, 84102.57948710218, 21090.683639745723, 21158.365249042552, 21387.0375833091, 21553.676694849277, 21957.231496897533, 19608.80391196127, 21225.787742122382, 24069.157579485334, 24127.638085429084, 24298.0, 18031.729524057413, 18806.153723082767, 18825.77632453886, 18866.905665471586, 19210.0, 16732.247048883073, 17219.22513486921, 17287.7406838895, 17376.218070187126, 17764.022978735775, 18686.252549898396, 19136.23455061782, 19360.903195483934, 19373.51566210856, 19623.11695973656, 20994.790052099284, 21135.8943205283, 21398.144074236858, 21468.892655536623, 21477.89261053685, 16276.10481423533, 16440.095794731405, 16444.917775411046, 16445.424410145588, 16543.586410339838], "value": 12493.884353670413, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.1667707682698, 1378.111118328678, 1386.3484162443654, 1392.3595146232744, 1405.7750759878422, 14307.213103279315, 14801.555953321391, 14815.629609259788, 14921.179335136563, 15152.621184470394, 15652.452164174256, 15914.283857226425, 15927.761083583755, 15929.283182256797, 15933.20333983302, 2585.1598230575046, 2701.2706569226298, 2760.944781104387, 2765.612814206013, 2767.3081729567607, 2759.365345970428, 2787.818791778528, 2791.860406979653, 2791.9860400697967, 2793.064323451646, 2633.5917932720363, 2678.651775269212, 2817.8872845086275, 2838.5600231964077, 2852.87162077707, 2608.804339674532, 2621.986890065547, 2635.235781623327, 2639.4325220077776, 2641.828281161718, 2573.5882258838496, 2602.2323414592743, 2613.1899111275375, 2614.830036047651, 2617.5419301622333, 14940.0, 15279.159646219454, 15286.694266114615, 15301.846981530154, 15301.923490382476, 7859.0, 7963.761087167394, 8198.057223419324, 8244.876326855101, 8247.092819789817, 5559.082751346023, 5676.7183686323815, 5755.856103597402, 5828.271466066742, 5838.941610583882, 1828.0859570214893, 1841.300305883769, 1852.9629407411776, 1875.9906200469047, 1885.9339923102625, 26735.19794406171, 26943.595846062326, 26948.326291842666, 26962.730372696216, 26992.1902342932, 6723.226828914738, 6729.384947612635, 6908.792736217922, 6941.68762405693, 6947.930520694728, 17053.31786728542, 17070.658586828442, 17075.48773536796, 17157.57106072333, 17176.31294748221, 7997.800054998672, 8142.389320800964, 8150.470219435718, 8154.062282837431, 8160.714374996846, 7331.193568707438, 7501.024866767285, 7513.39892808574, 7517.511361761468, 7542.132654744665, 5104.744762761847, 5342.519173274346, 5354.0, 5383.327084114486, 5386.380566234933, 1532.578540901261, 1542.1826431991062, 1551.2786554252327, 1566.0525382143867, 1589.6105454163803, 881.9162179592928, 911.093462005309, 927.7541451515353, 936.784539555906, 940.8776859008285, 7481.364084052808, 7677.846443071107, 7707.190744971697, 7724.188960159215, 7855.214478552099, 719.4927576058866, 726.5241266970143, 732.9322261448937, 739.3235189801379, 744.9590272534954, 4605.42407739267, 4678.345031695565, 5457.372402173801, 5649.519790817826, 5704.657720536781, 21443.356699299045, 21497.67753483699, 21675.132994680036, 21833.12667493314, 22012.889935550542, 22108.0, 22417.0, 22694.205702800322, 22727.772722272566, 22752.20367287137, 10725.83911241332, 10741.785164296827, 11016.779664406668, 11027.779444411068, 11113.444327783578, 8137.7965550859335, 8622.439541429787, 8640.481571105754, 8695.652173913226, 8793.560321984003, 18808.52978675544, 18835.90582047081, 18865.434036978913, 18866.622667546846, 18942.52643683919, 19136.425907222805, 19187.424377268704, 19190.0, 19211.231550737815, 19324.13041413168, 11984.041276698017, 12368.690782730144, 12378.133530652767, 12386.62840114798, 12399.132060756021, 13589.388477518338, 13825.10136841102, 13964.4414223434, 13969.231692257023, 13980.0, 10607.893921060691, 10713.0, 10744.247902646739, 10774.353538787698, 10960.12319014461, 11144.60993865211, 11278.154138439813, 11455.484503197542, 11468.254563453349, 11501.137414693763, 9165.587548560197, 9237.722868313962, 9250.167484926389, 9251.629934802533, 9306.627734890528, 6840.110785597841, 6966.3730264276455, 6974.965125174342, 6988.79033628992, 7106.111736032996, 6875.896861547181, 6915.308469153045, 6964.721411143498, 7019.824504387227, 7060.117485314336], "value": 6645.140566151432, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.644575905348, 500.04740968455104, 525.4797750227275, 532.5526557691538, 535.6197100058957, 6467.353264673537, 6485.286618471964, 6516.315786842384, 6527.2167339919215, 6562.506247625902, 6116.847078823032, 6248.468880145193, 6292.905606415896, 6312.842178945529, 6330.525210609199, 5290.338707661543, 5325.147976323789, 5355.758990845402, 5364.168553874156, 5367.382750983628, 5269.8946021079555, 5293.179557168645, 5296.8146114885985, 5302.469753024705, 5310.203469479589, 5125.641205115643, 5263.394709608387, 5277.815276465324, 5289.8942021159555, 5315.601329900253, 5324.787008519655, 5337.5996800239745, 5343.145096784535, 5356.812511562096, 5370.812021579226, 4943.406791185081, 4977.328060711794, 4999.550040496373, 5003.774830132616, 5012.1980483122525, 6328.702940574158, 6336.556441049129, 6367.376319038615, 6368.872622547523, 6405.135306733578, 6142.96928515359, 6167.7532898684, 6173.660448675321, 6176.320604733521, 6197.16338294329, 6747.426468750162, 6767.661616919184, 6775.864482710367, 6785.355391237825, 6843.213030501506, 5338.866528336784, 5371.301730775013, 5379.515843574058, 5379.9731001345135, 5408.432114627986, 6970.651467426608, 7043.859122817515, 7129.928700712979, 7159.319864612853, 7171.89242161368, 5610.803621873215, 5640.8154287599245, 5644.463775941279, 5645.830625081172, 5649.971750141223, 5579.637323574036, 5582.888342233133, 5584.329880414295, 5667.716614169275, 5694.686792226467, 6033.758649653964, 6049.340149209753, 6052.485538729169, 6099.634021958696, 6144.969275153596, 6758.68824276111, 6765.323467653294, 6784.966075169593, 6810.693518791666, 6832.624205668637, 1813.8820976636475, 1856.5544269375252, 1950.5123719070232, 1960.6764883794015, 1975.2691504143559, 1366.3509832829525, 1419.8012278281049, 1461.5457619668498, 1463.6706740983407, 1476.60131764423, 5764.798232061858, 5777.7977770777825, 5842.766289348463, 5951.761929522857, 5978.459154859254, 6626.680661768342, 6648.800535983928, 6730.360615741497, 6771.762988295386, 6776.864462710718, 5788.408187748319, 5802.709864506842, 5841.357450680422, 5909.188573370152, 6024.819255422344, 3591.964080359163, 3606.2246616977477, 3627.981860090683, 3643.961470980758, 3658.3963645998642, 6487.599588152414, 6566.967165164144, 6655.334466553403, 6670.799876003728, 6692.631905245258, 6913.930860691429, 6951.165860096719, 6960.0, 6973.755918542826, 7050.15398152225, 6573.868522629521, 6594.175728033996, 6596.901046484307, 6680.331966803281, 6682.69927853238, 6770.627615480998, 6771.356721111487, 6783.966080169567, 6850.9314906850295, 6883.89857622778, 6628.5028622852515, 6641.933580664325, 6680.331966803281, 6704.66476676174, 6771.28901465339, 6554.180727409074, 6592.0, 6658.300878407697, 6676.9332306676315, 6718.294579069127, 5478.986387518322, 5498.45015498463, 5532.253145825423, 5600.0, 5615.2700148980375, 6757.560758550679, 6843.247242803387, 6843.897341539781, 6877.724891004304, 6885.724571017103, 6590.604563726191, 6591.703373348115, 6606.207255129223, 6606.339366063356, 6639.900401494078, 6375.426211640789, 6437.613743175424, 6537.836554085996, 6574.539782215199, 6657.234418041987, 6301.873962520815, 6551.639659818756, 6581.473482121514, 6608.471322294301, 6626.421732264524, 6697.430718388991, 6702.162229721285, 6711.295313991961, 6711.765088221889, 6712.463002959849, 6420.2937676856445, 6481.902771458337, 6506.284308725942, 6526.543141980016, 6566.606003639797], "value": 5289.06054391679, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [899.4693131052682, 909.9044600316967, 924.2375040591505, 927.0636656976452, 930.0838673906201, 16926.576835579115, 17151.914240428796, 17152.313907443688, 17204.139793010345, 18589.721154182665, 18656.533586660342, 18658.0, 18699.532511687215, 18737.0, 18779.0, 958.6644674363987, 977.4917043137564, 979.3144798640944, 981.8281800684887, 983.5868935047297, 941.5339406993513, 977.569869257528, 985.9112679858847, 986.058314309836, 986.3145114145672, 1054.4833031814433, 1056.1128651932374, 1057.2757661002238, 1057.376148072637, 1057.60868478663, 1050.5482642463737, 1050.6112738286884, 1051.9000694934007, 1051.9368837869752, 1052.2266134391216, 771.6026246483066, 772.0272456704537, 772.142921357293, 772.3512249710215, 774.5159275452842, 16960.40638577656, 17265.13674316279, 18110.63778724431, 18213.99823009734, 18280.994545300004, 18859.057047147726, 19113.139908704143, 19376.80623193764, 19407.51481212967, 19557.80442195574, 15088.0, 15331.923340383226, 15495.302711377903, 15620.76568851468, 15625.296861641253, 3752.6810221131236, 3802.1445174835717, 3807.447920051577, 3808.542974843009, 3818.0264032671766, 126327.0, 127479.36260318731, 128122.35938820339, 129005.35497322453, 130146.34926825305, 15290.770638440432, 15310.234488275542, 15424.228788560527, 15618.531444056696, 15700.528984130495, 30895.536566951512, 31142.84428577888, 32031.67968320287, 32038.83980580082, 32045.0, 8659.0, 8782.648694052166, 8787.077356877564, 8787.209151176425, 8801.603927823264, 3468.236987862666, 3654.9634503655157, 4052.1287923096675, 4062.1875624875142, 4063.817128229237, 5150.381954165449, 5277.551408130352, 5277.920831187458, 5297.020051290524, 5297.231901374279, 1038.6416686243233, 1052.2634156090767, 1059.2320567588438, 1073.3238060022136, 1076.235872530512, 691.6196092149398, 705.4109818301673, 709.8854797967142, 715.3704739828918, 718.9352958233679, 10149.898501015043, 10190.796184076276, 10311.175105991502, 10342.224333174892, 10553.419561924167, 678.375894177358, 699.8075529229502, 702.1433850702135, 715.677944924786, 716.3338095571169, 5124.897502049938, 5187.662801917863, 5256.211568264791, 5264.710440925785, 5416.322959630046, 106331.32521100264, 107295.4635226819, 109425.45287273671, 113256.0, 113415.43292283651, 93501.53249233711, 96814.0, 97743.0, 99529.50235248922, 102418.0, 85489.57255213684, 85870.57064714762, 86247.13752862517, 88611.55694221487, 90352.0, 10077.546510407195, 10079.596816127272, 10257.538410771682, 10340.482975851028, 10409.895901041196, 41761.79119104385, 41781.37327940023, 42271.365929511696, 43023.56976430196, 43131.568684312755, 70296.6485167571, 72933.27066729264, 73537.0, 73572.0, 77152.61423692845, 16659.416920407726, 16694.916525417295, 16726.916365418096, 16925.66148676995, 17140.742888856428, 15171.24143792828, 21205.78794212038, 22021.33935981923, 22113.115475380804, 22117.115315387204, 13962.720745585235, 14364.425422982964, 14724.0, 15617.375304987674, 15650.765238521659, 15854.44509442164, 15979.680406392039, 15985.200739962722, 16103.516894493185, 16138.112403817433, 14077.366518506897, 14187.14877107377, 14188.361523731663, 14299.356528956014, 14351.354189061725, 11717.76564468723, 11920.403979801149, 11968.700782480159, 12384.81422778641, 12462.252264864137, 11507.252028618113, 11559.537618495166, 11596.36220007874, 11644.91409776427, 11736.589219377282], "value": 8737.082483433647, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1486.6999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 107.0, 99.0, 111.0, 107.0, 113.0, 108.0, 98.0, 106.0, 109.0], "value": 105.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 115.0, 152.0, 168.0, 116.0, 141.0, 148.0, 118.0, 167.0, 118.0], "value": 139.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 161.0, 162.0, 162.0, 161.0, 162.0, 165.0, 168.0, 169.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 176.0, 74.0, 75.0, 74.0, 74.0, 154.0, 75.0], "value": 92.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 131.0, 80.0, 79.0, 79.0, 129.0, 79.0, 82.0, 128.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 114.0, 114.0, 114.0, 115.0, 114.0, 115.0, 114.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 157.0, 148.0, 147.0, 150.0, 150.0, 147.0, 149.0, 147.0], "value": 148.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 93.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 55.0, 55.0, 54.0, 56.0, 54.0, 54.0, 54.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 38.0, 39.0, 38.0, 38.0, 37.0, 38.0, 38.0, 38.0], "value": 38.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 79.0, 77.0, 80.0, 74.0, 75.0, 75.0, 76.0, 76.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 128.0, 119.0, 129.0, 118.0, 118.0, 125.0, 118.0, 126.0, 170.0], "value": 127.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 179.0, 175.0, 172.0, 171.0, 172.0, 173.0, 171.0, 168.0, 169.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 66.0, 65.0, 65.0, 64.0, 64.0, 66.0, 63.0, 65.0], "value": 65.5, "unit": "score"}]}]} 09:14:12 INFO - Return code: 0 09:14:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:14:12 INFO - # TBPL SUCCESS # 09:14:12 INFO - Running post-action listener: _package_coverage_data 09:14:12 INFO - Running post-action listener: _resource_record_post_action 09:14:12 INFO - [mozharness: 2017-09-01 16:14:12.928760Z] Finished run-tests step (success) 09:14:12 INFO - Running post-run listener: _resource_record_post_run 09:14:13 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 401600512; Read time: 12; Write time: 304596 09:14:13 INFO - TinderboxPrint: CPU usage
13.1% 09:14:13 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 09:14:13 INFO - TinderboxPrint: I/O write bytes / time
401,600,512 / 304,596 09:14:13 INFO - TinderboxPrint: CPU idle
8,235.3 (86.9%) 09:14:13 INFO - TinderboxPrint: CPU user
1,193.7 (12.6%) 09:14:13 INFO - TinderboxPrint: Swap in / out
0 / 0 09:14:13 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 117194752; Read time: 0; Write time: 87256 09:14:13 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:14:13 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 283975680; Read time: 12; Write time: 217320 09:14:13 INFO - Running post-run listener: _upload_blobber_files 09:14:13 INFO - Blob upload gear active. 09:14:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:14:13 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 09:14:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:14:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:14:13 INFO - (blobuploader) - INFO - Open directory for files ... 09:14:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:14:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:14:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:14 INFO - (blobuploader) - INFO - Done attempting. 09:14:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:14:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:14:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:18 INFO - (blobuploader) - INFO - Done attempting. 09:14:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:14:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:14:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:20 INFO - (blobuploader) - INFO - Done attempting. 09:14:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:14:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:14:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:21 INFO - (blobuploader) - INFO - Done attempting. 09:14:21 INFO - (blobuploader) - INFO - Iteration through files over. 09:14:21 INFO - Return code: 0 09:14:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:14:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:14:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/88e77b8405d4ac4886c01d3fabab89684c8bce5eeb208228d4ddf974cc58ff99d2b0a25cbb4611fbda0f720f5359b81082fbdb5df59e3a622809e9ddfbf1eb3d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d37cc593ac9142d1e9720d80ea7675a357c9ceb6a26856a7203d3ceabdba1cda9acd92d15e40019f07896d7c86e35cc971b4d40d2393b7ac9fc163efc94c7863", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/df823d50a871b9b060813ee4601081e3fba6348503e851952837146eb6939409ee04b3654cdaca7f74a9a7abbe773a9fc7e78d27de8120cbd628e9341c69ca85", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/31f1485f31bf22da8decb9018868dc2309ed3f8ea53bea7b8b8a9e5cf0605760e5794816a19f04f47fc0534b682f6aea58380a8f30344e5890f5067934af279e"} 09:14:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:14:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:14:21 INFO - Contents: 09:14:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/88e77b8405d4ac4886c01d3fabab89684c8bce5eeb208228d4ddf974cc58ff99d2b0a25cbb4611fbda0f720f5359b81082fbdb5df59e3a622809e9ddfbf1eb3d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d37cc593ac9142d1e9720d80ea7675a357c9ceb6a26856a7203d3ceabdba1cda9acd92d15e40019f07896d7c86e35cc971b4d40d2393b7ac9fc163efc94c7863", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/df823d50a871b9b060813ee4601081e3fba6348503e851952837146eb6939409ee04b3654cdaca7f74a9a7abbe773a9fc7e78d27de8120cbd628e9341c69ca85", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/31f1485f31bf22da8decb9018868dc2309ed3f8ea53bea7b8b8a9e5cf0605760e5794816a19f04f47fc0534b682f6aea58380a8f30344e5890f5067934af279e"} 09:14:21 INFO - Running post-run listener: copy_logs_to_upload_dir 09:14:21 INFO - Copying logs to upload dir... 09:14:21 INFO - mkdir: /builds/slave/test/build/upload/logs 09:14:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.614718 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-01 09:14:21.170698) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 09:14:21.177850) ========= 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=4300f881736b422476174874000001bc-1504281210.647201-1575338579 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/88e77b8405d4ac4886c01d3fabab89684c8bce5eeb208228d4ddf974cc58ff99d2b0a25cbb4611fbda0f720f5359b81082fbdb5df59e3a622809e9ddfbf1eb3d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d37cc593ac9142d1e9720d80ea7675a357c9ceb6a26856a7203d3ceabdba1cda9acd92d15e40019f07896d7c86e35cc971b4d40d2393b7ac9fc163efc94c7863", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/df823d50a871b9b060813ee4601081e3fba6348503e851952837146eb6939409ee04b3654cdaca7f74a9a7abbe773a9fc7e78d27de8120cbd628e9341c69ca85", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/31f1485f31bf22da8decb9018868dc2309ed3f8ea53bea7b8b8a9e5cf0605760e5794816a19f04f47fc0534b682f6aea58380a8f30344e5890f5067934af279e"} build_url:https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011276 build_url: 'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/88e77b8405d4ac4886c01d3fabab89684c8bce5eeb208228d4ddf974cc58ff99d2b0a25cbb4611fbda0f720f5359b81082fbdb5df59e3a622809e9ddfbf1eb3d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d37cc593ac9142d1e9720d80ea7675a357c9ceb6a26856a7203d3ceabdba1cda9acd92d15e40019f07896d7c86e35cc971b4d40d2393b7ac9fc163efc94c7863", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/df823d50a871b9b060813ee4601081e3fba6348503e851952837146eb6939409ee04b3654cdaca7f74a9a7abbe773a9fc7e78d27de8120cbd628e9341c69ca85", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/31f1485f31bf22da8decb9018868dc2309ed3f8ea53bea7b8b8a9e5cf0605760e5794816a19f04f47fc0534b682f6aea58380a8f30344e5890f5067934af279e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 09:14:21.207557) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:14:21.207881) ========= 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=4300f881736b422476174874000001bc-1504281210.647201-1575338579 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004528 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:14:21.289300) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 09:14:21.291059) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 09:14:21.291420) ========= ========= Total master_lag: 0.20 =========