builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-038 starttime: 1500254520.42 results: success (0) revision: aff336ac161daa3ea350e59a288963edbd58ed39 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-16 18:22:00.416587) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-16 18:22:00.417359) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-16 18:22:00.417641) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-16 18:22:00.493963) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 18:22:00.494334) ========= 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=8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-16 18:22: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% 39.1M=0s 2017-07-16 18:22:00 (39.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.181853 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 18:22:00.716261) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 18:22:00.716563) ========= 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=8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.049359 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 18:22:00.775765) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 18:22:00.776091) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev aff336ac161daa3ea350e59a288963edbd58ed39 --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 aff336ac161daa3ea350e59a288963edbd58ed39 --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=8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-16 18:22:00,766 truncating revision to first 12 chars 2017-07-16 18:22:00,766 Setting DEBUG logging. 2017-07-16 18:22:00,766 attempt 1/10 2017-07-16 18:22:00,766 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/aff336ac161d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-16 18:22:01,221 unpacking tar archive at: mozilla-central-aff336ac161d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.767426 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 18:22:01.561799) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-16 18:22:01.562123) ========= script_repo_revision: aff336ac161daa3ea350e59a288963edbd58ed39 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-16 18:22:01.562531) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-16 18:22:01.562819) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-16 18:22:01.580179) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-07-16 18:22:01.580485) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:22:01 INFO - MultiFileLogger online at 20170716 18:22:01 in /builds/slave/test 18:22:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:22:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:22:01 INFO - {'append_to_log': False, 18:22:01 INFO - 'base_work_dir': '/builds/slave/test', 18:22:01 INFO - 'blob_upload_branch': 'Firefox', 18:22:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:22:01 INFO - 'branch': 'Firefox', 18:22:01 INFO - 'buildbot_json_path': 'buildprops.json', 18:22:01 INFO - 'code_coverage': False, 18:22:01 INFO - 'config_files': ('talos/linux_config.py',), 18:22:01 INFO - 'default_actions': ('clobber', 18:22:01 INFO - 'read-buildbot-config', 18:22:01 INFO - 'download-and-extract', 18:22:01 INFO - 'populate-webroot', 18:22:01 INFO - 'create-virtualenv', 18:22:01 INFO - 'install', 18:22:01 INFO - 'setup-mitmproxy', 18:22:01 INFO - 'run-tests'), 18:22:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:22:01 INFO - 'disable_ccov_upload': False, 18:22:01 INFO - 'download_minidump_stackwalk': True, 18:22:01 INFO - 'download_symbols': 'ondemand', 18:22:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:22:01 INFO - 'tooltool.py': '/tools/tooltool.py', 18:22:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:22:01 INFO - '/tools/misc-python/virtualenv.py')}, 18:22:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:22:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:22:01 INFO - 'gecko_profile': False, 18:22:01 INFO - 'gecko_profile_interval': 0, 18:22:01 INFO - 'installer_path': 'installer.exe', 18:22:01 INFO - 'log_level': 'info', 18:22:01 INFO - 'log_name': 'talos', 18:22:01 INFO - 'log_to_console': True, 18:22:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:22:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:22:01 INFO - 'opt_config_files': (), 18:22:01 INFO - 'pip_index': False, 18:22:01 INFO - 'suite': 'dromaeojs-e10s', 18:22:01 INFO - 'system_bits': '32', 18:22:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:22:01 INFO - 'title': 'talos-linux64-ix-038', 18:22:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:22:01 INFO - 'use_talos_json': True, 18:22:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:22:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:22:01 INFO - 'work_dir': 'build'} 18:22:01 INFO - [mozharness: 2017-07-17 01:22:01.795137Z] Running clobber step. 18:22:01 INFO - Running pre-action listener: _resource_record_pre_action 18:22:01 INFO - Running main action method: clobber 18:22:01 INFO - rmtree: /builds/slave/test/build 18:22:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:22:04 INFO - Running post-action listener: _resource_record_post_action 18:22:04 INFO - [mozharness: 2017-07-17 01:22:04.367063Z] Finished clobber step (success) 18:22:04 INFO - [mozharness: 2017-07-17 01:22:04.367222Z] Running read-buildbot-config step. 18:22:04 INFO - Running pre-action listener: _resource_record_pre_action 18:22:04 INFO - Running main action method: read_buildbot_config 18:22:04 INFO - Using buildbot properties: 18:22:04 INFO - { 18:22:04 INFO - "project": "", 18:22:04 INFO - "product": "firefox", 18:22:04 INFO - "who": "nnethercote@mozilla.com", 18:22:04 INFO - "installer_path": "public/build/target.tar.bz2", 18:22:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:22:04 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:22:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 18:22:04 INFO - "stage_platform": "linux64-pgo", 18:22:04 INFO - "basedir": "/builds/slave/test", 18:22:04 INFO - "buildnumber": 67, 18:22:04 INFO - "platform": "ubuntu64_hw", 18:22:04 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:22:04 INFO - "slavebuilddir": "test", 18:22:04 INFO - "taskId": "UeB3xUpsSRubfCce-IJ04A", 18:22:04 INFO - "branch": "mozilla-central", 18:22:04 INFO - "script_repo_revision": "production", 18:22:04 INFO - "revision": "aff336ac161daa3ea350e59a288963edbd58ed39", 18:22:04 INFO - "slavename": "talos-linux64-ix-038", 18:22:04 INFO - "repo_path": "mozilla-central" 18:22:04 INFO - } 18:22:04 INFO - Finding installer, test and symbols from parent task. 18:22:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UeB3xUpsSRubfCce-IJ04A'}, attempt #1 18:22:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UeB3xUpsSRubfCce-IJ04A'}, attempt #1 18:22:05 INFO - Task dependencies: L_tPsB8BSw60ipwcCtx25w 18:22:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w'}, attempt #1 18:22:05 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.tar.bz2 18:22:05 INFO - Set test_url: https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/test_packages.json 18:22:05 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.crashreporter-symbols.zip 18:22:05 INFO - Running post-action listener: _resource_record_post_action 18:22:05 INFO - [mozharness: 2017-07-17 01:22:05.734248Z] Finished read-buildbot-config step (success) 18:22:05 INFO - [mozharness: 2017-07-17 01:22:05.734565Z] Running download-and-extract step. 18:22:05 INFO - Running pre-action listener: _resource_record_pre_action 18:22:05 INFO - Running main action method: download_and_extract 18:22:05 INFO - mkdir: /builds/slave/test/build/tests 18:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:06 INFO - trying https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.test_packages.json 18:22:06 INFO - Downloading https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:22:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:22:06 INFO - Downloaded 989 bytes. 18:22:06 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:22:06 INFO - Using the following test package requirements: 18:22:06 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:22:06 INFO - u'common': [u'target.common.tests.zip'], 18:22:06 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:22:06 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:22:06 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:22:06 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:22:06 INFO - u'mozbase': [u'target.common.tests.zip'], 18:22:06 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:22:06 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:22:06 INFO - u'web-platform': [u'target.common.tests.zip', 18:22:06 INFO - u'target.web-platform.tests.tar.gz'], 18:22:06 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:22:06 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:22:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.common.tests.zip 18:22:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:22:06 INFO - Fetch https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.common.tests.zip into memory 18:22:07 INFO - Content-Length response header: 73391397 18:22:07 INFO - Bytes received: 73391397 18:22:19 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:22:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.common.tests.zip 18:22:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:22:19 INFO - Fetch https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.common.tests.zip into memory 18:22:20 INFO - Content-Length response header: 73391397 18:22:20 INFO - Bytes received: 73391397 18:22:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.talos.tests.zip 18:22:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:22:36 INFO - Fetch https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.talos.tests.zip into memory 18:22:36 INFO - Content-Length response header: 14495033 18:22:36 INFO - Bytes received: 14495033 18:22:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:37 INFO - trying https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.tar.bz2 18:22:37 INFO - Downloading https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:22:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:22:38 INFO - Downloaded 62614741 bytes. 18:22:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.tar.bz2 18:22:38 INFO - mkdir: /builds/slave/test/properties 18:22:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:22:38 INFO - Writing to file /builds/slave/test/properties/build_url 18:22:38 INFO - Contents: 18:22:38 INFO - build_url:https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.tar.bz2 18:22:38 INFO - Running post-action listener: _resource_record_post_action 18:22:38 INFO - Running post-action listener: set_extra_try_arguments 18:22:38 INFO - [mozharness: 2017-07-17 01:22:38.372555Z] Finished download-and-extract step (success) 18:22:38 INFO - [mozharness: 2017-07-17 01:22:38.372652Z] Running populate-webroot step. 18:22:38 INFO - Running pre-action listener: _resource_record_pre_action 18:22:38 INFO - Running main action method: populate_webroot 18:22:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:22:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:22:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:22:38 INFO - '--tppagecycles', 18:22:38 INFO - '7'], 18:22:38 INFO - 'tests': ['tsvgm']}}, 18:22:38 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:22:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:22:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:22:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:22:38 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:22:38 INFO - 'talos_options': ['--disable-e10s'], 18:22:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:22:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:22:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:22:38 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:22:38 INFO - 'talos_options': ['--disable-e10s'], 18:22:38 INFO - 'tests': ['damp', 'tps']}, 18:22:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:22:38 INFO - 'tests': ['damp', 'tps']}, 18:22:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:22:38 INFO - 'tests': ['dromaeo_dom']}, 18:22:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:22:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:22:38 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:22:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:22:38 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:22:38 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:22:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:22:38 INFO - 'tests': ['a11yr', 18:22:38 INFO - 'ts_paint', 18:22:38 INFO - 'tpaint', 18:22:38 INFO - 'sessionrestore', 18:22:38 INFO - 'sessionrestore_no_auto_restore', 18:22:38 INFO - 'tabpaint']}, 18:22:38 INFO - 'other-e10s': {'tests': ['a11yr', 18:22:38 INFO - 'ts_paint', 18:22:38 INFO - 'tpaint', 18:22:38 INFO - 'sessionrestore', 18:22:38 INFO - 'sessionrestore_many_windows', 18:22:38 INFO - 'sessionrestore_no_auto_restore', 18:22:38 INFO - 'tabpaint']}, 18:22:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:22:38 INFO - 'ts_paint', 18:22:38 INFO - 'tpaint', 18:22:38 INFO - 'sessionrestore', 18:22:38 INFO - 'sessionrestore_many_windows', 18:22:38 INFO - 'sessionrestore_no_auto_restore', 18:22:38 INFO - 'tabpaint']}, 18:22:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:22:38 INFO - 'ts_paint', 18:22:38 INFO - 'tpaint', 18:22:38 INFO - 'sessionrestore', 18:22:38 INFO - 'sessionrestore_many_windows', 18:22:38 INFO - 'sessionrestore_no_auto_restore', 18:22:38 INFO - 'tabpaint']}, 18:22:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:22:38 INFO - 'tests': ['a11yr', 18:22:38 INFO - 'ts_paint', 18:22:38 INFO - 'tpaint', 18:22:38 INFO - 'sessionrestore', 18:22:38 INFO - 'sessionrestore_no_auto_restore', 18:22:38 INFO - 'tabpaint']}, 18:22:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:22:38 INFO - 'tests': ['a11yr', 18:22:38 INFO - 'ts_paint', 18:22:38 INFO - 'tpaint', 18:22:38 INFO - 'sessionrestore', 18:22:38 INFO - 'sessionrestore_no_auto_restore', 18:22:38 INFO - 'tabpaint']}, 18:22:38 INFO - 'perf-reftest': {'tests': ['bloom_basic']}, 18:22:38 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:22:38 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:22:38 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:22:38 INFO - 'talos_options': ['--mitmproxy', 18:22:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:22:38 INFO - '--firstNonBlankPaint'], 18:22:38 INFO - 'tests': ['quantum_pageload_google', 18:22:38 INFO - 'quantum_pageload_youtube', 18:22:38 INFO - 'quantum_pageload_amazon', 18:22:38 INFO - 'quantum_pageload_facebook']}, 18:22:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:22:38 INFO - 'tests': ['tsvgx', 18:22:38 INFO - 'tsvgr_opacity', 18:22:38 INFO - 'tart', 18:22:38 INFO - 'tscrollx', 18:22:38 INFO - 'cart', 18:22:38 INFO - 'tsvg_static']}, 18:22:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:22:38 INFO - 'tsvgr_opacity', 18:22:38 INFO - 'tart', 18:22:38 INFO - 'tscrollx', 18:22:38 INFO - 'cart', 18:22:38 INFO - 'tsvg_static']}, 18:22:38 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:22:38 INFO - 'talos_options': ['--disable-e10s'], 18:22:38 INFO - 'tests': ['tp5o']}, 18:22:38 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:22:38 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:22:38 INFO - 'talos_options': ['--disable-e10s', 18:22:38 INFO - '--xperf_path', 18:22:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:22:38 INFO - 'tests': ['tp5n']}, 18:22:38 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:22:38 INFO - 'talos_options': ['--xperf_path', 18:22:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:22:38 INFO - 'tests': ['tp5n']}}, 18:22:38 INFO - 'talos.zip': {'path': '', 18:22:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:22:38 INFO - Running post-action listener: _resource_record_post_action 18:22:38 INFO - [mozharness: 2017-07-17 01:22:38.387977Z] Finished populate-webroot step (success) 18:22:38 INFO - [mozharness: 2017-07-17 01:22:38.388068Z] Running create-virtualenv step. 18:22:38 INFO - Running pre-action listener: _resource_record_pre_action 18:22:38 INFO - Running main action method: create_virtualenv 18:22:38 INFO - Creating virtualenv /builds/slave/test/build/venv 18:22:38 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 18:22:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:22:38 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:22:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:22:38 INFO - Using real prefix '/usr' 18:22:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:22:39 INFO - Installing distribute.............................................................................................................................................................................................done. 18:22:41 INFO - Installing pip.................done. 18:22:41 INFO - Return code: 0 18:22:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:22:41 INFO - Reading from file tmpfile_stdout 18:22:41 INFO - Output received: 18:22:41 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) 18:22:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:41 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 0xd3a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf06a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10abb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d00>, '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 0x10a9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa090>, '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': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:22:41 INFO - Using env: {'DISPLAY': ':0', 18:22:41 INFO - 'HOME': '/home/cltbld', 18:22:41 INFO - 'LANG': 'en_US.UTF-8', 18:22:41 INFO - 'LANGUAGE': 'en_US:en', 18:22:41 INFO - 'LOGNAME': 'cltbld', 18:22:41 INFO - 'MAIL': '/var/mail/cltbld', 18:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:41 INFO - 'MOZ_NO_REMOTE': '1', 18:22:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:41 INFO - 'NO_EM_RESTART': '1', 18:22:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:41 INFO - 'PWD': '/builds/slave/test', 18:22:41 INFO - 'SHELL': '/bin/bash', 18:22:41 INFO - 'SHLVL': '1', 18:22:41 INFO - 'TERM': 'linux', 18:22:41 INFO - 'TMOUT': '86400', 18:22:41 INFO - 'USER': 'cltbld', 18:22:41 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 18:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:41 INFO - '_': '/tools/buildbot/bin/python'} 18:22:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:41 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 18:22:41 INFO - Cleaning up... 18:22:41 INFO - Return code: 0 18:22:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:41 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 0xd3a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf06a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10abb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d00>, '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 0x10a9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa090>, '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': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:22:41 INFO - Using env: {'DISPLAY': ':0', 18:22:41 INFO - 'HOME': '/home/cltbld', 18:22:41 INFO - 'LANG': 'en_US.UTF-8', 18:22:41 INFO - 'LANGUAGE': 'en_US:en', 18:22:41 INFO - 'LOGNAME': 'cltbld', 18:22:41 INFO - 'MAIL': '/var/mail/cltbld', 18:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:41 INFO - 'MOZ_NO_REMOTE': '1', 18:22:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:41 INFO - 'NO_EM_RESTART': '1', 18:22:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:41 INFO - 'PWD': '/builds/slave/test', 18:22:41 INFO - 'SHELL': '/bin/bash', 18:22:41 INFO - 'SHLVL': '1', 18:22:41 INFO - 'TERM': 'linux', 18:22:41 INFO - 'TMOUT': '86400', 18:22:41 INFO - 'USER': 'cltbld', 18:22:41 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 18:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:41 INFO - '_': '/tools/buildbot/bin/python'} 18:22:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:41 INFO - Downloading/unpacking psutil>=3.1.1 18:22: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 18:22: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 18:22: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 18:22: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 18:22:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:22:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:43 INFO - Installing collected packages: psutil 18:22:43 INFO - Running setup.py install for psutil 18:22:43 INFO - building 'psutil._psutil_linux' extension 18:22:43 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:22:44 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:22:44 INFO - building 'psutil._psutil_posix' extension 18:22:44 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:22:44 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:22:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:44 INFO - Successfully installed psutil 18:22:44 INFO - Cleaning up... 18:22:44 INFO - Return code: 0 18:22:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:22: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')]} 18:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22: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')]} 18:22:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd3a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf06a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10abb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d00>, '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 0x10a9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa090>, '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': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:22: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:22: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 mozsystemmonitor==0.3 18:22:44 INFO - Using env: {'DISPLAY': ':0', 18:22:44 INFO - 'HOME': '/home/cltbld', 18:22:44 INFO - 'LANG': 'en_US.UTF-8', 18:22:44 INFO - 'LANGUAGE': 'en_US:en', 18:22:44 INFO - 'LOGNAME': 'cltbld', 18:22:44 INFO - 'MAIL': '/var/mail/cltbld', 18:22:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:44 INFO - 'MOZ_NO_REMOTE': '1', 18:22:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:44 INFO - 'NO_EM_RESTART': '1', 18:22:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:44 INFO - 'PWD': '/builds/slave/test', 18:22:44 INFO - 'SHELL': '/bin/bash', 18:22:44 INFO - 'SHLVL': '1', 18:22:44 INFO - 'TERM': 'linux', 18:22:44 INFO - 'TMOUT': '86400', 18:22:44 INFO - 'USER': 'cltbld', 18:22:44 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 18:22:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:44 INFO - '_': '/tools/buildbot/bin/python'} 18:22:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:22: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 18:22: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 18:22: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 18:22: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 18:22:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:22:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:22:46 INFO - Installing collected packages: mozsystemmonitor 18:22:46 INFO - Running setup.py install for mozsystemmonitor 18:22:46 INFO - Successfully installed mozsystemmonitor 18:22:46 INFO - Cleaning up... 18:22:46 INFO - Return code: 0 18:22:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:22: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')]} 18:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22: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')]} 18:22:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:46 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 0xd3a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf06a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10abb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d00>, '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 0x10a9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa090>, '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': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:22:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:22:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:22:46 INFO - Using env: {'DISPLAY': ':0', 18:22:46 INFO - 'HOME': '/home/cltbld', 18:22:46 INFO - 'LANG': 'en_US.UTF-8', 18:22:46 INFO - 'LANGUAGE': 'en_US:en', 18:22:46 INFO - 'LOGNAME': 'cltbld', 18:22:46 INFO - 'MAIL': '/var/mail/cltbld', 18:22:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:46 INFO - 'MOZ_NO_REMOTE': '1', 18:22:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:46 INFO - 'NO_EM_RESTART': '1', 18:22:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:46 INFO - 'PWD': '/builds/slave/test', 18:22:46 INFO - 'SHELL': '/bin/bash', 18:22:46 INFO - 'SHLVL': '1', 18:22:46 INFO - 'TERM': 'linux', 18:22:46 INFO - 'TMOUT': '86400', 18:22:46 INFO - 'USER': 'cltbld', 18:22:46 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 18:22:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:46 INFO - '_': '/tools/buildbot/bin/python'} 18:22:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:46 INFO - Downloading/unpacking jsonschema==2.5.1 18:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:22:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:47 INFO - Downloading functools32-3.2.3-2.tar.gz 18:22:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:22:47 INFO - warning: no files found matching '*.txt' 18:22:47 INFO - no previously-included directories found matching 'build' 18:22:47 INFO - no previously-included directories found matching 'dist' 18:22:47 INFO - no previously-included directories found matching '.git*' 18:22:47 INFO - Installing collected packages: jsonschema, functools32 18:22:47 INFO - Running setup.py install for functools32 18:22:47 INFO - warning: no files found matching '*.txt' 18:22:47 INFO - no previously-included directories found matching 'build' 18:22:47 INFO - no previously-included directories found matching 'dist' 18:22:47 INFO - no previously-included directories found matching '.git*' 18:22:47 INFO - Successfully installed jsonschema functools32 18:22:47 INFO - Cleaning up... 18:22:48 INFO - Return code: 0 18:22:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:22:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd3a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf06a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10abb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d00>, '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 0x10a9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa090>, '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': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:22:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:22:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:22:48 INFO - Using env: {'DISPLAY': ':0', 18:22:48 INFO - 'HOME': '/home/cltbld', 18:22:48 INFO - 'LANG': 'en_US.UTF-8', 18:22:48 INFO - 'LANGUAGE': 'en_US:en', 18:22:48 INFO - 'LOGNAME': 'cltbld', 18:22:48 INFO - 'MAIL': '/var/mail/cltbld', 18:22:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:48 INFO - 'MOZ_NO_REMOTE': '1', 18:22:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:48 INFO - 'NO_EM_RESTART': '1', 18:22:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:48 INFO - 'PWD': '/builds/slave/test', 18:22:48 INFO - 'SHELL': '/bin/bash', 18:22:48 INFO - 'SHLVL': '1', 18:22:48 INFO - 'TERM': 'linux', 18:22:48 INFO - 'TMOUT': '86400', 18:22:48 INFO - 'USER': 'cltbld', 18:22:48 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 18:22:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:48 INFO - '_': '/tools/buildbot/bin/python'} 18:22:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:22:48 INFO - Cleaning up... 18:22:48 INFO - Return code: 0 18:22:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:22:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd3a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf06a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10abb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d00>, '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 0x10a9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa090>, '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': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:22:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:22:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:22:48 INFO - Using env: {'DISPLAY': ':0', 18:22:48 INFO - 'HOME': '/home/cltbld', 18:22:48 INFO - 'LANG': 'en_US.UTF-8', 18:22:48 INFO - 'LANGUAGE': 'en_US:en', 18:22:48 INFO - 'LOGNAME': 'cltbld', 18:22:48 INFO - 'MAIL': '/var/mail/cltbld', 18:22:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:48 INFO - 'MOZ_NO_REMOTE': '1', 18:22:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:48 INFO - 'NO_EM_RESTART': '1', 18:22:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:48 INFO - 'PWD': '/builds/slave/test', 18:22:48 INFO - 'SHELL': '/bin/bash', 18:22:48 INFO - 'SHLVL': '1', 18:22:48 INFO - 'TERM': 'linux', 18:22:48 INFO - 'TMOUT': '86400', 18:22:48 INFO - 'USER': 'cltbld', 18:22:48 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 18:22:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:48 INFO - '_': '/tools/buildbot/bin/python'} 18:22:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:48 INFO - Downloading/unpacking blobuploader==1.2.4 18:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:49 INFO - Downloading blobuploader-1.2.4.tar.gz 18:22:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:22:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:22:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:50 INFO - Downloading docopt-0.6.1.tar.gz 18:22:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:22:50 INFO - Installing collected packages: blobuploader, requests, docopt 18:22:50 INFO - Running setup.py install for blobuploader 18:22:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:22:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:22:50 INFO - Running setup.py install for requests 18:22:50 INFO - Running setup.py install for docopt 18:22:50 INFO - Successfully installed blobuploader requests docopt 18:22:50 INFO - Cleaning up... 18:22:50 INFO - Return code: 0 18:22:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:50 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 0xd3a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf06a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10abb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d00>, '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 0x10a9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa090>, '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': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:22:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:22:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:22:50 INFO - Using env: {'DISPLAY': ':0', 18:22:50 INFO - 'HOME': '/home/cltbld', 18:22:50 INFO - 'LANG': 'en_US.UTF-8', 18:22:50 INFO - 'LANGUAGE': 'en_US:en', 18:22:50 INFO - 'LOGNAME': 'cltbld', 18:22:50 INFO - 'MAIL': '/var/mail/cltbld', 18:22:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:50 INFO - 'MOZ_NO_REMOTE': '1', 18:22:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:50 INFO - 'NO_EM_RESTART': '1', 18:22:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:50 INFO - 'PWD': '/builds/slave/test', 18:22:50 INFO - 'SHELL': '/bin/bash', 18:22:50 INFO - 'SHLVL': '1', 18:22:50 INFO - 'TERM': 'linux', 18:22:50 INFO - 'TMOUT': '86400', 18:22:50 INFO - 'USER': 'cltbld', 18:22:50 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 18:22:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:50 INFO - '_': '/tools/buildbot/bin/python'} 18:22:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:22:50 INFO - Running setup.py (path:/tmp/pip-C_4XGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:22:51 INFO - Running setup.py (path:/tmp/pip-ZDgFZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:22:51 INFO - Running setup.py (path:/tmp/pip-noD6AA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:22:51 INFO - Running setup.py (path:/tmp/pip-Lbwa0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:22:51 INFO - Running setup.py (path:/tmp/pip-rZZbjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:22:51 INFO - Running setup.py (path:/tmp/pip-qXBsXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:22:51 INFO - Running setup.py (path:/tmp/pip-X62rt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:22:51 INFO - Running setup.py (path:/tmp/pip-iSrKwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:22:51 INFO - Running setup.py (path:/tmp/pip-ze8asT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:22:51 INFO - Running setup.py (path:/tmp/pip-lupSop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:22:51 INFO - Running setup.py (path:/tmp/pip-wfHASC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:22:51 INFO - Running setup.py (path:/tmp/pip-7FjnaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:22:51 INFO - Running setup.py (path:/tmp/pip-hipIBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:22:51 INFO - Running setup.py (path:/tmp/pip-8JF_lk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:51 INFO - Running setup.py (path:/tmp/pip-6G3uCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:22:52 INFO - Running setup.py (path:/tmp/pip-Ni9nP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:22:52 INFO - Running setup.py (path:/tmp/pip-aiiI33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:22:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:22:52 INFO - Running setup.py install for manifestparser 18:22:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:22:52 INFO - Running setup.py install for mozcrash 18:22:52 INFO - Running setup.py install for mozdebug 18:22:52 INFO - Running setup.py install for mozdevice 18:22:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:22:52 INFO - Running setup.py install for mozfile 18:22:52 INFO - Running setup.py install for mozhttpd 18:22:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:22:52 INFO - Running setup.py install for mozinfo 18:22:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:22:52 INFO - Running setup.py install for mozInstall 18:22:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:22:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:22:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:22:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:22:52 INFO - Running setup.py install for mozleak 18:22:53 INFO - Running setup.py install for mozlog 18:22:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:22:53 INFO - Running setup.py install for moznetwork 18:22:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:22:53 INFO - Running setup.py install for mozprocess 18:22:53 INFO - Running setup.py install for mozprofile 18:22:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:22:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:22:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:22:53 INFO - Running setup.py install for mozrunner 18:22:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:22:53 INFO - Running setup.py install for mozscreenshot 18:22:53 INFO - Running setup.py install for moztest 18:22:53 INFO - Running setup.py install for mozversion 18:22:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:22:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:22:53 INFO - Cleaning up... 18:22:53 INFO - Return code: 0 18:22:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:53 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 0xd3a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf06a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10abb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d00>, '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 0x10a9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa090>, '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': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:22:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:22:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:22:53 INFO - Using env: {'DISPLAY': ':0', 18:22:53 INFO - 'HOME': '/home/cltbld', 18:22:53 INFO - 'LANG': 'en_US.UTF-8', 18:22:53 INFO - 'LANGUAGE': 'en_US:en', 18:22:53 INFO - 'LOGNAME': 'cltbld', 18:22:53 INFO - 'MAIL': '/var/mail/cltbld', 18:22:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:53 INFO - 'MOZ_NO_REMOTE': '1', 18:22:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:53 INFO - 'NO_EM_RESTART': '1', 18:22:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:53 INFO - 'PWD': '/builds/slave/test', 18:22:53 INFO - 'SHELL': '/bin/bash', 18:22:53 INFO - 'SHLVL': '1', 18:22:53 INFO - 'TERM': 'linux', 18:22:53 INFO - 'TMOUT': '86400', 18:22:53 INFO - 'USER': 'cltbld', 18:22:53 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 18:22:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:53 INFO - '_': '/tools/buildbot/bin/python'} 18:22:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:22:54 INFO - Running setup.py (path:/tmp/pip-ErKlxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:22:54 INFO - Running setup.py (path:/tmp/pip-Qz8dF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:22:54 INFO - Running setup.py (path:/tmp/pip-XpBYFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:22:54 INFO - Running setup.py (path:/tmp/pip-z_QEx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:22:54 INFO - Running setup.py (path:/tmp/pip-bjUtss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:22:54 INFO - Running setup.py (path:/tmp/pip-hCz3OA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:22:54 INFO - Running setup.py (path:/tmp/pip-HbIoD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:22:54 INFO - Running setup.py (path:/tmp/pip-hPdKfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:22:54 INFO - Running setup.py (path:/tmp/pip-qVPlvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:22:54 INFO - Running setup.py (path:/tmp/pip-FqN_Jt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:22:54 INFO - Running setup.py (path:/tmp/pip-9WlHcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:22:54 INFO - Running setup.py (path:/tmp/pip-uqJlLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:22:55 INFO - Running setup.py (path:/tmp/pip-HC0uLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:22:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:22:55 INFO - Running setup.py (path:/tmp/pip-ZP9c0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:22:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:55 INFO - Running setup.py (path:/tmp/pip-bNsc_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:22:55 INFO - Running setup.py (path:/tmp/pip-Bl_FIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:22:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:22:55 INFO - Running setup.py (path:/tmp/pip-Lzz4D4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:22:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:22:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:22:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:22:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:22:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:22:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:22:55 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:22:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:57 INFO - Downloading blessings-1.6.tar.gz 18:22:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:22:57 INFO - Installing collected packages: blessings 18:22:57 INFO - Running setup.py install for blessings 18:22:57 INFO - Successfully installed blessings 18:22:57 INFO - Cleaning up... 18:22:57 INFO - Return code: 0 18:22:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:22:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:22:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:22:57 INFO - Reading from file tmpfile_stdout 18:22:57 INFO - Current package versions: 18:22:57 INFO - argparse == 1.2.1 18:22:57 INFO - blessings == 1.6 18:22:57 INFO - blobuploader == 1.2.4 18:22:57 INFO - docopt == 0.6.1 18:22:57 INFO - functools32 == 3.2.3-2 18:22:57 INFO - jsonschema == 2.5.1 18:22:57 INFO - manifestparser == 1.1 18:22:57 INFO - mozInstall == 1.13 18:22:57 INFO - mozcrash == 1.0 18:22:57 INFO - mozdebug == 0.1 18:22:57 INFO - mozdevice == 0.51 18:22:57 INFO - mozfile == 1.2 18:22:57 INFO - mozhttpd == 0.7 18:22:57 INFO - mozinfo == 0.10 18:22:57 INFO - mozleak == 0.1 18:22:57 INFO - mozlog == 3.5 18:22:57 INFO - moznetwork == 0.27 18:22:57 INFO - mozprocess == 0.25 18:22:57 INFO - mozprofile == 0.28 18:22:57 INFO - mozrunner == 6.13 18:22:57 INFO - mozscreenshot == 0.1 18:22:57 INFO - mozsystemmonitor == 0.3 18:22:57 INFO - moztest == 0.8 18:22:57 INFO - mozversion == 1.4 18:22:57 INFO - psutil == 3.1.1 18:22:57 INFO - requests == 1.2.3 18:22:57 INFO - wsgiref == 0.1.2 18:22:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:57 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 0xd3a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf06a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10abb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d00>, '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 0x10a9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa090>, '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': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:22:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:22:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:22:57 INFO - Using env: {'DISPLAY': ':0', 18:22:57 INFO - 'HOME': '/home/cltbld', 18:22:57 INFO - 'LANG': 'en_US.UTF-8', 18:22:57 INFO - 'LANGUAGE': 'en_US:en', 18:22:57 INFO - 'LOGNAME': 'cltbld', 18:22:57 INFO - 'MAIL': '/var/mail/cltbld', 18:22:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:57 INFO - 'MOZ_NO_REMOTE': '1', 18:22:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:57 INFO - 'NO_EM_RESTART': '1', 18:22:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:57 INFO - 'PWD': '/builds/slave/test', 18:22:57 INFO - 'SHELL': '/bin/bash', 18:22:57 INFO - 'SHLVL': '1', 18:22:57 INFO - 'TERM': 'linux', 18:22:57 INFO - 'TMOUT': '86400', 18:22:57 INFO - 'USER': 'cltbld', 18:22:57 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 18:22:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:57 INFO - '_': '/tools/buildbot/bin/python'} 18:22:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:22:58 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:59 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:22:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:22:59 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 18:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:22:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:22:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:22:59 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)) 18:22:59 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)) 18:22:59 INFO - Installing collected packages: marionette-driver, simplejson 18:22:59 INFO - Running setup.py install for marionette-driver 18:22:59 INFO - Running setup.py install for simplejson 18:22:59 INFO - building 'simplejson._speedups' extension 18:22:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 18:23:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:23:00 INFO - Successfully installed marionette-driver simplejson 18:23:00 INFO - Cleaning up... 18:23:00 INFO - Return code: 0 18:23:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd3a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf06a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10abb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d00>, '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 0x10a9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa090>, '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': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:23:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:23:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:23:00 INFO - Using env: {'DISPLAY': ':0', 18:23:00 INFO - 'HOME': '/home/cltbld', 18:23:00 INFO - 'LANG': 'en_US.UTF-8', 18:23:00 INFO - 'LANGUAGE': 'en_US:en', 18:23:00 INFO - 'LOGNAME': 'cltbld', 18:23:00 INFO - 'MAIL': '/var/mail/cltbld', 18:23:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:00 INFO - 'MOZ_NO_REMOTE': '1', 18:23:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:00 INFO - 'NO_EM_RESTART': '1', 18:23:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:00 INFO - 'PWD': '/builds/slave/test', 18:23:00 INFO - 'SHELL': '/bin/bash', 18:23:00 INFO - 'SHLVL': '1', 18:23:00 INFO - 'TERM': 'linux', 18:23:00 INFO - 'TMOUT': '86400', 18:23:00 INFO - 'USER': 'cltbld', 18:23:00 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 18:23:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:00 INFO - '_': '/tools/buildbot/bin/python'} 18:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:23:00 INFO - Cleaning up... 18:23:00 INFO - Return code: 0 18:23:00 INFO - Running post-action listener: _resource_record_post_action 18:23:00 INFO - Running post-action listener: _start_resource_monitoring 18:23:00 INFO - Starting resource monitoring. 18:23:00 INFO - [mozharness: 2017-07-17 01:23:00.976869Z] Finished create-virtualenv step (success) 18:23:00 INFO - [mozharness: 2017-07-17 01:23:00.977914Z] Running install step. 18:23:00 INFO - Running pre-action listener: _resource_record_pre_action 18:23:00 INFO - Running main action method: install 18:23:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:23:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:23:01 INFO - Reading from file tmpfile_stdout 18:23:01 INFO - Detecting whether we're running mozinstall >=1.0... 18:23:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:23:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:23:01 INFO - Reading from file tmpfile_stdout 18:23:01 INFO - Output received: 18:23:01 INFO - Usage: mozinstall [options] installer 18:23:01 INFO - Options: 18:23:01 INFO - -h, --help show this help message and exit 18:23:01 INFO - -d DEST, --destination=DEST 18:23:01 INFO - Directory to install application into. [default: 18:23:01 INFO - "/builds/slave/test"] 18:23:01 INFO - --app=APP Application being installed. [default: firefox] 18:23:01 INFO - mkdir: /builds/slave/test/build/application 18:23:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:23:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:23:19 INFO - Reading from file tmpfile_stdout 18:23:19 INFO - Output received: 18:23:19 INFO - /builds/slave/test/build/application/firefox/firefox 18:23:19 INFO - Running post-action listener: _resource_record_post_action 18:23:19 INFO - [mozharness: 2017-07-17 01:23:19.061097Z] Finished install step (success) 18:23:19 INFO - [mozharness: 2017-07-17 01:23:19.061351Z] Running setup-mitmproxy step. 18:23:19 INFO - Running pre-action listener: _resource_record_pre_action 18:23:19 INFO - Running main action method: setup_mitmproxy 18:23:19 INFO - Skipping: mitmproxy is not required 18:23:19 INFO - Running post-action listener: _resource_record_post_action 18:23:19 INFO - [mozharness: 2017-07-17 01:23:19.062561Z] Finished setup-mitmproxy step (success) 18:23:19 INFO - [mozharness: 2017-07-17 01:23:19.062797Z] Running run-tests step. 18:23:19 INFO - Running pre-action listener: _resource_record_pre_action 18:23:19 INFO - Running pre-action listener: _set_gcov_prefix 18:23:19 INFO - Running main action method: run_tests 18:23:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:23:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:23:19 INFO - Python 2.7.3 18:23:19 INFO - Return code: 0 18:23:19 INFO - grabbing minidump binary from tooltool 18:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:19 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 0x10a8d00>, '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 0x10a9c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa090>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:23:19 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 18:23:19 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 18:23:19 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 18:23:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:23:19 INFO - Return code: 0 18:23:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:23:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:23:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:23:19 INFO - ENV: RUST_BACKTRACE is now 1 18:23:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:23:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:23:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:23:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-038 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:23:19 INFO - Using env: {'DISPLAY': ':0', 18:23:19 INFO - 'HOME': '/home/cltbld', 18:23:19 INFO - 'LANG': 'en_US.UTF-8', 18:23:19 INFO - 'LANGUAGE': 'en_US:en', 18:23:19 INFO - 'LOGNAME': 'cltbld', 18:23:19 INFO - 'MAIL': '/var/mail/cltbld', 18:23:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:23:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:23:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:19 INFO - 'MOZ_NO_REMOTE': '1', 18:23:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:23:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:19 INFO - 'NO_EM_RESTART': '1', 18:23:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:19 INFO - 'PWD': '/builds/slave/test', 18:23:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:23:19 INFO - 'RUST_BACKTRACE': '1', 18:23:19 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:23:19 INFO - 'SHELL': '/bin/bash', 18:23:19 INFO - 'SHLVL': '1', 18:23:19 INFO - 'TERM': 'linux', 18:23:19 INFO - 'TMOUT': '86400', 18:23:19 INFO - 'USER': 'cltbld', 18:23:19 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233', 18:23:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:19 INFO - '_': '/tools/buildbot/bin/python'} 18:23:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:23:19 INFO - mozversion application_buildid: 20170717000747 18:23:19 INFO - mozversion application_changeset: aff336ac161daa3ea350e59a288963edbd58ed39 18:23:19 INFO - mozversion application_display_name: Nightly 18:23:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:23:19 INFO - mozversion application_name: Firefox 18:23:19 INFO - mozversion application_remotingname: firefox 18:23:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:23:19 INFO - mozversion application_vendor: Mozilla 18:23:19 INFO - mozversion application_version: 56.0a1 18:23:19 INFO - mozversion platform_buildid: 20170717000747 18:23:19 INFO - mozversion platform_changeset: aff336ac161daa3ea350e59a288963edbd58ed39 18:23:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:23:19 INFO - mozversion platform_version: 56.0a1 18:23:19 INFO - using testdate: 1500254599 18:23:19 INFO - actual date: 1500254599 18:23:19 INFO - starting webserver on 'localhost:49189' 18:23:19 INFO - SUITE-START | Running 2 tests 18:23:19 INFO - TEST-START | dromaeo_css 18:23:19 INFO - Initialising browser for dromaeo_css test... 18:23:19 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49189/getInfo.html -profile /tmp/tmp7F4cHk/profile 18:23:19 INFO - TEST-INFO | started process 3349 (/builds/slave/test/build/application/firefox/firefox http://localhost:49189/getInfo.html) 18:23:32 INFO - TEST-INFO | 3349: exit 0 18:23:32 INFO - Browser initialized. 18:23:32 INFO - Running cycle 1/1 for dromaeo_css test... 18:23:32 INFO - TEST-INFO | started process 3558 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7F4cHk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:23:43 INFO - PID 3558 | RSS: Main: 164040704 18:23:43 INFO - PID 3558 | 18:26:41 INFO - PID 3558 | Cycle 1(1): loaded http://localhost:49189/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49189/tests/dromaeo/cssquery-ext.html) 18:26:42 INFO - PID 3558 | RSS: Main: 169545728 18:26:42 INFO - PID 3558 | 18:29:44 INFO - PID 3558 | Cycle 1(1): loaded http://localhost:49189/tests/dromaeo/cssquery-ext.html (next: http://localhost:49189/tests/dromaeo/cssquery-jquery.html) 18:29:44 INFO - PID 3558 | RSS: Main: 167587840 18:29:44 INFO - PID 3558 | 18:32:46 INFO - PID 3558 | Cycle 1(1): loaded http://localhost:49189/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49189/tests/dromaeo/cssquery-mootools.html) 18:32:46 INFO - PID 3558 | RSS: Main: 168472576 18:32:46 INFO - PID 3558 | 18:35:48 INFO - PID 3558 | Cycle 1(1): loaded http://localhost:49189/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49189/tests/dromaeo/cssquery-prototype.html) 18:35:49 INFO - PID 3558 | RSS: Main: 169353216 18:35:49 INFO - PID 3558 | 18:38:51 INFO - PID 3558 | Cycle 1(1): loaded http://localhost:49189/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49189/tests/dromaeo/cssquery-yui.html) 18:38:52 INFO - PID 3558 | RSS: Main: 168218624 18:38:52 INFO - PID 3558 | 18:41:53 INFO - PID 3558 | Cycle 1(1): loaded http://localhost:49189/tests/dromaeo/cssquery-yui.html (next: http://localhost:49189/tests/dromaeo/cssquery-dojo.html) 18:41:54 INFO - PID 3558 | RSS: Main: 171921408 18:41:54 INFO - PID 3558 | 18:41:54 INFO - PID 3558 | __start_tp_report 18:41:54 INFO - PID 3558 | _x_x_mozilla_page_load 18:41:54 INFO - PID 3558 | _x_x_mozilla_page_load_details 18:41:54 INFO - PID 3558 | |i|pagename|runs| 18:41:54 INFO - PID 3558 | |0;dojo.html;3329.650386709397;3396.7282617390606;3408.420568503355;3419.2135808763983;3420.538227339307;6252.499800015999;6350.8729825403525;6353.30113687494;6378.872422551546;6435.485161187105;6164.291106522751;6270.31026587075;6274.874502509947;6302.212223472066;6326.65203413812;5525.033119204143;5529.695866727328;5547.889042219153;5548.58385621078;5569.916451253234;5577.135543990688;5598.412166722476;5602.635828671123;5611.887762244753;5615.438456154372;5588.608797384185;5594.776208951637;5599.440055994409;5606.383297837235;5609.298837645294;5566.749496272678;5576.553875689935;5583.05088135018;5588.692621905793;5590;5494;5496.065668836308;5543.362513310981;5548.58385621076;5637.069883469223;6244.906326405107;6250.281217659982;6272.435480806704;6277.937220627781;6305.558610897239;6752.291009443987;6761.898571521431;6836.1113055302985;6836.897446538306;6839.008343790122;6773.559718618323;6869.107016087876;6880.311968803129;6880.827979300508;6884.621345825977;5484.84818188177;6267.341929097424;6296.748130074791;6333.1450254216015;6346.143270658448;6918.7232510699005;6925;6928.030075789394;6997.265287144878;6998.685059172248;6606.174228221473;6650.334966503311;6689.933100668931;6707.295733947963;6708.597484150967;6697.933020669731;6699.43054840344;6732.057511948331;6758.493113016543;6761.29006454325;6629.303923088103;6642.302558231413;6663.866722665616;6695.163104611924;6713.865722685519;5792.82621521355;6821.249662537216;6839.760608378683;6907.171139463196;6908.067410899464;5112.309838171874;5135.717535535507;5171.844844654666;5173.249878767558;5176.870578235499;4489.416375871116;4501.077279157765;4532.886677833015;4591.03588246472;4592.8622141335145;6419.293877673452;6425.871482570323;6434.260060093149;6434.613923164625;6438.581492202992;6787.796366108926;6792.660366981631;6832.965835170892;6853.760118395832;6856.9314306856295;6328.651924144124;6331.461825744863;6366.808995730135;6385.648789316632;6421.229452465641;5084.896259828869;5739.253896993364;5771.94228057714;5799.3040835100055;5839.065749480063;7067.92932070683;7074.292570742988;7089.82275443118;7091.716331346688;7095.929040709527;6806.2513123557355;6823.761168359084;6827.6244806536115;6827.726890924308;6860.0738900247825;6993.545419547713;7176.856462870609;7226.638668066471;7234.529755565872;7252.673629686571;6720.294369091175;6769.086173366532;6773.559718618274;6846.486513511407;6864.6910889011115;6783.491238157059;6834.487413444111;6867.278935711878;6880.58716477013;6999.30006999296;6522.119513865568;6525.119108920235;6579.4736421085245;6665.0668906352175;6714.3957043866285;6538.640374779433;6563.343665633399;6575.769848055295;6657.2677005528385;6666.300038496082;6584.242812076673;6590.143281373391;6614.074029635951;6617.404433600806;6639.468842492685;6513.413792758674;6585.6377899216;6617.205935287796;6642.634655094016;6723.025161351675;6477.77327793525;6495.902561461677;6497.18785151856;6497.837554061186;6500.804975850732 18:41:54 INFO - PID 3558 | |1;ext.html;867.6853390590762;913.5843191347933;943.3490891285013;963.0177219236375;966.7244835221964;42473.150536989284;43712.3443148353;43943.56056439435;43954.34068488967;44135.11729765407;40553.18893622126;40698.79650601743;40815.38776918341;40869.38695919564;40973.180536389256;6700.061991321207;6745.5615385000065;6755.4933379996455;6769.390754832064;6793.218779840332;6652.733890644368;6710.094137291477;6762.120924279837;6762.797116086525;6768.221654509745;6732.865342693143;6744;6766.391024807766;6768.424683901874;6782.660866956657;6694.531382803206;6716.294789047153;6717.059611654372;6735.12443382362;6758.05387245786;6237.532185086091;6238.500919926395;6276.497880169622;6277.560570760049;6303.212098487689;35164.82417587896;35228.823855880815;35475;36005;36402.817985910166;3296.175956010997;3349.7822641528223;3350.7654464187517;3454.756287736413;3521.4893840393256;31664;31747;31830.363392732248;31845.840770795996;31901.361972760416;4807.110684523374;4829.951700482985;4835.540623640749;4874.4150701916;4917.606591472673;196386;199703;202108;204381;207393;51247.48752512502;51546;52269.73865130726;52535;52619.210711839354;54688.17967730487;55008;55147;55162.17256741152;55245.17132243019;15858.920705396398;15950.840491594936;15954.68090638204;15991.68016639684;15998.200089995453;12880.484780608858;12992.480300787862;13072.607821765363;13097.41061652228;13183.077184596987;15200.847991520164;15286;15318.161820581601;15463.922680386751;15509.612259693598;6018.759249629966;6089.391060893875;6139.815805525842;6155.169052177988;6196.783112591128;908.5638893331238;916.408916249029;936.8313703533294;937.4047479850208;944.4758159221698;21598.460038498848;21644.783552164277;22032;22455;22609.321720348416;1010.2877471382658;1039.5218199628105;1041.671873359889;1047.9214058945608;1056.334509259162;11396.373199474108;11432.485538150802;11539.48072336747;11712.88287117135;11844.76310473803;275429;276382;277910;278010;279361;56882;57088.71455642695;57113.14330285049;57197.14204286939;57242.42757572454;1459.1318165691396;1514.9772753408708;1528.2893454543691;1533.7162624914426;1549.984500154984;21865;21935.232266870582;22150.22474213395;22160.113595456;30834;54564;54567;54898;55195;55238.72380638071;227577;228262;228801;229003;229506;34916.30167396689;35110.473342899364;35276;36547.81726091459;37745.81127094347;41777;45604.543954561355;46388;46542.30186547272;46834.29748553706;32719.509207362385;32731;33691.663083368854;33977.49033764446;34113.658863411045;32783.672163279014;33535.32929341351;33692;33698.66301337053;33887.491687625195;37664.43503347507;39473;39554;39782;40017;32369.51445728269;32780.344393111525;32967.17582060468;33432.498512521845;33610.83194584109;25027.87486062558;25628.74371256338;25637.3590660235;25807.741922580535;25989.350266243495 18:41:54 INFO - PID 3558 | |2;jquery.html;560.6636018388965;567.5573053018647;574.6408494690818;575.1717526761462;588.4674369695425;23148.88425557872;23688.40778980522;23698;23852.403689907784;23900;23350.766492335115;24477.51044979104;27173.592396114072;27462;27475.587866181973;6196.287426945892;6284.434400903918;6286.434220920116;6319.273283572402;6370.840728981766;6136.447719705226;6168.414000669929;6198.752049917998;6211.751529938797;6231.971724665447;6154.476869466101;6184.25788905332;6187.907181392283;6515.902261466082;6808.2510923798345;6772.424343930772;6783.253842077367;6836.248012718597;6837.965810170967;6843.3498817612;6659.4006539411685;6722.83192920176;6726.596404215763;6730.562513436611;6742.460603151785;32122.196945076328;33842.6615733842;34369.31261374783;34805;34922.650773492445;2151.257816053475;2174.173813950689;2198.274569392097;2201.8458707890454;2207.4812419081504;20975;21608;21625;21836.563268734695;21837.67243491349;2479.2438306316567;2503.3866702657965;2582.9741702582924;2596.5715656916586;2598.233521111277;151083;156272;156632;157184;157716;20410.591788164154;23909.641355379685;23925.282241532783;23965.400864978514;24369.39076523101;47854.5214547857;48043.75978120087;48352;48375.274370884465;48518;11858.229215100991;11933.761324773455;12147.149699520949;12234.510619575296;12375.381230938418;9922.156616687518;9969.152622027208;9992.40045597252;10045.497725113715;10093.848592271122;11530.538778448768;12065.577704780291;12464.501419943283;12523.311217883103;12668.1765685232;3970.583088775637;4211.873643790691;4375.496817865923;4440.511543730187;4557.270836666117;1040.8646875906084;1046.6755305855208;1049.6063976008998;1062.3997441445558;1071.8606581144402;21410;25425.364365891;26853.194404167905;26906;27037;1070.9250352475408;1133.7449073958458;1135.1089394825024;1145.4673576786668;1147.7647082348265;11950.521979120911;12134.453949572291;12171.208871423329;12207.633770986884;12321.507139714491;597231;601253;650804;668446;670198;138695;139630;140967.29516352352;141101;141802.2909885444;1308.8756568126014;1345.0584590786293;1350.2371160294401;1352.932353382327;1368.3295185359102;19346.129424175666;19507.317243896396;19633.70549441731;19671.213151474352;19868.304609338604;64436;65593.67203163954;65630;67901.66049169723;68392;82795;83684;84476;85111;85511.57244213739;14965.251737413304;15415.306311215798;15480.84519154794;15514.224288785741;15703.21483925822;24798.87600562058;25047.373815654024;25125;25216;25236.74763252344;20417;20564.69152962677;20612.278570249968;20712.792872071688;20850.895745521175;17222.483325500256;17575.121243937498;18124.18441170124;18134.5466363342;18648.44054678362;19870.70193947063;20644.690329644767;20889.582208356052;21019.89490052601;21146.894265528575;15282.847171528143;15297.235138243266;15298.31157597933;15375.231238438257;15446;19320.130594123017;20344;20346.389608311776;20483.38549843507;20782 18:41:54 INFO - PID 3558 | |3;mootools.html;1421.296458253165;1456.3737592835078;1459.0807791091618;1479.3269062576521;1495.6186172526004;14811.851881481196;15398.461053863117;15471.767923481157;15476.613084672888;15496.225188740575;14923.403063877431;15344.61638459039;16132.516024519284;16140.83859161408;16164.676706465892;2618.17527478844;2685.194441667492;2687.7446642568925;2691.2329985954007;2695.2588038289528;2707.2690373599116;2708.146933715883;2734.7265273472594;2735.083746944776;2735.8358498490056;2641.6433781439546;2674.398260391416;2709.187243826854;2715.7691596214345;2718.823276487022;2645.4180080382284;2748.093129267337;2750.518659234626;2752.146834481317;2758.3931535062256;2675.5050315691465;2721.482918245527;2723.8774255158564;2723.986380068087;2725.168823508829;15217.61955951099;15229.771553426708;15306.846931530654;15378.692426151527;15388.384464621402;7994.560299183542;8214.794630134294;8278.420510564263;8281.420300578962;8300.875486867702;5181.378234611833;5482.643628164196;5615.354234263071;5631.380548139701;5640.576956728261;1817.2004318099994;1896.5732710140246;1908.5896532245633;1938.6122775444826;1944.2611807513053;26131.477370452485;26459.206223813126;26485.470290594272;26512;26523.204303870916;6934.653267336613;6979.30206979298;7018.368346848706;7150.606716630636;7223.422126229889;18260.452186434428;18265.086745662662;18276.177572009292;18306.542336441697;18308.908455457637;7952;8108.148644392372;8115.3507719382305;8122.9187708123345;8128.552929588929;7207.171175314801;7444.330010299099;7618.6190690465255;7632.80917977055;7725.381969442431;5419.132938729785;5499.50504454601;5508.20131080999;5554.1113421853115;5570.5265052470995;1534.8465153484785;1536.6081649179612;1552.2161308539116;1554.8056492938383;1585.4530187085436;847.1359213602092;853.2491407561306;878.6309749905055;886.259972922604;889.5552223888056;7221.133463984355;8166.632501537445;8225.300849427866;8290.917090829134;8313.958430207811;721.4444877444339;740.8703476891523;750.2909750286036;762.9771106866802;772.4669977715358;4619.815207391666;4641.86074417768;4795.20879054959;4841.733704646278;4980.501949805063;21385.89307053455;21401.14395424197;21550.461238469165;21628.89185554062;21889.67165492519;21198.47003824917;21664.35006949794;21878.45303867416;21915.232966846084;21962.670559941613;11921;11954.402279885971;12184.20802647825;12201.69495762613;12262.202956807958;7414.369778568882;7887.329576986021;7976.680932762624;7977;7998.3201427879285;18243.179056942736;18319.633607328044;18437.631247375244;18533.907330463262;18974.620507590047;19061.332853350064;19661.50846228856;19816.40550783479;19929.501762456053;20112.195512179347;11600.767984640428;11673.12451566153;11795.52817887275;12884.226946383247;12943.676408089874;13827.585172444842;14027;14042.719145616826;14378.424863005363;14473.131612103307;10764.677059688222;11211.103111750877;11304.830427543759;11583.594574189863;11816.291022538675;11013.614523491638;11236.719082023015;11490.48292826808;11606.94196529012;11644.825327619923;9693.563789629343;9811.460369679737;9834.311598188058;9835.311528192959;9841;7390.556566606021;7397.926020739724;7399.452558390519;7445.9627701861145;7456.17982021988;7111.466640001917;7183.640817958976;7200.89198662031;7218.314260145278;7228.277172282729 18:41:54 INFO - PID 3558 | |4;prototype.html;500.3370534042395;502.78631581577844;530.7638101045037;533.7331334332833;533.7384681506063;5922.318933322669;6089.878202435954;6102.2067131272925;6142.938570614292;6185.257769067707;5895.380984996578;5963.493103086243;6000.579959402844;6016.669083200422;6079.148919151322;4676.054085925862;4710.576048155665;4733.195356789337;4774.546418090275;4791.209450440673;4737.644676649248;4742.4309082910095;4745.810167593292;4761.690490118149;4777.713337199779;4589.151007063687;4637.791299391519;4698.862017781918;4704.141863963732;4745.477997420281;4712.858614241578;4725.456572494173;4738.630684657671;4743.952560474386;4756.453007904101;4307.892302692458;4330.02574420756;4333.2200203963275;4333.349997500369;4334.804933777988;5767.221425107598;5851.736671849777;5902.6458412495385;5919.852003699899;5931.199288096095;5606.495415412633;5627.127795191752;5643.294588176478;5653.886922261532;5692.832969241295;6440.645764482952;6442.130312407859;6477.967610161919;6491.67541622917;6494.480441564663;4852.320675105488;4893.217085266376;4919.237518184651;4922.310876477296;4924.679895806797;6454.806355809286;6674.132362792854;6700.664966751643;6764.425023872976;6859.897101543431;4878.731669758126;4958.190260554871;4964.429090654621;4972.254161875675;5001.44984051754;5004.924926126111;5017.3226614406585;5040.31955685985;5054.620903432184;5074.517920797518;5570.303712035996;5595.38450770415;5614.326280846324;5637.774489020475;5654.321481422256;6354.841128971812;6378.840528986812;6403.48772098231;6448.9045258275355;6469.126667899867;1753.3863147898135;1766.7349897515219;1774.9822501774818;1785.1877395785145;1789.5257756694486;1182.3497076607832;1247.920548725346;1249.262934868432;1254.2787896959285;1267;6093.69531523422;6124.724387402578;6133.26400831903;6176.7838125665385;6210.72051757672;6522.238995471196;6571.98134289186;6644.867102658017;6649.700763465656;6652.900206496906;6114.174586430864;6125.326214116443;6125.816225513242;6165.537584681167;6209.316975132731;3488.89533314001;3513.314903593826;3528.4178110611974;3540.7698499597445;3569.5002699622073;6776.899975514842;6869.793906182823;6930.792076237721;6957.408620267334;6987.301269872972;6611.173603299588;6711.6241170559915;6775.695093720795;6778.491613129035;6820.31796820324;6428.935710642833;6783.186017677812;6797.830054248683;6799.422049125781;6903.929890866923;6484;6546.541742078009;6552.6068435894;6617.966910165611;6642.103316052271;6424.164858568543;6447.742090316335;6494.181629143658;6525.7063432146615;6560.704768285534;6791.72833086671;6842.692078856364;6888.41448476885;6916;6950.8609827804175;4992.675476094042;5014.373203349582;5019.498050195098;5020.824271150492;5034.546890779848;6377.649229292248;6575.514977062165;6621.536492445675;6663.7001334941015;6671.866562668816;6285.49716022708;6411.6153030818305;6424.807255782334;6462.2228887056435;6535.477161827138;6299.937000630119;6319.24169099711;6348.77779277723;6380.298167201511;6447.742690175371;6269;6338.714757836001;6390.329015453311;6421.678916054085;6432.2602900667025;6337.841553961188;6349.460295874902;6358.777442789666;6371.82121307541;6390.93609063922;6266.780662676784;6283.246010478589;6318.4313411793155;6337.397947195009;6366.29970703213 18:41:54 INFO - PID 3558 | |5;yui.html;922.2069020642242;943.8065196634691;948.8839887051874;951.6764300137952;980.14727187347;18103.456896293115;18717.906410467964;18743.718844217346;18878.622427551458;19038.238470461165;20167;21071.47321316968;21777.128914843426;21844.235451759192;22141.114355425765;1034.3328553083256;1065.206421216195;1068.8770791359016;1070.053003092265;1072.3244356055675;803.6704950970103;1075.9031687148156;1078.1698092468794;1079.2499213046935;1079.4926384599264;910.1217325281102;922.3128769067055;1087.7063192937942;1125.7016890524017;1141.703157179131;1129.2603344809163;1132.8697199822043;1138.1918837625296;1138.595798491532;1139.0204424195144;827.38359921858;831.1314676163363;833.9791505212357;834.3450391442749;836.0552575589545;19525.609487810165;20458.693119603216;20565.897170514203;20643.793562064486;20716.79283207164;20016.799832001783;20032.69950950737;20549.075291611913;20665.483362915897;20777.480562985897;15989.360425582963;16220.675586488322;16417.67164656712;16456.917715411466;16760.078195699232;3544.5923718772156;3582.7492075554724;3602.873899413508;3606.6393360663724;3607.603163651996;126638;130940;134334.3283283587;134739;137525;15368.308426120851;16877.915610422115;17230.655386892442;17450.738238926173;17701.291948321923;24358.756412436003;24507.26478205621;24569.262922112364;25758.871205643853;26594.86702566475;9014.86477702835;9106.907171139506;9161.954190229006;9194.218491428304;9202.81594368109;4086.5096188457574;4160.50073991123;4175.812088456027;4199.0342221288665;4259.424977628042;5238.7904483820985;5421.810236641778;5444.074507333684;5451.64564303319;5463.180522921514;962.7400601837464;982.9557669904872;996.446971930571;1001.9098281154731;1095.5234473004268;706.1808302369329;717.6519388096768;725.7858931615186;728.6793810723265;741.7255615422221;12587.685307867194;13001;13021.153625014344;13236.272005039622;13479.445492379995;693.2616763147232;709.5848928376957;713.9464540159508;733.1165945036219;737.4432303610739;5828.912566311508;6109.450149486567;6117.327094019654;6216.564840461125;6223.564350495421;123304.38347808203;124629;127405.36297318454;129403.35298323637;132941;107130.46434767933;111470.44264778786;111474;113115;114745.42627286976;93157.53421232986;93824;94250;96069.51965240129;101880;13444.798328024892;13479.86520134786;13564;13568.525101621395;13696.726065478435;42832.357514636686;44798.552014479435;45443.31835022543;45966.310505341775;46503.53496464992;79601.2039879617;83229.16770832214;87070;87683.56158219169;88127;20871.791282087594;20929.1628334871;20980;21176.470588235417;21416.250431234832;23089.422764431023;23570.410739731644;23707.762922371247;24148;24364;20366.59266814626;21113.472163196042;21279.255226067013;21365.67951480698;21540.784592154505;21330.573388531833;21835.344939651837;21895.12419503202;21949.341519754435;22326.88836555807;20096;20112.899435502728;20334.186632535126;20391.082401292275;20649.69025464589;12998.415071321624;13739.656508587364;13924.443022278996;14070.366833492722;14870.182139982406;15179.154718862985;15258;15357.462488812838;15390.538283851502;15420.383184672488 18:41:54 INFO - PID 3558 | __end_tp_report 18:41:54 INFO - PID 3558 | __start_cc_report 18:41:54 INFO - PID 3558 | _x_x_mozilla_cycle_collect,310 18:41:54 INFO - PID 3558 | __end_cc_report 18:41:54 INFO - PID 3558 | __startTimestamp1500255714116__endTimestamp 18:41:54 INFO - PID 3558 | 18:41:54 INFO - PID 3558 | ------- Summary: start ------- 18:41:54 INFO - PID 3558 | Number of tests: 6 18:41:54 INFO - PID 3558 | 18:41:54 INFO - PID 3558 | [#0] dojo.html Cycles:165 Average:6251.12 Median:6522.12 stddev:796.68 (12.2%) stddev-sans-first:765.45 18:41:54 INFO - PID 3558 | Values: 3329.7 3396.7 3408.4 3419.2 3420.5 6252.5 6350.9 6353.3 6378.9 6435.5 6164.3 6270.3 6274.9 6302.2 6326.7 5525.0 5529.7 5547.9 5548.6 5569.9 5577.1 5598.4 5602.6 5611.9 5615.4 5588.6 5594.8 5599.4 5606.4 5609.3 5566.7 5576.6 5583.1 5588.7 5590.0 5494.0 5496.1 5543.4 5548.6 5637.1 6244.9 6250.3 6272.4 6277.9 6305.6 6752.3 6761.9 6836.1 6836.9 6839.0 6773.6 6869.1 6880.3 6880.8 6884.6 5484.8 6267.3 6296.7 6333.1 6346.1 6918.7 6925.0 6928.0 6997.3 6998.7 6606.2 6650.3 6689.9 6707.3 6708.6 6697.9 6699.4 6732.1 6758.5 6761.3 6629.3 6642.3 6663.9 6695.2 6713.9 5792.8 6821.2 6839.8 6907.2 6908.1 5112.3 5135.7 5171.8 5173.2 5176.9 4489.4 4501.1 4532.9 4591.0 4592.9 6419.3 6425.9 6434.3 6434.6 6438.6 6787.8 6792.7 6833.0 6853.8 6856.9 6328.7 6331.5 6366.8 6385.6 6421.2 5084.9 5739.3 5771.9 5799.3 5839.1 7067.9 7074.3 7089.8 7091.7 7095.9 6806.3 6823.8 6827.6 6827.7 6860.1 6993.5 7176.9 7226.6 7234.5 7252.7 6720.3 6769.1 6773.6 6846.5 6864.7 6783.5 6834.5 6867.3 6880.6 6999.3 6522.1 6525.1 6579.5 6665.1 6714.4 6538.6 6563.3 6575.8 6657.3 6666.3 6584.2 6590.1 6614.1 6617.4 6639.5 6513.4 6585.6 6617.2 6642.6 6723.0 6477.8 6495.9 6497.2 6497.8 6500.8 18:41:54 INFO - PID 3558 | 18:41:54 INFO - PID 3558 | [#1] ext.html Cycles:180 Average:41085.72 Median:35740.00 stddev:62131.41 (173.8%) stddev-sans-first:62232.32 18:41:54 INFO - PID 3558 | Values: 867.7 913.6 943.3 963.0 966.7 42473.2 43712.3 43943.6 43954.3 44135.1 40553.2 40698.8 40815.4 40869.4 40973.2 6700.1 6745.6 6755.5 6769.4 6793.2 6652.7 6710.1 6762.1 6762.8 6768.2 6732.9 6744.0 6766.4 6768.4 6782.7 6694.5 6716.3 6717.1 6735.1 6758.1 6237.5 6238.5 6276.5 6277.6 6303.2 35164.8 35228.8 35475.0 36005.0 36402.8 3296.2 3349.8 3350.8 3454.8 3521.5 31664.0 31747.0 31830.4 31845.8 31901.4 4807.1 4830.0 4835.5 4874.4 4917.6 196386.0 199703.0 202108.0 204381.0 207393.0 51247.5 51546.0 52269.7 52535.0 52619.2 54688.2 55008.0 55147.0 55162.2 55245.2 15858.9 15950.8 15954.7 15991.7 15998.2 12880.5 12992.5 13072.6 13097.4 13183.1 15200.8 15286.0 15318.2 15463.9 15509.6 6018.8 6089.4 6139.8 6155.2 6196.8 908.6 916.4 936.8 937.4 944.5 21598.5 21644.8 22032.0 22455.0 22609.3 1010.3 1039.5 1041.7 1047.9 1056.3 11396.4 11432.5 11539.5 11712.9 11844.8 275429.0 276382.0 277910.0 278010.0 279361.0 56882.0 57088.7 57113.1 57197.1 57242.4 1459.1 1515.0 1528.3 1533.7 1550.0 21865.0 21935.2 22150.2 22160.1 30834.0 54564.0 54567.0 54898.0 55195.0 55238.7 227577.0 228262.0 228801.0 229003.0 229506.0 34916.3 35110.5 35276.0 36547.8 37745.8 41777.0 45604.5 46388.0 46542.3 46834.3 32719.5 32731.0 33691.7 33977.5 34113.7 32783.7 33535.3 33692.0 33698.7 33887.5 37664.4 39473.0 39554.0 39782.0 40017.0 32369.5 32780.3 32967.2 33432.5 33610.8 25027.9 25628.7 25637.4 25807.7 25989.4 18:41:54 INFO - PID 3558 | 18:41:54 INFO - PID 3558 | [#2] jquery.html Cycles:180 Average:42607.09 Median:23519.59 stddev:106750.02 (453.9%) stddev-sans-first:107002.80 18:41:54 INFO - PID 3558 | Values: 560.7 567.6 574.6 575.2 588.5 23148.9 23688.4 23698.0 23852.4 23900.0 23350.8 24477.5 27173.6 27462.0 27475.6 6196.3 6284.4 6286.4 6319.3 6370.8 6136.4 6168.4 6198.8 6211.8 6232.0 6154.5 6184.3 6187.9 6515.9 6808.3 6772.4 6783.3 6836.2 6838.0 6843.3 6659.4 6722.8 6726.6 6730.6 6742.5 32122.2 33842.7 34369.3 34805.0 34922.7 2151.3 2174.2 2198.3 2201.8 2207.5 20975.0 21608.0 21625.0 21836.6 21837.7 2479.2 2503.4 2583.0 2596.6 2598.2 151083.0 156272.0 156632.0 157184.0 157716.0 20410.6 23909.6 23925.3 23965.4 24369.4 47854.5 48043.8 48352.0 48375.3 48518.0 11858.2 11933.8 12147.1 12234.5 12375.4 9922.2 9969.2 9992.4 10045.5 10093.8 11530.5 12065.6 12464.5 12523.3 12668.2 3970.6 4211.9 4375.5 4440.5 4557.3 1040.9 1046.7 1049.6 1062.4 1071.9 21410.0 25425.4 26853.2 26906.0 27037.0 1070.9 1133.7 1135.1 1145.5 1147.8 11950.5 12134.5 12171.2 12207.6 12321.5 597231.0 601253.0 650804.0 668446.0 670198.0 138695.0 139630.0 140967.3 141101.0 141802.3 1308.9 1345.1 1350.2 1352.9 1368.3 19346.1 19507.3 19633.7 19671.2 19868.3 64436.0 65593.7 65630.0 67901.7 68392.0 82795.0 83684.0 84476.0 85111.0 85511.6 14965.3 15415.3 15480.8 15514.2 15703.2 24798.9 25047.4 25125.0 25216.0 25236.7 20417.0 20564.7 20612.3 20712.8 20850.9 17222.5 17575.1 18124.2 18134.5 18648.4 19870.7 20644.7 20889.6 21019.9 21146.9 15282.8 15297.2 15298.3 15375.2 15446.0 19320.1 20344.0 20346.4 20483.4 20782.0 18:41:54 INFO - PID 3558 | 18:41:54 INFO - PID 3558 | [#3] mootools.html Cycles:180 Average:9467.33 Median:2686.47 stddev:6777.30 (252.3%) stddev-sans-first:6769.35 18:41:54 INFO - PID 3558 | Values: 1421.3 1456.4 1459.1 1479.3 1495.6 14811.9 15398.5 15471.8 15476.6 15496.2 14923.4 15344.6 16132.5 16140.8 16164.7 2618.2 2685.2 2687.7 2691.2 2695.3 2707.3 2708.1 2734.7 2735.1 2735.8 2641.6 2674.4 2709.2 2715.8 2718.8 2645.4 2748.1 2750.5 2752.1 2758.4 2675.5 2721.5 2723.9 2724.0 2725.2 15217.6 15229.8 15306.8 15378.7 15388.4 7994.6 8214.8 8278.4 8281.4 8300.9 5181.4 5482.6 5615.4 5631.4 5640.6 1817.2 1896.6 1908.6 1938.6 1944.3 26131.5 26459.2 26485.5 26512.0 26523.2 6934.7 6979.3 7018.4 7150.6 7223.4 18260.5 18265.1 18276.2 18306.5 18308.9 7952.0 8108.1 8115.4 8122.9 8128.6 7207.2 7444.3 7618.6 7632.8 7725.4 5419.1 5499.5 5508.2 5554.1 5570.5 1534.8 1536.6 1552.2 1554.8 1585.5 847.1 853.2 878.6 886.3 889.6 7221.1 8166.6 8225.3 8290.9 8314.0 721.4 740.9 750.3 763.0 772.5 4619.8 4641.9 4795.2 4841.7 4980.5 21385.9 21401.1 21550.5 21628.9 21889.7 21198.5 21664.4 21878.5 21915.2 21962.7 11921.0 11954.4 12184.2 12201.7 12262.2 7414.4 7887.3 7976.7 7977.0 7998.3 18243.2 18319.6 18437.6 18533.9 18974.6 19061.3 19661.5 19816.4 19929.5 20112.2 11600.8 11673.1 11795.5 12884.2 12943.7 13827.6 14027.0 14042.7 14378.4 14473.1 10764.7 11211.1 11304.8 11583.6 11816.3 11013.6 11236.7 11490.5 11606.9 11644.8 9693.6 9811.5 9834.3 9835.3 9841.0 7390.6 7397.9 7399.5 7446.0 7456.2 7111.5 7183.6 7200.9 7218.3 7228.3 18:41:54 INFO - PID 3558 | 18:41:54 INFO - PID 3558 | [#4] prototype.html Cycles:180 Average:5461.87 Median:6125.03 stddev:1547.10 (25.3%) stddev-sans-first:1505.95 18:41:54 INFO - PID 3558 | Values: 500.3 502.8 530.8 533.7 533.7 5922.3 6089.9 6102.2 6142.9 6185.3 5895.4 5963.5 6000.6 6016.7 6079.1 4676.1 4710.6 4733.2 4774.5 4791.2 4737.6 4742.4 4745.8 4761.7 4777.7 4589.2 4637.8 4698.9 4704.1 4745.5 4712.9 4725.5 4738.6 4744.0 4756.5 4307.9 4330.0 4333.2 4333.3 4334.8 5767.2 5851.7 5902.6 5919.9 5931.2 5606.5 5627.1 5643.3 5653.9 5692.8 6440.6 6442.1 6478.0 6491.7 6494.5 4852.3 4893.2 4919.2 4922.3 4924.7 6454.8 6674.1 6700.7 6764.4 6859.9 4878.7 4958.2 4964.4 4972.3 5001.4 5004.9 5017.3 5040.3 5054.6 5074.5 5570.3 5595.4 5614.3 5637.8 5654.3 6354.8 6378.8 6403.5 6448.9 6469.1 1753.4 1766.7 1775.0 1785.2 1789.5 1182.3 1247.9 1249.3 1254.3 1267.0 6093.7 6124.7 6133.3 6176.8 6210.7 6522.2 6572.0 6644.9 6649.7 6652.9 6114.2 6125.3 6125.8 6165.5 6209.3 3488.9 3513.3 3528.4 3540.8 3569.5 6776.9 6869.8 6930.8 6957.4 6987.3 6611.2 6711.6 6775.7 6778.5 6820.3 6428.9 6783.2 6797.8 6799.4 6903.9 6484.0 6546.5 6552.6 6618.0 6642.1 6424.2 6447.7 6494.2 6525.7 6560.7 6791.7 6842.7 6888.4 6916.0 6950.9 4992.7 5014.4 5019.5 5020.8 5034.5 6377.6 6575.5 6621.5 6663.7 6671.9 6285.5 6411.6 6424.8 6462.2 6535.5 6299.9 6319.2 6348.8 6380.3 6447.7 6269.0 6338.7 6390.3 6421.7 6432.3 6337.8 6349.5 6358.8 6371.8 6390.9 6266.8 6283.2 6318.4 6337.4 6366.3 18:41:54 INFO - PID 3558 | 18:41:54 INFO - PID 3558 | [#5] yui.html Cycles:180 Average:26064.04 Median:21092.47 stddev:36070.27 (171.0%) stddev-sans-first:36122.06 18:41:54 INFO - PID 3558 | Values: 922.2 943.8 948.9 951.7 980.1 18103.5 18717.9 18743.7 18878.6 19038.2 20167.0 21071.5 21777.1 21844.2 22141.1 1034.3 1065.2 1068.9 1070.1 1072.3 803.7 1075.9 1078.2 1079.2 1079.5 910.1 922.3 1087.7 1125.7 1141.7 1129.3 1132.9 1138.2 1138.6 1139.0 827.4 831.1 834.0 834.3 836.1 19525.6 20458.7 20565.9 20643.8 20716.8 20016.8 20032.7 20549.1 20665.5 20777.5 15989.4 16220.7 16417.7 16456.9 16760.1 3544.6 3582.7 3602.9 3606.6 3607.6 126638.0 130940.0 134334.3 134739.0 137525.0 15368.3 16877.9 17230.7 17450.7 17701.3 24358.8 24507.3 24569.3 25758.9 26594.9 9014.9 9106.9 9162.0 9194.2 9202.8 4086.5 4160.5 4175.8 4199.0 4259.4 5238.8 5421.8 5444.1 5451.6 5463.2 962.7 983.0 996.4 1001.9 1095.5 706.2 717.7 725.8 728.7 741.7 12587.7 13001.0 13021.2 13236.3 13479.4 693.3 709.6 713.9 733.1 737.4 5828.9 6109.5 6117.3 6216.6 6223.6 123304.4 124629.0 127405.4 129403.4 132941.0 107130.5 111470.4 111474.0 113115.0 114745.4 93157.5 93824.0 94250.0 96069.5 101880.0 13444.8 13479.9 13564.0 13568.5 13696.7 42832.4 44798.6 45443.3 45966.3 46503.5 79601.2 83229.2 87070.0 87683.6 88127.0 20871.8 20929.2 20980.0 21176.5 21416.3 23089.4 23570.4 23707.8 24148.0 24364.0 20366.6 21113.5 21279.3 21365.7 21540.8 21330.6 21835.3 21895.1 21949.3 22326.9 20096.0 20112.9 20334.2 20391.1 20649.7 12998.4 13739.7 13924.4 14070.4 14870.2 15179.2 15258.0 15357.5 15390.5 15420.4 18:41:54 INFO - PID 3558 | -------- Summary: end -------- 18:41:54 INFO - PID 3558 | 18:41:54 INFO - TEST-INFO | 3558: exit 0 18:41:54 INFO - TEST-OK | dromaeo_css | took 1114753ms 18:41:54 INFO - TEST-START | kraken 18:41:54 INFO - Initialising browser for kraken test... 18:41:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49189/getInfo.html -profile /tmp/tmpAa0C6z/profile 18:41:54 INFO - TEST-INFO | started process 3966 (/builds/slave/test/build/application/firefox/firefox http://localhost:49189/getInfo.html) 18:42:01 INFO - TEST-INFO | 3966: exit 0 18:42:01 INFO - Browser initialized. 18:42:01 INFO - Running cycle 1/1 for kraken test... 18:42:01 INFO - TEST-INFO | started process 4174 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAa0C6z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:42:12 INFO - PID 4174 | RSS: Main: 164106240 18:42:12 INFO - PID 4174 | 18:42:15 INFO - PID 4174 | Cycle 1(1): loaded http://localhost:49189/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49189/tests/kraken/driver.html?testName=audio-beat-detection) 18:42:15 INFO - PID 4174 | RSS: Main: 170737664 18:42:15 INFO - PID 4174 | 18:42:18 INFO - PID 4174 | Cycle 1(1): loaded http://localhost:49189/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49189/tests/kraken/driver.html?testName=audio-dft) 18:42:19 INFO - PID 4174 | RSS: Main: 171036672 18:42:19 INFO - PID 4174 | 18:42:21 INFO - PID 4174 | Cycle 1(1): loaded http://localhost:49189/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49189/tests/kraken/driver.html?testName=audio-fft) 18:42:22 INFO - PID 4174 | RSS: Main: 164581376 18:42:22 INFO - PID 4174 | 18:42:24 INFO - PID 4174 | Cycle 1(1): loaded http://localhost:49189/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49189/tests/kraken/driver.html?testName=audio-oscillator) 18:42:25 INFO - PID 4174 | RSS: Main: 165908480 18:42:25 INFO - PID 4174 | 18:42:27 INFO - PID 4174 | Cycle 1(1): loaded http://localhost:49189/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49189/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:42:27 INFO - PID 4174 | RSS: Main: 166506496 18:42:27 INFO - PID 4174 | 18:42:30 INFO - PID 4174 | Cycle 1(1): loaded http://localhost:49189/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49189/tests/kraken/driver.html?testName=imaging-darkroom) 18:42:31 INFO - PID 4174 | RSS: Main: 166371328 18:42:31 INFO - PID 4174 | 18:42:34 INFO - PID 4174 | Cycle 1(1): loaded http://localhost:49189/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49189/tests/kraken/driver.html?testName=imaging-desaturate) 18:42:35 INFO - PID 4174 | RSS: Main: 169263104 18:42:35 INFO - PID 4174 | 18:42:38 INFO - PID 4174 | Cycle 1(1): loaded http://localhost:49189/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49189/tests/kraken/driver.html?testName=json-parse-financial) 18:42:38 INFO - PID 4174 | RSS: Main: 169828352 18:42:38 INFO - PID 4174 | 18:42:40 INFO - PID 4174 | Cycle 1(1): loaded http://localhost:49189/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49189/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:42:40 INFO - PID 4174 | RSS: Main: 169037824 18:42:40 INFO - PID 4174 | 18:42:42 INFO - PID 4174 | Cycle 1(1): loaded http://localhost:49189/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49189/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:42:43 INFO - PID 4174 | RSS: Main: 169299968 18:42:43 INFO - PID 4174 | 18:42:45 INFO - PID 4174 | Cycle 1(1): loaded http://localhost:49189/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49189/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:42:45 INFO - PID 4174 | RSS: Main: 166170624 18:42:45 INFO - PID 4174 | 18:42:48 INFO - PID 4174 | Cycle 1(1): loaded http://localhost:49189/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49189/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:42:48 INFO - PID 4174 | RSS: Main: 166191104 18:42:48 INFO - PID 4174 | 18:42:51 INFO - PID 4174 | Cycle 1(1): loaded http://localhost:49189/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49189/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:42:52 INFO - PID 4174 | RSS: Main: 166477824 18:42:52 INFO - PID 4174 | 18:42:54 INFO - PID 4174 | Cycle 1(1): loaded http://localhost:49189/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49189/tests/kraken/driver.html?testName=ai-astar) 18:42:54 INFO - PID 4174 | RSS: Main: 168435712 18:42:54 INFO - PID 4174 | 18:42:54 INFO - PID 4174 | __start_tp_report 18:42:54 INFO - PID 4174 | _x_x_mozilla_page_load 18:42:54 INFO - PID 4174 | _x_x_mozilla_page_load_details 18:42:54 INFO - PID 4174 | |i|pagename|runs| 18:42:54 INFO - PID 4174 | |0;ai-astar;98;107;106;106;106;108;108;106;107;108 18:42:54 INFO - PID 4174 | |1;audio-beat-detection;156;114;142;168;118;143;143;115;163;115 18:42:54 INFO - PID 4174 | |2;audio-dft;162;161;164;159;163;170;161;164;159;169 18:42:54 INFO - PID 4174 | |3;audio-fft;73;74;78;74;73;73;73;145;73;73 18:42:54 INFO - PID 4174 | |4;audio-oscillator;80;80;124;81;80;80;135;80;80;133 18:42:54 INFO - PID 4174 | |5;imaging-gaussian-blur;114;114;115;113;113;113;113;114;113;114 18:42:54 INFO - PID 4174 | |6;imaging-darkroom;147;148;148;148;147;148;146;149;147;147 18:42:54 INFO - PID 4174 | |7;imaging-desaturate;94;94;94;94;94;94;94;94;94;94 18:42:54 INFO - PID 4174 | |8;json-parse-financial;51;47;47;47;51;47;47;47;56;46 18:42:54 INFO - PID 4174 | |9;json-stringify-tinderbox;55;56;55;55;55;56;55;56;56;55 18:42:54 INFO - PID 4174 | |10;stanford-crypto-aes;79;74;74;74;74;75;79;75;76;75 18:42:54 INFO - PID 4174 | |11;stanford-crypto-ccm;127;120;117;117;116;117;117;120;119;136 18:42:54 INFO - PID 4174 | |12;stanford-crypto-pbkdf2;180;178;194;169;174;174;186;172;168;168 18:42:54 INFO - PID 4174 | |13;stanford-crypto-sha256-iterative;62;62;59;61;63;63;61;60;61;63 18:42:54 INFO - PID 4174 | __end_tp_report 18:42:54 INFO - PID 4174 | __start_cc_report 18:42:54 INFO - PID 4174 | _x_x_mozilla_cycle_collect,842 18:42:54 INFO - PID 4174 | __end_cc_report 18:42:54 INFO - PID 4174 | __startTimestamp1500255774285__endTimestamp 18:42:54 INFO - PID 4174 | 18:42:54 INFO - PID 4174 | ------- Summary: start ------- 18:42:54 INFO - PID 4174 | Number of tests: 14 18:42:54 INFO - PID 4174 | 18:42:54 INFO - PID 4174 | [#0] ai-astar Cycles:10 Average:106.00 Median:107.50 stddev:2.94 (2.7%) stddev-sans-first:0.93 18:42:54 INFO - PID 4174 | Values: 98.0 107.0 106.0 106.0 106.0 108.0 108.0 106.0 107.0 108.0 18:42:54 INFO - PID 4174 | 18:42:54 INFO - PID 4174 | [#1] audio-beat-detection Cycles:10 Average:137.70 Median:143.00 stddev:20.94 (14.6%) stddev-sans-first:21.14 18:42:54 INFO - PID 4174 | Values: 156.0 114.0 142.0 168.0 118.0 143.0 143.0 115.0 163.0 115.0 18:42:54 INFO - PID 4174 | 18:42:54 INFO - PID 4174 | [#2] audio-dft Cycles:10 Average:163.20 Median:163.50 stddev:3.77 (2.3%) stddev-sans-first:3.97 18:42:54 INFO - PID 4174 | Values: 162.0 161.0 164.0 159.0 163.0 170.0 161.0 164.0 159.0 169.0 18:42:54 INFO - PID 4174 | 18:42:54 INFO - PID 4174 | [#3] audio-fft Cycles:10 Average:80.90 Median:73.00 stddev:22.58 (30.9%) stddev-sans-first:23.76 18:42:54 INFO - PID 4174 | Values: 73.0 74.0 78.0 74.0 73.0 73.0 73.0 145.0 73.0 73.0 18:42:54 INFO - PID 4174 | 18:42:54 INFO - PID 4174 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:80.00 stddev:24.56 (30.7%) stddev-sans-first:25.42 18:42:54 INFO - PID 4174 | Values: 80.0 80.0 124.0 81.0 80.0 80.0 135.0 80.0 80.0 133.0 18:42:54 INFO - PID 4174 | 18:42:54 INFO - PID 4174 | [#5] imaging-gaussian-blur Cycles:10 Average:113.60 Median:114.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 18:42:54 INFO - PID 4174 | Values: 114.0 114.0 115.0 113.0 113.0 113.0 113.0 114.0 113.0 114.0 18:42:54 INFO - PID 4174 | 18:42:54 INFO - PID 4174 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:0.85 (0.6%) stddev-sans-first:0.88 18:42:54 INFO - PID 4174 | Values: 147.0 148.0 148.0 148.0 147.0 148.0 146.0 149.0 147.0 147.0 18:42:54 INFO - PID 4174 | 18:42:54 INFO - PID 4174 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 18:42:54 INFO - PID 4174 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 18:42:54 INFO - PID 4174 | 18:42:54 INFO - PID 4174 | [#8] json-parse-financial Cycles:10 Average:48.60 Median:47.00 stddev:3.13 (6.7%) stddev-sans-first:3.20 18:42:54 INFO - PID 4174 | Values: 51.0 47.0 47.0 47.0 51.0 47.0 47.0 47.0 56.0 46.0 18:42:54 INFO - PID 4174 | 18:42:54 INFO - PID 4174 | [#9] json-stringify-tinderbox Cycles:10 Average:55.40 Median:55.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 18:42:54 INFO - PID 4174 | Values: 55.0 56.0 55.0 55.0 55.0 56.0 55.0 56.0 56.0 55.0 18:42:54 INFO - PID 4174 | 18:42:54 INFO - PID 4174 | [#10] stanford-crypto-aes Cycles:10 Average:75.50 Median:75.00 stddev:1.96 (2.6%) stddev-sans-first:1.62 18:42:54 INFO - PID 4174 | Values: 79.0 74.0 74.0 74.0 74.0 75.0 79.0 75.0 76.0 75.0 18:42:54 INFO - PID 4174 | 18:42:54 INFO - PID 4174 | [#11] stanford-crypto-ccm Cycles:10 Average:120.60 Median:119.50 stddev:6.28 (5.3%) stddev-sans-first:6.21 18:42:54 INFO - PID 4174 | Values: 127.0 120.0 117.0 117.0 116.0 117.0 117.0 120.0 119.0 136.0 18:42:54 INFO - PID 4174 | 18:42:54 INFO - PID 4174 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.30 Median:176.00 stddev:8.46 (4.8%) stddev-sans-first:8.87 18:42:54 INFO - PID 4174 | Values: 180.0 178.0 194.0 169.0 174.0 174.0 186.0 172.0 168.0 168.0 18:42:54 INFO - PID 4174 | 18:42:54 INFO - PID 4174 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:62.00 stddev:1.35 (2.2%) stddev-sans-first:1.42 18:42:54 INFO - PID 4174 | Values: 62.0 62.0 59.0 61.0 63.0 63.0 61.0 60.0 61.0 63.0 18:42:54 INFO - PID 4174 | -------- Summary: end -------- 18:42:54 INFO - PID 4174 | 18:42:54 INFO - PID 4174 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:42:55 INFO - TEST-INFO | 4174: exit 0 18:42:55 INFO - TEST-OK | kraken | took 60329ms 18:42:55 INFO - SUITE-END | took 1175s 18:42:55 INFO - Completed test suite (00:19:35) 18:42:55 INFO - javascript benchmark 18:42:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8856.793178642796, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3329.650386709397, 3396.7282617390606, 3408.420568503355, 3419.2135808763983, 3420.538227339307, 6252.499800015999, 6350.8729825403525, 6353.30113687494, 6378.872422551546, 6435.485161187105, 6164.291106522751, 6270.31026587075, 6274.874502509947, 6302.212223472066, 6326.65203413812, 5525.033119204143, 5529.695866727328, 5547.889042219153, 5548.58385621078, 5569.916451253234, 5577.135543990688, 5598.412166722476, 5602.635828671123, 5611.887762244753, 5615.438456154372, 5588.608797384185, 5594.776208951637, 5599.440055994409, 5606.383297837235, 5609.298837645294, 5566.749496272678, 5576.553875689935, 5583.05088135018, 5588.692621905793, 5590.0, 5494.0, 5496.065668836308, 5543.362513310981, 5548.58385621076, 5637.069883469223, 6244.906326405107, 6250.281217659982, 6272.435480806704, 6277.937220627781, 6305.558610897239, 6752.291009443987, 6761.898571521431, 6836.1113055302985, 6836.897446538306, 6839.008343790122, 6773.559718618323, 6869.107016087876, 6880.311968803129, 6880.827979300508, 6884.621345825977, 5484.84818188177, 6267.341929097424, 6296.748130074791, 6333.1450254216015, 6346.143270658448, 6918.7232510699005, 6925.0, 6928.030075789394, 6997.265287144878, 6998.685059172248, 6606.174228221473, 6650.334966503311, 6689.933100668931, 6707.295733947963, 6708.597484150967, 6697.933020669731, 6699.43054840344, 6732.057511948331, 6758.493113016543, 6761.29006454325, 6629.303923088103, 6642.302558231413, 6663.866722665616, 6695.163104611924, 6713.865722685519, 5792.82621521355, 6821.249662537216, 6839.760608378683, 6907.171139463196, 6908.067410899464, 5112.309838171874, 5135.717535535507, 5171.844844654666, 5173.249878767558, 5176.870578235499, 4489.416375871116, 4501.077279157765, 4532.886677833015, 4591.03588246472, 4592.8622141335145, 6419.293877673452, 6425.871482570323, 6434.260060093149, 6434.613923164625, 6438.581492202992, 6787.796366108926, 6792.660366981631, 6832.965835170892, 6853.760118395832, 6856.9314306856295, 6328.651924144124, 6331.461825744863, 6366.808995730135, 6385.648789316632, 6421.229452465641, 5084.896259828869, 5739.253896993364, 5771.94228057714, 5799.3040835100055, 5839.065749480063, 7067.92932070683, 7074.292570742988, 7089.82275443118, 7091.716331346688, 7095.929040709527, 6806.2513123557355, 6823.761168359084, 6827.6244806536115, 6827.726890924308, 6860.0738900247825, 6993.545419547713, 7176.856462870609, 7226.638668066471, 7234.529755565872, 7252.673629686571, 6720.294369091175, 6769.086173366532, 6773.559718618274, 6846.486513511407, 6864.6910889011115, 6783.491238157059, 6834.487413444111, 6867.278935711878, 6880.58716477013, 6999.30006999296, 6522.119513865568, 6525.119108920235, 6579.4736421085245, 6665.0668906352175, 6714.3957043866285, 6538.640374779433, 6563.343665633399, 6575.769848055295, 6657.2677005528385, 6666.300038496082, 6584.242812076673, 6590.143281373391, 6614.074029635951, 6617.404433600806, 6639.468842492685, 6513.413792758674, 6585.6377899216, 6617.205935287796, 6642.634655094016, 6723.025161351675, 6477.77327793525, 6495.902561461677, 6497.18785151856, 6497.837554061186, 6500.804975850732], "value": 6190.985986304335, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [867.6853390590762, 913.5843191347933, 943.3490891285013, 963.0177219236375, 966.7244835221964, 42473.150536989284, 43712.3443148353, 43943.56056439435, 43954.34068488967, 44135.11729765407, 40553.18893622126, 40698.79650601743, 40815.38776918341, 40869.38695919564, 40973.180536389256, 6700.061991321207, 6745.5615385000065, 6755.4933379996455, 6769.390754832064, 6793.218779840332, 6652.733890644368, 6710.094137291477, 6762.120924279837, 6762.797116086525, 6768.221654509745, 6732.865342693143, 6744.0, 6766.391024807766, 6768.424683901874, 6782.660866956657, 6694.531382803206, 6716.294789047153, 6717.059611654372, 6735.12443382362, 6758.05387245786, 6237.532185086091, 6238.500919926395, 6276.497880169622, 6277.560570760049, 6303.212098487689, 35164.82417587896, 35228.823855880815, 35475.0, 36005.0, 36402.817985910166, 3296.175956010997, 3349.7822641528223, 3350.7654464187517, 3454.756287736413, 3521.4893840393256, 31664.0, 31747.0, 31830.363392732248, 31845.840770795996, 31901.361972760416, 4807.110684523374, 4829.951700482985, 4835.540623640749, 4874.4150701916, 4917.606591472673, 196386.0, 199703.0, 202108.0, 204381.0, 207393.0, 51247.48752512502, 51546.0, 52269.73865130726, 52535.0, 52619.210711839354, 54688.17967730487, 55008.0, 55147.0, 55162.17256741152, 55245.17132243019, 15858.920705396398, 15950.840491594936, 15954.68090638204, 15991.68016639684, 15998.200089995453, 12880.484780608858, 12992.480300787862, 13072.607821765363, 13097.41061652228, 13183.077184596987, 15200.847991520164, 15286.0, 15318.161820581601, 15463.922680386751, 15509.612259693598, 6018.759249629966, 6089.391060893875, 6139.815805525842, 6155.169052177988, 6196.783112591128, 908.5638893331238, 916.408916249029, 936.8313703533294, 937.4047479850208, 944.4758159221698, 21598.460038498848, 21644.783552164277, 22032.0, 22455.0, 22609.321720348416, 1010.2877471382658, 1039.5218199628105, 1041.671873359889, 1047.9214058945608, 1056.334509259162, 11396.373199474108, 11432.485538150802, 11539.48072336747, 11712.88287117135, 11844.76310473803, 275429.0, 276382.0, 277910.0, 278010.0, 279361.0, 56882.0, 57088.71455642695, 57113.14330285049, 57197.14204286939, 57242.42757572454, 1459.1318165691396, 1514.9772753408708, 1528.2893454543691, 1533.7162624914426, 1549.984500154984, 21865.0, 21935.232266870582, 22150.22474213395, 22160.113595456, 30834.0, 54564.0, 54567.0, 54898.0, 55195.0, 55238.72380638071, 227577.0, 228262.0, 228801.0, 229003.0, 229506.0, 34916.30167396689, 35110.473342899364, 35276.0, 36547.81726091459, 37745.81127094347, 41777.0, 45604.543954561355, 46388.0, 46542.30186547272, 46834.29748553706, 32719.509207362385, 32731.0, 33691.663083368854, 33977.49033764446, 34113.658863411045, 32783.672163279014, 33535.32929341351, 33692.0, 33698.66301337053, 33887.491687625195, 37664.43503347507, 39473.0, 39554.0, 39782.0, 40017.0, 32369.51445728269, 32780.344393111525, 32967.17582060468, 33432.498512521845, 33610.83194584109, 25027.87486062558, 25628.74371256338, 25637.3590660235, 25807.741922580535, 25989.350266243495], "value": 17434.12621307648, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.6636018388965, 567.5573053018647, 574.6408494690818, 575.1717526761462, 588.4674369695425, 23148.88425557872, 23688.40778980522, 23698.0, 23852.403689907784, 23900.0, 23350.766492335115, 24477.51044979104, 27173.592396114072, 27462.0, 27475.587866181973, 6196.287426945892, 6284.434400903918, 6286.434220920116, 6319.273283572402, 6370.840728981766, 6136.447719705226, 6168.414000669929, 6198.752049917998, 6211.751529938797, 6231.971724665447, 6154.476869466101, 6184.25788905332, 6187.907181392283, 6515.902261466082, 6808.2510923798345, 6772.424343930772, 6783.253842077367, 6836.248012718597, 6837.965810170967, 6843.3498817612, 6659.4006539411685, 6722.83192920176, 6726.596404215763, 6730.562513436611, 6742.460603151785, 32122.196945076328, 33842.6615733842, 34369.31261374783, 34805.0, 34922.650773492445, 2151.257816053475, 2174.173813950689, 2198.274569392097, 2201.8458707890454, 2207.4812419081504, 20975.0, 21608.0, 21625.0, 21836.563268734695, 21837.67243491349, 2479.2438306316567, 2503.3866702657965, 2582.9741702582924, 2596.5715656916586, 2598.233521111277, 151083.0, 156272.0, 156632.0, 157184.0, 157716.0, 20410.591788164154, 23909.641355379685, 23925.282241532783, 23965.400864978514, 24369.39076523101, 47854.5214547857, 48043.75978120087, 48352.0, 48375.274370884465, 48518.0, 11858.229215100991, 11933.761324773455, 12147.149699520949, 12234.510619575296, 12375.381230938418, 9922.156616687518, 9969.152622027208, 9992.40045597252, 10045.497725113715, 10093.848592271122, 11530.538778448768, 12065.577704780291, 12464.501419943283, 12523.311217883103, 12668.1765685232, 3970.583088775637, 4211.873643790691, 4375.496817865923, 4440.511543730187, 4557.270836666117, 1040.8646875906084, 1046.6755305855208, 1049.6063976008998, 1062.3997441445558, 1071.8606581144402, 21410.0, 25425.364365891, 26853.194404167905, 26906.0, 27037.0, 1070.9250352475408, 1133.7449073958458, 1135.1089394825024, 1145.4673576786668, 1147.7647082348265, 11950.521979120911, 12134.453949572291, 12171.208871423329, 12207.633770986884, 12321.507139714491, 597231.0, 601253.0, 650804.0, 668446.0, 670198.0, 138695.0, 139630.0, 140967.29516352352, 141101.0, 141802.2909885444, 1308.8756568126014, 1345.0584590786293, 1350.2371160294401, 1352.932353382327, 1368.3295185359102, 19346.129424175666, 19507.317243896396, 19633.70549441731, 19671.213151474352, 19868.304609338604, 64436.0, 65593.67203163954, 65630.0, 67901.66049169723, 68392.0, 82795.0, 83684.0, 84476.0, 85111.0, 85511.57244213739, 14965.251737413304, 15415.306311215798, 15480.84519154794, 15514.224288785741, 15703.21483925822, 24798.87600562058, 25047.373815654024, 25125.0, 25216.0, 25236.74763252344, 20417.0, 20564.69152962677, 20612.278570249968, 20712.792872071688, 20850.895745521175, 17222.483325500256, 17575.121243937498, 18124.18441170124, 18134.5466363342, 18648.44054678362, 19870.70193947063, 20644.690329644767, 20889.582208356052, 21019.89490052601, 21146.894265528575, 15282.847171528143, 15297.235138243266, 15298.31157597933, 15375.231238438257, 15446.0, 19320.130594123017, 20344.0, 20346.389608311776, 20483.38549843507, 20782.0], "value": 13696.70792574281, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1421.296458253165, 1456.3737592835078, 1459.0807791091618, 1479.3269062576521, 1495.6186172526004, 14811.851881481196, 15398.461053863117, 15471.767923481157, 15476.613084672888, 15496.225188740575, 14923.403063877431, 15344.61638459039, 16132.516024519284, 16140.83859161408, 16164.676706465892, 2618.17527478844, 2685.194441667492, 2687.7446642568925, 2691.2329985954007, 2695.2588038289528, 2707.2690373599116, 2708.146933715883, 2734.7265273472594, 2735.083746944776, 2735.8358498490056, 2641.6433781439546, 2674.398260391416, 2709.187243826854, 2715.7691596214345, 2718.823276487022, 2645.4180080382284, 2748.093129267337, 2750.518659234626, 2752.146834481317, 2758.3931535062256, 2675.5050315691465, 2721.482918245527, 2723.8774255158564, 2723.986380068087, 2725.168823508829, 15217.61955951099, 15229.771553426708, 15306.846931530654, 15378.692426151527, 15388.384464621402, 7994.560299183542, 8214.794630134294, 8278.420510564263, 8281.420300578962, 8300.875486867702, 5181.378234611833, 5482.643628164196, 5615.354234263071, 5631.380548139701, 5640.576956728261, 1817.2004318099994, 1896.5732710140246, 1908.5896532245633, 1938.6122775444826, 1944.2611807513053, 26131.477370452485, 26459.206223813126, 26485.470290594272, 26512.0, 26523.204303870916, 6934.653267336613, 6979.30206979298, 7018.368346848706, 7150.606716630636, 7223.422126229889, 18260.452186434428, 18265.086745662662, 18276.177572009292, 18306.542336441697, 18308.908455457637, 7952.0, 8108.148644392372, 8115.3507719382305, 8122.9187708123345, 8128.552929588929, 7207.171175314801, 7444.330010299099, 7618.6190690465255, 7632.80917977055, 7725.381969442431, 5419.132938729785, 5499.50504454601, 5508.20131080999, 5554.1113421853115, 5570.5265052470995, 1534.8465153484785, 1536.6081649179612, 1552.2161308539116, 1554.8056492938383, 1585.4530187085436, 847.1359213602092, 853.2491407561306, 878.6309749905055, 886.259972922604, 889.5552223888056, 7221.133463984355, 8166.632501537445, 8225.300849427866, 8290.917090829134, 8313.958430207811, 721.4444877444339, 740.8703476891523, 750.2909750286036, 762.9771106866802, 772.4669977715358, 4619.815207391666, 4641.86074417768, 4795.20879054959, 4841.733704646278, 4980.501949805063, 21385.89307053455, 21401.14395424197, 21550.461238469165, 21628.89185554062, 21889.67165492519, 21198.47003824917, 21664.35006949794, 21878.45303867416, 21915.232966846084, 21962.670559941613, 11921.0, 11954.402279885971, 12184.20802647825, 12201.69495762613, 12262.202956807958, 7414.369778568882, 7887.329576986021, 7976.680932762624, 7977.0, 7998.3201427879285, 18243.179056942736, 18319.633607328044, 18437.631247375244, 18533.907330463262, 18974.620507590047, 19061.332853350064, 19661.50846228856, 19816.40550783479, 19929.501762456053, 20112.195512179347, 11600.767984640428, 11673.12451566153, 11795.52817887275, 12884.226946383247, 12943.676408089874, 13827.585172444842, 14027.0, 14042.719145616826, 14378.424863005363, 14473.131612103307, 10764.677059688222, 11211.103111750877, 11304.830427543759, 11583.594574189863, 11816.291022538675, 11013.614523491638, 11236.719082023015, 11490.48292826808, 11606.94196529012, 11644.825327619923, 9693.563789629343, 9811.460369679737, 9834.311598188058, 9835.311528192959, 9841.0, 7390.556566606021, 7397.926020739724, 7399.452558390519, 7445.9627701861145, 7456.17982021988, 7111.466640001917, 7183.640817958976, 7200.89198662031, 7218.314260145278, 7228.277172282729], "value": 6707.758941548828, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.3370534042395, 502.78631581577844, 530.7638101045037, 533.7331334332833, 533.7384681506063, 5922.318933322669, 6089.878202435954, 6102.2067131272925, 6142.938570614292, 6185.257769067707, 5895.380984996578, 5963.493103086243, 6000.579959402844, 6016.669083200422, 6079.148919151322, 4676.054085925862, 4710.576048155665, 4733.195356789337, 4774.546418090275, 4791.209450440673, 4737.644676649248, 4742.4309082910095, 4745.810167593292, 4761.690490118149, 4777.713337199779, 4589.151007063687, 4637.791299391519, 4698.862017781918, 4704.141863963732, 4745.477997420281, 4712.858614241578, 4725.456572494173, 4738.630684657671, 4743.952560474386, 4756.453007904101, 4307.892302692458, 4330.02574420756, 4333.2200203963275, 4333.349997500369, 4334.804933777988, 5767.221425107598, 5851.736671849777, 5902.6458412495385, 5919.852003699899, 5931.199288096095, 5606.495415412633, 5627.127795191752, 5643.294588176478, 5653.886922261532, 5692.832969241295, 6440.645764482952, 6442.130312407859, 6477.967610161919, 6491.67541622917, 6494.480441564663, 4852.320675105488, 4893.217085266376, 4919.237518184651, 4922.310876477296, 4924.679895806797, 6454.806355809286, 6674.132362792854, 6700.664966751643, 6764.425023872976, 6859.897101543431, 4878.731669758126, 4958.190260554871, 4964.429090654621, 4972.254161875675, 5001.44984051754, 5004.924926126111, 5017.3226614406585, 5040.31955685985, 5054.620903432184, 5074.517920797518, 5570.303712035996, 5595.38450770415, 5614.326280846324, 5637.774489020475, 5654.321481422256, 6354.841128971812, 6378.840528986812, 6403.48772098231, 6448.9045258275355, 6469.126667899867, 1753.3863147898135, 1766.7349897515219, 1774.9822501774818, 1785.1877395785145, 1789.5257756694486, 1182.3497076607832, 1247.920548725346, 1249.262934868432, 1254.2787896959285, 1267.0, 6093.69531523422, 6124.724387402578, 6133.26400831903, 6176.7838125665385, 6210.72051757672, 6522.238995471196, 6571.98134289186, 6644.867102658017, 6649.700763465656, 6652.900206496906, 6114.174586430864, 6125.326214116443, 6125.816225513242, 6165.537584681167, 6209.316975132731, 3488.89533314001, 3513.314903593826, 3528.4178110611974, 3540.7698499597445, 3569.5002699622073, 6776.899975514842, 6869.793906182823, 6930.792076237721, 6957.408620267334, 6987.301269872972, 6611.173603299588, 6711.6241170559915, 6775.695093720795, 6778.491613129035, 6820.31796820324, 6428.935710642833, 6783.186017677812, 6797.830054248683, 6799.422049125781, 6903.929890866923, 6484.0, 6546.541742078009, 6552.6068435894, 6617.966910165611, 6642.103316052271, 6424.164858568543, 6447.742090316335, 6494.181629143658, 6525.7063432146615, 6560.704768285534, 6791.72833086671, 6842.692078856364, 6888.41448476885, 6916.0, 6950.8609827804175, 4992.675476094042, 5014.373203349582, 5019.498050195098, 5020.824271150492, 5034.546890779848, 6377.649229292248, 6575.514977062165, 6621.536492445675, 6663.7001334941015, 6671.866562668816, 6285.49716022708, 6411.6153030818305, 6424.807255782334, 6462.2228887056435, 6535.477161827138, 6299.937000630119, 6319.24169099711, 6348.77779277723, 6380.298167201511, 6447.742690175371, 6269.0, 6338.714757836001, 6390.329015453311, 6421.678916054085, 6432.2602900667025, 6337.841553961188, 6349.460295874902, 6358.777442789666, 6371.82121307541, 6390.93609063922, 6266.780662676784, 6283.246010478589, 6318.4313411793155, 6337.397947195009, 6366.29970703213], "value": 5012.9186286123995, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [922.2069020642242, 943.8065196634691, 948.8839887051874, 951.6764300137952, 980.14727187347, 18103.456896293115, 18717.906410467964, 18743.718844217346, 18878.622427551458, 19038.238470461165, 20167.0, 21071.47321316968, 21777.128914843426, 21844.235451759192, 22141.114355425765, 1034.3328553083256, 1065.206421216195, 1068.8770791359016, 1070.053003092265, 1072.3244356055675, 803.6704950970103, 1075.9031687148156, 1078.1698092468794, 1079.2499213046935, 1079.4926384599264, 910.1217325281102, 922.3128769067055, 1087.7063192937942, 1125.7016890524017, 1141.703157179131, 1129.2603344809163, 1132.8697199822043, 1138.1918837625296, 1138.595798491532, 1139.0204424195144, 827.38359921858, 831.1314676163363, 833.9791505212357, 834.3450391442749, 836.0552575589545, 19525.609487810165, 20458.693119603216, 20565.897170514203, 20643.793562064486, 20716.79283207164, 20016.799832001783, 20032.69950950737, 20549.075291611913, 20665.483362915897, 20777.480562985897, 15989.360425582963, 16220.675586488322, 16417.67164656712, 16456.917715411466, 16760.078195699232, 3544.5923718772156, 3582.7492075554724, 3602.873899413508, 3606.6393360663724, 3607.603163651996, 126638.0, 130940.0, 134334.3283283587, 134739.0, 137525.0, 15368.308426120851, 16877.915610422115, 17230.655386892442, 17450.738238926173, 17701.291948321923, 24358.756412436003, 24507.26478205621, 24569.262922112364, 25758.871205643853, 26594.86702566475, 9014.86477702835, 9106.907171139506, 9161.954190229006, 9194.218491428304, 9202.81594368109, 4086.5096188457574, 4160.50073991123, 4175.812088456027, 4199.0342221288665, 4259.424977628042, 5238.7904483820985, 5421.810236641778, 5444.074507333684, 5451.64564303319, 5463.180522921514, 962.7400601837464, 982.9557669904872, 996.446971930571, 1001.9098281154731, 1095.5234473004268, 706.1808302369329, 717.6519388096768, 725.7858931615186, 728.6793810723265, 741.7255615422221, 12587.685307867194, 13001.0, 13021.153625014344, 13236.272005039622, 13479.445492379995, 693.2616763147232, 709.5848928376957, 713.9464540159508, 733.1165945036219, 737.4432303610739, 5828.912566311508, 6109.450149486567, 6117.327094019654, 6216.564840461125, 6223.564350495421, 123304.38347808203, 124629.0, 127405.36297318454, 129403.35298323637, 132941.0, 107130.46434767933, 111470.44264778786, 111474.0, 113115.0, 114745.42627286976, 93157.53421232986, 93824.0, 94250.0, 96069.51965240129, 101880.0, 13444.798328024892, 13479.86520134786, 13564.0, 13568.525101621395, 13696.726065478435, 42832.357514636686, 44798.552014479435, 45443.31835022543, 45966.310505341775, 46503.53496464992, 79601.2039879617, 83229.16770832214, 87070.0, 87683.56158219169, 88127.0, 20871.791282087594, 20929.1628334871, 20980.0, 21176.470588235417, 21416.250431234832, 23089.422764431023, 23570.410739731644, 23707.762922371247, 24148.0, 24364.0, 20366.59266814626, 21113.472163196042, 21279.255226067013, 21365.67951480698, 21540.784592154505, 21330.573388531833, 21835.344939651837, 21895.12419503202, 21949.341519754435, 22326.88836555807, 20096.0, 20112.899435502728, 20334.186632535126, 20391.082401292275, 20649.69025464589, 12998.415071321624, 13739.656508587364, 13924.443022278996, 14070.366833492722, 14870.182139982406, 15179.154718862985, 15258.0, 15357.462488812838, 15390.538283851502, 15420.383184672488], "value": 9709.258321833913, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1476.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 107.0, 106.0, 106.0, 106.0, 108.0, 108.0, 106.0, 107.0, 108.0], "value": 106.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 114.0, 142.0, 168.0, 118.0, 143.0, 143.0, 115.0, 163.0, 115.0], "value": 137.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 164.0, 159.0, 163.0, 170.0, 161.0, 164.0, 159.0, 169.0], "value": 163.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 78.0, 74.0, 73.0, 73.0, 73.0, 145.0, 73.0, 73.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 124.0, 81.0, 80.0, 80.0, 135.0, 80.0, 80.0, 133.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 113.0, 113.0, 113.0, 113.0, 114.0, 113.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 148.0, 147.0, 148.0, 146.0, 149.0, 147.0, 147.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 47.0, 47.0, 47.0, 51.0, 47.0, 47.0, 47.0, 56.0, 46.0], "value": 48.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 55.0, 55.0, 56.0, 55.0, 56.0, 56.0, 55.0], "value": 55.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 74.0, 74.0, 74.0, 75.0, 79.0, 75.0, 76.0, 75.0], "value": 75.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 120.0, 117.0, 117.0, 116.0, 117.0, 117.0, 120.0, 119.0, 136.0], "value": 120.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 178.0, 194.0, 169.0, 174.0, 174.0, 186.0, 172.0, 168.0, 168.0], "value": 176.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 59.0, 61.0, 63.0, 63.0, 61.0, 60.0, 61.0, 63.0], "value": 61.5, "unit": "score"}]}]} 18:42:55 INFO - Return code: 0 18:42:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:42:55 INFO - # TBPL SUCCESS # 18:42:55 INFO - Running post-action listener: _package_coverage_data 18:42:55 INFO - Running post-action listener: _resource_record_post_action 18:42:55 INFO - [mozharness: 2017-07-17 01:42:55.591599Z] Finished run-tests step (success) 18:42:55 INFO - Running post-run listener: _resource_record_post_run 18:42:55 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 5750784; Write bytes: 440516608; Read time: 2352; Write time: 940624 18:42:55 INFO - TinderboxPrint: CPU usage
13.1% 18:42:55 INFO - TinderboxPrint: I/O read bytes / time
5,750,784 / 2,352 18:42:55 INFO - TinderboxPrint: I/O write bytes / time
440,516,608 / 940,624 18:42:55 INFO - TinderboxPrint: CPU idle
8,277.7 (86.9%) 18:42:55 INFO - TinderboxPrint: CPU user
1,186.0 (12.5%) 18:42:55 INFO - TinderboxPrint: Swap in / out
0 / 0 18:42:55 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 144175104; Read time: 0; Write time: 298604 18:42:55 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:42:55 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 3563520; Write bytes: 265601024; Read time: 1736; Write time: 585844 18:42:56 INFO - Running post-run listener: _upload_blobber_files 18:42:56 INFO - Blob upload gear active. 18:42:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:42:56 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:42:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:42:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:42:56 INFO - Traceback (most recent call last): 18:42:56 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 18:42:56 INFO - main() 18:42:56 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 18:42:56 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 18:42:56 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 18:42:56 INFO - return set(response.json().get('whitelist', [])) 18:42:56 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 18:42:56 INFO - return json.loads(self.text or self.content, **kwargs) 18:42:56 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/__init__.py", line 488, in loads 18:42:56 INFO - return _default_decoder.decode(s) 18:42:56 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 370, in decode 18:42:56 INFO - obj, end = self.raw_decode(s) 18:42:56 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 389, in raw_decode 18:42:56 INFO - return self.scan_once(s, idx=_w(s, idx).end()) 18:42:56 INFO - simplejson.scanner.JSONDecodeError: Expecting value: line 1 column 1 (char 0) 18:42:56 ERROR - Return code: 1 18:42:56 INFO - Setting buildbot property blobber_files to {} 18:42:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:42:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:42:56 INFO - Contents: 18:42:56 INFO - blobber_files:{} 18:42:56 INFO - Running post-run listener: copy_logs_to_upload_dir 18:42:56 INFO - Copying logs to upload dir... 18:42:56 INFO - mkdir: /builds/slave/test/build/upload/logs 18:42:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.164354 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-07-16 18:42:56.776282) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-16 18:42:56.784159) ========= 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=8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011123 build_url: 'https://queue.taskcluster.net/v1/task/L_tPsB8BSw60ipwcCtx25w/artifacts/public/build/target.tar.bz2' blobber_files: '{}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-16 18:42:56.837548) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 18:42:56.837872) ========= 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=8783db0840cc980e80bb0bd400000236-1500252508.852310-776713233 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004420 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 18:42:56.887811) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-16 18:42:56.888134) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-16 18:42:56.888435) ========= ========= Total master_lag: 0.19 =========