builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-078 starttime: 1505299586.39 results: success (0) revision: 124f2094635bbf1901c1d14fc2c0a80bf7f847bb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:26.387779) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:26.388187) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:26.388485) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:26.409340) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:26.409638) ========= 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=dac658600622fd96716c9acf000001b3-1505298517.995116-199668604 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-13 03:46:26-- 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% 41.8M=0s 2017-09-13 03:46:26 (41.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.078707 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:26.513783) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:26.514081) ========= 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=dac658600622fd96716c9acf000001b3-1505298517.995116-199668604 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018073 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:26.595293) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:26.595824) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 124f2094635bbf1901c1d14fc2c0a80bf7f847bb --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 124f2094635bbf1901c1d14fc2c0a80bf7f847bb --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=dac658600622fd96716c9acf000001b3-1505298517.995116-199668604 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-13 03:46:26,591 truncating revision to first 12 chars 2017-09-13 03:46:26,591 Setting DEBUG logging. 2017-09-13 03:46:26,591 attempt 1/10 2017-09-13 03:46:26,591 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/124f2094635b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-13 03:46:26,955 unpacking tar archive at: mozilla-central-124f2094635b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.679630 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:27.296560) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:27.296853) ========= script_repo_revision: 124f2094635bbf1901c1d14fc2c0a80bf7f847bb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:27.297242) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:27.297517) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:27.311315) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-13 03:46:27.311601) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=dac658600622fd96716c9acf000001b3-1505298517.995116-199668604 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:46:27 INFO - MultiFileLogger online at 20170913 03:46:27 in /builds/slave/test 03:46:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:46:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:46:27 INFO - {'append_to_log': False, 03:46:27 INFO - 'base_work_dir': '/builds/slave/test', 03:46:27 INFO - 'blob_upload_branch': 'Firefox', 03:46:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:46:27 INFO - 'branch': 'Firefox', 03:46:27 INFO - 'buildbot_json_path': 'buildprops.json', 03:46:27 INFO - 'code_coverage': False, 03:46:27 INFO - 'config_files': ('talos/linux_config.py',), 03:46:27 INFO - 'default_actions': ('clobber', 03:46:27 INFO - 'read-buildbot-config', 03:46:27 INFO - 'download-and-extract', 03:46:27 INFO - 'populate-webroot', 03:46:27 INFO - 'create-virtualenv', 03:46:27 INFO - 'install', 03:46:27 INFO - 'setup-mitmproxy', 03:46:27 INFO - 'run-tests'), 03:46:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:46:27 INFO - 'disable_ccov_upload': False, 03:46:27 INFO - 'disable_stylo': False, 03:46:27 INFO - 'download_minidump_stackwalk': True, 03:46:27 INFO - 'download_symbols': 'ondemand', 03:46:27 INFO - 'enable_stylo': False, 03:46:27 INFO - 'enable_webrender': False, 03:46:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:46:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:46:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:46:27 INFO - 'gecko_profile': False, 03:46:27 INFO - 'gecko_profile_interval': 0, 03:46:27 INFO - 'installer_path': 'installer.exe', 03:46:27 INFO - 'log_level': 'info', 03:46:27 INFO - 'log_name': 'talos', 03:46:27 INFO - 'log_to_console': True, 03:46:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:46:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:46:27 INFO - 'opt_config_files': (), 03:46:27 INFO - 'pip_index': False, 03:46:27 INFO - 'suite': 'dromaeojs-e10s', 03:46:27 INFO - 'system_bits': '32', 03:46:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:46:27 INFO - 'title': 'talos-linux64-ix-078', 03:46:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:46:27 INFO - 'use_talos_json': True, 03:46:27 INFO - 'verify': 'False', 03:46:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:46:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:46:27 INFO - 'work_dir': 'build'} 03:46:27 INFO - [mozharness: 2017-09-13 10:46:27.446894Z] Running clobber step. 03:46:27 INFO - Running pre-action listener: _resource_record_pre_action 03:46:27 INFO - Running main action method: clobber 03:46:27 INFO - rmtree: /builds/slave/test/build 03:46:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:46:28 INFO - Running post-action listener: _resource_record_post_action 03:46:28 INFO - [mozharness: 2017-09-13 10:46:28.456654Z] Finished clobber step (success) 03:46:28 INFO - [mozharness: 2017-09-13 10:46:28.456751Z] Running read-buildbot-config step. 03:46:28 INFO - Running pre-action listener: _resource_record_pre_action 03:46:28 INFO - Running main action method: read_buildbot_config 03:46:28 INFO - Using buildbot properties: 03:46:28 INFO - { 03:46:28 INFO - "project": "", 03:46:28 INFO - "product": "firefox", 03:46:28 INFO - "who": "archaeopteryx@coole-files.de", 03:46:28 INFO - "installer_path": "public/build/target.tar.bz2", 03:46:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:46:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:46:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 03:46:28 INFO - "stage_platform": "linux64-pgo", 03:46:28 INFO - "basedir": "/builds/slave/test", 03:46:28 INFO - "buildnumber": 148, 03:46:28 INFO - "platform": "ubuntu64_hw", 03:46:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:46:28 INFO - "slavebuilddir": "test", 03:46:28 INFO - "taskId": "AZcA4B4ISe2hDEir1dGu2g", 03:46:28 INFO - "branch": "mozilla-central", 03:46:28 INFO - "script_repo_revision": "production", 03:46:28 INFO - "revision": "124f2094635bbf1901c1d14fc2c0a80bf7f847bb", 03:46:28 INFO - "slavename": "talos-linux64-ix-078", 03:46:28 INFO - "repo_path": "mozilla-central" 03:46:28 INFO - } 03:46:28 INFO - Finding installer, test and symbols from parent task. 03:46:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AZcA4B4ISe2hDEir1dGu2g'}, attempt #1 03:46:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AZcA4B4ISe2hDEir1dGu2g'}, attempt #1 03:46:29 INFO - Task dependencies: UIAOECxVTHSi9bdMrSDP9w 03:46:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w'}, attempt #1 03:46:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.tar.bz2 03:46:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.test_packages.json 03:46:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.crashreporter-symbols.zip 03:46:29 INFO - Running post-action listener: _resource_record_post_action 03:46:29 INFO - [mozharness: 2017-09-13 10:46:29.803121Z] Finished read-buildbot-config step (success) 03:46:29 INFO - [mozharness: 2017-09-13 10:46:29.803336Z] Running download-and-extract step. 03:46:29 INFO - Running pre-action listener: _resource_record_pre_action 03:46:29 INFO - Running main action method: download_and_extract 03:46:29 INFO - mkdir: /builds/slave/test/build/tests 03:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:29 INFO - trying https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.test_packages.json 03:46:29 INFO - Downloading https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:46:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:46:30 INFO - Downloaded 989 bytes. 03:46:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:46:30 INFO - Using the following test package requirements: 03:46:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:46:30 INFO - u'common': [u'target.common.tests.zip'], 03:46:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:46:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:46:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:46:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:46:30 INFO - u'mozbase': [u'target.common.tests.zip'], 03:46:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:46:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:46:30 INFO - u'web-platform': [u'target.common.tests.zip', 03:46:30 INFO - u'target.web-platform.tests.tar.gz'], 03:46:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:46:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:46:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.common.tests.zip 03:46:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:46:30 INFO - Fetch https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.common.tests.zip into memory 03:46:31 INFO - Content-Length response header: 55664180 03:46:31 INFO - Bytes received: 55664180 03:46:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.talos.tests.zip 03:46:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:46:42 INFO - Fetch https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.talos.tests.zip into memory 03:46:43 INFO - Content-Length response header: 14499231 03:46:43 INFO - Bytes received: 14499231 03:46:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:44 INFO - trying https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.tar.bz2 03:46:44 INFO - Downloading https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:46:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:46:46 INFO - Downloaded 63695375 bytes. 03:46:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.tar.bz2 03:46:46 INFO - mkdir: /builds/slave/test/properties 03:46:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:46:46 INFO - Writing to file /builds/slave/test/properties/build_url 03:46:46 INFO - Contents: 03:46:46 INFO - build_url:https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.tar.bz2 03:46:46 INFO - Running post-action listener: _resource_record_post_action 03:46:46 INFO - Running post-action listener: find_tests_for_verification 03:46:46 INFO - Running post-action listener: set_extra_try_arguments 03:46:46 INFO - [mozharness: 2017-09-13 10:46:46.780960Z] Finished download-and-extract step (success) 03:46:46 INFO - [mozharness: 2017-09-13 10:46:46.781081Z] Running populate-webroot step. 03:46:46 INFO - Running pre-action listener: _resource_record_pre_action 03:46:46 INFO - Running main action method: populate_webroot 03:46:46 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:46:46 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:46 INFO - 'tests': ['tresize', 03:46:46 INFO - 'tcanvasmark']}, 03:46:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:46:46 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:46 INFO - 'tests': ['dromaeo_css', 03:46:46 INFO - 'kraken']}, 03:46:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:46:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:46:46 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:46 INFO - 'talos_options': ['--disable-stylo'], 03:46:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:46:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:46:46 INFO - 'tests': ['damp', 'tps']}, 03:46:46 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:46 INFO - 'talos_options': ['--disable-stylo'], 03:46:46 INFO - 'tests': ['damp', 'tps']}, 03:46:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:46:46 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:46 INFO - 'tests': ['dromaeo_dom']}, 03:46:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:46:46 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:46 INFO - 'tests': ['basic_compositor_video', 03:46:46 INFO - 'glvideo']}, 03:46:46 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:46:46 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:46:46 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:46 INFO - 'talos_options': ['--disable-stylo'], 03:46:46 INFO - 'tests': ['ts_paint_webext', 03:46:46 INFO - 'tp5o_webext']}, 03:46:46 INFO - 'other-e10s': {'tests': ['a11yr', 03:46:46 INFO - 'ts_paint', 03:46:46 INFO - 'tpaint', 03:46:46 INFO - 'sessionrestore', 03:46:46 INFO - 'sessionrestore_many_windows', 03:46:46 INFO - 'sessionrestore_no_auto_restore', 03:46:46 INFO - 'tabpaint']}, 03:46:46 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:46 INFO - 'tests': ['a11yr', 03:46:46 INFO - 'ts_paint', 03:46:46 INFO - 'tpaint', 03:46:46 INFO - 'sessionrestore', 03:46:46 INFO - 'sessionrestore_many_windows', 03:46:46 INFO - 'sessionrestore_no_auto_restore', 03:46:46 INFO - 'tabpaint']}, 03:46:46 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:46:46 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:46:46 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:46 INFO - 'tests': ['bloom_basic_singleton']}, 03:46:46 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:46 INFO - 'tests': ['bloom_basic']}, 03:46:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:46:46 INFO - 'tsvgr_opacity', 03:46:46 INFO - 'tart', 03:46:46 INFO - 'tscrollx', 03:46:46 INFO - 'tsvg_static']}, 03:46:46 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:46 INFO - 'tests': ['tsvgx', 03:46:46 INFO - 'tsvgr_opacity', 03:46:46 INFO - 'tart', 03:46:46 INFO - 'tscrollx', 03:46:46 INFO - 'tsvg_static']}, 03:46:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:46:46 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:46 INFO - 'talos_options': ['--disable-stylo'], 03:46:46 INFO - 'tests': ['tp5o']}, 03:46:46 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:46:46 INFO - 'talos_options': ['--mitmproxy', 03:46:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:46:46 INFO - '--firstNonBlankPaint'], 03:46:46 INFO - 'tests': ['tp6_google', 03:46:46 INFO - 'tp6_youtube', 03:46:46 INFO - 'tp6_amazon', 03:46:46 INFO - 'tp6_facebook']}, 03:46:46 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:46:46 INFO - 'talos_options': ['--disable-stylo', 03:46:46 INFO - '--mitmproxy', 03:46:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:46:46 INFO - '--firstNonBlankPaint'], 03:46:46 INFO - 'tests': ['tp6_google', 03:46:46 INFO - 'tp6_youtube', 03:46:46 INFO - 'tp6_amazon', 03:46:46 INFO - 'tp6_facebook']}, 03:46:46 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:46:46 INFO - 'talos_options': ['--stylo-threads=1', 03:46:46 INFO - '--mitmproxy', 03:46:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:46:46 INFO - '--firstNonBlankPaint'], 03:46:46 INFO - 'tests': ['tp6_google', 03:46:46 INFO - 'tp6_youtube', 03:46:46 INFO - 'tp6_amazon', 03:46:46 INFO - 'tp6_facebook']}, 03:46:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:46:46 INFO - 'talos_options': ['--xperf_path', 03:46:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:46:46 INFO - 'tests': ['tp5n']}, 03:46:46 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:46 INFO - 'talos_options': ['--disable-stylo', 03:46:46 INFO - '--xperf_path', 03:46:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:46:46 INFO - 'tests': ['tp5n']}}} 03:46:46 INFO - Running post-action listener: _resource_record_post_action 03:46:46 INFO - [mozharness: 2017-09-13 10:46:46.798578Z] Finished populate-webroot step (success) 03:46:46 INFO - [mozharness: 2017-09-13 10:46:46.798667Z] Running create-virtualenv step. 03:46:46 INFO - Running pre-action listener: _resource_record_pre_action 03:46:46 INFO - Running main action method: create_virtualenv 03:46:46 INFO - Creating virtualenv /builds/slave/test/build/venv 03:46:46 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:46:46 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:46:46 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:46:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:46:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:46:48 INFO - Installing distribute.............................................................................................................................................................................................done. 03:46:49 INFO - Installing pip...............done. 03:46:49 INFO - Return code: 0 03:46:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:46:49 INFO - Reading from file tmpfile_stdout 03:46:49 INFO - Output received: 03:46:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:46:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e2090>, '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 0x16e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dcf90>, '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': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:46:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:46:49 INFO - Using env: {'DISPLAY': ':0', 03:46:49 INFO - 'HOME': '/home/cltbld', 03:46:49 INFO - 'LANG': 'en_US.UTF-8', 03:46:49 INFO - 'LANGUAGE': 'en_US:en', 03:46:49 INFO - 'LOGNAME': 'cltbld', 03:46:49 INFO - 'MAIL': '/var/mail/cltbld', 03:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:49 INFO - 'MOZ_NO_REMOTE': '1', 03:46:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:49 INFO - 'NO_EM_RESTART': '1', 03:46:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:49 INFO - 'PWD': '/builds/slave/test', 03:46:49 INFO - 'SHELL': '/bin/bash', 03:46:49 INFO - 'SHLVL': '1', 03:46:49 INFO - 'TERM': 'linux', 03:46:49 INFO - 'TMOUT': '86400', 03:46:49 INFO - 'USER': 'cltbld', 03:46:49 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 03:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:49 INFO - '_': '/tools/buildbot/bin/python'} 03:46:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:46:50 INFO - Downloading/unpacking pip>=1.5 03:46:50 INFO - Running setup.py egg_info for package pip 03:46:50 INFO - warning: no files found matching 'pip/cacert.pem' 03:46:50 INFO - warning: no files found matching '*.html' under directory 'docs' 03:46:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:46:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:46:50 INFO - Installing collected packages: pip 03:46:50 INFO - Found existing installation: pip 1.1 03:46:50 INFO - Uninstalling pip: 03:46:50 INFO - Successfully uninstalled pip 03:46:50 INFO - Running setup.py install for pip 03:46:50 INFO - warning: no files found matching 'pip/cacert.pem' 03:46:50 INFO - warning: no files found matching '*.html' under directory 'docs' 03:46:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:46:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:46:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:46:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:46:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:46:50 INFO - Successfully installed pip 03:46:50 INFO - Cleaning up... 03:46:50 INFO - Return code: 0 03:46:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e2090>, '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 0x16e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dcf90>, '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': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:46:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:46:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:46:50 INFO - Using env: {'DISPLAY': ':0', 03:46:50 INFO - 'HOME': '/home/cltbld', 03:46:50 INFO - 'LANG': 'en_US.UTF-8', 03:46:50 INFO - 'LANGUAGE': 'en_US:en', 03:46:50 INFO - 'LOGNAME': 'cltbld', 03:46:50 INFO - 'MAIL': '/var/mail/cltbld', 03:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:50 INFO - 'MOZ_NO_REMOTE': '1', 03:46:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:50 INFO - 'NO_EM_RESTART': '1', 03:46:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:50 INFO - 'PWD': '/builds/slave/test', 03:46:50 INFO - 'SHELL': '/bin/bash', 03:46:50 INFO - 'SHLVL': '1', 03:46:50 INFO - 'TERM': 'linux', 03:46:50 INFO - 'TMOUT': '86400', 03:46:50 INFO - 'USER': 'cltbld', 03:46:50 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 03:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:50 INFO - '_': '/tools/buildbot/bin/python'} 03:46:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:50 INFO - Downloading/unpacking psutil>=3.1.1 03:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:46:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:46:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:46:51 INFO - Installing collected packages: psutil 03:46:51 INFO - Running setup.py install for psutil 03:46:52 INFO - building 'psutil._psutil_linux' extension 03:46:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:46:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:46:52 INFO - building 'psutil._psutil_posix' extension 03:46:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:46:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:46:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:46:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:46:52 INFO - Successfully installed psutil 03:46:52 INFO - Cleaning up... 03:46:52 INFO - Return code: 0 03:46:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:46:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e2090>, '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 0x16e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dcf90>, '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': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:46:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:46:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:46:52 INFO - Using env: {'DISPLAY': ':0', 03:46:52 INFO - 'HOME': '/home/cltbld', 03:46:52 INFO - 'LANG': 'en_US.UTF-8', 03:46:52 INFO - 'LANGUAGE': 'en_US:en', 03:46:52 INFO - 'LOGNAME': 'cltbld', 03:46:52 INFO - 'MAIL': '/var/mail/cltbld', 03:46:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:52 INFO - 'MOZ_NO_REMOTE': '1', 03:46:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:52 INFO - 'NO_EM_RESTART': '1', 03:46:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:52 INFO - 'PWD': '/builds/slave/test', 03:46:52 INFO - 'SHELL': '/bin/bash', 03:46:52 INFO - 'SHLVL': '1', 03:46:52 INFO - 'TERM': 'linux', 03:46:52 INFO - 'TMOUT': '86400', 03:46:52 INFO - 'USER': 'cltbld', 03:46:52 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 03:46:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:52 INFO - '_': '/tools/buildbot/bin/python'} 03:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:46:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:46:53 INFO - Installing collected packages: mozsystemmonitor 03:46:53 INFO - Running setup.py install for mozsystemmonitor 03:46:53 INFO - Successfully installed mozsystemmonitor 03:46:53 INFO - Cleaning up... 03:46:54 INFO - Return code: 0 03:46:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:46:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e2090>, '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 0x16e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dcf90>, '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': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:46:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:46:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:46:54 INFO - Using env: {'DISPLAY': ':0', 03:46:54 INFO - 'HOME': '/home/cltbld', 03:46:54 INFO - 'LANG': 'en_US.UTF-8', 03:46:54 INFO - 'LANGUAGE': 'en_US:en', 03:46:54 INFO - 'LOGNAME': 'cltbld', 03:46:54 INFO - 'MAIL': '/var/mail/cltbld', 03:46:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:54 INFO - 'MOZ_NO_REMOTE': '1', 03:46:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:54 INFO - 'NO_EM_RESTART': '1', 03:46:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:54 INFO - 'PWD': '/builds/slave/test', 03:46:54 INFO - 'SHELL': '/bin/bash', 03:46:54 INFO - 'SHLVL': '1', 03:46:54 INFO - 'TERM': 'linux', 03:46:54 INFO - 'TMOUT': '86400', 03:46:54 INFO - 'USER': 'cltbld', 03:46:54 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 03:46:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:54 INFO - '_': '/tools/buildbot/bin/python'} 03:46:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:54 INFO - Downloading/unpacking jsonschema==2.5.1 03:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:46:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:55 INFO - Downloading functools32-3.2.3-2.tar.gz 03:46:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:46:55 INFO - warning: no files found matching '*.txt' 03:46:55 INFO - no previously-included directories found matching 'build' 03:46:55 INFO - no previously-included directories found matching 'dist' 03:46:55 INFO - no previously-included directories found matching '.git*' 03:46:55 INFO - Installing collected packages: jsonschema, functools32 03:46:56 INFO - Running setup.py install for functools32 03:46:56 INFO - warning: no files found matching '*.txt' 03:46:56 INFO - no previously-included directories found matching 'build' 03:46:56 INFO - no previously-included directories found matching 'dist' 03:46:56 INFO - no previously-included directories found matching '.git*' 03:46:56 INFO - Successfully installed jsonschema functools32 03:46:56 INFO - Cleaning up... 03:46:56 INFO - Return code: 0 03:46:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e2090>, '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 0x16e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dcf90>, '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': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:46:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:46:56 INFO - Using env: {'DISPLAY': ':0', 03:46:56 INFO - 'HOME': '/home/cltbld', 03:46:56 INFO - 'LANG': 'en_US.UTF-8', 03:46:56 INFO - 'LANGUAGE': 'en_US:en', 03:46:56 INFO - 'LOGNAME': 'cltbld', 03:46:56 INFO - 'MAIL': '/var/mail/cltbld', 03:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:56 INFO - 'MOZ_NO_REMOTE': '1', 03:46:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:56 INFO - 'NO_EM_RESTART': '1', 03:46:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:56 INFO - 'PWD': '/builds/slave/test', 03:46:56 INFO - 'SHELL': '/bin/bash', 03:46:56 INFO - 'SHLVL': '1', 03:46:56 INFO - 'TERM': 'linux', 03:46:56 INFO - 'TMOUT': '86400', 03:46:56 INFO - 'USER': 'cltbld', 03:46:56 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 03:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:56 INFO - '_': '/tools/buildbot/bin/python'} 03:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:46:56 INFO - Cleaning up... 03:46:56 INFO - Return code: 0 03:46:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e2090>, '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 0x16e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dcf90>, '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': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:46:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:46:56 INFO - Using env: {'DISPLAY': ':0', 03:46:56 INFO - 'HOME': '/home/cltbld', 03:46:56 INFO - 'LANG': 'en_US.UTF-8', 03:46:56 INFO - 'LANGUAGE': 'en_US:en', 03:46:56 INFO - 'LOGNAME': 'cltbld', 03:46:56 INFO - 'MAIL': '/var/mail/cltbld', 03:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:56 INFO - 'MOZ_NO_REMOTE': '1', 03:46:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:56 INFO - 'NO_EM_RESTART': '1', 03:46:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:56 INFO - 'PWD': '/builds/slave/test', 03:46:56 INFO - 'SHELL': '/bin/bash', 03:46:56 INFO - 'SHLVL': '1', 03:46:56 INFO - 'TERM': 'linux', 03:46:56 INFO - 'TMOUT': '86400', 03:46:56 INFO - 'USER': 'cltbld', 03:46:56 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 03:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:56 INFO - '_': '/tools/buildbot/bin/python'} 03:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:56 INFO - Downloading/unpacking blobuploader==1.2.4 03:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:57 INFO - Downloading blobuploader-1.2.4.tar.gz 03:46:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:46:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:46:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:58 INFO - Downloading docopt-0.6.1.tar.gz 03:46:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:46:58 INFO - Installing collected packages: blobuploader, requests, docopt 03:46:58 INFO - Running setup.py install for blobuploader 03:46:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:46:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:46:58 INFO - Running setup.py install for requests 03:46:58 INFO - Running setup.py install for docopt 03:46:58 INFO - Successfully installed blobuploader requests docopt 03:46:58 INFO - Cleaning up... 03:46:58 INFO - Return code: 0 03:46:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e2090>, '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 0x16e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dcf90>, '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': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:46:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:46:58 INFO - Using env: {'DISPLAY': ':0', 03:46:58 INFO - 'HOME': '/home/cltbld', 03:46:58 INFO - 'LANG': 'en_US.UTF-8', 03:46:58 INFO - 'LANGUAGE': 'en_US:en', 03:46:58 INFO - 'LOGNAME': 'cltbld', 03:46:58 INFO - 'MAIL': '/var/mail/cltbld', 03:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:58 INFO - 'MOZ_NO_REMOTE': '1', 03:46:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:58 INFO - 'NO_EM_RESTART': '1', 03:46:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:58 INFO - 'PWD': '/builds/slave/test', 03:46:58 INFO - 'SHELL': '/bin/bash', 03:46:58 INFO - 'SHLVL': '1', 03:46:58 INFO - 'TERM': 'linux', 03:46:58 INFO - 'TMOUT': '86400', 03:46:58 INFO - 'USER': 'cltbld', 03:46:58 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 03:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:58 INFO - '_': '/tools/buildbot/bin/python'} 03:46:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:46:59 INFO - Running setup.py (path:/tmp/pip-YunH1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:46:59 INFO - Running setup.py (path:/tmp/pip-4J1Qut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:46:59 INFO - Running setup.py (path:/tmp/pip-zE2Byf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:46:59 INFO - Running setup.py (path:/tmp/pip-muNQ2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:46:59 INFO - Running setup.py (path:/tmp/pip-fio3xF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:46:59 INFO - Running setup.py (path:/tmp/pip-HrooRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:46:59 INFO - Running setup.py (path:/tmp/pip-Ug8KjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:46:59 INFO - Running setup.py (path:/tmp/pip-_ZdG1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:46:59 INFO - Running setup.py (path:/tmp/pip-8qHf4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:46:59 INFO - Running setup.py (path:/tmp/pip-xg7rj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:46:59 INFO - Running setup.py (path:/tmp/pip-uELGVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:46:59 INFO - Running setup.py (path:/tmp/pip-84xXxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:46:59 INFO - Running setup.py (path:/tmp/pip-UaIQov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:47:00 INFO - Running setup.py (path:/tmp/pip-EInSMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:47:00 INFO - Running setup.py (path:/tmp/pip-NavAlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:47:00 INFO - Running setup.py (path:/tmp/pip-L2E4lO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:47:00 INFO - Running setup.py (path:/tmp/pip-jN8qFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:47:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:47:00 INFO - Running setup.py install for manifestparser 03:47:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:47:00 INFO - Running setup.py install for mozcrash 03:47:00 INFO - Running setup.py install for mozdebug 03:47:00 INFO - Running setup.py install for mozdevice 03:47:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:47:00 INFO - Running setup.py install for mozfile 03:47:00 INFO - Running setup.py install for mozhttpd 03:47:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:47:00 INFO - Running setup.py install for mozinfo 03:47:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:47:00 INFO - Running setup.py install for mozInstall 03:47:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:47:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:47:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:47:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:47:01 INFO - Running setup.py install for mozleak 03:47:01 INFO - Running setup.py install for mozlog 03:47:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:47:01 INFO - Running setup.py install for moznetwork 03:47:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:47:01 INFO - Running setup.py install for mozprocess 03:47:01 INFO - Running setup.py install for mozprofile 03:47:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:47:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:47:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:47:01 INFO - Running setup.py install for mozrunner 03:47:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:47:01 INFO - Running setup.py install for mozscreenshot 03:47:01 INFO - Running setup.py install for moztest 03:47:01 INFO - Running setup.py install for mozversion 03:47:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:47:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:47:01 INFO - Cleaning up... 03:47:02 INFO - Return code: 0 03:47:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:02 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 0x13721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e2090>, '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 0x16e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dcf90>, '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': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:47:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:47:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:47:02 INFO - Using env: {'DISPLAY': ':0', 03:47:02 INFO - 'HOME': '/home/cltbld', 03:47:02 INFO - 'LANG': 'en_US.UTF-8', 03:47:02 INFO - 'LANGUAGE': 'en_US:en', 03:47:02 INFO - 'LOGNAME': 'cltbld', 03:47:02 INFO - 'MAIL': '/var/mail/cltbld', 03:47:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:02 INFO - 'MOZ_NO_REMOTE': '1', 03:47:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:02 INFO - 'NO_EM_RESTART': '1', 03:47:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:02 INFO - 'PWD': '/builds/slave/test', 03:47:02 INFO - 'SHELL': '/bin/bash', 03:47:02 INFO - 'SHLVL': '1', 03:47:02 INFO - 'TERM': 'linux', 03:47:02 INFO - 'TMOUT': '86400', 03:47:02 INFO - 'USER': 'cltbld', 03:47:02 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 03:47:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:02 INFO - '_': '/tools/buildbot/bin/python'} 03:47:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:47:02 INFO - Running setup.py (path:/tmp/pip-Q7Mu53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:47:02 INFO - Running setup.py (path:/tmp/pip-azcV3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:47:02 INFO - Running setup.py (path:/tmp/pip-r3xNdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:47:02 INFO - Running setup.py (path:/tmp/pip-P9DdWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:47:02 INFO - Running setup.py (path:/tmp/pip-69nwwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:47:02 INFO - Running setup.py (path:/tmp/pip-8z_xwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:47:02 INFO - Running setup.py (path:/tmp/pip-S57fTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:47:02 INFO - Running setup.py (path:/tmp/pip-JjwKU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:47:02 INFO - Running setup.py (path:/tmp/pip-ageAyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:47:02 INFO - Running setup.py (path:/tmp/pip-A8Y0vL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:47:02 INFO - Running setup.py (path:/tmp/pip-ahgoue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:47:03 INFO - Running setup.py (path:/tmp/pip-WdxxTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:47:03 INFO - Running setup.py (path:/tmp/pip-6EbNCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:47:03 INFO - Running setup.py (path:/tmp/pip-9j0uD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:47:03 INFO - Running setup.py (path:/tmp/pip-b3MHio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:47:03 INFO - Running setup.py (path:/tmp/pip-oxvSMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:47:03 INFO - Running setup.py (path:/tmp/pip-n0V5FR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:47:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:04 INFO - Downloading blessings-1.6.tar.gz 03:47:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:47:04 INFO - Installing collected packages: blessings 03:47:04 INFO - Running setup.py install for blessings 03:47:04 INFO - Successfully installed blessings 03:47:04 INFO - Cleaning up... 03:47:04 INFO - Return code: 0 03:47:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:47:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:47:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:47:04 INFO - Reading from file tmpfile_stdout 03:47:04 INFO - Current package versions: 03:47:04 INFO - argparse == 1.2.1 03:47:04 INFO - blessings == 1.6 03:47:04 INFO - blobuploader == 1.2.4 03:47:04 INFO - docopt == 0.6.1 03:47:04 INFO - functools32 == 3.2.3-2 03:47:04 INFO - jsonschema == 2.5.1 03:47:04 INFO - manifestparser == 1.1 03:47:04 INFO - mozInstall == 1.13 03:47:04 INFO - mozcrash == 1.0 03:47:04 INFO - mozdebug == 0.1 03:47:04 INFO - mozdevice == 0.51 03:47:04 INFO - mozfile == 1.2 03:47:04 INFO - mozhttpd == 0.7 03:47:04 INFO - mozinfo == 0.10 03:47:04 INFO - mozleak == 0.1 03:47:04 INFO - mozlog == 3.5 03:47:04 INFO - moznetwork == 0.27 03:47:04 INFO - mozprocess == 0.25 03:47:04 INFO - mozprofile == 0.28 03:47:04 INFO - mozrunner == 6.13 03:47:04 INFO - mozscreenshot == 0.1 03:47:04 INFO - mozsystemmonitor == 0.3 03:47:04 INFO - moztest == 0.8 03:47:04 INFO - mozversion == 1.4 03:47:04 INFO - psutil == 3.1.1 03:47:04 INFO - requests == 1.2.3 03:47:04 INFO - wsgiref == 0.1.2 03:47:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:05 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 0x13721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e2090>, '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 0x16e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dcf90>, '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': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:47:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:47:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:47:05 INFO - Using env: {'DISPLAY': ':0', 03:47:05 INFO - 'HOME': '/home/cltbld', 03:47:05 INFO - 'LANG': 'en_US.UTF-8', 03:47:05 INFO - 'LANGUAGE': 'en_US:en', 03:47:05 INFO - 'LOGNAME': 'cltbld', 03:47:05 INFO - 'MAIL': '/var/mail/cltbld', 03:47:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:05 INFO - 'MOZ_NO_REMOTE': '1', 03:47:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:05 INFO - 'NO_EM_RESTART': '1', 03:47:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:05 INFO - 'PWD': '/builds/slave/test', 03:47:05 INFO - 'SHELL': '/bin/bash', 03:47:05 INFO - 'SHLVL': '1', 03:47:05 INFO - 'TERM': 'linux', 03:47:05 INFO - 'TMOUT': '86400', 03:47:05 INFO - 'USER': 'cltbld', 03:47:05 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 03:47:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:05 INFO - '_': '/tools/buildbot/bin/python'} 03:47:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:47:05 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:47:06 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:47:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:47:06 INFO - Installing collected packages: simplejson 03:47:06 INFO - Running setup.py install for simplejson 03:47:06 INFO - building 'simplejson._speedups' extension 03:47:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 03:47:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 03:47:07 INFO - Successfully installed simplejson 03:47:07 INFO - Cleaning up... 03:47:07 INFO - Return code: 0 03:47:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:07 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 0x13721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x153ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e2090>, '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 0x16e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dcf90>, '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': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:47:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:47:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:47:07 INFO - Using env: {'DISPLAY': ':0', 03:47:07 INFO - 'HOME': '/home/cltbld', 03:47:07 INFO - 'LANG': 'en_US.UTF-8', 03:47:07 INFO - 'LANGUAGE': 'en_US:en', 03:47:07 INFO - 'LOGNAME': 'cltbld', 03:47:07 INFO - 'MAIL': '/var/mail/cltbld', 03:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:07 INFO - 'MOZ_NO_REMOTE': '1', 03:47:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:07 INFO - 'NO_EM_RESTART': '1', 03:47:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:07 INFO - 'PWD': '/builds/slave/test', 03:47:07 INFO - 'SHELL': '/bin/bash', 03:47:07 INFO - 'SHLVL': '1', 03:47:07 INFO - 'TERM': 'linux', 03:47:07 INFO - 'TMOUT': '86400', 03:47:07 INFO - 'USER': 'cltbld', 03:47:07 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 03:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:07 INFO - '_': '/tools/buildbot/bin/python'} 03:47:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:47:07 INFO - Cleaning up... 03:47:07 INFO - Return code: 0 03:47:07 INFO - Running post-action listener: _resource_record_post_action 03:47:07 INFO - Running post-action listener: _start_resource_monitoring 03:47:07 INFO - Starting resource monitoring. 03:47:07 INFO - [mozharness: 2017-09-13 10:47:07.710319Z] Finished create-virtualenv step (success) 03:47:07 INFO - [mozharness: 2017-09-13 10:47:07.710835Z] Running install step. 03:47:07 INFO - Running pre-action listener: _resource_record_pre_action 03:47:07 INFO - Running main action method: install 03:47:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:47:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:47:07 INFO - Reading from file tmpfile_stdout 03:47:07 INFO - Detecting whether we're running mozinstall >=1.0... 03:47:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:47:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:47:08 INFO - Reading from file tmpfile_stdout 03:47:08 INFO - Output received: 03:47:08 INFO - Usage: mozinstall [options] installer 03:47:08 INFO - Options: 03:47:08 INFO - -h, --help show this help message and exit 03:47:08 INFO - -d DEST, --destination=DEST 03:47:08 INFO - Directory to install application into. [default: 03:47:08 INFO - "/builds/slave/test"] 03:47:08 INFO - --app=APP Application being installed. [default: firefox] 03:47:08 INFO - mkdir: /builds/slave/test/build/application 03:47:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:47:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:47:24 INFO - Reading from file tmpfile_stdout 03:47:24 INFO - Output received: 03:47:24 INFO - /builds/slave/test/build/application/firefox/firefox 03:47:24 INFO - Running post-action listener: _resource_record_post_action 03:47:24 INFO - [mozharness: 2017-09-13 10:47:24.428947Z] Finished install step (success) 03:47:24 INFO - [mozharness: 2017-09-13 10:47:24.429202Z] Running setup-mitmproxy step. 03:47:24 INFO - Running pre-action listener: _resource_record_pre_action 03:47:24 INFO - Running main action method: setup_mitmproxy 03:47:24 INFO - Skipping: mitmproxy is not required 03:47:24 INFO - Running post-action listener: _resource_record_post_action 03:47:24 INFO - [mozharness: 2017-09-13 10:47:24.430309Z] Finished setup-mitmproxy step (success) 03:47:24 INFO - [mozharness: 2017-09-13 10:47:24.430526Z] Running run-tests step. 03:47:24 INFO - Running pre-action listener: _resource_record_pre_action 03:47:24 INFO - Running pre-action listener: _set_gcov_prefix 03:47:24 INFO - Running main action method: run_tests 03:47:24 WARNING - Try message not found. 03:47:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:47:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:47:24 INFO - Python 2.7.3 03:47:24 INFO - Return code: 0 03:47:24 INFO - grabbing minidump binary from tooltool 03:47:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:24 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e2090>, '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 0x16e2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dcf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:47:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:47:24 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:47:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:47:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:47:24 INFO - Return code: 0 03:47:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:47:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:47:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:47:24 INFO - ENV: RUST_BACKTRACE is now full 03:47:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:47:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:47:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-078', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:47:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-078 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:47:24 INFO - Using env: {'DISPLAY': ':0', 03:47:24 INFO - 'HOME': '/home/cltbld', 03:47:24 INFO - 'LANG': 'en_US.UTF-8', 03:47:24 INFO - 'LANGUAGE': 'en_US:en', 03:47:24 INFO - 'LOGNAME': 'cltbld', 03:47:24 INFO - 'MAIL': '/var/mail/cltbld', 03:47:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:47:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:47:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:24 INFO - 'MOZ_NO_REMOTE': '1', 03:47:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:47:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:24 INFO - 'NO_EM_RESTART': '1', 03:47:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:24 INFO - 'PWD': '/builds/slave/test', 03:47:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:47:24 INFO - 'RUST_BACKTRACE': 'full', 03:47:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:47:24 INFO - 'SHELL': '/bin/bash', 03:47:24 INFO - 'SHLVL': '1', 03:47:24 INFO - 'TERM': 'linux', 03:47:24 INFO - 'TMOUT': '86400', 03:47:24 INFO - 'USER': 'cltbld', 03:47:24 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 03:47:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:24 INFO - '_': '/tools/buildbot/bin/python'} 03:47:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-078', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:47:24 INFO - mozversion application_buildid: 20170913092615 03:47:24 INFO - mozversion application_changeset: 124f2094635bbf1901c1d14fc2c0a80bf7f847bb 03:47:24 INFO - mozversion application_display_name: Nightly 03:47:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:47:24 INFO - mozversion application_name: Firefox 03:47:24 INFO - mozversion application_remotingname: firefox 03:47:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:47:24 INFO - mozversion application_vendor: Mozilla 03:47:24 INFO - mozversion application_version: 57.0a1 03:47:24 INFO - mozversion platform_buildid: 20170913092615 03:47:24 INFO - mozversion platform_changeset: 124f2094635bbf1901c1d14fc2c0a80bf7f847bb 03:47:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:47:24 INFO - mozversion platform_version: 57.0a1 03:47:24 INFO - using testdate: 1505299644 03:47:24 INFO - actual date: 1505299644 03:47:24 INFO - starting webserver on 'localhost:60403' 03:47:24 INFO - SUITE-START | Running 2 tests 03:47:24 INFO - TEST-START | dromaeo_css 03:47:24 INFO - Initialising browser for dromaeo_css test... 03:47:24 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60403/getInfo.html -profile /tmp/tmpAMxiS1/profile 03:47:24 INFO - TEST-INFO | started process 14805 (/builds/slave/test/build/application/firefox/firefox http://localhost:60403/getInfo.html) 03:47:29 INFO - TEST-INFO | 14805: exit 0 03:47:29 INFO - Browser initialized. 03:47:29 INFO - Running cycle 1/1 for dromaeo_css test... 03:47:29 INFO - Using env: {'DISPLAY': ':0', 03:47:29 INFO - 'HOME': '/home/cltbld', 03:47:29 INFO - 'JSGC_DISABLE_POISONING': '1', 03:47:29 INFO - 'LANG': 'en_US.UTF-8', 03:47:29 INFO - 'LANGUAGE': 'en_US:en', 03:47:29 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:47:29 INFO - 'LOGNAME': 'cltbld', 03:47:29 INFO - 'MAIL': '/var/mail/cltbld', 03:47:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:47:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:47:29 INFO - 'MOZ_CRASHREPORTER': '1', 03:47:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:29 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:47:29 INFO - 'MOZ_NO_REMOTE': '1', 03:47:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:47:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:29 INFO - 'NO_EM_RESTART': '1', 03:47:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:29 INFO - 'PWD': '/builds/slave/test', 03:47:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:47:29 INFO - 'RUST_BACKTRACE': 'full', 03:47:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:47:29 INFO - 'SHELL': '/bin/bash', 03:47:29 INFO - 'SHLVL': '1', 03:47:29 INFO - 'TERM': 'linux', 03:47:29 INFO - 'TMOUT': '86400', 03:47:29 INFO - 'USER': 'cltbld', 03:47:29 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 03:47:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:29 INFO - '_': '/tools/buildbot/bin/python'} 03:47:29 INFO - TEST-INFO | started process 15005 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAMxiS1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:47:30 INFO - PID 15005 | 03:47:30 INFO - PID 15005 | (/builds/slave/test/build/application/firefox/firefox:15056): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:47:30 INFO - PID 15005 | 03:47:41 INFO - PID 15005 | RSS: Main: 166354944 03:47:41 INFO - PID 15005 | 03:50:37 INFO - PID 15005 | Cycle 1(1): loaded http://localhost:60403/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60403/tests/dromaeo/cssquery-ext.html) 03:50:38 INFO - PID 15005 | RSS: Main: 168202240 03:50:38 INFO - PID 15005 | 03:53:40 INFO - PID 15005 | Cycle 1(1): loaded http://localhost:60403/tests/dromaeo/cssquery-ext.html (next: http://localhost:60403/tests/dromaeo/cssquery-jquery.html) 03:53:41 INFO - PID 15005 | RSS: Main: 164884480 03:53:41 INFO - PID 15005 | 03:56:42 INFO - PID 15005 | Cycle 1(1): loaded http://localhost:60403/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60403/tests/dromaeo/cssquery-mootools.html) 03:56:43 INFO - PID 15005 | RSS: Main: 167538688 03:56:43 INFO - PID 15005 | 03:59:44 INFO - PID 15005 | Cycle 1(1): loaded http://localhost:60403/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60403/tests/dromaeo/cssquery-prototype.html) 03:59:45 INFO - PID 15005 | RSS: Main: 165695488 03:59:45 INFO - PID 15005 | 04:02:48 INFO - PID 15005 | Cycle 1(1): loaded http://localhost:60403/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60403/tests/dromaeo/cssquery-yui.html) 04:02:49 INFO - PID 15005 | RSS: Main: 167170048 04:02:49 INFO - PID 15005 | 04:05:50 INFO - PID 15005 | Cycle 1(1): loaded http://localhost:60403/tests/dromaeo/cssquery-yui.html (next: http://localhost:60403/tests/dromaeo/cssquery-dojo.html) 04:05:50 INFO - PID 15005 | RSS: Main: 169906176 04:05:50 INFO - PID 15005 | 04:05:50 INFO - PID 15005 | __start_tp_report 04:05:50 INFO - PID 15005 | _x_x_mozilla_page_load 04:05:50 INFO - PID 15005 | _x_x_mozilla_page_load_details 04:05:50 INFO - PID 15005 | |i|pagename|runs| 04:05:50 INFO - PID 15005 | |0;dojo.html;3833.9424908626365;3847.826847791849;3849.1724279279956;3863.845446182153;3866.4393662918897;6194.504439644829;6261.655608941506;6355.71399287032;6360;6417.165768450107;6300.46446052085;6331.271903731073;6343.270523889742;6347.079673447347;6405.359464053604;5572.0527510323345;5715.914261286084;5733.713314334266;5736.512396446307;5741.712914354286;140.80746999770284;5742.971285143569;5764.077747560391;5794.797182098628;5827.388124246935;5665;5673.517750991171;5682.715864206793;5768.567357448166;5769.538436925078;5674.205611214434;5674.687892165929;5749.568782341341;5759.452851979056;5768.711564421804;5672.574556908248;5690.118031705092;5739.081746920514;5761.942380576183;5774;6334.461570766491;6348.1430006949395;6350.33321501245;6397.8400539986405;6410.967945160244;6651.334866513358;6816.182058153054;6832.145981752281;6877.759278425231;6966.582005079661;6822.795316140524;6897.896531552031;6910.101686780737;6917.100776898971;6919.8962015569805;6373.681315934231;6379.904301435482;6419.807405777834;6449.871002579922;6456.677166141674;7042.330978557029;7044.401225895754;7123.7506687265695;7147.714091436284;7193.676284567207;6680.966595166993;6730.966345168341;6780.32196780318;6799.3200679931615;6829.829254268683;6942.514024018371;6968.303169683092;6971.6862741176765;6980;6999.160100787938;6821.147356580427;6822.317768223138;6892.276310987274;6907.274736152632;6935.063766391472;6813.7955861324235;6826.931730682729;6834.282400347991;6913.89629155563;6917.239103698589;4910.8281210157475;4949.628777841676;4980.850574482772;5052.520010598987;5052.9242061369105;4528.932066019012;4547.090581883636;4586.610138138296;4600.263957766741;4611.123886461562;6070.575059745864;6163.19878415812;6198.907016394758;6215.875682486325;6223.96888015566;6761.695723692347;6799.422049125781;6870.725170993204;6924.93075069253;6941.236463988957;6171.783987560503;6208.379162083846;6225.6264624121795;6228.190335256388;6229.906551401733;5435.755391007414;5552;5561.249231353715;5577.079781836032;5580.386157522669;6938.722451101899;6970.686319115551;6972.721091156297;7007.124109486314;7040.225575186725;6870.072540207008;6882.208546017272;6936.132983377078;6940.514164008471;6959.4432445405255;6914.066601009001;6986.126734158231;6995.440364770704;7100.147982242164;7102;6778.288279730755;6813.04617353561;6826.658667066527;6833.145856767904;6846.383825455733;6852.28051054652;6935.375816176568;6948.33990770876;6949.583025018515;6977.197622273504;6528.804135875931;6552.3775241352;6558.245801732862;6587.110740050223;6604.471642268703;6484.902726459201;6515.087887695631;6572.309907459648;6590.373914478117;6661.4004739573675;6543.083968244355;6581.210254769267;6583.967080164569;6584.144061272004;6627.138471998609;6527.216733991761;6610.867782644224;6627.271000189879;6642.4021838034805;6694.263631000684;6427.1002059710745;6450.258220304538;6490.675466226764;6492.156019717407;6537.673116344259 04:05:50 INFO - PID 15005 | |1;ext.html;949.8242825077361;962.2350231565904;984.6159997600937;1015.786684796193;1017.7455636090978;44381;44857.87855303615;45042.09915801682;45056.3241551377;45085.323720144224;41739.33042678289;41968.160636787245;42084.158316833644;42091.368629470504;42106.78946605263;7407.407407407394;7476.9252307477045;7491.101067871863;7521.435892308072;7530.397568194531;7320.450966177531;7417.072865891763;7479.401647868158;7494.288042635941;7495.775126746206;7430.219826918177;7460.104787425489;7463.216362281936;7881.724139655084;8041.919580804176;7879.724209652663;7936.127026027132;7938.5236885787035;7944.08643006053;7944.483608565425;7722.652480638329;7811.531308121531;7818.648160832776;7827.295543401065;7907.288344049006;41587;41935.7903210485;41968.79015604933;42022.579774202175;42032.36951445731;3617.981910090459;3655.6161603031564;3655.780653160792;3656.634336566349;3657;33713.15717107092;33716;33729.49405758915;33782.32435351304;33791.66208337934;5451.182322651634;5451.64564303323;5473.233747275345;5475.808346707886;5480.561555075585;226639;226771;227356;227423;227473.99999999834;52056.73971630118;52255.216171757456;52260.738696306275;52271.73864130655;52626.73686631619;56176.71911640416;56256.437435625936;56332.15501767477;56375.15437268444;56379.71810140923;15932.681346373007;16135.112568808829;16142;16181.110038947736;16183.514494565183;13004.349782511026;13008.414621342063;13009.284489353176;13014.089013768946;13024.609261722164;14976.326065327086;15263.771043434357;15348.616284592974;15359.078455292718;15438.459653912283;6093.8476538086015;6145.385461453909;6220.657863817443;6231.252249729971;6402.423781859655;1102.2725001499132;1102.2780079048237;1109.866815982071;1112.6884472347756;1127.8139107047245;25164.74835251624;25210.495790084096;25343;25373;25480.49039019209;1191.53530123252;1209.0690168570152;1211.5517258614193;1219.8475190601175;1219.8597161326384;13284.73430531384;13453.327333633279;13459.461621535029;13512.864871351358;13581.709737574914;284389;284609;285687;285941;286380;51748.22377664338;52413.7379313101;57933.71033144807;59420.405795941486;59902.10146847801;1550.4418409372618;1557.128008315347;1565.7807906893045;1565.827758946515;1580.0203873598443;30351.544726828673;30858;30904.845475772476;30953.845230773702;31325.216869577533;41770.58229417667;41873.79063104787;41925.371119432624;41977.37033944432;41988.37017444802;250565;251625;251733;251968;254039;36011.09972250715;36073.0981725459;36155.27689446249;36257.63742362648;36436.635633644386;46575;46661;46822.76588617035;47063;47128.528714713786;34212.48681269733;34422.13944651404;34539.654603453644;34718;34743;34439.65560344364;34738;35006.12484687801;35256.823715881255;35411;39077.41383879187;39794;40198;40337.1932561353;40458.190836182526;33649.32701345911;34004.82997585095;34074.829625852704;34189.14527136742;34272.65727342795;24877.378065547782;25012.37469063288;25625;26042.479150417264;26198.476030479665 04:05:50 INFO - PID 15005 | |2;jquery.html;585.5403508246028;590.8227531740476;591.5208680968414;601.4646964201861;612.9295131059928;22912.541749165026;23290.301290961277;23336.8833155834;23338.41653958652;23486.76513234867;23853.16513922013;25042.749572504268;25211.369715757115;25446.10938617144;25476.74523254767;6474.320196379383;6697.799066028003;6706.094677218586;6706.664666766642;6710;6480.125183100292;6528.2166140063255;6657.933420665804;6736.259011508755;6773.627450490221;6715.362040606159;6720.966395168018;6728.663566821664;6730.83172920676;6734.3939045485895;6645.734170633169;6700.564463309894;6709.027191057269;6715.496337774637;6724.361185687328;6197.876042479125;6213.347598502182;6248;6264.1543391642;6264.467520260738;28753.56869646936;29486.115416537537;29919.700802992127;30037.699623003708;30136.095917122522;2283.3834864586634;2290.5533420982915;2290.8510946788406;2316.4324740438533;2317.501737126509;20167.19331226764;20543;20799.16803327865;20975.265865694626;20977.685334719838;2416.987915060431;2478.479519300949;2503.8873250703764;2538.631898374725;2584.147231413629;140921;145504;157403;165124.17437912853;165812;15386.692266154614;15587.922060389625;15662.765058524132;15780.210989450481;16500.504984850475;25199;25207.495850082894;25298.494030119295;25302;25374.365640859127;10487.37075775456;10488.73778155537;10491.213159013105;10687.091597214168;10806.081483074027;9663.371880827724;9712.61149554024;9713.125818676353;9742.07450292221;9795.804083918281;9396.624135034659;9515.476648784383;9676.274279429072;9738.46438445878;9760.779902512186;2771.3487330477324;2777.1113243762206;2884.5096333623333;2890.07517594372;2894.2185609885214;1040.1626690514208;1061.4904845673966;1065.174489770434;1070.7430216747925;1071.3036526257995;20908.268210612554;21032.78967210339;21153.36539903805;21165.89417052905;21366.35900922975;1131.7227279316621;1136.108155098244;1138.6754774889116;1142.4230763464564;1143.5997400909614;11352.091832653368;11591.536338546532;11618.477168527437;11710.70723231926;11778.646640600795;584954;599712;603615;611945;613311;125380.37309813393;127680;130345.34827325803;131064;132766;1110.6112860498922;1161.59344229519;1221.926684398939;1222.541546919905;1271.0657666614845;21586.70573612124;21953.560928781655;21972;22080.558388832455;22264.443388915406;62214.37785622086;63614.68192659007;63616.68191659012;63841.361586383544;66119.66940165269;80758.19241807506;82234.58882705549;82653;83167.5841620788;83276;14964.551063468114;15036.924815375853;15065;15248.77126843076;15313.387464501295;23494.177703780286;23727.1695490657;23869.64195537103;23937.64093538633;24104.276871693877;19376.031198440305;19400.902995484932;19516.707249391555;19737.210511579375;19761;17144.142792860555;17287.827121729126;17569.945803251845;17722.5569360767;17986.100694964938;19650.50873728168;20080.799192007893;20114.59770804605;20214.898925505866;20298.492537686678;15122.470713524974;15156.469523566622;15183.392664293304;15190.924045379703;15271.007384519971;19103.140358683613;19248.711269331252;19258.7111193335;19498.220071196993;19581.804181958 04:05:50 INFO - PID 15005 | |3;mootools.html;1390.7009992851538;1435.7989881416597;1441.8774404175651;1443.5669299210235;1451.462958705279;14149.221792801405;14755;14758;16833.494995150166;17279.04965226912;16973.57566060849;17194.39819606314;17227.138643067858;17242.39651612194;17248.827511724878;2844.7439730424258;2866.727660872219;2914.8251104933665;2918.591397204393;2950.3066779306846;2845.089571337173;2865.4269146170745;2871.55490898911;2891.1037578350674;2893.782966277527;2864.69920658331;2914.562815577659;2924.722151395614;2928.3411232472736;2930.472514947309;2793.3435642623967;2803.355228297503;2806.9473947269876;2810.1148138336357;2824.3362809739697;2735.39821239329;2740.328619488218;2756.517609418344;2758.089830355998;2760.1029665358637;16395.344186232538;16764.83235167645;16777;16779.748303775454;16788.328466861432;8656.740297791077;8936.285097192209;8964.551772411354;8977.506237156953;8979.416337938079;5646.435356464362;5677.886442271131;5790.884182316372;5823.9708801456145;5858.619189752695;2031.837453003756;2036.5417780999157;2068.493219161315;2081.3339731285996;2100.2124203423714;30381.544276835866;30539;30598.69401305981;30626.23434414135;30659.233519161753;7232.168300645388;7272.600006999666;7414.703411863573;7428.888566671504;7538.170801211862;19152.8084719151;19154.233830646896;19186.328478503186;19187.32844350441;19212.423627291202;7603.543787372665;8510.361722870686;8653.39426240157;8655.56722163902;8660.437071590326;6918.584884906922;7825.647845846951;7915.248051435105;7950.920490795133;7968.12350641429;5593.489757765462;5660.943390566124;5763.625364351304;5778.393268706809;5817.476427121579;1641.458318754808;1683.6026098338596;1690.5435532406182;1700.8469237768413;1713.254734190631;988.5946761827616;993.1260490768078;1000.5347513406156;1002.1031177096457;1024.4212020208558;8773.605187766565;8835.337349698799;8871.290296776371;8887.466751994902;8924.68763593271;785.4070177016316;798.8122791144076;799.9160088190772;807.8990126234221;819.3936486999578;5336.839894803162;5338.813141540028;5457.126859702428;5478.534324582455;5515.862103447446;24304.635430468556;24662.26013219606;24790.38024049413;25053.12314068999;25112.12107576226;24546.140885068937;24647.5070498589;24660;24907.377315566893;24954.12660556872;12018.579349722717;12380.133390662568;12583.496660133493;12627.621171364874;12653;9626.62305283532;9780.804383912424;9805.803883922425;9814.950925245328;9844;20478.283260085824;20507.38477845667;21350;21384;21608.459788505414;21332.36002919915;21434.356969290948;21697.783022169577;21720.782792172507;21751.891240543697;12065.698357541132;12139.75720485603;12144.75710485803;12175.75648487043;12217.08371872131;15400.15299158557;15514.146721930401;15586.220688965734;15651.843481565038;15705;12429.875701243234;12678.809817852924;12755.87244127584;12899.806502902276;13011.154274972476;11988.280703157838;12017.399130043637;12185.51257949672;12262.141650084408;12367.62897113088;10917.290376125526;11083.168762342695;11084.279521831055;11087.611933582286;11124.777504449703;7827.217278272355;7972.1230664628;7986.081600616004;8009.799755006172;8022.759317220492;8054.23484768946;8325.791855203668;8327.583620818814;8330.416870818985;8400.201980811813 04:05:50 INFO - PID 15005 | |4;prototype.html;541.0963690636636;555.6305057137004;560.215698022768;568.9829305120847;580.8576898659828;6287.799270255408;6341.7146228419715;6411.871762564746;6422.357764223575;6425.742970281189;6172.4444799968;6179.867945558786;6225.719842607083;6273.309935907047;6281.874362512747;5174.974125129388;5196.896062078757;5199.818006369778;5227.21591761237;5230.450802665722;5057.2666963290485;5089.2620570017325;5136.8972620547565;5155.304033955405;5220.347456567929;5224.582033437316;5226.451222621608;5239.6115029517205;5268.341457317835;5287.259783630295;5251.973740131313;5263.894582137775;5267.52592266696;5285.497877701612;5308.442613525563;4786.856394308176;4902.828401005948;4909.140900342426;4925.408950925912;4935.210366341404;6223.253209614875;6266.279377871558;6309;6315.273743519509;6353.650549219791;6178.69106544671;6205.782797602107;6250.87498250037;6265.373462653698;6302.338254483259;6740.224874139439;6747.898781518281;6748.730050797963;6854.9657251713925;6860.451163906875;5433.510984011418;5448.645838020516;5486.61593688442;5498.092814685572;5517.806876759334;6875.278095799969;6905.413039891615;6933.306669333027;7005.89491157628;7103.3251841075535;5677.489025987681;5697.715114244271;5737.627054241461;5741.564608847788;5743.885122297531;5779.24869766936;5783.306003279633;5785.190073389729;5812.244408226903;5831.679257640785;6151.72317245725;6206.627602343874;6252.468540174136;6259.715203950525;6277.901367260711;6791.4906382021545;6807.01298311742;6907.274736152732;6912.412444942236;6933.375996160269;1975.2000439821895;1987.125664707553;2038.194913009353;2040.6224848403092;2048.566003797348;1463.4000059975358;1487.8586534279443;1517.840626734177;1538.1155835394693;1539.3919401836436;6152.876942461216;6160.691965401712;6186.721597528122;6229.968850155811;6285.182926219653;6761.01965215041;6796.864062718817;6802.455803535705;6810.467644779885;6847.014365833852;6152;6158.846028849225;6164.938350616437;6180.289266734293;6194.969025154845;3602.819859007039;3613.747037707335;3629.7459177857295;3635.8545658173375;3644.180059486647;7021.543599666005;7038.824029399306;7059.964700176466;7111.502194846415;7135.928640713631;7024.911138773498;7044.133304674266;7049.436045116378;7064.505484616027;7102.92897071033;6982.965085174643;7010.719571217298;7062.611556364346;7072;7095.822604434828;6815.216250131299;6816.182058153054;6824.7952561423235;6841.794746157623;6861.931380686329;6783.423409010289;6795.864082718219;6796.524243302922;6813.69338379784;6853.314668533107;6919.792406227822;6941.652917354213;6941.965290173517;6942.757003504853;7107.395871350993;5539.861503462445;5646.322441307069;5692.829215123553;5742.339630942495;5794.145873320497;6457.257415397102;6766.661666916536;6812.216595091628;6887.896681549681;6941.930580694128;6524.217093948757;6551.770688025897;6596.868062638624;6620.437262832521;6647.268800432045;6498.772542960974;6500.934990650222;6580.572262803094;6584.275729669636;6637.933620663925;6454.5159113065765;6486.837829054122;6499.967500162658;6529.771457998947;6628.966855165693;6562.245341785756;6657.40083392497;6683.699233534599;6685.8997115044285;6742.572673837317;6477.708503117466;6480.287168411565;6523.804285871432;6540.967295163494;6547.836304092436 04:05:50 INFO - PID 15005 | |5;yui.html;970.0881171698599;978.7895602445474;998.4059484606661;1002.5989604158336;1006.1699098243951;19891.104900279486;20210.292639757612;20256.696149557767;20285.39143825686;20315.288964886233;18552.72170917434;18715;18741.906290468527;18773.155208015636;18850.81149188511;986.6053578568558;1006.6476733143396;1009.7122320138762;1009.9444530550817;1011.4689787861358;1092.2190633696905;1095.8904109589057;1097.6432659385691;1097.9560817567287;1099.598646494032;1074.8441475985978;1111.2943281016533;1118.1893127482551;1122.9101671866272;1124.3984468309457;1111.838783376406;1112.6884472347756;1112.8330750387427;1113.4544073404054;1113.6492005018392;791.0625908298684;804.8390321935577;810.7810891059381;814.8573999550114;819.2585709932521;20178.5964280715;20351;20352.898235508877;20378.79621203784;20384.694229586567;23132;23305.533889322287;23864.880675596683;23955.760442395527;23986.400339991465;17355.47933561995;17808.821911780975;18022.098895055195;18050.27798888043;18105.547361316072;3959.703022273341;3978.1645854370618;3989.8005099744896;3993.9600603993877;3995.2808494470983;135887;157610;159767;161032;162727.9999999988;16351.345946162259;16472.505824825275;16638.75041874373;16662.33350665984;17342.479725608253;26875.86562067177;26968.59547106795;27069.53352696783;27070.593941090898;27772.58341124885;9538.713838584854;9570.712878613653;9577.425354478753;9612.855807162898;9648.807023859483;4534.569215924482;4593.6554758392585;4597.42532183477;4602.194615942263;4606.700564463299;6084.30030546484;6244.12582238487;6254.937450625526;6273.843153921189;6335.968320158462;1144.7367105565766;1159.2754528419737;1177.587844254504;1182.5683625476638;1182.7457096724245;688.5283580747205;699.9370056694922;703.1702590942737;712.3588770106842;714.5534041224234;12384.81422778659;12861.4212360444;13550;14657.706845863024;14692.853071469148;692.4806395203598;695.1796879682024;700.6636814329048;704.1339152842033;715.2632788228134;6063.969125248718;6065.5754097212775;6102.938970610237;6149.600275982137;6161.907571386433;153624;154761;155261.2236938808;156973;157571;134890;135016;135760;136692.31653841666;142844;117791.41104294424;118937.40531297289;120021.79978200106;120206;122909.77090229162;11073.224874258725;11075.446227688744;11272.436378180893;11327.15046371509;11349;55581.72209138928;55961.440385595626;55968.72015639896;56036.43963560475;56525;73558.793236203;74097;74393.6280318595;74692.62653686914;75477.62261188659;16744.74882876782;16927.407540736014;16990.830091698925;17050.14749262507;17067.658646826745;19098.52253693669;19569;19586.11862466221;19637.11632976491;19760;15438.691226175639;15444.150571718663;15736.606584835014;15880.205989700238;15882;16251;16372;16464.67070658604;16519.33922643081;16580;17356.91321543384;17454.650906982042;17497.38759143424;17631;17660.1169941505;12438.37808109609;12460.626181214579;12482.687932801753;12495.312757798045;12552.937235313764;12774.489020439078;12775.297358645365;12902.096853220184;13002.869971300544;13028.804567931678 04:05:50 INFO - PID 15005 | __end_tp_report 04:05:50 INFO - PID 15005 | __start_cc_report 04:05:50 INFO - PID 15005 | _x_x_mozilla_cycle_collect,361 04:05:50 INFO - PID 15005 | __end_cc_report 04:05:50 INFO - PID 15005 | __startTimestamp1505300750838__endTimestamp 04:05:50 INFO - PID 15005 | 04:05:50 INFO - PID 15005 | ------- Summary: start ------- 04:05:50 INFO - PID 15005 | Number of tests: 6 04:05:50 INFO - PID 15005 | 04:05:50 INFO - PID 15005 | [#0] dojo.html Cycles:165 Average:6271.73 Median:6528.80 stddev:887.48 (13.6%) stddev-sans-first:869.35 04:05:50 INFO - PID 15005 | Values: 3833.9 3847.8 3849.2 3863.8 3866.4 6194.5 6261.7 6355.7 6360.0 6417.2 6300.5 6331.3 6343.3 6347.1 6405.4 5572.1 5715.9 5733.7 5736.5 5741.7 140.8 5743.0 5764.1 5794.8 5827.4 5665.0 5673.5 5682.7 5768.6 5769.5 5674.2 5674.7 5749.6 5759.5 5768.7 5672.6 5690.1 5739.1 5761.9 5774.0 6334.5 6348.1 6350.3 6397.8 6411.0 6651.3 6816.2 6832.1 6877.8 6966.6 6822.8 6897.9 6910.1 6917.1 6919.9 6373.7 6379.9 6419.8 6449.9 6456.7 7042.3 7044.4 7123.8 7147.7 7193.7 6681.0 6731.0 6780.3 6799.3 6829.8 6942.5 6968.3 6971.7 6980.0 6999.2 6821.1 6822.3 6892.3 6907.3 6935.1 6813.8 6826.9 6834.3 6913.9 6917.2 4910.8 4949.6 4980.9 5052.5 5052.9 4528.9 4547.1 4586.6 4600.3 4611.1 6070.6 6163.2 6198.9 6215.9 6224.0 6761.7 6799.4 6870.7 6924.9 6941.2 6171.8 6208.4 6225.6 6228.2 6229.9 5435.8 5552.0 5561.2 5577.1 5580.4 6938.7 6970.7 6972.7 7007.1 7040.2 6870.1 6882.2 6936.1 6940.5 6959.4 6914.1 6986.1 6995.4 7100.1 7102.0 6778.3 6813.0 6826.7 6833.1 6846.4 6852.3 6935.4 6948.3 6949.6 6977.2 6528.8 6552.4 6558.2 6587.1 6604.5 6484.9 6515.1 6572.3 6590.4 6661.4 6543.1 6581.2 6584.0 6584.1 6627.1 6527.2 6610.9 6627.3 6642.4 6694.3 6427.1 6450.3 6490.7 6492.2 6537.7 04:05:50 INFO - PID 15005 | 04:05:50 INFO - PID 15005 | [#1] ext.html Cycles:180 Average:43292.58 Median:19937.81 stddev:66587.55 (334.0%) stddev-sans-first:66698.44 04:05:50 INFO - PID 15005 | Values: 949.8 962.2 984.6 1015.8 1017.7 44381.0 44857.9 45042.1 45056.3 45085.3 41739.3 41968.2 42084.2 42091.4 42106.8 7407.4 7476.9 7491.1 7521.4 7530.4 7320.5 7417.1 7479.4 7494.3 7495.8 7430.2 7460.1 7463.2 7881.7 8041.9 7879.7 7936.1 7938.5 7944.1 7944.5 7722.7 7811.5 7818.6 7827.3 7907.3 41587.0 41935.8 41968.8 42022.6 42032.4 3618.0 3655.6 3655.8 3656.6 3657.0 33713.2 33716.0 33729.5 33782.3 33791.7 5451.2 5451.6 5473.2 5475.8 5480.6 226639.0 226771.0 227356.0 227423.0 227474.0 52056.7 52255.2 52260.7 52271.7 52626.7 56176.7 56256.4 56332.2 56375.2 56379.7 15932.7 16135.1 16142.0 16181.1 16183.5 13004.3 13008.4 13009.3 13014.1 13024.6 14976.3 15263.8 15348.6 15359.1 15438.5 6093.8 6145.4 6220.7 6231.3 6402.4 1102.3 1102.3 1109.9 1112.7 1127.8 25164.7 25210.5 25343.0 25373.0 25480.5 1191.5 1209.1 1211.6 1219.8 1219.9 13284.7 13453.3 13459.5 13512.9 13581.7 284389.0 284609.0 285687.0 285941.0 286380.0 51748.2 52413.7 57933.7 59420.4 59902.1 1550.4 1557.1 1565.8 1565.8 1580.0 30351.5 30858.0 30904.8 30953.8 31325.2 41770.6 41873.8 41925.4 41977.4 41988.4 250565.0 251625.0 251733.0 251968.0 254039.0 36011.1 36073.1 36155.3 36257.6 36436.6 46575.0 46661.0 46822.8 47063.0 47128.5 34212.5 34422.1 34539.7 34718.0 34743.0 34439.7 34738.0 35006.1 35256.8 35411.0 39077.4 39794.0 40198.0 40337.2 40458.2 33649.3 34004.8 34074.8 34189.1 34272.7 24877.4 25012.4 25625.0 26042.5 26198.5 04:05:50 INFO - PID 15005 | 04:05:50 INFO - PID 15005 | [#2] jquery.html Cycles:180 Average:39805.36 Median:12803.90 stddev:101046.96 (789.2%) stddev-sans-first:101287.51 04:05:50 INFO - PID 15005 | Values: 585.5 590.8 591.5 601.5 612.9 22912.5 23290.3 23336.9 23338.4 23486.8 23853.2 25042.7 25211.4 25446.1 25476.7 6474.3 6697.8 6706.1 6706.7 6710.0 6480.1 6528.2 6657.9 6736.3 6773.6 6715.4 6721.0 6728.7 6730.8 6734.4 6645.7 6700.6 6709.0 6715.5 6724.4 6197.9 6213.3 6248.0 6264.2 6264.5 28753.6 29486.1 29919.7 30037.7 30136.1 2283.4 2290.6 2290.9 2316.4 2317.5 20167.2 20543.0 20799.2 20975.3 20977.7 2417.0 2478.5 2503.9 2538.6 2584.1 140921.0 145504.0 157403.0 165124.2 165812.0 15386.7 15587.9 15662.8 15780.2 16500.5 25199.0 25207.5 25298.5 25302.0 25374.4 10487.4 10488.7 10491.2 10687.1 10806.1 9663.4 9712.6 9713.1 9742.1 9795.8 9396.6 9515.5 9676.3 9738.5 9760.8 2771.3 2777.1 2884.5 2890.1 2894.2 1040.2 1061.5 1065.2 1070.7 1071.3 20908.3 21032.8 21153.4 21165.9 21366.4 1131.7 1136.1 1138.7 1142.4 1143.6 11352.1 11591.5 11618.5 11710.7 11778.6 584954.0 599712.0 603615.0 611945.0 613311.0 125380.4 127680.0 130345.3 131064.0 132766.0 1110.6 1161.6 1221.9 1222.5 1271.1 21586.7 21953.6 21972.0 22080.6 22264.4 62214.4 63614.7 63616.7 63841.4 66119.7 80758.2 82234.6 82653.0 83167.6 83276.0 14964.6 15036.9 15065.0 15248.8 15313.4 23494.2 23727.2 23869.6 23937.6 24104.3 19376.0 19400.9 19516.7 19737.2 19761.0 17144.1 17287.8 17569.9 17722.6 17986.1 19650.5 20080.8 20114.6 20214.9 20298.5 15122.5 15156.5 15183.4 15190.9 15271.0 19103.1 19248.7 19258.7 19498.2 19581.8 04:05:50 INFO - PID 15005 | 04:05:50 INFO - PID 15005 | [#3] mootools.html Cycles:180 Average:10359.40 Median:2757.30 stddev:7637.25 (277.0%) stddev-sans-first:7628.95 04:05:50 INFO - PID 15005 | Values: 1390.7 1435.8 1441.9 1443.6 1451.5 14149.2 14755.0 14758.0 16833.5 17279.0 16973.6 17194.4 17227.1 17242.4 17248.8 2844.7 2866.7 2914.8 2918.6 2950.3 2845.1 2865.4 2871.6 2891.1 2893.8 2864.7 2914.6 2924.7 2928.3 2930.5 2793.3 2803.4 2806.9 2810.1 2824.3 2735.4 2740.3 2756.5 2758.1 2760.1 16395.3 16764.8 16777.0 16779.7 16788.3 8656.7 8936.3 8964.6 8977.5 8979.4 5646.4 5677.9 5790.9 5824.0 5858.6 2031.8 2036.5 2068.5 2081.3 2100.2 30381.5 30539.0 30598.7 30626.2 30659.2 7232.2 7272.6 7414.7 7428.9 7538.2 19152.8 19154.2 19186.3 19187.3 19212.4 7603.5 8510.4 8653.4 8655.6 8660.4 6918.6 7825.6 7915.2 7950.9 7968.1 5593.5 5660.9 5763.6 5778.4 5817.5 1641.5 1683.6 1690.5 1700.8 1713.3 988.6 993.1 1000.5 1002.1 1024.4 8773.6 8835.3 8871.3 8887.5 8924.7 785.4 798.8 799.9 807.9 819.4 5336.8 5338.8 5457.1 5478.5 5515.9 24304.6 24662.3 24790.4 25053.1 25112.1 24546.1 24647.5 24660.0 24907.4 24954.1 12018.6 12380.1 12583.5 12627.6 12653.0 9626.6 9780.8 9805.8 9815.0 9844.0 20478.3 20507.4 21350.0 21384.0 21608.5 21332.4 21434.4 21697.8 21720.8 21751.9 12065.7 12139.8 12144.8 12175.8 12217.1 15400.2 15514.1 15586.2 15651.8 15705.0 12429.9 12678.8 12755.9 12899.8 13011.2 11988.3 12017.4 12185.5 12262.1 12367.6 10917.3 11083.2 11084.3 11087.6 11124.8 7827.2 7972.1 7986.1 8009.8 8022.8 8054.2 8325.8 8327.6 8330.4 8400.2 04:05:50 INFO - PID 15005 | 04:05:50 INFO - PID 15005 | [#4] prototype.html Cycles:180 Average:5797.94 Median:6286.49 stddev:1544.23 (24.6%) stddev-sans-first:1497.31 04:05:50 INFO - PID 15005 | Values: 541.1 555.6 560.2 569.0 580.9 6287.8 6341.7 6411.9 6422.4 6425.7 6172.4 6179.9 6225.7 6273.3 6281.9 5175.0 5196.9 5199.8 5227.2 5230.5 5057.3 5089.3 5136.9 5155.3 5220.3 5224.6 5226.5 5239.6 5268.3 5287.3 5252.0 5263.9 5267.5 5285.5 5308.4 4786.9 4902.8 4909.1 4925.4 4935.2 6223.3 6266.3 6309.0 6315.3 6353.7 6178.7 6205.8 6250.9 6265.4 6302.3 6740.2 6747.9 6748.7 6855.0 6860.5 5433.5 5448.6 5486.6 5498.1 5517.8 6875.3 6905.4 6933.3 7005.9 7103.3 5677.5 5697.7 5737.6 5741.6 5743.9 5779.2 5783.3 5785.2 5812.2 5831.7 6151.7 6206.6 6252.5 6259.7 6277.9 6791.5 6807.0 6907.3 6912.4 6933.4 1975.2 1987.1 2038.2 2040.6 2048.6 1463.4 1487.9 1517.8 1538.1 1539.4 6152.9 6160.7 6186.7 6230.0 6285.2 6761.0 6796.9 6802.5 6810.5 6847.0 6152.0 6158.8 6164.9 6180.3 6195.0 3602.8 3613.7 3629.7 3635.9 3644.2 7021.5 7038.8 7060.0 7111.5 7135.9 7024.9 7044.1 7049.4 7064.5 7102.9 6983.0 7010.7 7062.6 7072.0 7095.8 6815.2 6816.2 6824.8 6841.8 6861.9 6783.4 6795.9 6796.5 6813.7 6853.3 6919.8 6941.7 6942.0 6942.8 7107.4 5539.9 5646.3 5692.8 5742.3 5794.1 6457.3 6766.7 6812.2 6887.9 6941.9 6524.2 6551.8 6596.9 6620.4 6647.3 6498.8 6500.9 6580.6 6584.3 6637.9 6454.5 6486.8 6500.0 6529.8 6629.0 6562.2 6657.4 6683.7 6685.9 6742.6 6477.7 6480.3 6523.8 6541.0 6547.8 04:05:50 INFO - PID 15005 | 04:05:50 INFO - PID 15005 | [#5] yui.html Cycles:180 Average:28296.75 Median:17645.56 stddev:43235.45 (245.0%) stddev-sans-first:43308.05 04:05:50 INFO - PID 15005 | Values: 970.1 978.8 998.4 1002.6 1006.2 19891.1 20210.3 20256.7 20285.4 20315.3 18552.7 18715.0 18741.9 18773.2 18850.8 986.6 1006.6 1009.7 1009.9 1011.5 1092.2 1095.9 1097.6 1098.0 1099.6 1074.8 1111.3 1118.2 1122.9 1124.4 1111.8 1112.7 1112.8 1113.5 1113.6 791.1 804.8 810.8 814.9 819.3 20178.6 20351.0 20352.9 20378.8 20384.7 23132.0 23305.5 23864.9 23955.8 23986.4 17355.5 17808.8 18022.1 18050.3 18105.5 3959.7 3978.2 3989.8 3994.0 3995.3 135887.0 157610.0 159767.0 161032.0 162728.0 16351.3 16472.5 16638.8 16662.3 17342.5 26875.9 26968.6 27069.5 27070.6 27772.6 9538.7 9570.7 9577.4 9612.9 9648.8 4534.6 4593.7 4597.4 4602.2 4606.7 6084.3 6244.1 6254.9 6273.8 6336.0 1144.7 1159.3 1177.6 1182.6 1182.7 688.5 699.9 703.2 712.4 714.6 12384.8 12861.4 13550.0 14657.7 14692.9 692.5 695.2 700.7 704.1 715.3 6064.0 6065.6 6102.9 6149.6 6161.9 153624.0 154761.0 155261.2 156973.0 157571.0 134890.0 135016.0 135760.0 136692.3 142844.0 117791.4 118937.4 120021.8 120206.0 122909.8 11073.2 11075.4 11272.4 11327.2 11349.0 55581.7 55961.4 55968.7 56036.4 56525.0 73558.8 74097.0 74393.6 74692.6 75477.6 16744.7 16927.4 16990.8 17050.1 17067.7 19098.5 19569.0 19586.1 19637.1 19760.0 15438.7 15444.2 15736.6 15880.2 15882.0 16251.0 16372.0 16464.7 16519.3 16580.0 17356.9 17454.7 17497.4 17631.0 17660.1 12438.4 12460.6 12482.7 12495.3 12552.9 12774.5 12775.3 12902.1 13002.9 13028.8 04:05:50 INFO - PID 15005 | -------- Summary: end -------- 04:05:50 INFO - PID 15005 | 04:05:51 INFO - PID 15005 | 04:05:51 INFO - PID 15005 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 04:05:51 INFO - PID 15005 | 04:05:51 INFO - TEST-INFO | 15005: exit 0 04:05:51 INFO - TEST-OK | dromaeo_css | took 1106756ms 04:05:51 INFO - TEST-START | kraken 04:05:51 INFO - Initialising browser for kraken test... 04:05:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60403/getInfo.html -profile /tmp/tmpy92Tar/profile 04:05:51 INFO - TEST-INFO | started process 15388 (/builds/slave/test/build/application/firefox/firefox http://localhost:60403/getInfo.html) 04:05:56 INFO - TEST-INFO | 15388: exit 0 04:05:56 INFO - Browser initialized. 04:05:56 INFO - Running cycle 1/1 for kraken test... 04:05:56 INFO - Using env: {'DISPLAY': ':0', 04:05:56 INFO - 'HOME': '/home/cltbld', 04:05:56 INFO - 'JSGC_DISABLE_POISONING': '1', 04:05:56 INFO - 'LANG': 'en_US.UTF-8', 04:05:56 INFO - 'LANGUAGE': 'en_US:en', 04:05:56 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:05:56 INFO - 'LOGNAME': 'cltbld', 04:05:56 INFO - 'MAIL': '/var/mail/cltbld', 04:05:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:05:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:05:56 INFO - 'MOZ_CRASHREPORTER': '1', 04:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:56 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:05:56 INFO - 'MOZ_NO_REMOTE': '1', 04:05:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:05:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:56 INFO - 'NO_EM_RESTART': '1', 04:05:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:56 INFO - 'PWD': '/builds/slave/test', 04:05:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:05:56 INFO - 'RUST_BACKTRACE': 'full', 04:05:56 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:05:56 INFO - 'SHELL': '/bin/bash', 04:05:56 INFO - 'SHLVL': '1', 04:05:56 INFO - 'TERM': 'linux', 04:05:56 INFO - 'TMOUT': '86400', 04:05:56 INFO - 'USER': 'cltbld', 04:05:56 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1505298517.995116-199668604', 04:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:56 INFO - '_': '/tools/buildbot/bin/python'} 04:05:56 INFO - TEST-INFO | started process 15587 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpy92Tar/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:05:57 INFO - PID 15587 | 04:05:57 INFO - PID 15587 | (/builds/slave/test/build/application/firefox/firefox:15638): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:05:57 INFO - PID 15587 | 04:06:07 INFO - PID 15587 | RSS: Main: 166092800 04:06:07 INFO - PID 15587 | 04:06:10 INFO - PID 15587 | Cycle 1(1): loaded http://localhost:60403/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60403/tests/kraken/driver.html?testName=audio-beat-detection) 04:06:11 INFO - PID 15587 | RSS: Main: 170762240 04:06:11 INFO - PID 15587 | 04:06:13 INFO - PID 15587 | Cycle 1(1): loaded http://localhost:60403/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60403/tests/kraken/driver.html?testName=audio-dft) 04:06:14 INFO - PID 15587 | RSS: Main: 171212800 04:06:14 INFO - PID 15587 | 04:06:17 INFO - PID 15587 | Cycle 1(1): loaded http://localhost:60403/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60403/tests/kraken/driver.html?testName=audio-fft) 04:06:17 INFO - PID 15587 | RSS: Main: 165933056 04:06:17 INFO - PID 15587 | 04:06:19 INFO - PID 15587 | Cycle 1(1): loaded http://localhost:60403/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60403/tests/kraken/driver.html?testName=audio-oscillator) 04:06:20 INFO - PID 15587 | RSS: Main: 167804928 04:06:20 INFO - PID 15587 | 04:06:22 INFO - PID 15587 | Cycle 1(1): loaded http://localhost:60403/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60403/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:06:23 INFO - PID 15587 | RSS: Main: 167444480 04:06:23 INFO - PID 15587 | 04:06:26 INFO - PID 15587 | Cycle 1(1): loaded http://localhost:60403/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60403/tests/kraken/driver.html?testName=imaging-darkroom) 04:06:26 INFO - PID 15587 | RSS: Main: 170983424 04:06:26 INFO - PID 15587 | 04:06:30 INFO - PID 15587 | Cycle 1(1): loaded http://localhost:60403/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60403/tests/kraken/driver.html?testName=imaging-desaturate) 04:06:31 INFO - PID 15587 | RSS: Main: 170340352 04:06:31 INFO - PID 15587 | 04:06:34 INFO - PID 15587 | Cycle 1(1): loaded http://localhost:60403/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60403/tests/kraken/driver.html?testName=json-parse-financial) 04:06:34 INFO - PID 15587 | RSS: Main: 170110976 04:06:34 INFO - PID 15587 | 04:06:36 INFO - PID 15587 | Cycle 1(1): loaded http://localhost:60403/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60403/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:06:36 INFO - PID 15587 | RSS: Main: 170127360 04:06:36 INFO - PID 15587 | 04:06:38 INFO - PID 15587 | Cycle 1(1): loaded http://localhost:60403/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60403/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:06:39 INFO - PID 15587 | RSS: Main: 170225664 04:06:39 INFO - PID 15587 | 04:06:41 INFO - PID 15587 | Cycle 1(1): loaded http://localhost:60403/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60403/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:06:41 INFO - PID 15587 | RSS: Main: 166871040 04:06:41 INFO - PID 15587 | 04:06:44 INFO - PID 15587 | Cycle 1(1): loaded http://localhost:60403/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60403/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:06:44 INFO - PID 15587 | RSS: Main: 166780928 04:06:44 INFO - PID 15587 | 04:06:47 INFO - PID 15587 | Cycle 1(1): loaded http://localhost:60403/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60403/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:06:48 INFO - PID 15587 | RSS: Main: 168304640 04:06:48 INFO - PID 15587 | 04:06:49 INFO - PID 15587 | Cycle 1(1): loaded http://localhost:60403/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60403/tests/kraken/driver.html?testName=ai-astar) 04:06:50 INFO - PID 15587 | RSS: Main: 169385984 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | __start_tp_report 04:06:50 INFO - PID 15587 | _x_x_mozilla_page_load 04:06:50 INFO - PID 15587 | _x_x_mozilla_page_load_details 04:06:50 INFO - PID 15587 | |i|pagename|runs| 04:06:50 INFO - PID 15587 | |0;ai-astar;106;106;104;105;105;105;106;105;106;106 04:06:50 INFO - PID 15587 | |1;audio-beat-detection;113;112;114;114;111;112;113;133;113;114 04:06:50 INFO - PID 15587 | |2;audio-dft;162;172;162;162;162;162;163;164;163;163 04:06:50 INFO - PID 15587 | |3;audio-fft;74;74;73;73;73;73;73;73;73;74 04:06:50 INFO - PID 15587 | |4;audio-oscillator;78;76;77;78;78;78;78;79;78;78 04:06:50 INFO - PID 15587 | |5;imaging-gaussian-blur;113;114;113;114;113;114;115;114;115;114 04:06:50 INFO - PID 15587 | |6;imaging-darkroom;149;149;149;149;149;148;150;151;152;153 04:06:50 INFO - PID 15587 | |7;imaging-desaturate;93;93;93;94;94;94;94;94;94;94 04:06:50 INFO - PID 15587 | |8;json-parse-financial;45;45;44;47;46;46;45;43;44;44 04:06:50 INFO - PID 15587 | |9;json-stringify-tinderbox;37;37;36;38;38;37;37;37;37;37 04:06:50 INFO - PID 15587 | |10;stanford-crypto-aes;74;76;73;75;71;72;71;73;74;73 04:06:50 INFO - PID 15587 | |11;stanford-crypto-ccm;110;103;106;106;111;115;107;111;103;102 04:06:50 INFO - PID 15587 | |12;stanford-crypto-pbkdf2;159;160;164;156;157;162;151;154;153;149 04:06:50 INFO - PID 15587 | |13;stanford-crypto-sha256-iterative;60;57;59;60;59;58;59;60;59;63 04:06:50 INFO - PID 15587 | __end_tp_report 04:06:50 INFO - PID 15587 | __start_cc_report 04:06:50 INFO - PID 15587 | _x_x_mozilla_cycle_collect,889 04:06:50 INFO - PID 15587 | __end_cc_report 04:06:50 INFO - PID 15587 | __startTimestamp1505300810206__endTimestamp 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | ------- Summary: start ------- 04:06:50 INFO - PID 15587 | Number of tests: 14 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | [#0] ai-astar Cycles:10 Average:105.40 Median:106.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 04:06:50 INFO - PID 15587 | Values: 106.0 106.0 104.0 105.0 105.0 105.0 106.0 105.0 106.0 106.0 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | [#1] audio-beat-detection Cycles:10 Average:114.90 Median:113.50 stddev:6.44 (5.7%) stddev-sans-first:6.79 04:06:50 INFO - PID 15587 | Values: 113.0 112.0 114.0 114.0 111.0 112.0 113.0 133.0 113.0 114.0 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.00 stddev:3.06 (1.9%) stddev-sans-first:3.20 04:06:50 INFO - PID 15587 | Values: 162.0 172.0 162.0 162.0 162.0 162.0 163.0 164.0 163.0 163.0 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | [#3] audio-fft Cycles:10 Average:73.30 Median:73.00 stddev:0.48 (0.7%) stddev-sans-first:0.44 04:06:50 INFO - PID 15587 | Values: 74.0 74.0 73.0 73.0 73.0 73.0 73.0 73.0 73.0 74.0 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:78.00 stddev:0.79 (1.0%) stddev-sans-first:0.83 04:06:50 INFO - PID 15587 | Values: 78.0 76.0 77.0 78.0 78.0 78.0 78.0 79.0 78.0 78.0 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.71 04:06:50 INFO - PID 15587 | Values: 113.0 114.0 113.0 114.0 113.0 114.0 115.0 114.0 115.0 114.0 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | [#6] imaging-darkroom Cycles:10 Average:149.90 Median:149.50 stddev:1.60 (1.1%) stddev-sans-first:1.66 04:06:50 INFO - PID 15587 | Values: 149.0 149.0 149.0 149.0 149.0 148.0 150.0 151.0 152.0 153.0 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 04:06:50 INFO - PID 15587 | Values: 93.0 93.0 93.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | [#8] json-parse-financial Cycles:10 Average:44.90 Median:45.00 stddev:1.20 (2.7%) stddev-sans-first:1.27 04:06:50 INFO - PID 15587 | Values: 45.0 45.0 44.0 47.0 46.0 46.0 45.0 43.0 44.0 44.0 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | [#9] json-stringify-tinderbox Cycles:10 Average:37.10 Median:37.00 stddev:0.57 (1.5%) stddev-sans-first:0.60 04:06:50 INFO - PID 15587 | Values: 37.0 37.0 36.0 38.0 38.0 37.0 37.0 37.0 37.0 37.0 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | [#10] stanford-crypto-aes Cycles:10 Average:73.20 Median:73.50 stddev:1.62 (2.2%) stddev-sans-first:1.69 04:06:50 INFO - PID 15587 | Values: 74.0 76.0 73.0 75.0 71.0 72.0 71.0 73.0 74.0 73.0 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | [#11] stanford-crypto-ccm Cycles:10 Average:107.40 Median:108.50 stddev:4.25 (3.9%) stddev-sans-first:4.40 04:06:50 INFO - PID 15587 | Values: 110.0 103.0 106.0 106.0 111.0 115.0 107.0 111.0 103.0 102.0 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.50 Median:158.00 stddev:4.84 (3.1%) stddev-sans-first:5.04 04:06:50 INFO - PID 15587 | Values: 159.0 160.0 164.0 156.0 157.0 162.0 151.0 154.0 153.0 149.0 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.40 Median:59.50 stddev:1.58 (2.7%) stddev-sans-first:1.66 04:06:50 INFO - PID 15587 | Values: 60.0 57.0 59.0 60.0 59.0 58.0 59.0 60.0 59.0 63.0 04:06:50 INFO - PID 15587 | -------- Summary: end -------- 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 04:06:50 INFO - PID 15587 | 04:06:50 INFO - PID 15587 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:06:50 INFO - TEST-INFO | 15587: exit 0 04:06:50 INFO - TEST-OK | kraken | took 59365ms 04:06:50 INFO - SUITE-END | took 1166s 04:06:51 INFO - Completed test suite (00:19:26) 04:06:51 INFO - javascript benchmark 04:06:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9072.89731385683, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3833.9424908626365, 3847.826847791849, 3849.1724279279956, 3863.845446182153, 3866.4393662918897, 6194.504439644829, 6261.655608941506, 6355.71399287032, 6360.0, 6417.165768450107, 6300.46446052085, 6331.271903731073, 6343.270523889742, 6347.079673447347, 6405.359464053604, 5572.0527510323345, 5715.914261286084, 5733.713314334266, 5736.512396446307, 5741.712914354286, 140.80746999770284, 5742.971285143569, 5764.077747560391, 5794.797182098628, 5827.388124246935, 5665.0, 5673.517750991171, 5682.715864206793, 5768.567357448166, 5769.538436925078, 5674.205611214434, 5674.687892165929, 5749.568782341341, 5759.452851979056, 5768.711564421804, 5672.574556908248, 5690.118031705092, 5739.081746920514, 5761.942380576183, 5774.0, 6334.461570766491, 6348.1430006949395, 6350.33321501245, 6397.8400539986405, 6410.967945160244, 6651.334866513358, 6816.182058153054, 6832.145981752281, 6877.759278425231, 6966.582005079661, 6822.795316140524, 6897.896531552031, 6910.101686780737, 6917.100776898971, 6919.8962015569805, 6373.681315934231, 6379.904301435482, 6419.807405777834, 6449.871002579922, 6456.677166141674, 7042.330978557029, 7044.401225895754, 7123.7506687265695, 7147.714091436284, 7193.676284567207, 6680.966595166993, 6730.966345168341, 6780.32196780318, 6799.3200679931615, 6829.829254268683, 6942.514024018371, 6968.303169683092, 6971.6862741176765, 6980.0, 6999.160100787938, 6821.147356580427, 6822.317768223138, 6892.276310987274, 6907.274736152632, 6935.063766391472, 6813.7955861324235, 6826.931730682729, 6834.282400347991, 6913.89629155563, 6917.239103698589, 4910.8281210157475, 4949.628777841676, 4980.850574482772, 5052.520010598987, 5052.9242061369105, 4528.932066019012, 4547.090581883636, 4586.610138138296, 4600.263957766741, 4611.123886461562, 6070.575059745864, 6163.19878415812, 6198.907016394758, 6215.875682486325, 6223.96888015566, 6761.695723692347, 6799.422049125781, 6870.725170993204, 6924.93075069253, 6941.236463988957, 6171.783987560503, 6208.379162083846, 6225.6264624121795, 6228.190335256388, 6229.906551401733, 5435.755391007414, 5552.0, 5561.249231353715, 5577.079781836032, 5580.386157522669, 6938.722451101899, 6970.686319115551, 6972.721091156297, 7007.124109486314, 7040.225575186725, 6870.072540207008, 6882.208546017272, 6936.132983377078, 6940.514164008471, 6959.4432445405255, 6914.066601009001, 6986.126734158231, 6995.440364770704, 7100.147982242164, 7102.0, 6778.288279730755, 6813.04617353561, 6826.658667066527, 6833.145856767904, 6846.383825455733, 6852.28051054652, 6935.375816176568, 6948.33990770876, 6949.583025018515, 6977.197622273504, 6528.804135875931, 6552.3775241352, 6558.245801732862, 6587.110740050223, 6604.471642268703, 6484.902726459201, 6515.087887695631, 6572.309907459648, 6590.373914478117, 6661.4004739573675, 6543.083968244355, 6581.210254769267, 6583.967080164569, 6584.144061272004, 6627.138471998609, 6527.216733991761, 6610.867782644224, 6627.271000189879, 6642.4021838034805, 6694.263631000684, 6427.1002059710745, 6450.258220304538, 6490.675466226764, 6492.156019717407, 6537.673116344259], "value": 6213.937052663861, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [949.8242825077361, 962.2350231565904, 984.6159997600937, 1015.786684796193, 1017.7455636090978, 44381.0, 44857.87855303615, 45042.09915801682, 45056.3241551377, 45085.323720144224, 41739.33042678289, 41968.160636787245, 42084.158316833644, 42091.368629470504, 42106.78946605263, 7407.407407407394, 7476.9252307477045, 7491.101067871863, 7521.435892308072, 7530.397568194531, 7320.450966177531, 7417.072865891763, 7479.401647868158, 7494.288042635941, 7495.775126746206, 7430.219826918177, 7460.104787425489, 7463.216362281936, 7881.724139655084, 8041.919580804176, 7879.724209652663, 7936.127026027132, 7938.5236885787035, 7944.08643006053, 7944.483608565425, 7722.652480638329, 7811.531308121531, 7818.648160832776, 7827.295543401065, 7907.288344049006, 41587.0, 41935.7903210485, 41968.79015604933, 42022.579774202175, 42032.36951445731, 3617.981910090459, 3655.6161603031564, 3655.780653160792, 3656.634336566349, 3657.0, 33713.15717107092, 33716.0, 33729.49405758915, 33782.32435351304, 33791.66208337934, 5451.182322651634, 5451.64564303323, 5473.233747275345, 5475.808346707886, 5480.561555075585, 226639.0, 226771.0, 227356.0, 227423.0, 227473.99999999834, 52056.73971630118, 52255.216171757456, 52260.738696306275, 52271.73864130655, 52626.73686631619, 56176.71911640416, 56256.437435625936, 56332.15501767477, 56375.15437268444, 56379.71810140923, 15932.681346373007, 16135.112568808829, 16142.0, 16181.110038947736, 16183.514494565183, 13004.349782511026, 13008.414621342063, 13009.284489353176, 13014.089013768946, 13024.609261722164, 14976.326065327086, 15263.771043434357, 15348.616284592974, 15359.078455292718, 15438.459653912283, 6093.8476538086015, 6145.385461453909, 6220.657863817443, 6231.252249729971, 6402.423781859655, 1102.2725001499132, 1102.2780079048237, 1109.866815982071, 1112.6884472347756, 1127.8139107047245, 25164.74835251624, 25210.495790084096, 25343.0, 25373.0, 25480.49039019209, 1191.53530123252, 1209.0690168570152, 1211.5517258614193, 1219.8475190601175, 1219.8597161326384, 13284.73430531384, 13453.327333633279, 13459.461621535029, 13512.864871351358, 13581.709737574914, 284389.0, 284609.0, 285687.0, 285941.0, 286380.0, 51748.22377664338, 52413.7379313101, 57933.71033144807, 59420.405795941486, 59902.10146847801, 1550.4418409372618, 1557.128008315347, 1565.7807906893045, 1565.827758946515, 1580.0203873598443, 30351.544726828673, 30858.0, 30904.845475772476, 30953.845230773702, 31325.216869577533, 41770.58229417667, 41873.79063104787, 41925.371119432624, 41977.37033944432, 41988.37017444802, 250565.0, 251625.0, 251733.0, 251968.0, 254039.0, 36011.09972250715, 36073.0981725459, 36155.27689446249, 36257.63742362648, 36436.635633644386, 46575.0, 46661.0, 46822.76588617035, 47063.0, 47128.528714713786, 34212.48681269733, 34422.13944651404, 34539.654603453644, 34718.0, 34743.0, 34439.65560344364, 34738.0, 35006.12484687801, 35256.823715881255, 35411.0, 39077.41383879187, 39794.0, 40198.0, 40337.1932561353, 40458.190836182526, 33649.32701345911, 34004.82997585095, 34074.829625852704, 34189.14527136742, 34272.65727342795, 24877.378065547782, 25012.37469063288, 25625.0, 26042.479150417264, 26198.476030479665], "value": 18590.402498062474, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.5403508246028, 590.8227531740476, 591.5208680968414, 601.4646964201861, 612.9295131059928, 22912.541749165026, 23290.301290961277, 23336.8833155834, 23338.41653958652, 23486.76513234867, 23853.16513922013, 25042.749572504268, 25211.369715757115, 25446.10938617144, 25476.74523254767, 6474.320196379383, 6697.799066028003, 6706.094677218586, 6706.664666766642, 6710.0, 6480.125183100292, 6528.2166140063255, 6657.933420665804, 6736.259011508755, 6773.627450490221, 6715.362040606159, 6720.966395168018, 6728.663566821664, 6730.83172920676, 6734.3939045485895, 6645.734170633169, 6700.564463309894, 6709.027191057269, 6715.496337774637, 6724.361185687328, 6197.876042479125, 6213.347598502182, 6248.0, 6264.1543391642, 6264.467520260738, 28753.56869646936, 29486.115416537537, 29919.700802992127, 30037.699623003708, 30136.095917122522, 2283.3834864586634, 2290.5533420982915, 2290.8510946788406, 2316.4324740438533, 2317.501737126509, 20167.19331226764, 20543.0, 20799.16803327865, 20975.265865694626, 20977.685334719838, 2416.987915060431, 2478.479519300949, 2503.8873250703764, 2538.631898374725, 2584.147231413629, 140921.0, 145504.0, 157403.0, 165124.17437912853, 165812.0, 15386.692266154614, 15587.922060389625, 15662.765058524132, 15780.210989450481, 16500.504984850475, 25199.0, 25207.495850082894, 25298.494030119295, 25302.0, 25374.365640859127, 10487.37075775456, 10488.73778155537, 10491.213159013105, 10687.091597214168, 10806.081483074027, 9663.371880827724, 9712.61149554024, 9713.125818676353, 9742.07450292221, 9795.804083918281, 9396.624135034659, 9515.476648784383, 9676.274279429072, 9738.46438445878, 9760.779902512186, 2771.3487330477324, 2777.1113243762206, 2884.5096333623333, 2890.07517594372, 2894.2185609885214, 1040.1626690514208, 1061.4904845673966, 1065.174489770434, 1070.7430216747925, 1071.3036526257995, 20908.268210612554, 21032.78967210339, 21153.36539903805, 21165.89417052905, 21366.35900922975, 1131.7227279316621, 1136.108155098244, 1138.6754774889116, 1142.4230763464564, 1143.5997400909614, 11352.091832653368, 11591.536338546532, 11618.477168527437, 11710.70723231926, 11778.646640600795, 584954.0, 599712.0, 603615.0, 611945.0, 613311.0, 125380.37309813393, 127680.0, 130345.34827325803, 131064.0, 132766.0, 1110.6112860498922, 1161.59344229519, 1221.926684398939, 1222.541546919905, 1271.0657666614845, 21586.70573612124, 21953.560928781655, 21972.0, 22080.558388832455, 22264.443388915406, 62214.37785622086, 63614.68192659007, 63616.68191659012, 63841.361586383544, 66119.66940165269, 80758.19241807506, 82234.58882705549, 82653.0, 83167.5841620788, 83276.0, 14964.551063468114, 15036.924815375853, 15065.0, 15248.77126843076, 15313.387464501295, 23494.177703780286, 23727.1695490657, 23869.64195537103, 23937.64093538633, 24104.276871693877, 19376.031198440305, 19400.902995484932, 19516.707249391555, 19737.210511579375, 19761.0, 17144.142792860555, 17287.827121729126, 17569.945803251845, 17722.5569360767, 17986.100694964938, 19650.50873728168, 20080.799192007893, 20114.59770804605, 20214.898925505866, 20298.492537686678, 15122.470713524974, 15156.469523566622, 15183.392664293304, 15190.924045379703, 15271.007384519971, 19103.140358683613, 19248.711269331252, 19258.7111193335, 19498.220071196993, 19581.804181958], "value": 12746.672856714666, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1390.7009992851538, 1435.7989881416597, 1441.8774404175651, 1443.5669299210235, 1451.462958705279, 14149.221792801405, 14755.0, 14758.0, 16833.494995150166, 17279.04965226912, 16973.57566060849, 17194.39819606314, 17227.138643067858, 17242.39651612194, 17248.827511724878, 2844.7439730424258, 2866.727660872219, 2914.8251104933665, 2918.591397204393, 2950.3066779306846, 2845.089571337173, 2865.4269146170745, 2871.55490898911, 2891.1037578350674, 2893.782966277527, 2864.69920658331, 2914.562815577659, 2924.722151395614, 2928.3411232472736, 2930.472514947309, 2793.3435642623967, 2803.355228297503, 2806.9473947269876, 2810.1148138336357, 2824.3362809739697, 2735.39821239329, 2740.328619488218, 2756.517609418344, 2758.089830355998, 2760.1029665358637, 16395.344186232538, 16764.83235167645, 16777.0, 16779.748303775454, 16788.328466861432, 8656.740297791077, 8936.285097192209, 8964.551772411354, 8977.506237156953, 8979.416337938079, 5646.435356464362, 5677.886442271131, 5790.884182316372, 5823.9708801456145, 5858.619189752695, 2031.837453003756, 2036.5417780999157, 2068.493219161315, 2081.3339731285996, 2100.2124203423714, 30381.544276835866, 30539.0, 30598.69401305981, 30626.23434414135, 30659.233519161753, 7232.168300645388, 7272.600006999666, 7414.703411863573, 7428.888566671504, 7538.170801211862, 19152.8084719151, 19154.233830646896, 19186.328478503186, 19187.32844350441, 19212.423627291202, 7603.543787372665, 8510.361722870686, 8653.39426240157, 8655.56722163902, 8660.437071590326, 6918.584884906922, 7825.647845846951, 7915.248051435105, 7950.920490795133, 7968.12350641429, 5593.489757765462, 5660.943390566124, 5763.625364351304, 5778.393268706809, 5817.476427121579, 1641.458318754808, 1683.6026098338596, 1690.5435532406182, 1700.8469237768413, 1713.254734190631, 988.5946761827616, 993.1260490768078, 1000.5347513406156, 1002.1031177096457, 1024.4212020208558, 8773.605187766565, 8835.337349698799, 8871.290296776371, 8887.466751994902, 8924.68763593271, 785.4070177016316, 798.8122791144076, 799.9160088190772, 807.8990126234221, 819.3936486999578, 5336.839894803162, 5338.813141540028, 5457.126859702428, 5478.534324582455, 5515.862103447446, 24304.635430468556, 24662.26013219606, 24790.38024049413, 25053.12314068999, 25112.12107576226, 24546.140885068937, 24647.5070498589, 24660.0, 24907.377315566893, 24954.12660556872, 12018.579349722717, 12380.133390662568, 12583.496660133493, 12627.621171364874, 12653.0, 9626.62305283532, 9780.804383912424, 9805.803883922425, 9814.950925245328, 9844.0, 20478.283260085824, 20507.38477845667, 21350.0, 21384.0, 21608.459788505414, 21332.36002919915, 21434.356969290948, 21697.783022169577, 21720.782792172507, 21751.891240543697, 12065.698357541132, 12139.75720485603, 12144.75710485803, 12175.75648487043, 12217.08371872131, 15400.15299158557, 15514.146721930401, 15586.220688965734, 15651.843481565038, 15705.0, 12429.875701243234, 12678.809817852924, 12755.87244127584, 12899.806502902276, 13011.154274972476, 11988.280703157838, 12017.399130043637, 12185.51257949672, 12262.141650084408, 12367.62897113088, 10917.290376125526, 11083.168762342695, 11084.279521831055, 11087.611933582286, 11124.777504449703, 7827.217278272355, 7972.1230664628, 7986.081600616004, 8009.799755006172, 8022.759317220492, 8054.23484768946, 8325.791855203668, 8327.583620818814, 8330.416870818985, 8400.201980811813], "value": 7256.266493649275, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [541.0963690636636, 555.6305057137004, 560.215698022768, 568.9829305120847, 580.8576898659828, 6287.799270255408, 6341.7146228419715, 6411.871762564746, 6422.357764223575, 6425.742970281189, 6172.4444799968, 6179.867945558786, 6225.719842607083, 6273.309935907047, 6281.874362512747, 5174.974125129388, 5196.896062078757, 5199.818006369778, 5227.21591761237, 5230.450802665722, 5057.2666963290485, 5089.2620570017325, 5136.8972620547565, 5155.304033955405, 5220.347456567929, 5224.582033437316, 5226.451222621608, 5239.6115029517205, 5268.341457317835, 5287.259783630295, 5251.973740131313, 5263.894582137775, 5267.52592266696, 5285.497877701612, 5308.442613525563, 4786.856394308176, 4902.828401005948, 4909.140900342426, 4925.408950925912, 4935.210366341404, 6223.253209614875, 6266.279377871558, 6309.0, 6315.273743519509, 6353.650549219791, 6178.69106544671, 6205.782797602107, 6250.87498250037, 6265.373462653698, 6302.338254483259, 6740.224874139439, 6747.898781518281, 6748.730050797963, 6854.9657251713925, 6860.451163906875, 5433.510984011418, 5448.645838020516, 5486.61593688442, 5498.092814685572, 5517.806876759334, 6875.278095799969, 6905.413039891615, 6933.306669333027, 7005.89491157628, 7103.3251841075535, 5677.489025987681, 5697.715114244271, 5737.627054241461, 5741.564608847788, 5743.885122297531, 5779.24869766936, 5783.306003279633, 5785.190073389729, 5812.244408226903, 5831.679257640785, 6151.72317245725, 6206.627602343874, 6252.468540174136, 6259.715203950525, 6277.901367260711, 6791.4906382021545, 6807.01298311742, 6907.274736152732, 6912.412444942236, 6933.375996160269, 1975.2000439821895, 1987.125664707553, 2038.194913009353, 2040.6224848403092, 2048.566003797348, 1463.4000059975358, 1487.8586534279443, 1517.840626734177, 1538.1155835394693, 1539.3919401836436, 6152.876942461216, 6160.691965401712, 6186.721597528122, 6229.968850155811, 6285.182926219653, 6761.01965215041, 6796.864062718817, 6802.455803535705, 6810.467644779885, 6847.014365833852, 6152.0, 6158.846028849225, 6164.938350616437, 6180.289266734293, 6194.969025154845, 3602.819859007039, 3613.747037707335, 3629.7459177857295, 3635.8545658173375, 3644.180059486647, 7021.543599666005, 7038.824029399306, 7059.964700176466, 7111.502194846415, 7135.928640713631, 7024.911138773498, 7044.133304674266, 7049.436045116378, 7064.505484616027, 7102.92897071033, 6982.965085174643, 7010.719571217298, 7062.611556364346, 7072.0, 7095.822604434828, 6815.216250131299, 6816.182058153054, 6824.7952561423235, 6841.794746157623, 6861.931380686329, 6783.423409010289, 6795.864082718219, 6796.524243302922, 6813.69338379784, 6853.314668533107, 6919.792406227822, 6941.652917354213, 6941.965290173517, 6942.757003504853, 7107.395871350993, 5539.861503462445, 5646.322441307069, 5692.829215123553, 5742.339630942495, 5794.145873320497, 6457.257415397102, 6766.661666916536, 6812.216595091628, 6887.896681549681, 6941.930580694128, 6524.217093948757, 6551.770688025897, 6596.868062638624, 6620.437262832521, 6647.268800432045, 6498.772542960974, 6500.934990650222, 6580.572262803094, 6584.275729669636, 6637.933620663925, 6454.5159113065765, 6486.837829054122, 6499.967500162658, 6529.771457998947, 6628.966855165693, 6562.245341785756, 6657.40083392497, 6683.699233534599, 6685.8997115044285, 6742.572673837317, 6477.708503117466, 6480.287168411565, 6523.804285871432, 6540.967295163494, 6547.836304092436], "value": 5362.0187723501995, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [970.0881171698599, 978.7895602445474, 998.4059484606661, 1002.5989604158336, 1006.1699098243951, 19891.104900279486, 20210.292639757612, 20256.696149557767, 20285.39143825686, 20315.288964886233, 18552.72170917434, 18715.0, 18741.906290468527, 18773.155208015636, 18850.81149188511, 986.6053578568558, 1006.6476733143396, 1009.7122320138762, 1009.9444530550817, 1011.4689787861358, 1092.2190633696905, 1095.8904109589057, 1097.6432659385691, 1097.9560817567287, 1099.598646494032, 1074.8441475985978, 1111.2943281016533, 1118.1893127482551, 1122.9101671866272, 1124.3984468309457, 1111.838783376406, 1112.6884472347756, 1112.8330750387427, 1113.4544073404054, 1113.6492005018392, 791.0625908298684, 804.8390321935577, 810.7810891059381, 814.8573999550114, 819.2585709932521, 20178.5964280715, 20351.0, 20352.898235508877, 20378.79621203784, 20384.694229586567, 23132.0, 23305.533889322287, 23864.880675596683, 23955.760442395527, 23986.400339991465, 17355.47933561995, 17808.821911780975, 18022.098895055195, 18050.27798888043, 18105.547361316072, 3959.703022273341, 3978.1645854370618, 3989.8005099744896, 3993.9600603993877, 3995.2808494470983, 135887.0, 157610.0, 159767.0, 161032.0, 162727.9999999988, 16351.345946162259, 16472.505824825275, 16638.75041874373, 16662.33350665984, 17342.479725608253, 26875.86562067177, 26968.59547106795, 27069.53352696783, 27070.593941090898, 27772.58341124885, 9538.713838584854, 9570.712878613653, 9577.425354478753, 9612.855807162898, 9648.807023859483, 4534.569215924482, 4593.6554758392585, 4597.42532183477, 4602.194615942263, 4606.700564463299, 6084.30030546484, 6244.12582238487, 6254.937450625526, 6273.843153921189, 6335.968320158462, 1144.7367105565766, 1159.2754528419737, 1177.587844254504, 1182.5683625476638, 1182.7457096724245, 688.5283580747205, 699.9370056694922, 703.1702590942737, 712.3588770106842, 714.5534041224234, 12384.81422778659, 12861.4212360444, 13550.0, 14657.706845863024, 14692.853071469148, 692.4806395203598, 695.1796879682024, 700.6636814329048, 704.1339152842033, 715.2632788228134, 6063.969125248718, 6065.5754097212775, 6102.938970610237, 6149.600275982137, 6161.907571386433, 153624.0, 154761.0, 155261.2236938808, 156973.0, 157571.0, 134890.0, 135016.0, 135760.0, 136692.31653841666, 142844.0, 117791.41104294424, 118937.40531297289, 120021.79978200106, 120206.0, 122909.77090229162, 11073.224874258725, 11075.446227688744, 11272.436378180893, 11327.15046371509, 11349.0, 55581.72209138928, 55961.440385595626, 55968.72015639896, 56036.43963560475, 56525.0, 73558.793236203, 74097.0, 74393.6280318595, 74692.62653686914, 75477.62261188659, 16744.74882876782, 16927.407540736014, 16990.830091698925, 17050.14749262507, 17067.658646826745, 19098.52253693669, 19569.0, 19586.11862466221, 19637.11632976491, 19760.0, 15438.691226175639, 15444.150571718663, 15736.606584835014, 15880.205989700238, 15882.0, 16251.0, 16372.0, 16464.67070658604, 16519.33922643081, 16580.0, 17356.91321543384, 17454.650906982042, 17497.38759143424, 17631.0, 17660.1169941505, 12438.37808109609, 12460.626181214579, 12482.687932801753, 12495.312757798045, 12552.937235313764, 12774.489020439078, 12775.297358645365, 12902.096853220184, 13002.869971300544, 13028.804567931678], "value": 9735.457572484622, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1370.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 106.0, 104.0, 105.0, 105.0, 105.0, 106.0, 105.0, 106.0, 106.0], "value": 105.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 114.0, 114.0, 111.0, 112.0, 113.0, 133.0, 113.0, 114.0], "value": 114.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 172.0, 162.0, 162.0, 162.0, 162.0, 163.0, 164.0, 163.0, 163.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 73.0, 73.0, 73.0, 73.0, 73.0, 73.0, 74.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 77.0, 78.0, 78.0, 78.0, 78.0, 79.0, 78.0, 78.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 114.0, 113.0, 114.0, 115.0, 114.0, 115.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 149.0, 149.0, 148.0, 150.0, 151.0, 152.0, 153.0], "value": 149.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 45.0, 44.0, 47.0, 46.0, 46.0, 45.0, 43.0, 44.0, 44.0], "value": 44.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 36.0, 38.0, 38.0, 37.0, 37.0, 37.0, 37.0, 37.0], "value": 37.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 73.0, 75.0, 71.0, 72.0, 71.0, 73.0, 74.0, 73.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 103.0, 106.0, 106.0, 111.0, 115.0, 107.0, 111.0, 103.0, 102.0], "value": 107.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 164.0, 156.0, 157.0, 162.0, 151.0, 154.0, 153.0, 149.0], "value": 156.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 57.0, 59.0, 60.0, 59.0, 58.0, 59.0, 60.0, 59.0, 63.0], "value": 59.4, "unit": "score"}]}]} 04:06:51 INFO - Return code: 0 04:06:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:06:51 INFO - # TBPL SUCCESS # 04:06:51 INFO - Running post-action listener: _package_coverage_data 04:06:51 INFO - Running post-action listener: _resource_record_post_action 04:06:51 INFO - [mozharness: 2017-09-13 11:06:51.508220Z] Finished run-tests step (success) 04:06:51 INFO - Running post-run listener: _resource_record_post_run 04:06:51 INFO - Total resource usage - Wall time: 1183s; CPU: 13.0%; Read bytes: 151552; Write bytes: 301330432; Read time: 412; Write time: 156824 04:06:51 INFO - TinderboxPrint: CPU usage
13.1% 04:06:51 INFO - TinderboxPrint: I/O read bytes / time
151,552 / 412 04:06:51 INFO - TinderboxPrint: I/O write bytes / time
301,330,432 / 156,824 04:06:51 INFO - TinderboxPrint: CPU idle
8,199.7 (86.9%) 04:06:51 INFO - TinderboxPrint: CPU user
1,185.7 (12.6%) 04:06:51 INFO - TinderboxPrint: Swap in / out
0 / 0 04:06:51 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 21139456; Read time: 0; Write time: 4828 04:06:51 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:06:51 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 151552; Write bytes: 278228992; Read time: 412; Write time: 151696 04:06:52 INFO - Running post-run listener: _upload_blobber_files 04:06:52 INFO - Blob upload gear active. 04:06:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:06:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:06:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:06:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:06:52 INFO - (blobuploader) - INFO - Open directory for files ... 04:06:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:06:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:06:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:06:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:06:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:06:59 INFO - (blobuploader) - INFO - Done attempting. 04:06:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:06:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:06:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:07:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:07:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:07:00 INFO - (blobuploader) - INFO - Done attempting. 04:07:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:07:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:07:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:07:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:07:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:07:01 INFO - (blobuploader) - INFO - Done attempting. 04:07:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:07:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:07:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:07:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:07:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:07:02 INFO - (blobuploader) - INFO - Done attempting. 04:07:02 INFO - (blobuploader) - INFO - Iteration through files over. 04:07:02 INFO - Return code: 0 04:07:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:07:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:07:02 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95dc2ba162f4b84ff22ed829358483c5a1b9ba6462ae1d296ec05bc1f73ca9d37b05e529b41c18b937ee235b92bbb2b1a0b4cc64316ed2571aa4209426e9b3a0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d17e5d2a69a0323288458f0de847e5c29e51c9cc8951b2da267547de0998b0fb2ef10511b8997122dcefa33efd3ef510e4e7e726822676afdcf26e4519f8997", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91508bb5eb5f0bfe1b4b10bfde1bc4193929867dad89f093748acecbb3bc4328d8d1d3fe5e7b5c0cec64b93afcc6bb1b104f6ca957a7f6382869d034a61f923b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e756c9ed51b75987b113f76b9bf1e4684d0b8fe0ca2f13b8fabc7813c457f81a21a7ac095f4fa183dad382581bc3de7632dcaddea82d2d77be0b02650da95d8"} 04:07:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:07:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:07:02 INFO - Contents: 04:07:02 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95dc2ba162f4b84ff22ed829358483c5a1b9ba6462ae1d296ec05bc1f73ca9d37b05e529b41c18b937ee235b92bbb2b1a0b4cc64316ed2571aa4209426e9b3a0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d17e5d2a69a0323288458f0de847e5c29e51c9cc8951b2da267547de0998b0fb2ef10511b8997122dcefa33efd3ef510e4e7e726822676afdcf26e4519f8997", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91508bb5eb5f0bfe1b4b10bfde1bc4193929867dad89f093748acecbb3bc4328d8d1d3fe5e7b5c0cec64b93afcc6bb1b104f6ca957a7f6382869d034a61f923b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e756c9ed51b75987b113f76b9bf1e4684d0b8fe0ca2f13b8fabc7813c457f81a21a7ac095f4fa183dad382581bc3de7632dcaddea82d2d77be0b02650da95d8"} 04:07:02 INFO - Running post-run listener: copy_logs_to_upload_dir 04:07:02 INFO - Copying logs to upload dir... 04:07:02 INFO - mkdir: /builds/slave/test/build/upload/logs 04:07:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.219465 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-13 04:07:02.522750) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 04:07:02.525767) ========= 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=dac658600622fd96716c9acf000001b3-1505298517.995116-199668604 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/sha512/95dc2ba162f4b84ff22ed829358483c5a1b9ba6462ae1d296ec05bc1f73ca9d37b05e529b41c18b937ee235b92bbb2b1a0b4cc64316ed2571aa4209426e9b3a0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d17e5d2a69a0323288458f0de847e5c29e51c9cc8951b2da267547de0998b0fb2ef10511b8997122dcefa33efd3ef510e4e7e726822676afdcf26e4519f8997", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91508bb5eb5f0bfe1b4b10bfde1bc4193929867dad89f093748acecbb3bc4328d8d1d3fe5e7b5c0cec64b93afcc6bb1b104f6ca957a7f6382869d034a61f923b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e756c9ed51b75987b113f76b9bf1e4684d0b8fe0ca2f13b8fabc7813c457f81a21a7ac095f4fa183dad382581bc3de7632dcaddea82d2d77be0b02650da95d8"} build_url:https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011350 build_url: 'https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95dc2ba162f4b84ff22ed829358483c5a1b9ba6462ae1d296ec05bc1f73ca9d37b05e529b41c18b937ee235b92bbb2b1a0b4cc64316ed2571aa4209426e9b3a0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d17e5d2a69a0323288458f0de847e5c29e51c9cc8951b2da267547de0998b0fb2ef10511b8997122dcefa33efd3ef510e4e7e726822676afdcf26e4519f8997", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91508bb5eb5f0bfe1b4b10bfde1bc4193929867dad89f093748acecbb3bc4328d8d1d3fe5e7b5c0cec64b93afcc6bb1b104f6ca957a7f6382869d034a61f923b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e756c9ed51b75987b113f76b9bf1e4684d0b8fe0ca2f13b8fabc7813c457f81a21a7ac095f4fa183dad382581bc3de7632dcaddea82d2d77be0b02650da95d8"}' ========= master_lag: 0.49 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 04:07:03.027113) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 04:07:03.027542) ========= 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=dac658600622fd96716c9acf000001b3-1505298517.995116-199668604 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004448 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 04:07:03.046961) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 04:07:03.047277) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 04:07:03.050603) ========= ========= Total master_lag: 0.61 =========