builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-028 starttime: 1504393262.65 results: success (0) revision: f5d99e9f3d7bf19c8a222db97bedc924da27fd58 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:02.653325) ========= 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:02.653803) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:02.654089) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:02.672322) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:02.672743) ========= 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=836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-02 16:01:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.8M=0s 2017-09-02 16:01:02 (69.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.154097 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:02.849420) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:02.851769) ========= 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=836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015365 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:02.911683) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:02.912354) ========= 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=836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-02 16:01:02,901 truncating revision to first 12 chars 2017-09-02 16:01:02,901 Setting DEBUG logging. 2017-09-02 16:01:02,901 attempt 1/10 2017-09-02 16:01:02,901 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:03,343 unpacking tar archive at: mozilla-central-f5d99e9f3d7b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.806188 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:03.739435) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:03.739793) ========= script_repo_revision: f5d99e9f3d7bf19c8a222db97bedc924da27fd58 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:03.740181) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:03.740452) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 16:01:03.757058) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-02 16:01:03.757461) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:01:03 INFO - MultiFileLogger online at 20170902 16:01:03 in /builds/slave/test 16:01:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 16:01:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:01:03 INFO - {'append_to_log': False, 16:01:03 INFO - 'base_work_dir': '/builds/slave/test', 16:01:03 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:01:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:01:03 INFO - 'branch': 'Firefox-Non-PGO', 16:01:03 INFO - 'buildbot_json_path': 'buildprops.json', 16:01:03 INFO - 'code_coverage': False, 16:01:03 INFO - 'config_files': ('talos/linux_config.py',), 16:01:03 INFO - 'default_actions': ('clobber', 16:01:03 INFO - 'read-buildbot-config', 16:01:03 INFO - 'download-and-extract', 16:01:03 INFO - 'populate-webroot', 16:01:03 INFO - 'create-virtualenv', 16:01:03 INFO - 'install', 16:01:03 INFO - 'setup-mitmproxy', 16:01:03 INFO - 'run-tests'), 16:01:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:01:03 INFO - 'disable_ccov_upload': False, 16:01:03 INFO - 'disable_stylo': False, 16:01:03 INFO - 'download_minidump_stackwalk': True, 16:01:03 INFO - 'download_symbols': 'ondemand', 16:01:03 INFO - 'enable_stylo': False, 16:01:03 INFO - 'enable_webrender': False, 16:01:03 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:01:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:01:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:01:03 INFO - 'gecko_profile': False, 16:01:03 INFO - 'gecko_profile_interval': 0, 16:01:03 INFO - 'installer_path': 'installer.exe', 16:01:03 INFO - 'log_level': 'info', 16:01:03 INFO - 'log_name': 'talos', 16:01:03 INFO - 'log_to_console': True, 16:01:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:01:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:01:03 INFO - 'opt_config_files': (), 16:01:03 INFO - 'pip_index': False, 16:01:03 INFO - 'suite': 'dromaeojs-e10s', 16:01:03 INFO - 'system_bits': '32', 16:01:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:01:03 INFO - 'title': 'talos-linux64-ix-028', 16:01:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:01:03 INFO - 'use_talos_json': True, 16:01:03 INFO - 'verify': 'False', 16:01:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:01:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:01:03 INFO - 'work_dir': 'build'} 16:01:03 INFO - [mozharness: 2017-09-02 23:01:03.882491Z] Running clobber step. 16:01:03 INFO - Running pre-action listener: _resource_record_pre_action 16:01:03 INFO - Running main action method: clobber 16:01:03 INFO - rmtree: /builds/slave/test/build 16:01:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:01:04 INFO - Running post-action listener: _resource_record_post_action 16:01:04 INFO - [mozharness: 2017-09-02 23:01:04.894260Z] Finished clobber step (success) 16:01:04 INFO - [mozharness: 2017-09-02 23:01:04.894357Z] Running read-buildbot-config step. 16:01:04 INFO - Running pre-action listener: _resource_record_pre_action 16:01:04 INFO - Running main action method: read_buildbot_config 16:01:04 INFO - Using buildbot properties: 16:01:04 INFO - { 16:01:04 INFO - "project": "", 16:01:04 INFO - "product": "firefox", 16:01:04 INFO - "who": "archaeopteryx@coole-files.de", 16:01:04 INFO - "installer_path": "public/build/target.tar.bz2", 16:01:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:01:04 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:01:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 16:01:04 INFO - "stage_platform": "linux64", 16:01:04 INFO - "basedir": "/builds/slave/test", 16:01:04 INFO - "buildnumber": 805, 16:01:04 INFO - "platform": "ubuntu64_hw", 16:01:04 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:01:04 INFO - "slavebuilddir": "test", 16:01:04 INFO - "taskId": "edrZjHT1RfGBVOGvty0ySA", 16:01:04 INFO - "branch": "mozilla-central", 16:01:04 INFO - "script_repo_revision": "production", 16:01:04 INFO - "revision": "f5d99e9f3d7bf19c8a222db97bedc924da27fd58", 16:01:04 INFO - "slavename": "talos-linux64-ix-028", 16:01:04 INFO - "repo_path": "mozilla-central" 16:01:04 INFO - } 16:01:04 INFO - Finding installer, test and symbols from parent task. 16:01:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/edrZjHT1RfGBVOGvty0ySA'}, attempt #1 16:01:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/edrZjHT1RfGBVOGvty0ySA'}, attempt #1 16:01:05 INFO - Task dependencies: Y5WsbGReTUeKPP-utDhE2w 16:01:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w'}, attempt #1 16:01:06 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2 16:01:06 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.test_packages.json 16:01:06 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.crashreporter-symbols.zip 16:01:06 INFO - Running post-action listener: _resource_record_post_action 16:01:06 INFO - [mozharness: 2017-09-02 23:01:06.246861Z] Finished read-buildbot-config step (success) 16:01:06 INFO - [mozharness: 2017-09-02 23:01:06.247078Z] Running download-and-extract step. 16:01:06 INFO - Running pre-action listener: _resource_record_pre_action 16:01:06 INFO - Running main action method: download_and_extract 16:01:06 INFO - mkdir: /builds/slave/test/build/tests 16:01: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:01:06 INFO - trying https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.test_packages.json 16:01:06 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:01:06 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:01:06 INFO - Downloaded 989 bytes. 16:01:06 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:01:06 INFO - Using the following test package requirements: 16:01:06 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:01:06 INFO - u'common': [u'target.common.tests.zip'], 16:01:06 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:01:06 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:01:06 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:01:06 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:01:06 INFO - u'mozbase': [u'target.common.tests.zip'], 16:01:06 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:01:06 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:01:06 INFO - u'web-platform': [u'target.common.tests.zip', 16:01:06 INFO - u'target.web-platform.tests.tar.gz'], 16:01:06 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:01:06 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:01:06 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:01:06 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:01:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.common.tests.zip into memory 16:01:07 INFO - Content-Length response header: 55022372 16:01:07 INFO - Bytes received: 55022372 16:01:19 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:01:19 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:01:19 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:01:19 INFO - Fetch https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.common.tests.zip into memory 16:01:20 INFO - Content-Length response header: 55022372 16:01:20 INFO - Bytes received: 55022372 16:01:32 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:01:32 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:01:32 INFO - Fetch https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.talos.tests.zip into memory 16:01:32 INFO - Content-Length response header: 14496650 16:01:32 INFO - Bytes received: 14496650 16:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:33 INFO - trying https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2 16:01:33 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:01:33 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:01:34 INFO - Downloaded 62581036 bytes. 16:01:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2 16:01:34 INFO - mkdir: /builds/slave/test/properties 16:01:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:01:34 INFO - Writing to file /builds/slave/test/properties/build_url 16:01:34 INFO - Contents: 16:01:34 INFO - build_url:https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2 16:01:34 INFO - Running post-action listener: _resource_record_post_action 16:01:34 INFO - Running post-action listener: find_tests_for_verification 16:01:34 INFO - Running post-action listener: set_extra_try_arguments 16:01:34 INFO - [mozharness: 2017-09-02 23:01:34.213018Z] Finished download-and-extract step (success) 16:01:34 INFO - [mozharness: 2017-09-02 23:01:34.213115Z] Running populate-webroot step. 16:01:34 INFO - Running pre-action listener: _resource_record_pre_action 16:01:34 INFO - Running main action method: populate_webroot 16:01:34 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:01:34 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:01:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:01:34 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:01:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:01:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:34 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:01:34 INFO - 'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:01:34 INFO - 'tests': ['damp', 'tps']}, 16:01:34 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:01:34 INFO - 'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['damp', 'tps']}, 16:01:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:01:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:01:34 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:01:34 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:01:34 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:01:34 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:01:34 INFO - 'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:01:34 INFO - 'other-e10s': {'tests': ['a11yr', 16:01:34 INFO - 'ts_paint', 16:01:34 INFO - 'tpaint', 16:01:34 INFO - 'sessionrestore', 16:01:34 INFO - 'sessionrestore_many_windows', 16:01:34 INFO - 'sessionrestore_no_auto_restore', 16:01:34 INFO - 'tabpaint']}, 16:01:34 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['a11yr', 16:01:34 INFO - 'ts_paint', 16:01:34 INFO - 'tpaint', 16:01:34 INFO - 'sessionrestore', 16:01:34 INFO - 'sessionrestore_many_windows', 16:01:34 INFO - 'sessionrestore_no_auto_restore', 16:01:34 INFO - 'tabpaint']}, 16:01:34 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:01:34 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:01:34 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['bloom_basic_singleton']}, 16:01:34 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['bloom_basic']}, 16:01:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:01:34 INFO - 'tsvgr_opacity', 16:01:34 INFO - 'tart', 16:01:34 INFO - 'tscrollx', 16:01:34 INFO - 'tsvg_static']}, 16:01:34 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['tsvgx', 16:01:34 INFO - 'tsvgr_opacity', 16:01:34 INFO - 'tart', 16:01:34 INFO - 'tscrollx', 16:01:34 INFO - 'tsvg_static']}, 16:01:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:01:34 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:01:34 INFO - 'talos_options': ['--enable-stylo'], 16:01:34 INFO - 'tests': ['tp5o']}, 16:01:34 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:01:34 INFO - 'talos_options': ['--mitmproxy', 16:01:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:01:34 INFO - '--firstNonBlankPaint'], 16:01:34 INFO - 'tests': ['tp6_google', 16:01:34 INFO - 'tp6_youtube', 16:01:34 INFO - 'tp6_amazon', 16:01:34 INFO - 'tp6_facebook']}, 16:01:34 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:01:34 INFO - 'talos_options': ['--enable-stylo', 16:01:34 INFO - '--mitmproxy', 16:01:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:01:34 INFO - '--firstNonBlankPaint'], 16:01:34 INFO - 'tests': ['tp6_google', 16:01:34 INFO - 'tp6_youtube', 16:01:34 INFO - 'tp6_amazon', 16:01:34 INFO - 'tp6_facebook']}, 16:01:34 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:01:34 INFO - 'talos_options': ['--enable-stylo', 16:01:34 INFO - '--stylo-threads=1', 16:01:34 INFO - '--mitmproxy', 16:01:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:01:34 INFO - '--firstNonBlankPaint'], 16:01:34 INFO - 'tests': ['tp6_google', 16:01:34 INFO - 'tp6_youtube', 16:01:34 INFO - 'tp6_amazon', 16:01:34 INFO - 'tp6_facebook']}, 16:01:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:01:34 INFO - 'talos_options': ['--xperf_path', 16:01:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:34 INFO - 'tests': ['tp5n']}, 16:01:34 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:01:34 INFO - 'talos_options': ['--enable-stylo', 16:01:34 INFO - '--xperf_path', 16:01:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:34 INFO - 'tests': ['tp5n']}}} 16:01:34 INFO - Running post-action listener: _resource_record_post_action 16:01:34 INFO - [mozharness: 2017-09-02 23:01:34.227502Z] Finished populate-webroot step (success) 16:01:34 INFO - [mozharness: 2017-09-02 23:01:34.227591Z] Running create-virtualenv step. 16:01:34 INFO - Running pre-action listener: _resource_record_pre_action 16:01:34 INFO - Running main action method: create_virtualenv 16:01:34 INFO - Creating virtualenv /builds/slave/test/build/venv 16:01:34 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:01:34 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:01:34 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:01:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:01:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:01:34 INFO - Installing distribute.............................................................................................................................................................................................done. 16:01:35 INFO - Installing pip...............done. 16:01:35 INFO - Return code: 0 16:01:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:01:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:01:35 INFO - Reading from file tmpfile_stdout 16:01:35 INFO - Output received: 16:01:35 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:01:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:01: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:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01: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:01:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191090>, '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 0x2191d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218bf90>, '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': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', '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:01: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', 'pip>=1.5'] in /builds/slave/test/build 16:01: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 pip>=1.5 16:01:35 INFO - Using env: {'DISPLAY': ':0', 16:01:35 INFO - 'HOME': '/home/cltbld', 16:01:35 INFO - 'LANG': 'en_US.UTF-8', 16:01:35 INFO - 'LANGUAGE': 'en_US:en', 16:01:35 INFO - 'LOGNAME': 'cltbld', 16:01:35 INFO - 'MAIL': '/var/mail/cltbld', 16:01:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:35 INFO - 'MOZ_NO_REMOTE': '1', 16:01:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:35 INFO - 'NO_EM_RESTART': '1', 16:01:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:35 INFO - 'PWD': '/builds/slave/test', 16:01:35 INFO - 'SHELL': '/bin/bash', 16:01:35 INFO - 'SHLVL': '1', 16:01:35 INFO - 'TERM': 'linux', 16:01:35 INFO - 'TMOUT': '86400', 16:01:35 INFO - 'USER': 'cltbld', 16:01:35 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', 16:01:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:35 INFO - '_': '/tools/buildbot/bin/python'} 16:01:36 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:01:36 INFO - Downloading/unpacking pip>=1.5 16:01:36 INFO - Running setup.py egg_info for package pip 16:01:36 INFO - warning: no files found matching 'pip/cacert.pem' 16:01:36 INFO - warning: no files found matching '*.html' under directory 'docs' 16:01:36 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:01:36 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:01:36 INFO - Installing collected packages: pip 16:01:36 INFO - Found existing installation: pip 1.1 16:01:36 INFO - Uninstalling pip: 16:01:36 INFO - Successfully uninstalled pip 16:01:36 INFO - Running setup.py install for pip 16:01:36 INFO - warning: no files found matching 'pip/cacert.pem' 16:01:36 INFO - warning: no files found matching '*.html' under directory 'docs' 16:01:36 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:01:36 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:01:36 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:01:36 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:01:36 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:01:36 INFO - Successfully installed pip 16:01:36 INFO - Cleaning up... 16:01:36 INFO - Return code: 0 16:01:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:01:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:36 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 0x1e211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191090>, '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 0x2191d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218bf90>, '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': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', '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:01:36 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:01:36 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:01:36 INFO - Using env: {'DISPLAY': ':0', 16:01:36 INFO - 'HOME': '/home/cltbld', 16:01:36 INFO - 'LANG': 'en_US.UTF-8', 16:01:36 INFO - 'LANGUAGE': 'en_US:en', 16:01:36 INFO - 'LOGNAME': 'cltbld', 16:01:36 INFO - 'MAIL': '/var/mail/cltbld', 16:01:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:36 INFO - 'MOZ_NO_REMOTE': '1', 16:01:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:36 INFO - 'NO_EM_RESTART': '1', 16:01:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:36 INFO - 'PWD': '/builds/slave/test', 16:01:36 INFO - 'SHELL': '/bin/bash', 16:01:36 INFO - 'SHLVL': '1', 16:01:36 INFO - 'TERM': 'linux', 16:01:36 INFO - 'TMOUT': '86400', 16:01:36 INFO - 'USER': 'cltbld', 16:01:36 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', 16:01:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:36 INFO - '_': '/tools/buildbot/bin/python'} 16:01:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:36 INFO - Downloading/unpacking psutil>=3.1.1 16:01: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:01: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:01: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:01: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:01:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:01:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:01:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:01:37 INFO - Installing collected packages: psutil 16:01:37 INFO - Running setup.py install for psutil 16:01:37 INFO - building 'psutil._psutil_linux' extension 16:01:37 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:01:38 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:01:38 INFO - building 'psutil._psutil_posix' extension 16:01:38 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:01:38 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:01:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:01:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:01:38 INFO - Successfully installed psutil 16:01:38 INFO - Cleaning up... 16:01:38 INFO - Return code: 0 16:01:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:01:38 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:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:38 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:01:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:38 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 0x1e211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191090>, '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 0x2191d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218bf90>, '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': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', '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:01:38 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:01:38 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:01:38 INFO - Using env: {'DISPLAY': ':0', 16:01:38 INFO - 'HOME': '/home/cltbld', 16:01:38 INFO - 'LANG': 'en_US.UTF-8', 16:01:38 INFO - 'LANGUAGE': 'en_US:en', 16:01:38 INFO - 'LOGNAME': 'cltbld', 16:01:38 INFO - 'MAIL': '/var/mail/cltbld', 16:01:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:38 INFO - 'MOZ_NO_REMOTE': '1', 16:01:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:38 INFO - 'NO_EM_RESTART': '1', 16:01:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:38 INFO - 'PWD': '/builds/slave/test', 16:01:38 INFO - 'SHELL': '/bin/bash', 16:01:38 INFO - 'SHLVL': '1', 16:01:38 INFO - 'TERM': 'linux', 16:01:38 INFO - 'TMOUT': '86400', 16:01:38 INFO - 'USER': 'cltbld', 16:01:38 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', 16:01:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:38 INFO - '_': '/tools/buildbot/bin/python'} 16:01:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:01: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:01: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:01: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:01: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:01:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:01:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:01:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:01:39 INFO - Installing collected packages: mozsystemmonitor 16:01:39 INFO - Running setup.py install for mozsystemmonitor 16:01:39 INFO - Successfully installed mozsystemmonitor 16:01:39 INFO - Cleaning up... 16:01:39 INFO - Return code: 0 16:01:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:01:39 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:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:39 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:01:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:39 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 0x1e211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191090>, '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 0x2191d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218bf90>, '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': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', '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:01:39 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:01:39 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:01:39 INFO - Using env: {'DISPLAY': ':0', 16:01:39 INFO - 'HOME': '/home/cltbld', 16:01:39 INFO - 'LANG': 'en_US.UTF-8', 16:01:39 INFO - 'LANGUAGE': 'en_US:en', 16:01:39 INFO - 'LOGNAME': 'cltbld', 16:01:39 INFO - 'MAIL': '/var/mail/cltbld', 16:01:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:39 INFO - 'MOZ_NO_REMOTE': '1', 16:01:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:39 INFO - 'NO_EM_RESTART': '1', 16:01:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:39 INFO - 'PWD': '/builds/slave/test', 16:01:39 INFO - 'SHELL': '/bin/bash', 16:01:39 INFO - 'SHLVL': '1', 16:01:39 INFO - 'TERM': 'linux', 16:01:39 INFO - 'TMOUT': '86400', 16:01:39 INFO - 'USER': 'cltbld', 16:01:39 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', 16:01:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:39 INFO - '_': '/tools/buildbot/bin/python'} 16:01:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:40 INFO - Downloading/unpacking jsonschema==2.5.1 16:01:40 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:01:40 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:01:40 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:01:40 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:01:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:01:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:01:41 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:01:41 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:01:41 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:01:41 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:01:41 INFO - Downloading functools32-3.2.3-2.tar.gz 16:01:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:01:41 INFO - warning: no files found matching '*.txt' 16:01:41 INFO - no previously-included directories found matching 'build' 16:01:41 INFO - no previously-included directories found matching 'dist' 16:01:41 INFO - no previously-included directories found matching '.git*' 16:01:41 INFO - Installing collected packages: jsonschema, functools32 16:01:41 INFO - Running setup.py install for functools32 16:01:41 INFO - warning: no files found matching '*.txt' 16:01:41 INFO - no previously-included directories found matching 'build' 16:01:41 INFO - no previously-included directories found matching 'dist' 16:01:41 INFO - no previously-included directories found matching '.git*' 16:01:41 INFO - Successfully installed jsonschema functools32 16:01:41 INFO - Cleaning up... 16:01:41 INFO - Return code: 0 16:01:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:01:41 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:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:41 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:01:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:41 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 0x1e211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191090>, '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 0x2191d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218bf90>, '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': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', '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:01:41 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:01:41 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:01:41 INFO - Using env: {'DISPLAY': ':0', 16:01:41 INFO - 'HOME': '/home/cltbld', 16:01:41 INFO - 'LANG': 'en_US.UTF-8', 16:01:41 INFO - 'LANGUAGE': 'en_US:en', 16:01:41 INFO - 'LOGNAME': 'cltbld', 16:01:41 INFO - 'MAIL': '/var/mail/cltbld', 16:01:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:41 INFO - 'MOZ_NO_REMOTE': '1', 16:01:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:41 INFO - 'NO_EM_RESTART': '1', 16:01:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:41 INFO - 'PWD': '/builds/slave/test', 16:01:41 INFO - 'SHELL': '/bin/bash', 16:01:41 INFO - 'SHLVL': '1', 16:01:41 INFO - 'TERM': 'linux', 16:01:41 INFO - 'TMOUT': '86400', 16:01:41 INFO - 'USER': 'cltbld', 16:01:41 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', 16:01:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:41 INFO - '_': '/tools/buildbot/bin/python'} 16:01:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:01:41 INFO - Cleaning up... 16:01:41 INFO - Return code: 0 16:01:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:01:41 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:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:41 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:01:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:41 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 0x1e211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191090>, '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 0x2191d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218bf90>, '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': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', '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:01:41 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:01:41 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:01:41 INFO - Using env: {'DISPLAY': ':0', 16:01:41 INFO - 'HOME': '/home/cltbld', 16:01:41 INFO - 'LANG': 'en_US.UTF-8', 16:01:41 INFO - 'LANGUAGE': 'en_US:en', 16:01:41 INFO - 'LOGNAME': 'cltbld', 16:01:41 INFO - 'MAIL': '/var/mail/cltbld', 16:01:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:41 INFO - 'MOZ_NO_REMOTE': '1', 16:01:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:41 INFO - 'NO_EM_RESTART': '1', 16:01:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:41 INFO - 'PWD': '/builds/slave/test', 16:01:41 INFO - 'SHELL': '/bin/bash', 16:01:41 INFO - 'SHLVL': '1', 16:01:41 INFO - 'TERM': 'linux', 16:01:41 INFO - 'TMOUT': '86400', 16:01:41 INFO - 'USER': 'cltbld', 16:01:41 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', 16:01:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:41 INFO - '_': '/tools/buildbot/bin/python'} 16:01:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:41 INFO - Downloading/unpacking blobuploader==1.2.4 16:01:41 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:01:41 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:01:41 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:01:41 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:01:43 INFO - Downloading blobuploader-1.2.4.tar.gz 16:01:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:01:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:01:43 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:01:43 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:01:43 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:01:43 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:01:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:01:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:01:43 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:01:43 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:01:43 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:01:43 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:01:43 INFO - Downloading docopt-0.6.1.tar.gz 16:01:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:01:43 INFO - Installing collected packages: blobuploader, requests, docopt 16:01:43 INFO - Running setup.py install for blobuploader 16:01:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:01:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:01:43 INFO - Running setup.py install for requests 16:01:44 INFO - Running setup.py install for docopt 16:01:44 INFO - Successfully installed blobuploader requests docopt 16:01:44 INFO - Cleaning up... 16:01:44 INFO - Return code: 0 16:01:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:44 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 0x1e211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191090>, '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 0x2191d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218bf90>, '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': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', '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:01:44 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:01:44 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:01:44 INFO - Using env: {'DISPLAY': ':0', 16:01:44 INFO - 'HOME': '/home/cltbld', 16:01:44 INFO - 'LANG': 'en_US.UTF-8', 16:01:44 INFO - 'LANGUAGE': 'en_US:en', 16:01:44 INFO - 'LOGNAME': 'cltbld', 16:01:44 INFO - 'MAIL': '/var/mail/cltbld', 16:01:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:44 INFO - 'MOZ_NO_REMOTE': '1', 16:01:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:44 INFO - 'NO_EM_RESTART': '1', 16:01:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:44 INFO - 'PWD': '/builds/slave/test', 16:01:44 INFO - 'SHELL': '/bin/bash', 16:01:44 INFO - 'SHLVL': '1', 16:01:44 INFO - 'TERM': 'linux', 16:01:44 INFO - 'TMOUT': '86400', 16:01:44 INFO - 'USER': 'cltbld', 16:01:44 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', 16:01:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:44 INFO - '_': '/tools/buildbot/bin/python'} 16:01:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:01:44 INFO - Running setup.py (path:/tmp/pip-Rmc_Eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:01:44 INFO - Running setup.py (path:/tmp/pip-SY2cCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:01:44 INFO - Running setup.py (path:/tmp/pip-7C1557-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:01:44 INFO - Running setup.py (path:/tmp/pip-o5jH1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:01:44 INFO - Running setup.py (path:/tmp/pip-bntD6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:01:44 INFO - Running setup.py (path:/tmp/pip-pebNpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:01:44 INFO - Running setup.py (path:/tmp/pip-fp0Ec2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:01:45 INFO - Running setup.py (path:/tmp/pip-S1qgar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:01:45 INFO - Running setup.py (path:/tmp/pip-TepYlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:01:45 INFO - Running setup.py (path:/tmp/pip-0JM2ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:01:45 INFO - Running setup.py (path:/tmp/pip-0vQt0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:01:45 INFO - Running setup.py (path:/tmp/pip-UuaJTp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:01:45 INFO - Running setup.py (path:/tmp/pip-SVLrgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:01:45 INFO - Running setup.py (path:/tmp/pip-ZNlA7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:45 INFO - Running setup.py (path:/tmp/pip-onIovw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:01:45 INFO - Running setup.py (path:/tmp/pip-w5v6tS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:01:45 INFO - Running setup.py (path:/tmp/pip-De3ixM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:01:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:01:45 INFO - Running setup.py install for manifestparser 16:01:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:01:45 INFO - Running setup.py install for mozcrash 16:01:45 INFO - Running setup.py install for mozdebug 16:01:46 INFO - Running setup.py install for mozdevice 16:01:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:01:46 INFO - Running setup.py install for mozfile 16:01:46 INFO - Running setup.py install for mozhttpd 16:01:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:01:46 INFO - Running setup.py install for mozinfo 16:01:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:01:46 INFO - Running setup.py install for mozInstall 16:01:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:01:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:01:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:01:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:01:46 INFO - Running setup.py install for mozleak 16:01:46 INFO - Running setup.py install for mozlog 16:01:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:01:46 INFO - Running setup.py install for moznetwork 16:01:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:01:46 INFO - Running setup.py install for mozprocess 16:01:46 INFO - Running setup.py install for mozprofile 16:01:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:01:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:01:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:01:47 INFO - Running setup.py install for mozrunner 16:01:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:01:47 INFO - Running setup.py install for mozscreenshot 16:01:47 INFO - Running setup.py install for moztest 16:01:47 INFO - Running setup.py install for mozversion 16:01:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:01:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:01:47 INFO - Cleaning up... 16:01:47 INFO - Return code: 0 16:01:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:47 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 0x1e211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191090>, '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 0x2191d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218bf90>, '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': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', '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:01:47 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:01:47 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:01:47 INFO - Using env: {'DISPLAY': ':0', 16:01:47 INFO - 'HOME': '/home/cltbld', 16:01:47 INFO - 'LANG': 'en_US.UTF-8', 16:01:47 INFO - 'LANGUAGE': 'en_US:en', 16:01:47 INFO - 'LOGNAME': 'cltbld', 16:01:47 INFO - 'MAIL': '/var/mail/cltbld', 16:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:47 INFO - 'MOZ_NO_REMOTE': '1', 16:01:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:47 INFO - 'NO_EM_RESTART': '1', 16:01:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:47 INFO - 'PWD': '/builds/slave/test', 16:01:47 INFO - 'SHELL': '/bin/bash', 16:01:47 INFO - 'SHLVL': '1', 16:01:47 INFO - 'TERM': 'linux', 16:01:47 INFO - 'TMOUT': '86400', 16:01:47 INFO - 'USER': 'cltbld', 16:01:47 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', 16:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:47 INFO - '_': '/tools/buildbot/bin/python'} 16:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:01:47 INFO - Running setup.py (path:/tmp/pip-MldnO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:01:47 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:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:01:47 INFO - Running setup.py (path:/tmp/pip-cf_mbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:01:47 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:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:01:47 INFO - Running setup.py (path:/tmp/pip-Xqgtlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:01:47 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:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:01:47 INFO - Running setup.py (path:/tmp/pip-yQ2OCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:01:48 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:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:01:48 INFO - Running setup.py (path:/tmp/pip-2Aqk0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:01:48 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:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:01:48 INFO - Running setup.py (path:/tmp/pip-KscusS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:01:48 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:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:01:48 INFO - Running setup.py (path:/tmp/pip-Cb8N78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:01:48 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:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:01:48 INFO - Running setup.py (path:/tmp/pip-OYwCra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:01:48 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:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:01:48 INFO - Running setup.py (path:/tmp/pip-f9Y_lW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:01:48 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:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:01:48 INFO - Running setup.py (path:/tmp/pip-xhZfdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:01:48 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:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:01:48 INFO - Running setup.py (path:/tmp/pip-mz5bLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:01:48 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:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:01:48 INFO - Running setup.py (path:/tmp/pip-NKSCk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:01:48 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:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:01:48 INFO - Running setup.py (path:/tmp/pip-zLXKbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:01:48 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:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:01:48 INFO - Running setup.py (path:/tmp/pip-bRQy4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:01:48 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:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:48 INFO - Running setup.py (path:/tmp/pip-NXhki_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:48 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:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:01:48 INFO - Running setup.py (path:/tmp/pip-OoBucJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:01:48 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:01:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:01:48 INFO - Running setup.py (path:/tmp/pip-niZuUx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:01:48 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:01:48 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:01:48 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:01:48 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:01:48 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:01:48 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:01:48 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:01:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:01:48 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:01:48 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:01:48 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:01:48 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:01:50 INFO - Downloading blessings-1.6.tar.gz 16:01:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:01:50 INFO - Installing collected packages: blessings 16:01:50 INFO - Running setup.py install for blessings 16:01:50 INFO - Successfully installed blessings 16:01:50 INFO - Cleaning up... 16:01:50 INFO - Return code: 0 16:01:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:01:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:01:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:01:50 INFO - Reading from file tmpfile_stdout 16:01:50 INFO - Current package versions: 16:01:50 INFO - argparse == 1.2.1 16:01:50 INFO - blessings == 1.6 16:01:50 INFO - blobuploader == 1.2.4 16:01:50 INFO - docopt == 0.6.1 16:01:50 INFO - functools32 == 3.2.3-2 16:01:50 INFO - jsonschema == 2.5.1 16:01:50 INFO - manifestparser == 1.1 16:01:50 INFO - mozInstall == 1.13 16:01:50 INFO - mozcrash == 1.0 16:01:50 INFO - mozdebug == 0.1 16:01:50 INFO - mozdevice == 0.51 16:01:50 INFO - mozfile == 1.2 16:01:50 INFO - mozhttpd == 0.7 16:01:50 INFO - mozinfo == 0.10 16:01:50 INFO - mozleak == 0.1 16:01:50 INFO - mozlog == 3.5 16:01:50 INFO - moznetwork == 0.27 16:01:50 INFO - mozprocess == 0.25 16:01:50 INFO - mozprofile == 0.28 16:01:50 INFO - mozrunner == 6.13 16:01:50 INFO - mozscreenshot == 0.1 16:01:50 INFO - mozsystemmonitor == 0.3 16:01:50 INFO - moztest == 0.8 16:01:50 INFO - mozversion == 1.4 16:01:50 INFO - psutil == 3.1.1 16:01:50 INFO - requests == 1.2.3 16:01:50 INFO - wsgiref == 0.1.2 16:01:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:50 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 0x1e211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191090>, '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 0x2191d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218bf90>, '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': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', '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:01:50 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:01:50 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:01:50 INFO - Using env: {'DISPLAY': ':0', 16:01:50 INFO - 'HOME': '/home/cltbld', 16:01:50 INFO - 'LANG': 'en_US.UTF-8', 16:01:50 INFO - 'LANGUAGE': 'en_US:en', 16:01:50 INFO - 'LOGNAME': 'cltbld', 16:01:50 INFO - 'MAIL': '/var/mail/cltbld', 16:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:50 INFO - 'MOZ_NO_REMOTE': '1', 16:01:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:50 INFO - 'NO_EM_RESTART': '1', 16:01:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:50 INFO - 'PWD': '/builds/slave/test', 16:01:50 INFO - 'SHELL': '/bin/bash', 16:01:50 INFO - 'SHLVL': '1', 16:01:50 INFO - 'TERM': 'linux', 16:01:50 INFO - 'TMOUT': '86400', 16:01:50 INFO - 'USER': 'cltbld', 16:01:50 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', 16:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:50 INFO - '_': '/tools/buildbot/bin/python'} 16:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:50 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:01:50 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:01:50 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:01:50 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:01:50 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:01:50 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:01:50 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:01:50 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:01:50 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:01:50 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:01:51 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:01:51 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:01:51 INFO - Installing collected packages: simplejson 16:01:51 INFO - Running setup.py install for simplejson 16:01:51 INFO - building 'simplejson._speedups' extension 16:01:51 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:01:52 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:01:52 INFO - Successfully installed simplejson 16:01:52 INFO - Cleaning up... 16:01:52 INFO - Return code: 0 16:01:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2191090>, '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 0x2191d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218bf90>, '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': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', '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:01:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:01:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:01:52 INFO - Using env: {'DISPLAY': ':0', 16:01:52 INFO - 'HOME': '/home/cltbld', 16:01:52 INFO - 'LANG': 'en_US.UTF-8', 16:01:52 INFO - 'LANGUAGE': 'en_US:en', 16:01:52 INFO - 'LOGNAME': 'cltbld', 16:01:52 INFO - 'MAIL': '/var/mail/cltbld', 16:01:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:52 INFO - 'MOZ_NO_REMOTE': '1', 16:01:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:52 INFO - 'NO_EM_RESTART': '1', 16:01:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:52 INFO - 'PWD': '/builds/slave/test', 16:01:52 INFO - 'SHELL': '/bin/bash', 16:01:52 INFO - 'SHLVL': '1', 16:01:52 INFO - 'TERM': 'linux', 16:01:52 INFO - 'TMOUT': '86400', 16:01:52 INFO - 'USER': 'cltbld', 16:01:52 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', 16:01:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:52 INFO - '_': '/tools/buildbot/bin/python'} 16:01:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:01:53 INFO - Cleaning up... 16:01:53 INFO - Return code: 0 16:01:53 INFO - Running post-action listener: _resource_record_post_action 16:01:53 INFO - Running post-action listener: _start_resource_monitoring 16:01:53 INFO - Starting resource monitoring. 16:01:53 INFO - [mozharness: 2017-09-02 23:01:53.198543Z] Finished create-virtualenv step (success) 16:01:53 INFO - [mozharness: 2017-09-02 23:01:53.199060Z] Running install step. 16:01:53 INFO - Running pre-action listener: _resource_record_pre_action 16:01:53 INFO - Running main action method: install 16:01:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:01:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:01:53 INFO - Reading from file tmpfile_stdout 16:01:53 INFO - Detecting whether we're running mozinstall >=1.0... 16:01:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:01:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:01:53 INFO - Reading from file tmpfile_stdout 16:01:53 INFO - Output received: 16:01:53 INFO - Usage: mozinstall [options] installer 16:01:53 INFO - Options: 16:01:53 INFO - -h, --help show this help message and exit 16:01:53 INFO - -d DEST, --destination=DEST 16:01:53 INFO - Directory to install application into. [default: 16:01:53 INFO - "/builds/slave/test"] 16:01:53 INFO - --app=APP Application being installed. [default: firefox] 16:01:53 INFO - mkdir: /builds/slave/test/build/application 16:01:53 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:01:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:02:10 INFO - Reading from file tmpfile_stdout 16:02:10 INFO - Output received: 16:02:10 INFO - /builds/slave/test/build/application/firefox/firefox 16:02:10 INFO - Running post-action listener: _resource_record_post_action 16:02:10 INFO - [mozharness: 2017-09-02 23:02:10.024232Z] Finished install step (success) 16:02:10 INFO - [mozharness: 2017-09-02 23:02:10.024489Z] Running setup-mitmproxy step. 16:02:10 INFO - Running pre-action listener: _resource_record_pre_action 16:02:10 INFO - Running main action method: setup_mitmproxy 16:02:10 INFO - Skipping: mitmproxy is not required 16:02:10 INFO - Running post-action listener: _resource_record_post_action 16:02:10 INFO - [mozharness: 2017-09-02 23:02:10.025624Z] Finished setup-mitmproxy step (success) 16:02:10 INFO - [mozharness: 2017-09-02 23:02:10.025845Z] Running run-tests step. 16:02:10 INFO - Running pre-action listener: _resource_record_pre_action 16:02:10 INFO - Running pre-action listener: _set_gcov_prefix 16:02:10 INFO - Running main action method: run_tests 16:02:10 WARNING - Try message not found. 16:02:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:02:10 INFO - Python 2.7.3 16:02:10 INFO - Return code: 0 16:02:10 INFO - grabbing minidump binary from tooltool 16:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:10 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 0x2191090>, '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 0x2191d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218bf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:02:10 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:02:10 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:02:10 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:02:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:02:10 INFO - Return code: 0 16:02:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:02:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:02:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:02:10 INFO - ENV: RUST_BACKTRACE is now full 16:02:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:02:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:02:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-028 --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:02:10 INFO - Using env: {'DISPLAY': ':0', 16:02:10 INFO - 'HOME': '/home/cltbld', 16:02:10 INFO - 'LANG': 'en_US.UTF-8', 16:02:10 INFO - 'LANGUAGE': 'en_US:en', 16:02:10 INFO - 'LOGNAME': 'cltbld', 16:02:10 INFO - 'MAIL': '/var/mail/cltbld', 16:02:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:02:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:10 INFO - 'MOZ_NO_REMOTE': '1', 16:02:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:02:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:10 INFO - 'NO_EM_RESTART': '1', 16:02:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:10 INFO - 'PWD': '/builds/slave/test', 16:02:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:02:10 INFO - 'RUST_BACKTRACE': 'full', 16:02:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:02:10 INFO - 'SHELL': '/bin/bash', 16:02:10 INFO - 'SHLVL': '1', 16:02:10 INFO - 'TERM': 'linux', 16:02:10 INFO - 'TMOUT': '86400', 16:02:10 INFO - 'USER': 'cltbld', 16:02:10 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', 16:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:10 INFO - '_': '/tools/buildbot/bin/python'} 16:02:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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:02:10 INFO - mozversion application_buildid: 20170902220034 16:02:10 INFO - mozversion application_changeset: f5d99e9f3d7bf19c8a222db97bedc924da27fd58 16:02:10 INFO - mozversion application_display_name: Nightly 16:02:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:02:10 INFO - mozversion application_name: Firefox 16:02:10 INFO - mozversion application_remotingname: firefox 16:02:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:02:10 INFO - mozversion application_vendor: Mozilla 16:02:10 INFO - mozversion application_version: 57.0a1 16:02:10 INFO - mozversion platform_buildid: 20170902220034 16:02:10 INFO - mozversion platform_changeset: f5d99e9f3d7bf19c8a222db97bedc924da27fd58 16:02:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:02:10 INFO - mozversion platform_version: 57.0a1 16:02:10 INFO - using testdate: 1504393330 16:02:10 INFO - actual date: 1504393330 16:02:10 INFO - starting webserver on 'localhost:60951' 16:02:10 INFO - SUITE-START | Running 2 tests 16:02:10 INFO - TEST-START | dromaeo_css 16:02:10 INFO - Initialising browser for dromaeo_css test... 16:02:10 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60951/getInfo.html -profile /tmp/tmpGPU4cB/profile 16:02:10 INFO - TEST-INFO | started process 18669 (/builds/slave/test/build/application/firefox/firefox http://localhost:60951/getInfo.html) 16:02:16 INFO - TEST-INFO | 18669: exit 0 16:02:16 INFO - Browser initialized. 16:02:16 INFO - Running cycle 1/1 for dromaeo_css test... 16:02:16 INFO - Using env: {'DISPLAY': ':0', 16:02:16 INFO - 'HOME': '/home/cltbld', 16:02:16 INFO - 'JSGC_DISABLE_POISONING': '1', 16:02:16 INFO - 'LANG': 'en_US.UTF-8', 16:02:16 INFO - 'LANGUAGE': 'en_US:en', 16:02:16 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:02:16 INFO - 'LOGNAME': 'cltbld', 16:02:16 INFO - 'MAIL': '/var/mail/cltbld', 16:02:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:02:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:02:16 INFO - 'MOZ_CRASHREPORTER': '1', 16:02:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:16 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:02:16 INFO - 'MOZ_NO_REMOTE': '1', 16:02:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:02:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:16 INFO - 'NO_EM_RESTART': '1', 16:02:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:16 INFO - 'PWD': '/builds/slave/test', 16:02:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:02:16 INFO - 'RUST_BACKTRACE': 'full', 16:02:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:02:16 INFO - 'SHELL': '/bin/bash', 16:02:16 INFO - 'SHLVL': '1', 16:02:16 INFO - 'TERM': 'linux', 16:02:16 INFO - 'TMOUT': '86400', 16:02:16 INFO - 'USER': 'cltbld', 16:02:16 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', 16:02:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:16 INFO - '_': '/tools/buildbot/bin/python'} 16:02:16 INFO - TEST-INFO | started process 18858 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGPU4cB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:02:17 INFO - PID 18858 | 16:02:17 INFO - PID 18858 | (/builds/slave/test/build/application/firefox/firefox:18910): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:02:17 INFO - PID 18858 | 16:02:28 INFO - PID 18858 | RSS: Main: 168263680 16:02:28 INFO - PID 18858 | 16:05:26 INFO - PID 18858 | Cycle 1(1): loaded http://localhost:60951/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60951/tests/dromaeo/cssquery-ext.html) 16:05:28 INFO - PID 18858 | RSS: Main: 171081728 16:05:28 INFO - PID 18858 | 16:08:29 INFO - PID 18858 | Cycle 1(1): loaded http://localhost:60951/tests/dromaeo/cssquery-ext.html (next: http://localhost:60951/tests/dromaeo/cssquery-jquery.html) 16:08:30 INFO - PID 18858 | RSS: Main: 168464384 16:08:30 INFO - PID 18858 | 16:11:32 INFO - PID 18858 | Cycle 1(1): loaded http://localhost:60951/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60951/tests/dromaeo/cssquery-mootools.html) 16:11:32 INFO - PID 18858 | RSS: Main: 170127360 16:11:32 INFO - PID 18858 | 16:14:34 INFO - PID 18858 | Cycle 1(1): loaded http://localhost:60951/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60951/tests/dromaeo/cssquery-prototype.html) 16:14:34 INFO - PID 18858 | RSS: Main: 168611840 16:14:34 INFO - PID 18858 | 16:17:38 INFO - PID 18858 | Cycle 1(1): loaded http://localhost:60951/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60951/tests/dromaeo/cssquery-yui.html) 16:17:39 INFO - PID 18858 | RSS: Main: 170242048 16:17:39 INFO - PID 18858 | 16:20:40 INFO - PID 18858 | Cycle 1(1): loaded http://localhost:60951/tests/dromaeo/cssquery-yui.html (next: http://localhost:60951/tests/dromaeo/cssquery-dojo.html) 16:20:40 INFO - PID 18858 | RSS: Main: 172113920 16:20:40 INFO - PID 18858 | 16:20:40 INFO - PID 18858 | __start_tp_report 16:20:40 INFO - PID 18858 | _x_x_mozilla_page_load 16:20:40 INFO - PID 18858 | _x_x_mozilla_page_load_details 16:20:40 INFO - PID 18858 | |i|pagename|runs| 16:20:40 INFO - PID 18858 | |0;dojo.html;3304.4217261979147;3337.6662333766626;3340.5490258815053;3366.141633883359;3369.2419205678725;6205.100260462242;6348.873022539547;6463.935360646392;6479.384458476443;6535.281119076903;6317.936820631792;6336.271328797178;6384.680765961695;6394.040893865922;6402.295747467775;5826.737796799133;5837.766489340422;5858.033424484955;5864.23764910561;5931.021381472052;5945.286565612111;5967.522598192156;5994.400559944014;6007.399260074001;6038.637681739087;827.6555722651248;5851.297844258674;5875.471207591295;5943.375945525722;5990.670513121777;5842.766289348421;5896.469317761422;5969.462748352669;5999.880002399927;5999.910001349983;5854.560907931879;5901.321348044987;5957.1957785698805;5974.283086029661;5975.551833612496;6322.810315690536;6373.267074286424;6524.445422139076;6545.639989800559;6551.475881929434;6843.589384636938;6855.177378714537;6870.7595234166565;6930.896036559456;6985.685644146026;6829.829254268633;6893.689783959734;6941.167059952838;7003.614801185933;7027.227005029443;6429.517786166056;6488.740450381979;6515.185601799775;6516.739330426777;6519.837004074841;7010.92989070113;7091.964540177266;7106.18278897923;7119.21688614252;7124.5725256484775;6677.465802735769;6691.330866913269;6770.864582708318;6785.389314961677;6811.523193376515;6609.206895172514;6714.093597364294;6825.078614387094;6861.965690171517;6928.653567321614;6756.797296081126;6894.034835123087;6922.688479018456;6925.896111558331;6930.029795828588;6823.488238382141;6900.723971041102;6914.101166848278;6921.377076063179;6925.653717314215;5278.577713782888;5319.6808191508635;5352.518273355417;5436.3476382834315;5442.0204363215;4684.344191813149;4691.5308469152815;4698.812047518061;4734.692245004064;4735.976320118446;6036.15493830864;6058.1215723719815;6093.238345206849;6099.268087829489;6115.602485838406;6914.827129321807;6954.826129346706;6983.511154219156;6986.965065174743;7002.404795592432;5942.494887934487;5995.880082398327;6046.3651316612;6060.060690592965;6090.964536028886;5747.425257474303;5833.562482813721;5868.207791948118;5882.794102206402;5920.674362910081;6562.639054852029;6643.269240383554;6647.5346725729705;6650.767223147167;6653.567518111307;6888.690008949609;6949.131358580177;7033.507654464139;7038.085048943809;7051.1186101737285;6700.195976482853;6702.83242918931;6732.091167692299;6786;6812.0803691503;6675.4325882300545;6779.15260592426;6793.116894803644;6796.35434633709;6893.689783959834;6815.659217039228;6828.556143850634;6833.45332373399;6836.692348844414;6842.144731908512;6475.320091390525;6570.704318305592;6578.309277525791;6686.933130668631;6723.428508576826;6701.899471508029;6708.966455167693;6721.899171512334;6726.394624483624;6726.461883049442;6580.605163690194;6583.242927063257;6671.66641667905;6672.866542669217;6677.599344039373;6663.13379260693;6668.566543174678;6762.357576030269;6783.423409010092;6801.2858649841055;6371.649559274099;6458;6494.285628580947;6548.116004339544;6589.308122647053 16:20:40 INFO - PID 18858 | |1;ext.html;830.7964548685572;836.8912041434612;863.9222469977701;889.4618755652832;891.6567121658163;41023.58976410235;41798.791006044965;41864.37203441951;41901.371479427755;42152.57847421525;37787.622123778754;38004.80997595015;38112;38138;38149.42775858357;6968.477364197693;7071.328223818729;7079.469039822034;7081.221065682771;7082.64586770662;6978.546394484368;7089.71641134354;7089.89365159523;7092.50352475327;7101.934709793521;7648.158702542716;7838.843223135534;7845.725399611015;7872.763817085496;7877.527348359117;7756.224377562255;7816.257455541743;7821.139674635786;7825.608719563999;7835.177306382833;7539.547627142389;7716.575588342639;7743.225677432268;7750.302472777477;7754.806129846743;38165;38492.42261366082;38504;38521.614783852085;38664.42003369952;3532.311199316134;3586.264815712773;3592;3605.801680907549;3607.0261029522144;32149.357012859844;32274.6772532274;32277.031689049367;32323.51514727281;32335.51496727551;5137.948620513785;5200.557952574036;5252.553532949704;5259.710715910624;5269.2886460327745;183694;186192;189977;190965;194116;46458.76770616193;46823;46965.76517117392;46968.295475567895;47000.5299947003;47545.762271188425;47878.28182577264;47905.520944790806;47975.76012119987;47987.280190797166;15240.923795380952;15260.618484537754;15333.080015199124;15357.078575285519;15435.922820386051;12585.37073146339;12600.936995314965;12614;12749.298788566717;12754.744905102032;13092.214467132002;13137.474501020044;13183.934080329536;13190.934045329905;13227.404766785517;5656.8868622627315;5667.1499275108235;5694.829155125353;5704;5711.200431939532;1001.7896241789306;1011.1758916483043;1013.6148263659762;1017.3285631483186;1031.0565832263544;22903.312900613008;28360.290992725346;28449.85775071153;28486.145415637566;28766.42467150645;1100.8238681811033;1102.862142232221;1103.2112039891515;1108.9889101109047;1113.1429596448345;12509.687257818627;12601;12650.936745316214;12654.810177847156;16280.837191627932;220733;225055;225449;225775;226134;54811;54851.451485485435;54981.450185498434;55243.72378138084;55271.447285527436;1438.5468577398296;1455.6433673749787;1456.6649670575614;1457.2786470697072;1469.2139705257637;26565.60151597766;26577.60133597959;26652.60021099646;26762.732372676022;26813.597796032685;36369.45445818262;36522.8173859129;36554.45168322531;36584.45123323205;36641.083972899825;193884;194765;194939;195593;196058;32723.18192045123;37337.62662373341;37473.62526374702;37490.81254593709;37527.43708844424;45339.77330113328;45436.77281613571;45517.54482455266;46271;46445.3033204509;32505.837470812494;32777;32936.67063329336;33100.66899331072;33868.153296167795;32461.350772983937;32786.18034549156;32955.50566741453;32986.505202421504;33086.338273234884;37128.257434851694;37365.43951840779;37436.06409839776;37514.437283440224;37522;32519;32651.34697306088;32672.34655306833;32853.17867053343;32866.1783455406;23954.40113997164;24335.14826981118;24398.87800560986;24432.267031989068;24877.253682389557 16:20:40 INFO - PID 18858 | |2;jquery.html;557.5762420560378;564.9830505084847;568.7497501099514;569.5301376364499;578.1385735254471;21116;21825.236116735916;22042.669359959615;22060.558788824233;22064.88967555164;25459.490810183786;25969.74030259697;26030.088946886863;26053.348666283353;26058.47883042338;6574.638394888279;6684.799456016327;6687.331266873322;6690.330966903295;6691.230508491537;6018.759249630031;6592.6703664816805;6602.075709400688;6603.537752357337;6615.437687796543;6522.706478208492;6526.477881769447;6526.869462610745;6551.344865513434;6560.442362399202;6523.869522609545;6542.509311801634;6546.967265163691;6553.901691474632;6582.901256481156;6064.696765161724;6072.089186621998;6082.422169893853;6082.817515474499;6108.633481991094;26826.86586567074;27068.458630827274;28567.142985710463;28727.569086463718;28837.567436488258;2059.6910463430454;2077.1587507059667;2077.781832907553;2079.958400831975;2080.729505164334;19011.61976760457;19510.51223719404;19511.414657560297;19517;19523.414297571097;2384.3856467609435;2389.689340385739;2440.8291419600637;2448.5837407640743;2463.187148241076;133633;135181.6481835179;136118;137363;140269.2986535071;13945.721085578434;14560.708785824436;14618.707625847423;14719.705605887822;14780.33488492999;22516;23291.534169316517;23350.299491015296;23397.7660223399;23448.06207751705;10204.38773673582;10235.641752538626;10313.690589282334;10421.322614030063;10492;9643.373180743229;9672.56473458682;9676.806463870824;9696.903030969741;9714.61141554344;8901.821963560693;9011.504367259864;9077.546122693971;9261.49061801594;9431.952840235892;2663.067926225844;2703.9864800676264;2752.8348299102117;2760.654918135233;2874.238326843388;920.9769755756159;934.1872570863393;939.4738946190018;942.8727121838601;955.0354142316311;19425.4172374829;19551.511212219524;19689.704654430196;19887.60224795525;20033.79966200319;1009.6769033909079;1009.6869970309227;1011.9645812396678;1015.9644412445529;1019.7756493571551;10663;11029.338239705643;11056.336619802838;11085;11193.720156995978;449029;451851;452656;457880;459441;112219;114776.42611786888;118241;118249;118919.40540297244;1153.9596114136132;1163.0869767232682;1186.2645160000855;1191.4995701805262;1202.6512311429587;17094.572635684206;17429.302827886742;17618.383356582457;17645;17733.20200590951;49750.26872984613;49770.502294976584;53662.195067074805;55087;59501.404985949586;67780.6610966942;67868.6606566964;67876;68100.6594967022;68402;14400.927995359956;14577.489787857374;14742.557723268319;14746.336414861142;14746.852531474548;22639.094436223026;22710.205142819923;22906.54186916219;23036;23062.538749225256;17449.56376090608;17494;17560.034198118716;17612.91193544024;17753.644927101126;16225.51323460298;16278.51164465068;16869;17002.48992530226;17016.57458563546;18610.883346999224;18754.906225468785;18892.716609250598;18898;18965.14656840418;14334.426622935383;14405.9279703605;14409.13545187292;14454.132752034911;14454.566363009126;18077.276908923497;18138.095499972314;18197.272109115485;18212.817871821113;18260.908695456437 16:20:40 INFO - PID 18858 | |3;mootools.html;1318.6307833806534;1372.574501904409;1376.4838185680367;1379.0346757269913;1379.903406761527;15041.623959401008;16196.514104576867;16237.83762162378;16250.431234906751;16278.674426511463;15736.527904162893;16050.1172435516;16073.276702548384;16082.195890205501;16082.678346433093;2753.325435268362;2803.593478945552;2806.2423145750636;2806.3825958289144;2806.705295943927;2673.1579552441017;2731.166994066809;2742.5886117082496;2744.6020327052665;2745.862706864659;2504.8497090174556;2556.8465892046443;2558.692956845172;2561.9487610247784;2565.0381107084845;2605.2054123492326;2628.1195799407224;2643;2650.08572042645;2650.9204723858315;2550.4389034412397;2568.9486210275877;2572.65269188659;2577.2139497453268;2584.276402607254;14577.344019519041;14998.325075371635;15002.474913378088;15004.399824007027;15028.398864045424;7886.6845326186885;8163.79590510242;8175.3459723222;8185.754427367188;8186.426950113494;5267.631265811395;5377.784888604451;5403.648762830443;5501.5323697485765;5508.669479831223;1938.2925232290254;1944.6791279438876;1946.8734532255357;1954.677478216107;1989.4230673104782;26298.6055209172;26583;26602;26602.866985664947;26676.33309167267;6804.251532331439;6865;6930.722771089201;7035.964820175866;7060;17150.31398744061;17150.571235719206;17178.31286748541;17184.054876981885;17202.741958870625;7973.202679732097;8090.393220508486;8099.392545559107;8112.837743245102;8116.797080073045;6176.413240742122;6464.25661048976;6609.735610575523;7485.438592105614;7508.549487030796;5224.52979231871;5325.334333208349;5449;5454.3454785426;5521.668699878021;1571.3321838218712;1609.8148712897932;1617.0782653887172;1622.7809245751673;1636.1492024147376;912.885889263842;913.8172365526789;917.2661870503704;921.953902304882;927.0914503786328;7835.608219589112;7861.056673199253;7872.212778722082;7974.800629984181;8015.278624923785;715.5921124958724;730.9342159205697;733.3839574757229;735.9668814903342;750.8348163404042;4829.396325459317;5132.409772876167;5506.394296627368;5535.916961245584;5568.053430916673;21758.347249582537;22456.10175592958;22506.32481025572;22530.774692253195;22773.31680049568;21382.251621193496;21984.230551930606;22613.434664133198;22629.094836206696;22651.660225096635;11075;11379.146564007568;11395.772084558263;11400.828987565194;11409.48657310423;8758.38691291629;9015.233705134875;9146.817063658822;9267.53662316895;9274.25805935537;18314.725279121092;18643.161057752706;18744.906275469082;18824.24703011864;19689.704654430483;19393;19804.801951980873;19807.603847922674;19898.50253743668;19946.900265498578;11820.586279480176;11851.348175850411;12013.75972480563;12029.879701202875;12104.152709310263;13815;14082.155070695791;14211.786823197453;14255.073420227653;14289.42842286297;11166.553337866395;11186;11195.664130076111;11314.434278286217;11466.541338346373;10581.571487849042;10779.299345542515;10830.51262693165;10913.508892099715;11136.610218642309;9725.367851089657;9961.252906031932;9992.65025724096;9993.300468967103;10029.29794914349;7379.631018449163;7385;7417.072865891763;7455.962720186364;7541.7360392386;7475.775726728207;7513.774586762406;7519.661615227437;7525.510841795266;7700.499467534592 16:20:40 INFO - PID 18858 | |4;prototype.html;516.5944733384295;517.1596156246098;529.1665626638046;540.42174872886;541.6777017674485;6417.133686952272;6443.420092191702;6452.032195170721;6460.870782584346;6463.709133089011;6307.810765677037;6316.778912738055;6317.178766760327;6319.210098737658;6346.555741098125;5214.035403450355;5258.7370631468275;5291.761870715808;5303.151495760679;5315.813946511854;5011.398632164127;5114.1050316194705;5272.8154514592;5282.366116066078;5332.600054995872;5031.924521132168;5056;5060.2156665716875;5076.060928728197;5078.17185813107;5013.899722005558;5014.824481143142;5038.269450929613;5040.697558146523;5114.769835357418;4332.350147477903;4492.168948744493;4519.209138400766;4556.54434556545;4561.6806823522365;6156.599821011665;6187.648693496488;6209.59637623553;6224;6253.280872699653;5698.601097923147;5769.221155144044;5774.566907481913;5789.88420231593;5808.099744539603;6522.902156467657;6559.672016399161;6626.138601981712;6656.234533028715;6668.19981602206;4862.465128835825;4916.827911023132;4922.138625740481;4924.8768780780765;4935.284383764334;6600;6670.966645166792;6714.83212919676;6947.965260173717;7044.154701435809;4936.555709986119;4960.255961605716;5026.84919452417;5030.748462576857;5046.78593374529;5031.572316353077;5082.288479612857;5106.234064890296;5113.360829896263;5154.819581314636;5639.238702775155;5695.772169113272;5706.514946229617;5723.340231332867;5757.071381087383;6684.799456016327;6692.19693636757;6698.296678848748;6714.52998290115;6746.460283177334;1752.7370894365692;1771.2383675019864;1791.8387345139;1793.9282428702707;1806.837384635389;1186.1578279421708;1209.9637010889687;1235.3144005077088;1240.4108048676806;1249.5938819883575;5914.497267732291;5921.437463440966;5942.881142377215;5957.821265362046;5974.970125149346;6804.591724496546;6840.281770414035;6871.415929646036;6881.13985751781;6934.271901450376;5891.1457838614015;5891.49922256613;5916.792912248051;5943.514121469632;5956.821295361146;3593.658602432765;3609.620989796086;3641.435577485494;3650.908727281839;3662.7802331860175;6650.733970641217;6651.268360480343;6668.699908504129;6700.698468568926;6718.395344419026;6593.310415582759;6945.47908906834;6989.7846990385;7003.92996070043;7007.264237255116;6593.241777195588;6666.96639541216;6682.9331706682315;6687;6687.465002799764;6794.72821087151;6851.520393572402;6864.901615741457;6867.21027081872;6920.446364290945;6709.698063587248;6738.393544581011;6765.052892594942;6773.18721753373;6778.186617605918;6619.602823830585;6659.800205993828;6661.168178750882;6666.766663166766;6671.46628269747;5047;5064.848054558369;5068.543831055223;5070.146688516827;5094.388673359221;6584.374484423972;6763.357481039294;6788.558743681645;6967.268436814062;6988.37104660583;6471.838204044749;6607.57050791697;6609.603423794588;6648.667566621552;6685.799426017227;6603.603783772797;6614.437772789174;6621;6627.9337206627315;6632.502562307943;6593.340665933369;6598.449364399458;6613.272540020497;6627.734890604322;6635.473841016496;6653.401193892573;6663.300353462817;6665.433438157811;6671.966640166768;6674.799756007327;6353.301136875032;6372.808815735535;6405.455536279468;6410.743570257137;6478.319776423407 16:20:40 INFO - PID 18858 | |5;yui.html;900.0954041188604;902.2826852652145;918.6922381002366;924.7549399409157;927.7773334399747;17093.658126837458;17738.64522709545;17783.55541111473;17795.288188472477;17800;16466.588335291624;16808.831911680878;19910.50223744407;21652.242171524;21710.023048962794;971.9416834989905;984.3700031979503;985.014985014985;987.2792861211284;988.574912787501;1000.4347543637822;1002.5939494504737;1003.9799204015916;1005.6882366466418;1007.3804610164733;956.6699488676395;1001.9699409017741;1011.5448048378222;1070.261519551507;1076.633944458884;995.3082607587729;1003.5634498992926;1003.6637726361678;1003.9247056470793;1003.9899601004042;742.9405647548238;746.2686567164155;746.5073051785795;747.5888261456232;747.6560782040214;19059.61880762377;19507.317243896396;19740.50648733779;19758.110885010065;20262.797372026238;20805.687914681293;20887.68668469974;21061;21423.14307427701;21483.140674373008;16497.505074847773;16589.336426542923;16673.24970376336;16709.91645041767;16777.748333775;3709.3879509880835;3715.368387374153;3726.8136593170234;3727.384981478053;3727.5154229949935;123995;126295.36852315771;126330;127264;127798;15905.443309484344;16354.182290885408;16484.258208380652;16536.503904882873;16548.917255413646;29081.127566173047;29856.40287194244;29866.253343666584;29883.850580746956;29888.25279368033;8541.615627296787;8720.389572729848;8731.039585645574;8736.082711315348;8758.386912916161;4188.4555007848785;4269.829206831754;4274.401583778274;4291.249031419551;4298.935515967263;5175.301334319819;5185.818496352609;5235.65968212065;5243.895122097537;5251.343582052244;1060.771934034171;1096.3586302013252;1098.1983152298708;1102.7959827431791;1103.0458653264868;665.7270519087151;679.7043286170532;682.743971010871;696.8432102776835;700.1807884774724;12147.271163730204;12168.391580420943;12432.191907525981;12899.677508062186;13099.803502947463;650.3626446082865;654.443722835586;656.3895577113235;663.3101574362705;667.1127400557248;5814.592978491465;5840.8539786504825;5929;5936.376680448577;6018.488428483541;109691;111842.44078779717;112353.43823280832;115114;116598.41700791442;101655;102055;103137.48431257946;104939.475302623;108057;84450.5777471121;84720;84896.57551712079;86655;88588.5570572143;10911.290766100177;10937.523344704736;11083.667489975314;11098;11269.774604508028;40975.385369218886;41862;42117.57882421136;42581.787091065584;42867.57132428761;51642.22536662028;51708.224376633625;51757.48242517677;54412.45587544074;54589.45410545844;14468.927655361656;14550.199739014455;14949.77575336349;15096.320665570296;15097.622559435662;17353.30586776565;17397.565060873578;17547.912260439127;17665.29338826484;17742.645147097242;15270;15430.305636246172;15550.300236489611;15555.84444155544;15730.449434269745;16205.351785928431;16291.918540407696;16306.429274975319;16453.91773041127;16488.752668709738;15754.448594299145;15999.520014399586;16019.599510012344;16071.598210044842;16073.517794466185;11526.538938442369;11702;11888.583899563473;11969.940150299193;12086.75826483483;12033.037357011593;12127.211731237794;12134.211276267015;12270.502906671556;14816.185109819064 16:20:40 INFO - PID 18858 | __end_tp_report 16:20:40 INFO - PID 18858 | __start_cc_report 16:20:40 INFO - PID 18858 | _x_x_mozilla_cycle_collect,361 16:20:40 INFO - PID 18858 | __end_cc_report 16:20:40 INFO - PID 18858 | __startTimestamp1504394440887__endTimestamp 16:20:40 INFO - PID 18858 | 16:20:40 INFO - PID 18858 | ------- Summary: start ------- 16:20:40 INFO - PID 18858 | Number of tests: 6 16:20:40 INFO - PID 18858 | 16:20:40 INFO - PID 18858 | [#0] dojo.html Cycles:165 Average:6306.84 Median:6580.61 stddev:859.10 (13.1%) stddev-sans-first:828.82 16:20:40 INFO - PID 18858 | Values: 3304.4 3337.7 3340.5 3366.1 3369.2 6205.1 6348.9 6463.9 6479.4 6535.3 6317.9 6336.3 6384.7 6394.0 6402.3 5826.7 5837.8 5858.0 5864.2 5931.0 5945.3 5967.5 5994.4 6007.4 6038.6 827.7 5851.3 5875.5 5943.4 5990.7 5842.8 5896.5 5969.5 5999.9 5999.9 5854.6 5901.3 5957.2 5974.3 5975.6 6322.8 6373.3 6524.4 6545.6 6551.5 6843.6 6855.2 6870.8 6930.9 6985.7 6829.8 6893.7 6941.2 7003.6 7027.2 6429.5 6488.7 6515.2 6516.7 6519.8 7010.9 7092.0 7106.2 7119.2 7124.6 6677.5 6691.3 6770.9 6785.4 6811.5 6609.2 6714.1 6825.1 6862.0 6928.7 6756.8 6894.0 6922.7 6925.9 6930.0 6823.5 6900.7 6914.1 6921.4 6925.7 5278.6 5319.7 5352.5 5436.3 5442.0 4684.3 4691.5 4698.8 4734.7 4736.0 6036.2 6058.1 6093.2 6099.3 6115.6 6914.8 6954.8 6983.5 6987.0 7002.4 5942.5 5995.9 6046.4 6060.1 6091.0 5747.4 5833.6 5868.2 5882.8 5920.7 6562.6 6643.3 6647.5 6650.8 6653.6 6888.7 6949.1 7033.5 7038.1 7051.1 6700.2 6702.8 6732.1 6786.0 6812.1 6675.4 6779.2 6793.1 6796.4 6893.7 6815.7 6828.6 6833.5 6836.7 6842.1 6475.3 6570.7 6578.3 6686.9 6723.4 6701.9 6709.0 6721.9 6726.4 6726.5 6580.6 6583.2 6671.7 6672.9 6677.6 6663.1 6668.6 6762.4 6783.4 6801.3 6371.6 6458.0 6494.3 6548.1 6589.3 16:20:40 INFO - PID 18858 | 16:20:40 INFO - PID 18858 | [#1] ext.html Cycles:180 Average:37586.73 Median:19988.24 stddev:52634.71 (263.3%) stddev-sans-first:52710.00 16:20:40 INFO - PID 18858 | Values: 830.8 836.9 863.9 889.5 891.7 41023.6 41798.8 41864.4 41901.4 42152.6 37787.6 38004.8 38112.0 38138.0 38149.4 6968.5 7071.3 7079.5 7081.2 7082.6 6978.5 7089.7 7089.9 7092.5 7101.9 7648.2 7838.8 7845.7 7872.8 7877.5 7756.2 7816.3 7821.1 7825.6 7835.2 7539.5 7716.6 7743.2 7750.3 7754.8 38165.0 38492.4 38504.0 38521.6 38664.4 3532.3 3586.3 3592.0 3605.8 3607.0 32149.4 32274.7 32277.0 32323.5 32335.5 5137.9 5200.6 5252.6 5259.7 5269.3 183694.0 186192.0 189977.0 190965.0 194116.0 46458.8 46823.0 46965.8 46968.3 47000.5 47545.8 47878.3 47905.5 47975.8 47987.3 15240.9 15260.6 15333.1 15357.1 15435.9 12585.4 12600.9 12614.0 12749.3 12754.7 13092.2 13137.5 13183.9 13190.9 13227.4 5656.9 5667.1 5694.8 5704.0 5711.2 1001.8 1011.2 1013.6 1017.3 1031.1 22903.3 28360.3 28449.9 28486.1 28766.4 1100.8 1102.9 1103.2 1109.0 1113.1 12509.7 12601.0 12650.9 12654.8 16280.8 220733.0 225055.0 225449.0 225775.0 226134.0 54811.0 54851.5 54981.5 55243.7 55271.4 1438.5 1455.6 1456.7 1457.3 1469.2 26565.6 26577.6 26652.6 26762.7 26813.6 36369.5 36522.8 36554.5 36584.5 36641.1 193884.0 194765.0 194939.0 195593.0 196058.0 32723.2 37337.6 37473.6 37490.8 37527.4 45339.8 45436.8 45517.5 46271.0 46445.3 32505.8 32777.0 32936.7 33100.7 33868.2 32461.4 32786.2 32955.5 32986.5 33086.3 37128.3 37365.4 37436.1 37514.4 37522.0 32519.0 32651.3 32672.3 32853.2 32866.2 23954.4 24335.1 24398.9 24432.3 24877.3 16:20:40 INFO - PID 18858 | 16:20:40 INFO - PID 18858 | [#2] jquery.html Cycles:180 Average:33484.38 Median:22577.55 stddev:76998.98 (341.0%) stddev-sans-first:77175.29 16:20:40 INFO - PID 18858 | Values: 557.6 565.0 568.7 569.5 578.1 21116.0 21825.2 22042.7 22060.6 22064.9 25459.5 25969.7 26030.1 26053.3 26058.5 6574.6 6684.8 6687.3 6690.3 6691.2 6018.8 6592.7 6602.1 6603.5 6615.4 6522.7 6526.5 6526.9 6551.3 6560.4 6523.9 6542.5 6547.0 6553.9 6582.9 6064.7 6072.1 6082.4 6082.8 6108.6 26826.9 27068.5 28567.1 28727.6 28837.6 2059.7 2077.2 2077.8 2080.0 2080.7 19011.6 19510.5 19511.4 19517.0 19523.4 2384.4 2389.7 2440.8 2448.6 2463.2 133633.0 135181.6 136118.0 137363.0 140269.3 13945.7 14560.7 14618.7 14719.7 14780.3 22516.0 23291.5 23350.3 23397.8 23448.1 10204.4 10235.6 10313.7 10421.3 10492.0 9643.4 9672.6 9676.8 9696.9 9714.6 8901.8 9011.5 9077.5 9261.5 9432.0 2663.1 2704.0 2752.8 2760.7 2874.2 921.0 934.2 939.5 942.9 955.0 19425.4 19551.5 19689.7 19887.6 20033.8 1009.7 1009.7 1012.0 1016.0 1019.8 10663.0 11029.3 11056.3 11085.0 11193.7 449029.0 451851.0 452656.0 457880.0 459441.0 112219.0 114776.4 118241.0 118249.0 118919.4 1154.0 1163.1 1186.3 1191.5 1202.7 17094.6 17429.3 17618.4 17645.0 17733.2 49750.3 49770.5 53662.2 55087.0 59501.4 67780.7 67868.7 67876.0 68100.7 68402.0 14400.9 14577.5 14742.6 14746.3 14746.9 22639.1 22710.2 22906.5 23036.0 23062.5 17449.6 17494.0 17560.0 17612.9 17753.6 16225.5 16278.5 16869.0 17002.5 17016.6 18610.9 18754.9 18892.7 18898.0 18965.1 14334.4 14405.9 14409.1 14454.1 14454.6 18077.3 18138.1 18197.3 18212.8 18260.9 16:20:40 INFO - PID 18858 | 16:20:40 INFO - PID 18858 | [#3] mootools.html Cycles:180 Average:9482.28 Median:2594.74 stddev:6835.11 (263.4%) stddev-sans-first:6826.76 16:20:40 INFO - PID 18858 | Values: 1318.6 1372.6 1376.5 1379.0 1379.9 15041.6 16196.5 16237.8 16250.4 16278.7 15736.5 16050.1 16073.3 16082.2 16082.7 2753.3 2803.6 2806.2 2806.4 2806.7 2673.2 2731.2 2742.6 2744.6 2745.9 2504.8 2556.8 2558.7 2561.9 2565.0 2605.2 2628.1 2643.0 2650.1 2650.9 2550.4 2568.9 2572.7 2577.2 2584.3 14577.3 14998.3 15002.5 15004.4 15028.4 7886.7 8163.8 8175.3 8185.8 8186.4 5267.6 5377.8 5403.6 5501.5 5508.7 1938.3 1944.7 1946.9 1954.7 1989.4 26298.6 26583.0 26602.0 26602.9 26676.3 6804.3 6865.0 6930.7 7036.0 7060.0 17150.3 17150.6 17178.3 17184.1 17202.7 7973.2 8090.4 8099.4 8112.8 8116.8 6176.4 6464.3 6609.7 7485.4 7508.5 5224.5 5325.3 5449.0 5454.3 5521.7 1571.3 1609.8 1617.1 1622.8 1636.1 912.9 913.8 917.3 922.0 927.1 7835.6 7861.1 7872.2 7974.8 8015.3 715.6 730.9 733.4 736.0 750.8 4829.4 5132.4 5506.4 5535.9 5568.1 21758.3 22456.1 22506.3 22530.8 22773.3 21382.3 21984.2 22613.4 22629.1 22651.7 11075.0 11379.1 11395.8 11400.8 11409.5 8758.4 9015.2 9146.8 9267.5 9274.3 18314.7 18643.2 18744.9 18824.2 19689.7 19393.0 19804.8 19807.6 19898.5 19946.9 11820.6 11851.3 12013.8 12029.9 12104.2 13815.0 14082.2 14211.8 14255.1 14289.4 11166.6 11186.0 11195.7 11314.4 11466.5 10581.6 10779.3 10830.5 10913.5 11136.6 9725.4 9961.3 9992.7 9993.3 10029.3 7379.6 7385.0 7417.1 7456.0 7541.7 7475.8 7513.8 7519.7 7525.5 7700.5 16:20:40 INFO - PID 18858 | 16:20:40 INFO - PID 18858 | [#4] prototype.html Cycles:180 Average:5607.06 Median:6312.29 stddev:1571.42 (24.9%) stddev-sans-first:1528.68 16:20:40 INFO - PID 18858 | Values: 516.6 517.2 529.2 540.4 541.7 6417.1 6443.4 6452.0 6460.9 6463.7 6307.8 6316.8 6317.2 6319.2 6346.6 5214.0 5258.7 5291.8 5303.2 5315.8 5011.4 5114.1 5272.8 5282.4 5332.6 5031.9 5056.0 5060.2 5076.1 5078.2 5013.9 5014.8 5038.3 5040.7 5114.8 4332.4 4492.2 4519.2 4556.5 4561.7 6156.6 6187.6 6209.6 6224.0 6253.3 5698.6 5769.2 5774.6 5789.9 5808.1 6522.9 6559.7 6626.1 6656.2 6668.2 4862.5 4916.8 4922.1 4924.9 4935.3 6600.0 6671.0 6714.8 6948.0 7044.2 4936.6 4960.3 5026.8 5030.7 5046.8 5031.6 5082.3 5106.2 5113.4 5154.8 5639.2 5695.8 5706.5 5723.3 5757.1 6684.8 6692.2 6698.3 6714.5 6746.5 1752.7 1771.2 1791.8 1793.9 1806.8 1186.2 1210.0 1235.3 1240.4 1249.6 5914.5 5921.4 5942.9 5957.8 5975.0 6804.6 6840.3 6871.4 6881.1 6934.3 5891.1 5891.5 5916.8 5943.5 5956.8 3593.7 3609.6 3641.4 3650.9 3662.8 6650.7 6651.3 6668.7 6700.7 6718.4 6593.3 6945.5 6989.8 7003.9 7007.3 6593.2 6667.0 6682.9 6687.0 6687.5 6794.7 6851.5 6864.9 6867.2 6920.4 6709.7 6738.4 6765.1 6773.2 6778.2 6619.6 6659.8 6661.2 6666.8 6671.5 5047.0 5064.8 5068.5 5070.1 5094.4 6584.4 6763.4 6788.6 6967.3 6988.4 6471.8 6607.6 6609.6 6648.7 6685.8 6603.6 6614.4 6621.0 6627.9 6632.5 6593.3 6598.4 6613.3 6627.7 6635.5 6653.4 6663.3 6665.4 6672.0 6674.8 6353.3 6372.8 6405.5 6410.7 6478.3 16:20:40 INFO - PID 18858 | 16:20:40 INFO - PID 18858 | [#5] yui.html Cycles:180 Average:23025.67 Median:17740.65 stddev:32335.83 (182.3%) stddev-sans-first:32383.86 16:20:40 INFO - PID 18858 | Values: 900.1 902.3 918.7 924.8 927.8 17093.7 17738.6 17783.6 17795.3 17800.0 16466.6 16808.8 19910.5 21652.2 21710.0 971.9 984.4 985.0 987.3 988.6 1000.4 1002.6 1004.0 1005.7 1007.4 956.7 1002.0 1011.5 1070.3 1076.6 995.3 1003.6 1003.7 1003.9 1004.0 742.9 746.3 746.5 747.6 747.7 19059.6 19507.3 19740.5 19758.1 20262.8 20805.7 20887.7 21061.0 21423.1 21483.1 16497.5 16589.3 16673.2 16709.9 16777.7 3709.4 3715.4 3726.8 3727.4 3727.5 123995.0 126295.4 126330.0 127264.0 127798.0 15905.4 16354.2 16484.3 16536.5 16548.9 29081.1 29856.4 29866.3 29883.9 29888.3 8541.6 8720.4 8731.0 8736.1 8758.4 4188.5 4269.8 4274.4 4291.2 4298.9 5175.3 5185.8 5235.7 5243.9 5251.3 1060.8 1096.4 1098.2 1102.8 1103.0 665.7 679.7 682.7 696.8 700.2 12147.3 12168.4 12432.2 12899.7 13099.8 650.4 654.4 656.4 663.3 667.1 5814.6 5840.9 5929.0 5936.4 6018.5 109691.0 111842.4 112353.4 115114.0 116598.4 101655.0 102055.0 103137.5 104939.5 108057.0 84450.6 84720.0 84896.6 86655.0 88588.6 10911.3 10937.5 11083.7 11098.0 11269.8 40975.4 41862.0 42117.6 42581.8 42867.6 51642.2 51708.2 51757.5 54412.5 54589.5 14468.9 14550.2 14949.8 15096.3 15097.6 17353.3 17397.6 17547.9 17665.3 17742.6 15270.0 15430.3 15550.3 15555.8 15730.4 16205.4 16291.9 16306.4 16453.9 16488.8 15754.4 15999.5 16019.6 16071.6 16073.5 11526.5 11702.0 11888.6 11969.9 12086.8 12033.0 12127.2 12134.2 12270.5 14816.2 16:20:40 INFO - PID 18858 | -------- Summary: end -------- 16:20:40 INFO - PID 18858 | 16:20:41 INFO - TEST-INFO | 18858: exit 0 16:20:41 INFO - TEST-OK | dromaeo_css | took 1111269ms 16:20:41 INFO - TEST-START | kraken 16:20:41 INFO - Initialising browser for kraken test... 16:20:41 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60951/getInfo.html -profile /tmp/tmpG5utn9/profile 16:20:41 INFO - TEST-INFO | started process 19237 (/builds/slave/test/build/application/firefox/firefox http://localhost:60951/getInfo.html) 16:20:47 INFO - TEST-INFO | 19237: exit 0 16:20:47 INFO - Browser initialized. 16:20:47 INFO - Running cycle 1/1 for kraken test... 16:20:47 INFO - Using env: {'DISPLAY': ':0', 16:20:47 INFO - 'HOME': '/home/cltbld', 16:20:47 INFO - 'JSGC_DISABLE_POISONING': '1', 16:20:47 INFO - 'LANG': 'en_US.UTF-8', 16:20:47 INFO - 'LANGUAGE': 'en_US:en', 16:20:47 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:20:47 INFO - 'LOGNAME': 'cltbld', 16:20:47 INFO - 'MAIL': '/var/mail/cltbld', 16:20:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:20:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:20:47 INFO - 'MOZ_CRASHREPORTER': '1', 16:20:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:47 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:20:47 INFO - 'MOZ_NO_REMOTE': '1', 16:20:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:20:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:20:47 INFO - 'NO_EM_RESTART': '1', 16:20:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:20:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:47 INFO - 'PWD': '/builds/slave/test', 16:20:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:20:47 INFO - 'RUST_BACKTRACE': 'full', 16:20:47 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:20:47 INFO - 'SHELL': '/bin/bash', 16:20:47 INFO - 'SHLVL': '1', 16:20:47 INFO - 'TERM': 'linux', 16:20:47 INFO - 'TMOUT': '86400', 16:20:47 INFO - 'USER': 'cltbld', 16:20:47 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425', 16:20:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:47 INFO - '_': '/tools/buildbot/bin/python'} 16:20:47 INFO - TEST-INFO | started process 19426 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpG5utn9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:20:48 INFO - PID 19426 | 16:20:48 INFO - PID 19426 | (/builds/slave/test/build/application/firefox/firefox:19479): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:20:48 INFO - PID 19426 | 16:20:59 INFO - PID 19426 | RSS: Main: 168435712 16:20:59 INFO - PID 19426 | 16:21:01 INFO - PID 19426 | Cycle 1(1): loaded http://localhost:60951/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60951/tests/kraken/driver.html?testName=audio-beat-detection) 16:21:02 INFO - PID 19426 | RSS: Main: 174292992 16:21:02 INFO - PID 19426 | 16:21:05 INFO - PID 19426 | Cycle 1(1): loaded http://localhost:60951/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60951/tests/kraken/driver.html?testName=audio-dft) 16:21:06 INFO - PID 19426 | RSS: Main: 178282496 16:21:06 INFO - PID 19426 | 16:21:09 INFO - PID 19426 | Cycle 1(1): loaded http://localhost:60951/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60951/tests/kraken/driver.html?testName=audio-fft) 16:21:09 INFO - PID 19426 | RSS: Main: 170782720 16:21:09 INFO - PID 19426 | 16:21:11 INFO - PID 19426 | Cycle 1(1): loaded http://localhost:60951/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60951/tests/kraken/driver.html?testName=audio-oscillator) 16:21:12 INFO - PID 19426 | RSS: Main: 171036672 16:21:12 INFO - PID 19426 | 16:21:14 INFO - PID 19426 | Cycle 1(1): loaded http://localhost:60951/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60951/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:21:15 INFO - PID 19426 | RSS: Main: 171302912 16:21:15 INFO - PID 19426 | 16:21:18 INFO - PID 19426 | Cycle 1(1): loaded http://localhost:60951/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60951/tests/kraken/driver.html?testName=imaging-darkroom) 16:21:18 INFO - PID 19426 | RSS: Main: 172617728 16:21:18 INFO - PID 19426 | 16:21:22 INFO - PID 19426 | Cycle 1(1): loaded http://localhost:60951/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60951/tests/kraken/driver.html?testName=imaging-desaturate) 16:21:23 INFO - PID 19426 | RSS: Main: 172519424 16:21:23 INFO - PID 19426 | 16:21:25 INFO - PID 19426 | Cycle 1(1): loaded http://localhost:60951/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60951/tests/kraken/driver.html?testName=json-parse-financial) 16:21:26 INFO - PID 19426 | RSS: Main: 172859392 16:21:26 INFO - PID 19426 | 16:21:28 INFO - PID 19426 | Cycle 1(1): loaded http://localhost:60951/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60951/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:21:28 INFO - PID 19426 | RSS: Main: 172883968 16:21:28 INFO - PID 19426 | 16:21:30 INFO - PID 19426 | Cycle 1(1): loaded http://localhost:60951/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60951/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:21:30 INFO - PID 19426 | RSS: Main: 173166592 16:21:30 INFO - PID 19426 | 16:21:33 INFO - PID 19426 | Cycle 1(1): loaded http://localhost:60951/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60951/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:21:33 INFO - PID 19426 | RSS: Main: 170156032 16:21:33 INFO - PID 19426 | 16:21:36 INFO - PID 19426 | Cycle 1(1): loaded http://localhost:60951/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60951/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:21:37 INFO - PID 19426 | RSS: Main: 170250240 16:21:37 INFO - PID 19426 | 16:21:39 INFO - PID 19426 | Cycle 1(1): loaded http://localhost:60951/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60951/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:21:40 INFO - PID 19426 | RSS: Main: 170455040 16:21:40 INFO - PID 19426 | 16:21:42 INFO - PID 19426 | Cycle 1(1): loaded http://localhost:60951/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60951/tests/kraken/driver.html?testName=ai-astar) 16:21:42 INFO - PID 19426 | RSS: Main: 171749376 16:21:42 INFO - PID 19426 | 16:21:42 INFO - PID 19426 | __start_tp_report 16:21:42 INFO - PID 19426 | _x_x_mozilla_page_load 16:21:42 INFO - PID 19426 | _x_x_mozilla_page_load_details 16:21:42 INFO - PID 19426 | |i|pagename|runs| 16:21:42 INFO - PID 19426 | |0;ai-astar;100;108;99;107;109;109;109;99;109;109 16:21:42 INFO - PID 19426 | |1;audio-beat-detection;149;116;140;177;121;154;145;116;179;118 16:21:42 INFO - PID 19426 | |2;audio-dft;161;163;162;160;162;163;163;172;164;161 16:21:42 INFO - PID 19426 | |3;audio-fft;74;73;74;172;75;74;75;88;96;75 16:21:42 INFO - PID 19426 | |4;audio-oscillator;80;80;125;80;79;79;136;79;78;135 16:21:42 INFO - PID 19426 | |5;imaging-gaussian-blur;115;114;115;114;115;115;114;115;114;115 16:21:42 INFO - PID 19426 | |6;imaging-darkroom;148;148;148;148;147;148;147;148;151;148 16:21:42 INFO - PID 19426 | |7;imaging-desaturate;94;95;94;93;97;94;94;95;93;94 16:21:42 INFO - PID 19426 | |8;json-parse-financial;56;53;52;52;51;54;52;51;51;52 16:21:42 INFO - PID 19426 | |9;json-stringify-tinderbox;38;38;38;37;37;38;38;39;37;38 16:21:42 INFO - PID 19426 | |10;stanford-crypto-aes;75;75;76;82;74;74;74;75;74;74 16:21:42 INFO - PID 19426 | |11;stanford-crypto-ccm;119;118;127;119;128;129;118;119;124;151 16:21:42 INFO - PID 19426 | |12;stanford-crypto-pbkdf2;174;177;168;169;170;176;174;180;183;173 16:21:42 INFO - PID 19426 | |13;stanford-crypto-sha256-iterative;66;63;70;65;64;63;63;65;69;69 16:21:42 INFO - PID 19426 | __end_tp_report 16:21:42 INFO - PID 19426 | __start_cc_report 16:21:42 INFO - PID 19426 | _x_x_mozilla_cycle_collect,948 16:21:42 INFO - PID 19426 | __end_cc_report 16:21:42 INFO - PID 19426 | __startTimestamp1504394502637__endTimestamp 16:21:42 INFO - PID 19426 | 16:21:42 INFO - PID 19426 | ------- Summary: start ------- 16:21:42 INFO - PID 19426 | Number of tests: 14 16:21:42 INFO - PID 19426 | 16:21:42 INFO - PID 19426 | [#0] ai-astar Cycles:10 Average:105.80 Median:109.00 stddev:4.52 (4.1%) stddev-sans-first:4.28 16:21:42 INFO - PID 19426 | Values: 100.0 108.0 99.0 107.0 109.0 109.0 109.0 99.0 109.0 109.0 16:21:42 INFO - PID 19426 | 16:21:42 INFO - PID 19426 | [#1] audio-beat-detection Cycles:10 Average:141.50 Median:147.00 stddev:23.96 (16.3%) stddev-sans-first:25.26 16:21:42 INFO - PID 19426 | Values: 149.0 116.0 140.0 177.0 121.0 154.0 145.0 116.0 179.0 118.0 16:21:42 INFO - PID 19426 | 16:21:42 INFO - PID 19426 | [#2] audio-dft Cycles:10 Average:163.10 Median:163.00 stddev:3.35 (2.1%) stddev-sans-first:3.46 16:21:42 INFO - PID 19426 | Values: 161.0 163.0 162.0 160.0 162.0 163.0 163.0 172.0 164.0 161.0 16:21:42 INFO - PID 19426 | 16:21:42 INFO - PID 19426 | [#3] audio-fft Cycles:10 Average:87.60 Median:75.00 stddev:30.62 (40.8%) stddev-sans-first:32.08 16:21:42 INFO - PID 19426 | Values: 74.0 73.0 74.0 172.0 75.0 74.0 75.0 88.0 96.0 75.0 16:21:42 INFO - PID 19426 | 16:21:42 INFO - PID 19426 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:79.00 stddev:25.63 (32.4%) stddev-sans-first:26.60 16:21:42 INFO - PID 19426 | Values: 80.0 80.0 125.0 80.0 79.0 79.0 136.0 79.0 78.0 135.0 16:21:42 INFO - PID 19426 | 16:21:42 INFO - PID 19426 | [#5] imaging-gaussian-blur Cycles:10 Average:114.60 Median:115.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 16:21:42 INFO - PID 19426 | Values: 115.0 114.0 115.0 114.0 115.0 115.0 114.0 115.0 114.0 115.0 16:21:42 INFO - PID 19426 | 16:21:42 INFO - PID 19426 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 16:21:42 INFO - PID 19426 | Values: 148.0 148.0 148.0 148.0 147.0 148.0 147.0 148.0 151.0 148.0 16:21:42 INFO - PID 19426 | 16:21:42 INFO - PID 19426 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:1.16 (1.2%) stddev-sans-first:1.22 16:21:42 INFO - PID 19426 | Values: 94.0 95.0 94.0 93.0 97.0 94.0 94.0 95.0 93.0 94.0 16:21:42 INFO - PID 19426 | 16:21:42 INFO - PID 19426 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:52.00 stddev:1.58 (3.0%) stddev-sans-first:1.00 16:21:42 INFO - PID 19426 | Values: 56.0 53.0 52.0 52.0 51.0 54.0 52.0 51.0 51.0 52.0 16:21:42 INFO - PID 19426 | 16:21:42 INFO - PID 19426 | [#9] json-stringify-tinderbox Cycles:10 Average:37.80 Median:38.00 stddev:0.63 (1.7%) stddev-sans-first:0.67 16:21:42 INFO - PID 19426 | Values: 38.0 38.0 38.0 37.0 37.0 38.0 38.0 39.0 37.0 38.0 16:21:42 INFO - PID 19426 | 16:21:42 INFO - PID 19426 | [#10] stanford-crypto-aes Cycles:10 Average:75.30 Median:75.00 stddev:2.45 (3.3%) stddev-sans-first:2.60 16:21:42 INFO - PID 19426 | Values: 75.0 75.0 76.0 82.0 74.0 74.0 74.0 75.0 74.0 74.0 16:21:42 INFO - PID 19426 | 16:21:42 INFO - PID 19426 | [#11] stanford-crypto-ccm Cycles:10 Average:125.20 Median:125.50 stddev:10.06 (8.0%) stddev-sans-first:10.42 16:21:42 INFO - PID 19426 | Values: 119.0 118.0 127.0 119.0 128.0 129.0 118.0 119.0 124.0 151.0 16:21:42 INFO - PID 19426 | 16:21:42 INFO - PID 19426 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.40 Median:175.00 stddev:4.79 (2.7%) stddev-sans-first:5.08 16:21:42 INFO - PID 19426 | Values: 174.0 177.0 168.0 169.0 170.0 176.0 174.0 180.0 183.0 173.0 16:21:42 INFO - PID 19426 | 16:21:42 INFO - PID 19426 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:65.50 stddev:2.71 (4.1%) stddev-sans-first:2.87 16:21:42 INFO - PID 19426 | Values: 66.0 63.0 70.0 65.0 64.0 63.0 63.0 65.0 69.0 69.0 16:21:42 INFO - PID 19426 | -------- Summary: end -------- 16:21:42 INFO - PID 19426 | 16:21:43 INFO - PID 19426 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:21:43 INFO - TEST-INFO | 19426: exit 0 16:21:43 INFO - TEST-OK | kraken | took 61792ms 16:21:43 INFO - SUITE-END | took 1173s 16:21:43 INFO - Completed test suite (00:19:33) 16:21:43 INFO - javascript benchmark 16:21:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8542.89567494181, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3304.4217261979147, 3337.6662333766626, 3340.5490258815053, 3366.141633883359, 3369.2419205678725, 6205.100260462242, 6348.873022539547, 6463.935360646392, 6479.384458476443, 6535.281119076903, 6317.936820631792, 6336.271328797178, 6384.680765961695, 6394.040893865922, 6402.295747467775, 5826.737796799133, 5837.766489340422, 5858.033424484955, 5864.23764910561, 5931.021381472052, 5945.286565612111, 5967.522598192156, 5994.400559944014, 6007.399260074001, 6038.637681739087, 827.6555722651248, 5851.297844258674, 5875.471207591295, 5943.375945525722, 5990.670513121777, 5842.766289348421, 5896.469317761422, 5969.462748352669, 5999.880002399927, 5999.910001349983, 5854.560907931879, 5901.321348044987, 5957.1957785698805, 5974.283086029661, 5975.551833612496, 6322.810315690536, 6373.267074286424, 6524.445422139076, 6545.639989800559, 6551.475881929434, 6843.589384636938, 6855.177378714537, 6870.7595234166565, 6930.896036559456, 6985.685644146026, 6829.829254268633, 6893.689783959734, 6941.167059952838, 7003.614801185933, 7027.227005029443, 6429.517786166056, 6488.740450381979, 6515.185601799775, 6516.739330426777, 6519.837004074841, 7010.92989070113, 7091.964540177266, 7106.18278897923, 7119.21688614252, 7124.5725256484775, 6677.465802735769, 6691.330866913269, 6770.864582708318, 6785.389314961677, 6811.523193376515, 6609.206895172514, 6714.093597364294, 6825.078614387094, 6861.965690171517, 6928.653567321614, 6756.797296081126, 6894.034835123087, 6922.688479018456, 6925.896111558331, 6930.029795828588, 6823.488238382141, 6900.723971041102, 6914.101166848278, 6921.377076063179, 6925.653717314215, 5278.577713782888, 5319.6808191508635, 5352.518273355417, 5436.3476382834315, 5442.0204363215, 4684.344191813149, 4691.5308469152815, 4698.812047518061, 4734.692245004064, 4735.976320118446, 6036.15493830864, 6058.1215723719815, 6093.238345206849, 6099.268087829489, 6115.602485838406, 6914.827129321807, 6954.826129346706, 6983.511154219156, 6986.965065174743, 7002.404795592432, 5942.494887934487, 5995.880082398327, 6046.3651316612, 6060.060690592965, 6090.964536028886, 5747.425257474303, 5833.562482813721, 5868.207791948118, 5882.794102206402, 5920.674362910081, 6562.639054852029, 6643.269240383554, 6647.5346725729705, 6650.767223147167, 6653.567518111307, 6888.690008949609, 6949.131358580177, 7033.507654464139, 7038.085048943809, 7051.1186101737285, 6700.195976482853, 6702.83242918931, 6732.091167692299, 6786.0, 6812.0803691503, 6675.4325882300545, 6779.15260592426, 6793.116894803644, 6796.35434633709, 6893.689783959834, 6815.659217039228, 6828.556143850634, 6833.45332373399, 6836.692348844414, 6842.144731908512, 6475.320091390525, 6570.704318305592, 6578.309277525791, 6686.933130668631, 6723.428508576826, 6701.899471508029, 6708.966455167693, 6721.899171512334, 6726.394624483624, 6726.461883049442, 6580.605163690194, 6583.242927063257, 6671.66641667905, 6672.866542669217, 6677.599344039373, 6663.13379260693, 6668.566543174678, 6762.357576030269, 6783.423409010092, 6801.2858649841055, 6371.649559274099, 6458.0, 6494.285628580947, 6548.116004339544, 6589.308122647053], "value": 6246.993927067677, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [830.7964548685572, 836.8912041434612, 863.9222469977701, 889.4618755652832, 891.6567121658163, 41023.58976410235, 41798.791006044965, 41864.37203441951, 41901.371479427755, 42152.57847421525, 37787.622123778754, 38004.80997595015, 38112.0, 38138.0, 38149.42775858357, 6968.477364197693, 7071.328223818729, 7079.469039822034, 7081.221065682771, 7082.64586770662, 6978.546394484368, 7089.71641134354, 7089.89365159523, 7092.50352475327, 7101.934709793521, 7648.158702542716, 7838.843223135534, 7845.725399611015, 7872.763817085496, 7877.527348359117, 7756.224377562255, 7816.257455541743, 7821.139674635786, 7825.608719563999, 7835.177306382833, 7539.547627142389, 7716.575588342639, 7743.225677432268, 7750.302472777477, 7754.806129846743, 38165.0, 38492.42261366082, 38504.0, 38521.614783852085, 38664.42003369952, 3532.311199316134, 3586.264815712773, 3592.0, 3605.801680907549, 3607.0261029522144, 32149.357012859844, 32274.6772532274, 32277.031689049367, 32323.51514727281, 32335.51496727551, 5137.948620513785, 5200.557952574036, 5252.553532949704, 5259.710715910624, 5269.2886460327745, 183694.0, 186192.0, 189977.0, 190965.0, 194116.0, 46458.76770616193, 46823.0, 46965.76517117392, 46968.295475567895, 47000.5299947003, 47545.762271188425, 47878.28182577264, 47905.520944790806, 47975.76012119987, 47987.280190797166, 15240.923795380952, 15260.618484537754, 15333.080015199124, 15357.078575285519, 15435.922820386051, 12585.37073146339, 12600.936995314965, 12614.0, 12749.298788566717, 12754.744905102032, 13092.214467132002, 13137.474501020044, 13183.934080329536, 13190.934045329905, 13227.404766785517, 5656.8868622627315, 5667.1499275108235, 5694.829155125353, 5704.0, 5711.200431939532, 1001.7896241789306, 1011.1758916483043, 1013.6148263659762, 1017.3285631483186, 1031.0565832263544, 22903.312900613008, 28360.290992725346, 28449.85775071153, 28486.145415637566, 28766.42467150645, 1100.8238681811033, 1102.862142232221, 1103.2112039891515, 1108.9889101109047, 1113.1429596448345, 12509.687257818627, 12601.0, 12650.936745316214, 12654.810177847156, 16280.837191627932, 220733.0, 225055.0, 225449.0, 225775.0, 226134.0, 54811.0, 54851.451485485435, 54981.450185498434, 55243.72378138084, 55271.447285527436, 1438.5468577398296, 1455.6433673749787, 1456.6649670575614, 1457.2786470697072, 1469.2139705257637, 26565.60151597766, 26577.60133597959, 26652.60021099646, 26762.732372676022, 26813.597796032685, 36369.45445818262, 36522.8173859129, 36554.45168322531, 36584.45123323205, 36641.083972899825, 193884.0, 194765.0, 194939.0, 195593.0, 196058.0, 32723.18192045123, 37337.62662373341, 37473.62526374702, 37490.81254593709, 37527.43708844424, 45339.77330113328, 45436.77281613571, 45517.54482455266, 46271.0, 46445.3033204509, 32505.837470812494, 32777.0, 32936.67063329336, 33100.66899331072, 33868.153296167795, 32461.350772983937, 32786.18034549156, 32955.50566741453, 32986.505202421504, 33086.338273234884, 37128.257434851694, 37365.43951840779, 37436.06409839776, 37514.437283440224, 37522.0, 32519.0, 32651.34697306088, 32672.34655306833, 32853.17867053343, 32866.1783455406, 23954.40113997164, 24335.14826981118, 24398.87800560986, 24432.267031989068, 24877.253682389557], "value": 17272.715657206474, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.5762420560378, 564.9830505084847, 568.7497501099514, 569.5301376364499, 578.1385735254471, 21116.0, 21825.236116735916, 22042.669359959615, 22060.558788824233, 22064.88967555164, 25459.490810183786, 25969.74030259697, 26030.088946886863, 26053.348666283353, 26058.47883042338, 6574.638394888279, 6684.799456016327, 6687.331266873322, 6690.330966903295, 6691.230508491537, 6018.759249630031, 6592.6703664816805, 6602.075709400688, 6603.537752357337, 6615.437687796543, 6522.706478208492, 6526.477881769447, 6526.869462610745, 6551.344865513434, 6560.442362399202, 6523.869522609545, 6542.509311801634, 6546.967265163691, 6553.901691474632, 6582.901256481156, 6064.696765161724, 6072.089186621998, 6082.422169893853, 6082.817515474499, 6108.633481991094, 26826.86586567074, 27068.458630827274, 28567.142985710463, 28727.569086463718, 28837.567436488258, 2059.6910463430454, 2077.1587507059667, 2077.781832907553, 2079.958400831975, 2080.729505164334, 19011.61976760457, 19510.51223719404, 19511.414657560297, 19517.0, 19523.414297571097, 2384.3856467609435, 2389.689340385739, 2440.8291419600637, 2448.5837407640743, 2463.187148241076, 133633.0, 135181.6481835179, 136118.0, 137363.0, 140269.2986535071, 13945.721085578434, 14560.708785824436, 14618.707625847423, 14719.705605887822, 14780.33488492999, 22516.0, 23291.534169316517, 23350.299491015296, 23397.7660223399, 23448.06207751705, 10204.38773673582, 10235.641752538626, 10313.690589282334, 10421.322614030063, 10492.0, 9643.373180743229, 9672.56473458682, 9676.806463870824, 9696.903030969741, 9714.61141554344, 8901.821963560693, 9011.504367259864, 9077.546122693971, 9261.49061801594, 9431.952840235892, 2663.067926225844, 2703.9864800676264, 2752.8348299102117, 2760.654918135233, 2874.238326843388, 920.9769755756159, 934.1872570863393, 939.4738946190018, 942.8727121838601, 955.0354142316311, 19425.4172374829, 19551.511212219524, 19689.704654430196, 19887.60224795525, 20033.79966200319, 1009.6769033909079, 1009.6869970309227, 1011.9645812396678, 1015.9644412445529, 1019.7756493571551, 10663.0, 11029.338239705643, 11056.336619802838, 11085.0, 11193.720156995978, 449029.0, 451851.0, 452656.0, 457880.0, 459441.0, 112219.0, 114776.42611786888, 118241.0, 118249.0, 118919.40540297244, 1153.9596114136132, 1163.0869767232682, 1186.2645160000855, 1191.4995701805262, 1202.6512311429587, 17094.572635684206, 17429.302827886742, 17618.383356582457, 17645.0, 17733.20200590951, 49750.26872984613, 49770.502294976584, 53662.195067074805, 55087.0, 59501.404985949586, 67780.6610966942, 67868.6606566964, 67876.0, 68100.6594967022, 68402.0, 14400.927995359956, 14577.489787857374, 14742.557723268319, 14746.336414861142, 14746.852531474548, 22639.094436223026, 22710.205142819923, 22906.54186916219, 23036.0, 23062.538749225256, 17449.56376090608, 17494.0, 17560.034198118716, 17612.91193544024, 17753.644927101126, 16225.51323460298, 16278.51164465068, 16869.0, 17002.48992530226, 17016.57458563546, 18610.883346999224, 18754.906225468785, 18892.716609250598, 18898.0, 18965.14656840418, 14334.426622935383, 14405.9279703605, 14409.13545187292, 14454.132752034911, 14454.566363009126, 18077.276908923497, 18138.095499972314, 18197.272109115485, 18212.817871821113, 18260.908695456437], "value": 11854.626647785326, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1318.6307833806534, 1372.574501904409, 1376.4838185680367, 1379.0346757269913, 1379.903406761527, 15041.623959401008, 16196.514104576867, 16237.83762162378, 16250.431234906751, 16278.674426511463, 15736.527904162893, 16050.1172435516, 16073.276702548384, 16082.195890205501, 16082.678346433093, 2753.325435268362, 2803.593478945552, 2806.2423145750636, 2806.3825958289144, 2806.705295943927, 2673.1579552441017, 2731.166994066809, 2742.5886117082496, 2744.6020327052665, 2745.862706864659, 2504.8497090174556, 2556.8465892046443, 2558.692956845172, 2561.9487610247784, 2565.0381107084845, 2605.2054123492326, 2628.1195799407224, 2643.0, 2650.08572042645, 2650.9204723858315, 2550.4389034412397, 2568.9486210275877, 2572.65269188659, 2577.2139497453268, 2584.276402607254, 14577.344019519041, 14998.325075371635, 15002.474913378088, 15004.399824007027, 15028.398864045424, 7886.6845326186885, 8163.79590510242, 8175.3459723222, 8185.754427367188, 8186.426950113494, 5267.631265811395, 5377.784888604451, 5403.648762830443, 5501.5323697485765, 5508.669479831223, 1938.2925232290254, 1944.6791279438876, 1946.8734532255357, 1954.677478216107, 1989.4230673104782, 26298.6055209172, 26583.0, 26602.0, 26602.866985664947, 26676.33309167267, 6804.251532331439, 6865.0, 6930.722771089201, 7035.964820175866, 7060.0, 17150.31398744061, 17150.571235719206, 17178.31286748541, 17184.054876981885, 17202.741958870625, 7973.202679732097, 8090.393220508486, 8099.392545559107, 8112.837743245102, 8116.797080073045, 6176.413240742122, 6464.25661048976, 6609.735610575523, 7485.438592105614, 7508.549487030796, 5224.52979231871, 5325.334333208349, 5449.0, 5454.3454785426, 5521.668699878021, 1571.3321838218712, 1609.8148712897932, 1617.0782653887172, 1622.7809245751673, 1636.1492024147376, 912.885889263842, 913.8172365526789, 917.2661870503704, 921.953902304882, 927.0914503786328, 7835.608219589112, 7861.056673199253, 7872.212778722082, 7974.800629984181, 8015.278624923785, 715.5921124958724, 730.9342159205697, 733.3839574757229, 735.9668814903342, 750.8348163404042, 4829.396325459317, 5132.409772876167, 5506.394296627368, 5535.916961245584, 5568.053430916673, 21758.347249582537, 22456.10175592958, 22506.32481025572, 22530.774692253195, 22773.31680049568, 21382.251621193496, 21984.230551930606, 22613.434664133198, 22629.094836206696, 22651.660225096635, 11075.0, 11379.146564007568, 11395.772084558263, 11400.828987565194, 11409.48657310423, 8758.38691291629, 9015.233705134875, 9146.817063658822, 9267.53662316895, 9274.25805935537, 18314.725279121092, 18643.161057752706, 18744.906275469082, 18824.24703011864, 19689.704654430483, 19393.0, 19804.801951980873, 19807.603847922674, 19898.50253743668, 19946.900265498578, 11820.586279480176, 11851.348175850411, 12013.75972480563, 12029.879701202875, 12104.152709310263, 13815.0, 14082.155070695791, 14211.786823197453, 14255.073420227653, 14289.42842286297, 11166.553337866395, 11186.0, 11195.664130076111, 11314.434278286217, 11466.541338346373, 10581.571487849042, 10779.299345542515, 10830.51262693165, 10913.508892099715, 11136.610218642309, 9725.367851089657, 9961.252906031932, 9992.65025724096, 9993.300468967103, 10029.29794914349, 7379.631018449163, 7385.0, 7417.072865891763, 7455.962720186364, 7541.7360392386, 7475.775726728207, 7513.774586762406, 7519.661615227437, 7525.510841795266, 7700.499467534592], "value": 6705.112868627629, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.5944733384295, 517.1596156246098, 529.1665626638046, 540.42174872886, 541.6777017674485, 6417.133686952272, 6443.420092191702, 6452.032195170721, 6460.870782584346, 6463.709133089011, 6307.810765677037, 6316.778912738055, 6317.178766760327, 6319.210098737658, 6346.555741098125, 5214.035403450355, 5258.7370631468275, 5291.761870715808, 5303.151495760679, 5315.813946511854, 5011.398632164127, 5114.1050316194705, 5272.8154514592, 5282.366116066078, 5332.600054995872, 5031.924521132168, 5056.0, 5060.2156665716875, 5076.060928728197, 5078.17185813107, 5013.899722005558, 5014.824481143142, 5038.269450929613, 5040.697558146523, 5114.769835357418, 4332.350147477903, 4492.168948744493, 4519.209138400766, 4556.54434556545, 4561.6806823522365, 6156.599821011665, 6187.648693496488, 6209.59637623553, 6224.0, 6253.280872699653, 5698.601097923147, 5769.221155144044, 5774.566907481913, 5789.88420231593, 5808.099744539603, 6522.902156467657, 6559.672016399161, 6626.138601981712, 6656.234533028715, 6668.19981602206, 4862.465128835825, 4916.827911023132, 4922.138625740481, 4924.8768780780765, 4935.284383764334, 6600.0, 6670.966645166792, 6714.83212919676, 6947.965260173717, 7044.154701435809, 4936.555709986119, 4960.255961605716, 5026.84919452417, 5030.748462576857, 5046.78593374529, 5031.572316353077, 5082.288479612857, 5106.234064890296, 5113.360829896263, 5154.819581314636, 5639.238702775155, 5695.772169113272, 5706.514946229617, 5723.340231332867, 5757.071381087383, 6684.799456016327, 6692.19693636757, 6698.296678848748, 6714.52998290115, 6746.460283177334, 1752.7370894365692, 1771.2383675019864, 1791.8387345139, 1793.9282428702707, 1806.837384635389, 1186.1578279421708, 1209.9637010889687, 1235.3144005077088, 1240.4108048676806, 1249.5938819883575, 5914.497267732291, 5921.437463440966, 5942.881142377215, 5957.821265362046, 5974.970125149346, 6804.591724496546, 6840.281770414035, 6871.415929646036, 6881.13985751781, 6934.271901450376, 5891.1457838614015, 5891.49922256613, 5916.792912248051, 5943.514121469632, 5956.821295361146, 3593.658602432765, 3609.620989796086, 3641.435577485494, 3650.908727281839, 3662.7802331860175, 6650.733970641217, 6651.268360480343, 6668.699908504129, 6700.698468568926, 6718.395344419026, 6593.310415582759, 6945.47908906834, 6989.7846990385, 7003.92996070043, 7007.264237255116, 6593.241777195588, 6666.96639541216, 6682.9331706682315, 6687.0, 6687.465002799764, 6794.72821087151, 6851.520393572402, 6864.901615741457, 6867.21027081872, 6920.446364290945, 6709.698063587248, 6738.393544581011, 6765.052892594942, 6773.18721753373, 6778.186617605918, 6619.602823830585, 6659.800205993828, 6661.168178750882, 6666.766663166766, 6671.46628269747, 5047.0, 5064.848054558369, 5068.543831055223, 5070.146688516827, 5094.388673359221, 6584.374484423972, 6763.357481039294, 6788.558743681645, 6967.268436814062, 6988.37104660583, 6471.838204044749, 6607.57050791697, 6609.603423794588, 6648.667566621552, 6685.799426017227, 6603.603783772797, 6614.437772789174, 6621.0, 6627.9337206627315, 6632.502562307943, 6593.340665933369, 6598.449364399458, 6613.272540020497, 6627.734890604322, 6635.473841016496, 6653.401193892573, 6663.300353462817, 6665.433438157811, 6671.966640166768, 6674.799756007327, 6353.301136875032, 6372.808815735535, 6405.455536279468, 6410.743570257137, 6478.319776423407], "value": 5144.383496175634, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [900.0954041188604, 902.2826852652145, 918.6922381002366, 924.7549399409157, 927.7773334399747, 17093.658126837458, 17738.64522709545, 17783.55541111473, 17795.288188472477, 17800.0, 16466.588335291624, 16808.831911680878, 19910.50223744407, 21652.242171524, 21710.023048962794, 971.9416834989905, 984.3700031979503, 985.014985014985, 987.2792861211284, 988.574912787501, 1000.4347543637822, 1002.5939494504737, 1003.9799204015916, 1005.6882366466418, 1007.3804610164733, 956.6699488676395, 1001.9699409017741, 1011.5448048378222, 1070.261519551507, 1076.633944458884, 995.3082607587729, 1003.5634498992926, 1003.6637726361678, 1003.9247056470793, 1003.9899601004042, 742.9405647548238, 746.2686567164155, 746.5073051785795, 747.5888261456232, 747.6560782040214, 19059.61880762377, 19507.317243896396, 19740.50648733779, 19758.110885010065, 20262.797372026238, 20805.687914681293, 20887.68668469974, 21061.0, 21423.14307427701, 21483.140674373008, 16497.505074847773, 16589.336426542923, 16673.24970376336, 16709.91645041767, 16777.748333775, 3709.3879509880835, 3715.368387374153, 3726.8136593170234, 3727.384981478053, 3727.5154229949935, 123995.0, 126295.36852315771, 126330.0, 127264.0, 127798.0, 15905.443309484344, 16354.182290885408, 16484.258208380652, 16536.503904882873, 16548.917255413646, 29081.127566173047, 29856.40287194244, 29866.253343666584, 29883.850580746956, 29888.25279368033, 8541.615627296787, 8720.389572729848, 8731.039585645574, 8736.082711315348, 8758.386912916161, 4188.4555007848785, 4269.829206831754, 4274.401583778274, 4291.249031419551, 4298.935515967263, 5175.301334319819, 5185.818496352609, 5235.65968212065, 5243.895122097537, 5251.343582052244, 1060.771934034171, 1096.3586302013252, 1098.1983152298708, 1102.7959827431791, 1103.0458653264868, 665.7270519087151, 679.7043286170532, 682.743971010871, 696.8432102776835, 700.1807884774724, 12147.271163730204, 12168.391580420943, 12432.191907525981, 12899.677508062186, 13099.803502947463, 650.3626446082865, 654.443722835586, 656.3895577113235, 663.3101574362705, 667.1127400557248, 5814.592978491465, 5840.8539786504825, 5929.0, 5936.376680448577, 6018.488428483541, 109691.0, 111842.44078779717, 112353.43823280832, 115114.0, 116598.41700791442, 101655.0, 102055.0, 103137.48431257946, 104939.475302623, 108057.0, 84450.5777471121, 84720.0, 84896.57551712079, 86655.0, 88588.5570572143, 10911.290766100177, 10937.523344704736, 11083.667489975314, 11098.0, 11269.774604508028, 40975.385369218886, 41862.0, 42117.57882421136, 42581.787091065584, 42867.57132428761, 51642.22536662028, 51708.224376633625, 51757.48242517677, 54412.45587544074, 54589.45410545844, 14468.927655361656, 14550.199739014455, 14949.77575336349, 15096.320665570296, 15097.622559435662, 17353.30586776565, 17397.565060873578, 17547.912260439127, 17665.29338826484, 17742.645147097242, 15270.0, 15430.305636246172, 15550.300236489611, 15555.84444155544, 15730.449434269745, 16205.351785928431, 16291.918540407696, 16306.429274975319, 16453.91773041127, 16488.752668709738, 15754.448594299145, 15999.520014399586, 16019.599510012344, 16071.598210044842, 16073.517794466185, 11526.538938442369, 11702.0, 11888.583899563473, 11969.940150299193, 12086.75826483483, 12033.037357011593, 12127.211731237794, 12134.211276267015, 12270.502906671556, 14816.185109819064], "value": 8809.410290017753, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1480.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 108.0, 99.0, 107.0, 109.0, 109.0, 109.0, 99.0, 109.0, 109.0], "value": 105.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 116.0, 140.0, 177.0, 121.0, 154.0, 145.0, 116.0, 179.0, 118.0], "value": 141.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 162.0, 160.0, 162.0, 163.0, 163.0, 172.0, 164.0, 161.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 74.0, 172.0, 75.0, 74.0, 75.0, 88.0, 96.0, 75.0], "value": 87.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 125.0, 80.0, 79.0, 79.0, 136.0, 79.0, 78.0, 135.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 115.0, 114.0, 115.0, 115.0, 114.0, 115.0, 114.0, 115.0], "value": 114.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 148.0, 147.0, 148.0, 147.0, 148.0, 151.0, 148.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 93.0, 97.0, 94.0, 94.0, 95.0, 93.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 52.0, 52.0, 51.0, 54.0, 52.0, 51.0, 51.0, 52.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 37.0, 37.0, 38.0, 38.0, 39.0, 37.0, 38.0], "value": 37.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 82.0, 74.0, 74.0, 74.0, 75.0, 74.0, 74.0], "value": 75.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 118.0, 127.0, 119.0, 128.0, 129.0, 118.0, 119.0, 124.0, 151.0], "value": 125.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 177.0, 168.0, 169.0, 170.0, 176.0, 174.0, 180.0, 183.0, 173.0], "value": 174.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 70.0, 65.0, 64.0, 63.0, 63.0, 65.0, 69.0, 69.0], "value": 65.7, "unit": "score"}]}]} 16:21:43 INFO - Return code: 0 16:21:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:21:43 INFO - # TBPL SUCCESS # 16:21:43 INFO - Running post-action listener: _package_coverage_data 16:21:43 INFO - Running post-action listener: _resource_record_post_action 16:21:43 INFO - [mozharness: 2017-09-02 23:21:43.914166Z] Finished run-tests step (success) 16:21:43 INFO - Running post-run listener: _resource_record_post_run 16:21:43 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 389402624; Read time: 12; Write time: 455348 16:21:43 INFO - TinderboxPrint: CPU usage
13.1% 16:21:43 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:21:43 INFO - TinderboxPrint: I/O write bytes / time
389,402,624 / 455,348 16:21:43 INFO - TinderboxPrint: CPU idle
8,242.4 (86.9%) 16:21:43 INFO - TinderboxPrint: CPU user
1,192.0 (12.6%) 16:21:43 INFO - TinderboxPrint: Swap in / out
0 / 0 16:21:43 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 84471808; Read time: 0; Write time: 62348 16:21:43 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:21:44 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 282189824; Read time: 12; Write time: 301788 16:21:44 INFO - Running post-run listener: _upload_blobber_files 16:21:44 INFO - Blob upload gear active. 16:21:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:21:44 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:21:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:21:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:21:44 INFO - (blobuploader) - INFO - Open directory for files ... 16:21:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:21:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:21:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:45 INFO - (blobuploader) - INFO - Done attempting. 16:21:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:21:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:21:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:46 INFO - (blobuploader) - INFO - Done attempting. 16:21:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:21:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:21:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:50 INFO - (blobuploader) - INFO - Done attempting. 16:21:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:21:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:21:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:51 INFO - (blobuploader) - INFO - Done attempting. 16:21:51 INFO - (blobuploader) - INFO - Iteration through files over. 16:21:51 INFO - Return code: 0 16:21:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:21:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:21:51 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/16459228918884dd69984af8ea5a0ff06778845be9881e7d1b53aa56f1dbe84007c4a5d7b8619935c864754e917b33f53e6e3406ff59ae79d669e703b2700ff0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1eceabf0e155c93fee6791cbfe9fc09e6cd5ed0ffbe4e659b269925f23741ab81cc882af05133a7d60603873a09c2eecfd11c601db0b8e23a121187c38d812fd", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ac21f7f0303d2fe104deea7f2494cd29ce3f3b2784a249ec880d3d14ac401d41ebe50d6811acbb0a813baa2342b46d8bf60cebb19fe2b693394ed85471a60e53", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8fa73a1ffc80539ccdd2dc032d4972e9efd3486f18f7434be53ea24d3998e8363ce743dc326c872e3fa166591cc6bfd6331804023feeaf3daaf21f3c57d7fc9d"} 16:21:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:21:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:21:51 INFO - Contents: 16:21:51 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/16459228918884dd69984af8ea5a0ff06778845be9881e7d1b53aa56f1dbe84007c4a5d7b8619935c864754e917b33f53e6e3406ff59ae79d669e703b2700ff0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1eceabf0e155c93fee6791cbfe9fc09e6cd5ed0ffbe4e659b269925f23741ab81cc882af05133a7d60603873a09c2eecfd11c601db0b8e23a121187c38d812fd", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ac21f7f0303d2fe104deea7f2494cd29ce3f3b2784a249ec880d3d14ac401d41ebe50d6811acbb0a813baa2342b46d8bf60cebb19fe2b693394ed85471a60e53", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8fa73a1ffc80539ccdd2dc032d4972e9efd3486f18f7434be53ea24d3998e8363ce743dc326c872e3fa166591cc6bfd6331804023feeaf3daaf21f3c57d7fc9d"} 16:21:51 INFO - Running post-run listener: copy_logs_to_upload_dir 16:21:51 INFO - Copying logs to upload dir... 16:21:51 INFO - mkdir: /builds/slave/test/build/upload/logs 16:21:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.693002 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-02 16:21:51.524081) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 16:21:51.532040) ========= 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=836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/16459228918884dd69984af8ea5a0ff06778845be9881e7d1b53aa56f1dbe84007c4a5d7b8619935c864754e917b33f53e6e3406ff59ae79d669e703b2700ff0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1eceabf0e155c93fee6791cbfe9fc09e6cd5ed0ffbe4e659b269925f23741ab81cc882af05133a7d60603873a09c2eecfd11c601db0b8e23a121187c38d812fd", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ac21f7f0303d2fe104deea7f2494cd29ce3f3b2784a249ec880d3d14ac401d41ebe50d6811acbb0a813baa2342b46d8bf60cebb19fe2b693394ed85471a60e53", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8fa73a1ffc80539ccdd2dc032d4972e9efd3486f18f7434be53ea24d3998e8363ce743dc326c872e3fa166591cc6bfd6331804023feeaf3daaf21f3c57d7fc9d"} build_url:https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011387 build_url: 'https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/16459228918884dd69984af8ea5a0ff06778845be9881e7d1b53aa56f1dbe84007c4a5d7b8619935c864754e917b33f53e6e3406ff59ae79d669e703b2700ff0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1eceabf0e155c93fee6791cbfe9fc09e6cd5ed0ffbe4e659b269925f23741ab81cc882af05133a7d60603873a09c2eecfd11c601db0b8e23a121187c38d812fd", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ac21f7f0303d2fe104deea7f2494cd29ce3f3b2784a249ec880d3d14ac401d41ebe50d6811acbb0a813baa2342b46d8bf60cebb19fe2b693394ed85471a60e53", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8fa73a1ffc80539ccdd2dc032d4972e9efd3486f18f7434be53ea24d3998e8363ce743dc326c872e3fa166591cc6bfd6331804023feeaf3daaf21f3c57d7fc9d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 16:21:51.563241) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:21:51.563578) ========= 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=836cfdc75598d54cb747368e000001a9-1504393260.991969-1637024425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004473 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:21:51.644518) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 16:21:51.647833) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 16:21:51.648128) ========= ========= Total master_lag: 0.26 =========