builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-008 starttime: 1506990591.31 results: success (0) revision: 65dac33a5682f3ec5a675e7f3314b0c1520a13fa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:51.308017) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:51.308449) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:51.308757) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:51.402168) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:51.402513) ========= 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=32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-02 17:29:51-- 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% 546K=0.02s 2017-10-02 17:29:51 (546 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.183848 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:51.708652) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:51.708936) ========= 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=32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015369 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:51.759479) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-02 17:29:51.759865) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 65dac33a5682f3ec5a675e7f3314b0c1520a13fa --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 65dac33a5682f3ec5a675e7f3314b0c1520a13fa --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=32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-02 17:29:51,784 truncating revision to first 12 chars 2017-10-02 17:29:51,785 Setting DEBUG logging. 2017-10-02 17:29:51,785 attempt 1/10 2017-10-02 17:29:51,785 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/65dac33a5682?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-02 17:29:52,040 unpacking tar archive at: mozilla-central-65dac33a5682/testing/mozharness/ program finished with exit code 0 elapsedTime=1.231230 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-02 17:29:53.021815) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:53.022124) ========= script_repo_revision: 65dac33a5682f3ec5a675e7f3314b0c1520a13fa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:53.022493) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:53.022761) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-02 17:29:53.040183) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-10-02 17:29:53.040466) ========= /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=32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:29:53 INFO - MultiFileLogger online at 20171002 17:29:53 in /builds/slave/test 17:29:53 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 17:29:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:29:53 INFO - {'append_to_log': False, 17:29:53 INFO - 'base_work_dir': '/builds/slave/test', 17:29:53 INFO - 'blob_upload_branch': 'Firefox', 17:29:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:29:53 INFO - 'branch': 'Firefox', 17:29:53 INFO - 'buildbot_json_path': 'buildprops.json', 17:29:53 INFO - 'code_coverage': False, 17:29:53 INFO - 'config_files': ('talos/linux_config.py',), 17:29:53 INFO - 'default_actions': ('clobber', 17:29:53 INFO - 'read-buildbot-config', 17:29:53 INFO - 'download-and-extract', 17:29:53 INFO - 'populate-webroot', 17:29:53 INFO - 'create-virtualenv', 17:29:53 INFO - 'install', 17:29:53 INFO - 'setup-mitmproxy', 17:29:53 INFO - 'run-tests'), 17:29:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:29:53 INFO - 'disable_ccov_upload': False, 17:29:53 INFO - 'disable_stylo': False, 17:29:53 INFO - 'download_minidump_stackwalk': True, 17:29:53 INFO - 'download_symbols': 'ondemand', 17:29:53 INFO - 'enable_stylo': False, 17:29:53 INFO - 'enable_webrender': False, 17:29:53 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 17:29:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:29:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:29:53 INFO - 'gecko_profile': False, 17:29:53 INFO - 'gecko_profile_interval': 0, 17:29:53 INFO - 'installer_path': 'installer.exe', 17:29:53 INFO - 'log_level': 'info', 17:29:53 INFO - 'log_name': 'talos', 17:29:53 INFO - 'log_to_console': True, 17:29:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:29:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:29:53 INFO - 'opt_config_files': (), 17:29:53 INFO - 'pip_index': False, 17:29:53 INFO - 'suite': 'dromaeojs-e10s', 17:29:53 INFO - 'system_bits': '32', 17:29:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:29:53 INFO - 'title': 'talos-linux64-ix-008', 17:29:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:29:53 INFO - 'use_talos_json': True, 17:29:53 INFO - 'verify': 'False', 17:29:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:29:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:29:53 INFO - 'work_dir': 'build'} 17:29:53 INFO - [mozharness: 2017-10-03 00:29:53.199342Z] Running clobber step. 17:29:53 INFO - Running pre-action listener: _resource_record_pre_action 17:29:53 INFO - Running main action method: clobber 17:29:53 INFO - rmtree: /builds/slave/test/build 17:29:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:29:54 INFO - Running post-action listener: _resource_record_post_action 17:29:54 INFO - [mozharness: 2017-10-03 00:29:54.718588Z] Finished clobber step (success) 17:29:54 INFO - [mozharness: 2017-10-03 00:29:54.718686Z] Running read-buildbot-config step. 17:29:54 INFO - Running pre-action listener: _resource_record_pre_action 17:29:54 INFO - Running main action method: read_buildbot_config 17:29:54 INFO - Using buildbot properties: 17:29:54 INFO - { 17:29:54 INFO - "project": "", 17:29:54 INFO - "product": "firefox", 17:29:54 INFO - "who": "kwierso@gmail.com", 17:29:54 INFO - "installer_path": "public/build/target.tar.bz2", 17:29:54 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 17:29:54 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:29:54 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 17:29:54 INFO - "stage_platform": "linux64-qr", 17:29:54 INFO - "basedir": "/builds/slave/test", 17:29:54 INFO - "buildnumber": 117, 17:29:54 INFO - "platform": "ubuntu64_hw_qr", 17:29:54 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:29:54 INFO - "slavebuilddir": "test", 17:29:54 INFO - "taskId": "OOMjZrWQT7iszHM6D-guEQ", 17:29:54 INFO - "branch": "mozilla-central", 17:29:54 INFO - "script_repo_revision": "production", 17:29:54 INFO - "revision": "65dac33a5682f3ec5a675e7f3314b0c1520a13fa", 17:29:54 INFO - "slavename": "talos-linux64-ix-008", 17:29:54 INFO - "repo_path": "mozilla-central" 17:29:54 INFO - } 17:29:54 INFO - Finding installer, test and symbols from parent task. 17:29:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OOMjZrWQT7iszHM6D-guEQ'}, attempt #1 17:29:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OOMjZrWQT7iszHM6D-guEQ'}, attempt #1 17:29:55 INFO - Task dependencies: Hy3hXRK2T5ipTB-nz_T5Yw 17:29:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw'}, attempt #1 17:29:56 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.tar.bz2 17:29:56 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.test_packages.json 17:29:56 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.crashreporter-symbols.zip 17:29:56 INFO - Running post-action listener: _resource_record_post_action 17:29:56 INFO - [mozharness: 2017-10-03 00:29:56.331333Z] Finished read-buildbot-config step (success) 17:29:56 INFO - [mozharness: 2017-10-03 00:29:56.331716Z] Running download-and-extract step. 17:29:56 INFO - Running pre-action listener: _resource_record_pre_action 17:29:56 INFO - Running main action method: download_and_extract 17:29:56 INFO - mkdir: /builds/slave/test/build/tests 17:29: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')]} 17:29:56 INFO - trying https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.test_packages.json 17:29:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:29:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:29:57 INFO - Downloaded 989 bytes. 17:29:57 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:29:57 INFO - Using the following test package requirements: 17:29:57 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:29:57 INFO - u'common': [u'target.common.tests.zip'], 17:29:57 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:29:57 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:29:57 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:29:57 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:29:57 INFO - u'mozbase': [u'target.common.tests.zip'], 17:29:57 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:29:57 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:29:57 INFO - u'web-platform': [u'target.common.tests.zip', 17:29:57 INFO - u'target.web-platform.tests.tar.gz'], 17:29:57 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:29:57 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 17:29:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.common.tests.zip 17:29:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:29:57 INFO - Fetch https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.common.tests.zip into memory 17:29:58 INFO - Content-Length response header: 55346447 17:29:58 INFO - Bytes received: 55346447 17:30:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.talos.tests.zip 17:30:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:30:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.talos.tests.zip into memory 17:30:09 INFO - Content-Length response header: 14522427 17:30:09 INFO - Bytes received: 14522427 17:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:10 INFO - trying https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.tar.bz2 17:30:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:30:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:30:11 INFO - Downloaded 61637688 bytes. 17:30:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.tar.bz2 17:30:11 INFO - mkdir: /builds/slave/test/properties 17:30:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:30:11 INFO - Writing to file /builds/slave/test/properties/build_url 17:30:11 INFO - Contents: 17:30:11 INFO - build_url:https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.tar.bz2 17:30:11 INFO - Running post-action listener: _resource_record_post_action 17:30:11 INFO - Running post-action listener: find_tests_for_verification 17:30:11 INFO - Running post-action listener: set_extra_try_arguments 17:30:11 INFO - [mozharness: 2017-10-03 00:30:11.565713Z] Finished download-and-extract step (success) 17:30:11 INFO - [mozharness: 2017-10-03 00:30:11.565849Z] Running populate-webroot step. 17:30:11 INFO - Running pre-action listener: _resource_record_pre_action 17:30:11 INFO - Running main action method: populate_webroot 17:30:11 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:30:11 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:11 INFO - 'tests': ['tresize', 17:30:11 INFO - 'tcanvasmark']}, 17:30:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:30:11 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:11 INFO - 'tests': ['dromaeo_css', 17:30:11 INFO - 'kraken']}, 17:30:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:30:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:30:11 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:11 INFO - 'talos_options': ['--disable-stylo'], 17:30:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:30:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:30:11 INFO - 'tests': ['damp', 'tps']}, 17:30:11 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:11 INFO - 'talos_options': ['--disable-stylo'], 17:30:11 INFO - 'tests': ['damp', 'tps']}, 17:30:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:30:11 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:11 INFO - 'tests': ['dromaeo_dom']}, 17:30:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:30:11 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:11 INFO - 'tests': ['basic_compositor_video', 17:30:11 INFO - 'glvideo']}, 17:30:11 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:30:11 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:30:11 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:11 INFO - 'talos_options': ['--disable-stylo'], 17:30:11 INFO - 'tests': ['ts_paint_webext', 17:30:11 INFO - 'tp5o_webext']}, 17:30:11 INFO - 'other-e10s': {'tests': ['a11yr', 17:30:11 INFO - 'ts_paint', 17:30:11 INFO - 'tpaint', 17:30:11 INFO - 'sessionrestore', 17:30:11 INFO - 'sessionrestore_many_windows', 17:30:11 INFO - 'sessionrestore_no_auto_restore', 17:30:11 INFO - 'tabpaint', 17:30:11 INFO - 'cpstartup']}, 17:30:11 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:11 INFO - 'tests': ['a11yr', 17:30:11 INFO - 'ts_paint', 17:30:11 INFO - 'tpaint', 17:30:11 INFO - 'sessionrestore', 17:30:11 INFO - 'sessionrestore_many_windows', 17:30:11 INFO - 'sessionrestore_no_auto_restore', 17:30:11 INFO - 'tabpaint', 17:30:11 INFO - 'cpstartup']}, 17:30:11 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 17:30:11 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 17:30:11 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:11 INFO - 'tests': ['perf_reftest_singletons']}, 17:30:11 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:11 INFO - 'tests': ['perf_reftest']}, 17:30:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:30:11 INFO - 'tsvgr_opacity', 17:30:11 INFO - 'tart', 17:30:11 INFO - 'tscrollx', 17:30:11 INFO - 'tsvg_static']}, 17:30:11 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:11 INFO - 'tests': ['tsvgx', 17:30:11 INFO - 'tsvgr_opacity', 17:30:11 INFO - 'tart', 17:30:11 INFO - 'tscrollx', 17:30:11 INFO - 'tsvg_static']}, 17:30:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:30:11 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:11 INFO - 'talos_options': ['--disable-stylo'], 17:30:11 INFO - 'tests': ['tp5o']}, 17:30:11 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:30:11 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:30:11 INFO - 'talos_options': ['--mitmproxy', 17:30:11 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:30:11 INFO - '--firstNonBlankPaint'], 17:30:11 INFO - 'tests': ['tp6_google', 17:30:11 INFO - 'tp6_youtube', 17:30:11 INFO - 'tp6_amazon', 17:30:11 INFO - 'tp6_facebook']}, 17:30:11 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:30:11 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:30:11 INFO - 'talos_options': ['--disable-stylo', 17:30:11 INFO - '--mitmproxy', 17:30:11 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:30:11 INFO - '--firstNonBlankPaint'], 17:30:11 INFO - 'tests': ['tp6_google', 17:30:11 INFO - 'tp6_youtube', 17:30:11 INFO - 'tp6_amazon', 17:30:11 INFO - 'tp6_facebook']}, 17:30:11 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:30:11 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:30:11 INFO - 'talos_options': ['--stylo-threads=1', 17:30:11 INFO - '--mitmproxy', 17:30:11 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:30:11 INFO - '--firstNonBlankPaint'], 17:30:11 INFO - 'tests': ['tp6_google', 17:30:11 INFO - 'tp6_youtube', 17:30:11 INFO - 'tp6_amazon', 17:30:11 INFO - 'tp6_facebook']}, 17:30:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:30:11 INFO - 'talos_options': ['--xperf_path', 17:30:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:30:11 INFO - 'tests': ['tp5n']}, 17:30:11 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:11 INFO - 'talos_options': ['--disable-stylo', 17:30:11 INFO - '--xperf_path', 17:30:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:30:11 INFO - 'tests': ['tp5n']}}} 17:30:11 INFO - Running post-action listener: _resource_record_post_action 17:30:11 INFO - [mozharness: 2017-10-03 00:30:11.582707Z] Finished populate-webroot step (success) 17:30:11 INFO - [mozharness: 2017-10-03 00:30:11.582815Z] Running create-virtualenv step. 17:30:11 INFO - Running pre-action listener: _resource_record_pre_action 17:30:11 INFO - Running main action method: create_virtualenv 17:30:11 INFO - Creating virtualenv /builds/slave/test/build/venv 17:30:11 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:30:11 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 17:30:11 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:30:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:30:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:30:12 INFO - Installing distribute.............................................................................................................................................................................................done. 17:30:12 INFO - Installing pip...............done. 17:30:12 INFO - Return code: 0 17:30:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:30:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:30:12 INFO - Reading from file tmpfile_stdout 17:30:12 INFO - Output received: 17:30:12 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 17:30:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:30: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')]} 17:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30: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')]} 17:30:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e19b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbbd60>, '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 0x1fbca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbcef0>, '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': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', '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 17:30:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:30:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:30:12 INFO - Using env: {'DISPLAY': ':0', 17:30:12 INFO - 'HOME': '/home/cltbld', 17:30:12 INFO - 'LANG': 'en_US.UTF-8', 17:30:12 INFO - 'LANGUAGE': 'en_US:en', 17:30:12 INFO - 'LOGNAME': 'cltbld', 17:30:12 INFO - 'MAIL': '/var/mail/cltbld', 17:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:12 INFO - 'MOZ_NO_REMOTE': '1', 17:30:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:12 INFO - 'NO_EM_RESTART': '1', 17:30:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:12 INFO - 'PWD': '/builds/slave/test', 17:30:12 INFO - 'SHELL': '/bin/bash', 17:30:12 INFO - 'SHLVL': '1', 17:30:12 INFO - 'TERM': 'linux', 17:30:12 INFO - 'TMOUT': '86400', 17:30:12 INFO - 'USER': 'cltbld', 17:30:12 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', 17:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:12 INFO - '_': '/tools/buildbot/bin/python'} 17:30:13 INFO - Ignoring indexes: http://pypi.python.org/simple/ 17:30:13 INFO - Downloading/unpacking pip>=1.5 17:30:13 INFO - Running setup.py egg_info for package pip 17:30:13 INFO - warning: no files found matching 'pip/cacert.pem' 17:30:13 INFO - warning: no files found matching '*.html' under directory 'docs' 17:30:13 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:30:13 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:30:13 INFO - Installing collected packages: pip 17:30:13 INFO - Found existing installation: pip 1.1 17:30:13 INFO - Uninstalling pip: 17:30:13 INFO - Successfully uninstalled pip 17:30:13 INFO - Running setup.py install for pip 17:30:13 INFO - warning: no files found matching 'pip/cacert.pem' 17:30:13 INFO - warning: no files found matching '*.html' under directory 'docs' 17:30:13 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:30:13 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:30:13 INFO - Installing pip script to /builds/slave/test/build/venv/bin 17:30:13 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 17:30:13 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 17:30:13 INFO - Successfully installed pip 17:30:13 INFO - Cleaning up... 17:30:13 INFO - Return code: 0 17:30:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:30:13 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')]} 17:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:13 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')]} 17:30:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:13 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 0x1c511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e19b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbbd60>, '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 0x1fbca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbcef0>, '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': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', '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 17:30:13 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 17:30:13 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 17:30:13 INFO - Using env: {'DISPLAY': ':0', 17:30:13 INFO - 'HOME': '/home/cltbld', 17:30:13 INFO - 'LANG': 'en_US.UTF-8', 17:30:13 INFO - 'LANGUAGE': 'en_US:en', 17:30:13 INFO - 'LOGNAME': 'cltbld', 17:30:13 INFO - 'MAIL': '/var/mail/cltbld', 17:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:13 INFO - 'MOZ_NO_REMOTE': '1', 17:30:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:13 INFO - 'NO_EM_RESTART': '1', 17:30:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:13 INFO - 'PWD': '/builds/slave/test', 17:30:13 INFO - 'SHELL': '/bin/bash', 17:30:13 INFO - 'SHLVL': '1', 17:30:13 INFO - 'TERM': 'linux', 17:30:13 INFO - 'TMOUT': '86400', 17:30:13 INFO - 'USER': 'cltbld', 17:30:13 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', 17:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:13 INFO - '_': '/tools/buildbot/bin/python'} 17:30:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:13 INFO - Downloading/unpacking psutil>=3.1.1 17:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:30:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:30:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:30:15 INFO - Installing collected packages: psutil 17:30:15 INFO - Running setup.py install for psutil 17:30:15 INFO - building 'psutil._psutil_linux' extension 17:30:15 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 17:30:15 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 17:30:15 INFO - building 'psutil._psutil_posix' extension 17:30:15 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 17:30:15 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 17:30:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:30:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:30:15 INFO - Successfully installed psutil 17:30:15 INFO - Cleaning up... 17:30:15 INFO - Return code: 0 17:30:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:30:15 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')]} 17:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:15 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')]} 17:30:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:15 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 0x1c511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e19b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbbd60>, '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 0x1fbca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbcef0>, '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': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', '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 17:30:15 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 17:30:15 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 17:30:15 INFO - Using env: {'DISPLAY': ':0', 17:30:15 INFO - 'HOME': '/home/cltbld', 17:30:15 INFO - 'LANG': 'en_US.UTF-8', 17:30:15 INFO - 'LANGUAGE': 'en_US:en', 17:30:15 INFO - 'LOGNAME': 'cltbld', 17:30:15 INFO - 'MAIL': '/var/mail/cltbld', 17:30:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:15 INFO - 'MOZ_NO_REMOTE': '1', 17:30:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:15 INFO - 'NO_EM_RESTART': '1', 17:30:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:15 INFO - 'PWD': '/builds/slave/test', 17:30:15 INFO - 'SHELL': '/bin/bash', 17:30:15 INFO - 'SHLVL': '1', 17:30:15 INFO - 'TERM': 'linux', 17:30:15 INFO - 'TMOUT': '86400', 17:30:15 INFO - 'USER': 'cltbld', 17:30:15 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', 17:30:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:15 INFO - '_': '/tools/buildbot/bin/python'} 17:30:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:30:15 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 17:30:15 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 17:30:15 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 17:30:15 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 17:30:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:30:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:30:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:30:17 INFO - Installing collected packages: mozsystemmonitor 17:30:17 INFO - Running setup.py install for mozsystemmonitor 17:30:17 INFO - Successfully installed mozsystemmonitor 17:30:17 INFO - Cleaning up... 17:30:17 INFO - Return code: 0 17:30:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:17 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 0x1c511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e19b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbbd60>, '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 0x1fbca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbcef0>, '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': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', '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 17:30:17 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 17:30:17 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 17:30:17 INFO - Using env: {'DISPLAY': ':0', 17:30:17 INFO - 'HOME': '/home/cltbld', 17:30:17 INFO - 'LANG': 'en_US.UTF-8', 17:30:17 INFO - 'LANGUAGE': 'en_US:en', 17:30:17 INFO - 'LOGNAME': 'cltbld', 17:30:17 INFO - 'MAIL': '/var/mail/cltbld', 17:30:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:17 INFO - 'MOZ_NO_REMOTE': '1', 17:30:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:17 INFO - 'NO_EM_RESTART': '1', 17:30:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:17 INFO - 'PWD': '/builds/slave/test', 17:30:17 INFO - 'SHELL': '/bin/bash', 17:30:17 INFO - 'SHLVL': '1', 17:30:17 INFO - 'TERM': 'linux', 17:30:17 INFO - 'TMOUT': '86400', 17:30:17 INFO - 'USER': 'cltbld', 17:30:17 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', 17:30:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:17 INFO - '_': '/tools/buildbot/bin/python'} 17:30:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:18 INFO - Downloading/unpacking jsonschema==2.5.1 17:30:18 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 17:30:18 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 17:30:18 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 17:30:18 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 17:30:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:30:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:19 INFO - Downloading functools32-3.2.3-2.tar.gz 17:30:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:30:19 INFO - warning: no files found matching '*.txt' 17:30:19 INFO - no previously-included directories found matching 'build' 17:30:19 INFO - no previously-included directories found matching 'dist' 17:30:19 INFO - no previously-included directories found matching '.git*' 17:30:19 INFO - Installing collected packages: jsonschema, functools32 17:30:19 INFO - Running setup.py install for functools32 17:30:20 INFO - warning: no files found matching '*.txt' 17:30:20 INFO - no previously-included directories found matching 'build' 17:30:20 INFO - no previously-included directories found matching 'dist' 17:30:20 INFO - no previously-included directories found matching '.git*' 17:30:20 INFO - Successfully installed jsonschema functools32 17:30:20 INFO - Cleaning up... 17:30:20 INFO - Return code: 0 17:30:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:30:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:20 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 0x1c511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e19b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbbd60>, '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 0x1fbca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbcef0>, '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': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', '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 17:30:20 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 17:30:20 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 17:30:20 INFO - Using env: {'DISPLAY': ':0', 17:30:20 INFO - 'HOME': '/home/cltbld', 17:30:20 INFO - 'LANG': 'en_US.UTF-8', 17:30:20 INFO - 'LANGUAGE': 'en_US:en', 17:30:20 INFO - 'LOGNAME': 'cltbld', 17:30:20 INFO - 'MAIL': '/var/mail/cltbld', 17:30:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:20 INFO - 'MOZ_NO_REMOTE': '1', 17:30:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:20 INFO - 'NO_EM_RESTART': '1', 17:30:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:20 INFO - 'PWD': '/builds/slave/test', 17:30:20 INFO - 'SHELL': '/bin/bash', 17:30:20 INFO - 'SHLVL': '1', 17:30:20 INFO - 'TERM': 'linux', 17:30:20 INFO - 'TMOUT': '86400', 17:30:20 INFO - 'USER': 'cltbld', 17:30:20 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', 17:30:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:20 INFO - '_': '/tools/buildbot/bin/python'} 17:30:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:30:20 INFO - Cleaning up... 17:30:20 INFO - Return code: 0 17:30:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:30:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:20 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 0x1c511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e19b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbbd60>, '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 0x1fbca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbcef0>, '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': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', '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 17:30:20 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 17:30:20 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 17:30:20 INFO - Using env: {'DISPLAY': ':0', 17:30:20 INFO - 'HOME': '/home/cltbld', 17:30:20 INFO - 'LANG': 'en_US.UTF-8', 17:30:20 INFO - 'LANGUAGE': 'en_US:en', 17:30:20 INFO - 'LOGNAME': 'cltbld', 17:30:20 INFO - 'MAIL': '/var/mail/cltbld', 17:30:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:20 INFO - 'MOZ_NO_REMOTE': '1', 17:30:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:20 INFO - 'NO_EM_RESTART': '1', 17:30:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:20 INFO - 'PWD': '/builds/slave/test', 17:30:20 INFO - 'SHELL': '/bin/bash', 17:30:20 INFO - 'SHLVL': '1', 17:30:20 INFO - 'TERM': 'linux', 17:30:20 INFO - 'TMOUT': '86400', 17:30:20 INFO - 'USER': 'cltbld', 17:30:20 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', 17:30:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:20 INFO - '_': '/tools/buildbot/bin/python'} 17:30:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:20 INFO - Downloading/unpacking blobuploader==1.2.4 17:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:21 INFO - Downloading blobuploader-1.2.4.tar.gz 17:30:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:30:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:30:21 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 17:30:21 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 17:30:21 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 17:30:21 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 17:30:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:30:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:30:22 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 17:30:22 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 17:30:22 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 17:30:22 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 17:30:22 INFO - Downloading docopt-0.6.1.tar.gz 17:30:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:30:22 INFO - Installing collected packages: blobuploader, requests, docopt 17:30:22 INFO - Running setup.py install for blobuploader 17:30:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:30:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:30:22 INFO - Running setup.py install for requests 17:30:22 INFO - Running setup.py install for docopt 17:30:22 INFO - Successfully installed blobuploader requests docopt 17:30:22 INFO - Cleaning up... 17:30:22 INFO - Return code: 0 17:30:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:30:22 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')]} 17:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:22 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')]} 17:30:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:22 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 0x1c511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e19b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbbd60>, '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 0x1fbca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbcef0>, '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': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', '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 17:30:22 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 17:30:22 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 17:30:22 INFO - Using env: {'DISPLAY': ':0', 17:30:22 INFO - 'HOME': '/home/cltbld', 17:30:22 INFO - 'LANG': 'en_US.UTF-8', 17:30:22 INFO - 'LANGUAGE': 'en_US:en', 17:30:22 INFO - 'LOGNAME': 'cltbld', 17:30:22 INFO - 'MAIL': '/var/mail/cltbld', 17:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:22 INFO - 'MOZ_NO_REMOTE': '1', 17:30:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:22 INFO - 'NO_EM_RESTART': '1', 17:30:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:22 INFO - 'PWD': '/builds/slave/test', 17:30:22 INFO - 'SHELL': '/bin/bash', 17:30:22 INFO - 'SHLVL': '1', 17:30:22 INFO - 'TERM': 'linux', 17:30:22 INFO - 'TMOUT': '86400', 17:30:22 INFO - 'USER': 'cltbld', 17:30:22 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', 17:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:22 INFO - '_': '/tools/buildbot/bin/python'} 17:30:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:30:23 INFO - Running setup.py (path:/tmp/pip-DkenSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:30:23 INFO - Running setup.py (path:/tmp/pip-nZq17g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:30:23 INFO - Running setup.py (path:/tmp/pip-k_84oP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:30:23 INFO - Running setup.py (path:/tmp/pip-mYk5wd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:30:23 INFO - Running setup.py (path:/tmp/pip-yUqECF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:30:23 INFO - Running setup.py (path:/tmp/pip-DteZ8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:30:23 INFO - Running setup.py (path:/tmp/pip-iVEPke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:30:23 INFO - Running setup.py (path:/tmp/pip-iY1enX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:30:23 INFO - Running setup.py (path:/tmp/pip-Eeww6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:30:23 INFO - Running setup.py (path:/tmp/pip-xpWxwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:30:23 INFO - Running setup.py (path:/tmp/pip-EFwo7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:30:23 INFO - Running setup.py (path:/tmp/pip-gSrFsw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:30:24 INFO - Running setup.py (path:/tmp/pip-EFFEJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:30:24 INFO - Running setup.py (path:/tmp/pip-_aBGTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:24 INFO - Running setup.py (path:/tmp/pip-hGF1Cm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:30:24 INFO - Running setup.py (path:/tmp/pip-15kgL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:30:24 INFO - Running setup.py (path:/tmp/pip-lW0pr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:30:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:30:24 INFO - Running setup.py install for manifestparser 17:30:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:30:24 INFO - Running setup.py install for mozcrash 17:30:24 INFO - Running setup.py install for mozdebug 17:30:24 INFO - Running setup.py install for mozdevice 17:30:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:30:24 INFO - Running setup.py install for mozfile 17:30:24 INFO - Running setup.py install for mozhttpd 17:30:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:30:24 INFO - Running setup.py install for mozinfo 17:30:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:30:25 INFO - Running setup.py install for mozInstall 17:30:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:30:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:30:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:30:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:30:25 INFO - Running setup.py install for mozleak 17:30:25 INFO - Running setup.py install for mozlog 17:30:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:30:25 INFO - Running setup.py install for moznetwork 17:30:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:30:25 INFO - Running setup.py install for mozprocess 17:30:25 INFO - Running setup.py install for mozprofile 17:30:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:30:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:30:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:30:25 INFO - Running setup.py install for mozrunner 17:30:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:30:25 INFO - Running setup.py install for mozscreenshot 17:30:25 INFO - Running setup.py install for moztest 17:30:26 INFO - Running setup.py install for mozversion 17:30:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:30:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:30:26 INFO - Cleaning up... 17:30:26 INFO - Return code: 0 17:30:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:30:26 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')]} 17:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:26 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')]} 17:30:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:26 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 0x1c511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e19b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbbd60>, '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 0x1fbca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbcef0>, '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': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', '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 17:30:26 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 17:30:26 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 17:30:26 INFO - Using env: {'DISPLAY': ':0', 17:30:26 INFO - 'HOME': '/home/cltbld', 17:30:26 INFO - 'LANG': 'en_US.UTF-8', 17:30:26 INFO - 'LANGUAGE': 'en_US:en', 17:30:26 INFO - 'LOGNAME': 'cltbld', 17:30:26 INFO - 'MAIL': '/var/mail/cltbld', 17:30:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:26 INFO - 'MOZ_NO_REMOTE': '1', 17:30:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:26 INFO - 'NO_EM_RESTART': '1', 17:30:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:26 INFO - 'PWD': '/builds/slave/test', 17:30:26 INFO - 'SHELL': '/bin/bash', 17:30:26 INFO - 'SHLVL': '1', 17:30:26 INFO - 'TERM': 'linux', 17:30:26 INFO - 'TMOUT': '86400', 17:30:26 INFO - 'USER': 'cltbld', 17:30:26 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', 17:30:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:26 INFO - '_': '/tools/buildbot/bin/python'} 17:30:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:30:26 INFO - Running setup.py (path:/tmp/pip-fHL05i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:30:26 INFO - Running setup.py (path:/tmp/pip-fzpJ8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:30:26 INFO - Running setup.py (path:/tmp/pip-w3eKDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:30:26 INFO - Running setup.py (path:/tmp/pip-IxNpib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:30:26 INFO - Running setup.py (path:/tmp/pip-LPM8Ne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:30:26 INFO - Running setup.py (path:/tmp/pip-pNDktz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:30:26 INFO - Running setup.py (path:/tmp/pip-jXukCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:30:26 INFO - Running setup.py (path:/tmp/pip-_fvm2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:30:26 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)) 17:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:30:26 INFO - Running setup.py (path:/tmp/pip-GWSFdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:30:27 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)) 17:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:30:27 INFO - Running setup.py (path:/tmp/pip-CTtq7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:30:27 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)) 17:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:30:27 INFO - Running setup.py (path:/tmp/pip-9w3IMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:30:27 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)) 17:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:30:27 INFO - Running setup.py (path:/tmp/pip-LF8phW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:30:27 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)) 17:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:30:27 INFO - Running setup.py (path:/tmp/pip-pJ0MHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:30:27 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)) 17:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:30:27 INFO - Running setup.py (path:/tmp/pip-ZCMXTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:30:27 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)) 17:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:27 INFO - Running setup.py (path:/tmp/pip-WL9b7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:27 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)) 17:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:30:27 INFO - Running setup.py (path:/tmp/pip-Yf2nS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:30:27 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)) 17:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:30:27 INFO - Running setup.py (path:/tmp/pip-_7ntYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:30:27 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)) 17:30:27 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)) 17:30:27 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)) 17:30:27 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)) 17:30:27 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)) 17:30:27 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)) 17:30:27 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)) 17:30:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:30:27 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 17:30:27 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 17:30:27 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 17:30:27 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 17:30:28 INFO - Downloading blessings-1.6.tar.gz 17:30:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:30:28 INFO - Installing collected packages: blessings 17:30:28 INFO - Running setup.py install for blessings 17:30:28 INFO - Successfully installed blessings 17:30:28 INFO - Cleaning up... 17:30:28 INFO - Return code: 0 17:30:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:30:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:30:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:30:29 INFO - Reading from file tmpfile_stdout 17:30:29 INFO - Current package versions: 17:30:29 INFO - argparse == 1.2.1 17:30:29 INFO - blessings == 1.6 17:30:29 INFO - blobuploader == 1.2.4 17:30:29 INFO - docopt == 0.6.1 17:30:29 INFO - functools32 == 3.2.3-2 17:30:29 INFO - jsonschema == 2.5.1 17:30:29 INFO - manifestparser == 1.1 17:30:29 INFO - mozInstall == 1.13 17:30:29 INFO - mozcrash == 1.0 17:30:29 INFO - mozdebug == 0.1 17:30:29 INFO - mozdevice == 0.51 17:30:29 INFO - mozfile == 1.2 17:30:29 INFO - mozhttpd == 0.7 17:30:29 INFO - mozinfo == 0.10 17:30:29 INFO - mozleak == 0.1 17:30:29 INFO - mozlog == 3.5 17:30:29 INFO - moznetwork == 0.27 17:30:29 INFO - mozprocess == 0.25 17:30:29 INFO - mozprofile == 0.28 17:30:29 INFO - mozrunner == 6.13 17:30:29 INFO - mozscreenshot == 0.1 17:30:29 INFO - mozsystemmonitor == 0.3 17:30:29 INFO - moztest == 0.8 17:30:29 INFO - mozversion == 1.4 17:30:29 INFO - psutil == 3.1.1 17:30:29 INFO - requests == 1.2.3 17:30:29 INFO - wsgiref == 0.1.2 17:30:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:30:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:29 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 0x1c511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e19b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbbd60>, '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 0x1fbca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbcef0>, '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': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', '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 17:30:29 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 17:30:29 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 17:30:29 INFO - Using env: {'DISPLAY': ':0', 17:30:29 INFO - 'HOME': '/home/cltbld', 17:30:29 INFO - 'LANG': 'en_US.UTF-8', 17:30:29 INFO - 'LANGUAGE': 'en_US:en', 17:30:29 INFO - 'LOGNAME': 'cltbld', 17:30:29 INFO - 'MAIL': '/var/mail/cltbld', 17:30:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:29 INFO - 'MOZ_NO_REMOTE': '1', 17:30:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:29 INFO - 'NO_EM_RESTART': '1', 17:30:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:29 INFO - 'PWD': '/builds/slave/test', 17:30:29 INFO - 'SHELL': '/bin/bash', 17:30:29 INFO - 'SHLVL': '1', 17:30:29 INFO - 'TERM': 'linux', 17:30:29 INFO - 'TMOUT': '86400', 17:30:29 INFO - 'USER': 'cltbld', 17:30:29 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', 17:30:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:29 INFO - '_': '/tools/buildbot/bin/python'} 17:30:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:29 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)) 17:30:29 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)) 17:30:29 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)) 17:30:29 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)) 17:30:29 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)) 17:30:29 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)) 17:30:29 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)) 17:30:29 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)) 17:30:29 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)) 17:30:29 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:30:29 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 17:30:29 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 17:30:29 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 17:30:29 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 17:30:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:30:30 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)) 17:30:30 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)) 17:30:30 INFO - Installing collected packages: simplejson 17:30:30 INFO - Running setup.py install for simplejson 17:30:30 INFO - building 'simplejson._speedups' extension 17:30:30 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 17:30:31 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 17:30:31 INFO - Successfully installed simplejson 17:30:31 INFO - Cleaning up... 17:30:31 INFO - Return code: 0 17:30:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:30:31 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')]} 17:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:31 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')]} 17:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:31 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 0x1c511f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e19b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbbd60>, '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 0x1fbca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbcef0>, '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': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', '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 17:30:31 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 17:30:31 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 17:30:31 INFO - Using env: {'DISPLAY': ':0', 17:30:31 INFO - 'HOME': '/home/cltbld', 17:30:31 INFO - 'LANG': 'en_US.UTF-8', 17:30:31 INFO - 'LANGUAGE': 'en_US:en', 17:30:31 INFO - 'LOGNAME': 'cltbld', 17:30:31 INFO - 'MAIL': '/var/mail/cltbld', 17:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:31 INFO - 'MOZ_NO_REMOTE': '1', 17:30:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:31 INFO - 'NO_EM_RESTART': '1', 17:30:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:31 INFO - 'PWD': '/builds/slave/test', 17:30:31 INFO - 'SHELL': '/bin/bash', 17:30:31 INFO - 'SHLVL': '1', 17:30:31 INFO - 'TERM': 'linux', 17:30:31 INFO - 'TMOUT': '86400', 17:30:31 INFO - 'USER': 'cltbld', 17:30:31 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', 17:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:31 INFO - '_': '/tools/buildbot/bin/python'} 17:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:30:31 INFO - Cleaning up... 17:30:31 INFO - Return code: 0 17:30:31 INFO - Running post-action listener: _resource_record_post_action 17:30:31 INFO - Running post-action listener: _start_resource_monitoring 17:30:31 INFO - Starting resource monitoring. 17:30:31 INFO - [mozharness: 2017-10-03 00:30:31.880733Z] Finished create-virtualenv step (success) 17:30:31 INFO - [mozharness: 2017-10-03 00:30:31.881404Z] Running install step. 17:30:31 INFO - Running pre-action listener: _resource_record_pre_action 17:30:31 INFO - Running main action method: install 17:30:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:30:32 INFO - Reading from file tmpfile_stdout 17:30:32 INFO - Detecting whether we're running mozinstall >=1.0... 17:30:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:30:32 INFO - Reading from file tmpfile_stdout 17:30:32 INFO - Output received: 17:30:32 INFO - Usage: mozinstall [options] installer 17:30:32 INFO - Options: 17:30:32 INFO - -h, --help show this help message and exit 17:30:32 INFO - -d DEST, --destination=DEST 17:30:32 INFO - Directory to install application into. [default: 17:30:32 INFO - "/builds/slave/test"] 17:30:32 INFO - --app=APP Application being installed. [default: firefox] 17:30:32 INFO - mkdir: /builds/slave/test/build/application 17:30:32 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'] 17:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:30:50 INFO - Reading from file tmpfile_stdout 17:30:50 INFO - Output received: 17:30:50 INFO - /builds/slave/test/build/application/firefox/firefox 17:30:50 INFO - Running post-action listener: _resource_record_post_action 17:30:50 INFO - [mozharness: 2017-10-03 00:30:50.028466Z] Finished install step (success) 17:30:50 INFO - [mozharness: 2017-10-03 00:30:50.028614Z] Running setup-mitmproxy step. 17:30:50 INFO - Running pre-action listener: _resource_record_pre_action 17:30:50 INFO - Running main action method: setup_mitmproxy 17:30:50 INFO - Skipping: mitmproxy is not required 17:30:50 INFO - Running post-action listener: _resource_record_post_action 17:30:50 INFO - [mozharness: 2017-10-03 00:30:50.029313Z] Finished setup-mitmproxy step (success) 17:30:50 INFO - [mozharness: 2017-10-03 00:30:50.029551Z] Running run-tests step. 17:30:50 INFO - Running pre-action listener: _resource_record_pre_action 17:30:50 INFO - Running pre-action listener: _set_gcov_prefix 17:30:50 INFO - Running main action method: run_tests 17:30:50 WARNING - Try message not found. 17:30:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:30:50 INFO - Python 2.7.3 17:30:50 INFO - Return code: 0 17:30:50 INFO - grabbing minidump binary from tooltool 17:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:50 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 0x1fbbd60>, '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 0x1fbca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbcef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:30:50 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 17:30:50 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 17:30:50 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 17:30:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:30:50 INFO - Return code: 0 17:30:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:30:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:30:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:30:50 INFO - ENV: RUST_BACKTRACE is now full 17:30:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:30:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:30:50 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/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--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 17:30:50 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/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-008 --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 17:30:50 INFO - Using env: {'DISPLAY': ':0', 17:30:50 INFO - 'HOME': '/home/cltbld', 17:30:50 INFO - 'LANG': 'en_US.UTF-8', 17:30:50 INFO - 'LANGUAGE': 'en_US:en', 17:30:50 INFO - 'LOGNAME': 'cltbld', 17:30:50 INFO - 'MAIL': '/var/mail/cltbld', 17:30:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:30:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:30:50 INFO - 'MOZ_ACCELERATED': '1', 17:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:50 INFO - 'MOZ_NO_REMOTE': '1', 17:30:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:30:50 INFO - 'MOZ_WEBRENDER': '1', 17:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:50 INFO - 'NO_EM_RESTART': '1', 17:30:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:50 INFO - 'PWD': '/builds/slave/test', 17:30:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:30:50 INFO - 'RUST_BACKTRACE': 'full', 17:30:50 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:30:50 INFO - 'SHELL': '/bin/bash', 17:30:50 INFO - 'SHLVL': '1', 17:30:50 INFO - 'TERM': 'linux', 17:30:50 INFO - 'TMOUT': '86400', 17:30:50 INFO - 'USER': 'cltbld', 17:30:50 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', 17:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:50 INFO - '_': '/tools/buildbot/bin/python'} 17:30:50 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/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--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 17:30:50 INFO - mozversion application_buildid: 20171002232253 17:30:50 INFO - mozversion application_changeset: 65dac33a5682f3ec5a675e7f3314b0c1520a13fa 17:30:50 INFO - mozversion application_display_name: Nightly 17:30:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:30:50 INFO - mozversion application_name: Firefox 17:30:50 INFO - mozversion application_remotingname: firefox 17:30:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:30:50 INFO - mozversion application_vendor: Mozilla 17:30:50 INFO - mozversion application_version: 58.0a1 17:30:50 INFO - mozversion platform_buildid: 20171002232253 17:30:50 INFO - mozversion platform_changeset: 65dac33a5682f3ec5a675e7f3314b0c1520a13fa 17:30:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:30:50 INFO - mozversion platform_version: 58.0a1 17:30:50 INFO - using testdate: 1506990650 17:30:50 INFO - actual date: 1506990650 17:30:50 INFO - starting webserver on 'localhost:58008' 17:30:50 INFO - SUITE-START | Running 2 tests 17:30:50 INFO - TEST-START | dromaeo_css 17:30:50 INFO - Initialising browser for dromaeo_css test... 17:30:50 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58008/getInfo.html -profile /tmp/tmpM6Vf00/profile 17:30:50 INFO - TEST-INFO | started process 8504 (/builds/slave/test/build/application/firefox/firefox http://localhost:58008/getInfo.html) 17:30:56 INFO - TEST-INFO | 8504: exit 0 17:30:56 INFO - Browser initialized. 17:30:56 INFO - Running cycle 1/1 for dromaeo_css test... 17:30:56 INFO - Using env: {'DISPLAY': ':0', 17:30:56 INFO - 'HOME': '/home/cltbld', 17:30:56 INFO - 'JSGC_DISABLE_POISONING': '1', 17:30:56 INFO - 'LANG': 'en_US.UTF-8', 17:30:56 INFO - 'LANGUAGE': 'en_US:en', 17:30:56 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:30:56 INFO - 'LOGNAME': 'cltbld', 17:30:56 INFO - 'MAIL': '/var/mail/cltbld', 17:30:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:30:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:30:56 INFO - 'MOZ_ACCELERATED': '1', 17:30:56 INFO - 'MOZ_CRASHREPORTER': '1', 17:30:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:56 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:30:56 INFO - 'MOZ_NO_REMOTE': '1', 17:30:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:30:56 INFO - 'MOZ_WEBRENDER': '1', 17:30:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:56 INFO - 'NO_EM_RESTART': '1', 17:30:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:56 INFO - 'PWD': '/builds/slave/test', 17:30:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:30:56 INFO - 'RUST_BACKTRACE': 'full', 17:30:56 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:30:56 INFO - 'SHELL': '/bin/bash', 17:30:56 INFO - 'SHLVL': '1', 17:30:56 INFO - 'TERM': 'linux', 17:30:56 INFO - 'TMOUT': '86400', 17:30:56 INFO - 'USER': 'cltbld', 17:30:56 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', 17:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:56 INFO - '_': '/tools/buildbot/bin/python'} 17:30:56 INFO - TEST-INFO | started process 8788 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpM6Vf00/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:30:57 INFO - PID 8788 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:30:57 INFO - PID 8788 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:30:57 INFO - PID 8788 | 17:30:57 INFO - PID 8788 | (/builds/slave/test/build/application/firefox/firefox:8846): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:30:57 INFO - PID 8788 | 17:31:07 INFO - PID 8788 | RSS: Main: 193040384 17:31:07 INFO - PID 8788 | 17:34:05 INFO - PID 8788 | Cycle 1(1): loaded http://localhost:58008/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58008/tests/dromaeo/cssquery-ext.html) 17:34:07 INFO - PID 8788 | RSS: Main: 184492032 17:34:07 INFO - PID 8788 | 17:37:09 INFO - PID 8788 | Cycle 1(1): loaded http://localhost:58008/tests/dromaeo/cssquery-ext.html (next: http://localhost:58008/tests/dromaeo/cssquery-jquery.html) 17:37:10 INFO - PID 8788 | RSS: Main: 183730176 17:37:10 INFO - PID 8788 | 17:40:12 INFO - PID 8788 | Cycle 1(1): loaded http://localhost:58008/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58008/tests/dromaeo/cssquery-mootools.html) 17:40:12 INFO - PID 8788 | RSS: Main: 185110528 17:40:12 INFO - PID 8788 | 17:43:14 INFO - PID 8788 | Cycle 1(1): loaded http://localhost:58008/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58008/tests/dromaeo/cssquery-prototype.html) 17:43:15 INFO - PID 8788 | RSS: Main: 184848384 17:43:15 INFO - PID 8788 | 17:46:18 INFO - PID 8788 | Cycle 1(1): loaded http://localhost:58008/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58008/tests/dromaeo/cssquery-yui.html) 17:46:19 INFO - PID 8788 | RSS: Main: 188194816 17:46:19 INFO - PID 8788 | 17:49:21 INFO - PID 8788 | Cycle 1(1): loaded http://localhost:58008/tests/dromaeo/cssquery-yui.html (next: http://localhost:58008/tests/dromaeo/cssquery-dojo.html) 17:49:21 INFO - PID 8788 | RSS: Main: 188743680 17:49:21 INFO - PID 8788 | 17:49:21 INFO - PID 8788 | __start_tp_report 17:49:21 INFO - PID 8788 | _x_x_mozilla_page_load 17:49:21 INFO - PID 8788 | _x_x_mozilla_page_load_details 17:49:21 INFO - PID 8788 | |i|pagename|runs| 17:49:21 INFO - PID 8788 | |0;dojo.html;3139.372125574884;3141.670124636913;3151.180693019814;3189.995151527268;3191.266008817972;6121.326654068049;6253.312135665079;6280;6358.68206589671;6360.777372791953;6107.847303817407;6191.288001879785;6193;6224.502039836813;6248.968755156229;5684.37471878093;5700.543956483481;5770.740316685759;5770.7691692332255;5828.388019257959;5753.626014309057;5783.681897495636;5785;5790.478856902878;5848.561357898154;5249.448807875195;5514.448555144493;5638.40796716343;5698;5766.740496677659;5716.599838011341;5728;5751.108578170349;5767.423257674241;5789.2763404574425;5569.749361278752;5632.802851900205;5701.173329867188;5757.0788673811985;5778.451047150514;6120.387961203888;6131.47882429994;6184.628922264678;6213.906791398133;6256.0615907613765;6661.500387470911;6698.430633396167;6724.899126513106;6729.966350168218;6732.3267673232285;6642.767503137367;6670.533062685614;6735.629540375277;6780.288069752654;6783.253842077367;6072.05883088122;6178.660173690445;6191.87616247677;6280.937190628127;6335.493160547145;6798.422134118557;6841;6915.481338899502;6926.134233220848;6928.722851086001;6515.576487528296;6646.534742567974;6659.300773418819;6674.43267322283;6684.766033188815;6677.632730199885;6682.766103186365;6754.864902701918;6770.390664840188;6783;6439.517036222208;6609.6695165241545;6660.666966651648;6728.293529179366;6739.696713647898;6551.574147680385;6607.669616519251;6611.173603299588;6611.537192396486;6639.236487803964;5126.718030508283;5197.402298735618;5201.843944681666;5227.660202086852;5276.314079169683;4648.744319062416;4659.184642687516;4659.767011649404;4682.672212945129;4712.363830882787;5680.602357834912;5706.600537962386;5776.277965254343;5783.074708046693;5805.709714514257;6498.122753428322;6614.437772789367;6634;6744.291849355845;6780.627065511346;5661;5741.827745167651;5744.540436765049;5744.741486633112;5778.913316300259;5632.633878797865;5695.45893140151;5721.5708821838525;5722.914156287659;5730.713464326767;6498.187726534184;6502.544821862424;6511.479081673455;6587.0448784926875;6596;6689.397954184147;6706.295838936939;6723.159605049369;6774.81440747877;6806.625635589991;6341.334159913143;6474.5791523550815;6485.6108633482145;6500;6552.967235163794;6432.292447830829;6569.047488114101;6570.211574611078;6577.835554110994;6580.736770529125;6723.4285085766305;6767.560108592926;6768.526203165928;6792.456603471809;6813.04617353561;6489.675516224075;6530.967345163243;6553.017047442731;6582.243042050225;6587.374199451045;6490.610563366213;6491.058796474579;6530.934690653223;6547.901781473186;6558.1802274715665;6284.3087260402235;6357.4278314950025;6371.171747672773;6383.489320854228;6400.039994000937;6432.35676432353;6449.258335291313;6519.771807986698;6526.053722210158;6553.672316384257;6211.409916057967;6233.438990490878;6254.249490061222;6317.936820631735;6336.176297081534 17:49:21 INFO - PID 8788 | |1;ext.html;809.2959125560761;823.9134890836466;829.4401279136583;833.3916241143969;834.1283358889959;38431.23137537251;39130.804345978264;41407.58592414075;41644.375334369935;41852.372214416806;37707.24585508288;38246.426303605396;38248;38427.61572384282;38691.61308386915;7034.085568876052;7091.822704432405;7124.465665075114;7175.820604484878;7177.712891484361;7055.294470552929;7073.540219885716;7075.115610548682;7128.465365097639;7146.142462904459;7016.824579385505;7069.045678833369;7071.0807595012575;7104.4671649626225;7118.608476533788;7742.961285193566;7901.920980790176;7911.090224624184;7928.643211055516;7971.043474783005;7834.686612535492;7853;7906.407019473505;7909.288164065261;7910.4462687611895;37218.25563488742;37857.621423785684;38075.80962095199;38375.232495349934;39645.20709585821;3493.1441796759705;3569.94645080324;3572.3391172633146;3574.535310409656;3576.195356044895;30865.228369290722;30950.53574196389;30987.535186972214;31040.53439198414;31081.844590777127;5210.947890521122;5226.451222621608;5229.529342359206;5242.842714718565;5270.156774915995;185501;187797;187888;188226;188670;43235.13529729388;43597.12805743868;48466;48992.7550362253;49449.25826112611;50076;51155.95376184911;51343.229851552256;51716.22425663618;51770;15891.523254302389;16120.193990300439;16122.919385402998;16175.514734557983;16175.838241617434;13044.152130111512;13113.934430327787;13201.60395188146;13270.203787772765;13287.269200193889;13671.589852304447;13749.106308089942;13851.099678520864;13973.231472269123;14088.225147616775;5490.972545137249;5655.745491452894;5665.3768085510565;5707.914381284284;5838.591298609056;1010.2826992835022;1016.974575635615;1021.1575450253571;1032.2722480651123;1037.2946396450486;22949.655755163687;22960;23214.071437142662;23301;23601.763982359957;1124.516457923101;1131.1798945764256;1132.416805345248;1134.3194083549804;1140.6349968010322;12282.263064216175;12341.75316493683;12410.875891240972;12415.255084694947;12492;219158;219654;221151;221305;222288;51920;51927.740361297954;52311.47688523142;52646.47353526492;52703.47296527062;1409.8449170591227;1472.0725942656272;1478.209158100411;1478.4160256698544;1485.8439863814576;22799.202027928943;22897.771022289562;23156.18953336625;23247;23296.06815727352;36789.26421471502;37041.25917481689;37095;37202;37225.6277437233;188049;189693;190602.04698976417;190877;192158;29796.85101574478;34289.82855085808;35001.824990874884;35355;35717.28565428729;43706.34440483332;43858.56141438545;44083.55916440795;44129.77935110432;44641.55358446504;31730.682693172774;31927.521087183246;31986.52020219745;32050.19874503156;32094.19764505906;30954.0713778587;31100.37799243957;31178.532322015642;31324.53013204758;31552.684473154975;36091.81954090213;36133.457998129525;36421;36424.27151457009;36435.27129457343;30483.2379190522;30605.23486912756;30641.387172256877;30825;31934.201644959063;23233.651495227925;23438.41403964846;23650.763492364855;23674.763252367255;23677.17129900445 17:49:21 INFO - PID 8788 | |2;jquery.html;581.2298704216911;582.6678793087939;586.4986230679533;587.5094296262621;594.5451729426987;22324.776752232472;23132.0747170113;23148.42128946773;23161.884190579025;23216.071357145716;23608;24497.51004979899;24542.509149816993;25053.749462505413;25198;6306.905396419046;6452.709628066725;6459.741610335581;6519.771807986722;6560.475161987029;6526.510511711617;6564.442022428099;6629.403353698166;6638.50211234157;6648.401643852052;6309.305976342599;6513.576617519846;6545.5418120731565;6566.441852442548;6599.769008084718;6531.575447595915;6543.509236807211;6553.344665533408;6579.276279609263;6585.2097748270035;5831.125331200312;6078.996965500687;6087.939120608826;6100.877982440371;6127.969360153215;27197.184084477496;27708.168754937386;28026.439471210666;28055.859720701468;28087.157385278475;2041.2447394463995;2060.093558834108;2083.98958005209;2086.0612724274138;2091.801278878504;18282.451526454093;18940.43178704641;18947.33684321042;19127.61744765097;19144.329948451876;2415.5048215115858;2471.1104002559064;2517.609770485578;2522.911698090558;2537.073968001666;124252;128560.35719821435;130710;133332;140607.29696351453;14272.530333498487;14747.009153764318;14800.777988330183;14875.70248595022;14893.32980015902;23103.422414439774;23309.883450582638;23351.7664823353;23372.76627233706;23379.064837406313;9906.80186396268;10159.390436573829;10191.9490402549;10207.948960255151;10264.897351026544;9306.72079837606;9349;9381.437113773196;9384.390014649027;9522.714318570454;8913.153250441197;8991.145841145211;9060.365774395728;9063.68277110298;9160.358774885828;2723.6595425571804;2820.4782115308735;2838.176928690698;2839.3185635447344;2864.770818334528;966.6954909203579;969.6848524229654;973.1679414100984;983.4345251480428;999.2905037423511;18787.436376908714;19029.904850475938;19190.424287271126;19628.607427851362;19665.311714089938;1004.2116938203621;1040.4693606260862;1044.2404548336078;1051.7370657335666;1056.1128651932413;10718.839217411745;10746;10805.40570268627;10936.726581835359;10984.231103822816;437623;446161;446208;448792;452068;114465.4276728611;114567.42716286532;115487;116162;116440;1149.201305092969;1162.1864694713734;1183.1658680630135;1183.674489515373;1190.5356910804794;17166.914165429094;17206.569835754206;17303.7404438931;17489.212985415943;18053.277868885096;53337;53374.199387010005;53789.19316210338;54971.72514137564;55220.17169742537;67154.32845671481;67614.66192669005;67630.3236967624;67882;68626;14289;14415.918806089796;14458.349374278394;14467.638309042359;14577.344019518934;22144.778552214273;22147.446313842283;22344.664830027887;22517.43706407353;22866.771332286466;17028.233729481955;17130.14349282556;17288.222030008932;17314.134293285537;18168.545786354916;15904.443344482888;16265.51203463898;16437.91781041135;16559.42042028523;16767.496975090766;17374.826251737828;18014.909925450287;18069.63860722752;18136.546586335448;18199.18103685364;14167.149971001772;14227.786583201467;14253.287335633384;14373.7843932339;14378.065425747292;16663.166841658112;17688.469345919642;17763.822361776733;17832.643347133246;17998.460046198634 17:49:21 INFO - PID 8788 | |3;mootools.html;1286.4275397448134;1340.0485655184818;1355.7695191817397;1360.0751488987485;1366.4397596985239;13995.230262335568;14551.563453096398;14593.416263349452;14628.268586570655;14669.926650366746;14373.78439323411;14391.64020899478;14424.134551926865;14455.710885782279;14558.854411455883;2615.2546524240597;2734.5624700047997;2736.1107640016976;2739.301478123086;2742.478929003483;2647.5234457797587;2664.960025599608;2679.2498100531884;2682;2715.592661100831;2427.8664673442954;2529.380301826055;2530.708968468622;2534.974650253492;2637.551616225246;2700.6894207166133;2737.2335745991154;2737.3704048069058;2739.301478123086;2741.575055866344;2599.3371690218873;2636.36727185476;2643.418447941469;2645.4180080382475;2651.992242947687;13731.45074197031;14064.367103480368;14200.147991120566;14209;14240.50158244467;7675.539467631904;7959.403044771608;7970.4819186752675;7980.640871160812;7980.880286795703;4941.925871111936;5168.302279192299;5174.172132458826;5184.092783762826;5192;1866.1042699504314;1890.6407782521414;1906.1041310584003;1955.7555305586802;1965.1255191439816;23369.766302336928;23867.09063274951;23880.522389552287;23887.88056059726;23900.641490377657;6553.836154096186;6559.180102487189;6578.572392794453;6674.43267322283;6760.121184246016;16066.839331606769;16080.195990200444;16123.758143627854;16136.596585085466;16186.433474828324;7511.286427789351;7618.200088990687;7641.3886889048745;7649.426293028045;7649.541027538366;6639.336066393322;7028.859422811515;7031.156261248683;7043.520860619225;7052.9647351762915;4881.658283920092;5094.388673359146;5111.284420181178;5117.437081920985;5154.742262886841;1558.75059990401;1564.608847788053;1569.5246468319742;1571.9764203536956;1575.4845062437544;831.3307787231216;836.8744688296682;853.2107800284712;856.6102423397339;867.8481265778463;7107.147142342952;7812.453128280966;7820.726274580363;7946.086200086948;8008.119106898236;720.5172534401963;723.7973367457121;729.7555318968099;731.2541207967843;744.8100734312824;4324.697271190987;4407.140607581491;4436.534163912743;4437.489688685778;4493.550644935481;18731.531711707317;20258.59482810365;20279.4930126748;20301.390958271277;20353.898230508752;18337.266509339475;19745.50636234077;19967.301144460165;20015.299464518674;20150.89924550368;10291;10337.423465702226;11033;11049.337039777478;11169.83245251322;7471.738489152854;7564.130125035691;7621.580813055335;7663.233676632292;7709.113451953097;17095.48713538596;17132.828671713123;17233.74149387735;17413;17748.201330940396;17443.389481368093;18095.02409307699;18098.366557170437;18370.540736481697;18701.158447869606;10110.545025474019;10623.575056997634;10685.732856678645;10735.409552474688;10772.838407423738;13006.674833129247;13081.411336490071;13100.213987160801;13164.341782911008;13223.3388330585;10544;10617.734556635836;10748.086412654699;10854.29447085937;10952.452377381258;10328.948355258175;10603.840942386025;10728.302660327054;10836.133109351076;10970.177236707119;9215.631374744935;9460.952695236756;9580.856287155837;9630.518474076409;9697.272704547046;6718.8320291993095;6739.528233023641;7025.754098606524;7032.978006340842;7118.750843720445;6749.730010799513;6776.86446271062;6839.487038472035;6917.515773895779;6948.270431604608 17:49:21 INFO - PID 8788 | |4;prototype.html;506.8986202759448;517.4670089807497;539.887831068;549.1405949688736;552.872839246973;6269.7492100315985;6296.685165741712;6310.148130002448;6318.304986451487;6318.873622527547;6181.56729028967;6191.318954914956;6209.534284928626;6261.3738626137365;6272.968635156818;5195.870103247411;5217.843464696065;5220.243064755608;5220.608454365928;5238.68567885928;5188.844334669947;5201.973990130044;5229.973850130763;5247.763850626731;5251.396089449705;5080.466551012146;5095.210242412413;5115.923261151086;5118.462561431052;5132.794688212467;5070.996956202298;5083.517065878736;5104.93250233656;5123.847134394769;5193.662411943231;4704.153252414564;4732.952670473285;4735.526447355272;4740.549647783455;4775.80896764129;6064.3935606439445;6069.573650192201;6094.481969032638;6167.4757645600175;6176.938230617681;5829.329627092896;5938.2280303560265;5960.254968128984;5962.731677074542;5982.910256346115;6521;6558.491207204171;6574.178227721535;6587.571807832475;6625.86748265032;4940.827071052532;5116.513931176532;5132.8460146195675;5145.125328694131;5206.869828254286;6533.444657204143;6616.437602803768;6850.869606514919;6910.170779506491;6937.369718116239;5399.163129714901;5433.157860531624;5475.302656176518;5481.561475081984;5482.122860342326;5470.425605311465;5496.587755918322;5531.25328080712;5544.556435485232;5571.5178013741415;5696.544276457874;5851;5993.970030149909;6006.939930600638;6056.818295451144;6575.145231120019;6632.801015969529;6636.369544893228;6659.86680266392;6676.899846502306;1914.8851068935908;1915.837153841939;1942.3975220443192;1951.7462729845313;1958.676818324989;1400.390829988958;1451.528253317676;1463.6706740983193;1469.889758268134;1478.3716920308825;5573.665580065209;5619.353774315924;5620.426280641427;5637.67377247426;5638;6385.425311721874;6607.603543787388;6624.072629831828;6644.634545100066;6667.966660166668;5536.8062117826485;5545.473180047889;5580.218769372291;5676.8864622707315;5682;3454.946241396348;3486.2156014896445;3504.807235602066;3518.437050071976;3526.7354948378975;6383.3616638336725;6416.518761092937;6421.357864213542;6428.935710642833;6449.451796597342;6536.901946470807;6677.499187560855;6694.196696396366;6746.561473504305;6752.800343735296;6369.299377068519;6431.324710905287;6460.838479038061;6481.773137940244;6513.706883190268;6407.839804004937;6557.311482294291;6562.8359291018105;6645.700943457653;6652.168478940132;6504;6519.869602607826;6542.967285163544;6615.37153970372;6633.336666333328;6181.81454556364;6184.288806787277;6325.240971083499;6411.166548348685;6420.871582568416;5027.723475208899;5325.520703136736;5326.627136100436;5365.168398898177;5403.216533602685;6473.838154046187;6573.244076931214;6579.190722551337;6600.8349791255605;6690.230623478362;6295.26880107974;6338.58799178052;6431.324710905287;6461.289258181502;6509.7396104155305;6344.256888435845;6362;6390.5526613136635;6400.871982560229;6418.326075761977;6265.248170219603;6284.465820405317;6295.653739044396;6318.515148939903;6345.809625711236;6416.165898433173;6431.228252609717;6450.548461607643;6487.870242595027;6535.901961470487;6147.538934579835;6205.47253483459;6242.596600309004;6246.593971391845;6248.593841400295 17:49:21 INFO - PID 8788 | |5;yui.html;891.3849443883723;915.3729695158817;936.2369668719988;938.7559234599003;943.8914524829643;19796.60406791865;20478.89760551197;20552.28067017653;20568.588628227426;23106.653400198968;20544.589108217828;20634.587308253864;20719;20784.48038799031;21001.054952527134;971.0629242780715;983.8671779309809;984.8178087053882;993.2401712689798;995.9950200248988;1001.6494227020521;1001.919846412289;1008.8335424654923;1011.8886922438526;1013.807376598444;1049.968500944969;1060.4114716332433;1065.7016035510069;1067.1782727300013;1068.2041878800305;1013.9290249682525;1032.9845052324222;1037.4708898461763;1041.7187359412917;1050.4327663061938;764.9158592554815;766.9041369828772;767.2059418501824;767.5893397032617;767.6392095715005;18625.813741862545;18626.720599191023;18638.763348398144;18743.718844217346;18832.62334753311;21058.684119738216;22087.226947056937;22088.668669969964;22129.557408851895;22317.888410557844;16717.91641041787;17381.21784519687;17415;17480.47558573245;17518.38685646009;3582.247727977128;3654.8903532894055;3682.650148235913;3684.926301473982;3690.944635830465;123926;125519;125718.37140814328;126885;127412.72587274101;15882.841171588367;16096.919515402347;16478.34086636545;16478.83521164773;16553.25510352037;24541.38646533851;25070.997160113755;25228.243152705447;25282.873585631954;25559.872200638878;8038.598070096472;8145.714899978472;8191.672333106609;8222.712205072794;8226.136255693185;3956.0703234739813;4003.079291762853;4010.2781499329817;4014.578469260686;4026.214888096793;5412.810551630674;5466.070767969455;5513.283273174462;5526.115821468546;5533.584981126432;944.7118628818276;958.1807554540907;960.7790208252142;964.363520076746;996.4121168510613;671.4930227678071;685.4619123987645;706.1737766812842;707.9221285658573;711.121764620693;11629.47667354971;11901.226420282826;11924.821127683092;12802.935985320013;12881.613551593467;653.183520599251;654.5483616304734;670.0518765946229;673.8787018336747;674.0125715826338;5529.806456773994;5562.499375056183;5600.355959064678;5620.185073164368;5739.8565035874435;119953;122757;124053.37973310075;124251.37874310752;124314;109630;109662.45168774265;110991.44504277427;113663;114574.42712786549;94304.52847735854;94998.52500737591;95161.52419237998;98311.50844245733;98969.50515247378;10286.032985419322;10487.475626218811;10572.524236409226;10721;10721.41032243234;43160;43442.131157377305;44412.77793611011;44472.33291500695;44729.77635111804;57381.71309143428;58526.70736646289;59111.40888591231;60286.698566506886;60506;14898.553043408716;15113.471028514388;15117.622059448602;15188;15252.313645886185;15236.619084522976;15416.845831541541;15498.225088745292;15632.921835391206;15674.686506270038;15137;15156.924215378853;15214.771778423112;15274.389024438899;15280.15959122259;16340.754888676916;16596.336146554004;16612;16629.5842603931;16920.830791692417;15608.609784755472;15756.527304180892;15814;15918.28367723432;16109.194540272705;10066.144377727975;10178.18574514052;10187.337823041478;10573.2598718089;10603.575856965635;11595.072394208612;11703.356315402734;11707.356095414494;11896.643100706993;11951.76096478083 17:49:21 INFO - PID 8788 | __end_tp_report 17:49:21 INFO - PID 8788 | __start_cc_report 17:49:21 INFO - PID 8788 | _x_x_mozilla_cycle_collect,291 17:49:21 INFO - PID 8788 | __end_cc_report 17:49:21 INFO - PID 8788 | __startTimestamp1506991761764__endTimestamp 17:49:21 INFO - PID 8788 | 17:49:21 INFO - PID 8788 | ------- Summary: start ------- 17:49:21 INFO - PID 8788 | Number of tests: 6 17:49:21 INFO - PID 8788 | 17:49:21 INFO - PID 8788 | [#0] dojo.html Cycles:165 Average:6136.00 Median:6360.78 stddev:735.52 (11.6%) stddev-sans-first:699.20 17:49:21 INFO - PID 8788 | Values: 3139.4 3141.7 3151.2 3190.0 3191.3 6121.3 6253.3 6280.0 6358.7 6360.8 6107.8 6191.3 6193.0 6224.5 6249.0 5684.4 5700.5 5770.7 5770.8 5828.4 5753.6 5783.7 5785.0 5790.5 5848.6 5249.4 5514.4 5638.4 5698.0 5766.7 5716.6 5728.0 5751.1 5767.4 5789.3 5569.7 5632.8 5701.2 5757.1 5778.5 6120.4 6131.5 6184.6 6213.9 6256.1 6661.5 6698.4 6724.9 6730.0 6732.3 6642.8 6670.5 6735.6 6780.3 6783.3 6072.1 6178.7 6191.9 6280.9 6335.5 6798.4 6841.0 6915.5 6926.1 6928.7 6515.6 6646.5 6659.3 6674.4 6684.8 6677.6 6682.8 6754.9 6770.4 6783.0 6439.5 6609.7 6660.7 6728.3 6739.7 6551.6 6607.7 6611.2 6611.5 6639.2 5126.7 5197.4 5201.8 5227.7 5276.3 4648.7 4659.2 4659.8 4682.7 4712.4 5680.6 5706.6 5776.3 5783.1 5805.7 6498.1 6614.4 6634.0 6744.3 6780.6 5661.0 5741.8 5744.5 5744.7 5778.9 5632.6 5695.5 5721.6 5722.9 5730.7 6498.2 6502.5 6511.5 6587.0 6596.0 6689.4 6706.3 6723.2 6774.8 6806.6 6341.3 6474.6 6485.6 6500.0 6553.0 6432.3 6569.0 6570.2 6577.8 6580.7 6723.4 6767.6 6768.5 6792.5 6813.0 6489.7 6531.0 6553.0 6582.2 6587.4 6490.6 6491.1 6530.9 6547.9 6558.2 6284.3 6357.4 6371.2 6383.5 6400.0 6432.4 6449.3 6519.8 6526.1 6553.7 6211.4 6233.4 6254.2 6317.9 6336.2 17:49:21 INFO - PID 8788 | 17:49:21 INFO - PID 8788 | [#1] ext.html Cycles:180 Average:36770.61 Median:19643.62 stddev:51793.10 (263.7%) stddev-sans-first:51868.00 17:49:21 INFO - PID 8788 | Values: 809.3 823.9 829.4 833.4 834.1 38431.2 39130.8 41407.6 41644.4 41852.4 37707.2 38246.4 38248.0 38427.6 38691.6 7034.1 7091.8 7124.5 7175.8 7177.7 7055.3 7073.5 7075.1 7128.5 7146.1 7016.8 7069.0 7071.1 7104.5 7118.6 7743.0 7901.9 7911.1 7928.6 7971.0 7834.7 7853.0 7906.4 7909.3 7910.4 37218.3 37857.6 38075.8 38375.2 39645.2 3493.1 3569.9 3572.3 3574.5 3576.2 30865.2 30950.5 30987.5 31040.5 31081.8 5210.9 5226.5 5229.5 5242.8 5270.2 185501.0 187797.0 187888.0 188226.0 188670.0 43235.1 43597.1 48466.0 48992.8 49449.3 50076.0 51156.0 51343.2 51716.2 51770.0 15891.5 16120.2 16122.9 16175.5 16175.8 13044.2 13113.9 13201.6 13270.2 13287.3 13671.6 13749.1 13851.1 13973.2 14088.2 5491.0 5655.7 5665.4 5707.9 5838.6 1010.3 1017.0 1021.2 1032.3 1037.3 22949.7 22960.0 23214.1 23301.0 23601.8 1124.5 1131.2 1132.4 1134.3 1140.6 12282.3 12341.8 12410.9 12415.3 12492.0 219158.0 219654.0 221151.0 221305.0 222288.0 51920.0 51927.7 52311.5 52646.5 52703.5 1409.8 1472.1 1478.2 1478.4 1485.8 22799.2 22897.8 23156.2 23247.0 23296.1 36789.3 37041.3 37095.0 37202.0 37225.6 188049.0 189693.0 190602.0 190877.0 192158.0 29796.9 34289.8 35001.8 35355.0 35717.3 43706.3 43858.6 44083.6 44129.8 44641.6 31730.7 31927.5 31986.5 32050.2 32094.2 30954.1 31100.4 31178.5 31324.5 31552.7 36091.8 36133.5 36421.0 36424.3 36435.3 30483.2 30605.2 30641.4 30825.0 31934.2 23233.7 23438.4 23650.8 23674.8 23677.2 17:49:21 INFO - PID 8788 | 17:49:21 INFO - PID 8788 | [#2] jquery.html Cycles:180 Average:32977.88 Median:23117.75 stddev:75588.16 (327.0%) stddev-sans-first:75761.07 17:49:21 INFO - PID 8788 | Values: 581.2 582.7 586.5 587.5 594.5 22324.8 23132.1 23148.4 23161.9 23216.1 23608.0 24497.5 24542.5 25053.7 25198.0 6306.9 6452.7 6459.7 6519.8 6560.5 6526.5 6564.4 6629.4 6638.5 6648.4 6309.3 6513.6 6545.5 6566.4 6599.8 6531.6 6543.5 6553.3 6579.3 6585.2 5831.1 6079.0 6087.9 6100.9 6128.0 27197.2 27708.2 28026.4 28055.9 28087.2 2041.2 2060.1 2084.0 2086.1 2091.8 18282.5 18940.4 18947.3 19127.6 19144.3 2415.5 2471.1 2517.6 2522.9 2537.1 124252.0 128560.4 130710.0 133332.0 140607.3 14272.5 14747.0 14800.8 14875.7 14893.3 23103.4 23309.9 23351.8 23372.8 23379.1 9906.8 10159.4 10191.9 10207.9 10264.9 9306.7 9349.0 9381.4 9384.4 9522.7 8913.2 8991.1 9060.4 9063.7 9160.4 2723.7 2820.5 2838.2 2839.3 2864.8 966.7 969.7 973.2 983.4 999.3 18787.4 19029.9 19190.4 19628.6 19665.3 1004.2 1040.5 1044.2 1051.7 1056.1 10718.8 10746.0 10805.4 10936.7 10984.2 437623.0 446161.0 446208.0 448792.0 452068.0 114465.4 114567.4 115487.0 116162.0 116440.0 1149.2 1162.2 1183.2 1183.7 1190.5 17166.9 17206.6 17303.7 17489.2 18053.3 53337.0 53374.2 53789.2 54971.7 55220.2 67154.3 67614.7 67630.3 67882.0 68626.0 14289.0 14415.9 14458.3 14467.6 14577.3 22144.8 22147.4 22344.7 22517.4 22866.8 17028.2 17130.1 17288.2 17314.1 18168.5 15904.4 16265.5 16437.9 16559.4 16767.5 17374.8 18014.9 18069.6 18136.5 18199.2 14167.1 14227.8 14253.3 14373.8 14378.1 16663.2 17688.5 17763.8 17832.6 17998.5 17:49:21 INFO - PID 8788 | 17:49:21 INFO - PID 8788 | [#3] mootools.html Cycles:180 Average:8777.58 Median:2625.81 stddev:6134.19 (233.6%) stddev-sans-first:6125.58 17:49:21 INFO - PID 8788 | Values: 1286.4 1340.0 1355.8 1360.1 1366.4 13995.2 14551.6 14593.4 14628.3 14669.9 14373.8 14391.6 14424.1 14455.7 14558.9 2615.3 2734.6 2736.1 2739.3 2742.5 2647.5 2665.0 2679.2 2682.0 2715.6 2427.9 2529.4 2530.7 2535.0 2637.6 2700.7 2737.2 2737.4 2739.3 2741.6 2599.3 2636.4 2643.4 2645.4 2652.0 13731.5 14064.4 14200.1 14209.0 14240.5 7675.5 7959.4 7970.5 7980.6 7980.9 4941.9 5168.3 5174.2 5184.1 5192.0 1866.1 1890.6 1906.1 1955.8 1965.1 23369.8 23867.1 23880.5 23887.9 23900.6 6553.8 6559.2 6578.6 6674.4 6760.1 16066.8 16080.2 16123.8 16136.6 16186.4 7511.3 7618.2 7641.4 7649.4 7649.5 6639.3 7028.9 7031.2 7043.5 7053.0 4881.7 5094.4 5111.3 5117.4 5154.7 1558.8 1564.6 1569.5 1572.0 1575.5 831.3 836.9 853.2 856.6 867.8 7107.1 7812.5 7820.7 7946.1 8008.1 720.5 723.8 729.8 731.3 744.8 4324.7 4407.1 4436.5 4437.5 4493.6 18731.5 20258.6 20279.5 20301.4 20353.9 18337.3 19745.5 19967.3 20015.3 20150.9 10291.0 10337.4 11033.0 11049.3 11169.8 7471.7 7564.1 7621.6 7663.2 7709.1 17095.5 17132.8 17233.7 17413.0 17748.2 17443.4 18095.0 18098.4 18370.5 18701.2 10110.5 10623.6 10685.7 10735.4 10772.8 13006.7 13081.4 13100.2 13164.3 13223.3 10544.0 10617.7 10748.1 10854.3 10952.5 10328.9 10603.8 10728.3 10836.1 10970.2 9215.6 9461.0 9580.9 9630.5 9697.3 6718.8 6739.5 7025.8 7033.0 7118.8 6749.7 6776.9 6839.5 6917.5 6948.3 17:49:21 INFO - PID 8788 | 17:49:21 INFO - PID 8788 | [#4] prototype.html Cycles:180 Average:5550.79 Median:6179.25 stddev:1467.93 (23.8%) stddev-sans-first:1422.39 17:49:21 INFO - PID 8788 | Values: 506.9 517.5 539.9 549.1 552.9 6269.7 6296.7 6310.1 6318.3 6318.9 6181.6 6191.3 6209.5 6261.4 6273.0 5195.9 5217.8 5220.2 5220.6 5238.7 5188.8 5202.0 5230.0 5247.8 5251.4 5080.5 5095.2 5115.9 5118.5 5132.8 5071.0 5083.5 5104.9 5123.8 5193.7 4704.2 4733.0 4735.5 4740.5 4775.8 6064.4 6069.6 6094.5 6167.5 6176.9 5829.3 5938.2 5960.3 5962.7 5982.9 6521.0 6558.5 6574.2 6587.6 6625.9 4940.8 5116.5 5132.8 5145.1 5206.9 6533.4 6616.4 6850.9 6910.2 6937.4 5399.2 5433.2 5475.3 5481.6 5482.1 5470.4 5496.6 5531.3 5544.6 5571.5 5696.5 5851.0 5994.0 6006.9 6056.8 6575.1 6632.8 6636.4 6659.9 6676.9 1914.9 1915.8 1942.4 1951.7 1958.7 1400.4 1451.5 1463.7 1469.9 1478.4 5573.7 5619.4 5620.4 5637.7 5638.0 6385.4 6607.6 6624.1 6644.6 6668.0 5536.8 5545.5 5580.2 5676.9 5682.0 3454.9 3486.2 3504.8 3518.4 3526.7 6383.4 6416.5 6421.4 6428.9 6449.5 6536.9 6677.5 6694.2 6746.6 6752.8 6369.3 6431.3 6460.8 6481.8 6513.7 6407.8 6557.3 6562.8 6645.7 6652.2 6504.0 6519.9 6543.0 6615.4 6633.3 6181.8 6184.3 6325.2 6411.2 6420.9 5027.7 5325.5 5326.6 5365.2 5403.2 6473.8 6573.2 6579.2 6600.8 6690.2 6295.3 6338.6 6431.3 6461.3 6509.7 6344.3 6362.0 6390.6 6400.9 6418.3 6265.2 6284.5 6295.7 6318.5 6345.8 6416.2 6431.2 6450.5 6487.9 6535.9 6147.5 6205.5 6242.6 6246.6 6248.6 17:49:21 INFO - PID 8788 | 17:49:21 INFO - PID 8788 | [#5] yui.html Cycles:180 Average:23872.68 Median:20137.75 stddev:34449.85 (171.1%) stddev-sans-first:34503.28 17:49:21 INFO - PID 8788 | Values: 891.4 915.4 936.2 938.8 943.9 19796.6 20478.9 20552.3 20568.6 23106.7 20544.6 20634.6 20719.0 20784.5 21001.1 971.1 983.9 984.8 993.2 996.0 1001.6 1001.9 1008.8 1011.9 1013.8 1050.0 1060.4 1065.7 1067.2 1068.2 1013.9 1033.0 1037.5 1041.7 1050.4 764.9 766.9 767.2 767.6 767.6 18625.8 18626.7 18638.8 18743.7 18832.6 21058.7 22087.2 22088.7 22129.6 22317.9 16717.9 17381.2 17415.0 17480.5 17518.4 3582.2 3654.9 3682.7 3684.9 3690.9 123926.0 125519.0 125718.4 126885.0 127412.7 15882.8 16096.9 16478.3 16478.8 16553.3 24541.4 25071.0 25228.2 25282.9 25559.9 8038.6 8145.7 8191.7 8222.7 8226.1 3956.1 4003.1 4010.3 4014.6 4026.2 5412.8 5466.1 5513.3 5526.1 5533.6 944.7 958.2 960.8 964.4 996.4 671.5 685.5 706.2 707.9 711.1 11629.5 11901.2 11924.8 12802.9 12881.6 653.2 654.5 670.1 673.9 674.0 5529.8 5562.5 5600.4 5620.2 5739.9 119953.0 122757.0 124053.4 124251.4 124314.0 109630.0 109662.5 110991.4 113663.0 114574.4 94304.5 94998.5 95161.5 98311.5 98969.5 10286.0 10487.5 10572.5 10721.0 10721.4 43160.0 43442.1 44412.8 44472.3 44729.8 57381.7 58526.7 59111.4 60286.7 60506.0 14898.6 15113.5 15117.6 15188.0 15252.3 15236.6 15416.8 15498.2 15632.9 15674.7 15137.0 15156.9 15214.8 15274.4 15280.2 16340.8 16596.3 16612.0 16629.6 16920.8 15608.6 15756.5 15814.0 15918.3 16109.2 10066.1 10178.2 10187.3 10573.3 10603.6 11595.1 11703.4 11707.4 11896.6 11951.8 17:49:21 INFO - PID 8788 | -------- Summary: end -------- 17:49:21 INFO - PID 8788 | 17:49:22 INFO - TEST-INFO | 8788: exit 0 17:49:22 INFO - TEST-OK | dromaeo_css | took 1112040ms 17:49:22 INFO - TEST-START | kraken 17:49:22 INFO - Initialising browser for kraken test... 17:49:22 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58008/getInfo.html -profile /tmp/tmp00nxcK/profile 17:49:22 INFO - TEST-INFO | started process 9190 (/builds/slave/test/build/application/firefox/firefox http://localhost:58008/getInfo.html) 17:49:28 INFO - TEST-INFO | 9190: exit 0 17:49:28 INFO - Browser initialized. 17:49:28 INFO - Running cycle 1/1 for kraken test... 17:49:28 INFO - Using env: {'DISPLAY': ':0', 17:49:28 INFO - 'HOME': '/home/cltbld', 17:49:28 INFO - 'JSGC_DISABLE_POISONING': '1', 17:49:28 INFO - 'LANG': 'en_US.UTF-8', 17:49:28 INFO - 'LANGUAGE': 'en_US:en', 17:49:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:49:28 INFO - 'LOGNAME': 'cltbld', 17:49:28 INFO - 'MAIL': '/var/mail/cltbld', 17:49:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:49:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:49:28 INFO - 'MOZ_ACCELERATED': '1', 17:49:28 INFO - 'MOZ_CRASHREPORTER': '1', 17:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:49:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:49:28 INFO - 'MOZ_NO_REMOTE': '1', 17:49:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:49:28 INFO - 'MOZ_WEBRENDER': '1', 17:49:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:49:28 INFO - 'NO_EM_RESTART': '1', 17:49:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:49:28 INFO - 'PWD': '/builds/slave/test', 17:49:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:49:28 INFO - 'RUST_BACKTRACE': 'full', 17:49:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:49:28 INFO - 'SHELL': '/bin/bash', 17:49:28 INFO - 'SHLVL': '1', 17:49:28 INFO - 'TERM': 'linux', 17:49:28 INFO - 'TMOUT': '86400', 17:49:28 INFO - 'USER': 'cltbld', 17:49:28 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801', 17:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:49:28 INFO - '_': '/tools/buildbot/bin/python'} 17:49:28 INFO - TEST-INFO | started process 9471 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp00nxcK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:49:28 INFO - PID 9471 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:49:29 INFO - PID 9471 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 17:49:29 INFO - PID 9471 | 17:49:29 INFO - PID 9471 | (/builds/slave/test/build/application/firefox/firefox:9529): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:49:29 INFO - PID 9471 | 17:49:39 INFO - PID 9471 | RSS: Main: 185421824 17:49:39 INFO - PID 9471 | 17:49:42 INFO - PID 9471 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58008/tests/kraken/driver.html?testName=audio-beat-detection) 17:49:43 INFO - PID 9471 | RSS: Main: 186093568 17:49:43 INFO - PID 9471 | 17:49:46 INFO - PID 9471 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58008/tests/kraken/driver.html?testName=audio-dft) 17:49:46 INFO - PID 9471 | RSS: Main: 186847232 17:49:46 INFO - PID 9471 | 17:49:49 INFO - PID 9471 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58008/tests/kraken/driver.html?testName=audio-fft) 17:49:50 INFO - PID 9471 | RSS: Main: 183500800 17:49:50 INFO - PID 9471 | 17:49:52 INFO - PID 9471 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58008/tests/kraken/driver.html?testName=audio-oscillator) 17:49:53 INFO - PID 9471 | RSS: Main: 183775232 17:49:53 INFO - PID 9471 | 17:49:55 INFO - PID 9471 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58008/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:49:55 INFO - PID 9471 | RSS: Main: 183746560 17:49:55 INFO - PID 9471 | 17:49:59 INFO - PID 9471 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58008/tests/kraken/driver.html?testName=imaging-darkroom) 17:49:59 INFO - PID 9471 | RSS: Main: 186241024 17:49:59 INFO - PID 9471 | 17:50:03 INFO - PID 9471 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58008/tests/kraken/driver.html?testName=imaging-desaturate) 17:50:03 INFO - PID 9471 | RSS: Main: 186122240 17:50:03 INFO - PID 9471 | 17:50:07 INFO - PID 9471 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58008/tests/kraken/driver.html?testName=json-parse-financial) 17:50:07 INFO - PID 9471 | RSS: Main: 186658816 17:50:07 INFO - PID 9471 | 17:50:09 INFO - PID 9471 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58008/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:50:09 INFO - PID 9471 | RSS: Main: 186458112 17:50:09 INFO - PID 9471 | 17:50:11 INFO - PID 9471 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58008/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:50:12 INFO - PID 9471 | RSS: Main: 186281984 17:50:12 INFO - PID 9471 | 17:50:14 INFO - PID 9471 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58008/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:50:15 INFO - PID 9471 | RSS: Main: 186331136 17:50:15 INFO - PID 9471 | 17:50:17 INFO - PID 9471 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58008/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:50:18 INFO - PID 9471 | RSS: Main: 187174912 17:50:18 INFO - PID 9471 | 17:50:21 INFO - PID 9471 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58008/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:50:21 INFO - PID 9471 | RSS: Main: 186974208 17:50:21 INFO - PID 9471 | 17:50:23 INFO - PID 9471 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58008/tests/kraken/driver.html?testName=ai-astar) 17:50:23 INFO - PID 9471 | RSS: Main: 188428288 17:50:23 INFO - PID 9471 | 17:50:23 INFO - PID 9471 | __start_tp_report 17:50:23 INFO - PID 9471 | _x_x_mozilla_page_load 17:50:23 INFO - PID 9471 | _x_x_mozilla_page_load_details 17:50:23 INFO - PID 9471 | |i|pagename|runs| 17:50:23 INFO - PID 9471 | |0;ai-astar;116;110;116;111;117;113;110;110;116;111 17:50:23 INFO - PID 9471 | |1;audio-beat-detection;124;121;117;118;117;127;117;150;125;120 17:50:23 INFO - PID 9471 | |2;audio-dft;166;163;162;168;165;166;165;169;166;170 17:50:23 INFO - PID 9471 | |3;audio-fft;83;81;74;79;73;73;75;73;104;78 17:50:23 INFO - PID 9471 | |4;audio-oscillator;80;86;87;79;81;82;81;86;82;79 17:50:23 INFO - PID 9471 | |5;imaging-gaussian-blur;117;109;118;117;113;116;110;114;124;115 17:50:23 INFO - PID 9471 | |6;imaging-darkroom;155;154;149;150;152;149;159;149;149;148 17:50:23 INFO - PID 9471 | |7;imaging-desaturate;100;100;102;99;100;93;95;94;94;94 17:50:23 INFO - PID 9471 | |8;json-parse-financial;64;58;57;53;53;53;54;56;64;53 17:50:23 INFO - PID 9471 | |9;json-stringify-tinderbox;38;37;45;45;44;37;43;39;46;37 17:50:23 INFO - PID 9471 | |10;stanford-crypto-aes;76;73;73;76;73;74;74;75;70;83 17:50:23 INFO - PID 9471 | |11;stanford-crypto-ccm;115;119;119;122;120;112;110;121;112;120 17:50:23 INFO - PID 9471 | |12;stanford-crypto-pbkdf2;161;163;164;162;164;166;173;165;169;169 17:50:23 INFO - PID 9471 | |13;stanford-crypto-sha256-iterative;70;66;71;65;75;64;67;62;63;65 17:50:23 INFO - PID 9471 | __end_tp_report 17:50:23 INFO - PID 9471 | __start_cc_report 17:50:23 INFO - PID 9471 | _x_x_mozilla_cycle_collect,772 17:50:23 INFO - PID 9471 | __end_cc_report 17:50:23 INFO - PID 9471 | __startTimestamp1506991823925__endTimestamp 17:50:23 INFO - PID 9471 | 17:50:23 INFO - PID 9471 | ------- Summary: start ------- 17:50:23 INFO - PID 9471 | Number of tests: 14 17:50:23 INFO - PID 9471 | 17:50:23 INFO - PID 9471 | [#0] ai-astar Cycles:10 Average:113.00 Median:114.50 stddev:2.94 (2.6%) stddev-sans-first:2.92 17:50:23 INFO - PID 9471 | Values: 116.0 110.0 116.0 111.0 117.0 113.0 110.0 110.0 116.0 111.0 17:50:23 INFO - PID 9471 | 17:50:23 INFO - PID 9471 | [#1] audio-beat-detection Cycles:10 Average:123.60 Median:122.50 stddev:9.96 (8.1%) stddev-sans-first:10.56 17:50:23 INFO - PID 9471 | Values: 124.0 121.0 117.0 118.0 117.0 127.0 117.0 150.0 125.0 120.0 17:50:23 INFO - PID 9471 | 17:50:23 INFO - PID 9471 | [#2] audio-dft Cycles:10 Average:166.00 Median:166.00 stddev:2.49 (1.5%) stddev-sans-first:2.65 17:50:23 INFO - PID 9471 | Values: 166.0 163.0 162.0 168.0 165.0 166.0 165.0 169.0 166.0 170.0 17:50:23 INFO - PID 9471 | 17:50:23 INFO - PID 9471 | [#3] audio-fft Cycles:10 Average:79.30 Median:76.50 stddev:9.39 (12.3%) stddev-sans-first:9.87 17:50:23 INFO - PID 9471 | Values: 83.0 81.0 74.0 79.0 73.0 73.0 75.0 73.0 104.0 78.0 17:50:23 INFO - PID 9471 | 17:50:23 INFO - PID 9471 | [#4] audio-oscillator Cycles:10 Average:82.30 Median:82.00 stddev:2.98 (3.6%) stddev-sans-first:3.05 17:50:23 INFO - PID 9471 | Values: 80.0 86.0 87.0 79.0 81.0 82.0 81.0 86.0 82.0 79.0 17:50:23 INFO - PID 9471 | 17:50:23 INFO - PID 9471 | [#5] imaging-gaussian-blur Cycles:10 Average:115.30 Median:116.50 stddev:4.27 (3.7%) stddev-sans-first:4.48 17:50:23 INFO - PID 9471 | Values: 117.0 109.0 118.0 117.0 113.0 116.0 110.0 114.0 124.0 115.0 17:50:23 INFO - PID 9471 | 17:50:23 INFO - PID 9471 | [#6] imaging-darkroom Cycles:10 Average:151.40 Median:151.00 stddev:3.57 (2.4%) stddev-sans-first:3.54 17:50:23 INFO - PID 9471 | Values: 155.0 154.0 149.0 150.0 152.0 149.0 159.0 149.0 149.0 148.0 17:50:23 INFO - PID 9471 | 17:50:23 INFO - PID 9471 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:94.00 stddev:3.38 (3.6%) stddev-sans-first:3.42 17:50:23 INFO - PID 9471 | Values: 100.0 100.0 102.0 99.0 100.0 93.0 95.0 94.0 94.0 94.0 17:50:23 INFO - PID 9471 | 17:50:23 INFO - PID 9471 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:56.50 stddev:4.35 (7.7%) stddev-sans-first:3.67 17:50:23 INFO - PID 9471 | Values: 64.0 58.0 57.0 53.0 53.0 53.0 54.0 56.0 64.0 53.0 17:50:23 INFO - PID 9471 | 17:50:23 INFO - PID 9471 | [#9] json-stringify-tinderbox Cycles:10 Average:41.10 Median:43.50 stddev:3.81 (8.8%) stddev-sans-first:3.88 17:50:23 INFO - PID 9471 | Values: 38.0 37.0 45.0 45.0 44.0 37.0 43.0 39.0 46.0 37.0 17:50:23 INFO - PID 9471 | 17:50:23 INFO - PID 9471 | [#10] stanford-crypto-aes Cycles:10 Average:74.70 Median:74.50 stddev:3.40 (4.6%) stddev-sans-first:3.57 17:50:23 INFO - PID 9471 | Values: 76.0 73.0 73.0 76.0 73.0 74.0 74.0 75.0 70.0 83.0 17:50:23 INFO - PID 9471 | 17:50:23 INFO - PID 9471 | [#11] stanford-crypto-ccm Cycles:10 Average:117.00 Median:119.50 stddev:4.35 (3.6%) stddev-sans-first:4.55 17:50:23 INFO - PID 9471 | Values: 115.0 119.0 119.0 122.0 120.0 112.0 110.0 121.0 112.0 120.0 17:50:23 INFO - PID 9471 | 17:50:23 INFO - PID 9471 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.60 Median:165.50 stddev:3.72 (2.2%) stddev-sans-first:3.55 17:50:23 INFO - PID 9471 | Values: 161.0 163.0 164.0 162.0 164.0 166.0 173.0 165.0 169.0 169.0 17:50:23 INFO - PID 9471 | 17:50:23 INFO - PID 9471 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:66.50 stddev:4.05 (6.1%) stddev-sans-first:4.13 17:50:23 INFO - PID 9471 | Values: 70.0 66.0 71.0 65.0 75.0 64.0 67.0 62.0 63.0 65.0 17:50:23 INFO - PID 9471 | -------- Summary: end -------- 17:50:23 INFO - PID 9471 | 17:50:24 INFO - PID 9471 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:50:24 INFO - TEST-INFO | 9471: exit 0 17:50:24 INFO - TEST-OK | kraken | took 62325ms 17:50:24 INFO - SUITE-END | took 1174s 17:50:25 INFO - Completed test suite (00:19:34) 17:50:25 INFO - javascript benchmark 17:50:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8383.027754324792, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3139.372125574884, 3141.670124636913, 3151.180693019814, 3189.995151527268, 3191.266008817972, 6121.326654068049, 6253.312135665079, 6280.0, 6358.68206589671, 6360.777372791953, 6107.847303817407, 6191.288001879785, 6193.0, 6224.502039836813, 6248.968755156229, 5684.37471878093, 5700.543956483481, 5770.740316685759, 5770.7691692332255, 5828.388019257959, 5753.626014309057, 5783.681897495636, 5785.0, 5790.478856902878, 5848.561357898154, 5249.448807875195, 5514.448555144493, 5638.40796716343, 5698.0, 5766.740496677659, 5716.599838011341, 5728.0, 5751.108578170349, 5767.423257674241, 5789.2763404574425, 5569.749361278752, 5632.802851900205, 5701.173329867188, 5757.0788673811985, 5778.451047150514, 6120.387961203888, 6131.47882429994, 6184.628922264678, 6213.906791398133, 6256.0615907613765, 6661.500387470911, 6698.430633396167, 6724.899126513106, 6729.966350168218, 6732.3267673232285, 6642.767503137367, 6670.533062685614, 6735.629540375277, 6780.288069752654, 6783.253842077367, 6072.05883088122, 6178.660173690445, 6191.87616247677, 6280.937190628127, 6335.493160547145, 6798.422134118557, 6841.0, 6915.481338899502, 6926.134233220848, 6928.722851086001, 6515.576487528296, 6646.534742567974, 6659.300773418819, 6674.43267322283, 6684.766033188815, 6677.632730199885, 6682.766103186365, 6754.864902701918, 6770.390664840188, 6783.0, 6439.517036222208, 6609.6695165241545, 6660.666966651648, 6728.293529179366, 6739.696713647898, 6551.574147680385, 6607.669616519251, 6611.173603299588, 6611.537192396486, 6639.236487803964, 5126.718030508283, 5197.402298735618, 5201.843944681666, 5227.660202086852, 5276.314079169683, 4648.744319062416, 4659.184642687516, 4659.767011649404, 4682.672212945129, 4712.363830882787, 5680.602357834912, 5706.600537962386, 5776.277965254343, 5783.074708046693, 5805.709714514257, 6498.122753428322, 6614.437772789367, 6634.0, 6744.291849355845, 6780.627065511346, 5661.0, 5741.827745167651, 5744.540436765049, 5744.741486633112, 5778.913316300259, 5632.633878797865, 5695.45893140151, 5721.5708821838525, 5722.914156287659, 5730.713464326767, 6498.187726534184, 6502.544821862424, 6511.479081673455, 6587.0448784926875, 6596.0, 6689.397954184147, 6706.295838936939, 6723.159605049369, 6774.81440747877, 6806.625635589991, 6341.334159913143, 6474.5791523550815, 6485.6108633482145, 6500.0, 6552.967235163794, 6432.292447830829, 6569.047488114101, 6570.211574611078, 6577.835554110994, 6580.736770529125, 6723.4285085766305, 6767.560108592926, 6768.526203165928, 6792.456603471809, 6813.04617353561, 6489.675516224075, 6530.967345163243, 6553.017047442731, 6582.243042050225, 6587.374199451045, 6490.610563366213, 6491.058796474579, 6530.934690653223, 6547.901781473186, 6558.1802274715665, 6284.3087260402235, 6357.4278314950025, 6371.171747672773, 6383.489320854228, 6400.039994000937, 6432.35676432353, 6449.258335291313, 6519.771807986698, 6526.053722210158, 6553.672316384257, 6211.409916057967, 6233.438990490878, 6254.249490061222, 6317.936820631735, 6336.176297081534], "value": 6080.60588765764, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [809.2959125560761, 823.9134890836466, 829.4401279136583, 833.3916241143969, 834.1283358889959, 38431.23137537251, 39130.804345978264, 41407.58592414075, 41644.375334369935, 41852.372214416806, 37707.24585508288, 38246.426303605396, 38248.0, 38427.61572384282, 38691.61308386915, 7034.085568876052, 7091.822704432405, 7124.465665075114, 7175.820604484878, 7177.712891484361, 7055.294470552929, 7073.540219885716, 7075.115610548682, 7128.465365097639, 7146.142462904459, 7016.824579385505, 7069.045678833369, 7071.0807595012575, 7104.4671649626225, 7118.608476533788, 7742.961285193566, 7901.920980790176, 7911.090224624184, 7928.643211055516, 7971.043474783005, 7834.686612535492, 7853.0, 7906.407019473505, 7909.288164065261, 7910.4462687611895, 37218.25563488742, 37857.621423785684, 38075.80962095199, 38375.232495349934, 39645.20709585821, 3493.1441796759705, 3569.94645080324, 3572.3391172633146, 3574.535310409656, 3576.195356044895, 30865.228369290722, 30950.53574196389, 30987.535186972214, 31040.53439198414, 31081.844590777127, 5210.947890521122, 5226.451222621608, 5229.529342359206, 5242.842714718565, 5270.156774915995, 185501.0, 187797.0, 187888.0, 188226.0, 188670.0, 43235.13529729388, 43597.12805743868, 48466.0, 48992.7550362253, 49449.25826112611, 50076.0, 51155.95376184911, 51343.229851552256, 51716.22425663618, 51770.0, 15891.523254302389, 16120.193990300439, 16122.919385402998, 16175.514734557983, 16175.838241617434, 13044.152130111512, 13113.934430327787, 13201.60395188146, 13270.203787772765, 13287.269200193889, 13671.589852304447, 13749.106308089942, 13851.099678520864, 13973.231472269123, 14088.225147616775, 5490.972545137249, 5655.745491452894, 5665.3768085510565, 5707.914381284284, 5838.591298609056, 1010.2826992835022, 1016.974575635615, 1021.1575450253571, 1032.2722480651123, 1037.2946396450486, 22949.655755163687, 22960.0, 23214.071437142662, 23301.0, 23601.763982359957, 1124.516457923101, 1131.1798945764256, 1132.416805345248, 1134.3194083549804, 1140.6349968010322, 12282.263064216175, 12341.75316493683, 12410.875891240972, 12415.255084694947, 12492.0, 219158.0, 219654.0, 221151.0, 221305.0, 222288.0, 51920.0, 51927.740361297954, 52311.47688523142, 52646.47353526492, 52703.47296527062, 1409.8449170591227, 1472.0725942656272, 1478.209158100411, 1478.4160256698544, 1485.8439863814576, 22799.202027928943, 22897.771022289562, 23156.18953336625, 23247.0, 23296.06815727352, 36789.26421471502, 37041.25917481689, 37095.0, 37202.0, 37225.6277437233, 188049.0, 189693.0, 190602.04698976417, 190877.0, 192158.0, 29796.85101574478, 34289.82855085808, 35001.824990874884, 35355.0, 35717.28565428729, 43706.34440483332, 43858.56141438545, 44083.55916440795, 44129.77935110432, 44641.55358446504, 31730.682693172774, 31927.521087183246, 31986.52020219745, 32050.19874503156, 32094.19764505906, 30954.0713778587, 31100.37799243957, 31178.532322015642, 31324.53013204758, 31552.684473154975, 36091.81954090213, 36133.457998129525, 36421.0, 36424.27151457009, 36435.27129457343, 30483.2379190522, 30605.23486912756, 30641.387172256877, 30825.0, 31934.201644959063, 23233.651495227925, 23438.41403964846, 23650.763492364855, 23674.763252367255, 23677.17129900445], "value": 16932.4232090188, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.2298704216911, 582.6678793087939, 586.4986230679533, 587.5094296262621, 594.5451729426987, 22324.776752232472, 23132.0747170113, 23148.42128946773, 23161.884190579025, 23216.071357145716, 23608.0, 24497.51004979899, 24542.509149816993, 25053.749462505413, 25198.0, 6306.905396419046, 6452.709628066725, 6459.741610335581, 6519.771807986722, 6560.475161987029, 6526.510511711617, 6564.442022428099, 6629.403353698166, 6638.50211234157, 6648.401643852052, 6309.305976342599, 6513.576617519846, 6545.5418120731565, 6566.441852442548, 6599.769008084718, 6531.575447595915, 6543.509236807211, 6553.344665533408, 6579.276279609263, 6585.2097748270035, 5831.125331200312, 6078.996965500687, 6087.939120608826, 6100.877982440371, 6127.969360153215, 27197.184084477496, 27708.168754937386, 28026.439471210666, 28055.859720701468, 28087.157385278475, 2041.2447394463995, 2060.093558834108, 2083.98958005209, 2086.0612724274138, 2091.801278878504, 18282.451526454093, 18940.43178704641, 18947.33684321042, 19127.61744765097, 19144.329948451876, 2415.5048215115858, 2471.1104002559064, 2517.609770485578, 2522.911698090558, 2537.073968001666, 124252.0, 128560.35719821435, 130710.0, 133332.0, 140607.29696351453, 14272.530333498487, 14747.009153764318, 14800.777988330183, 14875.70248595022, 14893.32980015902, 23103.422414439774, 23309.883450582638, 23351.7664823353, 23372.76627233706, 23379.064837406313, 9906.80186396268, 10159.390436573829, 10191.9490402549, 10207.948960255151, 10264.897351026544, 9306.72079837606, 9349.0, 9381.437113773196, 9384.390014649027, 9522.714318570454, 8913.153250441197, 8991.145841145211, 9060.365774395728, 9063.68277110298, 9160.358774885828, 2723.6595425571804, 2820.4782115308735, 2838.176928690698, 2839.3185635447344, 2864.770818334528, 966.6954909203579, 969.6848524229654, 973.1679414100984, 983.4345251480428, 999.2905037423511, 18787.436376908714, 19029.904850475938, 19190.424287271126, 19628.607427851362, 19665.311714089938, 1004.2116938203621, 1040.4693606260862, 1044.2404548336078, 1051.7370657335666, 1056.1128651932413, 10718.839217411745, 10746.0, 10805.40570268627, 10936.726581835359, 10984.231103822816, 437623.0, 446161.0, 446208.0, 448792.0, 452068.0, 114465.4276728611, 114567.42716286532, 115487.0, 116162.0, 116440.0, 1149.201305092969, 1162.1864694713734, 1183.1658680630135, 1183.674489515373, 1190.5356910804794, 17166.914165429094, 17206.569835754206, 17303.7404438931, 17489.212985415943, 18053.277868885096, 53337.0, 53374.199387010005, 53789.19316210338, 54971.72514137564, 55220.17169742537, 67154.32845671481, 67614.66192669005, 67630.3236967624, 67882.0, 68626.0, 14289.0, 14415.918806089796, 14458.349374278394, 14467.638309042359, 14577.344019518934, 22144.778552214273, 22147.446313842283, 22344.664830027887, 22517.43706407353, 22866.771332286466, 17028.233729481955, 17130.14349282556, 17288.222030008932, 17314.134293285537, 18168.545786354916, 15904.443344482888, 16265.51203463898, 16437.91781041135, 16559.42042028523, 16767.496975090766, 17374.826251737828, 18014.909925450287, 18069.63860722752, 18136.546586335448, 18199.18103685364, 14167.149971001772, 14227.786583201467, 14253.287335633384, 14373.7843932339, 14378.065425747292, 16663.166841658112, 17688.469345919642, 17763.822361776733, 17832.643347133246, 17998.460046198634], "value": 11786.745686242974, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1286.4275397448134, 1340.0485655184818, 1355.7695191817397, 1360.0751488987485, 1366.4397596985239, 13995.230262335568, 14551.563453096398, 14593.416263349452, 14628.268586570655, 14669.926650366746, 14373.78439323411, 14391.64020899478, 14424.134551926865, 14455.710885782279, 14558.854411455883, 2615.2546524240597, 2734.5624700047997, 2736.1107640016976, 2739.301478123086, 2742.478929003483, 2647.5234457797587, 2664.960025599608, 2679.2498100531884, 2682.0, 2715.592661100831, 2427.8664673442954, 2529.380301826055, 2530.708968468622, 2534.974650253492, 2637.551616225246, 2700.6894207166133, 2737.2335745991154, 2737.3704048069058, 2739.301478123086, 2741.575055866344, 2599.3371690218873, 2636.36727185476, 2643.418447941469, 2645.4180080382475, 2651.992242947687, 13731.45074197031, 14064.367103480368, 14200.147991120566, 14209.0, 14240.50158244467, 7675.539467631904, 7959.403044771608, 7970.4819186752675, 7980.640871160812, 7980.880286795703, 4941.925871111936, 5168.302279192299, 5174.172132458826, 5184.092783762826, 5192.0, 1866.1042699504314, 1890.6407782521414, 1906.1041310584003, 1955.7555305586802, 1965.1255191439816, 23369.766302336928, 23867.09063274951, 23880.522389552287, 23887.88056059726, 23900.641490377657, 6553.836154096186, 6559.180102487189, 6578.572392794453, 6674.43267322283, 6760.121184246016, 16066.839331606769, 16080.195990200444, 16123.758143627854, 16136.596585085466, 16186.433474828324, 7511.286427789351, 7618.200088990687, 7641.3886889048745, 7649.426293028045, 7649.541027538366, 6639.336066393322, 7028.859422811515, 7031.156261248683, 7043.520860619225, 7052.9647351762915, 4881.658283920092, 5094.388673359146, 5111.284420181178, 5117.437081920985, 5154.742262886841, 1558.75059990401, 1564.608847788053, 1569.5246468319742, 1571.9764203536956, 1575.4845062437544, 831.3307787231216, 836.8744688296682, 853.2107800284712, 856.6102423397339, 867.8481265778463, 7107.147142342952, 7812.453128280966, 7820.726274580363, 7946.086200086948, 8008.119106898236, 720.5172534401963, 723.7973367457121, 729.7555318968099, 731.2541207967843, 744.8100734312824, 4324.697271190987, 4407.140607581491, 4436.534163912743, 4437.489688685778, 4493.550644935481, 18731.531711707317, 20258.59482810365, 20279.4930126748, 20301.390958271277, 20353.898230508752, 18337.266509339475, 19745.50636234077, 19967.301144460165, 20015.299464518674, 20150.89924550368, 10291.0, 10337.423465702226, 11033.0, 11049.337039777478, 11169.83245251322, 7471.738489152854, 7564.130125035691, 7621.580813055335, 7663.233676632292, 7709.113451953097, 17095.48713538596, 17132.828671713123, 17233.74149387735, 17413.0, 17748.201330940396, 17443.389481368093, 18095.02409307699, 18098.366557170437, 18370.540736481697, 18701.158447869606, 10110.545025474019, 10623.575056997634, 10685.732856678645, 10735.409552474688, 10772.838407423738, 13006.674833129247, 13081.411336490071, 13100.213987160801, 13164.341782911008, 13223.3388330585, 10544.0, 10617.734556635836, 10748.086412654699, 10854.29447085937, 10952.452377381258, 10328.948355258175, 10603.840942386025, 10728.302660327054, 10836.133109351076, 10970.177236707119, 9215.631374744935, 9460.952695236756, 9580.856287155837, 9630.518474076409, 9697.272704547046, 6718.8320291993095, 6739.528233023641, 7025.754098606524, 7032.978006340842, 7118.750843720445, 6749.730010799513, 6776.86446271062, 6839.487038472035, 6917.515773895779, 6948.270431604608], "value": 6321.650815555399, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.8986202759448, 517.4670089807497, 539.887831068, 549.1405949688736, 552.872839246973, 6269.7492100315985, 6296.685165741712, 6310.148130002448, 6318.304986451487, 6318.873622527547, 6181.56729028967, 6191.318954914956, 6209.534284928626, 6261.3738626137365, 6272.968635156818, 5195.870103247411, 5217.843464696065, 5220.243064755608, 5220.608454365928, 5238.68567885928, 5188.844334669947, 5201.973990130044, 5229.973850130763, 5247.763850626731, 5251.396089449705, 5080.466551012146, 5095.210242412413, 5115.923261151086, 5118.462561431052, 5132.794688212467, 5070.996956202298, 5083.517065878736, 5104.93250233656, 5123.847134394769, 5193.662411943231, 4704.153252414564, 4732.952670473285, 4735.526447355272, 4740.549647783455, 4775.80896764129, 6064.3935606439445, 6069.573650192201, 6094.481969032638, 6167.4757645600175, 6176.938230617681, 5829.329627092896, 5938.2280303560265, 5960.254968128984, 5962.731677074542, 5982.910256346115, 6521.0, 6558.491207204171, 6574.178227721535, 6587.571807832475, 6625.86748265032, 4940.827071052532, 5116.513931176532, 5132.8460146195675, 5145.125328694131, 5206.869828254286, 6533.444657204143, 6616.437602803768, 6850.869606514919, 6910.170779506491, 6937.369718116239, 5399.163129714901, 5433.157860531624, 5475.302656176518, 5481.561475081984, 5482.122860342326, 5470.425605311465, 5496.587755918322, 5531.25328080712, 5544.556435485232, 5571.5178013741415, 5696.544276457874, 5851.0, 5993.970030149909, 6006.939930600638, 6056.818295451144, 6575.145231120019, 6632.801015969529, 6636.369544893228, 6659.86680266392, 6676.899846502306, 1914.8851068935908, 1915.837153841939, 1942.3975220443192, 1951.7462729845313, 1958.676818324989, 1400.390829988958, 1451.528253317676, 1463.6706740983193, 1469.889758268134, 1478.3716920308825, 5573.665580065209, 5619.353774315924, 5620.426280641427, 5637.67377247426, 5638.0, 6385.425311721874, 6607.603543787388, 6624.072629831828, 6644.634545100066, 6667.966660166668, 5536.8062117826485, 5545.473180047889, 5580.218769372291, 5676.8864622707315, 5682.0, 3454.946241396348, 3486.2156014896445, 3504.807235602066, 3518.437050071976, 3526.7354948378975, 6383.3616638336725, 6416.518761092937, 6421.357864213542, 6428.935710642833, 6449.451796597342, 6536.901946470807, 6677.499187560855, 6694.196696396366, 6746.561473504305, 6752.800343735296, 6369.299377068519, 6431.324710905287, 6460.838479038061, 6481.773137940244, 6513.706883190268, 6407.839804004937, 6557.311482294291, 6562.8359291018105, 6645.700943457653, 6652.168478940132, 6504.0, 6519.869602607826, 6542.967285163544, 6615.37153970372, 6633.336666333328, 6181.81454556364, 6184.288806787277, 6325.240971083499, 6411.166548348685, 6420.871582568416, 5027.723475208899, 5325.520703136736, 5326.627136100436, 5365.168398898177, 5403.216533602685, 6473.838154046187, 6573.244076931214, 6579.190722551337, 6600.8349791255605, 6690.230623478362, 6295.26880107974, 6338.58799178052, 6431.324710905287, 6461.289258181502, 6509.7396104155305, 6344.256888435845, 6362.0, 6390.5526613136635, 6400.871982560229, 6418.326075761977, 6265.248170219603, 6284.465820405317, 6295.653739044396, 6318.515148939903, 6345.809625711236, 6416.165898433173, 6431.228252609717, 6450.548461607643, 6487.870242595027, 6535.901961470487, 6147.538934579835, 6205.47253483459, 6242.596600309004, 6246.593971391845, 6248.593841400295], "value": 5135.945397980653, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [891.3849443883723, 915.3729695158817, 936.2369668719988, 938.7559234599003, 943.8914524829643, 19796.60406791865, 20478.89760551197, 20552.28067017653, 20568.588628227426, 23106.653400198968, 20544.589108217828, 20634.587308253864, 20719.0, 20784.48038799031, 21001.054952527134, 971.0629242780715, 983.8671779309809, 984.8178087053882, 993.2401712689798, 995.9950200248988, 1001.6494227020521, 1001.919846412289, 1008.8335424654923, 1011.8886922438526, 1013.807376598444, 1049.968500944969, 1060.4114716332433, 1065.7016035510069, 1067.1782727300013, 1068.2041878800305, 1013.9290249682525, 1032.9845052324222, 1037.4708898461763, 1041.7187359412917, 1050.4327663061938, 764.9158592554815, 766.9041369828772, 767.2059418501824, 767.5893397032617, 767.6392095715005, 18625.813741862545, 18626.720599191023, 18638.763348398144, 18743.718844217346, 18832.62334753311, 21058.684119738216, 22087.226947056937, 22088.668669969964, 22129.557408851895, 22317.888410557844, 16717.91641041787, 17381.21784519687, 17415.0, 17480.47558573245, 17518.38685646009, 3582.247727977128, 3654.8903532894055, 3682.650148235913, 3684.926301473982, 3690.944635830465, 123926.0, 125519.0, 125718.37140814328, 126885.0, 127412.72587274101, 15882.841171588367, 16096.919515402347, 16478.34086636545, 16478.83521164773, 16553.25510352037, 24541.38646533851, 25070.997160113755, 25228.243152705447, 25282.873585631954, 25559.872200638878, 8038.598070096472, 8145.714899978472, 8191.672333106609, 8222.712205072794, 8226.136255693185, 3956.0703234739813, 4003.079291762853, 4010.2781499329817, 4014.578469260686, 4026.214888096793, 5412.810551630674, 5466.070767969455, 5513.283273174462, 5526.115821468546, 5533.584981126432, 944.7118628818276, 958.1807554540907, 960.7790208252142, 964.363520076746, 996.4121168510613, 671.4930227678071, 685.4619123987645, 706.1737766812842, 707.9221285658573, 711.121764620693, 11629.47667354971, 11901.226420282826, 11924.821127683092, 12802.935985320013, 12881.613551593467, 653.183520599251, 654.5483616304734, 670.0518765946229, 673.8787018336747, 674.0125715826338, 5529.806456773994, 5562.499375056183, 5600.355959064678, 5620.185073164368, 5739.8565035874435, 119953.0, 122757.0, 124053.37973310075, 124251.37874310752, 124314.0, 109630.0, 109662.45168774265, 110991.44504277427, 113663.0, 114574.42712786549, 94304.52847735854, 94998.52500737591, 95161.52419237998, 98311.50844245733, 98969.50515247378, 10286.032985419322, 10487.475626218811, 10572.524236409226, 10721.0, 10721.41032243234, 43160.0, 43442.131157377305, 44412.77793611011, 44472.33291500695, 44729.77635111804, 57381.71309143428, 58526.70736646289, 59111.40888591231, 60286.698566506886, 60506.0, 14898.553043408716, 15113.471028514388, 15117.622059448602, 15188.0, 15252.313645886185, 15236.619084522976, 15416.845831541541, 15498.225088745292, 15632.921835391206, 15674.686506270038, 15137.0, 15156.924215378853, 15214.771778423112, 15274.389024438899, 15280.15959122259, 16340.754888676916, 16596.336146554004, 16612.0, 16629.5842603931, 16920.830791692417, 15608.609784755472, 15756.527304180892, 15814.0, 15918.28367723432, 16109.194540272705, 10066.144377727975, 10178.18574514052, 10187.337823041478, 10573.2598718089, 10603.575856965635, 11595.072394208612, 11703.356315402734, 11707.356095414494, 11896.643100706993, 11951.76096478083], "value": 8807.83152739283, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1449.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 110.0, 116.0, 111.0, 117.0, 113.0, 110.0, 110.0, 116.0, 111.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 121.0, 117.0, 118.0, 117.0, 127.0, 117.0, 150.0, 125.0, 120.0], "value": 123.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 162.0, 168.0, 165.0, 166.0, 165.0, 169.0, 166.0, 170.0], "value": 166.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 74.0, 79.0, 73.0, 73.0, 75.0, 73.0, 104.0, 78.0], "value": 79.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 86.0, 87.0, 79.0, 81.0, 82.0, 81.0, 86.0, 82.0, 79.0], "value": 82.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 109.0, 118.0, 117.0, 113.0, 116.0, 110.0, 114.0, 124.0, 115.0], "value": 115.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 154.0, 149.0, 150.0, 152.0, 149.0, 159.0, 149.0, 149.0, 148.0], "value": 151.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 100.0, 102.0, 99.0, 100.0, 93.0, 95.0, 94.0, 94.0, 94.0], "value": 97.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 58.0, 57.0, 53.0, 53.0, 53.0, 54.0, 56.0, 64.0, 53.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 37.0, 45.0, 45.0, 44.0, 37.0, 43.0, 39.0, 46.0, 37.0], "value": 41.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 73.0, 76.0, 73.0, 74.0, 74.0, 75.0, 70.0, 83.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 119.0, 119.0, 122.0, 120.0, 112.0, 110.0, 121.0, 112.0, 120.0], "value": 117.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 164.0, 162.0, 164.0, 166.0, 173.0, 165.0, 169.0, 169.0], "value": 165.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 71.0, 65.0, 75.0, 64.0, 67.0, 62.0, 63.0, 65.0], "value": 66.8, "unit": "score"}]}]} 17:50:25 INFO - Return code: 0 17:50:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:50:25 INFO - # TBPL SUCCESS # 17:50:25 INFO - Running post-action listener: _package_coverage_data 17:50:25 INFO - Running post-action listener: _resource_record_post_action 17:50:25 INFO - [mozharness: 2017-10-03 00:50:25.201954Z] Finished run-tests step (success) 17:50:25 INFO - Running post-run listener: _resource_record_post_run 17:50:25 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 345001984; Read time: 16; Write time: 276488 17:50:25 INFO - TinderboxPrint: CPU usage
13.2% 17:50:25 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 17:50:25 INFO - TinderboxPrint: I/O write bytes / time
345,001,984 / 276,488 17:50:25 INFO - TinderboxPrint: CPU idle
8,248.3 (86.8%) 17:50:25 INFO - TinderboxPrint: CPU user
1,199.4 (12.6%) 17:50:25 INFO - TinderboxPrint: Swap in / out
0 / 0 17:50:25 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13484032; Read time: 0; Write time: 2812 17:50:25 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:50:25 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 331517952; Read time: 0; Write time: 273676 17:50:25 INFO - Running post-run listener: _upload_blobber_files 17:50:25 INFO - Blob upload gear active. 17:50:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:50:25 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 17:50:25 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'] 17:50:25 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 17:50:26 INFO - (blobuploader) - INFO - Open directory for files ... 17:50:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:50:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:50:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:29 INFO - (blobuploader) - INFO - Done attempting. 17:50:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:50:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:50:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:30 INFO - (blobuploader) - INFO - Done attempting. 17:50:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:50:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:50:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:31 INFO - (blobuploader) - INFO - Done attempting. 17:50:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:50:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:50:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:32 INFO - (blobuploader) - INFO - Done attempting. 17:50:32 INFO - (blobuploader) - INFO - Iteration through files over. 17:50:32 INFO - Return code: 0 17:50:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:50:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:50:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3654b06e323393afbc426032ce5dc1986a1fa6572aa0a001a4a2bd35ccb51ab9a918ce2b97539609cf8844d453567f811767b0cb7267606f52ff64e620227f33", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4763a10f236d858d89b528f50caa1c8f054949e4ae6b69b34ed63f1d715b149db609f2d7672b7263f3fdc886557b82141d583fc84f2ac71891852e42bc9783a0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdb64ec759675357cf728a3cf9d376485bb3e579e77b75c5a373a1b55749334bf4fd0c0a247dea26913e4efc9440bb93d97ecd94d8198633ff9146d327304c82", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8f728ca22c4bd9173d30aac1d1ec51ffdef00a429a68183e069872116e9b44e455ad95a6db4da338d5be614d537e79d0daf75528ab526fc9634bc1c62ecd9ed"} 17:50:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:50:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:50:32 INFO - Contents: 17:50:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3654b06e323393afbc426032ce5dc1986a1fa6572aa0a001a4a2bd35ccb51ab9a918ce2b97539609cf8844d453567f811767b0cb7267606f52ff64e620227f33", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4763a10f236d858d89b528f50caa1c8f054949e4ae6b69b34ed63f1d715b149db609f2d7672b7263f3fdc886557b82141d583fc84f2ac71891852e42bc9783a0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdb64ec759675357cf728a3cf9d376485bb3e579e77b75c5a373a1b55749334bf4fd0c0a247dea26913e4efc9440bb93d97ecd94d8198633ff9146d327304c82", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8f728ca22c4bd9173d30aac1d1ec51ffdef00a429a68183e069872116e9b44e455ad95a6db4da338d5be614d537e79d0daf75528ab526fc9634bc1c62ecd9ed"} 17:50:32 INFO - Running post-run listener: copy_logs_to_upload_dir 17:50:32 INFO - Copying logs to upload dir... 17:50:32 INFO - mkdir: /builds/slave/test/build/upload/logs 17:50:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.494235 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-10-02 17:50:32.589499) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-02 17:50:32.597030) ========= 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=32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801 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/3654b06e323393afbc426032ce5dc1986a1fa6572aa0a001a4a2bd35ccb51ab9a918ce2b97539609cf8844d453567f811767b0cb7267606f52ff64e620227f33", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4763a10f236d858d89b528f50caa1c8f054949e4ae6b69b34ed63f1d715b149db609f2d7672b7263f3fdc886557b82141d583fc84f2ac71891852e42bc9783a0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdb64ec759675357cf728a3cf9d376485bb3e579e77b75c5a373a1b55749334bf4fd0c0a247dea26913e4efc9440bb93d97ecd94d8198633ff9146d327304c82", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8f728ca22c4bd9173d30aac1d1ec51ffdef00a429a68183e069872116e9b44e455ad95a6db4da338d5be614d537e79d0daf75528ab526fc9634bc1c62ecd9ed"} build_url:https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011317 build_url: 'https://queue.taskcluster.net/v1/task/Hy3hXRK2T5ipTB-nz_T5Yw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3654b06e323393afbc426032ce5dc1986a1fa6572aa0a001a4a2bd35ccb51ab9a918ce2b97539609cf8844d453567f811767b0cb7267606f52ff64e620227f33", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4763a10f236d858d89b528f50caa1c8f054949e4ae6b69b34ed63f1d715b149db609f2d7672b7263f3fdc886557b82141d583fc84f2ac71891852e42bc9783a0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdb64ec759675357cf728a3cf9d376485bb3e579e77b75c5a373a1b55749334bf4fd0c0a247dea26913e4efc9440bb93d97ecd94d8198633ff9146d327304c82", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8f728ca22c4bd9173d30aac1d1ec51ffdef00a429a68183e069872116e9b44e455ad95a6db4da338d5be614d537e79d0daf75528ab526fc9634bc1c62ecd9ed"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-02 17:50:32.625957) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:50:32.626261) ========= 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=32c5991c409366f7fec8a718000001d2-1506989967.619359-534320801 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004393 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:50:32.713803) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-02 17:50:32.714166) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-02 17:50:32.714514) ========= ========= Total master_lag: 0.34 =========