builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-045 starttime: 1506193110.59 results: success (0) revision: 2d9d0bebb5c66ba3dfdffd713a888a8c49730631 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 11:58:30.586563) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 11:58:30.587276) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 11:58:30.587604) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 11:58:30.686220) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:58:30.686486) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1506192021.541468-1509883357 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-23 11:58:30-- 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% 36.1M=0s 2017-09-23 11:58:30 (36.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.152029 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:58:30.855926) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:58:30.856242) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1506192021.541468-1509883357 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015593 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:58:30.909920) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:58:30.910218) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2d9d0bebb5c66ba3dfdffd713a888a8c49730631 --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 2d9d0bebb5c66ba3dfdffd713a888a8c49730631 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1506192021.541468-1509883357 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-23 11:58:30,957 truncating revision to first 12 chars 2017-09-23 11:58:30,958 Setting DEBUG logging. 2017-09-23 11:58:30,958 attempt 1/10 2017-09-23 11:58:30,958 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2d9d0bebb5c6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-23 11:58:31,345 unpacking tar archive at: mozilla-central-2d9d0bebb5c6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.678643 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:58:31.602068) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 11:58:31.602347) ========= script_repo_revision: 2d9d0bebb5c66ba3dfdffd713a888a8c49730631 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 11:58:31.602783) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 11:58:31.603057) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 11:58:31.618862) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2017-09-23 11:58:31.619136) ========= /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=4300f881736b422476174874000001bc-1506192021.541468-1509883357 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:58:31 INFO - MultiFileLogger online at 20170923 11:58:31 in /builds/slave/test 11:58:31 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 11:58:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:58:31 INFO - {'append_to_log': False, 11:58:31 INFO - 'base_work_dir': '/builds/slave/test', 11:58:31 INFO - 'blob_upload_branch': 'Firefox', 11:58:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:58:31 INFO - 'branch': 'Firefox', 11:58:31 INFO - 'buildbot_json_path': 'buildprops.json', 11:58:31 INFO - 'code_coverage': False, 11:58:31 INFO - 'config_files': ('talos/linux_config.py',), 11:58:31 INFO - 'default_actions': ('clobber', 11:58:31 INFO - 'read-buildbot-config', 11:58:31 INFO - 'download-and-extract', 11:58:31 INFO - 'populate-webroot', 11:58:31 INFO - 'create-virtualenv', 11:58:31 INFO - 'install', 11:58:31 INFO - 'setup-mitmproxy', 11:58:31 INFO - 'run-tests'), 11:58:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:58:31 INFO - 'disable_ccov_upload': False, 11:58:31 INFO - 'disable_stylo': False, 11:58:31 INFO - 'download_minidump_stackwalk': True, 11:58:31 INFO - 'download_symbols': 'ondemand', 11:58:31 INFO - 'enable_stylo': False, 11:58:31 INFO - 'enable_webrender': False, 11:58:31 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:58:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:58:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:58:31 INFO - 'gecko_profile': False, 11:58:31 INFO - 'gecko_profile_interval': 0, 11:58:31 INFO - 'installer_path': 'installer.exe', 11:58:31 INFO - 'log_level': 'info', 11:58:31 INFO - 'log_name': 'talos', 11:58:31 INFO - 'log_to_console': True, 11:58:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:58:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:58:31 INFO - 'opt_config_files': (), 11:58:31 INFO - 'pip_index': False, 11:58:31 INFO - 'suite': 'dromaeojs-e10s', 11:58:31 INFO - 'system_bits': '32', 11:58:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:58:31 INFO - 'title': 'talos-linux64-ix-045', 11:58:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:58:31 INFO - 'use_talos_json': True, 11:58:31 INFO - 'verify': 'False', 11:58:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:58:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:58:31 INFO - 'work_dir': 'build'} 11:58:31 INFO - [mozharness: 2017-09-23 18:58:31.809236Z] Running clobber step. 11:58:31 INFO - Running pre-action listener: _resource_record_pre_action 11:58:31 INFO - Running main action method: clobber 11:58:31 INFO - rmtree: /builds/slave/test/build 11:58:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:58:32 INFO - Running post-action listener: _resource_record_post_action 11:58:32 INFO - [mozharness: 2017-09-23 18:58:32.797873Z] Finished clobber step (success) 11:58:32 INFO - [mozharness: 2017-09-23 18:58:32.797970Z] Running read-buildbot-config step. 11:58:32 INFO - Running pre-action listener: _resource_record_pre_action 11:58:32 INFO - Running main action method: read_buildbot_config 11:58:32 INFO - Using buildbot properties: 11:58:32 INFO - { 11:58:32 INFO - "project": "", 11:58:32 INFO - "product": "firefox", 11:58:32 INFO - "who": "ffxbld@noreply.mozilla.org", 11:58:32 INFO - "installer_path": "public/build/target.tar.bz2", 11:58:32 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:58:32 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:58:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 11:58:32 INFO - "stage_platform": "linux64-pgo", 11:58:32 INFO - "basedir": "/builds/slave/test", 11:58:32 INFO - "buildnumber": 200, 11:58:32 INFO - "platform": "ubuntu64_hw", 11:58:32 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:58:32 INFO - "slavebuilddir": "test", 11:58:32 INFO - "taskId": "Gt-a7gppTAG52Eu37UuVOg", 11:58:32 INFO - "branch": "mozilla-central", 11:58:32 INFO - "script_repo_revision": "production", 11:58:32 INFO - "revision": "2d9d0bebb5c66ba3dfdffd713a888a8c49730631", 11:58:32 INFO - "slavename": "talos-linux64-ix-045", 11:58:32 INFO - "repo_path": "mozilla-central" 11:58:32 INFO - } 11:58:32 INFO - Finding installer, test and symbols from parent task. 11:58:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Gt-a7gppTAG52Eu37UuVOg'}, attempt #1 11:58:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Gt-a7gppTAG52Eu37UuVOg'}, attempt #1 11:58:33 INFO - Task dependencies: P_fmKY9XR4WTD3BXDh9SKg 11:58:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg'}, attempt #1 11:58:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.tar.bz2 11:58:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.test_packages.json 11:58:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.crashreporter-symbols.zip 11:58:34 INFO - Running post-action listener: _resource_record_post_action 11:58:34 INFO - [mozharness: 2017-09-23 18:58:34.131427Z] Finished read-buildbot-config step (success) 11:58:34 INFO - [mozharness: 2017-09-23 18:58:34.131645Z] Running download-and-extract step. 11:58:34 INFO - Running pre-action listener: _resource_record_pre_action 11:58:34 INFO - Running main action method: download_and_extract 11:58:34 INFO - mkdir: /builds/slave/test/build/tests 11:58:34 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')]} 11:58:34 INFO - trying https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.test_packages.json 11:58:34 INFO - Downloading https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:58:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:58:34 INFO - Downloaded 989 bytes. 11:58:34 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:58:34 INFO - Using the following test package requirements: 11:58:34 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:58:34 INFO - u'common': [u'target.common.tests.zip'], 11:58:34 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:58:34 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:58:34 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:58:34 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:58:34 INFO - u'mozbase': [u'target.common.tests.zip'], 11:58:34 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:58:34 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:58:34 INFO - u'web-platform': [u'target.common.tests.zip', 11:58:34 INFO - u'target.web-platform.tests.tar.gz'], 11:58:34 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:58:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 11:58:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.common.tests.zip 11:58:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:58:34 INFO - Fetch https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.common.tests.zip into memory 11:58:35 INFO - Content-Length response header: 55342517 11:58:35 INFO - Bytes received: 55342517 11:58:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.talos.tests.zip 11:58:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:58:46 INFO - Fetch https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.talos.tests.zip into memory 11:58:47 INFO - Content-Length response header: 14513823 11:58:47 INFO - Bytes received: 14513823 11:58:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:47 INFO - trying https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.tar.bz2 11:58:47 INFO - Downloading https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:58:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:58:53 INFO - Downloaded 62380268 bytes. 11:58:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.tar.bz2 11:58:53 INFO - mkdir: /builds/slave/test/properties 11:58:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:58:53 INFO - Writing to file /builds/slave/test/properties/build_url 11:58:53 INFO - Contents: 11:58:53 INFO - build_url:https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.tar.bz2 11:58:53 INFO - Running post-action listener: _resource_record_post_action 11:58:53 INFO - Running post-action listener: find_tests_for_verification 11:58:53 INFO - Running post-action listener: set_extra_try_arguments 11:58:53 INFO - [mozharness: 2017-09-23 18:58:53.990380Z] Finished download-and-extract step (success) 11:58:53 INFO - [mozharness: 2017-09-23 18:58:53.990632Z] Running populate-webroot step. 11:58:53 INFO - Running pre-action listener: _resource_record_pre_action 11:58:53 INFO - Running main action method: populate_webroot 11:58:54 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:58:54 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:58:54 INFO - 'tests': ['tresize', 11:58:54 INFO - 'tcanvasmark']}, 11:58:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:58:54 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:58:54 INFO - 'tests': ['dromaeo_css', 11:58:54 INFO - 'kraken']}, 11:58:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:58:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:58:54 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:58:54 INFO - 'talos_options': ['--disable-stylo'], 11:58:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:58:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:58:54 INFO - 'tests': ['damp', 'tps']}, 11:58:54 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:58:54 INFO - 'talos_options': ['--disable-stylo'], 11:58:54 INFO - 'tests': ['damp', 'tps']}, 11:58:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:58:54 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:58:54 INFO - 'tests': ['dromaeo_dom']}, 11:58:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:58:54 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:58:54 INFO - 'tests': ['basic_compositor_video', 11:58:54 INFO - 'glvideo']}, 11:58:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:58:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:58:54 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:58:54 INFO - 'talos_options': ['--disable-stylo'], 11:58:54 INFO - 'tests': ['ts_paint_webext', 11:58:54 INFO - 'tp5o_webext']}, 11:58:54 INFO - 'other-e10s': {'tests': ['a11yr', 11:58:54 INFO - 'ts_paint', 11:58:54 INFO - 'tpaint', 11:58:54 INFO - 'sessionrestore', 11:58:54 INFO - 'sessionrestore_many_windows', 11:58:54 INFO - 'sessionrestore_no_auto_restore', 11:58:54 INFO - 'tabpaint']}, 11:58:54 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:58:54 INFO - 'tests': ['a11yr', 11:58:54 INFO - 'ts_paint', 11:58:54 INFO - 'tpaint', 11:58:54 INFO - 'sessionrestore', 11:58:54 INFO - 'sessionrestore_many_windows', 11:58:54 INFO - 'sessionrestore_no_auto_restore', 11:58:54 INFO - 'tabpaint']}, 11:58:54 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 11:58:54 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 11:58:54 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:58:54 INFO - 'tests': ['perf_reftest_singletons']}, 11:58:54 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:58:54 INFO - 'tests': ['perf_reftest']}, 11:58:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:58:54 INFO - 'tsvgr_opacity', 11:58:54 INFO - 'tart', 11:58:54 INFO - 'tscrollx', 11:58:54 INFO - 'tsvg_static']}, 11:58:54 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:58:54 INFO - 'tests': ['tsvgx', 11:58:54 INFO - 'tsvgr_opacity', 11:58:54 INFO - 'tart', 11:58:54 INFO - 'tscrollx', 11:58:54 INFO - 'tsvg_static']}, 11:58:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:58:54 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:58:54 INFO - 'talos_options': ['--disable-stylo'], 11:58:54 INFO - 'tests': ['tp5o']}, 11:58:54 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:58:54 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:58:54 INFO - 'talos_options': ['--mitmproxy', 11:58:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:58:54 INFO - '--firstNonBlankPaint'], 11:58:54 INFO - 'tests': ['tp6_google', 11:58:54 INFO - 'tp6_youtube', 11:58:54 INFO - 'tp6_amazon', 11:58:54 INFO - 'tp6_facebook']}, 11:58:54 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:58:54 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:58:54 INFO - 'talos_options': ['--disable-stylo', 11:58:54 INFO - '--mitmproxy', 11:58:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:58:54 INFO - '--firstNonBlankPaint'], 11:58:54 INFO - 'tests': ['tp6_google', 11:58:54 INFO - 'tp6_youtube', 11:58:54 INFO - 'tp6_amazon', 11:58:54 INFO - 'tp6_facebook']}, 11:58:54 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:58:54 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:58:54 INFO - 'talos_options': ['--stylo-threads=1', 11:58:54 INFO - '--mitmproxy', 11:58:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:58:54 INFO - '--firstNonBlankPaint'], 11:58:54 INFO - 'tests': ['tp6_google', 11:58:54 INFO - 'tp6_youtube', 11:58:54 INFO - 'tp6_amazon', 11:58:54 INFO - 'tp6_facebook']}, 11:58:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:58:54 INFO - 'talos_options': ['--xperf_path', 11:58:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:58:54 INFO - 'tests': ['tp5n']}, 11:58:54 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:58:54 INFO - 'talos_options': ['--disable-stylo', 11:58:54 INFO - '--xperf_path', 11:58:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:58:54 INFO - 'tests': ['tp5n']}}} 11:58:54 INFO - Running post-action listener: _resource_record_post_action 11:58:54 INFO - [mozharness: 2017-09-23 18:58:54.011567Z] Finished populate-webroot step (success) 11:58:54 INFO - [mozharness: 2017-09-23 18:58:54.011673Z] Running create-virtualenv step. 11:58:54 INFO - Running pre-action listener: _resource_record_pre_action 11:58:54 INFO - Running main action method: create_virtualenv 11:58:54 INFO - Creating virtualenv /builds/slave/test/build/venv 11:58:54 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:58:54 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:58:54 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:58:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:58:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:58:54 INFO - Installing distribute.............................................................................................................................................................................................done. 11:58:54 INFO - Installing pip...............done. 11:58:54 INFO - Return code: 0 11:58:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:58:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:58:55 INFO - Reading from file tmpfile_stdout 11:58:55 INFO - Output received: 11:58:55 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:58:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:58:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1411b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b69c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b3d60>, '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 0x15b4a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b4ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', '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 11:58:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:58:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:58:55 INFO - Using env: {'DISPLAY': ':0', 11:58:55 INFO - 'HOME': '/home/cltbld', 11:58:55 INFO - 'LANG': 'en_US.UTF-8', 11:58:55 INFO - 'LANGUAGE': 'en_US:en', 11:58:55 INFO - 'LOGNAME': 'cltbld', 11:58:55 INFO - 'MAIL': '/var/mail/cltbld', 11:58:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:55 INFO - 'MOZ_NO_REMOTE': '1', 11:58:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:55 INFO - 'NO_EM_RESTART': '1', 11:58:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:55 INFO - 'PWD': '/builds/slave/test', 11:58:55 INFO - 'SHELL': '/bin/bash', 11:58:55 INFO - 'SHLVL': '1', 11:58:55 INFO - 'TERM': 'linux', 11:58:55 INFO - 'TMOUT': '86400', 11:58:55 INFO - 'USER': 'cltbld', 11:58:55 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', 11:58:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:55 INFO - '_': '/tools/buildbot/bin/python'} 11:58:56 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:58:56 INFO - Downloading/unpacking pip>=1.5 11:58:56 INFO - Running setup.py egg_info for package pip 11:58:56 INFO - warning: no files found matching 'pip/cacert.pem' 11:58:56 INFO - warning: no files found matching '*.html' under directory 'docs' 11:58:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:58:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:58:56 INFO - Installing collected packages: pip 11:58:56 INFO - Found existing installation: pip 1.1 11:58:56 INFO - Uninstalling pip: 11:58:56 INFO - Successfully uninstalled pip 11:58:56 INFO - Running setup.py install for pip 11:58:56 INFO - warning: no files found matching 'pip/cacert.pem' 11:58:56 INFO - warning: no files found matching '*.html' under directory 'docs' 11:58:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:58:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:58:56 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:58:56 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:58:56 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:58:56 INFO - Successfully installed pip 11:58:56 INFO - Cleaning up... 11:58:56 INFO - Return code: 0 11:58:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:58: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')]} 11:58:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58: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')]} 11:58:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58: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', '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 0x12491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1411b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b69c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b3d60>, '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 0x15b4a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b4ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', '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 11:58: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:58: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 psutil>=3.1.1 11:58:56 INFO - Using env: {'DISPLAY': ':0', 11:58:56 INFO - 'HOME': '/home/cltbld', 11:58:56 INFO - 'LANG': 'en_US.UTF-8', 11:58:56 INFO - 'LANGUAGE': 'en_US:en', 11:58:56 INFO - 'LOGNAME': 'cltbld', 11:58:56 INFO - 'MAIL': '/var/mail/cltbld', 11:58:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:56 INFO - 'MOZ_NO_REMOTE': '1', 11:58:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:56 INFO - 'NO_EM_RESTART': '1', 11:58:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:56 INFO - 'PWD': '/builds/slave/test', 11:58:56 INFO - 'SHELL': '/bin/bash', 11:58:56 INFO - 'SHLVL': '1', 11:58:56 INFO - 'TERM': 'linux', 11:58:56 INFO - 'TMOUT': '86400', 11:58:56 INFO - 'USER': 'cltbld', 11:58:56 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', 11:58:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:56 INFO - '_': '/tools/buildbot/bin/python'} 11:58:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:56 INFO - Downloading/unpacking psutil>=3.1.1 11:58: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 11:58: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 11:58: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 11:58: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 11:58:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:58:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:58:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:58:57 INFO - Installing collected packages: psutil 11:58:57 INFO - Running setup.py install for psutil 11:58:57 INFO - building 'psutil._psutil_linux' extension 11:58:57 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 11:58:57 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 11:58:57 INFO - building 'psutil._psutil_posix' extension 11:58:57 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 11:58:58 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 11:58:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:58:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:58:58 INFO - Successfully installed psutil 11:58:58 INFO - Cleaning up... 11:58:58 INFO - Return code: 0 11:58:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:58: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')]} 11:58:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58: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')]} 11:58:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1411b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b69c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b3d60>, '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 0x15b4a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b4ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', '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 11:58:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:58:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:58:58 INFO - Using env: {'DISPLAY': ':0', 11:58:58 INFO - 'HOME': '/home/cltbld', 11:58:58 INFO - 'LANG': 'en_US.UTF-8', 11:58:58 INFO - 'LANGUAGE': 'en_US:en', 11:58:58 INFO - 'LOGNAME': 'cltbld', 11:58:58 INFO - 'MAIL': '/var/mail/cltbld', 11:58:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:58 INFO - 'MOZ_NO_REMOTE': '1', 11:58:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:58 INFO - 'NO_EM_RESTART': '1', 11:58:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:58 INFO - 'PWD': '/builds/slave/test', 11:58:58 INFO - 'SHELL': '/bin/bash', 11:58:58 INFO - 'SHLVL': '1', 11:58:58 INFO - 'TERM': 'linux', 11:58:58 INFO - 'TMOUT': '86400', 11:58:58 INFO - 'USER': 'cltbld', 11:58:58 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', 11:58:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:58 INFO - '_': '/tools/buildbot/bin/python'} 11:58:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:58: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 11:58: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 11:58: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 11:58: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 11:58:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:58:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:58:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:58:59 INFO - Installing collected packages: mozsystemmonitor 11:58:59 INFO - Running setup.py install for mozsystemmonitor 11:58:59 INFO - Successfully installed mozsystemmonitor 11:58:59 INFO - Cleaning up... 11:59:00 INFO - Return code: 0 11:59:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:59:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:00 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 0x12491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1411b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b69c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b3d60>, '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 0x15b4a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b4ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', '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 11:59:00 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 11:59:00 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 11:59:00 INFO - Using env: {'DISPLAY': ':0', 11:59:00 INFO - 'HOME': '/home/cltbld', 11:59:00 INFO - 'LANG': 'en_US.UTF-8', 11:59:00 INFO - 'LANGUAGE': 'en_US:en', 11:59:00 INFO - 'LOGNAME': 'cltbld', 11:59:00 INFO - 'MAIL': '/var/mail/cltbld', 11:59:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:00 INFO - 'MOZ_NO_REMOTE': '1', 11:59:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:00 INFO - 'NO_EM_RESTART': '1', 11:59:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:00 INFO - 'PWD': '/builds/slave/test', 11:59:00 INFO - 'SHELL': '/bin/bash', 11:59:00 INFO - 'SHLVL': '1', 11:59:00 INFO - 'TERM': 'linux', 11:59:00 INFO - 'TMOUT': '86400', 11:59:00 INFO - 'USER': 'cltbld', 11:59:00 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', 11:59:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:00 INFO - '_': '/tools/buildbot/bin/python'} 11:59:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:00 INFO - Downloading/unpacking jsonschema==2.5.1 11:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:59:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:59: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 11:59: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 11:59: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 11:59: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 11:59:03 INFO - Downloading functools32-3.2.3-2.tar.gz 11:59:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:59:03 INFO - warning: no files found matching '*.txt' 11:59:03 INFO - no previously-included directories found matching 'build' 11:59:03 INFO - no previously-included directories found matching 'dist' 11:59:03 INFO - no previously-included directories found matching '.git*' 11:59:03 INFO - Installing collected packages: jsonschema, functools32 11:59:03 INFO - Running setup.py install for functools32 11:59:03 INFO - warning: no files found matching '*.txt' 11:59:03 INFO - no previously-included directories found matching 'build' 11:59:03 INFO - no previously-included directories found matching 'dist' 11:59:03 INFO - no previously-included directories found matching '.git*' 11:59:03 INFO - Successfully installed jsonschema functools32 11:59:03 INFO - Cleaning up... 11:59:03 INFO - Return code: 0 11:59:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:59:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:03 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 0x12491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1411b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b69c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b3d60>, '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 0x15b4a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b4ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', '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 11:59:03 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 11:59:03 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 11:59:03 INFO - Using env: {'DISPLAY': ':0', 11:59:03 INFO - 'HOME': '/home/cltbld', 11:59:03 INFO - 'LANG': 'en_US.UTF-8', 11:59:03 INFO - 'LANGUAGE': 'en_US:en', 11:59:03 INFO - 'LOGNAME': 'cltbld', 11:59:03 INFO - 'MAIL': '/var/mail/cltbld', 11:59:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:03 INFO - 'MOZ_NO_REMOTE': '1', 11:59:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:03 INFO - 'NO_EM_RESTART': '1', 11:59:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:03 INFO - 'PWD': '/builds/slave/test', 11:59:03 INFO - 'SHELL': '/bin/bash', 11:59:03 INFO - 'SHLVL': '1', 11:59:03 INFO - 'TERM': 'linux', 11:59:03 INFO - 'TMOUT': '86400', 11:59:03 INFO - 'USER': 'cltbld', 11:59:03 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', 11:59:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:03 INFO - '_': '/tools/buildbot/bin/python'} 11:59:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:59:03 INFO - Cleaning up... 11:59:03 INFO - Return code: 0 11:59:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:59:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:03 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 0x12491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1411b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b69c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b3d60>, '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 0x15b4a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b4ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', '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 11:59:03 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 11:59:03 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 11:59:03 INFO - Using env: {'DISPLAY': ':0', 11:59:03 INFO - 'HOME': '/home/cltbld', 11:59:03 INFO - 'LANG': 'en_US.UTF-8', 11:59:03 INFO - 'LANGUAGE': 'en_US:en', 11:59:03 INFO - 'LOGNAME': 'cltbld', 11:59:03 INFO - 'MAIL': '/var/mail/cltbld', 11:59:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:03 INFO - 'MOZ_NO_REMOTE': '1', 11:59:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:03 INFO - 'NO_EM_RESTART': '1', 11:59:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:03 INFO - 'PWD': '/builds/slave/test', 11:59:03 INFO - 'SHELL': '/bin/bash', 11:59:03 INFO - 'SHLVL': '1', 11:59:03 INFO - 'TERM': 'linux', 11:59:03 INFO - 'TMOUT': '86400', 11:59:03 INFO - 'USER': 'cltbld', 11:59:03 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', 11:59:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:03 INFO - '_': '/tools/buildbot/bin/python'} 11:59:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:04 INFO - Downloading/unpacking blobuploader==1.2.4 11:59:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:05 INFO - Downloading blobuploader-1.2.4.tar.gz 11:59:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:59:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:59: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 11:59: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 11:59: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 11:59: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 11:59:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:59:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:59: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 11:59: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 11:59: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 11:59: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 11:59:05 INFO - Downloading docopt-0.6.1.tar.gz 11:59:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:59:05 INFO - Installing collected packages: blobuploader, requests, docopt 11:59:05 INFO - Running setup.py install for blobuploader 11:59:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:59:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:59:05 INFO - Running setup.py install for requests 11:59:06 INFO - Running setup.py install for docopt 11:59:06 INFO - Successfully installed blobuploader requests docopt 11:59:06 INFO - Cleaning up... 11:59:06 INFO - Return code: 0 11:59:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:59:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:06 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 0x12491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1411b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b69c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b3d60>, '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 0x15b4a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b4ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', '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 11:59:06 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 11:59:06 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 11:59:06 INFO - Using env: {'DISPLAY': ':0', 11:59:06 INFO - 'HOME': '/home/cltbld', 11:59:06 INFO - 'LANG': 'en_US.UTF-8', 11:59:06 INFO - 'LANGUAGE': 'en_US:en', 11:59:06 INFO - 'LOGNAME': 'cltbld', 11:59:06 INFO - 'MAIL': '/var/mail/cltbld', 11:59:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:06 INFO - 'MOZ_NO_REMOTE': '1', 11:59:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:06 INFO - 'NO_EM_RESTART': '1', 11:59:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:06 INFO - 'PWD': '/builds/slave/test', 11:59:06 INFO - 'SHELL': '/bin/bash', 11:59:06 INFO - 'SHLVL': '1', 11:59:06 INFO - 'TERM': 'linux', 11:59:06 INFO - 'TMOUT': '86400', 11:59:06 INFO - 'USER': 'cltbld', 11:59:06 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', 11:59:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:06 INFO - '_': '/tools/buildbot/bin/python'} 11:59:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:59:06 INFO - Running setup.py (path:/tmp/pip-q7_3tN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:59:06 INFO - Running setup.py (path:/tmp/pip-VBoYfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:59:06 INFO - Running setup.py (path:/tmp/pip-jv8mUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:59:06 INFO - Running setup.py (path:/tmp/pip-uBjfQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:59:06 INFO - Running setup.py (path:/tmp/pip-SS0h9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:59:06 INFO - Running setup.py (path:/tmp/pip-6q_Ezo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:59:06 INFO - Running setup.py (path:/tmp/pip-klTu03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:59:07 INFO - Running setup.py (path:/tmp/pip-3S7qIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:59:07 INFO - Running setup.py (path:/tmp/pip-3mF1Vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:59:07 INFO - Running setup.py (path:/tmp/pip-czOmTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:59:07 INFO - Running setup.py (path:/tmp/pip-TXqXgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:59:07 INFO - Running setup.py (path:/tmp/pip-eyGohj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:59:07 INFO - Running setup.py (path:/tmp/pip-MaTAlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:59:07 INFO - Running setup.py (path:/tmp/pip-46kyxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:59:07 INFO - Running setup.py (path:/tmp/pip-74ixZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:59:07 INFO - Running setup.py (path:/tmp/pip-eWSqaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:59:07 INFO - Running setup.py (path:/tmp/pip-MqPg7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:59:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:59:07 INFO - Running setup.py install for manifestparser 11:59:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:59:07 INFO - Running setup.py install for mozcrash 11:59:07 INFO - Running setup.py install for mozdebug 11:59:08 INFO - Running setup.py install for mozdevice 11:59:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:59:08 INFO - Running setup.py install for mozfile 11:59:08 INFO - Running setup.py install for mozhttpd 11:59:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:59:08 INFO - Running setup.py install for mozinfo 11:59:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:59:08 INFO - Running setup.py install for mozInstall 11:59:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:59:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:59:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:59:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:59:08 INFO - Running setup.py install for mozleak 11:59:08 INFO - Running setup.py install for mozlog 11:59:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:59:08 INFO - Running setup.py install for moznetwork 11:59:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:59:08 INFO - Running setup.py install for mozprocess 11:59:08 INFO - Running setup.py install for mozprofile 11:59:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:59:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:59:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:59:09 INFO - Running setup.py install for mozrunner 11:59:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:59:09 INFO - Running setup.py install for mozscreenshot 11:59:09 INFO - Running setup.py install for moztest 11:59:09 INFO - Running setup.py install for mozversion 11:59:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:59:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:59:09 INFO - Cleaning up... 11:59:09 INFO - Return code: 0 11:59:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:09 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 0x12491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1411b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b69c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b3d60>, '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 0x15b4a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b4ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', '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 11:59:09 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 11:59:09 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 11:59:09 INFO - Using env: {'DISPLAY': ':0', 11:59:09 INFO - 'HOME': '/home/cltbld', 11:59:09 INFO - 'LANG': 'en_US.UTF-8', 11:59:09 INFO - 'LANGUAGE': 'en_US:en', 11:59:09 INFO - 'LOGNAME': 'cltbld', 11:59:09 INFO - 'MAIL': '/var/mail/cltbld', 11:59:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:09 INFO - 'MOZ_NO_REMOTE': '1', 11:59:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:09 INFO - 'NO_EM_RESTART': '1', 11:59:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:09 INFO - 'PWD': '/builds/slave/test', 11:59:09 INFO - 'SHELL': '/bin/bash', 11:59:09 INFO - 'SHLVL': '1', 11:59:09 INFO - 'TERM': 'linux', 11:59:09 INFO - 'TMOUT': '86400', 11:59:09 INFO - 'USER': 'cltbld', 11:59:09 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', 11:59:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:09 INFO - '_': '/tools/buildbot/bin/python'} 11:59:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:59:09 INFO - Running setup.py (path:/tmp/pip-VCJZCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:59:09 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)) 11:59:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:59:09 INFO - Running setup.py (path:/tmp/pip-2PXdwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:59:09 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)) 11:59:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:59:09 INFO - Running setup.py (path:/tmp/pip-JkSBkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:59:09 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)) 11:59:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:59:09 INFO - Running setup.py (path:/tmp/pip-jKUBIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:59:10 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)) 11:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:59:10 INFO - Running setup.py (path:/tmp/pip-Ck1grN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:59:10 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)) 11:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:59:10 INFO - Running setup.py (path:/tmp/pip-TbarUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:59:10 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)) 11:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:59:10 INFO - Running setup.py (path:/tmp/pip-8Y_h9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:59:10 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)) 11:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:59:10 INFO - Running setup.py (path:/tmp/pip-QWvoz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:59:10 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)) 11:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:59:10 INFO - Running setup.py (path:/tmp/pip-g_Mzhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:59:10 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)) 11:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:59:10 INFO - Running setup.py (path:/tmp/pip-N7wLZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:59:10 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)) 11:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:59:10 INFO - Running setup.py (path:/tmp/pip-cmZDUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:59:10 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)) 11:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:59:10 INFO - Running setup.py (path:/tmp/pip-qLVKqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:59:10 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)) 11:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:59:10 INFO - Running setup.py (path:/tmp/pip-8kGwJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:59:10 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)) 11:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:59:10 INFO - Running setup.py (path:/tmp/pip-Ilc06R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:59:10 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)) 11:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:59:10 INFO - Running setup.py (path:/tmp/pip-Tx8dhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:59:10 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)) 11:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:59:10 INFO - Running setup.py (path:/tmp/pip-vODGwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:59:10 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)) 11:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:59:10 INFO - Running setup.py (path:/tmp/pip-ScD3_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:59:10 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)) 11:59:10 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)) 11:59:10 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)) 11:59:10 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)) 11:59:10 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)) 11:59:10 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)) 11:59:10 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)) 11:59:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:59:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:12 INFO - Downloading blessings-1.6.tar.gz 11:59:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:59:12 INFO - Installing collected packages: blessings 11:59:12 INFO - Running setup.py install for blessings 11:59:12 INFO - Successfully installed blessings 11:59:12 INFO - Cleaning up... 11:59:12 INFO - Return code: 0 11:59:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:59:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:59:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:59:12 INFO - Reading from file tmpfile_stdout 11:59:12 INFO - Current package versions: 11:59:12 INFO - argparse == 1.2.1 11:59:12 INFO - blessings == 1.6 11:59:12 INFO - blobuploader == 1.2.4 11:59:12 INFO - docopt == 0.6.1 11:59:12 INFO - functools32 == 3.2.3-2 11:59:12 INFO - jsonschema == 2.5.1 11:59:12 INFO - manifestparser == 1.1 11:59:12 INFO - mozInstall == 1.13 11:59:12 INFO - mozcrash == 1.0 11:59:12 INFO - mozdebug == 0.1 11:59:12 INFO - mozdevice == 0.51 11:59:12 INFO - mozfile == 1.2 11:59:12 INFO - mozhttpd == 0.7 11:59:12 INFO - mozinfo == 0.10 11:59:12 INFO - mozleak == 0.1 11:59:12 INFO - mozlog == 3.5 11:59:12 INFO - moznetwork == 0.27 11:59:12 INFO - mozprocess == 0.25 11:59:12 INFO - mozprofile == 0.28 11:59:12 INFO - mozrunner == 6.13 11:59:12 INFO - mozscreenshot == 0.1 11:59:12 INFO - mozsystemmonitor == 0.3 11:59:12 INFO - moztest == 0.8 11:59:12 INFO - mozversion == 1.4 11:59:12 INFO - psutil == 3.1.1 11:59:12 INFO - requests == 1.2.3 11:59:12 INFO - wsgiref == 0.1.2 11:59:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:59:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:12 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 0x12491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1411b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b69c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b3d60>, '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 0x15b4a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b4ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', '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 11:59:12 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 11:59:12 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 11:59:12 INFO - Using env: {'DISPLAY': ':0', 11:59:12 INFO - 'HOME': '/home/cltbld', 11:59:12 INFO - 'LANG': 'en_US.UTF-8', 11:59:12 INFO - 'LANGUAGE': 'en_US:en', 11:59:12 INFO - 'LOGNAME': 'cltbld', 11:59:12 INFO - 'MAIL': '/var/mail/cltbld', 11:59:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:12 INFO - 'MOZ_NO_REMOTE': '1', 11:59:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:12 INFO - 'NO_EM_RESTART': '1', 11:59:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:12 INFO - 'PWD': '/builds/slave/test', 11:59:12 INFO - 'SHELL': '/bin/bash', 11:59:12 INFO - 'SHLVL': '1', 11:59:12 INFO - 'TERM': 'linux', 11:59:12 INFO - 'TMOUT': '86400', 11:59:12 INFO - 'USER': 'cltbld', 11:59:12 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', 11:59:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:12 INFO - '_': '/tools/buildbot/bin/python'} 11:59:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:12 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)) 11:59:12 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)) 11:59:12 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)) 11:59:12 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)) 11:59:12 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)) 11:59:12 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)) 11:59:12 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)) 11:59:12 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)) 11:59:12 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)) 11:59:12 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:59:13 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)) 11:59:13 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)) 11:59:13 INFO - Installing collected packages: simplejson 11:59:13 INFO - Running setup.py install for simplejson 11:59:14 INFO - building 'simplejson._speedups' extension 11:59:14 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 11:59:14 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 11:59:14 INFO - Successfully installed simplejson 11:59:14 INFO - Cleaning up... 11:59:14 INFO - Return code: 0 11:59:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:14 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 0x12491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1411b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b69c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b3d60>, '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 0x15b4a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b4ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', '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 11:59:14 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 11:59:14 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 11:59:14 INFO - Using env: {'DISPLAY': ':0', 11:59:14 INFO - 'HOME': '/home/cltbld', 11:59:14 INFO - 'LANG': 'en_US.UTF-8', 11:59:14 INFO - 'LANGUAGE': 'en_US:en', 11:59:14 INFO - 'LOGNAME': 'cltbld', 11:59:14 INFO - 'MAIL': '/var/mail/cltbld', 11:59:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:14 INFO - 'MOZ_NO_REMOTE': '1', 11:59:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:14 INFO - 'NO_EM_RESTART': '1', 11:59:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:14 INFO - 'PWD': '/builds/slave/test', 11:59:14 INFO - 'SHELL': '/bin/bash', 11:59:14 INFO - 'SHLVL': '1', 11:59:14 INFO - 'TERM': 'linux', 11:59:14 INFO - 'TMOUT': '86400', 11:59:14 INFO - 'USER': 'cltbld', 11:59:14 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', 11:59:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:14 INFO - '_': '/tools/buildbot/bin/python'} 11:59:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:59:15 INFO - Cleaning up... 11:59:15 INFO - Return code: 0 11:59:15 INFO - Running post-action listener: _resource_record_post_action 11:59:15 INFO - Running post-action listener: _start_resource_monitoring 11:59:15 INFO - Starting resource monitoring. 11:59:15 INFO - [mozharness: 2017-09-23 18:59:15.230369Z] Finished create-virtualenv step (success) 11:59:15 INFO - [mozharness: 2017-09-23 18:59:15.230964Z] Running install step. 11:59:15 INFO - Running pre-action listener: _resource_record_pre_action 11:59:15 INFO - Running main action method: install 11:59:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:59:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:59:15 INFO - Reading from file tmpfile_stdout 11:59:15 INFO - Detecting whether we're running mozinstall >=1.0... 11:59:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:59:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:59:15 INFO - Reading from file tmpfile_stdout 11:59:15 INFO - Output received: 11:59:15 INFO - Usage: mozinstall [options] installer 11:59:15 INFO - Options: 11:59:15 INFO - -h, --help show this help message and exit 11:59:15 INFO - -d DEST, --destination=DEST 11:59:15 INFO - Directory to install application into. [default: 11:59:15 INFO - "/builds/slave/test"] 11:59:15 INFO - --app=APP Application being installed. [default: firefox] 11:59:15 INFO - mkdir: /builds/slave/test/build/application 11:59:15 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'] 11:59:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:59:33 INFO - Reading from file tmpfile_stdout 11:59:33 INFO - Output received: 11:59:33 INFO - /builds/slave/test/build/application/firefox/firefox 11:59:33 INFO - Running post-action listener: _resource_record_post_action 11:59:33 INFO - [mozharness: 2017-09-23 18:59:33.208777Z] Finished install step (success) 11:59:33 INFO - [mozharness: 2017-09-23 18:59:33.209027Z] Running setup-mitmproxy step. 11:59:33 INFO - Running pre-action listener: _resource_record_pre_action 11:59:33 INFO - Running main action method: setup_mitmproxy 11:59:33 INFO - Skipping: mitmproxy is not required 11:59:33 INFO - Running post-action listener: _resource_record_post_action 11:59:33 INFO - [mozharness: 2017-09-23 18:59:33.210161Z] Finished setup-mitmproxy step (success) 11:59:33 INFO - [mozharness: 2017-09-23 18:59:33.210381Z] Running run-tests step. 11:59:33 INFO - Running pre-action listener: _resource_record_pre_action 11:59:33 INFO - Running pre-action listener: _set_gcov_prefix 11:59:33 INFO - Running main action method: run_tests 11:59:33 WARNING - Try message not found. 11:59:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:59:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:59:33 INFO - Python 2.7.3 11:59:33 INFO - Return code: 0 11:59:33 INFO - grabbing minidump binary from tooltool 11:59:33 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')]} 11:59:33 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 0x15b3d60>, '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 0x15b4a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b4ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:59:33 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 11:59:33 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 11:59:33 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 11:59:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:59:33 INFO - Return code: 0 11:59:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:59:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:59:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:59:33 INFO - ENV: RUST_BACKTRACE is now full 11:59:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:59:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:59:33 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/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:59:33 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/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-045 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:59:33 INFO - Using env: {'DISPLAY': ':0', 11:59:33 INFO - 'HOME': '/home/cltbld', 11:59:33 INFO - 'LANG': 'en_US.UTF-8', 11:59:33 INFO - 'LANGUAGE': 'en_US:en', 11:59:33 INFO - 'LOGNAME': 'cltbld', 11:59:33 INFO - 'MAIL': '/var/mail/cltbld', 11:59:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:59:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:59:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:33 INFO - 'MOZ_NO_REMOTE': '1', 11:59:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:59:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:33 INFO - 'NO_EM_RESTART': '1', 11:59:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:33 INFO - 'PWD': '/builds/slave/test', 11:59:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:59:33 INFO - 'RUST_BACKTRACE': 'full', 11:59:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:59:33 INFO - 'SHELL': '/bin/bash', 11:59:33 INFO - 'SHLVL': '1', 11:59:33 INFO - 'TERM': 'linux', 11:59:33 INFO - 'TMOUT': '86400', 11:59:33 INFO - 'USER': 'cltbld', 11:59:33 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', 11:59:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:33 INFO - '_': '/tools/buildbot/bin/python'} 11:59:33 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/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:59:33 INFO - mozversion application_buildid: 20170923173344 11:59:33 INFO - mozversion application_changeset: 2d9d0bebb5c66ba3dfdffd713a888a8c49730631 11:59:33 INFO - mozversion application_display_name: Nightly 11:59:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:59:33 INFO - mozversion application_name: Firefox 11:59:33 INFO - mozversion application_remotingname: firefox 11:59:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:59:33 INFO - mozversion application_vendor: Mozilla 11:59:33 INFO - mozversion application_version: 58.0a1 11:59:33 INFO - mozversion platform_buildid: 20170923173344 11:59:33 INFO - mozversion platform_changeset: 2d9d0bebb5c66ba3dfdffd713a888a8c49730631 11:59:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:59:33 INFO - mozversion platform_version: 58.0a1 11:59:33 INFO - using testdate: 1506193173 11:59:33 INFO - actual date: 1506193173 11:59:33 INFO - starting webserver on 'localhost:36527' 11:59:33 INFO - SUITE-START | Running 2 tests 11:59:33 INFO - TEST-START | dromaeo_css 11:59:33 INFO - Initialising browser for dromaeo_css test... 11:59:33 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36527/getInfo.html -profile /tmp/tmp2DPJsK/profile 11:59:33 INFO - TEST-INFO | started process 11135 (/builds/slave/test/build/application/firefox/firefox http://localhost:36527/getInfo.html) 11:59:39 INFO - TEST-INFO | 11135: exit 0 11:59:39 INFO - Browser initialized. 11:59:39 INFO - Running cycle 1/1 for dromaeo_css test... 11:59:39 INFO - Using env: {'DISPLAY': ':0', 11:59:39 INFO - 'HOME': '/home/cltbld', 11:59:39 INFO - 'JSGC_DISABLE_POISONING': '1', 11:59:39 INFO - 'LANG': 'en_US.UTF-8', 11:59:39 INFO - 'LANGUAGE': 'en_US:en', 11:59:39 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:59:39 INFO - 'LOGNAME': 'cltbld', 11:59:39 INFO - 'MAIL': '/var/mail/cltbld', 11:59:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:59:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:59:39 INFO - 'MOZ_CRASHREPORTER': '1', 11:59:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:39 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:59:39 INFO - 'MOZ_NO_REMOTE': '1', 11:59:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:59:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:39 INFO - 'NO_EM_RESTART': '1', 11:59:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:39 INFO - 'PWD': '/builds/slave/test', 11:59:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:59:39 INFO - 'RUST_BACKTRACE': 'full', 11:59:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:59:39 INFO - 'SHELL': '/bin/bash', 11:59:39 INFO - 'SHLVL': '1', 11:59:39 INFO - 'TERM': 'linux', 11:59:39 INFO - 'TMOUT': '86400', 11:59:39 INFO - 'USER': 'cltbld', 11:59:39 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', 11:59:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:39 INFO - '_': '/tools/buildbot/bin/python'} 11:59:39 INFO - TEST-INFO | started process 11375 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2DPJsK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:59:40 INFO - PID 11375 | 11:59:40 INFO - PID 11375 | (/builds/slave/test/build/application/firefox/firefox:11425): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:59:40 INFO - PID 11375 | 11:59:51 INFO - PID 11375 | RSS: Main: 140705792 11:59:51 INFO - PID 11375 | 12:02:48 INFO - PID 11375 | Cycle 1(1): loaded http://localhost:36527/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36527/tests/dromaeo/cssquery-ext.html) 12:02:49 INFO - PID 11375 | RSS: Main: 146026496 12:02:49 INFO - PID 11375 | 12:05:51 INFO - PID 11375 | Cycle 1(1): loaded http://localhost:36527/tests/dromaeo/cssquery-ext.html (next: http://localhost:36527/tests/dromaeo/cssquery-jquery.html) 12:05:51 INFO - PID 11375 | RSS: Main: 146534400 12:05:51 INFO - PID 11375 | 12:08:53 INFO - PID 11375 | Cycle 1(1): loaded http://localhost:36527/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36527/tests/dromaeo/cssquery-mootools.html) 12:08:54 INFO - PID 11375 | RSS: Main: 148615168 12:08:54 INFO - PID 11375 | 12:11:55 INFO - PID 11375 | Cycle 1(1): loaded http://localhost:36527/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36527/tests/dromaeo/cssquery-prototype.html) 12:11:56 INFO - PID 11375 | RSS: Main: 146669568 12:11:56 INFO - PID 11375 | 12:14:59 INFO - PID 11375 | Cycle 1(1): loaded http://localhost:36527/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36527/tests/dromaeo/cssquery-yui.html) 12:14:59 INFO - PID 11375 | RSS: Main: 147841024 12:14:59 INFO - PID 11375 | 12:18:01 INFO - PID 11375 | Cycle 1(1): loaded http://localhost:36527/tests/dromaeo/cssquery-yui.html (next: http://localhost:36527/tests/dromaeo/cssquery-dojo.html) 12:18:01 INFO - PID 11375 | RSS: Main: 150691840 12:18:01 INFO - PID 11375 | 12:18:01 INFO - PID 11375 | __start_tp_report 12:18:01 INFO - PID 11375 | _x_x_mozilla_page_load 12:18:01 INFO - PID 11375 | _x_x_mozilla_page_load_details 12:18:01 INFO - PID 11375 | |i|pagename|runs| 12:18:01 INFO - PID 11375 | |0;dojo.html;3931.272714547807;3933.76397416155;3937.6259255370765;3941.842326306948;3942.724009319347;6229.750809967607;6290.96854515728;6339.651319177449;6351.174347334846;6380.074889141072;6327.050942358648;6370.458511026568;6378.712957916893;6384.5211609129265;6407.167068281117;5664.490195882369;5669.688167150805;5689.317281926154;5696.829095127153;5753.424657534255;5693.430656934294;5736.082226843706;5778.335491418498;5788.218590490293;5789.131630255453;5594.888102237933;5665.3201615806165;5738.196652468637;5739.483446489815;5751.683657398862;5497.8075767347955;5577.9163312550345;5579.804706835242;5647.745851436695;5659.773609055674;5583.162525621149;5596.384397716248;5627.634203776742;5636.661800291996;5664.57515686321;6116.418940200674;6176.197094377702;6178.042403427431;6193.4425901669065;6273.968630156865;6757.222919364287;6798.354156355138;6808.9659551701925;6833.180018397824;6889.827754306132;6927.3419025192525;6931.168259808805;6935.4104901083465;6943.826404339881;6949.166100068024;6343.460805831556;6348.968255158695;6417.967910160466;6428.357164283628;6449.838754031187;6905.482088843357;6974.686139123752;7002.089728335284;7075.469339799535;7104.502684812117;6639.834004149839;6760.357766012221;6763.357481039392;6802.6258555779905;6837.5555588887555;6864.2106157791495;6874.553154044971;6950.026996220533;6950.131233595801;6959.82600434983;6791.185057792997;6808.489363297773;6823.522353435311;6895.172579290417;6907;6875.4155896749335;6896.310368963063;6904.206016308089;6928.1339832520935;6930.203026651899;4970.552650261494;5035.924461133086;5046.091703493333;5057.342545469065;5058.038972595195;4493.370928070073;4505.07645932583;4543.886402839889;4546.386237857847;4598.609118224988;6316.399942005502;6332.588381755171;6396.1045453636525;6420.807375778734;6435.292117867031;6850.451963842881;6872.312768723088;6926.549774264657;6953;6963.617001064888;6356.14192084072;6358;6430.421262086435;6433.485321174295;6452.161219041588;5429.294191755047;5515.062439385314;5515.227868098469;5519.917201241985;5577.609567330248;7010.088688470568;7041.929580704127;7048.048513450619;7051.647417629098;7059.929400705927;6822.692978816064;6885;6937.653117344214;6941;6943;7055.329743674342;7059.647017649199;7076.893846592202;7168.928310716826;7170.211276759448;6660.900086498609;6772.356626120511;6773.05177275172;6869.656517174021;6885.51801373899;6818.420434263143;6826.556273842184;6832.829179270558;6841.828954276183;6845.212800528003;6524;6596.60420374779;6613.338666133348;6615.5038372123245;6673.432758215412;6620.867582648417;6686.53194276396;6701.865962680817;6705.765298214539;6709.429698475684;6562.80311590653;6569.868602628017;6656.201255849329;6660.433863121689;6668.733250670113;6538.215414150332;6664.900026499509;6716.83207919806;6789.15135608049;6791.117154769849;6494.545381823227;6528.086067950396;6587.176602924635;6598.571092878948;6609.339066093352 12:18:01 INFO - PID 11375 | |1;ext.html;895.3463971300954;927.8515437529994;960.9039096090391;964.7154089543588;988.4020167798486;44475.77762111189;45575.31637025447;45613.77193114034;45698.543014569885;45856.3121553177;42613.14773704524;42962.355564666555;46116.76941615296;46276.76861615696;46299;8395.58022098894;8588.914110858905;8601.225889669928;8602.784930376729;8618.78453038676;8529.786755331135;8657.783555411133;8658.826823463527;8668.8699669505;8678.262347700453;8568.614412351428;8628.568571571397;8655.047944726106;8669.566521673923;8682.26200772935;8526.232639062515;8531.65873365071;8587.871181932245;8608.784780380478;8633.09352517983;8438.662453501853;8439.324854011664;8478.448900821428;8480.957595211985;8511.233988940963;42311.788441057906;42387.36418953687;42456.36315455271;42576;42600.360994585106;3776.0937375029794;3846.211526637033;3846.9230615387537;3848.172642881766;3848.8460461581503;34681.65318346809;34929.825350873085;34935.12662183466;35006.47490287648;35119;5406.972965135149;5455.972720136374;5459.617826752129;5487.6981766002855;5577.442255774431;223498;230366;230955;232167;232609;47884.28173577399;54647.726761366735;55042;55061.72469137629;55078.72460637671;58678;58884.41115588875;59082.70458647679;59085.40914590885;59185.40814591885;15469.226538673021;15476.76784848228;15479.690406192038;15575.454859079879;15602.765958510632;12429.37853107341;12461.252324860538;12482.43829027696;12574.81137782934;12597.433115509824;14414;14535.927320363331;14564.119976232367;14567.344469498898;14715.117092974242;5695.60130790849;5763.221965034751;5796.88406231873;5809.302883653989;5912.822615321547;1071;1083.1984331594563;1089.7983872983523;1091.645215305029;1110.1618278199908;26155.346116346864;26156.476870462866;26199.214023579323;26412.735872641028;26542.33644158873;1157.242006485753;1183.2722875431548;1184.8341232227494;1187.6377704799945;1201.2192075151047;13379.732405352033;13531.188128712112;13550;13634.1137826041;13686.589402317946;290643;290925;291475;292200;293213;58771.41228587745;58942.41057589455;59077.40922590805;59239.40760592425;59449.702751485966;1498.303288970634;1537.0393504060187;1537.500312398475;1547.156799544251;1557.6806754615277;25131.623025654997;25186.24441266765;25262.494750105263;25443.36391590151;25621;42416;42500.36249456199;42506;42532.36201456919;42601.786991064844;250522;251006;251348;251866;252961;34088.82955585206;34238;36621;37020.814895925345;37711;47921.281180783015;47946.76026619845;48115.27827082526;48521;48585;35564.644353556134;35624.46563301501;35879.10302242465;36043.81978090093;36715.26569468649;35080.12299692528;35274.823625881705;35329.29341413106;35781;35799.46300805438;40205;40911.38632920449;41100;41161;41340.1731965365;34222.14444638904;34299;34660.82669586636;34691;35054.824725876206;25902.22293331203;26045.609315860656;26084.34789130287;26222.73777262203;26229.34426639349 12:18:01 INFO - PID 11375 | |2;jquery.html;586.3755100817626;596.1058412381428;615.9815205543832;629.7764293675745;638.5274896576532;23382.181623643177;24061.278161655133;24080.157194498195;24261.75738242617;24281.514369712597;20994;22484.43788905278;22617.095316187373;22666;22746.772532274674;6819.045333653305;6926.307369263059;6965.6517174141345;6968.094147760809;6969.372756451919;6583.1441912551545;6772.153480814898;6860.828479288008;6865.0732151159455;6867.931320686804;6819.727210911557;6823.044773731682;6844.041834143224;6851.588904665736;6855.520113592051;6530.118434011395;6768.323167683241;6794.320567943166;6824.829379265533;6825.215100263459;6334.746610135589;6341.3341599132345;6341.556091073627;6345.714442850037;6349.047642853563;29053.854730726212;29682.25794355137;29749.702502975128;29768.255793605116;29873.40253194924;2246.9550608987893;2252;2268.0814270220585;2271.136967952184;2276.64711969645;20047.1981120755;20333.085011174386;21201.25795597162;21262.574748504943;21267.36197914065;2532.5061612985473;2535.733747956456;2579.87100644967;2589.2491177558672;2603.7916966642624;138897;144397.27801360926;154731;160512.3948760509;160526.19736901356;15856.912974383147;16077.839221607634;16309.184540772914;16603.916980415022;16672.333106675596;25399.61900571493;25557.23328300154;25614;25624.615630765555;25667.614985775228;9714.22286217101;9798.216142708567;9981.101700846959;10034.448105354275;10116.898831011742;8766.517841518778;8847.159519845605;8893.244074253631;8900.955495222483;8946.463212207289;9444.952775236217;9678.661246856327;9762.267829912786;9892.356996795184;9918;2784.1647505748197;2813.6904940456534;2889.7688184945573;2936.8707686926805;3022.607061082045;1036.1658864613908;1053.5733028123623;1057.8575138850024;1063.4788953412926;1063.5107850388908;20558.58882822335;20990.265340713308;21234;21387.8930605346;21458.356249312546;1114.5653195253858;1121.5345631562973;1121.831725241204;1122;1130.327455164176;11179.161562882817;11696.766064678659;11698.76602467963;11827.586034488751;11851.407429628483;639986;658252;659567;660487;673500;132289;133933;134433.3278333602;134837;138056.30971845277;1079.5681727309138;1206.4088596587785;1208.4018410886667;1246.389269258057;1246.8441444819398;21548;21747;21943.67084493702;22004.669929951382;22218.77781222167;66286.33713662994;67712.3228767706;68140.6592967032;68363.3163668357;70135;86071.56964215139;87599;87758;88009.79975500664;88150.55924720336;15207.695846082795;15464.14947177871;15584.610384740019;15718.056916585041;15738.449154280002;23910.402239944142;23969.760302396753;24055.639165412184;24070.518589627758;24102.03591856376;18276;18526.444206673823;18581.25674972986;18642.81357186465;19404.611907761482;16755.664886701954;17268.22292996793;17356.39252626152;17463.30146794114;17662.293508259525;19574.217031319156;19578.60842783165;19803.702944456134;20052;20070.19719211215;15150.393984240507;15227.847721523087;15256;15283.15942623122;15288.312025959081;18973;19357.709634355215;19542.21831126796;19662.213511459955;19772.50568735793 12:18:01 INFO - PID 11375 | |3;mootools.html;1442.4302400551783;1500.9474668386608;1509.3057193690902;1511.0404892893023;1515.8332583415827;16776.66446671066;17286.654266914655;17323;17360.56598585036;17406.30374785009;17052.829471705278;17226.655466890654;17238.913805430955;17239.655206895884;17241.91379043103;2865.985670071647;2888.3789985153135;2943.2641839540115;2952.645682518101;2953.8818447262083;2756.0767143007015;2765.1151631477937;2771.3487330477324;2771.598118272849;2775.084222206667;2827.0529372660185;2868.8709008094684;2887.9422411551755;2888.22018055125;2889.248795313223;2786.874590643426;2817.619621351112;2817.732020590732;2817.8309301441877;2819.6898341182455;2670.0654770830247;2693.7036925938132;2707.4179051503825;2718.660167479065;2718.8504632245217;16272.43046493367;16373.590660233589;16423.753643695352;16518.504444866554;16601.83398166027;8022.9598852005365;8262.958685206595;8271.710490132817;8327.292180164693;8340.624671889718;5299.973500132513;5586.050371436865;5586.910552442276;5599.860003499904;5738.167965645;1826.4977131289002;1862.7112797516272;1866.2908094923841;1909.3508207209477;1918.386116442739;27703.722962770316;28884.566731499046;30582;31502.52746208809;31514.842425787952;7880.64537095832;7934.880976785353;8040.19598040203;8092.959535202286;8166.183381661786;23522.882385587964;23533.29400117965;23535.17626883051;23549.29352119405;23571.29286121419;8555.957220213859;8579.742607721779;8640.827183456297;8701.912980870236;8703.521306328214;7230.602316872622;7268.636568171571;7355.521891077063;7356.190819009905;7357.632118394059;5776.393478684761;5961.195238642815;5987.1318658794235;6065.211522502134;6217.067439883963;1808.828161324672;1831.3407173417559;1835.4578908212222;1841.1162641931949;1849.352726545725;963.1861077389594;965.9291353841076;968.9127978481971;983.7786498037884;991.667791289926;9016.278697704169;9260.166585007382;9308.8603670945;9372;9627.855582166274;791.2127433203999;801.9558924259105;802.3100133884855;806.5967016491754;812.2243257688893;4850.854474365704;4964;4977.676451030671;5081.23781432788;5097.108006098918;24268.271951841474;24354.391140221636;24753.25740227796;25303.493930121294;25384.492310153695;24628.50742985166;24824.875875620506;25157;25195.74804251934;25381.111661091774;12757.617271482057;12909.741805163843;12935.353232338533;12942.223466592035;12965.740685186243;8806.91193088061;8823.735287941372;8890.688825891062;8946.105389461001;8961.417507861968;20129.698054529486;20860.269890553758;21112.68330975006;21967.560648786613;22185.223517176822;21952;22035;22050;22140.778592213872;22312.21907233239;13499.122557033761;13619.114757540729;13630.318484075558;13659.180449173082;13668.31658417095;15707.136107514196;15749.44876929302;15810.130442825755;15897.841021590099;15905.522834314988;12746.745065098461;12746.808797868225;13106.541271055848;13158.276294803878;13332.533361332307;12534.435950382436;12553.811692824798;12606.936965315115;12789.296588687712;12888.742225155633;10574.154067674413;10803.729906752395;10810.567577297135;10851.186161037796;10906.509207085859;7714.1128770192145;7986.440949133505;7988.520688758693;8070.87893681607;8085.5957202140835;7901.249381308767;7909.802254943673;7939.245771651684;7956.801079972815;7978.720744773905 12:18:01 INFO - PID 11375 | |4;prototype.html;545.81715125433;560.6888177061729;575.1688809670023;588.3763210996344;590.583638534833;6148.231471066117;6149.846253843656;6203.937960620392;6233.501319894409;6240.656763877985;6026.939730602693;6054.394560543943;6097.176881121048;6159.414855588712;6180.412860778219;4812.518748125194;4998.2752500887345;5002.799888004475;5023.598112151019;5092.108880945838;4929.4824043475455;4972.428170760355;4982.651214414992;4987.426445958707;5017.749112544377;4858.465568787448;4911.115999120157;4931.408231012284;4946.183879659852;4946.356973593428;4882.926756098661;4915.729634870081;4940.3330550375595;4943.6539442239055;4945.530174633404;4486.6186374157905;4509.6392288616835;4521.118381915528;4529.977350113261;4535.206338890681;6008;6067.089936509515;6112.755489780404;6204.689765511752;6205.007198848162;5728.484436400744;5768;5803.82588522345;5830.5044071254;5847.2983242010805;6507.494614573376;6537.215534135934;6606.141201643755;6609.669516524203;6620.834479138012;4767.570918617306;5006.724630145341;5017.49825017499;5033.924491132636;5042.722650254234;6629.469642428594;6684.3984041436015;6801.4898882584;6807.221991794552;6824.112865327475;5073.162928116893;5085;5086.0845047891735;5095.210242412432;5097.898042039138;5153.484651534816;5154.896902061938;5191.454897235812;5212.687238765686;5239.083160446907;5765.250517432707;5797.391273916262;5810.767569297265;5815.098659707752;5821.18503409523;6542.443892269115;6554.967225163844;6578;6623.569467984566;6666.200055993312;1734.37562477508;1756.5784211789078;1777.4578753480312;1784.2684499355462;1788.4098247578268;1231.1628092896738;1273.57335292678;1290.6128161551496;1292.3796577642784;1314.4347930389833;6078.848028799227;6088.8782224356155;6173.290071641819;6184.907226391608;6225.097360882647;6436.613803171825;6572.934270657328;6590.934090659128;6678.131842860397;6682.498812589075;6045.21412216409;6088.604240724428;6116.296625887991;6149.13912052313;6173.876522469525;3369.7641165118207;3442.3115376924716;3458.6022607400473;3495.7552971291766;3526.5415495985358;6825.249222585511;6892.862142757117;6915.412189963909;6933.493229400829;6983.3714965653335;6902.309769023058;6911.205211400653;6917.688704008231;6930.861382772317;6952.85277929136;6888.41448476875;6899.275576064541;6938.410235130071;6976.767434421038;6984.482259015321;6596.472282217507;6629.436497897733;6632.966835165793;6636.900446493403;6642.468602511691;6627.933720662924;6652.634105124071;6699.095622090956;6709.605208588382;6710.966445167938;6704.597724136567;6782.287859774653;6843.692033858588;6871.3128687132885;6922.757703480354;5041.773120209673;5102.515261050195;5118.97440512795;5121.74391280442;5129.230615407719;6492.577982431127;6653.600783952785;6663.400293973566;6686.3313668632745;6735.831604209934;6251.843703907439;6524.478041756743;6551.541392102507;6568.343165683394;6579.835504112435;6453.290138084713;6455.7740479083;6467.514936379885;6474.5791523550815;6505.349465053457;6282.685865706788;6308.779192728232;6329.778457753957;6423.710933007932;6424.582402143846;6421.325760795049;6480.57876238043;6528.967355163194;6536.150300460909;6577.934220657732;6256.812295631138;6258.468030217483;6290.923995081704;6355.52333574993;6444.065610486359 12:18:01 INFO - PID 11375 | |5;yui.html;962.3359881681644;969.6945462179409;972;981.8036392721458;983.6458874805069;16329.510114696563;17330.653386932252;19085.332013379517;20045.699314510293;20047.799522004774;16805.243764030616;16823.158842057877;16826.24281907314;19354.322598709048;20068.69896951547;1062.941538215398;1088.9891101088967;1089;1089.7929393415225;1090;1065.3022270412891;1082.377632861104;1089.4008295437518;1095.6329629574102;1096.0245381610373;1118.6811758648787;1131.8132508136148;1131.9094472442225;1134.5972179876148;1136.9488373023191;1123.5842738186923;1125.4597793059336;1126.1666366888485;1128.2158899564806;1129.1023636209234;830.219593582037;830.3440282177073;831.3515457942814;834.4117397234935;836.995815020921;18028.819711802975;18634;18687.53281167968;18689;18766.155523001496;23030.88484557583;23327.41681457977;23671.88164059186;23678.64482032771;23729.406764830845;18560.721589176173;18583.349582764673;18668.43994680148;18670;18718.43844684648;3860.343741563941;3880.185161116169;3958.44581758554;3967.8809635710977;3977.105151340925;164434;167680;168242;170522.99999999875;171580;16829.074400907823;17250.223739931735;17342.739858902125;17346;17372.3051077958;33241;34822.82588587041;35022;35085.64914350875;35118;8708.825823483496;9026.729198124067;9617.471039092918;9987.950060249797;10040.397576145455;4762.738049407316;4892.633052521075;4896.192128298862;4904.190808516555;4911.50884911506;5864.530837532987;5925.229720136354;5932.58471906971;5964.731587078505;5999.610025348338;1264.943077561512;1290.0324756432676;1302.8827405533548;1304.8695130486876;1306.1836352279824;719.2340157732103;723.804572765361;739.2570466681059;743.3347154296982;749.9850002999909;12384.629967771147;12593.874061259454;13015.154014988995;13448.932755336356;13617.046806723632;715.8210447388153;716.176397143279;722.0757430488983;740.5223630758131;744.9329560339595;5615.971920140373;5690.77236910519;5719.027765279912;5763.827085187451;5901.203337549462;146879;148266;148814;148914.52068160375;157098;128424;130748;131444.3427782855;132431;134241;114869.0785528676;115219;117260;118596.40701796436;120503;10678.199135064746;10755.19336049815;10933.781324373627;10991.780164396827;11080.224384293022;53452.465475346304;53643.731781340844;53715.46284537261;53974.73012634912;55881;69836.65081674559;69913.65043174752;70698;71403.28596714174;71940.28059719336;16540.75188872144;17209.827901720822;17472;17582.20880060375;17599.472015839547;23651.172208972603;23681.64477532804;23776.881115595002;23939.640905386084;23990;17884.731729023813;17911.820881791537;18236.726449103007;18286.634267314847;18480.537986550444;20865.791342086384;21441.571168576855;21592.244271450425;21655.35033948984;21835.126594936024;16612.75080873812;18595.72106418432;19294.421167365002;19427.51431214231;19666.50833729111;15210.771838422212;15220.782337413202;15289.6177595561;15410.691786164438;15744.055356678637;13766.587002389944;13989.160650361011;14073.155610663393;14276.714465710835;14341.282935853375 12:18:01 INFO - PID 11375 | __end_tp_report 12:18:01 INFO - PID 11375 | __start_cc_report 12:18:01 INFO - PID 11375 | _x_x_mozilla_cycle_collect,267 12:18:01 INFO - PID 11375 | __end_cc_report 12:18:01 INFO - PID 11375 | __startTimestamp1506194281633__endTimestamp 12:18:01 INFO - PID 11375 | 12:18:01 INFO - PID 11375 | ------- Summary: start ------- 12:18:01 INFO - PID 11375 | Number of tests: 6 12:18:01 INFO - PID 11375 | 12:18:01 INFO - PID 11375 | [#0] dojo.html Cycles:165 Average:6315.66 Median:6596.60 stddev:750.96 (11.4%) stddev-sans-first:729.60 12:18:01 INFO - PID 11375 | Values: 3931.3 3933.8 3937.6 3941.8 3942.7 6229.8 6291.0 6339.7 6351.2 6380.1 6327.1 6370.5 6378.7 6384.5 6407.2 5664.5 5669.7 5689.3 5696.8 5753.4 5693.4 5736.1 5778.3 5788.2 5789.1 5594.9 5665.3 5738.2 5739.5 5751.7 5497.8 5577.9 5579.8 5647.7 5659.8 5583.2 5596.4 5627.6 5636.7 5664.6 6116.4 6176.2 6178.0 6193.4 6274.0 6757.2 6798.4 6809.0 6833.2 6889.8 6927.3 6931.2 6935.4 6943.8 6949.2 6343.5 6349.0 6418.0 6428.4 6449.8 6905.5 6974.7 7002.1 7075.5 7104.5 6639.8 6760.4 6763.4 6802.6 6837.6 6864.2 6874.6 6950.0 6950.1 6959.8 6791.2 6808.5 6823.5 6895.2 6907.0 6875.4 6896.3 6904.2 6928.1 6930.2 4970.6 5035.9 5046.1 5057.3 5058.0 4493.4 4505.1 4543.9 4546.4 4598.6 6316.4 6332.6 6396.1 6420.8 6435.3 6850.5 6872.3 6926.5 6953.0 6963.6 6356.1 6358.0 6430.4 6433.5 6452.2 5429.3 5515.1 5515.2 5519.9 5577.6 7010.1 7041.9 7048.0 7051.6 7059.9 6822.7 6885.0 6937.7 6941.0 6943.0 7055.3 7059.6 7076.9 7168.9 7170.2 6660.9 6772.4 6773.1 6869.7 6885.5 6818.4 6826.6 6832.8 6841.8 6845.2 6524.0 6596.6 6613.3 6615.5 6673.4 6620.9 6686.5 6701.9 6705.8 6709.4 6562.8 6569.9 6656.2 6660.4 6668.7 6538.2 6664.9 6716.8 6789.2 6791.1 6494.5 6528.1 6587.2 6598.6 6609.3 12:18:01 INFO - PID 11375 | 12:18:01 INFO - PID 11375 | [#1] ext.html Cycles:180 Average:43963.83 Median:3811.15 stddev:67416.20 (1768.9%) stddev-sans-first:67527.77 12:18:01 INFO - PID 11375 | Values: 895.3 927.9 960.9 964.7 988.4 44475.8 45575.3 45613.8 45698.5 45856.3 42613.1 42962.4 46116.8 46276.8 46299.0 8395.6 8588.9 8601.2 8602.8 8618.8 8529.8 8657.8 8658.8 8668.9 8678.3 8568.6 8628.6 8655.0 8669.6 8682.3 8526.2 8531.7 8587.9 8608.8 8633.1 8438.7 8439.3 8478.4 8481.0 8511.2 42311.8 42387.4 42456.4 42576.0 42600.4 3776.1 3846.2 3846.9 3848.2 3848.8 34681.7 34929.8 34935.1 35006.5 35119.0 5407.0 5456.0 5459.6 5487.7 5577.4 223498.0 230366.0 230955.0 232167.0 232609.0 47884.3 54647.7 55042.0 55061.7 55078.7 58678.0 58884.4 59082.7 59085.4 59185.4 15469.2 15476.8 15479.7 15575.5 15602.8 12429.4 12461.3 12482.4 12574.8 12597.4 14414.0 14535.9 14564.1 14567.3 14715.1 5695.6 5763.2 5796.9 5809.3 5912.8 1071.0 1083.2 1089.8 1091.6 1110.2 26155.3 26156.5 26199.2 26412.7 26542.3 1157.2 1183.3 1184.8 1187.6 1201.2 13379.7 13531.2 13550.0 13634.1 13686.6 290643.0 290925.0 291475.0 292200.0 293213.0 58771.4 58942.4 59077.4 59239.4 59449.7 1498.3 1537.0 1537.5 1547.2 1557.7 25131.6 25186.2 25262.5 25443.4 25621.0 42416.0 42500.4 42506.0 42532.4 42601.8 250522.0 251006.0 251348.0 251866.0 252961.0 34088.8 34238.0 36621.0 37020.8 37711.0 47921.3 47946.8 48115.3 48521.0 48585.0 35564.6 35624.5 35879.1 36043.8 36715.3 35080.1 35274.8 35329.3 35781.0 35799.5 40205.0 40911.4 41100.0 41161.0 41340.2 34222.1 34299.0 34660.8 34691.0 35054.8 25902.2 26045.6 26084.3 26222.7 26229.3 12:18:01 INFO - PID 11375 | 12:18:01 INFO - PID 11375 | [#2] jquery.html Cycles:180 Average:41605.60 Median:12511.71 stddev:109871.93 (878.2%) stddev-sans-first:110136.98 12:18:01 INFO - PID 11375 | Values: 586.4 596.1 616.0 629.8 638.5 23382.2 24061.3 24080.2 24261.8 24281.5 20994.0 22484.4 22617.1 22666.0 22746.8 6819.0 6926.3 6965.7 6968.1 6969.4 6583.1 6772.2 6860.8 6865.1 6867.9 6819.7 6823.0 6844.0 6851.6 6855.5 6530.1 6768.3 6794.3 6824.8 6825.2 6334.7 6341.3 6341.6 6345.7 6349.0 29053.9 29682.3 29749.7 29768.3 29873.4 2247.0 2252.0 2268.1 2271.1 2276.6 20047.2 20333.1 21201.3 21262.6 21267.4 2532.5 2535.7 2579.9 2589.2 2603.8 138897.0 144397.3 154731.0 160512.4 160526.2 15856.9 16077.8 16309.2 16603.9 16672.3 25399.6 25557.2 25614.0 25624.6 25667.6 9714.2 9798.2 9981.1 10034.4 10116.9 8766.5 8847.2 8893.2 8901.0 8946.5 9445.0 9678.7 9762.3 9892.4 9918.0 2784.2 2813.7 2889.8 2936.9 3022.6 1036.2 1053.6 1057.9 1063.5 1063.5 20558.6 20990.3 21234.0 21387.9 21458.4 1114.6 1121.5 1121.8 1122.0 1130.3 11179.2 11696.8 11698.8 11827.6 11851.4 639986.0 658252.0 659567.0 660487.0 673500.0 132289.0 133933.0 134433.3 134837.0 138056.3 1079.6 1206.4 1208.4 1246.4 1246.8 21548.0 21747.0 21943.7 22004.7 22218.8 66286.3 67712.3 68140.7 68363.3 70135.0 86071.6 87599.0 87758.0 88009.8 88150.6 15207.7 15464.1 15584.6 15718.1 15738.4 23910.4 23969.8 24055.6 24070.5 24102.0 18276.0 18526.4 18581.3 18642.8 19404.6 16755.7 17268.2 17356.4 17463.3 17662.3 19574.2 19578.6 19803.7 20052.0 20070.2 15150.4 15227.8 15256.0 15283.2 15288.3 18973.0 19357.7 19542.2 19662.2 19772.5 12:18:01 INFO - PID 11375 | 12:18:01 INFO - PID 11375 | [#3] mootools.html Cycles:180 Average:10566.79 Median:2760.60 stddev:7890.91 (285.8%) stddev-sans-first:7883.27 12:18:01 INFO - PID 11375 | Values: 1442.4 1500.9 1509.3 1511.0 1515.8 16776.7 17286.7 17323.0 17360.6 17406.3 17052.8 17226.7 17238.9 17239.7 17241.9 2866.0 2888.4 2943.3 2952.6 2953.9 2756.1 2765.1 2771.3 2771.6 2775.1 2827.1 2868.9 2887.9 2888.2 2889.2 2786.9 2817.6 2817.7 2817.8 2819.7 2670.1 2693.7 2707.4 2718.7 2718.9 16272.4 16373.6 16423.8 16518.5 16601.8 8023.0 8263.0 8271.7 8327.3 8340.6 5300.0 5586.1 5586.9 5599.9 5738.2 1826.5 1862.7 1866.3 1909.4 1918.4 27703.7 28884.6 30582.0 31502.5 31514.8 7880.6 7934.9 8040.2 8093.0 8166.2 23522.9 23533.3 23535.2 23549.3 23571.3 8556.0 8579.7 8640.8 8701.9 8703.5 7230.6 7268.6 7355.5 7356.2 7357.6 5776.4 5961.2 5987.1 6065.2 6217.1 1808.8 1831.3 1835.5 1841.1 1849.4 963.2 965.9 968.9 983.8 991.7 9016.3 9260.2 9308.9 9372.0 9627.9 791.2 802.0 802.3 806.6 812.2 4850.9 4964.0 4977.7 5081.2 5097.1 24268.3 24354.4 24753.3 25303.5 25384.5 24628.5 24824.9 25157.0 25195.7 25381.1 12757.6 12909.7 12935.4 12942.2 12965.7 8806.9 8823.7 8890.7 8946.1 8961.4 20129.7 20860.3 21112.7 21967.6 22185.2 21952.0 22035.0 22050.0 22140.8 22312.2 13499.1 13619.1 13630.3 13659.2 13668.3 15707.1 15749.4 15810.1 15897.8 15905.5 12746.7 12746.8 13106.5 13158.3 13332.5 12534.4 12553.8 12606.9 12789.3 12888.7 10574.2 10803.7 10810.6 10851.2 10906.5 7714.1 7986.4 7988.5 8070.9 8085.6 7901.2 7909.8 7939.2 7956.8 7978.7 12:18:01 INFO - PID 11375 | 12:18:01 INFO - PID 11375 | [#4] prototype.html Cycles:180 Average:5562.50 Median:6173.58 stddev:1554.51 (25.2%) stddev-sans-first:1512.58 12:18:01 INFO - PID 11375 | Values: 545.8 560.7 575.2 588.4 590.6 6148.2 6149.8 6203.9 6233.5 6240.7 6026.9 6054.4 6097.2 6159.4 6180.4 4812.5 4998.3 5002.8 5023.6 5092.1 4929.5 4972.4 4982.7 4987.4 5017.7 4858.5 4911.1 4931.4 4946.2 4946.4 4882.9 4915.7 4940.3 4943.7 4945.5 4486.6 4509.6 4521.1 4530.0 4535.2 6008.0 6067.1 6112.8 6204.7 6205.0 5728.5 5768.0 5803.8 5830.5 5847.3 6507.5 6537.2 6606.1 6609.7 6620.8 4767.6 5006.7 5017.5 5033.9 5042.7 6629.5 6684.4 6801.5 6807.2 6824.1 5073.2 5085.0 5086.1 5095.2 5097.9 5153.5 5154.9 5191.5 5212.7 5239.1 5765.3 5797.4 5810.8 5815.1 5821.2 6542.4 6555.0 6578.0 6623.6 6666.2 1734.4 1756.6 1777.5 1784.3 1788.4 1231.2 1273.6 1290.6 1292.4 1314.4 6078.8 6088.9 6173.3 6184.9 6225.1 6436.6 6572.9 6590.9 6678.1 6682.5 6045.2 6088.6 6116.3 6149.1 6173.9 3369.8 3442.3 3458.6 3495.8 3526.5 6825.2 6892.9 6915.4 6933.5 6983.4 6902.3 6911.2 6917.7 6930.9 6952.9 6888.4 6899.3 6938.4 6976.8 6984.5 6596.5 6629.4 6633.0 6636.9 6642.5 6627.9 6652.6 6699.1 6709.6 6711.0 6704.6 6782.3 6843.7 6871.3 6922.8 5041.8 5102.5 5119.0 5121.7 5129.2 6492.6 6653.6 6663.4 6686.3 6735.8 6251.8 6524.5 6551.5 6568.3 6579.8 6453.3 6455.8 6467.5 6474.6 6505.3 6282.7 6308.8 6329.8 6423.7 6424.6 6421.3 6480.6 6529.0 6536.2 6577.9 6256.8 6258.5 6290.9 6355.5 6444.1 12:18:01 INFO - PID 11375 | 12:18:01 INFO - PID 11375 | [#5] yui.html Cycles:180 Average:28694.40 Median:18614.86 stddev:43132.70 (231.7%) stddev-sans-first:43203.44 12:18:01 INFO - PID 11375 | Values: 962.3 969.7 972.0 981.8 983.6 16329.5 17330.7 19085.3 20045.7 20047.8 16805.2 16823.2 16826.2 19354.3 20068.7 1062.9 1089.0 1089.0 1089.8 1090.0 1065.3 1082.4 1089.4 1095.6 1096.0 1118.7 1131.8 1131.9 1134.6 1136.9 1123.6 1125.5 1126.2 1128.2 1129.1 830.2 830.3 831.4 834.4 837.0 18028.8 18634.0 18687.5 18689.0 18766.2 23030.9 23327.4 23671.9 23678.6 23729.4 18560.7 18583.3 18668.4 18670.0 18718.4 3860.3 3880.2 3958.4 3967.9 3977.1 164434.0 167680.0 168242.0 170523.0 171580.0 16829.1 17250.2 17342.7 17346.0 17372.3 33241.0 34822.8 35022.0 35085.6 35118.0 8708.8 9026.7 9617.5 9988.0 10040.4 4762.7 4892.6 4896.2 4904.2 4911.5 5864.5 5925.2 5932.6 5964.7 5999.6 1264.9 1290.0 1302.9 1304.9 1306.2 719.2 723.8 739.3 743.3 750.0 12384.6 12593.9 13015.2 13448.9 13617.0 715.8 716.2 722.1 740.5 744.9 5616.0 5690.8 5719.0 5763.8 5901.2 146879.0 148266.0 148814.0 148914.5 157098.0 128424.0 130748.0 131444.3 132431.0 134241.0 114869.1 115219.0 117260.0 118596.4 120503.0 10678.2 10755.2 10933.8 10991.8 11080.2 53452.5 53643.7 53715.5 53974.7 55881.0 69836.7 69913.7 70698.0 71403.3 71940.3 16540.8 17209.8 17472.0 17582.2 17599.5 23651.2 23681.6 23776.9 23939.6 23990.0 17884.7 17911.8 18236.7 18286.6 18480.5 20865.8 21441.6 21592.2 21655.4 21835.1 16612.8 18595.7 19294.4 19427.5 19666.5 15210.8 15220.8 15289.6 15410.7 15744.1 13766.6 13989.2 14073.2 14276.7 14341.3 12:18:01 INFO - PID 11375 | -------- Summary: end -------- 12:18:01 INFO - PID 11375 | 12:18:02 INFO - TEST-INFO | 11375: exit 0 12:18:02 INFO - TEST-OK | dromaeo_css | took 1108719ms 12:18:02 INFO - TEST-START | kraken 12:18:02 INFO - Initialising browser for kraken test... 12:18:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36527/getInfo.html -profile /tmp/tmpMmHV0p/profile 12:18:02 INFO - TEST-INFO | started process 11754 (/builds/slave/test/build/application/firefox/firefox http://localhost:36527/getInfo.html) 12:18:07 INFO - TEST-INFO | 11754: exit 0 12:18:07 INFO - Browser initialized. 12:18:07 INFO - Running cycle 1/1 for kraken test... 12:18:07 INFO - Using env: {'DISPLAY': ':0', 12:18:07 INFO - 'HOME': '/home/cltbld', 12:18:07 INFO - 'JSGC_DISABLE_POISONING': '1', 12:18:07 INFO - 'LANG': 'en_US.UTF-8', 12:18:07 INFO - 'LANGUAGE': 'en_US:en', 12:18:07 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:18:07 INFO - 'LOGNAME': 'cltbld', 12:18:07 INFO - 'MAIL': '/var/mail/cltbld', 12:18:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:18:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:18:07 INFO - 'MOZ_CRASHREPORTER': '1', 12:18:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:07 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:18:07 INFO - 'MOZ_NO_REMOTE': '1', 12:18:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:18:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:07 INFO - 'NO_EM_RESTART': '1', 12:18:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:07 INFO - 'PWD': '/builds/slave/test', 12:18:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:18:07 INFO - 'RUST_BACKTRACE': 'full', 12:18:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:18:07 INFO - 'SHELL': '/bin/bash', 12:18:07 INFO - 'SHLVL': '1', 12:18:07 INFO - 'TERM': 'linux', 12:18:07 INFO - 'TMOUT': '86400', 12:18:07 INFO - 'USER': 'cltbld', 12:18:07 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1506192021.541468-1509883357', 12:18:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:07 INFO - '_': '/tools/buildbot/bin/python'} 12:18:07 INFO - TEST-INFO | started process 11990 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMmHV0p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:18:08 INFO - PID 11990 | 12:18:08 INFO - PID 11990 | (/builds/slave/test/build/application/firefox/firefox:12039): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:18:08 INFO - PID 11990 | 12:18:19 INFO - PID 11990 | RSS: Main: 144785408 12:18:19 INFO - PID 11990 | 12:18:21 INFO - PID 11990 | Cycle 1(1): loaded http://localhost:36527/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36527/tests/kraken/driver.html?testName=audio-beat-detection) 12:18:22 INFO - PID 11990 | RSS: Main: 150843392 12:18:22 INFO - PID 11990 | 12:18:24 INFO - PID 11990 | Cycle 1(1): loaded http://localhost:36527/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36527/tests/kraken/driver.html?testName=audio-dft) 12:18:25 INFO - PID 11990 | RSS: Main: 151519232 12:18:25 INFO - PID 11990 | 12:18:28 INFO - PID 11990 | Cycle 1(1): loaded http://localhost:36527/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36527/tests/kraken/driver.html?testName=audio-fft) 12:18:29 INFO - PID 11990 | RSS: Main: 147746816 12:18:29 INFO - PID 11990 | 12:18:31 INFO - PID 11990 | Cycle 1(1): loaded http://localhost:36527/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36527/tests/kraken/driver.html?testName=audio-oscillator) 12:18:31 INFO - PID 11990 | RSS: Main: 148746240 12:18:31 INFO - PID 11990 | 12:18:33 INFO - PID 11990 | Cycle 1(1): loaded http://localhost:36527/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36527/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:18:34 INFO - PID 11990 | RSS: Main: 148541440 12:18:34 INFO - PID 11990 | 12:18:37 INFO - PID 11990 | Cycle 1(1): loaded http://localhost:36527/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36527/tests/kraken/driver.html?testName=imaging-darkroom) 12:18:38 INFO - PID 11990 | RSS: Main: 148488192 12:18:38 INFO - PID 11990 | 12:18:41 INFO - PID 11990 | Cycle 1(1): loaded http://localhost:36527/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36527/tests/kraken/driver.html?testName=imaging-desaturate) 12:18:42 INFO - PID 11990 | RSS: Main: 150175744 12:18:42 INFO - PID 11990 | 12:18:45 INFO - PID 11990 | Cycle 1(1): loaded http://localhost:36527/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36527/tests/kraken/driver.html?testName=json-parse-financial) 12:18:45 INFO - PID 11990 | RSS: Main: 151547904 12:18:45 INFO - PID 11990 | 12:18:47 INFO - PID 11990 | Cycle 1(1): loaded http://localhost:36527/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36527/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:18:47 INFO - PID 11990 | RSS: Main: 151355392 12:18:47 INFO - PID 11990 | 12:18:49 INFO - PID 11990 | Cycle 1(1): loaded http://localhost:36527/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36527/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:18:50 INFO - PID 11990 | RSS: Main: 151613440 12:18:50 INFO - PID 11990 | 12:18:52 INFO - PID 11990 | Cycle 1(1): loaded http://localhost:36527/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36527/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:18:52 INFO - PID 11990 | RSS: Main: 148594688 12:18:52 INFO - PID 11990 | 12:18:55 INFO - PID 11990 | Cycle 1(1): loaded http://localhost:36527/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36527/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:18:55 INFO - PID 11990 | RSS: Main: 148676608 12:18:55 INFO - PID 11990 | 12:18:58 INFO - PID 11990 | Cycle 1(1): loaded http://localhost:36527/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36527/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:18:59 INFO - PID 11990 | RSS: Main: 149139456 12:18:59 INFO - PID 11990 | 12:19:00 INFO - PID 11990 | Cycle 1(1): loaded http://localhost:36527/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36527/tests/kraken/driver.html?testName=ai-astar) 12:19:01 INFO - PID 11990 | RSS: Main: 150720512 12:19:01 INFO - PID 11990 | 12:19:01 INFO - PID 11990 | __start_tp_report 12:19:01 INFO - PID 11990 | _x_x_mozilla_page_load 12:19:01 INFO - PID 11990 | _x_x_mozilla_page_load_details 12:19:01 INFO - PID 11990 | |i|pagename|runs| 12:19:01 INFO - PID 11990 | |0;ai-astar;107;105;104;105;107;105;107;105;105;106 12:19:01 INFO - PID 11990 | |1;audio-beat-detection;116;113;116;117;119;115;113;122;114;117 12:19:01 INFO - PID 11990 | |2;audio-dft;163;163;162;162;162;162;163;162;167;166 12:19:01 INFO - PID 11990 | |3;audio-fft;75;73;74;74;73;82;74;75;74;74 12:19:01 INFO - PID 11990 | |4;audio-oscillator;79;78;78;79;78;81;79;78;78;84 12:19:01 INFO - PID 11990 | |5;imaging-gaussian-blur;110;113;113;110;114;114;114;117;114;115 12:19:01 INFO - PID 11990 | |6;imaging-darkroom;153;149;150;149;151;151;150;152;149;151 12:19:01 INFO - PID 11990 | |7;imaging-desaturate;95;95;94;94;94;99;95;94;94;94 12:19:01 INFO - PID 11990 | |8;json-parse-financial;45;44;45;46;44;46;44;45;45;45 12:19:01 INFO - PID 11990 | |9;json-stringify-tinderbox;44;40;40;39;42;39;39;40;44;39 12:19:01 INFO - PID 11990 | |10;stanford-crypto-aes;71;76;72;73;71;71;75;76;73;72 12:19:01 INFO - PID 11990 | |11;stanford-crypto-ccm;105;106;102;108;110;111;107;108;108;106 12:19:01 INFO - PID 11990 | |12;stanford-crypto-pbkdf2;159;153;152;148;149;147;153;155;148;154 12:19:01 INFO - PID 11990 | |13;stanford-crypto-sha256-iterative;67;68;60;59;58;62;60;67;60;60 12:19:01 INFO - PID 11990 | __end_tp_report 12:19:01 INFO - PID 11990 | __start_cc_report 12:19:01 INFO - PID 11990 | _x_x_mozilla_cycle_collect,708 12:19:01 INFO - PID 11990 | __end_cc_report 12:19:01 INFO - PID 11990 | __startTimestamp1506194341056__endTimestamp 12:19:01 INFO - PID 11990 | 12:19:01 INFO - PID 11990 | ------- Summary: start ------- 12:19:01 INFO - PID 11990 | Number of tests: 14 12:19:01 INFO - PID 11990 | 12:19:01 INFO - PID 11990 | [#0] ai-astar Cycles:10 Average:105.60 Median:105.50 stddev:1.07 (1.0%) stddev-sans-first:1.01 12:19:01 INFO - PID 11990 | Values: 107.0 105.0 104.0 105.0 107.0 105.0 107.0 105.0 105.0 106.0 12:19:01 INFO - PID 11990 | 12:19:01 INFO - PID 11990 | [#1] audio-beat-detection Cycles:10 Average:116.20 Median:116.50 stddev:2.78 (2.4%) stddev-sans-first:2.95 12:19:01 INFO - PID 11990 | Values: 116.0 113.0 116.0 117.0 119.0 115.0 113.0 122.0 114.0 117.0 12:19:01 INFO - PID 11990 | 12:19:01 INFO - PID 11990 | [#2] audio-dft Cycles:10 Average:163.20 Median:163.00 stddev:1.81 (1.1%) stddev-sans-first:1.92 12:19:01 INFO - PID 11990 | Values: 163.0 163.0 162.0 162.0 162.0 162.0 163.0 162.0 167.0 166.0 12:19:01 INFO - PID 11990 | 12:19:01 INFO - PID 11990 | [#3] audio-fft Cycles:10 Average:74.80 Median:74.00 stddev:2.62 (3.5%) stddev-sans-first:2.77 12:19:01 INFO - PID 11990 | Values: 75.0 73.0 74.0 74.0 73.0 82.0 74.0 75.0 74.0 74.0 12:19:01 INFO - PID 11990 | 12:19:01 INFO - PID 11990 | [#4] audio-oscillator Cycles:10 Average:79.20 Median:79.00 stddev:1.93 (2.4%) stddev-sans-first:2.05 12:19:01 INFO - PID 11990 | Values: 79.0 78.0 78.0 79.0 78.0 81.0 79.0 78.0 78.0 84.0 12:19:01 INFO - PID 11990 | 12:19:01 INFO - PID 11990 | [#5] imaging-gaussian-blur Cycles:10 Average:113.40 Median:114.00 stddev:2.12 (1.9%) stddev-sans-first:1.86 12:19:01 INFO - PID 11990 | Values: 110.0 113.0 113.0 110.0 114.0 114.0 114.0 117.0 114.0 115.0 12:19:01 INFO - PID 11990 | 12:19:01 INFO - PID 11990 | [#6] imaging-darkroom Cycles:10 Average:150.50 Median:151.00 stddev:1.35 (0.9%) stddev-sans-first:1.09 12:19:01 INFO - PID 11990 | Values: 153.0 149.0 150.0 149.0 151.0 151.0 150.0 152.0 149.0 151.0 12:19:01 INFO - PID 11990 | 12:19:01 INFO - PID 11990 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:94.50 stddev:1.55 (1.6%) stddev-sans-first:1.64 12:19:01 INFO - PID 11990 | Values: 95.0 95.0 94.0 94.0 94.0 99.0 95.0 94.0 94.0 94.0 12:19:01 INFO - PID 11990 | 12:19:01 INFO - PID 11990 | [#8] json-parse-financial Cycles:10 Average:44.90 Median:45.00 stddev:0.74 (1.6%) stddev-sans-first:0.78 12:19:01 INFO - PID 11990 | Values: 45.0 44.0 45.0 46.0 44.0 46.0 44.0 45.0 45.0 45.0 12:19:01 INFO - PID 11990 | 12:19:01 INFO - PID 11990 | [#9] json-stringify-tinderbox Cycles:10 Average:40.60 Median:40.00 stddev:2.01 (5.0%) stddev-sans-first:1.72 12:19:01 INFO - PID 11990 | Values: 44.0 40.0 40.0 39.0 42.0 39.0 39.0 40.0 44.0 39.0 12:19:01 INFO - PID 11990 | 12:19:01 INFO - PID 11990 | [#10] stanford-crypto-aes Cycles:10 Average:73.00 Median:73.00 stddev:2.00 (2.7%) stddev-sans-first:1.99 12:19:01 INFO - PID 11990 | Values: 71.0 76.0 72.0 73.0 71.0 71.0 75.0 76.0 73.0 72.0 12:19:01 INFO - PID 11990 | 12:19:01 INFO - PID 11990 | [#11] stanford-crypto-ccm Cycles:10 Average:107.10 Median:108.00 stddev:2.56 (2.4%) stddev-sans-first:2.60 12:19:01 INFO - PID 11990 | Values: 105.0 106.0 102.0 108.0 110.0 111.0 107.0 108.0 108.0 106.0 12:19:01 INFO - PID 11990 | 12:19:01 INFO - PID 11990 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.80 Median:153.00 stddev:3.79 (2.5%) stddev-sans-first:3.00 12:19:01 INFO - PID 11990 | Values: 159.0 153.0 152.0 148.0 149.0 147.0 153.0 155.0 148.0 154.0 12:19:01 INFO - PID 11990 | 12:19:01 INFO - PID 11990 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:61.00 stddev:3.75 (6.2%) stddev-sans-first:3.54 12:19:01 INFO - PID 11990 | Values: 67.0 68.0 60.0 59.0 58.0 62.0 60.0 67.0 60.0 60.0 12:19:01 INFO - PID 11990 | -------- Summary: end -------- 12:19:01 INFO - PID 11990 | 12:19:01 INFO - PID 11990 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:19:01 INFO - TEST-INFO | 11990: exit 0 12:19:01 INFO - TEST-OK | kraken | took 59396ms 12:19:01 INFO - SUITE-END | took 1168s 12:19:01 INFO - Completed test suite (00:19:28) 12:19:01 INFO - javascript benchmark 12:19:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9105.921349103877, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3931.272714547807, 3933.76397416155, 3937.6259255370765, 3941.842326306948, 3942.724009319347, 6229.750809967607, 6290.96854515728, 6339.651319177449, 6351.174347334846, 6380.074889141072, 6327.050942358648, 6370.458511026568, 6378.712957916893, 6384.5211609129265, 6407.167068281117, 5664.490195882369, 5669.688167150805, 5689.317281926154, 5696.829095127153, 5753.424657534255, 5693.430656934294, 5736.082226843706, 5778.335491418498, 5788.218590490293, 5789.131630255453, 5594.888102237933, 5665.3201615806165, 5738.196652468637, 5739.483446489815, 5751.683657398862, 5497.8075767347955, 5577.9163312550345, 5579.804706835242, 5647.745851436695, 5659.773609055674, 5583.162525621149, 5596.384397716248, 5627.634203776742, 5636.661800291996, 5664.57515686321, 6116.418940200674, 6176.197094377702, 6178.042403427431, 6193.4425901669065, 6273.968630156865, 6757.222919364287, 6798.354156355138, 6808.9659551701925, 6833.180018397824, 6889.827754306132, 6927.3419025192525, 6931.168259808805, 6935.4104901083465, 6943.826404339881, 6949.166100068024, 6343.460805831556, 6348.968255158695, 6417.967910160466, 6428.357164283628, 6449.838754031187, 6905.482088843357, 6974.686139123752, 7002.089728335284, 7075.469339799535, 7104.502684812117, 6639.834004149839, 6760.357766012221, 6763.357481039392, 6802.6258555779905, 6837.5555588887555, 6864.2106157791495, 6874.553154044971, 6950.026996220533, 6950.131233595801, 6959.82600434983, 6791.185057792997, 6808.489363297773, 6823.522353435311, 6895.172579290417, 6907.0, 6875.4155896749335, 6896.310368963063, 6904.206016308089, 6928.1339832520935, 6930.203026651899, 4970.552650261494, 5035.924461133086, 5046.091703493333, 5057.342545469065, 5058.038972595195, 4493.370928070073, 4505.07645932583, 4543.886402839889, 4546.386237857847, 4598.609118224988, 6316.399942005502, 6332.588381755171, 6396.1045453636525, 6420.807375778734, 6435.292117867031, 6850.451963842881, 6872.312768723088, 6926.549774264657, 6953.0, 6963.617001064888, 6356.14192084072, 6358.0, 6430.421262086435, 6433.485321174295, 6452.161219041588, 5429.294191755047, 5515.062439385314, 5515.227868098469, 5519.917201241985, 5577.609567330248, 7010.088688470568, 7041.929580704127, 7048.048513450619, 7051.647417629098, 7059.929400705927, 6822.692978816064, 6885.0, 6937.653117344214, 6941.0, 6943.0, 7055.329743674342, 7059.647017649199, 7076.893846592202, 7168.928310716826, 7170.211276759448, 6660.900086498609, 6772.356626120511, 6773.05177275172, 6869.656517174021, 6885.51801373899, 6818.420434263143, 6826.556273842184, 6832.829179270558, 6841.828954276183, 6845.212800528003, 6524.0, 6596.60420374779, 6613.338666133348, 6615.5038372123245, 6673.432758215412, 6620.867582648417, 6686.53194276396, 6701.865962680817, 6705.765298214539, 6709.429698475684, 6562.80311590653, 6569.868602628017, 6656.201255849329, 6660.433863121689, 6668.733250670113, 6538.215414150332, 6664.900026499509, 6716.83207919806, 6789.15135608049, 6791.117154769849, 6494.545381823227, 6528.086067950396, 6587.176602924635, 6598.571092878948, 6609.339066093352], "value": 6264.645029034012, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [895.3463971300954, 927.8515437529994, 960.9039096090391, 964.7154089543588, 988.4020167798486, 44475.77762111189, 45575.31637025447, 45613.77193114034, 45698.543014569885, 45856.3121553177, 42613.14773704524, 42962.355564666555, 46116.76941615296, 46276.76861615696, 46299.0, 8395.58022098894, 8588.914110858905, 8601.225889669928, 8602.784930376729, 8618.78453038676, 8529.786755331135, 8657.783555411133, 8658.826823463527, 8668.8699669505, 8678.262347700453, 8568.614412351428, 8628.568571571397, 8655.047944726106, 8669.566521673923, 8682.26200772935, 8526.232639062515, 8531.65873365071, 8587.871181932245, 8608.784780380478, 8633.09352517983, 8438.662453501853, 8439.324854011664, 8478.448900821428, 8480.957595211985, 8511.233988940963, 42311.788441057906, 42387.36418953687, 42456.36315455271, 42576.0, 42600.360994585106, 3776.0937375029794, 3846.211526637033, 3846.9230615387537, 3848.172642881766, 3848.8460461581503, 34681.65318346809, 34929.825350873085, 34935.12662183466, 35006.47490287648, 35119.0, 5406.972965135149, 5455.972720136374, 5459.617826752129, 5487.6981766002855, 5577.442255774431, 223498.0, 230366.0, 230955.0, 232167.0, 232609.0, 47884.28173577399, 54647.726761366735, 55042.0, 55061.72469137629, 55078.72460637671, 58678.0, 58884.41115588875, 59082.70458647679, 59085.40914590885, 59185.40814591885, 15469.226538673021, 15476.76784848228, 15479.690406192038, 15575.454859079879, 15602.765958510632, 12429.37853107341, 12461.252324860538, 12482.43829027696, 12574.81137782934, 12597.433115509824, 14414.0, 14535.927320363331, 14564.119976232367, 14567.344469498898, 14715.117092974242, 5695.60130790849, 5763.221965034751, 5796.88406231873, 5809.302883653989, 5912.822615321547, 1071.0, 1083.1984331594563, 1089.7983872983523, 1091.645215305029, 1110.1618278199908, 26155.346116346864, 26156.476870462866, 26199.214023579323, 26412.735872641028, 26542.33644158873, 1157.242006485753, 1183.2722875431548, 1184.8341232227494, 1187.6377704799945, 1201.2192075151047, 13379.732405352033, 13531.188128712112, 13550.0, 13634.1137826041, 13686.589402317946, 290643.0, 290925.0, 291475.0, 292200.0, 293213.0, 58771.41228587745, 58942.41057589455, 59077.40922590805, 59239.40760592425, 59449.702751485966, 1498.303288970634, 1537.0393504060187, 1537.500312398475, 1547.156799544251, 1557.6806754615277, 25131.623025654997, 25186.24441266765, 25262.494750105263, 25443.36391590151, 25621.0, 42416.0, 42500.36249456199, 42506.0, 42532.36201456919, 42601.786991064844, 250522.0, 251006.0, 251348.0, 251866.0, 252961.0, 34088.82955585206, 34238.0, 36621.0, 37020.814895925345, 37711.0, 47921.281180783015, 47946.76026619845, 48115.27827082526, 48521.0, 48585.0, 35564.644353556134, 35624.46563301501, 35879.10302242465, 36043.81978090093, 36715.26569468649, 35080.12299692528, 35274.823625881705, 35329.29341413106, 35781.0, 35799.46300805438, 40205.0, 40911.38632920449, 41100.0, 41161.0, 41340.1731965365, 34222.14444638904, 34299.0, 34660.82669586636, 34691.0, 35054.824725876206, 25902.22293331203, 26045.609315860656, 26084.34789130287, 26222.73777262203, 26229.34426639349], "value": 18899.706603961076, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.3755100817626, 596.1058412381428, 615.9815205543832, 629.7764293675745, 638.5274896576532, 23382.181623643177, 24061.278161655133, 24080.157194498195, 24261.75738242617, 24281.514369712597, 20994.0, 22484.43788905278, 22617.095316187373, 22666.0, 22746.772532274674, 6819.045333653305, 6926.307369263059, 6965.6517174141345, 6968.094147760809, 6969.372756451919, 6583.1441912551545, 6772.153480814898, 6860.828479288008, 6865.0732151159455, 6867.931320686804, 6819.727210911557, 6823.044773731682, 6844.041834143224, 6851.588904665736, 6855.520113592051, 6530.118434011395, 6768.323167683241, 6794.320567943166, 6824.829379265533, 6825.215100263459, 6334.746610135589, 6341.3341599132345, 6341.556091073627, 6345.714442850037, 6349.047642853563, 29053.854730726212, 29682.25794355137, 29749.702502975128, 29768.255793605116, 29873.40253194924, 2246.9550608987893, 2252.0, 2268.0814270220585, 2271.136967952184, 2276.64711969645, 20047.1981120755, 20333.085011174386, 21201.25795597162, 21262.574748504943, 21267.36197914065, 2532.5061612985473, 2535.733747956456, 2579.87100644967, 2589.2491177558672, 2603.7916966642624, 138897.0, 144397.27801360926, 154731.0, 160512.3948760509, 160526.19736901356, 15856.912974383147, 16077.839221607634, 16309.184540772914, 16603.916980415022, 16672.333106675596, 25399.61900571493, 25557.23328300154, 25614.0, 25624.615630765555, 25667.614985775228, 9714.22286217101, 9798.216142708567, 9981.101700846959, 10034.448105354275, 10116.898831011742, 8766.517841518778, 8847.159519845605, 8893.244074253631, 8900.955495222483, 8946.463212207289, 9444.952775236217, 9678.661246856327, 9762.267829912786, 9892.356996795184, 9918.0, 2784.1647505748197, 2813.6904940456534, 2889.7688184945573, 2936.8707686926805, 3022.607061082045, 1036.1658864613908, 1053.5733028123623, 1057.8575138850024, 1063.4788953412926, 1063.5107850388908, 20558.58882822335, 20990.265340713308, 21234.0, 21387.8930605346, 21458.356249312546, 1114.5653195253858, 1121.5345631562973, 1121.831725241204, 1122.0, 1130.327455164176, 11179.161562882817, 11696.766064678659, 11698.76602467963, 11827.586034488751, 11851.407429628483, 639986.0, 658252.0, 659567.0, 660487.0, 673500.0, 132289.0, 133933.0, 134433.3278333602, 134837.0, 138056.30971845277, 1079.5681727309138, 1206.4088596587785, 1208.4018410886667, 1246.389269258057, 1246.8441444819398, 21548.0, 21747.0, 21943.67084493702, 22004.669929951382, 22218.77781222167, 66286.33713662994, 67712.3228767706, 68140.6592967032, 68363.3163668357, 70135.0, 86071.56964215139, 87599.0, 87758.0, 88009.79975500664, 88150.55924720336, 15207.695846082795, 15464.14947177871, 15584.610384740019, 15718.056916585041, 15738.449154280002, 23910.402239944142, 23969.760302396753, 24055.639165412184, 24070.518589627758, 24102.03591856376, 18276.0, 18526.444206673823, 18581.25674972986, 18642.81357186465, 19404.611907761482, 16755.664886701954, 17268.22292996793, 17356.39252626152, 17463.30146794114, 17662.293508259525, 19574.217031319156, 19578.60842783165, 19803.702944456134, 20052.0, 20070.19719211215, 15150.393984240507, 15227.847721523087, 15256.0, 15283.15942623122, 15288.312025959081, 18973.0, 19357.709634355215, 19542.21831126796, 19662.213511459955, 19772.50568735793], "value": 12804.289683385105, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1442.4302400551783, 1500.9474668386608, 1509.3057193690902, 1511.0404892893023, 1515.8332583415827, 16776.66446671066, 17286.654266914655, 17323.0, 17360.56598585036, 17406.30374785009, 17052.829471705278, 17226.655466890654, 17238.913805430955, 17239.655206895884, 17241.91379043103, 2865.985670071647, 2888.3789985153135, 2943.2641839540115, 2952.645682518101, 2953.8818447262083, 2756.0767143007015, 2765.1151631477937, 2771.3487330477324, 2771.598118272849, 2775.084222206667, 2827.0529372660185, 2868.8709008094684, 2887.9422411551755, 2888.22018055125, 2889.248795313223, 2786.874590643426, 2817.619621351112, 2817.732020590732, 2817.8309301441877, 2819.6898341182455, 2670.0654770830247, 2693.7036925938132, 2707.4179051503825, 2718.660167479065, 2718.8504632245217, 16272.43046493367, 16373.590660233589, 16423.753643695352, 16518.504444866554, 16601.83398166027, 8022.9598852005365, 8262.958685206595, 8271.710490132817, 8327.292180164693, 8340.624671889718, 5299.973500132513, 5586.050371436865, 5586.910552442276, 5599.860003499904, 5738.167965645, 1826.4977131289002, 1862.7112797516272, 1866.2908094923841, 1909.3508207209477, 1918.386116442739, 27703.722962770316, 28884.566731499046, 30582.0, 31502.52746208809, 31514.842425787952, 7880.64537095832, 7934.880976785353, 8040.19598040203, 8092.959535202286, 8166.183381661786, 23522.882385587964, 23533.29400117965, 23535.17626883051, 23549.29352119405, 23571.29286121419, 8555.957220213859, 8579.742607721779, 8640.827183456297, 8701.912980870236, 8703.521306328214, 7230.602316872622, 7268.636568171571, 7355.521891077063, 7356.190819009905, 7357.632118394059, 5776.393478684761, 5961.195238642815, 5987.1318658794235, 6065.211522502134, 6217.067439883963, 1808.828161324672, 1831.3407173417559, 1835.4578908212222, 1841.1162641931949, 1849.352726545725, 963.1861077389594, 965.9291353841076, 968.9127978481971, 983.7786498037884, 991.667791289926, 9016.278697704169, 9260.166585007382, 9308.8603670945, 9372.0, 9627.855582166274, 791.2127433203999, 801.9558924259105, 802.3100133884855, 806.5967016491754, 812.2243257688893, 4850.854474365704, 4964.0, 4977.676451030671, 5081.23781432788, 5097.108006098918, 24268.271951841474, 24354.391140221636, 24753.25740227796, 25303.493930121294, 25384.492310153695, 24628.50742985166, 24824.875875620506, 25157.0, 25195.74804251934, 25381.111661091774, 12757.617271482057, 12909.741805163843, 12935.353232338533, 12942.223466592035, 12965.740685186243, 8806.91193088061, 8823.735287941372, 8890.688825891062, 8946.105389461001, 8961.417507861968, 20129.698054529486, 20860.269890553758, 21112.68330975006, 21967.560648786613, 22185.223517176822, 21952.0, 22035.0, 22050.0, 22140.778592213872, 22312.21907233239, 13499.122557033761, 13619.114757540729, 13630.318484075558, 13659.180449173082, 13668.31658417095, 15707.136107514196, 15749.44876929302, 15810.130442825755, 15897.841021590099, 15905.522834314988, 12746.745065098461, 12746.808797868225, 13106.541271055848, 13158.276294803878, 13332.533361332307, 12534.435950382436, 12553.811692824798, 12606.936965315115, 12789.296588687712, 12888.742225155633, 10574.154067674413, 10803.729906752395, 10810.567577297135, 10851.186161037796, 10906.509207085859, 7714.1128770192145, 7986.440949133505, 7988.520688758693, 8070.87893681607, 8085.5957202140835, 7901.249381308767, 7909.802254943673, 7939.245771651684, 7956.801079972815, 7978.720744773905], "value": 7314.837816643502, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [545.81715125433, 560.6888177061729, 575.1688809670023, 588.3763210996344, 590.583638534833, 6148.231471066117, 6149.846253843656, 6203.937960620392, 6233.501319894409, 6240.656763877985, 6026.939730602693, 6054.394560543943, 6097.176881121048, 6159.414855588712, 6180.412860778219, 4812.518748125194, 4998.2752500887345, 5002.799888004475, 5023.598112151019, 5092.108880945838, 4929.4824043475455, 4972.428170760355, 4982.651214414992, 4987.426445958707, 5017.749112544377, 4858.465568787448, 4911.115999120157, 4931.408231012284, 4946.183879659852, 4946.356973593428, 4882.926756098661, 4915.729634870081, 4940.3330550375595, 4943.6539442239055, 4945.530174633404, 4486.6186374157905, 4509.6392288616835, 4521.118381915528, 4529.977350113261, 4535.206338890681, 6008.0, 6067.089936509515, 6112.755489780404, 6204.689765511752, 6205.007198848162, 5728.484436400744, 5768.0, 5803.82588522345, 5830.5044071254, 5847.2983242010805, 6507.494614573376, 6537.215534135934, 6606.141201643755, 6609.669516524203, 6620.834479138012, 4767.570918617306, 5006.724630145341, 5017.49825017499, 5033.924491132636, 5042.722650254234, 6629.469642428594, 6684.3984041436015, 6801.4898882584, 6807.221991794552, 6824.112865327475, 5073.162928116893, 5085.0, 5086.0845047891735, 5095.210242412432, 5097.898042039138, 5153.484651534816, 5154.896902061938, 5191.454897235812, 5212.687238765686, 5239.083160446907, 5765.250517432707, 5797.391273916262, 5810.767569297265, 5815.098659707752, 5821.18503409523, 6542.443892269115, 6554.967225163844, 6578.0, 6623.569467984566, 6666.200055993312, 1734.37562477508, 1756.5784211789078, 1777.4578753480312, 1784.2684499355462, 1788.4098247578268, 1231.1628092896738, 1273.57335292678, 1290.6128161551496, 1292.3796577642784, 1314.4347930389833, 6078.848028799227, 6088.8782224356155, 6173.290071641819, 6184.907226391608, 6225.097360882647, 6436.613803171825, 6572.934270657328, 6590.934090659128, 6678.131842860397, 6682.498812589075, 6045.21412216409, 6088.604240724428, 6116.296625887991, 6149.13912052313, 6173.876522469525, 3369.7641165118207, 3442.3115376924716, 3458.6022607400473, 3495.7552971291766, 3526.5415495985358, 6825.249222585511, 6892.862142757117, 6915.412189963909, 6933.493229400829, 6983.3714965653335, 6902.309769023058, 6911.205211400653, 6917.688704008231, 6930.861382772317, 6952.85277929136, 6888.41448476875, 6899.275576064541, 6938.410235130071, 6976.767434421038, 6984.482259015321, 6596.472282217507, 6629.436497897733, 6632.966835165793, 6636.900446493403, 6642.468602511691, 6627.933720662924, 6652.634105124071, 6699.095622090956, 6709.605208588382, 6710.966445167938, 6704.597724136567, 6782.287859774653, 6843.692033858588, 6871.3128687132885, 6922.757703480354, 5041.773120209673, 5102.515261050195, 5118.97440512795, 5121.74391280442, 5129.230615407719, 6492.577982431127, 6653.600783952785, 6663.400293973566, 6686.3313668632745, 6735.831604209934, 6251.843703907439, 6524.478041756743, 6551.541392102507, 6568.343165683394, 6579.835504112435, 6453.290138084713, 6455.7740479083, 6467.514936379885, 6474.5791523550815, 6505.349465053457, 6282.685865706788, 6308.779192728232, 6329.778457753957, 6423.710933007932, 6424.582402143846, 6421.325760795049, 6480.57876238043, 6528.967355163194, 6536.150300460909, 6577.934220657732, 6256.812295631138, 6258.468030217483, 6290.923995081704, 6355.52333574993, 6444.065610486359], "value": 5118.298543676872, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [962.3359881681644, 969.6945462179409, 972.0, 981.8036392721458, 983.6458874805069, 16329.510114696563, 17330.653386932252, 19085.332013379517, 20045.699314510293, 20047.799522004774, 16805.243764030616, 16823.158842057877, 16826.24281907314, 19354.322598709048, 20068.69896951547, 1062.941538215398, 1088.9891101088967, 1089.0, 1089.7929393415225, 1090.0, 1065.3022270412891, 1082.377632861104, 1089.4008295437518, 1095.6329629574102, 1096.0245381610373, 1118.6811758648787, 1131.8132508136148, 1131.9094472442225, 1134.5972179876148, 1136.9488373023191, 1123.5842738186923, 1125.4597793059336, 1126.1666366888485, 1128.2158899564806, 1129.1023636209234, 830.219593582037, 830.3440282177073, 831.3515457942814, 834.4117397234935, 836.995815020921, 18028.819711802975, 18634.0, 18687.53281167968, 18689.0, 18766.155523001496, 23030.88484557583, 23327.41681457977, 23671.88164059186, 23678.64482032771, 23729.406764830845, 18560.721589176173, 18583.349582764673, 18668.43994680148, 18670.0, 18718.43844684648, 3860.343741563941, 3880.185161116169, 3958.44581758554, 3967.8809635710977, 3977.105151340925, 164434.0, 167680.0, 168242.0, 170522.99999999875, 171580.0, 16829.074400907823, 17250.223739931735, 17342.739858902125, 17346.0, 17372.3051077958, 33241.0, 34822.82588587041, 35022.0, 35085.64914350875, 35118.0, 8708.825823483496, 9026.729198124067, 9617.471039092918, 9987.950060249797, 10040.397576145455, 4762.738049407316, 4892.633052521075, 4896.192128298862, 4904.190808516555, 4911.50884911506, 5864.530837532987, 5925.229720136354, 5932.58471906971, 5964.731587078505, 5999.610025348338, 1264.943077561512, 1290.0324756432676, 1302.8827405533548, 1304.8695130486876, 1306.1836352279824, 719.2340157732103, 723.804572765361, 739.2570466681059, 743.3347154296982, 749.9850002999909, 12384.629967771147, 12593.874061259454, 13015.154014988995, 13448.932755336356, 13617.046806723632, 715.8210447388153, 716.176397143279, 722.0757430488983, 740.5223630758131, 744.9329560339595, 5615.971920140373, 5690.77236910519, 5719.027765279912, 5763.827085187451, 5901.203337549462, 146879.0, 148266.0, 148814.0, 148914.52068160375, 157098.0, 128424.0, 130748.0, 131444.3427782855, 132431.0, 134241.0, 114869.0785528676, 115219.0, 117260.0, 118596.40701796436, 120503.0, 10678.199135064746, 10755.19336049815, 10933.781324373627, 10991.780164396827, 11080.224384293022, 53452.465475346304, 53643.731781340844, 53715.46284537261, 53974.73012634912, 55881.0, 69836.65081674559, 69913.65043174752, 70698.0, 71403.28596714174, 71940.28059719336, 16540.75188872144, 17209.827901720822, 17472.0, 17582.20880060375, 17599.472015839547, 23651.172208972603, 23681.64477532804, 23776.881115595002, 23939.640905386084, 23990.0, 17884.731729023813, 17911.820881791537, 18236.726449103007, 18286.634267314847, 18480.537986550444, 20865.791342086384, 21441.571168576855, 21592.244271450425, 21655.35033948984, 21835.126594936024, 16612.75080873812, 18595.72106418432, 19294.421167365002, 19427.51431214231, 19666.50833729111, 15210.771838422212, 15220.782337413202, 15289.6177595561, 15410.691786164438, 15744.055356678637, 13766.587002389944, 13989.160650361011, 14073.155610663393, 14276.714465710835, 14341.282935853375], "value": 10043.348062595378, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1377.1999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 105.0, 104.0, 105.0, 107.0, 105.0, 107.0, 105.0, 105.0, 106.0], "value": 105.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 113.0, 116.0, 117.0, 119.0, 115.0, 113.0, 122.0, 114.0, 117.0], "value": 116.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 162.0, 162.0, 162.0, 163.0, 162.0, 167.0, 166.0], "value": 163.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 74.0, 74.0, 73.0, 82.0, 74.0, 75.0, 74.0, 74.0], "value": 74.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 78.0, 79.0, 78.0, 81.0, 79.0, 78.0, 78.0, 84.0], "value": 79.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 113.0, 110.0, 114.0, 114.0, 114.0, 117.0, 114.0, 115.0], "value": 113.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 149.0, 150.0, 149.0, 151.0, 151.0, 150.0, 152.0, 149.0, 151.0], "value": 150.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 94.0, 99.0, 95.0, 94.0, 94.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 44.0, 45.0, 46.0, 44.0, 46.0, 44.0, 45.0, 45.0, 45.0], "value": 44.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 40.0, 40.0, 39.0, 42.0, 39.0, 39.0, 40.0, 44.0, 39.0], "value": 40.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 76.0, 72.0, 73.0, 71.0, 71.0, 75.0, 76.0, 73.0, 72.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 106.0, 102.0, 108.0, 110.0, 111.0, 107.0, 108.0, 108.0, 106.0], "value": 107.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 153.0, 152.0, 148.0, 149.0, 147.0, 153.0, 155.0, 148.0, 154.0], "value": 151.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 60.0, 59.0, 58.0, 62.0, 60.0, 67.0, 60.0, 60.0], "value": 62.1, "unit": "score"}]}]} 12:19:01 INFO - Return code: 0 12:19:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:19:01 INFO - # TBPL SUCCESS # 12:19:01 INFO - Running post-action listener: _package_coverage_data 12:19:01 INFO - Running post-action listener: _resource_record_post_action 12:19:01 INFO - [mozharness: 2017-09-23 19:19:01.842474Z] Finished run-tests step (success) 12:19:01 INFO - Running post-run listener: _resource_record_post_run 12:19:01 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 8192; Write bytes: 329637888; Read time: 156; Write time: 237004 12:19:01 INFO - TinderboxPrint: CPU usage
13.1% 12:19:01 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 156 12:19:01 INFO - TinderboxPrint: I/O write bytes / time
329,637,888 / 237,004 12:19:01 INFO - TinderboxPrint: CPU idle
8,218.5 (86.9%) 12:19:01 INFO - TinderboxPrint: CPU user
1,187.4 (12.6%) 12:19:01 INFO - TinderboxPrint: Swap in / out
0 / 0 12:19:01 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13320192; Read time: 0; Write time: 2868 12:19:01 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:19:01 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 8192; Write bytes: 316317696; Read time: 156; Write time: 234136 12:19:02 INFO - Running post-run listener: _upload_blobber_files 12:19:02 INFO - Blob upload gear active. 12:19:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:19:02 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 12:19:02 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'] 12:19:02 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 12:19:02 INFO - (blobuploader) - INFO - Open directory for files ... 12:19:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:19:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:19:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:19:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:19:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:19:03 INFO - (blobuploader) - INFO - Done attempting. 12:19:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:19:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:19:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:19:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:19:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:19:10 INFO - (blobuploader) - INFO - Done attempting. 12:19:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:19:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:19:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:19:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:19:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:19:11 INFO - (blobuploader) - INFO - Done attempting. 12:19:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:19:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:19:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:19:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:19:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:19:12 INFO - (blobuploader) - INFO - Done attempting. 12:19:12 INFO - (blobuploader) - INFO - Iteration through files over. 12:19:12 INFO - Return code: 0 12:19:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:19:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:19:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/718c046ea18c5446df89a3c836ae26909d1a796ad7b73d13d5adcd0c21e9f8b91924f04e62b6a775c008782a1c4bc0374dd2840339ba153aaa13d2ece7237ad3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/323fe6a84abbc62d24a8e811e3e3c26feb146f8230722df7a47569fd9362f545c14985bb1b98ebb0ddccbaa23b2ec701dbd1c08e5de2ec2f5edeec09a5c44011", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c72f422558935c7310540e3f96f026d0db4879481735b670cdba3187f18e3aabed5bf21ecc34b4659c9d83583404f7176563d9acbfa5248cb9f034915f349ee", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2721e3ec9920ebc7305f530197557720451304c770099281b980d3ec3119dad99bc3c9178eea8039665652b5444e765d7d94a1bf5a5c0db5b2989bce1023a465"} 12:19:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:19:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:19:12 INFO - Contents: 12:19:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/718c046ea18c5446df89a3c836ae26909d1a796ad7b73d13d5adcd0c21e9f8b91924f04e62b6a775c008782a1c4bc0374dd2840339ba153aaa13d2ece7237ad3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/323fe6a84abbc62d24a8e811e3e3c26feb146f8230722df7a47569fd9362f545c14985bb1b98ebb0ddccbaa23b2ec701dbd1c08e5de2ec2f5edeec09a5c44011", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c72f422558935c7310540e3f96f026d0db4879481735b670cdba3187f18e3aabed5bf21ecc34b4659c9d83583404f7176563d9acbfa5248cb9f034915f349ee", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2721e3ec9920ebc7305f530197557720451304c770099281b980d3ec3119dad99bc3c9178eea8039665652b5444e765d7d94a1bf5a5c0db5b2989bce1023a465"} 12:19:12 INFO - Running post-run listener: copy_logs_to_upload_dir 12:19:12 INFO - Copying logs to upload dir... 12:19:12 INFO - mkdir: /builds/slave/test/build/upload/logs 12:19:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.124149 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2017-09-23 12:19:12.779531) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 12:19:12.782904) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1506192021.541468-1509883357 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/718c046ea18c5446df89a3c836ae26909d1a796ad7b73d13d5adcd0c21e9f8b91924f04e62b6a775c008782a1c4bc0374dd2840339ba153aaa13d2ece7237ad3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/323fe6a84abbc62d24a8e811e3e3c26feb146f8230722df7a47569fd9362f545c14985bb1b98ebb0ddccbaa23b2ec701dbd1c08e5de2ec2f5edeec09a5c44011", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c72f422558935c7310540e3f96f026d0db4879481735b670cdba3187f18e3aabed5bf21ecc34b4659c9d83583404f7176563d9acbfa5248cb9f034915f349ee", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2721e3ec9920ebc7305f530197557720451304c770099281b980d3ec3119dad99bc3c9178eea8039665652b5444e765d7d94a1bf5a5c0db5b2989bce1023a465"} build_url:https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011516 build_url: 'https://queue.taskcluster.net/v1/task/P_fmKY9XR4WTD3BXDh9SKg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/718c046ea18c5446df89a3c836ae26909d1a796ad7b73d13d5adcd0c21e9f8b91924f04e62b6a775c008782a1c4bc0374dd2840339ba153aaa13d2ece7237ad3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/323fe6a84abbc62d24a8e811e3e3c26feb146f8230722df7a47569fd9362f545c14985bb1b98ebb0ddccbaa23b2ec701dbd1c08e5de2ec2f5edeec09a5c44011", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c72f422558935c7310540e3f96f026d0db4879481735b670cdba3187f18e3aabed5bf21ecc34b4659c9d83583404f7176563d9acbfa5248cb9f034915f349ee", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2721e3ec9920ebc7305f530197557720451304c770099281b980d3ec3119dad99bc3c9178eea8039665652b5444e765d7d94a1bf5a5c0db5b2989bce1023a465"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 12:19:12.811294) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 12:19:12.811652) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1506192021.541468-1509883357 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004582 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 12:19:12.896338) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 12:19:12.896693) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 12:19:12.897018) ========= ========= Total master_lag: 0.20 =========