builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-002 starttime: 1504478785.24 results: success (0) revision: 8e05298328da75f3056a9f1f9609938870d756a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:25.238296) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:25.238732) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:25.239017) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:25.374414) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:25.374690) ========= 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=a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-03 15:46:25-- 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% 226M=0s 2017-09-03 15:46:25 (226 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123909 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:25.515668) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:25.518994) ========= 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=a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015663 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:25.600885) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:25.601191) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8e05298328da75f3056a9f1f9609938870d756a0 --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 8e05298328da75f3056a9f1f9609938870d756a0 --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=a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-03 15:46:25,730 truncating revision to first 12 chars 2017-09-03 15:46:25,730 Setting DEBUG logging. 2017-09-03 15:46:25,730 attempt 1/10 2017-09-03 15:46:25,730 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8e05298328da?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-03 15:46:26,096 unpacking tar archive at: mozilla-central-8e05298328da/testing/mozharness/ program finished with exit code 0 elapsedTime=0.695889 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:26.322857) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:26.326177) ========= script_repo_revision: 8e05298328da75f3056a9f1f9609938870d756a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:26.326537) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:26.326792) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 15:46:26.362343) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-03 15:46:26.362603) ========= /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=a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:46:26 INFO - MultiFileLogger online at 20170903 15:46:26 in /builds/slave/test 15:46:26 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 15:46:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:46:26 INFO - {'append_to_log': False, 15:46:26 INFO - 'base_work_dir': '/builds/slave/test', 15:46:26 INFO - 'blob_upload_branch': 'Firefox', 15:46:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:46:26 INFO - 'branch': 'Firefox', 15:46:26 INFO - 'buildbot_json_path': 'buildprops.json', 15:46:26 INFO - 'code_coverage': False, 15:46:26 INFO - 'config_files': ('talos/linux_config.py',), 15:46:26 INFO - 'default_actions': ('clobber', 15:46:26 INFO - 'read-buildbot-config', 15:46:26 INFO - 'download-and-extract', 15:46:26 INFO - 'populate-webroot', 15:46:26 INFO - 'create-virtualenv', 15:46:26 INFO - 'install', 15:46:26 INFO - 'setup-mitmproxy', 15:46:26 INFO - 'run-tests'), 15:46:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:46:26 INFO - 'disable_ccov_upload': False, 15:46:26 INFO - 'disable_stylo': False, 15:46:26 INFO - 'download_minidump_stackwalk': True, 15:46:26 INFO - 'download_symbols': 'ondemand', 15:46:26 INFO - 'enable_stylo': False, 15:46:26 INFO - 'enable_webrender': False, 15:46:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:46:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:46:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:46:26 INFO - 'gecko_profile': False, 15:46:26 INFO - 'gecko_profile_interval': 0, 15:46:26 INFO - 'installer_path': 'installer.exe', 15:46:26 INFO - 'log_level': 'info', 15:46:26 INFO - 'log_name': 'talos', 15:46:26 INFO - 'log_to_console': True, 15:46:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:46:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:46:26 INFO - 'opt_config_files': (), 15:46:26 INFO - 'pip_index': False, 15:46:26 INFO - 'suite': 'dromaeojs-e10s', 15:46:26 INFO - 'system_bits': '32', 15:46:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:46:26 INFO - 'title': 'talos-linux64-ix-002', 15:46:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:46:26 INFO - 'use_talos_json': True, 15:46:26 INFO - 'verify': 'False', 15:46:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:46:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:46:26 INFO - 'work_dir': 'build'} 15:46:26 INFO - [mozharness: 2017-09-03 22:46:26.626278Z] Running clobber step. 15:46:26 INFO - Running pre-action listener: _resource_record_pre_action 15:46:26 INFO - Running main action method: clobber 15:46:26 INFO - rmtree: /builds/slave/test/build 15:46:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:46:27 INFO - Running post-action listener: _resource_record_post_action 15:46:27 INFO - [mozharness: 2017-09-03 22:46:27.632192Z] Finished clobber step (success) 15:46:27 INFO - [mozharness: 2017-09-03 22:46:27.632290Z] Running read-buildbot-config step. 15:46:27 INFO - Running pre-action listener: _resource_record_pre_action 15:46:27 INFO - Running main action method: read_buildbot_config 15:46:27 INFO - Using buildbot properties: 15:46:27 INFO - { 15:46:27 INFO - "project": "", 15:46:27 INFO - "product": "firefox", 15:46:27 INFO - "who": "archaeopteryx@coole-files.de", 15:46:27 INFO - "installer_path": "public/build/target.tar.bz2", 15:46:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 15:46:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:46:27 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 15:46:27 INFO - "stage_platform": "linux64-stylosequential", 15:46:27 INFO - "basedir": "/builds/slave/test", 15:46:27 INFO - "buildnumber": 186, 15:46:27 INFO - "platform": "ubuntu64_hw_styloseq", 15:46:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:46:27 INFO - "slavebuilddir": "test", 15:46:27 INFO - "taskId": "BzBwT-7_S4eQjmf6TU4Hpw", 15:46:27 INFO - "branch": "mozilla-central", 15:46:27 INFO - "script_repo_revision": "production", 15:46:27 INFO - "revision": "8e05298328da75f3056a9f1f9609938870d756a0", 15:46:27 INFO - "slavename": "talos-linux64-ix-002", 15:46:27 INFO - "repo_path": "mozilla-central" 15:46:27 INFO - } 15:46:27 INFO - Finding installer, test and symbols from parent task. 15:46:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BzBwT-7_S4eQjmf6TU4Hpw'}, attempt #1 15:46:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BzBwT-7_S4eQjmf6TU4Hpw'}, attempt #1 15:46:28 INFO - Task dependencies: ResnVHHTQe2eByEEfu188A 15:46:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A'}, attempt #1 15:46:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.tar.bz2 15:46:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.test_packages.json 15:46:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.crashreporter-symbols.zip 15:46:29 INFO - Running post-action listener: _resource_record_post_action 15:46:29 INFO - [mozharness: 2017-09-03 22:46:29.091926Z] Finished read-buildbot-config step (success) 15:46:29 INFO - [mozharness: 2017-09-03 22:46:29.092143Z] Running download-and-extract step. 15:46:29 INFO - Running pre-action listener: _resource_record_pre_action 15:46:29 INFO - Running main action method: download_and_extract 15:46:29 INFO - mkdir: /builds/slave/test/build/tests 15:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:29 INFO - trying https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.test_packages.json 15:46:29 INFO - Downloading https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:46:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:46:29 INFO - Downloaded 989 bytes. 15:46:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:46:29 INFO - Using the following test package requirements: 15:46:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:46:29 INFO - u'common': [u'target.common.tests.zip'], 15:46:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:46:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:46:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:46:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:46:29 INFO - u'mozbase': [u'target.common.tests.zip'], 15:46:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:46:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:46:29 INFO - u'web-platform': [u'target.common.tests.zip', 15:46:29 INFO - u'target.web-platform.tests.tar.gz'], 15:46:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:46:29 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:46:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.common.tests.zip 15:46:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:46:29 INFO - Fetch https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.common.tests.zip into memory 15:46:30 INFO - Content-Length response header: 55022405 15:46:30 INFO - Bytes received: 55022405 15:46:41 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:46:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.common.tests.zip 15:46:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:46:41 INFO - Fetch https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.common.tests.zip into memory 15:46:43 INFO - Content-Length response header: 55022405 15:46:43 INFO - Bytes received: 55022405 15:46:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.talos.tests.zip 15:46:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:46:56 INFO - Fetch https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.talos.tests.zip into memory 15:46:57 INFO - Content-Length response header: 14496650 15:46:57 INFO - Bytes received: 14496650 15:46:57 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')]} 15:46:57 INFO - trying https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.tar.bz2 15:46:57 INFO - Downloading https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:46:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:46:59 INFO - Downloaded 62635453 bytes. 15:46:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.tar.bz2 15:46:59 INFO - mkdir: /builds/slave/test/properties 15:46:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:46:59 INFO - Writing to file /builds/slave/test/properties/build_url 15:46:59 INFO - Contents: 15:46:59 INFO - build_url:https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.tar.bz2 15:46:59 INFO - Running post-action listener: _resource_record_post_action 15:46:59 INFO - Running post-action listener: find_tests_for_verification 15:46:59 INFO - Running post-action listener: set_extra_try_arguments 15:46:59 INFO - [mozharness: 2017-09-03 22:46:59.071209Z] Finished download-and-extract step (success) 15:46:59 INFO - [mozharness: 2017-09-03 22:46:59.071307Z] Running populate-webroot step. 15:46:59 INFO - Running pre-action listener: _resource_record_pre_action 15:46:59 INFO - Running main action method: populate_webroot 15:46:59 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:46:59 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:46:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:46:59 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:46:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:46:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:46:59 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:59 INFO - 'talos_options': ['--enable-stylo'], 15:46:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:46:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:46:59 INFO - 'tests': ['damp', 'tps']}, 15:46:59 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:59 INFO - 'talos_options': ['--enable-stylo'], 15:46:59 INFO - 'tests': ['damp', 'tps']}, 15:46:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:46:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:46:59 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:59 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:46:59 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:46:59 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:46:59 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:59 INFO - 'talos_options': ['--enable-stylo'], 15:46:59 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:46:59 INFO - 'other-e10s': {'tests': ['a11yr', 15:46:59 INFO - 'ts_paint', 15:46:59 INFO - 'tpaint', 15:46:59 INFO - 'sessionrestore', 15:46:59 INFO - 'sessionrestore_many_windows', 15:46:59 INFO - 'sessionrestore_no_auto_restore', 15:46:59 INFO - 'tabpaint']}, 15:46:59 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:59 INFO - 'tests': ['a11yr', 15:46:59 INFO - 'ts_paint', 15:46:59 INFO - 'tpaint', 15:46:59 INFO - 'sessionrestore', 15:46:59 INFO - 'sessionrestore_many_windows', 15:46:59 INFO - 'sessionrestore_no_auto_restore', 15:46:59 INFO - 'tabpaint']}, 15:46:59 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:46:59 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:46:59 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:59 INFO - 'tests': ['bloom_basic_singleton']}, 15:46:59 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:59 INFO - 'tests': ['bloom_basic']}, 15:46:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:46:59 INFO - 'tsvgr_opacity', 15:46:59 INFO - 'tart', 15:46:59 INFO - 'tscrollx', 15:46:59 INFO - 'tsvg_static']}, 15:46:59 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:59 INFO - 'tests': ['tsvgx', 15:46:59 INFO - 'tsvgr_opacity', 15:46:59 INFO - 'tart', 15:46:59 INFO - 'tscrollx', 15:46:59 INFO - 'tsvg_static']}, 15:46:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:46:59 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:59 INFO - 'talos_options': ['--enable-stylo'], 15:46:59 INFO - 'tests': ['tp5o']}, 15:46:59 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:46:59 INFO - 'talos_options': ['--mitmproxy', 15:46:59 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:46:59 INFO - '--firstNonBlankPaint'], 15:46:59 INFO - 'tests': ['tp6_google', 15:46:59 INFO - 'tp6_youtube', 15:46:59 INFO - 'tp6_amazon', 15:46:59 INFO - 'tp6_facebook']}, 15:46:59 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:46:59 INFO - 'talos_options': ['--enable-stylo', 15:46:59 INFO - '--mitmproxy', 15:46:59 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:46:59 INFO - '--firstNonBlankPaint'], 15:46:59 INFO - 'tests': ['tp6_google', 15:46:59 INFO - 'tp6_youtube', 15:46:59 INFO - 'tp6_amazon', 15:46:59 INFO - 'tp6_facebook']}, 15:46:59 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:46:59 INFO - 'talos_options': ['--enable-stylo', 15:46:59 INFO - '--stylo-threads=1', 15:46:59 INFO - '--mitmproxy', 15:46:59 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:46:59 INFO - '--firstNonBlankPaint'], 15:46:59 INFO - 'tests': ['tp6_google', 15:46:59 INFO - 'tp6_youtube', 15:46:59 INFO - 'tp6_amazon', 15:46:59 INFO - 'tp6_facebook']}, 15:46:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:46:59 INFO - 'talos_options': ['--xperf_path', 15:46:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:46:59 INFO - 'tests': ['tp5n']}, 15:46:59 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:59 INFO - 'talos_options': ['--enable-stylo', 15:46:59 INFO - '--xperf_path', 15:46:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:46:59 INFO - 'tests': ['tp5n']}}} 15:46:59 INFO - Running post-action listener: _resource_record_post_action 15:46:59 INFO - [mozharness: 2017-09-03 22:46:59.085598Z] Finished populate-webroot step (success) 15:46:59 INFO - [mozharness: 2017-09-03 22:46:59.085688Z] Running create-virtualenv step. 15:46:59 INFO - Running pre-action listener: _resource_record_pre_action 15:46:59 INFO - Running main action method: create_virtualenv 15:46:59 INFO - Creating virtualenv /builds/slave/test/build/venv 15:46:59 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:46:59 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:46:59 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:46:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:46:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:46:59 INFO - Installing distribute.............................................................................................................................................................................................done. 15:46:59 INFO - Installing pip...............done. 15:46:59 INFO - Return code: 0 15:46:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:46:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:47:00 INFO - Reading from file tmpfile_stdout 15:47:00 INFO - Output received: 15:47:00 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:47:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0090>, '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 0x1cb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caaf90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', '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 15:47:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:47:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:47:00 INFO - Using env: {'DISPLAY': ':0', 15:47:00 INFO - 'HOME': '/home/cltbld', 15:47:00 INFO - 'LANG': 'en_US.UTF-8', 15:47:00 INFO - 'LANGUAGE': 'en_US:en', 15:47:00 INFO - 'LOGNAME': 'cltbld', 15:47:00 INFO - 'MAIL': '/var/mail/cltbld', 15:47:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:00 INFO - 'MOZ_NO_REMOTE': '1', 15:47:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:00 INFO - 'NO_EM_RESTART': '1', 15:47:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:00 INFO - 'PWD': '/builds/slave/test', 15:47:00 INFO - 'SHELL': '/bin/bash', 15:47:00 INFO - 'SHLVL': '1', 15:47:00 INFO - 'TERM': 'linux', 15:47:00 INFO - 'TMOUT': '86400', 15:47:00 INFO - 'USER': 'cltbld', 15:47:00 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', 15:47:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:00 INFO - '_': '/tools/buildbot/bin/python'} 15:47:01 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:47:01 INFO - Downloading/unpacking pip>=1.5 15:47:01 INFO - Running setup.py egg_info for package pip 15:47:01 INFO - warning: no files found matching 'pip/cacert.pem' 15:47:01 INFO - warning: no files found matching '*.html' under directory 'docs' 15:47:01 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:47:01 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:47:01 INFO - Installing collected packages: pip 15:47:01 INFO - Found existing installation: pip 1.1 15:47:01 INFO - Uninstalling pip: 15:47:01 INFO - Successfully uninstalled pip 15:47:01 INFO - Running setup.py install for pip 15:47:01 INFO - warning: no files found matching 'pip/cacert.pem' 15:47:01 INFO - warning: no files found matching '*.html' under directory 'docs' 15:47:01 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:47:01 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:47:01 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:47:01 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:47:01 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:47:01 INFO - Successfully installed pip 15:47:01 INFO - Cleaning up... 15:47:01 INFO - Return code: 0 15:47:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:47:01 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')]} 15:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:01 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')]} 15:47:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:01 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 0x19401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0090>, '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 0x1cb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caaf90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', '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 15:47:01 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 15:47:01 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 15:47:01 INFO - Using env: {'DISPLAY': ':0', 15:47:01 INFO - 'HOME': '/home/cltbld', 15:47:01 INFO - 'LANG': 'en_US.UTF-8', 15:47:01 INFO - 'LANGUAGE': 'en_US:en', 15:47:01 INFO - 'LOGNAME': 'cltbld', 15:47:01 INFO - 'MAIL': '/var/mail/cltbld', 15:47:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:01 INFO - 'MOZ_NO_REMOTE': '1', 15:47:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:01 INFO - 'NO_EM_RESTART': '1', 15:47:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:01 INFO - 'PWD': '/builds/slave/test', 15:47:01 INFO - 'SHELL': '/bin/bash', 15:47:01 INFO - 'SHLVL': '1', 15:47:01 INFO - 'TERM': 'linux', 15:47:01 INFO - 'TMOUT': '86400', 15:47:01 INFO - 'USER': 'cltbld', 15:47:01 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', 15:47:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:01 INFO - '_': '/tools/buildbot/bin/python'} 15:47:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:01 INFO - Downloading/unpacking psutil>=3.1.1 15:47:01 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 15:47:01 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 15:47:01 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 15:47:01 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 15:47:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:47:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:47:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:47:02 INFO - Installing collected packages: psutil 15:47:02 INFO - Running setup.py install for psutil 15:47:02 INFO - building 'psutil._psutil_linux' extension 15:47:02 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 15:47:02 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 15:47:02 INFO - building 'psutil._psutil_posix' extension 15:47:02 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 15:47:03 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 15:47:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:47:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:47:03 INFO - Successfully installed psutil 15:47:03 INFO - Cleaning up... 15:47:03 INFO - Return code: 0 15:47:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0090>, '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 0x1cb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caaf90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', '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 15:47:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:47:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:47:03 INFO - Using env: {'DISPLAY': ':0', 15:47:03 INFO - 'HOME': '/home/cltbld', 15:47:03 INFO - 'LANG': 'en_US.UTF-8', 15:47:03 INFO - 'LANGUAGE': 'en_US:en', 15:47:03 INFO - 'LOGNAME': 'cltbld', 15:47:03 INFO - 'MAIL': '/var/mail/cltbld', 15:47:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:03 INFO - 'MOZ_NO_REMOTE': '1', 15:47:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:03 INFO - 'NO_EM_RESTART': '1', 15:47:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:03 INFO - 'PWD': '/builds/slave/test', 15:47:03 INFO - 'SHELL': '/bin/bash', 15:47:03 INFO - 'SHLVL': '1', 15:47:03 INFO - 'TERM': 'linux', 15:47:03 INFO - 'TMOUT': '86400', 15:47:03 INFO - 'USER': 'cltbld', 15:47:03 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', 15:47:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:03 INFO - '_': '/tools/buildbot/bin/python'} 15:47:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:47:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:47:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:47:04 INFO - Installing collected packages: mozsystemmonitor 15:47:04 INFO - Running setup.py install for mozsystemmonitor 15:47:04 INFO - Successfully installed mozsystemmonitor 15:47:04 INFO - Cleaning up... 15:47:04 INFO - Return code: 0 15:47:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:47:04 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')]} 15:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:04 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')]} 15:47:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:04 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 0x19401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0090>, '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 0x1cb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caaf90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', '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 15:47:04 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 15:47:04 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 15:47:04 INFO - Using env: {'DISPLAY': ':0', 15:47:04 INFO - 'HOME': '/home/cltbld', 15:47:04 INFO - 'LANG': 'en_US.UTF-8', 15:47:04 INFO - 'LANGUAGE': 'en_US:en', 15:47:04 INFO - 'LOGNAME': 'cltbld', 15:47:04 INFO - 'MAIL': '/var/mail/cltbld', 15:47:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:04 INFO - 'MOZ_NO_REMOTE': '1', 15:47:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:04 INFO - 'NO_EM_RESTART': '1', 15:47:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:04 INFO - 'PWD': '/builds/slave/test', 15:47:04 INFO - 'SHELL': '/bin/bash', 15:47:04 INFO - 'SHLVL': '1', 15:47:04 INFO - 'TERM': 'linux', 15:47:04 INFO - 'TMOUT': '86400', 15:47:04 INFO - 'USER': 'cltbld', 15:47:04 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', 15:47:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:04 INFO - '_': '/tools/buildbot/bin/python'} 15:47:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:04 INFO - Downloading/unpacking jsonschema==2.5.1 15:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:47:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:47:06 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 15:47:06 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 15:47:06 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 15:47:06 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 15:47:06 INFO - Downloading functools32-3.2.3-2.tar.gz 15:47:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:47:06 INFO - warning: no files found matching '*.txt' 15:47:06 INFO - no previously-included directories found matching 'build' 15:47:06 INFO - no previously-included directories found matching 'dist' 15:47:06 INFO - no previously-included directories found matching '.git*' 15:47:06 INFO - Installing collected packages: jsonschema, functools32 15:47:06 INFO - Running setup.py install for functools32 15:47:06 INFO - warning: no files found matching '*.txt' 15:47:06 INFO - no previously-included directories found matching 'build' 15:47:06 INFO - no previously-included directories found matching 'dist' 15:47:06 INFO - no previously-included directories found matching '.git*' 15:47:06 INFO - Successfully installed jsonschema functools32 15:47:06 INFO - Cleaning up... 15:47:06 INFO - Return code: 0 15:47:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:06 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 0x19401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0090>, '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 0x1cb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caaf90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', '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 15:47:06 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 15:47:06 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 15:47:06 INFO - Using env: {'DISPLAY': ':0', 15:47:06 INFO - 'HOME': '/home/cltbld', 15:47:06 INFO - 'LANG': 'en_US.UTF-8', 15:47:06 INFO - 'LANGUAGE': 'en_US:en', 15:47:06 INFO - 'LOGNAME': 'cltbld', 15:47:06 INFO - 'MAIL': '/var/mail/cltbld', 15:47:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:06 INFO - 'MOZ_NO_REMOTE': '1', 15:47:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:06 INFO - 'NO_EM_RESTART': '1', 15:47:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:06 INFO - 'PWD': '/builds/slave/test', 15:47:06 INFO - 'SHELL': '/bin/bash', 15:47:06 INFO - 'SHLVL': '1', 15:47:06 INFO - 'TERM': 'linux', 15:47:06 INFO - 'TMOUT': '86400', 15:47:06 INFO - 'USER': 'cltbld', 15:47:06 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', 15:47:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:06 INFO - '_': '/tools/buildbot/bin/python'} 15:47:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:47:06 INFO - Cleaning up... 15:47:06 INFO - Return code: 0 15:47:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:06 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 0x19401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0090>, '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 0x1cb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caaf90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', '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 15:47:06 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 15:47:06 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 15:47:06 INFO - Using env: {'DISPLAY': ':0', 15:47:06 INFO - 'HOME': '/home/cltbld', 15:47:06 INFO - 'LANG': 'en_US.UTF-8', 15:47:06 INFO - 'LANGUAGE': 'en_US:en', 15:47:06 INFO - 'LOGNAME': 'cltbld', 15:47:06 INFO - 'MAIL': '/var/mail/cltbld', 15:47:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:06 INFO - 'MOZ_NO_REMOTE': '1', 15:47:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:06 INFO - 'NO_EM_RESTART': '1', 15:47:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:06 INFO - 'PWD': '/builds/slave/test', 15:47:06 INFO - 'SHELL': '/bin/bash', 15:47:06 INFO - 'SHLVL': '1', 15:47:06 INFO - 'TERM': 'linux', 15:47:06 INFO - 'TMOUT': '86400', 15:47:06 INFO - 'USER': 'cltbld', 15:47:06 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', 15:47:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:06 INFO - '_': '/tools/buildbot/bin/python'} 15:47:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:06 INFO - Downloading/unpacking blobuploader==1.2.4 15:47:06 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 15:47:06 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 15:47:06 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 15:47:06 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 15:47:07 INFO - Downloading blobuploader-1.2.4.tar.gz 15:47:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:47:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:47:07 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 15:47:07 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 15:47:07 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 15:47:07 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 15:47:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:47:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:47:08 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 15:47:08 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 15:47:08 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 15:47:08 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 15:47:08 INFO - Downloading docopt-0.6.1.tar.gz 15:47:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:47:08 INFO - Installing collected packages: blobuploader, requests, docopt 15:47:08 INFO - Running setup.py install for blobuploader 15:47:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:47:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:47:08 INFO - Running setup.py install for requests 15:47:08 INFO - Running setup.py install for docopt 15:47:09 INFO - Successfully installed blobuploader requests docopt 15:47:09 INFO - Cleaning up... 15:47:09 INFO - Return code: 0 15:47:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:47: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')]} 15:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47: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')]} 15:47:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:09 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 0x19401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0090>, '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 0x1cb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caaf90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', '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 15:47:09 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 15:47:09 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 15:47:09 INFO - Using env: {'DISPLAY': ':0', 15:47:09 INFO - 'HOME': '/home/cltbld', 15:47:09 INFO - 'LANG': 'en_US.UTF-8', 15:47:09 INFO - 'LANGUAGE': 'en_US:en', 15:47:09 INFO - 'LOGNAME': 'cltbld', 15:47:09 INFO - 'MAIL': '/var/mail/cltbld', 15:47:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:09 INFO - 'MOZ_NO_REMOTE': '1', 15:47:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:09 INFO - 'NO_EM_RESTART': '1', 15:47:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:09 INFO - 'PWD': '/builds/slave/test', 15:47:09 INFO - 'SHELL': '/bin/bash', 15:47:09 INFO - 'SHLVL': '1', 15:47:09 INFO - 'TERM': 'linux', 15:47:09 INFO - 'TMOUT': '86400', 15:47:09 INFO - 'USER': 'cltbld', 15:47:09 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', 15:47:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:09 INFO - '_': '/tools/buildbot/bin/python'} 15:47:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:47:09 INFO - Running setup.py (path:/tmp/pip-r8RtjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:47:09 INFO - Running setup.py (path:/tmp/pip-XkOMeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:47:09 INFO - Running setup.py (path:/tmp/pip-PyRequ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:47:09 INFO - Running setup.py (path:/tmp/pip-cW3WcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:47:09 INFO - Running setup.py (path:/tmp/pip-gExS7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:47:09 INFO - Running setup.py (path:/tmp/pip-11NhdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:47:09 INFO - Running setup.py (path:/tmp/pip-65JjUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:47:09 INFO - Running setup.py (path:/tmp/pip-id1ziO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:47:09 INFO - Running setup.py (path:/tmp/pip-i9VguW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:47:09 INFO - Running setup.py (path:/tmp/pip-Bc0z_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:47:10 INFO - Running setup.py (path:/tmp/pip-EHiR4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:47:10 INFO - Running setup.py (path:/tmp/pip-UIPjwP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:47:10 INFO - Running setup.py (path:/tmp/pip-OUruiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:47:10 INFO - Running setup.py (path:/tmp/pip-UgE2b0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:10 INFO - Running setup.py (path:/tmp/pip-MvvJ6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:47:10 INFO - Running setup.py (path:/tmp/pip-LdjXXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:47:10 INFO - Running setup.py (path:/tmp/pip-3ENqcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:47:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:47:10 INFO - Running setup.py install for manifestparser 15:47:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:47:10 INFO - Running setup.py install for mozcrash 15:47:10 INFO - Running setup.py install for mozdebug 15:47:10 INFO - Running setup.py install for mozdevice 15:47:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:47:10 INFO - Running setup.py install for mozfile 15:47:11 INFO - Running setup.py install for mozhttpd 15:47:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:47:11 INFO - Running setup.py install for mozinfo 15:47:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:47:11 INFO - Running setup.py install for mozInstall 15:47:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:47:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:47:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:47:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:47:11 INFO - Running setup.py install for mozleak 15:47:11 INFO - Running setup.py install for mozlog 15:47:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:47:11 INFO - Running setup.py install for moznetwork 15:47:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:47:11 INFO - Running setup.py install for mozprocess 15:47:11 INFO - Running setup.py install for mozprofile 15:47:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:47:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:47:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:47:11 INFO - Running setup.py install for mozrunner 15:47:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:47:11 INFO - Running setup.py install for mozscreenshot 15:47:12 INFO - Running setup.py install for moztest 15:47:12 INFO - Running setup.py install for mozversion 15:47:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:47:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:47:12 INFO - Cleaning up... 15:47:12 INFO - Return code: 0 15:47:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:47:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:12 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 0x19401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0090>, '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 0x1cb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caaf90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', '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 15:47:12 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 15:47:12 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 15:47:12 INFO - Using env: {'DISPLAY': ':0', 15:47:12 INFO - 'HOME': '/home/cltbld', 15:47:12 INFO - 'LANG': 'en_US.UTF-8', 15:47:12 INFO - 'LANGUAGE': 'en_US:en', 15:47:12 INFO - 'LOGNAME': 'cltbld', 15:47:12 INFO - 'MAIL': '/var/mail/cltbld', 15:47:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:12 INFO - 'MOZ_NO_REMOTE': '1', 15:47:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:12 INFO - 'NO_EM_RESTART': '1', 15:47:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:12 INFO - 'PWD': '/builds/slave/test', 15:47:12 INFO - 'SHELL': '/bin/bash', 15:47:12 INFO - 'SHLVL': '1', 15:47:12 INFO - 'TERM': 'linux', 15:47:12 INFO - 'TMOUT': '86400', 15:47:12 INFO - 'USER': 'cltbld', 15:47:12 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', 15:47:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:12 INFO - '_': '/tools/buildbot/bin/python'} 15:47:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:47:12 INFO - Running setup.py (path:/tmp/pip-SfU2_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:47:12 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)) 15:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:47:12 INFO - Running setup.py (path:/tmp/pip-W75pID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:47:12 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)) 15:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:47:12 INFO - Running setup.py (path:/tmp/pip-_nNQ1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:47:12 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)) 15:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:47:12 INFO - Running setup.py (path:/tmp/pip-6rlzyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:47:12 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)) 15:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:47:12 INFO - Running setup.py (path:/tmp/pip-79f2qv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:47:12 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)) 15:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:47:12 INFO - Running setup.py (path:/tmp/pip-yuj1HN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:47:12 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)) 15:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:47:12 INFO - Running setup.py (path:/tmp/pip-UM_ZhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:47:12 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)) 15:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:47:12 INFO - Running setup.py (path:/tmp/pip-TXOa5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:47:13 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)) 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:47:13 INFO - Running setup.py (path:/tmp/pip-JgMC3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:47:13 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)) 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:47:13 INFO - Running setup.py (path:/tmp/pip-89ymt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:47:13 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)) 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:47:13 INFO - Running setup.py (path:/tmp/pip-77LkDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:47:13 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)) 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:47:13 INFO - Running setup.py (path:/tmp/pip-RMaJnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:47:13 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)) 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:47:13 INFO - Running setup.py (path:/tmp/pip-URex6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:47:13 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)) 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:47:13 INFO - Running setup.py (path:/tmp/pip-8e5FZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:47:13 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)) 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:13 INFO - Running setup.py (path:/tmp/pip-Bd9w4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:13 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)) 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:47:13 INFO - Running setup.py (path:/tmp/pip-cSRs_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:47:13 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)) 15:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:47:13 INFO - Running setup.py (path:/tmp/pip-tV7aMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:47:13 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)) 15:47:13 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)) 15:47:13 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)) 15:47:13 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)) 15:47:13 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)) 15:47:13 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)) 15:47:13 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)) 15:47:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:14 INFO - Downloading blessings-1.6.tar.gz 15:47:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:47:14 INFO - Installing collected packages: blessings 15:47:14 INFO - Running setup.py install for blessings 15:47:14 INFO - Successfully installed blessings 15:47:14 INFO - Cleaning up... 15:47:15 INFO - Return code: 0 15:47:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:47:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:47:15 INFO - Reading from file tmpfile_stdout 15:47:15 INFO - Current package versions: 15:47:15 INFO - argparse == 1.2.1 15:47:15 INFO - blessings == 1.6 15:47:15 INFO - blobuploader == 1.2.4 15:47:15 INFO - docopt == 0.6.1 15:47:15 INFO - functools32 == 3.2.3-2 15:47:15 INFO - jsonschema == 2.5.1 15:47:15 INFO - manifestparser == 1.1 15:47:15 INFO - mozInstall == 1.13 15:47:15 INFO - mozcrash == 1.0 15:47:15 INFO - mozdebug == 0.1 15:47:15 INFO - mozdevice == 0.51 15:47:15 INFO - mozfile == 1.2 15:47:15 INFO - mozhttpd == 0.7 15:47:15 INFO - mozinfo == 0.10 15:47:15 INFO - mozleak == 0.1 15:47:15 INFO - mozlog == 3.5 15:47:15 INFO - moznetwork == 0.27 15:47:15 INFO - mozprocess == 0.25 15:47:15 INFO - mozprofile == 0.28 15:47:15 INFO - mozrunner == 6.13 15:47:15 INFO - mozscreenshot == 0.1 15:47:15 INFO - mozsystemmonitor == 0.3 15:47:15 INFO - moztest == 0.8 15:47:15 INFO - mozversion == 1.4 15:47:15 INFO - psutil == 3.1.1 15:47:15 INFO - requests == 1.2.3 15:47:15 INFO - wsgiref == 0.1.2 15:47:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:47:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:15 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 0x19401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0090>, '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 0x1cb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caaf90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', '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 15:47:15 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 15:47:15 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 15:47:15 INFO - Using env: {'DISPLAY': ':0', 15:47:15 INFO - 'HOME': '/home/cltbld', 15:47:15 INFO - 'LANG': 'en_US.UTF-8', 15:47:15 INFO - 'LANGUAGE': 'en_US:en', 15:47:15 INFO - 'LOGNAME': 'cltbld', 15:47:15 INFO - 'MAIL': '/var/mail/cltbld', 15:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:15 INFO - 'MOZ_NO_REMOTE': '1', 15:47:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:15 INFO - 'NO_EM_RESTART': '1', 15:47:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:15 INFO - 'PWD': '/builds/slave/test', 15:47:15 INFO - 'SHELL': '/bin/bash', 15:47:15 INFO - 'SHLVL': '1', 15:47:15 INFO - 'TERM': 'linux', 15:47:15 INFO - 'TMOUT': '86400', 15:47:15 INFO - 'USER': 'cltbld', 15:47:15 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', 15:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:15 INFO - '_': '/tools/buildbot/bin/python'} 15:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:15 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)) 15:47:15 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)) 15:47:15 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)) 15:47:15 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)) 15:47:15 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)) 15:47:15 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)) 15:47:15 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)) 15:47:15 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)) 15:47:15 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)) 15:47:15 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:47:16 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)) 15:47:16 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)) 15:47:16 INFO - Installing collected packages: simplejson 15:47:16 INFO - Running setup.py install for simplejson 15:47:16 INFO - building 'simplejson._speedups' extension 15:47:16 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 15:47:17 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 15:47:17 INFO - Successfully installed simplejson 15:47:17 INFO - Cleaning up... 15:47:17 INFO - Return code: 0 15:47:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:47:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1caca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0090>, '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 0x1cb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caaf90>, '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': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', '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 15:47:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:47:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:47:17 INFO - Using env: {'DISPLAY': ':0', 15:47:17 INFO - 'HOME': '/home/cltbld', 15:47:17 INFO - 'LANG': 'en_US.UTF-8', 15:47:17 INFO - 'LANGUAGE': 'en_US:en', 15:47:17 INFO - 'LOGNAME': 'cltbld', 15:47:17 INFO - 'MAIL': '/var/mail/cltbld', 15:47:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:17 INFO - 'MOZ_NO_REMOTE': '1', 15:47:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:17 INFO - 'NO_EM_RESTART': '1', 15:47:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:17 INFO - 'PWD': '/builds/slave/test', 15:47:17 INFO - 'SHELL': '/bin/bash', 15:47:17 INFO - 'SHLVL': '1', 15:47:17 INFO - 'TERM': 'linux', 15:47:17 INFO - 'TMOUT': '86400', 15:47:17 INFO - 'USER': 'cltbld', 15:47:17 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', 15:47:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:17 INFO - '_': '/tools/buildbot/bin/python'} 15:47:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:47:17 INFO - Cleaning up... 15:47:17 INFO - Return code: 0 15:47:17 INFO - Running post-action listener: _resource_record_post_action 15:47:17 INFO - Running post-action listener: _start_resource_monitoring 15:47:17 INFO - Starting resource monitoring. 15:47:17 INFO - [mozharness: 2017-09-03 22:47:17.970898Z] Finished create-virtualenv step (success) 15:47:17 INFO - [mozharness: 2017-09-03 22:47:17.971444Z] Running install step. 15:47:17 INFO - Running pre-action listener: _resource_record_pre_action 15:47:17 INFO - Running main action method: install 15:47:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:47:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:47:18 INFO - Reading from file tmpfile_stdout 15:47:18 INFO - Detecting whether we're running mozinstall >=1.0... 15:47:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:47:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:47:18 INFO - Reading from file tmpfile_stdout 15:47:18 INFO - Output received: 15:47:18 INFO - Usage: mozinstall [options] installer 15:47:18 INFO - Options: 15:47:18 INFO - -h, --help show this help message and exit 15:47:18 INFO - -d DEST, --destination=DEST 15:47:18 INFO - Directory to install application into. [default: 15:47:18 INFO - "/builds/slave/test"] 15:47:18 INFO - --app=APP Application being installed. [default: firefox] 15:47:18 INFO - mkdir: /builds/slave/test/build/application 15:47:18 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'] 15:47:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:47:34 INFO - Reading from file tmpfile_stdout 15:47:34 INFO - Output received: 15:47:34 INFO - /builds/slave/test/build/application/firefox/firefox 15:47:34 INFO - Running post-action listener: _resource_record_post_action 15:47:34 INFO - [mozharness: 2017-09-03 22:47:34.858686Z] Finished install step (success) 15:47:34 INFO - [mozharness: 2017-09-03 22:47:34.858830Z] Running setup-mitmproxy step. 15:47:34 INFO - Running pre-action listener: _resource_record_pre_action 15:47:34 INFO - Running main action method: setup_mitmproxy 15:47:34 INFO - Skipping: mitmproxy is not required 15:47:34 INFO - Running post-action listener: _resource_record_post_action 15:47:34 INFO - [mozharness: 2017-09-03 22:47:34.859699Z] Finished setup-mitmproxy step (success) 15:47:34 INFO - [mozharness: 2017-09-03 22:47:34.859916Z] Running run-tests step. 15:47:34 INFO - Running pre-action listener: _resource_record_pre_action 15:47:34 INFO - Running pre-action listener: _set_gcov_prefix 15:47:34 INFO - Running main action method: run_tests 15:47:34 WARNING - Try message not found. 15:47:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:47:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:47:34 INFO - Python 2.7.3 15:47:34 INFO - Return code: 0 15:47:34 INFO - grabbing minidump binary from tooltool 15:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:34 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 0x1cb0090>, '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 0x1cb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caaf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:47:34 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 15:47:34 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 15:47:34 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 15:47:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:47:34 INFO - Return code: 0 15:47:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:47:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:47:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:47:34 INFO - ENV: RUST_BACKTRACE is now full 15:47:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:47:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:47:34 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/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 15:47:34 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/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-002 --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 15:47:34 INFO - Using env: {'DISPLAY': ':0', 15:47:34 INFO - 'HOME': '/home/cltbld', 15:47:34 INFO - 'LANG': 'en_US.UTF-8', 15:47:34 INFO - 'LANGUAGE': 'en_US:en', 15:47:34 INFO - 'LOGNAME': 'cltbld', 15:47:34 INFO - 'MAIL': '/var/mail/cltbld', 15:47:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:47:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:34 INFO - 'MOZ_NO_REMOTE': '1', 15:47:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:47:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:34 INFO - 'NO_EM_RESTART': '1', 15:47:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:34 INFO - 'PWD': '/builds/slave/test', 15:47:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:47:35 INFO - 'RUST_BACKTRACE': 'full', 15:47:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:47:35 INFO - 'SHELL': '/bin/bash', 15:47:35 INFO - 'SHLVL': '1', 15:47:35 INFO - 'STYLO_FORCE_ENABLED': '1', 15:47:35 INFO - 'STYLO_THREADS': '1', 15:47:35 INFO - 'TERM': 'linux', 15:47:35 INFO - 'TMOUT': '86400', 15:47:35 INFO - 'USER': 'cltbld', 15:47:35 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', 15:47:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:35 INFO - '_': '/tools/buildbot/bin/python'} 15:47:35 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/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 15:47:35 INFO - mozversion application_buildid: 20170903215731 15:47:35 INFO - mozversion application_changeset: 8e05298328da75f3056a9f1f9609938870d756a0 15:47:35 INFO - mozversion application_display_name: Nightly 15:47:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:47:35 INFO - mozversion application_name: Firefox 15:47:35 INFO - mozversion application_remotingname: firefox 15:47:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:47:35 INFO - mozversion application_vendor: Mozilla 15:47:35 INFO - mozversion application_version: 57.0a1 15:47:35 INFO - mozversion platform_buildid: 20170903215731 15:47:35 INFO - mozversion platform_changeset: 8e05298328da75f3056a9f1f9609938870d756a0 15:47:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:47:35 INFO - mozversion platform_version: 57.0a1 15:47:35 INFO - using testdate: 1504478855 15:47:35 INFO - actual date: 1504478855 15:47:35 INFO - starting webserver on 'localhost:49228' 15:47:35 INFO - SUITE-START | Running 2 tests 15:47:35 INFO - TEST-START | dromaeo_css 15:47:35 INFO - Initialising browser for dromaeo_css test... 15:47:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49228/getInfo.html -profile /tmp/tmpBCWc21/profile 15:47:35 INFO - TEST-INFO | started process 4997 (/builds/slave/test/build/application/firefox/firefox http://localhost:49228/getInfo.html) 15:47:41 INFO - TEST-INFO | 4997: exit 0 15:47:41 INFO - Browser initialized. 15:47:41 INFO - Running cycle 1/1 for dromaeo_css test... 15:47:41 INFO - Using env: {'DISPLAY': ':0', 15:47:41 INFO - 'HOME': '/home/cltbld', 15:47:41 INFO - 'JSGC_DISABLE_POISONING': '1', 15:47:41 INFO - 'LANG': 'en_US.UTF-8', 15:47:41 INFO - 'LANGUAGE': 'en_US:en', 15:47:41 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:47:41 INFO - 'LOGNAME': 'cltbld', 15:47:41 INFO - 'MAIL': '/var/mail/cltbld', 15:47:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:47:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:47:41 INFO - 'MOZ_CRASHREPORTER': '1', 15:47:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:41 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:47:41 INFO - 'MOZ_NO_REMOTE': '1', 15:47:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:47:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:41 INFO - 'NO_EM_RESTART': '1', 15:47:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:41 INFO - 'PWD': '/builds/slave/test', 15:47:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:47:41 INFO - 'RUST_BACKTRACE': 'full', 15:47:41 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:47:41 INFO - 'SHELL': '/bin/bash', 15:47:41 INFO - 'SHLVL': '1', 15:47:41 INFO - 'STYLO_FORCE_ENABLED': '1', 15:47:41 INFO - 'STYLO_THREADS': '1', 15:47:41 INFO - 'TERM': 'linux', 15:47:41 INFO - 'TMOUT': '86400', 15:47:41 INFO - 'USER': 'cltbld', 15:47:41 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', 15:47:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:41 INFO - '_': '/tools/buildbot/bin/python'} 15:47:41 INFO - TEST-INFO | started process 5182 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBCWc21/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:47:42 INFO - PID 5182 | 15:47:42 INFO - PID 5182 | (/builds/slave/test/build/application/firefox/firefox:5234): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:47:42 INFO - PID 5182 | 15:47:52 INFO - PID 5182 | RSS: Main: 166887424 15:47:52 INFO - PID 5182 | 15:50:51 INFO - PID 5182 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 15:50:52 INFO - PID 5182 | RSS: Main: 174002176 15:50:52 INFO - PID 5182 | 15:53:54 INFO - PID 5182 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 15:53:54 INFO - PID 5182 | RSS: Main: 170156032 15:53:54 INFO - PID 5182 | 15:56:56 INFO - PID 5182 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 15:56:57 INFO - PID 5182 | RSS: Main: 172040192 15:56:57 INFO - PID 5182 | 15:59:58 INFO - PID 5182 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 15:59:59 INFO - PID 5182 | RSS: Main: 170561536 15:59:59 INFO - PID 5182 | 16:03:02 INFO - PID 5182 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 16:03:03 INFO - PID 5182 | RSS: Main: 174116864 16:03:03 INFO - PID 5182 | 16:06:05 INFO - PID 5182 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 16:06:05 INFO - PID 5182 | RSS: Main: 173883392 16:06:05 INFO - PID 5182 | 16:06:05 INFO - PID 5182 | __start_tp_report 16:06:05 INFO - PID 5182 | _x_x_mozilla_page_load 16:06:05 INFO - PID 5182 | _x_x_mozilla_page_load_details 16:06:05 INFO - PID 5182 | |i|pagename|runs| 16:06:05 INFO - PID 5182 | |0;dojo.html;3320.4687250039997;3355.89932302031;3357.6306606273306;3361.59660840699;3376.105332086997;6393.200849893763;6431.2282526096815;6463.192100987377;6479.514036447267;6481.870362592757;6563.1796025496815;6598.604083754978;6606.768763093292;6635.60186388817;6659.833504162399;5953.851153721148;5987.820365389023;6091.847703807396;6122.693865306739;6184.536159788011;5851.385604511529;5911.054231322989;5925.73334199962;5931.436513531207;6002.279726432834;5901.055831067051;5979.820605381846;5979.940200598004;5980.372060933626;5989.970050149743;5870.295564532284;5886.440788125121;5975.760969561213;6001.459868611846;6006.279246490405;5826.271716035496;5907.940920590782;5909.8227053188475;5927.851803704899;5976.641401515923;6472.126262954535;6504.9349506504805;6535.967320163416;6545.738170473175;6546.70539825709;6986.476014298948;6999.12510936133;7068.36384725372;7070.540414873017;7098.219195888448;6993.475489338269;7011.859762804766;7079.256678048782;7120.25237350076;7143.285671432867;6517.967410162966;6524.510661700391;6586.176727909012;6603.438707709851;6622.801315960529;7125.679344429514;7224.74713385029;7238.819029524304;7335.303146201102;7346.11846578414;6893.75871844483;6904.654767261617;6905.551139175937;6930.792076237721;6936.167659880846;6983.615901125385;6993.335633114846;7063.2583578724525;7087.858242835115;7090.149182098181;6952.687129079203;6957.130358705162;7038.944158376285;7040;7052.92947070533;6791.592504449749;6919.965400172967;6940.479464040217;6964.373206411447;7004.4396448284015;5471.097268950658;5518.972405138029;5531.115021596605;5563.554915606742;5564.304461942257;4799.928001079987;4825.710457372569;4826.396700412449;4854.75726213695;4864.075825593126;6094.786682466092;6123.357047509948;6193.163922870445;6208.255009398811;6210.192674952227;6858.279880612564;6992.755253566203;6999.23008469068;6999.300069993062;7030.578165309996;6042.214512113485;6048.969755151196;6064.02975523914;6121.908171377433;6133.325334213233;5963.731632076567;5968.731407086691;6028.125921741411;6048.516118710492;6053.061775424816;6755.088063111417;6759.763408280785;6826.692798824164;6827.624480653512;6874.450043996469;6833.043373927555;6890.758823441155;6951.200611929693;6951.756688515878;6960.234374218933;6695.698693558899;6709.194896612243;6749.021391898049;6777.830554236183;6782.423494003065;6744.359285867835;6762.966185169043;6772.830679233059;6825.658717064028;6865.176178858569;6914.51598388108;6917.377436030782;7003.6498175092065;7027.15674119109;7087.14954205478;6774.966125169342;6775.085363475868;6821;6848.486363522856;6858.039874417485;6699.866002679822;6751.426128779109;6777.762778302736;6844.315568443116;6881.587104773729;6450.8064758057335;6599.769008084694;6682.465402767864;6725.192976842614;6773.288804675438;6703.698333574904;6709.4632429404555;6801.047853300641;6821.761238356832;6825.976103584502;6603.372679595431;6621.238557565942;6674.699638516376;6694.732210711712;6717.093192418961 16:06:05 INFO - PID 5182 | |1;ext.html;794.221662770485;843.7974886027353;869.3306154261217;1137.8008848451525;1151.1941640851405;29426.264343391427;30305.242368940788;39235.60764392359;40437.59562404375;40701;39629.60370396295;39997;40100.79949600255;40345.19309613806;40410.19179616406;7159.35565799078;7571.734989275363;7819.100803407624;7824.882626760603;7831.41264405169;7636.694532218732;7748.922510774904;7771.727989520368;7772.65023073963;7777.5333479991095;7699.076110866676;7757.883631745528;7761.573113478757;7801.960990195041;7810.570418626973;7719.8070048248965;7725.72959946402;7739.458237923348;7746.30283274508;7748.845023099535;7652.693892244303;7715.344195743369;7719.536827790351;7726.845463090763;7729.265719756614;36082.819585902165;36197.81901090504;36230;36345.63654363449;36376.45435318446;3545.7872527648424;3581.3195492856275;3583.6595523425235;3585.551806024247;3588.74878758487;29807.70192298071;29823;29885.551716724265;29912.40175196484;29920.551191732142;4997.775100120467;5168.328117344721;5184.7148406837605;5219.895602087975;5220.843374698765;184602;187161;187293;187336;187361;44730;45137.54862451399;45149.29104253755;45222.77388613036;45351;46790.29814552784;46930.29604555934;47477.81305467322;47490;47615.52384476111;14372.137671739729;16140;16255.349786008665;16330.67338653244;16342.10118443497;12050.457729402198;13211.009174311965;13582.456701732017;13596.11625244356;13608.455661773616;14369.13785172893;14391.92804035994;14439.278036098152;14460.783088253685;14564.41742330316;5624.521915637135;5713.771449142071;5732.97383662867;5774.335951365562;5786.797462088806;1009.1976878381704;1027.0653705128448;1027.3527677563088;1028.290479569095;1036.7097212780284;22567.435814104778;22956.54086918252;22981.77018229796;23024.539509210055;23153.305400838002;1124.241137232365;1131.5643477261149;1132.0547342968548;1136.4829002803704;1139.3847322445952;11903.928646421864;12086.39568021613;12103.63689089311;12149.817752733716;12217.389130543437;229388;230115;230133;230268;230436;50054.49945500498;50059;50136.247956280684;50177.49822501728;50225;1450.1081834671602;1453.7455945209547;1462.5977856089448;1464.655805885616;1471.1026273972868;21901.452463688536;22307.10771569119;22409.551808963402;22584.66123008189;22744.20395286157;36449.088772780895;36449.271014579026;36453.63546364503;36467;36591.63408365989;197672;197888;198543;199676;200168;32957.67042329546;34465.65534344625;35232.823835880656;35241.47137792986;35254.82372588121;42605.78697106495;44221;44281;44314.77842610766;44431.55568444274;31609;32401.351972959936;32489;32498.51252231171;33495.33009339849;31964.360712786078;32339;32407.51388729124;32705.509417359233;33069;36307;36376.454353185254;36406;36528.81735591305;36603.63396366109;31868.6813131875;32455.188620284684;32489;32605.836970814995;32795.34409311848;23094.307170784905;23617.29148125559;23958.161464348665;24590.385240369134;25198.244052677717 16:06:05 INFO - PID 5182 | |2;jquery.html;560.8205374280229;564.5511818104607;567.0926517571886;575.3929604267502;578.1328007988018;21777.455563610918;22278.665820012713;22450.32649020528;22504.43738906526;23189.536209275804;26001.739982600167;26409.339766505847;26410;26463.206103816916;26541.203763887064;6451.741930322782;6562.212534495867;6574.967125164368;6581.473482121419;6592.044153597725;6487.708053137597;6548.541602087832;6868.416184624288;7119.572825630453;7184.676689548983;7179.425645948311;7195.568265904037;7202.927970720278;7207.5675459472595;7208.207097219301;7167.211606723256;7174.426045916314;7175.641217939082;7176.856462870714;7183.281671832801;7019.929800701979;7028.683709233097;7032.261612530662;7035.613041282727;7036.788896333118;30723.846380767955;31493.52759708606;31510.842445787854;31630.683693163002;31676.524852127237;2107.7259956205594;2163.0590693048416;2168.728908886389;2172.3048624440185;2180.661997390391;20917.5816483671;21512.247071352584;21532.138714451405;21535.138594456203;21556.353309400743;2434.440078781872;2491.2401717476164;2538.149719843836;2555.744425557448;2560.039985005623;137425.312873436;142011.28994355066;142095.28952355275;142467;142602;20630.793692063187;21662.241821536172;22071.12395446373;22097.889510552344;22449.438764030703;43616.563834361885;43642;43757.34363984479;43857.78071109624;43859.12281754411;10649.094826939798;10799.784004320027;10813.945930270298;10869.945650271857;10994.835077473845;7805.726799561988;8650.486164921114;8654.48073115615;8688.304935605136;8820.117988201257;9650.276229282692;11543.711407214887;11576.536938522364;11730.824037639442;11769.764604707858;4122.216778812016;4178.86877692379;4262.211490874198;4301.397804307399;4324.978375108104;961.3414810854447;967.5355829202023;983.1053741095569;993.8211121997965;1005.4872015272264;19673.50816229577;19858.808471491757;20162;23784.699725890856;23950.161744338864;1034.3897100710467;1070.0583486531957;1071.9464026798785;1088.151242031217;1088.4448931045224;11422.428878556038;11490;11491.908268714458;11602.709932251593;11617.244879082833;487183;490540;492854;499271;499421;125419.3729031349;125599;126899;130323.34838325747;131595;1238.4365113873166;1252.3863306979515;1280.8206851040861;1289.8839104480642;1290.387066143593;21020.68468972936;21051.738084062858;21317.147314107264;21497.355079347646;21505.892470537547;59648;59690.40309597022;59904.400955989884;60455.69772151111;61262.08106878311;73920.63039684767;78083.21916780986;78341;78805;78949.21050789418;14353.210573418562;14475.855241447873;14923.403063877324;15053.623659408602;15070.397184112951;23295.068197271918;23537.293881183592;23684.52630947406;23792.88103559471;23952;18427.17077731532;18643.347482838035;18993;19121.904390477957;19202.711959320342;16783.56638287466;16942.152892355578;16947.66104677924;17503.47489575315;17533.473995780147;18970.146343414304;19201.61596768085;19204.71192932135;19211.135498902862;19281.51796205106;14775.408983640535;14819.036762610827;14893.77659335089;14897.627559311104;14898.329575168927;18148;18215.817841821412;18327.816721832613;18668.7199692002;18807.247710091444 16:06:05 INFO - PID 5182 | |3;mootools.html;1290.6644272489152;1350.7771217749073;1363.2025265219845;1365.3241645385535;1374.635721533793;14745.852541474582;15438.84561154388;15453.690926181484;15472.458463953764;15613.531594052196;15734;15980.200989950514;15999.640030597413;16004.279807408666;16012.759808602881;2797.5244208484505;2852.286928267933;2852.9714702853016;2853.4863724529578;2856.742893139617;2763.69599344072;2951.970480295202;2953.4831404504234;2954.5715871198663;2961.585378047075;2768.9169324920185;2800.103966730647;2814.3245621051005;2841.7016213297616;2841.829490230593;2755.9448811023767;2759.65504311961;2767.5848622706653;2772.916812495628;2778.097118436496;2711.6881558620616;2712.715164907676;2721.4285000149994;2723.046933573253;2723.9319017024536;14955.850441495555;15263.771043434357;15280.694386112327;15281.770773438408;15291.541253762294;8045.316148127416;8283.29591984682;8293.129221431784;8293.8341233175;8307.792305192359;5334.866628334284;5442.346918369782;5469.343678758534;5510.779568817283;5569.637973531748;1887.7451544041517;1891.7446144770556;1894.9336773213008;1924.2110734598887;1932.7390802241798;26547.46905061907;26651.999999999807;26778.5983210252;26782;26876;7284.380827629605;7365.742199023116;7538.962305188548;7610.505317154367;7629.923700763033;17524.824751752574;17764.9111754443;17767.822321776875;17773.555661108316;17799.822001780074;7979.202079791974;8121.269085782308;8122.634481448349;8125.593720313961;8128.187181281824;7408.518446300973;7475.401967842559;7500.212477689873;7507.024086868672;7508.474406791473;5055.266986287042;5149.073166829931;5154.5103215194495;5239.607029472805;5249.133892907704;1428.0788891165141;1444.8627380398846;1445.6169115184484;1460.2041887171517;1466.2961778346453;919.1911118216061;939.7133874168308;949.767307009787;953.50894289441;958.520739630185;7990.760277191694;8014.639341229658;8023.077346105156;8042.235987581171;8086.433949623588;725.2892165677665;731.8902164675235;737.4137560639198;742.5915746339492;742.7087435262168;5083.313752643419;5386.084365657847;5687.402822703557;6050.48570871472;6147.293061298008;21207.78792212089;22418.88790556037;22431.775682243297;22468.550628987654;22527.77472225257;21174;22012.339629811133;22178.33464996053;22183.556328873332;22362;10798.514066867167;11592.47833847479;11668.591599294154;11938.164328497096;11946.761064778655;9076.591553379982;9138.268938484773;9161.49611771359;9324.300677449353;9433.905660943303;18519.53701157482;18870;19086.33197838069;19397.70903436478;19525.60948781045;19064.142113605198;19244.711329330352;19374;19770.60458790845;20383.8980805095;12116.575919842764;12380.504779809067;12383.504659813507;12406.937965310115;12416.317102559446;13983.860161398256;13995.930020349833;14037.1577705338;14122;14137.293135342987;10836.349819010884;11143.498542565767;11146.721331966766;11160.944195278971;11329.773404532029;10828.18788590843;10879.891201088205;10919.290246133976;11020.44897755125;11231.887681123084;9834.01659834011;10002;10012.749681257736;10020.09819116283;10042.548085336031;7383.372413344711;7399.297066778648;7404.296591823768;7416.814579635552;7458.328750412272;7468.663910124166;7502.737404190827;7618.200088990576;7621.885671714817;7673 16:06:05 INFO - PID 5182 | |4;prototype.html;503.32554377134653;508.3137764018575;534.6017217173206;540.4541413172695;541.4314969282252;6487.0214584551695;6508.51186161038;6518.511111666626;6529.640869752162;6565.967170164155;6186.505079593621;6353.650549219791;6358.3323751006055;6369.172007639;6385.329540398261;5103.183490641507;5107.774134207782;5117.923231151536;5118;5156.20078887773;4989.675671081368;5108.412532558747;5134.409542902558;5184.222366644995;5189.117849965515;5070.1380765269805;5095.796168153288;5100.311457953185;5103.56619687327;5109.489051094898;5070.416902056274;5078.898422031539;5080.0601888650535;5083.440821509649;5090.261912022773;4547.499775024745;4581.885452863705;4586.633069354443;4589.2198326284615;4598.94224328403;6331.176946996861;6344;6355.205599300088;6358;6366.522510811707;5842.808946645008;5858.472737453607;5863.208466856962;5879.970600147015;5887.470127688529;6694.899576506356;6695.660867826406;6704.329567043256;6704.787420151339;6709.054374231467;4949.653524253304;4980.352554167971;5005.274235235906;5008.84973450797;5013.498650134994;7092.822679433056;7107.6446177690905;7143.571385716874;7215.845464725671;7219.602921839297;5094.7962081516325;5113.53978141969;5136.409312928986;5141.511556402136;5146.176611742103;5192.428832828386;5194.220866869924;5214.478552144755;5215.713135777569;5224.686518808808;5749.626274292157;5808.419158084158;5819.883602328015;5868.530517558585;5875.23621929155;6604.48525481219;6673.965535342029;6674.699638516278;6753.797386078426;6754.966225168842;1690.8562772164257;1742.4424184261168;1753.3074435597866;1765.6645237404853;1784.5627821183894;1201.279232460534;1203.7171264752776;1247.9188852724544;1249.175544140881;1250.5623031938749;5853.64878107315;5855.648661080349;5869.794557190563;5916.319623243382;5928.79249226275;6566.770163044271;6692.954705608936;6820.897686534706;6887.931120688729;6951.513394062468;5883.264591926009;5985.730642121115;5993.970030149909;6048.818535443944;6051.939480605226;3612.3858943979585;3630.5643322801434;3632.146445585285;3664.5785734640326;3669.94495082574;6625.08496261653;6656.733730650816;6674.998750187511;6704.3295670433545;6716.697748601325;6925.411340036053;6988.720451181895;7040.5071644984355;7043.330883566053;7052.858942821114;6515.579320856494;6519.674016299166;6551.4103730664465;6642.302558231316;6715.46276297895;6835.829104272433;6902.240753517009;6965.164180298397;6969.860602787814;6972.884338506017;6841.076454678754;6871.862562748817;6876.724931002704;6932.0988271524375;6935.549189302679;6695.665216739046;6745.291744366771;6750.35871592198;6759.459243260626;6781.525293229427;5067.341245638044;5069.41701704294;5082.186850103965;5132.615053871049;5163.845084647466;6663.400293973566;6950.270221626656;7024.033270684216;7050.717971281091;7051.435885129074;6455.386738259858;6538.476921846146;6599.340065993362;6731.596104233761;6732.451536146616;6619.881637875834;6694;6709.463242940651;6714.496412768965;6741.561798483083;6613.867722645617;6640.767573134917;6646.570987237767;6649.501287403367;6655.134832471747;6599.736010559716;6627.23786764509;6635.867282654416;6706.295838937037;6746.696398661974;6390.753803008554;6440.710168042543;6460.644664543495;6466.612003279818;6533.379328963741 16:06:05 INFO - PID 5182 | |5;yui.html;884.783228109113;898.3397203055755;917.7751450894532;917.9449233046017;933.5985526223723;16171.110588917605;16733.07968061756;17095.743563846554;17117.486475405727;19649.50876228095;19453.513662158453;19461.70807437886;19482;19505.609887802235;19558.511037224078;913.5569248914271;935.0602644342426;993.6373223773343;1001.0290018681867;1001.8447140693168;1009.6769033909152;1017.4454922067454;1019.5208252121491;1019.6329321444274;1024.7028361775076;988.85661579071;1041.739565108723;1047.6228557719253;1064.675274041417;1067.3062509368933;1035.777307878806;1039.1063685230695;1041.198277326462;1041.4844651897283;1043.0039312456586;747.9065116860393;754.5397307642334;755.4787196834221;755.9697612095509;757.1028331427276;16336.591585210346;17690.292388304453;17928.372506962194;18134.637307253914;18230.179641916147;18429.907850460662;18702.345417910445;18894.811051889443;18924.716129257933;18953.147108380155;15241.23793810305;15322.463713769965;15369.154696491689;15371.615709607237;15379.461718839786;3599.6400359964055;3767.9811600942094;3821.3694740367805;3843.4042723377643;3848.057225979625;128351;128421;128986;130627;133232;16081;16846.747298790528;16865.07242101696;16866.241019154168;17070.48788536346;30238.09285721432;30272.54591181134;30700.232494187825;30912.381752364825;30933.381332373225;7567.091948966159;9035.954820225857;9146.817063658822;9161.633534658673;9161.679341223025;3760.2855457463543;4171.666266698657;4349.978250108729;4354.216241076573;4357.3899654048455;4911.165101932608;4993.6005119590345;4997.525235102659;5004.274380214849;5005.524475174852;1006.3006210683504;1044.3316277582353;1051.663467690347;1054.7046826888484;1055.4933631856752;668.812732434919;675.5845155229498;683.6376720338229;687.3916583823352;687.9965600171967;11300.491477883515;11406.42967851604;11788.941055294668;12167.330796806084;12295.262284262973;641.8940874755613;650.0768908150343;655;656.1896058367912;658.9341065893373;5363.865903352369;5402.837914862561;5406.567474602022;5433.402325744165;5574.832755017274;115007.42496287465;115947;116694;117673;120867.39566302112;99700;100509.49745251227;104541.47729261305;104806.47596761967;105332;86924.56537717397;88340.55829720938;89626;90704.5464772672;92488.53755731178;9895.25785566071;10027.398356098658;10086.747831303983;10289.022542858416;10349.9482502587;41273.587264126974;41320.1735965273;42361;42637.786811065744;43161.78419107884;50405.24392134047;52248.47751522436;52574.47425525696;52870.471295288095;53217.20174197468;15475.380984760484;15602.921985390001;15662.608434789221;15715.449959251371;15732.60668483297;15909.204539773196;19158.71261931044;19790.10944507472;19792.20831166795;19853.900730496254;17205.967641941523;17585.208665610335;17847.107644617456;18149.81850181534;18313.450596482126;17309.74035389445;17528.56178595545;17607.20767565488;17640.29438822484;17686.292548297923;17000.574985624964;17343.306267749653;17350.305987760854;17421.128943553023;17507.47477575675;12324.445399956845;12373;12838.422270998015;12867.613971580868;12984.415701293276;12514.801436858797;12821;12828.743425131259;12843.422046007765;13052.21686698801 16:06:05 INFO - PID 5182 | __end_tp_report 16:06:05 INFO - PID 5182 | __start_cc_report 16:06:05 INFO - PID 5182 | _x_x_mozilla_cycle_collect,366 16:06:05 INFO - PID 5182 | __end_cc_report 16:06:05 INFO - PID 5182 | __startTimestamp1504479965510__endTimestamp 16:06:05 INFO - PID 5182 | 16:06:05 INFO - PID 5182 | ------- Summary: start ------- 16:06:05 INFO - PID 5182 | Number of tests: 6 16:06:05 INFO - PID 5182 | 16:06:05 INFO - PID 5182 | [#0] dojo.html Cycles:165 Average:6428.04 Median:6695.70 stddev:757.34 (11.3%) stddev-sans-first:719.36 16:06:05 INFO - PID 5182 | Values: 3320.5 3355.9 3357.6 3361.6 3376.1 6393.2 6431.2 6463.2 6479.5 6481.9 6563.2 6598.6 6606.8 6635.6 6659.8 5953.9 5987.8 6091.8 6122.7 6184.5 5851.4 5911.1 5925.7 5931.4 6002.3 5901.1 5979.8 5979.9 5980.4 5990.0 5870.3 5886.4 5975.8 6001.5 6006.3 5826.3 5907.9 5909.8 5927.9 5976.6 6472.1 6504.9 6536.0 6545.7 6546.7 6986.5 6999.1 7068.4 7070.5 7098.2 6993.5 7011.9 7079.3 7120.3 7143.3 6518.0 6524.5 6586.2 6603.4 6622.8 7125.7 7224.7 7238.8 7335.3 7346.1 6893.8 6904.7 6905.6 6930.8 6936.2 6983.6 6993.3 7063.3 7087.9 7090.1 6952.7 6957.1 7038.9 7040.0 7052.9 6791.6 6920.0 6940.5 6964.4 7004.4 5471.1 5519.0 5531.1 5563.6 5564.3 4799.9 4825.7 4826.4 4854.8 4864.1 6094.8 6123.4 6193.2 6208.3 6210.2 6858.3 6992.8 6999.2 6999.3 7030.6 6042.2 6049.0 6064.0 6121.9 6133.3 5963.7 5968.7 6028.1 6048.5 6053.1 6755.1 6759.8 6826.7 6827.6 6874.5 6833.0 6890.8 6951.2 6951.8 6960.2 6695.7 6709.2 6749.0 6777.8 6782.4 6744.4 6763.0 6772.8 6825.7 6865.2 6914.5 6917.4 7003.6 7027.2 7087.1 6775.0 6775.1 6821.0 6848.5 6858.0 6699.9 6751.4 6777.8 6844.3 6881.6 6450.8 6599.8 6682.5 6725.2 6773.3 6703.7 6709.5 6801.0 6821.8 6826.0 6603.4 6621.2 6674.7 6694.7 6717.1 16:06:05 INFO - PID 5182 | 16:06:05 INFO - PID 5182 | [#1] ext.html Cycles:180 Average:37000.12 Median:34849.24 stddev:53295.55 (152.9%) stddev-sans-first:53375.72 16:06:05 INFO - PID 5182 | Values: 794.2 843.8 869.3 1137.8 1151.2 29426.3 30305.2 39235.6 40437.6 40701.0 39629.6 39997.0 40100.8 40345.2 40410.2 7159.4 7571.7 7819.1 7824.9 7831.4 7636.7 7748.9 7771.7 7772.7 7777.5 7699.1 7757.9 7761.6 7802.0 7810.6 7719.8 7725.7 7739.5 7746.3 7748.8 7652.7 7715.3 7719.5 7726.8 7729.3 36082.8 36197.8 36230.0 36345.6 36376.5 3545.8 3581.3 3583.7 3585.6 3588.7 29807.7 29823.0 29885.6 29912.4 29920.6 4997.8 5168.3 5184.7 5219.9 5220.8 184602.0 187161.0 187293.0 187336.0 187361.0 44730.0 45137.5 45149.3 45222.8 45351.0 46790.3 46930.3 47477.8 47490.0 47615.5 14372.1 16140.0 16255.3 16330.7 16342.1 12050.5 13211.0 13582.5 13596.1 13608.5 14369.1 14391.9 14439.3 14460.8 14564.4 5624.5 5713.8 5733.0 5774.3 5786.8 1009.2 1027.1 1027.4 1028.3 1036.7 22567.4 22956.5 22981.8 23024.5 23153.3 1124.2 1131.6 1132.1 1136.5 1139.4 11903.9 12086.4 12103.6 12149.8 12217.4 229388.0 230115.0 230133.0 230268.0 230436.0 50054.5 50059.0 50136.2 50177.5 50225.0 1450.1 1453.7 1462.6 1464.7 1471.1 21901.5 22307.1 22409.6 22584.7 22744.2 36449.1 36449.3 36453.6 36467.0 36591.6 197672.0 197888.0 198543.0 199676.0 200168.0 32957.7 34465.7 35232.8 35241.5 35254.8 42605.8 44221.0 44281.0 44314.8 44431.6 31609.0 32401.4 32489.0 32498.5 33495.3 31964.4 32339.0 32407.5 32705.5 33069.0 36307.0 36376.5 36406.0 36528.8 36603.6 31868.7 32455.2 32489.0 32605.8 32795.3 23094.3 23617.3 23958.2 24590.4 25198.2 16:06:05 INFO - PID 5182 | 16:06:05 INFO - PID 5182 | [#2] jquery.html Cycles:180 Average:36875.37 Median:22477.38 stddev:83566.53 (371.8%) stddev-sans-first:83756.48 16:06:05 INFO - PID 5182 | Values: 560.8 564.6 567.1 575.4 578.1 21777.5 22278.7 22450.3 22504.4 23189.5 26001.7 26409.3 26410.0 26463.2 26541.2 6451.7 6562.2 6575.0 6581.5 6592.0 6487.7 6548.5 6868.4 7119.6 7184.7 7179.4 7195.6 7202.9 7207.6 7208.2 7167.2 7174.4 7175.6 7176.9 7183.3 7019.9 7028.7 7032.3 7035.6 7036.8 30723.8 31493.5 31510.8 31630.7 31676.5 2107.7 2163.1 2168.7 2172.3 2180.7 20917.6 21512.2 21532.1 21535.1 21556.4 2434.4 2491.2 2538.1 2555.7 2560.0 137425.3 142011.3 142095.3 142467.0 142602.0 20630.8 21662.2 22071.1 22097.9 22449.4 43616.6 43642.0 43757.3 43857.8 43859.1 10649.1 10799.8 10813.9 10869.9 10994.8 7805.7 8650.5 8654.5 8688.3 8820.1 9650.3 11543.7 11576.5 11730.8 11769.8 4122.2 4178.9 4262.2 4301.4 4325.0 961.3 967.5 983.1 993.8 1005.5 19673.5 19858.8 20162.0 23784.7 23950.2 1034.4 1070.1 1071.9 1088.2 1088.4 11422.4 11490.0 11491.9 11602.7 11617.2 487183.0 490540.0 492854.0 499271.0 499421.0 125419.4 125599.0 126899.0 130323.3 131595.0 1238.4 1252.4 1280.8 1289.9 1290.4 21020.7 21051.7 21317.1 21497.4 21505.9 59648.0 59690.4 59904.4 60455.7 61262.1 73920.6 78083.2 78341.0 78805.0 78949.2 14353.2 14475.9 14923.4 15053.6 15070.4 23295.1 23537.3 23684.5 23792.9 23952.0 18427.2 18643.3 18993.0 19121.9 19202.7 16783.6 16942.2 16947.7 17503.5 17533.5 18970.1 19201.6 19204.7 19211.1 19281.5 14775.4 14819.0 14893.8 14897.6 14898.3 18148.0 18215.8 18327.8 18668.7 18807.2 16:06:05 INFO - PID 5182 | 16:06:05 INFO - PID 5182 | [#3] mootools.html Cycles:180 Average:9560.23 Median:2717.07 stddev:6803.54 (250.4%) stddev-sans-first:6794.26 16:06:05 INFO - PID 5182 | Values: 1290.7 1350.8 1363.2 1365.3 1374.6 14745.9 15438.8 15453.7 15472.5 15613.5 15734.0 15980.2 15999.6 16004.3 16012.8 2797.5 2852.3 2853.0 2853.5 2856.7 2763.7 2952.0 2953.5 2954.6 2961.6 2768.9 2800.1 2814.3 2841.7 2841.8 2755.9 2759.7 2767.6 2772.9 2778.1 2711.7 2712.7 2721.4 2723.0 2723.9 14955.9 15263.8 15280.7 15281.8 15291.5 8045.3 8283.3 8293.1 8293.8 8307.8 5334.9 5442.3 5469.3 5510.8 5569.6 1887.7 1891.7 1894.9 1924.2 1932.7 26547.5 26652.0 26778.6 26782.0 26876.0 7284.4 7365.7 7539.0 7610.5 7629.9 17524.8 17764.9 17767.8 17773.6 17799.8 7979.2 8121.3 8122.6 8125.6 8128.2 7408.5 7475.4 7500.2 7507.0 7508.5 5055.3 5149.1 5154.5 5239.6 5249.1 1428.1 1444.9 1445.6 1460.2 1466.3 919.2 939.7 949.8 953.5 958.5 7990.8 8014.6 8023.1 8042.2 8086.4 725.3 731.9 737.4 742.6 742.7 5083.3 5386.1 5687.4 6050.5 6147.3 21207.8 22418.9 22431.8 22468.6 22527.8 21174.0 22012.3 22178.3 22183.6 22362.0 10798.5 11592.5 11668.6 11938.2 11946.8 9076.6 9138.3 9161.5 9324.3 9433.9 18519.5 18870.0 19086.3 19397.7 19525.6 19064.1 19244.7 19374.0 19770.6 20383.9 12116.6 12380.5 12383.5 12406.9 12416.3 13983.9 13995.9 14037.2 14122.0 14137.3 10836.3 11143.5 11146.7 11160.9 11329.8 10828.2 10879.9 10919.3 11020.4 11231.9 9834.0 10002.0 10012.7 10020.1 10042.5 7383.4 7399.3 7404.3 7416.8 7458.3 7468.7 7502.7 7618.2 7621.9 7673.0 16:06:05 INFO - PID 5182 | 16:06:05 INFO - PID 5182 | [#4] prototype.html Cycles:180 Average:5653.88 Median:6358.17 stddev:1593.75 (25.1%) stddev-sans-first:1550.63 16:06:05 INFO - PID 5182 | Values: 503.3 508.3 534.6 540.5 541.4 6487.0 6508.5 6518.5 6529.6 6566.0 6186.5 6353.7 6358.3 6369.2 6385.3 5103.2 5107.8 5117.9 5118.0 5156.2 4989.7 5108.4 5134.4 5184.2 5189.1 5070.1 5095.8 5100.3 5103.6 5109.5 5070.4 5078.9 5080.1 5083.4 5090.3 4547.5 4581.9 4586.6 4589.2 4598.9 6331.2 6344.0 6355.2 6358.0 6366.5 5842.8 5858.5 5863.2 5880.0 5887.5 6694.9 6695.7 6704.3 6704.8 6709.1 4949.7 4980.4 5005.3 5008.8 5013.5 7092.8 7107.6 7143.6 7215.8 7219.6 5094.8 5113.5 5136.4 5141.5 5146.2 5192.4 5194.2 5214.5 5215.7 5224.7 5749.6 5808.4 5819.9 5868.5 5875.2 6604.5 6674.0 6674.7 6753.8 6755.0 1690.9 1742.4 1753.3 1765.7 1784.6 1201.3 1203.7 1247.9 1249.2 1250.6 5853.6 5855.6 5869.8 5916.3 5928.8 6566.8 6693.0 6820.9 6887.9 6951.5 5883.3 5985.7 5994.0 6048.8 6051.9 3612.4 3630.6 3632.1 3664.6 3669.9 6625.1 6656.7 6675.0 6704.3 6716.7 6925.4 6988.7 7040.5 7043.3 7052.9 6515.6 6519.7 6551.4 6642.3 6715.5 6835.8 6902.2 6965.2 6969.9 6972.9 6841.1 6871.9 6876.7 6932.1 6935.5 6695.7 6745.3 6750.4 6759.5 6781.5 5067.3 5069.4 5082.2 5132.6 5163.8 6663.4 6950.3 7024.0 7050.7 7051.4 6455.4 6538.5 6599.3 6731.6 6732.5 6619.9 6694.0 6709.5 6714.5 6741.6 6613.9 6640.8 6646.6 6649.5 6655.1 6599.7 6627.2 6635.9 6706.3 6746.7 6390.8 6440.7 6460.6 6466.6 6533.4 16:06:05 INFO - PID 5182 | 16:06:05 INFO - PID 5182 | [#5] yui.html Cycles:180 Average:23336.70 Median:18142.23 stddev:33111.78 (182.5%) stddev-sans-first:33161.75 16:06:05 INFO - PID 5182 | Values: 884.8 898.3 917.8 917.9 933.6 16171.1 16733.1 17095.7 17117.5 19649.5 19453.5 19461.7 19482.0 19505.6 19558.5 913.6 935.1 993.6 1001.0 1001.8 1009.7 1017.4 1019.5 1019.6 1024.7 988.9 1041.7 1047.6 1064.7 1067.3 1035.8 1039.1 1041.2 1041.5 1043.0 747.9 754.5 755.5 756.0 757.1 16336.6 17690.3 17928.4 18134.6 18230.2 18429.9 18702.3 18894.8 18924.7 18953.1 15241.2 15322.5 15369.2 15371.6 15379.5 3599.6 3768.0 3821.4 3843.4 3848.1 128351.0 128421.0 128986.0 130627.0 133232.0 16081.0 16846.7 16865.1 16866.2 17070.5 30238.1 30272.5 30700.2 30912.4 30933.4 7567.1 9036.0 9146.8 9161.6 9161.7 3760.3 4171.7 4350.0 4354.2 4357.4 4911.2 4993.6 4997.5 5004.3 5005.5 1006.3 1044.3 1051.7 1054.7 1055.5 668.8 675.6 683.6 687.4 688.0 11300.5 11406.4 11788.9 12167.3 12295.3 641.9 650.1 655.0 656.2 658.9 5363.9 5402.8 5406.6 5433.4 5574.8 115007.4 115947.0 116694.0 117673.0 120867.4 99700.0 100509.5 104541.5 104806.5 105332.0 86924.6 88340.6 89626.0 90704.5 92488.5 9895.3 10027.4 10086.7 10289.0 10349.9 41273.6 41320.2 42361.0 42637.8 43161.8 50405.2 52248.5 52574.5 52870.5 53217.2 15475.4 15602.9 15662.6 15715.4 15732.6 15909.2 19158.7 19790.1 19792.2 19853.9 17206.0 17585.2 17847.1 18149.8 18313.5 17309.7 17528.6 17607.2 17640.3 17686.3 17000.6 17343.3 17350.3 17421.1 17507.5 12324.4 12373.0 12838.4 12867.6 12984.4 12514.8 12821.0 12828.7 12843.4 13052.2 16:06:05 INFO - PID 5182 | -------- Summary: end -------- 16:06:05 INFO - PID 5182 | 16:06:06 INFO - TEST-INFO | 5182: exit 0 16:06:06 INFO - TEST-OK | dromaeo_css | took 1111066ms 16:06:06 INFO - TEST-START | kraken 16:06:06 INFO - Initialising browser for kraken test... 16:06:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49228/getInfo.html -profile /tmp/tmpQxS5ha/profile 16:06:06 INFO - TEST-INFO | started process 5557 (/builds/slave/test/build/application/firefox/firefox http://localhost:49228/getInfo.html) 16:06:12 INFO - TEST-INFO | 5557: exit 0 16:06:12 INFO - Browser initialized. 16:06:12 INFO - Running cycle 1/1 for kraken test... 16:06:12 INFO - Using env: {'DISPLAY': ':0', 16:06:12 INFO - 'HOME': '/home/cltbld', 16:06:12 INFO - 'JSGC_DISABLE_POISONING': '1', 16:06:12 INFO - 'LANG': 'en_US.UTF-8', 16:06:12 INFO - 'LANGUAGE': 'en_US:en', 16:06:12 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:06:12 INFO - 'LOGNAME': 'cltbld', 16:06:12 INFO - 'MAIL': '/var/mail/cltbld', 16:06:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:06:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:06:12 INFO - 'MOZ_CRASHREPORTER': '1', 16:06:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:12 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:06:12 INFO - 'MOZ_NO_REMOTE': '1', 16:06:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:06:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:12 INFO - 'NO_EM_RESTART': '1', 16:06:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:12 INFO - 'PWD': '/builds/slave/test', 16:06:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:06:12 INFO - 'RUST_BACKTRACE': 'full', 16:06:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:06:12 INFO - 'SHELL': '/bin/bash', 16:06:12 INFO - 'SHLVL': '1', 16:06:12 INFO - 'STYLO_FORCE_ENABLED': '1', 16:06:12 INFO - 'STYLO_THREADS': '1', 16:06:12 INFO - 'TERM': 'linux', 16:06:12 INFO - 'TMOUT': '86400', 16:06:12 INFO - 'USER': 'cltbld', 16:06:12 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971', 16:06:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:12 INFO - '_': '/tools/buildbot/bin/python'} 16:06:12 INFO - TEST-INFO | started process 5742 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQxS5ha/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:06:13 INFO - PID 5742 | 16:06:13 INFO - PID 5742 | (/builds/slave/test/build/application/firefox/firefox:5794): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:06:13 INFO - PID 5742 | 16:06:23 INFO - PID 5742 | RSS: Main: 170762240 16:06:23 INFO - PID 5742 | 16:06:26 INFO - PID 5742 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 16:06:27 INFO - PID 5742 | RSS: Main: 175452160 16:06:27 INFO - PID 5742 | 16:06:30 INFO - PID 5742 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 16:06:30 INFO - PID 5742 | RSS: Main: 179957760 16:06:30 INFO - PID 5742 | 16:06:33 INFO - PID 5742 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 16:06:34 INFO - PID 5742 | RSS: Main: 173146112 16:06:34 INFO - PID 5742 | 16:06:36 INFO - PID 5742 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 16:06:37 INFO - PID 5742 | RSS: Main: 173711360 16:06:37 INFO - PID 5742 | 16:06:39 INFO - PID 5742 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:06:39 INFO - PID 5742 | RSS: Main: 173719552 16:06:39 INFO - PID 5742 | 16:06:42 INFO - PID 5742 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 16:06:43 INFO - PID 5742 | RSS: Main: 175104000 16:06:43 INFO - PID 5742 | 16:06:47 INFO - PID 5742 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 16:06:47 INFO - PID 5742 | RSS: Main: 175124480 16:06:47 INFO - PID 5742 | 16:06:50 INFO - PID 5742 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 16:06:51 INFO - PID 5742 | RSS: Main: 175374336 16:06:51 INFO - PID 5742 | 16:06:52 INFO - PID 5742 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:06:53 INFO - PID 5742 | RSS: Main: 175239168 16:06:53 INFO - PID 5742 | 16:06:55 INFO - PID 5742 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:06:55 INFO - PID 5742 | RSS: Main: 175415296 16:06:55 INFO - PID 5742 | 16:06:57 INFO - PID 5742 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:06:58 INFO - PID 5742 | RSS: Main: 172359680 16:06:58 INFO - PID 5742 | 16:07:01 INFO - PID 5742 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:07:01 INFO - PID 5742 | RSS: Main: 172523520 16:07:01 INFO - PID 5742 | 16:07:04 INFO - PID 5742 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:07:05 INFO - PID 5742 | RSS: Main: 172687360 16:07:05 INFO - PID 5742 | 16:07:07 INFO - PID 5742 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 16:07:07 INFO - PID 5742 | RSS: Main: 174129152 16:07:07 INFO - PID 5742 | 16:07:07 INFO - PID 5742 | __start_tp_report 16:07:07 INFO - PID 5742 | _x_x_mozilla_page_load 16:07:07 INFO - PID 5742 | _x_x_mozilla_page_load_details 16:07:07 INFO - PID 5742 | |i|pagename|runs| 16:07:07 INFO - PID 5742 | |0;ai-astar;100;108;101;107;109;117;110;99;110;109 16:07:07 INFO - PID 5742 | |1;audio-beat-detection;143;117;146;178;116;147;148;114;177;120 16:07:07 INFO - PID 5742 | |2;audio-dft;166;162;162;160;164;162;160;166;167;160 16:07:07 INFO - PID 5742 | |3;audio-fft;73;75;75;170;77;74;73;75;167;74 16:07:07 INFO - PID 5742 | |4;audio-oscillator;79;79;131;78;85;80;136;80;78;131 16:07:07 INFO - PID 5742 | |5;imaging-gaussian-blur;113;114;115;114;114;114;115;115;113;114 16:07:07 INFO - PID 5742 | |6;imaging-darkroom;149;147;147;147;148;147;147;156;151;148 16:07:07 INFO - PID 5742 | |7;imaging-desaturate;94;95;94;93;93;94;94;94;94;94 16:07:07 INFO - PID 5742 | |8;json-parse-financial;53;53;53;52;52;51;52;54;53;52 16:07:07 INFO - PID 5742 | |9;json-stringify-tinderbox;38;38;37;37;38;37;38;38;38;37 16:07:07 INFO - PID 5742 | |10;stanford-crypto-aes;77;77;75;76;75;79;77;76;74;73 16:07:07 INFO - PID 5742 | |11;stanford-crypto-ccm;117;120;117;116;117;117;122;127;117;164 16:07:07 INFO - PID 5742 | |12;stanford-crypto-pbkdf2;180;172;170;172;170;178;170;182;168;169 16:07:07 INFO - PID 5742 | |13;stanford-crypto-sha256-iterative;64;66;66;66;63;62;66;65;63;66 16:07:07 INFO - PID 5742 | __end_tp_report 16:07:07 INFO - PID 5742 | __start_cc_report 16:07:07 INFO - PID 5742 | _x_x_mozilla_cycle_collect,961 16:07:07 INFO - PID 5742 | __end_cc_report 16:07:07 INFO - PID 5742 | __startTimestamp1504480027526__endTimestamp 16:07:07 INFO - PID 5742 | 16:07:07 INFO - PID 5742 | ------- Summary: start ------- 16:07:07 INFO - PID 5742 | Number of tests: 14 16:07:07 INFO - PID 5742 | 16:07:07 INFO - PID 5742 | [#0] ai-astar Cycles:10 Average:107.00 Median:109.50 stddev:5.54 (5.1%) stddev-sans-first:5.26 16:07:07 INFO - PID 5742 | Values: 100.0 108.0 101.0 107.0 109.0 117.0 110.0 99.0 110.0 109.0 16:07:07 INFO - PID 5742 | 16:07:07 INFO - PID 5742 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:146.50 stddev:23.92 (16.3%) stddev-sans-first:25.35 16:07:07 INFO - PID 5742 | Values: 143.0 117.0 146.0 178.0 116.0 147.0 148.0 114.0 177.0 120.0 16:07:07 INFO - PID 5742 | 16:07:07 INFO - PID 5742 | [#2] audio-dft Cycles:10 Average:162.90 Median:163.00 stddev:2.69 (1.6%) stddev-sans-first:2.60 16:07:07 INFO - PID 5742 | Values: 166.0 162.0 162.0 160.0 164.0 162.0 160.0 166.0 167.0 160.0 16:07:07 INFO - PID 5742 | 16:07:07 INFO - PID 5742 | [#3] audio-fft Cycles:10 Average:93.30 Median:74.50 stddev:39.66 (53.2%) stddev-sans-first:41.38 16:07:07 INFO - PID 5742 | Values: 73.0 75.0 75.0 170.0 77.0 74.0 73.0 75.0 167.0 74.0 16:07:07 INFO - PID 5742 | 16:07:07 INFO - PID 5742 | [#4] audio-oscillator Cycles:10 Average:95.70 Median:79.00 stddev:25.62 (32.4%) stddev-sans-first:26.45 16:07:07 INFO - PID 5742 | Values: 79.0 79.0 131.0 78.0 85.0 80.0 136.0 80.0 78.0 131.0 16:07:07 INFO - PID 5742 | 16:07:07 INFO - PID 5742 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.67 16:07:07 INFO - PID 5742 | Values: 113.0 114.0 115.0 114.0 114.0 114.0 115.0 115.0 113.0 114.0 16:07:07 INFO - PID 5742 | 16:07:07 INFO - PID 5742 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:148.00 stddev:2.87 (1.9%) stddev-sans-first:3.04 16:07:07 INFO - PID 5742 | Values: 149.0 147.0 147.0 147.0 148.0 147.0 147.0 156.0 151.0 148.0 16:07:07 INFO - PID 5742 | 16:07:07 INFO - PID 5742 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 16:07:07 INFO - PID 5742 | Values: 94.0 95.0 94.0 93.0 93.0 94.0 94.0 94.0 94.0 94.0 16:07:07 INFO - PID 5742 | 16:07:07 INFO - PID 5742 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:53.00 stddev:0.85 (1.6%) stddev-sans-first:0.88 16:07:07 INFO - PID 5742 | Values: 53.0 53.0 53.0 52.0 52.0 51.0 52.0 54.0 53.0 52.0 16:07:07 INFO - PID 5742 | 16:07:07 INFO - PID 5742 | [#9] json-stringify-tinderbox Cycles:10 Average:37.60 Median:38.00 stddev:0.52 (1.4%) stddev-sans-first:0.53 16:07:07 INFO - PID 5742 | Values: 38.0 38.0 37.0 37.0 38.0 37.0 38.0 38.0 38.0 37.0 16:07:07 INFO - PID 5742 | 16:07:07 INFO - PID 5742 | [#10] stanford-crypto-aes Cycles:10 Average:75.90 Median:76.50 stddev:1.73 (2.3%) stddev-sans-first:1.79 16:07:07 INFO - PID 5742 | Values: 77.0 77.0 75.0 76.0 75.0 79.0 77.0 76.0 74.0 73.0 16:07:07 INFO - PID 5742 | 16:07:07 INFO - PID 5742 | [#11] stanford-crypto-ccm Cycles:10 Average:123.40 Median:118.50 stddev:14.66 (12.4%) stddev-sans-first:15.37 16:07:07 INFO - PID 5742 | Values: 117.0 120.0 117.0 116.0 117.0 117.0 122.0 127.0 117.0 164.0 16:07:07 INFO - PID 5742 | 16:07:07 INFO - PID 5742 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.10 Median:172.00 stddev:5.00 (2.9%) stddev-sans-first:4.64 16:07:07 INFO - PID 5742 | Values: 180.0 172.0 170.0 172.0 170.0 178.0 170.0 182.0 168.0 169.0 16:07:07 INFO - PID 5742 | 16:07:07 INFO - PID 5742 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:66.00 stddev:1.57 (2.4%) stddev-sans-first:1.64 16:07:07 INFO - PID 5742 | Values: 64.0 66.0 66.0 66.0 63.0 62.0 66.0 65.0 63.0 66.0 16:07:07 INFO - PID 5742 | -------- Summary: end -------- 16:07:07 INFO - PID 5742 | 16:07:07 INFO - PID 5742 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:07:08 INFO - TEST-INFO | 5742: exit 0 16:07:08 INFO - TEST-OK | kraken | took 62013ms 16:07:08 INFO - SUITE-END | took 1173s 16:07:08 INFO - Completed test suite (00:19:33) 16:07:08 INFO - javascript benchmark 16:07:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8721.024835301509, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3320.4687250039997, 3355.89932302031, 3357.6306606273306, 3361.59660840699, 3376.105332086997, 6393.200849893763, 6431.2282526096815, 6463.192100987377, 6479.514036447267, 6481.870362592757, 6563.1796025496815, 6598.604083754978, 6606.768763093292, 6635.60186388817, 6659.833504162399, 5953.851153721148, 5987.820365389023, 6091.847703807396, 6122.693865306739, 6184.536159788011, 5851.385604511529, 5911.054231322989, 5925.73334199962, 5931.436513531207, 6002.279726432834, 5901.055831067051, 5979.820605381846, 5979.940200598004, 5980.372060933626, 5989.970050149743, 5870.295564532284, 5886.440788125121, 5975.760969561213, 6001.459868611846, 6006.279246490405, 5826.271716035496, 5907.940920590782, 5909.8227053188475, 5927.851803704899, 5976.641401515923, 6472.126262954535, 6504.9349506504805, 6535.967320163416, 6545.738170473175, 6546.70539825709, 6986.476014298948, 6999.12510936133, 7068.36384725372, 7070.540414873017, 7098.219195888448, 6993.475489338269, 7011.859762804766, 7079.256678048782, 7120.25237350076, 7143.285671432867, 6517.967410162966, 6524.510661700391, 6586.176727909012, 6603.438707709851, 6622.801315960529, 7125.679344429514, 7224.74713385029, 7238.819029524304, 7335.303146201102, 7346.11846578414, 6893.75871844483, 6904.654767261617, 6905.551139175937, 6930.792076237721, 6936.167659880846, 6983.615901125385, 6993.335633114846, 7063.2583578724525, 7087.858242835115, 7090.149182098181, 6952.687129079203, 6957.130358705162, 7038.944158376285, 7040.0, 7052.92947070533, 6791.592504449749, 6919.965400172967, 6940.479464040217, 6964.373206411447, 7004.4396448284015, 5471.097268950658, 5518.972405138029, 5531.115021596605, 5563.554915606742, 5564.304461942257, 4799.928001079987, 4825.710457372569, 4826.396700412449, 4854.75726213695, 4864.075825593126, 6094.786682466092, 6123.357047509948, 6193.163922870445, 6208.255009398811, 6210.192674952227, 6858.279880612564, 6992.755253566203, 6999.23008469068, 6999.300069993062, 7030.578165309996, 6042.214512113485, 6048.969755151196, 6064.02975523914, 6121.908171377433, 6133.325334213233, 5963.731632076567, 5968.731407086691, 6028.125921741411, 6048.516118710492, 6053.061775424816, 6755.088063111417, 6759.763408280785, 6826.692798824164, 6827.624480653512, 6874.450043996469, 6833.043373927555, 6890.758823441155, 6951.200611929693, 6951.756688515878, 6960.234374218933, 6695.698693558899, 6709.194896612243, 6749.021391898049, 6777.830554236183, 6782.423494003065, 6744.359285867835, 6762.966185169043, 6772.830679233059, 6825.658717064028, 6865.176178858569, 6914.51598388108, 6917.377436030782, 7003.6498175092065, 7027.15674119109, 7087.14954205478, 6774.966125169342, 6775.085363475868, 6821.0, 6848.486363522856, 6858.039874417485, 6699.866002679822, 6751.426128779109, 6777.762778302736, 6844.315568443116, 6881.587104773729, 6450.8064758057335, 6599.769008084694, 6682.465402767864, 6725.192976842614, 6773.288804675438, 6703.698333574904, 6709.4632429404555, 6801.047853300641, 6821.761238356832, 6825.976103584502, 6603.372679595431, 6621.238557565942, 6674.699638516376, 6694.732210711712, 6717.093192418961], "value": 6371.863262255025, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.221662770485, 843.7974886027353, 869.3306154261217, 1137.8008848451525, 1151.1941640851405, 29426.264343391427, 30305.242368940788, 39235.60764392359, 40437.59562404375, 40701.0, 39629.60370396295, 39997.0, 40100.79949600255, 40345.19309613806, 40410.19179616406, 7159.35565799078, 7571.734989275363, 7819.100803407624, 7824.882626760603, 7831.41264405169, 7636.694532218732, 7748.922510774904, 7771.727989520368, 7772.65023073963, 7777.5333479991095, 7699.076110866676, 7757.883631745528, 7761.573113478757, 7801.960990195041, 7810.570418626973, 7719.8070048248965, 7725.72959946402, 7739.458237923348, 7746.30283274508, 7748.845023099535, 7652.693892244303, 7715.344195743369, 7719.536827790351, 7726.845463090763, 7729.265719756614, 36082.819585902165, 36197.81901090504, 36230.0, 36345.63654363449, 36376.45435318446, 3545.7872527648424, 3581.3195492856275, 3583.6595523425235, 3585.551806024247, 3588.74878758487, 29807.70192298071, 29823.0, 29885.551716724265, 29912.40175196484, 29920.551191732142, 4997.775100120467, 5168.328117344721, 5184.7148406837605, 5219.895602087975, 5220.843374698765, 184602.0, 187161.0, 187293.0, 187336.0, 187361.0, 44730.0, 45137.54862451399, 45149.29104253755, 45222.77388613036, 45351.0, 46790.29814552784, 46930.29604555934, 47477.81305467322, 47490.0, 47615.52384476111, 14372.137671739729, 16140.0, 16255.349786008665, 16330.67338653244, 16342.10118443497, 12050.457729402198, 13211.009174311965, 13582.456701732017, 13596.11625244356, 13608.455661773616, 14369.13785172893, 14391.92804035994, 14439.278036098152, 14460.783088253685, 14564.41742330316, 5624.521915637135, 5713.771449142071, 5732.97383662867, 5774.335951365562, 5786.797462088806, 1009.1976878381704, 1027.0653705128448, 1027.3527677563088, 1028.290479569095, 1036.7097212780284, 22567.435814104778, 22956.54086918252, 22981.77018229796, 23024.539509210055, 23153.305400838002, 1124.241137232365, 1131.5643477261149, 1132.0547342968548, 1136.4829002803704, 1139.3847322445952, 11903.928646421864, 12086.39568021613, 12103.63689089311, 12149.817752733716, 12217.389130543437, 229388.0, 230115.0, 230133.0, 230268.0, 230436.0, 50054.49945500498, 50059.0, 50136.247956280684, 50177.49822501728, 50225.0, 1450.1081834671602, 1453.7455945209547, 1462.5977856089448, 1464.655805885616, 1471.1026273972868, 21901.452463688536, 22307.10771569119, 22409.551808963402, 22584.66123008189, 22744.20395286157, 36449.088772780895, 36449.271014579026, 36453.63546364503, 36467.0, 36591.63408365989, 197672.0, 197888.0, 198543.0, 199676.0, 200168.0, 32957.67042329546, 34465.65534344625, 35232.823835880656, 35241.47137792986, 35254.82372588121, 42605.78697106495, 44221.0, 44281.0, 44314.77842610766, 44431.55568444274, 31609.0, 32401.351972959936, 32489.0, 32498.51252231171, 33495.33009339849, 31964.360712786078, 32339.0, 32407.51388729124, 32705.509417359233, 33069.0, 36307.0, 36376.454353185254, 36406.0, 36528.81735591305, 36603.63396366109, 31868.6813131875, 32455.188620284684, 32489.0, 32605.836970814995, 32795.34409311848, 23094.307170784905, 23617.29148125559, 23958.161464348665, 24590.385240369134, 25198.244052677717], "value": 17033.76361321692, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.8205374280229, 564.5511818104607, 567.0926517571886, 575.3929604267502, 578.1328007988018, 21777.455563610918, 22278.665820012713, 22450.32649020528, 22504.43738906526, 23189.536209275804, 26001.739982600167, 26409.339766505847, 26410.0, 26463.206103816916, 26541.203763887064, 6451.741930322782, 6562.212534495867, 6574.967125164368, 6581.473482121419, 6592.044153597725, 6487.708053137597, 6548.541602087832, 6868.416184624288, 7119.572825630453, 7184.676689548983, 7179.425645948311, 7195.568265904037, 7202.927970720278, 7207.5675459472595, 7208.207097219301, 7167.211606723256, 7174.426045916314, 7175.641217939082, 7176.856462870714, 7183.281671832801, 7019.929800701979, 7028.683709233097, 7032.261612530662, 7035.613041282727, 7036.788896333118, 30723.846380767955, 31493.52759708606, 31510.842445787854, 31630.683693163002, 31676.524852127237, 2107.7259956205594, 2163.0590693048416, 2168.728908886389, 2172.3048624440185, 2180.661997390391, 20917.5816483671, 21512.247071352584, 21532.138714451405, 21535.138594456203, 21556.353309400743, 2434.440078781872, 2491.2401717476164, 2538.149719843836, 2555.744425557448, 2560.039985005623, 137425.312873436, 142011.28994355066, 142095.28952355275, 142467.0, 142602.0, 20630.793692063187, 21662.241821536172, 22071.12395446373, 22097.889510552344, 22449.438764030703, 43616.563834361885, 43642.0, 43757.34363984479, 43857.78071109624, 43859.12281754411, 10649.094826939798, 10799.784004320027, 10813.945930270298, 10869.945650271857, 10994.835077473845, 7805.726799561988, 8650.486164921114, 8654.48073115615, 8688.304935605136, 8820.117988201257, 9650.276229282692, 11543.711407214887, 11576.536938522364, 11730.824037639442, 11769.764604707858, 4122.216778812016, 4178.86877692379, 4262.211490874198, 4301.397804307399, 4324.978375108104, 961.3414810854447, 967.5355829202023, 983.1053741095569, 993.8211121997965, 1005.4872015272264, 19673.50816229577, 19858.808471491757, 20162.0, 23784.699725890856, 23950.161744338864, 1034.3897100710467, 1070.0583486531957, 1071.9464026798785, 1088.151242031217, 1088.4448931045224, 11422.428878556038, 11490.0, 11491.908268714458, 11602.709932251593, 11617.244879082833, 487183.0, 490540.0, 492854.0, 499271.0, 499421.0, 125419.3729031349, 125599.0, 126899.0, 130323.34838325747, 131595.0, 1238.4365113873166, 1252.3863306979515, 1280.8206851040861, 1289.8839104480642, 1290.387066143593, 21020.68468972936, 21051.738084062858, 21317.147314107264, 21497.355079347646, 21505.892470537547, 59648.0, 59690.40309597022, 59904.400955989884, 60455.69772151111, 61262.08106878311, 73920.63039684767, 78083.21916780986, 78341.0, 78805.0, 78949.21050789418, 14353.210573418562, 14475.855241447873, 14923.403063877324, 15053.623659408602, 15070.397184112951, 23295.068197271918, 23537.293881183592, 23684.52630947406, 23792.88103559471, 23952.0, 18427.17077731532, 18643.347482838035, 18993.0, 19121.904390477957, 19202.711959320342, 16783.56638287466, 16942.152892355578, 16947.66104677924, 17503.47489575315, 17533.473995780147, 18970.146343414304, 19201.61596768085, 19204.71192932135, 19211.135498902862, 19281.51796205106, 14775.408983640535, 14819.036762610827, 14893.77659335089, 14897.627559311104, 14898.329575168927, 18148.0, 18215.817841821412, 18327.816721832613, 18668.7199692002, 18807.247710091444], "value": 13054.810306692236, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1290.6644272489152, 1350.7771217749073, 1363.2025265219845, 1365.3241645385535, 1374.635721533793, 14745.852541474582, 15438.84561154388, 15453.690926181484, 15472.458463953764, 15613.531594052196, 15734.0, 15980.200989950514, 15999.640030597413, 16004.279807408666, 16012.759808602881, 2797.5244208484505, 2852.286928267933, 2852.9714702853016, 2853.4863724529578, 2856.742893139617, 2763.69599344072, 2951.970480295202, 2953.4831404504234, 2954.5715871198663, 2961.585378047075, 2768.9169324920185, 2800.103966730647, 2814.3245621051005, 2841.7016213297616, 2841.829490230593, 2755.9448811023767, 2759.65504311961, 2767.5848622706653, 2772.916812495628, 2778.097118436496, 2711.6881558620616, 2712.715164907676, 2721.4285000149994, 2723.046933573253, 2723.9319017024536, 14955.850441495555, 15263.771043434357, 15280.694386112327, 15281.770773438408, 15291.541253762294, 8045.316148127416, 8283.29591984682, 8293.129221431784, 8293.8341233175, 8307.792305192359, 5334.866628334284, 5442.346918369782, 5469.343678758534, 5510.779568817283, 5569.637973531748, 1887.7451544041517, 1891.7446144770556, 1894.9336773213008, 1924.2110734598887, 1932.7390802241798, 26547.46905061907, 26651.999999999807, 26778.5983210252, 26782.0, 26876.0, 7284.380827629605, 7365.742199023116, 7538.962305188548, 7610.505317154367, 7629.923700763033, 17524.824751752574, 17764.9111754443, 17767.822321776875, 17773.555661108316, 17799.822001780074, 7979.202079791974, 8121.269085782308, 8122.634481448349, 8125.593720313961, 8128.187181281824, 7408.518446300973, 7475.401967842559, 7500.212477689873, 7507.024086868672, 7508.474406791473, 5055.266986287042, 5149.073166829931, 5154.5103215194495, 5239.607029472805, 5249.133892907704, 1428.0788891165141, 1444.8627380398846, 1445.6169115184484, 1460.2041887171517, 1466.2961778346453, 919.1911118216061, 939.7133874168308, 949.767307009787, 953.50894289441, 958.520739630185, 7990.760277191694, 8014.639341229658, 8023.077346105156, 8042.235987581171, 8086.433949623588, 725.2892165677665, 731.8902164675235, 737.4137560639198, 742.5915746339492, 742.7087435262168, 5083.313752643419, 5386.084365657847, 5687.402822703557, 6050.48570871472, 6147.293061298008, 21207.78792212089, 22418.88790556037, 22431.775682243297, 22468.550628987654, 22527.77472225257, 21174.0, 22012.339629811133, 22178.33464996053, 22183.556328873332, 22362.0, 10798.514066867167, 11592.47833847479, 11668.591599294154, 11938.164328497096, 11946.761064778655, 9076.591553379982, 9138.268938484773, 9161.49611771359, 9324.300677449353, 9433.905660943303, 18519.53701157482, 18870.0, 19086.33197838069, 19397.70903436478, 19525.60948781045, 19064.142113605198, 19244.711329330352, 19374.0, 19770.60458790845, 20383.8980805095, 12116.575919842764, 12380.504779809067, 12383.504659813507, 12406.937965310115, 12416.317102559446, 13983.860161398256, 13995.930020349833, 14037.1577705338, 14122.0, 14137.293135342987, 10836.349819010884, 11143.498542565767, 11146.721331966766, 11160.944195278971, 11329.773404532029, 10828.18788590843, 10879.891201088205, 10919.290246133976, 11020.44897755125, 11231.887681123084, 9834.01659834011, 10002.0, 10012.749681257736, 10020.09819116283, 10042.548085336031, 7383.372413344711, 7399.297066778648, 7404.296591823768, 7416.814579635552, 7458.328750412272, 7468.663910124166, 7502.737404190827, 7618.200088990576, 7621.885671714817, 7673.0], "value": 6791.9285383906745, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.32554377134653, 508.3137764018575, 534.6017217173206, 540.4541413172695, 541.4314969282252, 6487.0214584551695, 6508.51186161038, 6518.511111666626, 6529.640869752162, 6565.967170164155, 6186.505079593621, 6353.650549219791, 6358.3323751006055, 6369.172007639, 6385.329540398261, 5103.183490641507, 5107.774134207782, 5117.923231151536, 5118.0, 5156.20078887773, 4989.675671081368, 5108.412532558747, 5134.409542902558, 5184.222366644995, 5189.117849965515, 5070.1380765269805, 5095.796168153288, 5100.311457953185, 5103.56619687327, 5109.489051094898, 5070.416902056274, 5078.898422031539, 5080.0601888650535, 5083.440821509649, 5090.261912022773, 4547.499775024745, 4581.885452863705, 4586.633069354443, 4589.2198326284615, 4598.94224328403, 6331.176946996861, 6344.0, 6355.205599300088, 6358.0, 6366.522510811707, 5842.808946645008, 5858.472737453607, 5863.208466856962, 5879.970600147015, 5887.470127688529, 6694.899576506356, 6695.660867826406, 6704.329567043256, 6704.787420151339, 6709.054374231467, 4949.653524253304, 4980.352554167971, 5005.274235235906, 5008.84973450797, 5013.498650134994, 7092.822679433056, 7107.6446177690905, 7143.571385716874, 7215.845464725671, 7219.602921839297, 5094.7962081516325, 5113.53978141969, 5136.409312928986, 5141.511556402136, 5146.176611742103, 5192.428832828386, 5194.220866869924, 5214.478552144755, 5215.713135777569, 5224.686518808808, 5749.626274292157, 5808.419158084158, 5819.883602328015, 5868.530517558585, 5875.23621929155, 6604.48525481219, 6673.965535342029, 6674.699638516278, 6753.797386078426, 6754.966225168842, 1690.8562772164257, 1742.4424184261168, 1753.3074435597866, 1765.6645237404853, 1784.5627821183894, 1201.279232460534, 1203.7171264752776, 1247.9188852724544, 1249.175544140881, 1250.5623031938749, 5853.64878107315, 5855.648661080349, 5869.794557190563, 5916.319623243382, 5928.79249226275, 6566.770163044271, 6692.954705608936, 6820.897686534706, 6887.931120688729, 6951.513394062468, 5883.264591926009, 5985.730642121115, 5993.970030149909, 6048.818535443944, 6051.939480605226, 3612.3858943979585, 3630.5643322801434, 3632.146445585285, 3664.5785734640326, 3669.94495082574, 6625.08496261653, 6656.733730650816, 6674.998750187511, 6704.3295670433545, 6716.697748601325, 6925.411340036053, 6988.720451181895, 7040.5071644984355, 7043.330883566053, 7052.858942821114, 6515.579320856494, 6519.674016299166, 6551.4103730664465, 6642.302558231316, 6715.46276297895, 6835.829104272433, 6902.240753517009, 6965.164180298397, 6969.860602787814, 6972.884338506017, 6841.076454678754, 6871.862562748817, 6876.724931002704, 6932.0988271524375, 6935.549189302679, 6695.665216739046, 6745.291744366771, 6750.35871592198, 6759.459243260626, 6781.525293229427, 5067.341245638044, 5069.41701704294, 5082.186850103965, 5132.615053871049, 5163.845084647466, 6663.400293973566, 6950.270221626656, 7024.033270684216, 7050.717971281091, 7051.435885129074, 6455.386738259858, 6538.476921846146, 6599.340065993362, 6731.596104233761, 6732.451536146616, 6619.881637875834, 6694.0, 6709.463242940651, 6714.496412768965, 6741.561798483083, 6613.867722645617, 6640.767573134917, 6646.570987237767, 6649.501287403367, 6655.134832471747, 6599.736010559716, 6627.23786764509, 6635.867282654416, 6706.295838937037, 6746.696398661974, 6390.753803008554, 6440.710168042543, 6460.644664543495, 6466.612003279818, 6533.379328963741], "value": 5181.148612707708, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [884.783228109113, 898.3397203055755, 917.7751450894532, 917.9449233046017, 933.5985526223723, 16171.110588917605, 16733.07968061756, 17095.743563846554, 17117.486475405727, 19649.50876228095, 19453.513662158453, 19461.70807437886, 19482.0, 19505.609887802235, 19558.511037224078, 913.5569248914271, 935.0602644342426, 993.6373223773343, 1001.0290018681867, 1001.8447140693168, 1009.6769033909152, 1017.4454922067454, 1019.5208252121491, 1019.6329321444274, 1024.7028361775076, 988.85661579071, 1041.739565108723, 1047.6228557719253, 1064.675274041417, 1067.3062509368933, 1035.777307878806, 1039.1063685230695, 1041.198277326462, 1041.4844651897283, 1043.0039312456586, 747.9065116860393, 754.5397307642334, 755.4787196834221, 755.9697612095509, 757.1028331427276, 16336.591585210346, 17690.292388304453, 17928.372506962194, 18134.637307253914, 18230.179641916147, 18429.907850460662, 18702.345417910445, 18894.811051889443, 18924.716129257933, 18953.147108380155, 15241.23793810305, 15322.463713769965, 15369.154696491689, 15371.615709607237, 15379.461718839786, 3599.6400359964055, 3767.9811600942094, 3821.3694740367805, 3843.4042723377643, 3848.057225979625, 128351.0, 128421.0, 128986.0, 130627.0, 133232.0, 16081.0, 16846.747298790528, 16865.07242101696, 16866.241019154168, 17070.48788536346, 30238.09285721432, 30272.54591181134, 30700.232494187825, 30912.381752364825, 30933.381332373225, 7567.091948966159, 9035.954820225857, 9146.817063658822, 9161.633534658673, 9161.679341223025, 3760.2855457463543, 4171.666266698657, 4349.978250108729, 4354.216241076573, 4357.3899654048455, 4911.165101932608, 4993.6005119590345, 4997.525235102659, 5004.274380214849, 5005.524475174852, 1006.3006210683504, 1044.3316277582353, 1051.663467690347, 1054.7046826888484, 1055.4933631856752, 668.812732434919, 675.5845155229498, 683.6376720338229, 687.3916583823352, 687.9965600171967, 11300.491477883515, 11406.42967851604, 11788.941055294668, 12167.330796806084, 12295.262284262973, 641.8940874755613, 650.0768908150343, 655.0, 656.1896058367912, 658.9341065893373, 5363.865903352369, 5402.837914862561, 5406.567474602022, 5433.402325744165, 5574.832755017274, 115007.42496287465, 115947.0, 116694.0, 117673.0, 120867.39566302112, 99700.0, 100509.49745251227, 104541.47729261305, 104806.47596761967, 105332.0, 86924.56537717397, 88340.55829720938, 89626.0, 90704.5464772672, 92488.53755731178, 9895.25785566071, 10027.398356098658, 10086.747831303983, 10289.022542858416, 10349.9482502587, 41273.587264126974, 41320.1735965273, 42361.0, 42637.786811065744, 43161.78419107884, 50405.24392134047, 52248.47751522436, 52574.47425525696, 52870.471295288095, 53217.20174197468, 15475.380984760484, 15602.921985390001, 15662.608434789221, 15715.449959251371, 15732.60668483297, 15909.204539773196, 19158.71261931044, 19790.10944507472, 19792.20831166795, 19853.900730496254, 17205.967641941523, 17585.208665610335, 17847.107644617456, 18149.81850181534, 18313.450596482126, 17309.74035389445, 17528.56178595545, 17607.20767565488, 17640.29438822484, 17686.292548297923, 17000.574985624964, 17343.306267749653, 17350.305987760854, 17421.128943553023, 17507.47477575675, 12324.445399956845, 12373.0, 12838.422270998015, 12867.613971580868, 12984.415701293276, 12514.801436858797, 12821.0, 12828.743425131259, 12843.422046007765, 13052.21686698801], "value": 8822.938274598288, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1483.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 108.0, 101.0, 107.0, 109.0, 117.0, 110.0, 99.0, 110.0, 109.0], "value": 107.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 117.0, 146.0, 178.0, 116.0, 147.0, 148.0, 114.0, 177.0, 120.0], "value": 140.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 162.0, 162.0, 160.0, 164.0, 162.0, 160.0, 166.0, 167.0, 160.0], "value": 162.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 75.0, 170.0, 77.0, 74.0, 73.0, 75.0, 167.0, 74.0], "value": 93.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 131.0, 78.0, 85.0, 80.0, 136.0, 80.0, 78.0, 131.0], "value": 95.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 115.0, 114.0, 114.0, 114.0, 115.0, 115.0, 113.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 147.0, 147.0, 148.0, 147.0, 147.0, 156.0, 151.0, 148.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 52.0, 52.0, 51.0, 52.0, 54.0, 53.0, 52.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 37.0, 37.0, 38.0, 37.0, 38.0, 38.0, 38.0, 37.0], "value": 37.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 75.0, 76.0, 75.0, 79.0, 77.0, 76.0, 74.0, 73.0], "value": 75.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 120.0, 117.0, 116.0, 117.0, 117.0, 122.0, 127.0, 117.0, 164.0], "value": 123.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 172.0, 170.0, 172.0, 170.0, 178.0, 170.0, 182.0, 168.0, 169.0], "value": 173.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 66.0, 66.0, 63.0, 62.0, 66.0, 65.0, 63.0, 66.0], "value": 64.7, "unit": "score"}]}]} 16:07:08 INFO - Return code: 0 16:07:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:07:08 INFO - # TBPL SUCCESS # 16:07:08 INFO - Running post-action listener: _package_coverage_data 16:07:08 INFO - Running post-action listener: _resource_record_post_action 16:07:08 INFO - [mozharness: 2017-09-03 23:07:08.840307Z] Finished run-tests step (success) 16:07:08 INFO - Running post-run listener: _resource_record_post_run 16:07:08 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 16384; Write bytes: 404336640; Read time: 236; Write time: 227080 16:07:08 INFO - TinderboxPrint: CPU usage
13.1% 16:07:08 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 236 16:07:08 INFO - TinderboxPrint: I/O write bytes / time
404,336,640 / 227,080 16:07:08 INFO - TinderboxPrint: CPU idle
8,243.7 (86.9%) 16:07:08 INFO - TinderboxPrint: CPU user
1,192.8 (12.6%) 16:07:08 INFO - TinderboxPrint: Swap in / out
0 / 0 16:07:08 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 4096; Write bytes: 120274944; Read time: 64; Write time: 89152 16:07:08 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:07:08 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 12288; Write bytes: 284061696; Read time: 172; Write time: 137928 16:07:09 INFO - Running post-run listener: _upload_blobber_files 16:07:09 INFO - Blob upload gear active. 16:07:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:07:09 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:07:09 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:07:09 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:07:09 INFO - (blobuploader) - INFO - Open directory for files ... 16:07:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:07:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:07:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:07:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:07:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:07:10 INFO - (blobuploader) - INFO - Done attempting. 16:07:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:07:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:07:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:07:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:07:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:07:13 INFO - (blobuploader) - INFO - Done attempting. 16:07:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:07:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:07:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:07:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:07:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:07:17 INFO - (blobuploader) - INFO - Done attempting. 16:07:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:07:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:07:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:07:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:07:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:07:18 INFO - (blobuploader) - INFO - Done attempting. 16:07:18 INFO - (blobuploader) - INFO - Iteration through files over. 16:07:18 INFO - Return code: 0 16:07:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:07:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:07:18 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35d9c3f20ffdb0a7bf3bb4a0738ba9b3107843817d620d3e8421d7ae6753151235ea63d08ca59be54c51f40cb68de35e873afdc3c95d8d0718886c43b5a144bb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/077948e70bc38dcde473eda4a22d178c9fded5ef5779196b80ed0b41b89d016490cb21667d71ee304a638897221c9d1dc0f63984a4831f21d25d5e381a1a8e72", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03ed3bb2830dd03564ed3faca778f3cdbb741e6bf2f04b6df17eb2fa6fe6211985694363ccdbf64f23e36a35e7ae6d046e52ac66e910a7cbde1a7037c257d86d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af11c93380b655b32e12511cf250754e57a7c054752ddf6509df821d6944454f74f1d608eb2756ea3eae26c0b414350aa4957f70e2c2f8428587555da4571517"} 16:07:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:07:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:07:18 INFO - Contents: 16:07:18 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35d9c3f20ffdb0a7bf3bb4a0738ba9b3107843817d620d3e8421d7ae6753151235ea63d08ca59be54c51f40cb68de35e873afdc3c95d8d0718886c43b5a144bb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/077948e70bc38dcde473eda4a22d178c9fded5ef5779196b80ed0b41b89d016490cb21667d71ee304a638897221c9d1dc0f63984a4831f21d25d5e381a1a8e72", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03ed3bb2830dd03564ed3faca778f3cdbb741e6bf2f04b6df17eb2fa6fe6211985694363ccdbf64f23e36a35e7ae6d046e52ac66e910a7cbde1a7037c257d86d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af11c93380b655b32e12511cf250754e57a7c054752ddf6509df821d6944454f74f1d608eb2756ea3eae26c0b414350aa4957f70e2c2f8428587555da4571517"} 16:07:18 INFO - Running post-run listener: copy_logs_to_upload_dir 16:07:18 INFO - Copying logs to upload dir... 16:07:18 INFO - mkdir: /builds/slave/test/build/upload/logs 16:07:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.912876 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-03 16:07:18.391644) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 16:07:18.396426) ========= 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=a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35d9c3f20ffdb0a7bf3bb4a0738ba9b3107843817d620d3e8421d7ae6753151235ea63d08ca59be54c51f40cb68de35e873afdc3c95d8d0718886c43b5a144bb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/077948e70bc38dcde473eda4a22d178c9fded5ef5779196b80ed0b41b89d016490cb21667d71ee304a638897221c9d1dc0f63984a4831f21d25d5e381a1a8e72", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03ed3bb2830dd03564ed3faca778f3cdbb741e6bf2f04b6df17eb2fa6fe6211985694363ccdbf64f23e36a35e7ae6d046e52ac66e910a7cbde1a7037c257d86d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af11c93380b655b32e12511cf250754e57a7c054752ddf6509df821d6944454f74f1d608eb2756ea3eae26c0b414350aa4957f70e2c2f8428587555da4571517"} build_url:https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011256 build_url: 'https://queue.taskcluster.net/v1/task/ResnVHHTQe2eByEEfu188A/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35d9c3f20ffdb0a7bf3bb4a0738ba9b3107843817d620d3e8421d7ae6753151235ea63d08ca59be54c51f40cb68de35e873afdc3c95d8d0718886c43b5a144bb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/077948e70bc38dcde473eda4a22d178c9fded5ef5779196b80ed0b41b89d016490cb21667d71ee304a638897221c9d1dc0f63984a4831f21d25d5e381a1a8e72", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03ed3bb2830dd03564ed3faca778f3cdbb741e6bf2f04b6df17eb2fa6fe6211985694363ccdbf64f23e36a35e7ae6d046e52ac66e910a7cbde1a7037c257d86d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af11c93380b655b32e12511cf250754e57a7c054752ddf6509df821d6944454f74f1d608eb2756ea3eae26c0b414350aa4957f70e2c2f8428587555da4571517"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 16:07:18.427566) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 16:07:18.427911) ========= 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=a0d2d2de893abaa8702a9954000001a7-1504471816.418543-1873673971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004535 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 16:07:18.510443) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 16:07:18.510863) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 16:07:18.514222) ========= ========= Total master_lag: 0.32 =========