builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-041 starttime: 1504393317.58 results: success (0) revision: f5d99e9f3d7bf19c8a222db97bedc924da27fd58 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:57.584630) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:57.585107) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:57.585387) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:57.603134) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:57.603545) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-02 16:01:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 23.8M=0s 2017-09-02 16:01:57 (23.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140189 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:57.760474) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:57.763887) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020067 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:57.816611) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-09-02 16:01:57.817005) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f5d99e9f3d7bf19c8a222db97bedc924da27fd58 --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 f5d99e9f3d7bf19c8a222db97bedc924da27fd58 --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=8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-02 16:01:57,794 truncating revision to first 12 chars 2017-09-02 16:01:57,794 Setting DEBUG logging. 2017-09-02 16:01:57,794 attempt 1/10 2017-09-02 16:01:57,794 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f5d99e9f3d7b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-02 16:01:58,274 unpacking tar archive at: mozilla-central-f5d99e9f3d7b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.819419 ========= master_lag: 1.22 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-09-02 16:01:59.857129) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:59.861832) ========= script_repo_revision: f5d99e9f3d7bf19c8a222db97bedc924da27fd58 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:59.862390) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:59.862763) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:59.880934) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-09-02 16:01:59.881327) ========= /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=8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:01:59 INFO - MultiFileLogger online at 20170902 16:01:59 in /builds/slave/test 16:01:59 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 16:01:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:01:59 INFO - {'append_to_log': False, 16:01:59 INFO - 'base_work_dir': '/builds/slave/test', 16:01:59 INFO - 'blob_upload_branch': 'Firefox', 16:01:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:01:59 INFO - 'branch': 'Firefox', 16:01:59 INFO - 'buildbot_json_path': 'buildprops.json', 16:01:59 INFO - 'code_coverage': False, 16:01:59 INFO - 'config_files': ('talos/linux_config.py',), 16:01:59 INFO - 'default_actions': ('clobber', 16:01:59 INFO - 'read-buildbot-config', 16:01:59 INFO - 'download-and-extract', 16:01:59 INFO - 'populate-webroot', 16:01:59 INFO - 'create-virtualenv', 16:01:59 INFO - 'install', 16:01:59 INFO - 'setup-mitmproxy', 16:01:59 INFO - 'run-tests'), 16:01:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:01:59 INFO - 'disable_ccov_upload': False, 16:01:59 INFO - 'disable_stylo': False, 16:01:59 INFO - 'download_minidump_stackwalk': True, 16:01:59 INFO - 'download_symbols': 'ondemand', 16:01:59 INFO - 'enable_stylo': False, 16:02:00 INFO - 'enable_webrender': False, 16:02:00 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:02:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:02:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:02:00 INFO - 'gecko_profile': False, 16:02:00 INFO - 'gecko_profile_interval': 0, 16:02:00 INFO - 'installer_path': 'installer.exe', 16:02:00 INFO - 'log_level': 'info', 16:02:00 INFO - 'log_name': 'talos', 16:02:00 INFO - 'log_to_console': True, 16:02:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:02:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:02:00 INFO - 'opt_config_files': (), 16:02:00 INFO - 'pip_index': False, 16:02:00 INFO - 'suite': 'dromaeojs-e10s', 16:02:00 INFO - 'system_bits': '32', 16:02:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:02:00 INFO - 'title': 'talos-linux64-ix-041', 16:02:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:02:00 INFO - 'use_talos_json': True, 16:02:00 INFO - 'verify': 'False', 16:02:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:02:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:02:00 INFO - 'work_dir': 'build'} 16:02:00 INFO - [mozharness: 2017-09-02 23:02:00.006164Z] Running clobber step. 16:02:00 INFO - Running pre-action listener: _resource_record_pre_action 16:02:00 INFO - Running main action method: clobber 16:02:00 INFO - rmtree: /builds/slave/test/build 16:02:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:02:01 INFO - Running post-action listener: _resource_record_post_action 16:02:01 INFO - [mozharness: 2017-09-02 23:02:01.018564Z] Finished clobber step (success) 16:02:01 INFO - [mozharness: 2017-09-02 23:02:01.018661Z] Running read-buildbot-config step. 16:02:01 INFO - Running pre-action listener: _resource_record_pre_action 16:02:01 INFO - Running main action method: read_buildbot_config 16:02:01 INFO - Using buildbot properties: 16:02:01 INFO - { 16:02:01 INFO - "project": "", 16:02:01 INFO - "product": "firefox", 16:02:01 INFO - "who": "archaeopteryx@coole-files.de", 16:02:01 INFO - "installer_path": "public/build/target.tar.bz2", 16:02:01 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 16:02:01 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:02:01 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 16:02:01 INFO - "stage_platform": "linux64-qr", 16:02:01 INFO - "basedir": "/builds/slave/test", 16:02:01 INFO - "buildnumber": 31, 16:02:01 INFO - "platform": "ubuntu64_hw_qr", 16:02:01 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:02:01 INFO - "slavebuilddir": "test", 16:02:01 INFO - "taskId": "F2VM56HET16ANQI7yEUAZQ", 16:02:01 INFO - "branch": "mozilla-central", 16:02:01 INFO - "script_repo_revision": "production", 16:02:01 INFO - "revision": "f5d99e9f3d7bf19c8a222db97bedc924da27fd58", 16:02:01 INFO - "slavename": "talos-linux64-ix-041", 16:02:01 INFO - "repo_path": "mozilla-central" 16:02:01 INFO - } 16:02:01 INFO - Finding installer, test and symbols from parent task. 16:02:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F2VM56HET16ANQI7yEUAZQ'}, attempt #1 16:02:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F2VM56HET16ANQI7yEUAZQ'}, attempt #1 16:02:01 INFO - Task dependencies: Y5WsbGReTUeKPP-utDhE2w 16:02:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w'}, attempt #1 16:02:02 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2 16:02:02 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.test_packages.json 16:02:02 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.crashreporter-symbols.zip 16:02:02 INFO - Running post-action listener: _resource_record_post_action 16:02:02 INFO - [mozharness: 2017-09-02 23:02:02.421656Z] Finished read-buildbot-config step (success) 16:02:02 INFO - [mozharness: 2017-09-02 23:02:02.421874Z] Running download-and-extract step. 16:02:02 INFO - Running pre-action listener: _resource_record_pre_action 16:02:02 INFO - Running main action method: download_and_extract 16:02:02 INFO - mkdir: /builds/slave/test/build/tests 16:02:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:02 INFO - trying https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.test_packages.json 16:02:02 INFO - Downloading https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:02:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:02:02 INFO - Downloaded 989 bytes. 16:02:02 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:02:02 INFO - Using the following test package requirements: 16:02:02 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:02:02 INFO - u'common': [u'target.common.tests.zip'], 16:02:02 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:02:02 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:02:02 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:02:02 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:02:02 INFO - u'mozbase': [u'target.common.tests.zip'], 16:02:02 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:02:02 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:02:02 INFO - u'web-platform': [u'target.common.tests.zip', 16:02:02 INFO - u'target.web-platform.tests.tar.gz'], 16:02:02 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:02:02 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:02:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.common.tests.zip 16:02:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:02:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.common.tests.zip into memory 16:02:04 INFO - Content-Length response header: 55022372 16:02:04 INFO - Bytes received: 55022372 16:02:15 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:02:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.common.tests.zip 16:02:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:02:15 INFO - Fetch https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.common.tests.zip into memory 16:02:16 INFO - Content-Length response header: 55022372 16:02:16 INFO - Bytes received: 55022372 16:02:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.talos.tests.zip 16:02:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:02:28 INFO - Fetch https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.talos.tests.zip into memory 16:02:28 INFO - Content-Length response header: 14496650 16:02:28 INFO - Bytes received: 14496650 16:02: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')]} 16:02:29 INFO - trying https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2 16:02:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:02:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:02:30 INFO - Downloaded 62581036 bytes. 16:02:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2 16:02:30 INFO - mkdir: /builds/slave/test/properties 16:02:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:02:30 INFO - Writing to file /builds/slave/test/properties/build_url 16:02:30 INFO - Contents: 16:02:30 INFO - build_url:https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2 16:02:30 INFO - Running post-action listener: _resource_record_post_action 16:02:30 INFO - Running post-action listener: find_tests_for_verification 16:02:30 INFO - Running post-action listener: set_extra_try_arguments 16:02:30 INFO - [mozharness: 2017-09-02 23:02:30.188457Z] Finished download-and-extract step (success) 16:02:30 INFO - [mozharness: 2017-09-02 23:02:30.188552Z] Running populate-webroot step. 16:02:30 INFO - Running pre-action listener: _resource_record_pre_action 16:02:30 INFO - Running main action method: populate_webroot 16:02:30 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:02:30 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:02:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:02:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:02:30 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:02:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:02:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:02:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:02:30 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:02:30 INFO - 'talos_options': ['--enable-stylo'], 16:02:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:02:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:02:30 INFO - 'tests': ['damp', 'tps']}, 16:02:30 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:02:30 INFO - 'talos_options': ['--enable-stylo'], 16:02:30 INFO - 'tests': ['damp', 'tps']}, 16:02:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:02:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:02:30 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:02:30 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:02:30 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:02:30 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:02:30 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:02:30 INFO - 'talos_options': ['--enable-stylo'], 16:02:30 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:02:30 INFO - 'other-e10s': {'tests': ['a11yr', 16:02:30 INFO - 'ts_paint', 16:02:30 INFO - 'tpaint', 16:02:30 INFO - 'sessionrestore', 16:02:30 INFO - 'sessionrestore_many_windows', 16:02:30 INFO - 'sessionrestore_no_auto_restore', 16:02:30 INFO - 'tabpaint']}, 16:02:30 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:02:30 INFO - 'tests': ['a11yr', 16:02:30 INFO - 'ts_paint', 16:02:30 INFO - 'tpaint', 16:02:30 INFO - 'sessionrestore', 16:02:30 INFO - 'sessionrestore_many_windows', 16:02:30 INFO - 'sessionrestore_no_auto_restore', 16:02:30 INFO - 'tabpaint']}, 16:02:30 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:02:30 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:02:30 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:02:30 INFO - 'tests': ['bloom_basic_singleton']}, 16:02:30 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:02:30 INFO - 'tests': ['bloom_basic']}, 16:02:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:02:30 INFO - 'tsvgr_opacity', 16:02:30 INFO - 'tart', 16:02:30 INFO - 'tscrollx', 16:02:30 INFO - 'tsvg_static']}, 16:02:30 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:02:30 INFO - 'tests': ['tsvgx', 16:02:30 INFO - 'tsvgr_opacity', 16:02:30 INFO - 'tart', 16:02:30 INFO - 'tscrollx', 16:02:30 INFO - 'tsvg_static']}, 16:02:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:02:30 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:02:30 INFO - 'talos_options': ['--enable-stylo'], 16:02:30 INFO - 'tests': ['tp5o']}, 16:02:30 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:02:30 INFO - 'talos_options': ['--mitmproxy', 16:02:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:02:30 INFO - '--firstNonBlankPaint'], 16:02:30 INFO - 'tests': ['tp6_google', 16:02:30 INFO - 'tp6_youtube', 16:02:30 INFO - 'tp6_amazon', 16:02:30 INFO - 'tp6_facebook']}, 16:02:30 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:02:30 INFO - 'talos_options': ['--enable-stylo', 16:02:30 INFO - '--mitmproxy', 16:02:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:02:30 INFO - '--firstNonBlankPaint'], 16:02:30 INFO - 'tests': ['tp6_google', 16:02:30 INFO - 'tp6_youtube', 16:02:30 INFO - 'tp6_amazon', 16:02:30 INFO - 'tp6_facebook']}, 16:02:30 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:02:30 INFO - 'talos_options': ['--enable-stylo', 16:02:30 INFO - '--stylo-threads=1', 16:02:30 INFO - '--mitmproxy', 16:02:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:02:30 INFO - '--firstNonBlankPaint'], 16:02:30 INFO - 'tests': ['tp6_google', 16:02:30 INFO - 'tp6_youtube', 16:02:30 INFO - 'tp6_amazon', 16:02:30 INFO - 'tp6_facebook']}, 16:02:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:02:30 INFO - 'talos_options': ['--xperf_path', 16:02:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:02:30 INFO - 'tests': ['tp5n']}, 16:02:30 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:02:30 INFO - 'talos_options': ['--enable-stylo', 16:02:30 INFO - '--xperf_path', 16:02:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:02:30 INFO - 'tests': ['tp5n']}}} 16:02:30 INFO - Running post-action listener: _resource_record_post_action 16:02:30 INFO - [mozharness: 2017-09-02 23:02:30.202766Z] Finished populate-webroot step (success) 16:02:30 INFO - [mozharness: 2017-09-02 23:02:30.202854Z] Running create-virtualenv step. 16:02:30 INFO - Running pre-action listener: _resource_record_pre_action 16:02:30 INFO - Running main action method: create_virtualenv 16:02:30 INFO - Creating virtualenv /builds/slave/test/build/venv 16:02:30 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:02:30 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:02:30 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:02:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:02:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:02:30 INFO - Installing distribute.............................................................................................................................................................................................done. 16:02:31 INFO - Installing pip...............done. 16:02:31 INFO - Return code: 0 16:02:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:02:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:02:31 INFO - Reading from file tmpfile_stdout 16:02:31 INFO - Output received: 16:02:31 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:02:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:02: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')]} 16:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02: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')]} 16:02:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfffa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1003090>, '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 0x1003d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xffdf90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', '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 16:02: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', 'pip>=1.5'] in /builds/slave/test/build 16:02: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 pip>=1.5 16:02:31 INFO - Using env: {'DISPLAY': ':0', 16:02:31 INFO - 'HOME': '/home/cltbld', 16:02:31 INFO - 'LANG': 'en_US.UTF-8', 16:02:31 INFO - 'LANGUAGE': 'en_US:en', 16:02:31 INFO - 'LOGNAME': 'cltbld', 16:02:31 INFO - 'MAIL': '/var/mail/cltbld', 16:02:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:31 INFO - 'MOZ_NO_REMOTE': '1', 16:02:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:31 INFO - 'NO_EM_RESTART': '1', 16:02:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:31 INFO - 'PWD': '/builds/slave/test', 16:02:31 INFO - 'SHELL': '/bin/bash', 16:02:31 INFO - 'SHLVL': '1', 16:02:31 INFO - 'TERM': 'linux', 16:02:31 INFO - 'TMOUT': '86400', 16:02:31 INFO - 'USER': 'cltbld', 16:02:31 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', 16:02:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:31 INFO - '_': '/tools/buildbot/bin/python'} 16:02:32 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:02:32 INFO - Downloading/unpacking pip>=1.5 16:02:32 INFO - Running setup.py egg_info for package pip 16:02:32 INFO - warning: no files found matching 'pip/cacert.pem' 16:02:32 INFO - warning: no files found matching '*.html' under directory 'docs' 16:02:32 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:02:32 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:02:32 INFO - Installing collected packages: pip 16:02:32 INFO - Found existing installation: pip 1.1 16:02:32 INFO - Uninstalling pip: 16:02:32 INFO - Successfully uninstalled pip 16:02:32 INFO - Running setup.py install for pip 16:02:32 INFO - warning: no files found matching 'pip/cacert.pem' 16:02:32 INFO - warning: no files found matching '*.html' under directory 'docs' 16:02:32 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:02:32 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:02:32 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:02:32 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:02:32 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:02:32 INFO - Successfully installed pip 16:02:32 INFO - Cleaning up... 16:02:32 INFO - Return code: 0 16:02:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:02:32 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')]} 16:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:32 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')]} 16:02:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:32 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 0xc931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfffa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1003090>, '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 0x1003d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xffdf90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', '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 16:02:32 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 16:02:32 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 16:02:32 INFO - Using env: {'DISPLAY': ':0', 16:02:32 INFO - 'HOME': '/home/cltbld', 16:02:32 INFO - 'LANG': 'en_US.UTF-8', 16:02:32 INFO - 'LANGUAGE': 'en_US:en', 16:02:32 INFO - 'LOGNAME': 'cltbld', 16:02:32 INFO - 'MAIL': '/var/mail/cltbld', 16:02:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:32 INFO - 'MOZ_NO_REMOTE': '1', 16:02:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:32 INFO - 'NO_EM_RESTART': '1', 16:02:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:32 INFO - 'PWD': '/builds/slave/test', 16:02:32 INFO - 'SHELL': '/bin/bash', 16:02:32 INFO - 'SHLVL': '1', 16:02:32 INFO - 'TERM': 'linux', 16:02:32 INFO - 'TMOUT': '86400', 16:02:32 INFO - 'USER': 'cltbld', 16:02:32 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', 16:02:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:32 INFO - '_': '/tools/buildbot/bin/python'} 16:02:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:32 INFO - Downloading/unpacking psutil>=3.1.1 16:02:32 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 16:02:32 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 16:02:32 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 16:02:32 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 16:02:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:02:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:02:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:02:33 INFO - Installing collected packages: psutil 16:02:33 INFO - Running setup.py install for psutil 16:02:34 INFO - building 'psutil._psutil_linux' extension 16:02:34 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 16:02:34 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 16:02:34 INFO - building 'psutil._psutil_posix' extension 16:02:34 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 16:02:34 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 16:02:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:02:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:02:34 INFO - Successfully installed psutil 16:02:34 INFO - Cleaning up... 16:02:34 INFO - Return code: 0 16:02:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:02:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:34 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 0xc931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfffa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1003090>, '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 0x1003d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xffdf90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', '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 16:02:34 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 16:02:34 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 16:02:34 INFO - Using env: {'DISPLAY': ':0', 16:02:34 INFO - 'HOME': '/home/cltbld', 16:02:34 INFO - 'LANG': 'en_US.UTF-8', 16:02:34 INFO - 'LANGUAGE': 'en_US:en', 16:02:34 INFO - 'LOGNAME': 'cltbld', 16:02:34 INFO - 'MAIL': '/var/mail/cltbld', 16:02:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:34 INFO - 'MOZ_NO_REMOTE': '1', 16:02:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:34 INFO - 'NO_EM_RESTART': '1', 16:02:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:34 INFO - 'PWD': '/builds/slave/test', 16:02:34 INFO - 'SHELL': '/bin/bash', 16:02:34 INFO - 'SHLVL': '1', 16:02:34 INFO - 'TERM': 'linux', 16:02:34 INFO - 'TMOUT': '86400', 16:02:34 INFO - 'USER': 'cltbld', 16:02:34 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', 16:02:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:34 INFO - '_': '/tools/buildbot/bin/python'} 16:02:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:02:34 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 16:02:34 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 16:02:34 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 16:02:34 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 16:02:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:02:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:02:35 INFO - Installing collected packages: mozsystemmonitor 16:02:35 INFO - Running setup.py install for mozsystemmonitor 16:02:35 INFO - Successfully installed mozsystemmonitor 16:02:35 INFO - Cleaning up... 16:02:35 INFO - Return code: 0 16:02:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:02:35 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')]} 16:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:35 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')]} 16:02:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:35 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 0xc931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfffa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1003090>, '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 0x1003d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xffdf90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', '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 16:02:35 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 16:02:35 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 16:02:35 INFO - Using env: {'DISPLAY': ':0', 16:02:35 INFO - 'HOME': '/home/cltbld', 16:02:35 INFO - 'LANG': 'en_US.UTF-8', 16:02:35 INFO - 'LANGUAGE': 'en_US:en', 16:02:35 INFO - 'LOGNAME': 'cltbld', 16:02:35 INFO - 'MAIL': '/var/mail/cltbld', 16:02:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:35 INFO - 'MOZ_NO_REMOTE': '1', 16:02:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:35 INFO - 'NO_EM_RESTART': '1', 16:02:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:35 INFO - 'PWD': '/builds/slave/test', 16:02:35 INFO - 'SHELL': '/bin/bash', 16:02:35 INFO - 'SHLVL': '1', 16:02:35 INFO - 'TERM': 'linux', 16:02:35 INFO - 'TMOUT': '86400', 16:02:35 INFO - 'USER': 'cltbld', 16:02:35 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', 16:02:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:35 INFO - '_': '/tools/buildbot/bin/python'} 16:02:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:36 INFO - Downloading/unpacking jsonschema==2.5.1 16:02:36 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 16:02:36 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 16:02:36 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 16:02:36 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 16:02:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:02:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:02:37 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 16:02:37 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 16:02:37 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 16:02:37 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 16:02:37 INFO - Downloading functools32-3.2.3-2.tar.gz 16:02:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:02:37 INFO - warning: no files found matching '*.txt' 16:02:37 INFO - no previously-included directories found matching 'build' 16:02:37 INFO - no previously-included directories found matching 'dist' 16:02:37 INFO - no previously-included directories found matching '.git*' 16:02:37 INFO - Installing collected packages: jsonschema, functools32 16:02:37 INFO - Running setup.py install for functools32 16:02:37 INFO - warning: no files found matching '*.txt' 16:02:37 INFO - no previously-included directories found matching 'build' 16:02:37 INFO - no previously-included directories found matching 'dist' 16:02:37 INFO - no previously-included directories found matching '.git*' 16:02:37 INFO - Successfully installed jsonschema functools32 16:02:37 INFO - Cleaning up... 16:02:37 INFO - Return code: 0 16:02:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:02:37 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')]} 16:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:37 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')]} 16:02:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:37 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 0xc931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfffa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1003090>, '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 0x1003d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xffdf90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', '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 16:02:37 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 16:02:37 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 16:02:37 INFO - Using env: {'DISPLAY': ':0', 16:02:37 INFO - 'HOME': '/home/cltbld', 16:02:37 INFO - 'LANG': 'en_US.UTF-8', 16:02:37 INFO - 'LANGUAGE': 'en_US:en', 16:02:37 INFO - 'LOGNAME': 'cltbld', 16:02:37 INFO - 'MAIL': '/var/mail/cltbld', 16:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:37 INFO - 'MOZ_NO_REMOTE': '1', 16:02:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:37 INFO - 'NO_EM_RESTART': '1', 16:02:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:37 INFO - 'PWD': '/builds/slave/test', 16:02:37 INFO - 'SHELL': '/bin/bash', 16:02:37 INFO - 'SHLVL': '1', 16:02:37 INFO - 'TERM': 'linux', 16:02:37 INFO - 'TMOUT': '86400', 16:02:37 INFO - 'USER': 'cltbld', 16:02:37 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', 16:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:37 INFO - '_': '/tools/buildbot/bin/python'} 16:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:02:37 INFO - Cleaning up... 16:02:37 INFO - Return code: 0 16:02:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:02:37 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')]} 16:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:37 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')]} 16:02:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:37 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 0xc931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfffa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1003090>, '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 0x1003d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xffdf90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', '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 16:02:37 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 16:02:37 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 16:02:37 INFO - Using env: {'DISPLAY': ':0', 16:02:37 INFO - 'HOME': '/home/cltbld', 16:02:37 INFO - 'LANG': 'en_US.UTF-8', 16:02:37 INFO - 'LANGUAGE': 'en_US:en', 16:02:37 INFO - 'LOGNAME': 'cltbld', 16:02:37 INFO - 'MAIL': '/var/mail/cltbld', 16:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:37 INFO - 'MOZ_NO_REMOTE': '1', 16:02:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:37 INFO - 'NO_EM_RESTART': '1', 16:02:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:37 INFO - 'PWD': '/builds/slave/test', 16:02:37 INFO - 'SHELL': '/bin/bash', 16:02:37 INFO - 'SHLVL': '1', 16:02:37 INFO - 'TERM': 'linux', 16:02:37 INFO - 'TMOUT': '86400', 16:02:37 INFO - 'USER': 'cltbld', 16:02:37 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', 16:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:37 INFO - '_': '/tools/buildbot/bin/python'} 16:02:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:38 INFO - Downloading/unpacking blobuploader==1.2.4 16:02:38 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 16:02:38 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 16:02:38 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 16:02:38 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 16:02:39 INFO - Downloading blobuploader-1.2.4.tar.gz 16:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:02:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:02:39 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 16:02:39 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 16:02:39 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 16:02:39 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 16:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:02:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:02:39 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 16:02:39 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 16:02:39 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 16:02:39 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 16:02:39 INFO - Downloading docopt-0.6.1.tar.gz 16:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:02:39 INFO - Installing collected packages: blobuploader, requests, docopt 16:02:39 INFO - Running setup.py install for blobuploader 16:02:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:02:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:02:39 INFO - Running setup.py install for requests 16:02:40 INFO - Running setup.py install for docopt 16:02:40 INFO - Successfully installed blobuploader requests docopt 16:02:40 INFO - Cleaning up... 16:02:40 INFO - Return code: 0 16:02:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:40 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 0xc931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfffa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1003090>, '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 0x1003d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xffdf90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', '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 16:02:40 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 16:02:40 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 16:02:40 INFO - Using env: {'DISPLAY': ':0', 16:02:40 INFO - 'HOME': '/home/cltbld', 16:02:40 INFO - 'LANG': 'en_US.UTF-8', 16:02:40 INFO - 'LANGUAGE': 'en_US:en', 16:02:40 INFO - 'LOGNAME': 'cltbld', 16:02:40 INFO - 'MAIL': '/var/mail/cltbld', 16:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:40 INFO - 'MOZ_NO_REMOTE': '1', 16:02:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:40 INFO - 'NO_EM_RESTART': '1', 16:02:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:40 INFO - 'PWD': '/builds/slave/test', 16:02:40 INFO - 'SHELL': '/bin/bash', 16:02:40 INFO - 'SHLVL': '1', 16:02:40 INFO - 'TERM': 'linux', 16:02:40 INFO - 'TMOUT': '86400', 16:02:40 INFO - 'USER': 'cltbld', 16:02:40 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', 16:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:40 INFO - '_': '/tools/buildbot/bin/python'} 16:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:02:40 INFO - Running setup.py (path:/tmp/pip-KQzvXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:02:40 INFO - Running setup.py (path:/tmp/pip-8UrAvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:02:40 INFO - Running setup.py (path:/tmp/pip-_8JwpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:02:40 INFO - Running setup.py (path:/tmp/pip-EGzl1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:02:40 INFO - Running setup.py (path:/tmp/pip-bhybnt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:02:40 INFO - Running setup.py (path:/tmp/pip-ua1ZyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:02:41 INFO - Running setup.py (path:/tmp/pip-0RXcz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:02:41 INFO - Running setup.py (path:/tmp/pip-faOZ5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:02:41 INFO - Running setup.py (path:/tmp/pip-iybdK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:02:41 INFO - Running setup.py (path:/tmp/pip-aH14gy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:02:41 INFO - Running setup.py (path:/tmp/pip-o4D1j8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:02:41 INFO - Running setup.py (path:/tmp/pip-wPJuhb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:02:41 INFO - Running setup.py (path:/tmp/pip-7b5K4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:02:41 INFO - Running setup.py (path:/tmp/pip-kPdYa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:41 INFO - Running setup.py (path:/tmp/pip-MdmmhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:02:41 INFO - Running setup.py (path:/tmp/pip-pnoKUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:02:41 INFO - Running setup.py (path:/tmp/pip-7prSYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:02:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:02:41 INFO - Running setup.py install for manifestparser 16:02:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:02:41 INFO - Running setup.py install for mozcrash 16:02:41 INFO - Running setup.py install for mozdebug 16:02:42 INFO - Running setup.py install for mozdevice 16:02:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:02:42 INFO - Running setup.py install for mozfile 16:02:42 INFO - Running setup.py install for mozhttpd 16:02:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:02:42 INFO - Running setup.py install for mozinfo 16:02:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:02:42 INFO - Running setup.py install for mozInstall 16:02:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:02:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:02:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:02:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:02:42 INFO - Running setup.py install for mozleak 16:02:42 INFO - Running setup.py install for mozlog 16:02:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:02:42 INFO - Running setup.py install for moznetwork 16:02:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:02:42 INFO - Running setup.py install for mozprocess 16:02:43 INFO - Running setup.py install for mozprofile 16:02:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:02:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:02:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:02:43 INFO - Running setup.py install for mozrunner 16:02:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:02:43 INFO - Running setup.py install for mozscreenshot 16:02:43 INFO - Running setup.py install for moztest 16:02:43 INFO - Running setup.py install for mozversion 16:02:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:02:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:02:43 INFO - Cleaning up... 16:02:43 INFO - Return code: 0 16:02:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02:43 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')]} 16:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:43 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')]} 16:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:43 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 0xc931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfffa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1003090>, '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 0x1003d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xffdf90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', '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 16:02:43 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 16:02:43 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 16:02:43 INFO - Using env: {'DISPLAY': ':0', 16:02:43 INFO - 'HOME': '/home/cltbld', 16:02:43 INFO - 'LANG': 'en_US.UTF-8', 16:02:43 INFO - 'LANGUAGE': 'en_US:en', 16:02:43 INFO - 'LOGNAME': 'cltbld', 16:02:43 INFO - 'MAIL': '/var/mail/cltbld', 16:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:43 INFO - 'MOZ_NO_REMOTE': '1', 16:02:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:43 INFO - 'NO_EM_RESTART': '1', 16:02:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:43 INFO - 'PWD': '/builds/slave/test', 16:02:43 INFO - 'SHELL': '/bin/bash', 16:02:43 INFO - 'SHLVL': '1', 16:02:43 INFO - 'TERM': 'linux', 16:02:43 INFO - 'TMOUT': '86400', 16:02:43 INFO - 'USER': 'cltbld', 16:02:43 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', 16:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:43 INFO - '_': '/tools/buildbot/bin/python'} 16:02:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:02:43 INFO - Running setup.py (path:/tmp/pip-ARb_I6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:02:43 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)) 16:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:02:43 INFO - Running setup.py (path:/tmp/pip-g74AqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:02:43 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)) 16:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:02:43 INFO - Running setup.py (path:/tmp/pip-scmYG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:02:43 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)) 16:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:02:43 INFO - Running setup.py (path:/tmp/pip-AqnLE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:02:44 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)) 16:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:02:44 INFO - Running setup.py (path:/tmp/pip-m8LVfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:02:44 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)) 16:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:02:44 INFO - Running setup.py (path:/tmp/pip-jHnkvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:02:44 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)) 16:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:02:44 INFO - Running setup.py (path:/tmp/pip-_yLN99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:02:44 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)) 16:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:02:44 INFO - Running setup.py (path:/tmp/pip-PTO_gc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:02:44 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)) 16:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:02:44 INFO - Running setup.py (path:/tmp/pip-tSFiMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:02:44 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)) 16:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:02:44 INFO - Running setup.py (path:/tmp/pip-Pi1XLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:02:44 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)) 16:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:02:44 INFO - Running setup.py (path:/tmp/pip-djSMhk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:02:44 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)) 16:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:02:44 INFO - Running setup.py (path:/tmp/pip-25h5we-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:02:44 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)) 16:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:02:44 INFO - Running setup.py (path:/tmp/pip-ktdMyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:02:44 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)) 16:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:02:44 INFO - Running setup.py (path:/tmp/pip-AwYKQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:02:44 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)) 16:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:44 INFO - Running setup.py (path:/tmp/pip-kSWTeS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:44 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)) 16:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:02:44 INFO - Running setup.py (path:/tmp/pip-uvBn1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:02:44 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)) 16:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:02:44 INFO - Running setup.py (path:/tmp/pip-ghbGZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:02:44 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)) 16:02:44 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)) 16:02:44 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)) 16:02:44 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)) 16:02:44 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)) 16:02:44 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)) 16:02:44 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)) 16:02:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:02:44 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 16:02:44 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 16:02:44 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 16:02:44 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 16:02:46 INFO - Downloading blessings-1.6.tar.gz 16:02:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:02:46 INFO - Installing collected packages: blessings 16:02:46 INFO - Running setup.py install for blessings 16:02:46 INFO - Successfully installed blessings 16:02:46 INFO - Cleaning up... 16:02:46 INFO - Return code: 0 16:02:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:02:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:02:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:02:46 INFO - Reading from file tmpfile_stdout 16:02:46 INFO - Current package versions: 16:02:46 INFO - argparse == 1.2.1 16:02:46 INFO - blessings == 1.6 16:02:46 INFO - blobuploader == 1.2.4 16:02:46 INFO - docopt == 0.6.1 16:02:46 INFO - functools32 == 3.2.3-2 16:02:46 INFO - jsonschema == 2.5.1 16:02:46 INFO - manifestparser == 1.1 16:02:46 INFO - mozInstall == 1.13 16:02:46 INFO - mozcrash == 1.0 16:02:46 INFO - mozdebug == 0.1 16:02:46 INFO - mozdevice == 0.51 16:02:46 INFO - mozfile == 1.2 16:02:46 INFO - mozhttpd == 0.7 16:02:46 INFO - mozinfo == 0.10 16:02:46 INFO - mozleak == 0.1 16:02:46 INFO - mozlog == 3.5 16:02:46 INFO - moznetwork == 0.27 16:02:46 INFO - mozprocess == 0.25 16:02:46 INFO - mozprofile == 0.28 16:02:46 INFO - mozrunner == 6.13 16:02:46 INFO - mozscreenshot == 0.1 16:02:46 INFO - mozsystemmonitor == 0.3 16:02:46 INFO - moztest == 0.8 16:02:46 INFO - mozversion == 1.4 16:02:46 INFO - psutil == 3.1.1 16:02:46 INFO - requests == 1.2.3 16:02:46 INFO - wsgiref == 0.1.2 16:02:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02:46 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')]} 16:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:46 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')]} 16:02:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:46 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 0xc931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfffa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1003090>, '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 0x1003d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xffdf90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', '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 16:02:46 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 16:02:46 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 16:02:46 INFO - Using env: {'DISPLAY': ':0', 16:02:46 INFO - 'HOME': '/home/cltbld', 16:02:46 INFO - 'LANG': 'en_US.UTF-8', 16:02:46 INFO - 'LANGUAGE': 'en_US:en', 16:02:46 INFO - 'LOGNAME': 'cltbld', 16:02:46 INFO - 'MAIL': '/var/mail/cltbld', 16:02:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:46 INFO - 'MOZ_NO_REMOTE': '1', 16:02:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:46 INFO - 'NO_EM_RESTART': '1', 16:02:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:46 INFO - 'PWD': '/builds/slave/test', 16:02:46 INFO - 'SHELL': '/bin/bash', 16:02:46 INFO - 'SHLVL': '1', 16:02:46 INFO - 'TERM': 'linux', 16:02:46 INFO - 'TMOUT': '86400', 16:02:46 INFO - 'USER': 'cltbld', 16:02:46 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', 16:02:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:46 INFO - '_': '/tools/buildbot/bin/python'} 16:02:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:46 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)) 16:02:46 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)) 16:02:46 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)) 16:02:46 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)) 16:02:46 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)) 16:02:46 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)) 16:02:46 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)) 16:02:46 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)) 16:02:46 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)) 16:02:46 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:02:46 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 16:02:46 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 16:02:46 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 16:02:46 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 16:02:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:02:47 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)) 16:02:47 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)) 16:02:47 INFO - Installing collected packages: simplejson 16:02:47 INFO - Running setup.py install for simplejson 16:02:47 INFO - building 'simplejson._speedups' extension 16:02:47 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 16:02:48 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 16:02:48 INFO - Successfully installed simplejson 16:02:48 INFO - Cleaning up... 16:02:48 INFO - Return code: 0 16:02:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:02:48 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')]} 16:02:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:48 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')]} 16:02:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:48 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 0xc931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfffa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1003090>, '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 0x1003d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xffdf90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', '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 16:02:48 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 16:02:48 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 16:02:48 INFO - Using env: {'DISPLAY': ':0', 16:02:48 INFO - 'HOME': '/home/cltbld', 16:02:48 INFO - 'LANG': 'en_US.UTF-8', 16:02:48 INFO - 'LANGUAGE': 'en_US:en', 16:02:48 INFO - 'LOGNAME': 'cltbld', 16:02:48 INFO - 'MAIL': '/var/mail/cltbld', 16:02:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:48 INFO - 'MOZ_NO_REMOTE': '1', 16:02:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:48 INFO - 'NO_EM_RESTART': '1', 16:02:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:48 INFO - 'PWD': '/builds/slave/test', 16:02:48 INFO - 'SHELL': '/bin/bash', 16:02:48 INFO - 'SHLVL': '1', 16:02:48 INFO - 'TERM': 'linux', 16:02:48 INFO - 'TMOUT': '86400', 16:02:48 INFO - 'USER': 'cltbld', 16:02:48 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', 16:02:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:48 INFO - '_': '/tools/buildbot/bin/python'} 16:02:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:02:49 INFO - Cleaning up... 16:02:49 INFO - Return code: 0 16:02:49 INFO - Running post-action listener: _resource_record_post_action 16:02:49 INFO - Running post-action listener: _start_resource_monitoring 16:02:49 INFO - Starting resource monitoring. 16:02:49 INFO - [mozharness: 2017-09-02 23:02:49.206294Z] Finished create-virtualenv step (success) 16:02:49 INFO - [mozharness: 2017-09-02 23:02:49.206814Z] Running install step. 16:02:49 INFO - Running pre-action listener: _resource_record_pre_action 16:02:49 INFO - Running main action method: install 16:02:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:02:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:02:49 INFO - Reading from file tmpfile_stdout 16:02:49 INFO - Detecting whether we're running mozinstall >=1.0... 16:02:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:02:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:02:49 INFO - Reading from file tmpfile_stdout 16:02:49 INFO - Output received: 16:02:49 INFO - Usage: mozinstall [options] installer 16:02:49 INFO - Options: 16:02:49 INFO - -h, --help show this help message and exit 16:02:49 INFO - -d DEST, --destination=DEST 16:02:49 INFO - Directory to install application into. [default: 16:02:49 INFO - "/builds/slave/test"] 16:02:49 INFO - --app=APP Application being installed. [default: firefox] 16:02:49 INFO - mkdir: /builds/slave/test/build/application 16:02:49 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'] 16:02:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:03:06 INFO - Reading from file tmpfile_stdout 16:03:06 INFO - Output received: 16:03:06 INFO - /builds/slave/test/build/application/firefox/firefox 16:03:06 INFO - Running post-action listener: _resource_record_post_action 16:03:06 INFO - [mozharness: 2017-09-02 23:03:06.088336Z] Finished install step (success) 16:03:06 INFO - [mozharness: 2017-09-02 23:03:06.088586Z] Running setup-mitmproxy step. 16:03:06 INFO - Running pre-action listener: _resource_record_pre_action 16:03:06 INFO - Running main action method: setup_mitmproxy 16:03:06 INFO - Skipping: mitmproxy is not required 16:03:06 INFO - Running post-action listener: _resource_record_post_action 16:03:06 INFO - [mozharness: 2017-09-02 23:03:06.089729Z] Finished setup-mitmproxy step (success) 16:03:06 INFO - [mozharness: 2017-09-02 23:03:06.089947Z] Running run-tests step. 16:03:06 INFO - Running pre-action listener: _resource_record_pre_action 16:03:06 INFO - Running pre-action listener: _set_gcov_prefix 16:03:06 INFO - Running main action method: run_tests 16:03:06 WARNING - Try message not found. 16:03:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:03:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:03:06 INFO - Python 2.7.3 16:03:06 INFO - Return code: 0 16:03:06 INFO - grabbing minidump binary from tooltool 16:03:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:06 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 0x1003090>, '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 0x1003d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xffdf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:03:06 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 16:03:06 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 16:03:06 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 16:03:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:03:06 INFO - Return code: 0 16:03:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:03:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:03:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:03:06 INFO - ENV: RUST_BACKTRACE is now full 16:03:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:03:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:03:06 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/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 16:03:06 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/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-041 --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 16:03:06 INFO - Using env: {'DISPLAY': ':0', 16:03:06 INFO - 'HOME': '/home/cltbld', 16:03:06 INFO - 'LANG': 'en_US.UTF-8', 16:03:06 INFO - 'LANGUAGE': 'en_US:en', 16:03:06 INFO - 'LOGNAME': 'cltbld', 16:03:06 INFO - 'MAIL': '/var/mail/cltbld', 16:03:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:03:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:03:06 INFO - 'MOZ_ACCELERATED': '1', 16:03:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:06 INFO - 'MOZ_NO_REMOTE': '1', 16:03:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:03:06 INFO - 'MOZ_WEBRENDER': '1', 16:03:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:06 INFO - 'NO_EM_RESTART': '1', 16:03:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:06 INFO - 'PWD': '/builds/slave/test', 16:03:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:03:06 INFO - 'RUST_BACKTRACE': 'full', 16:03:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:03:06 INFO - 'SHELL': '/bin/bash', 16:03:06 INFO - 'SHLVL': '1', 16:03:06 INFO - 'TERM': 'linux', 16:03:06 INFO - 'TMOUT': '86400', 16:03:06 INFO - 'USER': 'cltbld', 16:03:06 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', 16:03:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:06 INFO - '_': '/tools/buildbot/bin/python'} 16:03:06 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/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 16:03:06 INFO - mozversion application_buildid: 20170902220034 16:03:06 INFO - mozversion application_changeset: f5d99e9f3d7bf19c8a222db97bedc924da27fd58 16:03:06 INFO - mozversion application_display_name: Nightly 16:03:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:03:06 INFO - mozversion application_name: Firefox 16:03:06 INFO - mozversion application_remotingname: firefox 16:03:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:03:06 INFO - mozversion application_vendor: Mozilla 16:03:06 INFO - mozversion application_version: 57.0a1 16:03:06 INFO - mozversion platform_buildid: 20170902220034 16:03:06 INFO - mozversion platform_changeset: f5d99e9f3d7bf19c8a222db97bedc924da27fd58 16:03:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:03:06 INFO - mozversion platform_version: 57.0a1 16:03:06 INFO - using testdate: 1504393386 16:03:06 INFO - actual date: 1504393386 16:03:06 INFO - starting webserver on 'localhost:46338' 16:03:06 INFO - SUITE-START | Running 2 tests 16:03:06 INFO - TEST-START | dromaeo_css 16:03:06 INFO - Initialising browser for dromaeo_css test... 16:03:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46338/getInfo.html -profile /tmp/tmpnlDIJQ/profile 16:03:06 INFO - TEST-INFO | started process 18109 (/builds/slave/test/build/application/firefox/firefox http://localhost:46338/getInfo.html) 16:03:13 INFO - TEST-INFO | 18109: exit 0 16:03:14 INFO - Browser initialized. 16:03:14 INFO - Running cycle 1/1 for dromaeo_css test... 16:03:14 INFO - Using env: {'DISPLAY': ':0', 16:03:14 INFO - 'HOME': '/home/cltbld', 16:03:14 INFO - 'JSGC_DISABLE_POISONING': '1', 16:03:14 INFO - 'LANG': 'en_US.UTF-8', 16:03:14 INFO - 'LANGUAGE': 'en_US:en', 16:03:14 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:03:14 INFO - 'LOGNAME': 'cltbld', 16:03:14 INFO - 'MAIL': '/var/mail/cltbld', 16:03:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:03:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:03:14 INFO - 'MOZ_ACCELERATED': '1', 16:03:14 INFO - 'MOZ_CRASHREPORTER': '1', 16:03:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:14 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:03:14 INFO - 'MOZ_NO_REMOTE': '1', 16:03:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:03:14 INFO - 'MOZ_WEBRENDER': '1', 16:03:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:14 INFO - 'NO_EM_RESTART': '1', 16:03:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:14 INFO - 'PWD': '/builds/slave/test', 16:03:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:03:14 INFO - 'RUST_BACKTRACE': 'full', 16:03:14 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:03:14 INFO - 'SHELL': '/bin/bash', 16:03:14 INFO - 'SHLVL': '1', 16:03:14 INFO - 'TERM': 'linux', 16:03:14 INFO - 'TMOUT': '86400', 16:03:14 INFO - 'USER': 'cltbld', 16:03:14 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', 16:03:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:14 INFO - '_': '/tools/buildbot/bin/python'} 16:03:14 INFO - TEST-INFO | started process 18308 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnlDIJQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:03:14 INFO - PID 18308 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:03:15 INFO - PID 18308 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:03:15 INFO - PID 18308 | 16:03:15 INFO - PID 18308 | (/builds/slave/test/build/application/firefox/firefox:18370): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:03:15 INFO - PID 18308 | 16:03:25 INFO - PID 18308 | RSS: Main: 204173312 16:03:25 INFO - PID 18308 | 16:03:29 INFO - PID 18308 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:06:24 INFO - PID 18308 | Cycle 1(1): loaded http://localhost:46338/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46338/tests/dromaeo/cssquery-ext.html) 16:06:26 INFO - PID 18308 | RSS: Main: 217755648 16:06:26 INFO - PID 18308 | 16:09:28 INFO - PID 18308 | Cycle 1(1): loaded http://localhost:46338/tests/dromaeo/cssquery-ext.html (next: http://localhost:46338/tests/dromaeo/cssquery-jquery.html) 16:09:28 INFO - PID 18308 | RSS: Main: 214929408 16:09:28 INFO - PID 18308 | 16:12:30 INFO - PID 18308 | Cycle 1(1): loaded http://localhost:46338/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46338/tests/dromaeo/cssquery-mootools.html) 16:12:31 INFO - PID 18308 | RSS: Main: 220020736 16:12:31 INFO - PID 18308 | 16:15:33 INFO - PID 18308 | Cycle 1(1): loaded http://localhost:46338/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46338/tests/dromaeo/cssquery-prototype.html) 16:15:34 INFO - PID 18308 | RSS: Main: 215433216 16:15:34 INFO - PID 18308 | 16:18:38 INFO - PID 18308 | Cycle 1(1): loaded http://localhost:46338/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46338/tests/dromaeo/cssquery-yui.html) 16:18:38 INFO - PID 18308 | RSS: Main: 217128960 16:18:38 INFO - PID 18308 | 16:21:40 INFO - PID 18308 | Cycle 1(1): loaded http://localhost:46338/tests/dromaeo/cssquery-yui.html (next: http://localhost:46338/tests/dromaeo/cssquery-dojo.html) 16:21:41 INFO - PID 18308 | RSS: Main: 220286976 16:21:41 INFO - PID 18308 | 16:21:41 INFO - PID 18308 | __start_tp_report 16:21:41 INFO - PID 18308 | _x_x_mozilla_page_load 16:21:41 INFO - PID 18308 | _x_x_mozilla_page_load_details 16:21:41 INFO - PID 18308 | |i|pagename|runs| 16:21:41 INFO - PID 18308 | |0;dojo.html;3309.1727068232935;3316.5854268216467;3334.666533346664;3348.6986171244585;3374.5613070300856;6411.422971932525;6455.289918109004;6491.837704057395;6510.056041873925;6520.25017123031;6296.937030629692;6403.231612206541;6425.13260709804;6447.903281450782;6448;5741.569382296324;5813.82558523245;5823.3012038555435;5839.854003649911;5845.912311315334;5813.970930145343;5814.5057670098095;5815.621984570989;5823.941760582404;5828.3297420796725;5771.82684519463;5801.680907550083;5820.1269809528485;5840.883182336351;5846.590738648296;5753.280839895013;5819.156222347779;5842.73707683151;5853.414658534134;5857.033589457735;5738.311402631669;5909.379515150933;5936.376680448534;5971.313298970587;6069;6132.325444201134;6293.087502312139;6354.87290254197;6386.904196437057;6387.904181437282;6780.288069752654;6788.287229840845;6804.863902721967;6856.897146542806;6877.2091209511045;6986.825329366756;7018.649067546653;7025.964870175617;7043.260457651975;7048.400885924702;6352.777652782131;6395.584287021329;6401.071844582556;6489.643069631168;6491.51313651478;6896.137982752156;7003.929960700328;7037.683304251321;7164.355208031198;7169.139703235645;6684.766033188815;6699.196096468455;6722.495812814059;6724.731010759515;6745.2242992056545;6693.397594216543;6727.798166055026;6750.729970801113;6772.966135169391;6780.084688566981;5820;6736.191657001191;6840.213375461885;6953.791386258319;6968.825779355455;6862.416694581017;6876.862462750717;6968.338007889242;6970.93029069713;6987.720491180397;5291.153415453509;5376.946230537645;5398.730063496888;5410.97294513525;5464.1257398816;4740.383750112533;4749.26386410107;4754.310624959361;4756;4810.278458231293;5995.130706047686;6030.517558595302;6046.818595442144;6052.54605904559;6076.787312444043;6792;6844.657767111724;6897.48268879836;6947.617881016491;6966.164060312795;5821.505172060338;5920;5987.042073268256;6002.909956350658;6079.75680972765;5861.589688721749;5915.733791979371;5966.791162309299;5991.131285963511;5992.67040312787;6649.202095748445;6682.298358672367;6688.163979502562;6695.631740254235;6782.593044417351;6749.730010799513;6863.313668633096;6872.862542749217;6923.861522769415;6995.230524642387;6634.33656634352;6639.336066393322;6650.135482387259;6656.201255849135;6657.201135863727;6686.398224159849;6714.093597364488;6726.058351830846;6744.898826517704;6753.628550429774;6787.93212067873;6870.106886104774;6917.412019978358;6919.342662447059;6939.097917270722;6525.119108920235;6569.80290591283;6655.900161497679;6657.101291325609;6667.099941508029;5863.990125010179;6521.380468855451;6612.338766123349;6633.767818126343;6649.202095748541;6545.607263564201;6545.803625891041;6563.704633291609;6640.568363056386;6642.568233065029;6519.608823470607;6611.404973552211;6649.501287403367;6670.966645166744;6730.25967143604;6387.361263873576;6412.070249813844;6427.93572064292;6446.67766611677;6470.061841033118 16:21:41 INFO - PID 18308 | |1;ext.html;800.4676889868238;831.3432388413154;859.8151397449549;860.6342304520577;1124.5052177042105;30666.080017599452;30947.38105237894;31346;40708.389374159415;41293;42789.35815962763;43061.78469107658;43100.568994310044;43330;43363.566364336344;7591.202923693016;7729.497582657109;7863.095743989457;7871.606419679022;7911.129775724666;7681.57751323677;7773.883391749128;7780.105287891908;7792.142864284924;7795.610219489031;7769.456138070309;7806.882896756553;7815.10126335473;7829.86466962293;7836.843263134734;7792.649330780128;7798.376129909594;7801.921980780205;7812.453128281023;7825.491343062711;7685.0777906651;7776.066871975343;7795.220477952216;7797.064352277763;7804.609769511501;36749.81625091884;36929.815350923076;37103;37299.62700372989;37364.43953340702;3483.3207524532727;3499.790012599227;3499.930001399983;3519.225770330523;3527.523784289114;31034.689653103407;31064.689353106405;31093.067207983797;31188.376232475224;31263.531047034314;4979.950200497985;5136;5140.691558506502;5162.612804039674;5167.354080739908;186670;186765;187533;189151;189744;47216.29175562369;47446;47776.522234777905;47854;47926.5207347929;49499;53571;54375.728121359934;54398;54459;15676.764848527282;15974.840251597336;16119.919400402923;16176.919115404347;16229.675406491804;11604.361760103275;11860.525578976743;13211.735765284833;13380.732385352238;13419.664508387368;14288.28558572088;14434.927825360806;14449.638759031108;14554.199519026344;14641.121532708072;5714.914276285859;5741.770329186869;5821.18503409523;5858.8242352729485;5867.20792692981;972.8151651186251;998.5805961496189;999.48526508848;1003.8193125237382;1014.9594016239415;22618.886905565367;22702.205422810122;22828.885855570617;22922;22969.88515057414;1097.1880808202034;1111.6831702964616;1113.209621168979;1114.2924243105524;1116.7431490757172;11878.762424751456;12204.02367810573;12261.75476490483;12270.018398528096;12357.38213089342;236037;236204;236598;238672;238705;52352.21471677928;52358.73820630873;52414.73792631089;52489.737551312;52557.37213139404;1418.7942748301646;1441.8990670653163;1462.8683418492174;1463.1952426165567;1466.2829726642908;22266.443338916655;22582.435439113495;22936.770632293465;26871.865640671673;28330.2917427066;50593.24110138277;50701.74649126731;50917.23624145567;51072.744636276584;51189.48810511847;213215;213867;214130;214200;214320;31641.683583163875;31864.522032169072;32479.35041299208;32710.50934236036;35896.64103358933;38958.220835582564;43622.56377436185;44065.77967110144;44674.3298850511;45220;32001.839990799897;32195;32352;32455.513167302983;32498.350032998736;30866.382672346877;30912.69087309188;31573.526397103604;31608.683913160574;31651.84174079207;36005.63994360023;36296.4555531662;37961.43057854079;38792.41811372888;38835.417468737425;32679.50980735243;32710.83644581762;32930.34139317248;33067.66932330646;33482.99551013474;24061.398465038514;24255.272341829772;30172;30280.697193027787;30674.07977760671 16:21:41 INFO - PID 18308 | |2;jquery.html;561.8286422641092;573.4838645219302;582.2343618142146;582.2925145947672;595.523581135092;21828.345149645516;22217.2223972161;22406.88796556019;22666.433339166528;22694.773052269473;23717.407064823386;25006;26074.305170163927;26200.868995654993;26273.08044218448;6596.076549283104;6784.72861085556;6797.286284940084;6803.387695107439;6805.55763875349;6533.640649764261;6628.304028077055;6653.866922661544;6691.029800678923;6694.297098804628;6353.332900045498;6535.0524173994745;6553.967230163867;6564.80305590833;6671.132752742161;6531.150950376445;6582.769603063918;6596.736130554772;6597;6612.93387066128;6106.297775755756;6115.633062016249;6126.448619624255;6127.468132966758;6133.6626485543275;27940.58089128665;28598.714012860022;28733;28857.711422885714;28964.71035289662;2039.7552293724848;2057.609054279682;2073.39871437283;2076.730025096728;2079.8440116991137;18883.433496995112;19662.41012769605;19693.80306196934;19740.605187896163;19818.504537386536;2248.482848944752;2286.6455699366625;2335.0659736105695;2338.4621537046405;2349.5300939812105;133191.33404332917;133895.6610433893;134417;134572;135399;14172;14499.637509062359;15115.697686046218;15522.922385388001;15823.841761582236;23056.308310750705;23071.19250826213;23087.191948282067;23218.071277148723;23301;10032.949835250776;10077.899221007696;10126;10247;10258.28192026551;9302.116298951589;9395.718128456158;9487.098725621056;9620.278479114095;9710.805783884423;8886.200241978253;8966.462012279284;9011.684591039282;9098.454092754457;9333.20667743236;2611.66048413705;2623.3572774669947;2626.684797824273;2706.9593956090675;2718.415540658768;930.6137952749533;954.3939598354956;962.605331813953;963.9566219520138;976.5361453309621;19711.60576788456;20076.498087547927;20104.69842952357;20126.59746805085;20376;1006.8993100689872;1033.3541536539663;1037.3412882819464;1038.8441733739849;1056.5932116135189;11012.229143960007;11200.159988000933;11218.495167717472;11256.774864502664;11341.036011938913;448822;507582;512558;530616;549516;115241.4237928805;119047;120596.39701801435;121184.3940780308;121674.39162804306;1198.5146015863588;1254.1033161289722;1256.6983923858209;1262.9494820207271;1267.6260503151593;20688.275910343065;20838.687419688413;20958.266460674415;21376;21601.891990539945;55844;56119.158212626025;56546.434535654116;58303.708481457325;58789.11816322673;67345;68097.65951170212;68171;68180.31819681938;68999.65500172468;13606.319684015958;14098.36557354901;14109.929450353093;14553.781693274397;14828.629284267548;23585;23666.17168399098;23677.289681309587;23913.402164946016;24189.15337963163;18439.53901152482;19001.52496187551;19100.522486937938;19193.32823351176;19211.90394048021;17202.225899834288;17323.56691082733;17352.04563749006;17436.47690569285;17775.91112044483;18633.53416164607;19793.802061979197;19851.702224466357;19858.10638521241;19960.70058949146;14267.571972840831;14582;14676.3395647194;14689.41242350294;14740.262986850401;15900.12549309799;18348.816511835244;18351.265949361878;18720.15759290808;18733.157007934402 16:21:41 INFO - PID 18308 | |3;mootools.html;1318.1300341774427;1386.4939297156534;1387.9375428105734;1392.3525560614316;1404.2206575350676;14826;15256.160911149882;15499.302531386087;15529.145896975677;15559.29983150758;15427.305771240293;15843.287052082655;15852.28664710088;15863.127527985956;15870.285837137328;2732.7130651281677;2769.5014897318474;2779.069011881012;2785.5403858363447;2787.581862720598;2695.514807334679;2715.2804506805714;2770.390514086908;2778.19432364614;2884.480793457177;2816.084772448952;2817.8591070446496;2831.0940499040316;2844.3742376677096;2848.017433985282;2703.3106557827823;2718.7824974002033;2737.534619114765;2744.3550765570076;2796.566532187514;2573.9227823165334;2622.331305517081;2628.2772237634613;2641.9471610567875;2649.2449651849324;14886.255687215596;15060.246987650684;15099.849001510063;15207.923960380127;15246.46637367698;8034.9598252008955;8348.874766878502;8358.080611132771;8362.327534493126;8370.790730231793;5096.200800859452;5343.65266257692;5351.224072509464;5636.464535869086;5662.065759149736;1932.5265309999;1946.6787979983285;2000.41987823531;2031.9390418287328;2039.1537511932531;26362.604560931602;26423;26573.601395979076;26577.202683919513;26630.86684566584;6668.099806526154;6710.261871194165;6735.966320168466;6742.258351581322;6870;17043.659126817394;17195.7420638688;17213.397531086353;17251.74122388165;17277.222524986406;7957.641906114239;7987.480813747204;8000.319972802377;8010.559419231999;8041.557714325651;7423.9257607424315;7460.776176714708;7530.322270995636;7563.600733864253;7580.393568514505;5166.9224961625605;5316.362036555638;5332.093544097513;5386.730663466889;5401.432849550819;1428.8999770016208;1480.2302802542833;1573.9134347610761;1737.6177241006958;1806.4761219246273;900.1853322742928;921.1847514949184;924.4684306523776;925.3707478914268;940.3981451870808;7769.873368361556;7973.60131993398;8253.876191857127;8374.790630234293;8558.743237702878;991.1525645573075;991.7503945029315;994.5425104451889;1019.6329321444274;1030.3354336452946;5177.275181474596;5195.479413761434;5308.787648494017;5333.839984800462;5356.196570514454;20004.19983200685;21846.453838653844;21905;21931.67102493464;21932.342029739135;20566.38300850977;21097.578048438947;21443.57112857734;21620.35138945834;21655.891720541298;11120.22158448917;11174.888251117547;11438.771224575461;11519.88480115205;11531.48108335127;8265.95867020661;8461.40770146084;8467.153284671484;8562.657493700182;8915.42049766763;18174.36389726353;18671.906640467256;18691.719624205918;19269.903650481658;19289.710654339917;18145.727814082536;18995.71506427432;19062.428127156207;19595.118219680433;19706.31027914016;11862.881371186179;12166.209196402206;12319.322437266037;12323.69190770238;12361.07291953125;13795.862041379458;13881;13910.165390076629;13915.165090094626;13915.373808178858;10802.422936063665;10980.066694331072;11041.06150977176;11056.834147487632;11231.55073797039;10469.162467002468;10613.522391492246;11000.174986875856;11063.889361106609;11090.334579925231;9297.163255307054;9300.767480813034;9314.534273286445;9347.439153650803;9353.672621458216;6456.160699109086;6543.803685889431;6598.934010660024;6622.63575503352;6653.467722582279;6476.8704625906275;6552.803415897531;6678.899816502853;6738.59568425896;6889.448844092561 16:21:41 INFO - PID 18308 | |4;prototype.html;503.5115937540584;503.9190935443473;523.6203752279598;532.6005495878092;536.05654048874;6139.386061393859;6288;6374.617522948626;6380.872382552347;6445.548811583185;6221.906671399933;6224.564280500367;6244.469220116295;6288.182536270278;6313.305536390993;5015.297858299841;5031.597472202233;5050.090983622947;5070.923936140961;5100.285959965608;5028.7988480460735;5074.441811400743;5075.162598171316;5093.643444958854;5098.719570423626;4763.166445871959;4953.1332016896895;4960.206366981283;5017.648764586462;5100.821471248507;5030.798768049311;5031.874203144951;5045.495450454961;5052.267421223902;5103.897922041557;4505.189065968125;4532.116237333721;4542.8864278392975;4558.544145585448;4569.47451043131;6130.969345153246;6177.567570270083;6179.752809887644;6188.504919606421;6213.5339849511465;5782.132680097977;5791.131330300446;5799.21710569072;5813.505852002585;5824.737886795105;6652.800415987528;6652.866942661168;6658.434033107192;6664.8000559983275;6679.699413526354;4750.358701575312;4845.903081938377;4850.345203397532;4944.134776414149;4953.405591329028;7043.9647801761175;7066.505344625878;7066.540674856118;7068.056284521746;7080.672084120455;5009.173486374781;5041.6722913010535;5078.669886457443;5099.490050994945;5105.668131571436;5074.908894587681;5121.795128194831;5128.461511541309;5148.304978827885;5169.353830771153;5588.972055139699;5662.886742265132;5729.942700573024;5765.596408251382;5774.335951365647;6614.371634694697;6641.568298060611;6657.800265992028;6693.196816382066;6704.061431399608;1667.924943377551;1676.773635559184;1718.9742153867703;1753.9386121485686;1754.3158168314367;1194.8028575285155;1196.5512932650256;1227.7544491101637;1231.4704676988802;1248.9571208041204;5814.3313518945015;5895.616784908967;5916.26046744157;5930.614510056833;5931.7627294907725;6586;6738.258791532928;6776.2546119926765;6817.863642727118;6843.555168914005;5871.354151043467;5887.20522729426;5952.821415357546;5969;5993.250843644544;3485.006773069663;3513.4729790531624;3632.418812989909;3647.252313275825;3649.7445178837756;6637.900431493532;6643.534952553275;6655.073506631111;6662.433693136138;6685.799426017227;6875.4155896749335;6927.3419025192525;7040.823979400454;7061.788146355618;7069.929300706927;6581.868362632625;6617.636030018395;6667.43326817226;6680.13158289429;6687;6760.357766012221;6812.455003599601;6850.9314906850295;6858.931410686029;6870.244273130052;6605.768798092044;6714.227863795726;6758.324167583399;6763.512027353974;6793.966030169818;6529.9020514691365;6582.703778329891;6588.242352129375;6604.504662150454;6707.463402927851;4940.431850337257;4992.026554821775;5014.448410674896;5028.522290382408;5066.518680725325;6881.862362752817;6920.342567456084;6929.549579277331;6942.119020606594;7022.824429389306;6586.934130658632;6640.402363787282;6669.1330127083165;6682.231543372574;6682.298358672464;6438.935610643834;6501.284858665637;6517.348265173445;6532.778855189258;6628;5921.240012591949;6228.252609686685;6348.396902294275;6432.45324147434;6456.515761317826;6575.079488871466;6605.834854128493;6624.588337006349;6666;6675.299093595297;6373.2989371168205;6394.712237949397;6417.336805225779;6459.063435801876;6479.902801458076 16:21:41 INFO - PID 18308 | |5;yui.html;852.9530875801833;881.5371929736888;884.0364003236471;918.2011649864617;936.765808547863;17561.121943902803;18452.169652365643;18683.346082887103;18696.719549206755;18710.53223669409;16226.675466490664;17724.1137943103;17870.910645446787;17931;18096.457106286798;959.6976952260034;984.3749219245794;986.8223719730446;990.7572644702058;993.4635296939643;910.8360495110877;995.7460847483918;1001.8897921228659;1002.128148510793;1005.416858222233;1052.2791887557048;1052.8420736889452;1053.9841902371431;1058.1006144776916;1058.9682309530688;1050.9737256568571;1051.9263651544395;1054.752133248686;1055;1056.1814593689876;773.3465221887496;774.1755030892139;774.8217909880702;775.8874963130371;778.0313509680484;18699.53251168718;19257.9037104815;19656;19831.206751729915;19954.401367958984;21062.157513699432;21215.151393944223;21681.34955951324;21721.34835954908;22017;16795.496135115965;17113.914430427893;17252.482425527254;17252.827471725246;17728.91135544327;3672.1186915140174;3707.388280933643;3708.313961917027;3710.610385909468;3714.4799728038097;122935.3853230737;124551;131988;136706;138399;15652.4521641742;16339;16482.34070637161;16554.668906622042;16564.585885352964;30064.79092926992;30606.23484412863;30743.38513229723;31535;31599;9102.681406150752;9145.542722863964;9171.128742769426;9182.449053056838;9189.448633082036;4068.3694027425795;4217.367394890791;4227.027783609787;4232.111256636079;4242.109157077049;4769.236922092448;4973.875653108629;4974.950250497521;5446.754896029688;5668.206451096849;1088.4612117001977;1124.7075760302216;1129.9774004519863;1132.5526417065214;1140.8402823604702;682.9624370659562;693.7918624412656;696.4985210648325;704.457567672889;709.6664567653298;11519.769604607862;11715.707107322385;12200.938995304967;12344;13242.867571324163;709.5636183746958;710.0662628643317;716.6416791604198;726.5313872552174;734.4601717737459;5691.9715401423555;5731.398203188689;5737.139429085587;5802.187693722883;6017.127516510169;103274;105523;106838.46580767047;110115;110798.44600776944;91869.54065229764;92814.53592731993;95830;97783.51108244414;98278;87152.94964676333;88940.5552972231;90129.54935225414;91614;91722.54138729397;11694.356810375511;12032.398380081137;12084.697882553006;12115.333656648969;12263.38683065861;39626;39840.40239396354;40545.594544054184;40725.592744073365;41140.794296028325;52249.21626175534;52680.20979685231;53131;53846.730766345914;53951.730241348545;16910;17219.483415497052;17245.913770431067;17334.47996560105;17478.12609369501;18275.81724182741;18377.54056148607;18418.447446576625;18493.90753046226;18694.53263668365;17641.558961026076;17881.105944702973;18098.095095245448;18180.72728909041;18360.357387491375;16533.83466165323;16863.15684215809;16980;17029.23368448398;17063.23215455283;18150.45548633543;18160.90919545394;18238.72641910346;18289.725654114933;18443.907780461013;12677.80983285233;13312.667183320495;13346.599602011955;13567.389467474184;13810.516631917835;13588.388522516709;13634.86365136336;13790.586282411543;13925.79111313311;14135 16:21:41 INFO - PID 18308 | __end_tp_report 16:21:41 INFO - PID 18308 | __start_cc_report 16:21:41 INFO - PID 18308 | _x_x_mozilla_cycle_collect,369 16:21:41 INFO - PID 18308 | __end_cc_report 16:21:41 INFO - PID 18308 | __startTimestamp1504394501008__endTimestamp 16:21:41 INFO - PID 18308 | 16:21:41 INFO - PID 18308 | ------- Summary: start ------- 16:21:41 INFO - PID 18308 | Number of tests: 6 16:21:41 INFO - PID 18308 | 16:21:41 INFO - PID 18308 | [#0] dojo.html Cycles:165 Average:6292.85 Median:6520.25 stddev:738.16 (11.3%) stddev-sans-first:702.34 16:21:41 INFO - PID 18308 | Values: 3309.2 3316.6 3334.7 3348.7 3374.6 6411.4 6455.3 6491.8 6510.1 6520.3 6296.9 6403.2 6425.1 6447.9 6448.0 5741.6 5813.8 5823.3 5839.9 5845.9 5814.0 5814.5 5815.6 5823.9 5828.3 5771.8 5801.7 5820.1 5840.9 5846.6 5753.3 5819.2 5842.7 5853.4 5857.0 5738.3 5909.4 5936.4 5971.3 6069.0 6132.3 6293.1 6354.9 6386.9 6387.9 6780.3 6788.3 6804.9 6856.9 6877.2 6986.8 7018.6 7026.0 7043.3 7048.4 6352.8 6395.6 6401.1 6489.6 6491.5 6896.1 7003.9 7037.7 7164.4 7169.1 6684.8 6699.2 6722.5 6724.7 6745.2 6693.4 6727.8 6750.7 6773.0 6780.1 5820.0 6736.2 6840.2 6953.8 6968.8 6862.4 6876.9 6968.3 6970.9 6987.7 5291.2 5376.9 5398.7 5411.0 5464.1 4740.4 4749.3 4754.3 4756.0 4810.3 5995.1 6030.5 6046.8 6052.5 6076.8 6792.0 6844.7 6897.5 6947.6 6966.2 5821.5 5920.0 5987.0 6002.9 6079.8 5861.6 5915.7 5966.8 5991.1 5992.7 6649.2 6682.3 6688.2 6695.6 6782.6 6749.7 6863.3 6872.9 6923.9 6995.2 6634.3 6639.3 6650.1 6656.2 6657.2 6686.4 6714.1 6726.1 6744.9 6753.6 6787.9 6870.1 6917.4 6919.3 6939.1 6525.1 6569.8 6655.9 6657.1 6667.1 5864.0 6521.4 6612.3 6633.8 6649.2 6545.6 6545.8 6563.7 6640.6 6642.6 6519.6 6611.4 6649.5 6671.0 6730.3 6387.4 6412.1 6427.9 6446.7 6470.1 16:21:41 INFO - PID 18308 | 16:21:41 INFO - PID 18308 | [#1] ext.html Cycles:180 Average:38573.82 Median:3491.56 stddev:55506.97 (1589.7%) stddev-sans-first:55590.22 16:21:41 INFO - PID 18308 | Values: 800.5 831.3 859.8 860.6 1124.5 30666.1 30947.4 31346.0 40708.4 41293.0 42789.4 43061.8 43100.6 43330.0 43363.6 7591.2 7729.5 7863.1 7871.6 7911.1 7681.6 7773.9 7780.1 7792.1 7795.6 7769.5 7806.9 7815.1 7829.9 7836.8 7792.6 7798.4 7801.9 7812.5 7825.5 7685.1 7776.1 7795.2 7797.1 7804.6 36749.8 36929.8 37103.0 37299.6 37364.4 3483.3 3499.8 3499.9 3519.2 3527.5 31034.7 31064.7 31093.1 31188.4 31263.5 4980.0 5136.0 5140.7 5162.6 5167.4 186670.0 186765.0 187533.0 189151.0 189744.0 47216.3 47446.0 47776.5 47854.0 47926.5 49499.0 53571.0 54375.7 54398.0 54459.0 15676.8 15974.8 16119.9 16176.9 16229.7 11604.4 11860.5 13211.7 13380.7 13419.7 14288.3 14434.9 14449.6 14554.2 14641.1 5714.9 5741.8 5821.2 5858.8 5867.2 972.8 998.6 999.5 1003.8 1015.0 22618.9 22702.2 22828.9 22922.0 22969.9 1097.2 1111.7 1113.2 1114.3 1116.7 11878.8 12204.0 12261.8 12270.0 12357.4 236037.0 236204.0 236598.0 238672.0 238705.0 52352.2 52358.7 52414.7 52489.7 52557.4 1418.8 1441.9 1462.9 1463.2 1466.3 22266.4 22582.4 22936.8 26871.9 28330.3 50593.2 50701.7 50917.2 51072.7 51189.5 213215.0 213867.0 214130.0 214200.0 214320.0 31641.7 31864.5 32479.4 32710.5 35896.6 38958.2 43622.6 44065.8 44674.3 45220.0 32001.8 32195.0 32352.0 32455.5 32498.4 30866.4 30912.7 31573.5 31608.7 31651.8 36005.6 36296.5 37961.4 38792.4 38835.4 32679.5 32710.8 32930.3 33067.7 33483.0 24061.4 24255.3 30172.0 30280.7 30674.1 16:21:41 INFO - PID 18308 | 16:21:41 INFO - PID 18308 | [#2] jquery.html Cycles:180 Average:35399.08 Median:12490.71 stddev:85735.18 (686.4%) stddev-sans-first:85935.79 16:21:41 INFO - PID 18308 | Values: 561.8 573.5 582.2 582.3 595.5 21828.3 22217.2 22406.9 22666.4 22694.8 23717.4 25006.0 26074.3 26200.9 26273.1 6596.1 6784.7 6797.3 6803.4 6805.6 6533.6 6628.3 6653.9 6691.0 6694.3 6353.3 6535.1 6554.0 6564.8 6671.1 6531.2 6582.8 6596.7 6597.0 6612.9 6106.3 6115.6 6126.4 6127.5 6133.7 27940.6 28598.7 28733.0 28857.7 28964.7 2039.8 2057.6 2073.4 2076.7 2079.8 18883.4 19662.4 19693.8 19740.6 19818.5 2248.5 2286.6 2335.1 2338.5 2349.5 133191.3 133895.7 134417.0 134572.0 135399.0 14172.0 14499.6 15115.7 15522.9 15823.8 23056.3 23071.2 23087.2 23218.1 23301.0 10032.9 10077.9 10126.0 10247.0 10258.3 9302.1 9395.7 9487.1 9620.3 9710.8 8886.2 8966.5 9011.7 9098.5 9333.2 2611.7 2623.4 2626.7 2707.0 2718.4 930.6 954.4 962.6 964.0 976.5 19711.6 20076.5 20104.7 20126.6 20376.0 1006.9 1033.4 1037.3 1038.8 1056.6 11012.2 11200.2 11218.5 11256.8 11341.0 448822.0 507582.0 512558.0 530616.0 549516.0 115241.4 119047.0 120596.4 121184.4 121674.4 1198.5 1254.1 1256.7 1262.9 1267.6 20688.3 20838.7 20958.3 21376.0 21601.9 55844.0 56119.2 56546.4 58303.7 58789.1 67345.0 68097.7 68171.0 68180.3 68999.7 13606.3 14098.4 14109.9 14553.8 14828.6 23585.0 23666.2 23677.3 23913.4 24189.2 18439.5 19001.5 19100.5 19193.3 19211.9 17202.2 17323.6 17352.0 17436.5 17775.9 18633.5 19793.8 19851.7 19858.1 19960.7 14267.6 14582.0 14676.3 14689.4 14740.3 15900.1 18348.8 18351.3 18720.2 18733.2 16:21:41 INFO - PID 18308 | 16:21:41 INFO - PID 18308 | [#3] mootools.html Cycles:180 Average:9345.54 Median:26604.03 stddev:6676.35 (25.1%) stddev-sans-first:6667.83 16:21:41 INFO - PID 18308 | Values: 1318.1 1386.5 1387.9 1392.4 1404.2 14826.0 15256.2 15499.3 15529.1 15559.3 15427.3 15843.3 15852.3 15863.1 15870.3 2732.7 2769.5 2779.1 2785.5 2787.6 2695.5 2715.3 2770.4 2778.2 2884.5 2816.1 2817.9 2831.1 2844.4 2848.0 2703.3 2718.8 2737.5 2744.4 2796.6 2573.9 2622.3 2628.3 2641.9 2649.2 14886.3 15060.2 15099.8 15207.9 15246.5 8035.0 8348.9 8358.1 8362.3 8370.8 5096.2 5343.7 5351.2 5636.5 5662.1 1932.5 1946.7 2000.4 2031.9 2039.2 26362.6 26423.0 26573.6 26577.2 26630.9 6668.1 6710.3 6736.0 6742.3 6870.0 17043.7 17195.7 17213.4 17251.7 17277.2 7957.6 7987.5 8000.3 8010.6 8041.6 7423.9 7460.8 7530.3 7563.6 7580.4 5166.9 5316.4 5332.1 5386.7 5401.4 1428.9 1480.2 1573.9 1737.6 1806.5 900.2 921.2 924.5 925.4 940.4 7769.9 7973.6 8253.9 8374.8 8558.7 991.2 991.8 994.5 1019.6 1030.3 5177.3 5195.5 5308.8 5333.8 5356.2 20004.2 21846.5 21905.0 21931.7 21932.3 20566.4 21097.6 21443.6 21620.4 21655.9 11120.2 11174.9 11438.8 11519.9 11531.5 8266.0 8461.4 8467.2 8562.7 8915.4 18174.4 18671.9 18691.7 19269.9 19289.7 18145.7 18995.7 19062.4 19595.1 19706.3 11862.9 12166.2 12319.3 12323.7 12361.1 13795.9 13881.0 13910.2 13915.2 13915.4 10802.4 10980.1 11041.1 11056.8 11231.6 10469.2 10613.5 11000.2 11063.9 11090.3 9297.2 9300.8 9314.5 9347.4 9353.7 6456.2 6543.8 6598.9 6622.6 6653.5 6476.9 6552.8 6678.9 6738.6 6889.4 16:21:41 INFO - PID 18308 | 16:21:41 INFO - PID 18308 | [#4] prototype.html Cycles:180 Average:5581.73 Median:6201.02 stddev:1578.85 (25.5%) stddev-sans-first:1536.58 16:21:41 INFO - PID 18308 | Values: 503.5 503.9 523.6 532.6 536.1 6139.4 6288.0 6374.6 6380.9 6445.5 6221.9 6224.6 6244.5 6288.2 6313.3 5015.3 5031.6 5050.1 5070.9 5100.3 5028.8 5074.4 5075.2 5093.6 5098.7 4763.2 4953.1 4960.2 5017.6 5100.8 5030.8 5031.9 5045.5 5052.3 5103.9 4505.2 4532.1 4542.9 4558.5 4569.5 6131.0 6177.6 6179.8 6188.5 6213.5 5782.1 5791.1 5799.2 5813.5 5824.7 6652.8 6652.9 6658.4 6664.8 6679.7 4750.4 4845.9 4850.3 4944.1 4953.4 7044.0 7066.5 7066.5 7068.1 7080.7 5009.2 5041.7 5078.7 5099.5 5105.7 5074.9 5121.8 5128.5 5148.3 5169.4 5589.0 5662.9 5729.9 5765.6 5774.3 6614.4 6641.6 6657.8 6693.2 6704.1 1667.9 1676.8 1719.0 1753.9 1754.3 1194.8 1196.6 1227.8 1231.5 1249.0 5814.3 5895.6 5916.3 5930.6 5931.8 6586.0 6738.3 6776.3 6817.9 6843.6 5871.4 5887.2 5952.8 5969.0 5993.3 3485.0 3513.5 3632.4 3647.3 3649.7 6637.9 6643.5 6655.1 6662.4 6685.8 6875.4 6927.3 7040.8 7061.8 7069.9 6581.9 6617.6 6667.4 6680.1 6687.0 6760.4 6812.5 6850.9 6858.9 6870.2 6605.8 6714.2 6758.3 6763.5 6794.0 6529.9 6582.7 6588.2 6604.5 6707.5 4940.4 4992.0 5014.4 5028.5 5066.5 6881.9 6920.3 6929.5 6942.1 7022.8 6586.9 6640.4 6669.1 6682.2 6682.3 6438.9 6501.3 6517.3 6532.8 6628.0 5921.2 6228.3 6348.4 6432.5 6456.5 6575.1 6605.8 6624.6 6666.0 6675.3 6373.3 6394.7 6417.3 6459.1 6479.9 16:21:41 INFO - PID 18308 | 16:21:41 INFO - PID 18308 | [#5] yui.html Cycles:180 Average:23195.74 Median:18695.63 stddev:31890.45 (170.6%) stddev-sans-first:31935.78 16:21:41 INFO - PID 18308 | Values: 853.0 881.5 884.0 918.2 936.8 17561.1 18452.2 18683.3 18696.7 18710.5 16226.7 17724.1 17870.9 17931.0 18096.5 959.7 984.4 986.8 990.8 993.5 910.8 995.7 1001.9 1002.1 1005.4 1052.3 1052.8 1054.0 1058.1 1059.0 1051.0 1051.9 1054.8 1055.0 1056.2 773.3 774.2 774.8 775.9 778.0 18699.5 19257.9 19656.0 19831.2 19954.4 21062.2 21215.2 21681.3 21721.3 22017.0 16795.5 17113.9 17252.5 17252.8 17728.9 3672.1 3707.4 3708.3 3710.6 3714.5 122935.4 124551.0 131988.0 136706.0 138399.0 15652.5 16339.0 16482.3 16554.7 16564.6 30064.8 30606.2 30743.4 31535.0 31599.0 9102.7 9145.5 9171.1 9182.4 9189.4 4068.4 4217.4 4227.0 4232.1 4242.1 4769.2 4973.9 4975.0 5446.8 5668.2 1088.5 1124.7 1130.0 1132.6 1140.8 683.0 693.8 696.5 704.5 709.7 11519.8 11715.7 12200.9 12344.0 13242.9 709.6 710.1 716.6 726.5 734.5 5692.0 5731.4 5737.1 5802.2 6017.1 103274.0 105523.0 106838.5 110115.0 110798.4 91869.5 92814.5 95830.0 97783.5 98278.0 87152.9 88940.6 90129.5 91614.0 91722.5 11694.4 12032.4 12084.7 12115.3 12263.4 39626.0 39840.4 40545.6 40725.6 41140.8 52249.2 52680.2 53131.0 53846.7 53951.7 16910.0 17219.5 17245.9 17334.5 17478.1 18275.8 18377.5 18418.4 18493.9 18694.5 17641.6 17881.1 18098.1 18180.7 18360.4 16533.8 16863.2 16980.0 17029.2 17063.2 18150.5 18160.9 18238.7 18289.7 18443.9 12677.8 13312.7 13346.6 13567.4 13810.5 13588.4 13634.9 13790.6 13925.8 14135.0 16:21:41 INFO - PID 18308 | -------- Summary: end -------- 16:21:41 INFO - PID 18308 | 16:21:42 INFO - TEST-INFO | 18308: exit 0 16:21:42 INFO - TEST-OK | dromaeo_css | took 1115604ms 16:21:42 INFO - TEST-START | kraken 16:21:42 INFO - Initialising browser for kraken test... 16:21:42 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46338/getInfo.html -profile /tmp/tmpf_I2ji/profile 16:21:42 INFO - TEST-INFO | started process 18698 (/builds/slave/test/build/application/firefox/firefox http://localhost:46338/getInfo.html) 16:21:48 INFO - TEST-INFO | 18698: exit 0 16:21:48 INFO - Browser initialized. 16:21:48 INFO - Running cycle 1/1 for kraken test... 16:21:48 INFO - Using env: {'DISPLAY': ':0', 16:21:48 INFO - 'HOME': '/home/cltbld', 16:21:48 INFO - 'JSGC_DISABLE_POISONING': '1', 16:21:48 INFO - 'LANG': 'en_US.UTF-8', 16:21:48 INFO - 'LANGUAGE': 'en_US:en', 16:21:48 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:21:48 INFO - 'LOGNAME': 'cltbld', 16:21:48 INFO - 'MAIL': '/var/mail/cltbld', 16:21:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:21:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:21:48 INFO - 'MOZ_ACCELERATED': '1', 16:21:48 INFO - 'MOZ_CRASHREPORTER': '1', 16:21:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:48 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:21:48 INFO - 'MOZ_NO_REMOTE': '1', 16:21:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:21:48 INFO - 'MOZ_WEBRENDER': '1', 16:21:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:48 INFO - 'NO_EM_RESTART': '1', 16:21:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:48 INFO - 'PWD': '/builds/slave/test', 16:21:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:21:48 INFO - 'RUST_BACKTRACE': 'full', 16:21:48 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:21:48 INFO - 'SHELL': '/bin/bash', 16:21:48 INFO - 'SHLVL': '1', 16:21:48 INFO - 'TERM': 'linux', 16:21:48 INFO - 'TMOUT': '86400', 16:21:48 INFO - 'USER': 'cltbld', 16:21:48 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897', 16:21:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:48 INFO - '_': '/tools/buildbot/bin/python'} 16:21:48 INFO - TEST-INFO | started process 18895 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf_I2ji/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:21:49 INFO - PID 18895 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:21:49 INFO - PID 18895 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:21:49 INFO - PID 18895 | 16:21:49 INFO - PID 18895 | (/builds/slave/test/build/application/firefox/firefox:18958): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:21:49 INFO - PID 18895 | 16:22:00 INFO - PID 18895 | RSS: Main: 204455936 16:22:00 INFO - PID 18895 | 16:22:02 INFO - PID 18895 | Cycle 1(1): loaded http://localhost:46338/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46338/tests/kraken/driver.html?testName=audio-beat-detection) 16:22:03 INFO - PID 18895 | RSS: Main: 211345408 16:22:03 INFO - PID 18895 | 16:22:06 INFO - PID 18895 | Cycle 1(1): loaded http://localhost:46338/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46338/tests/kraken/driver.html?testName=audio-dft) 16:22:07 INFO - PID 18895 | RSS: Main: 211480576 16:22:07 INFO - PID 18895 | 16:22:10 INFO - PID 18895 | Cycle 1(1): loaded http://localhost:46338/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46338/tests/kraken/driver.html?testName=audio-fft) 16:22:10 INFO - PID 18895 | RSS: Main: 205422592 16:22:10 INFO - PID 18895 | 16:22:13 INFO - PID 18895 | Cycle 1(1): loaded http://localhost:46338/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46338/tests/kraken/driver.html?testName=audio-oscillator) 16:22:13 INFO - PID 18895 | RSS: Main: 206090240 16:22:13 INFO - PID 18895 | 16:22:16 INFO - PID 18895 | Cycle 1(1): loaded http://localhost:46338/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46338/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:22:16 INFO - PID 18895 | RSS: Main: 206487552 16:22:16 INFO - PID 18895 | 16:22:19 INFO - PID 18895 | Cycle 1(1): loaded http://localhost:46338/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46338/tests/kraken/driver.html?testName=imaging-darkroom) 16:22:20 INFO - PID 18895 | RSS: Main: 209973248 16:22:20 INFO - PID 18895 | 16:22:23 INFO - PID 18895 | Cycle 1(1): loaded http://localhost:46338/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46338/tests/kraken/driver.html?testName=imaging-desaturate) 16:22:24 INFO - PID 18895 | RSS: Main: 209084416 16:22:24 INFO - PID 18895 | 16:22:27 INFO - PID 18895 | Cycle 1(1): loaded http://localhost:46338/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46338/tests/kraken/driver.html?testName=json-parse-financial) 16:22:28 INFO - PID 18895 | RSS: Main: 208650240 16:22:28 INFO - PID 18895 | 16:22:29 INFO - PID 18895 | Cycle 1(1): loaded http://localhost:46338/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46338/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:22:30 INFO - PID 18895 | RSS: Main: 208670720 16:22:30 INFO - PID 18895 | 16:22:32 INFO - PID 18895 | Cycle 1(1): loaded http://localhost:46338/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46338/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:22:32 INFO - PID 18895 | RSS: Main: 208732160 16:22:32 INFO - PID 18895 | 16:22:34 INFO - PID 18895 | Cycle 1(1): loaded http://localhost:46338/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46338/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:22:35 INFO - PID 18895 | RSS: Main: 209350656 16:22:35 INFO - PID 18895 | 16:22:38 INFO - PID 18895 | Cycle 1(1): loaded http://localhost:46338/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46338/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:22:39 INFO - PID 18895 | RSS: Main: 209403904 16:22:39 INFO - PID 18895 | 16:22:42 INFO - PID 18895 | Cycle 1(1): loaded http://localhost:46338/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46338/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:22:42 INFO - PID 18895 | RSS: Main: 210493440 16:22:42 INFO - PID 18895 | 16:22:44 INFO - PID 18895 | Cycle 1(1): loaded http://localhost:46338/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46338/tests/kraken/driver.html?testName=ai-astar) 16:22:44 INFO - PID 18895 | RSS: Main: 212152320 16:22:44 INFO - PID 18895 | 16:22:44 INFO - PID 18895 | __start_tp_report 16:22:44 INFO - PID 18895 | _x_x_mozilla_page_load 16:22:44 INFO - PID 18895 | _x_x_mozilla_page_load_details 16:22:44 INFO - PID 18895 | |i|pagename|runs| 16:22:44 INFO - PID 18895 | |0;ai-astar;102;108;101;111;107;116;112;102;115;108 16:22:44 INFO - PID 18895 | |1;audio-beat-detection;152;125;152;176;134;160;149;122;170;119 16:22:44 INFO - PID 18895 | |2;audio-dft;166;171;173;165;162;166;174;170;170;167 16:22:44 INFO - PID 18895 | |3;audio-fft;83;76;75;168;76;74;85;91;99;78 16:22:44 INFO - PID 18895 | |4;audio-oscillator;86;88;131;80;80;80;137;84;85;135 16:22:44 INFO - PID 18895 | |5;imaging-gaussian-blur;117;117;123;116;120;121;116;119;114;116 16:22:44 INFO - PID 18895 | |6;imaging-darkroom;151;147;150;158;151;153;153;150;149;155 16:22:44 INFO - PID 18895 | |7;imaging-desaturate;95;102;94;102;95;95;94;105;94;95 16:22:44 INFO - PID 18895 | |8;json-parse-financial;54;53;55;53;53;52;53;53;56;51 16:22:44 INFO - PID 18895 | |9;json-stringify-tinderbox;42;39;41;38;51;38;42;39;38;39 16:22:44 INFO - PID 18895 | |10;stanford-crypto-aes;77;80;75;80;75;77;79;91;81;82 16:22:44 INFO - PID 18895 | |11;stanford-crypto-ccm;129;130;134;130;127;135;127;124;123;162 16:22:44 INFO - PID 18895 | |12;stanford-crypto-pbkdf2;176;169;182;169;173;170;177;171;183;174 16:22:44 INFO - PID 18895 | |13;stanford-crypto-sha256-iterative;69;69;68;65;67;65;64;67;65;63 16:22:44 INFO - PID 18895 | __end_tp_report 16:22:44 INFO - PID 18895 | __start_cc_report 16:22:44 INFO - PID 18895 | _x_x_mozilla_cycle_collect,930 16:22:44 INFO - PID 18895 | __end_cc_report 16:22:44 INFO - PID 18895 | __startTimestamp1504394564743__endTimestamp 16:22:44 INFO - PID 18895 | 16:22:44 INFO - PID 18895 | ------- Summary: start ------- 16:22:44 INFO - PID 18895 | Number of tests: 14 16:22:44 INFO - PID 18895 | 16:22:44 INFO - PID 18895 | [#0] ai-astar Cycles:10 Average:108.20 Median:109.50 stddev:5.37 (4.9%) stddev-sans-first:5.21 16:22:44 INFO - PID 18895 | Values: 102.0 108.0 101.0 111.0 107.0 116.0 112.0 102.0 115.0 108.0 16:22:44 INFO - PID 18895 | 16:22:44 INFO - PID 18895 | [#1] audio-beat-detection Cycles:10 Average:145.90 Median:152.00 stddev:20.12 (13.2%) stddev-sans-first:21.22 16:22:44 INFO - PID 18895 | Values: 152.0 125.0 152.0 176.0 134.0 160.0 149.0 122.0 170.0 119.0 16:22:44 INFO - PID 18895 | 16:22:44 INFO - PID 18895 | [#2] audio-dft Cycles:10 Average:168.40 Median:170.00 stddev:3.81 (2.2%) stddev-sans-first:3.94 16:22:44 INFO - PID 18895 | Values: 166.0 171.0 173.0 165.0 162.0 166.0 174.0 170.0 170.0 167.0 16:22:44 INFO - PID 18895 | 16:22:44 INFO - PID 18895 | [#3] audio-fft Cycles:10 Average:90.50 Median:80.50 stddev:28.39 (35.3%) stddev-sans-first:29.98 16:22:44 INFO - PID 18895 | Values: 83.0 76.0 75.0 168.0 76.0 74.0 85.0 91.0 99.0 78.0 16:22:44 INFO - PID 18895 | 16:22:44 INFO - PID 18895 | [#4] audio-oscillator Cycles:10 Average:98.60 Median:82.00 stddev:24.85 (30.3%) stddev-sans-first:25.93 16:22:44 INFO - PID 18895 | Values: 86.0 88.0 131.0 80.0 80.0 80.0 137.0 84.0 85.0 135.0 16:22:44 INFO - PID 18895 | 16:22:44 INFO - PID 18895 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:118.00 stddev:2.77 (2.3%) stddev-sans-first:2.92 16:22:44 INFO - PID 18895 | Values: 117.0 117.0 123.0 116.0 120.0 121.0 116.0 119.0 114.0 116.0 16:22:44 INFO - PID 18895 | 16:22:44 INFO - PID 18895 | [#6] imaging-darkroom Cycles:10 Average:151.70 Median:152.00 stddev:3.16 (2.1%) stddev-sans-first:3.35 16:22:44 INFO - PID 18895 | Values: 151.0 147.0 150.0 158.0 151.0 153.0 153.0 150.0 149.0 155.0 16:22:44 INFO - PID 18895 | 16:22:44 INFO - PID 18895 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:94.50 stddev:4.18 (4.4%) stddev-sans-first:4.36 16:22:44 INFO - PID 18895 | Values: 95.0 102.0 94.0 102.0 95.0 95.0 94.0 105.0 94.0 95.0 16:22:44 INFO - PID 18895 | 16:22:44 INFO - PID 18895 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.00 stddev:1.42 (2.7%) stddev-sans-first:1.48 16:22:44 INFO - PID 18895 | Values: 54.0 53.0 55.0 53.0 53.0 52.0 53.0 53.0 56.0 51.0 16:22:44 INFO - PID 18895 | 16:22:44 INFO - PID 18895 | [#9] json-stringify-tinderbox Cycles:10 Average:40.70 Median:40.00 stddev:3.95 (9.9%) stddev-sans-first:4.16 16:22:44 INFO - PID 18895 | Values: 42.0 39.0 41.0 38.0 51.0 38.0 42.0 39.0 38.0 39.0 16:22:44 INFO - PID 18895 | 16:22:44 INFO - PID 18895 | [#10] stanford-crypto-aes Cycles:10 Average:79.70 Median:80.00 stddev:4.64 (5.8%) stddev-sans-first:4.82 16:22:44 INFO - PID 18895 | Values: 77.0 80.0 75.0 80.0 75.0 77.0 79.0 91.0 81.0 82.0 16:22:44 INFO - PID 18895 | 16:22:44 INFO - PID 18895 | [#11] stanford-crypto-ccm Cycles:10 Average:132.10 Median:130.00 stddev:11.18 (8.6%) stddev-sans-first:11.80 16:22:44 INFO - PID 18895 | Values: 129.0 130.0 134.0 130.0 127.0 135.0 127.0 124.0 123.0 162.0 16:22:44 INFO - PID 18895 | 16:22:44 INFO - PID 18895 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.40 Median:175.00 stddev:5.08 (2.9%) stddev-sans-first:5.36 16:22:44 INFO - PID 18895 | Values: 176.0 169.0 182.0 169.0 173.0 170.0 177.0 171.0 183.0 174.0 16:22:44 INFO - PID 18895 | 16:22:44 INFO - PID 18895 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:67.00 stddev:2.10 (3.1%) stddev-sans-first:1.96 16:22:44 INFO - PID 18895 | Values: 69.0 69.0 68.0 65.0 67.0 65.0 64.0 67.0 65.0 63.0 16:22:44 INFO - PID 18895 | -------- Summary: end -------- 16:22:44 INFO - PID 18895 | 16:22:45 INFO - PID 18895 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:22:45 INFO - TEST-INFO | 18895: exit 0 16:22:45 INFO - TEST-OK | kraken | took 63656ms 16:22:45 INFO - SUITE-END | took 1179s 16:22:45 INFO - Completed test suite (00:19:39) 16:22:45 INFO - javascript benchmark 16:22:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8611.9822767438, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3309.1727068232935, 3316.5854268216467, 3334.666533346664, 3348.6986171244585, 3374.5613070300856, 6411.422971932525, 6455.289918109004, 6491.837704057395, 6510.056041873925, 6520.25017123031, 6296.937030629692, 6403.231612206541, 6425.13260709804, 6447.903281450782, 6448.0, 5741.569382296324, 5813.82558523245, 5823.3012038555435, 5839.854003649911, 5845.912311315334, 5813.970930145343, 5814.5057670098095, 5815.621984570989, 5823.941760582404, 5828.3297420796725, 5771.82684519463, 5801.680907550083, 5820.1269809528485, 5840.883182336351, 5846.590738648296, 5753.280839895013, 5819.156222347779, 5842.73707683151, 5853.414658534134, 5857.033589457735, 5738.311402631669, 5909.379515150933, 5936.376680448534, 5971.313298970587, 6069.0, 6132.325444201134, 6293.087502312139, 6354.87290254197, 6386.904196437057, 6387.904181437282, 6780.288069752654, 6788.287229840845, 6804.863902721967, 6856.897146542806, 6877.2091209511045, 6986.825329366756, 7018.649067546653, 7025.964870175617, 7043.260457651975, 7048.400885924702, 6352.777652782131, 6395.584287021329, 6401.071844582556, 6489.643069631168, 6491.51313651478, 6896.137982752156, 7003.929960700328, 7037.683304251321, 7164.355208031198, 7169.139703235645, 6684.766033188815, 6699.196096468455, 6722.495812814059, 6724.731010759515, 6745.2242992056545, 6693.397594216543, 6727.798166055026, 6750.729970801113, 6772.966135169391, 6780.084688566981, 5820.0, 6736.191657001191, 6840.213375461885, 6953.791386258319, 6968.825779355455, 6862.416694581017, 6876.862462750717, 6968.338007889242, 6970.93029069713, 6987.720491180397, 5291.153415453509, 5376.946230537645, 5398.730063496888, 5410.97294513525, 5464.1257398816, 4740.383750112533, 4749.26386410107, 4754.310624959361, 4756.0, 4810.278458231293, 5995.130706047686, 6030.517558595302, 6046.818595442144, 6052.54605904559, 6076.787312444043, 6792.0, 6844.657767111724, 6897.48268879836, 6947.617881016491, 6966.164060312795, 5821.505172060338, 5920.0, 5987.042073268256, 6002.909956350658, 6079.75680972765, 5861.589688721749, 5915.733791979371, 5966.791162309299, 5991.131285963511, 5992.67040312787, 6649.202095748445, 6682.298358672367, 6688.163979502562, 6695.631740254235, 6782.593044417351, 6749.730010799513, 6863.313668633096, 6872.862542749217, 6923.861522769415, 6995.230524642387, 6634.33656634352, 6639.336066393322, 6650.135482387259, 6656.201255849135, 6657.201135863727, 6686.398224159849, 6714.093597364488, 6726.058351830846, 6744.898826517704, 6753.628550429774, 6787.93212067873, 6870.106886104774, 6917.412019978358, 6919.342662447059, 6939.097917270722, 6525.119108920235, 6569.80290591283, 6655.900161497679, 6657.101291325609, 6667.099941508029, 5863.990125010179, 6521.380468855451, 6612.338766123349, 6633.767818126343, 6649.202095748541, 6545.607263564201, 6545.803625891041, 6563.704633291609, 6640.568363056386, 6642.568233065029, 6519.608823470607, 6611.404973552211, 6649.501287403367, 6670.966645166744, 6730.25967143604, 6387.361263873576, 6412.070249813844, 6427.93572064292, 6446.67766611677, 6470.061841033118], "value": 6239.657245676398, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [800.4676889868238, 831.3432388413154, 859.8151397449549, 860.6342304520577, 1124.5052177042105, 30666.080017599452, 30947.38105237894, 31346.0, 40708.389374159415, 41293.0, 42789.35815962763, 43061.78469107658, 43100.568994310044, 43330.0, 43363.566364336344, 7591.202923693016, 7729.497582657109, 7863.095743989457, 7871.606419679022, 7911.129775724666, 7681.57751323677, 7773.883391749128, 7780.105287891908, 7792.142864284924, 7795.610219489031, 7769.456138070309, 7806.882896756553, 7815.10126335473, 7829.86466962293, 7836.843263134734, 7792.649330780128, 7798.376129909594, 7801.921980780205, 7812.453128281023, 7825.491343062711, 7685.0777906651, 7776.066871975343, 7795.220477952216, 7797.064352277763, 7804.609769511501, 36749.81625091884, 36929.815350923076, 37103.0, 37299.62700372989, 37364.43953340702, 3483.3207524532727, 3499.790012599227, 3499.930001399983, 3519.225770330523, 3527.523784289114, 31034.689653103407, 31064.689353106405, 31093.067207983797, 31188.376232475224, 31263.531047034314, 4979.950200497985, 5136.0, 5140.691558506502, 5162.612804039674, 5167.354080739908, 186670.0, 186765.0, 187533.0, 189151.0, 189744.0, 47216.29175562369, 47446.0, 47776.522234777905, 47854.0, 47926.5207347929, 49499.0, 53571.0, 54375.728121359934, 54398.0, 54459.0, 15676.764848527282, 15974.840251597336, 16119.919400402923, 16176.919115404347, 16229.675406491804, 11604.361760103275, 11860.525578976743, 13211.735765284833, 13380.732385352238, 13419.664508387368, 14288.28558572088, 14434.927825360806, 14449.638759031108, 14554.199519026344, 14641.121532708072, 5714.914276285859, 5741.770329186869, 5821.18503409523, 5858.8242352729485, 5867.20792692981, 972.8151651186251, 998.5805961496189, 999.48526508848, 1003.8193125237382, 1014.9594016239415, 22618.886905565367, 22702.205422810122, 22828.885855570617, 22922.0, 22969.88515057414, 1097.1880808202034, 1111.6831702964616, 1113.209621168979, 1114.2924243105524, 1116.7431490757172, 11878.762424751456, 12204.02367810573, 12261.75476490483, 12270.018398528096, 12357.38213089342, 236037.0, 236204.0, 236598.0, 238672.0, 238705.0, 52352.21471677928, 52358.73820630873, 52414.73792631089, 52489.737551312, 52557.37213139404, 1418.7942748301646, 1441.8990670653163, 1462.8683418492174, 1463.1952426165567, 1466.2829726642908, 22266.443338916655, 22582.435439113495, 22936.770632293465, 26871.865640671673, 28330.2917427066, 50593.24110138277, 50701.74649126731, 50917.23624145567, 51072.744636276584, 51189.48810511847, 213215.0, 213867.0, 214130.0, 214200.0, 214320.0, 31641.683583163875, 31864.522032169072, 32479.35041299208, 32710.50934236036, 35896.64103358933, 38958.220835582564, 43622.56377436185, 44065.77967110144, 44674.3298850511, 45220.0, 32001.839990799897, 32195.0, 32352.0, 32455.513167302983, 32498.350032998736, 30866.382672346877, 30912.69087309188, 31573.526397103604, 31608.683913160574, 31651.84174079207, 36005.63994360023, 36296.4555531662, 37961.43057854079, 38792.41811372888, 38835.417468737425, 32679.50980735243, 32710.83644581762, 32930.34139317248, 33067.66932330646, 33482.99551013474, 24061.398465038514, 24255.272341829772, 30172.0, 30280.697193027787, 30674.07977760671], "value": 17431.69639808168, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.8286422641092, 573.4838645219302, 582.2343618142146, 582.2925145947672, 595.523581135092, 21828.345149645516, 22217.2223972161, 22406.88796556019, 22666.433339166528, 22694.773052269473, 23717.407064823386, 25006.0, 26074.305170163927, 26200.868995654993, 26273.08044218448, 6596.076549283104, 6784.72861085556, 6797.286284940084, 6803.387695107439, 6805.55763875349, 6533.640649764261, 6628.304028077055, 6653.866922661544, 6691.029800678923, 6694.297098804628, 6353.332900045498, 6535.0524173994745, 6553.967230163867, 6564.80305590833, 6671.132752742161, 6531.150950376445, 6582.769603063918, 6596.736130554772, 6597.0, 6612.93387066128, 6106.297775755756, 6115.633062016249, 6126.448619624255, 6127.468132966758, 6133.6626485543275, 27940.58089128665, 28598.714012860022, 28733.0, 28857.711422885714, 28964.71035289662, 2039.7552293724848, 2057.609054279682, 2073.39871437283, 2076.730025096728, 2079.8440116991137, 18883.433496995112, 19662.41012769605, 19693.80306196934, 19740.605187896163, 19818.504537386536, 2248.482848944752, 2286.6455699366625, 2335.0659736105695, 2338.4621537046405, 2349.5300939812105, 133191.33404332917, 133895.6610433893, 134417.0, 134572.0, 135399.0, 14172.0, 14499.637509062359, 15115.697686046218, 15522.922385388001, 15823.841761582236, 23056.308310750705, 23071.19250826213, 23087.191948282067, 23218.071277148723, 23301.0, 10032.949835250776, 10077.899221007696, 10126.0, 10247.0, 10258.28192026551, 9302.116298951589, 9395.718128456158, 9487.098725621056, 9620.278479114095, 9710.805783884423, 8886.200241978253, 8966.462012279284, 9011.684591039282, 9098.454092754457, 9333.20667743236, 2611.66048413705, 2623.3572774669947, 2626.684797824273, 2706.9593956090675, 2718.415540658768, 930.6137952749533, 954.3939598354956, 962.605331813953, 963.9566219520138, 976.5361453309621, 19711.60576788456, 20076.498087547927, 20104.69842952357, 20126.59746805085, 20376.0, 1006.8993100689872, 1033.3541536539663, 1037.3412882819464, 1038.8441733739849, 1056.5932116135189, 11012.229143960007, 11200.159988000933, 11218.495167717472, 11256.774864502664, 11341.036011938913, 448822.0, 507582.0, 512558.0, 530616.0, 549516.0, 115241.4237928805, 119047.0, 120596.39701801435, 121184.3940780308, 121674.39162804306, 1198.5146015863588, 1254.1033161289722, 1256.6983923858209, 1262.9494820207271, 1267.6260503151593, 20688.275910343065, 20838.687419688413, 20958.266460674415, 21376.0, 21601.891990539945, 55844.0, 56119.158212626025, 56546.434535654116, 58303.708481457325, 58789.11816322673, 67345.0, 68097.65951170212, 68171.0, 68180.31819681938, 68999.65500172468, 13606.319684015958, 14098.36557354901, 14109.929450353093, 14553.781693274397, 14828.629284267548, 23585.0, 23666.17168399098, 23677.289681309587, 23913.402164946016, 24189.15337963163, 18439.53901152482, 19001.52496187551, 19100.522486937938, 19193.32823351176, 19211.90394048021, 17202.225899834288, 17323.56691082733, 17352.04563749006, 17436.47690569285, 17775.91112044483, 18633.53416164607, 19793.802061979197, 19851.702224466357, 19858.10638521241, 19960.70058949146, 14267.571972840831, 14582.0, 14676.3395647194, 14689.41242350294, 14740.262986850401, 15900.12549309799, 18348.816511835244, 18351.265949361878, 18720.15759290808, 18733.157007934402], "value": 12084.873215129455, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1318.1300341774427, 1386.4939297156534, 1387.9375428105734, 1392.3525560614316, 1404.2206575350676, 14826.0, 15256.160911149882, 15499.302531386087, 15529.145896975677, 15559.29983150758, 15427.305771240293, 15843.287052082655, 15852.28664710088, 15863.127527985956, 15870.285837137328, 2732.7130651281677, 2769.5014897318474, 2779.069011881012, 2785.5403858363447, 2787.581862720598, 2695.514807334679, 2715.2804506805714, 2770.390514086908, 2778.19432364614, 2884.480793457177, 2816.084772448952, 2817.8591070446496, 2831.0940499040316, 2844.3742376677096, 2848.017433985282, 2703.3106557827823, 2718.7824974002033, 2737.534619114765, 2744.3550765570076, 2796.566532187514, 2573.9227823165334, 2622.331305517081, 2628.2772237634613, 2641.9471610567875, 2649.2449651849324, 14886.255687215596, 15060.246987650684, 15099.849001510063, 15207.923960380127, 15246.46637367698, 8034.9598252008955, 8348.874766878502, 8358.080611132771, 8362.327534493126, 8370.790730231793, 5096.200800859452, 5343.65266257692, 5351.224072509464, 5636.464535869086, 5662.065759149736, 1932.5265309999, 1946.6787979983285, 2000.41987823531, 2031.9390418287328, 2039.1537511932531, 26362.604560931602, 26423.0, 26573.601395979076, 26577.202683919513, 26630.86684566584, 6668.099806526154, 6710.261871194165, 6735.966320168466, 6742.258351581322, 6870.0, 17043.659126817394, 17195.7420638688, 17213.397531086353, 17251.74122388165, 17277.222524986406, 7957.641906114239, 7987.480813747204, 8000.319972802377, 8010.559419231999, 8041.557714325651, 7423.9257607424315, 7460.776176714708, 7530.322270995636, 7563.600733864253, 7580.393568514505, 5166.9224961625605, 5316.362036555638, 5332.093544097513, 5386.730663466889, 5401.432849550819, 1428.8999770016208, 1480.2302802542833, 1573.9134347610761, 1737.6177241006958, 1806.4761219246273, 900.1853322742928, 921.1847514949184, 924.4684306523776, 925.3707478914268, 940.3981451870808, 7769.873368361556, 7973.60131993398, 8253.876191857127, 8374.790630234293, 8558.743237702878, 991.1525645573075, 991.7503945029315, 994.5425104451889, 1019.6329321444274, 1030.3354336452946, 5177.275181474596, 5195.479413761434, 5308.787648494017, 5333.839984800462, 5356.196570514454, 20004.19983200685, 21846.453838653844, 21905.0, 21931.67102493464, 21932.342029739135, 20566.38300850977, 21097.578048438947, 21443.57112857734, 21620.35138945834, 21655.891720541298, 11120.22158448917, 11174.888251117547, 11438.771224575461, 11519.88480115205, 11531.48108335127, 8265.95867020661, 8461.40770146084, 8467.153284671484, 8562.657493700182, 8915.42049766763, 18174.36389726353, 18671.906640467256, 18691.719624205918, 19269.903650481658, 19289.710654339917, 18145.727814082536, 18995.71506427432, 19062.428127156207, 19595.118219680433, 19706.31027914016, 11862.881371186179, 12166.209196402206, 12319.322437266037, 12323.69190770238, 12361.07291953125, 13795.862041379458, 13881.0, 13910.165390076629, 13915.165090094626, 13915.373808178858, 10802.422936063665, 10980.066694331072, 11041.06150977176, 11056.834147487632, 11231.55073797039, 10469.162467002468, 10613.522391492246, 11000.174986875856, 11063.889361106609, 11090.334579925231, 9297.163255307054, 9300.767480813034, 9314.534273286445, 9347.439153650803, 9353.672621458216, 6456.160699109086, 6543.803685889431, 6598.934010660024, 6622.63575503352, 6653.467722582279, 6476.8704625906275, 6552.803415897531, 6678.899816502853, 6738.59568425896, 6889.448844092561], "value": 6713.370722345371, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.5115937540584, 503.9190935443473, 523.6203752279598, 532.6005495878092, 536.05654048874, 6139.386061393859, 6288.0, 6374.617522948626, 6380.872382552347, 6445.548811583185, 6221.906671399933, 6224.564280500367, 6244.469220116295, 6288.182536270278, 6313.305536390993, 5015.297858299841, 5031.597472202233, 5050.090983622947, 5070.923936140961, 5100.285959965608, 5028.7988480460735, 5074.441811400743, 5075.162598171316, 5093.643444958854, 5098.719570423626, 4763.166445871959, 4953.1332016896895, 4960.206366981283, 5017.648764586462, 5100.821471248507, 5030.798768049311, 5031.874203144951, 5045.495450454961, 5052.267421223902, 5103.897922041557, 4505.189065968125, 4532.116237333721, 4542.8864278392975, 4558.544145585448, 4569.47451043131, 6130.969345153246, 6177.567570270083, 6179.752809887644, 6188.504919606421, 6213.5339849511465, 5782.132680097977, 5791.131330300446, 5799.21710569072, 5813.505852002585, 5824.737886795105, 6652.800415987528, 6652.866942661168, 6658.434033107192, 6664.8000559983275, 6679.699413526354, 4750.358701575312, 4845.903081938377, 4850.345203397532, 4944.134776414149, 4953.405591329028, 7043.9647801761175, 7066.505344625878, 7066.540674856118, 7068.056284521746, 7080.672084120455, 5009.173486374781, 5041.6722913010535, 5078.669886457443, 5099.490050994945, 5105.668131571436, 5074.908894587681, 5121.795128194831, 5128.461511541309, 5148.304978827885, 5169.353830771153, 5588.972055139699, 5662.886742265132, 5729.942700573024, 5765.596408251382, 5774.335951365647, 6614.371634694697, 6641.568298060611, 6657.800265992028, 6693.196816382066, 6704.061431399608, 1667.924943377551, 1676.773635559184, 1718.9742153867703, 1753.9386121485686, 1754.3158168314367, 1194.8028575285155, 1196.5512932650256, 1227.7544491101637, 1231.4704676988802, 1248.9571208041204, 5814.3313518945015, 5895.616784908967, 5916.26046744157, 5930.614510056833, 5931.7627294907725, 6586.0, 6738.258791532928, 6776.2546119926765, 6817.863642727118, 6843.555168914005, 5871.354151043467, 5887.20522729426, 5952.821415357546, 5969.0, 5993.250843644544, 3485.006773069663, 3513.4729790531624, 3632.418812989909, 3647.252313275825, 3649.7445178837756, 6637.900431493532, 6643.534952553275, 6655.073506631111, 6662.433693136138, 6685.799426017227, 6875.4155896749335, 6927.3419025192525, 7040.823979400454, 7061.788146355618, 7069.929300706927, 6581.868362632625, 6617.636030018395, 6667.43326817226, 6680.13158289429, 6687.0, 6760.357766012221, 6812.455003599601, 6850.9314906850295, 6858.931410686029, 6870.244273130052, 6605.768798092044, 6714.227863795726, 6758.324167583399, 6763.512027353974, 6793.966030169818, 6529.9020514691365, 6582.703778329891, 6588.242352129375, 6604.504662150454, 6707.463402927851, 4940.431850337257, 4992.026554821775, 5014.448410674896, 5028.522290382408, 5066.518680725325, 6881.862362752817, 6920.342567456084, 6929.549579277331, 6942.119020606594, 7022.824429389306, 6586.934130658632, 6640.402363787282, 6669.1330127083165, 6682.231543372574, 6682.298358672464, 6438.935610643834, 6501.284858665637, 6517.348265173445, 6532.778855189258, 6628.0, 5921.240012591949, 6228.252609686685, 6348.396902294275, 6432.45324147434, 6456.515761317826, 6575.079488871466, 6605.834854128493, 6624.588337006349, 6666.0, 6675.299093595297, 6373.2989371168205, 6394.712237949397, 6417.336805225779, 6459.063435801876, 6479.902801458076], "value": 5114.013561087855, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [852.9530875801833, 881.5371929736888, 884.0364003236471, 918.2011649864617, 936.765808547863, 17561.121943902803, 18452.169652365643, 18683.346082887103, 18696.719549206755, 18710.53223669409, 16226.675466490664, 17724.1137943103, 17870.910645446787, 17931.0, 18096.457106286798, 959.6976952260034, 984.3749219245794, 986.8223719730446, 990.7572644702058, 993.4635296939643, 910.8360495110877, 995.7460847483918, 1001.8897921228659, 1002.128148510793, 1005.416858222233, 1052.2791887557048, 1052.8420736889452, 1053.9841902371431, 1058.1006144776916, 1058.9682309530688, 1050.9737256568571, 1051.9263651544395, 1054.752133248686, 1055.0, 1056.1814593689876, 773.3465221887496, 774.1755030892139, 774.8217909880702, 775.8874963130371, 778.0313509680484, 18699.53251168718, 19257.9037104815, 19656.0, 19831.206751729915, 19954.401367958984, 21062.157513699432, 21215.151393944223, 21681.34955951324, 21721.34835954908, 22017.0, 16795.496135115965, 17113.914430427893, 17252.482425527254, 17252.827471725246, 17728.91135544327, 3672.1186915140174, 3707.388280933643, 3708.313961917027, 3710.610385909468, 3714.4799728038097, 122935.3853230737, 124551.0, 131988.0, 136706.0, 138399.0, 15652.4521641742, 16339.0, 16482.34070637161, 16554.668906622042, 16564.585885352964, 30064.79092926992, 30606.23484412863, 30743.38513229723, 31535.0, 31599.0, 9102.681406150752, 9145.542722863964, 9171.128742769426, 9182.449053056838, 9189.448633082036, 4068.3694027425795, 4217.367394890791, 4227.027783609787, 4232.111256636079, 4242.109157077049, 4769.236922092448, 4973.875653108629, 4974.950250497521, 5446.754896029688, 5668.206451096849, 1088.4612117001977, 1124.7075760302216, 1129.9774004519863, 1132.5526417065214, 1140.8402823604702, 682.9624370659562, 693.7918624412656, 696.4985210648325, 704.457567672889, 709.6664567653298, 11519.769604607862, 11715.707107322385, 12200.938995304967, 12344.0, 13242.867571324163, 709.5636183746958, 710.0662628643317, 716.6416791604198, 726.5313872552174, 734.4601717737459, 5691.9715401423555, 5731.398203188689, 5737.139429085587, 5802.187693722883, 6017.127516510169, 103274.0, 105523.0, 106838.46580767047, 110115.0, 110798.44600776944, 91869.54065229764, 92814.53592731993, 95830.0, 97783.51108244414, 98278.0, 87152.94964676333, 88940.5552972231, 90129.54935225414, 91614.0, 91722.54138729397, 11694.356810375511, 12032.398380081137, 12084.697882553006, 12115.333656648969, 12263.38683065861, 39626.0, 39840.40239396354, 40545.594544054184, 40725.592744073365, 41140.794296028325, 52249.21626175534, 52680.20979685231, 53131.0, 53846.730766345914, 53951.730241348545, 16910.0, 17219.483415497052, 17245.913770431067, 17334.47996560105, 17478.12609369501, 18275.81724182741, 18377.54056148607, 18418.447446576625, 18493.90753046226, 18694.53263668365, 17641.558961026076, 17881.105944702973, 18098.095095245448, 18180.72728909041, 18360.357387491375, 16533.83466165323, 16863.15684215809, 16980.0, 17029.23368448398, 17063.23215455283, 18150.45548633543, 18160.90919545394, 18238.72641910346, 18289.725654114933, 18443.907780461013, 12677.80983285233, 13312.667183320495, 13346.599602011955, 13567.389467474184, 13810.516631917835, 13588.388522516709, 13634.86365136336, 13790.586282411543, 13925.79111313311, 14135.0], "value": 9039.579803143644, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1524.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 108.0, 101.0, 111.0, 107.0, 116.0, 112.0, 102.0, 115.0, 108.0], "value": 108.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 125.0, 152.0, 176.0, 134.0, 160.0, 149.0, 122.0, 170.0, 119.0], "value": 145.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 171.0, 173.0, 165.0, 162.0, 166.0, 174.0, 170.0, 170.0, 167.0], "value": 168.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 76.0, 75.0, 168.0, 76.0, 74.0, 85.0, 91.0, 99.0, 78.0], "value": 90.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 131.0, 80.0, 80.0, 80.0, 137.0, 84.0, 85.0, 135.0], "value": 98.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 123.0, 116.0, 120.0, 121.0, 116.0, 119.0, 114.0, 116.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 147.0, 150.0, 158.0, 151.0, 153.0, 153.0, 150.0, 149.0, 155.0], "value": 151.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 102.0, 94.0, 102.0, 95.0, 95.0, 94.0, 105.0, 94.0, 95.0], "value": 97.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 55.0, 53.0, 53.0, 52.0, 53.0, 53.0, 56.0, 51.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [42.0, 39.0, 41.0, 38.0, 51.0, 38.0, 42.0, 39.0, 38.0, 39.0], "value": 40.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 80.0, 75.0, 80.0, 75.0, 77.0, 79.0, 91.0, 81.0, 82.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 130.0, 134.0, 130.0, 127.0, 135.0, 127.0, 124.0, 123.0, 162.0], "value": 132.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 169.0, 182.0, 169.0, 173.0, 170.0, 177.0, 171.0, 183.0, 174.0], "value": 174.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 65.0, 67.0, 65.0, 64.0, 67.0, 65.0, 63.0], "value": 66.2, "unit": "score"}]}]} 16:22:45 INFO - Return code: 0 16:22:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:22:46 INFO - # TBPL SUCCESS # 16:22:46 INFO - Running post-action listener: _package_coverage_data 16:22:46 INFO - Running post-action listener: _resource_record_post_action 16:22:46 INFO - [mozharness: 2017-09-02 23:22:46.022423Z] Finished run-tests step (success) 16:22:46 INFO - Running post-run listener: _resource_record_post_run 16:22:46 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 8323072; Write bytes: 391880704; Read time: 3188; Write time: 314196 16:22:46 INFO - TinderboxPrint: CPU usage
13.3% 16:22:46 INFO - TinderboxPrint: I/O read bytes / time
8,323,072 / 3,188 16:22:46 INFO - TinderboxPrint: I/O write bytes / time
391,880,704 / 314,196 16:22:46 INFO - TinderboxPrint: CPU idle
8,264.4 (86.7%) 16:22:46 INFO - TinderboxPrint: CPU user
1,201.3 (12.6%) 16:22:46 INFO - TinderboxPrint: Swap in / out
0 / 0 16:22:46 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 99139584; Read time: 0; Write time: 85044 16:22:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:22:46 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 8323072; Write bytes: 292741120; Read time: 3188; Write time: 229152 16:22:46 INFO - Running post-run listener: _upload_blobber_files 16:22:46 INFO - Blob upload gear active. 16:22:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:22:46 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 16:22:46 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'] 16:22:46 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 16:22:47 INFO - (blobuploader) - INFO - Open directory for files ... 16:22:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:22:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:22:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:50 INFO - (blobuploader) - INFO - Done attempting. 16:22:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:22:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:22:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:51 INFO - (blobuploader) - INFO - Done attempting. 16:22:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:22:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:52 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:22:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:52 INFO - (blobuploader) - INFO - Done attempting. 16:22:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:22:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:22:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:53 INFO - (blobuploader) - INFO - Done attempting. 16:22:53 INFO - (blobuploader) - INFO - Iteration through files over. 16:22:53 INFO - Return code: 0 16:22:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:22:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:22:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03e5deb601774f90d1b7f21be88225a147def9b8401dcfb8be44a06a035d9162a5228fc9976b55e026f0cc606ce8dc5b0e47588a5280817c7c7837d678dd3af1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f82f9eda4b548daffd3acbe8db8a9f40e198b627ab026ab5935e397b237d6d51fc803c1fb9889626b459d0522e69471f7e8be5856746b51a52a13a9082d6c90", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abbf8ec7410482182b97312850a09472ac1666f5a6749c61b13d78780fe98e9096893c5f0533ad837ce1c9b889b9ed0305db44f62cfac81b29c9226ea88f2ed8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ef13739bb3c45014427ad3684bd2e081504b05ca5f76072330036f43ee54926d0ea265d01e2acc3d5873dccf41322b02ef868ead10bfa341b29d88ab72c58aa"} 16:22:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:22:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:22:53 INFO - Contents: 16:22:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03e5deb601774f90d1b7f21be88225a147def9b8401dcfb8be44a06a035d9162a5228fc9976b55e026f0cc606ce8dc5b0e47588a5280817c7c7837d678dd3af1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f82f9eda4b548daffd3acbe8db8a9f40e198b627ab026ab5935e397b237d6d51fc803c1fb9889626b459d0522e69471f7e8be5856746b51a52a13a9082d6c90", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abbf8ec7410482182b97312850a09472ac1666f5a6749c61b13d78780fe98e9096893c5f0533ad837ce1c9b889b9ed0305db44f62cfac81b29c9226ea88f2ed8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ef13739bb3c45014427ad3684bd2e081504b05ca5f76072330036f43ee54926d0ea265d01e2acc3d5873dccf41322b02ef868ead10bfa341b29d88ab72c58aa"} 16:22:53 INFO - Running post-run listener: copy_logs_to_upload_dir 16:22:53 INFO - Copying logs to upload dir... 16:22:53 INFO - mkdir: /builds/slave/test/build/upload/logs 16:22:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.117039 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-09-02 16:22:54.074107) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 16:22:54.078974) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897 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/03e5deb601774f90d1b7f21be88225a147def9b8401dcfb8be44a06a035d9162a5228fc9976b55e026f0cc606ce8dc5b0e47588a5280817c7c7837d678dd3af1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f82f9eda4b548daffd3acbe8db8a9f40e198b627ab026ab5935e397b237d6d51fc803c1fb9889626b459d0522e69471f7e8be5856746b51a52a13a9082d6c90", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abbf8ec7410482182b97312850a09472ac1666f5a6749c61b13d78780fe98e9096893c5f0533ad837ce1c9b889b9ed0305db44f62cfac81b29c9226ea88f2ed8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ef13739bb3c45014427ad3684bd2e081504b05ca5f76072330036f43ee54926d0ea265d01e2acc3d5873dccf41322b02ef868ead10bfa341b29d88ab72c58aa"} build_url:https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011266 build_url: 'https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03e5deb601774f90d1b7f21be88225a147def9b8401dcfb8be44a06a035d9162a5228fc9976b55e026f0cc606ce8dc5b0e47588a5280817c7c7837d678dd3af1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f82f9eda4b548daffd3acbe8db8a9f40e198b627ab026ab5935e397b237d6d51fc803c1fb9889626b459d0522e69471f7e8be5856746b51a52a13a9082d6c90", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abbf8ec7410482182b97312850a09472ac1666f5a6749c61b13d78780fe98e9096893c5f0533ad837ce1c9b889b9ed0305db44f62cfac81b29c9226ea88f2ed8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ef13739bb3c45014427ad3684bd2e081504b05ca5f76072330036f43ee54926d0ea265d01e2acc3d5873dccf41322b02ef868ead10bfa341b29d88ab72c58aa"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 16:22:54.109606) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:22:54.109907) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1504393316.375659-1452034897 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004573 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:22:54.191260) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 16:22:54.194577) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 16:22:54.194858) ========= ========= Total master_lag: 1.44 =========