builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-059 starttime: 1505179522.79 results: success (0) revision: bda524beac249b64aa36016800502a34073bf35a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-11 18:25:22.791681) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-11 18:25:22.792193) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-11 18:25:22.792576) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-11 18:25:22.823289) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 18:25:22.823680) ========= 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-1505179302.488033-1074970685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-11 18:25:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2017-09-11 18:25:22 (223 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.106614 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 18:25:22.947745) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 18:25:22.948079) ========= 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-1505179302.488033-1074970685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018363 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 18:25:22.999219) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-11 18:25:22.999752) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bda524beac249b64aa36016800502a34073bf35a --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 bda524beac249b64aa36016800502a34073bf35a --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-1505179302.488033-1074970685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-11 18:25:23,049 truncating revision to first 12 chars 2017-09-11 18:25:23,049 Setting DEBUG logging. 2017-09-11 18:25:23,049 attempt 1/10 2017-09-11 18:25:23,049 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bda524beac24?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-11 18:25:24,166 unpacking tar archive at: mozilla-central-bda524beac24/testing/mozharness/ program finished with exit code 0 elapsedTime=1.375918 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-11 18:25:24.392116) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-11 18:25:24.392642) ========= script_repo_revision: bda524beac249b64aa36016800502a34073bf35a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-11 18:25:24.393287) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-11 18:25:24.393694) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-11 18:25:24.407227) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-11 18:25:24.407648) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1505179302.488033-1074970685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:25:24 INFO - MultiFileLogger online at 20170911 18:25:24 in /builds/slave/test 18:25:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:25:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:25:24 INFO - {'append_to_log': False, 18:25:24 INFO - 'base_work_dir': '/builds/slave/test', 18:25:24 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:25:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:25:24 INFO - 'branch': 'Firefox-Non-PGO', 18:25:24 INFO - 'buildbot_json_path': 'buildprops.json', 18:25:24 INFO - 'code_coverage': False, 18:25:24 INFO - 'config_files': ('talos/linux_config.py',), 18:25:24 INFO - 'default_actions': ('clobber', 18:25:24 INFO - 'read-buildbot-config', 18:25:24 INFO - 'download-and-extract', 18:25:24 INFO - 'populate-webroot', 18:25:24 INFO - 'create-virtualenv', 18:25:24 INFO - 'install', 18:25:24 INFO - 'setup-mitmproxy', 18:25:24 INFO - 'run-tests'), 18:25:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:25:24 INFO - 'disable_ccov_upload': False, 18:25:24 INFO - 'disable_stylo': False, 18:25:24 INFO - 'download_minidump_stackwalk': True, 18:25:24 INFO - 'download_symbols': 'ondemand', 18:25:24 INFO - 'enable_stylo': False, 18:25:24 INFO - 'enable_webrender': False, 18:25:24 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:25:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:25:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:25:24 INFO - 'gecko_profile': False, 18:25:24 INFO - 'gecko_profile_interval': 0, 18:25:24 INFO - 'installer_path': 'installer.exe', 18:25:24 INFO - 'log_level': 'info', 18:25:24 INFO - 'log_name': 'talos', 18:25:24 INFO - 'log_to_console': True, 18:25:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:25:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:25:24 INFO - 'opt_config_files': (), 18:25:24 INFO - 'pip_index': False, 18:25:24 INFO - 'suite': 'dromaeojs-e10s', 18:25:24 INFO - 'system_bits': '32', 18:25:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:25:24 INFO - 'title': 'talos-linux64-ix-059', 18:25:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:25:24 INFO - 'use_talos_json': True, 18:25:24 INFO - 'verify': 'False', 18:25:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:25:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:25:24 INFO - 'work_dir': 'build'} 18:25:24 INFO - [mozharness: 2017-09-12 01:25:24.609927Z] Running clobber step. 18:25:24 INFO - Running pre-action listener: _resource_record_pre_action 18:25:24 INFO - Running main action method: clobber 18:25:24 INFO - rmtree: /builds/slave/test/build 18:25:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:25:25 INFO - Running post-action listener: _resource_record_post_action 18:25:25 INFO - [mozharness: 2017-09-12 01:25:25.648360Z] Finished clobber step (success) 18:25:25 INFO - [mozharness: 2017-09-12 01:25:25.648457Z] Running read-buildbot-config step. 18:25:25 INFO - Running pre-action listener: _resource_record_pre_action 18:25:25 INFO - Running main action method: read_buildbot_config 18:25:25 INFO - Using buildbot properties: 18:25:25 INFO - { 18:25:25 INFO - "project": "", 18:25:25 INFO - "product": "firefox", 18:25:25 INFO - "who": "kwierso@gmail.com", 18:25:25 INFO - "installer_path": "public/build/target.tar.bz2", 18:25:25 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:25:25 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:25:25 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 18:25:25 INFO - "stage_platform": "linux64", 18:25:25 INFO - "basedir": "/builds/slave/test", 18:25:25 INFO - "buildnumber": 1140, 18:25:25 INFO - "platform": "ubuntu64_hw", 18:25:25 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:25:25 INFO - "slavebuilddir": "test", 18:25:25 INFO - "taskId": "e9dVyWwTQDy1LaLj0AQcoA", 18:25:25 INFO - "branch": "mozilla-central", 18:25:25 INFO - "script_repo_revision": "production", 18:25:25 INFO - "revision": "bda524beac249b64aa36016800502a34073bf35a", 18:25:25 INFO - "slavename": "talos-linux64-ix-059", 18:25:25 INFO - "repo_path": "mozilla-central" 18:25:25 INFO - } 18:25:25 INFO - Finding installer, test and symbols from parent task. 18:25:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e9dVyWwTQDy1LaLj0AQcoA'}, attempt #1 18:25:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e9dVyWwTQDy1LaLj0AQcoA'}, attempt #1 18:25:26 INFO - Task dependencies: St3prP9UQSqX25PaZr-mYg 18:25:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg'}, attempt #1 18:25:27 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.tar.bz2 18:25:27 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.test_packages.json 18:25:27 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.crashreporter-symbols.zip 18:25:27 INFO - Running post-action listener: _resource_record_post_action 18:25:27 INFO - [mozharness: 2017-09-12 01:25:27.042079Z] Finished read-buildbot-config step (success) 18:25:27 INFO - [mozharness: 2017-09-12 01:25:27.042296Z] Running download-and-extract step. 18:25:27 INFO - Running pre-action listener: _resource_record_pre_action 18:25:27 INFO - Running main action method: download_and_extract 18:25:27 INFO - mkdir: /builds/slave/test/build/tests 18:25:27 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')]} 18:25:27 INFO - trying https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.test_packages.json 18:25:27 INFO - Downloading https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:25:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:25:27 INFO - Downloaded 989 bytes. 18:25:27 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:25:27 INFO - Using the following test package requirements: 18:25:27 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:25:27 INFO - u'common': [u'target.common.tests.zip'], 18:25:27 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:25:27 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:25:27 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:25:27 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:25:27 INFO - u'mozbase': [u'target.common.tests.zip'], 18:25:27 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:25:27 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:25:27 INFO - u'web-platform': [u'target.common.tests.zip', 18:25:27 INFO - u'target.web-platform.tests.tar.gz'], 18:25:27 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:25:27 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 18:25:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.common.tests.zip 18:25:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:25:27 INFO - Fetch https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.common.tests.zip into memory 18:25:28 INFO - Content-Length response header: 55028571 18:25:28 INFO - Bytes received: 55028571 18:25:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.talos.tests.zip 18:25:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:25:39 INFO - Fetch https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.talos.tests.zip into memory 18:25:40 INFO - Content-Length response header: 14497963 18:25:40 INFO - Bytes received: 14497963 18:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:40 INFO - trying https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.tar.bz2 18:25:40 INFO - Downloading https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:25:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:25:41 INFO - Downloaded 63004597 bytes. 18:25:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.tar.bz2 18:25:41 INFO - mkdir: /builds/slave/test/properties 18:25:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:25:41 INFO - Writing to file /builds/slave/test/properties/build_url 18:25:41 INFO - Contents: 18:25:41 INFO - build_url:https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.tar.bz2 18:25:41 INFO - Running post-action listener: _resource_record_post_action 18:25:41 INFO - Running post-action listener: find_tests_for_verification 18:25:41 INFO - Running post-action listener: set_extra_try_arguments 18:25:41 INFO - [mozharness: 2017-09-12 01:25:41.682579Z] Finished download-and-extract step (success) 18:25:41 INFO - [mozharness: 2017-09-12 01:25:41.682812Z] Running populate-webroot step. 18:25:41 INFO - Running pre-action listener: _resource_record_pre_action 18:25:41 INFO - Running main action method: populate_webroot 18:25:41 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:25:41 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:25:41 INFO - 'tests': ['tresize', 18:25:41 INFO - 'tcanvasmark']}, 18:25:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:25:41 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:25:41 INFO - 'tests': ['dromaeo_css', 18:25:41 INFO - 'kraken']}, 18:25:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:25:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:25:41 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:25:41 INFO - 'talos_options': ['--disable-stylo'], 18:25:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:25:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:25:41 INFO - 'tests': ['damp', 'tps']}, 18:25:41 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:25:41 INFO - 'talos_options': ['--disable-stylo'], 18:25:41 INFO - 'tests': ['damp', 'tps']}, 18:25:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:25:41 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:25:41 INFO - 'tests': ['dromaeo_dom']}, 18:25:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:25:41 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:25:41 INFO - 'tests': ['basic_compositor_video', 18:25:41 INFO - 'glvideo']}, 18:25:41 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:25:41 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:25:41 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:25:41 INFO - 'talos_options': ['--disable-stylo'], 18:25:41 INFO - 'tests': ['ts_paint_webext', 18:25:41 INFO - 'tp5o_webext']}, 18:25:41 INFO - 'other-e10s': {'tests': ['a11yr', 18:25:41 INFO - 'ts_paint', 18:25:41 INFO - 'tpaint', 18:25:41 INFO - 'sessionrestore', 18:25:41 INFO - 'sessionrestore_many_windows', 18:25:41 INFO - 'sessionrestore_no_auto_restore', 18:25:41 INFO - 'tabpaint']}, 18:25:41 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:25:41 INFO - 'tests': ['a11yr', 18:25:41 INFO - 'ts_paint', 18:25:41 INFO - 'tpaint', 18:25:41 INFO - 'sessionrestore', 18:25:41 INFO - 'sessionrestore_many_windows', 18:25:41 INFO - 'sessionrestore_no_auto_restore', 18:25:41 INFO - 'tabpaint']}, 18:25:41 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:25:41 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:25:41 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:25:41 INFO - 'tests': ['bloom_basic_singleton']}, 18:25:41 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:25:41 INFO - 'tests': ['bloom_basic']}, 18:25:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:25:41 INFO - 'tsvgr_opacity', 18:25:41 INFO - 'tart', 18:25:41 INFO - 'tscrollx', 18:25:41 INFO - 'tsvg_static']}, 18:25:41 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:25:41 INFO - 'tests': ['tsvgx', 18:25:41 INFO - 'tsvgr_opacity', 18:25:41 INFO - 'tart', 18:25:41 INFO - 'tscrollx', 18:25:41 INFO - 'tsvg_static']}, 18:25:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:25:41 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:25:41 INFO - 'talos_options': ['--disable-stylo'], 18:25:41 INFO - 'tests': ['tp5o']}, 18:25:41 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:25:41 INFO - 'talos_options': ['--mitmproxy', 18:25:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:25:41 INFO - '--firstNonBlankPaint'], 18:25:41 INFO - 'tests': ['tp6_google', 18:25:41 INFO - 'tp6_youtube', 18:25:41 INFO - 'tp6_amazon', 18:25:41 INFO - 'tp6_facebook']}, 18:25:41 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:25:41 INFO - 'talos_options': ['--disable-stylo', 18:25:41 INFO - '--mitmproxy', 18:25:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:25:41 INFO - '--firstNonBlankPaint'], 18:25:41 INFO - 'tests': ['tp6_google', 18:25:41 INFO - 'tp6_youtube', 18:25:41 INFO - 'tp6_amazon', 18:25:41 INFO - 'tp6_facebook']}, 18:25:41 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:25:41 INFO - 'talos_options': ['--stylo-threads=1', 18:25:41 INFO - '--mitmproxy', 18:25:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:25:41 INFO - '--firstNonBlankPaint'], 18:25:41 INFO - 'tests': ['tp6_google', 18:25:41 INFO - 'tp6_youtube', 18:25:41 INFO - 'tp6_amazon', 18:25:41 INFO - 'tp6_facebook']}, 18:25:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:25:41 INFO - 'talos_options': ['--xperf_path', 18:25:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:25:41 INFO - 'tests': ['tp5n']}, 18:25:41 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:25:41 INFO - 'talos_options': ['--disable-stylo', 18:25:41 INFO - '--xperf_path', 18:25:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:25:41 INFO - 'tests': ['tp5n']}}} 18:25:41 INFO - Running post-action listener: _resource_record_post_action 18:25:41 INFO - [mozharness: 2017-09-12 01:25:41.701117Z] Finished populate-webroot step (success) 18:25:41 INFO - [mozharness: 2017-09-12 01:25:41.701224Z] Running create-virtualenv step. 18:25:41 INFO - Running pre-action listener: _resource_record_pre_action 18:25:41 INFO - Running main action method: create_virtualenv 18:25:41 INFO - Creating virtualenv /builds/slave/test/build/venv 18:25:41 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:25:41 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:25:41 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:25:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:25:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:25:42 INFO - Installing distribute.............................................................................................................................................................................................done. 18:25:42 INFO - Installing pip...............done. 18:25:42 INFO - Return code: 0 18:25:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:25:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:25:42 INFO - Reading from file tmpfile_stdout 18:25:42 INFO - Output received: 18:25:42 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:25:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:25:42 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')]} 18:25:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:42 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')]} 18:25:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:42 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 0x165e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19caa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce090>, '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 0x19ced80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8f90>, '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-1505179302.488033-1074970685', '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 18:25:42 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 18:25:42 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 18:25:42 INFO - Using env: {'DISPLAY': ':0', 18:25:42 INFO - 'HOME': '/home/cltbld', 18:25:42 INFO - 'LANG': 'en_US.UTF-8', 18:25:42 INFO - 'LANGUAGE': 'en_US:en', 18:25:42 INFO - 'LOGNAME': 'cltbld', 18:25:42 INFO - 'MAIL': '/var/mail/cltbld', 18:25:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:42 INFO - 'MOZ_NO_REMOTE': '1', 18:25:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:42 INFO - 'NO_EM_RESTART': '1', 18:25:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:42 INFO - 'PWD': '/builds/slave/test', 18:25:42 INFO - 'SHELL': '/bin/bash', 18:25:42 INFO - 'SHLVL': '1', 18:25:42 INFO - 'TERM': 'linux', 18:25:42 INFO - 'TMOUT': '86400', 18:25:42 INFO - 'USER': 'cltbld', 18:25:42 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1505179302.488033-1074970685', 18:25:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:42 INFO - '_': '/tools/buildbot/bin/python'} 18:25:43 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:25:43 INFO - Downloading/unpacking pip>=1.5 18:25:43 INFO - Running setup.py egg_info for package pip 18:25:43 INFO - warning: no files found matching 'pip/cacert.pem' 18:25:43 INFO - warning: no files found matching '*.html' under directory 'docs' 18:25:43 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:25:43 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:25:43 INFO - Installing collected packages: pip 18:25:43 INFO - Found existing installation: pip 1.1 18:25:43 INFO - Uninstalling pip: 18:25:43 INFO - Successfully uninstalled pip 18:25:43 INFO - Running setup.py install for pip 18:25:43 INFO - warning: no files found matching 'pip/cacert.pem' 18:25:43 INFO - warning: no files found matching '*.html' under directory 'docs' 18:25:43 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:25:43 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:25:43 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:25:43 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:25:43 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:25:43 INFO - Successfully installed pip 18:25:43 INFO - Cleaning up... 18:25:43 INFO - Return code: 0 18:25:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:25:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x165e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19caa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce090>, '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 0x19ced80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8f90>, '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-1505179302.488033-1074970685', '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 18:25:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:25:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:25:43 INFO - Using env: {'DISPLAY': ':0', 18:25:43 INFO - 'HOME': '/home/cltbld', 18:25:43 INFO - 'LANG': 'en_US.UTF-8', 18:25:43 INFO - 'LANGUAGE': 'en_US:en', 18:25:43 INFO - 'LOGNAME': 'cltbld', 18:25:43 INFO - 'MAIL': '/var/mail/cltbld', 18:25:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:43 INFO - 'MOZ_NO_REMOTE': '1', 18:25:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:43 INFO - 'NO_EM_RESTART': '1', 18:25:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:43 INFO - 'PWD': '/builds/slave/test', 18:25:43 INFO - 'SHELL': '/bin/bash', 18:25:43 INFO - 'SHLVL': '1', 18:25:43 INFO - 'TERM': 'linux', 18:25:43 INFO - 'TMOUT': '86400', 18:25:43 INFO - 'USER': 'cltbld', 18:25:43 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1505179302.488033-1074970685', 18:25:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:43 INFO - '_': '/tools/buildbot/bin/python'} 18:25:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:44 INFO - Downloading/unpacking psutil>=3.1.1 18:25:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:25:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:25:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:25:45 INFO - Installing collected packages: psutil 18:25:45 INFO - Running setup.py install for psutil 18:25:45 INFO - building 'psutil._psutil_linux' extension 18:25:45 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 18:25:45 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 18:25:45 INFO - building 'psutil._psutil_posix' extension 18:25:45 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 18:25:45 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 18:25:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:25:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:25:45 INFO - Successfully installed psutil 18:25:45 INFO - Cleaning up... 18:25:45 INFO - Return code: 0 18:25:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:25:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19caa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce090>, '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 0x19ced80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8f90>, '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-1505179302.488033-1074970685', '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 18:25:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:25:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:25:45 INFO - Using env: {'DISPLAY': ':0', 18:25:45 INFO - 'HOME': '/home/cltbld', 18:25:45 INFO - 'LANG': 'en_US.UTF-8', 18:25:45 INFO - 'LANGUAGE': 'en_US:en', 18:25:45 INFO - 'LOGNAME': 'cltbld', 18:25:45 INFO - 'MAIL': '/var/mail/cltbld', 18:25:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:45 INFO - 'MOZ_NO_REMOTE': '1', 18:25:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:45 INFO - 'NO_EM_RESTART': '1', 18:25:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:45 INFO - 'PWD': '/builds/slave/test', 18:25:45 INFO - 'SHELL': '/bin/bash', 18:25:45 INFO - 'SHLVL': '1', 18:25:45 INFO - 'TERM': 'linux', 18:25:45 INFO - 'TMOUT': '86400', 18:25:45 INFO - 'USER': 'cltbld', 18:25:45 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1505179302.488033-1074970685', 18:25:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:45 INFO - '_': '/tools/buildbot/bin/python'} 18:25:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:25:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:25:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:25:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:25:47 INFO - Installing collected packages: mozsystemmonitor 18:25:47 INFO - Running setup.py install for mozsystemmonitor 18:25:47 INFO - Successfully installed mozsystemmonitor 18:25:47 INFO - Cleaning up... 18:25:47 INFO - Return code: 0 18:25:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:25:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:47 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 0x165e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19caa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce090>, '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 0x19ced80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8f90>, '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-1505179302.488033-1074970685', '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 18:25:47 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 18:25:47 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 18:25:47 INFO - Using env: {'DISPLAY': ':0', 18:25:47 INFO - 'HOME': '/home/cltbld', 18:25:47 INFO - 'LANG': 'en_US.UTF-8', 18:25:47 INFO - 'LANGUAGE': 'en_US:en', 18:25:47 INFO - 'LOGNAME': 'cltbld', 18:25:47 INFO - 'MAIL': '/var/mail/cltbld', 18:25:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:47 INFO - 'MOZ_NO_REMOTE': '1', 18:25:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:47 INFO - 'NO_EM_RESTART': '1', 18:25:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:47 INFO - 'PWD': '/builds/slave/test', 18:25:47 INFO - 'SHELL': '/bin/bash', 18:25:47 INFO - 'SHLVL': '1', 18:25:47 INFO - 'TERM': 'linux', 18:25:47 INFO - 'TMOUT': '86400', 18:25:47 INFO - 'USER': 'cltbld', 18:25:47 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1505179302.488033-1074970685', 18:25:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:47 INFO - '_': '/tools/buildbot/bin/python'} 18:25:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:47 INFO - Downloading/unpacking jsonschema==2.5.1 18:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:25:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:25:49 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 18:25:49 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 18:25:49 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 18:25:49 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 18:25:49 INFO - Downloading functools32-3.2.3-2.tar.gz 18:25:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:25:49 INFO - warning: no files found matching '*.txt' 18:25:49 INFO - no previously-included directories found matching 'build' 18:25:49 INFO - no previously-included directories found matching 'dist' 18:25:49 INFO - no previously-included directories found matching '.git*' 18:25:49 INFO - Installing collected packages: jsonschema, functools32 18:25:49 INFO - Running setup.py install for functools32 18:25:50 INFO - warning: no files found matching '*.txt' 18:25:50 INFO - no previously-included directories found matching 'build' 18:25:50 INFO - no previously-included directories found matching 'dist' 18:25:50 INFO - no previously-included directories found matching '.git*' 18:25:50 INFO - Successfully installed jsonschema functools32 18:25:50 INFO - Cleaning up... 18:25:50 INFO - Return code: 0 18:25:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:50 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 0x165e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19caa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce090>, '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 0x19ced80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8f90>, '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-1505179302.488033-1074970685', '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 18:25:50 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 18:25:50 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 18:25:50 INFO - Using env: {'DISPLAY': ':0', 18:25:50 INFO - 'HOME': '/home/cltbld', 18:25:50 INFO - 'LANG': 'en_US.UTF-8', 18:25:50 INFO - 'LANGUAGE': 'en_US:en', 18:25:50 INFO - 'LOGNAME': 'cltbld', 18:25:50 INFO - 'MAIL': '/var/mail/cltbld', 18:25:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:50 INFO - 'MOZ_NO_REMOTE': '1', 18:25:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:50 INFO - 'NO_EM_RESTART': '1', 18:25:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:50 INFO - 'PWD': '/builds/slave/test', 18:25:50 INFO - 'SHELL': '/bin/bash', 18:25:50 INFO - 'SHLVL': '1', 18:25:50 INFO - 'TERM': 'linux', 18:25:50 INFO - 'TMOUT': '86400', 18:25:50 INFO - 'USER': 'cltbld', 18:25:50 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1505179302.488033-1074970685', 18:25:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:50 INFO - '_': '/tools/buildbot/bin/python'} 18:25:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:25:50 INFO - Cleaning up... 18:25:50 INFO - Return code: 0 18:25:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:50 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 0x165e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19caa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce090>, '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 0x19ced80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8f90>, '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-1505179302.488033-1074970685', '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 18:25:50 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 18:25:50 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 18:25:50 INFO - Using env: {'DISPLAY': ':0', 18:25:50 INFO - 'HOME': '/home/cltbld', 18:25:50 INFO - 'LANG': 'en_US.UTF-8', 18:25:50 INFO - 'LANGUAGE': 'en_US:en', 18:25:50 INFO - 'LOGNAME': 'cltbld', 18:25:50 INFO - 'MAIL': '/var/mail/cltbld', 18:25:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:50 INFO - 'MOZ_NO_REMOTE': '1', 18:25:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:50 INFO - 'NO_EM_RESTART': '1', 18:25:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:50 INFO - 'PWD': '/builds/slave/test', 18:25:50 INFO - 'SHELL': '/bin/bash', 18:25:50 INFO - 'SHLVL': '1', 18:25:50 INFO - 'TERM': 'linux', 18:25:50 INFO - 'TMOUT': '86400', 18:25:50 INFO - 'USER': 'cltbld', 18:25:50 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1505179302.488033-1074970685', 18:25:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:50 INFO - '_': '/tools/buildbot/bin/python'} 18:25:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:50 INFO - Downloading/unpacking blobuploader==1.2.4 18:25: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 18:25: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 18:25: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 18:25: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 18:25:51 INFO - Downloading blobuploader-1.2.4.tar.gz 18:25:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:25:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:25:51 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 18:25:51 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 18:25:51 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 18:25:51 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 18:25:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:25:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:25: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 18:25: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 18:25: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 18:25: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 18:25:52 INFO - Downloading docopt-0.6.1.tar.gz 18:25:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:25:52 INFO - Installing collected packages: blobuploader, requests, docopt 18:25:52 INFO - Running setup.py install for blobuploader 18:25:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:25:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:25:52 INFO - Running setup.py install for requests 18:25:52 INFO - Running setup.py install for docopt 18:25:52 INFO - Successfully installed blobuploader requests docopt 18:25:52 INFO - Cleaning up... 18:25:52 INFO - Return code: 0 18:25:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:25: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')]} 18:25:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25: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')]} 18:25:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:52 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 0x165e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19caa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce090>, '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 0x19ced80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8f90>, '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-1505179302.488033-1074970685', '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 18:25:52 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 18:25:52 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 18:25:52 INFO - Using env: {'DISPLAY': ':0', 18:25:52 INFO - 'HOME': '/home/cltbld', 18:25:52 INFO - 'LANG': 'en_US.UTF-8', 18:25:52 INFO - 'LANGUAGE': 'en_US:en', 18:25:52 INFO - 'LOGNAME': 'cltbld', 18:25:52 INFO - 'MAIL': '/var/mail/cltbld', 18:25:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:52 INFO - 'MOZ_NO_REMOTE': '1', 18:25:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:52 INFO - 'NO_EM_RESTART': '1', 18:25:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:52 INFO - 'PWD': '/builds/slave/test', 18:25:52 INFO - 'SHELL': '/bin/bash', 18:25:52 INFO - 'SHLVL': '1', 18:25:52 INFO - 'TERM': 'linux', 18:25:52 INFO - 'TMOUT': '86400', 18:25:52 INFO - 'USER': 'cltbld', 18:25:52 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1505179302.488033-1074970685', 18:25:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:52 INFO - '_': '/tools/buildbot/bin/python'} 18:25:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:25:53 INFO - Running setup.py (path:/tmp/pip-yds79q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:25:53 INFO - Running setup.py (path:/tmp/pip-f2qWUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:25:53 INFO - Running setup.py (path:/tmp/pip-1Birhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:25:53 INFO - Running setup.py (path:/tmp/pip-xY7nkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:25:53 INFO - Running setup.py (path:/tmp/pip-L4ceem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:25:53 INFO - Running setup.py (path:/tmp/pip-v8WzU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:25:53 INFO - Running setup.py (path:/tmp/pip-XED99i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:25:53 INFO - Running setup.py (path:/tmp/pip-ksg2lX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:25:53 INFO - Running setup.py (path:/tmp/pip-OOGiDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:25:53 INFO - Running setup.py (path:/tmp/pip-rg2jEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:25:53 INFO - Running setup.py (path:/tmp/pip-MUGa3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:25:53 INFO - Running setup.py (path:/tmp/pip-L5hi5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:25:53 INFO - Running setup.py (path:/tmp/pip-525Ef4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:25:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:25:53 INFO - Running setup.py (path:/tmp/pip-qzuxUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:25:54 INFO - Running setup.py (path:/tmp/pip-Zy_KH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:25:54 INFO - Running setup.py (path:/tmp/pip-H7GCrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:25:54 INFO - Running setup.py (path:/tmp/pip-l5rG6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:25:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:25:54 INFO - Running setup.py install for manifestparser 18:25:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:25:54 INFO - Running setup.py install for mozcrash 18:25:54 INFO - Running setup.py install for mozdebug 18:25:54 INFO - Running setup.py install for mozdevice 18:25:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:25:54 INFO - Running setup.py install for mozfile 18:25:54 INFO - Running setup.py install for mozhttpd 18:25:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:25:54 INFO - Running setup.py install for mozinfo 18:25:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:25:54 INFO - Running setup.py install for mozInstall 18:25:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:25:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:25:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:25:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:25:54 INFO - Running setup.py install for mozleak 18:25:55 INFO - Running setup.py install for mozlog 18:25:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:25:55 INFO - Running setup.py install for moznetwork 18:25:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:25:55 INFO - Running setup.py install for mozprocess 18:25:55 INFO - Running setup.py install for mozprofile 18:25:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:25:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:25:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:25:55 INFO - Running setup.py install for mozrunner 18:25:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:25:55 INFO - Running setup.py install for mozscreenshot 18:25:55 INFO - Running setup.py install for moztest 18:25:55 INFO - Running setup.py install for mozversion 18:25:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:25:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:25:55 INFO - Cleaning up... 18:25:55 INFO - Return code: 0 18:25:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:25: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')]} 18:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25: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')]} 18:25:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:55 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 0x165e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19caa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce090>, '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 0x19ced80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8f90>, '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-1505179302.488033-1074970685', '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 18:25:55 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 18:25:55 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 18:25:55 INFO - Using env: {'DISPLAY': ':0', 18:25:55 INFO - 'HOME': '/home/cltbld', 18:25:55 INFO - 'LANG': 'en_US.UTF-8', 18:25:55 INFO - 'LANGUAGE': 'en_US:en', 18:25:55 INFO - 'LOGNAME': 'cltbld', 18:25:55 INFO - 'MAIL': '/var/mail/cltbld', 18:25:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:55 INFO - 'MOZ_NO_REMOTE': '1', 18:25:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:55 INFO - 'NO_EM_RESTART': '1', 18:25:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:55 INFO - 'PWD': '/builds/slave/test', 18:25:55 INFO - 'SHELL': '/bin/bash', 18:25:55 INFO - 'SHLVL': '1', 18:25:55 INFO - 'TERM': 'linux', 18:25:55 INFO - 'TMOUT': '86400', 18:25:55 INFO - 'USER': 'cltbld', 18:25:55 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1505179302.488033-1074970685', 18:25:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:55 INFO - '_': '/tools/buildbot/bin/python'} 18:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:25:56 INFO - Running setup.py (path:/tmp/pip-OHwJuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:25:56 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)) 18:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:25:56 INFO - Running setup.py (path:/tmp/pip-DreUCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:25:56 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)) 18:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:25:56 INFO - Running setup.py (path:/tmp/pip-lgqg1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:25:56 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)) 18:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:25:56 INFO - Running setup.py (path:/tmp/pip-DAreiC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:25:56 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)) 18:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:25:56 INFO - Running setup.py (path:/tmp/pip-Ye04ag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:25:56 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)) 18:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:25:56 INFO - Running setup.py (path:/tmp/pip-3ISTJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:25:56 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)) 18:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:25:56 INFO - Running setup.py (path:/tmp/pip-v3hNhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:25:56 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)) 18:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:25:56 INFO - Running setup.py (path:/tmp/pip-rU0R3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:25:56 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)) 18:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:25:56 INFO - Running setup.py (path:/tmp/pip-G5MX_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:25:56 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)) 18:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:25:56 INFO - Running setup.py (path:/tmp/pip-myj4hu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:25:56 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)) 18:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:25:56 INFO - Running setup.py (path:/tmp/pip-JEKrQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:25:56 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)) 18:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:25:56 INFO - Running setup.py (path:/tmp/pip-vfgqZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:25:57 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)) 18:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:25:57 INFO - Running setup.py (path:/tmp/pip-Q2tIjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:25:57 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)) 18:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:25:57 INFO - Running setup.py (path:/tmp/pip-IatWZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:25:57 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)) 18:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:25:57 INFO - Running setup.py (path:/tmp/pip-gvyUEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:25:57 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)) 18:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:25:57 INFO - Running setup.py (path:/tmp/pip-KLRARO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:25:57 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)) 18:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:25:57 INFO - Running setup.py (path:/tmp/pip-yIY9Ak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:25:57 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)) 18:25:57 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)) 18:25:57 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)) 18:25:57 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)) 18:25:57 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)) 18:25:57 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)) 18:25:57 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)) 18:25:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:25: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 18:25: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 18:25: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 18:25: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 18:25:58 INFO - Downloading blessings-1.6.tar.gz 18:25:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:25:58 INFO - Installing collected packages: blessings 18:25:58 INFO - Running setup.py install for blessings 18:25:58 INFO - Successfully installed blessings 18:25:58 INFO - Cleaning up... 18:25:58 INFO - Return code: 0 18:25:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:25:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:25:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:25:58 INFO - Reading from file tmpfile_stdout 18:25:58 INFO - Current package versions: 18:25:58 INFO - argparse == 1.2.1 18:25:58 INFO - blessings == 1.6 18:25:58 INFO - blobuploader == 1.2.4 18:25:58 INFO - docopt == 0.6.1 18:25:58 INFO - functools32 == 3.2.3-2 18:25:58 INFO - jsonschema == 2.5.1 18:25:58 INFO - manifestparser == 1.1 18:25:58 INFO - mozInstall == 1.13 18:25:58 INFO - mozcrash == 1.0 18:25:58 INFO - mozdebug == 0.1 18:25:58 INFO - mozdevice == 0.51 18:25:58 INFO - mozfile == 1.2 18:25:58 INFO - mozhttpd == 0.7 18:25:58 INFO - mozinfo == 0.10 18:25:58 INFO - mozleak == 0.1 18:25:58 INFO - mozlog == 3.5 18:25:58 INFO - moznetwork == 0.27 18:25:58 INFO - mozprocess == 0.25 18:25:58 INFO - mozprofile == 0.28 18:25:58 INFO - mozrunner == 6.13 18:25:58 INFO - mozscreenshot == 0.1 18:25:58 INFO - mozsystemmonitor == 0.3 18:25:58 INFO - moztest == 0.8 18:25:58 INFO - mozversion == 1.4 18:25:58 INFO - psutil == 3.1.1 18:25:58 INFO - requests == 1.2.3 18:25:58 INFO - wsgiref == 0.1.2 18:25:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:25: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')]} 18:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25: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')]} 18:25:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:58 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 0x165e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19caa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce090>, '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 0x19ced80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8f90>, '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-1505179302.488033-1074970685', '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 18:25:58 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 18:25:58 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 18:25:58 INFO - Using env: {'DISPLAY': ':0', 18:25:58 INFO - 'HOME': '/home/cltbld', 18:25:58 INFO - 'LANG': 'en_US.UTF-8', 18:25:58 INFO - 'LANGUAGE': 'en_US:en', 18:25:58 INFO - 'LOGNAME': 'cltbld', 18:25:58 INFO - 'MAIL': '/var/mail/cltbld', 18:25:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:58 INFO - 'MOZ_NO_REMOTE': '1', 18:25:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:58 INFO - 'NO_EM_RESTART': '1', 18:25:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:58 INFO - 'PWD': '/builds/slave/test', 18:25:58 INFO - 'SHELL': '/bin/bash', 18:25:58 INFO - 'SHLVL': '1', 18:25:58 INFO - 'TERM': 'linux', 18:25:58 INFO - 'TMOUT': '86400', 18:25:58 INFO - 'USER': 'cltbld', 18:25:58 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1505179302.488033-1074970685', 18:25:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:58 INFO - '_': '/tools/buildbot/bin/python'} 18:25:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:59 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)) 18:25:59 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)) 18:25:59 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)) 18:25:59 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)) 18:25:59 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)) 18:25:59 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)) 18:25:59 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)) 18:25:59 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)) 18:25:59 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)) 18:25:59 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:25:59 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 18:25:59 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 18:25:59 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 18:25:59 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 18:26:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:26:00 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)) 18:26:00 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)) 18:26:00 INFO - Installing collected packages: simplejson 18:26:00 INFO - Running setup.py install for simplejson 18:26:00 INFO - building 'simplejson._speedups' extension 18:26:00 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 18:26:01 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 18:26:01 INFO - Successfully installed simplejson 18:26:01 INFO - Cleaning up... 18:26:01 INFO - Return code: 0 18:26:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:26: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')]} 18:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26: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')]} 18:26:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19caa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ce090>, '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 0x19ced80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8f90>, '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-1505179302.488033-1074970685', '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 18:26:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:26:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:26:01 INFO - Using env: {'DISPLAY': ':0', 18:26:01 INFO - 'HOME': '/home/cltbld', 18:26:01 INFO - 'LANG': 'en_US.UTF-8', 18:26:01 INFO - 'LANGUAGE': 'en_US:en', 18:26:01 INFO - 'LOGNAME': 'cltbld', 18:26:01 INFO - 'MAIL': '/var/mail/cltbld', 18:26:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:01 INFO - 'MOZ_NO_REMOTE': '1', 18:26:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:01 INFO - 'NO_EM_RESTART': '1', 18:26:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:01 INFO - 'PWD': '/builds/slave/test', 18:26:01 INFO - 'SHELL': '/bin/bash', 18:26:01 INFO - 'SHLVL': '1', 18:26:01 INFO - 'TERM': 'linux', 18:26:01 INFO - 'TMOUT': '86400', 18:26:01 INFO - 'USER': 'cltbld', 18:26:01 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1505179302.488033-1074970685', 18:26:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:01 INFO - '_': '/tools/buildbot/bin/python'} 18:26:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:26:01 INFO - Cleaning up... 18:26:01 INFO - Return code: 0 18:26:01 INFO - Running post-action listener: _resource_record_post_action 18:26:01 INFO - Running post-action listener: _start_resource_monitoring 18:26:01 INFO - Starting resource monitoring. 18:26:01 INFO - [mozharness: 2017-09-12 01:26:01.643220Z] Finished create-virtualenv step (success) 18:26:01 INFO - [mozharness: 2017-09-12 01:26:01.643740Z] Running install step. 18:26:01 INFO - Running pre-action listener: _resource_record_pre_action 18:26:01 INFO - Running main action method: install 18:26:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:26:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:26:01 INFO - Reading from file tmpfile_stdout 18:26:01 INFO - Detecting whether we're running mozinstall >=1.0... 18:26:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:26:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:26:01 INFO - Reading from file tmpfile_stdout 18:26:01 INFO - Output received: 18:26:01 INFO - Usage: mozinstall [options] installer 18:26:01 INFO - Options: 18:26:01 INFO - -h, --help show this help message and exit 18:26:01 INFO - -d DEST, --destination=DEST 18:26:01 INFO - Directory to install application into. [default: 18:26:01 INFO - "/builds/slave/test"] 18:26:02 INFO - --app=APP Application being installed. [default: firefox] 18:26:02 INFO - mkdir: /builds/slave/test/build/application 18:26:02 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'] 18:26:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:26:18 INFO - Reading from file tmpfile_stdout 18:26:18 INFO - Output received: 18:26:18 INFO - /builds/slave/test/build/application/firefox/firefox 18:26:18 INFO - Running post-action listener: _resource_record_post_action 18:26:18 INFO - [mozharness: 2017-09-12 01:26:18.603300Z] Finished install step (success) 18:26:18 INFO - [mozharness: 2017-09-12 01:26:18.603445Z] Running setup-mitmproxy step. 18:26:18 INFO - Running pre-action listener: _resource_record_pre_action 18:26:18 INFO - Running main action method: setup_mitmproxy 18:26:18 INFO - Skipping: mitmproxy is not required 18:26:18 INFO - Running post-action listener: _resource_record_post_action 18:26:18 INFO - [mozharness: 2017-09-12 01:26:18.603969Z] Finished setup-mitmproxy step (success) 18:26:18 INFO - [mozharness: 2017-09-12 01:26:18.604070Z] Running run-tests step. 18:26:18 INFO - Running pre-action listener: _resource_record_pre_action 18:26:18 INFO - Running pre-action listener: _set_gcov_prefix 18:26:18 INFO - Running main action method: run_tests 18:26:18 WARNING - Try message not found. 18:26:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:26:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:26:18 INFO - Python 2.7.3 18:26:18 INFO - Return code: 0 18:26:18 INFO - grabbing minidump binary from tooltool 18:26:18 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')]} 18:26:18 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 0x19ce090>, '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 0x19ced80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:26:18 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 18:26:18 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 18:26:18 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 18:26:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:26:18 INFO - Return code: 0 18:26:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:26:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:26:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:26:18 INFO - ENV: RUST_BACKTRACE is now full 18:26:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:26:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:26:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/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 18:26:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/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 18:26:18 INFO - Using env: {'DISPLAY': ':0', 18:26:18 INFO - 'HOME': '/home/cltbld', 18:26:18 INFO - 'LANG': 'en_US.UTF-8', 18:26:18 INFO - 'LANGUAGE': 'en_US:en', 18:26:18 INFO - 'LOGNAME': 'cltbld', 18:26:18 INFO - 'MAIL': '/var/mail/cltbld', 18:26:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:26:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:26:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:18 INFO - 'MOZ_NO_REMOTE': '1', 18:26:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:26:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:18 INFO - 'NO_EM_RESTART': '1', 18:26:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:18 INFO - 'PWD': '/builds/slave/test', 18:26:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:26:18 INFO - 'RUST_BACKTRACE': 'full', 18:26:18 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:26:18 INFO - 'SHELL': '/bin/bash', 18:26:18 INFO - 'SHLVL': '1', 18:26:18 INFO - 'TERM': 'linux', 18:26:18 INFO - 'TMOUT': '86400', 18:26:18 INFO - 'USER': 'cltbld', 18:26:18 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1505179302.488033-1074970685', 18:26:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:18 INFO - '_': '/tools/buildbot/bin/python'} 18:26:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/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 18:26:18 INFO - mozversion application_buildid: 20170912003517 18:26:18 INFO - mozversion application_changeset: bda524beac249b64aa36016800502a34073bf35a 18:26:18 INFO - mozversion application_display_name: Nightly 18:26:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:26:18 INFO - mozversion application_name: Firefox 18:26:18 INFO - mozversion application_remotingname: firefox 18:26:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:26:18 INFO - mozversion application_vendor: Mozilla 18:26:18 INFO - mozversion application_version: 57.0a1 18:26:18 INFO - mozversion platform_buildid: 20170912003517 18:26:18 INFO - mozversion platform_changeset: bda524beac249b64aa36016800502a34073bf35a 18:26:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:26:18 INFO - mozversion platform_version: 57.0a1 18:26:18 INFO - using testdate: 1505179578 18:26:18 INFO - actual date: 1505179578 18:26:18 INFO - starting webserver on 'localhost:47979' 18:26:18 INFO - SUITE-START | Running 2 tests 18:26:18 INFO - TEST-START | dromaeo_css 18:26:18 INFO - Initialising browser for dromaeo_css test... 18:26:18 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47979/getInfo.html -profile /tmp/tmp5e7pVe/profile 18:26:18 INFO - TEST-INFO | started process 20330 (/builds/slave/test/build/application/firefox/firefox http://localhost:47979/getInfo.html) 18:26:23 INFO - TEST-INFO | 20330: exit 0 18:26:23 INFO - Browser initialized. 18:26:23 INFO - Running cycle 1/1 for dromaeo_css test... 18:26:23 INFO - Using env: {'DISPLAY': ':0', 18:26:23 INFO - 'HOME': '/home/cltbld', 18:26:23 INFO - 'JSGC_DISABLE_POISONING': '1', 18:26:23 INFO - 'LANG': 'en_US.UTF-8', 18:26:23 INFO - 'LANGUAGE': 'en_US:en', 18:26:23 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:26:23 INFO - 'LOGNAME': 'cltbld', 18:26:23 INFO - 'MAIL': '/var/mail/cltbld', 18:26:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:26:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:26:23 INFO - 'MOZ_CRASHREPORTER': '1', 18:26:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:23 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:26:23 INFO - 'MOZ_NO_REMOTE': '1', 18:26:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:26:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:23 INFO - 'NO_EM_RESTART': '1', 18:26:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:23 INFO - 'PWD': '/builds/slave/test', 18:26:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:26:23 INFO - 'RUST_BACKTRACE': 'full', 18:26:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:26:23 INFO - 'SHELL': '/bin/bash', 18:26:23 INFO - 'SHLVL': '1', 18:26:23 INFO - 'TERM': 'linux', 18:26:23 INFO - 'TMOUT': '86400', 18:26:23 INFO - 'USER': 'cltbld', 18:26:23 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1505179302.488033-1074970685', 18:26:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:23 INFO - '_': '/tools/buildbot/bin/python'} 18:26:23 INFO - TEST-INFO | started process 20532 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5e7pVe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:26:24 INFO - PID 20532 | 18:26:24 INFO - PID 20532 | (/builds/slave/test/build/application/firefox/firefox:20583): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:26:24 INFO - PID 20532 | 18:26:35 INFO - PID 20532 | RSS: Main: 172883968 18:26:35 INFO - PID 20532 | 18:29:32 INFO - PID 20532 | Cycle 1(1): loaded http://localhost:47979/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47979/tests/dromaeo/cssquery-ext.html) 18:29:33 INFO - PID 20532 | RSS: Main: 175513600 18:29:33 INFO - PID 20532 | 18:32:35 INFO - PID 20532 | Cycle 1(1): loaded http://localhost:47979/tests/dromaeo/cssquery-ext.html (next: http://localhost:47979/tests/dromaeo/cssquery-jquery.html) 18:32:36 INFO - PID 20532 | RSS: Main: 171499520 18:32:36 INFO - PID 20532 | 18:35:38 INFO - PID 20532 | Cycle 1(1): loaded http://localhost:47979/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47979/tests/dromaeo/cssquery-mootools.html) 18:35:38 INFO - PID 20532 | RSS: Main: 173928448 18:35:38 INFO - PID 20532 | 18:38:40 INFO - PID 20532 | Cycle 1(1): loaded http://localhost:47979/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47979/tests/dromaeo/cssquery-prototype.html) 18:38:41 INFO - PID 20532 | RSS: Main: 172449792 18:38:41 INFO - PID 20532 | 18:41:44 INFO - PID 20532 | Cycle 1(1): loaded http://localhost:47979/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47979/tests/dromaeo/cssquery-yui.html) 18:41:44 INFO - PID 20532 | RSS: Main: 173948928 18:41:44 INFO - PID 20532 | 18:44:46 INFO - PID 20532 | Cycle 1(1): loaded http://localhost:47979/tests/dromaeo/cssquery-yui.html (next: http://localhost:47979/tests/dromaeo/cssquery-dojo.html) 18:44:46 INFO - PID 20532 | RSS: Main: 176877568 18:44:46 INFO - PID 20532 | 18:44:46 INFO - PID 20532 | __start_tp_report 18:44:46 INFO - PID 20532 | _x_x_mozilla_page_load 18:44:46 INFO - PID 20532 | _x_x_mozilla_page_load_details 18:44:46 INFO - PID 20532 | |i|pagename|runs| 18:44:46 INFO - PID 20532 | |0;dojo.html;3069.278719500918;3084.7532197424207;3117.0804612639276;3128.6558478567354;3149.669284725105;6045.214122164112;6135.20242368492;6178.227721534809;6248;6306.779262725806;6050.546209034329;6061.787837425691;6083.057126145443;6207.875842483148;6242.438180563749;5699.629524080942;5759.65442073477;5796.50729687977;5809.854753631151;5853.239078919734;5457.426970168115;5706.1440783882545;5763.049096899007;5798.072308430652;5809;5682.204491371212;5700.486956173922;5750.108733146348;5763.740631671585;5768.278965129359;5608.4391560844;5706.372299047143;5709.800156994527;5758.539316854642;5760.135979603051;5681.289838770153;5712.114622233519;5713.257276554063;5719.59962802604;5753.88492230153;6213.37866213375;6215.782447614357;6224.782132625336;6288.654124023177;6298.4646305064125;6638.73445062197;6645.102911106987;6725.831854203635;6743.460523158135;6749.662516874137;6562.278149403562;6663.900041499382;6671.132752742161;6671.366220209073;6700.664966751643;6177.289611694668;6231.688415579203;6290.748370065237;6296.590721603127;6304.842378940517;6809.489288303397;6878.071460352789;6913.688884000232;6969.686364113627;6977.372036516738;6371.299157092716;6463.903041454382;6602.735890564323;6667.466602671775;6678.766243181465;6629.701663425158;6659.567128136656;6669.699863506154;6671.666416679147;6673.799786006428;6467.450266727286;6591.670416479157;6615.966920165464;6638.20341559016;6643.5017373697165;6578.914479110988;6582.506312026617;6668.399844014062;6672.666366681647;6678.065070890079;5146.922796158061;5159.2003239497935;5177.404598471148;5178.896422071537;5272.78908843642;4594.1730488512385;4617.468991066004;4656.185167595725;4672.6261899047995;4672.883177920579;5677.858053548612;5733.455321744427;5737.196792449061;5738.196652468658;5739.540836733051;6532.8366790830605;6621.635810030398;6629.237637671633;6703.698333575001;6715.597064176068;5587.916181257285;5676.574256930664;5692.174634677948;5696.031674615326;5697.686627235542;5711.685857277889;5774.740136693859;5792.275965504312;5798.275215598051;5823.359430462646;6145.600535965148;6227.06594010902;6326.715297811609;6366.427021567988;6393.456556192683;6543.770968016097;6545.8690826184165;6632.204135503771;6632.801015969529;6634.270230274569;6215.409536094064;6262.937370626418;6318.9052164216655;6325.304216536086;6344.968275158594;6396.072569477493;6511.73953041873;6516.218053833571;6548.901766473602;6636.004599310142;6730.226024007302;6742.561733487308;6743.527953043436;6745.763898263537;6754.088198093391;6421.133147025092;6444.420002199825;6504.6747662615735;6523.478121750154;6526.706298216497;6407.199100112486;6445.581037232565;6534.934650653623;6539.803805885831;6543.051257567721;6190.442860142609;6302.810915672354;6371.649559274284;6425.8714825704155;6438.130852335063;6451.032345148077;6497.603015351722;6506.804795856132;6513.316101809241;6531.738730450728;6124.173236613178;6240.188775459162;6267.843303917439;6321.273053598895;6322.841928951813 18:44:46 INFO - PID 20532 | |1;ext.html;824.7649419915324;846.8010017645852;847.8812966184735;854.1757204297851;861.3539845116163;38283.80858095709;41939.16121677565;42206.78896605513;42362.15275694492;42781.14437711244;39245.41131883017;39610.60389396105;39625.20749585014;39637.60362396383;39645.80177099111;6974.755883544077;7676.4242681798805;7846.372290216769;7856.017997750281;7864.567448790314;7638.236176382373;7758.7672369829;7768.378529717609;7768.883466748003;7772.455928085036;7728.343090837286;7734.071911370615;7791.337736292422;7804.414668899855;7806.570638614874;7720.691172353099;7772.028496437945;7779.7666070017995;7780.688772449095;7781.105172905131;7570.242975702441;7728.884066739003;7731.265529774662;7733.226677332278;7735.265149810759;38100.42849357262;38346.233075338336;38347.808260958795;38453.230935381136;38470.80764596159;3465.8093804840723;3516.7362447816517;3518;3518.190816112283;3520.806355650438;31409.685903141133;31529.842350788327;31551.211219719462;31553.21116972071;31572.210694732814;5242.9213561796605;5265.394479634852;5274.5780337572905;5280.630355875091;5297.073012222845;184343.07828460907;184841;184984;185196;185416;42363.788181058895;43390.783046084565;47779;47837.521624784;48263.27605085856;51979;52412;52447.73776131095;52451;52494.21258681123;14596.927015364856;14752.18862962526;14755.114693118447;14761.55715328542;14765.630859228391;11895.88104118965;11898.881011189951;11913.16607837443;11915;11922.76154476923;14325.212113333659;14372.568822935329;14401.351939162763;14406.927965360106;14546.854531454761;5558.499735023867;5559.9444005560235;5765.18396705045;5777.480026797524;5863.149843272701;995.4425521707722;1006.5470538257813;1007.2294694558668;1014.5485259059795;1024.4672770159475;22759.88620056889;22970.65544016841;22986.42533936665;22994.42513937165;23147.53704925892;1096.7203363142348;1097.8133717268083;1098.5056724474018;1112.2936935046305;1123.1351859068473;12237.93881030607;12312.569060082855;12338.136330456962;12407;12415.689607759878;229720;231414;231594;231870;232561;52258;52270.47729522732;52307;52411.21383179256;52749;1421.6161636358124;1461.7661174212076;1467.5303902751018;1475.7196132734746;1479.7632378819337;21921.780782191974;22290.554188916456;22520.211792587183;22580.774192257868;22775.544489109794;37279;37368.010559155744;37370.626293737805;37371.81314093412;37391.62608373881;193063;193236;193833;194495;194668;33437.16407089842;35782.28435431329;35889.102772429855;36160.63839361573;36336.27327453489;45007;45760.313595296764;46473.30290045584;46513;46563.76718116388;33516.162095947795;33702.49446258259;33709.49435758417;34175.316493670485;34459.31081378408;33287.83356083301;33522;33786.831065845494;33917.321653567284;34341.656583434844;37464.25071498609;37586.81206593949;37780.43329350117;37831.62168378281;37897.242055159295;32778;32945.505817412275;32996.50505242375;33091.83454082714;33194;23884;24001.879990599937;24232.87883560571;24564.50870982606;24644.137455188982 18:44:46 INFO - PID 20532 | |2;jquery.html;550.6310771782905;558.4697927678162;563.4844117632364;563.8731285460769;568.1846550200462;21432.785672143273;22138.667919981213;22148.66776998346;22150.556988860215;22155.66766498502;22987.310380688563;24182.637260441108;24418.267451976426;24535;24544.38639034025;6184.25788905332;6312.589681670676;6341.873162536746;6347.174867267248;6348.460380867632;6312.242530896299;6353.650549219791;6404;6407.295197528291;6413.775517856876;6204.720787564548;6454.3545645435315;6477.870442591146;6478.352164783531;6481.708323125447;6178.104174894637;6193.907091393633;6222.844428889269;6222.844428889269;6229.4704950079295;5764.481196692276;5768.826935191951;6026.0960855871535;6031.668258245794;6031.849203769941;28155;28684.569731454045;28694;28697.426051478855;28751.424971500663;2071.337172104927;2093.790620937894;2093.8953052347474;2096.706461095448;2097.035363732685;18351.54121146969;18757.531061723428;19059.90470047655;19067.523311917175;19127.33054343091;2432.598621227495;2460.9261722148362;2489.5020995800733;2498.0507407185337;2500.7874330681916;116672;122178.38910805389;135993;141873;142428.99999999895;14121.929390352983;14230.078939343262;14295.356708948122;14371.456663063294;14690.739685491302;21826.581272217256;22007.22974695878;22038.779612203994;22040.559188816133;22081;9120.270378369714;9295.90704092964;9304.953475232716;9389.389689670286;9474.763130921781;8503.872441913376;8579.099194584478;8589.184027517376;8620.267277281502;8691.652333906699;8856.822863542822;8886.377953543319;8962.193402593797;9028.729138125867;9189.632414703337;2581.625664278669;2610.399608090112;2723.5233834079354;2739.643846299968;2805.8877644894383;947.1759569174862;953.7663272498144;964.5098323090841;983.8622592836865;992.6624947517879;18156.364527241483;18408.907955460138;18837.62324753497;18902.52743681419;19182.520436988907;1016.3952448293254;1017.3133135133755;1021.6015753856002;1026.1790567545934;1026.373911913732;10338.53476593555;10344.5344959477;10370.429626370622;10568.418736969386;10749;490660;493119;498100;502185;503737;100967;107625;110047;112106;112477.43761281141;1033.2767063055892;1079.8218293981406;1085.3976043295888;1132.3432409202808;1144.729350421032;19388.8061119387;19471.902640486707;19705.901470492558;19758.20967161355;20109.597808043465;54667.72666136803;55161.72419137879;55499.72250138724;56353.436465636456;57508;69153.65423172852;69306;69591.65204173946;70168.64915675593;70276.29723702902;13716.65708357299;13884;13975.580732578039;13985.720285594027;14030.228337441538;22030.669539956594;22109.447263818533;22195.33413997583;22334.77665223327;22601.773982260624;17790.199441024924;17969.910150449163;17979.101044947438;18127.274909003492;18133.546661333043;14920.253987300808;14958.850411495745;15020.924895375454;15081.698366032399;15500.147491887603;18369.357072502396;18430.447086587425;18452.90773546124;18465.446036618923;18534;13826.446942122202;13846.169229846242;13858.4456621734;13898.16611003343;13929.442822287401;17580.472585822445;17786.46640600784;17856.285748569913;17884.731729023813;17898.5525361867 18:44:46 INFO - PID 20532 | |3;mootools.html;1331.6071758831145;1376.9862301376984;1377.5867239828053;1393.5262010916292;1396.7974643676662;15089.471868484605;15736.763948540767;15764.369425222978;15766.054036757789;15795.763063554055;15203.54389368318;15427.5371738848;15434.61413464664;15440.073595584272;15447.69104617907;2677.3708178578017;2773.2928103333725;2774.833509989397;2776.0561409120896;2791.860406979653;2755.1734479656125;2759.2550011496883;2777.8888844446196;2781.429806889583;2786.2755683522328;2630.2635262126637;2822;2823.731745484176;2825.858707064649;2826;2824.0962891874506;2832.150354893534;2838.0918106206022;2840.2899275181203;2865.0688526228955;2608.126277696974;2707.837529748221;2772.8059035867495;2783.4293969736154;2784.902528411516;15346.539603811903;15397;15495.845041549666;15505.534833954887;15525.146116963562;7874.173211812792;8132.227438393344;8143.144969778147;8179.059408168077;8179.509229446193;5106.591472682177;5186.870328241786;5224.08578498765;5325.307709997675;5338.599605029638;1866.412080194748;1893.943181704551;1898.3166060218311;1924.3745782620704;1936.2351871011012;26650.06724764624;26705;26889.445502677216;26934.46131077387;26951.32621684454;6487.22153341602;6534.248561415403;6654.534182607171;6658.1344425224415;6764.511807402364;16144.677106457804;16168.919155404148;16204;16220.756688649679;16226.918865405598;7701.614919254015;7781.455298129076;7834.4907581007055;7835.177306382861;7861;7155.463340249502;7217.494775365779;7291.416686665054;7411.295926886937;7450.0687414073245;5144.845654630367;5299.735013249399;5302.840914772563;5312.654677446031;5335.093034184198;1477.2761346939922;1477.5271912987741;1483.6661751105914;1495.297210311152;1495.4541592318726;757.4054367321626;792.4215322814377;797.4736673795268;801.4309840013656;813.515958004986;7555.9244407555225;7650.617469126521;7683.0780306363595;7696.345810606049;7699.345555627834;696.606417374182;705.5449235243342;709.170270783185;709.9396551293198;718.7520305494596;5330.173823057432;5424.887897980904;5441.183822426588;5531.861703457365;5620.9437905621235;21665.891670541547;22100.2264920727;22228.11087556512;22325.106995720314;22597.43506412353;20662.17351305961;21822.345329640135;21888.12447502114;21967.121315147215;21998.34004979853;10510.789784204273;11355.37545435009;11477.770444591062;11499.482523286471;11504.367259800623;8240.13478584764;8408.495490270603;8433.156684331763;8492.193241642035;8525.658973640984;18396.632067358845;18979.71530427072;19012.809871901656;19288.71066933969;19329.8067019328;18775.436736897915;18784.906075469535;18850.622987540446;18927.242910283985;19498.61002779965;10711.08955738771;11000.724981875517;11013.174011949297;11016.283941543774;11066.336019838835;12950.935245323713;12963.805542916674;13158.802617960548;13170.209787412403;13187.934060329637;10885.401302928414;11184.94407527957;11212.439378030902;11375.54497820078;11401;10991.680998280257;11036.5033573491;11124.276922000368;11207;11245.831312530483;9631.662891799033;9758.75603109928;9764.853527196956;9779.853302200616;9791.657291994747;7179.748708795167;7182.497225194186;7187.31720486553;7187.892181617175;7227.927720722936;7339.9266007341375;7449.739259125905;7526.887096693664;7590.468667193243;7598.506097103671 18:44:46 INFO - PID 20532 | |4;prototype.html;487.31810343157196;497.26009686626674;512.374902618805;523.1733860500409;533.7731464127745;6142.679323945351;6159.692015399229;6172.84567885802;6212.689365531723;6312.210973628296;6069.908951365733;6111.938880611193;6151.784687535937;6152.784652537162;6179.907301390483;5133.974330128353;5149.2791009258735;5162.92255616166;5163.586913046947;5196.8700782480555;4755.952440475603;4885.291632713254;5104.69371837691;5118.283440318359;5144.382674079097;4919.286703428001;4941.2094064949615;4946.332245146913;4961.280614310923;4999.450060493361;4925.655204135712;4927.655064145493;4955.677880937764;4970.428400733907;4987.825426110068;4333.631641310492;4439.289713645801;4448.888777780549;4461.929137007128;4490.39379683745;6113.144159817629;6118.908216376758;6138.785142519991;6139.140520327158;6156.815295541141;5682.346530149044;5682.460166284238;5697.857553561153;5709.172170035322;5717.48542631161;6537.771178008795;6548.312427195124;6553.836154096138;6565.868682626321;6584.967075164642;4837.0809546186465;4860.513948605147;4885.36490256268;4898.755062246909;4920.351682186471;6787.355201255922;6789.574189420227;6850.93149068508;6897.931020689779;6905.309469053054;4963.280324352949;5014.448410674823;5014.824481143142;5021.748912554358;5064.49355064498;5107.361579802525;5140.537351638371;5147.279380886679;5147.897042059138;5148.56237219833;5612.214289999403;5693.4021927696995;5701.543876489871;5727.198192253088;5729.9713501432225;6545.312742162099;6616.503762217853;6677.465802735769;6680.432163266177;6690.732370705117;1667.65813008333;1692.8053273873415;1723.4657256250603;1752.1589636974325;1756.9560760980821;1201.1651901928076;1215.1797536662893;1218.7988981818078;1221.8533775946944;1229.8524177098805;5654.3497497788285;5678.381661226531;5700.631848356447;5705.1727499512335;5719.5138413235345;6640.468762498988;6670.833229169212;6699.39705426514;6700.66496675174;6730.596164230162;5660.518855897295;5668.404817494186;5685.493344263773;5692.231548740867;5708.571857110734;3520.3663340598937;3526.153723106436;3549.1304630365726;3569.6430356964097;3574.8391322390553;6346.399744266004;6356;6374.203224596926;6390.233172019387;6391.073294372383;6814.216365117975;6823.931760682429;6881.793546193622;6899.48253880961;6902.309769023058;6338.144350512622;6361.395667411589;6375.681215939185;6378.553501254868;6384.457321127663;6634.601923884582;6689.331066893272;6697.129373181455;6728.158980127484;6769.086173366532;6640.402363787089;6642.4686025118845;6651.700673469609;6700.698468569024;6739.528233023641;6339.714712838026;6382.90425643625;6383.733774509982;6390.456811171102;6397;4983.975080124576;4990.950090499048;4997.85006449807;5010;5020.472850350807;6319.431251187415;6482.54622176462;6706.698198580978;6706.966465167643;6767.458603311625;6303.527235457267;6351.460125889351;6424.51816113784;6432.099506069248;6577.539572229898;6444.613323200623;6471.449926756279;6485.675716214076;6523.608583484816;6558.344165583403;6300.21247344082;6345.15990362808;6374.330695276929;6456.451201647913;6463.127477790438;6519.511036672364;6520.608763474017;6575.967120164369;6610.644817812241;6666.333366663295;6272.8118156455375;6357.237131544244;6386.648734319657;6434.420902118832;6453.483721302191 18:44:46 INFO - PID 20532 | |5;yui.html;847.2882778466087;856.8671855862343;861.974140775777;862.3877047296419;875.199192738644;14711.926440367797;14805.3337599808;14848.851511484881;14869.330880110394;15013.699726005474;16443.58891027725;16481;17166.055866927312;17294.654106917853;17300.30798768051;957.6169532187129;959.8464245720686;960.183843732825;962.9759256018621;963.3160456076159;941.1764705882352;953.0183910572088;958.8034452937131;962.8026254617787;970.2674480767012;1030.3560274828233;1034.053840735726;1035.2597892506856;1035.2959987208694;1035.8239099353127;989.2432289298692;1023.518946095334;1024.9948750256237;1025.1696126137815;1028.9022542858415;747.5402627384174;748.7753673897863;749.0187853911393;749.0674110732174;749.4004796163048;16478.2584783685;16629.50111496657;16727;16772;16798;21350.893245533673;21498.677519837216;21608.891955540123;21614.24350147753;21630.783692163033;17454.650906981788;17696.557586060324;17778.37775677858;17781;17826.286948522174;3772.5284339457567;3784.6404591563755;3797.3164830330807;3801.7718936863876;3803.2583646189005;126028;126780;126886;129768;131219.99999999904;16485.258163382678;16771.161441927856;16813;16936.57658558521;16987;28699.13902582926;29335.413291734047;29458.116256512338;29487.55768663472;29628.407431851672;7872.094709108412;7961.402894782915;7971.601419929097;7979.042514898133;7994.840103197904;3790.4124860646093;3877.2827027000094;3921.647051765298;3922.646961773397;3922.725409221327;5269.631125821233;5330.333708286465;5353.116735738559;5383.515483606494;5397.379301380392;916.9724908252763;931.0503286647717;932.3473568502076;942.8491441369348;942.8679984802134;629.8047605242298;644.7936660268763;654.2933631677777;660.8083655739878;672.831792051987;11179.552817887356;11258.268212566158;11421.543138274375;11700.180987330805;11734.589289374831;576.8615532272308;587.8883012227664;588.358689028952;592.599995003371;601.9006863867501;5500.7524661390335;5572.526335261548;5579.553635709133;5640.605157638925;5720.342160651495;124255;124543.37728311482;126636.36681816717;127209;130351.69648303396;108406;108997.45501272443;109824;110566;111133.88866111236;93270;95903.52048239714;96172.51913740527;100263;100594.4970275144;10254.948725256625;10257.640982565872;10462.372257664565;10506.054455099078;10585.153187745116;43122.78438607787;45130;45189.774051129534;45218;45500.089998200514;58090.4190958085;58326;58445.70777146087;59274.70362648159;59662.70168649129;14875.925620371829;14916.62708432298;14953.626159346104;15092.169930653481;15321.310541025903;15403.691926161639;15652;15786.60533486626;15862.286197121388;15900.204989750697;13726.931365343109;13884;13939.303034848419;13960.301984900918;13995.720085598028;14070.366833492313;14191.929040354733;14216.928915355356;14301.499447519287;14345.067570607876;13412.463501459832;13464.25946572948;13519.797203041766;13534.796978045533;13539.390727417487;10451;10691.198160138176;10828.783424331627;11022.834657479983;11115;10644.574217031543;10698.144148467949;10839.620613278497;10924.399158046384;10997.065249453566 18:44:46 INFO - PID 20532 | __end_tp_report 18:44:46 INFO - PID 20532 | __start_cc_report 18:44:46 INFO - PID 20532 | _x_x_mozilla_cycle_collect,376 18:44:46 INFO - PID 20532 | __end_cc_report 18:44:46 INFO - PID 20532 | __startTimestamp1505180686760__endTimestamp 18:44:46 INFO - PID 20532 | 18:44:46 INFO - PID 20532 | ------- Summary: start ------- 18:44:46 INFO - PID 20532 | Number of tests: 6 18:44:46 INFO - PID 20532 | 18:44:46 INFO - PID 20532 | [#0] dojo.html Cycles:165 Average:6104.87 Median:6304.84 stddev:726.69 (11.5%) stddev-sans-first:688.79 18:44:46 INFO - PID 20532 | Values: 3069.3 3084.8 3117.1 3128.7 3149.7 6045.2 6135.2 6178.2 6248.0 6306.8 6050.5 6061.8 6083.1 6207.9 6242.4 5699.6 5759.7 5796.5 5809.9 5853.2 5457.4 5706.1 5763.0 5798.1 5809.0 5682.2 5700.5 5750.1 5763.7 5768.3 5608.4 5706.4 5709.8 5758.5 5760.1 5681.3 5712.1 5713.3 5719.6 5753.9 6213.4 6215.8 6224.8 6288.7 6298.5 6638.7 6645.1 6725.8 6743.5 6749.7 6562.3 6663.9 6671.1 6671.4 6700.7 6177.3 6231.7 6290.7 6296.6 6304.8 6809.5 6878.1 6913.7 6969.7 6977.4 6371.3 6463.9 6602.7 6667.5 6678.8 6629.7 6659.6 6669.7 6671.7 6673.8 6467.5 6591.7 6616.0 6638.2 6643.5 6578.9 6582.5 6668.4 6672.7 6678.1 5146.9 5159.2 5177.4 5178.9 5272.8 4594.2 4617.5 4656.2 4672.6 4672.9 5677.9 5733.5 5737.2 5738.2 5739.5 6532.8 6621.6 6629.2 6703.7 6715.6 5587.9 5676.6 5692.2 5696.0 5697.7 5711.7 5774.7 5792.3 5798.3 5823.4 6145.6 6227.1 6326.7 6366.4 6393.5 6543.8 6545.9 6632.2 6632.8 6634.3 6215.4 6262.9 6318.9 6325.3 6345.0 6396.1 6511.7 6516.2 6548.9 6636.0 6730.2 6742.6 6743.5 6745.8 6754.1 6421.1 6444.4 6504.7 6523.5 6526.7 6407.2 6445.6 6534.9 6539.8 6543.1 6190.4 6302.8 6371.6 6425.9 6438.1 6451.0 6497.6 6506.8 6513.3 6531.7 6124.2 6240.2 6267.8 6321.3 6322.8 18:44:46 INFO - PID 20532 | 18:44:46 INFO - PID 20532 | [#1] ext.html Cycles:180 Average:37493.84 Median:35835.69 stddev:52956.68 (147.8%) stddev-sans-first:53033.66 18:44:46 INFO - PID 20532 | Values: 824.8 846.8 847.9 854.2 861.4 38283.8 41939.2 42206.8 42362.2 42781.1 39245.4 39610.6 39625.2 39637.6 39645.8 6974.8 7676.4 7846.4 7856.0 7864.6 7638.2 7758.8 7768.4 7768.9 7772.5 7728.3 7734.1 7791.3 7804.4 7806.6 7720.7 7772.0 7779.8 7780.7 7781.1 7570.2 7728.9 7731.3 7733.2 7735.3 38100.4 38346.2 38347.8 38453.2 38470.8 3465.8 3516.7 3518.0 3518.2 3520.8 31409.7 31529.8 31551.2 31553.2 31572.2 5242.9 5265.4 5274.6 5280.6 5297.1 184343.1 184841.0 184984.0 185196.0 185416.0 42363.8 43390.8 47779.0 47837.5 48263.3 51979.0 52412.0 52447.7 52451.0 52494.2 14596.9 14752.2 14755.1 14761.6 14765.6 11895.9 11898.9 11913.2 11915.0 11922.8 14325.2 14372.6 14401.4 14406.9 14546.9 5558.5 5559.9 5765.2 5777.5 5863.1 995.4 1006.5 1007.2 1014.5 1024.5 22759.9 22970.7 22986.4 22994.4 23147.5 1096.7 1097.8 1098.5 1112.3 1123.1 12237.9 12312.6 12338.1 12407.0 12415.7 229720.0 231414.0 231594.0 231870.0 232561.0 52258.0 52270.5 52307.0 52411.2 52749.0 1421.6 1461.8 1467.5 1475.7 1479.8 21921.8 22290.6 22520.2 22580.8 22775.5 37279.0 37368.0 37370.6 37371.8 37391.6 193063.0 193236.0 193833.0 194495.0 194668.0 33437.2 35782.3 35889.1 36160.6 36336.3 45007.0 45760.3 46473.3 46513.0 46563.8 33516.2 33702.5 33709.5 34175.3 34459.3 33287.8 33522.0 33786.8 33917.3 34341.7 37464.3 37586.8 37780.4 37831.6 37897.2 32778.0 32945.5 32996.5 33091.8 33194.0 23884.0 24001.9 24232.9 24564.5 24644.1 18:44:46 INFO - PID 20532 | 18:44:46 INFO - PID 20532 | [#2] jquery.html Cycles:180 Average:34189.26 Median:22153.11 stddev:83419.55 (376.6%) stddev-sans-first:83615.33 18:44:46 INFO - PID 20532 | Values: 550.6 558.5 563.5 563.9 568.2 21432.8 22138.7 22148.7 22150.6 22155.7 22987.3 24182.6 24418.3 24535.0 24544.4 6184.3 6312.6 6341.9 6347.2 6348.5 6312.2 6353.7 6404.0 6407.3 6413.8 6204.7 6454.4 6477.9 6478.4 6481.7 6178.1 6193.9 6222.8 6222.8 6229.5 5764.5 5768.8 6026.1 6031.7 6031.8 28155.0 28684.6 28694.0 28697.4 28751.4 2071.3 2093.8 2093.9 2096.7 2097.0 18351.5 18757.5 19059.9 19067.5 19127.3 2432.6 2460.9 2489.5 2498.1 2500.8 116672.0 122178.4 135993.0 141873.0 142429.0 14121.9 14230.1 14295.4 14371.5 14690.7 21826.6 22007.2 22038.8 22040.6 22081.0 9120.3 9295.9 9305.0 9389.4 9474.8 8503.9 8579.1 8589.2 8620.3 8691.7 8856.8 8886.4 8962.2 9028.7 9189.6 2581.6 2610.4 2723.5 2739.6 2805.9 947.2 953.8 964.5 983.9 992.7 18156.4 18408.9 18837.6 18902.5 19182.5 1016.4 1017.3 1021.6 1026.2 1026.4 10338.5 10344.5 10370.4 10568.4 10749.0 490660.0 493119.0 498100.0 502185.0 503737.0 100967.0 107625.0 110047.0 112106.0 112477.4 1033.3 1079.8 1085.4 1132.3 1144.7 19388.8 19471.9 19705.9 19758.2 20109.6 54667.7 55161.7 55499.7 56353.4 57508.0 69153.7 69306.0 69591.7 70168.6 70276.3 13716.7 13884.0 13975.6 13985.7 14030.2 22030.7 22109.4 22195.3 22334.8 22601.8 17790.2 17969.9 17979.1 18127.3 18133.5 14920.3 14958.9 15020.9 15081.7 15500.1 18369.4 18430.4 18452.9 18465.4 18534.0 13826.4 13846.2 13858.4 13898.2 13929.4 17580.5 17786.5 17856.3 17884.7 17898.6 18:44:46 INFO - PID 20532 | 18:44:46 INFO - PID 20532 | [#3] mootools.html Cycles:180 Average:9318.01 Median:2766.03 stddev:6718.93 (242.9%) stddev-sans-first:6710.98 18:44:46 INFO - PID 20532 | Values: 1331.6 1377.0 1377.6 1393.5 1396.8 15089.5 15736.8 15764.4 15766.1 15795.8 15203.5 15427.5 15434.6 15440.1 15447.7 2677.4 2773.3 2774.8 2776.1 2791.9 2755.2 2759.3 2777.9 2781.4 2786.3 2630.3 2822.0 2823.7 2825.9 2826.0 2824.1 2832.2 2838.1 2840.3 2865.1 2608.1 2707.8 2772.8 2783.4 2784.9 15346.5 15397.0 15495.8 15505.5 15525.1 7874.2 8132.2 8143.1 8179.1 8179.5 5106.6 5186.9 5224.1 5325.3 5338.6 1866.4 1893.9 1898.3 1924.4 1936.2 26650.1 26705.0 26889.4 26934.5 26951.3 6487.2 6534.2 6654.5 6658.1 6764.5 16144.7 16168.9 16204.0 16220.8 16226.9 7701.6 7781.5 7834.5 7835.2 7861.0 7155.5 7217.5 7291.4 7411.3 7450.1 5144.8 5299.7 5302.8 5312.7 5335.1 1477.3 1477.5 1483.7 1495.3 1495.5 757.4 792.4 797.5 801.4 813.5 7555.9 7650.6 7683.1 7696.3 7699.3 696.6 705.5 709.2 709.9 718.8 5330.2 5424.9 5441.2 5531.9 5620.9 21665.9 22100.2 22228.1 22325.1 22597.4 20662.2 21822.3 21888.1 21967.1 21998.3 10510.8 11355.4 11477.8 11499.5 11504.4 8240.1 8408.5 8433.2 8492.2 8525.7 18396.6 18979.7 19012.8 19288.7 19329.8 18775.4 18784.9 18850.6 18927.2 19498.6 10711.1 11000.7 11013.2 11016.3 11066.3 12950.9 12963.8 13158.8 13170.2 13187.9 10885.4 11184.9 11212.4 11375.5 11401.0 10991.7 11036.5 11124.3 11207.0 11245.8 9631.7 9758.8 9764.9 9779.9 9791.7 7179.7 7182.5 7187.3 7187.9 7227.9 7339.9 7449.7 7526.9 7590.5 7598.5 18:44:46 INFO - PID 20532 | 18:44:46 INFO - PID 20532 | [#4] prototype.html Cycles:180 Average:5492.26 Median:6140.91 stddev:1535.12 (25.0%) stddev-sans-first:1492.76 18:44:46 INFO - PID 20532 | Values: 487.3 497.3 512.4 523.2 533.8 6142.7 6159.7 6172.8 6212.7 6312.2 6069.9 6111.9 6151.8 6152.8 6179.9 5134.0 5149.3 5162.9 5163.6 5196.9 4756.0 4885.3 5104.7 5118.3 5144.4 4919.3 4941.2 4946.3 4961.3 4999.5 4925.7 4927.7 4955.7 4970.4 4987.8 4333.6 4439.3 4448.9 4461.9 4490.4 6113.1 6118.9 6138.8 6139.1 6156.8 5682.3 5682.5 5697.9 5709.2 5717.5 6537.8 6548.3 6553.8 6565.9 6585.0 4837.1 4860.5 4885.4 4898.8 4920.4 6787.4 6789.6 6850.9 6897.9 6905.3 4963.3 5014.4 5014.8 5021.7 5064.5 5107.4 5140.5 5147.3 5147.9 5148.6 5612.2 5693.4 5701.5 5727.2 5730.0 6545.3 6616.5 6677.5 6680.4 6690.7 1667.7 1692.8 1723.5 1752.2 1757.0 1201.2 1215.2 1218.8 1221.9 1229.9 5654.3 5678.4 5700.6 5705.2 5719.5 6640.5 6670.8 6699.4 6700.7 6730.6 5660.5 5668.4 5685.5 5692.2 5708.6 3520.4 3526.2 3549.1 3569.6 3574.8 6346.4 6356.0 6374.2 6390.2 6391.1 6814.2 6823.9 6881.8 6899.5 6902.3 6338.1 6361.4 6375.7 6378.6 6384.5 6634.6 6689.3 6697.1 6728.2 6769.1 6640.4 6642.5 6651.7 6700.7 6739.5 6339.7 6382.9 6383.7 6390.5 6397.0 4984.0 4991.0 4997.9 5010.0 5020.5 6319.4 6482.5 6706.7 6707.0 6767.5 6303.5 6351.5 6424.5 6432.1 6577.5 6444.6 6471.4 6485.7 6523.6 6558.3 6300.2 6345.2 6374.3 6456.5 6463.1 6519.5 6520.6 6576.0 6610.6 6666.3 6272.8 6357.2 6386.6 6434.4 6453.5 18:44:46 INFO - PID 20532 | 18:44:46 INFO - PID 20532 | [#5] yui.html Cycles:180 Average:23594.93 Median:17377.48 stddev:34971.83 (201.2%) stddev-sans-first:35028.23 18:44:46 INFO - PID 20532 | Values: 847.3 856.9 862.0 862.4 875.2 14711.9 14805.3 14848.9 14869.3 15013.7 16443.6 16481.0 17166.1 17294.7 17300.3 957.6 959.8 960.2 963.0 963.3 941.2 953.0 958.8 962.8 970.3 1030.4 1034.1 1035.3 1035.3 1035.8 989.2 1023.5 1025.0 1025.2 1028.9 747.5 748.8 749.0 749.1 749.4 16478.3 16629.5 16727.0 16772.0 16798.0 21350.9 21498.7 21608.9 21614.2 21630.8 17454.7 17696.6 17778.4 17781.0 17826.3 3772.5 3784.6 3797.3 3801.8 3803.3 126028.0 126780.0 126886.0 129768.0 131220.0 16485.3 16771.2 16813.0 16936.6 16987.0 28699.1 29335.4 29458.1 29487.6 29628.4 7872.1 7961.4 7971.6 7979.0 7994.8 3790.4 3877.3 3921.6 3922.6 3922.7 5269.6 5330.3 5353.1 5383.5 5397.4 917.0 931.1 932.3 942.8 942.9 629.8 644.8 654.3 660.8 672.8 11179.6 11258.3 11421.5 11700.2 11734.6 576.9 587.9 588.4 592.6 601.9 5500.8 5572.5 5579.6 5640.6 5720.3 124255.0 124543.4 126636.4 127209.0 130351.7 108406.0 108997.5 109824.0 110566.0 111133.9 93270.0 95903.5 96172.5 100263.0 100594.5 10254.9 10257.6 10462.4 10506.1 10585.2 43122.8 45130.0 45189.8 45218.0 45500.1 58090.4 58326.0 58445.7 59274.7 59662.7 14875.9 14916.6 14953.6 15092.2 15321.3 15403.7 15652.0 15786.6 15862.3 15900.2 13726.9 13884.0 13939.3 13960.3 13995.7 14070.4 14191.9 14216.9 14301.5 14345.1 13412.5 13464.3 13519.8 13534.8 13539.4 10451.0 10691.2 10828.8 11022.8 11115.0 10644.6 10698.1 10839.6 10924.4 10997.1 18:44:46 INFO - PID 20532 | -------- Summary: end -------- 18:44:46 INFO - PID 20532 | 18:44:47 INFO - TEST-INFO | 20532: exit 0 18:44:47 INFO - TEST-OK | dromaeo_css | took 1108497ms 18:44:47 INFO - TEST-START | kraken 18:44:47 INFO - Initialising browser for kraken test... 18:44:47 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47979/getInfo.html -profile /tmp/tmpFH2S15/profile 18:44:47 INFO - TEST-INFO | started process 20914 (/builds/slave/test/build/application/firefox/firefox http://localhost:47979/getInfo.html) 18:44:52 INFO - TEST-INFO | 20914: exit 0 18:44:52 INFO - Browser initialized. 18:44:52 INFO - Running cycle 1/1 for kraken test... 18:44:52 INFO - Using env: {'DISPLAY': ':0', 18:44:52 INFO - 'HOME': '/home/cltbld', 18:44:52 INFO - 'JSGC_DISABLE_POISONING': '1', 18:44:52 INFO - 'LANG': 'en_US.UTF-8', 18:44:52 INFO - 'LANGUAGE': 'en_US:en', 18:44:52 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:44:52 INFO - 'LOGNAME': 'cltbld', 18:44:52 INFO - 'MAIL': '/var/mail/cltbld', 18:44:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:44:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:44:52 INFO - 'MOZ_CRASHREPORTER': '1', 18:44:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:52 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:44:52 INFO - 'MOZ_NO_REMOTE': '1', 18:44:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:44:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:52 INFO - 'NO_EM_RESTART': '1', 18:44:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:52 INFO - 'PWD': '/builds/slave/test', 18:44:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:44:52 INFO - 'RUST_BACKTRACE': 'full', 18:44:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:44:52 INFO - 'SHELL': '/bin/bash', 18:44:52 INFO - 'SHLVL': '1', 18:44:52 INFO - 'TERM': 'linux', 18:44:52 INFO - 'TMOUT': '86400', 18:44:52 INFO - 'USER': 'cltbld', 18:44:52 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1505179302.488033-1074970685', 18:44:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:52 INFO - '_': '/tools/buildbot/bin/python'} 18:44:52 INFO - TEST-INFO | started process 21117 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFH2S15/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:44:53 INFO - PID 21117 | 18:44:53 INFO - PID 21117 | (/builds/slave/test/build/application/firefox/firefox:21168): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:44:53 INFO - PID 21117 | 18:45:04 INFO - PID 21117 | RSS: Main: 168013824 18:45:04 INFO - PID 21117 | 18:45:06 INFO - PID 21117 | Cycle 1(1): loaded http://localhost:47979/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47979/tests/kraken/driver.html?testName=audio-beat-detection) 18:45:07 INFO - PID 21117 | RSS: Main: 172326912 18:45:07 INFO - PID 21117 | 18:45:10 INFO - PID 21117 | Cycle 1(1): loaded http://localhost:47979/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47979/tests/kraken/driver.html?testName=audio-dft) 18:45:11 INFO - PID 21117 | RSS: Main: 173428736 18:45:11 INFO - PID 21117 | 18:45:14 INFO - PID 21117 | Cycle 1(1): loaded http://localhost:47979/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47979/tests/kraken/driver.html?testName=audio-fft) 18:45:14 INFO - PID 21117 | RSS: Main: 169209856 18:45:14 INFO - PID 21117 | 18:45:16 INFO - PID 21117 | Cycle 1(1): loaded http://localhost:47979/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47979/tests/kraken/driver.html?testName=audio-oscillator) 18:45:17 INFO - PID 21117 | RSS: Main: 169484288 18:45:17 INFO - PID 21117 | 18:45:19 INFO - PID 21117 | Cycle 1(1): loaded http://localhost:47979/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47979/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:45:20 INFO - PID 21117 | RSS: Main: 170549248 18:45:20 INFO - PID 21117 | 18:45:23 INFO - PID 21117 | Cycle 1(1): loaded http://localhost:47979/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47979/tests/kraken/driver.html?testName=imaging-darkroom) 18:45:24 INFO - PID 21117 | RSS: Main: 172396544 18:45:24 INFO - PID 21117 | 18:45:27 INFO - PID 21117 | Cycle 1(1): loaded http://localhost:47979/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47979/tests/kraken/driver.html?testName=imaging-desaturate) 18:45:28 INFO - PID 21117 | RSS: Main: 172232704 18:45:28 INFO - PID 21117 | 18:45:31 INFO - PID 21117 | Cycle 1(1): loaded http://localhost:47979/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47979/tests/kraken/driver.html?testName=json-parse-financial) 18:45:32 INFO - PID 21117 | RSS: Main: 172593152 18:45:32 INFO - PID 21117 | 18:45:33 INFO - PID 21117 | Cycle 1(1): loaded http://localhost:47979/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47979/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:45:34 INFO - PID 21117 | RSS: Main: 172126208 18:45:34 INFO - PID 21117 | 18:45:35 INFO - PID 21117 | Cycle 1(1): loaded http://localhost:47979/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47979/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:45:36 INFO - PID 21117 | RSS: Main: 172515328 18:45:36 INFO - PID 21117 | 18:45:38 INFO - PID 21117 | Cycle 1(1): loaded http://localhost:47979/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47979/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:45:39 INFO - PID 21117 | RSS: Main: 172859392 18:45:39 INFO - PID 21117 | 18:45:41 INFO - PID 21117 | Cycle 1(1): loaded http://localhost:47979/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47979/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:45:42 INFO - PID 21117 | RSS: Main: 173600768 18:45:42 INFO - PID 21117 | 18:45:45 INFO - PID 21117 | Cycle 1(1): loaded http://localhost:47979/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47979/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:45:46 INFO - PID 21117 | RSS: Main: 173445120 18:45:46 INFO - PID 21117 | 18:45:48 INFO - PID 21117 | Cycle 1(1): loaded http://localhost:47979/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47979/tests/kraken/driver.html?testName=ai-astar) 18:45:48 INFO - PID 21117 | RSS: Main: 174751744 18:45:48 INFO - PID 21117 | 18:45:48 INFO - PID 21117 | __start_tp_report 18:45:48 INFO - PID 21117 | _x_x_mozilla_page_load 18:45:48 INFO - PID 21117 | _x_x_mozilla_page_load_details 18:45:48 INFO - PID 21117 | |i|pagename|runs| 18:45:48 INFO - PID 21117 | |0;ai-astar;108;107;107;106;107;107;108;108;109;108 18:45:48 INFO - PID 21117 | |1;audio-beat-detection;117;117;146;118;117;116;119;146;119;118 18:45:48 INFO - PID 21117 | |2;audio-dft;165;163;164;163;163;161;165;168;167;165 18:45:48 INFO - PID 21117 | |3;audio-fft;74;74;73;73;74;75;75;75;74;74 18:45:48 INFO - PID 21117 | |4;audio-oscillator;81;79;79;79;80;80;79;79;79;78 18:45:48 INFO - PID 21117 | |5;imaging-gaussian-blur;114;113;114;113;113;114;113;114;116;114 18:45:48 INFO - PID 21117 | |6;imaging-darkroom;148;150;148;147;148;149;149;149;149;151 18:45:48 INFO - PID 21117 | |7;imaging-desaturate;94;95;94;94;95;94;94;94;94;96 18:45:48 INFO - PID 21117 | |8;json-parse-financial;57;54;54;54;54;56;54;54;52;52 18:45:48 INFO - PID 21117 | |9;json-stringify-tinderbox;38;37;37;38;38;37;37;38;37;38 18:45:48 INFO - PID 21117 | |10;stanford-crypto-aes;76;77;74;74;75;74;78;75;79;77 18:45:48 INFO - PID 21117 | |11;stanford-crypto-ccm;122;118;112;113;117;120;113;117;121;111 18:45:48 INFO - PID 21117 | |12;stanford-crypto-pbkdf2;173;166;184;169;185;168;174;173;170;167 18:45:48 INFO - PID 21117 | |13;stanford-crypto-sha256-iterative;69;68;68;70;62;71;65;71;66;63 18:45:48 INFO - PID 21117 | __end_tp_report 18:45:48 INFO - PID 21117 | __start_cc_report 18:45:48 INFO - PID 21117 | _x_x_mozilla_cycle_collect,950 18:45:48 INFO - PID 21117 | __end_cc_report 18:45:48 INFO - PID 21117 | __startTimestamp1505180748265__endTimestamp 18:45:48 INFO - PID 21117 | 18:45:48 INFO - PID 21117 | ------- Summary: start ------- 18:45:48 INFO - PID 21117 | Number of tests: 14 18:45:48 INFO - PID 21117 | 18:45:48 INFO - PID 21117 | [#0] ai-astar Cycles:10 Average:107.50 Median:108.00 stddev:0.85 (0.8%) stddev-sans-first:0.88 18:45:48 INFO - PID 21117 | Values: 108.0 107.0 107.0 106.0 107.0 107.0 108.0 108.0 109.0 108.0 18:45:48 INFO - PID 21117 | 18:45:48 INFO - PID 21117 | [#1] audio-beat-detection Cycles:10 Average:123.30 Median:118.50 stddev:12.00 (10.1%) stddev-sans-first:12.51 18:45:48 INFO - PID 21117 | Values: 117.0 117.0 146.0 118.0 117.0 116.0 119.0 146.0 119.0 118.0 18:45:48 INFO - PID 21117 | 18:45:48 INFO - PID 21117 | [#2] audio-dft Cycles:10 Average:164.40 Median:165.00 stddev:2.07 (1.3%) stddev-sans-first:2.18 18:45:48 INFO - PID 21117 | Values: 165.0 163.0 164.0 163.0 163.0 161.0 165.0 168.0 167.0 165.0 18:45:48 INFO - PID 21117 | 18:45:48 INFO - PID 21117 | [#3] audio-fft Cycles:10 Average:74.10 Median:74.00 stddev:0.74 (1.0%) stddev-sans-first:0.78 18:45:48 INFO - PID 21117 | Values: 74.0 74.0 73.0 73.0 74.0 75.0 75.0 75.0 74.0 74.0 18:45:48 INFO - PID 21117 | 18:45:48 INFO - PID 21117 | [#4] audio-oscillator Cycles:10 Average:79.30 Median:79.00 stddev:0.82 (1.0%) stddev-sans-first:0.60 18:45:48 INFO - PID 21117 | Values: 81.0 79.0 79.0 79.0 80.0 80.0 79.0 79.0 79.0 78.0 18:45:48 INFO - PID 21117 | 18:45:48 INFO - PID 21117 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.92 (0.8%) stddev-sans-first:0.97 18:45:48 INFO - PID 21117 | Values: 114.0 113.0 114.0 113.0 113.0 114.0 113.0 114.0 116.0 114.0 18:45:48 INFO - PID 21117 | 18:45:48 INFO - PID 21117 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:149.00 stddev:1.14 (0.8%) stddev-sans-first:1.17 18:45:48 INFO - PID 21117 | Values: 148.0 150.0 148.0 147.0 148.0 149.0 149.0 149.0 149.0 151.0 18:45:48 INFO - PID 21117 | 18:45:48 INFO - PID 21117 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 18:45:48 INFO - PID 21117 | Values: 94.0 95.0 94.0 94.0 95.0 94.0 94.0 94.0 94.0 96.0 18:45:48 INFO - PID 21117 | 18:45:48 INFO - PID 21117 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:1.52 (2.8%) stddev-sans-first:1.20 18:45:48 INFO - PID 21117 | Values: 57.0 54.0 54.0 54.0 54.0 56.0 54.0 54.0 52.0 52.0 18:45:48 INFO - PID 21117 | 18:45:48 INFO - PID 21117 | [#9] json-stringify-tinderbox Cycles:10 Average:37.50 Median:38.00 stddev:0.53 (1.4%) stddev-sans-first:0.53 18:45:48 INFO - PID 21117 | Values: 38.0 37.0 37.0 38.0 38.0 37.0 37.0 38.0 37.0 38.0 18:45:48 INFO - PID 21117 | 18:45:48 INFO - PID 21117 | [#10] stanford-crypto-aes Cycles:10 Average:75.90 Median:76.50 stddev:1.79 (2.3%) stddev-sans-first:1.90 18:45:48 INFO - PID 21117 | Values: 76.0 77.0 74.0 74.0 75.0 74.0 78.0 75.0 79.0 77.0 18:45:48 INFO - PID 21117 | 18:45:48 INFO - PID 21117 | [#11] stanford-crypto-ccm Cycles:10 Average:116.40 Median:117.50 stddev:3.95 (3.4%) stddev-sans-first:3.63 18:45:48 INFO - PID 21117 | Values: 122.0 118.0 112.0 113.0 117.0 120.0 113.0 117.0 121.0 111.0 18:45:48 INFO - PID 21117 | 18:45:48 INFO - PID 21117 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.90 Median:173.00 stddev:6.67 (3.9%) stddev-sans-first:7.08 18:45:48 INFO - PID 21117 | Values: 173.0 166.0 184.0 169.0 185.0 168.0 174.0 173.0 170.0 167.0 18:45:48 INFO - PID 21117 | 18:45:48 INFO - PID 21117 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.50 stddev:3.20 (4.7%) stddev-sans-first:3.33 18:45:48 INFO - PID 21117 | Values: 69.0 68.0 68.0 70.0 62.0 71.0 65.0 71.0 66.0 63.0 18:45:48 INFO - PID 21117 | -------- Summary: end -------- 18:45:48 INFO - PID 21117 | 18:45:48 INFO - PID 21117 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:45:48 INFO - TEST-INFO | 21117: exit 0 18:45:48 INFO - TEST-OK | kraken | took 61496ms 18:45:48 INFO - SUITE-END | took 1169s 18:45:49 INFO - Completed test suite (00:19:30) 18:45:49 INFO - javascript benchmark 18:45:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8353.150720163656, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3069.278719500918, 3084.7532197424207, 3117.0804612639276, 3128.6558478567354, 3149.669284725105, 6045.214122164112, 6135.20242368492, 6178.227721534809, 6248.0, 6306.779262725806, 6050.546209034329, 6061.787837425691, 6083.057126145443, 6207.875842483148, 6242.438180563749, 5699.629524080942, 5759.65442073477, 5796.50729687977, 5809.854753631151, 5853.239078919734, 5457.426970168115, 5706.1440783882545, 5763.049096899007, 5798.072308430652, 5809.0, 5682.204491371212, 5700.486956173922, 5750.108733146348, 5763.740631671585, 5768.278965129359, 5608.4391560844, 5706.372299047143, 5709.800156994527, 5758.539316854642, 5760.135979603051, 5681.289838770153, 5712.114622233519, 5713.257276554063, 5719.59962802604, 5753.88492230153, 6213.37866213375, 6215.782447614357, 6224.782132625336, 6288.654124023177, 6298.4646305064125, 6638.73445062197, 6645.102911106987, 6725.831854203635, 6743.460523158135, 6749.662516874137, 6562.278149403562, 6663.900041499382, 6671.132752742161, 6671.366220209073, 6700.664966751643, 6177.289611694668, 6231.688415579203, 6290.748370065237, 6296.590721603127, 6304.842378940517, 6809.489288303397, 6878.071460352789, 6913.688884000232, 6969.686364113627, 6977.372036516738, 6371.299157092716, 6463.903041454382, 6602.735890564323, 6667.466602671775, 6678.766243181465, 6629.701663425158, 6659.567128136656, 6669.699863506154, 6671.666416679147, 6673.799786006428, 6467.450266727286, 6591.670416479157, 6615.966920165464, 6638.20341559016, 6643.5017373697165, 6578.914479110988, 6582.506312026617, 6668.399844014062, 6672.666366681647, 6678.065070890079, 5146.922796158061, 5159.2003239497935, 5177.404598471148, 5178.896422071537, 5272.78908843642, 4594.1730488512385, 4617.468991066004, 4656.185167595725, 4672.6261899047995, 4672.883177920579, 5677.858053548612, 5733.455321744427, 5737.196792449061, 5738.196652468658, 5739.540836733051, 6532.8366790830605, 6621.635810030398, 6629.237637671633, 6703.698333575001, 6715.597064176068, 5587.916181257285, 5676.574256930664, 5692.174634677948, 5696.031674615326, 5697.686627235542, 5711.685857277889, 5774.740136693859, 5792.275965504312, 5798.275215598051, 5823.359430462646, 6145.600535965148, 6227.06594010902, 6326.715297811609, 6366.427021567988, 6393.456556192683, 6543.770968016097, 6545.8690826184165, 6632.204135503771, 6632.801015969529, 6634.270230274569, 6215.409536094064, 6262.937370626418, 6318.9052164216655, 6325.304216536086, 6344.968275158594, 6396.072569477493, 6511.73953041873, 6516.218053833571, 6548.901766473602, 6636.004599310142, 6730.226024007302, 6742.561733487308, 6743.527953043436, 6745.763898263537, 6754.088198093391, 6421.133147025092, 6444.420002199825, 6504.6747662615735, 6523.478121750154, 6526.706298216497, 6407.199100112486, 6445.581037232565, 6534.934650653623, 6539.803805885831, 6543.051257567721, 6190.442860142609, 6302.810915672354, 6371.649559274284, 6425.8714825704155, 6438.130852335063, 6451.032345148077, 6497.603015351722, 6506.804795856132, 6513.316101809241, 6531.738730450728, 6124.173236613178, 6240.188775459162, 6267.843303917439, 6321.273053598895, 6322.841928951813], "value": 6049.859594532973, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [824.7649419915324, 846.8010017645852, 847.8812966184735, 854.1757204297851, 861.3539845116163, 38283.80858095709, 41939.16121677565, 42206.78896605513, 42362.15275694492, 42781.14437711244, 39245.41131883017, 39610.60389396105, 39625.20749585014, 39637.60362396383, 39645.80177099111, 6974.755883544077, 7676.4242681798805, 7846.372290216769, 7856.017997750281, 7864.567448790314, 7638.236176382373, 7758.7672369829, 7768.378529717609, 7768.883466748003, 7772.455928085036, 7728.343090837286, 7734.071911370615, 7791.337736292422, 7804.414668899855, 7806.570638614874, 7720.691172353099, 7772.028496437945, 7779.7666070017995, 7780.688772449095, 7781.105172905131, 7570.242975702441, 7728.884066739003, 7731.265529774662, 7733.226677332278, 7735.265149810759, 38100.42849357262, 38346.233075338336, 38347.808260958795, 38453.230935381136, 38470.80764596159, 3465.8093804840723, 3516.7362447816517, 3518.0, 3518.190816112283, 3520.806355650438, 31409.685903141133, 31529.842350788327, 31551.211219719462, 31553.21116972071, 31572.210694732814, 5242.9213561796605, 5265.394479634852, 5274.5780337572905, 5280.630355875091, 5297.073012222845, 184343.07828460907, 184841.0, 184984.0, 185196.0, 185416.0, 42363.788181058895, 43390.783046084565, 47779.0, 47837.521624784, 48263.27605085856, 51979.0, 52412.0, 52447.73776131095, 52451.0, 52494.21258681123, 14596.927015364856, 14752.18862962526, 14755.114693118447, 14761.55715328542, 14765.630859228391, 11895.88104118965, 11898.881011189951, 11913.16607837443, 11915.0, 11922.76154476923, 14325.212113333659, 14372.568822935329, 14401.351939162763, 14406.927965360106, 14546.854531454761, 5558.499735023867, 5559.9444005560235, 5765.18396705045, 5777.480026797524, 5863.149843272701, 995.4425521707722, 1006.5470538257813, 1007.2294694558668, 1014.5485259059795, 1024.4672770159475, 22759.88620056889, 22970.65544016841, 22986.42533936665, 22994.42513937165, 23147.53704925892, 1096.7203363142348, 1097.8133717268083, 1098.5056724474018, 1112.2936935046305, 1123.1351859068473, 12237.93881030607, 12312.569060082855, 12338.136330456962, 12407.0, 12415.689607759878, 229720.0, 231414.0, 231594.0, 231870.0, 232561.0, 52258.0, 52270.47729522732, 52307.0, 52411.21383179256, 52749.0, 1421.6161636358124, 1461.7661174212076, 1467.5303902751018, 1475.7196132734746, 1479.7632378819337, 21921.780782191974, 22290.554188916456, 22520.211792587183, 22580.774192257868, 22775.544489109794, 37279.0, 37368.010559155744, 37370.626293737805, 37371.81314093412, 37391.62608373881, 193063.0, 193236.0, 193833.0, 194495.0, 194668.0, 33437.16407089842, 35782.28435431329, 35889.102772429855, 36160.63839361573, 36336.27327453489, 45007.0, 45760.313595296764, 46473.30290045584, 46513.0, 46563.76718116388, 33516.162095947795, 33702.49446258259, 33709.49435758417, 34175.316493670485, 34459.31081378408, 33287.83356083301, 33522.0, 33786.831065845494, 33917.321653567284, 34341.656583434844, 37464.25071498609, 37586.81206593949, 37780.43329350117, 37831.62168378281, 37897.242055159295, 32778.0, 32945.505817412275, 32996.50505242375, 33091.83454082714, 33194.0, 23884.0, 24001.879990599937, 24232.87883560571, 24564.50870982606, 24644.137455188982], "value": 17171.119783036444, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.6310771782905, 558.4697927678162, 563.4844117632364, 563.8731285460769, 568.1846550200462, 21432.785672143273, 22138.667919981213, 22148.66776998346, 22150.556988860215, 22155.66766498502, 22987.310380688563, 24182.637260441108, 24418.267451976426, 24535.0, 24544.38639034025, 6184.25788905332, 6312.589681670676, 6341.873162536746, 6347.174867267248, 6348.460380867632, 6312.242530896299, 6353.650549219791, 6404.0, 6407.295197528291, 6413.775517856876, 6204.720787564548, 6454.3545645435315, 6477.870442591146, 6478.352164783531, 6481.708323125447, 6178.104174894637, 6193.907091393633, 6222.844428889269, 6222.844428889269, 6229.4704950079295, 5764.481196692276, 5768.826935191951, 6026.0960855871535, 6031.668258245794, 6031.849203769941, 28155.0, 28684.569731454045, 28694.0, 28697.426051478855, 28751.424971500663, 2071.337172104927, 2093.790620937894, 2093.8953052347474, 2096.706461095448, 2097.035363732685, 18351.54121146969, 18757.531061723428, 19059.90470047655, 19067.523311917175, 19127.33054343091, 2432.598621227495, 2460.9261722148362, 2489.5020995800733, 2498.0507407185337, 2500.7874330681916, 116672.0, 122178.38910805389, 135993.0, 141873.0, 142428.99999999895, 14121.929390352983, 14230.078939343262, 14295.356708948122, 14371.456663063294, 14690.739685491302, 21826.581272217256, 22007.22974695878, 22038.779612203994, 22040.559188816133, 22081.0, 9120.270378369714, 9295.90704092964, 9304.953475232716, 9389.389689670286, 9474.763130921781, 8503.872441913376, 8579.099194584478, 8589.184027517376, 8620.267277281502, 8691.652333906699, 8856.822863542822, 8886.377953543319, 8962.193402593797, 9028.729138125867, 9189.632414703337, 2581.625664278669, 2610.399608090112, 2723.5233834079354, 2739.643846299968, 2805.8877644894383, 947.1759569174862, 953.7663272498144, 964.5098323090841, 983.8622592836865, 992.6624947517879, 18156.364527241483, 18408.907955460138, 18837.62324753497, 18902.52743681419, 19182.520436988907, 1016.3952448293254, 1017.3133135133755, 1021.6015753856002, 1026.1790567545934, 1026.373911913732, 10338.53476593555, 10344.5344959477, 10370.429626370622, 10568.418736969386, 10749.0, 490660.0, 493119.0, 498100.0, 502185.0, 503737.0, 100967.0, 107625.0, 110047.0, 112106.0, 112477.43761281141, 1033.2767063055892, 1079.8218293981406, 1085.3976043295888, 1132.3432409202808, 1144.729350421032, 19388.8061119387, 19471.902640486707, 19705.901470492558, 19758.20967161355, 20109.597808043465, 54667.72666136803, 55161.72419137879, 55499.72250138724, 56353.436465636456, 57508.0, 69153.65423172852, 69306.0, 69591.65204173946, 70168.64915675593, 70276.29723702902, 13716.65708357299, 13884.0, 13975.580732578039, 13985.720285594027, 14030.228337441538, 22030.669539956594, 22109.447263818533, 22195.33413997583, 22334.77665223327, 22601.773982260624, 17790.199441024924, 17969.910150449163, 17979.101044947438, 18127.274909003492, 18133.546661333043, 14920.253987300808, 14958.850411495745, 15020.924895375454, 15081.698366032399, 15500.147491887603, 18369.357072502396, 18430.447086587425, 18452.90773546124, 18465.446036618923, 18534.0, 13826.446942122202, 13846.169229846242, 13858.4456621734, 13898.16611003343, 13929.442822287401, 17580.472585822445, 17786.46640600784, 17856.285748569913, 17884.731729023813, 17898.5525361867], "value": 11610.041164839555, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.6071758831145, 1376.9862301376984, 1377.5867239828053, 1393.5262010916292, 1396.7974643676662, 15089.471868484605, 15736.763948540767, 15764.369425222978, 15766.054036757789, 15795.763063554055, 15203.54389368318, 15427.5371738848, 15434.61413464664, 15440.073595584272, 15447.69104617907, 2677.3708178578017, 2773.2928103333725, 2774.833509989397, 2776.0561409120896, 2791.860406979653, 2755.1734479656125, 2759.2550011496883, 2777.8888844446196, 2781.429806889583, 2786.2755683522328, 2630.2635262126637, 2822.0, 2823.731745484176, 2825.858707064649, 2826.0, 2824.0962891874506, 2832.150354893534, 2838.0918106206022, 2840.2899275181203, 2865.0688526228955, 2608.126277696974, 2707.837529748221, 2772.8059035867495, 2783.4293969736154, 2784.902528411516, 15346.539603811903, 15397.0, 15495.845041549666, 15505.534833954887, 15525.146116963562, 7874.173211812792, 8132.227438393344, 8143.144969778147, 8179.059408168077, 8179.509229446193, 5106.591472682177, 5186.870328241786, 5224.08578498765, 5325.307709997675, 5338.599605029638, 1866.412080194748, 1893.943181704551, 1898.3166060218311, 1924.3745782620704, 1936.2351871011012, 26650.06724764624, 26705.0, 26889.445502677216, 26934.46131077387, 26951.32621684454, 6487.22153341602, 6534.248561415403, 6654.534182607171, 6658.1344425224415, 6764.511807402364, 16144.677106457804, 16168.919155404148, 16204.0, 16220.756688649679, 16226.918865405598, 7701.614919254015, 7781.455298129076, 7834.4907581007055, 7835.177306382861, 7861.0, 7155.463340249502, 7217.494775365779, 7291.416686665054, 7411.295926886937, 7450.0687414073245, 5144.845654630367, 5299.735013249399, 5302.840914772563, 5312.654677446031, 5335.093034184198, 1477.2761346939922, 1477.5271912987741, 1483.6661751105914, 1495.297210311152, 1495.4541592318726, 757.4054367321626, 792.4215322814377, 797.4736673795268, 801.4309840013656, 813.515958004986, 7555.9244407555225, 7650.617469126521, 7683.0780306363595, 7696.345810606049, 7699.345555627834, 696.606417374182, 705.5449235243342, 709.170270783185, 709.9396551293198, 718.7520305494596, 5330.173823057432, 5424.887897980904, 5441.183822426588, 5531.861703457365, 5620.9437905621235, 21665.891670541547, 22100.2264920727, 22228.11087556512, 22325.106995720314, 22597.43506412353, 20662.17351305961, 21822.345329640135, 21888.12447502114, 21967.121315147215, 21998.34004979853, 10510.789784204273, 11355.37545435009, 11477.770444591062, 11499.482523286471, 11504.367259800623, 8240.13478584764, 8408.495490270603, 8433.156684331763, 8492.193241642035, 8525.658973640984, 18396.632067358845, 18979.71530427072, 19012.809871901656, 19288.71066933969, 19329.8067019328, 18775.436736897915, 18784.906075469535, 18850.622987540446, 18927.242910283985, 19498.61002779965, 10711.08955738771, 11000.724981875517, 11013.174011949297, 11016.283941543774, 11066.336019838835, 12950.935245323713, 12963.805542916674, 13158.802617960548, 13170.209787412403, 13187.934060329637, 10885.401302928414, 11184.94407527957, 11212.439378030902, 11375.54497820078, 11401.0, 10991.680998280257, 11036.5033573491, 11124.276922000368, 11207.0, 11245.831312530483, 9631.662891799033, 9758.75603109928, 9764.853527196956, 9779.853302200616, 9791.657291994747, 7179.748708795167, 7182.497225194186, 7187.31720486553, 7187.892181617175, 7227.927720722936, 7339.9266007341375, 7449.739259125905, 7526.887096693664, 7590.468667193243, 7598.506097103671], "value": 6600.573291789978, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.31810343157196, 497.26009686626674, 512.374902618805, 523.1733860500409, 533.7731464127745, 6142.679323945351, 6159.692015399229, 6172.84567885802, 6212.689365531723, 6312.210973628296, 6069.908951365733, 6111.938880611193, 6151.784687535937, 6152.784652537162, 6179.907301390483, 5133.974330128353, 5149.2791009258735, 5162.92255616166, 5163.586913046947, 5196.8700782480555, 4755.952440475603, 4885.291632713254, 5104.69371837691, 5118.283440318359, 5144.382674079097, 4919.286703428001, 4941.2094064949615, 4946.332245146913, 4961.280614310923, 4999.450060493361, 4925.655204135712, 4927.655064145493, 4955.677880937764, 4970.428400733907, 4987.825426110068, 4333.631641310492, 4439.289713645801, 4448.888777780549, 4461.929137007128, 4490.39379683745, 6113.144159817629, 6118.908216376758, 6138.785142519991, 6139.140520327158, 6156.815295541141, 5682.346530149044, 5682.460166284238, 5697.857553561153, 5709.172170035322, 5717.48542631161, 6537.771178008795, 6548.312427195124, 6553.836154096138, 6565.868682626321, 6584.967075164642, 4837.0809546186465, 4860.513948605147, 4885.36490256268, 4898.755062246909, 4920.351682186471, 6787.355201255922, 6789.574189420227, 6850.93149068508, 6897.931020689779, 6905.309469053054, 4963.280324352949, 5014.448410674823, 5014.824481143142, 5021.748912554358, 5064.49355064498, 5107.361579802525, 5140.537351638371, 5147.279380886679, 5147.897042059138, 5148.56237219833, 5612.214289999403, 5693.4021927696995, 5701.543876489871, 5727.198192253088, 5729.9713501432225, 6545.312742162099, 6616.503762217853, 6677.465802735769, 6680.432163266177, 6690.732370705117, 1667.65813008333, 1692.8053273873415, 1723.4657256250603, 1752.1589636974325, 1756.9560760980821, 1201.1651901928076, 1215.1797536662893, 1218.7988981818078, 1221.8533775946944, 1229.8524177098805, 5654.3497497788285, 5678.381661226531, 5700.631848356447, 5705.1727499512335, 5719.5138413235345, 6640.468762498988, 6670.833229169212, 6699.39705426514, 6700.66496675174, 6730.596164230162, 5660.518855897295, 5668.404817494186, 5685.493344263773, 5692.231548740867, 5708.571857110734, 3520.3663340598937, 3526.153723106436, 3549.1304630365726, 3569.6430356964097, 3574.8391322390553, 6346.399744266004, 6356.0, 6374.203224596926, 6390.233172019387, 6391.073294372383, 6814.216365117975, 6823.931760682429, 6881.793546193622, 6899.48253880961, 6902.309769023058, 6338.144350512622, 6361.395667411589, 6375.681215939185, 6378.553501254868, 6384.457321127663, 6634.601923884582, 6689.331066893272, 6697.129373181455, 6728.158980127484, 6769.086173366532, 6640.402363787089, 6642.4686025118845, 6651.700673469609, 6700.698468569024, 6739.528233023641, 6339.714712838026, 6382.90425643625, 6383.733774509982, 6390.456811171102, 6397.0, 4983.975080124576, 4990.950090499048, 4997.85006449807, 5010.0, 5020.472850350807, 6319.431251187415, 6482.54622176462, 6706.698198580978, 6706.966465167643, 6767.458603311625, 6303.527235457267, 6351.460125889351, 6424.51816113784, 6432.099506069248, 6577.539572229898, 6444.613323200623, 6471.449926756279, 6485.675716214076, 6523.608583484816, 6558.344165583403, 6300.21247344082, 6345.15990362808, 6374.330695276929, 6456.451201647913, 6463.127477790438, 6519.511036672364, 6520.608763474017, 6575.967120164369, 6610.644817812241, 6666.333366663295, 6272.8118156455375, 6357.237131544244, 6386.648734319657, 6434.420902118832, 6453.483721302191], "value": 5038.707089438876, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [847.2882778466087, 856.8671855862343, 861.974140775777, 862.3877047296419, 875.199192738644, 14711.926440367797, 14805.3337599808, 14848.851511484881, 14869.330880110394, 15013.699726005474, 16443.58891027725, 16481.0, 17166.055866927312, 17294.654106917853, 17300.30798768051, 957.6169532187129, 959.8464245720686, 960.183843732825, 962.9759256018621, 963.3160456076159, 941.1764705882352, 953.0183910572088, 958.8034452937131, 962.8026254617787, 970.2674480767012, 1030.3560274828233, 1034.053840735726, 1035.2597892506856, 1035.2959987208694, 1035.8239099353127, 989.2432289298692, 1023.518946095334, 1024.9948750256237, 1025.1696126137815, 1028.9022542858415, 747.5402627384174, 748.7753673897863, 749.0187853911393, 749.0674110732174, 749.4004796163048, 16478.2584783685, 16629.50111496657, 16727.0, 16772.0, 16798.0, 21350.893245533673, 21498.677519837216, 21608.891955540123, 21614.24350147753, 21630.783692163033, 17454.650906981788, 17696.557586060324, 17778.37775677858, 17781.0, 17826.286948522174, 3772.5284339457567, 3784.6404591563755, 3797.3164830330807, 3801.7718936863876, 3803.2583646189005, 126028.0, 126780.0, 126886.0, 129768.0, 131219.99999999904, 16485.258163382678, 16771.161441927856, 16813.0, 16936.57658558521, 16987.0, 28699.13902582926, 29335.413291734047, 29458.116256512338, 29487.55768663472, 29628.407431851672, 7872.094709108412, 7961.402894782915, 7971.601419929097, 7979.042514898133, 7994.840103197904, 3790.4124860646093, 3877.2827027000094, 3921.647051765298, 3922.646961773397, 3922.725409221327, 5269.631125821233, 5330.333708286465, 5353.116735738559, 5383.515483606494, 5397.379301380392, 916.9724908252763, 931.0503286647717, 932.3473568502076, 942.8491441369348, 942.8679984802134, 629.8047605242298, 644.7936660268763, 654.2933631677777, 660.8083655739878, 672.831792051987, 11179.552817887356, 11258.268212566158, 11421.543138274375, 11700.180987330805, 11734.589289374831, 576.8615532272308, 587.8883012227664, 588.358689028952, 592.599995003371, 601.9006863867501, 5500.7524661390335, 5572.526335261548, 5579.553635709133, 5640.605157638925, 5720.342160651495, 124255.0, 124543.37728311482, 126636.36681816717, 127209.0, 130351.69648303396, 108406.0, 108997.45501272443, 109824.0, 110566.0, 111133.88866111236, 93270.0, 95903.52048239714, 96172.51913740527, 100263.0, 100594.4970275144, 10254.948725256625, 10257.640982565872, 10462.372257664565, 10506.054455099078, 10585.153187745116, 43122.78438607787, 45130.0, 45189.774051129534, 45218.0, 45500.089998200514, 58090.4190958085, 58326.0, 58445.70777146087, 59274.70362648159, 59662.70168649129, 14875.925620371829, 14916.62708432298, 14953.626159346104, 15092.169930653481, 15321.310541025903, 15403.691926161639, 15652.0, 15786.60533486626, 15862.286197121388, 15900.204989750697, 13726.931365343109, 13884.0, 13939.303034848419, 13960.301984900918, 13995.720085598028, 14070.366833492313, 14191.929040354733, 14216.928915355356, 14301.499447519287, 14345.067570607876, 13412.463501459832, 13464.25946572948, 13519.797203041766, 13534.796978045533, 13539.390727417487, 10451.0, 10691.198160138176, 10828.783424331627, 11022.834657479983, 11115.0, 10644.574217031543, 10698.144148467949, 10839.620613278497, 10924.399158046384, 10997.065249453566], "value": 8468.327813546712, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1429.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 107.0, 107.0, 106.0, 107.0, 107.0, 108.0, 108.0, 109.0, 108.0], "value": 107.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 146.0, 118.0, 117.0, 116.0, 119.0, 146.0, 119.0, 118.0], "value": 123.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 164.0, 163.0, 163.0, 161.0, 165.0, 168.0, 167.0, 165.0], "value": 164.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 73.0, 74.0, 75.0, 75.0, 75.0, 74.0, 74.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 79.0, 79.0, 80.0, 80.0, 79.0, 79.0, 79.0, 78.0], "value": 79.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 113.0, 113.0, 114.0, 113.0, 114.0, 116.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 150.0, 148.0, 147.0, 148.0, 149.0, 149.0, 149.0, 149.0, 151.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 96.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 54.0, 54.0, 54.0, 56.0, 54.0, 54.0, 52.0, 52.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 37.0, 37.0, 38.0, 38.0, 37.0, 37.0, 38.0, 37.0, 38.0], "value": 37.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 74.0, 74.0, 75.0, 74.0, 78.0, 75.0, 79.0, 77.0], "value": 75.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 118.0, 112.0, 113.0, 117.0, 120.0, 113.0, 117.0, 121.0, 111.0], "value": 116.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 166.0, 184.0, 169.0, 185.0, 168.0, 174.0, 173.0, 170.0, 167.0], "value": 172.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 70.0, 62.0, 71.0, 65.0, 71.0, 66.0, 63.0], "value": 67.3, "unit": "score"}]}]} 18:45:49 INFO - Return code: 0 18:45:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:45:49 INFO - # TBPL SUCCESS # 18:45:49 INFO - Running post-action listener: _package_coverage_data 18:45:49 INFO - Running post-action listener: _resource_record_post_action 18:45:49 INFO - [mozharness: 2017-09-12 01:45:49.534607Z] Finished run-tests step (success) 18:45:49 INFO - Running post-run listener: _resource_record_post_run 18:45:49 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 8192; Write bytes: 307625984; Read time: 212; Write time: 179812 18:45:49 INFO - TinderboxPrint: CPU usage
13.1% 18:45:49 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 212 18:45:49 INFO - TinderboxPrint: I/O write bytes / time
307,625,984 / 179,812 18:45:49 INFO - TinderboxPrint: CPU idle
8,223.7 (86.9%) 18:45:49 INFO - TinderboxPrint: CPU user
1,191.2 (12.6%) 18:45:49 INFO - TinderboxPrint: Swap in / out
0 / 0 18:45:49 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 12578816; Read time: 0; Write time: 1088 18:45:49 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:45:49 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 8192; Write bytes: 295047168; Read time: 212; Write time: 178724 18:45:50 INFO - Running post-run listener: _upload_blobber_files 18:45:50 INFO - Blob upload gear active. 18:45:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:45:50 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 18:45:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:45:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:45:50 INFO - (blobuploader) - INFO - Open directory for files ... 18:45:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:45:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:45:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:45:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:45:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:45:51 INFO - (blobuploader) - INFO - Done attempting. 18:45:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:45:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:45:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:45:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:45:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:45:58 INFO - (blobuploader) - INFO - Done attempting. 18:45:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:45:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:45:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:46:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:46:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:46:00 INFO - (blobuploader) - INFO - Done attempting. 18:46:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:46:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:46:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:46:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:46:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:46:01 INFO - (blobuploader) - INFO - Done attempting. 18:46:01 INFO - (blobuploader) - INFO - Iteration through files over. 18:46:01 INFO - Return code: 0 18:46:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:46:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:46:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/817de644a4ec82adbe3194b06ab634aa620a404b09adfc032b9e76a1d02cf00dc2b373d4a6180249d5797b18d045a6f8266e77fd6e7a428a96cf9c5421e9dc73", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13617ffdc9191e43b2f4d66db75f2e81aac63d21620354f4f4f0ef9c8ec9f3457ae3610e111519a9ec27971583b012c72d457a93d17e527b2fa8c39ad7f8ab46", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f96cecef780876c3b7b00307616cd788a300756686994752f1ef3be0b62adff4930393de245576622b9baae18264247e12491df3d78d6eb95667b36c952f6c38", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6ae788cd90fbfef9c279b69740cb4d5d439e8e8f05c8d70463280cfbec7443788efdbb26066c4a45ee1ce24ddc4a8647c86f7086e72b25a7d2fe41ac5435ddf7"} 18:46:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:46:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:46:01 INFO - Contents: 18:46:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/817de644a4ec82adbe3194b06ab634aa620a404b09adfc032b9e76a1d02cf00dc2b373d4a6180249d5797b18d045a6f8266e77fd6e7a428a96cf9c5421e9dc73", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13617ffdc9191e43b2f4d66db75f2e81aac63d21620354f4f4f0ef9c8ec9f3457ae3610e111519a9ec27971583b012c72d457a93d17e527b2fa8c39ad7f8ab46", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f96cecef780876c3b7b00307616cd788a300756686994752f1ef3be0b62adff4930393de245576622b9baae18264247e12491df3d78d6eb95667b36c952f6c38", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6ae788cd90fbfef9c279b69740cb4d5d439e8e8f05c8d70463280cfbec7443788efdbb26066c4a45ee1ce24ddc4a8647c86f7086e72b25a7d2fe41ac5435ddf7"} 18:46:01 INFO - Running post-run listener: copy_logs_to_upload_dir 18:46:01 INFO - Copying logs to upload dir... 18:46:01 INFO - mkdir: /builds/slave/test/build/upload/logs 18:46:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.083097 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-11 18:46:01.523682) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-11 18:46:01.527232) ========= 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-1505179302.488033-1074970685 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-Non-PGO/sha512/817de644a4ec82adbe3194b06ab634aa620a404b09adfc032b9e76a1d02cf00dc2b373d4a6180249d5797b18d045a6f8266e77fd6e7a428a96cf9c5421e9dc73", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13617ffdc9191e43b2f4d66db75f2e81aac63d21620354f4f4f0ef9c8ec9f3457ae3610e111519a9ec27971583b012c72d457a93d17e527b2fa8c39ad7f8ab46", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f96cecef780876c3b7b00307616cd788a300756686994752f1ef3be0b62adff4930393de245576622b9baae18264247e12491df3d78d6eb95667b36c952f6c38", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6ae788cd90fbfef9c279b69740cb4d5d439e8e8f05c8d70463280cfbec7443788efdbb26066c4a45ee1ce24ddc4a8647c86f7086e72b25a7d2fe41ac5435ddf7"} build_url:https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011499 build_url: 'https://queue.taskcluster.net/v1/task/St3prP9UQSqX25PaZr-mYg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/817de644a4ec82adbe3194b06ab634aa620a404b09adfc032b9e76a1d02cf00dc2b373d4a6180249d5797b18d045a6f8266e77fd6e7a428a96cf9c5421e9dc73", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13617ffdc9191e43b2f4d66db75f2e81aac63d21620354f4f4f0ef9c8ec9f3457ae3610e111519a9ec27971583b012c72d457a93d17e527b2fa8c39ad7f8ab46", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f96cecef780876c3b7b00307616cd788a300756686994752f1ef3be0b62adff4930393de245576622b9baae18264247e12491df3d78d6eb95667b36c952f6c38", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6ae788cd90fbfef9c279b69740cb4d5d439e8e8f05c8d70463280cfbec7443788efdbb26066c4a45ee1ce24ddc4a8647c86f7086e72b25a7d2fe41ac5435ddf7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-11 18:46:01.559728) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 18:46:01.560045) ========= 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-1505179302.488033-1074970685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004564 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 18:46:01.643614) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-11 18:46:01.643928) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-11 18:46:01.644248) ========= ========= Total master_lag: 0.20 =========