builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-059 starttime: 1504035012.36 results: success (0) revision: 6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-29 12:30:12.361217) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-29 12:30:12.361843) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-29 12:30:12.362207) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-29 12:30:12.400616) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:30:12.401052) ========= 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=3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-29 12:30:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 110M=0s 2017-08-29 12:30:12 (110 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.180212 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:30:12.597635) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:30:12.597956) ========= 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=3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015961 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:30:12.647981) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:30:12.648313) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4 --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 6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4 --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=3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-29 12:30:12,714 truncating revision to first 12 chars 2017-08-29 12:30:12,714 Setting DEBUG logging. 2017-08-29 12:30:12,714 attempt 1/10 2017-08-29 12:30:12,714 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6c3510bac832?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-29 12:30:13,161 unpacking tar archive at: mozilla-central-6c3510bac832/testing/mozharness/ program finished with exit code 0 elapsedTime=0.767096 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:30:13.429676) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-29 12:30:13.430013) ========= script_repo_revision: 6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-29 12:30:13.430393) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-29 12:30:13.430672) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-29 12:30:13.441451) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-29 12:30:13.441883) ========= /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=3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:30:13 INFO - MultiFileLogger online at 20170829 12:30:13 in /builds/slave/test 12:30:13 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 12:30:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:30:13 INFO - {'append_to_log': False, 12:30:13 INFO - 'base_work_dir': '/builds/slave/test', 12:30:13 INFO - 'blob_upload_branch': 'Firefox', 12:30:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:30:13 INFO - 'branch': 'Firefox', 12:30:13 INFO - 'buildbot_json_path': 'buildprops.json', 12:30:13 INFO - 'code_coverage': False, 12:30:13 INFO - 'config_files': ('talos/linux_config.py',), 12:30:13 INFO - 'default_actions': ('clobber', 12:30:13 INFO - 'read-buildbot-config', 12:30:13 INFO - 'download-and-extract', 12:30:13 INFO - 'populate-webroot', 12:30:13 INFO - 'create-virtualenv', 12:30:13 INFO - 'install', 12:30:13 INFO - 'setup-mitmproxy', 12:30:13 INFO - 'run-tests'), 12:30:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:30:13 INFO - 'disable_ccov_upload': False, 12:30:13 INFO - 'disable_stylo': False, 12:30:13 INFO - 'download_minidump_stackwalk': True, 12:30:13 INFO - 'download_symbols': 'ondemand', 12:30:13 INFO - 'enable_stylo': False, 12:30:13 INFO - 'enable_webrender': False, 12:30:13 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:30:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:30:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:30:13 INFO - 'gecko_profile': False, 12:30:13 INFO - 'gecko_profile_interval': 0, 12:30:13 INFO - 'installer_path': 'installer.exe', 12:30:13 INFO - 'log_level': 'info', 12:30:13 INFO - 'log_name': 'talos', 12:30:13 INFO - 'log_to_console': True, 12:30:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:30:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:30:13 INFO - 'opt_config_files': (), 12:30:13 INFO - 'pip_index': False, 12:30:13 INFO - 'suite': 'dromaeojs-e10s', 12:30:13 INFO - 'system_bits': '32', 12:30:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:30:13 INFO - 'title': 'talos-linux64-ix-059', 12:30:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:30:13 INFO - 'use_talos_json': True, 12:30:13 INFO - 'verify': 'False', 12:30:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:30:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:30:13 INFO - 'work_dir': 'build'} 12:30:13 INFO - [mozharness: 2017-08-29 19:30:13.738719Z] Running clobber step. 12:30:13 INFO - Running pre-action listener: _resource_record_pre_action 12:30:13 INFO - Running main action method: clobber 12:30:13 INFO - rmtree: /builds/slave/test/build 12:30:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:30:14 INFO - Running post-action listener: _resource_record_post_action 12:30:14 INFO - [mozharness: 2017-08-29 19:30:14.714169Z] Finished clobber step (success) 12:30:14 INFO - [mozharness: 2017-08-29 19:30:14.714330Z] Running read-buildbot-config step. 12:30:14 INFO - Running pre-action listener: _resource_record_pre_action 12:30:14 INFO - Running main action method: read_buildbot_config 12:30:14 INFO - Using buildbot properties: 12:30:14 INFO - { 12:30:14 INFO - "project": "", 12:30:14 INFO - "product": "firefox", 12:30:14 INFO - "who": "archaeopteryx@coole-files.de", 12:30:14 INFO - "installer_path": "public/build/target.tar.bz2", 12:30:14 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 12:30:14 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:30:14 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 12:30:14 INFO - "stage_platform": "linux64-stylo", 12:30:14 INFO - "basedir": "/builds/slave/test", 12:30:14 INFO - "buildnumber": 286, 12:30:14 INFO - "platform": "ubuntu64_hw_stylo", 12:30:14 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:30:14 INFO - "slavebuilddir": "test", 12:30:14 INFO - "taskId": "HEzA-f_WR2GkUbWiVEIphA", 12:30:14 INFO - "branch": "mozilla-central", 12:30:14 INFO - "script_repo_revision": "production", 12:30:14 INFO - "revision": "6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4", 12:30:14 INFO - "slavename": "talos-linux64-ix-059", 12:30:14 INFO - "repo_path": "mozilla-central" 12:30:14 INFO - } 12:30:14 INFO - Finding installer, test and symbols from parent task. 12:30:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HEzA-f_WR2GkUbWiVEIphA'}, attempt #1 12:30:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HEzA-f_WR2GkUbWiVEIphA'}, attempt #1 12:30:15 INFO - Task dependencies: HdbSpWL2QCWufyiDij7DYg 12:30:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg'}, attempt #1 12:30:16 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2 12:30:16 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.test_packages.json 12:30:16 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.crashreporter-symbols.zip 12:30:16 INFO - Running post-action listener: _resource_record_post_action 12:30:16 INFO - [mozharness: 2017-08-29 19:30:16.160966Z] Finished read-buildbot-config step (success) 12:30:16 INFO - [mozharness: 2017-08-29 19:30:16.161183Z] Running download-and-extract step. 12:30:16 INFO - Running pre-action listener: _resource_record_pre_action 12:30:16 INFO - Running main action method: download_and_extract 12:30:16 INFO - mkdir: /builds/slave/test/build/tests 12:30:16 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')]} 12:30:16 INFO - trying https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.test_packages.json 12:30:16 INFO - Downloading https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:30:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:30:16 INFO - Downloaded 989 bytes. 12:30:16 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:30:16 INFO - Using the following test package requirements: 12:30:16 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:30:16 INFO - u'common': [u'target.common.tests.zip'], 12:30:16 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:30:16 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:30:16 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:30:16 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:30:16 INFO - u'mozbase': [u'target.common.tests.zip'], 12:30:16 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:30:16 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:30:16 INFO - u'web-platform': [u'target.common.tests.zip', 12:30:16 INFO - u'target.web-platform.tests.tar.gz'], 12:30:16 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:30:16 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 12:30:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.common.tests.zip 12:30:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:30:16 INFO - Fetch https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.common.tests.zip into memory 12:30:17 INFO - Content-Length response header: 54980744 12:30:17 INFO - Bytes received: 54980744 12:30:28 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 12:30:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.common.tests.zip 12:30:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:30:28 INFO - Fetch https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.common.tests.zip into memory 12:30:29 INFO - Content-Length response header: 54980744 12:30:29 INFO - Bytes received: 54980744 12:30:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.talos.tests.zip 12:30:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:30:42 INFO - Fetch https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.talos.tests.zip into memory 12:30:43 INFO - Content-Length response header: 14496610 12:30:43 INFO - Bytes received: 14496610 12:30:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:44 INFO - trying https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2 12:30:44 INFO - Downloading https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:30:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:30:45 INFO - Downloaded 62660028 bytes. 12:30:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2 12:30:45 INFO - mkdir: /builds/slave/test/properties 12:30:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:30:45 INFO - Writing to file /builds/slave/test/properties/build_url 12:30:45 INFO - Contents: 12:30:45 INFO - build_url:https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2 12:30:45 INFO - Running post-action listener: _resource_record_post_action 12:30:45 INFO - Running post-action listener: find_tests_for_verification 12:30:45 INFO - Running post-action listener: set_extra_try_arguments 12:30:45 INFO - [mozharness: 2017-08-29 19:30:45.155820Z] Finished download-and-extract step (success) 12:30:45 INFO - [mozharness: 2017-08-29 19:30:45.155917Z] Running populate-webroot step. 12:30:45 INFO - Running pre-action listener: _resource_record_pre_action 12:30:45 INFO - Running main action method: populate_webroot 12:30:45 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:30:45 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:30:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:30:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:30:45 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:30:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:30:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:30:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:30:45 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:30:45 INFO - 'talos_options': ['--enable-stylo'], 12:30:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:30:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:30:45 INFO - 'tests': ['damp', 'tps']}, 12:30:45 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:30:45 INFO - 'talos_options': ['--enable-stylo'], 12:30:45 INFO - 'tests': ['damp', 'tps']}, 12:30:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:30:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:30:45 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:30:45 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:30:45 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:30:45 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:30:45 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:30:45 INFO - 'talos_options': ['--enable-stylo'], 12:30:45 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:30:45 INFO - 'other-e10s': {'tests': ['a11yr', 12:30:45 INFO - 'ts_paint', 12:30:45 INFO - 'tpaint', 12:30:45 INFO - 'sessionrestore', 12:30:45 INFO - 'sessionrestore_many_windows', 12:30:45 INFO - 'sessionrestore_no_auto_restore', 12:30:45 INFO - 'tabpaint']}, 12:30:45 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:30:45 INFO - 'tests': ['a11yr', 12:30:45 INFO - 'ts_paint', 12:30:45 INFO - 'tpaint', 12:30:45 INFO - 'sessionrestore', 12:30:45 INFO - 'sessionrestore_many_windows', 12:30:45 INFO - 'sessionrestore_no_auto_restore', 12:30:45 INFO - 'tabpaint']}, 12:30:45 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 12:30:45 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 12:30:45 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:30:45 INFO - 'tests': ['bloom_basic_singleton']}, 12:30:45 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:30:45 INFO - 'tests': ['bloom_basic']}, 12:30:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:30:45 INFO - 'tsvgr_opacity', 12:30:45 INFO - 'tart', 12:30:45 INFO - 'tscrollx', 12:30:45 INFO - 'tsvg_static']}, 12:30:45 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:30:45 INFO - 'tests': ['tsvgx', 12:30:45 INFO - 'tsvgr_opacity', 12:30:45 INFO - 'tart', 12:30:45 INFO - 'tscrollx', 12:30:45 INFO - 'tsvg_static']}, 12:30:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:30:45 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:30:45 INFO - 'talos_options': ['--enable-stylo'], 12:30:45 INFO - 'tests': ['tp5o']}, 12:30:45 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:30:45 INFO - 'talos_options': ['--mitmproxy', 12:30:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:30:45 INFO - '--firstNonBlankPaint'], 12:30:45 INFO - 'tests': ['tp6_google', 12:30:45 INFO - 'tp6_youtube', 12:30:45 INFO - 'tp6_amazon', 12:30:45 INFO - 'tp6_facebook']}, 12:30:45 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:30:45 INFO - 'talos_options': ['--enable-stylo', 12:30:45 INFO - '--mitmproxy', 12:30:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:30:45 INFO - '--firstNonBlankPaint'], 12:30:45 INFO - 'tests': ['tp6_google', 12:30:45 INFO - 'tp6_youtube', 12:30:45 INFO - 'tp6_amazon', 12:30:45 INFO - 'tp6_facebook']}, 12:30:45 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:30:45 INFO - 'talos_options': ['--enable-stylo', 12:30:45 INFO - '--stylo-threads=1', 12:30:45 INFO - '--mitmproxy', 12:30:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:30:45 INFO - '--firstNonBlankPaint'], 12:30:45 INFO - 'tests': ['tp6_google', 12:30:45 INFO - 'tp6_youtube', 12:30:45 INFO - 'tp6_amazon', 12:30:45 INFO - 'tp6_facebook']}, 12:30:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:30:45 INFO - 'talos_options': ['--xperf_path', 12:30:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:30:45 INFO - 'tests': ['tp5n']}, 12:30:45 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:30:45 INFO - 'talos_options': ['--enable-stylo', 12:30:45 INFO - '--xperf_path', 12:30:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:30:45 INFO - 'tests': ['tp5n']}}} 12:30:45 INFO - Running post-action listener: _resource_record_post_action 12:30:45 INFO - [mozharness: 2017-08-29 19:30:45.170272Z] Finished populate-webroot step (success) 12:30:45 INFO - [mozharness: 2017-08-29 19:30:45.170363Z] Running create-virtualenv step. 12:30:45 INFO - Running pre-action listener: _resource_record_pre_action 12:30:45 INFO - Running main action method: create_virtualenv 12:30:45 INFO - Creating virtualenv /builds/slave/test/build/venv 12:30:45 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:30:45 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:30:45 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:30:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:30:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:30:47 INFO - Installing distribute.............................................................................................................................................................................................done. 12:30:47 INFO - Installing pip...............done. 12:30:47 INFO - Return code: 0 12:30:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:30:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:30:48 INFO - Reading from file tmpfile_stdout 12:30:48 INFO - Output received: 12:30:48 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:30:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c580>, '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 0x2a7d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d910>, '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': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', '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 12:30:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:30:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:30:48 INFO - Using env: {'DISPLAY': ':0', 12:30:48 INFO - 'HOME': '/home/cltbld', 12:30:48 INFO - 'LANG': 'en_US.UTF-8', 12:30:48 INFO - 'LANGUAGE': 'en_US:en', 12:30:48 INFO - 'LOGNAME': 'cltbld', 12:30:48 INFO - 'MAIL': '/var/mail/cltbld', 12:30:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:48 INFO - 'MOZ_NO_REMOTE': '1', 12:30:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:48 INFO - 'NO_EM_RESTART': '1', 12:30:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:48 INFO - 'PWD': '/builds/slave/test', 12:30:48 INFO - 'SHELL': '/bin/bash', 12:30:48 INFO - 'SHLVL': '1', 12:30:48 INFO - 'TERM': 'linux', 12:30:48 INFO - 'TMOUT': '86400', 12:30:48 INFO - 'USER': 'cltbld', 12:30:48 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', 12:30:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:48 INFO - '_': '/tools/buildbot/bin/python'} 12:30:49 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:30:49 INFO - Downloading/unpacking pip>=1.5 12:30:49 INFO - Running setup.py egg_info for package pip 12:30:49 INFO - warning: no files found matching 'pip/cacert.pem' 12:30:49 INFO - warning: no files found matching '*.html' under directory 'docs' 12:30:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:30:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:30:49 INFO - Installing collected packages: pip 12:30:49 INFO - Found existing installation: pip 1.1 12:30:49 INFO - Uninstalling pip: 12:30:49 INFO - Successfully uninstalled pip 12:30:49 INFO - Running setup.py install for pip 12:30:49 INFO - warning: no files found matching 'pip/cacert.pem' 12:30:49 INFO - warning: no files found matching '*.html' under directory 'docs' 12:30:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:30:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:30:49 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:30:49 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:30:49 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:30:49 INFO - Successfully installed pip 12:30:49 INFO - Cleaning up... 12:30:49 INFO - Return code: 0 12:30:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:30:49 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')]} 12:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:49 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')]} 12:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:49 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 0x27101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c580>, '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 0x2a7d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d910>, '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': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', '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 12:30:49 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 12:30:49 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 12:30:49 INFO - Using env: {'DISPLAY': ':0', 12:30:49 INFO - 'HOME': '/home/cltbld', 12:30:49 INFO - 'LANG': 'en_US.UTF-8', 12:30:49 INFO - 'LANGUAGE': 'en_US:en', 12:30:49 INFO - 'LOGNAME': 'cltbld', 12:30:49 INFO - 'MAIL': '/var/mail/cltbld', 12:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:49 INFO - 'MOZ_NO_REMOTE': '1', 12:30:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:49 INFO - 'NO_EM_RESTART': '1', 12:30:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:49 INFO - 'PWD': '/builds/slave/test', 12:30:49 INFO - 'SHELL': '/bin/bash', 12:30:49 INFO - 'SHLVL': '1', 12:30:49 INFO - 'TERM': 'linux', 12:30:49 INFO - 'TMOUT': '86400', 12:30:49 INFO - 'USER': 'cltbld', 12:30:49 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', 12:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:49 INFO - '_': '/tools/buildbot/bin/python'} 12:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:50 INFO - Downloading/unpacking psutil>=3.1.1 12:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:30:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:30:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:30:51 INFO - Installing collected packages: psutil 12:30:51 INFO - Running setup.py install for psutil 12:30:51 INFO - building 'psutil._psutil_linux' extension 12:30:51 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 12:30:52 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 12:30:52 INFO - building 'psutil._psutil_posix' extension 12:30:52 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 12:30:52 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 12:30:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:30:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:30:52 INFO - Successfully installed psutil 12:30:52 INFO - Cleaning up... 12:30:52 INFO - Return code: 0 12:30:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c580>, '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 0x2a7d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d910>, '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': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', '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 12:30:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:30:52 INFO - Using env: {'DISPLAY': ':0', 12:30:52 INFO - 'HOME': '/home/cltbld', 12:30:52 INFO - 'LANG': 'en_US.UTF-8', 12:30:52 INFO - 'LANGUAGE': 'en_US:en', 12:30:52 INFO - 'LOGNAME': 'cltbld', 12:30:52 INFO - 'MAIL': '/var/mail/cltbld', 12:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:52 INFO - 'MOZ_NO_REMOTE': '1', 12:30:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:52 INFO - 'NO_EM_RESTART': '1', 12:30:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:52 INFO - 'PWD': '/builds/slave/test', 12:30:52 INFO - 'SHELL': '/bin/bash', 12:30:52 INFO - 'SHLVL': '1', 12:30:52 INFO - 'TERM': 'linux', 12:30:52 INFO - 'TMOUT': '86400', 12:30:52 INFO - 'USER': 'cltbld', 12:30:52 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', 12:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:52 INFO - '_': '/tools/buildbot/bin/python'} 12:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:30:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:30:54 INFO - Installing collected packages: mozsystemmonitor 12:30:54 INFO - Running setup.py install for mozsystemmonitor 12:30:54 INFO - Successfully installed mozsystemmonitor 12:30:54 INFO - Cleaning up... 12:30:54 INFO - Return code: 0 12:30:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:54 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 0x27101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c580>, '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 0x2a7d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d910>, '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': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', '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 12:30:54 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 12:30:54 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 12:30:54 INFO - Using env: {'DISPLAY': ':0', 12:30:54 INFO - 'HOME': '/home/cltbld', 12:30:54 INFO - 'LANG': 'en_US.UTF-8', 12:30:54 INFO - 'LANGUAGE': 'en_US:en', 12:30:54 INFO - 'LOGNAME': 'cltbld', 12:30:54 INFO - 'MAIL': '/var/mail/cltbld', 12:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:54 INFO - 'MOZ_NO_REMOTE': '1', 12:30:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:54 INFO - 'NO_EM_RESTART': '1', 12:30:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:54 INFO - 'PWD': '/builds/slave/test', 12:30:54 INFO - 'SHELL': '/bin/bash', 12:30:54 INFO - 'SHLVL': '1', 12:30:54 INFO - 'TERM': 'linux', 12:30:54 INFO - 'TMOUT': '86400', 12:30:54 INFO - 'USER': 'cltbld', 12:30:54 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', 12:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:54 INFO - '_': '/tools/buildbot/bin/python'} 12:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:54 INFO - Downloading/unpacking jsonschema==2.5.1 12:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:30:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:30:55 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 12:30:55 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 12:30:55 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 12:30:55 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 12:30:55 INFO - Downloading functools32-3.2.3-2.tar.gz 12:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:30:55 INFO - warning: no files found matching '*.txt' 12:30:55 INFO - no previously-included directories found matching 'build' 12:30:55 INFO - no previously-included directories found matching 'dist' 12:30:55 INFO - no previously-included directories found matching '.git*' 12:30:55 INFO - Installing collected packages: jsonschema, functools32 12:30:55 INFO - Running setup.py install for functools32 12:30:55 INFO - warning: no files found matching '*.txt' 12:30:55 INFO - no previously-included directories found matching 'build' 12:30:55 INFO - no previously-included directories found matching 'dist' 12:30:55 INFO - no previously-included directories found matching '.git*' 12:30:55 INFO - Successfully installed jsonschema functools32 12:30:55 INFO - Cleaning up... 12:30:55 INFO - Return code: 0 12:30:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:30:55 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')]} 12:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:55 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')]} 12:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:55 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 0x27101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c580>, '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 0x2a7d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d910>, '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': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', '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 12:30:55 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 12:30:55 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 12:30:55 INFO - Using env: {'DISPLAY': ':0', 12:30:55 INFO - 'HOME': '/home/cltbld', 12:30:55 INFO - 'LANG': 'en_US.UTF-8', 12:30:55 INFO - 'LANGUAGE': 'en_US:en', 12:30:55 INFO - 'LOGNAME': 'cltbld', 12:30:55 INFO - 'MAIL': '/var/mail/cltbld', 12:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:55 INFO - 'MOZ_NO_REMOTE': '1', 12:30:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:55 INFO - 'NO_EM_RESTART': '1', 12:30:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:55 INFO - 'PWD': '/builds/slave/test', 12:30:55 INFO - 'SHELL': '/bin/bash', 12:30:55 INFO - 'SHLVL': '1', 12:30:55 INFO - 'TERM': 'linux', 12:30:55 INFO - 'TMOUT': '86400', 12:30:55 INFO - 'USER': 'cltbld', 12:30:55 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', 12:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:55 INFO - '_': '/tools/buildbot/bin/python'} 12:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:30:56 INFO - Cleaning up... 12:30:56 INFO - Return code: 0 12:30:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:30:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x27101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c580>, '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 0x2a7d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d910>, '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': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', '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 12:30:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:30:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:30:56 INFO - Using env: {'DISPLAY': ':0', 12:30:56 INFO - 'HOME': '/home/cltbld', 12:30:56 INFO - 'LANG': 'en_US.UTF-8', 12:30:56 INFO - 'LANGUAGE': 'en_US:en', 12:30:56 INFO - 'LOGNAME': 'cltbld', 12:30:56 INFO - 'MAIL': '/var/mail/cltbld', 12:30:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:56 INFO - 'MOZ_NO_REMOTE': '1', 12:30:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:56 INFO - 'NO_EM_RESTART': '1', 12:30:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:56 INFO - 'PWD': '/builds/slave/test', 12:30:56 INFO - 'SHELL': '/bin/bash', 12:30:56 INFO - 'SHLVL': '1', 12:30:56 INFO - 'TERM': 'linux', 12:30:56 INFO - 'TMOUT': '86400', 12:30:56 INFO - 'USER': 'cltbld', 12:30:56 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', 12:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:56 INFO - '_': '/tools/buildbot/bin/python'} 12:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:56 INFO - Downloading/unpacking blobuploader==1.2.4 12:30:56 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 12:30:56 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 12:30:56 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 12:30:56 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 12:30:57 INFO - Downloading blobuploader-1.2.4.tar.gz 12:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:30:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:30:57 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 12:30:57 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 12:30:57 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 12:30:57 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 12:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:30:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:30:57 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 12:30:57 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 12:30:57 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 12:30:57 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 12:30:57 INFO - Downloading docopt-0.6.1.tar.gz 12:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:30:58 INFO - Installing collected packages: blobuploader, requests, docopt 12:30:58 INFO - Running setup.py install for blobuploader 12:30:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:30:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:30:58 INFO - Running setup.py install for requests 12:30:58 INFO - Running setup.py install for docopt 12:30:58 INFO - Successfully installed blobuploader requests docopt 12:30:58 INFO - Cleaning up... 12:30:58 INFO - Return code: 0 12:30:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:30:58 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')]} 12:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:58 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')]} 12:30:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:58 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 0x27101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c580>, '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 0x2a7d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d910>, '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': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', '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 12:30:58 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 12:30:58 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 12:30:58 INFO - Using env: {'DISPLAY': ':0', 12:30:58 INFO - 'HOME': '/home/cltbld', 12:30:58 INFO - 'LANG': 'en_US.UTF-8', 12:30:58 INFO - 'LANGUAGE': 'en_US:en', 12:30:58 INFO - 'LOGNAME': 'cltbld', 12:30:58 INFO - 'MAIL': '/var/mail/cltbld', 12:30:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:58 INFO - 'MOZ_NO_REMOTE': '1', 12:30:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:58 INFO - 'NO_EM_RESTART': '1', 12:30:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:58 INFO - 'PWD': '/builds/slave/test', 12:30:58 INFO - 'SHELL': '/bin/bash', 12:30:58 INFO - 'SHLVL': '1', 12:30:58 INFO - 'TERM': 'linux', 12:30:58 INFO - 'TMOUT': '86400', 12:30:58 INFO - 'USER': 'cltbld', 12:30:58 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', 12:30:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:58 INFO - '_': '/tools/buildbot/bin/python'} 12:30:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:30:58 INFO - Running setup.py (path:/tmp/pip-Zhi87i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:30:58 INFO - Running setup.py (path:/tmp/pip-1_VhpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:30:58 INFO - Running setup.py (path:/tmp/pip-w6U7ft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:30:59 INFO - Running setup.py (path:/tmp/pip-4yU0Gl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:30:59 INFO - Running setup.py (path:/tmp/pip-I8lxK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:30:59 INFO - Running setup.py (path:/tmp/pip-BFgevm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:30:59 INFO - Running setup.py (path:/tmp/pip-WJhjWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:30:59 INFO - Running setup.py (path:/tmp/pip-cNwHM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:30:59 INFO - Running setup.py (path:/tmp/pip-C0uVka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:30:59 INFO - Running setup.py (path:/tmp/pip-xaw8ab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:30:59 INFO - Running setup.py (path:/tmp/pip-bzmS_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:30:59 INFO - Running setup.py (path:/tmp/pip-wdFQyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:30:59 INFO - Running setup.py (path:/tmp/pip-s0hDlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:30:59 INFO - Running setup.py (path:/tmp/pip-4HlI0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:30:59 INFO - Running setup.py (path:/tmp/pip-MKeQzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:30:59 INFO - Running setup.py (path:/tmp/pip-_U_8IE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:30:59 INFO - Running setup.py (path:/tmp/pip-FYURX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:31:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:31:00 INFO - Running setup.py install for manifestparser 12:31:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:31:00 INFO - Running setup.py install for mozcrash 12:31:00 INFO - Running setup.py install for mozdebug 12:31:00 INFO - Running setup.py install for mozdevice 12:31:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:31:00 INFO - Running setup.py install for mozfile 12:31:00 INFO - Running setup.py install for mozhttpd 12:31:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:31:00 INFO - Running setup.py install for mozinfo 12:31:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:31:00 INFO - Running setup.py install for mozInstall 12:31:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:31:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:31:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:31:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:31:00 INFO - Running setup.py install for mozleak 12:31:00 INFO - Running setup.py install for mozlog 12:31:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:31:01 INFO - Running setup.py install for moznetwork 12:31:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:31:01 INFO - Running setup.py install for mozprocess 12:31:01 INFO - Running setup.py install for mozprofile 12:31:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:31:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:31:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:31:01 INFO - Running setup.py install for mozrunner 12:31:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:31:01 INFO - Running setup.py install for mozscreenshot 12:31:01 INFO - Running setup.py install for moztest 12:31:01 INFO - Running setup.py install for mozversion 12:31:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:31:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:31:01 INFO - Cleaning up... 12:31:01 INFO - Return code: 0 12:31:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:31: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')]} 12:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:31: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')]} 12:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:31:01 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 0x27101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c580>, '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 0x2a7d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d910>, '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': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', '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 12:31:01 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 12:31:01 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 12:31:01 INFO - Using env: {'DISPLAY': ':0', 12:31:01 INFO - 'HOME': '/home/cltbld', 12:31:01 INFO - 'LANG': 'en_US.UTF-8', 12:31:01 INFO - 'LANGUAGE': 'en_US:en', 12:31:01 INFO - 'LOGNAME': 'cltbld', 12:31:01 INFO - 'MAIL': '/var/mail/cltbld', 12:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:01 INFO - 'MOZ_NO_REMOTE': '1', 12:31:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:31:01 INFO - 'NO_EM_RESTART': '1', 12:31:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:01 INFO - 'PWD': '/builds/slave/test', 12:31:01 INFO - 'SHELL': '/bin/bash', 12:31:01 INFO - 'SHLVL': '1', 12:31:01 INFO - 'TERM': 'linux', 12:31:01 INFO - 'TMOUT': '86400', 12:31:01 INFO - 'USER': 'cltbld', 12:31:01 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', 12:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:01 INFO - '_': '/tools/buildbot/bin/python'} 12:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:31:01 INFO - Running setup.py (path:/tmp/pip-UNiH84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:31:02 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)) 12:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:31:02 INFO - Running setup.py (path:/tmp/pip-Dh8yh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:31:02 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)) 12:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:31:02 INFO - Running setup.py (path:/tmp/pip-u47A9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:31:02 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)) 12:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:31:02 INFO - Running setup.py (path:/tmp/pip-L6qdgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:31:02 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)) 12:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:31:02 INFO - Running setup.py (path:/tmp/pip-Ax9Crr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:31:02 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)) 12:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:31:02 INFO - Running setup.py (path:/tmp/pip-PIyDtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:31:02 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)) 12:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:31:02 INFO - Running setup.py (path:/tmp/pip-GGUuE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:31:02 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)) 12:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:31:02 INFO - Running setup.py (path:/tmp/pip-GTr7HT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:31:02 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)) 12:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:31:02 INFO - Running setup.py (path:/tmp/pip-qyY9PR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:31:02 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)) 12:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:31:02 INFO - Running setup.py (path:/tmp/pip-PriJ7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:31:02 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)) 12:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:31:02 INFO - Running setup.py (path:/tmp/pip-WNc2bi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:31:02 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)) 12:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:31:02 INFO - Running setup.py (path:/tmp/pip-4LGWgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:31:02 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)) 12:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:31:02 INFO - Running setup.py (path:/tmp/pip-ynOs5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:31:02 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)) 12:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:31:02 INFO - Running setup.py (path:/tmp/pip-DFgohG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:31:02 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)) 12:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:31:02 INFO - Running setup.py (path:/tmp/pip-gCPTSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:31:03 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)) 12:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:31:03 INFO - Running setup.py (path:/tmp/pip-635kV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:31:03 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)) 12:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:31:03 INFO - Running setup.py (path:/tmp/pip-cd6lr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:31:03 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)) 12:31:03 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)) 12:31:03 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)) 12:31:03 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)) 12:31:03 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)) 12:31:03 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)) 12:31:03 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)) 12:31:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:31: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 12:31: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 12:31: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 12:31: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 12:31:04 INFO - Downloading blessings-1.6.tar.gz 12:31:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:31:04 INFO - Installing collected packages: blessings 12:31:04 INFO - Running setup.py install for blessings 12:31:04 INFO - Successfully installed blessings 12:31:04 INFO - Cleaning up... 12:31:04 INFO - Return code: 0 12:31:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:31:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:31:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:31:04 INFO - Reading from file tmpfile_stdout 12:31:04 INFO - Current package versions: 12:31:04 INFO - argparse == 1.2.1 12:31:04 INFO - blessings == 1.6 12:31:04 INFO - blobuploader == 1.2.4 12:31:04 INFO - docopt == 0.6.1 12:31:04 INFO - functools32 == 3.2.3-2 12:31:04 INFO - jsonschema == 2.5.1 12:31:04 INFO - manifestparser == 1.1 12:31:04 INFO - mozInstall == 1.13 12:31:04 INFO - mozcrash == 1.0 12:31:04 INFO - mozdebug == 0.1 12:31:04 INFO - mozdevice == 0.51 12:31:04 INFO - mozfile == 1.2 12:31:04 INFO - mozhttpd == 0.7 12:31:04 INFO - mozinfo == 0.10 12:31:04 INFO - mozleak == 0.1 12:31:04 INFO - mozlog == 3.5 12:31:04 INFO - moznetwork == 0.27 12:31:04 INFO - mozprocess == 0.25 12:31:04 INFO - mozprofile == 0.28 12:31:04 INFO - mozrunner == 6.13 12:31:04 INFO - mozscreenshot == 0.1 12:31:04 INFO - mozsystemmonitor == 0.3 12:31:04 INFO - moztest == 0.8 12:31:04 INFO - mozversion == 1.4 12:31:04 INFO - psutil == 3.1.1 12:31:04 INFO - requests == 1.2.3 12:31:04 INFO - wsgiref == 0.1.2 12:31:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:31: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')]} 12:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:31: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')]} 12:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:31:04 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 0x27101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c580>, '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 0x2a7d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d910>, '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': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', '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 12:31:04 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 12:31:04 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 12:31:04 INFO - Using env: {'DISPLAY': ':0', 12:31:04 INFO - 'HOME': '/home/cltbld', 12:31:04 INFO - 'LANG': 'en_US.UTF-8', 12:31:04 INFO - 'LANGUAGE': 'en_US:en', 12:31:04 INFO - 'LOGNAME': 'cltbld', 12:31:04 INFO - 'MAIL': '/var/mail/cltbld', 12:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:04 INFO - 'MOZ_NO_REMOTE': '1', 12:31:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:31:04 INFO - 'NO_EM_RESTART': '1', 12:31:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:04 INFO - 'PWD': '/builds/slave/test', 12:31:04 INFO - 'SHELL': '/bin/bash', 12:31:04 INFO - 'SHLVL': '1', 12:31:04 INFO - 'TERM': 'linux', 12:31:04 INFO - 'TMOUT': '86400', 12:31:04 INFO - 'USER': 'cltbld', 12:31:04 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', 12:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:04 INFO - '_': '/tools/buildbot/bin/python'} 12:31:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:31:05 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)) 12:31:05 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)) 12:31:05 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)) 12:31:05 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)) 12:31:05 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)) 12:31:05 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)) 12:31:05 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)) 12:31:05 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)) 12:31:05 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)) 12:31:05 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:31:05 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 12:31:05 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 12:31:05 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 12:31:05 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 12:31:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:31:06 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)) 12:31:06 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)) 12:31:06 INFO - Installing collected packages: simplejson 12:31:06 INFO - Running setup.py install for simplejson 12:31:06 INFO - building 'simplejson._speedups' extension 12:31:06 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 12:31:07 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 12:31:07 INFO - Successfully installed simplejson 12:31:07 INFO - Cleaning up... 12:31:07 INFO - Return code: 0 12:31:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:31:07 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')]} 12:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:31:07 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')]} 12:31:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:31:07 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 0x27101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7c580>, '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 0x2a7d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d910>, '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': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', '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 12:31:07 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 12:31:07 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 12:31:07 INFO - Using env: {'DISPLAY': ':0', 12:31:07 INFO - 'HOME': '/home/cltbld', 12:31:07 INFO - 'LANG': 'en_US.UTF-8', 12:31:07 INFO - 'LANGUAGE': 'en_US:en', 12:31:07 INFO - 'LOGNAME': 'cltbld', 12:31:07 INFO - 'MAIL': '/var/mail/cltbld', 12:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:07 INFO - 'MOZ_NO_REMOTE': '1', 12:31:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:31:07 INFO - 'NO_EM_RESTART': '1', 12:31:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:07 INFO - 'PWD': '/builds/slave/test', 12:31:07 INFO - 'SHELL': '/bin/bash', 12:31:07 INFO - 'SHLVL': '1', 12:31:07 INFO - 'TERM': 'linux', 12:31:07 INFO - 'TMOUT': '86400', 12:31:07 INFO - 'USER': 'cltbld', 12:31:07 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', 12:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:07 INFO - '_': '/tools/buildbot/bin/python'} 12:31:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:31:07 INFO - Cleaning up... 12:31:07 INFO - Return code: 0 12:31:07 INFO - Running post-action listener: _resource_record_post_action 12:31:07 INFO - Running post-action listener: _start_resource_monitoring 12:31:07 INFO - Starting resource monitoring. 12:31:07 INFO - [mozharness: 2017-08-29 19:31:07.538808Z] Finished create-virtualenv step (success) 12:31:07 INFO - [mozharness: 2017-08-29 19:31:07.539808Z] Running install step. 12:31:07 INFO - Running pre-action listener: _resource_record_pre_action 12:31:07 INFO - Running main action method: install 12:31:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:31:07 INFO - Reading from file tmpfile_stdout 12:31:07 INFO - Detecting whether we're running mozinstall >=1.0... 12:31:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:31:07 INFO - Reading from file tmpfile_stdout 12:31:07 INFO - Output received: 12:31:07 INFO - Usage: mozinstall [options] installer 12:31:07 INFO - Options: 12:31:07 INFO - -h, --help show this help message and exit 12:31:07 INFO - -d DEST, --destination=DEST 12:31:07 INFO - Directory to install application into. [default: 12:31:07 INFO - "/builds/slave/test"] 12:31:07 INFO - --app=APP Application being installed. [default: firefox] 12:31:07 INFO - mkdir: /builds/slave/test/build/application 12:31:07 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'] 12:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:31:24 INFO - Reading from file tmpfile_stdout 12:31:24 INFO - Output received: 12:31:24 INFO - /builds/slave/test/build/application/firefox/firefox 12:31:24 INFO - Running post-action listener: _resource_record_post_action 12:31:24 INFO - [mozharness: 2017-08-29 19:31:24.364937Z] Finished install step (success) 12:31:24 INFO - [mozharness: 2017-08-29 19:31:24.365076Z] Running setup-mitmproxy step. 12:31:24 INFO - Running pre-action listener: _resource_record_pre_action 12:31:24 INFO - Running main action method: setup_mitmproxy 12:31:24 INFO - Skipping: mitmproxy is not required 12:31:24 INFO - Running post-action listener: _resource_record_post_action 12:31:24 INFO - [mozharness: 2017-08-29 19:31:24.366095Z] Finished setup-mitmproxy step (success) 12:31:24 INFO - [mozharness: 2017-08-29 19:31:24.366313Z] Running run-tests step. 12:31:24 INFO - Running pre-action listener: _resource_record_pre_action 12:31:24 INFO - Running pre-action listener: _set_gcov_prefix 12:31:24 INFO - Running main action method: run_tests 12:31:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:31:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:31:24 INFO - Python 2.7.3 12:31:24 INFO - Return code: 0 12:31:24 INFO - grabbing minidump binary from tooltool 12:31:24 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')]} 12:31:24 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 0x2a7c580>, '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 0x2a7d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7d910>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:31:24 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 12:31:24 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 12:31:24 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 12:31:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:31:24 INFO - Return code: 0 12:31:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:31:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:31:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:31:24 INFO - ENV: RUST_BACKTRACE is now full 12:31:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:31:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:31:24 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/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 12:31:24 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/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-059 --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 12:31:24 INFO - Using env: {'DISPLAY': ':0', 12:31:24 INFO - 'HOME': '/home/cltbld', 12:31:24 INFO - 'LANG': 'en_US.UTF-8', 12:31:24 INFO - 'LANGUAGE': 'en_US:en', 12:31:24 INFO - 'LOGNAME': 'cltbld', 12:31:24 INFO - 'MAIL': '/var/mail/cltbld', 12:31:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:31:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:31:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:24 INFO - 'MOZ_NO_REMOTE': '1', 12:31:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:31:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:31:24 INFO - 'NO_EM_RESTART': '1', 12:31:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:31:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:24 INFO - 'PWD': '/builds/slave/test', 12:31:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:31:24 INFO - 'RUST_BACKTRACE': 'full', 12:31:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:31:24 INFO - 'SHELL': '/bin/bash', 12:31:24 INFO - 'SHLVL': '1', 12:31:24 INFO - 'STYLO_FORCE_ENABLED': '1', 12:31:24 INFO - 'TERM': 'linux', 12:31:24 INFO - 'TMOUT': '86400', 12:31:24 INFO - 'USER': 'cltbld', 12:31:24 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', 12:31:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:24 INFO - '_': '/tools/buildbot/bin/python'} 12:31:24 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/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 12:31:24 INFO - mozversion application_buildid: 20170829183112 12:31:24 INFO - mozversion application_changeset: 6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4 12:31:24 INFO - mozversion application_display_name: Nightly 12:31:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:31:24 INFO - mozversion application_name: Firefox 12:31:24 INFO - mozversion application_remotingname: firefox 12:31:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:31:24 INFO - mozversion application_vendor: Mozilla 12:31:24 INFO - mozversion application_version: 57.0a1 12:31:24 INFO - mozversion platform_buildid: 20170829183112 12:31:24 INFO - mozversion platform_changeset: 6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4 12:31:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:31:24 INFO - mozversion platform_version: 57.0a1 12:31:24 INFO - using testdate: 1504035084 12:31:24 INFO - actual date: 1504035084 12:31:24 INFO - starting webserver on 'localhost:59659' 12:31:24 INFO - SUITE-START | Running 2 tests 12:31:24 INFO - TEST-START | dromaeo_css 12:31:24 INFO - Initialising browser for dromaeo_css test... 12:31:24 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59659/getInfo.html -profile /tmp/tmpexca6w/profile 12:31:24 INFO - TEST-INFO | started process 3455 (/builds/slave/test/build/application/firefox/firefox http://localhost:59659/getInfo.html) 12:31:30 INFO - TEST-INFO | 3455: exit 0 12:31:30 INFO - Browser initialized. 12:31:30 INFO - Running cycle 1/1 for dromaeo_css test... 12:31:30 INFO - Using env: {'DISPLAY': ':0', 12:31:30 INFO - 'HOME': '/home/cltbld', 12:31:30 INFO - 'JSGC_DISABLE_POISONING': '1', 12:31:30 INFO - 'LANG': 'en_US.UTF-8', 12:31:30 INFO - 'LANGUAGE': 'en_US:en', 12:31:30 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:31:30 INFO - 'LOGNAME': 'cltbld', 12:31:30 INFO - 'MAIL': '/var/mail/cltbld', 12:31:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:31:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:31:30 INFO - 'MOZ_CRASHREPORTER': '1', 12:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:30 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:31:30 INFO - 'MOZ_NO_REMOTE': '1', 12:31:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:31:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:31:30 INFO - 'NO_EM_RESTART': '1', 12:31:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:30 INFO - 'PWD': '/builds/slave/test', 12:31:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:31:30 INFO - 'RUST_BACKTRACE': 'full', 12:31:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:31:30 INFO - 'SHELL': '/bin/bash', 12:31:30 INFO - 'SHLVL': '1', 12:31:30 INFO - 'STYLO_FORCE_ENABLED': '1', 12:31:30 INFO - 'TERM': 'linux', 12:31:30 INFO - 'TMOUT': '86400', 12:31:30 INFO - 'USER': 'cltbld', 12:31:30 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', 12:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:30 INFO - '_': '/tools/buildbot/bin/python'} 12:31:30 INFO - TEST-INFO | started process 3652 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpexca6w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:31:31 INFO - PID 3652 | 12:31:31 INFO - PID 3652 | (/builds/slave/test/build/application/firefox/firefox:3703): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:31:31 INFO - PID 3652 | 12:31:42 INFO - PID 3652 | RSS: Main: 172941312 12:31:42 INFO - PID 3652 | 12:34:41 INFO - PID 3652 | Cycle 1(1): loaded http://localhost:59659/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59659/tests/dromaeo/cssquery-ext.html) 12:34:42 INFO - PID 3652 | RSS: Main: 174014464 12:34:42 INFO - PID 3652 | 12:37:44 INFO - PID 3652 | Cycle 1(1): loaded http://localhost:59659/tests/dromaeo/cssquery-ext.html (next: http://localhost:59659/tests/dromaeo/cssquery-jquery.html) 12:37:45 INFO - PID 3652 | RSS: Main: 169054208 12:37:45 INFO - PID 3652 | 12:40:46 INFO - PID 3652 | Cycle 1(1): loaded http://localhost:59659/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59659/tests/dromaeo/cssquery-mootools.html) 12:40:47 INFO - PID 3652 | RSS: Main: 173666304 12:40:47 INFO - PID 3652 | 12:43:49 INFO - PID 3652 | Cycle 1(1): loaded http://localhost:59659/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59659/tests/dromaeo/cssquery-prototype.html) 12:43:49 INFO - PID 3652 | RSS: Main: 170192896 12:43:49 INFO - PID 3652 | 12:46:53 INFO - PID 3652 | Cycle 1(1): loaded http://localhost:59659/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59659/tests/dromaeo/cssquery-yui.html) 12:46:53 INFO - PID 3652 | RSS: Main: 173285376 12:46:53 INFO - PID 3652 | 12:49:55 INFO - PID 3652 | Cycle 1(1): loaded http://localhost:59659/tests/dromaeo/cssquery-yui.html (next: http://localhost:59659/tests/dromaeo/cssquery-dojo.html) 12:49:55 INFO - PID 3652 | RSS: Main: 174039040 12:49:55 INFO - PID 3652 | 12:49:55 INFO - PID 3652 | __start_tp_report 12:49:55 INFO - PID 3652 | _x_x_mozilla_page_load 12:49:55 INFO - PID 3652 | _x_x_mozilla_page_load_details 12:49:55 INFO - PID 3652 | |i|pagename|runs| 12:49:55 INFO - PID 3652 | |0;dojo.html;3330.9167270818225;3339.198592337839;3339.382214290355;3360.092774950763;3375.1562109472634;6313.210848643919;6366.299707032229;6389.776357827477;6432.517561182913;6472.741090356392;6237.688115594225;6331.683415829201;6338.271098823637;6339.5245356598325;6351.650659213753;5823.12653102036;5840.094785308284;5841.4450627190345;5852.122181672762;5864.208331875185;5746.626469279484;5846.64920104793;5868.559858010645;5876.08920617305;5885.028970219909;5832.650040997553;5965.985782416999;5980.760769569212;5984.88030239395;5989.550783691218;5796.217510636052;5921.822345329625;5945.197398351211;5953.404659534055;6022.187004754346;5650.5479561634975;5805.5065319447895;5882.147088672119;5883.676397798119;5944.7324870380935;6150.907736383958;6176.227971503562;6240.500759939239;6242.531810114214;6263.498920086382;6768.627725475097;6774.8306292342595;6823.692933817941;6833.58998460094;6856.657167141673;6698.4641228701585;6848.760293389708;6849.075374824385;6943.89584156233;6948.44412447003;6240.4071613196675;6345.587536810092;6394.584352017104;6415.230172379298;6423.389777971085;6995.685194166274;7098.574085554883;7102.076730025064;7104.7158113675905;7122.750703725448;6672.699728512229;6682.164729408824;6708.463322934153;6764.188297404343;6802.897956530656;6778.864422711518;6789.864202715918;6876.759313424106;6887.173539175331;6905.482088843256;6796.898046529306;6864.519483636098;6879.965600171967;6880.759173428906;6898.689558969859;6831.863362732817;6834.555753875982;6850.45196384298;6927.3419025192525;6944.895826562605;5234.293370395024;5330.573554115662;5331.786728530816;5341.439148889311;5349.946500535023;4672.532746725368;4704.694194877322;4711.434627844612;4718.646101542398;4779.06808172403;5961.254843144607;5992;6020.789272375446;6041.758329666764;6048.818535443944;6642.800715978528;6798.694058767367;6811.557248778813;6857.759978400832;6943.027976083353;5866.1494083357675;5890.911636325459;5897.970510147507;5913.379095195029;5917.911231331447;5774.162746401748;5788.421157884178;5793;5818.44724751148;5933.199018132583;6576.769813056616;6634.933650663528;6645.700943457556;6646.933530664632;6653.500987425962;6708.597484150967;6841.794746157623;6851.965740171467;6929.341712537301;6931.757388491378;6538.542302038812;6573.3426657333885;6591.736330546724;6595.076689263411;6606.174228221473;6684.598924064571;6705.597664140168;6725.899111513233;6750.05499230118;6767.830804229934;6786.558873673393;6822.829429264308;6869.793906182823;6878.69045892926;6931.896021559782;6588.670566471561;6606.702698378488;6675.666216689243;6692.866142677021;6706.094677218513;6496.155499785186;6576.80269591913;6581.638009909501;6582.045603387578;6583.637899915359;6455.419012288917;6505.41451269388;6505.967470162619;6561.80314590563;6623.470122390101;6634.203895532567;6666.933330666631;6682.899756503559;6720;6725.327467253235;6374.617522948638;6392.9041064385;6483;6483.4164925156965;6526.021096835323 12:49:55 INFO - PID 3652 | |1;ext.html;804.714326414123;864.9481031138132;901.6438506789821;902.5938327752509;1154.1113342726107;29851.70148298519;30431.087067387987;40619;40981.385279220834;41030.794846025805;41327.17345653085;41449.17101657973;41651.16697666045;41699;41768.164636707246;7341.1557670868;7665.233476652332;7807.960960195191;7920.841583168333;7948.364130869545;7774.650140743652;7908.7231946881875;7917.56453395063;7919;7924.841503169933;7874.488158269723;7889.566073865935;7909.367250619936;7921.168277330884;7941.483803552751;7849.843003139962;7857.41069419793;7908.841823163533;7910.090339610903;7915.010623672069;7680.731174408925;7797.844043119106;7801.336886364666;7803.297703206738;7809.960950195213;35880.64119358825;35966.64033359685;36052.278954421025;36059.115270778275;36091.4586281206;3491.8777842775635;3504.0539054455153;3505.3339865425487;3519.7360197985254;3525.806080665588;31056.068317950496;31093.222669433446;31115.84442077775;31135.844320778477;31164.53253201181;5056.494350564914;5079.923801142986;5083.720395378253;5096.566791822699;5103.821366252163;170394;171488;171610;172308.27691723048;172669;45967.77016114898;46809.765951170026;46829.76585117052;46888.531114689096;46940.29589556159;48361.27458088131;48380.75809621;48614.27078593824;48699.5130048695;48717.75641121772;15805.68388632221;15963;15997.440089596808;16019.919900400424;16052.197390130446;11235.606753763579;11369.602063927723;13072.869271307356;13094.869051309364;13239.602811915658;13393.397297121654;13428.597142085559;13445.126066805626;13499.325033748273;13543;5294;5386.407495175527;5410.810621628303;5498.835034948958;5532.419095994943;983.179045497018;997.3018886779139;1006.4615430744662;1021.2085633633993;1026.9229807764448;22674.65988010181;23028.88485557595;23040.235171779495;23567.0573177071;23571.17500887461;1058.6771034834449;1084.38732116354;1084.7830433913248;1102.2669924500117;1120.2662256222188;11963;11975.52097916091;12148.088893333035;12204.450799714034;12259.816102758288;223771;230106;233367;233614;233735;51583.742081290104;51667.48332516702;51730;51967.480325196266;51997.2200416994;1386.9930650346685;1446.5660301909386;1450.0574626492864;1457.2859298943654;1477.4385733421445;21671.133154673636;21790.89104554467;22115;22194.66707999349;22579.887100564392;35773.64226357703;35966.82016590005;35977.82011089928;35992;36023.27953440969;200969;202185;202207;202927;204563;32307;32720.836395817867;34978.47532287068;36036.45945310875;36604.450933235494;44792.10415791731;45477.77261113673;45523;45992;45994.31008534808;32543.18642033968;32687.34625307433;33100.66899331072;33430;33568.49647255244;32244.677553225105;32604.347913041132;32753.34493310073;32789.67210327962;33248.335033299685;35286.29427411386;37047.444288336235;37179.814100929325;37396.252074958895;37460.43809342808;31234.843825780725;31945.68054319427;32068.679313207504;32357;32842.50736239006;23627.881860590587;24484.14305499299;24569.754302457462;24708.258752237463;25559.361015974006 12:49:55 INFO - PID 3652 | |2;jquery.html;556.457453982367;558.4471373340392;559.1808001278127;565.4741090785568;572.7107810555668;21475.67786483204;22305.107795688153;22327;22339.664905026417;22429.878506074714;26038;26843.597346039824;26888.327791805215;26894.327641808966;27006;7185.676644550982;7356.889646655304;7359.227281135484;7363.374113200384;7365.705371785149;6525.738970441176;7142.964285178593;7175.318344757266;7177.102862142232;7178.8205294867785;7153.642317884111;7156.212816590171;7157.463190260752;7158.8568228635395;7159.89260161098;7154.213036565973;7159.105111861018;7167.3549380556;7168.641567921583;7174.174969878452;7029.086218791575;7029.89455158173;7041.225465198824;7058.823529411755;7059.047028651118;29973;31012.844935775403;31074.06777796647;31113.22216944572;31125.688743112507;1974.891380974046;1977.772556156046;1979.3270288102115;1982.0288058851204;1983.9107240174226;20105.095270712853;20953.37139885806;21131.47171320729;21132.788672113234;21264.36206913795;2495.1890635543523;2516.3331717094984;2519.1308998395525;2565.217608629367;2590.940202538986;133850;136794;137442;137553.31223343918;137663;19974.800251997585;21182.258620948192;21642.783572164077;21746.673799893015;22585.661215081785;43489;43490.56509434929;43663;43688.3446748299;43819.78090109593;11177.385243811668;11310.830337544943;11515.942420287845;11553.884461155281;11620.825687614693;8172.83654326922;8616.526091065052;9500.809983800285;9598.95200523993;9761.267904907161;9896.356736812084;11239.719007024889;11423.771524569629;11508.136889733303;11581.478833452515;3827.2252616853116;3834.616538346143;4291.227579035741;4341.6526677865695;4343.457067866517;947.1239103829068;977.379364103785;996.7607774134301;1006.7684432580547;1011.1758916483043;19929.800701993085;20036.799632003494;20132.496687582636;20655.69016464754;20913.477163071046;1062.3360399750156;1074.274864466482;1080.56777289085;1084.5228099636136;1088.0425225801243;10854.45727713611;11119;11306.717332066764;11406.771864562828;11502.884971150348;497346;501460;531476;537574;541221;116840;124738.37630811788;125978.37010814887;126643;127950;1239.3493415956514;1240.7580521798163;1283.9293838838953;1289.38109707341;1293.6442478318538;21513.462163446038;21584.244551440624;21607.135714571243;21615.675764863856;21673.566528669653;57427.42572574388;58881;59263;60491.09263361141;60817.087743683;68661.65669171623;77454;80670;81039.59480202562;81456;12483.56307529232;12901.290429026118;13881.652958676113;14234.786478203043;14661;22947;23119.07523699033;23429;23638;23676.289711308687;18127.27490900402;18526.444206673823;18602;18730.34443794461;18748.437546873614;15073.397064117313;15858.841411585736;16625.833741662427;17094.888832225864;17107.572310692332;18610.534736631693;18984.810151898306;19785;19788.50528736793;19870.701939471208;13903.652408689459;14453.205073721047;14552.199629020506;14560.781588276397;14576.271186440423;17339.739903901198;17540.561485962953;17557;18600.906995464935;18713.438596842116 12:49:55 INFO - PID 3652 | |3;mootools.html;1348.3999620169025;1358.6399604104913;1390.9234992075437;1411.9999999999993;1425.8003879456885;13438.126521776077;14058.578242652724;14062.57812265631;14071.507497237599;14082.57752267431;14393.352299146538;14711.11733296003;15386.307616157272;15443.768343474856;15449.45926892559;2687.90592329269;2770.515159847029;2771.140946306641;2774.278687541244;2775.098093119744;2672.27848480911;2684.939448917676;2742.9177124686294;2745.052956729925;2780.708025657307;2705.418335057962;2718.497078040569;2750.0787236275874;2757.1039412191017;2757.986210068957;2657.50836095323;2661.2149415922254;2682.798790090741;2686.892524299026;2689.7713694336003;2563.0772921748157;2582.7029891562524;2585.922422327333;2588.1071030494636;2594.9091781787733;14283.857161428461;14681.77977330341;14684.632884177874;14686.118832870063;14707.705845883022;7807.297343239135;8166.101728809886;8177.100518942912;8179;8180.181981801832;5298.072837253465;5488.69812160331;5497.092979658351;5521.613487055908;5621.409751976065;1796.7574377459007;1813.8276863697927;1846.0861873372633;1870.2892900697648;1894.5642502224428;24727.25818225456;24931.62602560945;24995.250142495755;25050.373740656447;25070.874645626838;6435.806925792233;6550.7707230246715;6674.466042716571;6727.697253623599;6730.360615741594;15494.5351639451;15653.217339132998;15700.68598628044;15701.214939252992;15705.607359816097;7727.961360193275;7732.922670773332;7739;7744.535327880345;7746.147923728386;7042.225355210922;7139.428845692332;7161.641917904084;7172.13934327884;7188.065551478275;4836.443808962014;4922.212446008622;5110.897782044338;5111.667741596784;5149.176131818891;1300.902432317561;1430.3849344781843;1458.2781523146111;1484.4284950294214;1487.5388629524666;903.304455569208;921.1433366968784;921.161742814036;930.7766136127416;940.3182692547848;7484.738034168887;7517.285857843496;7552.358049565849;7567.356774674105;7625.618719064025;981.5975450065439;988.683621241196;989.6981420666622;992.7369247149511;993.5181437003048;4519.960409105924;4614.630829533697;4631.258998560282;4665.930011049769;4709.987352719182;19945.401637950883;20012.49968750793;20246;20371.592568148553;20703.689444658343;19686.507837303896;19937.202511899362;20120.89939550293;20126.496837578885;20137.49656258576;10908.181886358554;10958.506867190996;11023.559057637605;11087.22389432732;11122.999999999838;7935.0874649416055;7937.444378893422;7979.361651068017;8018.158093400109;8102.837943240984;17452.389166379115;17472.30110795554;17526.73709894378;17695.823041769418;18015.909920450315;17865.464036078938;17954.371596994042;17991.82008179954;18095;18231.088445577934;10379.221558383;10570.577176912837;10573.52419141156;10577;10595.417252051211;13384.866151338361;13441.596752097452;13493.190408575518;13536.661583460493;13541.187528748307;10792;10807.027367536959;10848.620298289521;11123.83314250303;11194.776104478027;10566.101881340172;10634.41510716918;10786.137109031415;10841.132709383073;11025.61410350666;9062.04848490899;9229.630814767334;9231.215346695606;9232;9236.26109911219;6767.221769496374;6779.661016949034;6852.931470685229;6854.897176542256;6887.311268873073;6521.771737989147;6594.901076483952;6651.368120028589;6670.332966703291;6670.533062685566 12:49:55 INFO - PID 3652 | |4;prototype.html;464.6933024204022;478.25153634561906;489.7404375680889;495.640660521122;498.5039885314112;6182.350853160421;6222.657753823538;6291.748330066797;6323.146375239341;6430.710618022188;6091.604045737024;6101.69491525423;6167.286393986643;6174.382561743823;6271.905921411182;4901.117798796224;5016.372953380827;5021.774020169083;5022.799088036474;5046.293518907356;4791.832285869995;5005.599552035828;5018.34761481007;5022.221555658879;5042.47054059324;4809.523095605766;4851.611871050308;4880.926786098212;4936.950630493703;4939.357883475143;4914.287428322873;4934.383202099738;4947.975260123676;4950.712814668176;4966.2798894160505;4336.978315108404;4370.628496577795;4383.101464199832;4391.934120988188;4392.407025051609;6141.232345956755;6163.907541386883;6183.598066125687;6203.379662033761;6251.906221406683;5530.225768392428;5569.415211402826;5606.915896261559;5631.690257035862;5703.372629010805;6552.770653027121;6600.834979125512;6611.371919667624;6631.867362652768;6651.534392592521;4710.24636058229;4737.668363214576;4749.952500474986;4750.263709125091;4750.762461876892;6806.183258009021;6937.7571784987285;6943.79168624942;6993;7005.54463959841;4892.315075889378;4896.069746748178;4924.236743304794;4948.925766113512;4953.23224900141;4967.3542439482635;4994.200927851526;5016.573591244785;5024.2463630454995;5026.723530205873;5706.4864162225595;5723.399043100415;5735.139729040594;5758.942410575841;5764.510016648548;6659.567128136656;6695.1965764107645;6700.262971073178;6746.527743057939;6758.702041305881;1666.8916520426133;1708.7867613993956;1725.8360455756683;1746.97379539307;1752.1502071495315;1099.3623698254994;1171.2738102376582;1186.5135294529202;1188.8097904335439;1208.4078801387257;5883;5927.792527261611;5947.881042379128;5955.434233747787;5981.401859813984;6754.662266886636;6763;6809;6819.624920629413;6842.213145488333;5802.765935114927;5922.838164070084;5941.049432090845;5959.920880701747;5980.521558275327;3631.673149416565;3631.9648900063357;3644.671979521856;3647.744657873923;3655.8720444784835;6569.901451478136;6644.966775166094;6646.53474256807;6657.201135863727;6682.431993280528;6950.906846453279;6976.686049127801;7032.382552012963;7037.92962070383;7081.221065682771;6575.646420325573;6605.86788264232;6610.272869984202;6688.364605362385;6731.889238275728;6772.255051944184;6783.186017677713;6801.455883529405;6885.552439091443;6903.275156108536;6781.321867813179;6818.829529261808;6840.179178498612;6847.8016347138055;6868.9313106870295;6577.309382514958;6611.140551728245;6621.470282377302;6648.1025061616065;6684.933150668431;4954.603631709382;4965.255211718271;4968.751562421937;5004.174311238605;5013.674111182761;6919.896201557081;6927.411170050401;6963.7562685305775;6997.650117494003;7004.789856304319;6632.436242919406;6642;6647.069410282467;6692.765753198614;6732.3940845322195;6584.736610535717;6607.57050791697;6624.6025238485845;6674;6697.497687673542;6189.566730328834;6505.804825855232;6576.901346479903;6585.901211481736;6597;6422.871542569029;6541.934580654323;6572.572782769104;6619.536632435684;6682.565633233824;6296.559240853279;6365.490760739222;6394.008928616072;6411.781761465307;6443.613383197023 12:49:55 INFO - PID 3652 | |5;yui.html;894.4990805149115;895.5477483870644;895.9731208063756;913.118840319092;916.7983043730377;18986.43040708779;19952.800471995277;19967.400977970665;20006.59986800263;20167.294144704938;20473;21183.470413239676;21186.046627901742;21290.254841080565;21312.680309795327;961.447167878471;972.8151651186251;974.5030034682292;974.5663179884954;984.6504490905733;977.8924318325014;978.9118979291862;990.3315262198023;990.3810118675827;992.0724122945034;1008.5894711639188;1010.3331801011334;1020.0462567499375;1021.0555236406332;1033.0289527843802;1015.8069966706339;1019.6788011776267;1022.6471867205839;1024.1960061351876;1025.5897640943645;747.4580928826612;747.5290566942846;747.5701471653821;748.7304570354668;753.2467532467532;19552.902235488873;20075.89962050195;20184.293549725837;20391.796082039287;20415;21310.573788524296;21393.144274229013;21734.347969560782;21915;21923.561528769496;16769.24538395775;16965;17087;17137.314507419687;17149.142542872807;3730.4404339349044;3736.1967177057063;3738.6448287412654;3751.7936513491745;3755.4742336072973;121277;121320.03940416117;123787.381063095;126074;126441;16289.918550407172;16502.17489125563;16930.915345423193;16941.661166776594;17121.743173852403;29889.551656725165;30608.08175754731;30634.84682576573;30690.84654576713;30999.69000310013;8253.33973282136;8291.922050133444;8324.542150181676;8340.582970851434;8389.286910612544;4346.695731298842;4424.11517696462;4476.574725401082;4500.122476117126;4504.572065653823;5229.816956406507;5296.814611488579;5349.946500534945;5351.1973204019705;5373.059714549938;940.9764755881158;959.3428501476511;974.0405700385115;975.8292298847673;979.1041197304529;633.7940169444948;660.5673283998989;671.9764808231688;678.9694463749144;681.2846511163259;11336.093112551142;11797.056235501139;11981.221220620631;12000.039996800235;12052.819207711891;691.1188235000415;701.4283359062348;708.9397401220954;715.0132816712903;716.2944499667823;5272.604554658416;5380.650257733235;5419.430959749248;5513.917291240635;5571.8885622288135;111558;113431;113558;113667.43166284116;114867;92313.53843230741;97217.51391242999;99307.50346248223;102122.48938755259;103793;82308.17691823005;85590;87686.56156719176;88301;89908;12599.93700031494;13038.804417933548;13370.197788132744;13395.464181432633;13397.933010334886;38692;39953.60046399499;40186.598134018284;40257.59742402656;41178;69719.30280697127;69994.30005699878;70998.64500677463;71597.6420117896;71653.28346716466;18052.368167114088;18216.81783182204;18475.16861741198;18742;19115.522111947314;20357.185712571925;20799.376018719464;21028.158873645494;21299.574008520052;22004.559908801413;18117.909410452863;18325;18449.81550184481;19291.903540482206;19730.704039439133;19217.231310747415;19309;19375.4187374379;19487.025648717223;19639.31262405866;19350.709739354203;19521.219151233792;19689.606207875477;20145.6978145325;20268.695969560173;13533;13551.322433878464;13553.457861685816;13746.450141994208;14527.709445811235;13971.441142354599;14360.856391435951;14500.274986250435;15197.31612077437;15568.532944011698 12:49:55 INFO - PID 3652 | __end_tp_report 12:49:55 INFO - PID 3652 | __start_cc_report 12:49:55 INFO - PID 3652 | _x_x_mozilla_cycle_collect,366 12:49:55 INFO - PID 3652 | __end_cc_report 12:49:55 INFO - PID 3652 | __startTimestamp1504036195787__endTimestamp 12:49:55 INFO - PID 3652 | 12:49:55 INFO - PID 3652 | ------- Summary: start ------- 12:49:55 INFO - PID 3652 | Number of tests: 6 12:49:55 INFO - PID 3652 | 12:49:55 INFO - PID 3652 | [#0] dojo.html Cycles:165 Average:6279.58 Median:6505.97 stddev:733.30 (11.3%) stddev-sans-first:698.11 12:49:55 INFO - PID 3652 | Values: 3330.9 3339.2 3339.4 3360.1 3375.2 6313.2 6366.3 6389.8 6432.5 6472.7 6237.7 6331.7 6338.3 6339.5 6351.7 5823.1 5840.1 5841.4 5852.1 5864.2 5746.6 5846.6 5868.6 5876.1 5885.0 5832.7 5966.0 5980.8 5984.9 5989.6 5796.2 5921.8 5945.2 5953.4 6022.2 5650.5 5805.5 5882.1 5883.7 5944.7 6150.9 6176.2 6240.5 6242.5 6263.5 6768.6 6774.8 6823.7 6833.6 6856.7 6698.5 6848.8 6849.1 6943.9 6948.4 6240.4 6345.6 6394.6 6415.2 6423.4 6995.7 7098.6 7102.1 7104.7 7122.8 6672.7 6682.2 6708.5 6764.2 6802.9 6778.9 6789.9 6876.8 6887.2 6905.5 6796.9 6864.5 6880.0 6880.8 6898.7 6831.9 6834.6 6850.5 6927.3 6944.9 5234.3 5330.6 5331.8 5341.4 5349.9 4672.5 4704.7 4711.4 4718.6 4779.1 5961.3 5992.0 6020.8 6041.8 6048.8 6642.8 6798.7 6811.6 6857.8 6943.0 5866.1 5890.9 5898.0 5913.4 5917.9 5774.2 5788.4 5793.0 5818.4 5933.2 6576.8 6634.9 6645.7 6646.9 6653.5 6708.6 6841.8 6852.0 6929.3 6931.8 6538.5 6573.3 6591.7 6595.1 6606.2 6684.6 6705.6 6725.9 6750.1 6767.8 6786.6 6822.8 6869.8 6878.7 6931.9 6588.7 6606.7 6675.7 6692.9 6706.1 6496.2 6576.8 6581.6 6582.0 6583.6 6455.4 6505.4 6506.0 6561.8 6623.5 6634.2 6666.9 6682.9 6720.0 6725.3 6374.6 6392.9 6483.0 6483.4 6526.0 12:49:55 INFO - PID 3652 | 12:49:55 INFO - PID 3652 | [#1] ext.html Cycles:180 Average:36937.54 Median:3504.69 stddev:52634.55 (1501.8%) stddev-sans-first:52712.27 12:49:55 INFO - PID 3652 | Values: 804.7 864.9 901.6 902.6 1154.1 29851.7 30431.1 40619.0 40981.4 41030.8 41327.2 41449.2 41651.2 41699.0 41768.2 7341.2 7665.2 7808.0 7920.8 7948.4 7774.7 7908.7 7917.6 7919.0 7924.8 7874.5 7889.6 7909.4 7921.2 7941.5 7849.8 7857.4 7908.8 7910.1 7915.0 7680.7 7797.8 7801.3 7803.3 7810.0 35880.6 35966.6 36052.3 36059.1 36091.5 3491.9 3504.1 3505.3 3519.7 3525.8 31056.1 31093.2 31115.8 31135.8 31164.5 5056.5 5079.9 5083.7 5096.6 5103.8 170394.0 171488.0 171610.0 172308.3 172669.0 45967.8 46809.8 46829.8 46888.5 46940.3 48361.3 48380.8 48614.3 48699.5 48717.8 15805.7 15963.0 15997.4 16019.9 16052.2 11235.6 11369.6 13072.9 13094.9 13239.6 13393.4 13428.6 13445.1 13499.3 13543.0 5294.0 5386.4 5410.8 5498.8 5532.4 983.2 997.3 1006.5 1021.2 1026.9 22674.7 23028.9 23040.2 23567.1 23571.2 1058.7 1084.4 1084.8 1102.3 1120.3 11963.0 11975.5 12148.1 12204.5 12259.8 223771.0 230106.0 233367.0 233614.0 233735.0 51583.7 51667.5 51730.0 51967.5 51997.2 1387.0 1446.6 1450.1 1457.3 1477.4 21671.1 21790.9 22115.0 22194.7 22579.9 35773.6 35966.8 35977.8 35992.0 36023.3 200969.0 202185.0 202207.0 202927.0 204563.0 32307.0 32720.8 34978.5 36036.5 36604.5 44792.1 45477.8 45523.0 45992.0 45994.3 32543.2 32687.3 33100.7 33430.0 33568.5 32244.7 32604.3 32753.3 32789.7 33248.3 35286.3 37047.4 37179.8 37396.3 37460.4 31234.8 31945.7 32068.7 32357.0 32842.5 23627.9 24484.1 24569.8 24708.3 25559.4 12:49:55 INFO - PID 3652 | 12:49:55 INFO - PID 3652 | [#2] jquery.html Cycles:180 Average:37296.73 Median:22025.89 stddev:87675.18 (398.1%) stddev-sans-first:87877.73 12:49:55 INFO - PID 3652 | Values: 556.5 558.4 559.2 565.5 572.7 21475.7 22305.1 22327.0 22339.7 22429.9 26038.0 26843.6 26888.3 26894.3 27006.0 7185.7 7356.9 7359.2 7363.4 7365.7 6525.7 7143.0 7175.3 7177.1 7178.8 7153.6 7156.2 7157.5 7158.9 7159.9 7154.2 7159.1 7167.4 7168.6 7174.2 7029.1 7029.9 7041.2 7058.8 7059.0 29973.0 31012.8 31074.1 31113.2 31125.7 1974.9 1977.8 1979.3 1982.0 1983.9 20105.1 20953.4 21131.5 21132.8 21264.4 2495.2 2516.3 2519.1 2565.2 2590.9 133850.0 136794.0 137442.0 137553.3 137663.0 19974.8 21182.3 21642.8 21746.7 22585.7 43489.0 43490.6 43663.0 43688.3 43819.8 11177.4 11310.8 11515.9 11553.9 11620.8 8172.8 8616.5 9500.8 9599.0 9761.3 9896.4 11239.7 11423.8 11508.1 11581.5 3827.2 3834.6 4291.2 4341.7 4343.5 947.1 977.4 996.8 1006.8 1011.2 19929.8 20036.8 20132.5 20655.7 20913.5 1062.3 1074.3 1080.6 1084.5 1088.0 10854.5 11119.0 11306.7 11406.8 11502.9 497346.0 501460.0 531476.0 537574.0 541221.0 116840.0 124738.4 125978.4 126643.0 127950.0 1239.3 1240.8 1283.9 1289.4 1293.6 21513.5 21584.2 21607.1 21615.7 21673.6 57427.4 58881.0 59263.0 60491.1 60817.1 68661.7 77454.0 80670.0 81039.6 81456.0 12483.6 12901.3 13881.7 14234.8 14661.0 22947.0 23119.1 23429.0 23638.0 23676.3 18127.3 18526.4 18602.0 18730.3 18748.4 15073.4 15858.8 16625.8 17094.9 17107.6 18610.5 18984.8 19785.0 19788.5 19870.7 13903.7 14453.2 14552.2 14560.8 14576.3 17339.7 17540.6 17557.0 18600.9 18713.4 12:49:55 INFO - PID 3652 | 12:49:55 INFO - PID 3652 | [#3] mootools.html Cycles:180 Average:8886.26 Median:2659.36 stddev:6244.28 (234.8%) stddev-sans-first:6236.11 12:49:55 INFO - PID 3652 | Values: 1348.4 1358.6 1390.9 1412.0 1425.8 13438.1 14058.6 14062.6 14071.5 14082.6 14393.4 14711.1 15386.3 15443.8 15449.5 2687.9 2770.5 2771.1 2774.3 2775.1 2672.3 2684.9 2742.9 2745.1 2780.7 2705.4 2718.5 2750.1 2757.1 2758.0 2657.5 2661.2 2682.8 2686.9 2689.8 2563.1 2582.7 2585.9 2588.1 2594.9 14283.9 14681.8 14684.6 14686.1 14707.7 7807.3 8166.1 8177.1 8179.0 8180.2 5298.1 5488.7 5497.1 5521.6 5621.4 1796.8 1813.8 1846.1 1870.3 1894.6 24727.3 24931.6 24995.3 25050.4 25070.9 6435.8 6550.8 6674.5 6727.7 6730.4 15494.5 15653.2 15700.7 15701.2 15705.6 7728.0 7732.9 7739.0 7744.5 7746.1 7042.2 7139.4 7161.6 7172.1 7188.1 4836.4 4922.2 5110.9 5111.7 5149.2 1300.9 1430.4 1458.3 1484.4 1487.5 903.3 921.1 921.2 930.8 940.3 7484.7 7517.3 7552.4 7567.4 7625.6 981.6 988.7 989.7 992.7 993.5 4520.0 4614.6 4631.3 4665.9 4710.0 19945.4 20012.5 20246.0 20371.6 20703.7 19686.5 19937.2 20120.9 20126.5 20137.5 10908.2 10958.5 11023.6 11087.2 11123.0 7935.1 7937.4 7979.4 8018.2 8102.8 17452.4 17472.3 17526.7 17695.8 18015.9 17865.5 17954.4 17991.8 18095.0 18231.1 10379.2 10570.6 10573.5 10577.0 10595.4 13384.9 13441.6 13493.2 13536.7 13541.2 10792.0 10807.0 10848.6 11123.8 11194.8 10566.1 10634.4 10786.1 10841.1 11025.6 9062.0 9229.6 9231.2 9232.0 9236.3 6767.2 6779.7 6852.9 6854.9 6887.3 6521.8 6594.9 6651.4 6670.3 6670.5 12:49:55 INFO - PID 3652 | 12:49:55 INFO - PID 3652 | [#4] prototype.html Cycles:180 Average:5556.94 Median:6178.37 stddev:1597.44 (25.9%) stddev-sans-first:1555.53 12:49:55 INFO - PID 3652 | Values: 464.7 478.3 489.7 495.6 498.5 6182.4 6222.7 6291.7 6323.1 6430.7 6091.6 6101.7 6167.3 6174.4 6271.9 4901.1 5016.4 5021.8 5022.8 5046.3 4791.8 5005.6 5018.3 5022.2 5042.5 4809.5 4851.6 4880.9 4937.0 4939.4 4914.3 4934.4 4948.0 4950.7 4966.3 4337.0 4370.6 4383.1 4391.9 4392.4 6141.2 6163.9 6183.6 6203.4 6251.9 5530.2 5569.4 5606.9 5631.7 5703.4 6552.8 6600.8 6611.4 6631.9 6651.5 4710.2 4737.7 4750.0 4750.3 4750.8 6806.2 6937.8 6943.8 6993.0 7005.5 4892.3 4896.1 4924.2 4948.9 4953.2 4967.4 4994.2 5016.6 5024.2 5026.7 5706.5 5723.4 5735.1 5758.9 5764.5 6659.6 6695.2 6700.3 6746.5 6758.7 1666.9 1708.8 1725.8 1747.0 1752.2 1099.4 1171.3 1186.5 1188.8 1208.4 5883.0 5927.8 5947.9 5955.4 5981.4 6754.7 6763.0 6809.0 6819.6 6842.2 5802.8 5922.8 5941.0 5959.9 5980.5 3631.7 3632.0 3644.7 3647.7 3655.9 6569.9 6645.0 6646.5 6657.2 6682.4 6950.9 6976.7 7032.4 7037.9 7081.2 6575.6 6605.9 6610.3 6688.4 6731.9 6772.3 6783.2 6801.5 6885.6 6903.3 6781.3 6818.8 6840.2 6847.8 6868.9 6577.3 6611.1 6621.5 6648.1 6684.9 4954.6 4965.3 4968.8 5004.2 5013.7 6919.9 6927.4 6963.8 6997.7 7004.8 6632.4 6642.0 6647.1 6692.8 6732.4 6584.7 6607.6 6624.6 6674.0 6697.5 6189.6 6505.8 6576.9 6585.9 6597.0 6422.9 6541.9 6572.6 6619.5 6682.6 6296.6 6365.5 6394.0 6411.8 6443.6 12:49:55 INFO - PID 3652 | 12:49:55 INFO - PID 3652 | [#5] yui.html Cycles:180 Average:24087.00 Median:21311.63 stddev:32269.12 (151.4%) stddev-sans-first:32312.65 12:49:55 INFO - PID 3652 | Values: 894.5 895.5 896.0 913.1 916.8 18986.4 19952.8 19967.4 20006.6 20167.3 20473.0 21183.5 21186.0 21290.3 21312.7 961.4 972.8 974.5 974.6 984.7 977.9 978.9 990.3 990.4 992.1 1008.6 1010.3 1020.0 1021.1 1033.0 1015.8 1019.7 1022.6 1024.2 1025.6 747.5 747.5 747.6 748.7 753.2 19552.9 20075.9 20184.3 20391.8 20415.0 21310.6 21393.1 21734.3 21915.0 21923.6 16769.2 16965.0 17087.0 17137.3 17149.1 3730.4 3736.2 3738.6 3751.8 3755.5 121277.0 121320.0 123787.4 126074.0 126441.0 16289.9 16502.2 16930.9 16941.7 17121.7 29889.6 30608.1 30634.8 30690.8 30999.7 8253.3 8291.9 8324.5 8340.6 8389.3 4346.7 4424.1 4476.6 4500.1 4504.6 5229.8 5296.8 5349.9 5351.2 5373.1 941.0 959.3 974.0 975.8 979.1 633.8 660.6 672.0 679.0 681.3 11336.1 11797.1 11981.2 12000.0 12052.8 691.1 701.4 708.9 715.0 716.3 5272.6 5380.7 5419.4 5513.9 5571.9 111558.0 113431.0 113558.0 113667.4 114867.0 92313.5 97217.5 99307.5 102122.5 103793.0 82308.2 85590.0 87686.6 88301.0 89908.0 12599.9 13038.8 13370.2 13395.5 13397.9 38692.0 39953.6 40186.6 40257.6 41178.0 69719.3 69994.3 70998.6 71597.6 71653.3 18052.4 18216.8 18475.2 18742.0 19115.5 20357.2 20799.4 21028.2 21299.6 22004.6 18117.9 18325.0 18449.8 19291.9 19730.7 19217.2 19309.0 19375.4 19487.0 19639.3 19350.7 19521.2 19689.6 20145.7 20268.7 13533.0 13551.3 13553.5 13746.5 14527.7 13971.4 14360.9 14500.3 15197.3 15568.5 12:49:55 INFO - PID 3652 | -------- Summary: end -------- 12:49:55 INFO - PID 3652 | 12:49:56 INFO - TEST-INFO | 3652: exit 0 12:49:56 INFO - TEST-OK | dromaeo_css | took 1111690ms 12:49:56 INFO - TEST-START | kraken 12:49:56 INFO - Initialising browser for kraken test... 12:49:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59659/getInfo.html -profile /tmp/tmpKOG56y/profile 12:49:56 INFO - TEST-INFO | started process 4027 (/builds/slave/test/build/application/firefox/firefox http://localhost:59659/getInfo.html) 12:50:01 INFO - TEST-INFO | 4027: exit 0 12:50:01 INFO - Browser initialized. 12:50:01 INFO - Running cycle 1/1 for kraken test... 12:50:01 INFO - Using env: {'DISPLAY': ':0', 12:50:01 INFO - 'HOME': '/home/cltbld', 12:50:01 INFO - 'JSGC_DISABLE_POISONING': '1', 12:50:01 INFO - 'LANG': 'en_US.UTF-8', 12:50:01 INFO - 'LANGUAGE': 'en_US:en', 12:50:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:50:01 INFO - 'LOGNAME': 'cltbld', 12:50:01 INFO - 'MAIL': '/var/mail/cltbld', 12:50:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:50:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:50:01 INFO - 'MOZ_CRASHREPORTER': '1', 12:50:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:50:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:50:01 INFO - 'MOZ_NO_REMOTE': '1', 12:50:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:50:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:50:01 INFO - 'NO_EM_RESTART': '1', 12:50:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:50:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:50:01 INFO - 'PWD': '/builds/slave/test', 12:50:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:50:01 INFO - 'RUST_BACKTRACE': 'full', 12:50:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:50:01 INFO - 'SHELL': '/bin/bash', 12:50:01 INFO - 'SHLVL': '1', 12:50:01 INFO - 'STYLO_FORCE_ENABLED': '1', 12:50:01 INFO - 'TERM': 'linux', 12:50:01 INFO - 'TMOUT': '86400', 12:50:01 INFO - 'USER': 'cltbld', 12:50:01 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836', 12:50:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:50:01 INFO - '_': '/tools/buildbot/bin/python'} 12:50:01 INFO - TEST-INFO | started process 4222 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKOG56y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:50:02 INFO - PID 4222 | 12:50:02 INFO - PID 4222 | (/builds/slave/test/build/application/firefox/firefox:4273): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:50:02 INFO - PID 4222 | 12:50:12 INFO - PID 4222 | RSS: Main: 172965888 12:50:12 INFO - PID 4222 | 12:50:15 INFO - PID 4222 | Cycle 1(1): loaded http://localhost:59659/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59659/tests/kraken/driver.html?testName=audio-beat-detection) 12:50:16 INFO - PID 4222 | RSS: Main: 177754112 12:50:16 INFO - PID 4222 | 12:50:19 INFO - PID 4222 | Cycle 1(1): loaded http://localhost:59659/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59659/tests/kraken/driver.html?testName=audio-dft) 12:50:19 INFO - PID 4222 | RSS: Main: 181731328 12:50:19 INFO - PID 4222 | 12:50:22 INFO - PID 4222 | Cycle 1(1): loaded http://localhost:59659/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59659/tests/kraken/driver.html?testName=audio-fft) 12:50:23 INFO - PID 4222 | RSS: Main: 174833664 12:50:23 INFO - PID 4222 | 12:50:25 INFO - PID 4222 | Cycle 1(1): loaded http://localhost:59659/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59659/tests/kraken/driver.html?testName=audio-oscillator) 12:50:26 INFO - PID 4222 | RSS: Main: 175464448 12:50:26 INFO - PID 4222 | 12:50:28 INFO - PID 4222 | Cycle 1(1): loaded http://localhost:59659/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59659/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:50:28 INFO - PID 4222 | RSS: Main: 175792128 12:50:28 INFO - PID 4222 | 12:50:32 INFO - PID 4222 | Cycle 1(1): loaded http://localhost:59659/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59659/tests/kraken/driver.html?testName=imaging-darkroom) 12:50:32 INFO - PID 4222 | RSS: Main: 176873472 12:50:32 INFO - PID 4222 | 12:50:36 INFO - PID 4222 | Cycle 1(1): loaded http://localhost:59659/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59659/tests/kraken/driver.html?testName=imaging-desaturate) 12:50:36 INFO - PID 4222 | RSS: Main: 176672768 12:50:36 INFO - PID 4222 | 12:50:39 INFO - PID 4222 | Cycle 1(1): loaded http://localhost:59659/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59659/tests/kraken/driver.html?testName=json-parse-financial) 12:50:40 INFO - PID 4222 | RSS: Main: 177033216 12:50:40 INFO - PID 4222 | 12:50:41 INFO - PID 4222 | Cycle 1(1): loaded http://localhost:59659/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59659/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:50:42 INFO - PID 4222 | RSS: Main: 176807936 12:50:42 INFO - PID 4222 | 12:50:44 INFO - PID 4222 | Cycle 1(1): loaded http://localhost:59659/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59659/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:50:44 INFO - PID 4222 | RSS: Main: 176975872 12:50:44 INFO - PID 4222 | 12:50:46 INFO - PID 4222 | Cycle 1(1): loaded http://localhost:59659/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59659/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:50:47 INFO - PID 4222 | RSS: Main: 173350912 12:50:47 INFO - PID 4222 | 12:50:50 INFO - PID 4222 | Cycle 1(1): loaded http://localhost:59659/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59659/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:50:51 INFO - PID 4222 | RSS: Main: 173899776 12:50:51 INFO - PID 4222 | 12:50:53 INFO - PID 4222 | Cycle 1(1): loaded http://localhost:59659/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59659/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:50:54 INFO - PID 4222 | RSS: Main: 173932544 12:50:54 INFO - PID 4222 | 12:50:56 INFO - PID 4222 | Cycle 1(1): loaded http://localhost:59659/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59659/tests/kraken/driver.html?testName=ai-astar) 12:50:56 INFO - PID 4222 | RSS: Main: 175427584 12:50:56 INFO - PID 4222 | 12:50:56 INFO - PID 4222 | __start_tp_report 12:50:56 INFO - PID 4222 | _x_x_mozilla_page_load 12:50:56 INFO - PID 4222 | _x_x_mozilla_page_load_details 12:50:56 INFO - PID 4222 | |i|pagename|runs| 12:50:56 INFO - PID 4222 | |0;ai-astar;96;108;100;107;106;110;109;97;107;106 12:50:56 INFO - PID 4222 | |1;audio-beat-detection;151;117;145;171;117;159;153;122;182;118 12:50:56 INFO - PID 4222 | |2;audio-dft;163;162;162;162;161;161;171;167;169;165 12:50:56 INFO - PID 4222 | |3;audio-fft;74;74;74;172;75;74;75;90;94;75 12:50:56 INFO - PID 4222 | |4;audio-oscillator;78;79;124;79;79;80;130;80;80;131 12:50:56 INFO - PID 4222 | |5;imaging-gaussian-blur;114;114;114;114;115;115;114;114;114;115 12:50:56 INFO - PID 4222 | |6;imaging-darkroom;150;150;150;150;148;149;148;150;149;149 12:50:56 INFO - PID 4222 | |7;imaging-desaturate;94;94;94;94;94;95;95;94;98;95 12:50:56 INFO - PID 4222 | |8;json-parse-financial;53;56;63;52;53;52;52;52;51;53 12:50:56 INFO - PID 4222 | |9;json-stringify-tinderbox;38;38;38;38;38;38;38;37;40;39 12:50:56 INFO - PID 4222 | |10;stanford-crypto-aes;75;73;74;76;75;74;74;74;74;74 12:50:56 INFO - PID 4222 | |11;stanford-crypto-ccm;133;122;128;125;121;126;128;120;131;165 12:50:56 INFO - PID 4222 | |12;stanford-crypto-pbkdf2;175;177;173;175;169;170;172;172;173;179 12:50:56 INFO - PID 4222 | |13;stanford-crypto-sha256-iterative;66;70;67;69;65;65;63;66;64;64 12:50:56 INFO - PID 4222 | __end_tp_report 12:50:56 INFO - PID 4222 | __start_cc_report 12:50:56 INFO - PID 4222 | _x_x_mozilla_cycle_collect,972 12:50:56 INFO - PID 4222 | __end_cc_report 12:50:56 INFO - PID 4222 | __startTimestamp1504036256627__endTimestamp 12:50:56 INFO - PID 4222 | 12:50:56 INFO - PID 4222 | ------- Summary: start ------- 12:50:56 INFO - PID 4222 | Number of tests: 14 12:50:56 INFO - PID 4222 | 12:50:56 INFO - PID 4222 | [#0] ai-astar Cycles:10 Average:104.60 Median:108.50 stddev:5.04 (4.6%) stddev-sans-first:4.28 12:50:56 INFO - PID 4222 | Values: 96.0 108.0 100.0 107.0 106.0 110.0 109.0 97.0 107.0 106.0 12:50:56 INFO - PID 4222 | 12:50:56 INFO - PID 4222 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:152.00 stddev:23.91 (15.7%) stddev-sans-first:25.20 12:50:56 INFO - PID 4222 | Values: 151.0 117.0 145.0 171.0 117.0 159.0 153.0 122.0 182.0 118.0 12:50:56 INFO - PID 4222 | 12:50:56 INFO - PID 4222 | [#2] audio-dft Cycles:10 Average:164.30 Median:164.00 stddev:3.56 (2.2%) stddev-sans-first:3.75 12:50:56 INFO - PID 4222 | Values: 163.0 162.0 162.0 162.0 161.0 161.0 171.0 167.0 169.0 165.0 12:50:56 INFO - PID 4222 | 12:50:56 INFO - PID 4222 | [#3] audio-fft Cycles:10 Average:87.70 Median:75.00 stddev:30.53 (40.7%) stddev-sans-first:31.97 12:50:56 INFO - PID 4222 | Values: 74.0 74.0 74.0 172.0 75.0 74.0 75.0 90.0 94.0 75.0 12:50:56 INFO - PID 4222 | 12:50:56 INFO - PID 4222 | [#4] audio-oscillator Cycles:10 Average:94.00 Median:79.00 stddev:23.77 (30.1%) stddev-sans-first:24.49 12:50:56 INFO - PID 4222 | Values: 78.0 79.0 124.0 79.0 79.0 80.0 130.0 80.0 80.0 131.0 12:50:56 INFO - PID 4222 | 12:50:56 INFO - PID 4222 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 12:50:56 INFO - PID 4222 | Values: 114.0 114.0 114.0 114.0 115.0 115.0 114.0 114.0 114.0 115.0 12:50:56 INFO - PID 4222 | 12:50:56 INFO - PID 4222 | [#6] imaging-darkroom Cycles:10 Average:149.30 Median:150.00 stddev:0.82 (0.5%) stddev-sans-first:0.83 12:50:56 INFO - PID 4222 | Values: 150.0 150.0 150.0 150.0 148.0 149.0 148.0 150.0 149.0 149.0 12:50:56 INFO - PID 4222 | 12:50:56 INFO - PID 4222 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.50 stddev:1.25 (1.3%) stddev-sans-first:1.30 12:50:56 INFO - PID 4222 | Values: 94.0 94.0 94.0 94.0 94.0 95.0 95.0 94.0 98.0 95.0 12:50:56 INFO - PID 4222 | 12:50:56 INFO - PID 4222 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:53.00 stddev:3.53 (6.7%) stddev-sans-first:3.73 12:50:56 INFO - PID 4222 | Values: 53.0 56.0 63.0 52.0 53.0 52.0 52.0 52.0 51.0 53.0 12:50:56 INFO - PID 4222 | 12:50:56 INFO - PID 4222 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:0.79 (2.1%) stddev-sans-first:0.83 12:50:56 INFO - PID 4222 | Values: 38.0 38.0 38.0 38.0 38.0 38.0 38.0 37.0 40.0 39.0 12:50:56 INFO - PID 4222 | 12:50:56 INFO - PID 4222 | [#10] stanford-crypto-aes Cycles:10 Average:74.30 Median:74.00 stddev:0.82 (1.1%) stddev-sans-first:0.83 12:50:56 INFO - PID 4222 | Values: 75.0 73.0 74.0 76.0 75.0 74.0 74.0 74.0 74.0 74.0 12:50:56 INFO - PID 4222 | 12:50:56 INFO - PID 4222 | [#11] stanford-crypto-ccm Cycles:10 Average:129.90 Median:128.00 stddev:13.03 (10.2%) stddev-sans-first:13.78 12:50:56 INFO - PID 4222 | Values: 133.0 122.0 128.0 125.0 121.0 126.0 128.0 120.0 131.0 165.0 12:50:56 INFO - PID 4222 | 12:50:56 INFO - PID 4222 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:174.00 stddev:3.06 (1.8%) stddev-sans-first:3.20 12:50:56 INFO - PID 4222 | Values: 175.0 177.0 173.0 175.0 169.0 170.0 172.0 172.0 173.0 179.0 12:50:56 INFO - PID 4222 | 12:50:56 INFO - PID 4222 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:2.23 (3.4%) stddev-sans-first:2.37 12:50:56 INFO - PID 4222 | Values: 66.0 70.0 67.0 69.0 65.0 65.0 63.0 66.0 64.0 64.0 12:50:56 INFO - PID 4222 | -------- Summary: end -------- 12:50:56 INFO - PID 4222 | 12:50:56 INFO - PID 4222 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:50:57 INFO - TEST-INFO | 4222: exit 0 12:50:57 INFO - TEST-OK | kraken | took 60823ms 12:50:57 INFO - SUITE-END | took 1172s 12:50:57 INFO - Completed test suite (00:19:32) 12:50:57 INFO - javascript benchmark 12:50:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8637.341225434859, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3330.9167270818225, 3339.198592337839, 3339.382214290355, 3360.092774950763, 3375.1562109472634, 6313.210848643919, 6366.299707032229, 6389.776357827477, 6432.517561182913, 6472.741090356392, 6237.688115594225, 6331.683415829201, 6338.271098823637, 6339.5245356598325, 6351.650659213753, 5823.12653102036, 5840.094785308284, 5841.4450627190345, 5852.122181672762, 5864.208331875185, 5746.626469279484, 5846.64920104793, 5868.559858010645, 5876.08920617305, 5885.028970219909, 5832.650040997553, 5965.985782416999, 5980.760769569212, 5984.88030239395, 5989.550783691218, 5796.217510636052, 5921.822345329625, 5945.197398351211, 5953.404659534055, 6022.187004754346, 5650.5479561634975, 5805.5065319447895, 5882.147088672119, 5883.676397798119, 5944.7324870380935, 6150.907736383958, 6176.227971503562, 6240.500759939239, 6242.531810114214, 6263.498920086382, 6768.627725475097, 6774.8306292342595, 6823.692933817941, 6833.58998460094, 6856.657167141673, 6698.4641228701585, 6848.760293389708, 6849.075374824385, 6943.89584156233, 6948.44412447003, 6240.4071613196675, 6345.587536810092, 6394.584352017104, 6415.230172379298, 6423.389777971085, 6995.685194166274, 7098.574085554883, 7102.076730025064, 7104.7158113675905, 7122.750703725448, 6672.699728512229, 6682.164729408824, 6708.463322934153, 6764.188297404343, 6802.897956530656, 6778.864422711518, 6789.864202715918, 6876.759313424106, 6887.173539175331, 6905.482088843256, 6796.898046529306, 6864.519483636098, 6879.965600171967, 6880.759173428906, 6898.689558969859, 6831.863362732817, 6834.555753875982, 6850.45196384298, 6927.3419025192525, 6944.895826562605, 5234.293370395024, 5330.573554115662, 5331.786728530816, 5341.439148889311, 5349.946500535023, 4672.532746725368, 4704.694194877322, 4711.434627844612, 4718.646101542398, 4779.06808172403, 5961.254843144607, 5992.0, 6020.789272375446, 6041.758329666764, 6048.818535443944, 6642.800715978528, 6798.694058767367, 6811.557248778813, 6857.759978400832, 6943.027976083353, 5866.1494083357675, 5890.911636325459, 5897.970510147507, 5913.379095195029, 5917.911231331447, 5774.162746401748, 5788.421157884178, 5793.0, 5818.44724751148, 5933.199018132583, 6576.769813056616, 6634.933650663528, 6645.700943457556, 6646.933530664632, 6653.500987425962, 6708.597484150967, 6841.794746157623, 6851.965740171467, 6929.341712537301, 6931.757388491378, 6538.542302038812, 6573.3426657333885, 6591.736330546724, 6595.076689263411, 6606.174228221473, 6684.598924064571, 6705.597664140168, 6725.899111513233, 6750.05499230118, 6767.830804229934, 6786.558873673393, 6822.829429264308, 6869.793906182823, 6878.69045892926, 6931.896021559782, 6588.670566471561, 6606.702698378488, 6675.666216689243, 6692.866142677021, 6706.094677218513, 6496.155499785186, 6576.80269591913, 6581.638009909501, 6582.045603387578, 6583.637899915359, 6455.419012288917, 6505.41451269388, 6505.967470162619, 6561.80314590563, 6623.470122390101, 6634.203895532567, 6666.933330666631, 6682.899756503559, 6720.0, 6725.327467253235, 6374.617522948638, 6392.9041064385, 6483.0, 6483.4164925156965, 6526.021096835323], "value": 6226.367683437506, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [804.714326414123, 864.9481031138132, 901.6438506789821, 902.5938327752509, 1154.1113342726107, 29851.70148298519, 30431.087067387987, 40619.0, 40981.385279220834, 41030.794846025805, 41327.17345653085, 41449.17101657973, 41651.16697666045, 41699.0, 41768.164636707246, 7341.1557670868, 7665.233476652332, 7807.960960195191, 7920.841583168333, 7948.364130869545, 7774.650140743652, 7908.7231946881875, 7917.56453395063, 7919.0, 7924.841503169933, 7874.488158269723, 7889.566073865935, 7909.367250619936, 7921.168277330884, 7941.483803552751, 7849.843003139962, 7857.41069419793, 7908.841823163533, 7910.090339610903, 7915.010623672069, 7680.731174408925, 7797.844043119106, 7801.336886364666, 7803.297703206738, 7809.960950195213, 35880.64119358825, 35966.64033359685, 36052.278954421025, 36059.115270778275, 36091.4586281206, 3491.8777842775635, 3504.0539054455153, 3505.3339865425487, 3519.7360197985254, 3525.806080665588, 31056.068317950496, 31093.222669433446, 31115.84442077775, 31135.844320778477, 31164.53253201181, 5056.494350564914, 5079.923801142986, 5083.720395378253, 5096.566791822699, 5103.821366252163, 170394.0, 171488.0, 171610.0, 172308.27691723048, 172669.0, 45967.77016114898, 46809.765951170026, 46829.76585117052, 46888.531114689096, 46940.29589556159, 48361.27458088131, 48380.75809621, 48614.27078593824, 48699.5130048695, 48717.75641121772, 15805.68388632221, 15963.0, 15997.440089596808, 16019.919900400424, 16052.197390130446, 11235.606753763579, 11369.602063927723, 13072.869271307356, 13094.869051309364, 13239.602811915658, 13393.397297121654, 13428.597142085559, 13445.126066805626, 13499.325033748273, 13543.0, 5294.0, 5386.407495175527, 5410.810621628303, 5498.835034948958, 5532.419095994943, 983.179045497018, 997.3018886779139, 1006.4615430744662, 1021.2085633633993, 1026.9229807764448, 22674.65988010181, 23028.88485557595, 23040.235171779495, 23567.0573177071, 23571.17500887461, 1058.6771034834449, 1084.38732116354, 1084.7830433913248, 1102.2669924500117, 1120.2662256222188, 11963.0, 11975.52097916091, 12148.088893333035, 12204.450799714034, 12259.816102758288, 223771.0, 230106.0, 233367.0, 233614.0, 233735.0, 51583.742081290104, 51667.48332516702, 51730.0, 51967.480325196266, 51997.2200416994, 1386.9930650346685, 1446.5660301909386, 1450.0574626492864, 1457.2859298943654, 1477.4385733421445, 21671.133154673636, 21790.89104554467, 22115.0, 22194.66707999349, 22579.887100564392, 35773.64226357703, 35966.82016590005, 35977.82011089928, 35992.0, 36023.27953440969, 200969.0, 202185.0, 202207.0, 202927.0, 204563.0, 32307.0, 32720.836395817867, 34978.47532287068, 36036.45945310875, 36604.450933235494, 44792.10415791731, 45477.77261113673, 45523.0, 45992.0, 45994.31008534808, 32543.18642033968, 32687.34625307433, 33100.66899331072, 33430.0, 33568.49647255244, 32244.677553225105, 32604.347913041132, 32753.34493310073, 32789.67210327962, 33248.335033299685, 35286.29427411386, 37047.444288336235, 37179.814100929325, 37396.252074958895, 37460.43809342808, 31234.843825780725, 31945.68054319427, 32068.679313207504, 32357.0, 32842.50736239006, 23627.881860590587, 24484.14305499299, 24569.754302457462, 24708.258752237463, 25559.361015974006], "value": 17003.786038101993, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.457453982367, 558.4471373340392, 559.1808001278127, 565.4741090785568, 572.7107810555668, 21475.67786483204, 22305.107795688153, 22327.0, 22339.664905026417, 22429.878506074714, 26038.0, 26843.597346039824, 26888.327791805215, 26894.327641808966, 27006.0, 7185.676644550982, 7356.889646655304, 7359.227281135484, 7363.374113200384, 7365.705371785149, 6525.738970441176, 7142.964285178593, 7175.318344757266, 7177.102862142232, 7178.8205294867785, 7153.642317884111, 7156.212816590171, 7157.463190260752, 7158.8568228635395, 7159.89260161098, 7154.213036565973, 7159.105111861018, 7167.3549380556, 7168.641567921583, 7174.174969878452, 7029.086218791575, 7029.89455158173, 7041.225465198824, 7058.823529411755, 7059.047028651118, 29973.0, 31012.844935775403, 31074.06777796647, 31113.22216944572, 31125.688743112507, 1974.891380974046, 1977.772556156046, 1979.3270288102115, 1982.0288058851204, 1983.9107240174226, 20105.095270712853, 20953.37139885806, 21131.47171320729, 21132.788672113234, 21264.36206913795, 2495.1890635543523, 2516.3331717094984, 2519.1308998395525, 2565.217608629367, 2590.940202538986, 133850.0, 136794.0, 137442.0, 137553.31223343918, 137663.0, 19974.800251997585, 21182.258620948192, 21642.783572164077, 21746.673799893015, 22585.661215081785, 43489.0, 43490.56509434929, 43663.0, 43688.3446748299, 43819.78090109593, 11177.385243811668, 11310.830337544943, 11515.942420287845, 11553.884461155281, 11620.825687614693, 8172.83654326922, 8616.526091065052, 9500.809983800285, 9598.95200523993, 9761.267904907161, 9896.356736812084, 11239.719007024889, 11423.771524569629, 11508.136889733303, 11581.478833452515, 3827.2252616853116, 3834.616538346143, 4291.227579035741, 4341.6526677865695, 4343.457067866517, 947.1239103829068, 977.379364103785, 996.7607774134301, 1006.7684432580547, 1011.1758916483043, 19929.800701993085, 20036.799632003494, 20132.496687582636, 20655.69016464754, 20913.477163071046, 1062.3360399750156, 1074.274864466482, 1080.56777289085, 1084.5228099636136, 1088.0425225801243, 10854.45727713611, 11119.0, 11306.717332066764, 11406.771864562828, 11502.884971150348, 497346.0, 501460.0, 531476.0, 537574.0, 541221.0, 116840.0, 124738.37630811788, 125978.37010814887, 126643.0, 127950.0, 1239.3493415956514, 1240.7580521798163, 1283.9293838838953, 1289.38109707341, 1293.6442478318538, 21513.462163446038, 21584.244551440624, 21607.135714571243, 21615.675764863856, 21673.566528669653, 57427.42572574388, 58881.0, 59263.0, 60491.09263361141, 60817.087743683, 68661.65669171623, 77454.0, 80670.0, 81039.59480202562, 81456.0, 12483.56307529232, 12901.290429026118, 13881.652958676113, 14234.786478203043, 14661.0, 22947.0, 23119.07523699033, 23429.0, 23638.0, 23676.289711308687, 18127.27490900402, 18526.444206673823, 18602.0, 18730.34443794461, 18748.437546873614, 15073.397064117313, 15858.841411585736, 16625.833741662427, 17094.888832225864, 17107.572310692332, 18610.534736631693, 18984.810151898306, 19785.0, 19788.50528736793, 19870.701939471208, 13903.652408689459, 14453.205073721047, 14552.199629020506, 14560.781588276397, 14576.271186440423, 17339.739903901198, 17540.561485962953, 17557.0, 18600.906995464935, 18713.438596842116], "value": 12982.267731387119, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1348.3999620169025, 1358.6399604104913, 1390.9234992075437, 1411.9999999999993, 1425.8003879456885, 13438.126521776077, 14058.578242652724, 14062.57812265631, 14071.507497237599, 14082.57752267431, 14393.352299146538, 14711.11733296003, 15386.307616157272, 15443.768343474856, 15449.45926892559, 2687.90592329269, 2770.515159847029, 2771.140946306641, 2774.278687541244, 2775.098093119744, 2672.27848480911, 2684.939448917676, 2742.9177124686294, 2745.052956729925, 2780.708025657307, 2705.418335057962, 2718.497078040569, 2750.0787236275874, 2757.1039412191017, 2757.986210068957, 2657.50836095323, 2661.2149415922254, 2682.798790090741, 2686.892524299026, 2689.7713694336003, 2563.0772921748157, 2582.7029891562524, 2585.922422327333, 2588.1071030494636, 2594.9091781787733, 14283.857161428461, 14681.77977330341, 14684.632884177874, 14686.118832870063, 14707.705845883022, 7807.297343239135, 8166.101728809886, 8177.100518942912, 8179.0, 8180.181981801832, 5298.072837253465, 5488.69812160331, 5497.092979658351, 5521.613487055908, 5621.409751976065, 1796.7574377459007, 1813.8276863697927, 1846.0861873372633, 1870.2892900697648, 1894.5642502224428, 24727.25818225456, 24931.62602560945, 24995.250142495755, 25050.373740656447, 25070.874645626838, 6435.806925792233, 6550.7707230246715, 6674.466042716571, 6727.697253623599, 6730.360615741594, 15494.5351639451, 15653.217339132998, 15700.68598628044, 15701.214939252992, 15705.607359816097, 7727.961360193275, 7732.922670773332, 7739.0, 7744.535327880345, 7746.147923728386, 7042.225355210922, 7139.428845692332, 7161.641917904084, 7172.13934327884, 7188.065551478275, 4836.443808962014, 4922.212446008622, 5110.897782044338, 5111.667741596784, 5149.176131818891, 1300.902432317561, 1430.3849344781843, 1458.2781523146111, 1484.4284950294214, 1487.5388629524666, 903.304455569208, 921.1433366968784, 921.161742814036, 930.7766136127416, 940.3182692547848, 7484.738034168887, 7517.285857843496, 7552.358049565849, 7567.356774674105, 7625.618719064025, 981.5975450065439, 988.683621241196, 989.6981420666622, 992.7369247149511, 993.5181437003048, 4519.960409105924, 4614.630829533697, 4631.258998560282, 4665.930011049769, 4709.987352719182, 19945.401637950883, 20012.49968750793, 20246.0, 20371.592568148553, 20703.689444658343, 19686.507837303896, 19937.202511899362, 20120.89939550293, 20126.496837578885, 20137.49656258576, 10908.181886358554, 10958.506867190996, 11023.559057637605, 11087.22389432732, 11122.999999999838, 7935.0874649416055, 7937.444378893422, 7979.361651068017, 8018.158093400109, 8102.837943240984, 17452.389166379115, 17472.30110795554, 17526.73709894378, 17695.823041769418, 18015.909920450315, 17865.464036078938, 17954.371596994042, 17991.82008179954, 18095.0, 18231.088445577934, 10379.221558383, 10570.577176912837, 10573.52419141156, 10577.0, 10595.417252051211, 13384.866151338361, 13441.596752097452, 13493.190408575518, 13536.661583460493, 13541.187528748307, 10792.0, 10807.027367536959, 10848.620298289521, 11123.83314250303, 11194.776104478027, 10566.101881340172, 10634.41510716918, 10786.137109031415, 10841.132709383073, 11025.61410350666, 9062.04848490899, 9229.630814767334, 9231.215346695606, 9232.0, 9236.26109911219, 6767.221769496374, 6779.661016949034, 6852.931470685229, 6854.897176542256, 6887.311268873073, 6521.771737989147, 6594.901076483952, 6651.368120028589, 6670.332966703291, 6670.533062685566], "value": 6436.556452225358, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.6933024204022, 478.25153634561906, 489.7404375680889, 495.640660521122, 498.5039885314112, 6182.350853160421, 6222.657753823538, 6291.748330066797, 6323.146375239341, 6430.710618022188, 6091.604045737024, 6101.69491525423, 6167.286393986643, 6174.382561743823, 6271.905921411182, 4901.117798796224, 5016.372953380827, 5021.774020169083, 5022.799088036474, 5046.293518907356, 4791.832285869995, 5005.599552035828, 5018.34761481007, 5022.221555658879, 5042.47054059324, 4809.523095605766, 4851.611871050308, 4880.926786098212, 4936.950630493703, 4939.357883475143, 4914.287428322873, 4934.383202099738, 4947.975260123676, 4950.712814668176, 4966.2798894160505, 4336.978315108404, 4370.628496577795, 4383.101464199832, 4391.934120988188, 4392.407025051609, 6141.232345956755, 6163.907541386883, 6183.598066125687, 6203.379662033761, 6251.906221406683, 5530.225768392428, 5569.415211402826, 5606.915896261559, 5631.690257035862, 5703.372629010805, 6552.770653027121, 6600.834979125512, 6611.371919667624, 6631.867362652768, 6651.534392592521, 4710.24636058229, 4737.668363214576, 4749.952500474986, 4750.263709125091, 4750.762461876892, 6806.183258009021, 6937.7571784987285, 6943.79168624942, 6993.0, 7005.54463959841, 4892.315075889378, 4896.069746748178, 4924.236743304794, 4948.925766113512, 4953.23224900141, 4967.3542439482635, 4994.200927851526, 5016.573591244785, 5024.2463630454995, 5026.723530205873, 5706.4864162225595, 5723.399043100415, 5735.139729040594, 5758.942410575841, 5764.510016648548, 6659.567128136656, 6695.1965764107645, 6700.262971073178, 6746.527743057939, 6758.702041305881, 1666.8916520426133, 1708.7867613993956, 1725.8360455756683, 1746.97379539307, 1752.1502071495315, 1099.3623698254994, 1171.2738102376582, 1186.5135294529202, 1188.8097904335439, 1208.4078801387257, 5883.0, 5927.792527261611, 5947.881042379128, 5955.434233747787, 5981.401859813984, 6754.662266886636, 6763.0, 6809.0, 6819.624920629413, 6842.213145488333, 5802.765935114927, 5922.838164070084, 5941.049432090845, 5959.920880701747, 5980.521558275327, 3631.673149416565, 3631.9648900063357, 3644.671979521856, 3647.744657873923, 3655.8720444784835, 6569.901451478136, 6644.966775166094, 6646.53474256807, 6657.201135863727, 6682.431993280528, 6950.906846453279, 6976.686049127801, 7032.382552012963, 7037.92962070383, 7081.221065682771, 6575.646420325573, 6605.86788264232, 6610.272869984202, 6688.364605362385, 6731.889238275728, 6772.255051944184, 6783.186017677713, 6801.455883529405, 6885.552439091443, 6903.275156108536, 6781.321867813179, 6818.829529261808, 6840.179178498612, 6847.8016347138055, 6868.9313106870295, 6577.309382514958, 6611.140551728245, 6621.470282377302, 6648.1025061616065, 6684.933150668431, 4954.603631709382, 4965.255211718271, 4968.751562421937, 5004.174311238605, 5013.674111182761, 6919.896201557081, 6927.411170050401, 6963.7562685305775, 6997.650117494003, 7004.789856304319, 6632.436242919406, 6642.0, 6647.069410282467, 6692.765753198614, 6732.3940845322195, 6584.736610535717, 6607.57050791697, 6624.6025238485845, 6674.0, 6697.497687673542, 6189.566730328834, 6505.804825855232, 6576.901346479903, 6585.901211481736, 6597.0, 6422.871542569029, 6541.934580654323, 6572.572782769104, 6619.536632435684, 6682.565633233824, 6296.559240853279, 6365.490760739222, 6394.008928616072, 6411.781761465307, 6443.613383197023], "value": 5074.985043143132, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [894.4990805149115, 895.5477483870644, 895.9731208063756, 913.118840319092, 916.7983043730377, 18986.43040708779, 19952.800471995277, 19967.400977970665, 20006.59986800263, 20167.294144704938, 20473.0, 21183.470413239676, 21186.046627901742, 21290.254841080565, 21312.680309795327, 961.447167878471, 972.8151651186251, 974.5030034682292, 974.5663179884954, 984.6504490905733, 977.8924318325014, 978.9118979291862, 990.3315262198023, 990.3810118675827, 992.0724122945034, 1008.5894711639188, 1010.3331801011334, 1020.0462567499375, 1021.0555236406332, 1033.0289527843802, 1015.8069966706339, 1019.6788011776267, 1022.6471867205839, 1024.1960061351876, 1025.5897640943645, 747.4580928826612, 747.5290566942846, 747.5701471653821, 748.7304570354668, 753.2467532467532, 19552.902235488873, 20075.89962050195, 20184.293549725837, 20391.796082039287, 20415.0, 21310.573788524296, 21393.144274229013, 21734.347969560782, 21915.0, 21923.561528769496, 16769.24538395775, 16965.0, 17087.0, 17137.314507419687, 17149.142542872807, 3730.4404339349044, 3736.1967177057063, 3738.6448287412654, 3751.7936513491745, 3755.4742336072973, 121277.0, 121320.03940416117, 123787.381063095, 126074.0, 126441.0, 16289.918550407172, 16502.17489125563, 16930.915345423193, 16941.661166776594, 17121.743173852403, 29889.551656725165, 30608.08175754731, 30634.84682576573, 30690.84654576713, 30999.69000310013, 8253.33973282136, 8291.922050133444, 8324.542150181676, 8340.582970851434, 8389.286910612544, 4346.695731298842, 4424.11517696462, 4476.574725401082, 4500.122476117126, 4504.572065653823, 5229.816956406507, 5296.814611488579, 5349.946500534945, 5351.1973204019705, 5373.059714549938, 940.9764755881158, 959.3428501476511, 974.0405700385115, 975.8292298847673, 979.1041197304529, 633.7940169444948, 660.5673283998989, 671.9764808231688, 678.9694463749144, 681.2846511163259, 11336.093112551142, 11797.056235501139, 11981.221220620631, 12000.039996800235, 12052.819207711891, 691.1188235000415, 701.4283359062348, 708.9397401220954, 715.0132816712903, 716.2944499667823, 5272.604554658416, 5380.650257733235, 5419.430959749248, 5513.917291240635, 5571.8885622288135, 111558.0, 113431.0, 113558.0, 113667.43166284116, 114867.0, 92313.53843230741, 97217.51391242999, 99307.50346248223, 102122.48938755259, 103793.0, 82308.17691823005, 85590.0, 87686.56156719176, 88301.0, 89908.0, 12599.93700031494, 13038.804417933548, 13370.197788132744, 13395.464181432633, 13397.933010334886, 38692.0, 39953.60046399499, 40186.598134018284, 40257.59742402656, 41178.0, 69719.30280697127, 69994.30005699878, 70998.64500677463, 71597.6420117896, 71653.28346716466, 18052.368167114088, 18216.81783182204, 18475.16861741198, 18742.0, 19115.522111947314, 20357.185712571925, 20799.376018719464, 21028.158873645494, 21299.574008520052, 22004.559908801413, 18117.909410452863, 18325.0, 18449.81550184481, 19291.903540482206, 19730.704039439133, 19217.231310747415, 19309.0, 19375.4187374379, 19487.025648717223, 19639.31262405866, 19350.709739354203, 19521.219151233792, 19689.606207875477, 20145.6978145325, 20268.695969560173, 13533.0, 13551.322433878464, 13553.457861685816, 13746.450141994208, 14527.709445811235, 13971.441142354599, 14360.856391435951, 14500.274986250435, 15197.31612077437, 15568.532944011698], "value": 9247.707696695716, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1487.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 108.0, 100.0, 107.0, 106.0, 110.0, 109.0, 97.0, 107.0, 106.0], "value": 104.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 117.0, 145.0, 171.0, 117.0, 159.0, 153.0, 122.0, 182.0, 118.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 162.0, 161.0, 161.0, 171.0, 167.0, 169.0, 165.0], "value": 164.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 172.0, 75.0, 74.0, 75.0, 90.0, 94.0, 75.0], "value": 87.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 124.0, 79.0, 79.0, 80.0, 130.0, 80.0, 80.0, 131.0], "value": 94.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 115.0, 115.0, 114.0, 114.0, 114.0, 115.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 150.0, 150.0, 148.0, 149.0, 148.0, 150.0, 149.0, 149.0], "value": 149.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 98.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 56.0, 63.0, 52.0, 53.0, 52.0, 52.0, 52.0, 51.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 37.0, 40.0, 39.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 74.0, 76.0, 75.0, 74.0, 74.0, 74.0, 74.0, 74.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 122.0, 128.0, 125.0, 121.0, 126.0, 128.0, 120.0, 131.0, 165.0], "value": 129.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 177.0, 173.0, 175.0, 169.0, 170.0, 172.0, 172.0, 173.0, 179.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 67.0, 69.0, 65.0, 65.0, 63.0, 66.0, 64.0, 64.0], "value": 65.9, "unit": "score"}]}]} 12:50:57 INFO - Return code: 0 12:50:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:50:57 INFO - # TBPL SUCCESS # 12:50:57 INFO - Running post-action listener: _package_coverage_data 12:50:57 INFO - Running post-action listener: _resource_record_post_action 12:50:57 INFO - [mozharness: 2017-08-29 19:50:57.901410Z] Finished run-tests step (success) 12:50:57 INFO - Running post-run listener: _resource_record_post_run 12:50:57 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 6041600; Write bytes: 403337216; Read time: 3020; Write time: 257068 12:50:57 INFO - TinderboxPrint: CPU usage
13.1% 12:50:57 INFO - TinderboxPrint: I/O read bytes / time
6,041,600 / 3,020 12:50:57 INFO - TinderboxPrint: I/O write bytes / time
403,337,216 / 257,068 12:50:57 INFO - TinderboxPrint: CPU idle
8,243.1 (86.9%) 12:50:57 INFO - TinderboxPrint: CPU user
1,193.8 (12.6%) 12:50:57 INFO - TinderboxPrint: Swap in / out
0 / 0 12:50:57 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 118198272; Read time: 0; Write time: 80440 12:50:57 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:50:58 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4296704; Write bytes: 284762112; Read time: 2976; Write time: 176600 12:50:58 INFO - Running post-run listener: _upload_blobber_files 12:50:58 INFO - Blob upload gear active. 12:50:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:50:58 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 12:50:58 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'] 12:50:58 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 12:50:58 INFO - (blobuploader) - INFO - Open directory for files ... 12:50:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:50:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:50:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:50:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:50:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:50:59 INFO - (blobuploader) - INFO - Done attempting. 12:50:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:50:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:50:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:51:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:51:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:51:07 INFO - (blobuploader) - INFO - Done attempting. 12:51:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:51:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:51:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:51:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:51:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:51:08 INFO - (blobuploader) - INFO - Done attempting. 12:51:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:51:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:51:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:51:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:51:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:51:09 INFO - (blobuploader) - INFO - Done attempting. 12:51:09 INFO - (blobuploader) - INFO - Iteration through files over. 12:51:09 INFO - Return code: 0 12:51:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:51:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:51:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/161a66e14874f35eb0e2ad36b3ed1243c80675761170f7073e3bc86012585436a80ba61f9eab6387556744abc6b342158413bb1d32a014ff856e2d00fe1513cd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a978f8f69bc0ca879e31c409b38dfa3fe3200fe605320b628b0dc59f0a1e6f7c377964108f44ee4f05830de4f9b6e68985770cd09073f8316b90540d317dbe0b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86628e846ecbc738d1885c7e31b60b8024d0038b13c230d53b7dbe2c94d69eef5c9041c1556e068c8aac860a1ff4e668a212d46fb6d8c7df2b1994ffb121a939", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6d9f839ac3c2d02af27210a4549bdb267b6c720f058bfbc5f64af24d61d9356fdcc931e93f10400447416ba96e53f784935ab16a1b1dfc1459d709ba5767c22"} 12:51:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:51:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:51:09 INFO - Contents: 12:51:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/161a66e14874f35eb0e2ad36b3ed1243c80675761170f7073e3bc86012585436a80ba61f9eab6387556744abc6b342158413bb1d32a014ff856e2d00fe1513cd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a978f8f69bc0ca879e31c409b38dfa3fe3200fe605320b628b0dc59f0a1e6f7c377964108f44ee4f05830de4f9b6e68985770cd09073f8316b90540d317dbe0b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86628e846ecbc738d1885c7e31b60b8024d0038b13c230d53b7dbe2c94d69eef5c9041c1556e068c8aac860a1ff4e668a212d46fb6d8c7df2b1994ffb121a939", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6d9f839ac3c2d02af27210a4549bdb267b6c720f058bfbc5f64af24d61d9356fdcc931e93f10400447416ba96e53f784935ab16a1b1dfc1459d709ba5767c22"} 12:51:09 INFO - Running post-run listener: copy_logs_to_upload_dir 12:51:09 INFO - Copying logs to upload dir... 12:51:09 INFO - mkdir: /builds/slave/test/build/upload/logs 12:51:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.873889 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-29 12:51:09.352259) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-29 12:51:09.356222) ========= 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=3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/161a66e14874f35eb0e2ad36b3ed1243c80675761170f7073e3bc86012585436a80ba61f9eab6387556744abc6b342158413bb1d32a014ff856e2d00fe1513cd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a978f8f69bc0ca879e31c409b38dfa3fe3200fe605320b628b0dc59f0a1e6f7c377964108f44ee4f05830de4f9b6e68985770cd09073f8316b90540d317dbe0b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86628e846ecbc738d1885c7e31b60b8024d0038b13c230d53b7dbe2c94d69eef5c9041c1556e068c8aac860a1ff4e668a212d46fb6d8c7df2b1994ffb121a939", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6d9f839ac3c2d02af27210a4549bdb267b6c720f058bfbc5f64af24d61d9356fdcc931e93f10400447416ba96e53f784935ab16a1b1dfc1459d709ba5767c22"} build_url:https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011192 build_url: 'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/161a66e14874f35eb0e2ad36b3ed1243c80675761170f7073e3bc86012585436a80ba61f9eab6387556744abc6b342158413bb1d32a014ff856e2d00fe1513cd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a978f8f69bc0ca879e31c409b38dfa3fe3200fe605320b628b0dc59f0a1e6f7c377964108f44ee4f05830de4f9b6e68985770cd09073f8316b90540d317dbe0b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86628e846ecbc738d1885c7e31b60b8024d0038b13c230d53b7dbe2c94d69eef5c9041c1556e068c8aac860a1ff4e668a212d46fb6d8c7df2b1994ffb121a939", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6d9f839ac3c2d02af27210a4549bdb267b6c720f058bfbc5f64af24d61d9356fdcc931e93f10400447416ba96e53f784935ab16a1b1dfc1459d709ba5767c22"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-29 12:51:09.382726) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:51:09.383074) ========= 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=3a3620fb28aea1604ab609ef000001d2-1504035011.130933-985169836 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004568 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:51:09.436335) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-29 12:51:09.436668) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-29 12:51:09.436999) ========= ========= Total master_lag: 0.17 =========