builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-059 starttime: 1502582960.36 results: success (0) revision: 59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:20.363435) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:20.363930) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:20.364252) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:20.419273) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:20.419622) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-12 17:09:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2017-08-12 17:09:20 (46.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117273 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:20.562623) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:20.562947) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022060 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:20.622042) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:20.622631) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2 --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 59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-12 17:09:20,747 truncating revision to first 12 chars 2017-08-12 17:09:20,747 Setting DEBUG logging. 2017-08-12 17:09:20,747 attempt 1/10 2017-08-12 17:09:20,747 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/59594b3879cc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-12 17:09:21,163 unpacking tar archive at: mozilla-central-59594b3879cc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.737829 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:21.381225) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:21.381562) ========= script_repo_revision: 59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:21.381963) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:21.382264) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:21.392349) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-12 17:09:21.392697) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:09:21 INFO - MultiFileLogger online at 20170812 17:09:21 in /builds/slave/test 17:09:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 17:09:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:09:21 INFO - {'append_to_log': False, 17:09:21 INFO - 'base_work_dir': '/builds/slave/test', 17:09:21 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 17:09:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:09:21 INFO - 'branch': 'Firefox-Non-PGO', 17:09:21 INFO - 'buildbot_json_path': 'buildprops.json', 17:09:21 INFO - 'code_coverage': False, 17:09:21 INFO - 'config_files': ('talos/linux_config.py',), 17:09:21 INFO - 'default_actions': ('clobber', 17:09:21 INFO - 'read-buildbot-config', 17:09:21 INFO - 'download-and-extract', 17:09:21 INFO - 'populate-webroot', 17:09:21 INFO - 'create-virtualenv', 17:09:21 INFO - 'install', 17:09:21 INFO - 'setup-mitmproxy', 17:09:21 INFO - 'run-tests'), 17:09:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:09:21 INFO - 'disable_ccov_upload': False, 17:09:21 INFO - 'download_minidump_stackwalk': True, 17:09:21 INFO - 'download_symbols': 'ondemand', 17:09:21 INFO - 'enable_stylo': False, 17:09:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:09:21 INFO - 'tooltool.py': '/tools/tooltool.py', 17:09:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:09:21 INFO - '/tools/misc-python/virtualenv.py')}, 17:09:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:09:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:09:21 INFO - 'gecko_profile': False, 17:09:21 INFO - 'gecko_profile_interval': 0, 17:09:21 INFO - 'installer_path': 'installer.exe', 17:09:21 INFO - 'log_level': 'info', 17:09:21 INFO - 'log_name': 'talos', 17:09:21 INFO - 'log_to_console': True, 17:09:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:09:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:09:21 INFO - 'opt_config_files': (), 17:09:21 INFO - 'pip_index': False, 17:09:21 INFO - 'suite': 'dromaeojs-e10s', 17:09:21 INFO - 'system_bits': '32', 17:09:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:09:21 INFO - 'title': 'talos-linux64-ix-059', 17:09:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:09:21 INFO - 'use_talos_json': True, 17:09:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:09:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:09:21 INFO - 'work_dir': 'build'} 17:09:21 INFO - [mozharness: 2017-08-13 00:09:21.615239Z] Running clobber step. 17:09:21 INFO - Running pre-action listener: _resource_record_pre_action 17:09:21 INFO - Running main action method: clobber 17:09:21 INFO - rmtree: /builds/slave/test/build 17:09:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:09:22 INFO - Running post-action listener: _resource_record_post_action 17:09:22 INFO - [mozharness: 2017-08-13 00:09:22.639829Z] Finished clobber step (success) 17:09:22 INFO - [mozharness: 2017-08-13 00:09:22.639932Z] Running read-buildbot-config step. 17:09:22 INFO - Running pre-action listener: _resource_record_pre_action 17:09:22 INFO - Running main action method: read_buildbot_config 17:09:22 INFO - Using buildbot properties: 17:09:22 INFO - { 17:09:22 INFO - "project": "", 17:09:22 INFO - "product": "firefox", 17:09:22 INFO - "who": "archaeopteryx@coole-files.de", 17:09:22 INFO - "installer_path": "public/build/target.tar.bz2", 17:09:22 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 17:09:22 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:09:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 17:09:22 INFO - "stage_platform": "linux64", 17:09:22 INFO - "basedir": "/builds/slave/test", 17:09:22 INFO - "buildnumber": 1073, 17:09:22 INFO - "platform": "ubuntu64_hw", 17:09:22 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:09:22 INFO - "slavebuilddir": "test", 17:09:22 INFO - "taskId": "VIKW6M0rTeS4dBQFWqFRRQ", 17:09:22 INFO - "branch": "mozilla-central", 17:09:22 INFO - "script_repo_revision": "production", 17:09:22 INFO - "revision": "59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2", 17:09:22 INFO - "slavename": "talos-linux64-ix-059", 17:09:22 INFO - "repo_path": "mozilla-central" 17:09:22 INFO - } 17:09:22 INFO - Finding installer, test and symbols from parent task. 17:09:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VIKW6M0rTeS4dBQFWqFRRQ'}, attempt #1 17:09:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VIKW6M0rTeS4dBQFWqFRRQ'}, attempt #1 17:09:23 INFO - Task dependencies: CP2xHjBSS1Kh1OeG7_y_fA 17:09:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA'}, attempt #1 17:09:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2 17:09:24 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.test_packages.json 17:09:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.crashreporter-symbols.zip 17:09:24 INFO - Running post-action listener: _resource_record_post_action 17:09:24 INFO - [mozharness: 2017-08-13 00:09:24.175695Z] Finished read-buildbot-config step (success) 17:09:24 INFO - [mozharness: 2017-08-13 00:09:24.175919Z] Running download-and-extract step. 17:09:24 INFO - Running pre-action listener: _resource_record_pre_action 17:09:24 INFO - Running main action method: download_and_extract 17:09:24 INFO - mkdir: /builds/slave/test/build/tests 17:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:24 INFO - trying https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.test_packages.json 17:09:24 INFO - Downloading https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:09:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:09:24 INFO - Downloaded 989 bytes. 17:09:24 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:09:24 INFO - Using the following test package requirements: 17:09:24 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:09:24 INFO - u'common': [u'target.common.tests.zip'], 17:09:24 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:09:24 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:09:24 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:09:24 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:09:24 INFO - u'mozbase': [u'target.common.tests.zip'], 17:09:24 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:09:24 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:09:24 INFO - u'web-platform': [u'target.common.tests.zip', 17:09:24 INFO - u'target.web-platform.tests.tar.gz'], 17:09:24 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:09:24 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:09:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.common.tests.zip 17:09:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:09:24 INFO - Fetch https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.common.tests.zip into memory 17:09:25 INFO - Content-Length response header: 54034669 17:09:25 INFO - Bytes received: 54034669 17:09:36 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:09:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.common.tests.zip 17:09:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:09:36 INFO - Fetch https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.common.tests.zip into memory 17:09:38 INFO - Content-Length response header: 54034669 17:09:38 INFO - Bytes received: 54034669 17:09:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.talos.tests.zip 17:09:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:09:51 INFO - Fetch https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.talos.tests.zip into memory 17:09:52 INFO - Content-Length response header: 14496050 17:09:52 INFO - Bytes received: 14496050 17:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:52 INFO - trying https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2 17:09:52 INFO - Downloading https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:09:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:09:54 INFO - Downloaded 61674492 bytes. 17:09:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2 17:09:54 INFO - mkdir: /builds/slave/test/properties 17:09:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:09:54 INFO - Writing to file /builds/slave/test/properties/build_url 17:09:54 INFO - Contents: 17:09:54 INFO - build_url:https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2 17:09:54 INFO - Running post-action listener: _resource_record_post_action 17:09:54 INFO - Running post-action listener: set_extra_try_arguments 17:09:54 INFO - [mozharness: 2017-08-13 00:09:54.018529Z] Finished download-and-extract step (success) 17:09:54 INFO - [mozharness: 2017-08-13 00:09:54.018648Z] Running populate-webroot step. 17:09:54 INFO - Running pre-action listener: _resource_record_pre_action 17:09:54 INFO - Running main action method: populate_webroot 17:09:54 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:09:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:09:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:09:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:09:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:09:54 INFO - 'tests': ['damp', 'tps']}, 17:09:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:09:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:09:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:09:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:09:54 INFO - 'other-e10s': {'tests': ['a11yr', 17:09:54 INFO - 'ts_paint', 17:09:54 INFO - 'tpaint', 17:09:54 INFO - 'sessionrestore', 17:09:54 INFO - 'sessionrestore_many_windows', 17:09:54 INFO - 'sessionrestore_no_auto_restore', 17:09:54 INFO - 'tabpaint']}, 17:09:54 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 17:09:54 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 17:09:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:09:54 INFO - 'tsvgr_opacity', 17:09:54 INFO - 'tart', 17:09:54 INFO - 'tscrollx', 17:09:54 INFO - 'tsvg_static']}, 17:09:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:09:54 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:09:54 INFO - 'talos_options': ['--mitmproxy', 17:09:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:09:54 INFO - '--firstNonBlankPaint'], 17:09:54 INFO - 'tests': ['tp6_google', 17:09:54 INFO - 'tp6_youtube', 17:09:54 INFO - 'tp6_amazon', 17:09:54 INFO - 'tp6_facebook']}, 17:09:54 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:09:54 INFO - 'talos_options': ['--stylo', 17:09:54 INFO - '--mitmproxy', 17:09:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:09:54 INFO - '--firstNonBlankPaint'], 17:09:54 INFO - 'tests': ['tp6_google', 17:09:54 INFO - 'tp6_youtube', 17:09:54 INFO - 'tp6_amazon', 17:09:54 INFO - 'tp6_facebook']}, 17:09:54 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:09:54 INFO - 'talos_options': ['--stylo', 17:09:54 INFO - '--stylo-threads=1', 17:09:54 INFO - '--mitmproxy', 17:09:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:09:54 INFO - '--firstNonBlankPaint'], 17:09:54 INFO - 'tests': ['tp6_google', 17:09:54 INFO - 'tp6_youtube', 17:09:54 INFO - 'tp6_amazon', 17:09:54 INFO - 'tp6_facebook']}, 17:09:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:09:54 INFO - 'talos_options': ['--xperf_path', 17:09:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:09:54 INFO - 'tests': ['tp5n']}}} 17:09:54 INFO - Running post-action listener: _resource_record_post_action 17:09:54 INFO - [mozharness: 2017-08-13 00:09:54.029077Z] Finished populate-webroot step (success) 17:09:54 INFO - [mozharness: 2017-08-13 00:09:54.029188Z] Running create-virtualenv step. 17:09:54 INFO - Running pre-action listener: _resource_record_pre_action 17:09:54 INFO - Running main action method: create_virtualenv 17:09:54 INFO - Creating virtualenv /builds/slave/test/build/venv 17:09:54 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 17:09:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:09:54 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:09:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:09:54 INFO - Using real prefix '/usr' 17:09:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:09:54 INFO - Installing distribute.............................................................................................................................................................................................done. 17:09:56 INFO - Installing pip.................done. 17:09:56 INFO - Return code: 0 17:09:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:09:56 INFO - Reading from file tmpfile_stdout 17:09:56 INFO - Output received: 17:09:56 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) 17:09:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27feb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801f00>, '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 0x27fcba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fd050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', '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 17:09:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:09:56 INFO - Using env: {'DISPLAY': ':0', 17:09:56 INFO - 'HOME': '/home/cltbld', 17:09:56 INFO - 'LANG': 'en_US.UTF-8', 17:09:56 INFO - 'LANGUAGE': 'en_US:en', 17:09:56 INFO - 'LOGNAME': 'cltbld', 17:09:56 INFO - 'MAIL': '/var/mail/cltbld', 17:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:56 INFO - 'MOZ_NO_REMOTE': '1', 17:09:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:56 INFO - 'NO_EM_RESTART': '1', 17:09:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:56 INFO - 'PWD': '/builds/slave/test', 17:09:56 INFO - 'SHELL': '/bin/bash', 17:09:56 INFO - 'SHLVL': '1', 17:09:56 INFO - 'TERM': 'linux', 17:09:56 INFO - 'TMOUT': '86400', 17:09:56 INFO - 'USER': 'cltbld', 17:09:56 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', 17:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:56 INFO - '_': '/tools/buildbot/bin/python'} 17:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:57 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 17:09:57 INFO - Cleaning up... 17:09:57 INFO - Return code: 0 17:09:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:09: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')]} 17:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09: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')]} 17:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:57 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 0x24901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27feb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801f00>, '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 0x27fcba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fd050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', '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 17:09:57 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 17:09:57 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 17:09:57 INFO - Using env: {'DISPLAY': ':0', 17:09:57 INFO - 'HOME': '/home/cltbld', 17:09:57 INFO - 'LANG': 'en_US.UTF-8', 17:09:57 INFO - 'LANGUAGE': 'en_US:en', 17:09:57 INFO - 'LOGNAME': 'cltbld', 17:09:57 INFO - 'MAIL': '/var/mail/cltbld', 17:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:57 INFO - 'MOZ_NO_REMOTE': '1', 17:09:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:57 INFO - 'NO_EM_RESTART': '1', 17:09:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:57 INFO - 'PWD': '/builds/slave/test', 17:09:57 INFO - 'SHELL': '/bin/bash', 17:09:57 INFO - 'SHLVL': '1', 17:09:57 INFO - 'TERM': 'linux', 17:09:57 INFO - 'TMOUT': '86400', 17:09:57 INFO - 'USER': 'cltbld', 17:09:57 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', 17:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:57 INFO - '_': '/tools/buildbot/bin/python'} 17:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:57 INFO - Downloading/unpacking psutil>=3.1.1 17:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:09:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:09:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:09:58 INFO - Installing collected packages: psutil 17:09:58 INFO - Running setup.py install for psutil 17:09:58 INFO - building 'psutil._psutil_linux' extension 17:09:58 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 17:09:58 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 17:09:58 INFO - building 'psutil._psutil_posix' extension 17:09:58 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 17:09:58 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 17:09:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:09:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:09:58 INFO - Successfully installed psutil 17:09:58 INFO - Cleaning up... 17:09:59 INFO - Return code: 0 17:09:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:09:59 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')]} 17:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:59 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')]} 17:09:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:59 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 0x24901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27feb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801f00>, '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 0x27fcba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fd050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', '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 17:09:59 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 17:09:59 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 17:09:59 INFO - Using env: {'DISPLAY': ':0', 17:09:59 INFO - 'HOME': '/home/cltbld', 17:09:59 INFO - 'LANG': 'en_US.UTF-8', 17:09:59 INFO - 'LANGUAGE': 'en_US:en', 17:09:59 INFO - 'LOGNAME': 'cltbld', 17:09:59 INFO - 'MAIL': '/var/mail/cltbld', 17:09:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:59 INFO - 'MOZ_NO_REMOTE': '1', 17:09:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:59 INFO - 'NO_EM_RESTART': '1', 17:09:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:59 INFO - 'PWD': '/builds/slave/test', 17:09:59 INFO - 'SHELL': '/bin/bash', 17:09:59 INFO - 'SHLVL': '1', 17:09:59 INFO - 'TERM': 'linux', 17:09:59 INFO - 'TMOUT': '86400', 17:09:59 INFO - 'USER': 'cltbld', 17:09:59 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', 17:09:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:59 INFO - '_': '/tools/buildbot/bin/python'} 17:09:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:09: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 17:09: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 17:09: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 17:09: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 17:10:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:10:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:10:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:10:00 INFO - Installing collected packages: mozsystemmonitor 17:10:00 INFO - Running setup.py install for mozsystemmonitor 17:10:00 INFO - Successfully installed mozsystemmonitor 17:10:00 INFO - Cleaning up... 17:10:00 INFO - Return code: 0 17:10:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:10: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')]} 17:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10: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')]} 17:10:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27feb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801f00>, '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 0x27fcba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fd050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', '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 17:10: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==2.5.1'] in /builds/slave/test/build 17:10: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==2.5.1 17:10:00 INFO - Using env: {'DISPLAY': ':0', 17:10:00 INFO - 'HOME': '/home/cltbld', 17:10:00 INFO - 'LANG': 'en_US.UTF-8', 17:10:00 INFO - 'LANGUAGE': 'en_US:en', 17:10:00 INFO - 'LOGNAME': 'cltbld', 17:10:00 INFO - 'MAIL': '/var/mail/cltbld', 17:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:00 INFO - 'MOZ_NO_REMOTE': '1', 17:10:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:00 INFO - 'NO_EM_RESTART': '1', 17:10:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:00 INFO - 'PWD': '/builds/slave/test', 17:10:00 INFO - 'SHELL': '/bin/bash', 17:10:00 INFO - 'SHLVL': '1', 17:10:00 INFO - 'TERM': 'linux', 17:10:00 INFO - 'TMOUT': '86400', 17:10:00 INFO - 'USER': 'cltbld', 17:10:00 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', 17:10:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:00 INFO - '_': '/tools/buildbot/bin/python'} 17:10:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:00 INFO - Downloading/unpacking jsonschema==2.5.1 17:10:00 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 17:10:00 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 17:10:00 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 17:10:00 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 17:10:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:10:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:10:02 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 17:10:02 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 17:10:02 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 17:10:02 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 17:10:02 INFO - Downloading functools32-3.2.3-2.tar.gz 17:10:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:10:02 INFO - warning: no files found matching '*.txt' 17:10:02 INFO - no previously-included directories found matching 'build' 17:10:02 INFO - no previously-included directories found matching 'dist' 17:10:02 INFO - no previously-included directories found matching '.git*' 17:10:02 INFO - Installing collected packages: jsonschema, functools32 17:10:02 INFO - Running setup.py install for functools32 17:10:02 INFO - warning: no files found matching '*.txt' 17:10:02 INFO - no previously-included directories found matching 'build' 17:10:02 INFO - no previously-included directories found matching 'dist' 17:10:02 INFO - no previously-included directories found matching '.git*' 17:10:02 INFO - Successfully installed jsonschema functools32 17:10:02 INFO - Cleaning up... 17:10:02 INFO - Return code: 0 17:10:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:10:02 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')]} 17:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:02 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')]} 17:10:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:02 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 0x24901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27feb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801f00>, '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 0x27fcba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fd050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', '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 17:10:02 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 17:10:02 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 17:10:02 INFO - Using env: {'DISPLAY': ':0', 17:10:02 INFO - 'HOME': '/home/cltbld', 17:10:02 INFO - 'LANG': 'en_US.UTF-8', 17:10:02 INFO - 'LANGUAGE': 'en_US:en', 17:10:02 INFO - 'LOGNAME': 'cltbld', 17:10:02 INFO - 'MAIL': '/var/mail/cltbld', 17:10:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:02 INFO - 'MOZ_NO_REMOTE': '1', 17:10:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:02 INFO - 'NO_EM_RESTART': '1', 17:10:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:02 INFO - 'PWD': '/builds/slave/test', 17:10:02 INFO - 'SHELL': '/bin/bash', 17:10:02 INFO - 'SHLVL': '1', 17:10:02 INFO - 'TERM': 'linux', 17:10:02 INFO - 'TMOUT': '86400', 17:10:02 INFO - 'USER': 'cltbld', 17:10:02 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', 17:10:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:02 INFO - '_': '/tools/buildbot/bin/python'} 17:10:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:10:02 INFO - Cleaning up... 17:10:02 INFO - Return code: 0 17:10:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:10:02 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')]} 17:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:02 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')]} 17:10:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:02 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 0x24901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27feb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801f00>, '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 0x27fcba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fd050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', '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 17:10:02 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 17:10:02 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 17:10:02 INFO - Using env: {'DISPLAY': ':0', 17:10:02 INFO - 'HOME': '/home/cltbld', 17:10:02 INFO - 'LANG': 'en_US.UTF-8', 17:10:02 INFO - 'LANGUAGE': 'en_US:en', 17:10:02 INFO - 'LOGNAME': 'cltbld', 17:10:02 INFO - 'MAIL': '/var/mail/cltbld', 17:10:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:02 INFO - 'MOZ_NO_REMOTE': '1', 17:10:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:02 INFO - 'NO_EM_RESTART': '1', 17:10:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:02 INFO - 'PWD': '/builds/slave/test', 17:10:02 INFO - 'SHELL': '/bin/bash', 17:10:02 INFO - 'SHLVL': '1', 17:10:02 INFO - 'TERM': 'linux', 17:10:02 INFO - 'TMOUT': '86400', 17:10:02 INFO - 'USER': 'cltbld', 17:10:02 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', 17:10:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:02 INFO - '_': '/tools/buildbot/bin/python'} 17:10:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:02 INFO - Downloading/unpacking blobuploader==1.2.4 17:10:02 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 17:10:02 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 17:10:02 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 17:10:02 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 17:10:04 INFO - Downloading blobuploader-1.2.4.tar.gz 17:10:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:10:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:10:04 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 17:10:04 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 17:10:04 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 17:10:04 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 17:10:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:10:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:10:04 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 17:10:04 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 17:10:04 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 17:10:04 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 17:10:04 INFO - Downloading docopt-0.6.1.tar.gz 17:10:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:10:04 INFO - Installing collected packages: blobuploader, requests, docopt 17:10:04 INFO - Running setup.py install for blobuploader 17:10:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:10:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:10:04 INFO - Running setup.py install for requests 17:10:05 INFO - Running setup.py install for docopt 17:10:05 INFO - Successfully installed blobuploader requests docopt 17:10:05 INFO - Cleaning up... 17:10:05 INFO - Return code: 0 17:10:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:05 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 0x24901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27feb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801f00>, '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 0x27fcba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fd050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', '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 17:10:05 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 17:10:05 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 17:10:05 INFO - Using env: {'DISPLAY': ':0', 17:10:05 INFO - 'HOME': '/home/cltbld', 17:10:05 INFO - 'LANG': 'en_US.UTF-8', 17:10:05 INFO - 'LANGUAGE': 'en_US:en', 17:10:05 INFO - 'LOGNAME': 'cltbld', 17:10:05 INFO - 'MAIL': '/var/mail/cltbld', 17:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:05 INFO - 'MOZ_NO_REMOTE': '1', 17:10:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:05 INFO - 'NO_EM_RESTART': '1', 17:10:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:05 INFO - 'PWD': '/builds/slave/test', 17:10:05 INFO - 'SHELL': '/bin/bash', 17:10:05 INFO - 'SHLVL': '1', 17:10:05 INFO - 'TERM': 'linux', 17:10:05 INFO - 'TMOUT': '86400', 17:10:05 INFO - 'USER': 'cltbld', 17:10:05 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', 17:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:05 INFO - '_': '/tools/buildbot/bin/python'} 17:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:10:05 INFO - Running setup.py (path:/tmp/pip-jsFRFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:10:05 INFO - Running setup.py (path:/tmp/pip-3KkSlv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:10:05 INFO - Running setup.py (path:/tmp/pip-OJZpf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:10:05 INFO - Running setup.py (path:/tmp/pip-Eeu8LE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:10:05 INFO - Running setup.py (path:/tmp/pip-Mwgx5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:10:05 INFO - Running setup.py (path:/tmp/pip-9uoFll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:10:06 INFO - Running setup.py (path:/tmp/pip-Dou5hM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:10:06 INFO - Running setup.py (path:/tmp/pip-XXF9z6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:10:06 INFO - Running setup.py (path:/tmp/pip-tLELCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:10:06 INFO - Running setup.py (path:/tmp/pip-u1i0F8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:10:06 INFO - Running setup.py (path:/tmp/pip-ZTIPGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:10:06 INFO - Running setup.py (path:/tmp/pip-bIn_Km-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:10:06 INFO - Running setup.py (path:/tmp/pip-5QxN5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:10:06 INFO - Running setup.py (path:/tmp/pip-UrP7PQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:10:06 INFO - Running setup.py (path:/tmp/pip-YRSLjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:10:06 INFO - Running setup.py (path:/tmp/pip-z343nU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:10:06 INFO - Running setup.py (path:/tmp/pip-kx4gI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:10:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:10:06 INFO - Running setup.py install for manifestparser 17:10:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:10:06 INFO - Running setup.py install for mozcrash 17:10:07 INFO - Running setup.py install for mozdebug 17:10:07 INFO - Running setup.py install for mozdevice 17:10:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:10:07 INFO - Running setup.py install for mozfile 17:10:07 INFO - Running setup.py install for mozhttpd 17:10:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:10:07 INFO - Running setup.py install for mozinfo 17:10:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:10:07 INFO - Running setup.py install for mozInstall 17:10:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:10:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:10:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:10:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:10:07 INFO - Running setup.py install for mozleak 17:10:07 INFO - Running setup.py install for mozlog 17:10:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:10:07 INFO - Running setup.py install for moznetwork 17:10:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:10:07 INFO - Running setup.py install for mozprocess 17:10:08 INFO - Running setup.py install for mozprofile 17:10:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:10:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:10:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:10:08 INFO - Running setup.py install for mozrunner 17:10:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:10:08 INFO - Running setup.py install for mozscreenshot 17:10:08 INFO - Running setup.py install for moztest 17:10:08 INFO - Running setup.py install for mozversion 17:10:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:10:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:10:08 INFO - Cleaning up... 17:10:08 INFO - Return code: 0 17:10:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:10:08 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')]} 17:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:08 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')]} 17:10:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:08 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 0x24901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27feb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801f00>, '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 0x27fcba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fd050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', '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 17:10:08 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 17:10:08 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 17:10:08 INFO - Using env: {'DISPLAY': ':0', 17:10:08 INFO - 'HOME': '/home/cltbld', 17:10:08 INFO - 'LANG': 'en_US.UTF-8', 17:10:08 INFO - 'LANGUAGE': 'en_US:en', 17:10:08 INFO - 'LOGNAME': 'cltbld', 17:10:08 INFO - 'MAIL': '/var/mail/cltbld', 17:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:08 INFO - 'MOZ_NO_REMOTE': '1', 17:10:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:08 INFO - 'NO_EM_RESTART': '1', 17:10:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:08 INFO - 'PWD': '/builds/slave/test', 17:10:08 INFO - 'SHELL': '/bin/bash', 17:10:08 INFO - 'SHLVL': '1', 17:10:08 INFO - 'TERM': 'linux', 17:10:08 INFO - 'TMOUT': '86400', 17:10:08 INFO - 'USER': 'cltbld', 17:10:08 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', 17:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:08 INFO - '_': '/tools/buildbot/bin/python'} 17:10:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:10:08 INFO - Running setup.py (path:/tmp/pip-uEfmGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:10:08 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)) 17:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:10:08 INFO - Running setup.py (path:/tmp/pip-ANmdwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:10:08 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)) 17:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:10:08 INFO - Running setup.py (path:/tmp/pip-V5O2tU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:10:09 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)) 17:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:10:09 INFO - Running setup.py (path:/tmp/pip-KVDSP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:10:09 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)) 17:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:10:09 INFO - Running setup.py (path:/tmp/pip-XeW36I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:10:09 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)) 17:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:10:09 INFO - Running setup.py (path:/tmp/pip-Vg9v5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:10:09 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)) 17:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:10:09 INFO - Running setup.py (path:/tmp/pip-thuOKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:10:09 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)) 17:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:10:09 INFO - Running setup.py (path:/tmp/pip-HDzuD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:10:09 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)) 17:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:10:09 INFO - Running setup.py (path:/tmp/pip-FK7BHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:10:09 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)) 17:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:10:09 INFO - Running setup.py (path:/tmp/pip-HniwXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:10:09 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)) 17:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:10:09 INFO - Running setup.py (path:/tmp/pip-q9ZgPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:10:09 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)) 17:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:10:09 INFO - Running setup.py (path:/tmp/pip-3yShZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:10:09 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)) 17:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:10:09 INFO - Running setup.py (path:/tmp/pip-0f228V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:10:09 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)) 17:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:10:09 INFO - Running setup.py (path:/tmp/pip-u__04V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:10:09 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)) 17:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:10:09 INFO - Running setup.py (path:/tmp/pip-dEm2RB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:10:09 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)) 17:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:10:09 INFO - Running setup.py (path:/tmp/pip-0vQizG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:10:10 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)) 17:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:10:10 INFO - Running setup.py (path:/tmp/pip-UbC2OW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:10:10 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)) 17:10:10 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)) 17:10:10 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)) 17:10:10 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)) 17:10:10 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)) 17:10:10 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)) 17:10:10 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)) 17:10:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:10:10 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 17:10:10 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 17:10:10 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 17:10:10 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 17:10:11 INFO - Downloading blessings-1.6.tar.gz 17:10:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:10:11 INFO - Installing collected packages: blessings 17:10:11 INFO - Running setup.py install for blessings 17:10:11 INFO - Successfully installed blessings 17:10:11 INFO - Cleaning up... 17:10:11 INFO - Return code: 0 17:10:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:10:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:10:11 INFO - Reading from file tmpfile_stdout 17:10:11 INFO - Current package versions: 17:10:11 INFO - argparse == 1.2.1 17:10:11 INFO - blessings == 1.6 17:10:11 INFO - blobuploader == 1.2.4 17:10:11 INFO - docopt == 0.6.1 17:10:11 INFO - functools32 == 3.2.3-2 17:10:11 INFO - jsonschema == 2.5.1 17:10:11 INFO - manifestparser == 1.1 17:10:11 INFO - mozInstall == 1.13 17:10:11 INFO - mozcrash == 1.0 17:10:11 INFO - mozdebug == 0.1 17:10:11 INFO - mozdevice == 0.51 17:10:11 INFO - mozfile == 1.2 17:10:11 INFO - mozhttpd == 0.7 17:10:11 INFO - mozinfo == 0.10 17:10:11 INFO - mozleak == 0.1 17:10:11 INFO - mozlog == 3.5 17:10:11 INFO - moznetwork == 0.27 17:10:11 INFO - mozprocess == 0.25 17:10:11 INFO - mozprofile == 0.28 17:10:11 INFO - mozrunner == 6.13 17:10:11 INFO - mozscreenshot == 0.1 17:10:11 INFO - mozsystemmonitor == 0.3 17:10:11 INFO - moztest == 0.8 17:10:11 INFO - mozversion == 1.4 17:10:11 INFO - psutil == 3.1.1 17:10:11 INFO - requests == 1.2.3 17:10:11 INFO - wsgiref == 0.1.2 17:10:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:10:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:11 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 0x24901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27feb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801f00>, '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 0x27fcba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fd050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', '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 17:10:11 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 17:10:11 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 17:10:11 INFO - Using env: {'DISPLAY': ':0', 17:10:11 INFO - 'HOME': '/home/cltbld', 17:10:11 INFO - 'LANG': 'en_US.UTF-8', 17:10:11 INFO - 'LANGUAGE': 'en_US:en', 17:10:11 INFO - 'LOGNAME': 'cltbld', 17:10:11 INFO - 'MAIL': '/var/mail/cltbld', 17:10:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:11 INFO - 'MOZ_NO_REMOTE': '1', 17:10:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:11 INFO - 'NO_EM_RESTART': '1', 17:10:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:11 INFO - 'PWD': '/builds/slave/test', 17:10:11 INFO - 'SHELL': '/bin/bash', 17:10:11 INFO - 'SHLVL': '1', 17:10:11 INFO - 'TERM': 'linux', 17:10:11 INFO - 'TMOUT': '86400', 17:10:11 INFO - 'USER': 'cltbld', 17:10:11 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', 17:10:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:11 INFO - '_': '/tools/buildbot/bin/python'} 17:10:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:11 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)) 17:10:11 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)) 17:10:11 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)) 17:10:11 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)) 17:10:11 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)) 17:10:11 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)) 17:10:11 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)) 17:10:11 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)) 17:10:11 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)) 17:10:11 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:10:11 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 17:10:11 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 17:10:11 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 17:10:11 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 17:10:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:10:13 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)) 17:10:13 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)) 17:10:13 INFO - Installing collected packages: simplejson 17:10:13 INFO - Running setup.py install for simplejson 17:10:13 INFO - building 'simplejson._speedups' extension 17:10:13 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 17:10:13 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 17:10:14 INFO - Successfully installed simplejson 17:10:14 INFO - Cleaning up... 17:10:14 INFO - Return code: 0 17:10:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:10:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:14 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 0x24901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27feb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2801f00>, '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 0x27fcba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fd050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', '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 17:10:14 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 17:10:14 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 17:10:14 INFO - Using env: {'DISPLAY': ':0', 17:10:14 INFO - 'HOME': '/home/cltbld', 17:10:14 INFO - 'LANG': 'en_US.UTF-8', 17:10:14 INFO - 'LANGUAGE': 'en_US:en', 17:10:14 INFO - 'LOGNAME': 'cltbld', 17:10:14 INFO - 'MAIL': '/var/mail/cltbld', 17:10:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:14 INFO - 'MOZ_NO_REMOTE': '1', 17:10:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:14 INFO - 'NO_EM_RESTART': '1', 17:10:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:14 INFO - 'PWD': '/builds/slave/test', 17:10:14 INFO - 'SHELL': '/bin/bash', 17:10:14 INFO - 'SHLVL': '1', 17:10:14 INFO - 'TERM': 'linux', 17:10:14 INFO - 'TMOUT': '86400', 17:10:14 INFO - 'USER': 'cltbld', 17:10:14 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', 17:10:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:14 INFO - '_': '/tools/buildbot/bin/python'} 17:10:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:10:14 INFO - Cleaning up... 17:10:14 INFO - Return code: 0 17:10:14 INFO - Running post-action listener: _resource_record_post_action 17:10:14 INFO - Running post-action listener: _start_resource_monitoring 17:10:14 INFO - Starting resource monitoring. 17:10:14 INFO - [mozharness: 2017-08-13 00:10:14.355010Z] Finished create-virtualenv step (success) 17:10:14 INFO - [mozharness: 2017-08-13 00:10:14.355522Z] Running install step. 17:10:14 INFO - Running pre-action listener: _resource_record_pre_action 17:10:14 INFO - Running main action method: install 17:10:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:10:14 INFO - Reading from file tmpfile_stdout 17:10:14 INFO - Detecting whether we're running mozinstall >=1.0... 17:10:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:10:14 INFO - Reading from file tmpfile_stdout 17:10:14 INFO - Output received: 17:10:14 INFO - Usage: mozinstall [options] installer 17:10:14 INFO - Options: 17:10:14 INFO - -h, --help show this help message and exit 17:10:14 INFO - -d DEST, --destination=DEST 17:10:14 INFO - Directory to install application into. [default: 17:10:14 INFO - "/builds/slave/test"] 17:10:14 INFO - --app=APP Application being installed. [default: firefox] 17:10:14 INFO - mkdir: /builds/slave/test/build/application 17:10:14 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'] 17:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:10:32 INFO - Reading from file tmpfile_stdout 17:10:32 INFO - Output received: 17:10:32 INFO - /builds/slave/test/build/application/firefox/firefox 17:10:32 INFO - Running post-action listener: _resource_record_post_action 17:10:32 INFO - [mozharness: 2017-08-13 00:10:32.580562Z] Finished install step (success) 17:10:32 INFO - [mozharness: 2017-08-13 00:10:32.580788Z] Running setup-mitmproxy step. 17:10:32 INFO - Running pre-action listener: _resource_record_pre_action 17:10:32 INFO - Running main action method: setup_mitmproxy 17:10:32 INFO - Skipping: mitmproxy is not required 17:10:32 INFO - Running post-action listener: _resource_record_post_action 17:10:32 INFO - [mozharness: 2017-08-13 00:10:32.581801Z] Finished setup-mitmproxy step (success) 17:10:32 INFO - [mozharness: 2017-08-13 00:10:32.582021Z] Running run-tests step. 17:10:32 INFO - Running pre-action listener: _resource_record_pre_action 17:10:32 INFO - Running pre-action listener: _set_gcov_prefix 17:10:32 INFO - Running main action method: run_tests 17:10:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:10:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:10:32 INFO - Python 2.7.3 17:10:32 INFO - Return code: 0 17:10:32 INFO - grabbing minidump binary from tooltool 17:10:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:32 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 0x2801f00>, '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 0x27fcba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fd050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:10:32 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 17:10:32 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 17:10:32 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 17:10:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:10:32 INFO - Return code: 0 17:10:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:10:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:10:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:10:32 INFO - ENV: RUST_BACKTRACE is now full 17:10:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:10:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:10:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:10:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-059 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:10:32 INFO - Using env: {'DISPLAY': ':0', 17:10:32 INFO - 'HOME': '/home/cltbld', 17:10:32 INFO - 'LANG': 'en_US.UTF-8', 17:10:32 INFO - 'LANGUAGE': 'en_US:en', 17:10:32 INFO - 'LOGNAME': 'cltbld', 17:10:32 INFO - 'MAIL': '/var/mail/cltbld', 17:10:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:10:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:10:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:32 INFO - 'MOZ_NO_REMOTE': '1', 17:10:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:10:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:32 INFO - 'NO_EM_RESTART': '1', 17:10:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:32 INFO - 'PWD': '/builds/slave/test', 17:10:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:10:32 INFO - 'RUST_BACKTRACE': 'full', 17:10:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:10:32 INFO - 'SHELL': '/bin/bash', 17:10:32 INFO - 'SHLVL': '1', 17:10:32 INFO - 'TERM': 'linux', 17:10:32 INFO - 'TMOUT': '86400', 17:10:32 INFO - 'USER': 'cltbld', 17:10:32 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251', 17:10:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:32 INFO - '_': '/tools/buildbot/bin/python'} 17:10:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:10:32 INFO - mozversion application_buildid: 20170812232023 17:10:32 INFO - mozversion application_changeset: 59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2 17:10:32 INFO - mozversion application_display_name: Nightly 17:10:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:10:32 INFO - mozversion application_name: Firefox 17:10:32 INFO - mozversion application_remotingname: firefox 17:10:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:10:32 INFO - mozversion application_vendor: Mozilla 17:10:32 INFO - mozversion application_version: 57.0a1 17:10:32 INFO - mozversion platform_buildid: 20170812232023 17:10:32 INFO - mozversion platform_changeset: 59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2 17:10:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:10:32 INFO - mozversion platform_version: 57.0a1 17:10:32 INFO - using testdate: 1502583032 17:10:32 INFO - actual date: 1502583032 17:10:32 INFO - starting webserver on 'localhost:47733' 17:10:32 INFO - SUITE-START | Running 2 tests 17:10:32 INFO - TEST-START | dromaeo_css 17:10:32 INFO - Initialising browser for dromaeo_css test... 17:10:32 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47733/getInfo.html -profile /tmp/tmpdvH78h/profile 17:10:32 INFO - TEST-INFO | started process 4193 (/builds/slave/test/build/application/firefox/firefox http://localhost:47733/getInfo.html) 17:10:38 INFO - TEST-INFO | 4193: exit 0 17:10:38 INFO - Browser initialized. 17:10:38 INFO - Running cycle 1/1 for dromaeo_css test... 17:10:38 INFO - TEST-INFO | started process 4407 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdvH78h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:10:39 INFO - PID 4407 | 17:10:39 INFO - PID 4407 | (/builds/slave/test/build/application/firefox/firefox:4455): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:10:39 INFO - PID 4407 | 17:10:49 INFO - PID 4407 | RSS: Main: 165494784 17:10:49 INFO - PID 4407 | 17:13:48 INFO - PID 4407 | Cycle 1(1): loaded http://localhost:47733/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47733/tests/dromaeo/cssquery-ext.html) 17:13:50 INFO - PID 4407 | RSS: Main: 173027328 17:13:50 INFO - PID 4407 | 17:16:52 INFO - PID 4407 | Cycle 1(1): loaded http://localhost:47733/tests/dromaeo/cssquery-ext.html (next: http://localhost:47733/tests/dromaeo/cssquery-jquery.html) 17:16:53 INFO - PID 4407 | RSS: Main: 168865792 17:16:53 INFO - PID 4407 | 17:19:54 INFO - PID 4407 | Cycle 1(1): loaded http://localhost:47733/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47733/tests/dromaeo/cssquery-mootools.html) 17:19:55 INFO - PID 4407 | RSS: Main: 172793856 17:19:55 INFO - PID 4407 | 17:22:57 INFO - PID 4407 | Cycle 1(1): loaded http://localhost:47733/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47733/tests/dromaeo/cssquery-prototype.html) 17:22:57 INFO - PID 4407 | RSS: Main: 170573824 17:22:57 INFO - PID 4407 | 17:26:01 INFO - PID 4407 | Cycle 1(1): loaded http://localhost:47733/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47733/tests/dromaeo/cssquery-yui.html) 17:26:02 INFO - PID 4407 | RSS: Main: 174092288 17:26:02 INFO - PID 4407 | 17:29:03 INFO - PID 4407 | Cycle 1(1): loaded http://localhost:47733/tests/dromaeo/cssquery-yui.html (next: http://localhost:47733/tests/dromaeo/cssquery-dojo.html) 17:29:04 INFO - PID 4407 | RSS: Main: 174428160 17:29:04 INFO - PID 4407 | 17:29:04 INFO - PID 4407 | __start_tp_report 17:29:04 INFO - PID 4407 | _x_x_mozilla_page_load 17:29:04 INFO - PID 4407 | _x_x_mozilla_page_load_details 17:29:04 INFO - PID 4407 | |i|pagename|runs| 17:29:04 INFO - PID 4407 | |0;dojo.html;3147.7953932994355;3192.5849639546864;3197.5203719442097;3197.6642452542474;3230.7899986500875;6234.594751341159;6297.968510157454;6319.6524191169465;6362.58643188193;6371.171747672802;6176.876462470759;6244.063390491428;6270.310265870762;6270.435660790528;6331.240251169855;5667.809759950415;5694.430556944293;5720.427957204278;5752.597318187729;5762.250907382035;5656.660600363971;5712.428757124275;5722.713864306789;5726.198332233491;5762.596618236725;5739.856503587423;5822.417758224186;5822.737976791033;5831.591788574801;5846.064629659255;5551.611387202897;5677.034904066319;5763.0202865512965;5787.681677507735;5851.297844258695;5590.496855283044;5655.066913959192;5682.6306290091425;5721.6853073080965;5774.191613174159;6141.969290153521;6226.688665566703;6229.034499652561;6286.937130628681;6313.337099604521;6587.044878492592;6657;6680.799576012679;6692.56498327612;6821.863562728718;6768.69540870662;6775.559588626773;6832.043513908056;6848.280930502325;6850.897236541456;6246.718897649571;6315.873682526324;6365.681715914232;6369.172007638977;6369.840753981095;6903.482238832108;6907.861842763217;6910.965445172742;7012.123484564429;7020.368166864904;6470.5794123382775;6472.223333199952;6553.377429144223;6628.403443690091;6706.396424321737;6714.832129196711;6729.1252137221845;6734.090897728744;6763.492738044666;6764.627945462948;6683.933160668428;6751.054852320679;6764.594124352555;6766.18805743314;6772.322767723188;6706.295838936841;6716.496262780311;6724.899126513106;6815.625140617313;6829.487788415889;5327.840164795062;5378.1663842104535;5384.623076384615;5388.407275199725;5401.02781499326;4602.746848923274;4616.792244349012;4632.374629425052;4678.485366609671;4713.128071306819;5711.143328500758;5860.677662728591;5862.325832529228;5869.970650146722;5869.972754767899;6770.018347339608;6793.864122717518;6853.451723862178;6868.244493105754;6884.208316043719;5681.318241810926;5739.282589676291;5778.133280008033;5808.3901190375245;5826.776376960801;5846.444587764156;5861.238039054895;5921.28944526651;5926.259217597801;5933.910991335133;6429.260635026938;6468.3855033771715;6592.703328350236;6597.2742998271115;6600.504962127764;6670.666466676744;6717.294684058299;6747.966260168668;6781.457483401217;6842.486813489108;6592.538522303585;6603.108580341593;6606.867862642816;6622.768203112868;6630.53586248958;6586.176727909012;6721.495887808336;6723.7310507581105;6724.630145341857;6761;6808.455323574201;6809.8638027238185;6886.51794374389;6901.654917254217;6906.412954898688;6404;6447.6453795041725;6479.833629946655;6539.313372095861;6576.177977752781;6506.3493650634555;6514.218293804774;6531.608103513614;6575.046618240424;6588.242352129375;6343.682815859281;6438.90341644885;6460.353964603502;6478.1578394808375;6504.089427480062;6476.57902236372;6498.577592456475;6506.219253689587;6527.184101987252;6564.6389448580785;6274.403931626488;6274.717637706223;6345.302016778059;6416.711247993945;6448.967755161194 17:29:04 INFO - PID 4407 | |1;ext.html;797.4378063465258;850.2687688587813;872.6204101215969;875.8773771671968;1155.5839897636845;31347.686523134762;32253.35493290136;32322.676773232262;42059.78970105153;43133.568664313345;42842.35736463948;43168.352474712905;43247.567524324826;43321.35017974733;43348.133037339314;7602.049743782041;7701.3453856422275;7767.339776119036;7819.335356494705;7910.881336779953;7671.347935425496;7772.4947878388;7776.65005074773;7784.1826608206175;7785.41609379296;7765.301122898938;7808.258215469521;7810.843783124335;7834.764957051269;7837.882431763528;7719.72980945667;7759.45683802134;7778.183290754446;7779.533228006338;7787.532748035108;7712.228777122243;7722;7726.536407815493;7729.884051739228;7730.458867879251;39663.40504892429;39772.60227397718;39773.80113099445;39853.80073099645;39886.60113398887;3505.1237190702323;3541.097020259843;3548.6806187443253;3549.3256281306467;3556.968479141046;31540.211494712585;31668.208294792585;31692.524612130837;31693.36613267745;31781.04656860298;4998.9500104999215;5006.2740902569285;5037.420696619899;5047.066292735855;5062.2406639004075;177647;180183;180725.0963745186;180960;181238;49036.50963490391;49070.509294906595;49228;49231.0153796922;49305.26042109371;51574.48425515772;51639.74180129075;51888.74055629773;51943.220851687256;52043.73978130161;14162;15885.761713574306;16286.674266514603;16287;16291.348346066261;11707.531698732126;12984.67538311531;13172.60482185536;13175.209487430784;13192.802107968388;14743.41026358955;14894.776578351333;14898.925505372405;14932.925335373471;15021.849781502046;6007.189029481052;6117.724702388403;6160.815175544741;6173.351798061229;6175.722092505848;983.3313346924017;997.7954519323521;1003.4681618742078;1008.606643409071;1019.5666841592293;23251.7674823253;29269.707302927123;29384.706152938197;30097.69902300949;30678.233044173627;1089.9564017439372;1089.9891001090045;1090.7654854206385;1092.639428988448;1098.5770478365728;12399.194052386567;12980.545680901123;12980.935095324463;13078.542251021168;13119.606411807661;236134;236569;237884;238535;239140;49110.75444622754;49483.75258123686;49584.50415495871;49686.50313496891;49739.25391119136;1448.3771978049328;1459.9994024558841;1470.4853301344444;1485.6731519065788;1488.2038109611306;23273.185438509572;23307.650385244575;23358.532829343658;23485.76514234836;23500;39357.4417662851;39396;39586.20827583375;39604.60395396009;39614;206979;207078;207488;207931;208052;35243.64756352506;37171.81414092912;39448.802755986035;39546.40680389854;39572.2085558293;47152;49034.75482622564;49943.25085123653;49996.75001624969;50079.24881126859;35454.8227258862;35797.28405431825;36364.636353636124;36371;36439.27121457609;34660.65339346574;34999;35038.12404689903;35085.894846309304;35233.471497927036;38046.429303561024;38131.237375252895;38365.61634383732;38461.615383845805;38557.807210963765;33776.66223337735;34001.65998339985;34158;34410.31179376348;34533.48199776955;23816;24279.78601069904;24613.630795537723;25062.122825701743;25642.23073307804 17:29:04 INFO - PID 4407 | |2;jquery.html;571.6655756382514;573.1431509892711;581.7120525339957;582.8251524542636;586.6186978463999;22288.331350059485;22707.318780436613;23015.42461438465;23128.190513332036;23153.30540083798;26309.34226644335;26933.595996060074;26938.326541836464;27023.864880675617;27158.45683086337;6599.505037122211;6777.2545020047755;6780.321967803229;6781.525293229476;6808.965955170217;6016.909746353808;6629.171353580802;6638.701258443381;6644.269130395653;6690.464762818987;6603.240627327847;6611.305812889649;6612.008198770175;6612.801615951529;6614.272430032693;6577.112089867855;6590.505712071614;6604.70278837451;6606.8678626427445;6646.900296495556;6123.418275263843;6130.570860039799;6142.84642883927;6142.907856382158;6148;29553.408931821457;30202;30757.538636920464;30818.53772193419;30841.691583084106;1889.1404410992966;1924.220690620301;1941.6213838301537;1942.65032294187;1971.4479945615108;19037.61924761497;19429.611407771907;20295.898520507304;20458.488537786525;20587.58824823495;2446.681931348931;2467.235157101304;2501.3246423465744;2516.0313279387383;2531.8227724059325;144403;151369;151900;152151;152280.2385988063;14373.281335933161;15072.24638768079;15164.620884477976;15272.465463708717;15815.841841581667;24086;24101;24121.396965076012;24157.879210604187;24165.637515437284;9942.80114397708;9950.213940096784;10063.496825158712;10100.898991009995;10191.286609937382;8998.910010899937;9071.501067441222;9140.085991400807;9310.53447327631;9390.436573805593;7849;9107.863382049274;9213.90786092144;9327.813443731087;9718.659846905466;2660.640813490154;2668.639733635934;2672.7995400345053;2756.2558109310376;2810.943781124407;969.9175570076482;970.708787363788;978.7797745507204;995.4873240281261;1000.2897942460934;19895.701564476545;20036.499087522927;20326.898365508077;24013.51972960566;25284;1009.9444530550743;1024.9180065594735;1030.2273295028729;1030.4538594544904;1034.9586016559253;11422.828657570142;11502.827457588142;11587.362695051854;11689.766204676029;11698.70753231176;475076;479840;481828;493968;494312;118862;124481.37759311145;128226;130465.34767326103;131303.34348328196;1241.0567968344124;1259.6472987563498;1275.9808602870964;1282.7113899372753;1287.4914408808468;18579.44261672152;18730.25078996825;19017.904910475358;19071.332503362315;19071.71392429087;59406.40593594182;60443;60926.69536652289;61615.38384616096;62705.68647156735;79176.60411697904;79503;79747;80389.59805200937;81239;12059.67167847398;12593.748125037631;13033.087683862039;14442.422303107758;14893.329800158803;19150.7127393092;19480.415587532396;23319.883400582887;23925.88037059873;24395.878020609784;18602.34891778781;18687.906560467654;18857.43427697171;18979.24083036663;18993.145308460877;16910.40813571519;17044.659106818042;17570.472885813448;17603.82396176022;17665.47003589843;19053.904730476257;19259.22963081517;19322.903385483543;19374.515637109183;19401.514962125497;14829.777553336924;14839.851601483848;14848.62878428048;14854.702905942037;14895.106293622417;17297.04866232319;17422.912885435493;17529.211185496428;18711.345102921332;18841.71737423967 17:29:04 INFO - PID 4407 | |3;mootools.html;1337.277869950227;1400.0899415380002;1422.0898624880076;1423.6013916103495;1434.5481173430362;12359.960606499917;14002.789958150623;14557.70884582309;14568.271586420677;14623.634409139777;12386.938065309661;13616.863831361683;14727.558173254793;14769.483068092619;14770.11379317239;2642.973570264297;2702.9459410811774;2703.189043287016;2703.2025552461973;2706.905258315959;2500.812439067068;2594.5459544579717;2595.779358754499;2636.7363263673574;2732.7130651281627;2674.772644325225;2677.0897894715795;2687.6371689821917;2709.593560965861;2710;2560.2959186223843;2591.961120583193;2619.9607005894927;2620.2532278300687;2620.554505734011;2555.6038813983864;2573.4081161332997;2594.4811037792515;2595.6236345729953;2598.740125987405;13488;13586.456541738318;13625.114367566175;14032.43870245189;14232.92883535586;7294.671739771723;7561;7572.697092116308;7576.052993375828;7584.051993500812;4997.6501644884875;5112.795488180468;5158.587313014949;5216.843494695166;5345.732713364317;1919.2610844824842;1956.491312258823;1963.2675789816003;1994.0926878270498;2002.9499262518407;23185.53628927429;23406.882965585064;23670.64494032591;23673;23713;6851.280615535297;6880.31196880318;6933.479989000845;7070.964645176844;7083.220845706968;19057.61884762297;19070.33253836109;19078.713819292443;19089.90455047716;19099.427017189508;7655.0813902332075;7720.15078341382;7745.535267883833;7771.261730135628;7776.455648104691;6807.965960170168;6982.685779139951;7010.123734533184;7026.754063607749;7030.156381234285;5080.110980578384;5352.384475785257;5476.780928762884;5535.501804837503;5547.583931205095;1510.7960425342437;1715.759793628893;1742.477256822948;1759.8240175982298;1769.8495627871519;898.6899519665704;916.6058594804165;922.6263363337858;928.9071092890792;934.9859752103724;8258.8348233035;8392.83214335722;8449.366297527587;8456.281216096577;8521.57392130391;996.7059717383389;998.6604554451376;1006.6879267426974;1007.4408703169811;1027.9794404111876;4581.770911454414;4705;4842.273658951185;4889.413270407503;5094.745262736848;18720;19187.136578853984;19382.903085484766;19602.21591136367;19629;19233.326833560757;19473.31843385503;19499.610007799765;19502.512437189183;19513.80486195148;9999;10364.5335959882;10424.218183636258;10495.895041049493;10522.263441559171;8144.59277036134;8159.632816523389;8267.958660206661;8274.834503310021;8395.202455766692;16952.997731338568;16968.981861088374;16981.235844386785;17289.82710172882;17351;17273.740893886352;17334.653306934044;17398.73901891498;17429.47711568655;17636.294548217928;11059.115270778157;11367.374794386387;11397.487113079766;11422.942885285853;11424.428778560872;11808;12935.547255846;13036.934815325863;13039.348032598142;13052.804207937079;10554.366737995744;10821.124157479713;10914.563417463212;11031.282966607145;11161.218714689894;10412.791744164922;10419.687409377731;10491.527881245209;10530;10716;9704.75738106553;9829.705108846745;9834.75413114678;9838.114569688762;9840.753981150243;6987.161540615159;7054.753083642048;7072;7133.857322853618;7156.964215178891;7262.818429539304;7287.635618219174;7342;7353.191148973716;7416.48084634092 17:29:04 INFO - PID 4407 | |4;prototype.html;488.77049781275196;489.50049997619175;505.65615381540584;517.7954707890383;522.8013354925129;6196.59722118062;6200.441960223579;6306.558540902127;6329.113924050631;6382.234131904166;6067.120267561201;6261.436470717635;6270.363189350177;6284.780032698856;6317.620942743439;5168.767405466763;5196.92204616931;5213.348331458567;5219.5041471060185;5232.633715639907;5074.568661663763;5218.93851928488;5231.398389185235;5240.423553409122;5243.973780131113;5181.507756763121;5187.429382767892;5192.8701782455555;5199.740012999354;5234.555062819665;5201.791928322881;5203.791848326063;5206.765695543671;5212.635115541913;5213.139831927765;4862.635302352338;4864.781084851154;4871.512848715136;4875.707457552523;4877.5366340197625;6249.250089989185;6256.87486250277;6258.370145315706;6264.248290205204;6274.811755647338;5896.587238893279;5923.7926672566255;6034.155218269402;6045.33501314855;6071.574989750719;6646.335366463315;6649.800505984828;6666;6726.477570910908;6799.89203346959;5283.365996080456;5284.894302113936;5308.628396012281;5316.787328506855;5328.413874473805;6899.75850845218;7021.22766495685;7023.5785852848485;7089.220185779612;7145.321194486515;5427.810026649127;5436.891262174734;5444.727763611803;5491.36849262332;5508.504234618904;5545.889082218414;5548.4174161713245;5555.9443705695785;5567.3040869891265;5568.582356323292;5907.940920590739;5950.761969521171;5989.850253743692;6001.279846418458;6001.639901605918;6660.56706314088;6660.933390666129;6665.200175978914;6710.832229194211;6739;1802.8737988340952;1853.3791179954596;1855.8329750322537;1924.6920492721097;1944.7180158876884;1409.7251036048078;1430.2276770543997;1437.3100911562508;1448.5654303708845;1453.781932710102;5714.914276285859;5772.740226689809;5778.335491418541;5796.710164491758;5798.057650686983;6593.934060659428;6659.2008958925235;6697.028930805103;6740.831479213059;6755.088063111515;5795.971020144872;5840.328362238312;5877.206577112035;5889.057750759944;5902.675352855548;3610.945835812465;3624.2998965770144;3632.418812989909;3643.6720695137565;3653.1780349421697;6652.401283884474;6657.201135863727;6686.431653309523;6724.789537883228;6752.189737231465;7021.684024218923;7043.013978043078;7059.258777828357;7059.717611295593;7084.256153103953;6619.139511863427;6649.800505984828;6657.40083392497;6671.566348187353;6684.565503242274;6856.382925536725;6875.312468753084;6890.655467226718;6904.203797462036;6909.89635155463;6763.560368576027;6767.966160169168;6812.943993680988;6830.45356371499;6860.931390686029;6569.37590928861;6622.801315960529;6641.667916604054;6645.833854153491;6663.267040625624;5010.624203184703;5017.67385119966;5045.585113026103;5047.4700156484505;5049.570786483043;6760.492963027891;6832.760853370109;6838.589684618939;6858.931410685829;6916;6574.4411725002;6632.336766323522;6661.966690166518;6669.566478178903;6684.866302674017;6535.934640653532;6561.507886908405;6585.176852893388;6617.536772425884;6700.2964688708935;6156.476699480594;6162.630242185483;6383.36166383358;6463.547551671338;6473.967630161819;6498.707558159799;6514.739410423719;6534.673266336759;6617.272100069084;6617.735290588515;6312.905306420315;6319.936800632118;6361.491080713625;6378.77674281398;6402.519811014099 17:29:04 INFO - PID 4407 | |5;yui.html;909.7225346269386;910.8998010218877;928.535732133933;934.1592566689978;934.8457504511756;18720.81279187208;19620.117094730736;19628.018599070045;19631.411057668258;19706.408807735774;20599.897000515015;22302;22367.888160559214;22462.438439039033;22537.436564085907;988.6836212412031;1004.3170643962103;1006.8187726209279;1008.1027885182194;1009.0464511037073;967.4147140979711;1026.8767747870265;1033.7984093101848;1041.4792603698152;1073.8335557988523;1085.8316960871077;1088.6407485529755;1088.8149014667526;1089.4933855757072;1092.1153865369076;1083.766990097125;1084.3656460970342;1085.8371244313378;1085.9782804343947;1086.5165001574303;800.371708209059;800.8838718385801;801.1988011988012;801.4309840013598;802.5906787538339;20143.597128057358;20379.79620203794;20433.4891627709;20499.590008199903;20688.275910343065;21973.67039494409;21999.890000549894;22031.889840550855;22116.557668846694;22425.887870560706;16910.915445422816;16965.491035268962;17052;17081.829181708148;17335.13324333791;3612.837422316002;3617.9095522611883;3637.9636203638156;3638.3632864248652;3640.1809592841664;133222;133750;134012;134041;138212.30893845568;16781.496555103367;16851.74722379165;17369.392071277445;17476.650466990588;17494;30628;30981;31717;31796;31866.20334491656;9043.728688139367;9065.77335566603;9226.953865230631;9230.169284764403;9325.673601423918;3681.834317455721;4359.128174365076;4429.557044295545;4436.711613745097;4440.777961101932;4478.9552104478535;6020.668863212565;6211.254649442096;6253.6873156342;6253.937460625336;1163.784699830534;1186.5253898440692;1194.9940250298694;1217.7320989382322;1219.664592237142;580.7067430947344;583.748987935192;595.2916029924376;613.4693490131094;616.442119881508;10361.63734269297;10390.960903909549;10715.142788576895;11312.71718207035;11620.418979051014;579.756502269048;581.1950448628595;583.3641330949201;590.9763609455574;600.1388008208235;5168.431472538018;5225.137852254412;5292.8147514837565;5326.44072372403;5406.432324605938;109033.45483272533;113000;113223.43388283171;113466.43266783778;118379.40810296066;91938.70153246222;102206.48896755617;105510;107029.46485267524;108838.45580772046;82062.58968705118;83702;84183;87637.1236287629;88117.55941220382;11571.6528504145;11691.298522088702;11964.282143071443;12061.638150855488;12216.719344964109;37676.2464750709;38584.80707596444;38661.22677546489;39149.2170156601;40603.59396405998;49792.502074980235;50139.74930125326;50420;50458.74770626123;51585.22622160595;16725.330986760393;16751.07869067213;16778.993260404415;16856.831431685525;17091.230894609525;18626.348077817212;18739.156737947098;18826.811731883055;18845.434636960912;18906;17009.404670836462;17231.05229212405;17245.65508689844;17521.474355769347;18180.81819181791;16751.74872376891;16869.325226990783;17329.913350433166;17349.73975390345;18429.170687318834;16907.239174236944;17110.401135960183;17157.91421042887;17163.742543862103;17256.913715431343;12103.75792484163;12188.146829721834;12253.69365765863;12808.6797830055;13027.869721302666;12046.638600841989;12071.456784444897;12184.634460966185;12244.326562039174;12393.75212495727 17:29:04 INFO - PID 4407 | __end_tp_report 17:29:04 INFO - PID 4407 | __start_cc_report 17:29:04 INFO - PID 4407 | _x_x_mozilla_cycle_collect,365 17:29:04 INFO - PID 4407 | __end_cc_report 17:29:04 INFO - PID 4407 | __startTimestamp1502584144031__endTimestamp 17:29:04 INFO - PID 4407 | 17:29:04 INFO - PID 4407 | ------- Summary: start ------- 17:29:04 INFO - PID 4407 | Number of tests: 6 17:29:04 INFO - PID 4407 | 17:29:04 INFO - PID 4407 | [#0] dojo.html Cycles:165 Average:6195.08 Median:6438.90 stddev:741.97 (11.5%) stddev-sans-first:704.68 17:29:04 INFO - PID 4407 | Values: 3147.8 3192.6 3197.5 3197.7 3230.8 6234.6 6298.0 6319.7 6362.6 6371.2 6176.9 6244.1 6270.3 6270.4 6331.2 5667.8 5694.4 5720.4 5752.6 5762.3 5656.7 5712.4 5722.7 5726.2 5762.6 5739.9 5822.4 5822.7 5831.6 5846.1 5551.6 5677.0 5763.0 5787.7 5851.3 5590.5 5655.1 5682.6 5721.7 5774.2 6142.0 6226.7 6229.0 6286.9 6313.3 6587.0 6657.0 6680.8 6692.6 6821.9 6768.7 6775.6 6832.0 6848.3 6850.9 6246.7 6315.9 6365.7 6369.2 6369.8 6903.5 6907.9 6911.0 7012.1 7020.4 6470.6 6472.2 6553.4 6628.4 6706.4 6714.8 6729.1 6734.1 6763.5 6764.6 6683.9 6751.1 6764.6 6766.2 6772.3 6706.3 6716.5 6724.9 6815.6 6829.5 5327.8 5378.2 5384.6 5388.4 5401.0 4602.7 4616.8 4632.4 4678.5 4713.1 5711.1 5860.7 5862.3 5870.0 5870.0 6770.0 6793.9 6853.5 6868.2 6884.2 5681.3 5739.3 5778.1 5808.4 5826.8 5846.4 5861.2 5921.3 5926.3 5933.9 6429.3 6468.4 6592.7 6597.3 6600.5 6670.7 6717.3 6748.0 6781.5 6842.5 6592.5 6603.1 6606.9 6622.8 6630.5 6586.2 6721.5 6723.7 6724.6 6761.0 6808.5 6809.9 6886.5 6901.7 6906.4 6404.0 6447.6 6479.8 6539.3 6576.2 6506.3 6514.2 6531.6 6575.0 6588.2 6343.7 6438.9 6460.4 6478.2 6504.1 6476.6 6498.6 6506.2 6527.2 6564.6 6274.4 6274.7 6345.3 6416.7 6449.0 17:29:04 INFO - PID 4407 | 17:29:04 INFO - PID 4407 | [#1] ext.html Cycles:180 Average:38562.73 Median:36367.82 stddev:54306.93 (149.3%) stddev-sans-first:54385.24 17:29:04 INFO - PID 4407 | Values: 797.4 850.3 872.6 875.9 1155.6 31347.7 32253.4 32322.7 42059.8 43133.6 42842.4 43168.4 43247.6 43321.4 43348.1 7602.0 7701.3 7767.3 7819.3 7910.9 7671.3 7772.5 7776.7 7784.2 7785.4 7765.3 7808.3 7810.8 7834.8 7837.9 7719.7 7759.5 7778.2 7779.5 7787.5 7712.2 7722.0 7726.5 7729.9 7730.5 39663.4 39772.6 39773.8 39853.8 39886.6 3505.1 3541.1 3548.7 3549.3 3557.0 31540.2 31668.2 31692.5 31693.4 31781.0 4999.0 5006.3 5037.4 5047.1 5062.2 177647.0 180183.0 180725.1 180960.0 181238.0 49036.5 49070.5 49228.0 49231.0 49305.3 51574.5 51639.7 51888.7 51943.2 52043.7 14162.0 15885.8 16286.7 16287.0 16291.3 11707.5 12984.7 13172.6 13175.2 13192.8 14743.4 14894.8 14898.9 14932.9 15021.8 6007.2 6117.7 6160.8 6173.4 6175.7 983.3 997.8 1003.5 1008.6 1019.6 23251.8 29269.7 29384.7 30097.7 30678.2 1090.0 1090.0 1090.8 1092.6 1098.6 12399.2 12980.5 12980.9 13078.5 13119.6 236134.0 236569.0 237884.0 238535.0 239140.0 49110.8 49483.8 49584.5 49686.5 49739.3 1448.4 1460.0 1470.5 1485.7 1488.2 23273.2 23307.7 23358.5 23485.8 23500.0 39357.4 39396.0 39586.2 39604.6 39614.0 206979.0 207078.0 207488.0 207931.0 208052.0 35243.6 37171.8 39448.8 39546.4 39572.2 47152.0 49034.8 49943.3 49996.8 50079.2 35454.8 35797.3 36364.6 36371.0 36439.3 34660.7 34999.0 35038.1 35085.9 35233.5 38046.4 38131.2 38365.6 38461.6 38557.8 33776.7 34001.7 34158.0 34410.3 34533.5 23816.0 24279.8 24613.6 25062.1 25642.2 17:29:04 INFO - PID 4407 | 17:29:04 INFO - PID 4407 | [#2] jquery.html Cycles:180 Average:35848.39 Median:23969.70 stddev:82718.82 (345.1%) stddev-sans-first:82908.46 17:29:04 INFO - PID 4407 | Values: 571.7 573.1 581.7 582.8 586.6 22288.3 22707.3 23015.4 23128.2 23153.3 26309.3 26933.6 26938.3 27023.9 27158.5 6599.5 6777.3 6780.3 6781.5 6809.0 6016.9 6629.2 6638.7 6644.3 6690.5 6603.2 6611.3 6612.0 6612.8 6614.3 6577.1 6590.5 6604.7 6606.9 6646.9 6123.4 6130.6 6142.8 6142.9 6148.0 29553.4 30202.0 30757.5 30818.5 30841.7 1889.1 1924.2 1941.6 1942.7 1971.4 19037.6 19429.6 20295.9 20458.5 20587.6 2446.7 2467.2 2501.3 2516.0 2531.8 144403.0 151369.0 151900.0 152151.0 152280.2 14373.3 15072.2 15164.6 15272.5 15815.8 24086.0 24101.0 24121.4 24157.9 24165.6 9942.8 9950.2 10063.5 10100.9 10191.3 8998.9 9071.5 9140.1 9310.5 9390.4 7849.0 9107.9 9213.9 9327.8 9718.7 2660.6 2668.6 2672.8 2756.3 2810.9 969.9 970.7 978.8 995.5 1000.3 19895.7 20036.5 20326.9 24013.5 25284.0 1009.9 1024.9 1030.2 1030.5 1035.0 11422.8 11502.8 11587.4 11689.8 11698.7 475076.0 479840.0 481828.0 493968.0 494312.0 118862.0 124481.4 128226.0 130465.3 131303.3 1241.1 1259.6 1276.0 1282.7 1287.5 18579.4 18730.3 19017.9 19071.3 19071.7 59406.4 60443.0 60926.7 61615.4 62705.7 79176.6 79503.0 79747.0 80389.6 81239.0 12059.7 12593.7 13033.1 14442.4 14893.3 19150.7 19480.4 23319.9 23925.9 24395.9 18602.3 18687.9 18857.4 18979.2 18993.1 16910.4 17044.7 17570.5 17603.8 17665.5 19053.9 19259.2 19322.9 19374.5 19401.5 14829.8 14839.9 14848.6 14854.7 14895.1 17297.0 17422.9 17529.2 18711.3 18841.7 17:29:04 INFO - PID 4407 | 17:29:04 INFO - PID 4407 | [#3] mootools.html Cycles:180 Average:8868.70 Median:2582.68 stddev:6083.27 (235.5%) stddev-sans-first:6074.01 17:29:04 INFO - PID 4407 | Values: 1337.3 1400.1 1422.1 1423.6 1434.5 12360.0 14002.8 14557.7 14568.3 14623.6 12386.9 13616.9 14727.6 14769.5 14770.1 2643.0 2702.9 2703.2 2703.2 2706.9 2500.8 2594.5 2595.8 2636.7 2732.7 2674.8 2677.1 2687.6 2709.6 2710.0 2560.3 2592.0 2620.0 2620.3 2620.6 2555.6 2573.4 2594.5 2595.6 2598.7 13488.0 13586.5 13625.1 14032.4 14232.9 7294.7 7561.0 7572.7 7576.1 7584.1 4997.7 5112.8 5158.6 5216.8 5345.7 1919.3 1956.5 1963.3 1994.1 2002.9 23185.5 23406.9 23670.6 23673.0 23713.0 6851.3 6880.3 6933.5 7071.0 7083.2 19057.6 19070.3 19078.7 19089.9 19099.4 7655.1 7720.2 7745.5 7771.3 7776.5 6808.0 6982.7 7010.1 7026.8 7030.2 5080.1 5352.4 5476.8 5535.5 5547.6 1510.8 1715.8 1742.5 1759.8 1769.8 898.7 916.6 922.6 928.9 935.0 8258.8 8392.8 8449.4 8456.3 8521.6 996.7 998.7 1006.7 1007.4 1028.0 4581.8 4705.0 4842.3 4889.4 5094.7 18720.0 19187.1 19382.9 19602.2 19629.0 19233.3 19473.3 19499.6 19502.5 19513.8 9999.0 10364.5 10424.2 10495.9 10522.3 8144.6 8159.6 8268.0 8274.8 8395.2 16953.0 16969.0 16981.2 17289.8 17351.0 17273.7 17334.7 17398.7 17429.5 17636.3 11059.1 11367.4 11397.5 11422.9 11424.4 11808.0 12935.5 13036.9 13039.3 13052.8 10554.4 10821.1 10914.6 11031.3 11161.2 10412.8 10419.7 10491.5 10530.0 10716.0 9704.8 9829.7 9834.8 9838.1 9840.8 6987.2 7054.8 7072.0 7133.9 7157.0 7262.8 7287.6 7342.0 7353.2 7416.5 17:29:04 INFO - PID 4407 | 17:29:04 INFO - PID 4407 | [#4] prototype.html Cycles:180 Average:5667.73 Median:6253.06 stddev:1532.95 (24.5%) stddev-sans-first:1487.14 17:29:04 INFO - PID 4407 | Values: 488.8 489.5 505.7 517.8 522.8 6196.6 6200.4 6306.6 6329.1 6382.2 6067.1 6261.4 6270.4 6284.8 6317.6 5168.8 5196.9 5213.3 5219.5 5232.6 5074.6 5218.9 5231.4 5240.4 5244.0 5181.5 5187.4 5192.9 5199.7 5234.6 5201.8 5203.8 5206.8 5212.6 5213.1 4862.6 4864.8 4871.5 4875.7 4877.5 6249.3 6256.9 6258.4 6264.2 6274.8 5896.6 5923.8 6034.2 6045.3 6071.6 6646.3 6649.8 6666.0 6726.5 6799.9 5283.4 5284.9 5308.6 5316.8 5328.4 6899.8 7021.2 7023.6 7089.2 7145.3 5427.8 5436.9 5444.7 5491.4 5508.5 5545.9 5548.4 5555.9 5567.3 5568.6 5907.9 5950.8 5989.9 6001.3 6001.6 6660.6 6660.9 6665.2 6710.8 6739.0 1802.9 1853.4 1855.8 1924.7 1944.7 1409.7 1430.2 1437.3 1448.6 1453.8 5714.9 5772.7 5778.3 5796.7 5798.1 6593.9 6659.2 6697.0 6740.8 6755.1 5796.0 5840.3 5877.2 5889.1 5902.7 3610.9 3624.3 3632.4 3643.7 3653.2 6652.4 6657.2 6686.4 6724.8 6752.2 7021.7 7043.0 7059.3 7059.7 7084.3 6619.1 6649.8 6657.4 6671.6 6684.6 6856.4 6875.3 6890.7 6904.2 6909.9 6763.6 6768.0 6812.9 6830.5 6860.9 6569.4 6622.8 6641.7 6645.8 6663.3 5010.6 5017.7 5045.6 5047.5 5049.6 6760.5 6832.8 6838.6 6858.9 6916.0 6574.4 6632.3 6662.0 6669.6 6684.9 6535.9 6561.5 6585.2 6617.5 6700.3 6156.5 6162.6 6383.4 6463.5 6474.0 6498.7 6514.7 6534.7 6617.3 6617.7 6312.9 6319.9 6361.5 6378.8 6402.5 17:29:04 INFO - PID 4407 | 17:29:04 INFO - PID 4407 | [#5] yui.html Cycles:180 Average:23526.07 Median:18836.12 stddev:32878.15 (174.5%) stddev-sans-first:32926.52 17:29:04 INFO - PID 4407 | Values: 909.7 910.9 928.5 934.2 934.8 18720.8 19620.1 19628.0 19631.4 19706.4 20599.9 22302.0 22367.9 22462.4 22537.4 988.7 1004.3 1006.8 1008.1 1009.0 967.4 1026.9 1033.8 1041.5 1073.8 1085.8 1088.6 1088.8 1089.5 1092.1 1083.8 1084.4 1085.8 1086.0 1086.5 800.4 800.9 801.2 801.4 802.6 20143.6 20379.8 20433.5 20499.6 20688.3 21973.7 21999.9 22031.9 22116.6 22425.9 16910.9 16965.5 17052.0 17081.8 17335.1 3612.8 3617.9 3638.0 3638.4 3640.2 133222.0 133750.0 134012.0 134041.0 138212.3 16781.5 16851.7 17369.4 17476.7 17494.0 30628.0 30981.0 31717.0 31796.0 31866.2 9043.7 9065.8 9227.0 9230.2 9325.7 3681.8 4359.1 4429.6 4436.7 4440.8 4479.0 6020.7 6211.3 6253.7 6253.9 1163.8 1186.5 1195.0 1217.7 1219.7 580.7 583.7 595.3 613.5 616.4 10361.6 10391.0 10715.1 11312.7 11620.4 579.8 581.2 583.4 591.0 600.1 5168.4 5225.1 5292.8 5326.4 5406.4 109033.5 113000.0 113223.4 113466.4 118379.4 91938.7 102206.5 105510.0 107029.5 108838.5 82062.6 83702.0 84183.0 87637.1 88117.6 11571.7 11691.3 11964.3 12061.6 12216.7 37676.2 38584.8 38661.2 39149.2 40603.6 49792.5 50139.7 50420.0 50458.7 51585.2 16725.3 16751.1 16779.0 16856.8 17091.2 18626.3 18739.2 18826.8 18845.4 18906.0 17009.4 17231.1 17245.7 17521.5 18180.8 16751.7 16869.3 17329.9 17349.7 18429.2 16907.2 17110.4 17157.9 17163.7 17256.9 12103.8 12188.1 12253.7 12808.7 13027.9 12046.6 12071.5 12184.6 12244.3 12393.8 17:29:04 INFO - PID 4407 | -------- Summary: end -------- 17:29:04 INFO - PID 4407 | 17:29:04 INFO - TEST-INFO | 4407: exit 0 17:29:04 INFO - TEST-OK | dromaeo_css | took 1111752ms 17:29:04 INFO - TEST-START | kraken 17:29:04 INFO - Initialising browser for kraken test... 17:29:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47733/getInfo.html -profile /tmp/tmp8H0_v9/profile 17:29:04 INFO - TEST-INFO | started process 4770 (/builds/slave/test/build/application/firefox/firefox http://localhost:47733/getInfo.html) 17:29:10 INFO - TEST-INFO | 4770: exit 0 17:29:10 INFO - Browser initialized. 17:29:10 INFO - Running cycle 1/1 for kraken test... 17:29:10 INFO - TEST-INFO | started process 4984 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8H0_v9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:29:11 INFO - PID 4984 | 17:29:11 INFO - PID 4984 | (/builds/slave/test/build/application/firefox/firefox:5032): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:29:11 INFO - PID 4984 | 17:29:21 INFO - PID 4984 | RSS: Main: 165302272 17:29:21 INFO - PID 4984 | 17:29:24 INFO - PID 4984 | Cycle 1(1): loaded http://localhost:47733/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47733/tests/kraken/driver.html?testName=audio-beat-detection) 17:29:25 INFO - PID 4984 | RSS: Main: 171196416 17:29:25 INFO - PID 4984 | 17:29:27 INFO - PID 4984 | Cycle 1(1): loaded http://localhost:47733/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47733/tests/kraken/driver.html?testName=audio-dft) 17:29:28 INFO - PID 4984 | RSS: Main: 175030272 17:29:28 INFO - PID 4984 | 17:29:31 INFO - PID 4984 | Cycle 1(1): loaded http://localhost:47733/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47733/tests/kraken/driver.html?testName=audio-fft) 17:29:32 INFO - PID 4984 | RSS: Main: 168996864 17:29:32 INFO - PID 4984 | 17:29:34 INFO - PID 4984 | Cycle 1(1): loaded http://localhost:47733/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47733/tests/kraken/driver.html?testName=audio-oscillator) 17:29:34 INFO - PID 4984 | RSS: Main: 169418752 17:29:34 INFO - PID 4984 | 17:29:37 INFO - PID 4984 | Cycle 1(1): loaded http://localhost:47733/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47733/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:29:37 INFO - PID 4984 | RSS: Main: 170283008 17:29:37 INFO - PID 4984 | 17:29:40 INFO - PID 4984 | Cycle 1(1): loaded http://localhost:47733/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47733/tests/kraken/driver.html?testName=imaging-darkroom) 17:29:41 INFO - PID 4984 | RSS: Main: 170995712 17:29:41 INFO - PID 4984 | 17:29:44 INFO - PID 4984 | Cycle 1(1): loaded http://localhost:47733/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47733/tests/kraken/driver.html?testName=imaging-desaturate) 17:29:45 INFO - PID 4984 | RSS: Main: 171180032 17:29:45 INFO - PID 4984 | 17:29:48 INFO - PID 4984 | Cycle 1(1): loaded http://localhost:47733/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47733/tests/kraken/driver.html?testName=json-parse-financial) 17:29:48 INFO - PID 4984 | RSS: Main: 171462656 17:29:48 INFO - PID 4984 | 17:29:50 INFO - PID 4984 | Cycle 1(1): loaded http://localhost:47733/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47733/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:29:51 INFO - PID 4984 | RSS: Main: 171917312 17:29:51 INFO - PID 4984 | 17:29:52 INFO - PID 4984 | Cycle 1(1): loaded http://localhost:47733/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47733/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:29:53 INFO - PID 4984 | RSS: Main: 172498944 17:29:53 INFO - PID 4984 | 17:29:55 INFO - PID 4984 | Cycle 1(1): loaded http://localhost:47733/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47733/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:29:56 INFO - PID 4984 | RSS: Main: 171986944 17:29:56 INFO - PID 4984 | 17:29:58 INFO - PID 4984 | Cycle 1(1): loaded http://localhost:47733/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47733/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:29:59 INFO - PID 4984 | RSS: Main: 172326912 17:29:59 INFO - PID 4984 | 17:30:02 INFO - PID 4984 | Cycle 1(1): loaded http://localhost:47733/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47733/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:30:03 INFO - PID 4984 | RSS: Main: 172679168 17:30:03 INFO - PID 4984 | 17:30:05 INFO - PID 4984 | Cycle 1(1): loaded http://localhost:47733/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47733/tests/kraken/driver.html?testName=ai-astar) 17:30:05 INFO - PID 4984 | RSS: Main: 173584384 17:30:05 INFO - PID 4984 | 17:30:05 INFO - PID 4984 | __start_tp_report 17:30:05 INFO - PID 4984 | _x_x_mozilla_page_load 17:30:05 INFO - PID 4984 | _x_x_mozilla_page_load_details 17:30:05 INFO - PID 4984 | |i|pagename|runs| 17:30:05 INFO - PID 4984 | |0;ai-astar;99;108;104;110;111;112;109;100;110;109 17:30:05 INFO - PID 4984 | |1;audio-beat-detection;146;118;147;183;121;143;150;128;172;118 17:30:05 INFO - PID 4984 | |2;audio-dft;164;163;163;165;165;163;166;165;170;163 17:30:05 INFO - PID 4984 | |3;audio-fft;76;74;76;174;85;76;74;75;154;74 17:30:05 INFO - PID 4984 | |4;audio-oscillator;79;79;130;79;79;78;121;79;79;136 17:30:05 INFO - PID 4984 | |5;imaging-gaussian-blur;115;114;114;115;114;115;115;114;115;114 17:30:05 INFO - PID 4984 | |6;imaging-darkroom;151;150;151;150;150;151;150;151;152;151 17:30:05 INFO - PID 4984 | |7;imaging-desaturate;94;94;94;94;95;94;94;94;94;94 17:30:05 INFO - PID 4984 | |8;json-parse-financial;56;54;54;55;55;54;55;65;54;54 17:30:05 INFO - PID 4984 | |9;json-stringify-tinderbox;55;55;55;56;56;55;55;55;56;55 17:30:05 INFO - PID 4984 | |10;stanford-crypto-aes;79;88;78;78;77;78;78;79;79;85 17:30:05 INFO - PID 4984 | |11;stanford-crypto-ccm;130;135;127;123;122;128;121;119;124;169 17:30:05 INFO - PID 4984 | |12;stanford-crypto-pbkdf2;179;175;183;177;178;178;190;176;179;174 17:30:05 INFO - PID 4984 | |13;stanford-crypto-sha256-iterative;67;68;70;69;68;68;67;68;67;69 17:30:05 INFO - PID 4984 | __end_tp_report 17:30:05 INFO - PID 4984 | __start_cc_report 17:30:05 INFO - PID 4984 | _x_x_mozilla_cycle_collect,971 17:30:05 INFO - PID 4984 | __end_cc_report 17:30:05 INFO - PID 4984 | __startTimestamp1502584205466__endTimestamp 17:30:05 INFO - PID 4984 | 17:30:05 INFO - PID 4984 | ------- Summary: start ------- 17:30:05 INFO - PID 4984 | Number of tests: 14 17:30:05 INFO - PID 4984 | 17:30:05 INFO - PID 4984 | [#0] ai-astar Cycles:10 Average:107.20 Median:110.00 stddev:4.59 (4.2%) stddev-sans-first:3.79 17:30:05 INFO - PID 4984 | Values: 99.0 108.0 104.0 110.0 111.0 112.0 109.0 100.0 110.0 109.0 17:30:05 INFO - PID 4984 | 17:30:05 INFO - PID 4984 | [#1] audio-beat-detection Cycles:10 Average:142.60 Median:146.50 stddev:22.29 (15.2%) stddev-sans-first:23.61 17:30:05 INFO - PID 4984 | Values: 146.0 118.0 147.0 183.0 121.0 143.0 150.0 128.0 172.0 118.0 17:30:05 INFO - PID 4984 | 17:30:05 INFO - PID 4984 | [#2] audio-dft Cycles:10 Average:164.70 Median:165.00 stddev:2.16 (1.3%) stddev-sans-first:2.28 17:30:05 INFO - PID 4984 | Values: 164.0 163.0 163.0 165.0 165.0 163.0 166.0 165.0 170.0 163.0 17:30:05 INFO - PID 4984 | 17:30:05 INFO - PID 4984 | [#3] audio-fft Cycles:10 Average:93.80 Median:75.50 stddev:37.44 (49.6%) stddev-sans-first:39.15 17:30:05 INFO - PID 4984 | Values: 76.0 74.0 76.0 174.0 85.0 76.0 74.0 75.0 154.0 74.0 17:30:05 INFO - PID 4984 | 17:30:05 INFO - PID 4984 | [#4] audio-oscillator Cycles:10 Average:93.90 Median:79.00 stddev:24.48 (31.0%) stddev-sans-first:25.37 17:30:05 INFO - PID 4984 | Values: 79.0 79.0 130.0 79.0 79.0 78.0 121.0 79.0 79.0 136.0 17:30:05 INFO - PID 4984 | 17:30:05 INFO - PID 4984 | [#5] imaging-gaussian-blur Cycles:10 Average:114.50 Median:115.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 17:30:05 INFO - PID 4984 | Values: 115.0 114.0 114.0 115.0 114.0 115.0 115.0 114.0 115.0 114.0 17:30:05 INFO - PID 4984 | 17:30:05 INFO - PID 4984 | [#6] imaging-darkroom Cycles:10 Average:150.70 Median:151.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 17:30:05 INFO - PID 4984 | Values: 151.0 150.0 151.0 150.0 150.0 151.0 150.0 151.0 152.0 151.0 17:30:05 INFO - PID 4984 | 17:30:05 INFO - PID 4984 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 17:30:05 INFO - PID 4984 | Values: 94.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 94.0 94.0 17:30:05 INFO - PID 4984 | 17:30:05 INFO - PID 4984 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:55.00 stddev:3.37 (6.1%) stddev-sans-first:3.57 17:30:05 INFO - PID 4984 | Values: 56.0 54.0 54.0 55.0 55.0 54.0 55.0 65.0 54.0 54.0 17:30:05 INFO - PID 4984 | 17:30:05 INFO - PID 4984 | [#9] json-stringify-tinderbox Cycles:10 Average:55.30 Median:55.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 17:30:05 INFO - PID 4984 | Values: 55.0 55.0 55.0 56.0 56.0 55.0 55.0 55.0 56.0 55.0 17:30:05 INFO - PID 4984 | 17:30:05 INFO - PID 4984 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:79.00 stddev:3.60 (4.6%) stddev-sans-first:3.81 17:30:05 INFO - PID 4984 | Values: 79.0 88.0 78.0 78.0 77.0 78.0 78.0 79.0 79.0 85.0 17:30:05 INFO - PID 4984 | 17:30:05 INFO - PID 4984 | [#11] stanford-crypto-ccm Cycles:10 Average:129.80 Median:127.50 stddev:14.57 (11.4%) stddev-sans-first:15.45 17:30:05 INFO - PID 4984 | Values: 130.0 135.0 127.0 123.0 122.0 128.0 121.0 119.0 124.0 169.0 17:30:05 INFO - PID 4984 | 17:30:05 INFO - PID 4984 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.90 Median:178.50 stddev:4.63 (2.6%) stddev-sans-first:4.91 17:30:05 INFO - PID 4984 | Values: 179.0 175.0 183.0 177.0 178.0 178.0 190.0 176.0 179.0 174.0 17:30:05 INFO - PID 4984 | 17:30:05 INFO - PID 4984 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:0.99 (1.5%) stddev-sans-first:0.97 17:30:05 INFO - PID 4984 | Values: 67.0 68.0 70.0 69.0 68.0 68.0 67.0 68.0 67.0 69.0 17:30:05 INFO - PID 4984 | -------- Summary: end -------- 17:30:05 INFO - PID 4984 | 17:30:05 INFO - PID 4984 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:30:06 INFO - TEST-INFO | 4984: exit 0 17:30:06 INFO - TEST-OK | kraken | took 61510ms 17:30:06 INFO - SUITE-END | took 1173s 17:30:06 INFO - Completed test suite (00:19:33) 17:30:06 INFO - javascript benchmark 17:30:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8605.28021825358, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3147.7953932994355, 3192.5849639546864, 3197.5203719442097, 3197.6642452542474, 3230.7899986500875, 6234.594751341159, 6297.968510157454, 6319.6524191169465, 6362.58643188193, 6371.171747672802, 6176.876462470759, 6244.063390491428, 6270.310265870762, 6270.435660790528, 6331.240251169855, 5667.809759950415, 5694.430556944293, 5720.427957204278, 5752.597318187729, 5762.250907382035, 5656.660600363971, 5712.428757124275, 5722.713864306789, 5726.198332233491, 5762.596618236725, 5739.856503587423, 5822.417758224186, 5822.737976791033, 5831.591788574801, 5846.064629659255, 5551.611387202897, 5677.034904066319, 5763.0202865512965, 5787.681677507735, 5851.297844258695, 5590.496855283044, 5655.066913959192, 5682.6306290091425, 5721.6853073080965, 5774.191613174159, 6141.969290153521, 6226.688665566703, 6229.034499652561, 6286.937130628681, 6313.337099604521, 6587.044878492592, 6657.0, 6680.799576012679, 6692.56498327612, 6821.863562728718, 6768.69540870662, 6775.559588626773, 6832.043513908056, 6848.280930502325, 6850.897236541456, 6246.718897649571, 6315.873682526324, 6365.681715914232, 6369.172007638977, 6369.840753981095, 6903.482238832108, 6907.861842763217, 6910.965445172742, 7012.123484564429, 7020.368166864904, 6470.5794123382775, 6472.223333199952, 6553.377429144223, 6628.403443690091, 6706.396424321737, 6714.832129196711, 6729.1252137221845, 6734.090897728744, 6763.492738044666, 6764.627945462948, 6683.933160668428, 6751.054852320679, 6764.594124352555, 6766.18805743314, 6772.322767723188, 6706.295838936841, 6716.496262780311, 6724.899126513106, 6815.625140617313, 6829.487788415889, 5327.840164795062, 5378.1663842104535, 5384.623076384615, 5388.407275199725, 5401.02781499326, 4602.746848923274, 4616.792244349012, 4632.374629425052, 4678.485366609671, 4713.128071306819, 5711.143328500758, 5860.677662728591, 5862.325832529228, 5869.970650146722, 5869.972754767899, 6770.018347339608, 6793.864122717518, 6853.451723862178, 6868.244493105754, 6884.208316043719, 5681.318241810926, 5739.282589676291, 5778.133280008033, 5808.3901190375245, 5826.776376960801, 5846.444587764156, 5861.238039054895, 5921.28944526651, 5926.259217597801, 5933.910991335133, 6429.260635026938, 6468.3855033771715, 6592.703328350236, 6597.2742998271115, 6600.504962127764, 6670.666466676744, 6717.294684058299, 6747.966260168668, 6781.457483401217, 6842.486813489108, 6592.538522303585, 6603.108580341593, 6606.867862642816, 6622.768203112868, 6630.53586248958, 6586.176727909012, 6721.495887808336, 6723.7310507581105, 6724.630145341857, 6761.0, 6808.455323574201, 6809.8638027238185, 6886.51794374389, 6901.654917254217, 6906.412954898688, 6404.0, 6447.6453795041725, 6479.833629946655, 6539.313372095861, 6576.177977752781, 6506.3493650634555, 6514.218293804774, 6531.608103513614, 6575.046618240424, 6588.242352129375, 6343.682815859281, 6438.90341644885, 6460.353964603502, 6478.1578394808375, 6504.089427480062, 6476.57902236372, 6498.577592456475, 6506.219253689587, 6527.184101987252, 6564.6389448580785, 6274.403931626488, 6274.717637706223, 6345.302016778059, 6416.711247993945, 6448.967755161194], "value": 6139.063452667513, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [797.4378063465258, 850.2687688587813, 872.6204101215969, 875.8773771671968, 1155.5839897636845, 31347.686523134762, 32253.35493290136, 32322.676773232262, 42059.78970105153, 43133.568664313345, 42842.35736463948, 43168.352474712905, 43247.567524324826, 43321.35017974733, 43348.133037339314, 7602.049743782041, 7701.3453856422275, 7767.339776119036, 7819.335356494705, 7910.881336779953, 7671.347935425496, 7772.4947878388, 7776.65005074773, 7784.1826608206175, 7785.41609379296, 7765.301122898938, 7808.258215469521, 7810.843783124335, 7834.764957051269, 7837.882431763528, 7719.72980945667, 7759.45683802134, 7778.183290754446, 7779.533228006338, 7787.532748035108, 7712.228777122243, 7722.0, 7726.536407815493, 7729.884051739228, 7730.458867879251, 39663.40504892429, 39772.60227397718, 39773.80113099445, 39853.80073099645, 39886.60113398887, 3505.1237190702323, 3541.097020259843, 3548.6806187443253, 3549.3256281306467, 3556.968479141046, 31540.211494712585, 31668.208294792585, 31692.524612130837, 31693.36613267745, 31781.04656860298, 4998.9500104999215, 5006.2740902569285, 5037.420696619899, 5047.066292735855, 5062.2406639004075, 177647.0, 180183.0, 180725.0963745186, 180960.0, 181238.0, 49036.50963490391, 49070.509294906595, 49228.0, 49231.0153796922, 49305.26042109371, 51574.48425515772, 51639.74180129075, 51888.74055629773, 51943.220851687256, 52043.73978130161, 14162.0, 15885.761713574306, 16286.674266514603, 16287.0, 16291.348346066261, 11707.531698732126, 12984.67538311531, 13172.60482185536, 13175.209487430784, 13192.802107968388, 14743.41026358955, 14894.776578351333, 14898.925505372405, 14932.925335373471, 15021.849781502046, 6007.189029481052, 6117.724702388403, 6160.815175544741, 6173.351798061229, 6175.722092505848, 983.3313346924017, 997.7954519323521, 1003.4681618742078, 1008.606643409071, 1019.5666841592293, 23251.7674823253, 29269.707302927123, 29384.706152938197, 30097.69902300949, 30678.233044173627, 1089.9564017439372, 1089.9891001090045, 1090.7654854206385, 1092.639428988448, 1098.5770478365728, 12399.194052386567, 12980.545680901123, 12980.935095324463, 13078.542251021168, 13119.606411807661, 236134.0, 236569.0, 237884.0, 238535.0, 239140.0, 49110.75444622754, 49483.75258123686, 49584.50415495871, 49686.50313496891, 49739.25391119136, 1448.3771978049328, 1459.9994024558841, 1470.4853301344444, 1485.6731519065788, 1488.2038109611306, 23273.185438509572, 23307.650385244575, 23358.532829343658, 23485.76514234836, 23500.0, 39357.4417662851, 39396.0, 39586.20827583375, 39604.60395396009, 39614.0, 206979.0, 207078.0, 207488.0, 207931.0, 208052.0, 35243.64756352506, 37171.81414092912, 39448.802755986035, 39546.40680389854, 39572.2085558293, 47152.0, 49034.75482622564, 49943.25085123653, 49996.75001624969, 50079.24881126859, 35454.8227258862, 35797.28405431825, 36364.636353636124, 36371.0, 36439.27121457609, 34660.65339346574, 34999.0, 35038.12404689903, 35085.894846309304, 35233.471497927036, 38046.429303561024, 38131.237375252895, 38365.61634383732, 38461.615383845805, 38557.807210963765, 33776.66223337735, 34001.65998339985, 34158.0, 34410.31179376348, 34533.48199776955, 23816.0, 24279.78601069904, 24613.630795537723, 25062.122825701743, 25642.23073307804], "value": 17666.982415024817, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.6655756382514, 573.1431509892711, 581.7120525339957, 582.8251524542636, 586.6186978463999, 22288.331350059485, 22707.318780436613, 23015.42461438465, 23128.190513332036, 23153.30540083798, 26309.34226644335, 26933.595996060074, 26938.326541836464, 27023.864880675617, 27158.45683086337, 6599.505037122211, 6777.2545020047755, 6780.321967803229, 6781.525293229476, 6808.965955170217, 6016.909746353808, 6629.171353580802, 6638.701258443381, 6644.269130395653, 6690.464762818987, 6603.240627327847, 6611.305812889649, 6612.008198770175, 6612.801615951529, 6614.272430032693, 6577.112089867855, 6590.505712071614, 6604.70278837451, 6606.8678626427445, 6646.900296495556, 6123.418275263843, 6130.570860039799, 6142.84642883927, 6142.907856382158, 6148.0, 29553.408931821457, 30202.0, 30757.538636920464, 30818.53772193419, 30841.691583084106, 1889.1404410992966, 1924.220690620301, 1941.6213838301537, 1942.65032294187, 1971.4479945615108, 19037.61924761497, 19429.611407771907, 20295.898520507304, 20458.488537786525, 20587.58824823495, 2446.681931348931, 2467.235157101304, 2501.3246423465744, 2516.0313279387383, 2531.8227724059325, 144403.0, 151369.0, 151900.0, 152151.0, 152280.2385988063, 14373.281335933161, 15072.24638768079, 15164.620884477976, 15272.465463708717, 15815.841841581667, 24086.0, 24101.0, 24121.396965076012, 24157.879210604187, 24165.637515437284, 9942.80114397708, 9950.213940096784, 10063.496825158712, 10100.898991009995, 10191.286609937382, 8998.910010899937, 9071.501067441222, 9140.085991400807, 9310.53447327631, 9390.436573805593, 7849.0, 9107.863382049274, 9213.90786092144, 9327.813443731087, 9718.659846905466, 2660.640813490154, 2668.639733635934, 2672.7995400345053, 2756.2558109310376, 2810.943781124407, 969.9175570076482, 970.708787363788, 978.7797745507204, 995.4873240281261, 1000.2897942460934, 19895.701564476545, 20036.499087522927, 20326.898365508077, 24013.51972960566, 25284.0, 1009.9444530550743, 1024.9180065594735, 1030.2273295028729, 1030.4538594544904, 1034.9586016559253, 11422.828657570142, 11502.827457588142, 11587.362695051854, 11689.766204676029, 11698.70753231176, 475076.0, 479840.0, 481828.0, 493968.0, 494312.0, 118862.0, 124481.37759311145, 128226.0, 130465.34767326103, 131303.34348328196, 1241.0567968344124, 1259.6472987563498, 1275.9808602870964, 1282.7113899372753, 1287.4914408808468, 18579.44261672152, 18730.25078996825, 19017.904910475358, 19071.332503362315, 19071.71392429087, 59406.40593594182, 60443.0, 60926.69536652289, 61615.38384616096, 62705.68647156735, 79176.60411697904, 79503.0, 79747.0, 80389.59805200937, 81239.0, 12059.67167847398, 12593.748125037631, 13033.087683862039, 14442.422303107758, 14893.329800158803, 19150.7127393092, 19480.415587532396, 23319.883400582887, 23925.88037059873, 24395.878020609784, 18602.34891778781, 18687.906560467654, 18857.43427697171, 18979.24083036663, 18993.145308460877, 16910.40813571519, 17044.659106818042, 17570.472885813448, 17603.82396176022, 17665.47003589843, 19053.904730476257, 19259.22963081517, 19322.903385483543, 19374.515637109183, 19401.514962125497, 14829.777553336924, 14839.851601483848, 14848.62878428048, 14854.702905942037, 14895.106293622417, 17297.04866232319, 17422.912885435493, 17529.211185496428, 18711.345102921332, 18841.71737423967], "value": 12230.63865782727, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1337.277869950227, 1400.0899415380002, 1422.0898624880076, 1423.6013916103495, 1434.5481173430362, 12359.960606499917, 14002.789958150623, 14557.70884582309, 14568.271586420677, 14623.634409139777, 12386.938065309661, 13616.863831361683, 14727.558173254793, 14769.483068092619, 14770.11379317239, 2642.973570264297, 2702.9459410811774, 2703.189043287016, 2703.2025552461973, 2706.905258315959, 2500.812439067068, 2594.5459544579717, 2595.779358754499, 2636.7363263673574, 2732.7130651281627, 2674.772644325225, 2677.0897894715795, 2687.6371689821917, 2709.593560965861, 2710.0, 2560.2959186223843, 2591.961120583193, 2619.9607005894927, 2620.2532278300687, 2620.554505734011, 2555.6038813983864, 2573.4081161332997, 2594.4811037792515, 2595.6236345729953, 2598.740125987405, 13488.0, 13586.456541738318, 13625.114367566175, 14032.43870245189, 14232.92883535586, 7294.671739771723, 7561.0, 7572.697092116308, 7576.052993375828, 7584.051993500812, 4997.6501644884875, 5112.795488180468, 5158.587313014949, 5216.843494695166, 5345.732713364317, 1919.2610844824842, 1956.491312258823, 1963.2675789816003, 1994.0926878270498, 2002.9499262518407, 23185.53628927429, 23406.882965585064, 23670.64494032591, 23673.0, 23713.0, 6851.280615535297, 6880.31196880318, 6933.479989000845, 7070.964645176844, 7083.220845706968, 19057.61884762297, 19070.33253836109, 19078.713819292443, 19089.90455047716, 19099.427017189508, 7655.0813902332075, 7720.15078341382, 7745.535267883833, 7771.261730135628, 7776.455648104691, 6807.965960170168, 6982.685779139951, 7010.123734533184, 7026.754063607749, 7030.156381234285, 5080.110980578384, 5352.384475785257, 5476.780928762884, 5535.501804837503, 5547.583931205095, 1510.7960425342437, 1715.759793628893, 1742.477256822948, 1759.8240175982298, 1769.8495627871519, 898.6899519665704, 916.6058594804165, 922.6263363337858, 928.9071092890792, 934.9859752103724, 8258.8348233035, 8392.83214335722, 8449.366297527587, 8456.281216096577, 8521.57392130391, 996.7059717383389, 998.6604554451376, 1006.6879267426974, 1007.4408703169811, 1027.9794404111876, 4581.770911454414, 4705.0, 4842.273658951185, 4889.413270407503, 5094.745262736848, 18720.0, 19187.136578853984, 19382.903085484766, 19602.21591136367, 19629.0, 19233.326833560757, 19473.31843385503, 19499.610007799765, 19502.512437189183, 19513.80486195148, 9999.0, 10364.5335959882, 10424.218183636258, 10495.895041049493, 10522.263441559171, 8144.59277036134, 8159.632816523389, 8267.958660206661, 8274.834503310021, 8395.202455766692, 16952.997731338568, 16968.981861088374, 16981.235844386785, 17289.82710172882, 17351.0, 17273.740893886352, 17334.653306934044, 17398.73901891498, 17429.47711568655, 17636.294548217928, 11059.115270778157, 11367.374794386387, 11397.487113079766, 11422.942885285853, 11424.428778560872, 11808.0, 12935.547255846, 13036.934815325863, 13039.348032598142, 13052.804207937079, 10554.366737995744, 10821.124157479713, 10914.563417463212, 11031.282966607145, 11161.218714689894, 10412.791744164922, 10419.687409377731, 10491.527881245209, 10530.0, 10716.0, 9704.75738106553, 9829.705108846745, 9834.75413114678, 9838.114569688762, 9840.753981150243, 6987.161540615159, 7054.753083642048, 7072.0, 7133.857322853618, 7156.964215178891, 7262.818429539304, 7287.635618219174, 7342.0, 7353.191148973716, 7416.48084634092], "value": 6490.810135962351, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.77049781275196, 489.50049997619175, 505.65615381540584, 517.7954707890383, 522.8013354925129, 6196.59722118062, 6200.441960223579, 6306.558540902127, 6329.113924050631, 6382.234131904166, 6067.120267561201, 6261.436470717635, 6270.363189350177, 6284.780032698856, 6317.620942743439, 5168.767405466763, 5196.92204616931, 5213.348331458567, 5219.5041471060185, 5232.633715639907, 5074.568661663763, 5218.93851928488, 5231.398389185235, 5240.423553409122, 5243.973780131113, 5181.507756763121, 5187.429382767892, 5192.8701782455555, 5199.740012999354, 5234.555062819665, 5201.791928322881, 5203.791848326063, 5206.765695543671, 5212.635115541913, 5213.139831927765, 4862.635302352338, 4864.781084851154, 4871.512848715136, 4875.707457552523, 4877.5366340197625, 6249.250089989185, 6256.87486250277, 6258.370145315706, 6264.248290205204, 6274.811755647338, 5896.587238893279, 5923.7926672566255, 6034.155218269402, 6045.33501314855, 6071.574989750719, 6646.335366463315, 6649.800505984828, 6666.0, 6726.477570910908, 6799.89203346959, 5283.365996080456, 5284.894302113936, 5308.628396012281, 5316.787328506855, 5328.413874473805, 6899.75850845218, 7021.22766495685, 7023.5785852848485, 7089.220185779612, 7145.321194486515, 5427.810026649127, 5436.891262174734, 5444.727763611803, 5491.36849262332, 5508.504234618904, 5545.889082218414, 5548.4174161713245, 5555.9443705695785, 5567.3040869891265, 5568.582356323292, 5907.940920590739, 5950.761969521171, 5989.850253743692, 6001.279846418458, 6001.639901605918, 6660.56706314088, 6660.933390666129, 6665.200175978914, 6710.832229194211, 6739.0, 1802.8737988340952, 1853.3791179954596, 1855.8329750322537, 1924.6920492721097, 1944.7180158876884, 1409.7251036048078, 1430.2276770543997, 1437.3100911562508, 1448.5654303708845, 1453.781932710102, 5714.914276285859, 5772.740226689809, 5778.335491418541, 5796.710164491758, 5798.057650686983, 6593.934060659428, 6659.2008958925235, 6697.028930805103, 6740.831479213059, 6755.088063111515, 5795.971020144872, 5840.328362238312, 5877.206577112035, 5889.057750759944, 5902.675352855548, 3610.945835812465, 3624.2998965770144, 3632.418812989909, 3643.6720695137565, 3653.1780349421697, 6652.401283884474, 6657.201135863727, 6686.431653309523, 6724.789537883228, 6752.189737231465, 7021.684024218923, 7043.013978043078, 7059.258777828357, 7059.717611295593, 7084.256153103953, 6619.139511863427, 6649.800505984828, 6657.40083392497, 6671.566348187353, 6684.565503242274, 6856.382925536725, 6875.312468753084, 6890.655467226718, 6904.203797462036, 6909.89635155463, 6763.560368576027, 6767.966160169168, 6812.943993680988, 6830.45356371499, 6860.931390686029, 6569.37590928861, 6622.801315960529, 6641.667916604054, 6645.833854153491, 6663.267040625624, 5010.624203184703, 5017.67385119966, 5045.585113026103, 5047.4700156484505, 5049.570786483043, 6760.492963027891, 6832.760853370109, 6838.589684618939, 6858.931410685829, 6916.0, 6574.4411725002, 6632.336766323522, 6661.966690166518, 6669.566478178903, 6684.866302674017, 6535.934640653532, 6561.507886908405, 6585.176852893388, 6617.536772425884, 6700.2964688708935, 6156.476699480594, 6162.630242185483, 6383.36166383358, 6463.547551671338, 6473.967630161819, 6498.707558159799, 6514.739410423719, 6534.673266336759, 6617.272100069084, 6617.735290588515, 6312.905306420315, 6319.936800632118, 6361.491080713625, 6378.77674281398, 6402.519811014099], "value": 5221.708760507036, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [909.7225346269386, 910.8998010218877, 928.535732133933, 934.1592566689978, 934.8457504511756, 18720.81279187208, 19620.117094730736, 19628.018599070045, 19631.411057668258, 19706.408807735774, 20599.897000515015, 22302.0, 22367.888160559214, 22462.438439039033, 22537.436564085907, 988.6836212412031, 1004.3170643962103, 1006.8187726209279, 1008.1027885182194, 1009.0464511037073, 967.4147140979711, 1026.8767747870265, 1033.7984093101848, 1041.4792603698152, 1073.8335557988523, 1085.8316960871077, 1088.6407485529755, 1088.8149014667526, 1089.4933855757072, 1092.1153865369076, 1083.766990097125, 1084.3656460970342, 1085.8371244313378, 1085.9782804343947, 1086.5165001574303, 800.371708209059, 800.8838718385801, 801.1988011988012, 801.4309840013598, 802.5906787538339, 20143.597128057358, 20379.79620203794, 20433.4891627709, 20499.590008199903, 20688.275910343065, 21973.67039494409, 21999.890000549894, 22031.889840550855, 22116.557668846694, 22425.887870560706, 16910.915445422816, 16965.491035268962, 17052.0, 17081.829181708148, 17335.13324333791, 3612.837422316002, 3617.9095522611883, 3637.9636203638156, 3638.3632864248652, 3640.1809592841664, 133222.0, 133750.0, 134012.0, 134041.0, 138212.30893845568, 16781.496555103367, 16851.74722379165, 17369.392071277445, 17476.650466990588, 17494.0, 30628.0, 30981.0, 31717.0, 31796.0, 31866.20334491656, 9043.728688139367, 9065.77335566603, 9226.953865230631, 9230.169284764403, 9325.673601423918, 3681.834317455721, 4359.128174365076, 4429.557044295545, 4436.711613745097, 4440.777961101932, 4478.9552104478535, 6020.668863212565, 6211.254649442096, 6253.6873156342, 6253.937460625336, 1163.784699830534, 1186.5253898440692, 1194.9940250298694, 1217.7320989382322, 1219.664592237142, 580.7067430947344, 583.748987935192, 595.2916029924376, 613.4693490131094, 616.442119881508, 10361.63734269297, 10390.960903909549, 10715.142788576895, 11312.71718207035, 11620.418979051014, 579.756502269048, 581.1950448628595, 583.3641330949201, 590.9763609455574, 600.1388008208235, 5168.431472538018, 5225.137852254412, 5292.8147514837565, 5326.44072372403, 5406.432324605938, 109033.45483272533, 113000.0, 113223.43388283171, 113466.43266783778, 118379.40810296066, 91938.70153246222, 102206.48896755617, 105510.0, 107029.46485267524, 108838.45580772046, 82062.58968705118, 83702.0, 84183.0, 87637.1236287629, 88117.55941220382, 11571.6528504145, 11691.298522088702, 11964.282143071443, 12061.638150855488, 12216.719344964109, 37676.2464750709, 38584.80707596444, 38661.22677546489, 39149.2170156601, 40603.59396405998, 49792.502074980235, 50139.74930125326, 50420.0, 50458.74770626123, 51585.22622160595, 16725.330986760393, 16751.07869067213, 16778.993260404415, 16856.831431685525, 17091.230894609525, 18626.348077817212, 18739.156737947098, 18826.811731883055, 18845.434636960912, 18906.0, 17009.404670836462, 17231.05229212405, 17245.65508689844, 17521.474355769347, 18180.81819181791, 16751.74872376891, 16869.325226990783, 17329.913350433166, 17349.73975390345, 18429.170687318834, 16907.239174236944, 17110.401135960183, 17157.91421042887, 17163.742543862103, 17256.913715431343, 12103.75792484163, 12188.146829721834, 12253.69365765863, 12808.6797830055, 13027.869721302666, 12046.638600841989, 12071.456784444897, 12184.634460966185, 12244.326562039174, 12393.75212495727], "value": 9031.060050774382, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1529.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 108.0, 104.0, 110.0, 111.0, 112.0, 109.0, 100.0, 110.0, 109.0], "value": 107.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 118.0, 147.0, 183.0, 121.0, 143.0, 150.0, 128.0, 172.0, 118.0], "value": 142.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 163.0, 165.0, 165.0, 163.0, 166.0, 165.0, 170.0, 163.0], "value": 164.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 76.0, 174.0, 85.0, 76.0, 74.0, 75.0, 154.0, 74.0], "value": 93.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 130.0, 79.0, 79.0, 78.0, 121.0, 79.0, 79.0, 136.0], "value": 93.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 115.0, 114.0, 115.0, 115.0, 114.0, 115.0, 114.0], "value": 114.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 151.0, 150.0, 150.0, 151.0, 150.0, 151.0, 152.0, 151.0], "value": 150.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 54.0, 55.0, 55.0, 54.0, 55.0, 65.0, 54.0, 54.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 56.0, 56.0, 55.0, 55.0, 55.0, 56.0, 55.0], "value": 55.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 88.0, 78.0, 78.0, 77.0, 78.0, 78.0, 79.0, 79.0, 85.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 135.0, 127.0, 123.0, 122.0, 128.0, 121.0, 119.0, 124.0, 169.0], "value": 129.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 175.0, 183.0, 177.0, 178.0, 178.0, 190.0, 176.0, 179.0, 174.0], "value": 178.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 70.0, 69.0, 68.0, 68.0, 67.0, 68.0, 67.0, 69.0], "value": 68.1, "unit": "score"}]}]} 17:30:06 INFO - Return code: 0 17:30:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:30:06 INFO - # TBPL SUCCESS # 17:30:06 INFO - Running post-action listener: _package_coverage_data 17:30:06 INFO - Running post-action listener: _resource_record_post_action 17:30:06 INFO - [mozharness: 2017-08-13 00:30:06.772436Z] Finished run-tests step (success) 17:30:06 INFO - Running post-run listener: _resource_record_post_run 17:30:06 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 425984; Write bytes: 446861312; Read time: 64; Write time: 382772 17:30:06 INFO - TinderboxPrint: CPU usage
13.1% 17:30:06 INFO - TinderboxPrint: I/O read bytes / time
425,984 / 64 17:30:06 INFO - TinderboxPrint: I/O write bytes / time
446,861,312 / 382,772 17:30:06 INFO - TinderboxPrint: CPU idle
8,257.1 (86.9%) 17:30:06 INFO - TinderboxPrint: CPU user
1,199.4 (12.6%) 17:30:06 INFO - TinderboxPrint: Swap in / out
0 / 0 17:30:06 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 121155584; Read time: 0; Write time: 114144 17:30:06 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:30:06 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 421888; Write bytes: 325705728; Read time: 52; Write time: 268628 17:30:07 INFO - Running post-run listener: _upload_blobber_files 17:30:07 INFO - Blob upload gear active. 17:30:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:30:07 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 17:30:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:30:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:30:07 INFO - (blobuploader) - INFO - Open directory for files ... 17:30:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:30:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:30:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:30:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:30:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:30:08 INFO - (blobuploader) - INFO - Done attempting. 17:30:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:30:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:30:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:30:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:30:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:30:15 INFO - (blobuploader) - INFO - Done attempting. 17:30:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:30:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:30:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:30:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:30:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:30:16 INFO - (blobuploader) - INFO - Done attempting. 17:30:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:30:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:30:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:30:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:30:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:30:17 INFO - (blobuploader) - INFO - Done attempting. 17:30:17 INFO - (blobuploader) - INFO - Iteration through files over. 17:30:17 INFO - Return code: 0 17:30:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:30:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:30:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1308ac9fbd0ce17aed785d5ff202d3b907a151b902218f21c1e4584ae45ba9c166e5e5eadf4f671493c39341440e3cd76e8f91fb198d7ab583e8ad7b764cd0b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1059a5c3331ec28076939308e200423c4c5dc90d71a22f8063003c79f41ddeacadeeed6cd20581b2b1777bb7581766b6ac78164d78f07e0f80acd4e8f1344693", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/17d480b2a47d039fde62fd1dec4c72d3045b3c30596c318e2842125b63c79337f3500ebd91f69f266f19bf554dfdf022b7f9fdc44fc2ba3a9135ddc14514e851", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e40fbecbd0ea1beec15737fd0ed4c4f1c8b2ba7cb8643993a4231f664d154433fdc6404833a5d5660d149be78d395501eea38fb3f8de330d004bbe780841116"} 17:30:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:30:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:30:17 INFO - Contents: 17:30:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1308ac9fbd0ce17aed785d5ff202d3b907a151b902218f21c1e4584ae45ba9c166e5e5eadf4f671493c39341440e3cd76e8f91fb198d7ab583e8ad7b764cd0b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1059a5c3331ec28076939308e200423c4c5dc90d71a22f8063003c79f41ddeacadeeed6cd20581b2b1777bb7581766b6ac78164d78f07e0f80acd4e8f1344693", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/17d480b2a47d039fde62fd1dec4c72d3045b3c30596c318e2842125b63c79337f3500ebd91f69f266f19bf554dfdf022b7f9fdc44fc2ba3a9135ddc14514e851", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e40fbecbd0ea1beec15737fd0ed4c4f1c8b2ba7cb8643993a4231f664d154433fdc6404833a5d5660d149be78d395501eea38fb3f8de330d004bbe780841116"} 17:30:17 INFO - Running post-run listener: copy_logs_to_upload_dir 17:30:17 INFO - Copying logs to upload dir... 17:30:17 INFO - mkdir: /builds/slave/test/build/upload/logs 17:30:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.763663 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-12 17:30:17.155205) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-12 17:30:17.159067) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1308ac9fbd0ce17aed785d5ff202d3b907a151b902218f21c1e4584ae45ba9c166e5e5eadf4f671493c39341440e3cd76e8f91fb198d7ab583e8ad7b764cd0b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1059a5c3331ec28076939308e200423c4c5dc90d71a22f8063003c79f41ddeacadeeed6cd20581b2b1777bb7581766b6ac78164d78f07e0f80acd4e8f1344693", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/17d480b2a47d039fde62fd1dec4c72d3045b3c30596c318e2842125b63c79337f3500ebd91f69f266f19bf554dfdf022b7f9fdc44fc2ba3a9135ddc14514e851", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e40fbecbd0ea1beec15737fd0ed4c4f1c8b2ba7cb8643993a4231f664d154433fdc6404833a5d5660d149be78d395501eea38fb3f8de330d004bbe780841116"} build_url:https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011281 build_url: 'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1308ac9fbd0ce17aed785d5ff202d3b907a151b902218f21c1e4584ae45ba9c166e5e5eadf4f671493c39341440e3cd76e8f91fb198d7ab583e8ad7b764cd0b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1059a5c3331ec28076939308e200423c4c5dc90d71a22f8063003c79f41ddeacadeeed6cd20581b2b1777bb7581766b6ac78164d78f07e0f80acd4e8f1344693", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/17d480b2a47d039fde62fd1dec4c72d3045b3c30596c318e2842125b63c79337f3500ebd91f69f266f19bf554dfdf022b7f9fdc44fc2ba3a9135ddc14514e851", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e40fbecbd0ea1beec15737fd0ed4c4f1c8b2ba7cb8643993a4231f664d154433fdc6404833a5d5660d149be78d395501eea38fb3f8de330d004bbe780841116"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-12 17:30:17.183940) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:30:17.184269) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1502580332.747250-771404251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004373 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:30:17.266480) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-12 17:30:17.266862) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-12 17:30:17.267159) ========= ========= Total master_lag: 0.17 =========