builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-056 starttime: 1502194240.14 results: success (0) revision: 8c7788eda00e4cb4445638bdb7500009cd4cbe59 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-08 05:10:40.142242) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-08 05:10:40.142703) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-08 05:10:40.142978) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-08 05:10:40.156777) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:10:40.157087) ========= 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=52faae308a556c440401f8e5000001e8-1502194240.471162-214435039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-08 05:10:41-- 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% 33.1M=0s 2017-08-08 05:10:41 (33.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.174912 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:10:40.343500) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:10:40.343890) ========= 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=52faae308a556c440401f8e5000001e8-1502194240.471162-214435039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016248 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:10:40.393935) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:10:40.394306) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8c7788eda00e4cb4445638bdb7500009cd4cbe59 --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 8c7788eda00e4cb4445638bdb7500009cd4cbe59 --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=52faae308a556c440401f8e5000001e8-1502194240.471162-214435039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-08 05:10:41,296 truncating revision to first 12 chars 2017-08-08 05:10:41,296 Setting DEBUG logging. 2017-08-08 05:10:41,296 attempt 1/10 2017-08-08 05:10:41,296 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8c7788eda00e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-08 05:10:41,618 unpacking tar archive at: mozilla-central-8c7788eda00e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.660325 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:10:41.067164) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-08 05:10:41.067504) ========= script_repo_revision: 8c7788eda00e4cb4445638bdb7500009cd4cbe59 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-08 05:10:41.067880) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-08 05:10:41.068161) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-08 05:10:41.077050) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-08 05:10:41.077423) ========= /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=52faae308a556c440401f8e5000001e8-1502194240.471162-214435039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:10:42 INFO - MultiFileLogger online at 20170808 05:10:42 in /builds/slave/test 05:10:42 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 05:10:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:10:42 INFO - {'append_to_log': False, 05:10:42 INFO - 'base_work_dir': '/builds/slave/test', 05:10:42 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 05:10:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:10:42 INFO - 'branch': 'Firefox-Non-PGO', 05:10:42 INFO - 'buildbot_json_path': 'buildprops.json', 05:10:42 INFO - 'code_coverage': False, 05:10:42 INFO - 'config_files': ('talos/linux_config.py',), 05:10:42 INFO - 'default_actions': ('clobber', 05:10:42 INFO - 'read-buildbot-config', 05:10:42 INFO - 'download-and-extract', 05:10:42 INFO - 'populate-webroot', 05:10:42 INFO - 'create-virtualenv', 05:10:42 INFO - 'install', 05:10:42 INFO - 'setup-mitmproxy', 05:10:42 INFO - 'run-tests'), 05:10:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:10:42 INFO - 'disable_ccov_upload': False, 05:10:42 INFO - 'download_minidump_stackwalk': True, 05:10:42 INFO - 'download_symbols': 'ondemand', 05:10:42 INFO - 'enable_stylo': False, 05:10:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:10:42 INFO - 'tooltool.py': '/tools/tooltool.py', 05:10:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:10:42 INFO - '/tools/misc-python/virtualenv.py')}, 05:10:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:10:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:10:42 INFO - 'gecko_profile': False, 05:10:42 INFO - 'gecko_profile_interval': 0, 05:10:42 INFO - 'installer_path': 'installer.exe', 05:10:42 INFO - 'log_level': 'info', 05:10:42 INFO - 'log_name': 'talos', 05:10:42 INFO - 'log_to_console': True, 05:10:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:10:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:10:42 INFO - 'opt_config_files': (), 05:10:42 INFO - 'pip_index': False, 05:10:42 INFO - 'suite': 'dromaeojs-e10s', 05:10:42 INFO - 'system_bits': '32', 05:10:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:10:42 INFO - 'title': 'talos-linux64-ix-056', 05:10:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:10:42 INFO - 'use_talos_json': True, 05:10:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:10:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:10:42 INFO - 'work_dir': 'build'} 05:10:42 INFO - [mozharness: 2017-08-08 12:10:42.080686Z] Running clobber step. 05:10:42 INFO - Running pre-action listener: _resource_record_pre_action 05:10:42 INFO - Running main action method: clobber 05:10:42 INFO - rmtree: /builds/slave/test/build 05:10:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:10:43 INFO - Running post-action listener: _resource_record_post_action 05:10:43 INFO - [mozharness: 2017-08-08 12:10:43.122107Z] Finished clobber step (success) 05:10:43 INFO - [mozharness: 2017-08-08 12:10:43.122210Z] Running read-buildbot-config step. 05:10:43 INFO - Running pre-action listener: _resource_record_pre_action 05:10:43 INFO - Running main action method: read_buildbot_config 05:10:43 INFO - Using buildbot properties: 05:10:43 INFO - { 05:10:43 INFO - "project": "", 05:10:43 INFO - "product": "firefox", 05:10:43 INFO - "who": "cbook@mozilla.com", 05:10:43 INFO - "installer_path": "public/build/target.tar.bz2", 05:10:43 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:10:43 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:10:43 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 05:10:43 INFO - "stage_platform": "linux64", 05:10:43 INFO - "basedir": "/builds/slave/test", 05:10:43 INFO - "buildnumber": 1065, 05:10:43 INFO - "platform": "ubuntu64_hw", 05:10:43 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:10:43 INFO - "slavebuilddir": "test", 05:10:43 INFO - "taskId": "eC4nHvYaTLSg9vtFGcI0DA", 05:10:43 INFO - "branch": "mozilla-central", 05:10:43 INFO - "script_repo_revision": "production", 05:10:43 INFO - "revision": "8c7788eda00e4cb4445638bdb7500009cd4cbe59", 05:10:43 INFO - "slavename": "talos-linux64-ix-056", 05:10:43 INFO - "repo_path": "mozilla-central" 05:10:43 INFO - } 05:10:43 INFO - Finding installer, test and symbols from parent task. 05:10:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eC4nHvYaTLSg9vtFGcI0DA'}, attempt #1 05:10:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eC4nHvYaTLSg9vtFGcI0DA'}, attempt #1 05:10:44 INFO - Task dependencies: At2ih0kyQ7Ka5Inf4WhGAA 05:10:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA'}, attempt #1 05:10:44 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.tar.bz2 05:10:44 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.test_packages.json 05:10:44 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.crashreporter-symbols.zip 05:10:44 INFO - Running post-action listener: _resource_record_post_action 05:10:44 INFO - [mozharness: 2017-08-08 12:10:44.503093Z] Finished read-buildbot-config step (success) 05:10:44 INFO - [mozharness: 2017-08-08 12:10:44.503318Z] Running download-and-extract step. 05:10:44 INFO - Running pre-action listener: _resource_record_pre_action 05:10:44 INFO - Running main action method: download_and_extract 05:10:44 INFO - mkdir: /builds/slave/test/build/tests 05:10:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:44 INFO - trying https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.test_packages.json 05:10:44 INFO - Downloading https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:10:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:10:45 INFO - Downloaded 989 bytes. 05:10:45 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:10:45 INFO - Using the following test package requirements: 05:10:45 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:10:45 INFO - u'common': [u'target.common.tests.zip'], 05:10:45 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:10:45 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:10:45 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:10:45 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:10:45 INFO - u'mozbase': [u'target.common.tests.zip'], 05:10:45 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:10:45 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:10:45 INFO - u'web-platform': [u'target.common.tests.zip', 05:10:45 INFO - u'target.web-platform.tests.tar.gz'], 05:10:45 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:10:45 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:10:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.common.tests.zip 05:10:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:10:45 INFO - Fetch https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.common.tests.zip into memory 05:10:46 INFO - Content-Length response header: 54284886 05:10:46 INFO - Bytes received: 54284886 05:10:57 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:10:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.common.tests.zip 05:10:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:10:57 INFO - Fetch https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.common.tests.zip into memory 05:10:58 INFO - Content-Length response header: 54284886 05:10:58 INFO - Bytes received: 54284886 05:11:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.talos.tests.zip 05:11:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:11:10 INFO - Fetch https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.talos.tests.zip into memory 05:11:10 INFO - Content-Length response header: 14496008 05:11:10 INFO - Bytes received: 14496008 05:11:11 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')]} 05:11:11 INFO - trying https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.tar.bz2 05:11:11 INFO - Downloading https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:11:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:11:12 INFO - Downloaded 61790054 bytes. 05:11:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.tar.bz2 05:11:12 INFO - mkdir: /builds/slave/test/properties 05:11:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:11:12 INFO - Writing to file /builds/slave/test/properties/build_url 05:11:12 INFO - Contents: 05:11:12 INFO - build_url:https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.tar.bz2 05:11:12 INFO - Running post-action listener: _resource_record_post_action 05:11:12 INFO - Running post-action listener: set_extra_try_arguments 05:11:12 INFO - [mozharness: 2017-08-08 12:11:12.561690Z] Finished download-and-extract step (success) 05:11:12 INFO - [mozharness: 2017-08-08 12:11:12.561789Z] Running populate-webroot step. 05:11:12 INFO - Running pre-action listener: _resource_record_pre_action 05:11:12 INFO - Running main action method: populate_webroot 05:11:12 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:11:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:11:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:11:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:11:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:11:12 INFO - 'tests': ['damp', 'tps']}, 05:11:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:11:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:11:12 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:11:12 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:11:12 INFO - 'other-e10s': {'tests': ['a11yr', 05:11:12 INFO - 'ts_paint', 05:11:12 INFO - 'tpaint', 05:11:12 INFO - 'sessionrestore', 05:11:12 INFO - 'sessionrestore_many_windows', 05:11:12 INFO - 'sessionrestore_no_auto_restore', 05:11:12 INFO - 'tabpaint']}, 05:11:12 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:11:12 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:11:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:11:12 INFO - 'tsvgr_opacity', 05:11:12 INFO - 'tart', 05:11:12 INFO - 'tscrollx', 05:11:12 INFO - 'tsvg_static']}, 05:11:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:11:12 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:11:12 INFO - 'talos_options': ['--mitmproxy', 05:11:12 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:11:12 INFO - '--firstNonBlankPaint'], 05:11:12 INFO - 'tests': ['tp6_google', 05:11:12 INFO - 'tp6_youtube', 05:11:12 INFO - 'tp6_amazon', 05:11:12 INFO - 'tp6_facebook']}, 05:11:12 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:11:12 INFO - 'talos_options': ['--stylo', 05:11:12 INFO - '--mitmproxy', 05:11:12 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:11:12 INFO - '--firstNonBlankPaint'], 05:11:12 INFO - 'tests': ['tp6_google', 05:11:12 INFO - 'tp6_youtube', 05:11:12 INFO - 'tp6_amazon', 05:11:12 INFO - 'tp6_facebook']}, 05:11:12 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:11:12 INFO - 'talos_options': ['--stylo', 05:11:12 INFO - '--stylo-threads=1', 05:11:12 INFO - '--mitmproxy', 05:11:12 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:11:12 INFO - '--firstNonBlankPaint'], 05:11:12 INFO - 'tests': ['tp6_google', 05:11:12 INFO - 'tp6_youtube', 05:11:12 INFO - 'tp6_amazon', 05:11:12 INFO - 'tp6_facebook']}, 05:11:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:11:12 INFO - 'talos_options': ['--xperf_path', 05:11:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:11:12 INFO - 'tests': ['tp5n']}}} 05:11:12 INFO - Running post-action listener: _resource_record_post_action 05:11:12 INFO - [mozharness: 2017-08-08 12:11:12.570270Z] Finished populate-webroot step (success) 05:11:12 INFO - [mozharness: 2017-08-08 12:11:12.570361Z] Running create-virtualenv step. 05:11:12 INFO - Running pre-action listener: _resource_record_pre_action 05:11:12 INFO - Running main action method: create_virtualenv 05:11:12 INFO - Creating virtualenv /builds/slave/test/build/venv 05:11:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:11:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:11:12 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:11:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:11:12 INFO - Using real prefix '/usr' 05:11:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:11:13 INFO - Installing distribute.............................................................................................................................................................................................done. 05:11:15 INFO - Installing pip.................done. 05:11:15 INFO - Return code: 0 05:11:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:11:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:11:15 INFO - Reading from file tmpfile_stdout 05:11:15 INFO - Output received: 05:11:15 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:11:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:15 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 0x24ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282ad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827eb0>, '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 0x2828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2829240>, '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': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', '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 05:11:15 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 05:11:15 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 05:11:15 INFO - Using env: {'DISPLAY': ':0', 05:11:15 INFO - 'HOME': '/home/cltbld', 05:11:15 INFO - 'LANG': 'en_US.UTF-8', 05:11:15 INFO - 'LANGUAGE': 'en_US:en', 05:11:15 INFO - 'LOGNAME': 'cltbld', 05:11:15 INFO - 'MAIL': '/var/mail/cltbld', 05:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:15 INFO - 'MOZ_NO_REMOTE': '1', 05:11:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:15 INFO - 'NO_EM_RESTART': '1', 05:11:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:15 INFO - 'PWD': '/builds/slave/test', 05:11:15 INFO - 'SHELL': '/bin/bash', 05:11:15 INFO - 'SHLVL': '1', 05:11:15 INFO - 'TERM': 'linux', 05:11:15 INFO - 'TMOUT': '86400', 05:11:15 INFO - 'USER': 'cltbld', 05:11:15 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', 05:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:15 INFO - '_': '/tools/buildbot/bin/python'} 05:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:15 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:11:15 INFO - Cleaning up... 05:11:15 INFO - Return code: 0 05:11:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:15 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 0x24ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282ad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827eb0>, '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 0x2828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2829240>, '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': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', '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 05:11:15 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 05:11:15 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 05:11:15 INFO - Using env: {'DISPLAY': ':0', 05:11:15 INFO - 'HOME': '/home/cltbld', 05:11:15 INFO - 'LANG': 'en_US.UTF-8', 05:11:15 INFO - 'LANGUAGE': 'en_US:en', 05:11:15 INFO - 'LOGNAME': 'cltbld', 05:11:15 INFO - 'MAIL': '/var/mail/cltbld', 05:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:15 INFO - 'MOZ_NO_REMOTE': '1', 05:11:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:15 INFO - 'NO_EM_RESTART': '1', 05:11:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:15 INFO - 'PWD': '/builds/slave/test', 05:11:15 INFO - 'SHELL': '/bin/bash', 05:11:15 INFO - 'SHLVL': '1', 05:11:15 INFO - 'TERM': 'linux', 05:11:15 INFO - 'TMOUT': '86400', 05:11:15 INFO - 'USER': 'cltbld', 05:11:15 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', 05:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:15 INFO - '_': '/tools/buildbot/bin/python'} 05:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:15 INFO - Downloading/unpacking psutil>=3.1.1 05:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:11:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:11:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:11:17 INFO - Installing collected packages: psutil 05:11:17 INFO - Running setup.py install for psutil 05:11:17 INFO - building 'psutil._psutil_linux' extension 05:11:17 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 05:11:17 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 05:11:17 INFO - building 'psutil._psutil_posix' extension 05:11:17 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 05:11:17 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 05:11:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:11:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:11:17 INFO - Successfully installed psutil 05:11:17 INFO - Cleaning up... 05:11:17 INFO - Return code: 0 05:11:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:11:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282ad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827eb0>, '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 0x2828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2829240>, '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': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', '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 05:11:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:11:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:11:17 INFO - Using env: {'DISPLAY': ':0', 05:11:17 INFO - 'HOME': '/home/cltbld', 05:11:17 INFO - 'LANG': 'en_US.UTF-8', 05:11:17 INFO - 'LANGUAGE': 'en_US:en', 05:11:17 INFO - 'LOGNAME': 'cltbld', 05:11:17 INFO - 'MAIL': '/var/mail/cltbld', 05:11:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:17 INFO - 'MOZ_NO_REMOTE': '1', 05:11:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:17 INFO - 'NO_EM_RESTART': '1', 05:11:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:17 INFO - 'PWD': '/builds/slave/test', 05:11:17 INFO - 'SHELL': '/bin/bash', 05:11:17 INFO - 'SHLVL': '1', 05:11:17 INFO - 'TERM': 'linux', 05:11:17 INFO - 'TMOUT': '86400', 05:11:17 INFO - 'USER': 'cltbld', 05:11:17 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', 05:11:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:17 INFO - '_': '/tools/buildbot/bin/python'} 05:11:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:11:17 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 05:11:17 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 05:11:17 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 05:11:17 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 05:11:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:11:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:11:19 INFO - Installing collected packages: mozsystemmonitor 05:11:19 INFO - Running setup.py install for mozsystemmonitor 05:11:19 INFO - Successfully installed mozsystemmonitor 05:11:19 INFO - Cleaning up... 05:11:19 INFO - Return code: 0 05:11:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:11:19 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')]} 05:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:19 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')]} 05:11:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:19 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 0x24ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282ad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827eb0>, '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 0x2828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2829240>, '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': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', '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 05:11:19 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 05:11:19 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 05:11:19 INFO - Using env: {'DISPLAY': ':0', 05:11:19 INFO - 'HOME': '/home/cltbld', 05:11:19 INFO - 'LANG': 'en_US.UTF-8', 05:11:19 INFO - 'LANGUAGE': 'en_US:en', 05:11:19 INFO - 'LOGNAME': 'cltbld', 05:11:19 INFO - 'MAIL': '/var/mail/cltbld', 05:11:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:19 INFO - 'MOZ_NO_REMOTE': '1', 05:11:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:19 INFO - 'NO_EM_RESTART': '1', 05:11:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:19 INFO - 'PWD': '/builds/slave/test', 05:11:19 INFO - 'SHELL': '/bin/bash', 05:11:19 INFO - 'SHLVL': '1', 05:11:19 INFO - 'TERM': 'linux', 05:11:19 INFO - 'TMOUT': '86400', 05:11:19 INFO - 'USER': 'cltbld', 05:11:19 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', 05:11:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:19 INFO - '_': '/tools/buildbot/bin/python'} 05:11:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:19 INFO - Downloading/unpacking jsonschema==2.5.1 05:11:19 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 05:11:19 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 05:11:19 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 05:11:19 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 05:11:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:11:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:11:20 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 05:11:20 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 05:11:20 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 05:11:20 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 05:11:20 INFO - Downloading functools32-3.2.3-2.tar.gz 05:11:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:11:20 INFO - warning: no files found matching '*.txt' 05:11:20 INFO - no previously-included directories found matching 'build' 05:11:20 INFO - no previously-included directories found matching 'dist' 05:11:20 INFO - no previously-included directories found matching '.git*' 05:11:20 INFO - Installing collected packages: jsonschema, functools32 05:11:20 INFO - Running setup.py install for functools32 05:11:21 INFO - warning: no files found matching '*.txt' 05:11:21 INFO - no previously-included directories found matching 'build' 05:11:21 INFO - no previously-included directories found matching 'dist' 05:11:21 INFO - no previously-included directories found matching '.git*' 05:11:21 INFO - Successfully installed jsonschema functools32 05:11:21 INFO - Cleaning up... 05:11:21 INFO - Return code: 0 05:11:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:11:21 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')]} 05:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:21 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')]} 05:11:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:21 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 0x24ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282ad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827eb0>, '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 0x2828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2829240>, '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': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', '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 05:11:21 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 05:11:21 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 05:11:21 INFO - Using env: {'DISPLAY': ':0', 05:11:21 INFO - 'HOME': '/home/cltbld', 05:11:21 INFO - 'LANG': 'en_US.UTF-8', 05:11:21 INFO - 'LANGUAGE': 'en_US:en', 05:11:21 INFO - 'LOGNAME': 'cltbld', 05:11:21 INFO - 'MAIL': '/var/mail/cltbld', 05:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:21 INFO - 'MOZ_NO_REMOTE': '1', 05:11:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:21 INFO - 'NO_EM_RESTART': '1', 05:11:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:21 INFO - 'PWD': '/builds/slave/test', 05:11:21 INFO - 'SHELL': '/bin/bash', 05:11:21 INFO - 'SHLVL': '1', 05:11:21 INFO - 'TERM': 'linux', 05:11:21 INFO - 'TMOUT': '86400', 05:11:21 INFO - 'USER': 'cltbld', 05:11:21 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', 05:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:21 INFO - '_': '/tools/buildbot/bin/python'} 05:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:11:21 INFO - Cleaning up... 05:11:21 INFO - Return code: 0 05:11:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:11:21 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')]} 05:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:21 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')]} 05:11:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:21 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 0x24ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282ad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827eb0>, '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 0x2828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2829240>, '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': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', '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 05:11:21 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 05:11:21 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 05:11:21 INFO - Using env: {'DISPLAY': ':0', 05:11:21 INFO - 'HOME': '/home/cltbld', 05:11:21 INFO - 'LANG': 'en_US.UTF-8', 05:11:21 INFO - 'LANGUAGE': 'en_US:en', 05:11:21 INFO - 'LOGNAME': 'cltbld', 05:11:21 INFO - 'MAIL': '/var/mail/cltbld', 05:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:21 INFO - 'MOZ_NO_REMOTE': '1', 05:11:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:21 INFO - 'NO_EM_RESTART': '1', 05:11:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:21 INFO - 'PWD': '/builds/slave/test', 05:11:21 INFO - 'SHELL': '/bin/bash', 05:11:21 INFO - 'SHLVL': '1', 05:11:21 INFO - 'TERM': 'linux', 05:11:21 INFO - 'TMOUT': '86400', 05:11:21 INFO - 'USER': 'cltbld', 05:11:21 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', 05:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:21 INFO - '_': '/tools/buildbot/bin/python'} 05:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:21 INFO - Downloading/unpacking blobuploader==1.2.4 05:11:21 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 05:11:21 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 05:11:21 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 05:11:21 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 05:11:22 INFO - Downloading blobuploader-1.2.4.tar.gz 05:11:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:11:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:11:22 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 05:11:22 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 05:11:22 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 05:11:22 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 05:11:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:11:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:11:23 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 05:11:23 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 05:11:23 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 05:11:23 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 05:11:23 INFO - Downloading docopt-0.6.1.tar.gz 05:11:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:11:23 INFO - Installing collected packages: blobuploader, requests, docopt 05:11:23 INFO - Running setup.py install for blobuploader 05:11:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:11:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:11:23 INFO - Running setup.py install for requests 05:11:23 INFO - Running setup.py install for docopt 05:11:23 INFO - Successfully installed blobuploader requests docopt 05:11:23 INFO - Cleaning up... 05:11:23 INFO - Return code: 0 05:11:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:11:23 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')]} 05:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:23 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')]} 05:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:23 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 0x24ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282ad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827eb0>, '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 0x2828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2829240>, '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': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', '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 05:11:23 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 05:11:23 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 05:11:23 INFO - Using env: {'DISPLAY': ':0', 05:11:23 INFO - 'HOME': '/home/cltbld', 05:11:23 INFO - 'LANG': 'en_US.UTF-8', 05:11:23 INFO - 'LANGUAGE': 'en_US:en', 05:11:23 INFO - 'LOGNAME': 'cltbld', 05:11:23 INFO - 'MAIL': '/var/mail/cltbld', 05:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:23 INFO - 'MOZ_NO_REMOTE': '1', 05:11:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:23 INFO - 'NO_EM_RESTART': '1', 05:11:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:23 INFO - 'PWD': '/builds/slave/test', 05:11:23 INFO - 'SHELL': '/bin/bash', 05:11:23 INFO - 'SHLVL': '1', 05:11:23 INFO - 'TERM': 'linux', 05:11:23 INFO - 'TMOUT': '86400', 05:11:23 INFO - 'USER': 'cltbld', 05:11:23 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', 05:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:23 INFO - '_': '/tools/buildbot/bin/python'} 05:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:11:24 INFO - Running setup.py (path:/tmp/pip-XkkIe1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:11:24 INFO - Running setup.py (path:/tmp/pip-kHep9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:11:24 INFO - Running setup.py (path:/tmp/pip-YBPSsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:11:24 INFO - Running setup.py (path:/tmp/pip-drFYWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:11:24 INFO - Running setup.py (path:/tmp/pip-uKkYRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:11:24 INFO - Running setup.py (path:/tmp/pip-7KPLny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:11:24 INFO - Running setup.py (path:/tmp/pip-u4sFJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:11:24 INFO - Running setup.py (path:/tmp/pip-RxWSP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:11:24 INFO - Running setup.py (path:/tmp/pip-aqV1Uf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:11:24 INFO - Running setup.py (path:/tmp/pip-tHs5WO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:11:24 INFO - Running setup.py (path:/tmp/pip-ov8dMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:11:24 INFO - Running setup.py (path:/tmp/pip-2RqYx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:11:24 INFO - Running setup.py (path:/tmp/pip-hHyzNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:11:25 INFO - Running setup.py (path:/tmp/pip-bf3v79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:25 INFO - Running setup.py (path:/tmp/pip-OW2y8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:11:25 INFO - Running setup.py (path:/tmp/pip-aHLMD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:11:25 INFO - Running setup.py (path:/tmp/pip-sDhG1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:11:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:11:25 INFO - Running setup.py install for manifestparser 05:11:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:11:25 INFO - Running setup.py install for mozcrash 05:11:25 INFO - Running setup.py install for mozdebug 05:11:25 INFO - Running setup.py install for mozdevice 05:11:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:11:25 INFO - Running setup.py install for mozfile 05:11:25 INFO - Running setup.py install for mozhttpd 05:11:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:11:25 INFO - Running setup.py install for mozinfo 05:11:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:11:26 INFO - Running setup.py install for mozInstall 05:11:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:11:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:11:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:11:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:11:26 INFO - Running setup.py install for mozleak 05:11:26 INFO - Running setup.py install for mozlog 05:11:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:11:26 INFO - Running setup.py install for moznetwork 05:11:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:11:26 INFO - Running setup.py install for mozprocess 05:11:26 INFO - Running setup.py install for mozprofile 05:11:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:11:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:11:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:11:26 INFO - Running setup.py install for mozrunner 05:11:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:11:26 INFO - Running setup.py install for mozscreenshot 05:11:26 INFO - Running setup.py install for moztest 05:11:27 INFO - Running setup.py install for mozversion 05:11:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:11:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:11:27 INFO - Cleaning up... 05:11:27 INFO - Return code: 0 05:11:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:11: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')]} 05:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11: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')]} 05:11:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:27 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 0x24ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282ad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827eb0>, '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 0x2828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2829240>, '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': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', '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 05:11:27 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 05:11:27 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 05:11:27 INFO - Using env: {'DISPLAY': ':0', 05:11:27 INFO - 'HOME': '/home/cltbld', 05:11:27 INFO - 'LANG': 'en_US.UTF-8', 05:11:27 INFO - 'LANGUAGE': 'en_US:en', 05:11:27 INFO - 'LOGNAME': 'cltbld', 05:11:27 INFO - 'MAIL': '/var/mail/cltbld', 05:11:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:27 INFO - 'MOZ_NO_REMOTE': '1', 05:11:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:27 INFO - 'NO_EM_RESTART': '1', 05:11:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:27 INFO - 'PWD': '/builds/slave/test', 05:11:27 INFO - 'SHELL': '/bin/bash', 05:11:27 INFO - 'SHLVL': '1', 05:11:27 INFO - 'TERM': 'linux', 05:11:27 INFO - 'TMOUT': '86400', 05:11:27 INFO - 'USER': 'cltbld', 05:11:27 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', 05:11:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:27 INFO - '_': '/tools/buildbot/bin/python'} 05:11:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:11:27 INFO - Running setup.py (path:/tmp/pip-QwFSLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:11:27 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)) 05:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:11:27 INFO - Running setup.py (path:/tmp/pip-5PAoDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:11:27 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)) 05:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:11:27 INFO - Running setup.py (path:/tmp/pip-OGHScQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:11:27 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)) 05:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:11:27 INFO - Running setup.py (path:/tmp/pip-uVqx_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:11:27 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)) 05:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:11:27 INFO - Running setup.py (path:/tmp/pip-pZaeUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:11:27 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)) 05:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:11:27 INFO - Running setup.py (path:/tmp/pip-DIFEXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:11:27 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)) 05:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:11:27 INFO - Running setup.py (path:/tmp/pip-DM71bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:11:27 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)) 05:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:11:27 INFO - Running setup.py (path:/tmp/pip-ZxLvyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:11:27 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)) 05:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:11:27 INFO - Running setup.py (path:/tmp/pip-gFLUAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:11:28 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)) 05:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:11:28 INFO - Running setup.py (path:/tmp/pip-22xX35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:11:28 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)) 05:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:11:28 INFO - Running setup.py (path:/tmp/pip-NQr6WU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:11:28 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)) 05:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:11:28 INFO - Running setup.py (path:/tmp/pip-mTOHPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:11:28 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)) 05:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:11:28 INFO - Running setup.py (path:/tmp/pip-115wyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:11:28 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)) 05:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:11:28 INFO - Running setup.py (path:/tmp/pip-TxfCVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:11:28 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)) 05:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:28 INFO - Running setup.py (path:/tmp/pip-vPGcYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:28 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)) 05:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:11:28 INFO - Running setup.py (path:/tmp/pip-_ERE7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:11:28 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)) 05:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:11:28 INFO - Running setup.py (path:/tmp/pip-vB1eoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:11:28 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)) 05:11:28 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)) 05:11:28 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)) 05:11:28 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)) 05:11:28 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)) 05:11:28 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)) 05:11:28 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)) 05:11:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:11:28 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 05:11:28 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 05:11:28 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 05:11:28 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 05:11:29 INFO - Downloading blessings-1.6.tar.gz 05:11:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:11:29 INFO - Installing collected packages: blessings 05:11:29 INFO - Running setup.py install for blessings 05:11:29 INFO - Successfully installed blessings 05:11:29 INFO - Cleaning up... 05:11:29 INFO - Return code: 0 05:11:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:11:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:11:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:11:30 INFO - Reading from file tmpfile_stdout 05:11:30 INFO - Current package versions: 05:11:30 INFO - argparse == 1.2.1 05:11:30 INFO - blessings == 1.6 05:11:30 INFO - blobuploader == 1.2.4 05:11:30 INFO - docopt == 0.6.1 05:11:30 INFO - functools32 == 3.2.3-2 05:11:30 INFO - jsonschema == 2.5.1 05:11:30 INFO - manifestparser == 1.1 05:11:30 INFO - mozInstall == 1.13 05:11:30 INFO - mozcrash == 1.0 05:11:30 INFO - mozdebug == 0.1 05:11:30 INFO - mozdevice == 0.51 05:11:30 INFO - mozfile == 1.2 05:11:30 INFO - mozhttpd == 0.7 05:11:30 INFO - mozinfo == 0.10 05:11:30 INFO - mozleak == 0.1 05:11:30 INFO - mozlog == 3.5 05:11:30 INFO - moznetwork == 0.27 05:11:30 INFO - mozprocess == 0.25 05:11:30 INFO - mozprofile == 0.28 05:11:30 INFO - mozrunner == 6.13 05:11:30 INFO - mozscreenshot == 0.1 05:11:30 INFO - mozsystemmonitor == 0.3 05:11:30 INFO - moztest == 0.8 05:11:30 INFO - mozversion == 1.4 05:11:30 INFO - psutil == 3.1.1 05:11:30 INFO - requests == 1.2.3 05:11:30 INFO - wsgiref == 0.1.2 05:11:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:11:30 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')]} 05:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:30 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')]} 05:11:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:30 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 0x24ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282ad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827eb0>, '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 0x2828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2829240>, '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': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', '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 05:11:30 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 05:11:30 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 05:11:30 INFO - Using env: {'DISPLAY': ':0', 05:11:30 INFO - 'HOME': '/home/cltbld', 05:11:30 INFO - 'LANG': 'en_US.UTF-8', 05:11:30 INFO - 'LANGUAGE': 'en_US:en', 05:11:30 INFO - 'LOGNAME': 'cltbld', 05:11:30 INFO - 'MAIL': '/var/mail/cltbld', 05:11:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:30 INFO - 'MOZ_NO_REMOTE': '1', 05:11:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:30 INFO - 'NO_EM_RESTART': '1', 05:11:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:30 INFO - 'PWD': '/builds/slave/test', 05:11:30 INFO - 'SHELL': '/bin/bash', 05:11:30 INFO - 'SHLVL': '1', 05:11:30 INFO - 'TERM': 'linux', 05:11:30 INFO - 'TMOUT': '86400', 05:11:30 INFO - 'USER': 'cltbld', 05:11:30 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', 05:11:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:30 INFO - '_': '/tools/buildbot/bin/python'} 05:11:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:30 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)) 05:11:30 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)) 05:11:30 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)) 05:11:30 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)) 05:11:30 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)) 05:11:30 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)) 05:11:30 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)) 05:11:30 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)) 05:11:30 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)) 05:11:30 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:11:30 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 05:11:30 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 05:11:30 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 05:11:30 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 05:11:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:11:31 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)) 05:11:31 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)) 05:11:31 INFO - Installing collected packages: simplejson 05:11:31 INFO - Running setup.py install for simplejson 05:11:31 INFO - building 'simplejson._speedups' extension 05:11:31 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 05:11:32 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 05:11:32 INFO - Successfully installed simplejson 05:11:32 INFO - Cleaning up... 05:11:32 INFO - Return code: 0 05:11:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:11:32 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')]} 05:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:32 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')]} 05:11:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:32 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 0x24ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2687a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x282ad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827eb0>, '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 0x2828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2829240>, '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': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', '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 05:11:32 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 05:11:32 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 05:11:32 INFO - Using env: {'DISPLAY': ':0', 05:11:32 INFO - 'HOME': '/home/cltbld', 05:11:32 INFO - 'LANG': 'en_US.UTF-8', 05:11:32 INFO - 'LANGUAGE': 'en_US:en', 05:11:32 INFO - 'LOGNAME': 'cltbld', 05:11:32 INFO - 'MAIL': '/var/mail/cltbld', 05:11:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:32 INFO - 'MOZ_NO_REMOTE': '1', 05:11:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:32 INFO - 'NO_EM_RESTART': '1', 05:11:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:32 INFO - 'PWD': '/builds/slave/test', 05:11:32 INFO - 'SHELL': '/bin/bash', 05:11:32 INFO - 'SHLVL': '1', 05:11:32 INFO - 'TERM': 'linux', 05:11:32 INFO - 'TMOUT': '86400', 05:11:32 INFO - 'USER': 'cltbld', 05:11:32 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', 05:11:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:32 INFO - '_': '/tools/buildbot/bin/python'} 05:11:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:11:32 INFO - Cleaning up... 05:11:32 INFO - Return code: 0 05:11:32 INFO - Running post-action listener: _resource_record_post_action 05:11:32 INFO - Running post-action listener: _start_resource_monitoring 05:11:32 INFO - Starting resource monitoring. 05:11:32 INFO - [mozharness: 2017-08-08 12:11:32.848409Z] Finished create-virtualenv step (success) 05:11:32 INFO - [mozharness: 2017-08-08 12:11:32.849010Z] Running install step. 05:11:32 INFO - Running pre-action listener: _resource_record_pre_action 05:11:32 INFO - Running main action method: install 05:11:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:11:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:11:33 INFO - Reading from file tmpfile_stdout 05:11:33 INFO - Detecting whether we're running mozinstall >=1.0... 05:11:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:11:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:11:33 INFO - Reading from file tmpfile_stdout 05:11:33 INFO - Output received: 05:11:33 INFO - Usage: mozinstall [options] installer 05:11:33 INFO - Options: 05:11:33 INFO - -h, --help show this help message and exit 05:11:33 INFO - -d DEST, --destination=DEST 05:11:33 INFO - Directory to install application into. [default: 05:11:33 INFO - "/builds/slave/test"] 05:11:33 INFO - --app=APP Application being installed. [default: firefox] 05:11:33 INFO - mkdir: /builds/slave/test/build/application 05:11:33 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'] 05:11:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:11:51 INFO - Reading from file tmpfile_stdout 05:11:51 INFO - Output received: 05:11:51 INFO - /builds/slave/test/build/application/firefox/firefox 05:11:51 INFO - Running post-action listener: _resource_record_post_action 05:11:51 INFO - [mozharness: 2017-08-08 12:11:51.108900Z] Finished install step (success) 05:11:51 INFO - [mozharness: 2017-08-08 12:11:51.109184Z] Running setup-mitmproxy step. 05:11:51 INFO - Running pre-action listener: _resource_record_pre_action 05:11:51 INFO - Running main action method: setup_mitmproxy 05:11:51 INFO - Skipping: mitmproxy is not required 05:11:51 INFO - Running post-action listener: _resource_record_post_action 05:11:51 INFO - [mozharness: 2017-08-08 12:11:51.110337Z] Finished setup-mitmproxy step (success) 05:11:51 INFO - [mozharness: 2017-08-08 12:11:51.110623Z] Running run-tests step. 05:11:51 INFO - Running pre-action listener: _resource_record_pre_action 05:11:51 INFO - Running pre-action listener: _set_gcov_prefix 05:11:51 INFO - Running main action method: run_tests 05:11:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:11:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:11:51 INFO - Python 2.7.3 05:11:51 INFO - Return code: 0 05:11:51 INFO - grabbing minidump binary from tooltool 05:11:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:51 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 0x2827eb0>, '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 0x2828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2829240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:11:51 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 05:11:51 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:11:51 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 05:11:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:11:51 INFO - Return code: 0 05:11:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:11:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:11:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:11:51 INFO - ENV: RUST_BACKTRACE is now 1 05:11:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:11:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:11:51 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/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 05:11:51 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/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-056 --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 05:11:51 INFO - Using env: {'DISPLAY': ':0', 05:11:51 INFO - 'HOME': '/home/cltbld', 05:11:51 INFO - 'LANG': 'en_US.UTF-8', 05:11:51 INFO - 'LANGUAGE': 'en_US:en', 05:11:51 INFO - 'LOGNAME': 'cltbld', 05:11:51 INFO - 'MAIL': '/var/mail/cltbld', 05:11:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:11:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:11:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:51 INFO - 'MOZ_NO_REMOTE': '1', 05:11:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:11:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:51 INFO - 'NO_EM_RESTART': '1', 05:11:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:51 INFO - 'PWD': '/builds/slave/test', 05:11:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:11:51 INFO - 'RUST_BACKTRACE': '1', 05:11:51 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:11:51 INFO - 'SHELL': '/bin/bash', 05:11:51 INFO - 'SHLVL': '1', 05:11:51 INFO - 'TERM': 'linux', 05:11:51 INFO - 'TMOUT': '86400', 05:11:51 INFO - 'USER': 'cltbld', 05:11:51 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1502194240.471162-214435039', 05:11:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:51 INFO - '_': '/tools/buildbot/bin/python'} 05:11:51 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/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 05:11:51 INFO - mozversion application_buildid: 20170808111607 05:11:51 INFO - mozversion application_changeset: 8c7788eda00e4cb4445638bdb7500009cd4cbe59 05:11:51 INFO - mozversion application_display_name: Nightly 05:11:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:11:51 INFO - mozversion application_name: Firefox 05:11:51 INFO - mozversion application_remotingname: firefox 05:11:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:11:51 INFO - mozversion application_vendor: Mozilla 05:11:51 INFO - mozversion application_version: 57.0a1 05:11:51 INFO - mozversion platform_buildid: 20170808111607 05:11:51 INFO - mozversion platform_changeset: 8c7788eda00e4cb4445638bdb7500009cd4cbe59 05:11:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:11:51 INFO - mozversion platform_version: 57.0a1 05:11:51 INFO - using testdate: 1502194311 05:11:51 INFO - actual date: 1502194311 05:11:51 INFO - starting webserver on 'localhost:58848' 05:11:51 INFO - SUITE-START | Running 2 tests 05:11:51 INFO - TEST-START | dromaeo_css 05:11:51 INFO - Initialising browser for dromaeo_css test... 05:11:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58848/getInfo.html -profile /tmp/tmpu_wb6T/profile 05:11:51 INFO - TEST-INFO | started process 4682 (/builds/slave/test/build/application/firefox/firefox http://localhost:58848/getInfo.html) 05:11:57 INFO - TEST-INFO | 4682: exit 0 05:11:57 INFO - Browser initialized. 05:11:57 INFO - Running cycle 1/1 for dromaeo_css test... 05:11:57 INFO - TEST-INFO | started process 4890 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu_wb6T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:11:58 INFO - PID 4890 | 05:11:58 INFO - PID 4890 | (/builds/slave/test/build/application/firefox/firefox:4938): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:11:58 INFO - PID 4890 | 05:12:08 INFO - PID 4890 | RSS: Main: 171925504 05:12:08 INFO - PID 4890 | 05:15:08 INFO - PID 4890 | Cycle 1(1): loaded http://localhost:58848/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58848/tests/dromaeo/cssquery-ext.html) 05:15:09 INFO - PID 4890 | RSS: Main: 173817856 05:15:09 INFO - PID 4890 | 05:18:11 INFO - PID 4890 | Cycle 1(1): loaded http://localhost:58848/tests/dromaeo/cssquery-ext.html (next: http://localhost:58848/tests/dromaeo/cssquery-jquery.html) 05:18:11 INFO - PID 4890 | RSS: Main: 169140224 05:18:11 INFO - PID 4890 | 05:21:13 INFO - PID 4890 | Cycle 1(1): loaded http://localhost:58848/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58848/tests/dromaeo/cssquery-mootools.html) 05:21:14 INFO - PID 4890 | RSS: Main: 172900352 05:21:14 INFO - PID 4890 | 05:24:16 INFO - PID 4890 | Cycle 1(1): loaded http://localhost:58848/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58848/tests/dromaeo/cssquery-prototype.html) 05:24:16 INFO - PID 4890 | RSS: Main: 171171840 05:24:16 INFO - PID 4890 | 05:27:20 INFO - PID 4890 | Cycle 1(1): loaded http://localhost:58848/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58848/tests/dromaeo/cssquery-yui.html) 05:27:21 INFO - PID 4890 | RSS: Main: 174518272 05:27:21 INFO - PID 4890 | 05:30:22 INFO - PID 4890 | Cycle 1(1): loaded http://localhost:58848/tests/dromaeo/cssquery-yui.html (next: http://localhost:58848/tests/dromaeo/cssquery-dojo.html) 05:30:23 INFO - PID 4890 | RSS: Main: 174796800 05:30:23 INFO - PID 4890 | 05:30:23 INFO - PID 4890 | __start_tp_report 05:30:23 INFO - PID 4890 | _x_x_mozilla_page_load 05:30:23 INFO - PID 4890 | _x_x_mozilla_page_load_details 05:30:23 INFO - PID 4890 | |i|pagename|runs| 05:30:23 INFO - PID 4890 | |0;dojo.html;3161.74706023518;3180.5070214116813;3184.0607020928824;3224.7258982986446;3227.7256433203174;6230.7196176172065;6288.465480434163;6294.874102517947;6353.491720662348;6378.3621637836195;6126.387361263872;6166.3525329840395;6175.814725558229;6206.317305096447;6237.656928868911;5635.633683810539;5644.57665675074;5651.124075768242;5665.433456654343;5671.404502527247;5755.309362876461;5766.1639062335935;5768.42315768424;5773.624714393551;5788.392218817026;5608.971955140219;5689.544836413098;5720.827230417775;5724.227229324029;5741.655500669952;5539.806106786284;5608.663480191201;5616.9157462638095;5640.633358831704;5665.575081868877;5586.078297080977;5649.096144616841;5665.291838520185;5686.488216060575;5688.374278829326;6162.9831077872095;6167.629942203482;6169.568130230886;6247.9687601562155;6275.02737075754;6488.6106833589665;6647.734090636369;6651;6668.599884006927;6685.699143538553;6675.132232809752;6691.962745774413;6703.195616525999;6707.429868461187;6710.563813352116;6191.597546159485;6208.658523781235;6211.130441738161;6220;6221.9688901555655;6805.115335006517;6827.146606674166;6838.418734407631;6878.621675807779;6882.621455819878;6520.576162549419;6530.346965303432;6534.575252608565;6557.082008518812;6569.80290591283;5804.506616937523;6571.31001244872;6634.40290373859;6640.269570347258;6646.8005959821285;6580.243272023683;6635.767748128792;6672.732180885713;6695.096162018162;6764.5603035802515;6625.966870165618;6681.799546013627;6704.932950670431;6714.529982901247;6725.428338591177;5292.497212764782;5409.486098820606;5411.107167317348;5411.404745477994;5428.619996600279;4652.674312798139;4654.2785868190485;4662.927526668817;4683.578477937002;4720.221163508051;5825.24271844666;5829.8251052468495;5831.883362332815;5834.5624078194305;5846.064629659234;6697.263301036882;6759.222689390783;6769.882969310107;6807.897881531781;6840.452763778985;5733.455321744427;5807.738651760681;5825.330087040044;5892.381299963442;5913.231279933581;5893.734781934823;5956.910646340309;5985.820425387245;5987.012142996444;5989.951758442256;6471;6531.0529973153625;6576.44100251484;6595.208574971034;6615.536912416181;6735.49483788708;6741.898871517029;6747;6840.31596840312;6874.209465911485;6461.450776683847;6522.34776522344;6571.80284591463;6585.374389432996;6585.374389432996;6568.113304703804;6572.211334639874;6673.132492775908;6679.2318883329035;6679.666016699242;6736.831579210363;6741;6801.18385793708;6831.829204269933;6872.175338959357;6527.608343499405;6529.281779004211;6535.673216339259;6632.403083722488;6634.270230274762;6532.346765323429;6535.607863528204;6606.372394622503;6617.106690596708;6634.137562116894;6504.73981040772;6510.44661203784;6513.1532900722605;6518.152640156749;6523.412892839668;6579.769708060195;6595.934040659532;6651.16860392451;6675.065490831191;6704.692584946084;6334.588251763621;6379.617222966451;6404.263509696445;6404.519661025349;6435.742570297136 05:30:23 INFO - PID 4890 | |1;ext.html;846.991530084699;880.4673172730498;894.1282249806435;903.9819203615928;1173.1846366775085;43127.78436107823;44183;44194.558054419445;44211.77894110529;44390.77804610981;44790.10419791602;45092.3236151458;45208;45276.54723452764;45323.320150197695;7556.206598307181;7737.613119344024;7761.844763104735;7770.33952114071;7895.96052019742;7666.693332266703;7773.378129749634;7781.105172905131;7781.4437112577425;7824.295813376795;7716.459847810655;7737.303642672158;7745.225477452238;7790.5325680459355;7829.569373684445;7582.507137036075;7624.161342252348;7626.694932202733;7768.611569421506;7778.64996075178;7665.846683066363;7684.884726729104;7686.077670679554;7734.690612375498;7749.031371078615;39582;39773.80113099445;39935.800320998496;40008.79995600032;40017.79991100055;3533.6643018913164;3592.892213233607;3639.290338384016;3647.708183345326;3649;31442.528362074587;31466.842665786753;31489.527657085164;31777.682223177933;31850.522242166386;5004.8999020019755;5052.0906236877345;5087.771050302746;5091.643584949055;5118.155504341779;189111;189664;189672;191321.9999999986;191783;50378.24432633514;50598;50772.49227507677;50963.490365096615;51085.48914510808;52972.205416918776;53067.20399194015;53076.73461632744;53085.734571327666;53159;16839.49481515532;17286.22212000463;17349.13254337278;17380.739288910678;17395.043272619874;12279.631611051684;12477.688057798447;14155.363008664428;14185.645358866112;14539.563813085624;15028.398864045315;15110.848891510945;15153.848461515465;15187.544373668808;15413.383464661512;5755.395683453177;5889.646621202742;5914.526837852962;6000.0999850022845;6076.969615151985;999.3404353126901;1004.2719028704277;1014.9035841595037;1016.3495362967707;1029.9382037077798;23305.300840974796;23520.882395587912;23551.411214719425;23559;23734.16930407462;1114.587602587048;1119.138263537072;1122.36586328724;1126.9380184089953;1140.2987162894926;12414.503419863286;12429.689257768629;12545.686357841127;12581.685457863627;12586.87413125857;209597;210313;211180;212304;212738;55185.72407137939;55262.44737552573;55505.44494555084;55612.72193639006;55773.721131394086;1460.5545308680955;1482.5626440200167;1496.1172907984342;1517.7723341498643;1523.3373482535133;23060.538789224458;23270.534589308456;23382.415439614146;23440.413989650395;23513.88243058774;38286.61713382942;38488.61511384962;38563.807180963915;38612.420813687255;38661.22677546377;180566;181938;182439;182618;182859;35008.29983400267;35107.12232194113;35335;37495;37583.43624845575;46711.76644116758;48713.75643121762;49034.75482622564;49088;49198.26202606892;34851.825740871136;35114.648853511135;35362.29275414529;35714;35725.28549429049;33837;34650.30699386049;35865.28269434544;35902.820485897406;35907.82046089753;38100.428493572064;38451.230975379774;38807;39060;39260;33405.66377344879;34100.317993640485;34680.30639387249;35074.64925350714;35642.64357356393;23658;24273.150439734523;24275.39311517226;24598.262052138463;24676.38309042288 05:30:23 INFO - PID 4890 | |2;jquery.html;574.1674571870785;576.875341309636;578.0981668596986;586.3667239381467;596.6688487889221;22493;23228.18701345453;23783.64324535129;23785.643215351785;23817.642735358982;26034.869825650894;26326.21021369362;26638.467230655377;26643.06749263776;26751.331216719544;6507.90238146427;6720.260771315151;6722.630255335979;6723.29405412432;6737.427318677894;6662.233843108032;6731.93268067318;6835.418989385908;7144.464165187605;7171.677274522633;7157.176924653653;7162.677679504409;7163.498555101145;7164.534305270141;7164.677589508485;7052.330028647296;7053.153621565394;7063.540869843444;7067.505274630778;7100.644967751643;6974.755883544051;6992.5105242633035;6996.860062798715;7010.929890701079;7027.964860175666;31708.365832683216;31801.840990795128;33038.33923321544;33150.502742458884;33246;1921.1354890299422;1957.7552805899263;1965.1255191439816;1966.1840336260434;1969.2024729984237;20402.28591999273;20832.166713331448;21039.68440473394;21086;21170.788292117035;2461.815363847719;2469.086438017927;2546.503431830794;2647.7484638959268;2658.096247275916;144392;147574.26212868973;148014;148171;148535;19680;20333.694994575093;21646.727093655256;21845.890770546364;22841.657375139388;23013;24023.159189428286;45773.31340029902;45812.3128153078;46488.070238595035;10602.575896964036;10712.000639820862;10802.94598527018;10957.397343146204;11041.779164416826;10104.797904041878;10132.138768204784;10143.23925705575;10277.94861025705;10344.896551034544;9096.90903090974;9142.862857057149;9191.862122068174;9364;9734.707958761248;2575.3304140923497;2588.2623452315997;2684.2484104450787;2720.8231464954715;2746.1761471558452;928.5264515097349;947.6967370441392;967.4775621164633;968.7917097824002;972.7568107973007;19646.8035319645;19662.213511459668;19694.01529923498;19721.704174437396;20297.594048118954;1010.242318261304;1024.288119756762;1037.4242295526055;1043.5617040842865;1047.120418848156;10592.470376481146;10965.177611679155;11004.174686898514;11017.944910275557;11193.888061119285;477479;482574;483956;485678;488023;123700;123822;130389;131333;133060.3346983259;1254.5546331052528;1271.097520760252;1312;1313.2186349122258;1314.723907979335;20295.4926126848;20676.48308792232;20838.27066052681;20900.686489702362;20963.580728385652;56383.71808140933;59556.70221648864;62762.372376275656;62816.685916570124;63830.68084659547;76588.61705691436;76672;76672;77070.6146469264;77095.22904770881;14851.925740371662;15240;15312.693746125238;15395.846041539442;15433.074015559103;23712.762872371055;23904.760952390254;23955.401114972265;24296.51406971886;24459.143929962367;17941.551461213574;18818.6236275271;18827;19158;19192.232310707976;16601.50195494137;17006.23471943741;17242.568935776704;17280.22238999273;17351.132443378032;18640.34758783436;19234.615307694046;19325.13036913314;19347.4195774127;19548.413547593595;14859.925700371428;15072.095674259579;15081.396744130112;15204.771928421304;15216.695666086394;18373.54066148357;18492.630147396707;18826.52933676669;18861.811381886553;18877.811221888154 05:30:23 INFO - PID 4890 | |3;mootools.html;1331.1015064831238;1392.5891861900739;1397.5388121919768;1416.3060100550726;1419.290354822589;13825.654358641026;14477.855221447795;14487.637809054779;14489.130652160864;14514.274286285696;11744;12279.63161105166;12993.48026078958;13341.266230357327;13352.799708004364;2416.250962201714;2449.3876530867283;2461.4215659320043;2478.2689106713474;2481.0944005438064;2565.743425657429;2613.189911127547;2683.7718793902445;2725.3322935880637;2751.793615478847;2752.8348299102017;2777.125205560252;2778.0693467688347;2778.6804517480446;2782.2627003844;2629.211236629013;2633.5128001319817;2666.0269001814295;2675.4247836715003;2729;2587.598922167067;2638.5250654882116;2679.42392385638;2680.9463810723873;2689.636899018627;13878.097923635032;14010.719785604231;14075.718485630332;14107.576772696835;14121.78817317741;7423.220561841037;7669.7315593953945;7697.076350837935;7715.807104822425;7756.224377562255;5080.669756465855;5309.814156504503;5380.139177731544;5394.973025134849;5460.890782184374;1892.4511891551433;1928.1323404468046;1935.3516571948414;1968.9704654430195;1969.2910552201197;23304.76695233043;23480.64779028316;23538.882305588533;23559.4110147246;23642.172523961577;6546.705398256994;6557.573757705733;6588.934110658928;6679.365460281266;6719.966400168066;15877.682446351007;15904.602384940468;15911.124888131264;15912.522624321058;15941;7367.557946523226;7725.5364678119495;7726.111497177784;7728.304452599293;7732.806679832936;6923.549969252083;7013.473989450709;7097.858042839114;7128.572285662877;7156.069710937545;4790.688605240648;4970.527799859007;4971.825986090469;5015.598752099823;5091.3381260436645;1318.6044186743939;1322.973540529184;1461;1485.3093311610153;1488.2112480385413;899.5727029660859;925.7222833150028;929.177677755178;932.8880534335923;939.4785893828854;7693.49992250502;7866.134725180225;7983.2415920487465;8047.758567242992;8125.228103330174;969.418348990614;979.4417182206074;990.980180396388;994.9900500994897;1009.2682804966487;5125.15434953236;5617.578681598897;5714.857128571819;5768.509676677446;5790.131480277908;18971.71542426865;19598.804011959983;19605.411837644893;19623.116959736846;19809.306674266332;18484.537886552676;19245;19698.70451943222;19767.406977790688;19809.801901981085;10882.455877206266;11026.062784663394;11031.448427578589;11037.337759734442;11045.889541104647;7975.322097621535;8384.83230335402;8488.915110848813;8652.956735216283;8782.736517904474;16723.749143763096;16785.244663990394;16825.66348672995;16849.494515164566;17156.656866862842;16869.409570664968;17094.91452542779;17321.56696082608;17418.82581174172;17466.214020369367;10913.672589822001;11188.608398706006;11379.032782213604;11397.772044558897;11486.253393529394;11057.72355691082;12238.44926978306;12523.624291271277;12550.93724531408;12569.434375452944;9033.819323613623;10004.199664027006;10611.681649550526;10678.57285708563;10746.979036991474;10221.33561318512;10296.794064118825;10378.221633377376;10589.52347144365;10738.946305268424;8732.257758090633;8739.475631462132;8751.431156974777;8876.955615221883;8886.511241881759;6989;7052.5063245574365;7056.50604457683;7140.964295178491;7197.784066478014;6898.448124150156;6936.965315173392;6946.131733533309;6974.0236366909685;6991.335823096798 05:30:23 INFO - PID 4890 | |4;prototype.html;494.2109563380563;496.72431800350813;530.9973450132753;534.7540131539491;540.8161225183437;6146.231721034871;6205.162303089076;6239.188905442297;6319.1153238546585;6336.556441049129;5917.585768996161;6061.393860613936;6084.3611420800735;6151.907721384172;6157.415045570675;4965.659271996558;4975.925361119568;5025.874353141164;5033.597312215014;5033.723145227011;4997.400311962551;5005.249212618118;5006.1990081586955;5017.924731129017;5022.799088036474;4801.8079276828885;4932.7780249888665;4937.456879743226;4970.602351811846;5010.198368261079;4864.343313652647;5000.19996800512;5007.474215207423;5027.5977921766535;5035.54680078791;4454.2873140297725;4503.4145561077175;4527.868032991752;4530.456345238559;4539.52335004823;6038.829614452404;6050.909236361458;6063;6131.325554189036;6142.355052719489;5818.2145410369485;5823.737931793079;5847.327557330919;5847.678377689225;5849.85375365615;6550.017497375432;6665.93334066658;6669.799906002828;6709.832254193635;6724.4956628252585;4872.3422337984275;4896.657233993622;4905.901881962341;4916.877078073041;4935.6545041847085;6813.693383797741;6866.116231182915;6917.619530925797;6970.442364610819;7022.139133129623;5099.235114732745;5112.693238405707;5114.156164232934;5142.997115562504;5144.0997825381155;5163.870903227449;5170.044915639539;5173.085958195492;5187.377514698261;5196.974015129976;5745.224394706661;5748.31020277561;5753.539716822645;5769.221155144001;5781.53747700183;6547.934520654828;6645.933540664628;6699.564528305645;6710.429613482811;6729.327067293231;1668.1742537444043;1711.2299465240692;1728.1791149204028;1772.9645407091787;1778.6482273472254;1199.8860108289698;1233.173773571709;1238.5726924211313;1241.267652085274;1260.9243445393306;5681.289838770153;5746.942530574641;5769.423057694281;5769.826905192851;5771.192033115367;6549.9345006550275;6588.901166482507;6635.734570617218;6670.366315200048;6746;5671.553753792757;5675.1770993205755;5734.627249228787;5740.68426236561;5782.2483077199695;3532.0463474861644;3560.5193298904833;3576.2311103112957;3585.6952159066773;3592.98792676097;6423.728220733254;6449.580777249464;6451.387118223761;6524.575902566318;6544.9672751635935;6872.347127022925;6894.482913781487;6923.826904327331;6974.860502790018;7005.229424763272;6428.935710642927;6458.418742313214;6469.28837827848;6523.739050437929;6538.346165383518;6623.768168114093;6629.303923088007;6695.799126026227;6708.295628958889;6729.5625784323865;6609.438197753245;6680.131582894192;6682.298358672269;6733.528652994243;6744.460443164437;6455.128557644658;6476.935230647633;6502.382273683992;6526.151600291932;6545.789029029645;4970.130227210151;4992;5023.935399406432;5027.522385373383;5031.647784655039;6790.185177778698;6804.863902722017;6814.93185068143;6830.624315662686;6848.178218613798;6404.71178796946;6461.709223085067;6522.445592124723;6535.215774106948;6625.17185351831;6456.774012909714;6476.222853257639;6514.9348506514325;6556.213254409501;6562.573432726857;6186.412290832363;6340.7463701451425;6392.20097487814;6454.225492940877;6504.512161587805;6414.967925160345;6441.3880681335195;6479.546431749733;6579.407853293226;6614.8346291343105;6233;6241.656708881055;6339.2392912850755;6372.745090196526;6388.968055159695 05:30:23 INFO - PID 4890 | |5;yui.html;908.2915326045684;912.612139840568;928.767808047988;929.1916033051242;932.5849996751448;17938.641227175463;19155.233790648374;19161.61676766464;19234.80765192348;21610.567788644257;15906.284217210225;15984.680306393866;17609.7358539622;22014.44963875904;22078.668819967672;1011.4083261292153;1046.398320965448;1047.7223535763028;1048.9213309001818;1050.5062620568358;1006.6476733143414;1047.984280235797;1048.014866025933;1052.3422860712055;1052.584229229454;1088.6516314779274;1106.336198281033;1109.4563663804718;1109.7503061811108;1109.938953357565;1094.5676457799204;1101.0750969185922;1101.9283746556448;1104.276698762308;1105.1821651977518;801.1547817053007;802.5184889066571;806.5120602035743;807.0073809214699;810.8256724804196;17825.643487130186;18028.639427211383;19920.900395498076;20201.393958181277;20220.69668954967;20936.47658808527;21166.470838229012;21299.5740085199;21563.46091347713;21809.454763630878;16278.51164465068;17149;17337.479875603753;17371.30514779407;17435.30258789647;3574.338747331726;3607.621199774012;3608.332458495187;3609.151849315409;3609.205974685565;130498;131685.34157329152;133365;134402;135323.32338338342;15599;15633.843661563238;15853.445129420415;15868.603284917739;16156.111413872115;29602.407951840843;30147.69852301493;30208.54687179694;30872.228194294872;30933.8453307732;8269.62786674601;8298.502089874626;8398.412111152284;8454.830903381899;8481.15188481147;4006.419069235003;4070.5725898780793;4278.187144442546;4455.576720211575;4481.439820022497;4850.708957462563;4899.240617704261;4947.356843610308;4962.478939711278;4962.5533701967;1028.3264461777549;1048.7483004078965;1061.686802393296;1066.3708412036935;1080.735219871121;684.3088480634495;697.9616121113286;701.7368486817444;704.7674267491716;706.0856191232366;10177.592896284066;11904.072524685791;12162.5134994601;12480.625581232578;13015.869841301655;702.6873041496485;702.7399862050971;706.8020954132851;715.059696499102;726.4950859152943;5335.973320133375;5345.251664766858;5537.916931246034;5749.3963133871175;5795.681237531892;100832.49583752034;101116.49441752744;101247.49376253072;101915.49042254742;103864.48067759613;90523;91908.54045729729;92031;93158.53420732988;94043;84999.57500212583;85311.57344213239;85757.57121214354;86436.13563864406;88049.55975220083;11880;12294.20087694297;12379.9381003098;12593.504402992345;13177.868221318047;35691.821540893165;35909;36271.455928161624;36580.90257292285;38473.42289865598;64451;67037.66481167563;67549.32450675627;69797.30202697909;70990;18686.43940681782;18742.15660295317;18843.811561884206;18871.716924245873;19307.324243651405;19101.713474298176;20273.898630506752;20490.69263961012;20538.07578658994;20541;18078.638427231646;18696.7195492065;18765.155567999198;18852.434426967215;19428;19154.425367239004;19372.4188274352;19394.8060519393;19400.2239910402;19400.902995485496;17306.394276200273;17621.295148193927;18054.187561560026;18132.274709011494;18138.909305453388;13559.593212203255;13651;13680.384382703001;13832.542403329182;13892.583222503341;13794;13832.170069795844;13846.169229846242;13877.514286999907;13920 05:30:23 INFO - PID 4890 | __end_tp_report 05:30:23 INFO - PID 4890 | __start_cc_report 05:30:23 INFO - PID 4890 | _x_x_mozilla_cycle_collect,359 05:30:23 INFO - PID 4890 | __end_cc_report 05:30:23 INFO - PID 4890 | __startTimestamp1502195423211__endTimestamp 05:30:23 INFO - PID 4890 | 05:30:23 INFO - PID 4890 | ------- Summary: start ------- 05:30:23 INFO - PID 4890 | Number of tests: 6 05:30:23 INFO - PID 4890 | 05:30:23 INFO - PID 4890 | [#0] dojo.html Cycles:165 Average:6167.41 Median:6471.00 stddev:728.95 (11.3%) stddev-sans-first:692.00 05:30:23 INFO - PID 4890 | Values: 3161.7 3180.5 3184.1 3224.7 3227.7 6230.7 6288.5 6294.9 6353.5 6378.4 6126.4 6166.4 6175.8 6206.3 6237.7 5635.6 5644.6 5651.1 5665.4 5671.4 5755.3 5766.2 5768.4 5773.6 5788.4 5609.0 5689.5 5720.8 5724.2 5741.7 5539.8 5608.7 5616.9 5640.6 5665.6 5586.1 5649.1 5665.3 5686.5 5688.4 6163.0 6167.6 6169.6 6248.0 6275.0 6488.6 6647.7 6651.0 6668.6 6685.7 6675.1 6692.0 6703.2 6707.4 6710.6 6191.6 6208.7 6211.1 6220.0 6222.0 6805.1 6827.1 6838.4 6878.6 6882.6 6520.6 6530.3 6534.6 6557.1 6569.8 5804.5 6571.3 6634.4 6640.3 6646.8 6580.2 6635.8 6672.7 6695.1 6764.6 6626.0 6681.8 6704.9 6714.5 6725.4 5292.5 5409.5 5411.1 5411.4 5428.6 4652.7 4654.3 4662.9 4683.6 4720.2 5825.2 5829.8 5831.9 5834.6 5846.1 6697.3 6759.2 6769.9 6807.9 6840.5 5733.5 5807.7 5825.3 5892.4 5913.2 5893.7 5956.9 5985.8 5987.0 5990.0 6471.0 6531.1 6576.4 6595.2 6615.5 6735.5 6741.9 6747.0 6840.3 6874.2 6461.5 6522.3 6571.8 6585.4 6585.4 6568.1 6572.2 6673.1 6679.2 6679.7 6736.8 6741.0 6801.2 6831.8 6872.2 6527.6 6529.3 6535.7 6632.4 6634.3 6532.3 6535.6 6606.4 6617.1 6634.1 6504.7 6510.4 6513.2 6518.2 6523.4 6579.8 6595.9 6651.2 6675.1 6704.7 6334.6 6379.6 6404.3 6404.5 6435.7 05:30:23 INFO - PID 4890 | 05:30:23 INFO - PID 4890 | [#1] ext.html Cycles:180 Average:37724.99 Median:3643.50 stddev:50434.76 (1384.2%) stddev-sans-first:50500.22 05:30:23 INFO - PID 4890 | Values: 847.0 880.5 894.1 904.0 1173.2 43127.8 44183.0 44194.6 44211.8 44390.8 44790.1 45092.3 45208.0 45276.5 45323.3 7556.2 7737.6 7761.8 7770.3 7896.0 7666.7 7773.4 7781.1 7781.4 7824.3 7716.5 7737.3 7745.2 7790.5 7829.6 7582.5 7624.2 7626.7 7768.6 7778.6 7665.8 7684.9 7686.1 7734.7 7749.0 39582.0 39773.8 39935.8 40008.8 40017.8 3533.7 3592.9 3639.3 3647.7 3649.0 31442.5 31466.8 31489.5 31777.7 31850.5 5004.9 5052.1 5087.8 5091.6 5118.2 189111.0 189664.0 189672.0 191322.0 191783.0 50378.2 50598.0 50772.5 50963.5 51085.5 52972.2 53067.2 53076.7 53085.7 53159.0 16839.5 17286.2 17349.1 17380.7 17395.0 12279.6 12477.7 14155.4 14185.6 14539.6 15028.4 15110.8 15153.8 15187.5 15413.4 5755.4 5889.6 5914.5 6000.1 6077.0 999.3 1004.3 1014.9 1016.3 1029.9 23305.3 23520.9 23551.4 23559.0 23734.2 1114.6 1119.1 1122.4 1126.9 1140.3 12414.5 12429.7 12545.7 12581.7 12586.9 209597.0 210313.0 211180.0 212304.0 212738.0 55185.7 55262.4 55505.4 55612.7 55773.7 1460.6 1482.6 1496.1 1517.8 1523.3 23060.5 23270.5 23382.4 23440.4 23513.9 38286.6 38488.6 38563.8 38612.4 38661.2 180566.0 181938.0 182439.0 182618.0 182859.0 35008.3 35107.1 35335.0 37495.0 37583.4 46711.8 48713.8 49034.8 49088.0 49198.3 34851.8 35114.6 35362.3 35714.0 35725.3 33837.0 34650.3 35865.3 35902.8 35907.8 38100.4 38451.2 38807.0 39060.0 39260.0 33405.7 34100.3 34680.3 35074.6 35642.6 23658.0 24273.2 24275.4 24598.3 24676.4 05:30:23 INFO - PID 4890 | 05:30:23 INFO - PID 4890 | [#2] jquery.html Cycles:180 Average:36522.02 Median:22927.33 stddev:82266.56 (358.8%) stddev-sans-first:82453.07 05:30:23 INFO - PID 4890 | Values: 574.2 576.9 578.1 586.4 596.7 22493.0 23228.2 23783.6 23785.6 23817.6 26034.9 26326.2 26638.5 26643.1 26751.3 6507.9 6720.3 6722.6 6723.3 6737.4 6662.2 6731.9 6835.4 7144.5 7171.7 7157.2 7162.7 7163.5 7164.5 7164.7 7052.3 7053.2 7063.5 7067.5 7100.6 6974.8 6992.5 6996.9 7010.9 7028.0 31708.4 31801.8 33038.3 33150.5 33246.0 1921.1 1957.8 1965.1 1966.2 1969.2 20402.3 20832.2 21039.7 21086.0 21170.8 2461.8 2469.1 2546.5 2647.7 2658.1 144392.0 147574.3 148014.0 148171.0 148535.0 19680.0 20333.7 21646.7 21845.9 22841.7 23013.0 24023.2 45773.3 45812.3 46488.1 10602.6 10712.0 10802.9 10957.4 11041.8 10104.8 10132.1 10143.2 10277.9 10344.9 9096.9 9142.9 9191.9 9364.0 9734.7 2575.3 2588.3 2684.2 2720.8 2746.2 928.5 947.7 967.5 968.8 972.8 19646.8 19662.2 19694.0 19721.7 20297.6 1010.2 1024.3 1037.4 1043.6 1047.1 10592.5 10965.2 11004.2 11017.9 11193.9 477479.0 482574.0 483956.0 485678.0 488023.0 123700.0 123822.0 130389.0 131333.0 133060.3 1254.6 1271.1 1312.0 1313.2 1314.7 20295.5 20676.5 20838.3 20900.7 20963.6 56383.7 59556.7 62762.4 62816.7 63830.7 76588.6 76672.0 76672.0 77070.6 77095.2 14851.9 15240.0 15312.7 15395.8 15433.1 23712.8 23904.8 23955.4 24296.5 24459.1 17941.6 18818.6 18827.0 19158.0 19192.2 16601.5 17006.2 17242.6 17280.2 17351.1 18640.3 19234.6 19325.1 19347.4 19548.4 14859.9 15072.1 15081.4 15204.8 15216.7 18373.5 18492.6 18826.5 18861.8 18877.8 05:30:23 INFO - PID 4890 | 05:30:23 INFO - PID 4890 | [#3] mootools.html Cycles:180 Average:8686.32 Median:2576.67 stddev:5924.49 (229.9%) stddev-sans-first:5915.33 05:30:23 INFO - PID 4890 | Values: 1331.1 1392.6 1397.5 1416.3 1419.3 13825.7 14477.9 14487.6 14489.1 14514.3 11744.0 12279.6 12993.5 13341.3 13352.8 2416.3 2449.4 2461.4 2478.3 2481.1 2565.7 2613.2 2683.8 2725.3 2751.8 2752.8 2777.1 2778.1 2778.7 2782.3 2629.2 2633.5 2666.0 2675.4 2729.0 2587.6 2638.5 2679.4 2680.9 2689.6 13878.1 14010.7 14075.7 14107.6 14121.8 7423.2 7669.7 7697.1 7715.8 7756.2 5080.7 5309.8 5380.1 5395.0 5460.9 1892.5 1928.1 1935.4 1969.0 1969.3 23304.8 23480.6 23538.9 23559.4 23642.2 6546.7 6557.6 6588.9 6679.4 6720.0 15877.7 15904.6 15911.1 15912.5 15941.0 7367.6 7725.5 7726.1 7728.3 7732.8 6923.5 7013.5 7097.9 7128.6 7156.1 4790.7 4970.5 4971.8 5015.6 5091.3 1318.6 1323.0 1461.0 1485.3 1488.2 899.6 925.7 929.2 932.9 939.5 7693.5 7866.1 7983.2 8047.8 8125.2 969.4 979.4 991.0 995.0 1009.3 5125.2 5617.6 5714.9 5768.5 5790.1 18971.7 19598.8 19605.4 19623.1 19809.3 18484.5 19245.0 19698.7 19767.4 19809.8 10882.5 11026.1 11031.4 11037.3 11045.9 7975.3 8384.8 8488.9 8653.0 8782.7 16723.7 16785.2 16825.7 16849.5 17156.7 16869.4 17094.9 17321.6 17418.8 17466.2 10913.7 11188.6 11379.0 11397.8 11486.3 11057.7 12238.4 12523.6 12550.9 12569.4 9033.8 10004.2 10611.7 10678.6 10747.0 10221.3 10296.8 10378.2 10589.5 10738.9 8732.3 8739.5 8751.4 8877.0 8886.5 6989.0 7052.5 7056.5 7141.0 7197.8 6898.4 6937.0 6946.1 6974.0 6991.3 05:30:23 INFO - PID 4890 | 05:30:23 INFO - PID 4890 | [#4] prototype.html Cycles:180 Average:5524.48 Median:6107.84 stddev:1544.90 (25.3%) stddev-sans-first:1502.39 05:30:23 INFO - PID 4890 | Values: 494.2 496.7 531.0 534.8 540.8 6146.2 6205.2 6239.2 6319.1 6336.6 5917.6 6061.4 6084.4 6151.9 6157.4 4965.7 4975.9 5025.9 5033.6 5033.7 4997.4 5005.2 5006.2 5017.9 5022.8 4801.8 4932.8 4937.5 4970.6 5010.2 4864.3 5000.2 5007.5 5027.6 5035.5 4454.3 4503.4 4527.9 4530.5 4539.5 6038.8 6050.9 6063.0 6131.3 6142.4 5818.2 5823.7 5847.3 5847.7 5849.9 6550.0 6665.9 6669.8 6709.8 6724.5 4872.3 4896.7 4905.9 4916.9 4935.7 6813.7 6866.1 6917.6 6970.4 7022.1 5099.2 5112.7 5114.2 5143.0 5144.1 5163.9 5170.0 5173.1 5187.4 5197.0 5745.2 5748.3 5753.5 5769.2 5781.5 6547.9 6645.9 6699.6 6710.4 6729.3 1668.2 1711.2 1728.2 1773.0 1778.6 1199.9 1233.2 1238.6 1241.3 1260.9 5681.3 5746.9 5769.4 5769.8 5771.2 6549.9 6588.9 6635.7 6670.4 6746.0 5671.6 5675.2 5734.6 5740.7 5782.2 3532.0 3560.5 3576.2 3585.7 3593.0 6423.7 6449.6 6451.4 6524.6 6545.0 6872.3 6894.5 6923.8 6974.9 7005.2 6428.9 6458.4 6469.3 6523.7 6538.3 6623.8 6629.3 6695.8 6708.3 6729.6 6609.4 6680.1 6682.3 6733.5 6744.5 6455.1 6476.9 6502.4 6526.2 6545.8 4970.1 4992.0 5023.9 5027.5 5031.6 6790.2 6804.9 6814.9 6830.6 6848.2 6404.7 6461.7 6522.4 6535.2 6625.2 6456.8 6476.2 6514.9 6556.2 6562.6 6186.4 6340.7 6392.2 6454.2 6504.5 6415.0 6441.4 6479.5 6579.4 6614.8 6233.0 6241.7 6339.2 6372.7 6389.0 05:30:23 INFO - PID 4890 | 05:30:23 INFO - PID 4890 | [#5] yui.html Cycles:180 Average:23391.60 Median:19383.61 stddev:31694.48 (163.5%) stddev-sans-first:31738.42 05:30:23 INFO - PID 4890 | Values: 908.3 912.6 928.8 929.2 932.6 17938.6 19155.2 19161.6 19234.8 21610.6 15906.3 15984.7 17609.7 22014.4 22078.7 1011.4 1046.4 1047.7 1048.9 1050.5 1006.6 1048.0 1048.0 1052.3 1052.6 1088.7 1106.3 1109.5 1109.8 1109.9 1094.6 1101.1 1101.9 1104.3 1105.2 801.2 802.5 806.5 807.0 810.8 17825.6 18028.6 19920.9 20201.4 20220.7 20936.5 21166.5 21299.6 21563.5 21809.5 16278.5 17149.0 17337.5 17371.3 17435.3 3574.3 3607.6 3608.3 3609.2 3609.2 130498.0 131685.3 133365.0 134402.0 135323.3 15599.0 15633.8 15853.4 15868.6 16156.1 29602.4 30147.7 30208.5 30872.2 30933.8 8269.6 8298.5 8398.4 8454.8 8481.2 4006.4 4070.6 4278.2 4455.6 4481.4 4850.7 4899.2 4947.4 4962.5 4962.6 1028.3 1048.7 1061.7 1066.4 1080.7 684.3 698.0 701.7 704.8 706.1 10177.6 11904.1 12162.5 12480.6 13015.9 702.7 702.7 706.8 715.1 726.5 5336.0 5345.3 5537.9 5749.4 5795.7 100832.5 101116.5 101247.5 101915.5 103864.5 90523.0 91908.5 92031.0 93158.5 94043.0 84999.6 85311.6 85757.6 86436.1 88049.6 11880.0 12294.2 12379.9 12593.5 13177.9 35691.8 35909.0 36271.5 36580.9 38473.4 64451.0 67037.7 67549.3 69797.3 70990.0 18686.4 18742.2 18843.8 18871.7 19307.3 19101.7 20273.9 20490.7 20538.1 20541.0 18078.6 18696.7 18765.2 18852.4 19428.0 19154.4 19372.4 19394.8 19400.2 19400.9 17306.4 17621.3 18054.2 18132.3 18138.9 13559.6 13651.0 13680.4 13832.5 13892.6 13794.0 13832.2 13846.2 13877.5 13920.0 05:30:23 INFO - PID 4890 | -------- Summary: end -------- 05:30:23 INFO - PID 4890 | 05:30:23 INFO - TEST-INFO | 4890: exit 0 05:30:23 INFO - TEST-OK | dromaeo_css | took 1112404ms 05:30:23 INFO - TEST-START | kraken 05:30:23 INFO - Initialising browser for kraken test... 05:30:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58848/getInfo.html -profile /tmp/tmpx4n1wU/profile 05:30:23 INFO - TEST-INFO | started process 5258 (/builds/slave/test/build/application/firefox/firefox http://localhost:58848/getInfo.html) 05:30:29 INFO - TEST-INFO | 5258: exit 0 05:30:30 INFO - Browser initialized. 05:30:30 INFO - Running cycle 1/1 for kraken test... 05:30:30 INFO - TEST-INFO | started process 5464 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx4n1wU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:30:31 INFO - PID 5464 | 05:30:31 INFO - PID 5464 | (/builds/slave/test/build/application/firefox/firefox:5513): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:30:31 INFO - PID 5464 | 05:30:41 INFO - PID 5464 | RSS: Main: 171974656 05:30:41 INFO - PID 5464 | 05:30:44 INFO - PID 5464 | Cycle 1(1): loaded http://localhost:58848/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58848/tests/kraken/driver.html?testName=audio-beat-detection) 05:30:44 INFO - PID 5464 | RSS: Main: 177393664 05:30:44 INFO - PID 5464 | 05:30:47 INFO - PID 5464 | Cycle 1(1): loaded http://localhost:58848/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58848/tests/kraken/driver.html?testName=audio-dft) 05:30:48 INFO - PID 5464 | RSS: Main: 178057216 05:30:48 INFO - PID 5464 | 05:30:51 INFO - PID 5464 | Cycle 1(1): loaded http://localhost:58848/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58848/tests/kraken/driver.html?testName=audio-fft) 05:30:51 INFO - PID 5464 | RSS: Main: 171831296 05:30:51 INFO - PID 5464 | 05:30:54 INFO - PID 5464 | Cycle 1(1): loaded http://localhost:58848/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58848/tests/kraken/driver.html?testName=audio-oscillator) 05:30:54 INFO - PID 5464 | RSS: Main: 172584960 05:30:54 INFO - PID 5464 | 05:30:56 INFO - PID 5464 | Cycle 1(1): loaded http://localhost:58848/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58848/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:30:57 INFO - PID 5464 | RSS: Main: 172539904 05:30:57 INFO - PID 5464 | 05:31:00 INFO - PID 5464 | Cycle 1(1): loaded http://localhost:58848/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58848/tests/kraken/driver.html?testName=imaging-darkroom) 05:31:01 INFO - PID 5464 | RSS: Main: 175058944 05:31:01 INFO - PID 5464 | 05:31:04 INFO - PID 5464 | Cycle 1(1): loaded http://localhost:58848/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58848/tests/kraken/driver.html?testName=imaging-desaturate) 05:31:05 INFO - PID 5464 | RSS: Main: 175833088 05:31:05 INFO - PID 5464 | 05:31:08 INFO - PID 5464 | Cycle 1(1): loaded http://localhost:58848/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58848/tests/kraken/driver.html?testName=json-parse-financial) 05:31:08 INFO - PID 5464 | RSS: Main: 175734784 05:31:08 INFO - PID 5464 | 05:31:10 INFO - PID 5464 | Cycle 1(1): loaded http://localhost:58848/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58848/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:31:11 INFO - PID 5464 | RSS: Main: 175927296 05:31:11 INFO - PID 5464 | 05:31:12 INFO - PID 5464 | Cycle 1(1): loaded http://localhost:58848/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58848/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:31:13 INFO - PID 5464 | RSS: Main: 175636480 05:31:13 INFO - PID 5464 | 05:31:15 INFO - PID 5464 | Cycle 1(1): loaded http://localhost:58848/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58848/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:31:16 INFO - PID 5464 | RSS: Main: 172408832 05:31:16 INFO - PID 5464 | 05:31:18 INFO - PID 5464 | Cycle 1(1): loaded http://localhost:58848/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58848/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:31:19 INFO - PID 5464 | RSS: Main: 173080576 05:31:19 INFO - PID 5464 | 05:31:22 INFO - PID 5464 | Cycle 1(1): loaded http://localhost:58848/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58848/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:31:23 INFO - PID 5464 | RSS: Main: 173092864 05:31:23 INFO - PID 5464 | 05:31:25 INFO - PID 5464 | Cycle 1(1): loaded http://localhost:58848/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58848/tests/kraken/driver.html?testName=ai-astar) 05:31:25 INFO - PID 5464 | RSS: Main: 174661632 05:31:25 INFO - PID 5464 | 05:31:25 INFO - PID 5464 | __start_tp_report 05:31:25 INFO - PID 5464 | _x_x_mozilla_page_load 05:31:25 INFO - PID 5464 | _x_x_mozilla_page_load_details 05:31:25 INFO - PID 5464 | |i|pagename|runs| 05:31:25 INFO - PID 5464 | |0;ai-astar;99;108;102;108;110;110;108;98;108;111 05:31:25 INFO - PID 5464 | |1;audio-beat-detection;148;120;146;179;122;152;149;126;181;119 05:31:25 INFO - PID 5464 | |2;audio-dft;164;163;164;165;164;163;170;168;165;167 05:31:25 INFO - PID 5464 | |3;audio-fft;75;75;74;183;79;76;79;77;152;77 05:31:25 INFO - PID 5464 | |4;audio-oscillator;79;80;134;80;80;80;126;80;79;131 05:31:25 INFO - PID 5464 | |5;imaging-gaussian-blur;116;115;117;115;117;114;114;114;114;116 05:31:25 INFO - PID 5464 | |6;imaging-darkroom;153;152;151;153;153;153;154;151;155;151 05:31:25 INFO - PID 5464 | |7;imaging-desaturate;94;95;94;94;95;94;95;95;95;95 05:31:25 INFO - PID 5464 | |8;json-parse-financial;53;53;54;54;54;54;53;54;54;52 05:31:25 INFO - PID 5464 | |9;json-stringify-tinderbox;54;54;54;54;54;54;54;55;54;54 05:31:25 INFO - PID 5464 | |10;stanford-crypto-aes;80;78;79;79;87;90;78;91;89;78 05:31:25 INFO - PID 5464 | |11;stanford-crypto-ccm;135;145;131;129;138;127;132;126;132;166 05:31:25 INFO - PID 5464 | |12;stanford-crypto-pbkdf2;191;177;185;192;179;181;181;176;192;190 05:31:25 INFO - PID 5464 | |13;stanford-crypto-sha256-iterative;67;70;66;67;65;73;66;66;67;68 05:31:25 INFO - PID 5464 | __end_tp_report 05:31:25 INFO - PID 5464 | __start_cc_report 05:31:25 INFO - PID 5464 | _x_x_mozilla_cycle_collect,967 05:31:25 INFO - PID 5464 | __end_cc_report 05:31:25 INFO - PID 5464 | __startTimestamp1502195485471__endTimestamp 05:31:25 INFO - PID 5464 | 05:31:25 INFO - PID 5464 | ------- Summary: start ------- 05:31:25 INFO - PID 5464 | Number of tests: 14 05:31:25 INFO - PID 5464 | 05:31:25 INFO - PID 5464 | [#0] ai-astar Cycles:10 Average:106.20 Median:110.00 stddev:4.73 (4.3%) stddev-sans-first:4.24 05:31:25 INFO - PID 5464 | Values: 99.0 108.0 102.0 108.0 110.0 110.0 108.0 98.0 108.0 111.0 05:31:25 INFO - PID 5464 | 05:31:25 INFO - PID 5464 | [#1] audio-beat-detection Cycles:10 Average:144.20 Median:148.50 stddev:22.88 (15.4%) stddev-sans-first:24.23 05:31:25 INFO - PID 5464 | Values: 148.0 120.0 146.0 179.0 122.0 152.0 149.0 126.0 181.0 119.0 05:31:25 INFO - PID 5464 | 05:31:25 INFO - PID 5464 | [#2] audio-dft Cycles:10 Average:165.30 Median:165.00 stddev:2.31 (1.4%) stddev-sans-first:2.40 05:31:25 INFO - PID 5464 | Values: 164.0 163.0 164.0 165.0 164.0 163.0 170.0 168.0 165.0 167.0 05:31:25 INFO - PID 5464 | 05:31:25 INFO - PID 5464 | [#3] audio-fft Cycles:10 Average:94.70 Median:76.50 stddev:39.09 (51.1%) stddev-sans-first:40.81 05:31:25 INFO - PID 5464 | Values: 75.0 75.0 74.0 183.0 79.0 76.0 79.0 77.0 152.0 77.0 05:31:25 INFO - PID 5464 | 05:31:25 INFO - PID 5464 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:80.00 stddev:24.53 (30.7%) stddev-sans-first:25.33 05:31:25 INFO - PID 5464 | Values: 79.0 80.0 134.0 80.0 80.0 80.0 126.0 80.0 79.0 131.0 05:31:25 INFO - PID 5464 | 05:31:25 INFO - PID 5464 | [#5] imaging-gaussian-blur Cycles:10 Average:115.20 Median:115.50 stddev:1.23 (1.1%) stddev-sans-first:1.27 05:31:25 INFO - PID 5464 | Values: 116.0 115.0 117.0 115.0 117.0 114.0 114.0 114.0 114.0 116.0 05:31:25 INFO - PID 5464 | 05:31:25 INFO - PID 5464 | [#6] imaging-darkroom Cycles:10 Average:152.60 Median:153.00 stddev:1.35 (0.9%) stddev-sans-first:1.42 05:31:25 INFO - PID 5464 | Values: 153.0 152.0 151.0 153.0 153.0 153.0 154.0 151.0 155.0 151.0 05:31:25 INFO - PID 5464 | 05:31:25 INFO - PID 5464 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 05:31:25 INFO - PID 5464 | Values: 94.0 95.0 94.0 94.0 95.0 94.0 95.0 95.0 95.0 95.0 05:31:25 INFO - PID 5464 | 05:31:25 INFO - PID 5464 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:54.00 stddev:0.71 (1.3%) stddev-sans-first:0.73 05:31:25 INFO - PID 5464 | Values: 53.0 53.0 54.0 54.0 54.0 54.0 53.0 54.0 54.0 52.0 05:31:25 INFO - PID 5464 | 05:31:25 INFO - PID 5464 | [#9] json-stringify-tinderbox Cycles:10 Average:54.10 Median:54.00 stddev:0.32 (0.6%) stddev-sans-first:0.33 05:31:25 INFO - PID 5464 | Values: 54.0 54.0 54.0 54.0 54.0 54.0 54.0 55.0 54.0 54.0 05:31:25 INFO - PID 5464 | 05:31:25 INFO - PID 5464 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:83.50 stddev:5.59 (6.7%) stddev-sans-first:5.83 05:31:25 INFO - PID 5464 | Values: 80.0 78.0 79.0 79.0 87.0 90.0 78.0 91.0 89.0 78.0 05:31:25 INFO - PID 5464 | 05:31:25 INFO - PID 5464 | [#11] stanford-crypto-ccm Cycles:10 Average:136.10 Median:133.50 stddev:11.89 (8.9%) stddev-sans-first:12.61 05:31:25 INFO - PID 5464 | Values: 135.0 145.0 131.0 129.0 138.0 127.0 132.0 126.0 132.0 166.0 05:31:25 INFO - PID 5464 | 05:31:25 INFO - PID 5464 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.40 Median:187.50 stddev:6.40 (3.4%) stddev-sans-first:6.32 05:31:25 INFO - PID 5464 | Values: 191.0 177.0 185.0 192.0 179.0 181.0 181.0 176.0 192.0 190.0 05:31:25 INFO - PID 5464 | 05:31:25 INFO - PID 5464 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.00 stddev:2.37 (3.5%) stddev-sans-first:2.51 05:31:25 INFO - PID 5464 | Values: 67.0 70.0 66.0 67.0 65.0 73.0 66.0 66.0 67.0 68.0 05:31:25 INFO - PID 5464 | -------- Summary: end -------- 05:31:25 INFO - PID 5464 | 05:31:25 INFO - PID 5464 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 05:31:26 INFO - TEST-INFO | 5464: exit 0 05:31:26 INFO - TEST-OK | kraken | took 62344ms 05:31:26 INFO - SUITE-END | took 1174s 05:31:26 INFO - Completed test suite (00:19:35) 05:31:26 INFO - javascript benchmark 05:31:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8618.252808829453, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3161.74706023518, 3180.5070214116813, 3184.0607020928824, 3224.7258982986446, 3227.7256433203174, 6230.7196176172065, 6288.465480434163, 6294.874102517947, 6353.491720662348, 6378.3621637836195, 6126.387361263872, 6166.3525329840395, 6175.814725558229, 6206.317305096447, 6237.656928868911, 5635.633683810539, 5644.57665675074, 5651.124075768242, 5665.433456654343, 5671.404502527247, 5755.309362876461, 5766.1639062335935, 5768.42315768424, 5773.624714393551, 5788.392218817026, 5608.971955140219, 5689.544836413098, 5720.827230417775, 5724.227229324029, 5741.655500669952, 5539.806106786284, 5608.663480191201, 5616.9157462638095, 5640.633358831704, 5665.575081868877, 5586.078297080977, 5649.096144616841, 5665.291838520185, 5686.488216060575, 5688.374278829326, 6162.9831077872095, 6167.629942203482, 6169.568130230886, 6247.9687601562155, 6275.02737075754, 6488.6106833589665, 6647.734090636369, 6651.0, 6668.599884006927, 6685.699143538553, 6675.132232809752, 6691.962745774413, 6703.195616525999, 6707.429868461187, 6710.563813352116, 6191.597546159485, 6208.658523781235, 6211.130441738161, 6220.0, 6221.9688901555655, 6805.115335006517, 6827.146606674166, 6838.418734407631, 6878.621675807779, 6882.621455819878, 6520.576162549419, 6530.346965303432, 6534.575252608565, 6557.082008518812, 6569.80290591283, 5804.506616937523, 6571.31001244872, 6634.40290373859, 6640.269570347258, 6646.8005959821285, 6580.243272023683, 6635.767748128792, 6672.732180885713, 6695.096162018162, 6764.5603035802515, 6625.966870165618, 6681.799546013627, 6704.932950670431, 6714.529982901247, 6725.428338591177, 5292.497212764782, 5409.486098820606, 5411.107167317348, 5411.404745477994, 5428.619996600279, 4652.674312798139, 4654.2785868190485, 4662.927526668817, 4683.578477937002, 4720.221163508051, 5825.24271844666, 5829.8251052468495, 5831.883362332815, 5834.5624078194305, 5846.064629659234, 6697.263301036882, 6759.222689390783, 6769.882969310107, 6807.897881531781, 6840.452763778985, 5733.455321744427, 5807.738651760681, 5825.330087040044, 5892.381299963442, 5913.231279933581, 5893.734781934823, 5956.910646340309, 5985.820425387245, 5987.012142996444, 5989.951758442256, 6471.0, 6531.0529973153625, 6576.44100251484, 6595.208574971034, 6615.536912416181, 6735.49483788708, 6741.898871517029, 6747.0, 6840.31596840312, 6874.209465911485, 6461.450776683847, 6522.34776522344, 6571.80284591463, 6585.374389432996, 6585.374389432996, 6568.113304703804, 6572.211334639874, 6673.132492775908, 6679.2318883329035, 6679.666016699242, 6736.831579210363, 6741.0, 6801.18385793708, 6831.829204269933, 6872.175338959357, 6527.608343499405, 6529.281779004211, 6535.673216339259, 6632.403083722488, 6634.270230274762, 6532.346765323429, 6535.607863528204, 6606.372394622503, 6617.106690596708, 6634.137562116894, 6504.73981040772, 6510.44661203784, 6513.1532900722605, 6518.152640156749, 6523.412892839668, 6579.769708060195, 6595.934040659532, 6651.16860392451, 6675.065490831191, 6704.692584946084, 6334.588251763621, 6379.617222966451, 6404.263509696445, 6404.519661025349, 6435.742570297136], "value": 6113.2415138681445, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [846.991530084699, 880.4673172730498, 894.1282249806435, 903.9819203615928, 1173.1846366775085, 43127.78436107823, 44183.0, 44194.558054419445, 44211.77894110529, 44390.77804610981, 44790.10419791602, 45092.3236151458, 45208.0, 45276.54723452764, 45323.320150197695, 7556.206598307181, 7737.613119344024, 7761.844763104735, 7770.33952114071, 7895.96052019742, 7666.693332266703, 7773.378129749634, 7781.105172905131, 7781.4437112577425, 7824.295813376795, 7716.459847810655, 7737.303642672158, 7745.225477452238, 7790.5325680459355, 7829.569373684445, 7582.507137036075, 7624.161342252348, 7626.694932202733, 7768.611569421506, 7778.64996075178, 7665.846683066363, 7684.884726729104, 7686.077670679554, 7734.690612375498, 7749.031371078615, 39582.0, 39773.80113099445, 39935.800320998496, 40008.79995600032, 40017.79991100055, 3533.6643018913164, 3592.892213233607, 3639.290338384016, 3647.708183345326, 3649.0, 31442.528362074587, 31466.842665786753, 31489.527657085164, 31777.682223177933, 31850.522242166386, 5004.8999020019755, 5052.0906236877345, 5087.771050302746, 5091.643584949055, 5118.155504341779, 189111.0, 189664.0, 189672.0, 191321.9999999986, 191783.0, 50378.24432633514, 50598.0, 50772.49227507677, 50963.490365096615, 51085.48914510808, 52972.205416918776, 53067.20399194015, 53076.73461632744, 53085.734571327666, 53159.0, 16839.49481515532, 17286.22212000463, 17349.13254337278, 17380.739288910678, 17395.043272619874, 12279.631611051684, 12477.688057798447, 14155.363008664428, 14185.645358866112, 14539.563813085624, 15028.398864045315, 15110.848891510945, 15153.848461515465, 15187.544373668808, 15413.383464661512, 5755.395683453177, 5889.646621202742, 5914.526837852962, 6000.0999850022845, 6076.969615151985, 999.3404353126901, 1004.2719028704277, 1014.9035841595037, 1016.3495362967707, 1029.9382037077798, 23305.300840974796, 23520.882395587912, 23551.411214719425, 23559.0, 23734.16930407462, 1114.587602587048, 1119.138263537072, 1122.36586328724, 1126.9380184089953, 1140.2987162894926, 12414.503419863286, 12429.689257768629, 12545.686357841127, 12581.685457863627, 12586.87413125857, 209597.0, 210313.0, 211180.0, 212304.0, 212738.0, 55185.72407137939, 55262.44737552573, 55505.44494555084, 55612.72193639006, 55773.721131394086, 1460.5545308680955, 1482.5626440200167, 1496.1172907984342, 1517.7723341498643, 1523.3373482535133, 23060.538789224458, 23270.534589308456, 23382.415439614146, 23440.413989650395, 23513.88243058774, 38286.61713382942, 38488.61511384962, 38563.807180963915, 38612.420813687255, 38661.22677546377, 180566.0, 181938.0, 182439.0, 182618.0, 182859.0, 35008.29983400267, 35107.12232194113, 35335.0, 37495.0, 37583.43624845575, 46711.76644116758, 48713.75643121762, 49034.75482622564, 49088.0, 49198.26202606892, 34851.825740871136, 35114.648853511135, 35362.29275414529, 35714.0, 35725.28549429049, 33837.0, 34650.30699386049, 35865.28269434544, 35902.820485897406, 35907.82046089753, 38100.428493572064, 38451.230975379774, 38807.0, 39060.0, 39260.0, 33405.66377344879, 34100.317993640485, 34680.30639387249, 35074.64925350714, 35642.64357356393, 23658.0, 24273.150439734523, 24275.39311517226, 24598.262052138463, 24676.38309042288], "value": 17719.259771890403, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.1674571870785, 576.875341309636, 578.0981668596986, 586.3667239381467, 596.6688487889221, 22493.0, 23228.18701345453, 23783.64324535129, 23785.643215351785, 23817.642735358982, 26034.869825650894, 26326.21021369362, 26638.467230655377, 26643.06749263776, 26751.331216719544, 6507.90238146427, 6720.260771315151, 6722.630255335979, 6723.29405412432, 6737.427318677894, 6662.233843108032, 6731.93268067318, 6835.418989385908, 7144.464165187605, 7171.677274522633, 7157.176924653653, 7162.677679504409, 7163.498555101145, 7164.534305270141, 7164.677589508485, 7052.330028647296, 7053.153621565394, 7063.540869843444, 7067.505274630778, 7100.644967751643, 6974.755883544051, 6992.5105242633035, 6996.860062798715, 7010.929890701079, 7027.964860175666, 31708.365832683216, 31801.840990795128, 33038.33923321544, 33150.502742458884, 33246.0, 1921.1354890299422, 1957.7552805899263, 1965.1255191439816, 1966.1840336260434, 1969.2024729984237, 20402.28591999273, 20832.166713331448, 21039.68440473394, 21086.0, 21170.788292117035, 2461.815363847719, 2469.086438017927, 2546.503431830794, 2647.7484638959268, 2658.096247275916, 144392.0, 147574.26212868973, 148014.0, 148171.0, 148535.0, 19680.0, 20333.694994575093, 21646.727093655256, 21845.890770546364, 22841.657375139388, 23013.0, 24023.159189428286, 45773.31340029902, 45812.3128153078, 46488.070238595035, 10602.575896964036, 10712.000639820862, 10802.94598527018, 10957.397343146204, 11041.779164416826, 10104.797904041878, 10132.138768204784, 10143.23925705575, 10277.94861025705, 10344.896551034544, 9096.90903090974, 9142.862857057149, 9191.862122068174, 9364.0, 9734.707958761248, 2575.3304140923497, 2588.2623452315997, 2684.2484104450787, 2720.8231464954715, 2746.1761471558452, 928.5264515097349, 947.6967370441392, 967.4775621164633, 968.7917097824002, 972.7568107973007, 19646.8035319645, 19662.213511459668, 19694.01529923498, 19721.704174437396, 20297.594048118954, 1010.242318261304, 1024.288119756762, 1037.4242295526055, 1043.5617040842865, 1047.120418848156, 10592.470376481146, 10965.177611679155, 11004.174686898514, 11017.944910275557, 11193.888061119285, 477479.0, 482574.0, 483956.0, 485678.0, 488023.0, 123700.0, 123822.0, 130389.0, 131333.0, 133060.3346983259, 1254.5546331052528, 1271.097520760252, 1312.0, 1313.2186349122258, 1314.723907979335, 20295.4926126848, 20676.48308792232, 20838.27066052681, 20900.686489702362, 20963.580728385652, 56383.71808140933, 59556.70221648864, 62762.372376275656, 62816.685916570124, 63830.68084659547, 76588.61705691436, 76672.0, 76672.0, 77070.6146469264, 77095.22904770881, 14851.925740371662, 15240.0, 15312.693746125238, 15395.846041539442, 15433.074015559103, 23712.762872371055, 23904.760952390254, 23955.401114972265, 24296.51406971886, 24459.143929962367, 17941.551461213574, 18818.6236275271, 18827.0, 19158.0, 19192.232310707976, 16601.50195494137, 17006.23471943741, 17242.568935776704, 17280.22238999273, 17351.132443378032, 18640.34758783436, 19234.615307694046, 19325.13036913314, 19347.4195774127, 19548.413547593595, 14859.925700371428, 15072.095674259579, 15081.396744130112, 15204.771928421304, 15216.695666086394, 18373.54066148357, 18492.630147396707, 18826.52933676669, 18861.811381886553, 18877.811221888154], "value": 12781.49974862082, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.1015064831238, 1392.5891861900739, 1397.5388121919768, 1416.3060100550726, 1419.290354822589, 13825.654358641026, 14477.855221447795, 14487.637809054779, 14489.130652160864, 14514.274286285696, 11744.0, 12279.63161105166, 12993.48026078958, 13341.266230357327, 13352.799708004364, 2416.250962201714, 2449.3876530867283, 2461.4215659320043, 2478.2689106713474, 2481.0944005438064, 2565.743425657429, 2613.189911127547, 2683.7718793902445, 2725.3322935880637, 2751.793615478847, 2752.8348299102017, 2777.125205560252, 2778.0693467688347, 2778.6804517480446, 2782.2627003844, 2629.211236629013, 2633.5128001319817, 2666.0269001814295, 2675.4247836715003, 2729.0, 2587.598922167067, 2638.5250654882116, 2679.42392385638, 2680.9463810723873, 2689.636899018627, 13878.097923635032, 14010.719785604231, 14075.718485630332, 14107.576772696835, 14121.78817317741, 7423.220561841037, 7669.7315593953945, 7697.076350837935, 7715.807104822425, 7756.224377562255, 5080.669756465855, 5309.814156504503, 5380.139177731544, 5394.973025134849, 5460.890782184374, 1892.4511891551433, 1928.1323404468046, 1935.3516571948414, 1968.9704654430195, 1969.2910552201197, 23304.76695233043, 23480.64779028316, 23538.882305588533, 23559.4110147246, 23642.172523961577, 6546.705398256994, 6557.573757705733, 6588.934110658928, 6679.365460281266, 6719.966400168066, 15877.682446351007, 15904.602384940468, 15911.124888131264, 15912.522624321058, 15941.0, 7367.557946523226, 7725.5364678119495, 7726.111497177784, 7728.304452599293, 7732.806679832936, 6923.549969252083, 7013.473989450709, 7097.858042839114, 7128.572285662877, 7156.069710937545, 4790.688605240648, 4970.527799859007, 4971.825986090469, 5015.598752099823, 5091.3381260436645, 1318.6044186743939, 1322.973540529184, 1461.0, 1485.3093311610153, 1488.2112480385413, 899.5727029660859, 925.7222833150028, 929.177677755178, 932.8880534335923, 939.4785893828854, 7693.49992250502, 7866.134725180225, 7983.2415920487465, 8047.758567242992, 8125.228103330174, 969.418348990614, 979.4417182206074, 990.980180396388, 994.9900500994897, 1009.2682804966487, 5125.15434953236, 5617.578681598897, 5714.857128571819, 5768.509676677446, 5790.131480277908, 18971.71542426865, 19598.804011959983, 19605.411837644893, 19623.116959736846, 19809.306674266332, 18484.537886552676, 19245.0, 19698.70451943222, 19767.406977790688, 19809.801901981085, 10882.455877206266, 11026.062784663394, 11031.448427578589, 11037.337759734442, 11045.889541104647, 7975.322097621535, 8384.83230335402, 8488.915110848813, 8652.956735216283, 8782.736517904474, 16723.749143763096, 16785.244663990394, 16825.66348672995, 16849.494515164566, 17156.656866862842, 16869.409570664968, 17094.91452542779, 17321.56696082608, 17418.82581174172, 17466.214020369367, 10913.672589822001, 11188.608398706006, 11379.032782213604, 11397.772044558897, 11486.253393529394, 11057.72355691082, 12238.44926978306, 12523.624291271277, 12550.93724531408, 12569.434375452944, 9033.819323613623, 10004.199664027006, 10611.681649550526, 10678.57285708563, 10746.979036991474, 10221.33561318512, 10296.794064118825, 10378.221633377376, 10589.52347144365, 10738.946305268424, 8732.257758090633, 8739.475631462132, 8751.431156974777, 8876.955615221883, 8886.511241881759, 6989.0, 7052.5063245574365, 7056.50604457683, 7140.964295178491, 7197.784066478014, 6898.448124150156, 6936.965315173392, 6946.131733533309, 6974.0236366909685, 6991.335823096798], "value": 6374.03135669951, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.2109563380563, 496.72431800350813, 530.9973450132753, 534.7540131539491, 540.8161225183437, 6146.231721034871, 6205.162303089076, 6239.188905442297, 6319.1153238546585, 6336.556441049129, 5917.585768996161, 6061.393860613936, 6084.3611420800735, 6151.907721384172, 6157.415045570675, 4965.659271996558, 4975.925361119568, 5025.874353141164, 5033.597312215014, 5033.723145227011, 4997.400311962551, 5005.249212618118, 5006.1990081586955, 5017.924731129017, 5022.799088036474, 4801.8079276828885, 4932.7780249888665, 4937.456879743226, 4970.602351811846, 5010.198368261079, 4864.343313652647, 5000.19996800512, 5007.474215207423, 5027.5977921766535, 5035.54680078791, 4454.2873140297725, 4503.4145561077175, 4527.868032991752, 4530.456345238559, 4539.52335004823, 6038.829614452404, 6050.909236361458, 6063.0, 6131.325554189036, 6142.355052719489, 5818.2145410369485, 5823.737931793079, 5847.327557330919, 5847.678377689225, 5849.85375365615, 6550.017497375432, 6665.93334066658, 6669.799906002828, 6709.832254193635, 6724.4956628252585, 4872.3422337984275, 4896.657233993622, 4905.901881962341, 4916.877078073041, 4935.6545041847085, 6813.693383797741, 6866.116231182915, 6917.619530925797, 6970.442364610819, 7022.139133129623, 5099.235114732745, 5112.693238405707, 5114.156164232934, 5142.997115562504, 5144.0997825381155, 5163.870903227449, 5170.044915639539, 5173.085958195492, 5187.377514698261, 5196.974015129976, 5745.224394706661, 5748.31020277561, 5753.539716822645, 5769.221155144001, 5781.53747700183, 6547.934520654828, 6645.933540664628, 6699.564528305645, 6710.429613482811, 6729.327067293231, 1668.1742537444043, 1711.2299465240692, 1728.1791149204028, 1772.9645407091787, 1778.6482273472254, 1199.8860108289698, 1233.173773571709, 1238.5726924211313, 1241.267652085274, 1260.9243445393306, 5681.289838770153, 5746.942530574641, 5769.423057694281, 5769.826905192851, 5771.192033115367, 6549.9345006550275, 6588.901166482507, 6635.734570617218, 6670.366315200048, 6746.0, 5671.553753792757, 5675.1770993205755, 5734.627249228787, 5740.68426236561, 5782.2483077199695, 3532.0463474861644, 3560.5193298904833, 3576.2311103112957, 3585.6952159066773, 3592.98792676097, 6423.728220733254, 6449.580777249464, 6451.387118223761, 6524.575902566318, 6544.9672751635935, 6872.347127022925, 6894.482913781487, 6923.826904327331, 6974.860502790018, 7005.229424763272, 6428.935710642927, 6458.418742313214, 6469.28837827848, 6523.739050437929, 6538.346165383518, 6623.768168114093, 6629.303923088007, 6695.799126026227, 6708.295628958889, 6729.5625784323865, 6609.438197753245, 6680.131582894192, 6682.298358672269, 6733.528652994243, 6744.460443164437, 6455.128557644658, 6476.935230647633, 6502.382273683992, 6526.151600291932, 6545.789029029645, 4970.130227210151, 4992.0, 5023.935399406432, 5027.522385373383, 5031.647784655039, 6790.185177778698, 6804.863902722017, 6814.93185068143, 6830.624315662686, 6848.178218613798, 6404.71178796946, 6461.709223085067, 6522.445592124723, 6535.215774106948, 6625.17185351831, 6456.774012909714, 6476.222853257639, 6514.9348506514325, 6556.213254409501, 6562.573432726857, 6186.412290832363, 6340.7463701451425, 6392.20097487814, 6454.225492940877, 6504.512161587805, 6414.967925160345, 6441.3880681335195, 6479.546431749733, 6579.407853293226, 6614.8346291343105, 6233.0, 6241.656708881055, 6339.2392912850755, 6372.745090196526, 6388.968055159695], "value": 5070.262865365069, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [908.2915326045684, 912.612139840568, 928.767808047988, 929.1916033051242, 932.5849996751448, 17938.641227175463, 19155.233790648374, 19161.61676766464, 19234.80765192348, 21610.567788644257, 15906.284217210225, 15984.680306393866, 17609.7358539622, 22014.44963875904, 22078.668819967672, 1011.4083261292153, 1046.398320965448, 1047.7223535763028, 1048.9213309001818, 1050.5062620568358, 1006.6476733143414, 1047.984280235797, 1048.014866025933, 1052.3422860712055, 1052.584229229454, 1088.6516314779274, 1106.336198281033, 1109.4563663804718, 1109.7503061811108, 1109.938953357565, 1094.5676457799204, 1101.0750969185922, 1101.9283746556448, 1104.276698762308, 1105.1821651977518, 801.1547817053007, 802.5184889066571, 806.5120602035743, 807.0073809214699, 810.8256724804196, 17825.643487130186, 18028.639427211383, 19920.900395498076, 20201.393958181277, 20220.69668954967, 20936.47658808527, 21166.470838229012, 21299.5740085199, 21563.46091347713, 21809.454763630878, 16278.51164465068, 17149.0, 17337.479875603753, 17371.30514779407, 17435.30258789647, 3574.338747331726, 3607.621199774012, 3608.332458495187, 3609.151849315409, 3609.205974685565, 130498.0, 131685.34157329152, 133365.0, 134402.0, 135323.32338338342, 15599.0, 15633.843661563238, 15853.445129420415, 15868.603284917739, 16156.111413872115, 29602.407951840843, 30147.69852301493, 30208.54687179694, 30872.228194294872, 30933.8453307732, 8269.62786674601, 8298.502089874626, 8398.412111152284, 8454.830903381899, 8481.15188481147, 4006.419069235003, 4070.5725898780793, 4278.187144442546, 4455.576720211575, 4481.439820022497, 4850.708957462563, 4899.240617704261, 4947.356843610308, 4962.478939711278, 4962.5533701967, 1028.3264461777549, 1048.7483004078965, 1061.686802393296, 1066.3708412036935, 1080.735219871121, 684.3088480634495, 697.9616121113286, 701.7368486817444, 704.7674267491716, 706.0856191232366, 10177.592896284066, 11904.072524685791, 12162.5134994601, 12480.625581232578, 13015.869841301655, 702.6873041496485, 702.7399862050971, 706.8020954132851, 715.059696499102, 726.4950859152943, 5335.973320133375, 5345.251664766858, 5537.916931246034, 5749.3963133871175, 5795.681237531892, 100832.49583752034, 101116.49441752744, 101247.49376253072, 101915.49042254742, 103864.48067759613, 90523.0, 91908.54045729729, 92031.0, 93158.53420732988, 94043.0, 84999.57500212583, 85311.57344213239, 85757.57121214354, 86436.13563864406, 88049.55975220083, 11880.0, 12294.20087694297, 12379.9381003098, 12593.504402992345, 13177.868221318047, 35691.821540893165, 35909.0, 36271.455928161624, 36580.90257292285, 38473.42289865598, 64451.0, 67037.66481167563, 67549.32450675627, 69797.30202697909, 70990.0, 18686.43940681782, 18742.15660295317, 18843.811561884206, 18871.716924245873, 19307.324243651405, 19101.713474298176, 20273.898630506752, 20490.69263961012, 20538.07578658994, 20541.0, 18078.638427231646, 18696.7195492065, 18765.155567999198, 18852.434426967215, 19428.0, 19154.425367239004, 19372.4188274352, 19394.8060519393, 19400.2239910402, 19400.902995485496, 17306.394276200273, 17621.295148193927, 18054.187561560026, 18132.274709011494, 18138.909305453388, 13559.593212203255, 13651.0, 13680.384382703001, 13832.542403329182, 13892.583222503341, 13794.0, 13832.170069795844, 13846.169229846242, 13877.514286999907, 13920.0], "value": 9156.792525776778, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1546.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 108.0, 102.0, 108.0, 110.0, 110.0, 108.0, 98.0, 108.0, 111.0], "value": 106.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 120.0, 146.0, 179.0, 122.0, 152.0, 149.0, 126.0, 181.0, 119.0], "value": 144.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 164.0, 165.0, 164.0, 163.0, 170.0, 168.0, 165.0, 167.0], "value": 165.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 183.0, 79.0, 76.0, 79.0, 77.0, 152.0, 77.0], "value": 94.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 134.0, 80.0, 80.0, 80.0, 126.0, 80.0, 79.0, 131.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 117.0, 115.0, 117.0, 114.0, 114.0, 114.0, 114.0, 116.0], "value": 115.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 152.0, 151.0, 153.0, 153.0, 153.0, 154.0, 151.0, 155.0, 151.0], "value": 152.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 54.0, 54.0, 54.0, 54.0, 53.0, 54.0, 54.0, 52.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0, 55.0, 54.0, 54.0], "value": 54.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 79.0, 79.0, 87.0, 90.0, 78.0, 91.0, 89.0, 78.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 145.0, 131.0, 129.0, 138.0, 127.0, 132.0, 126.0, 132.0, 166.0], "value": 136.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 177.0, 185.0, 192.0, 179.0, 181.0, 181.0, 176.0, 192.0, 190.0], "value": 184.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 66.0, 67.0, 65.0, 73.0, 66.0, 66.0, 67.0, 68.0], "value": 67.5, "unit": "score"}]}]} 05:31:26 INFO - Return code: 0 05:31:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:31:26 INFO - # TBPL SUCCESS # 05:31:26 INFO - Running post-action listener: _package_coverage_data 05:31:26 INFO - Running post-action listener: _resource_record_post_action 05:31:26 INFO - [mozharness: 2017-08-08 12:31:26.758400Z] Finished run-tests step (success) 05:31:26 INFO - Running post-run listener: _resource_record_post_run 05:31:26 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 8192; Write bytes: 430600192; Read time: 28; Write time: 423220 05:31:26 INFO - TinderboxPrint: CPU usage
13.2% 05:31:26 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 28 05:31:26 INFO - TinderboxPrint: I/O write bytes / time
430,600,192 / 423,220 05:31:26 INFO - TinderboxPrint: CPU idle
8,256.0 (86.8%) 05:31:26 INFO - TinderboxPrint: CPU user
1,202.9 (12.6%) 05:31:26 INFO - TinderboxPrint: Swap in / out
0 / 0 05:31:26 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 109707264; Read time: 0; Write time: 151712 05:31:26 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:31:26 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 320499712; Read time: 20; Write time: 271468 05:31:27 INFO - Running post-run listener: _upload_blobber_files 05:31:27 INFO - Blob upload gear active. 05:31:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:31:27 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 05:31:27 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'] 05:31:27 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 05:31:27 INFO - (blobuploader) - INFO - Open directory for files ... 05:31:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:31:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:31:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:28 INFO - (blobuploader) - INFO - Done attempting. 05:31:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:31:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:31:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:35 INFO - (blobuploader) - INFO - Done attempting. 05:31:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:31:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:31:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:36 INFO - (blobuploader) - INFO - Done attempting. 05:31:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:31:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:31:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:37 INFO - (blobuploader) - INFO - Done attempting. 05:31:37 INFO - (blobuploader) - INFO - Iteration through files over. 05:31:37 INFO - Return code: 0 05:31:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:31:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:31:37 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ff87569625095feaf695ee6c998387f078b0b00148582de268025c4bc9aabd4a4ee40e6f8d9bd99bacdf50a7281a7c5cec6d7311caf3f37968dab23a5a4afdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/73bae46ae7a7e9adc52c8ddac4cc35d02d49223887c8881b3b4273bb0d149025fbd431b09d36f99f275f94e5f7360a4197b314e5e83646a651184e627c4fccce", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c2e087a36315c2ce3e7cf916b9def6598150905af7a24d43fb2739acf92dd31e753afec766bd129cf955a8ac8a6fb6970a15c994ec2b36ecd70b21e76c1ac76c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/737fc25ed1968dfa7f2db422e49e919134d495962fdbe944746f75869c38d346a50891dc59fcbd2377bca0f35d1e15bc71ce04ea632646bd9652e2ff0d981404"} 05:31:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:31:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:31:37 INFO - Contents: 05:31:37 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ff87569625095feaf695ee6c998387f078b0b00148582de268025c4bc9aabd4a4ee40e6f8d9bd99bacdf50a7281a7c5cec6d7311caf3f37968dab23a5a4afdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/73bae46ae7a7e9adc52c8ddac4cc35d02d49223887c8881b3b4273bb0d149025fbd431b09d36f99f275f94e5f7360a4197b314e5e83646a651184e627c4fccce", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c2e087a36315c2ce3e7cf916b9def6598150905af7a24d43fb2739acf92dd31e753afec766bd129cf955a8ac8a6fb6970a15c994ec2b36ecd70b21e76c1ac76c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/737fc25ed1968dfa7f2db422e49e919134d495962fdbe944746f75869c38d346a50891dc59fcbd2377bca0f35d1e15bc71ce04ea632646bd9652e2ff0d981404"} 05:31:37 INFO - Running post-run listener: copy_logs_to_upload_dir 05:31:37 INFO - Copying logs to upload dir... 05:31:37 INFO - mkdir: /builds/slave/test/build/upload/logs 05:31:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.603041 ========= master_lag: 0.19 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-08 05:31:36.868006) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-08 05:31:36.871940) ========= 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=52faae308a556c440401f8e5000001e8-1502194240.471162-214435039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ff87569625095feaf695ee6c998387f078b0b00148582de268025c4bc9aabd4a4ee40e6f8d9bd99bacdf50a7281a7c5cec6d7311caf3f37968dab23a5a4afdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/73bae46ae7a7e9adc52c8ddac4cc35d02d49223887c8881b3b4273bb0d149025fbd431b09d36f99f275f94e5f7360a4197b314e5e83646a651184e627c4fccce", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c2e087a36315c2ce3e7cf916b9def6598150905af7a24d43fb2739acf92dd31e753afec766bd129cf955a8ac8a6fb6970a15c994ec2b36ecd70b21e76c1ac76c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/737fc25ed1968dfa7f2db422e49e919134d495962fdbe944746f75869c38d346a50891dc59fcbd2377bca0f35d1e15bc71ce04ea632646bd9652e2ff0d981404"} build_url:https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011341 build_url: 'https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ff87569625095feaf695ee6c998387f078b0b00148582de268025c4bc9aabd4a4ee40e6f8d9bd99bacdf50a7281a7c5cec6d7311caf3f37968dab23a5a4afdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/73bae46ae7a7e9adc52c8ddac4cc35d02d49223887c8881b3b4273bb0d149025fbd431b09d36f99f275f94e5f7360a4197b314e5e83646a651184e627c4fccce", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c2e087a36315c2ce3e7cf916b9def6598150905af7a24d43fb2739acf92dd31e753afec766bd129cf955a8ac8a6fb6970a15c994ec2b36ecd70b21e76c1ac76c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/737fc25ed1968dfa7f2db422e49e919134d495962fdbe944746f75869c38d346a50891dc59fcbd2377bca0f35d1e15bc71ce04ea632646bd9652e2ff0d981404"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-08 05:31:36.896786) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:31:36.897125) ========= 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=52faae308a556c440401f8e5000001e8-1502194240.471162-214435039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004437 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:31:36.979553) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-08 05:31:36.979878) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-08 05:31:36.980178) ========= ========= Total master_lag: 0.34 =========