builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-081 starttime: 1504393565.19 results: success (0) revision: f5d99e9f3d7bf19c8a222db97bedc924da27fd58 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 16:06:05.190366) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-02 16:06:05.190846) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 16:06:05.191125) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-02 16:06:05.208605) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:06:05.208983) ========= 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=483e410453545d07cca6935c000001ab-1504393564.370210-524947057 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-02 16:06:05-- 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% 40.3M=0s 2017-09-02 16:06:05 (40.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.087750 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:06:05.313969) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:06:05.314329) ========= 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=483e410453545d07cca6935c000001ab-1504393564.370210-524947057 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021437 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:06:05.363986) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-02 16:06:05.364370) ========= 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=483e410453545d07cca6935c000001ab-1504393564.370210-524947057 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-02 16:06:05,422 truncating revision to first 12 chars 2017-09-02 16:06:05,422 Setting DEBUG logging. 2017-09-02 16:06:05,422 attempt 1/10 2017-09-02 16:06:05,422 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:06:06,864 unpacking tar archive at: mozilla-central-f5d99e9f3d7b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.852407 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-02 16:06:07.235463) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 16:06:07.235823) ========= script_repo_revision: f5d99e9f3d7bf19c8a222db97bedc924da27fd58 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-02 16:06:07.236218) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 16:06:07.236498) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-02 16:06:07.249636) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-09-02 16:06:07.250014) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=483e410453545d07cca6935c000001ab-1504393564.370210-524947057 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:06:07 INFO - MultiFileLogger online at 20170902 16:06:07 in /builds/slave/test 16:06:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:06:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:06:07 INFO - {'append_to_log': False, 16:06:07 INFO - 'base_work_dir': '/builds/slave/test', 16:06:07 INFO - 'blob_upload_branch': 'Firefox', 16:06:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:06:07 INFO - 'branch': 'Firefox', 16:06:07 INFO - 'buildbot_json_path': 'buildprops.json', 16:06:07 INFO - 'code_coverage': False, 16:06:07 INFO - 'config_files': ('talos/linux_config.py',), 16:06:07 INFO - 'default_actions': ('clobber', 16:06:07 INFO - 'read-buildbot-config', 16:06:07 INFO - 'download-and-extract', 16:06:07 INFO - 'populate-webroot', 16:06:07 INFO - 'create-virtualenv', 16:06:07 INFO - 'install', 16:06:07 INFO - 'setup-mitmproxy', 16:06:07 INFO - 'run-tests'), 16:06:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:06:07 INFO - 'disable_ccov_upload': False, 16:06:07 INFO - 'disable_stylo': False, 16:06:07 INFO - 'download_minidump_stackwalk': True, 16:06:07 INFO - 'download_symbols': 'ondemand', 16:06:07 INFO - 'enable_stylo': False, 16:06:07 INFO - 'enable_webrender': False, 16:06:07 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:06:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:06:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:06:07 INFO - 'gecko_profile': False, 16:06:07 INFO - 'gecko_profile_interval': 0, 16:06:07 INFO - 'installer_path': 'installer.exe', 16:06:07 INFO - 'log_level': 'info', 16:06:07 INFO - 'log_name': 'talos', 16:06:07 INFO - 'log_to_console': True, 16:06:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:06:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:06:07 INFO - 'opt_config_files': (), 16:06:07 INFO - 'pip_index': False, 16:06:07 INFO - 'suite': 'dromaeojs-e10s', 16:06:07 INFO - 'system_bits': '32', 16:06:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:06:07 INFO - 'title': 'talos-linux64-ix-081', 16:06:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:06:07 INFO - 'use_talos_json': True, 16:06:07 INFO - 'verify': 'False', 16:06:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:06:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:06:07 INFO - 'work_dir': 'build'} 16:06:07 INFO - [mozharness: 2017-09-02 23:06:07.440581Z] Running clobber step. 16:06:07 INFO - Running pre-action listener: _resource_record_pre_action 16:06:07 INFO - Running main action method: clobber 16:06:07 INFO - rmtree: /builds/slave/test/build 16:06:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:06:08 INFO - Running post-action listener: _resource_record_post_action 16:06:08 INFO - [mozharness: 2017-09-02 23:06:08.454855Z] Finished clobber step (success) 16:06:08 INFO - [mozharness: 2017-09-02 23:06:08.454952Z] Running read-buildbot-config step. 16:06:08 INFO - Running pre-action listener: _resource_record_pre_action 16:06:08 INFO - Running main action method: read_buildbot_config 16:06:08 INFO - Using buildbot properties: 16:06:08 INFO - { 16:06:08 INFO - "project": "", 16:06:08 INFO - "product": "firefox", 16:06:08 INFO - "who": "archaeopteryx@coole-files.de", 16:06:08 INFO - "installer_path": "public/build/target.tar.bz2", 16:06:08 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 16:06:08 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:06:08 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 16:06:08 INFO - "stage_platform": "linux64-stylosequential", 16:06:08 INFO - "basedir": "/builds/slave/test", 16:06:08 INFO - "buildnumber": 229, 16:06:08 INFO - "platform": "ubuntu64_hw_styloseq", 16:06:08 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:06:08 INFO - "slavebuilddir": "test", 16:06:08 INFO - "taskId": "UanaVTBoSPu7dg09Ma7Eug", 16:06:08 INFO - "branch": "mozilla-central", 16:06:08 INFO - "script_repo_revision": "production", 16:06:08 INFO - "revision": "f5d99e9f3d7bf19c8a222db97bedc924da27fd58", 16:06:08 INFO - "slavename": "talos-linux64-ix-081", 16:06:08 INFO - "repo_path": "mozilla-central" 16:06:08 INFO - } 16:06:08 INFO - Finding installer, test and symbols from parent task. 16:06:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UanaVTBoSPu7dg09Ma7Eug'}, attempt #1 16:06:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UanaVTBoSPu7dg09Ma7Eug'}, attempt #1 16:06:09 INFO - Task dependencies: Y5WsbGReTUeKPP-utDhE2w 16:06:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w'}, attempt #1 16:06:09 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2 16:06:09 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.test_packages.json 16:06:09 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.crashreporter-symbols.zip 16:06:09 INFO - Running post-action listener: _resource_record_post_action 16:06:09 INFO - [mozharness: 2017-09-02 23:06:09.818628Z] Finished read-buildbot-config step (success) 16:06:09 INFO - [mozharness: 2017-09-02 23:06:09.818843Z] Running download-and-extract step. 16:06:09 INFO - Running pre-action listener: _resource_record_pre_action 16:06:09 INFO - Running main action method: download_and_extract 16:06:09 INFO - mkdir: /builds/slave/test/build/tests 16:06:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:09 INFO - trying https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.test_packages.json 16:06:09 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:06:09 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:06:10 INFO - Downloaded 989 bytes. 16:06:10 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:06:10 INFO - Using the following test package requirements: 16:06:10 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:06:10 INFO - u'common': [u'target.common.tests.zip'], 16:06:10 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:06:10 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:06:10 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:06:10 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:06:10 INFO - u'mozbase': [u'target.common.tests.zip'], 16:06:10 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:06:10 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:06:10 INFO - u'web-platform': [u'target.common.tests.zip', 16:06:10 INFO - u'target.web-platform.tests.tar.gz'], 16:06:10 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:06:10 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:06:10 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:06:10 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:06:10 INFO - Fetch https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.common.tests.zip into memory 16:06:11 INFO - Content-Length response header: 55022372 16:06:11 INFO - Bytes received: 55022372 16:06:22 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:06:22 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:06:22 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:06:22 INFO - Fetch https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.common.tests.zip into memory 16:06:23 INFO - Content-Length response header: 55022372 16:06:23 INFO - Bytes received: 55022372 16:06:35 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:06:35 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:06:35 INFO - Fetch https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.talos.tests.zip into memory 16:06:36 INFO - Content-Length response header: 14496650 16:06:36 INFO - Bytes received: 14496650 16:06: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:06:36 INFO - trying https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2 16:06:36 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:06:36 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:06:37 INFO - Downloaded 62581036 bytes. 16:06:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2 16:06:37 INFO - mkdir: /builds/slave/test/properties 16:06:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:06:37 INFO - Writing to file /builds/slave/test/properties/build_url 16:06:37 INFO - Contents: 16:06:37 INFO - build_url:https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2 16:06:37 INFO - Running post-action listener: _resource_record_post_action 16:06:37 INFO - Running post-action listener: find_tests_for_verification 16:06:37 INFO - Running post-action listener: set_extra_try_arguments 16:06:37 INFO - [mozharness: 2017-09-02 23:06:37.810209Z] Finished download-and-extract step (success) 16:06:37 INFO - [mozharness: 2017-09-02 23:06:37.810305Z] Running populate-webroot step. 16:06:37 INFO - Running pre-action listener: _resource_record_pre_action 16:06:37 INFO - Running main action method: populate_webroot 16:06:37 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:06:37 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:06:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:06:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:06:37 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:06:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:06:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:06:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:06:37 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:06:37 INFO - 'talos_options': ['--enable-stylo'], 16:06:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:06:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:06:37 INFO - 'tests': ['damp', 'tps']}, 16:06:37 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:06:37 INFO - 'talos_options': ['--enable-stylo'], 16:06:37 INFO - 'tests': ['damp', 'tps']}, 16:06:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:06:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:06:37 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:06:37 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:06:37 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:06:37 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:06:37 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:06:37 INFO - 'talos_options': ['--enable-stylo'], 16:06:37 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:06:37 INFO - 'other-e10s': {'tests': ['a11yr', 16:06:37 INFO - 'ts_paint', 16:06:37 INFO - 'tpaint', 16:06:37 INFO - 'sessionrestore', 16:06:37 INFO - 'sessionrestore_many_windows', 16:06:37 INFO - 'sessionrestore_no_auto_restore', 16:06:37 INFO - 'tabpaint']}, 16:06:37 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:06:37 INFO - 'tests': ['a11yr', 16:06:37 INFO - 'ts_paint', 16:06:37 INFO - 'tpaint', 16:06:37 INFO - 'sessionrestore', 16:06:37 INFO - 'sessionrestore_many_windows', 16:06:37 INFO - 'sessionrestore_no_auto_restore', 16:06:37 INFO - 'tabpaint']}, 16:06:37 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:06:37 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:06:37 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:06:37 INFO - 'tests': ['bloom_basic_singleton']}, 16:06:37 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:06:37 INFO - 'tests': ['bloom_basic']}, 16:06:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:06:37 INFO - 'tsvgr_opacity', 16:06:37 INFO - 'tart', 16:06:37 INFO - 'tscrollx', 16:06:37 INFO - 'tsvg_static']}, 16:06:37 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:06:37 INFO - 'tests': ['tsvgx', 16:06:37 INFO - 'tsvgr_opacity', 16:06:37 INFO - 'tart', 16:06:37 INFO - 'tscrollx', 16:06:37 INFO - 'tsvg_static']}, 16:06:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:06:37 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:06:37 INFO - 'talos_options': ['--enable-stylo'], 16:06:37 INFO - 'tests': ['tp5o']}, 16:06:37 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:06:37 INFO - 'talos_options': ['--mitmproxy', 16:06:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:06:37 INFO - '--firstNonBlankPaint'], 16:06:37 INFO - 'tests': ['tp6_google', 16:06:37 INFO - 'tp6_youtube', 16:06:37 INFO - 'tp6_amazon', 16:06:37 INFO - 'tp6_facebook']}, 16:06:37 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:06:37 INFO - 'talos_options': ['--enable-stylo', 16:06:37 INFO - '--mitmproxy', 16:06:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:06:37 INFO - '--firstNonBlankPaint'], 16:06:37 INFO - 'tests': ['tp6_google', 16:06:37 INFO - 'tp6_youtube', 16:06:37 INFO - 'tp6_amazon', 16:06:37 INFO - 'tp6_facebook']}, 16:06:37 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:06:37 INFO - 'talos_options': ['--enable-stylo', 16:06:37 INFO - '--stylo-threads=1', 16:06:37 INFO - '--mitmproxy', 16:06:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:06:37 INFO - '--firstNonBlankPaint'], 16:06:37 INFO - 'tests': ['tp6_google', 16:06:37 INFO - 'tp6_youtube', 16:06:37 INFO - 'tp6_amazon', 16:06:37 INFO - 'tp6_facebook']}, 16:06:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:06:37 INFO - 'talos_options': ['--xperf_path', 16:06:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:06:37 INFO - 'tests': ['tp5n']}, 16:06:37 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:06:37 INFO - 'talos_options': ['--enable-stylo', 16:06:37 INFO - '--xperf_path', 16:06:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:06:37 INFO - 'tests': ['tp5n']}}} 16:06:37 INFO - Running post-action listener: _resource_record_post_action 16:06:37 INFO - [mozharness: 2017-09-02 23:06:37.824552Z] Finished populate-webroot step (success) 16:06:37 INFO - [mozharness: 2017-09-02 23:06:37.824640Z] Running create-virtualenv step. 16:06:37 INFO - Running pre-action listener: _resource_record_pre_action 16:06:37 INFO - Running main action method: create_virtualenv 16:06:37 INFO - Creating virtualenv /builds/slave/test/build/venv 16:06:37 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:06:37 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:06:37 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:06:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:06:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:06:38 INFO - Installing distribute.............................................................................................................................................................................................done. 16:06:38 INFO - Installing pip...............done. 16:06:38 INFO - Return code: 0 16:06:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:06:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:06:38 INFO - Reading from file tmpfile_stdout 16:06:38 INFO - Output received: 16:06:38 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:06:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:06: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:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06: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:06:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2831a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2835090>, '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 0x2835d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x282ff90>, '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': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', '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:06: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', 'pip>=1.5'] in /builds/slave/test/build 16:06: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 pip>=1.5 16:06:38 INFO - Using env: {'DISPLAY': ':0', 16:06:38 INFO - 'HOME': '/home/cltbld', 16:06:38 INFO - 'LANG': 'en_US.UTF-8', 16:06:38 INFO - 'LANGUAGE': 'en_US:en', 16:06:38 INFO - 'LOGNAME': 'cltbld', 16:06:38 INFO - 'MAIL': '/var/mail/cltbld', 16:06:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:38 INFO - 'MOZ_NO_REMOTE': '1', 16:06:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:38 INFO - 'NO_EM_RESTART': '1', 16:06:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:38 INFO - 'PWD': '/builds/slave/test', 16:06:38 INFO - 'SHELL': '/bin/bash', 16:06:38 INFO - 'SHLVL': '1', 16:06:38 INFO - 'TERM': 'linux', 16:06:38 INFO - 'TMOUT': '86400', 16:06:38 INFO - 'USER': 'cltbld', 16:06:38 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', 16:06:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:38 INFO - '_': '/tools/buildbot/bin/python'} 16:06:39 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:06:39 INFO - Downloading/unpacking pip>=1.5 16:06:39 INFO - Running setup.py egg_info for package pip 16:06:39 INFO - warning: no files found matching 'pip/cacert.pem' 16:06:39 INFO - warning: no files found matching '*.html' under directory 'docs' 16:06:39 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:06:39 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:06:39 INFO - Installing collected packages: pip 16:06:39 INFO - Found existing installation: pip 1.1 16:06:39 INFO - Uninstalling pip: 16:06:39 INFO - Successfully uninstalled pip 16:06:39 INFO - Running setup.py install for pip 16:06:39 INFO - warning: no files found matching 'pip/cacert.pem' 16:06:39 INFO - warning: no files found matching '*.html' under directory 'docs' 16:06:39 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:06:39 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:06:39 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:06:39 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:06:39 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:06:39 INFO - Successfully installed pip 16:06:39 INFO - Cleaning up... 16:06:39 INFO - Return code: 0 16:06:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:06: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:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06: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:06:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06: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', '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 0x24c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2831a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2835090>, '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 0x2835d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x282ff90>, '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': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', '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:06: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', 'psutil>=3.1.1'] in /builds/slave/test/build 16:06: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 psutil>=3.1.1 16:06:40 INFO - Using env: {'DISPLAY': ':0', 16:06:40 INFO - 'HOME': '/home/cltbld', 16:06:40 INFO - 'LANG': 'en_US.UTF-8', 16:06:40 INFO - 'LANGUAGE': 'en_US:en', 16:06:40 INFO - 'LOGNAME': 'cltbld', 16:06:40 INFO - 'MAIL': '/var/mail/cltbld', 16:06:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:40 INFO - 'MOZ_NO_REMOTE': '1', 16:06:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:40 INFO - 'NO_EM_RESTART': '1', 16:06:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:40 INFO - 'PWD': '/builds/slave/test', 16:06:40 INFO - 'SHELL': '/bin/bash', 16:06:40 INFO - 'SHLVL': '1', 16:06:40 INFO - 'TERM': 'linux', 16:06:40 INFO - 'TMOUT': '86400', 16:06:40 INFO - 'USER': 'cltbld', 16:06:40 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', 16:06:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:40 INFO - '_': '/tools/buildbot/bin/python'} 16:06:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:40 INFO - Downloading/unpacking psutil>=3.1.1 16:06: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:06: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:06: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:06: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:06:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:06:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:06:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:06:41 INFO - Installing collected packages: psutil 16:06:41 INFO - Running setup.py install for psutil 16:06:41 INFO - building 'psutil._psutil_linux' extension 16:06:41 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:06:41 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:06:41 INFO - building 'psutil._psutil_posix' extension 16:06:41 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:06:41 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:06:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:06:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:06:41 INFO - Successfully installed psutil 16:06:41 INFO - Cleaning up... 16:06:41 INFO - Return code: 0 16:06:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:06: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:06:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06: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:06:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2831a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2835090>, '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 0x2835d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x282ff90>, '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': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', '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:06: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:06: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 mozsystemmonitor==0.3 16:06:41 INFO - Using env: {'DISPLAY': ':0', 16:06:41 INFO - 'HOME': '/home/cltbld', 16:06:41 INFO - 'LANG': 'en_US.UTF-8', 16:06:41 INFO - 'LANGUAGE': 'en_US:en', 16:06:41 INFO - 'LOGNAME': 'cltbld', 16:06:41 INFO - 'MAIL': '/var/mail/cltbld', 16:06:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:41 INFO - 'MOZ_NO_REMOTE': '1', 16:06:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:41 INFO - 'NO_EM_RESTART': '1', 16:06:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:41 INFO - 'PWD': '/builds/slave/test', 16:06:41 INFO - 'SHELL': '/bin/bash', 16:06:41 INFO - 'SHLVL': '1', 16:06:41 INFO - 'TERM': 'linux', 16:06:41 INFO - 'TMOUT': '86400', 16:06:41 INFO - 'USER': 'cltbld', 16:06:41 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', 16:06:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:41 INFO - '_': '/tools/buildbot/bin/python'} 16:06:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:06:42 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:06:42 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:06:42 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:06:42 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:06:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:06:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:06:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:06:43 INFO - Installing collected packages: mozsystemmonitor 16:06:43 INFO - Running setup.py install for mozsystemmonitor 16:06:43 INFO - Successfully installed mozsystemmonitor 16:06:43 INFO - Cleaning up... 16:06:43 INFO - Return code: 0 16:06:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:43 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 0x24c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2831a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2835090>, '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 0x2835d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x282ff90>, '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': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', '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:06:43 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:06:43 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:06:43 INFO - Using env: {'DISPLAY': ':0', 16:06:43 INFO - 'HOME': '/home/cltbld', 16:06:43 INFO - 'LANG': 'en_US.UTF-8', 16:06:43 INFO - 'LANGUAGE': 'en_US:en', 16:06:43 INFO - 'LOGNAME': 'cltbld', 16:06:43 INFO - 'MAIL': '/var/mail/cltbld', 16:06:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:43 INFO - 'MOZ_NO_REMOTE': '1', 16:06:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:43 INFO - 'NO_EM_RESTART': '1', 16:06:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:43 INFO - 'PWD': '/builds/slave/test', 16:06:43 INFO - 'SHELL': '/bin/bash', 16:06:43 INFO - 'SHLVL': '1', 16:06:43 INFO - 'TERM': 'linux', 16:06:43 INFO - 'TMOUT': '86400', 16:06:43 INFO - 'USER': 'cltbld', 16:06:43 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', 16:06:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:43 INFO - '_': '/tools/buildbot/bin/python'} 16:06:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:43 INFO - Downloading/unpacking jsonschema==2.5.1 16:06: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:06: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:06: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:06: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:06:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:06:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:44 INFO - Downloading functools32-3.2.3-2.tar.gz 16:06:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:06:44 INFO - warning: no files found matching '*.txt' 16:06:44 INFO - no previously-included directories found matching 'build' 16:06:44 INFO - no previously-included directories found matching 'dist' 16:06:44 INFO - no previously-included directories found matching '.git*' 16:06:44 INFO - Installing collected packages: jsonschema, functools32 16:06:44 INFO - Running setup.py install for functools32 16:06:45 INFO - warning: no files found matching '*.txt' 16:06:45 INFO - no previously-included directories found matching 'build' 16:06:45 INFO - no previously-included directories found matching 'dist' 16:06:45 INFO - no previously-included directories found matching '.git*' 16:06:45 INFO - Successfully installed jsonschema functools32 16:06:45 INFO - Cleaning up... 16:06:45 INFO - Return code: 0 16:06:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:06:45 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:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:45 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:06:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:45 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 0x24c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2831a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2835090>, '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 0x2835d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x282ff90>, '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': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', '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:06:45 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:06:45 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:06:45 INFO - Using env: {'DISPLAY': ':0', 16:06:45 INFO - 'HOME': '/home/cltbld', 16:06:45 INFO - 'LANG': 'en_US.UTF-8', 16:06:45 INFO - 'LANGUAGE': 'en_US:en', 16:06:45 INFO - 'LOGNAME': 'cltbld', 16:06:45 INFO - 'MAIL': '/var/mail/cltbld', 16:06:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:45 INFO - 'MOZ_NO_REMOTE': '1', 16:06:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:45 INFO - 'NO_EM_RESTART': '1', 16:06:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:45 INFO - 'PWD': '/builds/slave/test', 16:06:45 INFO - 'SHELL': '/bin/bash', 16:06:45 INFO - 'SHLVL': '1', 16:06:45 INFO - 'TERM': 'linux', 16:06:45 INFO - 'TMOUT': '86400', 16:06:45 INFO - 'USER': 'cltbld', 16:06:45 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', 16:06:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:45 INFO - '_': '/tools/buildbot/bin/python'} 16:06:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:06:45 INFO - Cleaning up... 16:06:45 INFO - Return code: 0 16:06:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:06:45 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:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:45 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:06:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:45 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 0x24c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2831a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2835090>, '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 0x2835d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x282ff90>, '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': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', '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:06:45 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:06:45 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:06:45 INFO - Using env: {'DISPLAY': ':0', 16:06:45 INFO - 'HOME': '/home/cltbld', 16:06:45 INFO - 'LANG': 'en_US.UTF-8', 16:06:45 INFO - 'LANGUAGE': 'en_US:en', 16:06:45 INFO - 'LOGNAME': 'cltbld', 16:06:45 INFO - 'MAIL': '/var/mail/cltbld', 16:06:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:45 INFO - 'MOZ_NO_REMOTE': '1', 16:06:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:45 INFO - 'NO_EM_RESTART': '1', 16:06:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:45 INFO - 'PWD': '/builds/slave/test', 16:06:45 INFO - 'SHELL': '/bin/bash', 16:06:45 INFO - 'SHLVL': '1', 16:06:45 INFO - 'TERM': 'linux', 16:06:45 INFO - 'TMOUT': '86400', 16:06:45 INFO - 'USER': 'cltbld', 16:06:45 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', 16:06:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:45 INFO - '_': '/tools/buildbot/bin/python'} 16:06:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:45 INFO - Downloading/unpacking blobuploader==1.2.4 16:06:45 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:06:45 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:06:45 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:06:45 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:06:46 INFO - Downloading blobuploader-1.2.4.tar.gz 16:06:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:06:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:06:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:06:47 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:06:47 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:06:47 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:06:47 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:06:47 INFO - Downloading docopt-0.6.1.tar.gz 16:06:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:06:47 INFO - Installing collected packages: blobuploader, requests, docopt 16:06:47 INFO - Running setup.py install for blobuploader 16:06:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:06:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:06:47 INFO - Running setup.py install for requests 16:06:47 INFO - Running setup.py install for docopt 16:06:47 INFO - Successfully installed blobuploader requests docopt 16:06:47 INFO - Cleaning up... 16:06:47 INFO - Return code: 0 16:06:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:06: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:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06: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:06:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:47 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 0x24c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2831a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2835090>, '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 0x2835d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x282ff90>, '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': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', '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:06:47 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:06:47 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:06:47 INFO - Using env: {'DISPLAY': ':0', 16:06:47 INFO - 'HOME': '/home/cltbld', 16:06:47 INFO - 'LANG': 'en_US.UTF-8', 16:06:47 INFO - 'LANGUAGE': 'en_US:en', 16:06:47 INFO - 'LOGNAME': 'cltbld', 16:06:47 INFO - 'MAIL': '/var/mail/cltbld', 16:06:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:47 INFO - 'MOZ_NO_REMOTE': '1', 16:06:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:47 INFO - 'NO_EM_RESTART': '1', 16:06:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:47 INFO - 'PWD': '/builds/slave/test', 16:06:47 INFO - 'SHELL': '/bin/bash', 16:06:47 INFO - 'SHLVL': '1', 16:06:47 INFO - 'TERM': 'linux', 16:06:47 INFO - 'TMOUT': '86400', 16:06:47 INFO - 'USER': 'cltbld', 16:06:47 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', 16:06:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:47 INFO - '_': '/tools/buildbot/bin/python'} 16:06:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:06:48 INFO - Running setup.py (path:/tmp/pip-ez8Oj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:06:48 INFO - Running setup.py (path:/tmp/pip-KHdAu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:06:48 INFO - Running setup.py (path:/tmp/pip-HYnPsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:06:48 INFO - Running setup.py (path:/tmp/pip-eMmOUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:06:48 INFO - Running setup.py (path:/tmp/pip-ENoVsL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:06:48 INFO - Running setup.py (path:/tmp/pip-yGieyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:06:48 INFO - Running setup.py (path:/tmp/pip-7xwmiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:06:48 INFO - Running setup.py (path:/tmp/pip-4mhhOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:06:48 INFO - Running setup.py (path:/tmp/pip-A7SZ4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:06:48 INFO - Running setup.py (path:/tmp/pip-0Gcy4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:06:48 INFO - Running setup.py (path:/tmp/pip-hgYlmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:06:48 INFO - Running setup.py (path:/tmp/pip-b5LVpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:06:48 INFO - Running setup.py (path:/tmp/pip-Umengh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:06:49 INFO - Running setup.py (path:/tmp/pip-rjC8BH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:06:49 INFO - Running setup.py (path:/tmp/pip-BWrDaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:06:49 INFO - Running setup.py (path:/tmp/pip-0FlUAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:06:49 INFO - Running setup.py (path:/tmp/pip-zMpH3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:06:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:06:49 INFO - Running setup.py install for manifestparser 16:06:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:06:49 INFO - Running setup.py install for mozcrash 16:06:49 INFO - Running setup.py install for mozdebug 16:06:49 INFO - Running setup.py install for mozdevice 16:06:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:06:49 INFO - Running setup.py install for mozfile 16:06:49 INFO - Running setup.py install for mozhttpd 16:06:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:06:49 INFO - Running setup.py install for mozinfo 16:06:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Running setup.py install for mozInstall 16:06:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Running setup.py install for mozleak 16:06:50 INFO - Running setup.py install for mozlog 16:06:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Running setup.py install for moznetwork 16:06:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Running setup.py install for mozprocess 16:06:50 INFO - Running setup.py install for mozprofile 16:06:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Running setup.py install for mozrunner 16:06:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:06:50 INFO - Running setup.py install for mozscreenshot 16:06:50 INFO - Running setup.py install for moztest 16:06:50 INFO - Running setup.py install for mozversion 16:06:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:06:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:06:51 INFO - Cleaning up... 16:06:51 INFO - Return code: 0 16:06:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:06:51 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:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:51 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:06:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:51 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 0x24c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2831a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2835090>, '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 0x2835d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x282ff90>, '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': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', '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:06:51 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:06:51 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:06:51 INFO - Using env: {'DISPLAY': ':0', 16:06:51 INFO - 'HOME': '/home/cltbld', 16:06:51 INFO - 'LANG': 'en_US.UTF-8', 16:06:51 INFO - 'LANGUAGE': 'en_US:en', 16:06:51 INFO - 'LOGNAME': 'cltbld', 16:06:51 INFO - 'MAIL': '/var/mail/cltbld', 16:06:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:51 INFO - 'MOZ_NO_REMOTE': '1', 16:06:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:51 INFO - 'NO_EM_RESTART': '1', 16:06:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:51 INFO - 'PWD': '/builds/slave/test', 16:06:51 INFO - 'SHELL': '/bin/bash', 16:06:51 INFO - 'SHLVL': '1', 16:06:51 INFO - 'TERM': 'linux', 16:06:51 INFO - 'TMOUT': '86400', 16:06:51 INFO - 'USER': 'cltbld', 16:06:51 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', 16:06:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:51 INFO - '_': '/tools/buildbot/bin/python'} 16:06:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:06:51 INFO - Running setup.py (path:/tmp/pip-WeJ8gN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:06:51 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:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:06:51 INFO - Running setup.py (path:/tmp/pip-rP0LFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:06:51 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:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:06:51 INFO - Running setup.py (path:/tmp/pip-sbcFGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:06:51 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:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:06:51 INFO - Running setup.py (path:/tmp/pip-jv8wtQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:06:51 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:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:06:51 INFO - Running setup.py (path:/tmp/pip-oeggJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:06:51 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:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:06:51 INFO - Running setup.py (path:/tmp/pip-hCVENs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:06:51 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:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:06:51 INFO - Running setup.py (path:/tmp/pip-8pwqz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:06:51 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:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:06:51 INFO - Running setup.py (path:/tmp/pip-xXD99d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:06:51 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:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:06:51 INFO - Running setup.py (path:/tmp/pip-zkuvx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:06:51 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:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:06:51 INFO - Running setup.py (path:/tmp/pip-Yk_9yc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:06:52 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:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:06:52 INFO - Running setup.py (path:/tmp/pip-jNVuot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:06:52 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:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:06:52 INFO - Running setup.py (path:/tmp/pip-kcoV42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:06:52 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:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:06:52 INFO - Running setup.py (path:/tmp/pip-QMYg8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:06:52 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:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:06:52 INFO - Running setup.py (path:/tmp/pip-Zak4pO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:06:52 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:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:06:52 INFO - Running setup.py (path:/tmp/pip-MVm0z5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:06:52 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:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:06:52 INFO - Running setup.py (path:/tmp/pip-68UDns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:06:52 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:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:06:52 INFO - Running setup.py (path:/tmp/pip-BDaLVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:06:52 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:06:52 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:06:52 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:06:52 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:06:52 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:06:52 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:06:52 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:06:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:53 INFO - Downloading blessings-1.6.tar.gz 16:06:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:06:53 INFO - Installing collected packages: blessings 16:06:53 INFO - Running setup.py install for blessings 16:06:53 INFO - Successfully installed blessings 16:06:53 INFO - Cleaning up... 16:06:53 INFO - Return code: 0 16:06:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:06:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:06:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:06:54 INFO - Reading from file tmpfile_stdout 16:06:54 INFO - Current package versions: 16:06:54 INFO - argparse == 1.2.1 16:06:54 INFO - blessings == 1.6 16:06:54 INFO - blobuploader == 1.2.4 16:06:54 INFO - docopt == 0.6.1 16:06:54 INFO - functools32 == 3.2.3-2 16:06:54 INFO - jsonschema == 2.5.1 16:06:54 INFO - manifestparser == 1.1 16:06:54 INFO - mozInstall == 1.13 16:06:54 INFO - mozcrash == 1.0 16:06:54 INFO - mozdebug == 0.1 16:06:54 INFO - mozdevice == 0.51 16:06:54 INFO - mozfile == 1.2 16:06:54 INFO - mozhttpd == 0.7 16:06:54 INFO - mozinfo == 0.10 16:06:54 INFO - mozleak == 0.1 16:06:54 INFO - mozlog == 3.5 16:06:54 INFO - moznetwork == 0.27 16:06:54 INFO - mozprocess == 0.25 16:06:54 INFO - mozprofile == 0.28 16:06:54 INFO - mozrunner == 6.13 16:06:54 INFO - mozscreenshot == 0.1 16:06:54 INFO - mozsystemmonitor == 0.3 16:06:54 INFO - moztest == 0.8 16:06:54 INFO - mozversion == 1.4 16:06:54 INFO - psutil == 3.1.1 16:06:54 INFO - requests == 1.2.3 16:06:54 INFO - wsgiref == 0.1.2 16:06:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:54 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 0x24c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2831a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2835090>, '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 0x2835d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x282ff90>, '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': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', '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:06:54 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:06:54 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:06:54 INFO - Using env: {'DISPLAY': ':0', 16:06:54 INFO - 'HOME': '/home/cltbld', 16:06:54 INFO - 'LANG': 'en_US.UTF-8', 16:06:54 INFO - 'LANGUAGE': 'en_US:en', 16:06:54 INFO - 'LOGNAME': 'cltbld', 16:06:54 INFO - 'MAIL': '/var/mail/cltbld', 16:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:54 INFO - 'MOZ_NO_REMOTE': '1', 16:06:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:54 INFO - 'NO_EM_RESTART': '1', 16:06:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:54 INFO - 'PWD': '/builds/slave/test', 16:06:54 INFO - 'SHELL': '/bin/bash', 16:06:54 INFO - 'SHLVL': '1', 16:06:54 INFO - 'TERM': 'linux', 16:06:54 INFO - 'TMOUT': '86400', 16:06:54 INFO - 'USER': 'cltbld', 16:06:54 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', 16:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:54 INFO - '_': '/tools/buildbot/bin/python'} 16:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:54 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:06:54 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:06:54 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:06:54 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:06:54 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:06:54 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:06:54 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:06:54 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:06:54 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:06:54 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:06:55 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:06:55 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:06:55 INFO - Installing collected packages: simplejson 16:06:55 INFO - Running setup.py install for simplejson 16:06:55 INFO - building 'simplejson._speedups' extension 16:06:55 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:06:56 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:06:56 INFO - Successfully installed simplejson 16:06:56 INFO - Cleaning up... 16:06:56 INFO - Return code: 0 16:06:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24c51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2831a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2835090>, '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 0x2835d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x282ff90>, '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': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', '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:06:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:06:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:06:56 INFO - Using env: {'DISPLAY': ':0', 16:06:56 INFO - 'HOME': '/home/cltbld', 16:06:56 INFO - 'LANG': 'en_US.UTF-8', 16:06:56 INFO - 'LANGUAGE': 'en_US:en', 16:06:56 INFO - 'LOGNAME': 'cltbld', 16:06:56 INFO - 'MAIL': '/var/mail/cltbld', 16:06:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:56 INFO - 'MOZ_NO_REMOTE': '1', 16:06:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:56 INFO - 'NO_EM_RESTART': '1', 16:06:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:56 INFO - 'PWD': '/builds/slave/test', 16:06:56 INFO - 'SHELL': '/bin/bash', 16:06:56 INFO - 'SHLVL': '1', 16:06:56 INFO - 'TERM': 'linux', 16:06:56 INFO - 'TMOUT': '86400', 16:06:56 INFO - 'USER': 'cltbld', 16:06:56 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', 16:06:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:56 INFO - '_': '/tools/buildbot/bin/python'} 16:06:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:06:56 INFO - Cleaning up... 16:06:56 INFO - Return code: 0 16:06:56 INFO - Running post-action listener: _resource_record_post_action 16:06:56 INFO - Running post-action listener: _start_resource_monitoring 16:06:56 INFO - Starting resource monitoring. 16:06:56 INFO - [mozharness: 2017-09-02 23:06:56.817461Z] Finished create-virtualenv step (success) 16:06:56 INFO - [mozharness: 2017-09-02 23:06:56.817923Z] Running install step. 16:06:56 INFO - Running pre-action listener: _resource_record_pre_action 16:06:56 INFO - Running main action method: install 16:06:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:06:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:06:57 INFO - Reading from file tmpfile_stdout 16:06:57 INFO - Detecting whether we're running mozinstall >=1.0... 16:06:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:06:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:06:57 INFO - Reading from file tmpfile_stdout 16:06:57 INFO - Output received: 16:06:57 INFO - Usage: mozinstall [options] installer 16:06:57 INFO - Options: 16:06:57 INFO - -h, --help show this help message and exit 16:06:57 INFO - -d DEST, --destination=DEST 16:06:57 INFO - Directory to install application into. [default: 16:06:57 INFO - "/builds/slave/test"] 16:06:57 INFO - --app=APP Application being installed. [default: firefox] 16:06:57 INFO - mkdir: /builds/slave/test/build/application 16:06:57 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:06:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:07:13 INFO - Reading from file tmpfile_stdout 16:07:13 INFO - Output received: 16:07:13 INFO - /builds/slave/test/build/application/firefox/firefox 16:07:13 INFO - Running post-action listener: _resource_record_post_action 16:07:13 INFO - [mozharness: 2017-09-02 23:07:13.656833Z] Finished install step (success) 16:07:13 INFO - [mozharness: 2017-09-02 23:07:13.656979Z] Running setup-mitmproxy step. 16:07:13 INFO - Running pre-action listener: _resource_record_pre_action 16:07:13 INFO - Running main action method: setup_mitmproxy 16:07:13 INFO - Skipping: mitmproxy is not required 16:07:13 INFO - Running post-action listener: _resource_record_post_action 16:07:13 INFO - [mozharness: 2017-09-02 23:07:13.658267Z] Finished setup-mitmproxy step (success) 16:07:13 INFO - [mozharness: 2017-09-02 23:07:13.658574Z] Running run-tests step. 16:07:13 INFO - Running pre-action listener: _resource_record_pre_action 16:07:13 INFO - Running pre-action listener: _set_gcov_prefix 16:07:13 INFO - Running main action method: run_tests 16:07:13 WARNING - Try message not found. 16:07:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:07:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:07:13 INFO - Python 2.7.3 16:07:13 INFO - Return code: 0 16:07:13 INFO - grabbing minidump binary from tooltool 16:07:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:07:13 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 0x2835090>, '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 0x2835d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x282ff90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:07:13 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:07:13 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:07:13 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:07:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:07:13 INFO - Return code: 0 16:07:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:07:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:07:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:07:13 INFO - ENV: RUST_BACKTRACE is now full 16:07:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:07:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:07:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-081', '--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:07:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-081 --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:07:13 INFO - Using env: {'DISPLAY': ':0', 16:07:13 INFO - 'HOME': '/home/cltbld', 16:07:13 INFO - 'LANG': 'en_US.UTF-8', 16:07:13 INFO - 'LANGUAGE': 'en_US:en', 16:07:13 INFO - 'LOGNAME': 'cltbld', 16:07:13 INFO - 'MAIL': '/var/mail/cltbld', 16:07:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:07:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:07:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:13 INFO - 'MOZ_NO_REMOTE': '1', 16:07:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:07:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:07:13 INFO - 'NO_EM_RESTART': '1', 16:07:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:07:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:13 INFO - 'PWD': '/builds/slave/test', 16:07:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:07:13 INFO - 'RUST_BACKTRACE': 'full', 16:07:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:07:13 INFO - 'SHELL': '/bin/bash', 16:07:13 INFO - 'SHLVL': '1', 16:07:13 INFO - 'STYLO_FORCE_ENABLED': '1', 16:07:13 INFO - 'STYLO_THREADS': '1', 16:07:13 INFO - 'TERM': 'linux', 16:07:13 INFO - 'TMOUT': '86400', 16:07:13 INFO - 'USER': 'cltbld', 16:07:13 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', 16:07:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:13 INFO - '_': '/tools/buildbot/bin/python'} 16:07:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-081', '--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:07:14 INFO - mozversion application_buildid: 20170902220034 16:07:14 INFO - mozversion application_changeset: f5d99e9f3d7bf19c8a222db97bedc924da27fd58 16:07:14 INFO - mozversion application_display_name: Nightly 16:07:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:07:14 INFO - mozversion application_name: Firefox 16:07:14 INFO - mozversion application_remotingname: firefox 16:07:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:07:14 INFO - mozversion application_vendor: Mozilla 16:07:14 INFO - mozversion application_version: 57.0a1 16:07:14 INFO - mozversion platform_buildid: 20170902220034 16:07:14 INFO - mozversion platform_changeset: f5d99e9f3d7bf19c8a222db97bedc924da27fd58 16:07:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:07:14 INFO - mozversion platform_version: 57.0a1 16:07:14 INFO - using testdate: 1504393634 16:07:14 INFO - actual date: 1504393634 16:07:14 INFO - starting webserver on 'localhost:52889' 16:07:14 INFO - SUITE-START | Running 2 tests 16:07:14 INFO - TEST-START | dromaeo_css 16:07:14 INFO - Initialising browser for dromaeo_css test... 16:07:14 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52889/getInfo.html -profile /tmp/tmpZpghiS/profile 16:07:14 INFO - TEST-INFO | started process 5781 (/builds/slave/test/build/application/firefox/firefox http://localhost:52889/getInfo.html) 16:07:20 INFO - TEST-INFO | 5781: exit 0 16:07:20 INFO - Browser initialized. 16:07:20 INFO - Running cycle 1/1 for dromaeo_css test... 16:07:20 INFO - Using env: {'DISPLAY': ':0', 16:07:20 INFO - 'HOME': '/home/cltbld', 16:07:20 INFO - 'JSGC_DISABLE_POISONING': '1', 16:07:20 INFO - 'LANG': 'en_US.UTF-8', 16:07:20 INFO - 'LANGUAGE': 'en_US:en', 16:07:20 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:07:20 INFO - 'LOGNAME': 'cltbld', 16:07:20 INFO - 'MAIL': '/var/mail/cltbld', 16:07:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:07:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:07:20 INFO - 'MOZ_CRASHREPORTER': '1', 16:07:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:20 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:07:20 INFO - 'MOZ_NO_REMOTE': '1', 16:07:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:07:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:07:20 INFO - 'NO_EM_RESTART': '1', 16:07:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:07:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:20 INFO - 'PWD': '/builds/slave/test', 16:07:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:07:20 INFO - 'RUST_BACKTRACE': 'full', 16:07:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:07:20 INFO - 'SHELL': '/bin/bash', 16:07:20 INFO - 'SHLVL': '1', 16:07:20 INFO - 'STYLO_FORCE_ENABLED': '1', 16:07:20 INFO - 'STYLO_THREADS': '1', 16:07:20 INFO - 'TERM': 'linux', 16:07:20 INFO - 'TMOUT': '86400', 16:07:20 INFO - 'USER': 'cltbld', 16:07:20 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', 16:07:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:20 INFO - '_': '/tools/buildbot/bin/python'} 16:07:20 INFO - TEST-INFO | started process 5969 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZpghiS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:07:21 INFO - PID 5969 | 16:07:21 INFO - PID 5969 | (/builds/slave/test/build/application/firefox/firefox:6021): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:07:21 INFO - PID 5969 | 16:07:31 INFO - PID 5969 | RSS: Main: 167092224 16:07:31 INFO - PID 5969 | 16:10:30 INFO - PID 5969 | Cycle 1(1): loaded http://localhost:52889/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52889/tests/dromaeo/cssquery-ext.html) 16:10:31 INFO - PID 5969 | RSS: Main: 173461504 16:10:31 INFO - PID 5969 | 16:13:33 INFO - PID 5969 | Cycle 1(1): loaded http://localhost:52889/tests/dromaeo/cssquery-ext.html (next: http://localhost:52889/tests/dromaeo/cssquery-jquery.html) 16:13:34 INFO - PID 5969 | RSS: Main: 169500672 16:13:34 INFO - PID 5969 | 16:16:35 INFO - PID 5969 | Cycle 1(1): loaded http://localhost:52889/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52889/tests/dromaeo/cssquery-mootools.html) 16:16:36 INFO - PID 5969 | RSS: Main: 172126208 16:16:36 INFO - PID 5969 | 16:19:38 INFO - PID 5969 | Cycle 1(1): loaded http://localhost:52889/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52889/tests/dromaeo/cssquery-prototype.html) 16:19:38 INFO - PID 5969 | RSS: Main: 171114496 16:19:38 INFO - PID 5969 | 16:22:42 INFO - PID 5969 | Cycle 1(1): loaded http://localhost:52889/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52889/tests/dromaeo/cssquery-yui.html) 16:22:43 INFO - PID 5969 | RSS: Main: 172040192 16:22:43 INFO - PID 5969 | 16:25:44 INFO - PID 5969 | Cycle 1(1): loaded http://localhost:52889/tests/dromaeo/cssquery-yui.html (next: http://localhost:52889/tests/dromaeo/cssquery-dojo.html) 16:25:45 INFO - PID 5969 | RSS: Main: 174440448 16:25:45 INFO - PID 5969 | 16:25:45 INFO - PID 5969 | __start_tp_report 16:25:45 INFO - PID 5969 | _x_x_mozilla_page_load 16:25:45 INFO - PID 5969 | _x_x_mozilla_page_load_details 16:25:45 INFO - PID 5969 | |i|pagename|runs| 16:25:45 INFO - PID 5969 | |0;dojo.html;3280.212748940255;3327.750418718597;3348.430766769649;3374;3395.2360718838263;6277.748890044399;6323.399277068677;6334.841628959272;6353.968230158843;6480.773172938948;6462.741490340381;6486.448651864597;6590.044443555682;6590.308017658137;6604.207495100594;5901.05583106703;5959.165716799652;6000.489958353545;6018.729157187915;6071.423214794588;5640.407757185498;5827.096799996008;5879.353271140192;5967.224260846084;5977.910331345011;5847.649141051529;5909.822705318826;5923.318818335903;5929.851753706149;5940.2277703898435;5828.735907891594;5858.882822343572;5924.9703751480965;5962.970185149046;5965.4631083202285;5677.063284558043;5711.05767548354;5731.885362292773;5738.885222295572;5751.482366586985;6181.412765787243;6230.532710046719;6239.469645080174;6246.812595622138;6248.718807653667;6667.666616669196;6676.399124078856;6680.86638267232;6687.632180230085;6687.732490700366;6689.632070236135;6858.142732158481;6874;6894.931050689479;7030.191527974246;6422.133012043361;6501.902471462932;6506.024096385533;6513.185851768529;6515.696860627893;7067.646617669096;7077.433805295667;7081.433485321162;7191.604461754549;7192.7122915083855;6599.835004124935;6659.467242620579;6665.033570132304;6665.366790154928;6684.832879177962;6859.725610975505;7000.439964802804;7004.894926576106;7007.369336759716;7009.544379615309;6924.446044316443;7017.7192912282935;7018.789436316919;7032.824179395454;7036.753713619999;6923.238443771181;6961.060256865258;7026.543274687129;7039.26087760788;7041.647917604099;5289.68261904287;5352.652077614943;5421.26812880264;5500.80747173855;5501.889962200734;4694.600958918462;4772.522747725269;4799.28010798383;4831.589314908272;4842.201036828884;6058.303295121117;6103.664298463627;6121.724522396503;6143.6928153593035;6147.0779383092895;6956.930430695729;6957.617331046841;7038.788836334918;7062.858742825115;7074.540154889915;5886.175935369051;5995.130706047598;6033.336333003366;6064.4845188158615;6138.600990935575;5984.880302393928;5996.220491336186;5999.370066143079;6003.7898673546215;6008.188894499274;6710.362515561014;6742.629155396402;6796.694148763318;6801;6808.285130061371;6945.40964018054;6966.442684585119;6969.860602788017;7050.717971281091;7067.823304417225;6579.671016449254;6647.767328143492;6682.665866706742;6772.492063095386;6802.8639427212165;6767.526273161028;6784.830379240559;6798.626075565693;6811.2167100784045;6836;6879.862402752017;6892.310768923268;6897.586144831326;6927.861442771015;6963.7562685305775;6614.801555953329;6631.104800851823;6671.199456065303;6692.799216023527;6708.865822683421;6405.679716014274;6446.258680251643;6448.838779030374;6452.9677351612945;6513.8697226056165;6497.220333560003;6520.706568204537;6539.771108011197;6598.637074960923;6616.338366163345;6754.459643228432;6788.59268443895;6801.081853949653;6837.35045170708;6855.485838562028;6514.804555863332;6517.771877984248;6605.273419923708;6607.438367738604;6630.30381809903 16:25:45 INFO - PID 5969 | |1;ext.html;782.2881178127901;851.8296340731855;863.2230992107103;890.9064548222433;891.5319457284925;39582.80208598956;40200.195996080096;40276.798616006876;40352.59647403529;40406;36120;36292.455613165825;36403;36415.81792091043;36470.452943205804;6831.6242606656615;6873.106496155493;6876.037354770337;6877.552959057671;6878.69045892936;6841.315868413169;6927.584344939294;6931.7920462385955;6932.445404367663;6934.583924964519;7469.626518674072;7663.540187588735;7665.5783931883725;7670.923290767077;7674.5395276283325;7566.39468842494;7640.923590764077;7641.2740789624895;7647.42644301674;7649.961750191242;7316.524425912298;7483.251674832528;7548.8112797179965;7553.773386798405;7558.659860306299;37080.81459592712;37355;37360;37372.25255494875;37444.43833342502;3535.5580552430947;3589.9948014555966;3593.1915319053264;3594.5686517618055;3595.137167079908;30591.235219119477;30602.08193754191;30662.08013759591;30670.84664576663;30691.386172276652;5185.833187532813;5199.948000519984;5234.057869583474;5272.182811664198;5273.86815329616;185832;187751;189468;193909;208914.9999999985;48941.265881011816;49212.26181607279;49233;49284.50715492871;49331.50668493341;50425.747871260406;50454.74772626187;50526.747366262935;50531.49468505341;50646.74676626593;15081.095134291978;15109.39562417491;15190.620234494005;15195.468158614396;15223.61940951463;11989.940050299692;12004.219725717976;12009.279443233434;12030.87969120315;12076.516939322504;14221.85778142226;14272.14367137975;14274.714505709828;14333.928330358282;14413.639659008608;5783.392743761929;5843.883122337529;5851.707414629337;5899.911501327483;5908.793192238251;1013.503383342157;1019.2814066083394;1030.8917563655857;1031.2781053262745;1040.5837664933936;22453.101875925106;23318.883405582863;28513.857430713128;28976.565351519745;29431.85284073566;1119.6976816259728;1121.1479275750326;1123.224974767408;1123.2474242257706;1137.294877176156;12525.43635536403;12641.810372844415;12752.489900404065;12848.807267890988;12895.226286422658;234454;235546;235740;236189;236673;51200.48799511957;51256.74371628118;51509.74245128825;51566;51575;1433.276195521276;1444.4155321311077;1468.6548661064642;1489.2925860216528;1494.1931357067278;22660.546789064458;22681.865906704268;22728;22980.885095575082;23105.075796967933;37206.6279337214;37279.25441491101;37511.6248837519;37574.624253757116;37584.812075939444;203922;203948;204224;204267;204339;33184;36524.26951461009;37171;37248;37291.81354093212;44732.55267447284;44820.55179448294;44857;45012;45231.77384113058;33262.833685831414;33336;33635.49546756752;34185;34188.48717269193;33233.335333292714;33285.66714332825;33399.49900751539;33422;33746.66253337435;36251.45622815713;36622;36773.26453470969;37286.25427491489;37387.06532336605;32414.675853241166;32586.51120233151;32624.83687581547;32893.50659740154;32925.34149316952;23150.380631473854;24301.27096187117;24704.629430558198;24759.50480990334;25307.240782776546 16:25:45 INFO - PID 5969 | |2;jquery.html;556.3435146527096;565.5560385097699;570.6804189653794;575.2205761193583;585.3531847308724;21939.451513712163;22723.091076356948;22726.65910011349;22745.317640470814;23720.169794057212;24003.519929601396;24957.8768955397;24970.126045588408;24987.750122498815;24990.625140622906;6185.195924529828;6451.548391612589;6610.636414997197;6613.834654133661;6617.569857959242;6376.808695739135;6434.195725534309;6440.355964403569;6441.420272175504;6442.549021568492;6401.871962560746;6402.93597064028;6407.878621241264;6410.423061924426;6411.647359395231;6394;6397.360263973612;6402.807915762534;6404.039394090877;6406.551541392105;5922.940770592325;5930.525557955353;5931.614445061057;5932.3177834548715;5944.762209511614;27559.724402756117;28663;28771.9929802458;28785.280367990967;28799.71200287991;2028.5841402512597;2076.8961551922494;2078.9168433262653;2079.1371580793952;2080.032784755096;18502;18942.715859262124;19105.42683719477;19123.23507059716;19128.139233734517;2323.546908352872;2366.7633236676193;2417.4560723836994;2419.1049311754764;2428.138011006085;131290;134041.6595834039;134370;135772;139360.30319848337;13945.62924302871;14406.855931440552;14467.927660361842;14633.658614621609;14756.48352307664;22215.222467213895;22623.66064509034;23058;23068.423289417562;23115.653265201036;9837.704868853945;9905.2571057171;9928.50357482123;10099.7475063124;10147.797044059078;9233.584488698025;9288.814223715623;9369.250459963187;9394.248460123174;9530.142287194047;8965.775855603531;9006.819863602692;9087.591058402388;9234.1689247968;9531.142197202285;2557.667503224569;2559.436923876744;2568.29371922719;2571.7299683533333;2734.6308248386613;959.822432849911;960.4093482508347;973.0755782007121;984.2962281968427;989.351974456732;19749.506262343555;19764.01179940997;19819.504512387306;19886.90056549737;20259.29092481756;1000.5997600959529;1022.7187523431116;1047.9161667066617;1052.1214785654065;1054.1672079057612;10605.204609654307;10677;10687.679369618769;10933.890661093446;13080.934595327153;491656;502715;503344;503607;507699;118402;122416;122426;122594;122742;1222.2849632964815;1242.8073648584484;1273.9108262421728;1275.8851703346743;1280.097531240492;21456.356309310744;21504;21590.676139858228;21740.78259217451;21819.345419637437;53516.197257040396;54697.453025469236;55741.442585573626;59126.70436647789;59923.700381497816;70124.29875701178;71990.28009720045;72588.91116633361;72646.63676681583;72959.27040729525;14204.502842400467;14774.113553186844;14815.777763333343;14879.10725356482;14963.476278330207;23342.766572334738;23513;23770.049198031884;23829.165979190646;23857.761422386247;18249.726254106463;18408.26366945307;18497.167627457067;18727;18744.812551874307;16511.83488165103;16816.159192040595;16951;16952.915235423745;17291.91354043222;18409.079546022378;18777.155028024044;19255.711164332828;19491.61016779628;19541.70687439718;14496.782548261574;14515.637109071935;14591.416343346571;14599.489017884323;14604;18354;18445;18587.34944276944;18657.160427781055;18984.43046708601 16:25:45 INFO - PID 5969 | |3;mootools.html;1357.2467280659237;1403.158105136918;1420.907641003334;1424.380394528381;1433.4051368681999;15031.098134111948;15405;15673.529794106194;15693.52919412418;15721.449749258778;15948.84051159491;16208.270627821747;16208.513744587652;16208.811810405592;16215.432459863907;2655.74770396812;2717.701052884186;2768.335599456126;2771.8614069296555;2772.9445411091765;2820.323122450607;2846.146156153156;2853.215365774408;2856.1003283965483;2859.1565488181036;2776.6806817215975;2788.4004938938124;2810.0445848411537;2830.037787152368;2830.0519326025806;2684.489946910081;2691.58280466528;2705.8241214321006;2707.8646067696536;2712.308361367858;2606.905099858064;2621.030218819049;2634.7233540478355;2641.4452964877205;2642.5111354399496;14926.925365373212;15293.847061529465;15310.617234569225;15318.617034574225;15340.079595224212;8225.629846656915;8472.915270847336;8476.66093356265;8488.872666910001;8489.787755306104;5479.3424789025175;5611.775528978836;5620.185073164409;5734.996375634289;5742.540596752292;1928.5082304012528;1951.580410211797;1957.2366776956999;1968.9704654430195;2023.5548179400507;26495.867520662272;26763.19710408691;26944.05695800657;26982.5952610711;27047.864760676268;7450.18048014718;7473.962630186923;7549.660265288076;7690.500117492345;7759.18528554505;17277.481675549752;17369;17404.65190696179;17450.47648570545;17469.126543672766;8082.434229603989;8176.5094094353335;8193.79515512117;8198.959005204935;8205.671773129008;7590.848183036308;7599.620018999028;7600.619969001527;7636.198199189116;7696.191899850547;5240.65935714185;5405.621606487587;5422.511973922287;5452.2094296327605;5464.426235245242;1580.3915492535457;1583.200483755719;1585.3420830355503;1607.8070631524292;1626.1218941771547;900.9144131307515;916.8441364967838;929.8605209218535;931.2736065868634;932.2774849491698;7692.653830577638;7780.066392032993;7797.415193860483;7825.608719564113;7829.060512738508;736.3998341351781;740.5408646639119;743.7805847275067;746.5893758432841;752.1538269446872;5395.244665746799;5505;5536.363318218376;5643.661380317194;5664.575156863251;21466.356009319745;21998.45003874916;22313;22393.104275828784;22428.55142897133;22219;22335.329940101823;22335.55328893413;22370.105195792312;22408.55182896333;11055.668329950115;11117.888821111685;11150.99641032311;11462.140339474572;11516.078713703051;8657.696980605648;8765.91234087651;9098.545072746469;9307.301952353466;9315.39449935752;18729.531761706065;18758.812411875708;18786.718199227296;19157.13792879296;19591;19089;19215.711764323827;19258.518537036685;19576.21695132179;19781.70327445118;11574.305541667527;11974.760504789681;11990.940045299718;12013.159078864395;12036.157468977437;14205.431782728989;14342.85657143457;14454.710905782036;14504.564863054125;14512.056716313406;11309.943450282695;11554.017908477874;11597.304161750322;11698.35659038761;11734.119941004288;11268.718282042686;11304.095672346357;11366.943165284121;11416.543338266376;11420.771584568429;9864.210863131077;9918.950405247928;9934;9986.300958932805;10013.899861001588;7531.623418829146;7654.846903061796;7671.846563068596;7707.267809558083;7765.961170194113;7914.683412663429;8045.235702608243;8113.553754543556;8150.673973041012;8157.75526734199 16:25:45 INFO - PID 5969 | |4;prototype.html;503.4817775012564;512.4567957964559;524.6301357542933;530.8301343570057;531.4419958378652;6321.589096708716;6329.620222786631;6385.553011289206;6418.518611104162;6422.389872962061;6254.8123556293385;6282.403171698693;6325.304216536178;6339.492840572755;6392.520560957935;5160.638755287131;5208.8437346879655;5213.739313034352;5252.947470525303;5267.1045922193225;4876.707397556141;4934.310800613656;5144.3826740791155;5155.072087024334;5199.896002079956;4926.802927882881;4936.7778449969655;4987.077390682717;5027.723475208862;5029.572486338666;4917.508249175054;4963.677360971526;4976.726280054578;5022.493252024378;5036.118679231138;4365.607095361401;4458.487273963503;4467.910641787146;4516.683832131752;4533.591976722111;6043.305019922721;6159.876802463926;6204.162438070848;6207.968960155215;6263;5672.659640421588;5692.971535142339;5719.513841323493;5737.569682273805;5772.220750198711;6636.170478690164;6648.235452922928;6651.634160121192;6670.166229221348;6751.459883209332;4897;4913.410390753133;4914.926276105862;4935.950640493621;4961.280614310941;6697.8995315070315;6848.383645471931;6868.501131758756;6871.931280687229;6875.518713690094;5488.7530061147345;5554.250176226238;5558.44415558441;5561.610687251854;5572.5263352614675;5524.254225679482;5591.329040515083;5600.411956744565;5624.803131890364;5640.915386269209;5964.284285885721;5965.194698715618;5983.700814959235;5998.880022399528;6031.9698401508585;6654.6672666366485;6689.364510371507;6690.899636505456;6754.93245067543;6759.628220447824;1946.776120746104;1969.350114462224;1975.259277770836;1988;2000.5598768270956;1391.9512817051354;1419.6450887278181;1456.6940942402214;1459.8321193062934;1481.6221863424757;5787.565932555075;5853.678047707417;5883.940890639726;5908.615939963888;5941.940580594225;6618.801435956929;6671.699773510204;6722.394984451325;6733.326667333227;6745.662716864137;5944.4352786485215;5953.166556682068;5954.255718035246;5982.970085149634;5990.430909063632;3547.52108465359;3586.8923932282073;3593.2633810068874;3602.441621548664;3603.2973570153567;6533.607983520909;6550.574212676161;6610.4050635443045;6634.203895532471;6639.336066393322;6965.338292862271;7012.929870701228;7027.964860175666;7035.964820175969;7070.505064645426;6516.380943810331;6562.212534495891;6579.276279609239;6582.440492558187;6600.73597056122;6698.665066746546;6754.628495432798;6795.38841504267;6797.28628494001;6807.353301436356;6707.564008339442;6757.493188010821;6765.729370825112;6778.93221067783;6791.151106111736;6550.868982620416;6574.901376479261;6598.967005164944;6635.947464166537;6638.734450621921;5468.26178465902;5477.452254774491;5491.972540137273;5519.172124181245;5528.087865502147;6527.967360163168;6547.410733034049;6693.397594216543;6766.729330826712;6792.117024786746;6499.415052645285;6552.63960482159;6583.275839657538;6584.341565843569;6644.136262285871;6518.728847874619;6535.44448721864;6548.770793022221;6558.967205163944;6640.335966403321;6258.687065646791;6501.414872661483;6514.446272066927;6543.672816359258;6608.43828274602;6561.096147792056;6585.407313341822;6596.670166491752;6644.269130395749;6758.831029224309;6392.264889537577;6397.281155508983;6404.327545607644;6441.645709486023;6505.8048258550425 16:25:45 INFO - PID 5969 | |5;yui.html;921.2537844346084;930.534732633683;936.7470782888616;947.625687853298;963.0340043430944;18976.90511547442;19733.11200995955;19855.40433786987;20112.195512179496;20127.99999999998;20492;21078.683819742717;21348.25281115161;21364.359069227947;21370.786292137072;966.3718582921123;988.5057471264367;991.023841516106;991.6083147156869;992.7915137821067;964.7154089543567;994.2741798487109;996.1134590214715;1001.9499025048718;1009.7021378693303;1074.1889873145765;1076.8976947190004;1077.9299345542515;1077.9730506737317;1078.730317420645;1073.4847273308817;1073.7798751256014;1073.978520429587;1074.591655171038;1075.3924032921414;785.9960700196463;787.0594639405912;787.5078076202374;787.9093904200976;787.9763607091796;16488.17559122039;17234.396796112076;19795.80204197954;19798.307059252857;19820.108095135754;20932.5813483731;21033.5793284135;21416.357509274745;21425.464363390885;21713.891430542903;16450.506484805475;16618.50144495655;16638.584035399213;16679.91660041704;16761.580960476087;3765.8305376258136;3784.299904517673;3784.413415920537;3790.8673196438267;3802.2015376770637;126063.369683151;127001;130314.99999999905;130326;132764.33617831947;15668.138252396;16009.519714408587;16070.919645401698;16077.598060048358;16170.110643914697;29981.700182998327;30257.394852102836;30363.392732145676;30417.23956901095;30639.540406893913;7627.809304767315;8032.638531266107;8903.821923561492;8924.107589241154;9340.953295233481;4303.440552728125;4380.189664912002;4381.583749543788;4388.824447022083;4389.956100438954;5065.265536497187;5146.330976972969;5179.223116532475;5182.870428239325;5201.45384734605;1079.7354648111263;1110.1840147491587;1113.5100555755603;1121.85415895933;1121.9046381057537;708.7696498637963;721.0338146883201;727.0766127018657;735.7645553422853;742.3244847189032;11795.823062654066;12301.93849030767;12343.429515900854;12793.61619151427;12856.935715321364;695.0581961442274;704.6371118873784;714.292850078429;720.989185162223;722.5772922840092;5808.854778630483;5808.867270882222;5877.471027607536;5936.37668044849;6039.184710064173;112747;114655;115387;115489;118674.40662796631;96394.51802741081;96644;97229.51385243029;98576;98750.01249987552;83049;83209.16790832015;84347.99999999878;86713.56643216869;87226.1277387218;10824.4046577439;11045.33727976324;11251.662450126509;11269.267497612629;13052.34738263064;40644.796776017116;41140.794296028325;41312;41719.58280417157;43182.56817431786;69857.65071174612;70841.64579177072;71591;72965.6351718238;74301.25698742943;15437.459688910834;17971.281148753904;19109.426717198505;19155.32956346577;19752.703709444657;19271.90364048171;19385.224591016202;19400.320988765325;21379.572408552052;21504;18763.155657995696;19194;19320.227190912206;19399;19848.80151198527;18308.450746477625;18638.720419193993;18982.525436864187;19114.904425477784;19211.231550737815;18044.2782288707;18296.634067318846;18657.346992855186;19376.61246775085;19633;13113.27876966776;13214.141080829715;13375.732485350432;13535.458581656623;13664.496905340206;14209.715805683622;14619.19594422317;14778;14929.178895160436;15561 16:25:45 INFO - PID 5969 | __end_tp_report 16:25:45 INFO - PID 5969 | __start_cc_report 16:25:45 INFO - PID 5969 | _x_x_mozilla_cycle_collect,357 16:25:45 INFO - PID 5969 | __end_cc_report 16:25:45 INFO - PID 5969 | __startTimestamp1504394745077__endTimestamp 16:25:45 INFO - PID 5969 | 16:25:45 INFO - PID 5969 | ------- Summary: start ------- 16:25:45 INFO - PID 5969 | Number of tests: 6 16:25:45 INFO - PID 5969 | 16:25:45 INFO - PID 5969 | [#0] dojo.html Cycles:165 Average:6341.06 Median:6579.67 stddev:748.23 (11.4%) stddev-sans-first:710.96 16:25:45 INFO - PID 5969 | Values: 3280.2 3327.8 3348.4 3374.0 3395.2 6277.7 6323.4 6334.8 6354.0 6480.8 6462.7 6486.4 6590.0 6590.3 6604.2 5901.1 5959.2 6000.5 6018.7 6071.4 5640.4 5827.1 5879.4 5967.2 5977.9 5847.6 5909.8 5923.3 5929.9 5940.2 5828.7 5858.9 5925.0 5963.0 5965.5 5677.1 5711.1 5731.9 5738.9 5751.5 6181.4 6230.5 6239.5 6246.8 6248.7 6667.7 6676.4 6680.9 6687.6 6687.7 6689.6 6858.1 6874.0 6894.9 7030.2 6422.1 6501.9 6506.0 6513.2 6515.7 7067.6 7077.4 7081.4 7191.6 7192.7 6599.8 6659.5 6665.0 6665.4 6684.8 6859.7 7000.4 7004.9 7007.4 7009.5 6924.4 7017.7 7018.8 7032.8 7036.8 6923.2 6961.1 7026.5 7039.3 7041.6 5289.7 5352.7 5421.3 5500.8 5501.9 4694.6 4772.5 4799.3 4831.6 4842.2 6058.3 6103.7 6121.7 6143.7 6147.1 6956.9 6957.6 7038.8 7062.9 7074.5 5886.2 5995.1 6033.3 6064.5 6138.6 5984.9 5996.2 5999.4 6003.8 6008.2 6710.4 6742.6 6796.7 6801.0 6808.3 6945.4 6966.4 6969.9 7050.7 7067.8 6579.7 6647.8 6682.7 6772.5 6802.9 6767.5 6784.8 6798.6 6811.2 6836.0 6879.9 6892.3 6897.6 6927.9 6963.8 6614.8 6631.1 6671.2 6692.8 6708.9 6405.7 6446.3 6448.8 6453.0 6513.9 6497.2 6520.7 6539.8 6598.6 6616.3 6754.5 6788.6 6801.1 6837.4 6855.5 6514.8 6517.8 6605.3 6607.4 6630.3 16:25:45 INFO - PID 5969 | 16:25:45 INFO - PID 5969 | [#1] ext.html Cycles:180 Average:37991.48 Median:19857.57 stddev:54896.39 (276.5%) stddev-sans-first:54979.29 16:25:45 INFO - PID 5969 | Values: 782.3 851.8 863.2 890.9 891.5 39582.8 40200.2 40276.8 40352.6 40406.0 36120.0 36292.5 36403.0 36415.8 36470.5 6831.6 6873.1 6876.0 6877.6 6878.7 6841.3 6927.6 6931.8 6932.4 6934.6 7469.6 7663.5 7665.6 7670.9 7674.5 7566.4 7640.9 7641.3 7647.4 7650.0 7316.5 7483.3 7548.8 7553.8 7558.7 37080.8 37355.0 37360.0 37372.3 37444.4 3535.6 3590.0 3593.2 3594.6 3595.1 30591.2 30602.1 30662.1 30670.8 30691.4 5185.8 5199.9 5234.1 5272.2 5273.9 185832.0 187751.0 189468.0 193909.0 208915.0 48941.3 49212.3 49233.0 49284.5 49331.5 50425.7 50454.7 50526.7 50531.5 50646.7 15081.1 15109.4 15190.6 15195.5 15223.6 11989.9 12004.2 12009.3 12030.9 12076.5 14221.9 14272.1 14274.7 14333.9 14413.6 5783.4 5843.9 5851.7 5899.9 5908.8 1013.5 1019.3 1030.9 1031.3 1040.6 22453.1 23318.9 28513.9 28976.6 29431.9 1119.7 1121.1 1123.2 1123.2 1137.3 12525.4 12641.8 12752.5 12848.8 12895.2 234454.0 235546.0 235740.0 236189.0 236673.0 51200.5 51256.7 51509.7 51566.0 51575.0 1433.3 1444.4 1468.7 1489.3 1494.2 22660.5 22681.9 22728.0 22980.9 23105.1 37206.6 37279.3 37511.6 37574.6 37584.8 203922.0 203948.0 204224.0 204267.0 204339.0 33184.0 36524.3 37171.0 37248.0 37291.8 44732.6 44820.6 44857.0 45012.0 45231.8 33262.8 33336.0 33635.5 34185.0 34188.5 33233.3 33285.7 33399.5 33422.0 33746.7 36251.5 36622.0 36773.3 37286.3 37387.1 32414.7 32586.5 32624.8 32893.5 32925.3 23150.4 24301.3 24704.6 24759.5 25307.2 16:25:45 INFO - PID 5969 | 16:25:45 INFO - PID 5969 | [#2] jquery.html Cycles:180 Average:35258.51 Median:12719.60 stddev:84459.15 (664.0%) stddev-sans-first:84655.89 16:25:45 INFO - PID 5969 | Values: 556.3 565.6 570.7 575.2 585.4 21939.5 22723.1 22726.7 22745.3 23720.2 24003.5 24957.9 24970.1 24987.8 24990.6 6185.2 6451.5 6610.6 6613.8 6617.6 6376.8 6434.2 6440.4 6441.4 6442.5 6401.9 6402.9 6407.9 6410.4 6411.6 6394.0 6397.4 6402.8 6404.0 6406.6 5922.9 5930.5 5931.6 5932.3 5944.8 27559.7 28663.0 28772.0 28785.3 28799.7 2028.6 2076.9 2078.9 2079.1 2080.0 18502.0 18942.7 19105.4 19123.2 19128.1 2323.5 2366.8 2417.5 2419.1 2428.1 131290.0 134041.7 134370.0 135772.0 139360.3 13945.6 14406.9 14467.9 14633.7 14756.5 22215.2 22623.7 23058.0 23068.4 23115.7 9837.7 9905.3 9928.5 10099.7 10147.8 9233.6 9288.8 9369.3 9394.2 9530.1 8965.8 9006.8 9087.6 9234.2 9531.1 2557.7 2559.4 2568.3 2571.7 2734.6 959.8 960.4 973.1 984.3 989.4 19749.5 19764.0 19819.5 19886.9 20259.3 1000.6 1022.7 1047.9 1052.1 1054.2 10605.2 10677.0 10687.7 10933.9 13080.9 491656.0 502715.0 503344.0 503607.0 507699.0 118402.0 122416.0 122426.0 122594.0 122742.0 1222.3 1242.8 1273.9 1275.9 1280.1 21456.4 21504.0 21590.7 21740.8 21819.3 53516.2 54697.5 55741.4 59126.7 59923.7 70124.3 71990.3 72588.9 72646.6 72959.3 14204.5 14774.1 14815.8 14879.1 14963.5 23342.8 23513.0 23770.0 23829.2 23857.8 18249.7 18408.3 18497.2 18727.0 18744.8 16511.8 16816.2 16951.0 16952.9 17291.9 18409.1 18777.2 19255.7 19491.6 19541.7 14496.8 14515.6 14591.4 14599.5 14604.0 18354.0 18445.0 18587.3 18657.2 18984.4 16:25:45 INFO - PID 5969 | 16:25:45 INFO - PID 5969 | [#3] mootools.html Cycles:180 Average:9604.27 Median:14817.82 stddev:6807.44 (45.9%) stddev-sans-first:6798.34 16:25:45 INFO - PID 5969 | Values: 1357.2 1403.2 1420.9 1424.4 1433.4 15031.1 15405.0 15673.5 15693.5 15721.4 15948.8 16208.3 16208.5 16208.8 16215.4 2655.7 2717.7 2768.3 2771.9 2772.9 2820.3 2846.1 2853.2 2856.1 2859.2 2776.7 2788.4 2810.0 2830.0 2830.1 2684.5 2691.6 2705.8 2707.9 2712.3 2606.9 2621.0 2634.7 2641.4 2642.5 14926.9 15293.8 15310.6 15318.6 15340.1 8225.6 8472.9 8476.7 8488.9 8489.8 5479.3 5611.8 5620.2 5735.0 5742.5 1928.5 1951.6 1957.2 1969.0 2023.6 26495.9 26763.2 26944.1 26982.6 27047.9 7450.2 7474.0 7549.7 7690.5 7759.2 17277.5 17369.0 17404.7 17450.5 17469.1 8082.4 8176.5 8193.8 8199.0 8205.7 7590.8 7599.6 7600.6 7636.2 7696.2 5240.7 5405.6 5422.5 5452.2 5464.4 1580.4 1583.2 1585.3 1607.8 1626.1 900.9 916.8 929.9 931.3 932.3 7692.7 7780.1 7797.4 7825.6 7829.1 736.4 740.5 743.8 746.6 752.2 5395.2 5505.0 5536.4 5643.7 5664.6 21466.4 21998.5 22313.0 22393.1 22428.6 22219.0 22335.3 22335.6 22370.1 22408.6 11055.7 11117.9 11151.0 11462.1 11516.1 8657.7 8765.9 9098.5 9307.3 9315.4 18729.5 18758.8 18786.7 19157.1 19591.0 19089.0 19215.7 19258.5 19576.2 19781.7 11574.3 11974.8 11990.9 12013.2 12036.2 14205.4 14342.9 14454.7 14504.6 14512.1 11309.9 11554.0 11597.3 11698.4 11734.1 11268.7 11304.1 11366.9 11416.5 11420.8 9864.2 9919.0 9934.0 9986.3 10013.9 7531.6 7654.8 7671.8 7707.3 7766.0 7914.7 8045.2 8113.6 8150.7 8157.8 16:25:45 INFO - PID 5969 | 16:25:45 INFO - PID 5969 | [#4] prototype.html Cycles:180 Average:5633.15 Median:6260.84 stddev:1526.40 (24.4%) stddev-sans-first:1481.33 16:25:45 INFO - PID 5969 | Values: 503.5 512.5 524.6 530.8 531.4 6321.6 6329.6 6385.6 6418.5 6422.4 6254.8 6282.4 6325.3 6339.5 6392.5 5160.6 5208.8 5213.7 5252.9 5267.1 4876.7 4934.3 5144.4 5155.1 5199.9 4926.8 4936.8 4987.1 5027.7 5029.6 4917.5 4963.7 4976.7 5022.5 5036.1 4365.6 4458.5 4467.9 4516.7 4533.6 6043.3 6159.9 6204.2 6208.0 6263.0 5672.7 5693.0 5719.5 5737.6 5772.2 6636.2 6648.2 6651.6 6670.2 6751.5 4897.0 4913.4 4914.9 4936.0 4961.3 6697.9 6848.4 6868.5 6871.9 6875.5 5488.8 5554.3 5558.4 5561.6 5572.5 5524.3 5591.3 5600.4 5624.8 5640.9 5964.3 5965.2 5983.7 5998.9 6032.0 6654.7 6689.4 6690.9 6754.9 6759.6 1946.8 1969.4 1975.3 1988.0 2000.6 1392.0 1419.6 1456.7 1459.8 1481.6 5787.6 5853.7 5883.9 5908.6 5941.9 6618.8 6671.7 6722.4 6733.3 6745.7 5944.4 5953.2 5954.3 5983.0 5990.4 3547.5 3586.9 3593.3 3602.4 3603.3 6533.6 6550.6 6610.4 6634.2 6639.3 6965.3 7012.9 7028.0 7036.0 7070.5 6516.4 6562.2 6579.3 6582.4 6600.7 6698.7 6754.6 6795.4 6797.3 6807.4 6707.6 6757.5 6765.7 6778.9 6791.2 6550.9 6574.9 6599.0 6635.9 6638.7 5468.3 5477.5 5492.0 5519.2 5528.1 6528.0 6547.4 6693.4 6766.7 6792.1 6499.4 6552.6 6583.3 6584.3 6644.1 6518.7 6535.4 6548.8 6559.0 6640.3 6258.7 6501.4 6514.4 6543.7 6608.4 6561.1 6585.4 6596.7 6644.3 6758.8 6392.3 6397.3 6404.3 6441.6 6505.8 16:25:45 INFO - PID 5969 | 16:25:45 INFO - PID 5969 | [#5] yui.html Cycles:180 Average:24114.50 Median:20712.29 stddev:32801.52 (158.4%) stddev-sans-first:32847.30 16:25:45 INFO - PID 5969 | Values: 921.3 930.5 936.7 947.6 963.0 18976.9 19733.1 19855.4 20112.2 20128.0 20492.0 21078.7 21348.3 21364.4 21370.8 966.4 988.5 991.0 991.6 992.8 964.7 994.3 996.1 1001.9 1009.7 1074.2 1076.9 1077.9 1078.0 1078.7 1073.5 1073.8 1074.0 1074.6 1075.4 786.0 787.1 787.5 787.9 788.0 16488.2 17234.4 19795.8 19798.3 19820.1 20932.6 21033.6 21416.4 21425.5 21713.9 16450.5 16618.5 16638.6 16679.9 16761.6 3765.8 3784.3 3784.4 3790.9 3802.2 126063.4 127001.0 130315.0 130326.0 132764.3 15668.1 16009.5 16070.9 16077.6 16170.1 29981.7 30257.4 30363.4 30417.2 30639.5 7627.8 8032.6 8903.8 8924.1 9341.0 4303.4 4380.2 4381.6 4388.8 4390.0 5065.3 5146.3 5179.2 5182.9 5201.5 1079.7 1110.2 1113.5 1121.9 1121.9 708.8 721.0 727.1 735.8 742.3 11795.8 12301.9 12343.4 12793.6 12856.9 695.1 704.6 714.3 721.0 722.6 5808.9 5808.9 5877.5 5936.4 6039.2 112747.0 114655.0 115387.0 115489.0 118674.4 96394.5 96644.0 97229.5 98576.0 98750.0 83049.0 83209.2 84348.0 86713.6 87226.1 10824.4 11045.3 11251.7 11269.3 13052.3 40644.8 41140.8 41312.0 41719.6 43182.6 69857.7 70841.6 71591.0 72965.6 74301.3 15437.5 17971.3 19109.4 19155.3 19752.7 19271.9 19385.2 19400.3 21379.6 21504.0 18763.2 19194.0 19320.2 19399.0 19848.8 18308.5 18638.7 18982.5 19114.9 19211.2 18044.3 18296.6 18657.3 19376.6 19633.0 13113.3 13214.1 13375.7 13535.5 13664.5 14209.7 14619.2 14778.0 14929.2 15561.0 16:25:45 INFO - PID 5969 | -------- Summary: end -------- 16:25:45 INFO - PID 5969 | 16:25:45 INFO - TEST-INFO | 5969: exit 0 16:25:45 INFO - TEST-OK | dromaeo_css | took 1111825ms 16:25:45 INFO - TEST-START | kraken 16:25:45 INFO - Initialising browser for kraken test... 16:25:45 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52889/getInfo.html -profile /tmp/tmpJJsJNY/profile 16:25:45 INFO - TEST-INFO | started process 6347 (/builds/slave/test/build/application/firefox/firefox http://localhost:52889/getInfo.html) 16:25:51 INFO - TEST-INFO | 6347: exit 0 16:25:51 INFO - Browser initialized. 16:25:51 INFO - Running cycle 1/1 for kraken test... 16:25:51 INFO - Using env: {'DISPLAY': ':0', 16:25:51 INFO - 'HOME': '/home/cltbld', 16:25:51 INFO - 'JSGC_DISABLE_POISONING': '1', 16:25:51 INFO - 'LANG': 'en_US.UTF-8', 16:25:51 INFO - 'LANGUAGE': 'en_US:en', 16:25:51 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:25:51 INFO - 'LOGNAME': 'cltbld', 16:25:51 INFO - 'MAIL': '/var/mail/cltbld', 16:25:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:25:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:25:51 INFO - 'MOZ_CRASHREPORTER': '1', 16:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:51 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:25:51 INFO - 'MOZ_NO_REMOTE': '1', 16:25:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:25:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:25:51 INFO - 'NO_EM_RESTART': '1', 16:25:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:51 INFO - 'PWD': '/builds/slave/test', 16:25:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:25:51 INFO - 'RUST_BACKTRACE': 'full', 16:25:51 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:25:51 INFO - 'SHELL': '/bin/bash', 16:25:51 INFO - 'SHLVL': '1', 16:25:51 INFO - 'STYLO_FORCE_ENABLED': '1', 16:25:51 INFO - 'STYLO_THREADS': '1', 16:25:51 INFO - 'TERM': 'linux', 16:25:51 INFO - 'TMOUT': '86400', 16:25:51 INFO - 'USER': 'cltbld', 16:25:51 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1504393564.370210-524947057', 16:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:51 INFO - '_': '/tools/buildbot/bin/python'} 16:25:51 INFO - TEST-INFO | started process 6531 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJJsJNY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:25:52 INFO - PID 6531 | 16:25:52 INFO - PID 6531 | (/builds/slave/test/build/application/firefox/firefox:6584): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:25:52 INFO - PID 6531 | 16:26:02 INFO - PID 6531 | RSS: Main: 172851200 16:26:02 INFO - PID 6531 | 16:26:05 INFO - PID 6531 | Cycle 1(1): loaded http://localhost:52889/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52889/tests/kraken/driver.html?testName=audio-beat-detection) 16:26:06 INFO - PID 6531 | RSS: Main: 177176576 16:26:06 INFO - PID 6531 | 16:26:08 INFO - PID 6531 | Cycle 1(1): loaded http://localhost:52889/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52889/tests/kraken/driver.html?testName=audio-dft) 16:26:09 INFO - PID 6531 | RSS: Main: 181116928 16:26:09 INFO - PID 6531 | 16:26:12 INFO - PID 6531 | Cycle 1(1): loaded http://localhost:52889/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52889/tests/kraken/driver.html?testName=audio-fft) 16:26:12 INFO - PID 6531 | RSS: Main: 174043136 16:26:12 INFO - PID 6531 | 16:26:15 INFO - PID 6531 | Cycle 1(1): loaded http://localhost:52889/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52889/tests/kraken/driver.html?testName=audio-oscillator) 16:26:15 INFO - PID 6531 | RSS: Main: 175259648 16:26:15 INFO - PID 6531 | 16:26:17 INFO - PID 6531 | Cycle 1(1): loaded http://localhost:52889/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52889/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:26:18 INFO - PID 6531 | RSS: Main: 175349760 16:26:18 INFO - PID 6531 | 16:26:21 INFO - PID 6531 | Cycle 1(1): loaded http://localhost:52889/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52889/tests/kraken/driver.html?testName=imaging-darkroom) 16:26:22 INFO - PID 6531 | RSS: Main: 176267264 16:26:22 INFO - PID 6531 | 16:26:25 INFO - PID 6531 | Cycle 1(1): loaded http://localhost:52889/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52889/tests/kraken/driver.html?testName=imaging-desaturate) 16:26:26 INFO - PID 6531 | RSS: Main: 176541696 16:26:26 INFO - PID 6531 | 16:26:29 INFO - PID 6531 | Cycle 1(1): loaded http://localhost:52889/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52889/tests/kraken/driver.html?testName=json-parse-financial) 16:26:29 INFO - PID 6531 | RSS: Main: 176689152 16:26:29 INFO - PID 6531 | 16:26:31 INFO - PID 6531 | Cycle 1(1): loaded http://localhost:52889/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52889/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:26:32 INFO - PID 6531 | RSS: Main: 176123904 16:26:32 INFO - PID 6531 | 16:26:33 INFO - PID 6531 | Cycle 1(1): loaded http://localhost:52889/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52889/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:26:34 INFO - PID 6531 | RSS: Main: 176893952 16:26:34 INFO - PID 6531 | 16:26:36 INFO - PID 6531 | Cycle 1(1): loaded http://localhost:52889/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52889/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:26:37 INFO - PID 6531 | RSS: Main: 173445120 16:26:37 INFO - PID 6531 | 16:26:39 INFO - PID 6531 | Cycle 1(1): loaded http://localhost:52889/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52889/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:26:40 INFO - PID 6531 | RSS: Main: 173936640 16:26:40 INFO - PID 6531 | 16:26:43 INFO - PID 6531 | Cycle 1(1): loaded http://localhost:52889/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52889/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:26:44 INFO - PID 6531 | RSS: Main: 174309376 16:26:44 INFO - PID 6531 | 16:26:45 INFO - PID 6531 | Cycle 1(1): loaded http://localhost:52889/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52889/tests/kraken/driver.html?testName=ai-astar) 16:26:46 INFO - PID 6531 | RSS: Main: 175579136 16:26:46 INFO - PID 6531 | 16:26:46 INFO - PID 6531 | __start_tp_report 16:26:46 INFO - PID 6531 | _x_x_mozilla_page_load 16:26:46 INFO - PID 6531 | _x_x_mozilla_page_load_details 16:26:46 INFO - PID 6531 | |i|pagename|runs| 16:26:46 INFO - PID 6531 | |0;ai-astar;98;107;102;106;107;107;110;98;109;109 16:26:46 INFO - PID 6531 | |1;audio-beat-detection;139;116;143;174;118;138;151;117;174;120 16:26:46 INFO - PID 6531 | |2;audio-dft;163;163;162;161;162;164;160;160;163;166 16:26:46 INFO - PID 6531 | |3;audio-fft;74;74;74;169;77;74;73;73;153;77 16:26:46 INFO - PID 6531 | |4;audio-oscillator;80;79;144;80;80;79;137;79;79;136 16:26:46 INFO - PID 6531 | |5;imaging-gaussian-blur;113;113;114;114;113;114;114;114;114;115 16:26:46 INFO - PID 6531 | |6;imaging-darkroom;151;148;148;149;147;148;148;149;149;148 16:26:46 INFO - PID 6531 | |7;imaging-desaturate;94;94;94;95;95;94;94;94;94;94 16:26:46 INFO - PID 6531 | |8;json-parse-financial;53;53;52;53;53;59;59;52;54;53 16:26:46 INFO - PID 6531 | |9;json-stringify-tinderbox;38;39;39;38;38;38;38;38;39;39 16:26:46 INFO - PID 6531 | |10;stanford-crypto-aes;75;78;75;75;75;76;76;77;77;78 16:26:46 INFO - PID 6531 | |11;stanford-crypto-ccm;123;121;121;122;117;125;127;114;127;172 16:26:46 INFO - PID 6531 | |12;stanford-crypto-pbkdf2;177;182;173;169;170;173;184;168;169;167 16:26:46 INFO - PID 6531 | |13;stanford-crypto-sha256-iterative;65;63;64;63;64;63;63;65;64;64 16:26:46 INFO - PID 6531 | __end_tp_report 16:26:46 INFO - PID 6531 | __start_cc_report 16:26:46 INFO - PID 6531 | _x_x_mozilla_cycle_collect,940 16:26:46 INFO - PID 6531 | __end_cc_report 16:26:46 INFO - PID 6531 | __startTimestamp1504394806020__endTimestamp 16:26:46 INFO - PID 6531 | 16:26:46 INFO - PID 6531 | ------- Summary: start ------- 16:26:46 INFO - PID 6531 | Number of tests: 14 16:26:46 INFO - PID 6531 | 16:26:46 INFO - PID 6531 | [#0] ai-astar Cycles:10 Average:105.30 Median:109.00 stddev:4.42 (4.1%) stddev-sans-first:3.82 16:26:46 INFO - PID 6531 | Values: 98.0 107.0 102.0 106.0 107.0 107.0 110.0 98.0 109.0 109.0 16:26:46 INFO - PID 6531 | 16:26:46 INFO - PID 6531 | [#1] audio-beat-detection Cycles:10 Average:139.00 Median:141.00 stddev:22.18 (15.7%) stddev-sans-first:23.52 16:26:46 INFO - PID 6531 | Values: 139.0 116.0 143.0 174.0 118.0 138.0 151.0 117.0 174.0 120.0 16:26:46 INFO - PID 6531 | 16:26:46 INFO - PID 6531 | [#2] audio-dft Cycles:10 Average:162.40 Median:163.00 stddev:1.84 (1.1%) stddev-sans-first:1.94 16:26:46 INFO - PID 6531 | Values: 163.0 163.0 162.0 161.0 162.0 164.0 160.0 160.0 163.0 166.0 16:26:46 INFO - PID 6531 | 16:26:46 INFO - PID 6531 | [#3] audio-fft Cycles:10 Average:91.80 Median:74.00 stddev:36.69 (49.6%) stddev-sans-first:38.35 16:26:46 INFO - PID 6531 | Values: 74.0 74.0 74.0 169.0 77.0 74.0 73.0 73.0 153.0 77.0 16:26:46 INFO - PID 6531 | 16:26:46 INFO - PID 6531 | [#4] audio-oscillator Cycles:10 Average:97.30 Median:79.00 stddev:28.85 (36.5%) stddev-sans-first:29.92 16:26:46 INFO - PID 6531 | Values: 80.0 79.0 144.0 80.0 80.0 79.0 137.0 79.0 79.0 136.0 16:26:46 INFO - PID 6531 | 16:26:46 INFO - PID 6531 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.63 (0.6%) stddev-sans-first:0.60 16:26:46 INFO - PID 6531 | Values: 113.0 113.0 114.0 114.0 113.0 114.0 114.0 114.0 114.0 115.0 16:26:46 INFO - PID 6531 | 16:26:46 INFO - PID 6531 | [#6] imaging-darkroom Cycles:10 Average:148.50 Median:148.50 stddev:1.08 (0.7%) stddev-sans-first:0.67 16:26:46 INFO - PID 6531 | Values: 151.0 148.0 148.0 149.0 147.0 148.0 148.0 149.0 149.0 148.0 16:26:46 INFO - PID 6531 | 16:26:46 INFO - PID 6531 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:26:46 INFO - PID 6531 | Values: 94.0 94.0 94.0 95.0 95.0 94.0 94.0 94.0 94.0 94.0 16:26:46 INFO - PID 6531 | 16:26:46 INFO - PID 6531 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:53.00 stddev:2.64 (5.0%) stddev-sans-first:2.77 16:26:46 INFO - PID 6531 | Values: 53.0 53.0 52.0 53.0 53.0 59.0 59.0 52.0 54.0 53.0 16:26:46 INFO - PID 6531 | 16:26:46 INFO - PID 6531 | [#9] json-stringify-tinderbox Cycles:10 Average:38.40 Median:38.50 stddev:0.52 (1.3%) stddev-sans-first:0.53 16:26:46 INFO - PID 6531 | Values: 38.0 39.0 39.0 38.0 38.0 38.0 38.0 38.0 39.0 39.0 16:26:46 INFO - PID 6531 | 16:26:46 INFO - PID 6531 | [#10] stanford-crypto-aes Cycles:10 Average:76.20 Median:76.50 stddev:1.23 (1.6%) stddev-sans-first:1.22 16:26:46 INFO - PID 6531 | Values: 75.0 78.0 75.0 75.0 75.0 76.0 76.0 77.0 77.0 78.0 16:26:46 INFO - PID 6531 | 16:26:46 INFO - PID 6531 | [#11] stanford-crypto-ccm Cycles:10 Average:126.90 Median:124.00 stddev:16.37 (13.2%) stddev-sans-first:17.30 16:26:46 INFO - PID 6531 | Values: 123.0 121.0 121.0 122.0 117.0 125.0 127.0 114.0 127.0 172.0 16:26:46 INFO - PID 6531 | 16:26:46 INFO - PID 6531 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.20 Median:173.00 stddev:5.96 (3.4%) stddev-sans-first:6.16 16:26:46 INFO - PID 6531 | Values: 177.0 182.0 173.0 169.0 170.0 173.0 184.0 168.0 169.0 167.0 16:26:46 INFO - PID 6531 | 16:26:46 INFO - PID 6531 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.80 Median:64.00 stddev:0.79 (1.2%) stddev-sans-first:0.71 16:26:46 INFO - PID 6531 | Values: 65.0 63.0 64.0 63.0 64.0 63.0 63.0 65.0 64.0 64.0 16:26:46 INFO - PID 6531 | -------- Summary: end -------- 16:26:46 INFO - PID 6531 | 16:26:46 INFO - PID 6531 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:26:46 INFO - TEST-INFO | 6531: exit 0 16:26:46 INFO - TEST-OK | kraken | took 60861ms 16:26:46 INFO - SUITE-END | took 1172s 16:26:47 INFO - Completed test suite (00:19:33) 16:26:47 INFO - javascript benchmark 16:26:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8681.264748022582, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3280.212748940255, 3327.750418718597, 3348.430766769649, 3374.0, 3395.2360718838263, 6277.748890044399, 6323.399277068677, 6334.841628959272, 6353.968230158843, 6480.773172938948, 6462.741490340381, 6486.448651864597, 6590.044443555682, 6590.308017658137, 6604.207495100594, 5901.05583106703, 5959.165716799652, 6000.489958353545, 6018.729157187915, 6071.423214794588, 5640.407757185498, 5827.096799996008, 5879.353271140192, 5967.224260846084, 5977.910331345011, 5847.649141051529, 5909.822705318826, 5923.318818335903, 5929.851753706149, 5940.2277703898435, 5828.735907891594, 5858.882822343572, 5924.9703751480965, 5962.970185149046, 5965.4631083202285, 5677.063284558043, 5711.05767548354, 5731.885362292773, 5738.885222295572, 5751.482366586985, 6181.412765787243, 6230.532710046719, 6239.469645080174, 6246.812595622138, 6248.718807653667, 6667.666616669196, 6676.399124078856, 6680.86638267232, 6687.632180230085, 6687.732490700366, 6689.632070236135, 6858.142732158481, 6874.0, 6894.931050689479, 7030.191527974246, 6422.133012043361, 6501.902471462932, 6506.024096385533, 6513.185851768529, 6515.696860627893, 7067.646617669096, 7077.433805295667, 7081.433485321162, 7191.604461754549, 7192.7122915083855, 6599.835004124935, 6659.467242620579, 6665.033570132304, 6665.366790154928, 6684.832879177962, 6859.725610975505, 7000.439964802804, 7004.894926576106, 7007.369336759716, 7009.544379615309, 6924.446044316443, 7017.7192912282935, 7018.789436316919, 7032.824179395454, 7036.753713619999, 6923.238443771181, 6961.060256865258, 7026.543274687129, 7039.26087760788, 7041.647917604099, 5289.68261904287, 5352.652077614943, 5421.26812880264, 5500.80747173855, 5501.889962200734, 4694.600958918462, 4772.522747725269, 4799.28010798383, 4831.589314908272, 4842.201036828884, 6058.303295121117, 6103.664298463627, 6121.724522396503, 6143.6928153593035, 6147.0779383092895, 6956.930430695729, 6957.617331046841, 7038.788836334918, 7062.858742825115, 7074.540154889915, 5886.175935369051, 5995.130706047598, 6033.336333003366, 6064.4845188158615, 6138.600990935575, 5984.880302393928, 5996.220491336186, 5999.370066143079, 6003.7898673546215, 6008.188894499274, 6710.362515561014, 6742.629155396402, 6796.694148763318, 6801.0, 6808.285130061371, 6945.40964018054, 6966.442684585119, 6969.860602788017, 7050.717971281091, 7067.823304417225, 6579.671016449254, 6647.767328143492, 6682.665866706742, 6772.492063095386, 6802.8639427212165, 6767.526273161028, 6784.830379240559, 6798.626075565693, 6811.2167100784045, 6836.0, 6879.862402752017, 6892.310768923268, 6897.586144831326, 6927.861442771015, 6963.7562685305775, 6614.801555953329, 6631.104800851823, 6671.199456065303, 6692.799216023527, 6708.865822683421, 6405.679716014274, 6446.258680251643, 6448.838779030374, 6452.9677351612945, 6513.8697226056165, 6497.220333560003, 6520.706568204537, 6539.771108011197, 6598.637074960923, 6616.338366163345, 6754.459643228432, 6788.59268443895, 6801.081853949653, 6837.35045170708, 6855.485838562028, 6514.804555863332, 6517.771877984248, 6605.273419923708, 6607.438367738604, 6630.30381809903], "value": 6286.097486343431, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [782.2881178127901, 851.8296340731855, 863.2230992107103, 890.9064548222433, 891.5319457284925, 39582.80208598956, 40200.195996080096, 40276.798616006876, 40352.59647403529, 40406.0, 36120.0, 36292.455613165825, 36403.0, 36415.81792091043, 36470.452943205804, 6831.6242606656615, 6873.106496155493, 6876.037354770337, 6877.552959057671, 6878.69045892936, 6841.315868413169, 6927.584344939294, 6931.7920462385955, 6932.445404367663, 6934.583924964519, 7469.626518674072, 7663.540187588735, 7665.5783931883725, 7670.923290767077, 7674.5395276283325, 7566.39468842494, 7640.923590764077, 7641.2740789624895, 7647.42644301674, 7649.961750191242, 7316.524425912298, 7483.251674832528, 7548.8112797179965, 7553.773386798405, 7558.659860306299, 37080.81459592712, 37355.0, 37360.0, 37372.25255494875, 37444.43833342502, 3535.5580552430947, 3589.9948014555966, 3593.1915319053264, 3594.5686517618055, 3595.137167079908, 30591.235219119477, 30602.08193754191, 30662.08013759591, 30670.84664576663, 30691.386172276652, 5185.833187532813, 5199.948000519984, 5234.057869583474, 5272.182811664198, 5273.86815329616, 185832.0, 187751.0, 189468.0, 193909.0, 208914.9999999985, 48941.265881011816, 49212.26181607279, 49233.0, 49284.50715492871, 49331.50668493341, 50425.747871260406, 50454.74772626187, 50526.747366262935, 50531.49468505341, 50646.74676626593, 15081.095134291978, 15109.39562417491, 15190.620234494005, 15195.468158614396, 15223.61940951463, 11989.940050299692, 12004.219725717976, 12009.279443233434, 12030.87969120315, 12076.516939322504, 14221.85778142226, 14272.14367137975, 14274.714505709828, 14333.928330358282, 14413.639659008608, 5783.392743761929, 5843.883122337529, 5851.707414629337, 5899.911501327483, 5908.793192238251, 1013.503383342157, 1019.2814066083394, 1030.8917563655857, 1031.2781053262745, 1040.5837664933936, 22453.101875925106, 23318.883405582863, 28513.857430713128, 28976.565351519745, 29431.85284073566, 1119.6976816259728, 1121.1479275750326, 1123.224974767408, 1123.2474242257706, 1137.294877176156, 12525.43635536403, 12641.810372844415, 12752.489900404065, 12848.807267890988, 12895.226286422658, 234454.0, 235546.0, 235740.0, 236189.0, 236673.0, 51200.48799511957, 51256.74371628118, 51509.74245128825, 51566.0, 51575.0, 1433.276195521276, 1444.4155321311077, 1468.6548661064642, 1489.2925860216528, 1494.1931357067278, 22660.546789064458, 22681.865906704268, 22728.0, 22980.885095575082, 23105.075796967933, 37206.6279337214, 37279.25441491101, 37511.6248837519, 37574.624253757116, 37584.812075939444, 203922.0, 203948.0, 204224.0, 204267.0, 204339.0, 33184.0, 36524.26951461009, 37171.0, 37248.0, 37291.81354093212, 44732.55267447284, 44820.55179448294, 44857.0, 45012.0, 45231.77384113058, 33262.833685831414, 33336.0, 33635.49546756752, 34185.0, 34188.48717269193, 33233.335333292714, 33285.66714332825, 33399.49900751539, 33422.0, 33746.66253337435, 36251.45622815713, 36622.0, 36773.26453470969, 37286.25427491489, 37387.06532336605, 32414.675853241166, 32586.51120233151, 32624.83687581547, 32893.50659740154, 32925.34149316952, 23150.380631473854, 24301.27096187117, 24704.629430558198, 24759.50480990334, 25307.240782776546], "value": 17142.775443219827, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.3435146527096, 565.5560385097699, 570.6804189653794, 575.2205761193583, 585.3531847308724, 21939.451513712163, 22723.091076356948, 22726.65910011349, 22745.317640470814, 23720.169794057212, 24003.519929601396, 24957.8768955397, 24970.126045588408, 24987.750122498815, 24990.625140622906, 6185.195924529828, 6451.548391612589, 6610.636414997197, 6613.834654133661, 6617.569857959242, 6376.808695739135, 6434.195725534309, 6440.355964403569, 6441.420272175504, 6442.549021568492, 6401.871962560746, 6402.93597064028, 6407.878621241264, 6410.423061924426, 6411.647359395231, 6394.0, 6397.360263973612, 6402.807915762534, 6404.039394090877, 6406.551541392105, 5922.940770592325, 5930.525557955353, 5931.614445061057, 5932.3177834548715, 5944.762209511614, 27559.724402756117, 28663.0, 28771.9929802458, 28785.280367990967, 28799.71200287991, 2028.5841402512597, 2076.8961551922494, 2078.9168433262653, 2079.1371580793952, 2080.032784755096, 18502.0, 18942.715859262124, 19105.42683719477, 19123.23507059716, 19128.139233734517, 2323.546908352872, 2366.7633236676193, 2417.4560723836994, 2419.1049311754764, 2428.138011006085, 131290.0, 134041.6595834039, 134370.0, 135772.0, 139360.30319848337, 13945.62924302871, 14406.855931440552, 14467.927660361842, 14633.658614621609, 14756.48352307664, 22215.222467213895, 22623.66064509034, 23058.0, 23068.423289417562, 23115.653265201036, 9837.704868853945, 9905.2571057171, 9928.50357482123, 10099.7475063124, 10147.797044059078, 9233.584488698025, 9288.814223715623, 9369.250459963187, 9394.248460123174, 9530.142287194047, 8965.775855603531, 9006.819863602692, 9087.591058402388, 9234.1689247968, 9531.142197202285, 2557.667503224569, 2559.436923876744, 2568.29371922719, 2571.7299683533333, 2734.6308248386613, 959.822432849911, 960.4093482508347, 973.0755782007121, 984.2962281968427, 989.351974456732, 19749.506262343555, 19764.01179940997, 19819.504512387306, 19886.90056549737, 20259.29092481756, 1000.5997600959529, 1022.7187523431116, 1047.9161667066617, 1052.1214785654065, 1054.1672079057612, 10605.204609654307, 10677.0, 10687.679369618769, 10933.890661093446, 13080.934595327153, 491656.0, 502715.0, 503344.0, 503607.0, 507699.0, 118402.0, 122416.0, 122426.0, 122594.0, 122742.0, 1222.2849632964815, 1242.8073648584484, 1273.9108262421728, 1275.8851703346743, 1280.097531240492, 21456.356309310744, 21504.0, 21590.676139858228, 21740.78259217451, 21819.345419637437, 53516.197257040396, 54697.453025469236, 55741.442585573626, 59126.70436647789, 59923.700381497816, 70124.29875701178, 71990.28009720045, 72588.91116633361, 72646.63676681583, 72959.27040729525, 14204.502842400467, 14774.113553186844, 14815.777763333343, 14879.10725356482, 14963.476278330207, 23342.766572334738, 23513.0, 23770.049198031884, 23829.165979190646, 23857.761422386247, 18249.726254106463, 18408.26366945307, 18497.167627457067, 18727.0, 18744.812551874307, 16511.83488165103, 16816.159192040595, 16951.0, 16952.915235423745, 17291.91354043222, 18409.079546022378, 18777.155028024044, 19255.711164332828, 19491.61016779628, 19541.70687439718, 14496.782548261574, 14515.637109071935, 14591.416343346571, 14599.489017884323, 14604.0, 18354.0, 18445.0, 18587.34944276944, 18657.160427781055, 18984.43046708601], "value": 12018.025834036795, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.2467280659237, 1403.158105136918, 1420.907641003334, 1424.380394528381, 1433.4051368681999, 15031.098134111948, 15405.0, 15673.529794106194, 15693.52919412418, 15721.449749258778, 15948.84051159491, 16208.270627821747, 16208.513744587652, 16208.811810405592, 16215.432459863907, 2655.74770396812, 2717.701052884186, 2768.335599456126, 2771.8614069296555, 2772.9445411091765, 2820.323122450607, 2846.146156153156, 2853.215365774408, 2856.1003283965483, 2859.1565488181036, 2776.6806817215975, 2788.4004938938124, 2810.0445848411537, 2830.037787152368, 2830.0519326025806, 2684.489946910081, 2691.58280466528, 2705.8241214321006, 2707.8646067696536, 2712.308361367858, 2606.905099858064, 2621.030218819049, 2634.7233540478355, 2641.4452964877205, 2642.5111354399496, 14926.925365373212, 15293.847061529465, 15310.617234569225, 15318.617034574225, 15340.079595224212, 8225.629846656915, 8472.915270847336, 8476.66093356265, 8488.872666910001, 8489.787755306104, 5479.3424789025175, 5611.775528978836, 5620.185073164409, 5734.996375634289, 5742.540596752292, 1928.5082304012528, 1951.580410211797, 1957.2366776956999, 1968.9704654430195, 2023.5548179400507, 26495.867520662272, 26763.19710408691, 26944.05695800657, 26982.5952610711, 27047.864760676268, 7450.18048014718, 7473.962630186923, 7549.660265288076, 7690.500117492345, 7759.18528554505, 17277.481675549752, 17369.0, 17404.65190696179, 17450.47648570545, 17469.126543672766, 8082.434229603989, 8176.5094094353335, 8193.79515512117, 8198.959005204935, 8205.671773129008, 7590.848183036308, 7599.620018999028, 7600.619969001527, 7636.198199189116, 7696.191899850547, 5240.65935714185, 5405.621606487587, 5422.511973922287, 5452.2094296327605, 5464.426235245242, 1580.3915492535457, 1583.200483755719, 1585.3420830355503, 1607.8070631524292, 1626.1218941771547, 900.9144131307515, 916.8441364967838, 929.8605209218535, 931.2736065868634, 932.2774849491698, 7692.653830577638, 7780.066392032993, 7797.415193860483, 7825.608719564113, 7829.060512738508, 736.3998341351781, 740.5408646639119, 743.7805847275067, 746.5893758432841, 752.1538269446872, 5395.244665746799, 5505.0, 5536.363318218376, 5643.661380317194, 5664.575156863251, 21466.356009319745, 21998.45003874916, 22313.0, 22393.104275828784, 22428.55142897133, 22219.0, 22335.329940101823, 22335.55328893413, 22370.105195792312, 22408.55182896333, 11055.668329950115, 11117.888821111685, 11150.99641032311, 11462.140339474572, 11516.078713703051, 8657.696980605648, 8765.91234087651, 9098.545072746469, 9307.301952353466, 9315.39449935752, 18729.531761706065, 18758.812411875708, 18786.718199227296, 19157.13792879296, 19591.0, 19089.0, 19215.711764323827, 19258.518537036685, 19576.21695132179, 19781.70327445118, 11574.305541667527, 11974.760504789681, 11990.940045299718, 12013.159078864395, 12036.157468977437, 14205.431782728989, 14342.85657143457, 14454.710905782036, 14504.564863054125, 14512.056716313406, 11309.943450282695, 11554.017908477874, 11597.304161750322, 11698.35659038761, 11734.119941004288, 11268.718282042686, 11304.095672346357, 11366.943165284121, 11416.543338266376, 11420.771584568429, 9864.210863131077, 9918.950405247928, 9934.0, 9986.300958932805, 10013.899861001588, 7531.623418829146, 7654.846903061796, 7671.846563068596, 7707.267809558083, 7765.961170194113, 7914.683412663429, 8045.235702608243, 8113.553754543556, 8150.673973041012, 8157.75526734199], "value": 6836.771939382344, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.4817775012564, 512.4567957964559, 524.6301357542933, 530.8301343570057, 531.4419958378652, 6321.589096708716, 6329.620222786631, 6385.553011289206, 6418.518611104162, 6422.389872962061, 6254.8123556293385, 6282.403171698693, 6325.304216536178, 6339.492840572755, 6392.520560957935, 5160.638755287131, 5208.8437346879655, 5213.739313034352, 5252.947470525303, 5267.1045922193225, 4876.707397556141, 4934.310800613656, 5144.3826740791155, 5155.072087024334, 5199.896002079956, 4926.802927882881, 4936.7778449969655, 4987.077390682717, 5027.723475208862, 5029.572486338666, 4917.508249175054, 4963.677360971526, 4976.726280054578, 5022.493252024378, 5036.118679231138, 4365.607095361401, 4458.487273963503, 4467.910641787146, 4516.683832131752, 4533.591976722111, 6043.305019922721, 6159.876802463926, 6204.162438070848, 6207.968960155215, 6263.0, 5672.659640421588, 5692.971535142339, 5719.513841323493, 5737.569682273805, 5772.220750198711, 6636.170478690164, 6648.235452922928, 6651.634160121192, 6670.166229221348, 6751.459883209332, 4897.0, 4913.410390753133, 4914.926276105862, 4935.950640493621, 4961.280614310941, 6697.8995315070315, 6848.383645471931, 6868.501131758756, 6871.931280687229, 6875.518713690094, 5488.7530061147345, 5554.250176226238, 5558.44415558441, 5561.610687251854, 5572.5263352614675, 5524.254225679482, 5591.329040515083, 5600.411956744565, 5624.803131890364, 5640.915386269209, 5964.284285885721, 5965.194698715618, 5983.700814959235, 5998.880022399528, 6031.9698401508585, 6654.6672666366485, 6689.364510371507, 6690.899636505456, 6754.93245067543, 6759.628220447824, 1946.776120746104, 1969.350114462224, 1975.259277770836, 1988.0, 2000.5598768270956, 1391.9512817051354, 1419.6450887278181, 1456.6940942402214, 1459.8321193062934, 1481.6221863424757, 5787.565932555075, 5853.678047707417, 5883.940890639726, 5908.615939963888, 5941.940580594225, 6618.801435956929, 6671.699773510204, 6722.394984451325, 6733.326667333227, 6745.662716864137, 5944.4352786485215, 5953.166556682068, 5954.255718035246, 5982.970085149634, 5990.430909063632, 3547.52108465359, 3586.8923932282073, 3593.2633810068874, 3602.441621548664, 3603.2973570153567, 6533.607983520909, 6550.574212676161, 6610.4050635443045, 6634.203895532471, 6639.336066393322, 6965.338292862271, 7012.929870701228, 7027.964860175666, 7035.964820175969, 7070.505064645426, 6516.380943810331, 6562.212534495891, 6579.276279609239, 6582.440492558187, 6600.73597056122, 6698.665066746546, 6754.628495432798, 6795.38841504267, 6797.28628494001, 6807.353301436356, 6707.564008339442, 6757.493188010821, 6765.729370825112, 6778.93221067783, 6791.151106111736, 6550.868982620416, 6574.901376479261, 6598.967005164944, 6635.947464166537, 6638.734450621921, 5468.26178465902, 5477.452254774491, 5491.972540137273, 5519.172124181245, 5528.087865502147, 6527.967360163168, 6547.410733034049, 6693.397594216543, 6766.729330826712, 6792.117024786746, 6499.415052645285, 6552.63960482159, 6583.275839657538, 6584.341565843569, 6644.136262285871, 6518.728847874619, 6535.44448721864, 6548.770793022221, 6558.967205163944, 6640.335966403321, 6258.687065646791, 6501.414872661483, 6514.446272066927, 6543.672816359258, 6608.43828274602, 6561.096147792056, 6585.407313341822, 6596.670166491752, 6644.269130395749, 6758.831029224309, 6392.264889537577, 6397.281155508983, 6404.327545607644, 6441.645709486023, 6505.8048258550425], "value": 5197.319055101865, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [921.2537844346084, 930.534732633683, 936.7470782888616, 947.625687853298, 963.0340043430944, 18976.90511547442, 19733.11200995955, 19855.40433786987, 20112.195512179496, 20127.99999999998, 20492.0, 21078.683819742717, 21348.25281115161, 21364.359069227947, 21370.786292137072, 966.3718582921123, 988.5057471264367, 991.023841516106, 991.6083147156869, 992.7915137821067, 964.7154089543567, 994.2741798487109, 996.1134590214715, 1001.9499025048718, 1009.7021378693303, 1074.1889873145765, 1076.8976947190004, 1077.9299345542515, 1077.9730506737317, 1078.730317420645, 1073.4847273308817, 1073.7798751256014, 1073.978520429587, 1074.591655171038, 1075.3924032921414, 785.9960700196463, 787.0594639405912, 787.5078076202374, 787.9093904200976, 787.9763607091796, 16488.17559122039, 17234.396796112076, 19795.80204197954, 19798.307059252857, 19820.108095135754, 20932.5813483731, 21033.5793284135, 21416.357509274745, 21425.464363390885, 21713.891430542903, 16450.506484805475, 16618.50144495655, 16638.584035399213, 16679.91660041704, 16761.580960476087, 3765.8305376258136, 3784.299904517673, 3784.413415920537, 3790.8673196438267, 3802.2015376770637, 126063.369683151, 127001.0, 130314.99999999905, 130326.0, 132764.33617831947, 15668.138252396, 16009.519714408587, 16070.919645401698, 16077.598060048358, 16170.110643914697, 29981.700182998327, 30257.394852102836, 30363.392732145676, 30417.23956901095, 30639.540406893913, 7627.809304767315, 8032.638531266107, 8903.821923561492, 8924.107589241154, 9340.953295233481, 4303.440552728125, 4380.189664912002, 4381.583749543788, 4388.824447022083, 4389.956100438954, 5065.265536497187, 5146.330976972969, 5179.223116532475, 5182.870428239325, 5201.45384734605, 1079.7354648111263, 1110.1840147491587, 1113.5100555755603, 1121.85415895933, 1121.9046381057537, 708.7696498637963, 721.0338146883201, 727.0766127018657, 735.7645553422853, 742.3244847189032, 11795.823062654066, 12301.93849030767, 12343.429515900854, 12793.61619151427, 12856.935715321364, 695.0581961442274, 704.6371118873784, 714.292850078429, 720.989185162223, 722.5772922840092, 5808.854778630483, 5808.867270882222, 5877.471027607536, 5936.37668044849, 6039.184710064173, 112747.0, 114655.0, 115387.0, 115489.0, 118674.40662796631, 96394.51802741081, 96644.0, 97229.51385243029, 98576.0, 98750.01249987552, 83049.0, 83209.16790832015, 84347.99999999878, 86713.56643216869, 87226.1277387218, 10824.4046577439, 11045.33727976324, 11251.662450126509, 11269.267497612629, 13052.34738263064, 40644.796776017116, 41140.794296028325, 41312.0, 41719.58280417157, 43182.56817431786, 69857.65071174612, 70841.64579177072, 71591.0, 72965.6351718238, 74301.25698742943, 15437.459688910834, 17971.281148753904, 19109.426717198505, 19155.32956346577, 19752.703709444657, 19271.90364048171, 19385.224591016202, 19400.320988765325, 21379.572408552052, 21504.0, 18763.155657995696, 19194.0, 19320.227190912206, 19399.0, 19848.80151198527, 18308.450746477625, 18638.720419193993, 18982.525436864187, 19114.904425477784, 19211.231550737815, 18044.2782288707, 18296.634067318846, 18657.346992855186, 19376.61246775085, 19633.0, 13113.27876966776, 13214.141080829715, 13375.732485350432, 13535.458581656623, 13664.496905340206, 14209.715805683622, 14619.19594422317, 14778.0, 14929.178895160436, 15561.0], "value": 9301.392758630436, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1484.9000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 107.0, 102.0, 106.0, 107.0, 107.0, 110.0, 98.0, 109.0, 109.0], "value": 105.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 116.0, 143.0, 174.0, 118.0, 138.0, 151.0, 117.0, 174.0, 120.0], "value": 139.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 161.0, 162.0, 164.0, 160.0, 160.0, 163.0, 166.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 169.0, 77.0, 74.0, 73.0, 73.0, 153.0, 77.0], "value": 91.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 144.0, 80.0, 80.0, 79.0, 137.0, 79.0, 79.0, 136.0], "value": 97.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0, 114.0, 115.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 148.0, 148.0, 149.0, 147.0, 148.0, 148.0, 149.0, 149.0, 148.0], "value": 148.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 52.0, 53.0, 53.0, 59.0, 59.0, 52.0, 54.0, 53.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 39.0, 38.0, 38.0, 38.0, 38.0, 38.0, 39.0, 39.0], "value": 38.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 75.0, 75.0, 75.0, 76.0, 76.0, 77.0, 77.0, 78.0], "value": 76.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 121.0, 121.0, 122.0, 117.0, 125.0, 127.0, 114.0, 127.0, 172.0], "value": 126.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 182.0, 173.0, 169.0, 170.0, 173.0, 184.0, 168.0, 169.0, 167.0], "value": 173.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 64.0, 63.0, 64.0, 63.0, 63.0, 65.0, 64.0, 64.0], "value": 63.8, "unit": "score"}]}]} 16:26:47 INFO - Return code: 0 16:26:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:26:47 INFO - # TBPL SUCCESS # 16:26:47 INFO - Running post-action listener: _package_coverage_data 16:26:47 INFO - Running post-action listener: _resource_record_post_action 16:26:47 INFO - [mozharness: 2017-09-02 23:26:47.290725Z] Finished run-tests step (success) 16:26:47 INFO - Running post-run listener: _resource_record_post_run 16:26:47 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 394674176; Read time: 0; Write time: 188988 16:26:47 INFO - TinderboxPrint: CPU usage
13.1% 16:26:47 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 16:26:47 INFO - TinderboxPrint: I/O write bytes / time
394,674,176 / 188,988 16:26:47 INFO - TinderboxPrint: CPU idle
8,245.8 (86.9%) 16:26:47 INFO - TinderboxPrint: CPU user
1,193.3 (12.6%) 16:26:47 INFO - TinderboxPrint: Swap in / out
0 / 0 16:26:47 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 107065344; Read time: 0; Write time: 53456 16:26:47 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:26:47 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 287608832; Read time: 0; Write time: 135532 16:26:47 INFO - Running post-run listener: _upload_blobber_files 16:26:47 INFO - Blob upload gear active. 16:26:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:26:47 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:26:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:26:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:26:48 INFO - (blobuploader) - INFO - Open directory for files ... 16:26:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:26:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:26:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:26:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:26:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:26:49 INFO - (blobuploader) - INFO - Done attempting. 16:26:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:26:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:26:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:26:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:26:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:26:50 INFO - (blobuploader) - INFO - Done attempting. 16:26:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:26:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:26:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:26:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:26:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:26:56 INFO - (blobuploader) - INFO - Done attempting. 16:26:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:26:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:26:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:26:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:26:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:26:57 INFO - (blobuploader) - INFO - Done attempting. 16:26:57 INFO - (blobuploader) - INFO - Iteration through files over. 16:26:57 INFO - Return code: 0 16:26:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:26:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:26:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f6067630c5689f868ca7cef366c4d582ad59a7e0d61086b868119096ad9aa38425d8bf2131effc7703a8750310424ee81bf96327d7c3733f8e49d1776e80eb4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e97d2e6d6b7bf53f72bbe48a6ef23ca26eb308c1c9ffcfb882dfbc254898b5f03f3672c41ce7b0eefba2ec9afbe183ac1e1d8baa95fe81c13fb4e029d62c95f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49796f95281986629f1ae6e48e104367f6ac0e53cb0418a2e1d0230189f3a1e35f18e239172ea08aebeea7400c6f01b2739b3724ea637343c7677413b5d7de17", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46b99169d7a6bca8e1c5f32bf42811ea92857bc623d6aecd4c1c78e6ae727784421dc60cd2ace1066fd2a2f52cd70f68d9499ccb546306ada788b781bb74da75"} 16:26:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:26:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:26:57 INFO - Contents: 16:26:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f6067630c5689f868ca7cef366c4d582ad59a7e0d61086b868119096ad9aa38425d8bf2131effc7703a8750310424ee81bf96327d7c3733f8e49d1776e80eb4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e97d2e6d6b7bf53f72bbe48a6ef23ca26eb308c1c9ffcfb882dfbc254898b5f03f3672c41ce7b0eefba2ec9afbe183ac1e1d8baa95fe81c13fb4e029d62c95f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49796f95281986629f1ae6e48e104367f6ac0e53cb0418a2e1d0230189f3a1e35f18e239172ea08aebeea7400c6f01b2739b3724ea637343c7677413b5d7de17", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46b99169d7a6bca8e1c5f32bf42811ea92857bc623d6aecd4c1c78e6ae727784421dc60cd2ace1066fd2a2f52cd70f68d9499ccb546306ada788b781bb74da75"} 16:26:57 INFO - Running post-run listener: copy_logs_to_upload_dir 16:26:57 INFO - Copying logs to upload dir... 16:26:57 INFO - mkdir: /builds/slave/test/build/upload/logs 16:26:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.760696 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-09-02 16:26:58.044444) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 16:26:58.048215) ========= 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=483e410453545d07cca6935c000001ab-1504393564.370210-524947057 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f6067630c5689f868ca7cef366c4d582ad59a7e0d61086b868119096ad9aa38425d8bf2131effc7703a8750310424ee81bf96327d7c3733f8e49d1776e80eb4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e97d2e6d6b7bf53f72bbe48a6ef23ca26eb308c1c9ffcfb882dfbc254898b5f03f3672c41ce7b0eefba2ec9afbe183ac1e1d8baa95fe81c13fb4e029d62c95f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49796f95281986629f1ae6e48e104367f6ac0e53cb0418a2e1d0230189f3a1e35f18e239172ea08aebeea7400c6f01b2739b3724ea637343c7677413b5d7de17", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46b99169d7a6bca8e1c5f32bf42811ea92857bc623d6aecd4c1c78e6ae727784421dc60cd2ace1066fd2a2f52cd70f68d9499ccb546306ada788b781bb74da75"} build_url:https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011266 build_url: 'https://queue.taskcluster.net/v1/task/Y5WsbGReTUeKPP-utDhE2w/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f6067630c5689f868ca7cef366c4d582ad59a7e0d61086b868119096ad9aa38425d8bf2131effc7703a8750310424ee81bf96327d7c3733f8e49d1776e80eb4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e97d2e6d6b7bf53f72bbe48a6ef23ca26eb308c1c9ffcfb882dfbc254898b5f03f3672c41ce7b0eefba2ec9afbe183ac1e1d8baa95fe81c13fb4e029d62c95f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49796f95281986629f1ae6e48e104367f6ac0e53cb0418a2e1d0230189f3a1e35f18e239172ea08aebeea7400c6f01b2739b3724ea637343c7677413b5d7de17", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46b99169d7a6bca8e1c5f32bf42811ea92857bc623d6aecd4c1c78e6ae727784421dc60cd2ace1066fd2a2f52cd70f68d9499ccb546306ada788b781bb74da75"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-02 16:26:58.076759) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:26:58.077093) ========= 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=483e410453545d07cca6935c000001ab-1504393564.370210-524947057 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004546 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-02 16:26:58.161531) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 16:26:58.162027) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-02 16:26:58.162506) ========= ========= Total master_lag: 0.20 =========