builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-021 starttime: 1500295860.26 results: success (0) revision: d43779e278d2e4d3e21dba2fcb585a3bf4b1288e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-17 05:51:00.260019) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-17 05:51:00.260565) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-17 05:51:00.260949) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-17 05:51:00.277604) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 05:51:00.277975) ========= 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=df17445c3590ff7897617a0e00000225-1500288197.379760-841038802 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-17 05:51:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 108M=0s 2017-07-17 05:51:00 (108 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.074034 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 05:51:00.368630) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 05:51:00.369058) ========= 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=df17445c3590ff7897617a0e00000225-1500288197.379760-841038802 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021342 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 05:51:00.422778) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-17 05:51:00.423165) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d43779e278d2e4d3e21dba2fcb585a3bf4b1288e --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 d43779e278d2e4d3e21dba2fcb585a3bf4b1288e --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=df17445c3590ff7897617a0e00000225-1500288197.379760-841038802 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-17 05:51:01,101 truncating revision to first 12 chars 2017-07-17 05:51:01,101 Setting DEBUG logging. 2017-07-17 05:51:01,102 attempt 1/10 2017-07-17 05:51:01,102 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d43779e278d2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-17 05:51:01,831 unpacking tar archive at: mozilla-central-d43779e278d2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.047460 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-17 05:51:01.483322) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-17 05:51:01.483663) ========= script_repo_revision: d43779e278d2e4d3e21dba2fcb585a3bf4b1288e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-17 05:51:01.484095) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-17 05:51:01.484412) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-17 05:51:01.497583) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-07-17 05:51:01.498000) ========= /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=df17445c3590ff7897617a0e00000225-1500288197.379760-841038802 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:51:02 INFO - MultiFileLogger online at 20170717 05:51:02 in /builds/slave/test 05:51:02 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:51:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:51:02 INFO - {'append_to_log': False, 05:51:02 INFO - 'base_work_dir': '/builds/slave/test', 05:51:02 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 05:51:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:51:02 INFO - 'branch': 'Firefox-Non-PGO', 05:51:02 INFO - 'buildbot_json_path': 'buildprops.json', 05:51:02 INFO - 'code_coverage': False, 05:51:02 INFO - 'config_files': ('talos/linux_config.py',), 05:51:02 INFO - 'default_actions': ('clobber', 05:51:02 INFO - 'read-buildbot-config', 05:51:02 INFO - 'download-and-extract', 05:51:02 INFO - 'populate-webroot', 05:51:02 INFO - 'create-virtualenv', 05:51:02 INFO - 'install', 05:51:02 INFO - 'setup-mitmproxy', 05:51:02 INFO - 'run-tests'), 05:51:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:51:02 INFO - 'disable_ccov_upload': False, 05:51:02 INFO - 'download_minidump_stackwalk': True, 05:51:02 INFO - 'download_symbols': 'ondemand', 05:51:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:51:02 INFO - 'tooltool.py': '/tools/tooltool.py', 05:51:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:51:02 INFO - '/tools/misc-python/virtualenv.py')}, 05:51:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:51:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:51:02 INFO - 'gecko_profile': False, 05:51:02 INFO - 'gecko_profile_interval': 0, 05:51:02 INFO - 'installer_path': 'installer.exe', 05:51:02 INFO - 'log_level': 'info', 05:51:02 INFO - 'log_name': 'talos', 05:51:02 INFO - 'log_to_console': True, 05:51:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:51:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:51:02 INFO - 'opt_config_files': (), 05:51:02 INFO - 'pip_index': False, 05:51:02 INFO - 'suite': 'dromaeojs-e10s', 05:51:02 INFO - 'system_bits': '32', 05:51:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:51:02 INFO - 'title': 'talos-linux64-ix-021', 05:51:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:51:02 INFO - 'use_talos_json': True, 05:51:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:51:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:51:02 INFO - 'work_dir': 'build'} 05:51:02 INFO - [mozharness: 2017-07-17 12:51:02.275253Z] Running clobber step. 05:51:02 INFO - Running pre-action listener: _resource_record_pre_action 05:51:02 INFO - Running main action method: clobber 05:51:02 INFO - rmtree: /builds/slave/test/build 05:51:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:51:03 INFO - Running post-action listener: _resource_record_post_action 05:51:03 INFO - [mozharness: 2017-07-17 12:51:03.826974Z] Finished clobber step (success) 05:51:03 INFO - [mozharness: 2017-07-17 12:51:03.827085Z] Running read-buildbot-config step. 05:51:03 INFO - Running pre-action listener: _resource_record_pre_action 05:51:03 INFO - Running main action method: read_buildbot_config 05:51:03 INFO - Using buildbot properties: 05:51:03 INFO - { 05:51:03 INFO - "project": "", 05:51:03 INFO - "product": "firefox", 05:51:03 INFO - "who": "archaeopteryx@coole-files.de", 05:51:03 INFO - "installer_path": "public/build/target.tar.bz2", 05:51:03 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:51:03 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 05:51:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 05:51:03 INFO - "stage_platform": "linux64", 05:51:03 INFO - "basedir": "/builds/slave/test", 05:51:03 INFO - "buildnumber": 723, 05:51:03 INFO - "platform": "ubuntu64_hw", 05:51:03 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:51:03 INFO - "slavebuilddir": "test", 05:51:03 INFO - "taskId": "DphGZifLR5iLosgjpvhEBQ", 05:51:03 INFO - "branch": "mozilla-central", 05:51:03 INFO - "script_repo_revision": "production", 05:51:03 INFO - "revision": "d43779e278d2e4d3e21dba2fcb585a3bf4b1288e", 05:51:03 INFO - "slavename": "talos-linux64-ix-021", 05:51:03 INFO - "repo_path": "mozilla-central" 05:51:03 INFO - } 05:51:03 INFO - Finding installer, test and symbols from parent task. 05:51:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DphGZifLR5iLosgjpvhEBQ'}, attempt #1 05:51:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DphGZifLR5iLosgjpvhEBQ'}, attempt #1 05:51:04 INFO - Task dependencies: YTzcMzTxSwOAIfzphKHUfw 05:51:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw'}, attempt #1 05:51:05 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.tar.bz2 05:51:05 INFO - Set test_url: https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/test_packages.json 05:51:05 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.crashreporter-symbols.zip 05:51:05 INFO - Running post-action listener: _resource_record_post_action 05:51:05 INFO - [mozharness: 2017-07-17 12:51:05.172049Z] Finished read-buildbot-config step (success) 05:51:05 INFO - [mozharness: 2017-07-17 12:51:05.172271Z] Running download-and-extract step. 05:51:05 INFO - Running pre-action listener: _resource_record_pre_action 05:51:05 INFO - Running main action method: download_and_extract 05:51:05 INFO - mkdir: /builds/slave/test/build/tests 05:51:05 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:51:05 INFO - trying https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.test_packages.json 05:51:05 INFO - Downloading https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:51:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:51:05 INFO - Downloaded 989 bytes. 05:51:05 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:51:05 INFO - Using the following test package requirements: 05:51:05 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:51:05 INFO - u'common': [u'target.common.tests.zip'], 05:51:05 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:51:05 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:51:05 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:51:05 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:51:05 INFO - u'mozbase': [u'target.common.tests.zip'], 05:51:05 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:51:05 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:51:05 INFO - u'web-platform': [u'target.common.tests.zip', 05:51:05 INFO - u'target.web-platform.tests.tar.gz'], 05:51:05 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:51:05 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:51:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.common.tests.zip 05:51:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:51:05 INFO - Fetch https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.common.tests.zip into memory 05:51:08 INFO - Content-Length response header: 72769101 05:51:08 INFO - Bytes received: 72769101 05:51:19 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:51:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.common.tests.zip 05:51:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:51:19 INFO - Fetch https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.common.tests.zip into memory 05:51:20 INFO - Content-Length response header: 72769101 05:51:20 INFO - Bytes received: 72769101 05:51:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.talos.tests.zip 05:51:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:51:32 INFO - Fetch https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.talos.tests.zip into memory 05:51:34 INFO - Content-Length response header: 14495033 05:51:34 INFO - Bytes received: 14495033 05:51:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:34 INFO - trying https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.tar.bz2 05:51:34 INFO - Downloading https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:51:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:51:35 INFO - Downloaded 61876446 bytes. 05:51:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.tar.bz2 05:51:35 INFO - mkdir: /builds/slave/test/properties 05:51:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:51:35 INFO - Writing to file /builds/slave/test/properties/build_url 05:51:35 INFO - Contents: 05:51:35 INFO - build_url:https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.tar.bz2 05:51:35 INFO - Running post-action listener: _resource_record_post_action 05:51:35 INFO - Running post-action listener: set_extra_try_arguments 05:51:35 INFO - [mozharness: 2017-07-17 12:51:35.909687Z] Finished download-and-extract step (success) 05:51:35 INFO - [mozharness: 2017-07-17 12:51:35.909917Z] Running populate-webroot step. 05:51:35 INFO - Running pre-action listener: _resource_record_pre_action 05:51:35 INFO - Running main action method: populate_webroot 05:51:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:51:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:51:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:51:35 INFO - '--tppagecycles', 05:51:35 INFO - '7'], 05:51:35 INFO - 'tests': ['tsvgm']}}, 05:51:35 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:51:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:51:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:51:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:51:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:51:35 INFO - 'talos_options': ['--disable-e10s'], 05:51:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:51:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:51:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:51:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:51:35 INFO - 'talos_options': ['--disable-e10s'], 05:51:35 INFO - 'tests': ['damp', 'tps']}, 05:51:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:51:35 INFO - 'tests': ['damp', 'tps']}, 05:51:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:51:35 INFO - 'tests': ['dromaeo_dom']}, 05:51:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:51:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:51:35 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 05:51:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:51:35 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:51:35 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:51:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:51:35 INFO - 'tests': ['a11yr', 05:51:35 INFO - 'ts_paint', 05:51:35 INFO - 'tpaint', 05:51:35 INFO - 'sessionrestore', 05:51:35 INFO - 'sessionrestore_no_auto_restore', 05:51:35 INFO - 'tabpaint']}, 05:51:35 INFO - 'other-e10s': {'tests': ['a11yr', 05:51:35 INFO - 'ts_paint', 05:51:35 INFO - 'tpaint', 05:51:35 INFO - 'sessionrestore', 05:51:35 INFO - 'sessionrestore_many_windows', 05:51:35 INFO - 'sessionrestore_no_auto_restore', 05:51:35 INFO - 'tabpaint']}, 05:51:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:51:35 INFO - 'ts_paint', 05:51:35 INFO - 'tpaint', 05:51:35 INFO - 'sessionrestore', 05:51:35 INFO - 'sessionrestore_many_windows', 05:51:35 INFO - 'sessionrestore_no_auto_restore', 05:51:35 INFO - 'tabpaint']}, 05:51:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:51:35 INFO - 'ts_paint', 05:51:35 INFO - 'tpaint', 05:51:35 INFO - 'sessionrestore', 05:51:35 INFO - 'sessionrestore_many_windows', 05:51:35 INFO - 'sessionrestore_no_auto_restore', 05:51:35 INFO - 'tabpaint']}, 05:51:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:51:35 INFO - 'tests': ['a11yr', 05:51:35 INFO - 'ts_paint', 05:51:35 INFO - 'tpaint', 05:51:35 INFO - 'sessionrestore', 05:51:35 INFO - 'sessionrestore_no_auto_restore', 05:51:35 INFO - 'tabpaint']}, 05:51:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:51:35 INFO - 'tests': ['a11yr', 05:51:35 INFO - 'ts_paint', 05:51:35 INFO - 'tpaint', 05:51:35 INFO - 'sessionrestore', 05:51:35 INFO - 'sessionrestore_no_auto_restore', 05:51:35 INFO - 'tabpaint']}, 05:51:35 INFO - 'perf-reftest': {'tests': ['bloom_basic']}, 05:51:35 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:51:35 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:51:35 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:51:35 INFO - 'talos_options': ['--mitmproxy', 05:51:35 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:51:35 INFO - '--firstNonBlankPaint'], 05:51:35 INFO - 'tests': ['quantum_pageload_google', 05:51:35 INFO - 'quantum_pageload_youtube', 05:51:35 INFO - 'quantum_pageload_amazon', 05:51:35 INFO - 'quantum_pageload_facebook']}, 05:51:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:51:35 INFO - 'tests': ['tsvgx', 05:51:35 INFO - 'tsvgr_opacity', 05:51:35 INFO - 'tart', 05:51:35 INFO - 'tscrollx', 05:51:35 INFO - 'cart', 05:51:35 INFO - 'tsvg_static']}, 05:51:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:51:35 INFO - 'tsvgr_opacity', 05:51:35 INFO - 'tart', 05:51:35 INFO - 'tscrollx', 05:51:35 INFO - 'cart', 05:51:35 INFO - 'tsvg_static']}, 05:51:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:51:35 INFO - 'talos_options': ['--disable-e10s'], 05:51:35 INFO - 'tests': ['tp5o']}, 05:51:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:51:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:51:35 INFO - 'talos_options': ['--disable-e10s', 05:51:35 INFO - '--xperf_path', 05:51:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:51:35 INFO - 'tests': ['tp5n']}, 05:51:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:51:35 INFO - 'talos_options': ['--xperf_path', 05:51:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:51:35 INFO - 'tests': ['tp5n']}}, 05:51:35 INFO - 'talos.zip': {'path': '', 05:51:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:51:35 INFO - Running post-action listener: _resource_record_post_action 05:51:35 INFO - [mozharness: 2017-07-17 12:51:35.932177Z] Finished populate-webroot step (success) 05:51:35 INFO - [mozharness: 2017-07-17 12:51:35.932268Z] Running create-virtualenv step. 05:51:35 INFO - Running pre-action listener: _resource_record_pre_action 05:51:35 INFO - Running main action method: create_virtualenv 05:51:35 INFO - Creating virtualenv /builds/slave/test/build/venv 05:51:35 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:51:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:51:35 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:51:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:51:36 INFO - Using real prefix '/usr' 05:51:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:51:36 INFO - Installing distribute.............................................................................................................................................................................................done. 05:51:38 INFO - Installing pip.................done. 05:51:38 INFO - Return code: 0 05:51:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:51:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:51:38 INFO - Reading from file tmpfile_stdout 05:51:38 INFO - Output received: 05:51:38 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:51:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:51:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f68b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f65d10>, '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 0x1f66c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f670c0>, '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': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', '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:51:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:51:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:51:38 INFO - Using env: {'DISPLAY': ':0', 05:51:38 INFO - 'HOME': '/home/cltbld', 05:51:38 INFO - 'LANG': 'en_US.UTF-8', 05:51:38 INFO - 'LANGUAGE': 'en_US:en', 05:51:38 INFO - 'LOGNAME': 'cltbld', 05:51:38 INFO - 'MAIL': '/var/mail/cltbld', 05:51:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:38 INFO - 'MOZ_NO_REMOTE': '1', 05:51:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:51:38 INFO - 'NO_EM_RESTART': '1', 05:51:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:51:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:38 INFO - 'PWD': '/builds/slave/test', 05:51:38 INFO - 'SHELL': '/bin/bash', 05:51:38 INFO - 'SHLVL': '1', 05:51:38 INFO - 'TERM': 'linux', 05:51:38 INFO - 'TMOUT': '86400', 05:51:38 INFO - 'USER': 'cltbld', 05:51:38 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', 05:51:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:38 INFO - '_': '/tools/buildbot/bin/python'} 05:51:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:38 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:51:38 INFO - Cleaning up... 05:51:38 INFO - Return code: 0 05:51:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:51:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1bf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f68b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f65d10>, '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 0x1f66c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f670c0>, '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': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', '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:51:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:51:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:51:38 INFO - Using env: {'DISPLAY': ':0', 05:51:38 INFO - 'HOME': '/home/cltbld', 05:51:38 INFO - 'LANG': 'en_US.UTF-8', 05:51:38 INFO - 'LANGUAGE': 'en_US:en', 05:51:38 INFO - 'LOGNAME': 'cltbld', 05:51:38 INFO - 'MAIL': '/var/mail/cltbld', 05:51:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:38 INFO - 'MOZ_NO_REMOTE': '1', 05:51:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:51:38 INFO - 'NO_EM_RESTART': '1', 05:51:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:51:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:38 INFO - 'PWD': '/builds/slave/test', 05:51:38 INFO - 'SHELL': '/bin/bash', 05:51:38 INFO - 'SHLVL': '1', 05:51:38 INFO - 'TERM': 'linux', 05:51:38 INFO - 'TMOUT': '86400', 05:51:38 INFO - 'USER': 'cltbld', 05:51:38 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', 05:51:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:38 INFO - '_': '/tools/buildbot/bin/python'} 05:51:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:39 INFO - Downloading/unpacking psutil>=3.1.1 05:51:39 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:51:39 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:51:39 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:51:39 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:51:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:51:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:51:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:51:40 INFO - Installing collected packages: psutil 05:51:40 INFO - Running setup.py install for psutil 05:51:40 INFO - building 'psutil._psutil_linux' extension 05:51:40 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:51:40 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:51:40 INFO - building 'psutil._psutil_posix' extension 05:51:40 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:51:40 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:51:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:51:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:51:40 INFO - Successfully installed psutil 05:51:40 INFO - Cleaning up... 05:51:40 INFO - Return code: 0 05:51:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:40 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 0x1bf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f68b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f65d10>, '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 0x1f66c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f670c0>, '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': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', '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:51:40 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:51:40 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:51:40 INFO - Using env: {'DISPLAY': ':0', 05:51:40 INFO - 'HOME': '/home/cltbld', 05:51:40 INFO - 'LANG': 'en_US.UTF-8', 05:51:40 INFO - 'LANGUAGE': 'en_US:en', 05:51:40 INFO - 'LOGNAME': 'cltbld', 05:51:40 INFO - 'MAIL': '/var/mail/cltbld', 05:51:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:40 INFO - 'MOZ_NO_REMOTE': '1', 05:51:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:51:40 INFO - 'NO_EM_RESTART': '1', 05:51:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:51:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:40 INFO - 'PWD': '/builds/slave/test', 05:51:40 INFO - 'SHELL': '/bin/bash', 05:51:40 INFO - 'SHLVL': '1', 05:51:40 INFO - 'TERM': 'linux', 05:51:40 INFO - 'TMOUT': '86400', 05:51:40 INFO - 'USER': 'cltbld', 05:51:40 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', 05:51:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:40 INFO - '_': '/tools/buildbot/bin/python'} 05:51:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:51:41 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:51:41 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:51:41 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:51:41 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:51:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:51:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:51:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:51:42 INFO - Installing collected packages: mozsystemmonitor 05:51:42 INFO - Running setup.py install for mozsystemmonitor 05:51:42 INFO - Successfully installed mozsystemmonitor 05:51:42 INFO - Cleaning up... 05:51:42 INFO - Return code: 0 05:51:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:51:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1bf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f68b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f65d10>, '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 0x1f66c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f670c0>, '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': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', '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:51:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:51:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:51:42 INFO - Using env: {'DISPLAY': ':0', 05:51:42 INFO - 'HOME': '/home/cltbld', 05:51:42 INFO - 'LANG': 'en_US.UTF-8', 05:51:42 INFO - 'LANGUAGE': 'en_US:en', 05:51:42 INFO - 'LOGNAME': 'cltbld', 05:51:42 INFO - 'MAIL': '/var/mail/cltbld', 05:51:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:42 INFO - 'MOZ_NO_REMOTE': '1', 05:51:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:51:42 INFO - 'NO_EM_RESTART': '1', 05:51:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:51:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:42 INFO - 'PWD': '/builds/slave/test', 05:51:42 INFO - 'SHELL': '/bin/bash', 05:51:42 INFO - 'SHLVL': '1', 05:51:42 INFO - 'TERM': 'linux', 05:51:42 INFO - 'TMOUT': '86400', 05:51:42 INFO - 'USER': 'cltbld', 05:51:42 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', 05:51:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:42 INFO - '_': '/tools/buildbot/bin/python'} 05:51:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:42 INFO - Downloading/unpacking jsonschema==2.5.1 05:51:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:51:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:43 INFO - Downloading functools32-3.2.3-2.tar.gz 05:51:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:51:43 INFO - warning: no files found matching '*.txt' 05:51:43 INFO - no previously-included directories found matching 'build' 05:51:43 INFO - no previously-included directories found matching 'dist' 05:51:43 INFO - no previously-included directories found matching '.git*' 05:51:43 INFO - Installing collected packages: jsonschema, functools32 05:51:43 INFO - Running setup.py install for functools32 05:51:43 INFO - warning: no files found matching '*.txt' 05:51:43 INFO - no previously-included directories found matching 'build' 05:51:43 INFO - no previously-included directories found matching 'dist' 05:51:43 INFO - no previously-included directories found matching '.git*' 05:51:43 INFO - Successfully installed jsonschema functools32 05:51:43 INFO - Cleaning up... 05:51:44 INFO - Return code: 0 05:51:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:51: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:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51: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:51:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:44 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 0x1bf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f68b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f65d10>, '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 0x1f66c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f670c0>, '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': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', '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:51:44 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:51:44 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:51:44 INFO - Using env: {'DISPLAY': ':0', 05:51:44 INFO - 'HOME': '/home/cltbld', 05:51:44 INFO - 'LANG': 'en_US.UTF-8', 05:51:44 INFO - 'LANGUAGE': 'en_US:en', 05:51:44 INFO - 'LOGNAME': 'cltbld', 05:51:44 INFO - 'MAIL': '/var/mail/cltbld', 05:51:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:44 INFO - 'MOZ_NO_REMOTE': '1', 05:51:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:51:44 INFO - 'NO_EM_RESTART': '1', 05:51:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:51:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:44 INFO - 'PWD': '/builds/slave/test', 05:51:44 INFO - 'SHELL': '/bin/bash', 05:51:44 INFO - 'SHLVL': '1', 05:51:44 INFO - 'TERM': 'linux', 05:51:44 INFO - 'TMOUT': '86400', 05:51:44 INFO - 'USER': 'cltbld', 05:51:44 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', 05:51:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:44 INFO - '_': '/tools/buildbot/bin/python'} 05:51:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:51:44 INFO - Cleaning up... 05:51:44 INFO - Return code: 0 05:51:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:51: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:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51: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:51:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:44 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 0x1bf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f68b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f65d10>, '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 0x1f66c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f670c0>, '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': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', '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:51:44 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:51:44 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:51:44 INFO - Using env: {'DISPLAY': ':0', 05:51:44 INFO - 'HOME': '/home/cltbld', 05:51:44 INFO - 'LANG': 'en_US.UTF-8', 05:51:44 INFO - 'LANGUAGE': 'en_US:en', 05:51:44 INFO - 'LOGNAME': 'cltbld', 05:51:44 INFO - 'MAIL': '/var/mail/cltbld', 05:51:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:44 INFO - 'MOZ_NO_REMOTE': '1', 05:51:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:51:44 INFO - 'NO_EM_RESTART': '1', 05:51:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:51:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:44 INFO - 'PWD': '/builds/slave/test', 05:51:44 INFO - 'SHELL': '/bin/bash', 05:51:44 INFO - 'SHLVL': '1', 05:51:44 INFO - 'TERM': 'linux', 05:51:44 INFO - 'TMOUT': '86400', 05:51:44 INFO - 'USER': 'cltbld', 05:51:44 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', 05:51:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:44 INFO - '_': '/tools/buildbot/bin/python'} 05:51:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:44 INFO - Downloading/unpacking blobuploader==1.2.4 05:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:45 INFO - Downloading blobuploader-1.2.4.tar.gz 05:51:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:51:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:51:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:51:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:51:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:46 INFO - Downloading docopt-0.6.1.tar.gz 05:51:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:51:46 INFO - Installing collected packages: blobuploader, requests, docopt 05:51:46 INFO - Running setup.py install for blobuploader 05:51:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:51:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:51:46 INFO - Running setup.py install for requests 05:51:46 INFO - Running setup.py install for docopt 05:51:46 INFO - Successfully installed blobuploader requests docopt 05:51:46 INFO - Cleaning up... 05:51:46 INFO - Return code: 0 05:51:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:51:46 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:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:46 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:51:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:46 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 0x1bf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f68b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f65d10>, '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 0x1f66c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f670c0>, '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': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', '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:51:46 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:51:46 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:51:46 INFO - Using env: {'DISPLAY': ':0', 05:51:46 INFO - 'HOME': '/home/cltbld', 05:51:46 INFO - 'LANG': 'en_US.UTF-8', 05:51:46 INFO - 'LANGUAGE': 'en_US:en', 05:51:46 INFO - 'LOGNAME': 'cltbld', 05:51:46 INFO - 'MAIL': '/var/mail/cltbld', 05:51:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:46 INFO - 'MOZ_NO_REMOTE': '1', 05:51:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:51:46 INFO - 'NO_EM_RESTART': '1', 05:51:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:51:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:46 INFO - 'PWD': '/builds/slave/test', 05:51:46 INFO - 'SHELL': '/bin/bash', 05:51:46 INFO - 'SHLVL': '1', 05:51:46 INFO - 'TERM': 'linux', 05:51:46 INFO - 'TMOUT': '86400', 05:51:46 INFO - 'USER': 'cltbld', 05:51:46 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', 05:51:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:46 INFO - '_': '/tools/buildbot/bin/python'} 05:51:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:51:46 INFO - Running setup.py (path:/tmp/pip-iwVGLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:51:47 INFO - Running setup.py (path:/tmp/pip-p3F38S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:51:47 INFO - Running setup.py (path:/tmp/pip-6McgvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:51:47 INFO - Running setup.py (path:/tmp/pip-1dsOlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:51:47 INFO - Running setup.py (path:/tmp/pip-ZA4mYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:51:47 INFO - Running setup.py (path:/tmp/pip-81X8a1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:51:47 INFO - Running setup.py (path:/tmp/pip-sJ_CCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:51:47 INFO - Running setup.py (path:/tmp/pip-Xe58ww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:51:47 INFO - Running setup.py (path:/tmp/pip-_vWEAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:51:47 INFO - Running setup.py (path:/tmp/pip-D5FPOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:51:47 INFO - Running setup.py (path:/tmp/pip-icjl3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:51:47 INFO - Running setup.py (path:/tmp/pip-5HxZWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:51:47 INFO - Running setup.py (path:/tmp/pip-EpSD6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:51:47 INFO - Running setup.py (path:/tmp/pip-dp2h4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:51:47 INFO - Running setup.py (path:/tmp/pip-RCVMHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:51:48 INFO - Running setup.py (path:/tmp/pip-pTC1hd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:51:48 INFO - Running setup.py (path:/tmp/pip-SXnUxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:51:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:51:48 INFO - Running setup.py install for manifestparser 05:51:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:51:48 INFO - Running setup.py install for mozcrash 05:51:48 INFO - Running setup.py install for mozdebug 05:51:48 INFO - Running setup.py install for mozdevice 05:51:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:51:48 INFO - Running setup.py install for mozfile 05:51:48 INFO - Running setup.py install for mozhttpd 05:51:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:51:48 INFO - Running setup.py install for mozinfo 05:51:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:51:48 INFO - Running setup.py install for mozInstall 05:51:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:51:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:51:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:51:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:51:48 INFO - Running setup.py install for mozleak 05:51:49 INFO - Running setup.py install for mozlog 05:51:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:51:49 INFO - Running setup.py install for moznetwork 05:51:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:51:49 INFO - Running setup.py install for mozprocess 05:51:49 INFO - Running setup.py install for mozprofile 05:51:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:51:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:51:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:51:49 INFO - Running setup.py install for mozrunner 05:51:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:51:49 INFO - Running setup.py install for mozscreenshot 05:51:49 INFO - Running setup.py install for moztest 05:51:49 INFO - Running setup.py install for mozversion 05:51:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:51:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:51:49 INFO - Cleaning up... 05:51:49 INFO - Return code: 0 05:51:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:51:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:49 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 0x1bf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f68b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f65d10>, '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 0x1f66c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f670c0>, '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': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', '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:51:49 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:51:49 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:51:49 INFO - Using env: {'DISPLAY': ':0', 05:51:49 INFO - 'HOME': '/home/cltbld', 05:51:49 INFO - 'LANG': 'en_US.UTF-8', 05:51:49 INFO - 'LANGUAGE': 'en_US:en', 05:51:49 INFO - 'LOGNAME': 'cltbld', 05:51:49 INFO - 'MAIL': '/var/mail/cltbld', 05:51:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:49 INFO - 'MOZ_NO_REMOTE': '1', 05:51:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:51:49 INFO - 'NO_EM_RESTART': '1', 05:51:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:51:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:49 INFO - 'PWD': '/builds/slave/test', 05:51:49 INFO - 'SHELL': '/bin/bash', 05:51:49 INFO - 'SHLVL': '1', 05:51:49 INFO - 'TERM': 'linux', 05:51:49 INFO - 'TMOUT': '86400', 05:51:49 INFO - 'USER': 'cltbld', 05:51:49 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', 05:51:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:49 INFO - '_': '/tools/buildbot/bin/python'} 05:51:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:51:50 INFO - Running setup.py (path:/tmp/pip-H5HB3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:51:50 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:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:51:50 INFO - Running setup.py (path:/tmp/pip-_HUFVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:51:50 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:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:51:50 INFO - Running setup.py (path:/tmp/pip-KFv3ZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:51:50 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:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:51:50 INFO - Running setup.py (path:/tmp/pip-0nKwCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:51:50 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:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:51:50 INFO - Running setup.py (path:/tmp/pip-hC4gGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:51:50 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:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:51:50 INFO - Running setup.py (path:/tmp/pip-tjGLIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:51:50 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:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:51:50 INFO - Running setup.py (path:/tmp/pip-rYJU70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:51:50 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:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:51:50 INFO - Running setup.py (path:/tmp/pip-CKDceQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:51:50 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:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:51:50 INFO - Running setup.py (path:/tmp/pip-8qkVN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:51:50 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:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:51:50 INFO - Running setup.py (path:/tmp/pip-OjB4As-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:51:50 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:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:51:50 INFO - Running setup.py (path:/tmp/pip-tPApuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:51:50 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:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:51:50 INFO - Running setup.py (path:/tmp/pip-Qk6sce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:51:51 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:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:51:51 INFO - Running setup.py (path:/tmp/pip-uaJV7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:51:51 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:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:51:51 INFO - Running setup.py (path:/tmp/pip-RpDd_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:51:51 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:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:51:51 INFO - Running setup.py (path:/tmp/pip-Mv8Jyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:51:51 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:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:51:51 INFO - Running setup.py (path:/tmp/pip-Q0CzWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:51:51 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:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:51:51 INFO - Running setup.py (path:/tmp/pip-fYatEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:51:51 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:51:51 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:51:51 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:51:51 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:51:51 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:51:51 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:51:51 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:51:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:52 INFO - Downloading blessings-1.6.tar.gz 05:51:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:51:52 INFO - Installing collected packages: blessings 05:51:52 INFO - Running setup.py install for blessings 05:51:52 INFO - Successfully installed blessings 05:51:52 INFO - Cleaning up... 05:51:52 INFO - Return code: 0 05:51:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:51:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:51:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:51:52 INFO - Reading from file tmpfile_stdout 05:51:52 INFO - Current package versions: 05:51:52 INFO - argparse == 1.2.1 05:51:52 INFO - blessings == 1.6 05:51:52 INFO - blobuploader == 1.2.4 05:51:52 INFO - docopt == 0.6.1 05:51:52 INFO - functools32 == 3.2.3-2 05:51:52 INFO - jsonschema == 2.5.1 05:51:52 INFO - manifestparser == 1.1 05:51:52 INFO - mozInstall == 1.13 05:51:52 INFO - mozcrash == 1.0 05:51:52 INFO - mozdebug == 0.1 05:51:52 INFO - mozdevice == 0.51 05:51:52 INFO - mozfile == 1.2 05:51:52 INFO - mozhttpd == 0.7 05:51:52 INFO - mozinfo == 0.10 05:51:52 INFO - mozleak == 0.1 05:51:52 INFO - mozlog == 3.5 05:51:52 INFO - moznetwork == 0.27 05:51:52 INFO - mozprocess == 0.25 05:51:52 INFO - mozprofile == 0.28 05:51:52 INFO - mozrunner == 6.13 05:51:52 INFO - mozscreenshot == 0.1 05:51:52 INFO - mozsystemmonitor == 0.3 05:51:52 INFO - moztest == 0.8 05:51:52 INFO - mozversion == 1.4 05:51:52 INFO - psutil == 3.1.1 05:51:52 INFO - requests == 1.2.3 05:51:52 INFO - wsgiref == 0.1.2 05:51:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:51:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:52 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 0x1bf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f68b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f65d10>, '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 0x1f66c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f670c0>, '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': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', '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:51:52 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:51:52 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:51:52 INFO - Using env: {'DISPLAY': ':0', 05:51:52 INFO - 'HOME': '/home/cltbld', 05:51:52 INFO - 'LANG': 'en_US.UTF-8', 05:51:52 INFO - 'LANGUAGE': 'en_US:en', 05:51:52 INFO - 'LOGNAME': 'cltbld', 05:51:52 INFO - 'MAIL': '/var/mail/cltbld', 05:51:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:52 INFO - 'MOZ_NO_REMOTE': '1', 05:51:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:51:52 INFO - 'NO_EM_RESTART': '1', 05:51:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:51:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:52 INFO - 'PWD': '/builds/slave/test', 05:51:52 INFO - 'SHELL': '/bin/bash', 05:51:52 INFO - 'SHLVL': '1', 05:51:52 INFO - 'TERM': 'linux', 05:51:52 INFO - 'TMOUT': '86400', 05:51:52 INFO - 'USER': 'cltbld', 05:51:52 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', 05:51:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:52 INFO - '_': '/tools/buildbot/bin/python'} 05:51:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:53 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:51:53 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:51:53 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:51:53 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:51:53 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:51:53 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:51:53 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:51:53 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:51:53 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:51:53 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:51:53 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:51:53 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:51:53 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:51:53 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:51:54 INFO - Downloading marionette_driver-1.4.0.tar.gz 05:51:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 05:51:54 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 05:51:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:51:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:51:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:51:54 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:51:54 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:51:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:51:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:51:54 INFO - Installing collected packages: marionette-driver, simplejson 05:51:54 INFO - Running setup.py install for marionette-driver 05:51:54 INFO - Running setup.py install for simplejson 05:51:54 INFO - building 'simplejson._speedups' extension 05:51:54 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:51:55 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:51:55 INFO - Successfully installed marionette-driver simplejson 05:51:55 INFO - Cleaning up... 05:51:55 INFO - Return code: 0 05:51:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:51:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:51:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:51:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f68b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f65d10>, '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 0x1f66c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f670c0>, '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': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', '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:51:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:51:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:51:55 INFO - Using env: {'DISPLAY': ':0', 05:51:55 INFO - 'HOME': '/home/cltbld', 05:51:55 INFO - 'LANG': 'en_US.UTF-8', 05:51:55 INFO - 'LANGUAGE': 'en_US:en', 05:51:55 INFO - 'LOGNAME': 'cltbld', 05:51:55 INFO - 'MAIL': '/var/mail/cltbld', 05:51:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:55 INFO - 'MOZ_NO_REMOTE': '1', 05:51:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:51:55 INFO - 'NO_EM_RESTART': '1', 05:51:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:51:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:55 INFO - 'PWD': '/builds/slave/test', 05:51:55 INFO - 'SHELL': '/bin/bash', 05:51:55 INFO - 'SHLVL': '1', 05:51:55 INFO - 'TERM': 'linux', 05:51:55 INFO - 'TMOUT': '86400', 05:51:55 INFO - 'USER': 'cltbld', 05:51:55 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', 05:51:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:55 INFO - '_': '/tools/buildbot/bin/python'} 05:51:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:51:55 INFO - Cleaning up... 05:51:55 INFO - Return code: 0 05:51:55 INFO - Running post-action listener: _resource_record_post_action 05:51:55 INFO - Running post-action listener: _start_resource_monitoring 05:51:56 INFO - Starting resource monitoring. 05:51:56 INFO - [mozharness: 2017-07-17 12:51:56.013956Z] Finished create-virtualenv step (success) 05:51:56 INFO - [mozharness: 2017-07-17 12:51:56.014467Z] Running install step. 05:51:56 INFO - Running pre-action listener: _resource_record_pre_action 05:51:56 INFO - Running main action method: install 05:51:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:51:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:51:56 INFO - Reading from file tmpfile_stdout 05:51:56 INFO - Detecting whether we're running mozinstall >=1.0... 05:51:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:51:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:51:56 INFO - Reading from file tmpfile_stdout 05:51:56 INFO - Output received: 05:51:56 INFO - Usage: mozinstall [options] installer 05:51:56 INFO - Options: 05:51:56 INFO - -h, --help show this help message and exit 05:51:56 INFO - -d DEST, --destination=DEST 05:51:56 INFO - Directory to install application into. [default: 05:51:56 INFO - "/builds/slave/test"] 05:51:56 INFO - --app=APP Application being installed. [default: firefox] 05:51:56 INFO - mkdir: /builds/slave/test/build/application 05:51:56 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:51:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:52:14 INFO - Reading from file tmpfile_stdout 05:52:14 INFO - Output received: 05:52:14 INFO - /builds/slave/test/build/application/firefox/firefox 05:52:14 INFO - Running post-action listener: _resource_record_post_action 05:52:14 INFO - [mozharness: 2017-07-17 12:52:14.259169Z] Finished install step (success) 05:52:14 INFO - [mozharness: 2017-07-17 12:52:14.259422Z] Running setup-mitmproxy step. 05:52:14 INFO - Running pre-action listener: _resource_record_pre_action 05:52:14 INFO - Running main action method: setup_mitmproxy 05:52:14 INFO - Skipping: mitmproxy is not required 05:52:14 INFO - Running post-action listener: _resource_record_post_action 05:52:14 INFO - [mozharness: 2017-07-17 12:52:14.260571Z] Finished setup-mitmproxy step (success) 05:52:14 INFO - [mozharness: 2017-07-17 12:52:14.260859Z] Running run-tests step. 05:52:14 INFO - Running pre-action listener: _resource_record_pre_action 05:52:14 INFO - Running pre-action listener: _set_gcov_prefix 05:52:14 INFO - Running main action method: run_tests 05:52:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:52:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:52:14 INFO - Python 2.7.3 05:52:14 INFO - Return code: 0 05:52:14 INFO - grabbing minidump binary from tooltool 05:52:14 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:52:14 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 0x1f65d10>, '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 0x1f66c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f670c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:52:14 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:52:14 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:52:14 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:52:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:52:14 INFO - Return code: 0 05:52:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:52:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:52:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:52:14 INFO - ENV: RUST_BACKTRACE is now 1 05:52:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:52:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:52:14 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/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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:52:14 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/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-021 --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:52:14 INFO - Using env: {'DISPLAY': ':0', 05:52:14 INFO - 'HOME': '/home/cltbld', 05:52:14 INFO - 'LANG': 'en_US.UTF-8', 05:52:14 INFO - 'LANGUAGE': 'en_US:en', 05:52:14 INFO - 'LOGNAME': 'cltbld', 05:52:14 INFO - 'MAIL': '/var/mail/cltbld', 05:52:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:52:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:52:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:14 INFO - 'MOZ_NO_REMOTE': '1', 05:52:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:52:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:14 INFO - 'NO_EM_RESTART': '1', 05:52:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:14 INFO - 'PWD': '/builds/slave/test', 05:52:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:52:14 INFO - 'RUST_BACKTRACE': '1', 05:52:14 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:52:14 INFO - 'SHELL': '/bin/bash', 05:52:14 INFO - 'SHLVL': '1', 05:52:14 INFO - 'TERM': 'linux', 05:52:14 INFO - 'TMOUT': '86400', 05:52:14 INFO - 'USER': 'cltbld', 05:52:14 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1500288197.379760-841038802', 05:52:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:14 INFO - '_': '/tools/buildbot/bin/python'} 05:52:14 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/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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:52:14 INFO - mozversion application_buildid: 20170717120626 05:52:14 INFO - mozversion application_changeset: d43779e278d2e4d3e21dba2fcb585a3bf4b1288e 05:52:14 INFO - mozversion application_display_name: Nightly 05:52:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:52:14 INFO - mozversion application_name: Firefox 05:52:14 INFO - mozversion application_remotingname: firefox 05:52:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:52:14 INFO - mozversion application_vendor: Mozilla 05:52:14 INFO - mozversion application_version: 56.0a1 05:52:14 INFO - mozversion platform_buildid: 20170717120626 05:52:14 INFO - mozversion platform_changeset: d43779e278d2e4d3e21dba2fcb585a3bf4b1288e 05:52:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:52:14 INFO - mozversion platform_version: 56.0a1 05:52:14 INFO - using testdate: 1500295934 05:52:14 INFO - actual date: 1500295934 05:52:14 INFO - starting webserver on 'localhost:44722' 05:52:14 INFO - SUITE-START | Running 2 tests 05:52:14 INFO - TEST-START | dromaeo_css 05:52:14 INFO - Initialising browser for dromaeo_css test... 05:52:14 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44722/getInfo.html -profile /tmp/tmpNwg_LW/profile 05:52:14 INFO - TEST-INFO | started process 27117 (/builds/slave/test/build/application/firefox/firefox http://localhost:44722/getInfo.html) 05:52:21 INFO - TEST-INFO | 27117: exit 0 05:52:21 INFO - Browser initialized. 05:52:21 INFO - Running cycle 1/1 for dromaeo_css test... 05:52:21 INFO - TEST-INFO | started process 27324 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNwg_LW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:52:32 INFO - PID 27324 | RSS: Main: 167628800 05:52:32 INFO - PID 27324 | 05:55:30 INFO - PID 27324 | Cycle 1(1): loaded http://localhost:44722/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44722/tests/dromaeo/cssquery-ext.html) 05:55:32 INFO - PID 27324 | RSS: Main: 175521792 05:55:32 INFO - PID 27324 | 05:58:34 INFO - PID 27324 | Cycle 1(1): loaded http://localhost:44722/tests/dromaeo/cssquery-ext.html (next: http://localhost:44722/tests/dromaeo/cssquery-jquery.html) 05:58:34 INFO - PID 27324 | RSS: Main: 173260800 05:58:34 INFO - PID 27324 | 06:01:36 INFO - PID 27324 | Cycle 1(1): loaded http://localhost:44722/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44722/tests/dromaeo/cssquery-mootools.html) 06:01:37 INFO - PID 27324 | RSS: Main: 176746496 06:01:37 INFO - PID 27324 | 06:04:38 INFO - PID 27324 | Cycle 1(1): loaded http://localhost:44722/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44722/tests/dromaeo/cssquery-prototype.html) 06:04:39 INFO - PID 27324 | RSS: Main: 174649344 06:04:39 INFO - PID 27324 | 06:07:42 INFO - PID 27324 | Cycle 1(1): loaded http://localhost:44722/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44722/tests/dromaeo/cssquery-yui.html) 06:07:43 INFO - PID 27324 | RSS: Main: 174854144 06:07:43 INFO - PID 27324 | 06:10:45 INFO - PID 27324 | Cycle 1(1): loaded http://localhost:44722/tests/dromaeo/cssquery-yui.html (next: http://localhost:44722/tests/dromaeo/cssquery-dojo.html) 06:10:45 INFO - PID 27324 | RSS: Main: 177750016 06:10:45 INFO - PID 27324 | 06:10:45 INFO - PID 27324 | __start_tp_report 06:10:45 INFO - PID 27324 | _x_x_mozilla_page_load 06:10:45 INFO - PID 27324 | _x_x_mozilla_page_load_details 06:10:45 INFO - PID 27324 | |i|pagename|runs| 06:10:45 INFO - PID 27324 | |0;dojo.html;3057.6025116734822;3099.2406860319225;3135.3729254149175;3142.670019647936;3153.164411430971;5907;5937.37657545958;5964.4035596440335;5988.251468566429;6032.75868965242;5977.850553736159;6032.034874420094;6046.274447066347;6053.969730151343;6067.271927368722;5550.69471179085;5551.278333816598;5558.360788509312;5558.555315574744;5583.609147359686;5590.357108932484;5595.972020139893;5596.720163991805;5606.159076138591;5606.523445507137;5594.2447769551;5612.35457922338;5618.381977982419;5620.971895140518;5624.859378515549;5588.133839254922;5595.244641973322;5614.9719251403485;5616.606837521375;5624.268845050137;5439;5535.944640553583;5555.416681248451;5585.329760428734;5617.691026993513;6039.818805435844;6052.334243233241;6109.908351374733;6142.170806941051;6161.19904412428;6311.558190926637;6413.551051426402;6437.324080971524;6523.706433210469;6530.608163510205;6421.389967953037;6424.903626445607;6426.1003459515705;6472.190976127984;6486.610803351767;5941.435563621449;5956.344802071769;5980.730867110947;5988.401159884021;5990.790322338741;6473.708683109225;6598.340165983459;6616.702248398834;6619.768308109193;6710.832229194211;6236.563440559208;6291.150694656254;6302.842428939313;6389.6805159741825;6402.455791257796;6413.743450262031;6429.6142231466265;6499.415052645285;6501.122348482989;6502.902456463157;6328.525360597973;6390.169277993924;6391.648459334781;6401.583897046677;6415.615063096229;6322.458345804631;6339.302676705559;6393.104965304861;6393.648349340738;6499.642519661369;5138.97430512845;5189.948100519022;5230.37235531731;5289.444608316148;5319.042572337016;4489.483709373399;4513.7066090704;4521.299198624219;4528.320751887178;4529.932051019237;5512.035393806148;5515.393306736256;5526.944730552724;5529.281193444826;5545.833624991256;6444.291127975919;6445.51658625605;6451.838704032437;6452.548321617535;6453.483721302285;5475.589330800206;5478.91781623276;5554.833354999276;5564.860878478071;5575.804846830341;5626.2685850839125;5673.74468148926;5678.716064196857;5696.572757043155;5757.971210143923;6229.940910045303;6231.906521402183;6234.812955611339;6244;6259.624422534663;6320.146780184617;6483.092367068614;6484;6495.382938620824;6496.902546461902;5319.46805319465;6156.692165391623;6219.906701399573;6307.526935479766;6347.460465860268;6331.810045698637;6334.746610135543;6405.263394709668;6414.134091897534;6418.29398766126;6439.903401449075;6460.398736245764;6573.3426657333885;6590.47276217911;6611.801645950629;6172.135900973957;6301.7164227608955;6305.747770089145;6314.1160237565855;6325.778597749057;6305.180326557518;6306.180196574416;6306.432421082125;6313.147725057059;6318.0838778375955;6167;6235.376462353728;6243.719032643451;6260.248770147611;6260.342664020394;5498.532624726943;6312.179416675803;6360.554761166674;6441.937080381685;6463.25672547663;6129.540284478593;6163.322034576104;6244.438000579969;6246.531510136667;6254.655993920196 06:10:45 INFO - PID 27324 | |1;ext.html;782.5734974438931;830.8296799156174;861.2851333393285;879.6481407437025;887.2281115429575;40812.571559995406;41753.58246417531;41921;41974.58025419745;42072.78963605181;37984.43023354652;38038;38050.42924356137;38147.809260953654;38349.233015339676;6699.263081061092;6749.966250168743;6777.390034896858;6850.314968503159;6858.759943401982;6773.288804675512;6852.177738671367;6877.037214789933;6877.793666189998;6878.587284762905;6852.554583952027;6872.484563657721;6875.07186529817;6887.690053947585;6889.242183359852;6843.38409543141;7513.774586762406;7696;7729.536227826348;7736;7629.80925476862;7646.9617651911385;7692.499987500794;7696.884546731803;7706.036745406825;37361.439578406345;37783.62216377829;37797.433038504445;37800.62199378026;37953;3575.1598374382;3593.838277277529;3613.295407395559;3615.258871931248;3632.8001959892194;30811.229719256975;30815.845920770476;30930.845345773127;30958.380832383227;30962.690373096208;4881.316615673809;4888.902221955576;4898.706077635354;4905;4981.775820088104;191780;193926;216993;218448;218608.9999999984;48910.266346004835;49426.75286623544;49645;49675.50324496709;49924.25113623298;51482;51506.48493515092;51775.48224517782;51882;52049.47950520522;16427.507174784776;16572.17139143038;16610.501684949228;16614.83385166133;16696.58258543522;13546;13709.314534273246;13823.585292441243;13827.51603693866;13867.583972480841;14450.205238711762;14524.854751452562;14542.636434089021;14551.636209094646;14604.634884127981;5621.269234999506;5638.689872056994;5684.715764211773;5757.539396848242;5951.761929522857;998.3959704378891;1000.1398797034544;1016.5832008876473;1043.3635482355758;1053.947302634865;22085.227017054327;22294.442638934157;22718.659220111713;23210;23671.053157873837;1092.9890701092888;1119.692084676704;1128.6557599931934;1129.3788416371128;1138.7836311100864;12005.699857503632;12116.75766484683;12257.448414821354;12343.259404435763;12503.687407814878;232243;232411;232536;232650;232880;52737.47262527326;52998.73500632472;53260.46739532633;53268.733656332246;53285.200721989204;1320.2870449957109;1327.9070465067352;1330.82699249097;1332.5003123828565;1503.4587548482536;27532.72467275382;27890.163295101178;27904.16287511378;28100.297492562848;28397.290067748472;47792.7610361946;47936.5206347946;48211.75894120507;48445.27332090092;48472.27291590558;144701;144885;145275.27362363544;145611;146274;33806.49290260697;33910;33944.830275848464;34409;34499.31001380009;40015.1996960065;41494.1701165981;41828.1634367317;42366.5763342375;43156.352654709575;30741.846290768404;31879.36241275115;32134;32484.512732309508;32547;30157.698423016365;30907.536386953765;31072;31292.530612041293;31408.84295578599;33838;34176.82911585426;34207.4868876972;34359;34384.140396490286;29606.55590166106;30671.539926900667;30817.075487735405;30881.845590771904;31312.53031204488;24021.759782401954;25132.24603261832;25252.24243272705;25374.492510150063;25814.741852581235 06:10:45 INFO - PID 27324 | |2;jquery.html;569.2335250681687;584.2813339592304;590.1560768101614;590.571835419321;600.4115966352974;16029.278682459288;21445.14219431223;22179.778202217974;22190.778092219072;22369.328920132382;18728.344507942224;19298.421047368567;19305.324313649024;20049;21613.56772864542;3778.508793856795;3902.609739026089;6305.464035556983;6336.778212762531;6372.585781924184;6259.217597800275;6378.617282963014;6387.840303992414;6413.198350206224;6416;6355.459785918202;6355.9364406356035;6378;6383.202099737533;6386.521010924199;6334.904976425358;6343.8414039648915;6345.428911397973;6353.459955903753;6355.046742988542;5462.508374246337;5868.4718375346;5905.4094590541035;5908.645481271137;5931.64410135389;28274.434511309657;28710.569341459894;28746.137615871346;28763.424731505464;28773.136805895858;1908.5991941692123;1934.6807776716826;1943.543267332176;1943.7375954246138;1954.784973652897;18175.272989080422;18455.16951737175;18489.260429582802;18607.348742793936;19711.704324435144;2230.609643312414;2293.094227780034;2309.919152829643;2311.6763653088583;2319.9420014499606;119739;124688.37655811754;125513;133331;135019.6498035017;14168.834741978853;14708.191049492381;14875.181864997312;14894.329755161043;14903.882208834164;22670;22693.773062269494;22836.885815570815;22905.312840614475;22925.88537057304;9914.107730304308;9978.60085596568;10142.847857282146;10270.229732769923;10342.276040677081;9262.166405023445;9284.814303714023;9463.148316651397;9517.762055948657;9573.712788616212;8995.865062024075;9037.77405564853;9047.91897863482;9128.132827381389;9310.487923164155;2753.160286112715;2758.3517873299757;2795.720427957188;2833.8724757385967;2894.1751600794096;904.2088172848757;915.1306259053925;952.1288021460286;955.3551352836946;965.1217392173089;22276.55446891053;23382.883085584803;23719.288421347388;23927.28218153458;24119.758802412103;1002.9318775504211;1016.3089099412323;1018.8064267789097;1023.5496381592225;1035.2856528994969;10440;10716.678499645022;10717.517711702993;10830;10860.837087443695;533830;554540;557422;559727;561470;116686;117288;118648;122658;124355.3782231083;1129.8474705914762;1249.325364303266;1272.6818295426328;1286.7490839286436;1323.3647849032518;19863.404097877086;20146.225767881362;20218.393448196577;20374;21029.789702103397;56229.71885140712;56536;57083.71458142683;57980.71009644925;58262;68548.6572567134;70160.29839701537;71357.28642713507;71995;73413;13746.93126534361;14114.223717695215;14484.782728259295;14559;14893.255337233311;23147.652785207898;23156.379053466768;23209.76790232076;23388.6491702628;23428;16596.917015414845;17984.100794959937;18198.090095495438;18419.355322563646;18523.90738046301;15136.697266054838;15571.143587102819;16156.353745850034;16310.26603802808;16602.16989150562;19063.618727625646;19179.520511987314;19214.423567293004;19220.615587688448;19432.514187145436;13881.305934703023;14497.492587759378;14506.34721437559;14626.414943402571;14653.267336633338;18513.35203267879;18694.906525467286;18771.249150033847;18809.811901880807;18881.811181888555 06:10:45 INFO - PID 27324 | |3;mootools.html;1333.893288536917;1358.300475255243;1371.2937837013947;1372.3892867673883;1387.6184049386425;12975.805362919564;13541.119827211225;13600.251986140758;13656.453741850313;13657.863421365772;12964.157329773558;13301.866981330184;13390.330483475836;13411.12827666201;13439.328033598329;2511.2215213283894;2577.497388009339;2587.935301617456;2588.1847218126322;2588.8835002424935;2491.352248415407;2537.5939849624065;2541.5552278351306;2547.656066431027;2548.7833534149527;2490;2507.1976967370447;2513.472170844125;2516.48412075525;2528.1783420388356;2452.6688896998944;2461.1386014894733;2472.8516289022627;2479.7024357077084;2483.987580062106;2383.84505007174;2390.306811024801;2391.6651668766385;2394.988025059881;2403.182917807936;12816.102872798907;13162;13174.341282935815;13230.536931207458;13250.403731832092;7119.288071192891;7383.07711536058;7390;7401.370883474911;7407.629618519052;4888.584470320027;4912;4990.900181996376;5042.672226305279;5116.897662046738;1819.6360727854349;1832.789229238628;1872.2510995601733;1881.5296175956012;1906.609145125246;20241;20454.5909081819;20551.280705175395;20608.27871024522;20823.479413014644;5659.773609055674;6110.419510146529;6297.622142671455;6350.618962862243;6382.968085159545;14909.776353354708;14969.550913472613;14995.2502374883;15003.924980375028;15014.324355404033;6919.100516932867;7137.750178743719;7140.571565706075;7183.173934997438;7206.2793720627515;6662.40038396537;6704.932950670528;6761.830954226085;6780.559263647847;6859.965700171467;4951;5050.3182070420025;5054.368203974504;5099.260607211933;5133.717645529532;1466.2448838847788;1484.5175318021686;1493.111598598832;1493.245910815053;1498.243387089513;897.6050537763364;903.2773780975193;915.455304094063;928.2898582584328;935.2891802230354;7277.927220727831;7352.19125896151;7371.447141464411;7802.453828231969;8096.271335579826;939.5584075484511;973.6401111879202;979.1041197304387;987.8123156600223;1013.8023085498404;5485.753141108659;5607.061621429645;5704.1443783432815;5727.713614319267;5974.910376344358;17227.483175504756;17940.19269132892;18062;18082.276708931495;18092;16839.831601683825;17624.823751762575;17765.200565974563;17799;18497.907510462363;10163.18694504438;10263.640772573073;10379.325343852475;10588.682339529827;10591.417472038958;7566.35685966699;8138.877916831134;8229;8245.876311855447;8346.374021948499;15170.165640889856;15430.151341676315;15584.84415155834;15599.532014039596;15829.445969391016;15064.774028389802;15420.151891645619;16111.838881611035;16157;16349.509514714578;12359.3820308986;12630.810537841757;12647.367631618567;12692.682682933;12723;12511.249325040526;12575.622731318075;12690.682732931751;12696.936515317364;12709.93645031769;9827.705168844946;10123.493825308853;10450.320729152581;10663.093637040938;10730.624428144978;9894.901050989685;10089.394636321844;10229.335093218917;10443.164546836082;10445.216608754221;8791.956040219759;8963.865542016745;9140.817183656156;9274;9297.488638124969;6958.617276049866;7003.159620845531;7224.313690199423;7229.385502232369;7243.601601911945;6975.860482790417;7194.604296763729;7216.855662886818;7227.313405226495;7245.166805817398 06:10:45 INFO - PID 27324 | |4;prototype.html;485.9975700121499;495.7942453881639;509.8419489958111;513.2147813844817;519.2652396858446;6063.393660633935;6076.9392306076925;6104.328523862371;6106.816795496131;6162.168107305512;5841.299044114701;5973.402659734024;6009.278886533622;6022.849428764283;6030.366811484797;4888.975555122237;4900.607951363882;4901.901961960759;4925.7537123143875;4929.433115191745;4861.197902346109;4869.659123861331;4873.853784386474;4878.780454879521;4932;4812.518748125194;4851.151048566504;4853.214492695703;4861.927071093919;4870.464248932614;4599.402077729907;4769;4806.158922188603;4817.638677099231;4842.612590992712;4341.522432532419;4362.367456718758;4371.934420983688;4383.101464199832;4390.8024138913825;5807.622504537191;5823.359430462646;5881.2354393928945;5893.174955506232;5902.232709747748;5526.171074338841;5527.419620939783;5581;5588.0941910206875;5589.944100558983;6383.234011918599;6408.385962107275;6414.839629009265;6415.775447859349;6449.645269510175;4784.904301913943;4820.758962051884;4831.685940413862;4843.975780121112;4864.513548645143;6735.753885531192;6755.49333799967;6803.557768745065;6823.795286141423;6828.283030281848;4972.985940694025;4980;4980.8754781130765;4982.975085124551;4993.425756038029;5030.622703297267;5038.395392552917;5039.420466646383;5048.368953880765;5067.949320506821;5539.861503462445;5563.136349322941;5563.136349322941;5577.27495425592;5591.440855914376;6318.810435686937;6377.362263773678;6398.488120950313;6403.455706265019;6488.988413591802;1705.6588682263598;1715.5110793424064;1717.2787429279483;1741.634256806085;1754.4561186032115;1091.2049678279186;1216.519474807464;1221.91446598739;1224.344975438154;1230.4524486603375;5352.2506849041165;5491.478309560665;5503.669779813224;5584.664920104807;5606.635568688022;6402.6158430494315;6552.443042341359;6607.900881486781;6641.667916604247;6657.667116644148;5484.588655850827;5570.164475328652;5589.217509548666;5594.440555944454;5630.5360606242675;3414.6585341465657;3448.913777155591;3459.619441861393;3491.284286721216;3491.790492570454;6293.402126797947;6299.6850157491945;6313.905291420632;6363.681815909186;6366;6576.769813056616;6645.634490102994;6671.432928201158;6704.966475167593;6773.762918297836;6337.524685648595;6367.936320636827;6381.8085457436355;6388.968055159695;6437;6497.805065848032;6530.706118224596;6554.5092864711105;6571.310012448624;6574.211094668671;6486.481081513562;6494.415502604599;6507.772227971998;6550.181227346582;6600.60396376219;6354.586951848115;6360.395762402564;6394.58435201729;6416.550841441054;6437.485001199986;4858.659893807399;4866.343043689057;4869.269609558594;4900.509949005071;4909.729964851967;6594.934050659624;6599.934000659932;6600.867982640416;6629.138212032405;6642.900356494753;6402.231732192166;6419.64691941929;6419.711113000019;6427.903581446189;6436.774712885027;6321.683915804283;6332.416895776056;6389.9361006389345;6414.166158399565;6478.1578394808375;6304.968475157595;6306.684665766602;6306.990881458944;6314.358266850743;6411.679416029273;6304.122964376784;6377.8634157557735;6431.935680643133;6481.027845823164;6527.51043671717;5669.858253543694;6041.9395806041375;6172.135900973777;6241.157443745036;6245.96877015612 06:10:45 INFO - PID 27324 | |5;yui.html;872.6509396241503;894.7226359828453;904.4121321141262;914.2959920860937;914.4833169259368;17956.820431795677;18913.338033168842;18924.432267031978;18946.336878209248;18992.715109273355;15354.616134596641;16046.679066418665;16135.354585816583;16545.172741362945;17284.827151728477;1024.109025148122;1043.9321444106147;1044.2324891204958;1046.4244665434019;1047.6385646951828;1033.5504055735742;1034.8499467577196;1034.8809886862994;1042.2495803021811;1045.043784936782;1097.7145942055083;1111.0223003756723;1112.1436493899691;1114.0363585498567;1115.464577003039;1015.4415071710566;1051.9316244444087;1105.2318638546215;1106.330669944684;1109.0351394287022;798.3573223555036;798.7843282313801;802.173761026144;802.229859335039;802.4864086984335;18466.169022394024;19641.508962276057;19967.301144459874;19989.700154497696;20036.298729544396;19318.13068411925;19485.415437536754;19733.30933417338;19884.90057549703;19898.104585293695;15970.840291597051;16047.679046419124;16070.5982350441;16091;16160.353585856552;3440.17435815405;3460.5501284832812;3490.406630872758;3544.078539579702;3576.910577235564;118974.4051279738;119050;122708;124544.37727811394;125910;15362.846371536365;15557.922210388875;15794.605134871721;15857.444989425314;15914.92042539803;25663.230103096936;27495;27539.449211015668;27615.585766213524;27874.024409145582;8382.748517544484;8383.329333653293;8384.161583841567;8477.491350518989;8501.574921253912;4173.269677806372;4246.745195288293;4279.229738647011;4289.1421715657;4291.721038132511;4452.30989196675;4527.366168736379;4570.908581828345;4574.153781550423;4574.290984897346;987.086944576264;999.2305924438142;1036.233187441303;1037.678319720889;1045.6131231444263;656.8193746719595;668.6924014953177;672.8116127484312;695.183159787248;702.7610612391709;11768.138879272006;11824;11874.406279686154;11900.523979040741;11917.52329906794;669.9665016749143;672.862063277027;686.1902954513622;692.0829900382014;713.7644577289586;5224.738763061831;5253.789848406021;5317.73411329432;5327.733613319396;5423.376311724202;95067.52466237625;97768.51115744376;98964;101084;102684.48657756664;84287.99999999878;88428;88883.11116888878;89626;90112.5494372524;72916.270837292;74474.62762686152;74581.6270918642;75984;77159.2284077152;10475.528601213116;10475.790484190426;10483.108935740547;10617.681469555926;10820.080293174853;35483.29033419266;36954;37290.06774830651;37470.625293747806;38031.80984095062;44564.10871782481;44583;45700.31449528326;67538.0218638214;69683.30316696769;18383;18431.815681843545;18571.2571497144;18712.064396780377;19516.90241548783;19101.235950561822;20206.696899546223;20274.391768246976;20388.388348349574;20531.69202461994;17715.557111072325;18461.353852615095;18865.811341886405;19499.61000780005;19591.31430399929;18620.720689189944;18677;18761.34335298258;18822.905885470485;18993.050347482847;17592.371158674647;17705.468835934942;17787.288508460035;17965.101744912965;18232.635347293246;13130.212187268795;13806.17162970225;13899.930500347838;14242.501512447014;14315.355808988412;13628.93185534066;13882.861171388156;14161.362738676578;14301.642458938193;14423.639409014859 06:10:45 INFO - PID 27324 | __end_tp_report 06:10:45 INFO - PID 27324 | __start_cc_report 06:10:45 INFO - PID 27324 | _x_x_mozilla_cycle_collect,359 06:10:45 INFO - PID 27324 | __end_cc_report 06:10:45 INFO - PID 27324 | __startTimestamp1500297045296__endTimestamp 06:10:45 INFO - PID 27324 | 06:10:45 INFO - PID 27324 | ------- Summary: start ------- 06:10:45 INFO - PID 27324 | Number of tests: 6 06:10:45 INFO - PID 27324 | 06:10:45 INFO - PID 27324 | [#0] dojo.html Cycles:165 Average:5941.12 Median:6219.91 stddev:684.09 (11.0%) stddev-sans-first:647.71 06:10:45 INFO - PID 27324 | Values: 3057.6 3099.2 3135.4 3142.7 3153.2 5907.0 5937.4 5964.4 5988.3 6032.8 5977.9 6032.0 6046.3 6054.0 6067.3 5550.7 5551.3 5558.4 5558.6 5583.6 5590.4 5596.0 5596.7 5606.2 5606.5 5594.2 5612.4 5618.4 5621.0 5624.9 5588.1 5595.2 5615.0 5616.6 5624.3 5439.0 5535.9 5555.4 5585.3 5617.7 6039.8 6052.3 6109.9 6142.2 6161.2 6311.6 6413.6 6437.3 6523.7 6530.6 6421.4 6424.9 6426.1 6472.2 6486.6 5941.4 5956.3 5980.7 5988.4 5990.8 6473.7 6598.3 6616.7 6619.8 6710.8 6236.6 6291.2 6302.8 6389.7 6402.5 6413.7 6429.6 6499.4 6501.1 6502.9 6328.5 6390.2 6391.6 6401.6 6415.6 6322.5 6339.3 6393.1 6393.6 6499.6 5139.0 5189.9 5230.4 5289.4 5319.0 4489.5 4513.7 4521.3 4528.3 4529.9 5512.0 5515.4 5526.9 5529.3 5545.8 6444.3 6445.5 6451.8 6452.5 6453.5 5475.6 5478.9 5554.8 5564.9 5575.8 5626.3 5673.7 5678.7 5696.6 5758.0 6229.9 6231.9 6234.8 6244.0 6259.6 6320.1 6483.1 6484.0 6495.4 6496.9 5319.5 6156.7 6219.9 6307.5 6347.5 6331.8 6334.7 6405.3 6414.1 6418.3 6439.9 6460.4 6573.3 6590.5 6611.8 6172.1 6301.7 6305.7 6314.1 6325.8 6305.2 6306.2 6306.4 6313.1 6318.1 6167.0 6235.4 6243.7 6260.2 6260.3 5498.5 6312.2 6360.6 6441.9 6463.3 6129.5 6163.3 6244.4 6246.5 6254.7 06:10:45 INFO - PID 27324 | 06:10:45 INFO - PID 27324 | [#1] ext.html Cycles:180 Average:36883.20 Median:34371.57 stddev:51664.38 (150.3%) stddev-sans-first:51738.20 06:10:45 INFO - PID 27324 | Values: 782.6 830.8 861.3 879.6 887.2 40812.6 41753.6 41921.0 41974.6 42072.8 37984.4 38038.0 38050.4 38147.8 38349.2 6699.3 6750.0 6777.4 6850.3 6858.8 6773.3 6852.2 6877.0 6877.8 6878.6 6852.6 6872.5 6875.1 6887.7 6889.2 6843.4 7513.8 7696.0 7729.5 7736.0 7629.8 7647.0 7692.5 7696.9 7706.0 37361.4 37783.6 37797.4 37800.6 37953.0 3575.2 3593.8 3613.3 3615.3 3632.8 30811.2 30815.8 30930.8 30958.4 30962.7 4881.3 4888.9 4898.7 4905.0 4981.8 191780.0 193926.0 216993.0 218448.0 218609.0 48910.3 49426.8 49645.0 49675.5 49924.3 51482.0 51506.5 51775.5 51882.0 52049.5 16427.5 16572.2 16610.5 16614.8 16696.6 13546.0 13709.3 13823.6 13827.5 13867.6 14450.2 14524.9 14542.6 14551.6 14604.6 5621.3 5638.7 5684.7 5757.5 5951.8 998.4 1000.1 1016.6 1043.4 1053.9 22085.2 22294.4 22718.7 23210.0 23671.1 1093.0 1119.7 1128.7 1129.4 1138.8 12005.7 12116.8 12257.4 12343.3 12503.7 232243.0 232411.0 232536.0 232650.0 232880.0 52737.5 52998.7 53260.5 53268.7 53285.2 1320.3 1327.9 1330.8 1332.5 1503.5 27532.7 27890.2 27904.2 28100.3 28397.3 47792.8 47936.5 48211.8 48445.3 48472.3 144701.0 144885.0 145275.3 145611.0 146274.0 33806.5 33910.0 33944.8 34409.0 34499.3 40015.2 41494.2 41828.2 42366.6 43156.4 30741.8 31879.4 32134.0 32484.5 32547.0 30157.7 30907.5 31072.0 31292.5 31408.8 33838.0 34176.8 34207.5 34359.0 34384.1 29606.6 30671.5 30817.1 30881.8 31312.5 24021.8 25132.2 25252.2 25374.5 25814.7 06:10:45 INFO - PID 27324 | 06:10:45 INFO - PID 27324 | [#2] jquery.html Cycles:180 Average:36193.54 Median:22681.89 stddev:92270.96 (406.8%) stddev-sans-first:92491.04 06:10:45 INFO - PID 27324 | Values: 569.2 584.3 590.2 590.6 600.4 16029.3 21445.1 22179.8 22190.8 22369.3 18728.3 19298.4 19305.3 20049.0 21613.6 3778.5 3902.6 6305.5 6336.8 6372.6 6259.2 6378.6 6387.8 6413.2 6416.0 6355.5 6355.9 6378.0 6383.2 6386.5 6334.9 6343.8 6345.4 6353.5 6355.0 5462.5 5868.5 5905.4 5908.6 5931.6 28274.4 28710.6 28746.1 28763.4 28773.1 1908.6 1934.7 1943.5 1943.7 1954.8 18175.3 18455.2 18489.3 18607.3 19711.7 2230.6 2293.1 2309.9 2311.7 2319.9 119739.0 124688.4 125513.0 133331.0 135019.6 14168.8 14708.2 14875.2 14894.3 14903.9 22670.0 22693.8 22836.9 22905.3 22925.9 9914.1 9978.6 10142.8 10270.2 10342.3 9262.2 9284.8 9463.1 9517.8 9573.7 8995.9 9037.8 9047.9 9128.1 9310.5 2753.2 2758.4 2795.7 2833.9 2894.2 904.2 915.1 952.1 955.4 965.1 22276.6 23382.9 23719.3 23927.3 24119.8 1002.9 1016.3 1018.8 1023.5 1035.3 10440.0 10716.7 10717.5 10830.0 10860.8 533830.0 554540.0 557422.0 559727.0 561470.0 116686.0 117288.0 118648.0 122658.0 124355.4 1129.8 1249.3 1272.7 1286.7 1323.4 19863.4 20146.2 20218.4 20374.0 21029.8 56229.7 56536.0 57083.7 57980.7 58262.0 68548.7 70160.3 71357.3 71995.0 73413.0 13746.9 14114.2 14484.8 14559.0 14893.3 23147.7 23156.4 23209.8 23388.6 23428.0 16596.9 17984.1 18198.1 18419.4 18523.9 15136.7 15571.1 16156.4 16310.3 16602.2 19063.6 19179.5 19214.4 19220.6 19432.5 13881.3 14497.5 14506.3 14626.4 14653.3 18513.4 18694.9 18771.2 18809.8 18881.8 06:10:45 INFO - PID 27324 | 06:10:45 INFO - PID 27324 | [#3] mootools.html Cycles:180 Average:8329.78 Median:2467.00 stddev:5450.42 (220.9%) stddev-sans-first:5440.35 06:10:45 INFO - PID 27324 | Values: 1333.9 1358.3 1371.3 1372.4 1387.6 12975.8 13541.1 13600.3 13656.5 13657.9 12964.2 13301.9 13390.3 13411.1 13439.3 2511.2 2577.5 2587.9 2588.2 2588.9 2491.4 2537.6 2541.6 2547.7 2548.8 2490.0 2507.2 2513.5 2516.5 2528.2 2452.7 2461.1 2472.9 2479.7 2484.0 2383.8 2390.3 2391.7 2395.0 2403.2 12816.1 13162.0 13174.3 13230.5 13250.4 7119.3 7383.1 7390.0 7401.4 7407.6 4888.6 4912.0 4990.9 5042.7 5116.9 1819.6 1832.8 1872.3 1881.5 1906.6 20241.0 20454.6 20551.3 20608.3 20823.5 5659.8 6110.4 6297.6 6350.6 6383.0 14909.8 14969.6 14995.3 15003.9 15014.3 6919.1 7137.8 7140.6 7183.2 7206.3 6662.4 6704.9 6761.8 6780.6 6860.0 4951.0 5050.3 5054.4 5099.3 5133.7 1466.2 1484.5 1493.1 1493.2 1498.2 897.6 903.3 915.5 928.3 935.3 7277.9 7352.2 7371.4 7802.5 8096.3 939.6 973.6 979.1 987.8 1013.8 5485.8 5607.1 5704.1 5727.7 5974.9 17227.5 17940.2 18062.0 18082.3 18092.0 16839.8 17624.8 17765.2 17799.0 18497.9 10163.2 10263.6 10379.3 10588.7 10591.4 7566.4 8138.9 8229.0 8245.9 8346.4 15170.2 15430.2 15584.8 15599.5 15829.4 15064.8 15420.2 16111.8 16157.0 16349.5 12359.4 12630.8 12647.4 12692.7 12723.0 12511.2 12575.6 12690.7 12696.9 12709.9 9827.7 10123.5 10450.3 10663.1 10730.6 9894.9 10089.4 10229.3 10443.2 10445.2 8792.0 8963.9 9140.8 9274.0 9297.5 6958.6 7003.2 7224.3 7229.4 7243.6 6975.9 7194.6 7216.9 7227.3 7245.2 06:10:45 INFO - PID 27324 | 06:10:45 INFO - PID 27324 | [#4] prototype.html Cycles:180 Average:5384.20 Median:5897.70 stddev:1508.76 (25.6%) stddev-sans-first:1467.52 06:10:45 INFO - PID 27324 | Values: 486.0 495.8 509.8 513.2 519.3 6063.4 6076.9 6104.3 6106.8 6162.2 5841.3 5973.4 6009.3 6022.8 6030.4 4889.0 4900.6 4901.9 4925.8 4929.4 4861.2 4869.7 4873.9 4878.8 4932.0 4812.5 4851.2 4853.2 4861.9 4870.5 4599.4 4769.0 4806.2 4817.6 4842.6 4341.5 4362.4 4371.9 4383.1 4390.8 5807.6 5823.4 5881.2 5893.2 5902.2 5526.2 5527.4 5581.0 5588.1 5589.9 6383.2 6408.4 6414.8 6415.8 6449.6 4784.9 4820.8 4831.7 4844.0 4864.5 6735.8 6755.5 6803.6 6823.8 6828.3 4973.0 4980.0 4980.9 4983.0 4993.4 5030.6 5038.4 5039.4 5048.4 5067.9 5539.9 5563.1 5563.1 5577.3 5591.4 6318.8 6377.4 6398.5 6403.5 6489.0 1705.7 1715.5 1717.3 1741.6 1754.5 1091.2 1216.5 1221.9 1224.3 1230.5 5352.3 5491.5 5503.7 5584.7 5606.6 6402.6 6552.4 6607.9 6641.7 6657.7 5484.6 5570.2 5589.2 5594.4 5630.5 3414.7 3448.9 3459.6 3491.3 3491.8 6293.4 6299.7 6313.9 6363.7 6366.0 6576.8 6645.6 6671.4 6705.0 6773.8 6337.5 6367.9 6381.8 6389.0 6437.0 6497.8 6530.7 6554.5 6571.3 6574.2 6486.5 6494.4 6507.8 6550.2 6600.6 6354.6 6360.4 6394.6 6416.6 6437.5 4858.7 4866.3 4869.3 4900.5 4909.7 6594.9 6599.9 6600.9 6629.1 6642.9 6402.2 6419.6 6419.7 6427.9 6436.8 6321.7 6332.4 6389.9 6414.2 6478.2 6305.0 6306.7 6307.0 6314.4 6411.7 6304.1 6377.9 6431.9 6481.0 6527.5 5669.9 6041.9 6172.1 6241.2 6246.0 06:10:45 INFO - PID 27324 | 06:10:45 INFO - PID 27324 | [#5] yui.html Cycles:180 Average:21901.93 Median:19492.51 stddev:29248.28 (150.0%) stddev-sans-first:29287.70 06:10:45 INFO - PID 27324 | Values: 872.7 894.7 904.4 914.3 914.5 17956.8 18913.3 18924.4 18946.3 18992.7 15354.6 16046.7 16135.4 16545.2 17284.8 1024.1 1043.9 1044.2 1046.4 1047.6 1033.6 1034.8 1034.9 1042.2 1045.0 1097.7 1111.0 1112.1 1114.0 1115.5 1015.4 1051.9 1105.2 1106.3 1109.0 798.4 798.8 802.2 802.2 802.5 18466.2 19641.5 19967.3 19989.7 20036.3 19318.1 19485.4 19733.3 19884.9 19898.1 15970.8 16047.7 16070.6 16091.0 16160.4 3440.2 3460.6 3490.4 3544.1 3576.9 118974.4 119050.0 122708.0 124544.4 125910.0 15362.8 15557.9 15794.6 15857.4 15914.9 25663.2 27495.0 27539.4 27615.6 27874.0 8382.7 8383.3 8384.2 8477.5 8501.6 4173.3 4246.7 4279.2 4289.1 4291.7 4452.3 4527.4 4570.9 4574.2 4574.3 987.1 999.2 1036.2 1037.7 1045.6 656.8 668.7 672.8 695.2 702.8 11768.1 11824.0 11874.4 11900.5 11917.5 670.0 672.9 686.2 692.1 713.8 5224.7 5253.8 5317.7 5327.7 5423.4 95067.5 97768.5 98964.0 101084.0 102684.5 84288.0 88428.0 88883.1 89626.0 90112.5 72916.3 74474.6 74581.6 75984.0 77159.2 10475.5 10475.8 10483.1 10617.7 10820.1 35483.3 36954.0 37290.1 37470.6 38031.8 44564.1 44583.0 45700.3 67538.0 69683.3 18383.0 18431.8 18571.3 18712.1 19516.9 19101.2 20206.7 20274.4 20388.4 20531.7 17715.6 18461.4 18865.8 19499.6 19591.3 18620.7 18677.0 18761.3 18822.9 18993.1 17592.4 17705.5 17787.3 17965.1 18232.6 13130.2 13806.2 13899.9 14242.5 14315.4 13628.9 13882.9 14161.4 14301.6 14423.6 06:10:45 INFO - PID 27324 | -------- Summary: end -------- 06:10:45 INFO - PID 27324 | 06:10:45 INFO - TEST-INFO | 27324: exit 0 06:10:45 INFO - TEST-OK | dromaeo_css | took 1111245ms 06:10:45 INFO - TEST-START | kraken 06:10:45 INFO - Initialising browser for kraken test... 06:10:45 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44722/getInfo.html -profile /tmp/tmpN7MG6F/profile 06:10:45 INFO - TEST-INFO | started process 27727 (/builds/slave/test/build/application/firefox/firefox http://localhost:44722/getInfo.html) 06:10:52 INFO - TEST-INFO | 27727: exit 0 06:10:52 INFO - Browser initialized. 06:10:52 INFO - Running cycle 1/1 for kraken test... 06:10:52 INFO - TEST-INFO | started process 27934 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpN7MG6F/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 06:11:03 INFO - PID 27934 | RSS: Main: 167088128 06:11:03 INFO - PID 27934 | 06:11:06 INFO - PID 27934 | Cycle 1(1): loaded http://localhost:44722/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44722/tests/kraken/driver.html?testName=audio-beat-detection) 06:11:07 INFO - PID 27934 | RSS: Main: 173486080 06:11:07 INFO - PID 27934 | 06:11:10 INFO - PID 27934 | Cycle 1(1): loaded http://localhost:44722/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44722/tests/kraken/driver.html?testName=audio-dft) 06:11:10 INFO - PID 27934 | RSS: Main: 174137344 06:11:10 INFO - PID 27934 | 06:11:13 INFO - PID 27934 | Cycle 1(1): loaded http://localhost:44722/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44722/tests/kraken/driver.html?testName=audio-fft) 06:11:14 INFO - PID 27934 | RSS: Main: 167886848 06:11:14 INFO - PID 27934 | 06:11:16 INFO - PID 27934 | Cycle 1(1): loaded http://localhost:44722/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44722/tests/kraken/driver.html?testName=audio-oscillator) 06:11:16 INFO - PID 27934 | RSS: Main: 168951808 06:11:16 INFO - PID 27934 | 06:11:19 INFO - PID 27934 | Cycle 1(1): loaded http://localhost:44722/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44722/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:11:19 INFO - PID 27934 | RSS: Main: 169287680 06:11:19 INFO - PID 27934 | 06:11:22 INFO - PID 27934 | Cycle 1(1): loaded http://localhost:44722/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44722/tests/kraken/driver.html?testName=imaging-darkroom) 06:11:23 INFO - PID 27934 | RSS: Main: 172056576 06:11:23 INFO - PID 27934 | 06:11:26 INFO - PID 27934 | Cycle 1(1): loaded http://localhost:44722/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44722/tests/kraken/driver.html?testName=imaging-desaturate) 06:11:27 INFO - PID 27934 | RSS: Main: 173961216 06:11:27 INFO - PID 27934 | 06:11:30 INFO - PID 27934 | Cycle 1(1): loaded http://localhost:44722/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44722/tests/kraken/driver.html?testName=json-parse-financial) 06:11:30 INFO - PID 27934 | RSS: Main: 172654592 06:11:30 INFO - PID 27934 | 06:11:32 INFO - PID 27934 | Cycle 1(1): loaded http://localhost:44722/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44722/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:11:32 INFO - PID 27934 | RSS: Main: 172597248 06:11:32 INFO - PID 27934 | 06:11:34 INFO - PID 27934 | Cycle 1(1): loaded http://localhost:44722/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44722/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:11:35 INFO - PID 27934 | RSS: Main: 173023232 06:11:35 INFO - PID 27934 | 06:11:37 INFO - PID 27934 | Cycle 1(1): loaded http://localhost:44722/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44722/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:11:37 INFO - PID 27934 | RSS: Main: 172982272 06:11:37 INFO - PID 27934 | 06:11:40 INFO - PID 27934 | Cycle 1(1): loaded http://localhost:44722/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44722/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:11:41 INFO - PID 27934 | RSS: Main: 172896256 06:11:41 INFO - PID 27934 | 06:11:44 INFO - PID 27934 | Cycle 1(1): loaded http://localhost:44722/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44722/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:11:44 INFO - PID 27934 | RSS: Main: 172945408 06:11:44 INFO - PID 27934 | 06:11:46 INFO - PID 27934 | Cycle 1(1): loaded http://localhost:44722/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44722/tests/kraken/driver.html?testName=ai-astar) 06:11:46 INFO - PID 27934 | RSS: Main: 173850624 06:11:46 INFO - PID 27934 | 06:11:46 INFO - PID 27934 | __start_tp_report 06:11:46 INFO - PID 27934 | _x_x_mozilla_page_load 06:11:46 INFO - PID 27934 | _x_x_mozilla_page_load_details 06:11:46 INFO - PID 27934 | |i|pagename|runs| 06:11:46 INFO - PID 27934 | |0;ai-astar;102;107;110;109;112;108;109;110;109;109 06:11:46 INFO - PID 27934 | |1;audio-beat-detection;154;120;152;180;122;154;150;120;175;129 06:11:46 INFO - PID 27934 | |2;audio-dft;165;163;163;161;162;170;163;167;170;165 06:11:46 INFO - PID 27934 | |3;audio-fft;75;76;77;162;75;75;75;75;97;74 06:11:46 INFO - PID 27934 | |4;audio-oscillator;82;80;138;80;82;81;128;81;81;131 06:11:46 INFO - PID 27934 | |5;imaging-gaussian-blur;114;114;113;113;113;114;113;113;113;113 06:11:46 INFO - PID 27934 | |6;imaging-darkroom;149;149;150;151;149;152;150;151;149;150 06:11:46 INFO - PID 27934 | |7;imaging-desaturate;93;94;95;94;95;94;95;94;94;94 06:11:46 INFO - PID 27934 | |8;json-parse-financial;54;55;56;57;56;54;55;55;55;56 06:11:46 INFO - PID 27934 | |9;json-stringify-tinderbox;55;54;54;55;54;53;54;54;54;55 06:11:46 INFO - PID 27934 | |10;stanford-crypto-aes;77;79;76;76;87;76;75;77;76;76 06:11:46 INFO - PID 27934 | |11;stanford-crypto-ccm;139;133;128;142;123;124;123;123;125;155 06:11:46 INFO - PID 27934 | |12;stanford-crypto-pbkdf2;181;186;195;182;188;179;181;199;183;179 06:11:46 INFO - PID 27934 | |13;stanford-crypto-sha256-iterative;70;70;68;71;68;70;69;69;70;67 06:11:46 INFO - PID 27934 | __end_tp_report 06:11:46 INFO - PID 27934 | __start_cc_report 06:11:46 INFO - PID 27934 | _x_x_mozilla_cycle_collect,956 06:11:46 INFO - PID 27934 | __end_cc_report 06:11:46 INFO - PID 27934 | __startTimestamp1500297106822__endTimestamp 06:11:46 INFO - PID 27934 | 06:11:46 INFO - PID 27934 | ------- Summary: start ------- 06:11:46 INFO - PID 27934 | Number of tests: 14 06:11:46 INFO - PID 27934 | 06:11:46 INFO - PID 27934 | [#0] ai-astar Cycles:10 Average:108.50 Median:109.00 stddev:2.64 (2.4%) stddev-sans-first:1.39 06:11:46 INFO - PID 27934 | Values: 102.0 107.0 110.0 109.0 112.0 108.0 109.0 110.0 109.0 109.0 06:11:46 INFO - PID 27934 | 06:11:46 INFO - PID 27934 | [#1] audio-beat-detection Cycles:10 Average:145.60 Median:153.00 stddev:22.09 (14.4%) stddev-sans-first:23.22 06:11:46 INFO - PID 27934 | Values: 154.0 120.0 152.0 180.0 122.0 154.0 150.0 120.0 175.0 129.0 06:11:46 INFO - PID 27934 | 06:11:46 INFO - PID 27934 | [#2] audio-dft Cycles:10 Average:164.90 Median:165.00 stddev:3.18 (1.9%) stddev-sans-first:3.37 06:11:46 INFO - PID 27934 | Values: 165.0 163.0 163.0 161.0 162.0 170.0 163.0 167.0 170.0 165.0 06:11:46 INFO - PID 27934 | 06:11:46 INFO - PID 27934 | [#3] audio-fft Cycles:10 Average:86.10 Median:75.00 stddev:27.54 (36.7%) stddev-sans-first:28.92 06:11:46 INFO - PID 27934 | Values: 75.0 76.0 77.0 162.0 75.0 75.0 75.0 75.0 97.0 74.0 06:11:46 INFO - PID 27934 | 06:11:46 INFO - PID 27934 | [#4] audio-oscillator Cycles:10 Average:96.40 Median:81.00 stddev:24.92 (30.8%) stddev-sans-first:25.88 06:11:46 INFO - PID 27934 | Values: 82.0 80.0 138.0 80.0 82.0 81.0 128.0 81.0 81.0 131.0 06:11:46 INFO - PID 27934 | 06:11:46 INFO - PID 27934 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:113.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 06:11:46 INFO - PID 27934 | Values: 114.0 114.0 113.0 113.0 113.0 114.0 113.0 113.0 113.0 113.0 06:11:46 INFO - PID 27934 | 06:11:46 INFO - PID 27934 | [#6] imaging-darkroom Cycles:10 Average:150.00 Median:150.00 stddev:1.05 (0.7%) stddev-sans-first:1.05 06:11:46 INFO - PID 27934 | Values: 149.0 149.0 150.0 151.0 149.0 152.0 150.0 151.0 149.0 150.0 06:11:46 INFO - PID 27934 | 06:11:46 INFO - PID 27934 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 06:11:46 INFO - PID 27934 | Values: 93.0 94.0 95.0 94.0 95.0 94.0 95.0 94.0 94.0 94.0 06:11:46 INFO - PID 27934 | 06:11:46 INFO - PID 27934 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.50 stddev:0.95 (1.7%) stddev-sans-first:0.88 06:11:46 INFO - PID 27934 | Values: 54.0 55.0 56.0 57.0 56.0 54.0 55.0 55.0 55.0 56.0 06:11:46 INFO - PID 27934 | 06:11:46 INFO - PID 27934 | [#9] json-stringify-tinderbox Cycles:10 Average:54.20 Median:54.00 stddev:0.63 (1.2%) stddev-sans-first:0.60 06:11:46 INFO - PID 27934 | Values: 55.0 54.0 54.0 55.0 54.0 53.0 54.0 54.0 54.0 55.0 06:11:46 INFO - PID 27934 | 06:11:46 INFO - PID 27934 | [#10] stanford-crypto-aes Cycles:10 Average:77.50 Median:76.50 stddev:3.50 (4.6%) stddev-sans-first:3.71 06:11:46 INFO - PID 27934 | Values: 77.0 79.0 76.0 76.0 87.0 76.0 75.0 77.0 76.0 76.0 06:11:46 INFO - PID 27934 | 06:11:46 INFO - PID 27934 | [#11] stanford-crypto-ccm Cycles:10 Average:131.50 Median:130.50 stddev:10.79 (8.3%) stddev-sans-first:11.10 06:11:46 INFO - PID 27934 | Values: 139.0 133.0 128.0 142.0 123.0 124.0 123.0 123.0 125.0 155.0 06:11:46 INFO - PID 27934 | 06:11:46 INFO - PID 27934 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.30 Median:184.50 stddev:6.85 (3.7%) stddev-sans-first:7.08 06:11:46 INFO - PID 27934 | Values: 181.0 186.0 195.0 182.0 188.0 179.0 181.0 199.0 183.0 179.0 06:11:46 INFO - PID 27934 | 06:11:46 INFO - PID 27934 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:70.00 stddev:1.23 (1.8%) stddev-sans-first:1.27 06:11:46 INFO - PID 27934 | Values: 70.0 70.0 68.0 71.0 68.0 70.0 69.0 69.0 70.0 67.0 06:11:46 INFO - PID 27934 | -------- Summary: end -------- 06:11:46 INFO - PID 27934 | 06:11:47 INFO - PID 27934 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 06:11:47 INFO - TEST-INFO | 27934: exit 0 06:11:47 INFO - TEST-OK | kraken | took 61797ms 06:11:47 INFO - SUITE-END | took 1173s 06:11:47 INFO - Completed test suite (00:19:33) 06:11:47 INFO - javascript benchmark 06:11:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8262.431176708102, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3057.6025116734822, 3099.2406860319225, 3135.3729254149175, 3142.670019647936, 3153.164411430971, 5907.0, 5937.37657545958, 5964.4035596440335, 5988.251468566429, 6032.75868965242, 5977.850553736159, 6032.034874420094, 6046.274447066347, 6053.969730151343, 6067.271927368722, 5550.69471179085, 5551.278333816598, 5558.360788509312, 5558.555315574744, 5583.609147359686, 5590.357108932484, 5595.972020139893, 5596.720163991805, 5606.159076138591, 5606.523445507137, 5594.2447769551, 5612.35457922338, 5618.381977982419, 5620.971895140518, 5624.859378515549, 5588.133839254922, 5595.244641973322, 5614.9719251403485, 5616.606837521375, 5624.268845050137, 5439.0, 5535.944640553583, 5555.416681248451, 5585.329760428734, 5617.691026993513, 6039.818805435844, 6052.334243233241, 6109.908351374733, 6142.170806941051, 6161.19904412428, 6311.558190926637, 6413.551051426402, 6437.324080971524, 6523.706433210469, 6530.608163510205, 6421.389967953037, 6424.903626445607, 6426.1003459515705, 6472.190976127984, 6486.610803351767, 5941.435563621449, 5956.344802071769, 5980.730867110947, 5988.401159884021, 5990.790322338741, 6473.708683109225, 6598.340165983459, 6616.702248398834, 6619.768308109193, 6710.832229194211, 6236.563440559208, 6291.150694656254, 6302.842428939313, 6389.6805159741825, 6402.455791257796, 6413.743450262031, 6429.6142231466265, 6499.415052645285, 6501.122348482989, 6502.902456463157, 6328.525360597973, 6390.169277993924, 6391.648459334781, 6401.583897046677, 6415.615063096229, 6322.458345804631, 6339.302676705559, 6393.104965304861, 6393.648349340738, 6499.642519661369, 5138.97430512845, 5189.948100519022, 5230.37235531731, 5289.444608316148, 5319.042572337016, 4489.483709373399, 4513.7066090704, 4521.299198624219, 4528.320751887178, 4529.932051019237, 5512.035393806148, 5515.393306736256, 5526.944730552724, 5529.281193444826, 5545.833624991256, 6444.291127975919, 6445.51658625605, 6451.838704032437, 6452.548321617535, 6453.483721302285, 5475.589330800206, 5478.91781623276, 5554.833354999276, 5564.860878478071, 5575.804846830341, 5626.2685850839125, 5673.74468148926, 5678.716064196857, 5696.572757043155, 5757.971210143923, 6229.940910045303, 6231.906521402183, 6234.812955611339, 6244.0, 6259.624422534663, 6320.146780184617, 6483.092367068614, 6484.0, 6495.382938620824, 6496.902546461902, 5319.46805319465, 6156.692165391623, 6219.906701399573, 6307.526935479766, 6347.460465860268, 6331.810045698637, 6334.746610135543, 6405.263394709668, 6414.134091897534, 6418.29398766126, 6439.903401449075, 6460.398736245764, 6573.3426657333885, 6590.47276217911, 6611.801645950629, 6172.135900973957, 6301.7164227608955, 6305.747770089145, 6314.1160237565855, 6325.778597749057, 6305.180326557518, 6306.180196574416, 6306.432421082125, 6313.147725057059, 6318.0838778375955, 6167.0, 6235.376462353728, 6243.719032643451, 6260.248770147611, 6260.342664020394, 5498.532624726943, 6312.179416675803, 6360.554761166674, 6441.937080381685, 6463.25672547663, 6129.540284478593, 6163.322034576104, 6244.438000579969, 6246.531510136667, 6254.655993920196], "value": 5892.0625542913385, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [782.5734974438931, 830.8296799156174, 861.2851333393285, 879.6481407437025, 887.2281115429575, 40812.571559995406, 41753.58246417531, 41921.0, 41974.58025419745, 42072.78963605181, 37984.43023354652, 38038.0, 38050.42924356137, 38147.809260953654, 38349.233015339676, 6699.263081061092, 6749.966250168743, 6777.390034896858, 6850.314968503159, 6858.759943401982, 6773.288804675512, 6852.177738671367, 6877.037214789933, 6877.793666189998, 6878.587284762905, 6852.554583952027, 6872.484563657721, 6875.07186529817, 6887.690053947585, 6889.242183359852, 6843.38409543141, 7513.774586762406, 7696.0, 7729.536227826348, 7736.0, 7629.80925476862, 7646.9617651911385, 7692.499987500794, 7696.884546731803, 7706.036745406825, 37361.439578406345, 37783.62216377829, 37797.433038504445, 37800.62199378026, 37953.0, 3575.1598374382, 3593.838277277529, 3613.295407395559, 3615.258871931248, 3632.8001959892194, 30811.229719256975, 30815.845920770476, 30930.845345773127, 30958.380832383227, 30962.690373096208, 4881.316615673809, 4888.902221955576, 4898.706077635354, 4905.0, 4981.775820088104, 191780.0, 193926.0, 216993.0, 218448.0, 218608.9999999984, 48910.266346004835, 49426.75286623544, 49645.0, 49675.50324496709, 49924.25113623298, 51482.0, 51506.48493515092, 51775.48224517782, 51882.0, 52049.47950520522, 16427.507174784776, 16572.17139143038, 16610.501684949228, 16614.83385166133, 16696.58258543522, 13546.0, 13709.314534273246, 13823.585292441243, 13827.51603693866, 13867.583972480841, 14450.205238711762, 14524.854751452562, 14542.636434089021, 14551.636209094646, 14604.634884127981, 5621.269234999506, 5638.689872056994, 5684.715764211773, 5757.539396848242, 5951.761929522857, 998.3959704378891, 1000.1398797034544, 1016.5832008876473, 1043.3635482355758, 1053.947302634865, 22085.227017054327, 22294.442638934157, 22718.659220111713, 23210.0, 23671.053157873837, 1092.9890701092888, 1119.692084676704, 1128.6557599931934, 1129.3788416371128, 1138.7836311100864, 12005.699857503632, 12116.75766484683, 12257.448414821354, 12343.259404435763, 12503.687407814878, 232243.0, 232411.0, 232536.0, 232650.0, 232880.0, 52737.47262527326, 52998.73500632472, 53260.46739532633, 53268.733656332246, 53285.200721989204, 1320.2870449957109, 1327.9070465067352, 1330.82699249097, 1332.5003123828565, 1503.4587548482536, 27532.72467275382, 27890.163295101178, 27904.16287511378, 28100.297492562848, 28397.290067748472, 47792.7610361946, 47936.5206347946, 48211.75894120507, 48445.27332090092, 48472.27291590558, 144701.0, 144885.0, 145275.27362363544, 145611.0, 146274.0, 33806.49290260697, 33910.0, 33944.830275848464, 34409.0, 34499.31001380009, 40015.1996960065, 41494.1701165981, 41828.1634367317, 42366.5763342375, 43156.352654709575, 30741.846290768404, 31879.36241275115, 32134.0, 32484.512732309508, 32547.0, 30157.698423016365, 30907.536386953765, 31072.0, 31292.530612041293, 31408.84295578599, 33838.0, 34176.82911585426, 34207.4868876972, 34359.0, 34384.140396490286, 29606.55590166106, 30671.539926900667, 30817.075487735405, 30881.845590771904, 31312.53031204488, 24021.759782401954, 25132.24603261832, 25252.24243272705, 25374.492510150063, 25814.741852581235], "value": 17015.10458502403, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.2335250681687, 584.2813339592304, 590.1560768101614, 590.571835419321, 600.4115966352974, 16029.278682459288, 21445.14219431223, 22179.778202217974, 22190.778092219072, 22369.328920132382, 18728.344507942224, 19298.421047368567, 19305.324313649024, 20049.0, 21613.56772864542, 3778.508793856795, 3902.609739026089, 6305.464035556983, 6336.778212762531, 6372.585781924184, 6259.217597800275, 6378.617282963014, 6387.840303992414, 6413.198350206224, 6416.0, 6355.459785918202, 6355.9364406356035, 6378.0, 6383.202099737533, 6386.521010924199, 6334.904976425358, 6343.8414039648915, 6345.428911397973, 6353.459955903753, 6355.046742988542, 5462.508374246337, 5868.4718375346, 5905.4094590541035, 5908.645481271137, 5931.64410135389, 28274.434511309657, 28710.569341459894, 28746.137615871346, 28763.424731505464, 28773.136805895858, 1908.5991941692123, 1934.6807776716826, 1943.543267332176, 1943.7375954246138, 1954.784973652897, 18175.272989080422, 18455.16951737175, 18489.260429582802, 18607.348742793936, 19711.704324435144, 2230.609643312414, 2293.094227780034, 2309.919152829643, 2311.6763653088583, 2319.9420014499606, 119739.0, 124688.37655811754, 125513.0, 133331.0, 135019.6498035017, 14168.834741978853, 14708.191049492381, 14875.181864997312, 14894.329755161043, 14903.882208834164, 22670.0, 22693.773062269494, 22836.885815570815, 22905.312840614475, 22925.88537057304, 9914.107730304308, 9978.60085596568, 10142.847857282146, 10270.229732769923, 10342.276040677081, 9262.166405023445, 9284.814303714023, 9463.148316651397, 9517.762055948657, 9573.712788616212, 8995.865062024075, 9037.77405564853, 9047.91897863482, 9128.132827381389, 9310.487923164155, 2753.160286112715, 2758.3517873299757, 2795.720427957188, 2833.8724757385967, 2894.1751600794096, 904.2088172848757, 915.1306259053925, 952.1288021460286, 955.3551352836946, 965.1217392173089, 22276.55446891053, 23382.883085584803, 23719.288421347388, 23927.28218153458, 24119.758802412103, 1002.9318775504211, 1016.3089099412323, 1018.8064267789097, 1023.5496381592225, 1035.2856528994969, 10440.0, 10716.678499645022, 10717.517711702993, 10830.0, 10860.837087443695, 533830.0, 554540.0, 557422.0, 559727.0, 561470.0, 116686.0, 117288.0, 118648.0, 122658.0, 124355.3782231083, 1129.8474705914762, 1249.325364303266, 1272.6818295426328, 1286.7490839286436, 1323.3647849032518, 19863.404097877086, 20146.225767881362, 20218.393448196577, 20374.0, 21029.789702103397, 56229.71885140712, 56536.0, 57083.71458142683, 57980.71009644925, 58262.0, 68548.6572567134, 70160.29839701537, 71357.28642713507, 71995.0, 73413.0, 13746.93126534361, 14114.223717695215, 14484.782728259295, 14559.0, 14893.255337233311, 23147.652785207898, 23156.379053466768, 23209.76790232076, 23388.6491702628, 23428.0, 16596.917015414845, 17984.100794959937, 18198.090095495438, 18419.355322563646, 18523.90738046301, 15136.697266054838, 15571.143587102819, 16156.353745850034, 16310.26603802808, 16602.16989150562, 19063.618727625646, 19179.520511987314, 19214.423567293004, 19220.615587688448, 19432.514187145436, 13881.305934703023, 14497.492587759378, 14506.34721437559, 14626.414943402571, 14653.267336633338, 18513.35203267879, 18694.906525467286, 18771.249150033847, 18809.811901880807, 18881.811181888555], "value": 11808.92393129266, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.893288536917, 1358.300475255243, 1371.2937837013947, 1372.3892867673883, 1387.6184049386425, 12975.805362919564, 13541.119827211225, 13600.251986140758, 13656.453741850313, 13657.863421365772, 12964.157329773558, 13301.866981330184, 13390.330483475836, 13411.12827666201, 13439.328033598329, 2511.2215213283894, 2577.497388009339, 2587.935301617456, 2588.1847218126322, 2588.8835002424935, 2491.352248415407, 2537.5939849624065, 2541.5552278351306, 2547.656066431027, 2548.7833534149527, 2490.0, 2507.1976967370447, 2513.472170844125, 2516.48412075525, 2528.1783420388356, 2452.6688896998944, 2461.1386014894733, 2472.8516289022627, 2479.7024357077084, 2483.987580062106, 2383.84505007174, 2390.306811024801, 2391.6651668766385, 2394.988025059881, 2403.182917807936, 12816.102872798907, 13162.0, 13174.341282935815, 13230.536931207458, 13250.403731832092, 7119.288071192891, 7383.07711536058, 7390.0, 7401.370883474911, 7407.629618519052, 4888.584470320027, 4912.0, 4990.900181996376, 5042.672226305279, 5116.897662046738, 1819.6360727854349, 1832.789229238628, 1872.2510995601733, 1881.5296175956012, 1906.609145125246, 20241.0, 20454.5909081819, 20551.280705175395, 20608.27871024522, 20823.479413014644, 5659.773609055674, 6110.419510146529, 6297.622142671455, 6350.618962862243, 6382.968085159545, 14909.776353354708, 14969.550913472613, 14995.2502374883, 15003.924980375028, 15014.324355404033, 6919.100516932867, 7137.750178743719, 7140.571565706075, 7183.173934997438, 7206.2793720627515, 6662.40038396537, 6704.932950670528, 6761.830954226085, 6780.559263647847, 6859.965700171467, 4951.0, 5050.3182070420025, 5054.368203974504, 5099.260607211933, 5133.717645529532, 1466.2448838847788, 1484.5175318021686, 1493.111598598832, 1493.245910815053, 1498.243387089513, 897.6050537763364, 903.2773780975193, 915.455304094063, 928.2898582584328, 935.2891802230354, 7277.927220727831, 7352.19125896151, 7371.447141464411, 7802.453828231969, 8096.271335579826, 939.5584075484511, 973.6401111879202, 979.1041197304387, 987.8123156600223, 1013.8023085498404, 5485.753141108659, 5607.061621429645, 5704.1443783432815, 5727.713614319267, 5974.910376344358, 17227.483175504756, 17940.19269132892, 18062.0, 18082.276708931495, 18092.0, 16839.831601683825, 17624.823751762575, 17765.200565974563, 17799.0, 18497.907510462363, 10163.18694504438, 10263.640772573073, 10379.325343852475, 10588.682339529827, 10591.417472038958, 7566.35685966699, 8138.877916831134, 8229.0, 8245.876311855447, 8346.374021948499, 15170.165640889856, 15430.151341676315, 15584.84415155834, 15599.532014039596, 15829.445969391016, 15064.774028389802, 15420.151891645619, 16111.838881611035, 16157.0, 16349.509514714578, 12359.3820308986, 12630.810537841757, 12647.367631618567, 12692.682682933, 12723.0, 12511.249325040526, 12575.622731318075, 12690.682732931751, 12696.936515317364, 12709.93645031769, 9827.705168844946, 10123.493825308853, 10450.320729152581, 10663.093637040938, 10730.624428144978, 9894.901050989685, 10089.394636321844, 10229.335093218917, 10443.164546836082, 10445.216608754221, 8791.956040219759, 8963.865542016745, 9140.817183656156, 9274.0, 9297.488638124969, 6958.617276049866, 7003.159620845531, 7224.313690199423, 7229.385502232369, 7243.601601911945, 6975.860482790417, 7194.604296763729, 7216.855662886818, 7227.313405226495, 7245.166805817398], "value": 6174.8151983349435, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.9975700121499, 495.7942453881639, 509.8419489958111, 513.2147813844817, 519.2652396858446, 6063.393660633935, 6076.9392306076925, 6104.328523862371, 6106.816795496131, 6162.168107305512, 5841.299044114701, 5973.402659734024, 6009.278886533622, 6022.849428764283, 6030.366811484797, 4888.975555122237, 4900.607951363882, 4901.901961960759, 4925.7537123143875, 4929.433115191745, 4861.197902346109, 4869.659123861331, 4873.853784386474, 4878.780454879521, 4932.0, 4812.518748125194, 4851.151048566504, 4853.214492695703, 4861.927071093919, 4870.464248932614, 4599.402077729907, 4769.0, 4806.158922188603, 4817.638677099231, 4842.612590992712, 4341.522432532419, 4362.367456718758, 4371.934420983688, 4383.101464199832, 4390.8024138913825, 5807.622504537191, 5823.359430462646, 5881.2354393928945, 5893.174955506232, 5902.232709747748, 5526.171074338841, 5527.419620939783, 5581.0, 5588.0941910206875, 5589.944100558983, 6383.234011918599, 6408.385962107275, 6414.839629009265, 6415.775447859349, 6449.645269510175, 4784.904301913943, 4820.758962051884, 4831.685940413862, 4843.975780121112, 4864.513548645143, 6735.753885531192, 6755.49333799967, 6803.557768745065, 6823.795286141423, 6828.283030281848, 4972.985940694025, 4980.0, 4980.8754781130765, 4982.975085124551, 4993.425756038029, 5030.622703297267, 5038.395392552917, 5039.420466646383, 5048.368953880765, 5067.949320506821, 5539.861503462445, 5563.136349322941, 5563.136349322941, 5577.27495425592, 5591.440855914376, 6318.810435686937, 6377.362263773678, 6398.488120950313, 6403.455706265019, 6488.988413591802, 1705.6588682263598, 1715.5110793424064, 1717.2787429279483, 1741.634256806085, 1754.4561186032115, 1091.2049678279186, 1216.519474807464, 1221.91446598739, 1224.344975438154, 1230.4524486603375, 5352.2506849041165, 5491.478309560665, 5503.669779813224, 5584.664920104807, 5606.635568688022, 6402.6158430494315, 6552.443042341359, 6607.900881486781, 6641.667916604247, 6657.667116644148, 5484.588655850827, 5570.164475328652, 5589.217509548666, 5594.440555944454, 5630.5360606242675, 3414.6585341465657, 3448.913777155591, 3459.619441861393, 3491.284286721216, 3491.790492570454, 6293.402126797947, 6299.6850157491945, 6313.905291420632, 6363.681815909186, 6366.0, 6576.769813056616, 6645.634490102994, 6671.432928201158, 6704.966475167593, 6773.762918297836, 6337.524685648595, 6367.936320636827, 6381.8085457436355, 6388.968055159695, 6437.0, 6497.805065848032, 6530.706118224596, 6554.5092864711105, 6571.310012448624, 6574.211094668671, 6486.481081513562, 6494.415502604599, 6507.772227971998, 6550.181227346582, 6600.60396376219, 6354.586951848115, 6360.395762402564, 6394.58435201729, 6416.550841441054, 6437.485001199986, 4858.659893807399, 4866.343043689057, 4869.269609558594, 4900.509949005071, 4909.729964851967, 6594.934050659624, 6599.934000659932, 6600.867982640416, 6629.138212032405, 6642.900356494753, 6402.231732192166, 6419.64691941929, 6419.711113000019, 6427.903581446189, 6436.774712885027, 6321.683915804283, 6332.416895776056, 6389.9361006389345, 6414.166158399565, 6478.1578394808375, 6304.968475157595, 6306.684665766602, 6306.990881458944, 6314.358266850743, 6411.679416029273, 6304.122964376784, 6377.8634157557735, 6431.935680643133, 6481.027845823164, 6527.51043671717, 5669.858253543694, 6041.9395806041375, 6172.135900973777, 6241.157443745036, 6245.96877015612], "value": 4941.549264720458, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [872.6509396241503, 894.7226359828453, 904.4121321141262, 914.2959920860937, 914.4833169259368, 17956.820431795677, 18913.338033168842, 18924.432267031978, 18946.336878209248, 18992.715109273355, 15354.616134596641, 16046.679066418665, 16135.354585816583, 16545.172741362945, 17284.827151728477, 1024.109025148122, 1043.9321444106147, 1044.2324891204958, 1046.4244665434019, 1047.6385646951828, 1033.5504055735742, 1034.8499467577196, 1034.8809886862994, 1042.2495803021811, 1045.043784936782, 1097.7145942055083, 1111.0223003756723, 1112.1436493899691, 1114.0363585498567, 1115.464577003039, 1015.4415071710566, 1051.9316244444087, 1105.2318638546215, 1106.330669944684, 1109.0351394287022, 798.3573223555036, 798.7843282313801, 802.173761026144, 802.229859335039, 802.4864086984335, 18466.169022394024, 19641.508962276057, 19967.301144459874, 19989.700154497696, 20036.298729544396, 19318.13068411925, 19485.415437536754, 19733.30933417338, 19884.90057549703, 19898.104585293695, 15970.840291597051, 16047.679046419124, 16070.5982350441, 16091.0, 16160.353585856552, 3440.17435815405, 3460.5501284832812, 3490.406630872758, 3544.078539579702, 3576.910577235564, 118974.4051279738, 119050.0, 122708.0, 124544.37727811394, 125910.0, 15362.846371536365, 15557.922210388875, 15794.605134871721, 15857.444989425314, 15914.92042539803, 25663.230103096936, 27495.0, 27539.449211015668, 27615.585766213524, 27874.024409145582, 8382.748517544484, 8383.329333653293, 8384.161583841567, 8477.491350518989, 8501.574921253912, 4173.269677806372, 4246.745195288293, 4279.229738647011, 4289.1421715657, 4291.721038132511, 4452.30989196675, 4527.366168736379, 4570.908581828345, 4574.153781550423, 4574.290984897346, 987.086944576264, 999.2305924438142, 1036.233187441303, 1037.678319720889, 1045.6131231444263, 656.8193746719595, 668.6924014953177, 672.8116127484312, 695.183159787248, 702.7610612391709, 11768.138879272006, 11824.0, 11874.406279686154, 11900.523979040741, 11917.52329906794, 669.9665016749143, 672.862063277027, 686.1902954513622, 692.0829900382014, 713.7644577289586, 5224.738763061831, 5253.789848406021, 5317.73411329432, 5327.733613319396, 5423.376311724202, 95067.52466237625, 97768.51115744376, 98964.0, 101084.0, 102684.48657756664, 84287.99999999878, 88428.0, 88883.11116888878, 89626.0, 90112.5494372524, 72916.270837292, 74474.62762686152, 74581.6270918642, 75984.0, 77159.2284077152, 10475.528601213116, 10475.790484190426, 10483.108935740547, 10617.681469555926, 10820.080293174853, 35483.29033419266, 36954.0, 37290.06774830651, 37470.625293747806, 38031.80984095062, 44564.10871782481, 44583.0, 45700.31449528326, 67538.0218638214, 69683.30316696769, 18383.0, 18431.815681843545, 18571.2571497144, 18712.064396780377, 19516.90241548783, 19101.235950561822, 20206.696899546223, 20274.391768246976, 20388.388348349574, 20531.69202461994, 17715.557111072325, 18461.353852615095, 18865.811341886405, 19499.61000780005, 19591.31430399929, 18620.720689189944, 18677.0, 18761.34335298258, 18822.905885470485, 18993.050347482847, 17592.371158674647, 17705.468835934942, 17787.288508460035, 17965.101744912965, 18232.635347293246, 13130.212187268795, 13806.17162970225, 13899.930500347838, 14242.501512447014, 14315.355808988412, 13628.93185534066, 13882.861171388156, 14161.362738676578, 14301.642458938193, 14423.639409014859], "value": 8806.82931147576, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1532.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 107.0, 110.0, 109.0, 112.0, 108.0, 109.0, 110.0, 109.0, 109.0], "value": 108.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 120.0, 152.0, 180.0, 122.0, 154.0, 150.0, 120.0, 175.0, 129.0], "value": 145.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 163.0, 161.0, 162.0, 170.0, 163.0, 167.0, 170.0, 165.0], "value": 164.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 162.0, 75.0, 75.0, 75.0, 75.0, 97.0, 74.0], "value": 86.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 138.0, 80.0, 82.0, 81.0, 128.0, 81.0, 81.0, 131.0], "value": 96.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 150.0, 151.0, 149.0, 152.0, 150.0, 151.0, 149.0, 150.0], "value": 150.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 56.0, 57.0, 56.0, 54.0, 55.0, 55.0, 55.0, 56.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 55.0, 54.0, 53.0, 54.0, 54.0, 54.0, 55.0], "value": 54.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 79.0, 76.0, 76.0, 87.0, 76.0, 75.0, 77.0, 76.0, 76.0], "value": 77.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 133.0, 128.0, 142.0, 123.0, 124.0, 123.0, 123.0, 125.0, 155.0], "value": 131.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 186.0, 195.0, 182.0, 188.0, 179.0, 181.0, 199.0, 183.0, 179.0], "value": 185.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 68.0, 71.0, 68.0, 70.0, 69.0, 69.0, 70.0, 67.0], "value": 69.2, "unit": "score"}]}]} 06:11:48 INFO - Return code: 0 06:11:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:11:48 INFO - # TBPL SUCCESS # 06:11:48 INFO - Running post-action listener: _package_coverage_data 06:11:48 INFO - Running post-action listener: _resource_record_post_action 06:11:48 INFO - [mozharness: 2017-07-17 13:11:48.106636Z] Finished run-tests step (success) 06:11:48 INFO - Running post-run listener: _resource_record_post_run 06:11:48 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 433377280; Read time: 36; Write time: 554260 06:11:48 INFO - TinderboxPrint: CPU usage
13.1% 06:11:48 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 36 06:11:48 INFO - TinderboxPrint: I/O write bytes / time
433,377,280 / 554,260 06:11:48 INFO - TinderboxPrint: CPU idle
8,259.4 (86.9%) 06:11:48 INFO - TinderboxPrint: CPU user
1,196.6 (12.6%) 06:11:48 INFO - TinderboxPrint: Swap in / out
0 / 0 06:11:48 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 107352064; Read time: 0; Write time: 185536 06:11:48 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 06:11:48 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 326025216; Read time: 0; Write time: 368724 06:11:48 INFO - Running post-run listener: _upload_blobber_files 06:11:48 INFO - Blob upload gear active. 06:11:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:11:48 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 06:11:48 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'] 06:11:48 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 06:11:49 INFO - Traceback (most recent call last): 06:11:49 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 06:11:49 INFO - main() 06:11:49 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 06:11:49 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 06:11:49 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 06:11:49 INFO - return set(response.json().get('whitelist', [])) 06:11:49 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 06:11:49 INFO - return json.loads(self.text or self.content, **kwargs) 06:11:49 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/__init__.py", line 488, in loads 06:11:49 INFO - return _default_decoder.decode(s) 06:11:49 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 370, in decode 06:11:49 INFO - obj, end = self.raw_decode(s) 06:11:49 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 389, in raw_decode 06:11:49 INFO - return self.scan_once(s, idx=_w(s, idx).end()) 06:11:49 INFO - simplejson.scanner.JSONDecodeError: Expecting value: line 1 column 1 (char 0) 06:11:49 ERROR - Return code: 1 06:11:49 INFO - Setting buildbot property blobber_files to {} 06:11:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:11:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:11:49 INFO - Contents: 06:11:49 INFO - blobber_files:{} 06:11:49 INFO - Running post-run listener: copy_logs_to_upload_dir 06:11:49 INFO - Copying logs to upload dir... 06:11:49 INFO - mkdir: /builds/slave/test/build/upload/logs 06:11:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.115218 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-07-17 06:11:48.639623) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-17 06:11:48.646906) ========= 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=df17445c3590ff7897617a0e00000225-1500288197.379760-841038802 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011211 build_url: 'https://queue.taskcluster.net/v1/task/YTzcMzTxSwOAIfzphKHUfw/artifacts/public/build/target.tar.bz2' blobber_files: '{}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-17 06:11:48.705338) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 06:11:48.705709) ========= 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=df17445c3590ff7897617a0e00000225-1500288197.379760-841038802 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004449 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 06:11:48.756563) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-17 06:11:48.756904) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-17 06:11:48.757232) ========= ========= Total master_lag: 0.18 =========