builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-038 starttime: 1502761675.48 results: success (0) revision: 92f3de33d97f55d54b2baad585b87e76aaa5ec58 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-14 18:47:55.477278) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-14 18:47:55.477726) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-14 18:47:55.478011) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-14 18:47:55.493897) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 18:47:55.494176) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-14 18:47:56-- 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% 42.0M=0s 2017-08-14 18:47:56 (42.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101694 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 18:47:55.610868) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 18:47:55.614197) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019896 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 18:47:55.663676) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 18:47:55.665679) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 92f3de33d97f55d54b2baad585b87e76aaa5ec58 --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 92f3de33d97f55d54b2baad585b87e76aaa5ec58 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-14 18:47:56,234 truncating revision to first 12 chars 2017-08-14 18:47:56,234 Setting DEBUG logging. 2017-08-14 18:47:56,235 attempt 1/10 2017-08-14 18:47:56,235 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/92f3de33d97f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-14 18:47:56,646 unpacking tar archive at: mozilla-central-92f3de33d97f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.737101 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 18:47:56.417545) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-14 18:47:56.417867) ========= script_repo_revision: 92f3de33d97f55d54b2baad585b87e76aaa5ec58 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-14 18:47:56.421294) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-14 18:47:56.421561) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-14 18:47:56.432649) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-14 18:47:56.432943) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:47:57 INFO - MultiFileLogger online at 20170814 18:47:57 in /builds/slave/test 18:47:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:47:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:47:57 INFO - {'append_to_log': False, 18:47:57 INFO - 'base_work_dir': '/builds/slave/test', 18:47:57 INFO - 'blob_upload_branch': 'Firefox', 18:47:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:47:57 INFO - 'branch': 'Firefox', 18:47:57 INFO - 'buildbot_json_path': 'buildprops.json', 18:47:57 INFO - 'code_coverage': False, 18:47:57 INFO - 'config_files': ('talos/linux_config.py',), 18:47:57 INFO - 'default_actions': ('clobber', 18:47:57 INFO - 'read-buildbot-config', 18:47:57 INFO - 'download-and-extract', 18:47:57 INFO - 'populate-webroot', 18:47:57 INFO - 'create-virtualenv', 18:47:57 INFO - 'install', 18:47:57 INFO - 'setup-mitmproxy', 18:47:57 INFO - 'run-tests'), 18:47:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:47:57 INFO - 'disable_ccov_upload': False, 18:47:57 INFO - 'download_minidump_stackwalk': True, 18:47:57 INFO - 'download_symbols': 'ondemand', 18:47:57 INFO - 'enable_stylo': False, 18:47:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:47:57 INFO - 'tooltool.py': '/tools/tooltool.py', 18:47:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:47:57 INFO - '/tools/misc-python/virtualenv.py')}, 18:47:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:47:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:47:57 INFO - 'gecko_profile': False, 18:47:57 INFO - 'gecko_profile_interval': 0, 18:47:57 INFO - 'installer_path': 'installer.exe', 18:47:57 INFO - 'log_level': 'info', 18:47:57 INFO - 'log_name': 'talos', 18:47:57 INFO - 'log_to_console': True, 18:47:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:47:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:47:57 INFO - 'opt_config_files': (), 18:47:57 INFO - 'pip_index': False, 18:47:57 INFO - 'suite': 'dromaeojs-e10s', 18:47:57 INFO - 'system_bits': '32', 18:47:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:47:57 INFO - 'title': 'talos-linux64-ix-038', 18:47:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:47:57 INFO - 'use_talos_json': True, 18:47:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:47:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:47:57 INFO - 'work_dir': 'build'} 18:47:57 INFO - [mozharness: 2017-08-15 01:47:57.107329Z] Running clobber step. 18:47:57 INFO - Running pre-action listener: _resource_record_pre_action 18:47:57 INFO - Running main action method: clobber 18:47:57 INFO - rmtree: /builds/slave/test/build 18:47:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:47:58 INFO - Running post-action listener: _resource_record_post_action 18:47:58 INFO - [mozharness: 2017-08-15 01:47:58.161929Z] Finished clobber step (success) 18:47:58 INFO - [mozharness: 2017-08-15 01:47:58.162035Z] Running read-buildbot-config step. 18:47:58 INFO - Running pre-action listener: _resource_record_pre_action 18:47:58 INFO - Running main action method: read_buildbot_config 18:47:58 INFO - Using buildbot properties: 18:47:58 INFO - { 18:47:58 INFO - "project": "", 18:47:58 INFO - "product": "firefox", 18:47:58 INFO - "who": "kwierso@gmail.com", 18:47:58 INFO - "installer_path": "public/build/target.tar.bz2", 18:47:58 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:47:58 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:47:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 18:47:58 INFO - "stage_platform": "linux64-pgo", 18:47:58 INFO - "basedir": "/builds/slave/test", 18:47:58 INFO - "buildnumber": 113, 18:47:58 INFO - "platform": "ubuntu64_hw", 18:47:58 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:47:58 INFO - "slavebuilddir": "test", 18:47:58 INFO - "taskId": "GdaPqjU3Re6Y4zf1-XtHNA", 18:47:58 INFO - "branch": "mozilla-central", 18:47:58 INFO - "script_repo_revision": "production", 18:47:58 INFO - "revision": "92f3de33d97f55d54b2baad585b87e76aaa5ec58", 18:47:58 INFO - "slavename": "talos-linux64-ix-038", 18:47:58 INFO - "repo_path": "mozilla-central" 18:47:58 INFO - } 18:47:58 INFO - Finding installer, test and symbols from parent task. 18:47:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GdaPqjU3Re6Y4zf1-XtHNA'}, attempt #1 18:47:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GdaPqjU3Re6Y4zf1-XtHNA'}, attempt #1 18:47:59 INFO - Task dependencies: Qy9IsAQLQR6e-GsALDqk9g 18:47:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g'}, attempt #1 18:47:59 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.tar.bz2 18:47:59 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.test_packages.json 18:47:59 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.crashreporter-symbols.zip 18:47:59 INFO - Running post-action listener: _resource_record_post_action 18:47:59 INFO - [mozharness: 2017-08-15 01:47:59.546969Z] Finished read-buildbot-config step (success) 18:47:59 INFO - [mozharness: 2017-08-15 01:47:59.547190Z] Running download-and-extract step. 18:47:59 INFO - Running pre-action listener: _resource_record_pre_action 18:47:59 INFO - Running main action method: download_and_extract 18:47:59 INFO - mkdir: /builds/slave/test/build/tests 18:47: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')]} 18:47:59 INFO - trying https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.test_packages.json 18:47:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:47:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:48:00 INFO - Downloaded 989 bytes. 18:48:00 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:48:00 INFO - Using the following test package requirements: 18:48:00 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:48:00 INFO - u'common': [u'target.common.tests.zip'], 18:48:00 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:48:00 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:48:00 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:48:00 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:48:00 INFO - u'mozbase': [u'target.common.tests.zip'], 18:48:00 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:48:00 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:48:00 INFO - u'web-platform': [u'target.common.tests.zip', 18:48:00 INFO - u'target.web-platform.tests.tar.gz'], 18:48:00 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:48:00 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:48:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.common.tests.zip 18:48:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:48:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.common.tests.zip into memory 18:48:01 INFO - Content-Length response header: 54628611 18:48:01 INFO - Bytes received: 54628611 18:48:12 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:48:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.common.tests.zip 18:48:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:48:12 INFO - Fetch https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.common.tests.zip into memory 18:48:14 INFO - Content-Length response header: 54628611 18:48:14 INFO - Bytes received: 54628611 18:48:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.talos.tests.zip 18:48:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:48:26 INFO - Fetch https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.talos.tests.zip into memory 18:48:27 INFO - Content-Length response header: 14496050 18:48:27 INFO - Bytes received: 14496050 18:48:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:27 INFO - trying https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.tar.bz2 18:48:27 INFO - Downloading https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:48:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:48:31 INFO - Downloaded 62556908 bytes. 18:48:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.tar.bz2 18:48:31 INFO - mkdir: /builds/slave/test/properties 18:48:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:48:31 INFO - Writing to file /builds/slave/test/properties/build_url 18:48:31 INFO - Contents: 18:48:31 INFO - build_url:https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.tar.bz2 18:48:31 INFO - Running post-action listener: _resource_record_post_action 18:48:31 INFO - Running post-action listener: set_extra_try_arguments 18:48:31 INFO - [mozharness: 2017-08-15 01:48:31.802103Z] Finished download-and-extract step (success) 18:48:31 INFO - [mozharness: 2017-08-15 01:48:31.802335Z] Running populate-webroot step. 18:48:31 INFO - Running pre-action listener: _resource_record_pre_action 18:48:31 INFO - Running main action method: populate_webroot 18:48:31 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:48:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:48:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:48:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:48:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:48:31 INFO - 'tests': ['damp', 'tps']}, 18:48:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:48:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:48:31 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:48:31 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:48:31 INFO - 'other-e10s': {'tests': ['a11yr', 18:48:31 INFO - 'ts_paint', 18:48:31 INFO - 'tpaint', 18:48:31 INFO - 'sessionrestore', 18:48:31 INFO - 'sessionrestore_many_windows', 18:48:31 INFO - 'sessionrestore_no_auto_restore', 18:48:31 INFO - 'tabpaint']}, 18:48:31 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:48:31 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:48:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:48:31 INFO - 'tsvgr_opacity', 18:48:31 INFO - 'tart', 18:48:31 INFO - 'tscrollx', 18:48:31 INFO - 'tsvg_static']}, 18:48:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:48:31 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:48:31 INFO - 'talos_options': ['--mitmproxy', 18:48:31 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:48:31 INFO - '--firstNonBlankPaint'], 18:48:31 INFO - 'tests': ['tp6_google', 18:48:31 INFO - 'tp6_youtube', 18:48:31 INFO - 'tp6_amazon', 18:48:31 INFO - 'tp6_facebook']}, 18:48:31 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:48:31 INFO - 'talos_options': ['--stylo', 18:48:31 INFO - '--mitmproxy', 18:48:31 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:48:31 INFO - '--firstNonBlankPaint'], 18:48:31 INFO - 'tests': ['tp6_google', 18:48:31 INFO - 'tp6_youtube', 18:48:31 INFO - 'tp6_amazon', 18:48:31 INFO - 'tp6_facebook']}, 18:48:31 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:48:31 INFO - 'talos_options': ['--stylo', 18:48:31 INFO - '--stylo-threads=1', 18:48:31 INFO - '--mitmproxy', 18:48:31 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:48:31 INFO - '--firstNonBlankPaint'], 18:48:31 INFO - 'tests': ['tp6_google', 18:48:31 INFO - 'tp6_youtube', 18:48:31 INFO - 'tp6_amazon', 18:48:31 INFO - 'tp6_facebook']}, 18:48:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:48:31 INFO - 'talos_options': ['--xperf_path', 18:48:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:48:31 INFO - 'tests': ['tp5n']}}} 18:48:31 INFO - Running post-action listener: _resource_record_post_action 18:48:31 INFO - [mozharness: 2017-08-15 01:48:31.815230Z] Finished populate-webroot step (success) 18:48:31 INFO - [mozharness: 2017-08-15 01:48:31.815321Z] Running create-virtualenv step. 18:48:31 INFO - Running pre-action listener: _resource_record_pre_action 18:48:31 INFO - Running main action method: create_virtualenv 18:48:31 INFO - Creating virtualenv /builds/slave/test/build/venv 18:48:31 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:48:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:48:31 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:48:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:48:31 INFO - Using real prefix '/usr' 18:48:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:48:32 INFO - Installing distribute.............................................................................................................................................................................................done. 18:48:34 INFO - Installing pip.................done. 18:48:34 INFO - Return code: 0 18:48:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:48:34 INFO - Reading from file tmpfile_stdout 18:48:34 INFO - Output received: 18:48:34 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:48:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:34 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 0xfb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131eb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1321f00>, '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 0x131cba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131d050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:48:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:48:34 INFO - Using env: {'DISPLAY': ':0', 18:48:34 INFO - 'HOME': '/home/cltbld', 18:48:34 INFO - 'LANG': 'en_US.UTF-8', 18:48:34 INFO - 'LANGUAGE': 'en_US:en', 18:48:34 INFO - 'LOGNAME': 'cltbld', 18:48:34 INFO - 'MAIL': '/var/mail/cltbld', 18:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:34 INFO - 'MOZ_NO_REMOTE': '1', 18:48:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:34 INFO - 'NO_EM_RESTART': '1', 18:48:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:34 INFO - 'PWD': '/builds/slave/test', 18:48:34 INFO - 'SHELL': '/bin/bash', 18:48:34 INFO - 'SHLVL': '1', 18:48:34 INFO - 'TERM': 'linux', 18:48:34 INFO - 'TMOUT': '86400', 18:48:34 INFO - 'USER': 'cltbld', 18:48:34 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 18:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:34 INFO - '_': '/tools/buildbot/bin/python'} 18:48:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:48:34 INFO - Cleaning up... 18:48:34 INFO - Return code: 0 18:48:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:34 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 0xfb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131eb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1321f00>, '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 0x131cba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131d050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:48:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:48:34 INFO - Using env: {'DISPLAY': ':0', 18:48:34 INFO - 'HOME': '/home/cltbld', 18:48:34 INFO - 'LANG': 'en_US.UTF-8', 18:48:34 INFO - 'LANGUAGE': 'en_US:en', 18:48:34 INFO - 'LOGNAME': 'cltbld', 18:48:34 INFO - 'MAIL': '/var/mail/cltbld', 18:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:34 INFO - 'MOZ_NO_REMOTE': '1', 18:48:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:34 INFO - 'NO_EM_RESTART': '1', 18:48:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:34 INFO - 'PWD': '/builds/slave/test', 18:48:34 INFO - 'SHELL': '/bin/bash', 18:48:34 INFO - 'SHLVL': '1', 18:48:34 INFO - 'TERM': 'linux', 18:48:34 INFO - 'TMOUT': '86400', 18:48:34 INFO - 'USER': 'cltbld', 18:48:34 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 18:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:34 INFO - '_': '/tools/buildbot/bin/python'} 18:48:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:35 INFO - Downloading/unpacking psutil>=3.1.1 18:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:48:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:48:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:48:36 INFO - Installing collected packages: psutil 18:48:36 INFO - Running setup.py install for psutil 18:48:36 INFO - building 'psutil._psutil_linux' extension 18:48:36 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:48:36 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:48:36 INFO - building 'psutil._psutil_posix' extension 18:48:36 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:48:36 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:48:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:48:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:48:36 INFO - Successfully installed psutil 18:48:36 INFO - Cleaning up... 18:48:36 INFO - Return code: 0 18:48:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:36 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 0xfb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131eb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1321f00>, '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 0x131cba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131d050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:48:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:48:36 INFO - Using env: {'DISPLAY': ':0', 18:48:36 INFO - 'HOME': '/home/cltbld', 18:48:36 INFO - 'LANG': 'en_US.UTF-8', 18:48:36 INFO - 'LANGUAGE': 'en_US:en', 18:48:36 INFO - 'LOGNAME': 'cltbld', 18:48:36 INFO - 'MAIL': '/var/mail/cltbld', 18:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:36 INFO - 'MOZ_NO_REMOTE': '1', 18:48:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:36 INFO - 'NO_EM_RESTART': '1', 18:48:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:36 INFO - 'PWD': '/builds/slave/test', 18:48:36 INFO - 'SHELL': '/bin/bash', 18:48:36 INFO - 'SHLVL': '1', 18:48:36 INFO - 'TERM': 'linux', 18:48:36 INFO - 'TMOUT': '86400', 18:48:36 INFO - 'USER': 'cltbld', 18:48:36 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 18:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:36 INFO - '_': '/tools/buildbot/bin/python'} 18:48:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:48:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:48:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:48:38 INFO - Installing collected packages: mozsystemmonitor 18:48:38 INFO - Running setup.py install for mozsystemmonitor 18:48:38 INFO - Successfully installed mozsystemmonitor 18:48:38 INFO - Cleaning up... 18:48:38 INFO - Return code: 0 18:48:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xfb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131eb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1321f00>, '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 0x131cba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131d050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:48:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:48:38 INFO - Using env: {'DISPLAY': ':0', 18:48:38 INFO - 'HOME': '/home/cltbld', 18:48:38 INFO - 'LANG': 'en_US.UTF-8', 18:48:38 INFO - 'LANGUAGE': 'en_US:en', 18:48:38 INFO - 'LOGNAME': 'cltbld', 18:48:38 INFO - 'MAIL': '/var/mail/cltbld', 18:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:38 INFO - 'MOZ_NO_REMOTE': '1', 18:48:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:38 INFO - 'NO_EM_RESTART': '1', 18:48:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:38 INFO - 'PWD': '/builds/slave/test', 18:48:38 INFO - 'SHELL': '/bin/bash', 18:48:38 INFO - 'SHLVL': '1', 18:48:38 INFO - 'TERM': 'linux', 18:48:38 INFO - 'TMOUT': '86400', 18:48:38 INFO - 'USER': 'cltbld', 18:48:38 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 18:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:38 INFO - '_': '/tools/buildbot/bin/python'} 18:48:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:38 INFO - Downloading/unpacking jsonschema==2.5.1 18:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:48:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:39 INFO - Downloading functools32-3.2.3-2.tar.gz 18:48:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:48:39 INFO - warning: no files found matching '*.txt' 18:48:39 INFO - no previously-included directories found matching 'build' 18:48:39 INFO - no previously-included directories found matching 'dist' 18:48:39 INFO - no previously-included directories found matching '.git*' 18:48:39 INFO - Installing collected packages: jsonschema, functools32 18:48:39 INFO - Running setup.py install for functools32 18:48:40 INFO - warning: no files found matching '*.txt' 18:48:40 INFO - no previously-included directories found matching 'build' 18:48:40 INFO - no previously-included directories found matching 'dist' 18:48:40 INFO - no previously-included directories found matching '.git*' 18:48:40 INFO - Successfully installed jsonschema functools32 18:48:40 INFO - Cleaning up... 18:48:40 INFO - Return code: 0 18:48:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:48:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xfb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131eb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1321f00>, '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 0x131cba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131d050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:48:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:48:40 INFO - Using env: {'DISPLAY': ':0', 18:48:40 INFO - 'HOME': '/home/cltbld', 18:48:40 INFO - 'LANG': 'en_US.UTF-8', 18:48:40 INFO - 'LANGUAGE': 'en_US:en', 18:48:40 INFO - 'LOGNAME': 'cltbld', 18:48:40 INFO - 'MAIL': '/var/mail/cltbld', 18:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:40 INFO - 'MOZ_NO_REMOTE': '1', 18:48:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:40 INFO - 'NO_EM_RESTART': '1', 18:48:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:40 INFO - 'PWD': '/builds/slave/test', 18:48:40 INFO - 'SHELL': '/bin/bash', 18:48:40 INFO - 'SHLVL': '1', 18:48:40 INFO - 'TERM': 'linux', 18:48:40 INFO - 'TMOUT': '86400', 18:48:40 INFO - 'USER': 'cltbld', 18:48:40 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 18:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:40 INFO - '_': '/tools/buildbot/bin/python'} 18:48:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:48:40 INFO - Cleaning up... 18:48:40 INFO - Return code: 0 18:48:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:48:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xfb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131eb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1321f00>, '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 0x131cba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131d050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:48:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:48:40 INFO - Using env: {'DISPLAY': ':0', 18:48:40 INFO - 'HOME': '/home/cltbld', 18:48:40 INFO - 'LANG': 'en_US.UTF-8', 18:48:40 INFO - 'LANGUAGE': 'en_US:en', 18:48:40 INFO - 'LOGNAME': 'cltbld', 18:48:40 INFO - 'MAIL': '/var/mail/cltbld', 18:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:40 INFO - 'MOZ_NO_REMOTE': '1', 18:48:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:40 INFO - 'NO_EM_RESTART': '1', 18:48:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:40 INFO - 'PWD': '/builds/slave/test', 18:48:40 INFO - 'SHELL': '/bin/bash', 18:48:40 INFO - 'SHLVL': '1', 18:48:40 INFO - 'TERM': 'linux', 18:48:40 INFO - 'TMOUT': '86400', 18:48:40 INFO - 'USER': 'cltbld', 18:48:40 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 18:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:40 INFO - '_': '/tools/buildbot/bin/python'} 18:48:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:40 INFO - Downloading/unpacking blobuploader==1.2.4 18:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:41 INFO - Downloading blobuploader-1.2.4.tar.gz 18:48:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:48:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:48:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:42 INFO - Downloading docopt-0.6.1.tar.gz 18:48:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:48:42 INFO - Installing collected packages: blobuploader, requests, docopt 18:48:42 INFO - Running setup.py install for blobuploader 18:48:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:48:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:48:42 INFO - Running setup.py install for requests 18:48:42 INFO - Running setup.py install for docopt 18:48:42 INFO - Successfully installed blobuploader requests docopt 18:48:42 INFO - Cleaning up... 18:48:42 INFO - Return code: 0 18:48:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:42 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 0xfb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131eb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1321f00>, '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 0x131cba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131d050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:48:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:48:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:48:42 INFO - Using env: {'DISPLAY': ':0', 18:48:42 INFO - 'HOME': '/home/cltbld', 18:48:42 INFO - 'LANG': 'en_US.UTF-8', 18:48:42 INFO - 'LANGUAGE': 'en_US:en', 18:48:42 INFO - 'LOGNAME': 'cltbld', 18:48:42 INFO - 'MAIL': '/var/mail/cltbld', 18:48:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:42 INFO - 'MOZ_NO_REMOTE': '1', 18:48:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:42 INFO - 'NO_EM_RESTART': '1', 18:48:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:42 INFO - 'PWD': '/builds/slave/test', 18:48:42 INFO - 'SHELL': '/bin/bash', 18:48:42 INFO - 'SHLVL': '1', 18:48:42 INFO - 'TERM': 'linux', 18:48:42 INFO - 'TMOUT': '86400', 18:48:42 INFO - 'USER': 'cltbld', 18:48:42 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 18:48:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:42 INFO - '_': '/tools/buildbot/bin/python'} 18:48:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:48:43 INFO - Running setup.py (path:/tmp/pip-n6C2rL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:48:43 INFO - Running setup.py (path:/tmp/pip-Fug2Hq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:48:43 INFO - Running setup.py (path:/tmp/pip-Bn8H81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:48:43 INFO - Running setup.py (path:/tmp/pip-H5ElAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:48:43 INFO - Running setup.py (path:/tmp/pip-43xewj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:48:43 INFO - Running setup.py (path:/tmp/pip-3xq0ni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:48:43 INFO - Running setup.py (path:/tmp/pip-7aQ3Id-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:48:43 INFO - Running setup.py (path:/tmp/pip-E8iAhg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:48:43 INFO - Running setup.py (path:/tmp/pip-IISFXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:48:43 INFO - Running setup.py (path:/tmp/pip-Qp9n_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:48:43 INFO - Running setup.py (path:/tmp/pip-AVhpNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:48:43 INFO - Running setup.py (path:/tmp/pip-bJM_0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:48:43 INFO - Running setup.py (path:/tmp/pip-01jXz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:48:44 INFO - Running setup.py (path:/tmp/pip-Y4kAaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:44 INFO - Running setup.py (path:/tmp/pip-KFlNn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:48:44 INFO - Running setup.py (path:/tmp/pip-JwMiwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:48:44 INFO - Running setup.py (path:/tmp/pip-4Y5ntd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:48:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:48:44 INFO - Running setup.py install for manifestparser 18:48:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:48:44 INFO - Running setup.py install for mozcrash 18:48:44 INFO - Running setup.py install for mozdebug 18:48:44 INFO - Running setup.py install for mozdevice 18:48:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:48:44 INFO - Running setup.py install for mozfile 18:48:44 INFO - Running setup.py install for mozhttpd 18:48:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:48:44 INFO - Running setup.py install for mozinfo 18:48:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:48:45 INFO - Running setup.py install for mozInstall 18:48:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:48:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:48:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:48:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:48:45 INFO - Running setup.py install for mozleak 18:48:45 INFO - Running setup.py install for mozlog 18:48:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:48:45 INFO - Running setup.py install for moznetwork 18:48:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:48:45 INFO - Running setup.py install for mozprocess 18:48:45 INFO - Running setup.py install for mozprofile 18:48:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:48:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:48:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:48:45 INFO - Running setup.py install for mozrunner 18:48:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:48:45 INFO - Running setup.py install for mozscreenshot 18:48:45 INFO - Running setup.py install for moztest 18:48:45 INFO - Running setup.py install for mozversion 18:48:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:48:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:48:46 INFO - Cleaning up... 18:48:46 INFO - Return code: 0 18:48:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:48:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:46 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 0xfb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131eb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1321f00>, '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 0x131cba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131d050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:48:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:48:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:48:46 INFO - Using env: {'DISPLAY': ':0', 18:48:46 INFO - 'HOME': '/home/cltbld', 18:48:46 INFO - 'LANG': 'en_US.UTF-8', 18:48:46 INFO - 'LANGUAGE': 'en_US:en', 18:48:46 INFO - 'LOGNAME': 'cltbld', 18:48:46 INFO - 'MAIL': '/var/mail/cltbld', 18:48:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:46 INFO - 'MOZ_NO_REMOTE': '1', 18:48:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:46 INFO - 'NO_EM_RESTART': '1', 18:48:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:46 INFO - 'PWD': '/builds/slave/test', 18:48:46 INFO - 'SHELL': '/bin/bash', 18:48:46 INFO - 'SHLVL': '1', 18:48:46 INFO - 'TERM': 'linux', 18:48:46 INFO - 'TMOUT': '86400', 18:48:46 INFO - 'USER': 'cltbld', 18:48:46 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 18:48:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:46 INFO - '_': '/tools/buildbot/bin/python'} 18:48:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:48:46 INFO - Running setup.py (path:/tmp/pip-56XLHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:48:46 INFO - Running setup.py (path:/tmp/pip-6tqDrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:48:46 INFO - Running setup.py (path:/tmp/pip-H60mmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:48:46 INFO - Running setup.py (path:/tmp/pip-9Vga5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:48:46 INFO - Running setup.py (path:/tmp/pip-NF2oIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:48:46 INFO - Running setup.py (path:/tmp/pip-VAVtrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:48:46 INFO - Running setup.py (path:/tmp/pip-LgIUpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:48:46 INFO - Running setup.py (path:/tmp/pip-HGnnnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:48:46 INFO - Running setup.py (path:/tmp/pip-tzGPg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:48:46 INFO - Running setup.py (path:/tmp/pip-oNIb0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:48:47 INFO - Running setup.py (path:/tmp/pip-C0085L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:48:47 INFO - Running setup.py (path:/tmp/pip-HektVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:48:47 INFO - Running setup.py (path:/tmp/pip-Fuo0D3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:48:47 INFO - Running setup.py (path:/tmp/pip-vLGaMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:47 INFO - Running setup.py (path:/tmp/pip-uZseyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:48:47 INFO - Running setup.py (path:/tmp/pip-zzs13K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:48:47 INFO - Running setup.py (path:/tmp/pip-T_C9E6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:48:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:48 INFO - Downloading blessings-1.6.tar.gz 18:48:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:48:48 INFO - Installing collected packages: blessings 18:48:48 INFO - Running setup.py install for blessings 18:48:48 INFO - Successfully installed blessings 18:48:48 INFO - Cleaning up... 18:48:48 INFO - Return code: 0 18:48:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:48:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:48:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:48:49 INFO - Reading from file tmpfile_stdout 18:48:49 INFO - Current package versions: 18:48:49 INFO - argparse == 1.2.1 18:48:49 INFO - blessings == 1.6 18:48:49 INFO - blobuploader == 1.2.4 18:48:49 INFO - docopt == 0.6.1 18:48:49 INFO - functools32 == 3.2.3-2 18:48:49 INFO - jsonschema == 2.5.1 18:48:49 INFO - manifestparser == 1.1 18:48:49 INFO - mozInstall == 1.13 18:48:49 INFO - mozcrash == 1.0 18:48:49 INFO - mozdebug == 0.1 18:48:49 INFO - mozdevice == 0.51 18:48:49 INFO - mozfile == 1.2 18:48:49 INFO - mozhttpd == 0.7 18:48:49 INFO - mozinfo == 0.10 18:48:49 INFO - mozleak == 0.1 18:48:49 INFO - mozlog == 3.5 18:48:49 INFO - moznetwork == 0.27 18:48:49 INFO - mozprocess == 0.25 18:48:49 INFO - mozprofile == 0.28 18:48:49 INFO - mozrunner == 6.13 18:48:49 INFO - mozscreenshot == 0.1 18:48:49 INFO - mozsystemmonitor == 0.3 18:48:49 INFO - moztest == 0.8 18:48:49 INFO - mozversion == 1.4 18:48:49 INFO - psutil == 3.1.1 18:48:49 INFO - requests == 1.2.3 18:48:49 INFO - wsgiref == 0.1.2 18:48:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:48:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:49 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 0xfb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131eb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1321f00>, '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 0x131cba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131d050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:48:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:48:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:48:49 INFO - Using env: {'DISPLAY': ':0', 18:48:49 INFO - 'HOME': '/home/cltbld', 18:48:49 INFO - 'LANG': 'en_US.UTF-8', 18:48:49 INFO - 'LANGUAGE': 'en_US:en', 18:48:49 INFO - 'LOGNAME': 'cltbld', 18:48:49 INFO - 'MAIL': '/var/mail/cltbld', 18:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:49 INFO - 'MOZ_NO_REMOTE': '1', 18:48:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:49 INFO - 'NO_EM_RESTART': '1', 18:48:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:49 INFO - 'PWD': '/builds/slave/test', 18:48:49 INFO - 'SHELL': '/bin/bash', 18:48:49 INFO - 'SHLVL': '1', 18:48:49 INFO - 'TERM': 'linux', 18:48:49 INFO - 'TMOUT': '86400', 18:48:49 INFO - 'USER': 'cltbld', 18:48:49 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 18:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:49 INFO - '_': '/tools/buildbot/bin/python'} 18:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:48:49 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:48:50 INFO - Installing collected packages: simplejson 18:48:50 INFO - Running setup.py install for simplejson 18:48:50 INFO - building 'simplejson._speedups' extension 18:48:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 18:48:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:48:51 INFO - Successfully installed simplejson 18:48:51 INFO - Cleaning up... 18:48:51 INFO - Return code: 0 18:48:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:51 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 0xfb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x117da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x131eb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1321f00>, '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 0x131cba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131d050>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:48:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:48:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:48:51 INFO - Using env: {'DISPLAY': ':0', 18:48:51 INFO - 'HOME': '/home/cltbld', 18:48:51 INFO - 'LANG': 'en_US.UTF-8', 18:48:51 INFO - 'LANGUAGE': 'en_US:en', 18:48:51 INFO - 'LOGNAME': 'cltbld', 18:48:51 INFO - 'MAIL': '/var/mail/cltbld', 18:48:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:51 INFO - 'MOZ_NO_REMOTE': '1', 18:48:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:51 INFO - 'NO_EM_RESTART': '1', 18:48:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:51 INFO - 'PWD': '/builds/slave/test', 18:48:51 INFO - 'SHELL': '/bin/bash', 18:48:51 INFO - 'SHLVL': '1', 18:48:51 INFO - 'TERM': 'linux', 18:48:51 INFO - 'TMOUT': '86400', 18:48:51 INFO - 'USER': 'cltbld', 18:48:51 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 18:48:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:51 INFO - '_': '/tools/buildbot/bin/python'} 18:48:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:48:51 INFO - Cleaning up... 18:48:51 INFO - Return code: 0 18:48:51 INFO - Running post-action listener: _resource_record_post_action 18:48:51 INFO - Running post-action listener: _start_resource_monitoring 18:48:51 INFO - Starting resource monitoring. 18:48:51 INFO - [mozharness: 2017-08-15 01:48:51.839472Z] Finished create-virtualenv step (success) 18:48:51 INFO - [mozharness: 2017-08-15 01:48:51.839999Z] Running install step. 18:48:51 INFO - Running pre-action listener: _resource_record_pre_action 18:48:51 INFO - Running main action method: install 18:48:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:48:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:48:52 INFO - Reading from file tmpfile_stdout 18:48:52 INFO - Detecting whether we're running mozinstall >=1.0... 18:48:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:48:52 INFO - Reading from file tmpfile_stdout 18:48:52 INFO - Output received: 18:48:52 INFO - Usage: mozinstall [options] installer 18:48:52 INFO - Options: 18:48:52 INFO - -h, --help show this help message and exit 18:48:52 INFO - -d DEST, --destination=DEST 18:48:52 INFO - Directory to install application into. [default: 18:48:52 INFO - "/builds/slave/test"] 18:48:52 INFO - --app=APP Application being installed. [default: firefox] 18:48:52 INFO - mkdir: /builds/slave/test/build/application 18:48:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:49:09 INFO - Reading from file tmpfile_stdout 18:49:09 INFO - Output received: 18:49:09 INFO - /builds/slave/test/build/application/firefox/firefox 18:49:09 INFO - Running post-action listener: _resource_record_post_action 18:49:09 INFO - [mozharness: 2017-08-15 01:49:09.956922Z] Finished install step (success) 18:49:09 INFO - [mozharness: 2017-08-15 01:49:09.957053Z] Running setup-mitmproxy step. 18:49:09 INFO - Running pre-action listener: _resource_record_pre_action 18:49:09 INFO - Running main action method: setup_mitmproxy 18:49:09 INFO - Skipping: mitmproxy is not required 18:49:09 INFO - Running post-action listener: _resource_record_post_action 18:49:09 INFO - [mozharness: 2017-08-15 01:49:09.957818Z] Finished setup-mitmproxy step (success) 18:49:09 INFO - [mozharness: 2017-08-15 01:49:09.958042Z] Running run-tests step. 18:49:09 INFO - Running pre-action listener: _resource_record_pre_action 18:49:09 INFO - Running pre-action listener: _set_gcov_prefix 18:49:09 INFO - Running main action method: run_tests 18:49:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:49:09 INFO - Python 2.7.3 18:49:09 INFO - Return code: 0 18:49:09 INFO - grabbing minidump binary from tooltool 18:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:09 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 0x1321f00>, '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 0x131cba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x131d050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:49:09 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:49:09 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:49:09 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:49:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:49:10 INFO - Return code: 0 18:49:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:49:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:49:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:49:10 INFO - ENV: RUST_BACKTRACE is now full 18:49:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:49:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:49:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:49:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-038 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:49:10 INFO - Using env: {'DISPLAY': ':0', 18:49:10 INFO - 'HOME': '/home/cltbld', 18:49:10 INFO - 'LANG': 'en_US.UTF-8', 18:49:10 INFO - 'LANGUAGE': 'en_US:en', 18:49:10 INFO - 'LOGNAME': 'cltbld', 18:49:10 INFO - 'MAIL': '/var/mail/cltbld', 18:49:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:49:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:49:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:10 INFO - 'MOZ_NO_REMOTE': '1', 18:49:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:49:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:10 INFO - 'NO_EM_RESTART': '1', 18:49:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:10 INFO - 'PWD': '/builds/slave/test', 18:49:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:49:10 INFO - 'RUST_BACKTRACE': 'full', 18:49:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:49:10 INFO - 'SHELL': '/bin/bash', 18:49:10 INFO - 'SHLVL': '1', 18:49:10 INFO - 'TERM': 'linux', 18:49:10 INFO - 'TMOUT': '86400', 18:49:10 INFO - 'USER': 'cltbld', 18:49:10 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969', 18:49:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:10 INFO - '_': '/tools/buildbot/bin/python'} 18:49:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:49:10 INFO - mozversion application_buildid: 20170815002832 18:49:10 INFO - mozversion application_changeset: 92f3de33d97f55d54b2baad585b87e76aaa5ec58 18:49:10 INFO - mozversion application_display_name: Nightly 18:49:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:49:10 INFO - mozversion application_name: Firefox 18:49:10 INFO - mozversion application_remotingname: firefox 18:49:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:49:10 INFO - mozversion application_vendor: Mozilla 18:49:10 INFO - mozversion application_version: 57.0a1 18:49:10 INFO - mozversion platform_buildid: 20170815002832 18:49:10 INFO - mozversion platform_changeset: 92f3de33d97f55d54b2baad585b87e76aaa5ec58 18:49:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:49:10 INFO - mozversion platform_version: 57.0a1 18:49:10 INFO - using testdate: 1502761750 18:49:10 INFO - actual date: 1502761750 18:49:10 INFO - starting webserver on 'localhost:33247' 18:49:10 INFO - SUITE-START | Running 2 tests 18:49:10 INFO - TEST-START | dromaeo_css 18:49:10 INFO - Initialising browser for dromaeo_css test... 18:49:10 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33247/getInfo.html -profile /tmp/tmpkyZBqp/profile 18:49:10 INFO - TEST-INFO | started process 19544 (/builds/slave/test/build/application/firefox/firefox http://localhost:33247/getInfo.html) 18:49:17 INFO - TEST-INFO | 19544: exit 0 18:49:17 INFO - Browser initialized. 18:49:17 INFO - Running cycle 1/1 for dromaeo_css test... 18:49:17 INFO - TEST-INFO | started process 19756 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkyZBqp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:49:18 INFO - PID 19756 | 18:49:18 INFO - PID 19756 | (/builds/slave/test/build/application/firefox/firefox:19805): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:49:18 INFO - PID 19756 | 18:49:28 INFO - PID 19756 | RSS: Main: 158408704 18:49:28 INFO - PID 19756 | 18:52:27 INFO - PID 19756 | Cycle 1(1): loaded http://localhost:33247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33247/tests/dromaeo/cssquery-ext.html) 18:52:28 INFO - PID 19756 | RSS: Main: 166330368 18:52:28 INFO - PID 19756 | 18:55:29 INFO - PID 19756 | Cycle 1(1): loaded http://localhost:33247/tests/dromaeo/cssquery-ext.html (next: http://localhost:33247/tests/dromaeo/cssquery-jquery.html) 18:55:30 INFO - PID 19756 | RSS: Main: 164003840 18:55:30 INFO - PID 19756 | 18:58:31 INFO - PID 19756 | Cycle 1(1): loaded http://localhost:33247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33247/tests/dromaeo/cssquery-mootools.html) 18:58:32 INFO - PID 19756 | RSS: Main: 165187584 18:58:32 INFO - PID 19756 | 19:01:34 INFO - PID 19756 | Cycle 1(1): loaded http://localhost:33247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33247/tests/dromaeo/cssquery-prototype.html) 19:01:34 INFO - PID 19756 | RSS: Main: 164052992 19:01:34 INFO - PID 19756 | 19:04:37 INFO - PID 19756 | Cycle 1(1): loaded http://localhost:33247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33247/tests/dromaeo/cssquery-yui.html) 19:04:38 INFO - PID 19756 | RSS: Main: 167227392 19:04:38 INFO - PID 19756 | 19:07:39 INFO - PID 19756 | Cycle 1(1): loaded http://localhost:33247/tests/dromaeo/cssquery-yui.html (next: http://localhost:33247/tests/dromaeo/cssquery-dojo.html) 19:07:40 INFO - PID 19756 | RSS: Main: 168366080 19:07:40 INFO - PID 19756 | 19:07:40 INFO - PID 19756 | __start_tp_report 19:07:40 INFO - PID 19756 | _x_x_mozilla_page_load 19:07:40 INFO - PID 19756 | _x_x_mozilla_page_load_details 19:07:40 INFO - PID 19756 | |i|pagename|runs| 19:07:40 INFO - PID 19756 | |0;dojo.html;3489.7557170998007;3510.455879338703;3527.9647203527966;3533.9646603533965;3556.982215088924;6208.192934918454;6301.621902685836;6311.147995020677;6336.334684858092;6354.205724284465;6207.131001659771;6257.311695713468;6283.748650053993;6288.151099601552;6298.874022519558;5462.20797984292;5490.011797876391;5493.093639549469;5506.834794956158;5571.359293681238;5536.889262214753;5549.722513874311;5553.111502159655;5593.468620481068;5642.266505354298;5530.944690553083;5554.888902221933;5589.161625756128;5646.068398714227;5646.830595082154;5231.581473482112;5365.597580181463;5472.370677372073;5501.587380946445;5586.55307575393;5489.149181876815;5542.501174894239;5556.86107847303;5562.694051827148;5563.554915606782;6076.422739839708;6167.074938759222;6183.072539119124;6192.071189321593;6265.467435268008;6773.966130169318;6799.52403331767;6847.623380714059;6854.520183587151;6863.347981941708;6847.657617119125;6852.657367131624;6936.549124306955;6946.79159625212;6953.826154346131;6199.721012554446;6318.652474113922;6319.5576309658345;6393.872122557523;6405.5836370636225;6965.547239429421;6972;6972.09362782835;6973.79078627642;6984.161900571964;6669.199696036507;6722.899156512656;6728.259891411941;6738.157730283715;6744.7976560703255;6809.0467334573195;6862.3137686230975;6864.897026544606;6877.965610171917;6879.277675844064;6773.051772751819;6773.051772751819;6825.112735344473;6841.281665425158;6849.657517124124;6649.4347980421235;6767.898481522781;6773.119494465688;6773.830654233683;6880.621565813828;4922.064807686528;4967.900641987212;4969.304297398295;4971.527704868032;4978.925316120261;4361.996740749645;4384.47386313638;4403.3174857897075;4415.536368681306;4423.955760442354;6409.294977552466;6419.2296924369375;6457.870842583122;6518.804435866931;6522.608643481406;6834.384905358442;6848.863022739517;6859.897101543481;6869.828254293682;6878.58728476293;6426.678666066678;6503;6510.153680021567;6510.414062734282;6518.152640156749;5468.179773033997;5507.228987941691;5558.694271815089;5575.358833734092;5575.888482230333;7025.332593403618;7160.033395491646;7161.67772450241;7181.820454488679;7237.167725711611;6758.8648227036165;6842.110525631636;6858.485613578901;6863.416609588241;6865.931340686529;7050.083489146378;7146.64266786669;7207.459440541876;7208.96395518019;7227.927720722725;6663.833404164935;6691.5315927884585;6705;6771.6614169292325;6771.86456270862;6783.287754785676;6811.080504131879;6851.554648947803;6912.516123871281;6914.239433662193;6640.701168447335;6696.5647232929705;6705.530612857053;6706.0611514389;6720.294369091175;6507.28419873823;6566.179227596551;6567.047778072249;6592.2089349276475;6744;6538.084668146558;6546.509011824228;6554.213494380705;6577.901331480128;6600.2409722882485;6516.6415847128865;6621.635810030494;6638.17022872141;6677.632730199885;6704.530682852153;6383.872322553616;6434.2922278548385;6438.678066096582;6486.675666216576;6524.478041756743 19:07:40 INFO - PID 19756 | |1;ext.html;897.9551022448876;915.771057235691;944.1596978688966;966.5602151021286;967.6516454076532;43732;44525.332120018225;44562;44629.77685111574;44652.77673611636;40308.79845600775;40506;40530.18939621213;40537.59462405375;40562.79718601403;7131.572105673663;7238.674259658302;7254.202037775841;7256.746013889515;7257.020302259207;6957.0955775749335;6990.510664253505;6993.0908981832545;6994.265602111781;6996.020557121981;6975.546589471694;6996.685149168275;6997.790066298019;7002.19474760404;7027.789166325018;6953.304669533031;6965.268646792065;6984.057152284478;6993.825154371156;6995.265497122805;6495.090687303734;6554.606723596552;6587.571807832475;6590.7693230736695;6602.108715323466;37002.25995480076;37278.81360593207;37290.25419491622;37385.25229495395;37395.439068413994;3402.7447941404494;3422.6748458896363;3431.5367425397503;3448.637893021247;3450.999999999975;28551.85724071387;28553.286167845763;28628.85685571559;28665.85336586534;28745;4641.141388843075;4672.672912896099;4678.6491013174145;4724.126036683225;4725.243960966229;191731;193139;208196;208946;214574.99999999843;53140.73429632827;53260;53373.73313133487;53375.733121334924;53380.73309633427;55635.443645563835;55697.16454253189;55757;55803;55850.72074639601;14972.401103955719;15050.698986020217;15073.849261507465;15076.547703568911;15212.923935380473;12258.754824903452;12271.631851044483;12292.754144917051;12294.262344259372;12385.380730963416;14000.649983750487;14033.438662453387;14055;14064.43742250319;14115.71768564623;5223.921641175385;5269.604779641542;5283.4452382499285;5311.760970756325;5339.359276886798;1054.5254635414094;1058.9364638121738;1061.2147011211803;1062.85651437055;1080.1250986700798;24564.263072107507;24662.50674986526;24697.753022469544;24804.255872323858;24869.62695559568;1145;1177.8527684039495;1181.6514128332162;1183.1303991566192;1185.1644590563633;12896;12988.155769874928;13043.804342934864;13058.542950996669;13096.14875033139;245724;246533;247965;247995;248426;53742.46257537375;53862;53891;53897.46102539003;54332.72833635886;1507.3292384888618;1508.1855797869248;1510.4486862295403;1558.4545409106722;1562.3047743754148;24414.633780493663;29796;29995.550066749453;30570.235744106576;31062.844685776425;55215.72392138014;55542.44457555372;55590.72204638951;55628.16557751718;55664.721676392975;226163;226173;226237;226700;226923;35801.1049723759;37753.81123094477;38212.23575528529;38391.61608383992;38427.42358864563;47740.52259477361;49400.75299623479;49489.25766113583;49636;49915.50084499254;35556.82221588876;36295.27409451743;36416.635833641325;36556.26887462289;36808.26383472262;35242.11894702653;35316;35671.82164089163;35775.46336804898;35863.82068089643;41510.58489415188;41561.58438415577;41664.583354167284;41680.1663966725;41873.1625367497;33877.49183762295;33966.150846229044;34083.97748067562;34320.48519272163;34610.134746631535;25704.871475642503;25925.740742592334;26206.60690089612;26331.473370532098;26467.94128234849 19:07:40 INFO - PID 19756 | |2;jquery.html;576.3199424678877;577.347597215147;580.3007376111785;583.4136692623912;590.4892268188017;22223.666645000318;23335.066597336106;23393;23405.76594234057;23479.178228761997;27033;27534;27568.724312756865;27611.723882761165;27637.44725105502;5987.730552125143;6145.139680444741;6146.600470969395;6147.047207682816;6148.292946311187;5812.331581868097;6283.120363149163;6290.654014029226;6301.369863013708;6304.180456540644;6261.311255761885;6268.686565671721;6270;6272.5922815016875;6273.968630156843;6234.5324100692405;6244.12582238487;6265.937340626627;6266.686665666698;6269.153664255313;5809.593328467009;5831;5831.591788574801;5831.679257640828;5833.358330583633;30299.69700302991;30794.69205307963;30801;31690;31769.6823031769;2301.7698230176848;2331.4754180309296;2341.7424083350816;2343.1447521654472;2343.1798870395396;20151.79848201514;20599.38201853932;20653.17387304506;20709.378718638465;20742.79257207424;2401.771831675988;2423;2429.137656132083;2482.044412901028;2528.6333481645243;155511.2224438882;158180;158823;158856.20571897182;159241;15111.773323400157;15207.239638018054;15259.771103433457;15503.224838758242;16234.918825405797;23976.40058998504;24003.15988940379;24104.51790964171;24111.51776964451;24112.87943560306;10675.46622668879;10717.303375280582;10754.89245107539;10821;10935.125189984783;8531.274841638406;8895.377323587416;10092.949535252423;10136.986301369952;10179.796404072025;9386;9610.90389096114;9642.42145471274;9655.468949207721;10038.799224015625;2586.702529209165;2619.4499155177245;2686.516427043112;2711.8508482033285;2773.5285001549787;1026.1636766035658;1049.8897615750236;1063.7287491689724;1068.5939343049747;1084.2247792828166;21003.369898903056;21013.264535741175;21324.78675213259;21682;21705;1123.3035517978906;1128.9774204515863;1133.8639363276295;1136.210333818005;1142.9085673146128;11818;11819.586314478953;11920.821187682192;11940.940295298467;12084.818727719092;536124;547163;547208;555432;559516;115884.42057789826;118863;119861;123132.38433807953;123639;1216.288471244332;1250.0749445410331;1267.3283159925254;1267.6450593833556;1278.3544310123327;18999.810001899805;19036.53179305755;19040.904795475933;19250.614987700446;19567.70648440303;59814.70092649509;59953.700231498566;60077.098843516505;60760.69619651874;61244;74000.62999684968;84088;86672.56663716893;87317.56341218253;87443;13196.802047969097;13838.723225535634;15034.849651503782;15431.768523471932;15519.301631426388;24405.26784196477;24793.380165496008;24809;24845;24973.251872369485;19956.20175192976;20078.598428031648;20374.28689995843;20388.388348349574;20396;17716.557086072953;17765.911170444066;18168.27326906909;18281.634367312847;18523.35168269104;20474.38576842697;20633.48416289605;20655.690164647243;20886.47783805356;20990.68513972261;15174.696506069597;15228.695426091637;15305.46430874914;15312.387504499695;15335.386584536494;20175.69736453925;20263;20267;20422.79577204209;20510.69233961462 19:07:40 INFO - PID 19756 | |3;mootools.html;1429.9714005719886;1470.2942587557973;1479.423025020243;1499.2878382768183;1501.4969985055013;15688.921555392222;16372.83627163728;16400.67198656026;16420.507384778444;16459.83540164598;16304.918475407607;16689.91655041723;16709.415170469038;16721;16768.74846877295;2722.6868910075345;2776.5418705913603;2777.8611069446547;2779.110684580935;2780.902668406606;2639.5512762830267;2646.7882569394496;2729.6178535005115;2730.303772538;2741.876615552305;2756.104266113521;2760.3789147441867;2775.7501824835763;2786.7770578353784;2787.679416867066;2670.0654770830056;2707.593860920868;2708.4989276983724;2724.7956403269736;2728.8499132547704;2670.9866450667623;2677.263752468067;2693.5555633320478;2693.62289279501;2695;15550.688986220212;15879.126648034353;15905.840941590668;15907.60230994223;15910;7896.842063158705;8215.958920205361;8268.131846156188;8273.296769774577;8276.255137037695;4875.488073752241;5128.692278463267;5170.095233334151;5173;5183.170692689189;1871.4011516314786;1902.5909429472592;1909.5798924236647;1963.2834015584351;1976.4564744695324;25540.233792986055;26283.342916427053;26326.078587249547;28572.285692857637;28682.713172868214;6965.547239429421;6999.12510936133;7006.719731210695;7115.146182458138;7225.819354516074;19248.8075119247;19345.12946917392;19347.322843700404;19348.80651193498;19366.61266774685;8196.221358970888;8315;8376.539290338967;8377.832443351099;8379.45533540318;7539.849203016019;7626.351760100453;7645.426593005547;7652.1582625911105;7810.960945195351;5317.441668624816;5475.835724928259;5478.397376288605;5513.365962914316;5528.585356098229;1549.4731791190825;1560.6644571416973;1572.1981789287545;1576.3221814619824;1579.5656194546361;947.9478628675489;978.1001478639527;979;981.4356744871727;1014.6955913226003;8888.911110888808;9074.183323500916;9136.543172841331;9214.677486287948;9371.156595906265;1066.8666416697913;1070.6306324318098;1073.4471747050275;1103.812351900179;1105.9336439813637;5883.441073098049;5998.7313257645865;6031.909521357183;6173.536984726164;6238.563300568917;22392.77607223907;22790.772092279196;23331;23465.765342346698;23562.882185588962;22596.096156153897;23015.079396823938;23504.529909401717;23641.645375319385;23732.288031359087;11173.497192626352;11524.078073754079;12223.877761222451;12517.248965062125;12524.561640342545;9901.15840153595;9919.801603968024;10018.448985305879;10064;10065.396076235449;19749.605007899474;20137.79862201418;20476.897615511825;20536.897315513328;20828.27101051456;20763.584728305046;20788.27241046556;20876.58246835085;20880;21001.369958901258;11946.223495472766;12964.157329773534;13450.663733406353;13482.52811151605;13515.729685406433;14807.629809254855;14867.776983345042;14901.403943842124;14952.551423457313;14974.176420296988;11484.310941343547;11540.884591153981;11631.127665425296;11773.234739741889;11963.342016188844;12027.097967652286;12033.879661203277;12212.084093693185;12220.694482638006;12294.323812190058;10198.439085850052;10303.845442318221;10440.738981525219;10453;10457.320274182153;8163.3469322452875;8221.876671849808;8264.421490495608;8331.750047498585;8418.326533877398;7917.445778795429;8262.215089566482;8290.46112002718;8371.539565323723;8390.328773697967 19:07:40 INFO - PID 19756 | |4;prototype.html;517.8187634327986;523.277876530388;531.3995185440451;533.3359966841283;538.6633354153652;6151;6166.568340216187;6166.599171053878;6241;6248.343923887989;6029.608075475102;6038.365971572987;6059.454649081582;6121.173641558388;6148.815535533941;4911.533404326583;4926.162552366089;4929.186684197121;4933.629977751665;4940.382452193476;4714.175019371613;4824.1316563018645;4931.284963680264;4935.198652491833;4936.210206366982;4745.477997420281;4753.572178503951;4754.619630429557;4807.375041244633;4824.782884770194;4627.8148874045;4758.095961767253;4770.946594179728;4794.904101917959;4821.132196204681;4272.551382104896;4272.893177670552;4288.120935208306;4300.9139817203795;4317.460317460317;5846.824595262149;5859.6777177255235;5907.14346419771;6000.819975400745;6017.555786611226;5640.238567793336;5645.350784659776;5692.686902220376;5724.771009159628;5735.713214339266;6447.355264473562;6523.217213934358;6541.5093867960095;6541.574797638138;6570.6201697643555;4842.806287748486;4884.804607815683;4911.901761964741;4913.189323761575;4915.5330243626795;6506;6706.698198581075;6775.22084960226;6842.160503391086;6893.931060689379;4882.877928051827;4910.975445122752;4925.482824303468;4945.4807245238735;4959.20652695567;4988.401391833003;5011.749412529359;5050.72221027847;5060.797568097235;5068.113080210948;5611.438856114438;5616.241807355955;5627.943720562824;5669.716514174274;5680.591645012959;6536.8365790855605;6539.934600654028;6564.015397690385;6580.605163690194;6588.472922166311;1752.3165965273554;1753.6317373351485;1774.7426623139572;1781.118346418531;1799.6862290527727;1166.935818529989;1202.8015377462796;1229.8032314829584;1238.795598726218;1245.5080243303844;6247.875042499125;6265.749370025239;6280.246370435577;6334.5249106317215;6356.3325850785;6577.967110164514;6667.433268172163;6693.933060669331;6709.798706038827;6745.605310114838;6168.735409241095;6238.625682459067;6265.122882796412;6293.622382657055;6301.212348456443;3494.8951531454095;3504.176518518146;3521.4717792331357;3565.35823551758;3568.107973006748;7052.753153639701;7053.753118640926;7082.929170708227;7082.964585177144;7107.395871350993;6735.865282694319;6784;6832.795016149524;6849.691763870638;6875.690593923286;6992.125984251867;7001.004713656584;7018.753998720463;7068.187158476841;7115.359617634438;6208.720607572761;6631.701573429111;6633;6685.328667833041;6736.461083113241;6571.211454625284;6575.605463672195;6575.999999999809;6594.967025164843;6599.512331614548;6968.094147760961;7004.229534751072;7026.648667566704;7035.1909530404655;7068.575885446684;4889.7799599018845;4899.363082799213;4917.164082105979;4920.532549407801;4923.359963204761;6491.48068154537;6549.770758023446;6611.537192396679;6693.330666933268;6739.427148692416;6384.3934826191435;6428.55000149985;6441.3880681335195;6475.514336424882;6532.41208291256;6386.744530218739;6407.455366293917;6450.935490645221;6473.352664733489;6495.155629768289;6408.743650253938;6477.51418643613;6487.3836985486305;6506.121673574196;6578.703958321983;6551.934480655323;6558.04908288286;6584.670766461753;6614.6361950093205;6623.092375498479;6335.904961425675;6336.873262534816;6416.647084410216;6421.1010458536775;6437.163168788027 19:07:40 INFO - PID 19756 | |5;yui.html;943.0808305099123;954.9904500954989;961.9354100660022;964.9807003859921;969.2585172343154;19364.12861421236;19597.902010489965;20202.494937626547;20204.39386818398;20215.696764548524;20704.27535036274;21865.344039678796;22157.889210553963;22168.778312216873;22320.330390088282;1072.7210925159475;1090.7545802194481;1093.1746531368806;1094.8795632480421;1095.0746619106842;1075.634284343323;1076.6178006807588;1076.6985244131656;1076.7200527862774;1078.2398409121552;1133.9659810205708;1136.8067428537167;1136.9260998035102;1136.9886301137008;1137.3573930729153;1114.8494953181298;1116.60360571997;1116.6426743442103;1120.7982563138673;1123.0959077942252;830.1656834880906;832.0015980822976;832.4797001873828;835.1940377535647;836.3685417509741;20217;20641;20833.270835520685;20839.8958005209;20942.895285523628;22652.660210096696;22846.086156553716;23072.53854922892;23480;23561;18141.183646735928;18262.452126436227;18305.17626706788;18379.72430413545;18409.815901840942;3824.6366595173413;3848.499695039655;3848.615138486157;3849.942250866239;3850.2299540092095;148489;149085;149715;150438;151478;17625.294988200327;17713.202905869268;17746.822531774516;18006.639867202583;18213.81786182121;32163;32469.188270292958;32627;32879.34241315208;33195.336093278485;8437.493750374997;8711.564421778885;8928.464292142493;9378.718638440858;9501.42991420517;4352.1948439538155;4366.104948485553;4369.584889435499;4375.256206444912;4468.240399132155;5437.537809286255;5568.192612071227;5634.267545219095;5665.858353541112;5747.6838773867;1128.2609890521721;1143.9485223164977;1154.440096553171;1160.9535618575162;1162.7674465107013;704.2288693880193;726.9345758881726;727.7380143148463;731.5098883747902;752.1463139336782;12654.620361388988;13008.349582520836;13611.319434028259;13914.373853176632;13946.930265348608;718.6406796601699;726.2991213478913;736.0431439129111;750.4521699159644;752.4883079505988;5688.203651488795;5706.572007099484;5733.569982251348;5761.279840019997;5860.267466566679;132214;132289.33855330662;135719;136364.31817840846;138772.30613846867;120390;122071.38964305121;122407;122693;127126.36436817757;102859.48570257101;103450;105820.94179058264;105916.47041764742;106798.46600766947;13034.283114428797;13826.516071937434;14066.718665626426;14166.645833854236;14174.858251417354;48335.758321208166;48825.267620985;48950.755246224966;49698.254526181416;50439.49560504348;84387.15612843793;84799.57600211959;88647.55676221578;89722;89784;17871.910640446713;18844.811551884854;18857.622847543247;18911.621767564848;19101.23595056238;19916.60166796685;22921.54156916819;23046.193383231508;23047;23150;19150.808491914904;19293.710594341377;21104.68342974826;21118.155273788878;21162.470938226666;15616.297266623256;15729.606759831096;15835.366585336458;17383.91308043452;17864.46406607804;17661.82338176602;18060.638787223917;18127.546811329295;18371;18862;13680.794788077988;13992.440302387791;14025;14339.78490322667;14570.125792452487;15598.610034749223;15645.608859778142;15780.92109539491;16152.838471615134;16186.433474828324 19:07:40 INFO - PID 19756 | __end_tp_report 19:07:40 INFO - PID 19756 | __start_cc_report 19:07:40 INFO - PID 19756 | _x_x_mozilla_cycle_collect,335 19:07:40 INFO - PID 19756 | __end_cc_report 19:07:40 INFO - PID 19756 | __startTimestamp1502762860224__endTimestamp 19:07:40 INFO - PID 19756 | 19:07:40 INFO - PID 19756 | ------- Summary: start ------- 19:07:40 INFO - PID 19756 | Number of tests: 6 19:07:40 INFO - PID 19756 | 19:07:40 INFO - PID 19756 | [#0] dojo.html Cycles:165 Average:6257.63 Median:6538.08 stddev:816.59 (12.5%) stddev-sans-first:789.69 19:07:40 INFO - PID 19756 | Values: 3489.8 3510.5 3528.0 3534.0 3557.0 6208.2 6301.6 6311.1 6336.3 6354.2 6207.1 6257.3 6283.7 6288.2 6298.9 5462.2 5490.0 5493.1 5506.8 5571.4 5536.9 5549.7 5553.1 5593.5 5642.3 5530.9 5554.9 5589.2 5646.1 5646.8 5231.6 5365.6 5472.4 5501.6 5586.6 5489.1 5542.5 5556.9 5562.7 5563.6 6076.4 6167.1 6183.1 6192.1 6265.5 6774.0 6799.5 6847.6 6854.5 6863.3 6847.7 6852.7 6936.5 6946.8 6953.8 6199.7 6318.7 6319.6 6393.9 6405.6 6965.5 6972.0 6972.1 6973.8 6984.2 6669.2 6722.9 6728.3 6738.2 6744.8 6809.0 6862.3 6864.9 6878.0 6879.3 6773.1 6773.1 6825.1 6841.3 6849.7 6649.4 6767.9 6773.1 6773.8 6880.6 4922.1 4967.9 4969.3 4971.5 4978.9 4362.0 4384.5 4403.3 4415.5 4424.0 6409.3 6419.2 6457.9 6518.8 6522.6 6834.4 6848.9 6859.9 6869.8 6878.6 6426.7 6503.0 6510.2 6510.4 6518.2 5468.2 5507.2 5558.7 5575.4 5575.9 7025.3 7160.0 7161.7 7181.8 7237.2 6758.9 6842.1 6858.5 6863.4 6865.9 7050.1 7146.6 7207.5 7209.0 7227.9 6663.8 6691.5 6705.0 6771.7 6771.9 6783.3 6811.1 6851.6 6912.5 6914.2 6640.7 6696.6 6705.5 6706.1 6720.3 6507.3 6566.2 6567.0 6592.2 6744.0 6538.1 6546.5 6554.2 6577.9 6600.2 6516.6 6621.6 6638.2 6677.6 6704.5 6383.9 6434.3 6438.7 6486.7 6524.5 19:07:40 INFO - PID 19756 | 19:07:40 INFO - PID 19756 | [#1] ext.html Cycles:180 Average:40789.96 Median:36905.26 stddev:58919.11 (159.6%) stddev-sans-first:59008.25 19:07:40 INFO - PID 19756 | Values: 898.0 915.8 944.2 966.6 967.7 43732.0 44525.3 44562.0 44629.8 44652.8 40308.8 40506.0 40530.2 40537.6 40562.8 7131.6 7238.7 7254.2 7256.7 7257.0 6957.1 6990.5 6993.1 6994.3 6996.0 6975.5 6996.7 6997.8 7002.2 7027.8 6953.3 6965.3 6984.1 6993.8 6995.3 6495.1 6554.6 6587.6 6590.8 6602.1 37002.3 37278.8 37290.3 37385.3 37395.4 3402.7 3422.7 3431.5 3448.6 3451.0 28551.9 28553.3 28628.9 28665.9 28745.0 4641.1 4672.7 4678.6 4724.1 4725.2 191731.0 193139.0 208196.0 208946.0 214575.0 53140.7 53260.0 53373.7 53375.7 53380.7 55635.4 55697.2 55757.0 55803.0 55850.7 14972.4 15050.7 15073.8 15076.5 15212.9 12258.8 12271.6 12292.8 12294.3 12385.4 14000.6 14033.4 14055.0 14064.4 14115.7 5223.9 5269.6 5283.4 5311.8 5339.4 1054.5 1058.9 1061.2 1062.9 1080.1 24564.3 24662.5 24697.8 24804.3 24869.6 1145.0 1177.9 1181.7 1183.1 1185.2 12896.0 12988.2 13043.8 13058.5 13096.1 245724.0 246533.0 247965.0 247995.0 248426.0 53742.5 53862.0 53891.0 53897.5 54332.7 1507.3 1508.2 1510.4 1558.5 1562.3 24414.6 29796.0 29995.6 30570.2 31062.8 55215.7 55542.4 55590.7 55628.2 55664.7 226163.0 226173.0 226237.0 226700.0 226923.0 35801.1 37753.8 38212.2 38391.6 38427.4 47740.5 49400.8 49489.3 49636.0 49915.5 35556.8 36295.3 36416.6 36556.3 36808.3 35242.1 35316.0 35671.8 35775.5 35863.8 41510.6 41561.6 41664.6 41680.2 41873.2 33877.5 33966.2 34084.0 34320.5 34610.1 25704.9 25925.7 26206.6 26331.5 26467.9 19:07:40 INFO - PID 19756 | 19:07:40 INFO - PID 19756 | [#2] jquery.html Cycles:180 Average:38117.00 Median:2316.62 stddev:92613.62 (3997.8%) stddev-sans-first:92830.53 19:07:40 INFO - PID 19756 | Values: 576.3 577.3 580.3 583.4 590.5 22223.7 23335.1 23393.0 23405.8 23479.2 27033.0 27534.0 27568.7 27611.7 27637.4 5987.7 6145.1 6146.6 6147.0 6148.3 5812.3 6283.1 6290.7 6301.4 6304.2 6261.3 6268.7 6270.0 6272.6 6274.0 6234.5 6244.1 6265.9 6266.7 6269.2 5809.6 5831.0 5831.6 5831.7 5833.4 30299.7 30794.7 30801.0 31690.0 31769.7 2301.8 2331.5 2341.7 2343.1 2343.2 20151.8 20599.4 20653.2 20709.4 20742.8 2401.8 2423.0 2429.1 2482.0 2528.6 155511.2 158180.0 158823.0 158856.2 159241.0 15111.8 15207.2 15259.8 15503.2 16234.9 23976.4 24003.2 24104.5 24111.5 24112.9 10675.5 10717.3 10754.9 10821.0 10935.1 8531.3 8895.4 10092.9 10137.0 10179.8 9386.0 9610.9 9642.4 9655.5 10038.8 2586.7 2619.4 2686.5 2711.9 2773.5 1026.2 1049.9 1063.7 1068.6 1084.2 21003.4 21013.3 21324.8 21682.0 21705.0 1123.3 1129.0 1133.9 1136.2 1142.9 11818.0 11819.6 11920.8 11940.9 12084.8 536124.0 547163.0 547208.0 555432.0 559516.0 115884.4 118863.0 119861.0 123132.4 123639.0 1216.3 1250.1 1267.3 1267.6 1278.4 18999.8 19036.5 19040.9 19250.6 19567.7 59814.7 59953.7 60077.1 60760.7 61244.0 74000.6 84088.0 86672.6 87317.6 87443.0 13196.8 13838.7 15034.8 15431.8 15519.3 24405.3 24793.4 24809.0 24845.0 24973.3 19956.2 20078.6 20374.3 20388.4 20396.0 17716.6 17765.9 18168.3 18281.6 18523.4 20474.4 20633.5 20655.7 20886.5 20990.7 15174.7 15228.7 15305.5 15312.4 15335.4 20175.7 20263.0 20267.0 20422.8 20510.7 19:07:40 INFO - PID 19756 | 19:07:40 INFO - PID 19756 | [#3] mootools.html Cycles:180 Average:10031.27 Median:23533.71 stddev:7168.07 (30.5%) stddev-sans-first:7159.04 19:07:40 INFO - PID 19756 | Values: 1430.0 1470.3 1479.4 1499.3 1501.5 15688.9 16372.8 16400.7 16420.5 16459.8 16304.9 16689.9 16709.4 16721.0 16768.7 2722.7 2776.5 2777.9 2779.1 2780.9 2639.6 2646.8 2729.6 2730.3 2741.9 2756.1 2760.4 2775.8 2786.8 2787.7 2670.1 2707.6 2708.5 2724.8 2728.8 2671.0 2677.3 2693.6 2693.6 2695.0 15550.7 15879.1 15905.8 15907.6 15910.0 7896.8 8216.0 8268.1 8273.3 8276.3 4875.5 5128.7 5170.1 5173.0 5183.2 1871.4 1902.6 1909.6 1963.3 1976.5 25540.2 26283.3 26326.1 28572.3 28682.7 6965.5 6999.1 7006.7 7115.1 7225.8 19248.8 19345.1 19347.3 19348.8 19366.6 8196.2 8315.0 8376.5 8377.8 8379.5 7539.8 7626.4 7645.4 7652.2 7811.0 5317.4 5475.8 5478.4 5513.4 5528.6 1549.5 1560.7 1572.2 1576.3 1579.6 947.9 978.1 979.0 981.4 1014.7 8888.9 9074.2 9136.5 9214.7 9371.2 1066.9 1070.6 1073.4 1103.8 1105.9 5883.4 5998.7 6031.9 6173.5 6238.6 22392.8 22790.8 23331.0 23465.8 23562.9 22596.1 23015.1 23504.5 23641.6 23732.3 11173.5 11524.1 12223.9 12517.2 12524.6 9901.2 9919.8 10018.4 10064.0 10065.4 19749.6 20137.8 20476.9 20536.9 20828.3 20763.6 20788.3 20876.6 20880.0 21001.4 11946.2 12964.2 13450.7 13482.5 13515.7 14807.6 14867.8 14901.4 14952.6 14974.2 11484.3 11540.9 11631.1 11773.2 11963.3 12027.1 12033.9 12212.1 12220.7 12294.3 10198.4 10303.8 10440.7 10453.0 10457.3 8163.3 8221.9 8264.4 8331.8 8418.3 7917.4 8262.2 8290.5 8371.5 8390.3 19:07:40 INFO - PID 19756 | 19:07:40 INFO - PID 19756 | [#4] prototype.html Cycles:180 Average:5531.00 Median:6239.81 stddev:1586.01 (25.4%) stddev-sans-first:1545.18 19:07:40 INFO - PID 19756 | Values: 517.8 523.3 531.4 533.3 538.7 6151.0 6166.6 6166.6 6241.0 6248.3 6029.6 6038.4 6059.5 6121.2 6148.8 4911.5 4926.2 4929.2 4933.6 4940.4 4714.2 4824.1 4931.3 4935.2 4936.2 4745.5 4753.6 4754.6 4807.4 4824.8 4627.8 4758.1 4770.9 4794.9 4821.1 4272.6 4272.9 4288.1 4300.9 4317.5 5846.8 5859.7 5907.1 6000.8 6017.6 5640.2 5645.4 5692.7 5724.8 5735.7 6447.4 6523.2 6541.5 6541.6 6570.6 4842.8 4884.8 4911.9 4913.2 4915.5 6506.0 6706.7 6775.2 6842.2 6893.9 4882.9 4911.0 4925.5 4945.5 4959.2 4988.4 5011.7 5050.7 5060.8 5068.1 5611.4 5616.2 5627.9 5669.7 5680.6 6536.8 6539.9 6564.0 6580.6 6588.5 1752.3 1753.6 1774.7 1781.1 1799.7 1166.9 1202.8 1229.8 1238.8 1245.5 6247.9 6265.7 6280.2 6334.5 6356.3 6578.0 6667.4 6693.9 6709.8 6745.6 6168.7 6238.6 6265.1 6293.6 6301.2 3494.9 3504.2 3521.5 3565.4 3568.1 7052.8 7053.8 7082.9 7083.0 7107.4 6735.9 6784.0 6832.8 6849.7 6875.7 6992.1 7001.0 7018.8 7068.2 7115.4 6208.7 6631.7 6633.0 6685.3 6736.5 6571.2 6575.6 6576.0 6595.0 6599.5 6968.1 7004.2 7026.6 7035.2 7068.6 4889.8 4899.4 4917.2 4920.5 4923.4 6491.5 6549.8 6611.5 6693.3 6739.4 6384.4 6428.6 6441.4 6475.5 6532.4 6386.7 6407.5 6450.9 6473.4 6495.2 6408.7 6477.5 6487.4 6506.1 6578.7 6551.9 6558.0 6584.7 6614.6 6623.1 6335.9 6336.9 6416.6 6421.1 6437.2 19:07:40 INFO - PID 19756 | 19:07:40 INFO - PID 19756 | [#5] yui.html Cycles:180 Average:27634.21 Median:19222.26 stddev:39508.47 (205.5%) stddev-sans-first:39568.47 19:07:40 INFO - PID 19756 | Values: 943.1 955.0 961.9 965.0 969.3 19364.1 19597.9 20202.5 20204.4 20215.7 20704.3 21865.3 22157.9 22168.8 22320.3 1072.7 1090.8 1093.2 1094.9 1095.1 1075.6 1076.6 1076.7 1076.7 1078.2 1134.0 1136.8 1136.9 1137.0 1137.4 1114.8 1116.6 1116.6 1120.8 1123.1 830.2 832.0 832.5 835.2 836.4 20217.0 20641.0 20833.3 20839.9 20942.9 22652.7 22846.1 23072.5 23480.0 23561.0 18141.2 18262.5 18305.2 18379.7 18409.8 3824.6 3848.5 3848.6 3849.9 3850.2 148489.0 149085.0 149715.0 150438.0 151478.0 17625.3 17713.2 17746.8 18006.6 18213.8 32163.0 32469.2 32627.0 32879.3 33195.3 8437.5 8711.6 8928.5 9378.7 9501.4 4352.2 4366.1 4369.6 4375.3 4468.2 5437.5 5568.2 5634.3 5665.9 5747.7 1128.3 1143.9 1154.4 1161.0 1162.8 704.2 726.9 727.7 731.5 752.1 12654.6 13008.3 13611.3 13914.4 13946.9 718.6 726.3 736.0 750.5 752.5 5688.2 5706.6 5733.6 5761.3 5860.3 132214.0 132289.3 135719.0 136364.3 138772.3 120390.0 122071.4 122407.0 122693.0 127126.4 102859.5 103450.0 105820.9 105916.5 106798.5 13034.3 13826.5 14066.7 14166.6 14174.9 48335.8 48825.3 48950.8 49698.3 50439.5 84387.2 84799.6 88647.6 89722.0 89784.0 17871.9 18844.8 18857.6 18911.6 19101.2 19916.6 22921.5 23046.2 23047.0 23150.0 19150.8 19293.7 21104.7 21118.2 21162.5 15616.3 15729.6 15835.4 17383.9 17864.5 17661.8 18060.6 18127.5 18371.0 18862.0 13680.8 13992.4 14025.0 14339.8 14570.1 15598.6 15645.6 15780.9 16152.8 16186.4 19:07:40 INFO - PID 19756 | -------- Summary: end -------- 19:07:40 INFO - PID 19756 | 19:07:40 INFO - TEST-INFO | 19756: exit 0 19:07:40 INFO - TEST-OK | dromaeo_css | took 1110654ms 19:07:40 INFO - TEST-START | kraken 19:07:40 INFO - Initialising browser for kraken test... 19:07:40 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33247/getInfo.html -profile /tmp/tmpiKLIWS/profile 19:07:40 INFO - TEST-INFO | started process 20128 (/builds/slave/test/build/application/firefox/firefox http://localhost:33247/getInfo.html) 19:07:46 INFO - TEST-INFO | 20128: exit 0 19:07:47 INFO - Browser initialized. 19:07:47 INFO - Running cycle 1/1 for kraken test... 19:07:47 INFO - TEST-INFO | started process 20339 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiKLIWS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:07:47 INFO - PID 20339 | 19:07:47 INFO - PID 20339 | (/builds/slave/test/build/application/firefox/firefox:20387): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:07:47 INFO - PID 20339 | 19:07:58 INFO - PID 20339 | RSS: Main: 158445568 19:07:58 INFO - PID 20339 | 19:08:00 INFO - PID 20339 | Cycle 1(1): loaded http://localhost:33247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33247/tests/kraken/driver.html?testName=audio-beat-detection) 19:08:01 INFO - PID 20339 | RSS: Main: 164405248 19:08:01 INFO - PID 20339 | 19:08:04 INFO - PID 20339 | Cycle 1(1): loaded http://localhost:33247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33247/tests/kraken/driver.html?testName=audio-dft) 19:08:04 INFO - PID 20339 | RSS: Main: 168386560 19:08:04 INFO - PID 20339 | 19:08:07 INFO - PID 20339 | Cycle 1(1): loaded http://localhost:33247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33247/tests/kraken/driver.html?testName=audio-fft) 19:08:08 INFO - PID 20339 | RSS: Main: 161693696 19:08:08 INFO - PID 20339 | 19:08:10 INFO - PID 20339 | Cycle 1(1): loaded http://localhost:33247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33247/tests/kraken/driver.html?testName=audio-oscillator) 19:08:11 INFO - PID 20339 | RSS: Main: 162738176 19:08:11 INFO - PID 20339 | 19:08:13 INFO - PID 20339 | Cycle 1(1): loaded http://localhost:33247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:08:13 INFO - PID 20339 | RSS: Main: 162746368 19:08:13 INFO - PID 20339 | 19:08:16 INFO - PID 20339 | Cycle 1(1): loaded http://localhost:33247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33247/tests/kraken/driver.html?testName=imaging-darkroom) 19:08:17 INFO - PID 20339 | RSS: Main: 164720640 19:08:17 INFO - PID 20339 | 19:08:20 INFO - PID 20339 | Cycle 1(1): loaded http://localhost:33247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33247/tests/kraken/driver.html?testName=imaging-desaturate) 19:08:21 INFO - PID 20339 | RSS: Main: 166039552 19:08:21 INFO - PID 20339 | 19:08:24 INFO - PID 20339 | Cycle 1(1): loaded http://localhost:33247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33247/tests/kraken/driver.html?testName=json-parse-financial) 19:08:24 INFO - PID 20339 | RSS: Main: 165761024 19:08:24 INFO - PID 20339 | 19:08:26 INFO - PID 20339 | Cycle 1(1): loaded http://localhost:33247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:08:27 INFO - PID 20339 | RSS: Main: 165154816 19:08:27 INFO - PID 20339 | 19:08:28 INFO - PID 20339 | Cycle 1(1): loaded http://localhost:33247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33247/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:08:29 INFO - PID 20339 | RSS: Main: 165122048 19:08:29 INFO - PID 20339 | 19:08:31 INFO - PID 20339 | Cycle 1(1): loaded http://localhost:33247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:08:32 INFO - PID 20339 | RSS: Main: 165306368 19:08:32 INFO - PID 20339 | 19:08:34 INFO - PID 20339 | Cycle 1(1): loaded http://localhost:33247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:08:35 INFO - PID 20339 | RSS: Main: 165031936 19:08:35 INFO - PID 20339 | 19:08:38 INFO - PID 20339 | Cycle 1(1): loaded http://localhost:33247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:08:38 INFO - PID 20339 | RSS: Main: 164941824 19:08:38 INFO - PID 20339 | 19:08:40 INFO - PID 20339 | Cycle 1(1): loaded http://localhost:33247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33247/tests/kraken/driver.html?testName=ai-astar) 19:08:40 INFO - PID 20339 | RSS: Main: 167301120 19:08:40 INFO - PID 20339 | 19:08:40 INFO - PID 20339 | __start_tp_report 19:08:40 INFO - PID 20339 | _x_x_mozilla_page_load 19:08:40 INFO - PID 20339 | _x_x_mozilla_page_load_details 19:08:40 INFO - PID 20339 | |i|pagename|runs| 19:08:40 INFO - PID 20339 | |0;ai-astar;98;109;107;109;106;109;109;99;109;109 19:08:40 INFO - PID 20339 | |1;audio-beat-detection;141;114;140;157;116;136;143;118;164;115 19:08:40 INFO - PID 20339 | |2;audio-dft;163;163;162;161;163;162;161;162;168;173 19:08:40 INFO - PID 20339 | |3;audio-fft;75;75;74;140;76;74;73;94;94;76 19:08:40 INFO - PID 20339 | |4;audio-oscillator;78;79;139;78;77;77;123;79;78;123 19:08:40 INFO - PID 20339 | |5;imaging-gaussian-blur;113;114;113;113;113;114;114;114;114;114 19:08:40 INFO - PID 20339 | |6;imaging-darkroom;147;148;148;148;148;148;149;148;147;150 19:08:40 INFO - PID 20339 | |7;imaging-desaturate;96;94;93;95;94;93;93;94;94;95 19:08:40 INFO - PID 20339 | |8;json-parse-financial;48;52;48;49;56;49;47;47;47;46 19:08:40 INFO - PID 20339 | |9;json-stringify-tinderbox;54;55;54;54;54;54;54;54;56;54 19:08:40 INFO - PID 20339 | |10;stanford-crypto-aes;75;74;74;74;75;74;75;74;74;74 19:08:40 INFO - PID 20339 | |11;stanford-crypto-ccm;117;113;118;121;117;122;122;114;115;152 19:08:40 INFO - PID 20339 | |12;stanford-crypto-pbkdf2;166;167;162;164;161;173;163;162;162;161 19:08:40 INFO - PID 20339 | |13;stanford-crypto-sha256-iterative;63;63;62;64;62;69;60;63;61;66 19:08:40 INFO - PID 20339 | __end_tp_report 19:08:40 INFO - PID 20339 | __start_cc_report 19:08:40 INFO - PID 20339 | _x_x_mozilla_cycle_collect,875 19:08:40 INFO - PID 20339 | __end_cc_report 19:08:40 INFO - PID 20339 | __startTimestamp1502762920680__endTimestamp 19:08:40 INFO - PID 20339 | 19:08:40 INFO - PID 20339 | ------- Summary: start ------- 19:08:40 INFO - PID 20339 | Number of tests: 14 19:08:40 INFO - PID 20339 | 19:08:40 INFO - PID 20339 | [#0] ai-astar Cycles:10 Average:106.40 Median:109.00 stddev:4.30 (3.9%) stddev-sans-first:3.32 19:08:40 INFO - PID 20339 | Values: 98.0 109.0 107.0 109.0 106.0 109.0 109.0 99.0 109.0 109.0 19:08:40 INFO - PID 20339 | 19:08:40 INFO - PID 20339 | [#1] audio-beat-detection Cycles:10 Average:134.40 Median:140.50 stddev:18.07 (12.9%) stddev-sans-first:19.01 19:08:40 INFO - PID 20339 | Values: 141.0 114.0 140.0 157.0 116.0 136.0 143.0 118.0 164.0 115.0 19:08:40 INFO - PID 20339 | 19:08:40 INFO - PID 20339 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.00 stddev:3.79 (2.3%) stddev-sans-first:4.01 19:08:40 INFO - PID 20339 | Values: 163.0 163.0 162.0 161.0 163.0 162.0 161.0 162.0 168.0 173.0 19:08:40 INFO - PID 20339 | 19:08:40 INFO - PID 20339 | [#3] audio-fft Cycles:10 Average:85.10 Median:75.50 stddev:20.91 (27.7%) stddev-sans-first:21.86 19:08:40 INFO - PID 20339 | Values: 75.0 75.0 74.0 140.0 76.0 74.0 73.0 94.0 94.0 76.0 19:08:40 INFO - PID 20339 | 19:08:40 INFO - PID 20339 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:78.00 stddev:24.71 (31.7%) stddev-sans-first:25.60 19:08:40 INFO - PID 20339 | Values: 78.0 79.0 139.0 78.0 77.0 77.0 123.0 79.0 78.0 123.0 19:08:40 INFO - PID 20339 | 19:08:40 INFO - PID 20339 | [#5] imaging-gaussian-blur Cycles:10 Average:113.60 Median:114.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 19:08:40 INFO - PID 20339 | Values: 113.0 114.0 113.0 113.0 113.0 114.0 114.0 114.0 114.0 114.0 19:08:40 INFO - PID 20339 | 19:08:40 INFO - PID 20339 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:0.88 (0.6%) stddev-sans-first:0.83 19:08:40 INFO - PID 20339 | Values: 147.0 148.0 148.0 148.0 148.0 148.0 149.0 148.0 147.0 150.0 19:08:40 INFO - PID 20339 | 19:08:40 INFO - PID 20339 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.99 (1.1%) stddev-sans-first:0.78 19:08:40 INFO - PID 20339 | Values: 96.0 94.0 93.0 95.0 94.0 93.0 93.0 94.0 94.0 95.0 19:08:40 INFO - PID 20339 | 19:08:40 INFO - PID 20339 | [#8] json-parse-financial Cycles:10 Average:48.90 Median:48.50 stddev:3.00 (6.2%) stddev-sans-first:3.16 19:08:40 INFO - PID 20339 | Values: 48.0 52.0 48.0 49.0 56.0 49.0 47.0 47.0 47.0 46.0 19:08:40 INFO - PID 20339 | 19:08:40 INFO - PID 20339 | [#9] json-stringify-tinderbox Cycles:10 Average:54.30 Median:54.00 stddev:0.67 (1.2%) stddev-sans-first:0.71 19:08:40 INFO - PID 20339 | Values: 54.0 55.0 54.0 54.0 54.0 54.0 54.0 54.0 56.0 54.0 19:08:40 INFO - PID 20339 | 19:08:40 INFO - PID 20339 | [#10] stanford-crypto-aes Cycles:10 Average:74.30 Median:74.00 stddev:0.48 (0.7%) stddev-sans-first:0.44 19:08:40 INFO - PID 20339 | Values: 75.0 74.0 74.0 74.0 75.0 74.0 75.0 74.0 74.0 74.0 19:08:40 INFO - PID 20339 | 19:08:40 INFO - PID 20339 | [#11] stanford-crypto-ccm Cycles:10 Average:121.10 Median:119.50 stddev:11.32 (9.5%) stddev-sans-first:11.91 19:08:40 INFO - PID 20339 | Values: 117.0 113.0 118.0 121.0 117.0 122.0 122.0 114.0 115.0 152.0 19:08:40 INFO - PID 20339 | 19:08:40 INFO - PID 20339 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.10 Median:163.50 stddev:3.73 (2.3%) stddev-sans-first:3.89 19:08:40 INFO - PID 20339 | Values: 166.0 167.0 162.0 164.0 161.0 173.0 163.0 162.0 162.0 161.0 19:08:40 INFO - PID 20339 | 19:08:40 INFO - PID 20339 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.00 stddev:2.58 (4.1%) stddev-sans-first:2.74 19:08:40 INFO - PID 20339 | Values: 63.0 63.0 62.0 64.0 62.0 69.0 60.0 63.0 61.0 66.0 19:08:40 INFO - PID 20339 | -------- Summary: end -------- 19:08:40 INFO - PID 20339 | 19:08:41 INFO - PID 20339 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 19:08:41 INFO - TEST-INFO | 20339: exit 0 19:08:41 INFO - TEST-OK | kraken | took 60403ms 19:08:41 INFO - SUITE-END | took 1171s 19:08:41 INFO - Completed test suite (00:19:31) 19:08:41 INFO - javascript benchmark 19:08:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8910.242759446368, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3489.7557170998007, 3510.455879338703, 3527.9647203527966, 3533.9646603533965, 3556.982215088924, 6208.192934918454, 6301.621902685836, 6311.147995020677, 6336.334684858092, 6354.205724284465, 6207.131001659771, 6257.311695713468, 6283.748650053993, 6288.151099601552, 6298.874022519558, 5462.20797984292, 5490.011797876391, 5493.093639549469, 5506.834794956158, 5571.359293681238, 5536.889262214753, 5549.722513874311, 5553.111502159655, 5593.468620481068, 5642.266505354298, 5530.944690553083, 5554.888902221933, 5589.161625756128, 5646.068398714227, 5646.830595082154, 5231.581473482112, 5365.597580181463, 5472.370677372073, 5501.587380946445, 5586.55307575393, 5489.149181876815, 5542.501174894239, 5556.86107847303, 5562.694051827148, 5563.554915606782, 6076.422739839708, 6167.074938759222, 6183.072539119124, 6192.071189321593, 6265.467435268008, 6773.966130169318, 6799.52403331767, 6847.623380714059, 6854.520183587151, 6863.347981941708, 6847.657617119125, 6852.657367131624, 6936.549124306955, 6946.79159625212, 6953.826154346131, 6199.721012554446, 6318.652474113922, 6319.5576309658345, 6393.872122557523, 6405.5836370636225, 6965.547239429421, 6972.0, 6972.09362782835, 6973.79078627642, 6984.161900571964, 6669.199696036507, 6722.899156512656, 6728.259891411941, 6738.157730283715, 6744.7976560703255, 6809.0467334573195, 6862.3137686230975, 6864.897026544606, 6877.965610171917, 6879.277675844064, 6773.051772751819, 6773.051772751819, 6825.112735344473, 6841.281665425158, 6849.657517124124, 6649.4347980421235, 6767.898481522781, 6773.119494465688, 6773.830654233683, 6880.621565813828, 4922.064807686528, 4967.900641987212, 4969.304297398295, 4971.527704868032, 4978.925316120261, 4361.996740749645, 4384.47386313638, 4403.3174857897075, 4415.536368681306, 4423.955760442354, 6409.294977552466, 6419.2296924369375, 6457.870842583122, 6518.804435866931, 6522.608643481406, 6834.384905358442, 6848.863022739517, 6859.897101543481, 6869.828254293682, 6878.58728476293, 6426.678666066678, 6503.0, 6510.153680021567, 6510.414062734282, 6518.152640156749, 5468.179773033997, 5507.228987941691, 5558.694271815089, 5575.358833734092, 5575.888482230333, 7025.332593403618, 7160.033395491646, 7161.67772450241, 7181.820454488679, 7237.167725711611, 6758.8648227036165, 6842.110525631636, 6858.485613578901, 6863.416609588241, 6865.931340686529, 7050.083489146378, 7146.64266786669, 7207.459440541876, 7208.96395518019, 7227.927720722725, 6663.833404164935, 6691.5315927884585, 6705.0, 6771.6614169292325, 6771.86456270862, 6783.287754785676, 6811.080504131879, 6851.554648947803, 6912.516123871281, 6914.239433662193, 6640.701168447335, 6696.5647232929705, 6705.530612857053, 6706.0611514389, 6720.294369091175, 6507.28419873823, 6566.179227596551, 6567.047778072249, 6592.2089349276475, 6744.0, 6538.084668146558, 6546.509011824228, 6554.213494380705, 6577.901331480128, 6600.2409722882485, 6516.6415847128865, 6621.635810030494, 6638.17022872141, 6677.632730199885, 6704.530682852153, 6383.872322553616, 6434.2922278548385, 6438.678066096582, 6486.675666216576, 6524.478041756743], "value": 6194.692534029544, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [897.9551022448876, 915.771057235691, 944.1596978688966, 966.5602151021286, 967.6516454076532, 43732.0, 44525.332120018225, 44562.0, 44629.77685111574, 44652.77673611636, 40308.79845600775, 40506.0, 40530.18939621213, 40537.59462405375, 40562.79718601403, 7131.572105673663, 7238.674259658302, 7254.202037775841, 7256.746013889515, 7257.020302259207, 6957.0955775749335, 6990.510664253505, 6993.0908981832545, 6994.265602111781, 6996.020557121981, 6975.546589471694, 6996.685149168275, 6997.790066298019, 7002.19474760404, 7027.789166325018, 6953.304669533031, 6965.268646792065, 6984.057152284478, 6993.825154371156, 6995.265497122805, 6495.090687303734, 6554.606723596552, 6587.571807832475, 6590.7693230736695, 6602.108715323466, 37002.25995480076, 37278.81360593207, 37290.25419491622, 37385.25229495395, 37395.439068413994, 3402.7447941404494, 3422.6748458896363, 3431.5367425397503, 3448.637893021247, 3450.999999999975, 28551.85724071387, 28553.286167845763, 28628.85685571559, 28665.85336586534, 28745.0, 4641.141388843075, 4672.672912896099, 4678.6491013174145, 4724.126036683225, 4725.243960966229, 191731.0, 193139.0, 208196.0, 208946.0, 214574.99999999843, 53140.73429632827, 53260.0, 53373.73313133487, 53375.733121334924, 53380.73309633427, 55635.443645563835, 55697.16454253189, 55757.0, 55803.0, 55850.72074639601, 14972.401103955719, 15050.698986020217, 15073.849261507465, 15076.547703568911, 15212.923935380473, 12258.754824903452, 12271.631851044483, 12292.754144917051, 12294.262344259372, 12385.380730963416, 14000.649983750487, 14033.438662453387, 14055.0, 14064.43742250319, 14115.71768564623, 5223.921641175385, 5269.604779641542, 5283.4452382499285, 5311.760970756325, 5339.359276886798, 1054.5254635414094, 1058.9364638121738, 1061.2147011211803, 1062.85651437055, 1080.1250986700798, 24564.263072107507, 24662.50674986526, 24697.753022469544, 24804.255872323858, 24869.62695559568, 1145.0, 1177.8527684039495, 1181.6514128332162, 1183.1303991566192, 1185.1644590563633, 12896.0, 12988.155769874928, 13043.804342934864, 13058.542950996669, 13096.14875033139, 245724.0, 246533.0, 247965.0, 247995.0, 248426.0, 53742.46257537375, 53862.0, 53891.0, 53897.46102539003, 54332.72833635886, 1507.3292384888618, 1508.1855797869248, 1510.4486862295403, 1558.4545409106722, 1562.3047743754148, 24414.633780493663, 29796.0, 29995.550066749453, 30570.235744106576, 31062.844685776425, 55215.72392138014, 55542.44457555372, 55590.72204638951, 55628.16557751718, 55664.721676392975, 226163.0, 226173.0, 226237.0, 226700.0, 226923.0, 35801.1049723759, 37753.81123094477, 38212.23575528529, 38391.61608383992, 38427.42358864563, 47740.52259477361, 49400.75299623479, 49489.25766113583, 49636.0, 49915.50084499254, 35556.82221588876, 36295.27409451743, 36416.635833641325, 36556.26887462289, 36808.26383472262, 35242.11894702653, 35316.0, 35671.82164089163, 35775.46336804898, 35863.82068089643, 41510.58489415188, 41561.58438415577, 41664.583354167284, 41680.1663966725, 41873.1625367497, 33877.49183762295, 33966.150846229044, 34083.97748067562, 34320.48519272163, 34610.134746631535, 25704.871475642503, 25925.740742592334, 26206.60690089612, 26331.473370532098, 26467.94128234849], "value": 17808.044901086723, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.3199424678877, 577.347597215147, 580.3007376111785, 583.4136692623912, 590.4892268188017, 22223.666645000318, 23335.066597336106, 23393.0, 23405.76594234057, 23479.178228761997, 27033.0, 27534.0, 27568.724312756865, 27611.723882761165, 27637.44725105502, 5987.730552125143, 6145.139680444741, 6146.600470969395, 6147.047207682816, 6148.292946311187, 5812.331581868097, 6283.120363149163, 6290.654014029226, 6301.369863013708, 6304.180456540644, 6261.311255761885, 6268.686565671721, 6270.0, 6272.5922815016875, 6273.968630156843, 6234.5324100692405, 6244.12582238487, 6265.937340626627, 6266.686665666698, 6269.153664255313, 5809.593328467009, 5831.0, 5831.591788574801, 5831.679257640828, 5833.358330583633, 30299.69700302991, 30794.69205307963, 30801.0, 31690.0, 31769.6823031769, 2301.7698230176848, 2331.4754180309296, 2341.7424083350816, 2343.1447521654472, 2343.1798870395396, 20151.79848201514, 20599.38201853932, 20653.17387304506, 20709.378718638465, 20742.79257207424, 2401.771831675988, 2423.0, 2429.137656132083, 2482.044412901028, 2528.6333481645243, 155511.2224438882, 158180.0, 158823.0, 158856.20571897182, 159241.0, 15111.773323400157, 15207.239638018054, 15259.771103433457, 15503.224838758242, 16234.918825405797, 23976.40058998504, 24003.15988940379, 24104.51790964171, 24111.51776964451, 24112.87943560306, 10675.46622668879, 10717.303375280582, 10754.89245107539, 10821.0, 10935.125189984783, 8531.274841638406, 8895.377323587416, 10092.949535252423, 10136.986301369952, 10179.796404072025, 9386.0, 9610.90389096114, 9642.42145471274, 9655.468949207721, 10038.799224015625, 2586.702529209165, 2619.4499155177245, 2686.516427043112, 2711.8508482033285, 2773.5285001549787, 1026.1636766035658, 1049.8897615750236, 1063.7287491689724, 1068.5939343049747, 1084.2247792828166, 21003.369898903056, 21013.264535741175, 21324.78675213259, 21682.0, 21705.0, 1123.3035517978906, 1128.9774204515863, 1133.8639363276295, 1136.210333818005, 1142.9085673146128, 11818.0, 11819.586314478953, 11920.821187682192, 11940.940295298467, 12084.818727719092, 536124.0, 547163.0, 547208.0, 555432.0, 559516.0, 115884.42057789826, 118863.0, 119861.0, 123132.38433807953, 123639.0, 1216.288471244332, 1250.0749445410331, 1267.3283159925254, 1267.6450593833556, 1278.3544310123327, 18999.810001899805, 19036.53179305755, 19040.904795475933, 19250.614987700446, 19567.70648440303, 59814.70092649509, 59953.700231498566, 60077.098843516505, 60760.69619651874, 61244.0, 74000.62999684968, 84088.0, 86672.56663716893, 87317.56341218253, 87443.0, 13196.802047969097, 13838.723225535634, 15034.849651503782, 15431.768523471932, 15519.301631426388, 24405.26784196477, 24793.380165496008, 24809.0, 24845.0, 24973.251872369485, 19956.20175192976, 20078.598428031648, 20374.28689995843, 20388.388348349574, 20396.0, 17716.557086072953, 17765.911170444066, 18168.27326906909, 18281.634367312847, 18523.35168269104, 20474.38576842697, 20633.48416289605, 20655.690164647243, 20886.47783805356, 20990.68513972261, 15174.696506069597, 15228.695426091637, 15305.46430874914, 15312.387504499695, 15335.386584536494, 20175.69736453925, 20263.0, 20267.0, 20422.79577204209, 20510.69233961462], "value": 12579.70335208394, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1429.9714005719886, 1470.2942587557973, 1479.423025020243, 1499.2878382768183, 1501.4969985055013, 15688.921555392222, 16372.83627163728, 16400.67198656026, 16420.507384778444, 16459.83540164598, 16304.918475407607, 16689.91655041723, 16709.415170469038, 16721.0, 16768.74846877295, 2722.6868910075345, 2776.5418705913603, 2777.8611069446547, 2779.110684580935, 2780.902668406606, 2639.5512762830267, 2646.7882569394496, 2729.6178535005115, 2730.303772538, 2741.876615552305, 2756.104266113521, 2760.3789147441867, 2775.7501824835763, 2786.7770578353784, 2787.679416867066, 2670.0654770830056, 2707.593860920868, 2708.4989276983724, 2724.7956403269736, 2728.8499132547704, 2670.9866450667623, 2677.263752468067, 2693.5555633320478, 2693.62289279501, 2695.0, 15550.688986220212, 15879.126648034353, 15905.840941590668, 15907.60230994223, 15910.0, 7896.842063158705, 8215.958920205361, 8268.131846156188, 8273.296769774577, 8276.255137037695, 4875.488073752241, 5128.692278463267, 5170.095233334151, 5173.0, 5183.170692689189, 1871.4011516314786, 1902.5909429472592, 1909.5798924236647, 1963.2834015584351, 1976.4564744695324, 25540.233792986055, 26283.342916427053, 26326.078587249547, 28572.285692857637, 28682.713172868214, 6965.547239429421, 6999.12510936133, 7006.719731210695, 7115.146182458138, 7225.819354516074, 19248.8075119247, 19345.12946917392, 19347.322843700404, 19348.80651193498, 19366.61266774685, 8196.221358970888, 8315.0, 8376.539290338967, 8377.832443351099, 8379.45533540318, 7539.849203016019, 7626.351760100453, 7645.426593005547, 7652.1582625911105, 7810.960945195351, 5317.441668624816, 5475.835724928259, 5478.397376288605, 5513.365962914316, 5528.585356098229, 1549.4731791190825, 1560.6644571416973, 1572.1981789287545, 1576.3221814619824, 1579.5656194546361, 947.9478628675489, 978.1001478639527, 979.0, 981.4356744871727, 1014.6955913226003, 8888.911110888808, 9074.183323500916, 9136.543172841331, 9214.677486287948, 9371.156595906265, 1066.8666416697913, 1070.6306324318098, 1073.4471747050275, 1103.812351900179, 1105.9336439813637, 5883.441073098049, 5998.7313257645865, 6031.909521357183, 6173.536984726164, 6238.563300568917, 22392.77607223907, 22790.772092279196, 23331.0, 23465.765342346698, 23562.882185588962, 22596.096156153897, 23015.079396823938, 23504.529909401717, 23641.645375319385, 23732.288031359087, 11173.497192626352, 11524.078073754079, 12223.877761222451, 12517.248965062125, 12524.561640342545, 9901.15840153595, 9919.801603968024, 10018.448985305879, 10064.0, 10065.396076235449, 19749.605007899474, 20137.79862201418, 20476.897615511825, 20536.897315513328, 20828.27101051456, 20763.584728305046, 20788.27241046556, 20876.58246835085, 20880.0, 21001.369958901258, 11946.223495472766, 12964.157329773534, 13450.663733406353, 13482.52811151605, 13515.729685406433, 14807.629809254855, 14867.776983345042, 14901.403943842124, 14952.551423457313, 14974.176420296988, 11484.310941343547, 11540.884591153981, 11631.127665425296, 11773.234739741889, 11963.342016188844, 12027.097967652286, 12033.879661203277, 12212.084093693185, 12220.694482638006, 12294.323812190058, 10198.439085850052, 10303.845442318221, 10440.738981525219, 10453.0, 10457.320274182153, 8163.3469322452875, 8221.876671849808, 8264.421490495608, 8331.750047498585, 8418.326533877398, 7917.445778795429, 8262.215089566482, 8290.46112002718, 8371.539565323723, 8390.328773697967], "value": 7120.477847543982, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [517.8187634327986, 523.277876530388, 531.3995185440451, 533.3359966841283, 538.6633354153652, 6151.0, 6166.568340216187, 6166.599171053878, 6241.0, 6248.343923887989, 6029.608075475102, 6038.365971572987, 6059.454649081582, 6121.173641558388, 6148.815535533941, 4911.533404326583, 4926.162552366089, 4929.186684197121, 4933.629977751665, 4940.382452193476, 4714.175019371613, 4824.1316563018645, 4931.284963680264, 4935.198652491833, 4936.210206366982, 4745.477997420281, 4753.572178503951, 4754.619630429557, 4807.375041244633, 4824.782884770194, 4627.8148874045, 4758.095961767253, 4770.946594179728, 4794.904101917959, 4821.132196204681, 4272.551382104896, 4272.893177670552, 4288.120935208306, 4300.9139817203795, 4317.460317460317, 5846.824595262149, 5859.6777177255235, 5907.14346419771, 6000.819975400745, 6017.555786611226, 5640.238567793336, 5645.350784659776, 5692.686902220376, 5724.771009159628, 5735.713214339266, 6447.355264473562, 6523.217213934358, 6541.5093867960095, 6541.574797638138, 6570.6201697643555, 4842.806287748486, 4884.804607815683, 4911.901761964741, 4913.189323761575, 4915.5330243626795, 6506.0, 6706.698198581075, 6775.22084960226, 6842.160503391086, 6893.931060689379, 4882.877928051827, 4910.975445122752, 4925.482824303468, 4945.4807245238735, 4959.20652695567, 4988.401391833003, 5011.749412529359, 5050.72221027847, 5060.797568097235, 5068.113080210948, 5611.438856114438, 5616.241807355955, 5627.943720562824, 5669.716514174274, 5680.591645012959, 6536.8365790855605, 6539.934600654028, 6564.015397690385, 6580.605163690194, 6588.472922166311, 1752.3165965273554, 1753.6317373351485, 1774.7426623139572, 1781.118346418531, 1799.6862290527727, 1166.935818529989, 1202.8015377462796, 1229.8032314829584, 1238.795598726218, 1245.5080243303844, 6247.875042499125, 6265.749370025239, 6280.246370435577, 6334.5249106317215, 6356.3325850785, 6577.967110164514, 6667.433268172163, 6693.933060669331, 6709.798706038827, 6745.605310114838, 6168.735409241095, 6238.625682459067, 6265.122882796412, 6293.622382657055, 6301.212348456443, 3494.8951531454095, 3504.176518518146, 3521.4717792331357, 3565.35823551758, 3568.107973006748, 7052.753153639701, 7053.753118640926, 7082.929170708227, 7082.964585177144, 7107.395871350993, 6735.865282694319, 6784.0, 6832.795016149524, 6849.691763870638, 6875.690593923286, 6992.125984251867, 7001.004713656584, 7018.753998720463, 7068.187158476841, 7115.359617634438, 6208.720607572761, 6631.701573429111, 6633.0, 6685.328667833041, 6736.461083113241, 6571.211454625284, 6575.605463672195, 6575.999999999809, 6594.967025164843, 6599.512331614548, 6968.094147760961, 7004.229534751072, 7026.648667566704, 7035.1909530404655, 7068.575885446684, 4889.7799599018845, 4899.363082799213, 4917.164082105979, 4920.532549407801, 4923.359963204761, 6491.48068154537, 6549.770758023446, 6611.537192396679, 6693.330666933268, 6739.427148692416, 6384.3934826191435, 6428.55000149985, 6441.3880681335195, 6475.514336424882, 6532.41208291256, 6386.744530218739, 6407.455366293917, 6450.935490645221, 6473.352664733489, 6495.155629768289, 6408.743650253938, 6477.51418643613, 6487.3836985486305, 6506.121673574196, 6578.703958321983, 6551.934480655323, 6558.04908288286, 6584.670766461753, 6614.6361950093205, 6623.092375498479, 6335.904961425675, 6336.873262534816, 6416.647084410216, 6421.1010458536775, 6437.163168788027], "value": 5068.317541982495, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [943.0808305099123, 954.9904500954989, 961.9354100660022, 964.9807003859921, 969.2585172343154, 19364.12861421236, 19597.902010489965, 20202.494937626547, 20204.39386818398, 20215.696764548524, 20704.27535036274, 21865.344039678796, 22157.889210553963, 22168.778312216873, 22320.330390088282, 1072.7210925159475, 1090.7545802194481, 1093.1746531368806, 1094.8795632480421, 1095.0746619106842, 1075.634284343323, 1076.6178006807588, 1076.6985244131656, 1076.7200527862774, 1078.2398409121552, 1133.9659810205708, 1136.8067428537167, 1136.9260998035102, 1136.9886301137008, 1137.3573930729153, 1114.8494953181298, 1116.60360571997, 1116.6426743442103, 1120.7982563138673, 1123.0959077942252, 830.1656834880906, 832.0015980822976, 832.4797001873828, 835.1940377535647, 836.3685417509741, 20217.0, 20641.0, 20833.270835520685, 20839.8958005209, 20942.895285523628, 22652.660210096696, 22846.086156553716, 23072.53854922892, 23480.0, 23561.0, 18141.183646735928, 18262.452126436227, 18305.17626706788, 18379.72430413545, 18409.815901840942, 3824.6366595173413, 3848.499695039655, 3848.615138486157, 3849.942250866239, 3850.2299540092095, 148489.0, 149085.0, 149715.0, 150438.0, 151478.0, 17625.294988200327, 17713.202905869268, 17746.822531774516, 18006.639867202583, 18213.81786182121, 32163.0, 32469.188270292958, 32627.0, 32879.34241315208, 33195.336093278485, 8437.493750374997, 8711.564421778885, 8928.464292142493, 9378.718638440858, 9501.42991420517, 4352.1948439538155, 4366.104948485553, 4369.584889435499, 4375.256206444912, 4468.240399132155, 5437.537809286255, 5568.192612071227, 5634.267545219095, 5665.858353541112, 5747.6838773867, 1128.2609890521721, 1143.9485223164977, 1154.440096553171, 1160.9535618575162, 1162.7674465107013, 704.2288693880193, 726.9345758881726, 727.7380143148463, 731.5098883747902, 752.1463139336782, 12654.620361388988, 13008.349582520836, 13611.319434028259, 13914.373853176632, 13946.930265348608, 718.6406796601699, 726.2991213478913, 736.0431439129111, 750.4521699159644, 752.4883079505988, 5688.203651488795, 5706.572007099484, 5733.569982251348, 5761.279840019997, 5860.267466566679, 132214.0, 132289.33855330662, 135719.0, 136364.31817840846, 138772.30613846867, 120390.0, 122071.38964305121, 122407.0, 122693.0, 127126.36436817757, 102859.48570257101, 103450.0, 105820.94179058264, 105916.47041764742, 106798.46600766947, 13034.283114428797, 13826.516071937434, 14066.718665626426, 14166.645833854236, 14174.858251417354, 48335.758321208166, 48825.267620985, 48950.755246224966, 49698.254526181416, 50439.49560504348, 84387.15612843793, 84799.57600211959, 88647.55676221578, 89722.0, 89784.0, 17871.910640446713, 18844.811551884854, 18857.622847543247, 18911.621767564848, 19101.23595056238, 19916.60166796685, 22921.54156916819, 23046.193383231508, 23047.0, 23150.0, 19150.808491914904, 19293.710594341377, 21104.68342974826, 21118.155273788878, 21162.470938226666, 15616.297266623256, 15729.606759831096, 15835.366585336458, 17383.91308043452, 17864.46406607804, 17661.82338176602, 18060.638787223917, 18127.546811329295, 18371.0, 18862.0, 13680.794788077988, 13992.440302387791, 14025.0, 14339.78490322667, 14570.125792452487, 15598.610034749223, 15645.608859778142, 15780.92109539491, 16152.838471615134, 16186.433474828324], "value": 9991.510581862376, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1464.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 109.0, 107.0, 109.0, 106.0, 109.0, 109.0, 99.0, 109.0, 109.0], "value": 106.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 114.0, 140.0, 157.0, 116.0, 136.0, 143.0, 118.0, 164.0, 115.0], "value": 134.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 161.0, 163.0, 162.0, 161.0, 162.0, 168.0, 173.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 140.0, 76.0, 74.0, 73.0, 94.0, 94.0, 76.0], "value": 85.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 139.0, 78.0, 77.0, 77.0, 123.0, 79.0, 78.0, 123.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 113.0, 113.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 148.0, 148.0, 148.0, 149.0, 148.0, 147.0, 150.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 93.0, 95.0, 94.0, 93.0, 93.0, 94.0, 94.0, 95.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 52.0, 48.0, 49.0, 56.0, 49.0, 47.0, 47.0, 47.0, 46.0], "value": 48.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0, 56.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 74.0, 75.0, 74.0, 75.0, 74.0, 74.0, 74.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 113.0, 118.0, 121.0, 117.0, 122.0, 122.0, 114.0, 115.0, 152.0], "value": 121.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 162.0, 164.0, 161.0, 173.0, 163.0, 162.0, 162.0, 161.0], "value": 164.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 64.0, 62.0, 69.0, 60.0, 63.0, 61.0, 66.0], "value": 63.3, "unit": "score"}]}]} 19:08:41 INFO - Return code: 0 19:08:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:08:41 INFO - # TBPL SUCCESS # 19:08:41 INFO - Running post-action listener: _package_coverage_data 19:08:41 INFO - Running post-action listener: _resource_record_post_action 19:08:41 INFO - [mozharness: 2017-08-15 02:08:41.981312Z] Finished run-tests step (success) 19:08:41 INFO - Running post-run listener: _resource_record_post_run 19:08:42 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 12288; Write bytes: 428756992; Read time: 64; Write time: 539596 19:08:42 INFO - TinderboxPrint: CPU usage
13.1% 19:08:42 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 64 19:08:42 INFO - TinderboxPrint: I/O write bytes / time
428,756,992 / 539,596 19:08:42 INFO - TinderboxPrint: CPU idle
8,250.0 (86.9%) 19:08:42 INFO - TinderboxPrint: CPU user
1,189.0 (12.5%) 19:08:42 INFO - TinderboxPrint: Swap in / out
0 / 0 19:08:42 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 107347968; Read time: 40; Write time: 111056 19:08:42 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:08:42 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 321409024; Read time: 8; Write time: 428540 19:08:42 INFO - Running post-run listener: _upload_blobber_files 19:08:42 INFO - Blob upload gear active. 19:08:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:08:42 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 19:08:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:08:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:08:43 INFO - (blobuploader) - INFO - Open directory for files ... 19:08:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:08:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:08:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:08:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:44 INFO - (blobuploader) - INFO - Done attempting. 19:08:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:08:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:08:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:08:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:50 INFO - (blobuploader) - INFO - Done attempting. 19:08:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:08:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:08:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:08:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:51 INFO - (blobuploader) - INFO - Done attempting. 19:08:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:08:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:08:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:52 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:08:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:52 INFO - (blobuploader) - INFO - Done attempting. 19:08:52 INFO - (blobuploader) - INFO - Iteration through files over. 19:08:52 INFO - Return code: 0 19:08:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:08:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:08:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46f39bfea3647f3365d409e4da6caadea387bd70fd8466ba9524503ebab598dec672c088a9fae0a80680b35069908bfbf203900ed0d419a47cc77c24e3c9f44c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77a4635eceffa169beed536632acf426b66b65a225c534d3dd01db1a3dc5750324f9f30ef9b3be4ccb1e7d976d92f813307612f4e1a0785252145fa2b973aed7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21c29369a9e09b84d0d5a1797075bd1b35747115a542d3e1c85e20835b435b3f717eca29e65366e69ab694f19950df4a4f5075d9a55e8bd5f57716da3ae6910d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3cde945038dd180d6864d79c5de9656261b6d712325a8cb7b1dde89d4b2de4583b583a7ea7a5f8d7491b50ce4ad7cd75b165ce58a471f2ab0f1470924a0b5ca7"} 19:08:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:08:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:08:52 INFO - Contents: 19:08:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46f39bfea3647f3365d409e4da6caadea387bd70fd8466ba9524503ebab598dec672c088a9fae0a80680b35069908bfbf203900ed0d419a47cc77c24e3c9f44c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77a4635eceffa169beed536632acf426b66b65a225c534d3dd01db1a3dc5750324f9f30ef9b3be4ccb1e7d976d92f813307612f4e1a0785252145fa2b973aed7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21c29369a9e09b84d0d5a1797075bd1b35747115a542d3e1c85e20835b435b3f717eca29e65366e69ab694f19950df4a4f5075d9a55e8bd5f57716da3ae6910d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3cde945038dd180d6864d79c5de9656261b6d712325a8cb7b1dde89d4b2de4583b583a7ea7a5f8d7491b50ce4ad7cd75b165ce58a471f2ab0f1470924a0b5ca7"} 19:08:52 INFO - Running post-run listener: copy_logs_to_upload_dir 19:08:52 INFO - Copying logs to upload dir... 19:08:52 INFO - mkdir: /builds/slave/test/build/upload/logs 19:08:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.092540 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-14 19:08:51.563069) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-14 19:08:51.567344) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969 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/sha512/46f39bfea3647f3365d409e4da6caadea387bd70fd8466ba9524503ebab598dec672c088a9fae0a80680b35069908bfbf203900ed0d419a47cc77c24e3c9f44c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77a4635eceffa169beed536632acf426b66b65a225c534d3dd01db1a3dc5750324f9f30ef9b3be4ccb1e7d976d92f813307612f4e1a0785252145fa2b973aed7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21c29369a9e09b84d0d5a1797075bd1b35747115a542d3e1c85e20835b435b3f717eca29e65366e69ab694f19950df4a4f5075d9a55e8bd5f57716da3ae6910d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3cde945038dd180d6864d79c5de9656261b6d712325a8cb7b1dde89d4b2de4583b583a7ea7a5f8d7491b50ce4ad7cd75b165ce58a471f2ab0f1470924a0b5ca7"} build_url:https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011078 build_url: 'https://queue.taskcluster.net/v1/task/Qy9IsAQLQR6e-GsALDqk9g/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46f39bfea3647f3365d409e4da6caadea387bd70fd8466ba9524503ebab598dec672c088a9fae0a80680b35069908bfbf203900ed0d419a47cc77c24e3c9f44c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77a4635eceffa169beed536632acf426b66b65a225c534d3dd01db1a3dc5750324f9f30ef9b3be4ccb1e7d976d92f813307612f4e1a0785252145fa2b973aed7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21c29369a9e09b84d0d5a1797075bd1b35747115a542d3e1c85e20835b435b3f717eca29e65366e69ab694f19950df4a4f5075d9a55e8bd5f57716da3ae6910d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3cde945038dd180d6864d79c5de9656261b6d712325a8cb7b1dde89d4b2de4583b583a7ea7a5f8d7491b50ce4ad7cd75b165ce58a471f2ab0f1470924a0b5ca7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-14 19:08:51.596792) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 19:08:51.597123) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1502759200.452469-265423969 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004465 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 19:08:51.678806) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-14 19:08:51.682131) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-14 19:08:51.682405) ========= ========= Total master_lag: 0.19 =========