builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-035 starttime: 1501582710.61 results: success (0) revision: 51ffb9283f0c7c00e08eb8c39b33fbee218c370d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-01 03:18:30.607503) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-01 03:18:30.608021) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-01 03:18:30.608300) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-01 03:18:30.658217) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 03:18:30.658474) ========= 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=ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-01 03:18:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 230M=0s 2017-08-01 03:18:30 (230 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.201701 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 03:18:30.906392) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 03:18:30.909712) ========= 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=ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.060155 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 03:18:30.980830) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-01 03:18:30.981124) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 51ffb9283f0c7c00e08eb8c39b33fbee218c370d --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 51ffb9283f0c7c00e08eb8c39b33fbee218c370d --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=ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-01 03:18:31,054 truncating revision to first 12 chars 2017-08-01 03:18:31,054 Setting DEBUG logging. 2017-08-01 03:18:31,055 attempt 1/10 2017-08-01 03:18:31,055 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/51ffb9283f0c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-01 03:18:31,750 unpacking tar archive at: mozilla-central-51ffb9283f0c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.029053 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-01 03:18:32.025235) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-01 03:18:32.025528) ========= script_repo_revision: 51ffb9283f0c7c00e08eb8c39b33fbee218c370d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-01 03:18:32.028918) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-01 03:18:32.029179) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-01 03:18:32.037793) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-08-01 03:18:32.038057) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:18:32 INFO - MultiFileLogger online at 20170801 03:18:32 in /builds/slave/test 03:18:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 03:18:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:18:32 INFO - {'append_to_log': False, 03:18:32 INFO - 'base_work_dir': '/builds/slave/test', 03:18:32 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:18:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:18:32 INFO - 'branch': 'Firefox-Non-PGO', 03:18:32 INFO - 'buildbot_json_path': 'buildprops.json', 03:18:32 INFO - 'code_coverage': False, 03:18:32 INFO - 'config_files': ('talos/linux_config.py',), 03:18:32 INFO - 'default_actions': ('clobber', 03:18:32 INFO - 'read-buildbot-config', 03:18:32 INFO - 'download-and-extract', 03:18:32 INFO - 'populate-webroot', 03:18:32 INFO - 'create-virtualenv', 03:18:32 INFO - 'install', 03:18:32 INFO - 'setup-mitmproxy', 03:18:32 INFO - 'run-tests'), 03:18:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:18:32 INFO - 'disable_ccov_upload': False, 03:18:32 INFO - 'download_minidump_stackwalk': True, 03:18:32 INFO - 'download_symbols': 'ondemand', 03:18:32 INFO - 'enable_stylo': False, 03:18:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:18:32 INFO - 'tooltool.py': '/tools/tooltool.py', 03:18:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:18:32 INFO - '/tools/misc-python/virtualenv.py')}, 03:18:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:18:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:18:32 INFO - 'gecko_profile': False, 03:18:32 INFO - 'gecko_profile_interval': 0, 03:18:32 INFO - 'installer_path': 'installer.exe', 03:18:32 INFO - 'log_level': 'info', 03:18:32 INFO - 'log_name': 'talos', 03:18:32 INFO - 'log_to_console': True, 03:18:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:18:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:18:32 INFO - 'opt_config_files': (), 03:18:32 INFO - 'pip_index': False, 03:18:32 INFO - 'suite': 'dromaeojs-e10s', 03:18:32 INFO - 'system_bits': '32', 03:18:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:18:32 INFO - 'title': 'talos-linux64-ix-035', 03:18:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:18:32 INFO - 'use_talos_json': True, 03:18:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:18:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:18:32 INFO - 'work_dir': 'build'} 03:18:32 INFO - [mozharness: 2017-08-01 10:18:32.322066Z] Running clobber step. 03:18:32 INFO - Running pre-action listener: _resource_record_pre_action 03:18:32 INFO - Running main action method: clobber 03:18:32 INFO - rmtree: /builds/slave/test/build 03:18:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:18:38 INFO - Running post-action listener: _resource_record_post_action 03:18:38 INFO - [mozharness: 2017-08-01 10:18:38.980289Z] Finished clobber step (success) 03:18:38 INFO - [mozharness: 2017-08-01 10:18:38.980460Z] Running read-buildbot-config step. 03:18:38 INFO - Running pre-action listener: _resource_record_pre_action 03:18:38 INFO - Running main action method: read_buildbot_config 03:18:38 INFO - Using buildbot properties: 03:18:38 INFO - { 03:18:38 INFO - "project": "", 03:18:38 INFO - "product": "firefox", 03:18:38 INFO - "who": "archaeopteryx@coole-files.de", 03:18:38 INFO - "installer_path": "public/build/target.tar.bz2", 03:18:38 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:18:38 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:18:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:18:38 INFO - "stage_platform": "linux64", 03:18:38 INFO - "basedir": "/builds/slave/test", 03:18:38 INFO - "buildnumber": 747, 03:18:38 INFO - "platform": "ubuntu64_hw", 03:18:38 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:18:38 INFO - "slavebuilddir": "test", 03:18:38 INFO - "taskId": "IGWFBgMnTGiHbvikxBnKmg", 03:18:38 INFO - "branch": "mozilla-central", 03:18:38 INFO - "script_repo_revision": "production", 03:18:38 INFO - "revision": "51ffb9283f0c7c00e08eb8c39b33fbee218c370d", 03:18:38 INFO - "slavename": "talos-linux64-ix-035", 03:18:38 INFO - "repo_path": "mozilla-central" 03:18:38 INFO - } 03:18:38 INFO - Finding installer, test and symbols from parent task. 03:18:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IGWFBgMnTGiHbvikxBnKmg'}, attempt #1 03:18:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IGWFBgMnTGiHbvikxBnKmg'}, attempt #1 03:18:39 INFO - Task dependencies: JhuDbI3YQeS1KwM6F3P5lg 03:18:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg'}, attempt #1 03:18:40 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.tar.bz2 03:18:40 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.test_packages.json 03:18:40 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.crashreporter-symbols.zip 03:18:40 INFO - Running post-action listener: _resource_record_post_action 03:18:40 INFO - [mozharness: 2017-08-01 10:18:40.325410Z] Finished read-buildbot-config step (success) 03:18:40 INFO - [mozharness: 2017-08-01 10:18:40.325638Z] Running download-and-extract step. 03:18:40 INFO - Running pre-action listener: _resource_record_pre_action 03:18:40 INFO - Running main action method: download_and_extract 03:18:40 INFO - mkdir: /builds/slave/test/build/tests 03:18: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')]} 03:18:40 INFO - trying https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.test_packages.json 03:18:40 INFO - Downloading https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:18:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:18:40 INFO - Downloaded 989 bytes. 03:18:40 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:18:40 INFO - Using the following test package requirements: 03:18:40 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:18:40 INFO - u'common': [u'target.common.tests.zip'], 03:18:40 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:18:40 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:18:40 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:18:40 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:18:40 INFO - u'mozbase': [u'target.common.tests.zip'], 03:18:40 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:18:40 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:18:40 INFO - u'web-platform': [u'target.common.tests.zip', 03:18:40 INFO - u'target.web-platform.tests.tar.gz'], 03:18:40 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:18:40 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:18:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.common.tests.zip 03:18:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:18:40 INFO - Fetch https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.common.tests.zip into memory 03:18:42 INFO - Content-Length response header: 72390374 03:18:42 INFO - Bytes received: 72390374 03:18:54 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:18:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.common.tests.zip 03:18:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:18:54 INFO - Fetch https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.common.tests.zip into memory 03:18:55 INFO - Content-Length response header: 72390374 03:18:55 INFO - Bytes received: 72390374 03:19:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.talos.tests.zip 03:19:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:19:08 INFO - Fetch https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.talos.tests.zip into memory 03:19:09 INFO - Content-Length response header: 14496467 03:19:09 INFO - Bytes received: 14496467 03:19: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')]} 03:19:09 INFO - trying https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.tar.bz2 03:19:09 INFO - Downloading https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:19:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:19:10 INFO - Downloaded 62292244 bytes. 03:19:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.tar.bz2 03:19:10 INFO - mkdir: /builds/slave/test/properties 03:19:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:19:10 INFO - Writing to file /builds/slave/test/properties/build_url 03:19:10 INFO - Contents: 03:19:10 INFO - build_url:https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.tar.bz2 03:19:10 INFO - Running post-action listener: _resource_record_post_action 03:19:10 INFO - Running post-action listener: set_extra_try_arguments 03:19:10 INFO - [mozharness: 2017-08-01 10:19:10.765573Z] Finished download-and-extract step (success) 03:19:10 INFO - [mozharness: 2017-08-01 10:19:10.765671Z] Running populate-webroot step. 03:19:10 INFO - Running pre-action listener: _resource_record_pre_action 03:19:10 INFO - Running main action method: populate_webroot 03:19:10 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:19:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:19:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:19:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:19:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:19:10 INFO - 'tests': ['damp', 'tps']}, 03:19:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:19:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:19:10 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:19:10 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:19:10 INFO - 'other-e10s': {'tests': ['a11yr', 03:19:10 INFO - 'ts_paint', 03:19:10 INFO - 'tpaint', 03:19:10 INFO - 'sessionrestore', 03:19:10 INFO - 'sessionrestore_many_windows', 03:19:10 INFO - 'sessionrestore_no_auto_restore', 03:19:10 INFO - 'tabpaint']}, 03:19:10 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:19:10 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:19:10 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:19:10 INFO - 'talos_options': ['--mitmproxy', 03:19:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:19:10 INFO - '--firstNonBlankPaint'], 03:19:10 INFO - 'tests': ['quantum_pageload_google', 03:19:10 INFO - 'quantum_pageload_youtube', 03:19:10 INFO - 'quantum_pageload_amazon', 03:19:10 INFO - 'quantum_pageload_facebook']}, 03:19:10 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:19:10 INFO - 'talos_options': ['--stylo', 03:19:10 INFO - '--mitmproxy', 03:19:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:19:10 INFO - '--firstNonBlankPaint'], 03:19:10 INFO - 'tests': ['quantum_pageload_google', 03:19:10 INFO - 'quantum_pageload_youtube', 03:19:10 INFO - 'quantum_pageload_amazon', 03:19:10 INFO - 'quantum_pageload_facebook']}, 03:19:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:19:10 INFO - 'tsvgr_opacity', 03:19:10 INFO - 'tart', 03:19:10 INFO - 'tscrollx', 03:19:10 INFO - 'cart', 03:19:10 INFO - 'tsvg_static']}, 03:19:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:19:10 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:19:10 INFO - 'talos_options': ['--stylo', 03:19:10 INFO - '--stylo-threads=1', 03:19:10 INFO - '--mitmproxy', 03:19:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:19:10 INFO - '--firstNonBlankPaint'], 03:19:10 INFO - 'tests': ['tp6_google', 03:19:10 INFO - 'tp6_youtube', 03:19:10 INFO - 'tp6_amazon', 03:19:10 INFO - 'tp6_facebook']}, 03:19:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:19:10 INFO - 'talos_options': ['--xperf_path', 03:19:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:19:10 INFO - 'tests': ['tp5n']}}} 03:19:10 INFO - Running post-action listener: _resource_record_post_action 03:19:10 INFO - [mozharness: 2017-08-01 10:19:10.774388Z] Finished populate-webroot step (success) 03:19:10 INFO - [mozharness: 2017-08-01 10:19:10.774478Z] Running create-virtualenv step. 03:19:10 INFO - Running pre-action listener: _resource_record_pre_action 03:19:10 INFO - Running main action method: create_virtualenv 03:19:10 INFO - Creating virtualenv /builds/slave/test/build/venv 03:19:10 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 03:19:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:19:10 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:19:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:19:10 INFO - Using real prefix '/usr' 03:19:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:19:11 INFO - Installing distribute.............................................................................................................................................................................................done. 03:19:13 INFO - Installing pip.................done. 03:19:13 INFO - Return code: 0 03:19:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:19:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:19:13 INFO - Reading from file tmpfile_stdout 03:19:13 INFO - Output received: 03:19:13 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) 03:19:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:19:13 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')]} 03:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:13 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')]} 03:19:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:13 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 0x12a21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x146fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1612d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160feb0>, '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 0x1610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1611240>, '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': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', '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 03:19:13 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 03:19:13 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 03:19:13 INFO - Using env: {'DISPLAY': ':0', 03:19:13 INFO - 'HOME': '/home/cltbld', 03:19:13 INFO - 'LANG': 'en_US.UTF-8', 03:19:13 INFO - 'LANGUAGE': 'en_US:en', 03:19:13 INFO - 'LOGNAME': 'cltbld', 03:19:13 INFO - 'MAIL': '/var/mail/cltbld', 03:19:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:13 INFO - 'MOZ_NO_REMOTE': '1', 03:19:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:13 INFO - 'NO_EM_RESTART': '1', 03:19:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:13 INFO - 'PWD': '/builds/slave/test', 03:19:13 INFO - 'SHELL': '/bin/bash', 03:19:13 INFO - 'SHLVL': '1', 03:19:13 INFO - 'TERM': 'linux', 03:19:13 INFO - 'TMOUT': '86400', 03:19:13 INFO - 'USER': 'cltbld', 03:19:13 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', 03:19:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:13 INFO - '_': '/tools/buildbot/bin/python'} 03:19:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:13 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 03:19:13 INFO - Cleaning up... 03:19:14 INFO - Return code: 0 03:19:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:19:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x12a21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x146fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1612d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160feb0>, '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 0x1610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1611240>, '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': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', '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 03:19:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:19:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:19:14 INFO - Using env: {'DISPLAY': ':0', 03:19:14 INFO - 'HOME': '/home/cltbld', 03:19:14 INFO - 'LANG': 'en_US.UTF-8', 03:19:14 INFO - 'LANGUAGE': 'en_US:en', 03:19:14 INFO - 'LOGNAME': 'cltbld', 03:19:14 INFO - 'MAIL': '/var/mail/cltbld', 03:19:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:14 INFO - 'MOZ_NO_REMOTE': '1', 03:19:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:14 INFO - 'NO_EM_RESTART': '1', 03:19:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:14 INFO - 'PWD': '/builds/slave/test', 03:19:14 INFO - 'SHELL': '/bin/bash', 03:19:14 INFO - 'SHLVL': '1', 03:19:14 INFO - 'TERM': 'linux', 03:19:14 INFO - 'TMOUT': '86400', 03:19:14 INFO - 'USER': 'cltbld', 03:19:14 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', 03:19:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:14 INFO - '_': '/tools/buildbot/bin/python'} 03:19:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:14 INFO - Downloading/unpacking psutil>=3.1.1 03:19:14 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 03:19:14 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 03:19:14 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 03:19:14 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 03:19:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:19:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:19:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:19:15 INFO - Installing collected packages: psutil 03:19:15 INFO - Running setup.py install for psutil 03:19:15 INFO - building 'psutil._psutil_linux' extension 03:19:15 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 03:19:16 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 03:19:16 INFO - building 'psutil._psutil_posix' extension 03:19:16 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 03:19:16 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 03:19:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:19:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:19:16 INFO - Successfully installed psutil 03:19:16 INFO - Cleaning up... 03:19:16 INFO - Return code: 0 03:19:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:19:16 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')]} 03:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:16 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')]} 03:19:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:16 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 0x12a21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x146fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1612d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160feb0>, '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 0x1610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1611240>, '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': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', '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 03:19:16 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 03:19:16 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 03:19:16 INFO - Using env: {'DISPLAY': ':0', 03:19:16 INFO - 'HOME': '/home/cltbld', 03:19:16 INFO - 'LANG': 'en_US.UTF-8', 03:19:17 INFO - 'LANGUAGE': 'en_US:en', 03:19:17 INFO - 'LOGNAME': 'cltbld', 03:19:17 INFO - 'MAIL': '/var/mail/cltbld', 03:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:17 INFO - 'MOZ_NO_REMOTE': '1', 03:19:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:17 INFO - 'NO_EM_RESTART': '1', 03:19:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:17 INFO - 'PWD': '/builds/slave/test', 03:19:17 INFO - 'SHELL': '/bin/bash', 03:19:17 INFO - 'SHLVL': '1', 03:19:17 INFO - 'TERM': 'linux', 03:19:17 INFO - 'TMOUT': '86400', 03:19:17 INFO - 'USER': 'cltbld', 03:19:17 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', 03:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:17 INFO - '_': '/tools/buildbot/bin/python'} 03:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:19:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:19:18 INFO - Installing collected packages: mozsystemmonitor 03:19:18 INFO - Running setup.py install for mozsystemmonitor 03:19:18 INFO - Successfully installed mozsystemmonitor 03:19:18 INFO - Cleaning up... 03:19:18 INFO - Return code: 0 03:19:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:19:18 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')]} 03:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:18 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')]} 03:19:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:18 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 0x12a21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x146fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1612d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160feb0>, '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 0x1610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1611240>, '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': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', '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 03:19:18 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 03:19:18 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 03:19:18 INFO - Using env: {'DISPLAY': ':0', 03:19:18 INFO - 'HOME': '/home/cltbld', 03:19:18 INFO - 'LANG': 'en_US.UTF-8', 03:19:18 INFO - 'LANGUAGE': 'en_US:en', 03:19:18 INFO - 'LOGNAME': 'cltbld', 03:19:18 INFO - 'MAIL': '/var/mail/cltbld', 03:19:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:18 INFO - 'MOZ_NO_REMOTE': '1', 03:19:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:18 INFO - 'NO_EM_RESTART': '1', 03:19:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:18 INFO - 'PWD': '/builds/slave/test', 03:19:18 INFO - 'SHELL': '/bin/bash', 03:19:18 INFO - 'SHLVL': '1', 03:19:18 INFO - 'TERM': 'linux', 03:19:18 INFO - 'TMOUT': '86400', 03:19:18 INFO - 'USER': 'cltbld', 03:19:18 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', 03:19:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:18 INFO - '_': '/tools/buildbot/bin/python'} 03:19:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:18 INFO - Downloading/unpacking jsonschema==2.5.1 03:19:18 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 03:19:18 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 03:19:18 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 03:19:18 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 03:19:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:19:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:19 INFO - Downloading functools32-3.2.3-2.tar.gz 03:19:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:19:19 INFO - warning: no files found matching '*.txt' 03:19:19 INFO - no previously-included directories found matching 'build' 03:19:19 INFO - no previously-included directories found matching 'dist' 03:19:19 INFO - no previously-included directories found matching '.git*' 03:19:19 INFO - Installing collected packages: jsonschema, functools32 03:19:20 INFO - Running setup.py install for functools32 03:19:20 INFO - warning: no files found matching '*.txt' 03:19:20 INFO - no previously-included directories found matching 'build' 03:19:20 INFO - no previously-included directories found matching 'dist' 03:19:20 INFO - no previously-included directories found matching '.git*' 03:19:20 INFO - Successfully installed jsonschema functools32 03:19:20 INFO - Cleaning up... 03:19:20 INFO - Return code: 0 03:19:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:19:20 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')]} 03:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:20 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')]} 03:19:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:20 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 0x12a21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x146fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1612d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160feb0>, '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 0x1610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1611240>, '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': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', '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 03:19:20 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 03:19:20 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 03:19:20 INFO - Using env: {'DISPLAY': ':0', 03:19:20 INFO - 'HOME': '/home/cltbld', 03:19:20 INFO - 'LANG': 'en_US.UTF-8', 03:19:20 INFO - 'LANGUAGE': 'en_US:en', 03:19:20 INFO - 'LOGNAME': 'cltbld', 03:19:20 INFO - 'MAIL': '/var/mail/cltbld', 03:19:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:20 INFO - 'MOZ_NO_REMOTE': '1', 03:19:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:20 INFO - 'NO_EM_RESTART': '1', 03:19:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:20 INFO - 'PWD': '/builds/slave/test', 03:19:20 INFO - 'SHELL': '/bin/bash', 03:19:20 INFO - 'SHLVL': '1', 03:19:20 INFO - 'TERM': 'linux', 03:19:20 INFO - 'TMOUT': '86400', 03:19:20 INFO - 'USER': 'cltbld', 03:19:20 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', 03:19:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:20 INFO - '_': '/tools/buildbot/bin/python'} 03:19:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:19:20 INFO - Cleaning up... 03:19:20 INFO - Return code: 0 03:19:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:19:20 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')]} 03:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:20 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')]} 03:19:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:20 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 0x12a21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x146fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1612d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160feb0>, '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 0x1610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1611240>, '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': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', '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 03:19:20 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 03:19:20 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 03:19:20 INFO - Using env: {'DISPLAY': ':0', 03:19:20 INFO - 'HOME': '/home/cltbld', 03:19:20 INFO - 'LANG': 'en_US.UTF-8', 03:19:20 INFO - 'LANGUAGE': 'en_US:en', 03:19:20 INFO - 'LOGNAME': 'cltbld', 03:19:20 INFO - 'MAIL': '/var/mail/cltbld', 03:19:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:20 INFO - 'MOZ_NO_REMOTE': '1', 03:19:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:20 INFO - 'NO_EM_RESTART': '1', 03:19:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:20 INFO - 'PWD': '/builds/slave/test', 03:19:20 INFO - 'SHELL': '/bin/bash', 03:19:20 INFO - 'SHLVL': '1', 03:19:20 INFO - 'TERM': 'linux', 03:19:20 INFO - 'TMOUT': '86400', 03:19:20 INFO - 'USER': 'cltbld', 03:19:20 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', 03:19:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:20 INFO - '_': '/tools/buildbot/bin/python'} 03:19:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:20 INFO - Downloading/unpacking blobuploader==1.2.4 03:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:21 INFO - Downloading blobuploader-1.2.4.tar.gz 03:19:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:19:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:19:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:22 INFO - Downloading docopt-0.6.1.tar.gz 03:19:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:19:22 INFO - Installing collected packages: blobuploader, requests, docopt 03:19:22 INFO - Running setup.py install for blobuploader 03:19:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:19:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:19:22 INFO - Running setup.py install for requests 03:19:22 INFO - Running setup.py install for docopt 03:19:22 INFO - Successfully installed blobuploader requests docopt 03:19:22 INFO - Cleaning up... 03:19:22 INFO - Return code: 0 03:19:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:19:22 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')]} 03:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:22 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')]} 03:19:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:22 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 0x12a21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x146fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1612d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160feb0>, '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 0x1610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1611240>, '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': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', '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 03:19:22 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 03:19:22 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 03:19:22 INFO - Using env: {'DISPLAY': ':0', 03:19:22 INFO - 'HOME': '/home/cltbld', 03:19:22 INFO - 'LANG': 'en_US.UTF-8', 03:19:22 INFO - 'LANGUAGE': 'en_US:en', 03:19:22 INFO - 'LOGNAME': 'cltbld', 03:19:22 INFO - 'MAIL': '/var/mail/cltbld', 03:19:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:22 INFO - 'MOZ_NO_REMOTE': '1', 03:19:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:22 INFO - 'NO_EM_RESTART': '1', 03:19:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:22 INFO - 'PWD': '/builds/slave/test', 03:19:22 INFO - 'SHELL': '/bin/bash', 03:19:22 INFO - 'SHLVL': '1', 03:19:22 INFO - 'TERM': 'linux', 03:19:22 INFO - 'TMOUT': '86400', 03:19:22 INFO - 'USER': 'cltbld', 03:19:22 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', 03:19:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:22 INFO - '_': '/tools/buildbot/bin/python'} 03:19:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:19:23 INFO - Running setup.py (path:/tmp/pip-Qs9nhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:19:23 INFO - Running setup.py (path:/tmp/pip-jVLrdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:19:23 INFO - Running setup.py (path:/tmp/pip-UwcNk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:19:23 INFO - Running setup.py (path:/tmp/pip-s7U0ii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:19:23 INFO - Running setup.py (path:/tmp/pip-trgfzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:19:23 INFO - Running setup.py (path:/tmp/pip-x5Zdn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:19:23 INFO - Running setup.py (path:/tmp/pip-NVB5ny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:19:23 INFO - Running setup.py (path:/tmp/pip-OIppit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:19:23 INFO - Running setup.py (path:/tmp/pip-xNGRuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:19:23 INFO - Running setup.py (path:/tmp/pip-EXnmkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:19:23 INFO - Running setup.py (path:/tmp/pip-2jva56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:19:23 INFO - Running setup.py (path:/tmp/pip-H0DI07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:19:23 INFO - Running setup.py (path:/tmp/pip-0bGhix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:19:23 INFO - Running setup.py (path:/tmp/pip-3G8STH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:19:24 INFO - Running setup.py (path:/tmp/pip-17ecEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:19:24 INFO - Running setup.py (path:/tmp/pip-JN8R31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:19:24 INFO - Running setup.py (path:/tmp/pip-uUIvYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:19:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:19:24 INFO - Running setup.py install for manifestparser 03:19:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:19:24 INFO - Running setup.py install for mozcrash 03:19:24 INFO - Running setup.py install for mozdebug 03:19:24 INFO - Running setup.py install for mozdevice 03:19:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:19:24 INFO - Running setup.py install for mozfile 03:19:24 INFO - Running setup.py install for mozhttpd 03:19:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:19:24 INFO - Running setup.py install for mozinfo 03:19:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:19:24 INFO - Running setup.py install for mozInstall 03:19:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:19:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:19:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:19:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:19:25 INFO - Running setup.py install for mozleak 03:19:25 INFO - Running setup.py install for mozlog 03:19:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:19:25 INFO - Running setup.py install for moznetwork 03:19:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:19:25 INFO - Running setup.py install for mozprocess 03:19:25 INFO - Running setup.py install for mozprofile 03:19:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:19:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:19:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:19:25 INFO - Running setup.py install for mozrunner 03:19:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:19:25 INFO - Running setup.py install for mozscreenshot 03:19:25 INFO - Running setup.py install for moztest 03:19:25 INFO - Running setup.py install for mozversion 03:19:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:19:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:19:26 INFO - Cleaning up... 03:19:26 INFO - Return code: 0 03:19:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:19:26 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')]} 03:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:26 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')]} 03:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:26 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 0x12a21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x146fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1612d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160feb0>, '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 0x1610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1611240>, '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': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', '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 03:19:26 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 03:19:26 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 03:19:26 INFO - Using env: {'DISPLAY': ':0', 03:19:26 INFO - 'HOME': '/home/cltbld', 03:19:26 INFO - 'LANG': 'en_US.UTF-8', 03:19:26 INFO - 'LANGUAGE': 'en_US:en', 03:19:26 INFO - 'LOGNAME': 'cltbld', 03:19:26 INFO - 'MAIL': '/var/mail/cltbld', 03:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:26 INFO - 'MOZ_NO_REMOTE': '1', 03:19:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:26 INFO - 'NO_EM_RESTART': '1', 03:19:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:26 INFO - 'PWD': '/builds/slave/test', 03:19:26 INFO - 'SHELL': '/bin/bash', 03:19:26 INFO - 'SHLVL': '1', 03:19:26 INFO - 'TERM': 'linux', 03:19:26 INFO - 'TMOUT': '86400', 03:19:26 INFO - 'USER': 'cltbld', 03:19:26 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', 03:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:26 INFO - '_': '/tools/buildbot/bin/python'} 03:19:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:19:26 INFO - Running setup.py (path:/tmp/pip-QoghLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:19:26 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)) 03:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:19:26 INFO - Running setup.py (path:/tmp/pip-L7DPOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:19:26 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)) 03:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:19:26 INFO - Running setup.py (path:/tmp/pip-MNKBm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:19:26 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)) 03:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:19:26 INFO - Running setup.py (path:/tmp/pip-n3M2jM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:19:26 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)) 03:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:19:26 INFO - Running setup.py (path:/tmp/pip-Nsy5uQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:19:26 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)) 03:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:19:26 INFO - Running setup.py (path:/tmp/pip-kZSz13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:19:26 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)) 03:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:19:26 INFO - Running setup.py (path:/tmp/pip-EmBcAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:19:26 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)) 03:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:19:26 INFO - Running setup.py (path:/tmp/pip-bjwuEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:19:26 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)) 03:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:19:26 INFO - Running setup.py (path:/tmp/pip-zP984d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:19:26 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)) 03:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:19:26 INFO - Running setup.py (path:/tmp/pip-yidXle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:19:26 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)) 03:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:19:26 INFO - Running setup.py (path:/tmp/pip-f8LExA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:19:27 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)) 03:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:19:27 INFO - Running setup.py (path:/tmp/pip-kTYWqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:19:27 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)) 03:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:19:27 INFO - Running setup.py (path:/tmp/pip-CLI32l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:19:27 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)) 03:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:19:27 INFO - Running setup.py (path:/tmp/pip-rIrCLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:19:27 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)) 03:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:19:27 INFO - Running setup.py (path:/tmp/pip-T6CEO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:19:27 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)) 03:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:19:27 INFO - Running setup.py (path:/tmp/pip-_RGN_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:19:27 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)) 03:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:19:27 INFO - Running setup.py (path:/tmp/pip-rsLu5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:19:27 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)) 03:19:27 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)) 03:19:27 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)) 03:19:27 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)) 03:19:27 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)) 03:19:27 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)) 03:19:27 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)) 03:19:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:19:27 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 03:19:27 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 03:19:27 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 03:19:27 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 03:19:28 INFO - Downloading blessings-1.6.tar.gz 03:19:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:19:28 INFO - Installing collected packages: blessings 03:19:28 INFO - Running setup.py install for blessings 03:19:28 INFO - Successfully installed blessings 03:19:28 INFO - Cleaning up... 03:19:28 INFO - Return code: 0 03:19:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:19:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:19:29 INFO - Reading from file tmpfile_stdout 03:19:29 INFO - Current package versions: 03:19:29 INFO - argparse == 1.2.1 03:19:29 INFO - blessings == 1.6 03:19:29 INFO - blobuploader == 1.2.4 03:19:29 INFO - docopt == 0.6.1 03:19:29 INFO - functools32 == 3.2.3-2 03:19:29 INFO - jsonschema == 2.5.1 03:19:29 INFO - manifestparser == 1.1 03:19:29 INFO - mozInstall == 1.13 03:19:29 INFO - mozcrash == 1.0 03:19:29 INFO - mozdebug == 0.1 03:19:29 INFO - mozdevice == 0.51 03:19:29 INFO - mozfile == 1.2 03:19:29 INFO - mozhttpd == 0.7 03:19:29 INFO - mozinfo == 0.10 03:19:29 INFO - mozleak == 0.1 03:19:29 INFO - mozlog == 3.5 03:19:29 INFO - moznetwork == 0.27 03:19:29 INFO - mozprocess == 0.25 03:19:29 INFO - mozprofile == 0.28 03:19:29 INFO - mozrunner == 6.13 03:19:29 INFO - mozscreenshot == 0.1 03:19:29 INFO - mozsystemmonitor == 0.3 03:19:29 INFO - moztest == 0.8 03:19:29 INFO - mozversion == 1.4 03:19:29 INFO - psutil == 3.1.1 03:19:29 INFO - requests == 1.2.3 03:19:29 INFO - wsgiref == 0.1.2 03:19:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:19:29 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')]} 03:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:29 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')]} 03:19:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:29 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 0x12a21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x146fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1612d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160feb0>, '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 0x1610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1611240>, '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': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', '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 03:19:29 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 03:19:29 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 03:19:29 INFO - Using env: {'DISPLAY': ':0', 03:19:29 INFO - 'HOME': '/home/cltbld', 03:19:29 INFO - 'LANG': 'en_US.UTF-8', 03:19:29 INFO - 'LANGUAGE': 'en_US:en', 03:19:29 INFO - 'LOGNAME': 'cltbld', 03:19:29 INFO - 'MAIL': '/var/mail/cltbld', 03:19:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:29 INFO - 'MOZ_NO_REMOTE': '1', 03:19:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:29 INFO - 'NO_EM_RESTART': '1', 03:19:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:29 INFO - 'PWD': '/builds/slave/test', 03:19:29 INFO - 'SHELL': '/bin/bash', 03:19:29 INFO - 'SHLVL': '1', 03:19:29 INFO - 'TERM': 'linux', 03:19:29 INFO - 'TMOUT': '86400', 03:19:29 INFO - 'USER': 'cltbld', 03:19:29 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', 03:19:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:29 INFO - '_': '/tools/buildbot/bin/python'} 03:19:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:29 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)) 03:19:29 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)) 03:19:29 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)) 03:19:29 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)) 03:19:29 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)) 03:19:29 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)) 03:19:29 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)) 03:19:29 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)) 03:19:29 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)) 03:19:29 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:19:29 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 03:19:29 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 03:19:29 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 03:19:29 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 03:19:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:19:30 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)) 03:19:30 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)) 03:19:30 INFO - Installing collected packages: simplejson 03:19:30 INFO - Running setup.py install for simplejson 03:19:30 INFO - building 'simplejson._speedups' extension 03:19:30 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 03:19:31 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 03:19:31 INFO - Successfully installed simplejson 03:19:31 INFO - Cleaning up... 03:19:31 INFO - Return code: 0 03:19:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:19:31 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')]} 03:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:31 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')]} 03:19:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:31 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 0x12a21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x146fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1612d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160feb0>, '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 0x1610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1611240>, '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': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', '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 03:19:31 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 03:19:31 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 03:19:31 INFO - Using env: {'DISPLAY': ':0', 03:19:31 INFO - 'HOME': '/home/cltbld', 03:19:31 INFO - 'LANG': 'en_US.UTF-8', 03:19:31 INFO - 'LANGUAGE': 'en_US:en', 03:19:31 INFO - 'LOGNAME': 'cltbld', 03:19:31 INFO - 'MAIL': '/var/mail/cltbld', 03:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:31 INFO - 'MOZ_NO_REMOTE': '1', 03:19:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:31 INFO - 'NO_EM_RESTART': '1', 03:19:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:31 INFO - 'PWD': '/builds/slave/test', 03:19:31 INFO - 'SHELL': '/bin/bash', 03:19:31 INFO - 'SHLVL': '1', 03:19:31 INFO - 'TERM': 'linux', 03:19:31 INFO - 'TMOUT': '86400', 03:19:31 INFO - 'USER': 'cltbld', 03:19:31 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', 03:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:31 INFO - '_': '/tools/buildbot/bin/python'} 03:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:19:31 INFO - Cleaning up... 03:19:31 INFO - Return code: 0 03:19:31 INFO - Running post-action listener: _resource_record_post_action 03:19:31 INFO - Running post-action listener: _start_resource_monitoring 03:19:31 INFO - Starting resource monitoring. 03:19:31 INFO - [mozharness: 2017-08-01 10:19:31.730441Z] Finished create-virtualenv step (success) 03:19:31 INFO - [mozharness: 2017-08-01 10:19:31.731391Z] Running install step. 03:19:31 INFO - Running pre-action listener: _resource_record_pre_action 03:19:31 INFO - Running main action method: install 03:19:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:19:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:19:31 INFO - Reading from file tmpfile_stdout 03:19:31 INFO - Detecting whether we're running mozinstall >=1.0... 03:19:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:19:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:19:32 INFO - Reading from file tmpfile_stdout 03:19:32 INFO - Output received: 03:19:32 INFO - Usage: mozinstall [options] installer 03:19:32 INFO - Options: 03:19:32 INFO - -h, --help show this help message and exit 03:19:32 INFO - -d DEST, --destination=DEST 03:19:32 INFO - Directory to install application into. [default: 03:19:32 INFO - "/builds/slave/test"] 03:19:32 INFO - --app=APP Application being installed. [default: firefox] 03:19:32 INFO - mkdir: /builds/slave/test/build/application 03:19:32 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'] 03:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:19:50 INFO - Reading from file tmpfile_stdout 03:19:50 INFO - Output received: 03:19:50 INFO - /builds/slave/test/build/application/firefox/firefox 03:19:50 INFO - Running post-action listener: _resource_record_post_action 03:19:50 INFO - [mozharness: 2017-08-01 10:19:50.105107Z] Finished install step (success) 03:19:50 INFO - [mozharness: 2017-08-01 10:19:50.105256Z] Running setup-mitmproxy step. 03:19:50 INFO - Running pre-action listener: _resource_record_pre_action 03:19:50 INFO - Running main action method: setup_mitmproxy 03:19:50 INFO - Skipping: mitmproxy is not required 03:19:50 INFO - Running post-action listener: _resource_record_post_action 03:19:50 INFO - [mozharness: 2017-08-01 10:19:50.106311Z] Finished setup-mitmproxy step (success) 03:19:50 INFO - [mozharness: 2017-08-01 10:19:50.106531Z] Running run-tests step. 03:19:50 INFO - Running pre-action listener: _resource_record_pre_action 03:19:50 INFO - Running pre-action listener: _set_gcov_prefix 03:19:50 INFO - Running main action method: run_tests 03:19:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:19:50 INFO - Python 2.7.3 03:19:50 INFO - Return code: 0 03:19:50 INFO - grabbing minidump binary from tooltool 03:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:50 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 0x160feb0>, '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 0x1610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1611240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:19:50 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 03:19:50 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 03:19:50 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 03:19:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:19:50 INFO - Return code: 0 03:19:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:19:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:19:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:19:50 INFO - ENV: RUST_BACKTRACE is now 1 03:19:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:19:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:19:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 03:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-035 --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 03:19:50 INFO - Using env: {'DISPLAY': ':0', 03:19:50 INFO - 'HOME': '/home/cltbld', 03:19:50 INFO - 'LANG': 'en_US.UTF-8', 03:19:50 INFO - 'LANGUAGE': 'en_US:en', 03:19:50 INFO - 'LOGNAME': 'cltbld', 03:19:50 INFO - 'MAIL': '/var/mail/cltbld', 03:19:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:19:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:50 INFO - 'MOZ_NO_REMOTE': '1', 03:19:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:19:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:50 INFO - 'NO_EM_RESTART': '1', 03:19:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:50 INFO - 'PWD': '/builds/slave/test', 03:19:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:19:50 INFO - 'RUST_BACKTRACE': '1', 03:19:50 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:19:50 INFO - 'SHELL': '/bin/bash', 03:19:50 INFO - 'SHLVL': '1', 03:19:50 INFO - 'TERM': 'linux', 03:19:50 INFO - 'TMOUT': '86400', 03:19:50 INFO - 'USER': 'cltbld', 03:19:50 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813', 03:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:50 INFO - '_': '/tools/buildbot/bin/python'} 03:19:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 03:19:50 INFO - mozversion application_buildid: 20170801092414 03:19:50 INFO - mozversion application_changeset: 51ffb9283f0c7c00e08eb8c39b33fbee218c370d 03:19:50 INFO - mozversion application_display_name: Nightly 03:19:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:19:50 INFO - mozversion application_name: Firefox 03:19:50 INFO - mozversion application_remotingname: firefox 03:19:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:19:50 INFO - mozversion application_vendor: Mozilla 03:19:50 INFO - mozversion application_version: 56.0a1 03:19:50 INFO - mozversion platform_buildid: 20170801092414 03:19:50 INFO - mozversion platform_changeset: 51ffb9283f0c7c00e08eb8c39b33fbee218c370d 03:19:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:19:50 INFO - mozversion platform_version: 56.0a1 03:19:50 INFO - using testdate: 1501582790 03:19:50 INFO - actual date: 1501582790 03:19:50 INFO - starting webserver on 'localhost:52421' 03:19:50 INFO - SUITE-START | Running 2 tests 03:19:50 INFO - TEST-START | dromaeo_css 03:19:50 INFO - Initialising browser for dromaeo_css test... 03:19:50 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52421/getInfo.html -profile /tmp/tmppoxZlB/profile 03:19:50 INFO - TEST-INFO | started process 3390 (/builds/slave/test/build/application/firefox/firefox http://localhost:52421/getInfo.html) 03:19:57 INFO - TEST-INFO | 3390: exit 0 03:19:57 INFO - Browser initialized. 03:19:57 INFO - Running cycle 1/1 for dromaeo_css test... 03:19:57 INFO - TEST-INFO | started process 3599 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppoxZlB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:19:59 INFO - PID 3599 | 03:19:59 INFO - PID 3599 | (/builds/slave/test/build/application/firefox/firefox:3647): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:19:59 INFO - PID 3599 | 03:20:09 INFO - PID 3599 | RSS: Main: 171638784 03:20:09 INFO - PID 3599 | 03:23:09 INFO - PID 3599 | Cycle 1(1): loaded http://localhost:52421/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52421/tests/dromaeo/cssquery-ext.html) 03:23:10 INFO - PID 3599 | RSS: Main: 173350912 03:23:10 INFO - PID 3599 | 03:26:12 INFO - PID 3599 | Cycle 1(1): loaded http://localhost:52421/tests/dromaeo/cssquery-ext.html (next: http://localhost:52421/tests/dromaeo/cssquery-jquery.html) 03:26:12 INFO - PID 3599 | RSS: Main: 170893312 03:26:12 INFO - PID 3599 | 03:29:14 INFO - PID 3599 | Cycle 1(1): loaded http://localhost:52421/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52421/tests/dromaeo/cssquery-mootools.html) 03:29:15 INFO - PID 3599 | RSS: Main: 171446272 03:29:15 INFO - PID 3599 | 03:32:17 INFO - PID 3599 | Cycle 1(1): loaded http://localhost:52421/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52421/tests/dromaeo/cssquery-prototype.html) 03:32:17 INFO - PID 3599 | RSS: Main: 172089344 03:32:17 INFO - PID 3599 | 03:35:21 INFO - PID 3599 | Cycle 1(1): loaded http://localhost:52421/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52421/tests/dromaeo/cssquery-yui.html) 03:35:22 INFO - PID 3599 | RSS: Main: 175214592 03:35:22 INFO - PID 3599 | 03:38:24 INFO - PID 3599 | Cycle 1(1): loaded http://localhost:52421/tests/dromaeo/cssquery-yui.html (next: http://localhost:52421/tests/dromaeo/cssquery-dojo.html) 03:38:24 INFO - PID 3599 | RSS: Main: 173727744 03:38:24 INFO - PID 3599 | 03:38:24 INFO - PID 3599 | __start_tp_report 03:38:24 INFO - PID 3599 | _x_x_mozilla_page_load 03:38:24 INFO - PID 3599 | _x_x_mozilla_page_load_details 03:38:24 INFO - PID 3599 | |i|pagename|runs| 03:38:24 INFO - PID 3599 | |0;dojo.html;3121.0792816119256;3154.1956801015735;3168.0337497063415;3185.601799775028;3186.394585028844;6108.144859719643;6171.197744293241;6213.502919766419;6223.097650840626;6231.781887633933;6060.818175454732;6178.073289006651;6190.1333813266065;6200.1629779979685;6217.968910155454;5662.943370566303;5665.886682266362;5700.743466543995;5712.828615141552;5716.942830571704;5684.37471878093;5734.885302293973;5744.856378590548;5754.482096611304;5756.683382413966;5708.286464191976;5713.885722285552;5746.770129194827;5757.107648314518;5800.216970708963;5659.660420374749;5690.60165788395;5724.284464441924;5725.885482290351;5761.855953601152;5421.749764629147;5506.614536982413;5567.582431317667;5573.386927437979;5610.13042978339;6049.062395328687;6078.088286756978;6082.6600126742815;6176.81469555914;6179.258488981351;6447.3552644735155;6619.106420633201;6687.164104486939;6694;6718.395344419026;6607.471402287805;6646.368594983469;6702.463802895757;6733.966330168367;6764.492663050241;6225.28409232935;6254;6255.593386429913;6260.311365749764;6260.5304602154565;6802.387785099365;6871.622060786704;6878.105846239957;6879.415249703831;6886.449084073262;6447.580907241015;6458.903116453257;6461.8061458156335;6524.804255872332;6566.212054553484;6492.188476440445;6607.900881486781;6611.173603299588;6616.900746488806;6650.902601070769;6586.176727909012;6609.702563384563;6616.27221005689;6616.437602803816;6626.436752875963;6622.801315960529;6633.900491492632;6652.401283884474;6653.73385064592;6663.300353462913;5353.839384818461;5368.570514358842;5370.0065487884085;5371.301730775052;5371.892562148813;4625.421822272216;4642.419697537808;4643.233866412004;4648.48866624671;4659.5573420525;5664.773409063591;5738.454846789548;5739;5743.282089738783;5772.19189313488;6591.077249185118;6613.768518101843;6618.139641846626;6619.602823830585;6630.270670226271;5719.913216488937;5742.13867919807;5795.797147099831;5797.971010145006;5800.216970708984;5658.773649054074;5723.227364305849;5749.108888122348;5803.970980145156;5804.767809287581;6405.135306733625;6410.038494225903;6416.197975253093;6431.260405053479;6525.902111468236;6719.529632925648;6719.731210751515;6732.899006514808;6741.460683145435;6752.527323087336;6442.967785161044;6522.445592124723;6526;6526.314736952551;6538.673066346569;6511.511636627367;6516.902246466401;6520.18497687789;6627.4698024158915;6660.733570657119;6623.2383275923885;6644.966775166094;6677.966610167113;6691.2639609643875;6733.595984240961;6533.346665333619;6562.114114594468;6566.179227596551;6568.671566421755;6596.670166491752;6489.5781774184525;6507.674616269073;6566.24488183865;6587.341265873374;6597.538172327891;6396.840078997876;6445.065465507381;6498.155239818982;6522.412982831568;6531.281559028598;6290.937090629218;6468.191476065492;6470.579412338183;6470.708818103102;6477.77327793525;6294.748210071546;6316.62100273985;6318.399752023551;6320.399562041599;6335.556511044183 03:38:24 INFO - PID 3599 | |1;ext.html;823.8393513264916;871.5947084605659;903.9186473217413;907.2832462354738;1164.6331405607239;29302.999999999975;30845.38309233814;41021.58978410219;41902.37146442798;42002.57997420025;41026.79486602563;41301.38047929276;41367.379489307685;41404;41486.58513414865;7726.613669316525;7827.1390147083775;7878.172791856859;7884.724034658788;7895.131535531115;7696.615169241515;7793.844123117534;7802.375809935192;7809.804754881145;7811.648475818602;7817.2964433201005;7847.058352997648;7853.410994175431;7873.763787086396;7880.566568838712;7767;7781.766547003599;7791.415643826679;7817.022872141011;7822.452428330019;7624.923750762477;7670.693172273102;7718.884216736754;7721.459497835153;7731.22687731228;38402.80798596017;38737.806310968546;38822;38826.80586597049;38968.41547376791;3539.8761043363615;3583.605803361628;3583.8208089595414;3586.605473397924;3590.8204589770407;30380.392392152033;30428.69571304281;30623.693763062307;30679.846600766854;30746;4633.559811817872;4722.126406614787;4788.784504697297;4807.879803004917;4838.806447742086;181897;182282;182708;182789;183568;46895;46974.29538556925;47191.764041180264;47367.763161183975;47457.28814067792;48353;48529.757351213724;48724.26913596299;48733.75633121883;48979.26531102036;15917.204139792964;16158;16160.434384796476;16202.43291484816;16237.188140592923;13583.66040848967;13585.388657510435;13586.116902401312;13601.727965440834;13616.93191534036;12765.170263933;12851.678708032374;12915.22508649484;13022;13126.540571079966;5368.946310536923;5426.375966763794;5443.646162999393;5512.6968016759465;5598.944010559923;953.7997020625608;982.2240430060173;990.7225976726528;1019.607451131305;1022.6880801355509;22110.668339974913;22195.556088878133;22496.437589060406;22536.323910282717;22542;1074.1728868771158;1083.8482612434266;1114.0419239454063;1117.0895719988346;1131.1911982932243;11773.646790596296;11991.160618756603;12219;15588.532344029698;16238.675226495405;216635;217369;218296;218592;221905;56398.718006409705;56497.43502565004;56587;56671.14993275104;56766;1462.8390877003521;1481.0595272002356;1481.7110663420744;1496.8877334199979;1504.383202886833;25730.456172629703;26063.218103456926;26470.07354742575;26666.7333326672;26766.464670706086;33995.66004340024;34161.31677366488;34235.65764342325;34251;34296.31407371889;184706;185189;185414;185539.0723046376;186156;32933.67066329306;32945.835270823496;32982.835085824416;33065.338693226484;33247.168820779676;41656.00383861868;42357;43149.78425107854;43355.132897342504;43448;29220.7077929218;29952;30899.845500773252;31183.84408078036;31213.53179702261;32262.35475290528;32361.190970225933;32662;32944.67055329512;32997.83501082479;35835.64164358428;38193.4270985941;38194.427083594324;38308.80845595754;38388;33086.17284567905;33986.490202646484;34392;34428.13929651779;34687.306253875286;23598.52802943897;23989.76010239945;25081.49837003213;25233;25813.870930645226 03:38:24 INFO - PID 3599 | |2;jquery.html;574.9568782341323;581.5870731780436;584.4039080138259;591.0513625630863;593.6853467662138;22164.88917555412;22341.888290558563;23241.186558470457;23324.06703731851;23399.298021059352;25645.61531577028;26403.603945940777;26470;26472.470550588976;26500.747436467875;6390.360963903608;6539.215294164707;6555.180602424697;6561.868762624768;6570.572912760679;6483.675816209195;6551.344865513434;6565.277819439858;6932.480063995195;6948.166220053576;6939.0979172707475;6943;6953.89569156463;6957.79126626202;6971.895421568655;6945.617991010492;6979.720811167573;6981.476389270774;6981.546199497042;6988.19635741886;6807.65961701913;6811.557248778863;6831.2144103428245;6839.2818754031105;6840.384365407137;31329.216769580715;31703;32058.679413205802;33282.33435331304;33313.66686333154;1900;1944.9611007779906;1945.4552725236815;1948.1136083082163;1949.6588097082954;20573;21045.78954210469;21078.894605527028;21080.78919210819;21084.156633734634;2420.394901274681;2449.228493024709;2532.9493410131695;2549.1842610364693;2561.5645340292194;137345;144743;147779.99999999892;147940;148085;20752;21998.89000554987;22139.225127120797;22628;23055.19306824253;22161.889190554266;23166.53666926652;23212.071517139462;43612.127757445305;43780.78109609432;10490.527926243338;10703.411312377739;10753.892461075446;10761.83857242142;10890.945545272223;9990;10058.145057670034;10106.090451859222;10124.633174521345;10134.493275336205;9349.672761453316;9409.576569054272;9488.857667134998;9605.327627066177;9613.423194608346;2631.25009372328;2637.129747183425;2706.3369474478877;2759.296379423274;2839.971600284012;913.9405938613969;949.5204921514729;967.2503809547657;974.4104816585864;985.8028394321165;19596.60806783885;19759.802401976085;20056.09747561363;20210.191592336436;20334.186632534827;997.3866072365443;1017.5726194998118;1025.4052649463292;1034.7826956339102;1040.8542804007445;10826.56693732242;11192.608258710907;11197.552097916156;11313.943430282796;11355.261907975955;465638;469399;517053;518607;529622;116493.41753291349;124429.37785311016;126337;127598.36200818936;128554;1244.464880101547;1252.3612957391795;1303.9739205215842;1306.6406738147086;1314.493919840868;19483.220671172996;20605.38183854487;20697.172113115306;21820.563588728455;21907.342779716633;59703.402965969784;60210;60488;60643.39356606554;61669.38330616816;69406.65296673484;69513.3048669527;69708;69794.65102674454;70206.64896675484;14780.556583302518;14983.850161498245;14996.550103496913;15086.019408738397;15171.393144274105;23445.648315274942;23570.175043873383;23859.642105368086;23991.760082398952;24194.75805241925;18542.536436589195;18563.535911602317;19483.415497535098;19679.31122410709;19852.106655200263;15616.297266622802;15927.922363080801;16085.276162572478;16144.354225830835;16769.916150419656;18890.622187556448;19388.321408750628;19644.0177991097;20348.38954831298;20397;14988.100713957198;15032.549023529311;15091.622709432351;15100.697986039999;15178.468753593572;17862.55343616368;18802.811971880652;18829.811701882805;19116.8088319115;19440 03:38:24 INFO - PID 3599 | |3;mootools.html;1356.3218390804598;1394.6931675031497;1430.3348942741623;1432.4771458417674;1439.352291468839;13510.189388636676;14003;14182.645433864158;14183.574492765221;14221.360038798253;14365.640858978531;14523.201223932705;14560.635984100376;14570.562883113496;14611.342489587967;2642.114891511346;2690.9192724218306;2694.8248363856383;2703.648525691657;2711.024031348713;2242.1703969531295;2327.499587588668;2329.46422322865;2652.4429869727383;2747.1346525844397;2668.546347120994;2676.8661566921674;2680.664916885389;2682;2690.0315886281023;2445.3153117127144;2471.5798314286612;2491.5888878335054;2495.0768215760286;2495.126705653016;2454.521368333176;2470.9135180268604;2486;2566.948661026769;2611.8824652890476;13893.305334733222;13972.650683732989;13994.510192143227;14006.859931400759;14080.084794488326;7142.821429464305;7422.740204092831;7451.366633836131;7461.962690186514;7466.328030477229;4872.244802055687;4971;5044.47033061527;5130.050940576006;5176.870578235536;1656.1388078199388;1676.3210899585688;1681.915904204785;1719.4583706132528;1731.5065206416236;23103.191388301497;23385.76614233853;23398.53202935949;23428.531429371316;23442.17952371659;6260.843478913063;6503.349665033459;6530.2816690164045;6577.309382514863;6607.603543787388;15676.294566744524;15723.528294151194;15753.606159846095;15779.211039447982;15782.526524204291;7465.738699145504;7623.847523049508;7643.9617801911745;7668.578228197391;7681.731139410093;6563.113979612786;6924.446044316443;7047.894281585678;7051.153861536648;7053.894191587027;4441.267190913463;4639.489656137822;5188.584913206934;5214.165733482625;5238.292830467838;1492.1718446262212;1495.6410461489165;1518;1527.4501179575343;1556.9532914012598;791.8891355210275;797.2266901105918;816.9135050382977;820.2207902492656;838.7693384319361;7013.579185248901;7380.188179300273;7391.039164908635;7402.518836275625;7541.208173141851;904.1862323908535;932.2728271947891;937.3298091864214;956.5073986896754;976.5849513956682;4406.845760398371;4523.909521809611;4554.4990051094355;4652.185867473178;4690.413698287714;18549.165287562093;18660;19389.418317450218;19426.611467770847;19491.610167796563;18707.90646046761;19039.238430462905;19532.511687207934;19685.803141968398;19722.802771972096;10531.684049478528;10769.461526923778;10820.945895270474;10855.999999999842;10901;7062.929370706227;7652.426068044808;7716.42126840478;7783.143854175923;7875.448718589644;16156.788240881746;16587.253573588976;16865.747013794557;17011.91494042522;17063.146842658065;16880.662386752443;17126.486205413858;17173.914130429268;17397.652046959243;17428.825711743226;9981.60073597048;11387.373694446886;11570.652880413601;11596.188266821242;11602.477888495207;11577.479013444583;13222.867771322164;13226.338683065616;13230.206187628773;13279.203247805162;9209.631614735335;9463.810723785347;9534.09426104519;9572.282078844253;9608.615655373986;10070.69787906364;10465;10656.840147397641;10662.253642244968;10908.61819836302;8702.173293537104;8818.823623527622;8823.779405514657;8824;8829.38194326391;6114;6453;6638.070670106093;6680.866382672222;6778.559393639595;6673.299303573055;6676.766313179015;6822.931770682229;6867.519273650796;6940.41006514452 03:38:24 INFO - PID 3599 | |4;prototype.html;479.50610870803075;480.4930798008102;512.3595505617977;514.539487158993;522.2114606943514;6161.322254551996;6209.565330426861;6226.813195604133;6247.2815626203;6278.121063051165;5922.3485416604135;6036.849078773022;6071.60534565253;6103.694815259241;6124.295705993812;4878.682885612424;4888.266759985995;4912.410510738708;4943.009479265047;4946.159152944008;4821.54195351441;4884.340614017097;4930.334404855353;4969.552740253376;4982.87542811429;4897.853064408074;4933.605311575066;4938.629602779806;4942.752862356868;4955;4945.258211268302;4978.56050535237;4979.6265280104135;4981.576565991895;5029.874253143664;4401.141777353385;4402.675979635387;4438.579654510558;4449.443819522559;4451.910961780746;5949.6430214187285;6014.0678194879865;6094.847628809271;6120.755169793203;6131.325554189036;5674.460926212003;5683.318001839764;5690.918725442194;5705.6006079574445;5707.4577915098;6356.618602883796;6406.263279722845;6450.419462248373;6542.4438922691625;6567.310432404624;4805.471398146201;4826.420829500483;4867.245576935581;4868.683535570177;4872.707637541759;6777.762778302736;6848.691808868563;6922;6935.54918930278;6936.237013928464;4885.7312847793;4905.092557876804;4917.631177661689;4944.975275123673;5012.548870601663;5011.57401620859;5019.698818070928;5037.596992240612;5043.9747801260755;5051.621128415383;5641.379448260688;5677.489025987598;5698.572607054488;5726.398728133569;5729.341125770589;6417.679116044179;6494.616134109458;6525.771597994142;6550.443212327005;6669.533132680665;1627.8290779468223;1666.275170300923;1696.5080126763348;1750.0986991699285;1752.1589636974325;1190.5892467098838;1204.1931905623078;1214.4455097475245;1219.189239155969;1227.300438749904;5684.403137670568;5706.25818643582;5707.6575405475805;5734.741936612862;5756.884862302731;6673.366030227121;6739.483616186417;6753.49348798842;6757.493188010821;6774.796756097325;5851.970740146272;5858.355580886099;5863.765449381977;5871.588988770831;5900.321463031806;3616.439451884962;3638.5451818522683;3646.908827279286;3651.1419816343137;3659.524261845996;6525.042487253805;6541.247756507967;6541.771038013647;6549.115869357671;6577.934220657828;6823.283555226729;6872.312768723088;6901.930980690229;6906.309369063053;6971.5468494547695;6451.741930322828;6524.575902566318;6565.770198043046;6567.179102612174;6573.619539896676;6646.90029649546;6650.20197576294;6679.432248258952;6705.530612857053;6763.289854565397;6508.186476690414;6588.637624930675;6627.271000190071;6682.866342673216;6740.898886516804;6475.255345635123;6475.255345635313;6477.995910633859;6491;6529.183852018497;4938.950610493993;4982.676126051795;4992.8751781205765;5000.149974504416;5007.24891266313;6460.515461340325;6604.14146160996;6741.662916854236;6748.898766518408;6751.729930802713;6306.013605714185;6356.4279214870885;6402.871942561216;6424.169111803114;6473.126127972663;6413.390727880844;6461.61230326182;6534.085228068169;6542.967285163544;6547.639879806657;6466.805995820133;6495.382938620824;6504.284528701744;6517.340101827216;6532.771353002622;6410.903836442364;6484.935150648622;6490.610563366213;6497.577657452251;6555.80332590023;6232.376762323732;6296.842578935563;6316.368363163647;6353.52348573868;6384.138141350858 03:38:24 INFO - PID 3599 | |5;yui.html;896.0546623312405;901.333013569958;920.6731610278355;928.2295694573505;929.1080562659846;17821.55446113846;18487.075646217687;18539.443816685503;18550;18552.165152568166;16489.67020659586;16535.83464165361;16621.91689041556;19083.90458047708;21191.258305959294;1012.6708819633611;1028.0182425783396;1036.455860673149;1037.3827572594294;1041.0786453988237;1061.4480470155515;1063.3726101600055;1065.9680209593726;1066.317556763672;1066.317556763672;1048.1248157788257;1086.435053772042;1092.017184533918;1122.685648018552;1130.1354463835173;1071.9732006699855;1074.08702602787;1074.3661239868477;1074.5325783284297;1075.623531763884;778.7313376884965;779.419332597213;779.4699604269094;782.0732432068017;783.1815752538611;17787;17890.91054544732;18187;18336.63326733458;19280.4215873524;20343.49141271465;20465;20523.58952820935;20685.172593096257;20825.8958705207;15206.391744330214;15666.746660267152;15748.291326890318;15780.132092734895;15790.763138552931;3470.1151206442446;3487.163080860575;3494.0391392367046;3495.9825200873834;3500.929981400383;111685.44157279162;112189;112626.43686781514;114452;117281;16416.671666566603;17033.659326813395;17034.31862725502;17035.063071531185;17039.574010649834;28857.711422885503;30303.93936212222;30387.088387348416;30540.694593054228;30661.846690766404;9041;9208.355415121012;9276.02601726809;9279.257659387367;9290.303227257982;4266.616004559604;4387.846425375096;4390.034192477649;4405.163019026375;4410.80151393188;5004.174311238605;5140.665856719302;5149.45930677281;5149.819756308511;5162.948370516322;1007.5012868630075;1099.1371773158032;1101.3502033800096;1106.0598491282506;1107.595727559451;668.6289109544152;679.3444326225216;684.1311534351346;687.1376422589618;687.4603436302478;10704.19718521114;11335.263207891461;11838.704032399086;11929.642110736691;12326.50693972231;632.5666918161074;653.4641593892962;670.7283550162192;681.3697329969758;685.8491131951063;5168.3539557555305;5193.195054766517;5251.735028364137;5295.311609490742;5460;95089.52455237818;95908;100244;102270;104743;85360;86456.56771716102;86870;87781.5610921954;92004;75266;76946.61526692331;77635.22364776392;77651;78957.60521197357;10154.644587439405;10304.845427318745;10326.535305911102;10399.740006499898;10516.421596812248;34380.48429273513;36003.0999225011;36383.090422739646;37022.44466332953;37420.43869341908;45423.77288113538;45474;45771.31343029791;46133.76933115313;47464;15374.461893833663;15628.765568516708;15659.530214093595;15738.213089345716;15771.44799931997;17227.483175504756;17275.82724172742;17324.39364622232;17438.564035899202;17596.472105836845;14822.25888705582;14952.401903924156;14981.325840336993;14998.925005374904;15046.62383440379;16172.595685107966;16242.593935151715;17133.400330988854;17608.73586896223;17794.288228470716;16832.242549085076;17322.740158897373;17366.47900562985;17370.91314543419;17392.30430782805;12990.805137922749;13069.738605228033;13186.011049171158;13218.669533261746;13431.86568134306;13004.869951300745;13026;13203.537876174289;13464.93267533656;13672.316384180951 03:38:24 INFO - PID 3599 | __end_tp_report 03:38:24 INFO - PID 3599 | __start_cc_report 03:38:24 INFO - PID 3599 | _x_x_mozilla_cycle_collect,363 03:38:24 INFO - PID 3599 | __end_cc_report 03:38:24 INFO - PID 3599 | __startTimestamp1501583904285__endTimestamp 03:38:24 INFO - PID 3599 | 03:38:24 INFO - PID 3599 | ------- Summary: start ------- 03:38:24 INFO - PID 3599 | Number of tests: 6 03:38:24 INFO - PID 3599 | 03:38:24 INFO - PID 3599 | [#0] dojo.html Cycles:165 Average:6120.76 Median:6405.14 stddev:720.37 (11.2%) stddev-sans-first:683.07 03:38:24 INFO - PID 3599 | Values: 3121.1 3154.2 3168.0 3185.6 3186.4 6108.1 6171.2 6213.5 6223.1 6231.8 6060.8 6178.1 6190.1 6200.2 6218.0 5662.9 5665.9 5700.7 5712.8 5716.9 5684.4 5734.9 5744.9 5754.5 5756.7 5708.3 5713.9 5746.8 5757.1 5800.2 5659.7 5690.6 5724.3 5725.9 5761.9 5421.7 5506.6 5567.6 5573.4 5610.1 6049.1 6078.1 6082.7 6176.8 6179.3 6447.4 6619.1 6687.2 6694.0 6718.4 6607.5 6646.4 6702.5 6734.0 6764.5 6225.3 6254.0 6255.6 6260.3 6260.5 6802.4 6871.6 6878.1 6879.4 6886.4 6447.6 6458.9 6461.8 6524.8 6566.2 6492.2 6607.9 6611.2 6616.9 6650.9 6586.2 6609.7 6616.3 6616.4 6626.4 6622.8 6633.9 6652.4 6653.7 6663.3 5353.8 5368.6 5370.0 5371.3 5371.9 4625.4 4642.4 4643.2 4648.5 4659.6 5664.8 5738.5 5739.0 5743.3 5772.2 6591.1 6613.8 6618.1 6619.6 6630.3 5719.9 5742.1 5795.8 5798.0 5800.2 5658.8 5723.2 5749.1 5804.0 5804.8 6405.1 6410.0 6416.2 6431.3 6525.9 6719.5 6719.7 6732.9 6741.5 6752.5 6443.0 6522.4 6526.0 6526.3 6538.7 6511.5 6516.9 6520.2 6627.5 6660.7 6623.2 6645.0 6678.0 6691.3 6733.6 6533.3 6562.1 6566.2 6568.7 6596.7 6489.6 6507.7 6566.2 6587.3 6597.5 6396.8 6445.1 6498.2 6522.4 6531.3 6290.9 6468.2 6470.6 6470.7 6477.8 6294.7 6316.6 6318.4 6320.4 6335.6 03:38:24 INFO - PID 3599 | 03:38:24 INFO - PID 3599 | [#1] ext.html Cycles:180 Average:36631.24 Median:34273.66 stddev:50749.85 (148.1%) stddev-sans-first:50820.99 03:38:24 INFO - PID 3599 | Values: 823.8 871.6 903.9 907.3 1164.6 29303.0 30845.4 41021.6 41902.4 42002.6 41026.8 41301.4 41367.4 41404.0 41486.6 7726.6 7827.1 7878.2 7884.7 7895.1 7696.6 7793.8 7802.4 7809.8 7811.6 7817.3 7847.1 7853.4 7873.8 7880.6 7767.0 7781.8 7791.4 7817.0 7822.5 7624.9 7670.7 7718.9 7721.5 7731.2 38402.8 38737.8 38822.0 38826.8 38968.4 3539.9 3583.6 3583.8 3586.6 3590.8 30380.4 30428.7 30623.7 30679.8 30746.0 4633.6 4722.1 4788.8 4807.9 4838.8 181897.0 182282.0 182708.0 182789.0 183568.0 46895.0 46974.3 47191.8 47367.8 47457.3 48353.0 48529.8 48724.3 48733.8 48979.3 15917.2 16158.0 16160.4 16202.4 16237.2 13583.7 13585.4 13586.1 13601.7 13616.9 12765.2 12851.7 12915.2 13022.0 13126.5 5368.9 5426.4 5443.6 5512.7 5598.9 953.8 982.2 990.7 1019.6 1022.7 22110.7 22195.6 22496.4 22536.3 22542.0 1074.2 1083.8 1114.0 1117.1 1131.2 11773.6 11991.2 12219.0 15588.5 16238.7 216635.0 217369.0 218296.0 218592.0 221905.0 56398.7 56497.4 56587.0 56671.1 56766.0 1462.8 1481.1 1481.7 1496.9 1504.4 25730.5 26063.2 26470.1 26666.7 26766.5 33995.7 34161.3 34235.7 34251.0 34296.3 184706.0 185189.0 185414.0 185539.1 186156.0 32933.7 32945.8 32982.8 33065.3 33247.2 41656.0 42357.0 43149.8 43355.1 43448.0 29220.7 29952.0 30899.8 31183.8 31213.5 32262.4 32361.2 32662.0 32944.7 32997.8 35835.6 38193.4 38194.4 38308.8 38388.0 33086.2 33986.5 34392.0 34428.1 34687.3 23598.5 23989.8 25081.5 25233.0 25813.9 03:38:24 INFO - PID 3599 | 03:38:24 INFO - PID 3599 | [#2] jquery.html Cycles:180 Average:36419.90 Median:23110.86 stddev:84630.11 (366.2%) stddev-sans-first:84824.73 03:38:24 INFO - PID 3599 | Values: 575.0 581.6 584.4 591.1 593.7 22164.9 22341.9 23241.2 23324.1 23399.3 25645.6 26403.6 26470.0 26472.5 26500.7 6390.4 6539.2 6555.2 6561.9 6570.6 6483.7 6551.3 6565.3 6932.5 6948.2 6939.1 6943.0 6953.9 6957.8 6971.9 6945.6 6979.7 6981.5 6981.5 6988.2 6807.7 6811.6 6831.2 6839.3 6840.4 31329.2 31703.0 32058.7 33282.3 33313.7 1900.0 1945.0 1945.5 1948.1 1949.7 20573.0 21045.8 21078.9 21080.8 21084.2 2420.4 2449.2 2532.9 2549.2 2561.6 137345.0 144743.0 147780.0 147940.0 148085.0 20752.0 21998.9 22139.2 22628.0 23055.2 22161.9 23166.5 23212.1 43612.1 43780.8 10490.5 10703.4 10753.9 10761.8 10890.9 9990.0 10058.1 10106.1 10124.6 10134.5 9349.7 9409.6 9488.9 9605.3 9613.4 2631.3 2637.1 2706.3 2759.3 2840.0 913.9 949.5 967.3 974.4 985.8 19596.6 19759.8 20056.1 20210.2 20334.2 997.4 1017.6 1025.4 1034.8 1040.9 10826.6 11192.6 11197.6 11313.9 11355.3 465638.0 469399.0 517053.0 518607.0 529622.0 116493.4 124429.4 126337.0 127598.4 128554.0 1244.5 1252.4 1304.0 1306.6 1314.5 19483.2 20605.4 20697.2 21820.6 21907.3 59703.4 60210.0 60488.0 60643.4 61669.4 69406.7 69513.3 69708.0 69794.7 70206.6 14780.6 14983.9 14996.6 15086.0 15171.4 23445.6 23570.2 23859.6 23991.8 24194.8 18542.5 18563.5 19483.4 19679.3 19852.1 15616.3 15927.9 16085.3 16144.4 16769.9 18890.6 19388.3 19644.0 20348.4 20397.0 14988.1 15032.5 15091.6 15100.7 15178.5 17862.6 18802.8 18829.8 19116.8 19440.0 03:38:24 INFO - PID 3599 | 03:38:24 INFO - PID 3599 | [#3] mootools.html Cycles:180 Average:8561.78 Median:2589.42 stddev:5974.22 (230.7%) stddev-sans-first:5966.45 03:38:24 INFO - PID 3599 | Values: 1356.3 1394.7 1430.3 1432.5 1439.4 13510.2 14003.0 14182.6 14183.6 14221.4 14365.6 14523.2 14560.6 14570.6 14611.3 2642.1 2690.9 2694.8 2703.6 2711.0 2242.2 2327.5 2329.5 2652.4 2747.1 2668.5 2676.9 2680.7 2682.0 2690.0 2445.3 2471.6 2491.6 2495.1 2495.1 2454.5 2470.9 2486.0 2566.9 2611.9 13893.3 13972.7 13994.5 14006.9 14080.1 7142.8 7422.7 7451.4 7462.0 7466.3 4872.2 4971.0 5044.5 5130.1 5176.9 1656.1 1676.3 1681.9 1719.5 1731.5 23103.2 23385.8 23398.5 23428.5 23442.2 6260.8 6503.3 6530.3 6577.3 6607.6 15676.3 15723.5 15753.6 15779.2 15782.5 7465.7 7623.8 7644.0 7668.6 7681.7 6563.1 6924.4 7047.9 7051.2 7053.9 4441.3 4639.5 5188.6 5214.2 5238.3 1492.2 1495.6 1518.0 1527.5 1557.0 791.9 797.2 816.9 820.2 838.8 7013.6 7380.2 7391.0 7402.5 7541.2 904.2 932.3 937.3 956.5 976.6 4406.8 4523.9 4554.5 4652.2 4690.4 18549.2 18660.0 19389.4 19426.6 19491.6 18707.9 19039.2 19532.5 19685.8 19722.8 10531.7 10769.5 10820.9 10856.0 10901.0 7062.9 7652.4 7716.4 7783.1 7875.4 16156.8 16587.3 16865.7 17011.9 17063.1 16880.7 17126.5 17173.9 17397.7 17428.8 9981.6 11387.4 11570.7 11596.2 11602.5 11577.5 13222.9 13226.3 13230.2 13279.2 9209.6 9463.8 9534.1 9572.3 9608.6 10070.7 10465.0 10656.8 10662.3 10908.6 8702.2 8818.8 8823.8 8824.0 8829.4 6114.0 6453.0 6638.1 6680.9 6778.6 6673.3 6676.8 6822.9 6867.5 6940.4 03:38:24 INFO - PID 3599 | 03:38:24 INFO - PID 3599 | [#4] prototype.html Cycles:180 Average:5489.19 Median:6099.27 stddev:1544.83 (25.3%) stddev-sans-first:1502.71 03:38:24 INFO - PID 3599 | Values: 479.5 480.5 512.4 514.5 522.2 6161.3 6209.6 6226.8 6247.3 6278.1 5922.3 6036.8 6071.6 6103.7 6124.3 4878.7 4888.3 4912.4 4943.0 4946.2 4821.5 4884.3 4930.3 4969.6 4982.9 4897.9 4933.6 4938.6 4942.8 4955.0 4945.3 4978.6 4979.6 4981.6 5029.9 4401.1 4402.7 4438.6 4449.4 4451.9 5949.6 6014.1 6094.8 6120.8 6131.3 5674.5 5683.3 5690.9 5705.6 5707.5 6356.6 6406.3 6450.4 6542.4 6567.3 4805.5 4826.4 4867.2 4868.7 4872.7 6777.8 6848.7 6922.0 6935.5 6936.2 4885.7 4905.1 4917.6 4945.0 5012.5 5011.6 5019.7 5037.6 5044.0 5051.6 5641.4 5677.5 5698.6 5726.4 5729.3 6417.7 6494.6 6525.8 6550.4 6669.5 1627.8 1666.3 1696.5 1750.1 1752.2 1190.6 1204.2 1214.4 1219.2 1227.3 5684.4 5706.3 5707.7 5734.7 5756.9 6673.4 6739.5 6753.5 6757.5 6774.8 5852.0 5858.4 5863.8 5871.6 5900.3 3616.4 3638.5 3646.9 3651.1 3659.5 6525.0 6541.2 6541.8 6549.1 6577.9 6823.3 6872.3 6901.9 6906.3 6971.5 6451.7 6524.6 6565.8 6567.2 6573.6 6646.9 6650.2 6679.4 6705.5 6763.3 6508.2 6588.6 6627.3 6682.9 6740.9 6475.3 6475.3 6478.0 6491.0 6529.2 4939.0 4982.7 4992.9 5000.1 5007.2 6460.5 6604.1 6741.7 6748.9 6751.7 6306.0 6356.4 6402.9 6424.2 6473.1 6413.4 6461.6 6534.1 6543.0 6547.6 6466.8 6495.4 6504.3 6517.3 6532.8 6410.9 6484.9 6490.6 6497.6 6555.8 6232.4 6296.8 6316.4 6353.5 6384.1 03:38:24 INFO - PID 3599 | 03:38:24 INFO - PID 3599 | [#5] yui.html Cycles:180 Average:21250.62 Median:17790.64 stddev:28316.32 (159.2%) stddev-sans-first:28354.51 03:38:24 INFO - PID 3599 | Values: 896.1 901.3 920.7 928.2 929.1 17821.6 18487.1 18539.4 18550.0 18552.2 16489.7 16535.8 16621.9 19083.9 21191.3 1012.7 1028.0 1036.5 1037.4 1041.1 1061.4 1063.4 1066.0 1066.3 1066.3 1048.1 1086.4 1092.0 1122.7 1130.1 1072.0 1074.1 1074.4 1074.5 1075.6 778.7 779.4 779.5 782.1 783.2 17787.0 17890.9 18187.0 18336.6 19280.4 20343.5 20465.0 20523.6 20685.2 20825.9 15206.4 15666.7 15748.3 15780.1 15790.8 3470.1 3487.2 3494.0 3496.0 3500.9 111685.4 112189.0 112626.4 114452.0 117281.0 16416.7 17033.7 17034.3 17035.1 17039.6 28857.7 30303.9 30387.1 30540.7 30661.8 9041.0 9208.4 9276.0 9279.3 9290.3 4266.6 4387.8 4390.0 4405.2 4410.8 5004.2 5140.7 5149.5 5149.8 5162.9 1007.5 1099.1 1101.4 1106.1 1107.6 668.6 679.3 684.1 687.1 687.5 10704.2 11335.3 11838.7 11929.6 12326.5 632.6 653.5 670.7 681.4 685.8 5168.4 5193.2 5251.7 5295.3 5460.0 95089.5 95908.0 100244.0 102270.0 104743.0 85360.0 86456.6 86870.0 87781.6 92004.0 75266.0 76946.6 77635.2 77651.0 78957.6 10154.6 10304.8 10326.5 10399.7 10516.4 34380.5 36003.1 36383.1 37022.4 37420.4 45423.8 45474.0 45771.3 46133.8 47464.0 15374.5 15628.8 15659.5 15738.2 15771.4 17227.5 17275.8 17324.4 17438.6 17596.5 14822.3 14952.4 14981.3 14998.9 15046.6 16172.6 16242.6 17133.4 17608.7 17794.3 16832.2 17322.7 17366.5 17370.9 17392.3 12990.8 13069.7 13186.0 13218.7 13431.9 13004.9 13026.0 13203.5 13464.9 13672.3 03:38:24 INFO - PID 3599 | -------- Summary: end -------- 03:38:24 INFO - PID 3599 | 03:38:24 INFO - TEST-INFO | 3599: exit 0 03:38:24 INFO - TEST-OK | dromaeo_css | took 1114441ms 03:38:24 INFO - TEST-START | kraken 03:38:24 INFO - Initialising browser for kraken test... 03:38:24 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52421/getInfo.html -profile /tmp/tmpmCrOZJ/profile 03:38:24 INFO - TEST-INFO | started process 3959 (/builds/slave/test/build/application/firefox/firefox http://localhost:52421/getInfo.html) 03:38:30 INFO - TEST-INFO | 3959: exit 0 03:38:30 INFO - Browser initialized. 03:38:30 INFO - Running cycle 1/1 for kraken test... 03:38:30 INFO - TEST-INFO | started process 4166 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmCrOZJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:38:31 INFO - PID 4166 | 03:38:31 INFO - PID 4166 | (/builds/slave/test/build/application/firefox/firefox:4215): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:38:31 INFO - PID 4166 | 03:38:42 INFO - PID 4166 | RSS: Main: 171974656 03:38:42 INFO - PID 4166 | 03:38:44 INFO - PID 4166 | Cycle 1(1): loaded http://localhost:52421/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52421/tests/kraken/driver.html?testName=audio-beat-detection) 03:38:45 INFO - PID 4166 | RSS: Main: 177811456 03:38:45 INFO - PID 4166 | 03:38:48 INFO - PID 4166 | Cycle 1(1): loaded http://localhost:52421/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52421/tests/kraken/driver.html?testName=audio-dft) 03:38:48 INFO - PID 4166 | RSS: Main: 178003968 03:38:48 INFO - PID 4166 | 03:38:51 INFO - PID 4166 | Cycle 1(1): loaded http://localhost:52421/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52421/tests/kraken/driver.html?testName=audio-fft) 03:38:52 INFO - PID 4166 | RSS: Main: 171581440 03:38:52 INFO - PID 4166 | 03:38:54 INFO - PID 4166 | Cycle 1(1): loaded http://localhost:52421/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52421/tests/kraken/driver.html?testName=audio-oscillator) 03:38:55 INFO - PID 4166 | RSS: Main: 171958272 03:38:55 INFO - PID 4166 | 03:38:57 INFO - PID 4166 | Cycle 1(1): loaded http://localhost:52421/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52421/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:38:58 INFO - PID 4166 | RSS: Main: 173252608 03:38:58 INFO - PID 4166 | 03:39:01 INFO - PID 4166 | Cycle 1(1): loaded http://localhost:52421/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52421/tests/kraken/driver.html?testName=imaging-darkroom) 03:39:01 INFO - PID 4166 | RSS: Main: 175538176 03:39:01 INFO - PID 4166 | 03:39:05 INFO - PID 4166 | Cycle 1(1): loaded http://localhost:52421/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52421/tests/kraken/driver.html?testName=imaging-desaturate) 03:39:05 INFO - PID 4166 | RSS: Main: 175890432 03:39:05 INFO - PID 4166 | 03:39:08 INFO - PID 4166 | Cycle 1(1): loaded http://localhost:52421/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52421/tests/kraken/driver.html?testName=json-parse-financial) 03:39:09 INFO - PID 4166 | RSS: Main: 175611904 03:39:09 INFO - PID 4166 | 03:39:10 INFO - PID 4166 | Cycle 1(1): loaded http://localhost:52421/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52421/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:39:11 INFO - PID 4166 | RSS: Main: 175513600 03:39:11 INFO - PID 4166 | 03:39:13 INFO - PID 4166 | Cycle 1(1): loaded http://localhost:52421/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52421/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:39:14 INFO - PID 4166 | RSS: Main: 176005120 03:39:14 INFO - PID 4166 | 03:39:16 INFO - PID 4166 | Cycle 1(1): loaded http://localhost:52421/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52421/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:39:16 INFO - PID 4166 | RSS: Main: 172650496 03:39:16 INFO - PID 4166 | 03:39:19 INFO - PID 4166 | Cycle 1(1): loaded http://localhost:52421/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52421/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:39:20 INFO - PID 4166 | RSS: Main: 173395968 03:39:20 INFO - PID 4166 | 03:39:23 INFO - PID 4166 | Cycle 1(1): loaded http://localhost:52421/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52421/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:39:24 INFO - PID 4166 | RSS: Main: 173510656 03:39:24 INFO - PID 4166 | 03:39:25 INFO - PID 4166 | Cycle 1(1): loaded http://localhost:52421/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52421/tests/kraken/driver.html?testName=ai-astar) 03:39:26 INFO - PID 4166 | RSS: Main: 174948352 03:39:26 INFO - PID 4166 | 03:39:26 INFO - PID 4166 | __start_tp_report 03:39:26 INFO - PID 4166 | _x_x_mozilla_page_load 03:39:26 INFO - PID 4166 | _x_x_mozilla_page_load_details 03:39:26 INFO - PID 4166 | |i|pagename|runs| 03:39:26 INFO - PID 4166 | |0;ai-astar;98;108;106;108;108;113;108;99;111;111 03:39:26 INFO - PID 4166 | |1;audio-beat-detection;151;117;147;167;120;143;144;117;169;118 03:39:26 INFO - PID 4166 | |2;audio-dft;163;163;162;164;164;162;163;163;167;166 03:39:26 INFO - PID 4166 | |3;audio-fft;76;74;77;176;75;74;74;89;103;74 03:39:26 INFO - PID 4166 | |4;audio-oscillator;79;79;126;83;78;78;131;79;79;125 03:39:26 INFO - PID 4166 | |5;imaging-gaussian-blur;113;114;115;114;114;114;114;115;114;114 03:39:26 INFO - PID 4166 | |6;imaging-darkroom;151;151;151;151;151;151;150;150;151;152 03:39:26 INFO - PID 4166 | |7;imaging-desaturate;94;94;93;94;94;94;94;95;94;95 03:39:26 INFO - PID 4166 | |8;json-parse-financial;56;59;58;61;57;56;57;56;56;56 03:39:26 INFO - PID 4166 | |9;json-stringify-tinderbox;54;54;53;54;54;53;54;55;53;55 03:39:26 INFO - PID 4166 | |10;stanford-crypto-aes;80;90;85;79;83;89;80;91;92;87 03:39:26 INFO - PID 4166 | |11;stanford-crypto-ccm;129;129;131;135;126;135;131;145;128;166 03:39:26 INFO - PID 4166 | |12;stanford-crypto-pbkdf2;179;188;182;177;183;185;180;185;184;184 03:39:26 INFO - PID 4166 | |13;stanford-crypto-sha256-iterative;69;68;67;67;69;67;68;67;66;68 03:39:26 INFO - PID 4166 | __end_tp_report 03:39:26 INFO - PID 4166 | __start_cc_report 03:39:26 INFO - PID 4166 | _x_x_mozilla_cycle_collect,946 03:39:26 INFO - PID 4166 | __end_cc_report 03:39:26 INFO - PID 4166 | __startTimestamp1501583966029__endTimestamp 03:39:26 INFO - PID 4166 | 03:39:26 INFO - PID 4166 | ------- Summary: start ------- 03:39:26 INFO - PID 4166 | Number of tests: 14 03:39:26 INFO - PID 4166 | 03:39:26 INFO - PID 4166 | [#0] ai-astar Cycles:10 Average:107.00 Median:111.00 stddev:4.92 (4.4%) stddev-sans-first:4.00 03:39:26 INFO - PID 4166 | Values: 98.0 108.0 106.0 108.0 108.0 113.0 108.0 99.0 111.0 111.0 03:39:26 INFO - PID 4166 | 03:39:26 INFO - PID 4166 | [#1] audio-beat-detection Cycles:10 Average:139.30 Median:145.50 stddev:20.28 (13.9%) stddev-sans-first:21.07 03:39:26 INFO - PID 4166 | Values: 151.0 117.0 147.0 167.0 120.0 143.0 144.0 117.0 169.0 118.0 03:39:26 INFO - PID 4166 | 03:39:26 INFO - PID 4166 | [#2] audio-dft Cycles:10 Average:163.70 Median:163.50 stddev:1.64 (1.0%) stddev-sans-first:1.72 03:39:26 INFO - PID 4166 | Values: 163.0 163.0 162.0 164.0 164.0 162.0 163.0 163.0 167.0 166.0 03:39:26 INFO - PID 4166 | 03:39:26 INFO - PID 4166 | [#3] audio-fft Cycles:10 Average:89.20 Median:74.50 stddev:31.93 (42.9%) stddev-sans-first:33.50 03:39:26 INFO - PID 4166 | Values: 76.0 74.0 77.0 176.0 75.0 74.0 74.0 89.0 103.0 74.0 03:39:26 INFO - PID 4166 | 03:39:26 INFO - PID 4166 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:79.00 stddev:23.30 (29.5%) stddev-sans-first:24.10 03:39:26 INFO - PID 4166 | Values: 79.0 79.0 126.0 83.0 78.0 78.0 131.0 79.0 79.0 125.0 03:39:26 INFO - PID 4166 | 03:39:26 INFO - PID 4166 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.44 03:39:26 INFO - PID 4166 | Values: 113.0 114.0 115.0 114.0 114.0 114.0 114.0 115.0 114.0 114.0 03:39:26 INFO - PID 4166 | 03:39:26 INFO - PID 4166 | [#6] imaging-darkroom Cycles:10 Average:150.90 Median:151.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 03:39:26 INFO - PID 4166 | Values: 151.0 151.0 151.0 151.0 151.0 151.0 150.0 150.0 151.0 152.0 03:39:26 INFO - PID 4166 | 03:39:26 INFO - PID 4166 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 03:39:26 INFO - PID 4166 | Values: 94.0 94.0 93.0 94.0 94.0 94.0 94.0 95.0 94.0 95.0 03:39:26 INFO - PID 4166 | 03:39:26 INFO - PID 4166 | [#8] json-parse-financial Cycles:10 Average:57.20 Median:57.00 stddev:1.69 (3.0%) stddev-sans-first:1.73 03:39:26 INFO - PID 4166 | Values: 56.0 59.0 58.0 61.0 57.0 56.0 57.0 56.0 56.0 56.0 03:39:26 INFO - PID 4166 | 03:39:26 INFO - PID 4166 | [#9] json-stringify-tinderbox Cycles:10 Average:53.90 Median:54.00 stddev:0.74 (1.4%) stddev-sans-first:0.78 03:39:26 INFO - PID 4166 | Values: 54.0 54.0 53.0 54.0 54.0 53.0 54.0 55.0 53.0 55.0 03:39:26 INFO - PID 4166 | 03:39:26 INFO - PID 4166 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:88.00 stddev:4.90 (5.6%) stddev-sans-first:4.76 03:39:26 INFO - PID 4166 | Values: 80.0 90.0 85.0 79.0 83.0 89.0 80.0 91.0 92.0 87.0 03:39:26 INFO - PID 4166 | 03:39:26 INFO - PID 4166 | [#11] stanford-crypto-ccm Cycles:10 Average:135.50 Median:133.00 stddev:11.98 (9.0%) stddev-sans-first:12.48 03:39:26 INFO - PID 4166 | Values: 129.0 129.0 131.0 135.0 126.0 135.0 131.0 145.0 128.0 166.0 03:39:26 INFO - PID 4166 | 03:39:26 INFO - PID 4166 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.70 Median:184.00 stddev:3.27 (1.8%) stddev-sans-first:3.18 03:39:26 INFO - PID 4166 | Values: 179.0 188.0 182.0 177.0 183.0 185.0 180.0 185.0 184.0 184.0 03:39:26 INFO - PID 4166 | 03:39:26 INFO - PID 4166 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:0.97 (1.4%) stddev-sans-first:0.88 03:39:26 INFO - PID 4166 | Values: 69.0 68.0 67.0 67.0 69.0 67.0 68.0 67.0 66.0 68.0 03:39:26 INFO - PID 4166 | -------- Summary: end -------- 03:39:26 INFO - PID 4166 | 03:39:26 INFO - PID 4166 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:39:26 INFO - TEST-INFO | 4166: exit 0 03:39:26 INFO - TEST-OK | kraken | took 61760ms 03:39:26 INFO - SUITE-END | took 1176s 03:39:27 INFO - Completed test suite (00:19:36) 03:39:27 INFO - javascript benchmark 03:39:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8410.882762797888, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3121.0792816119256, 3154.1956801015735, 3168.0337497063415, 3185.601799775028, 3186.394585028844, 6108.144859719643, 6171.197744293241, 6213.502919766419, 6223.097650840626, 6231.781887633933, 6060.818175454732, 6178.073289006651, 6190.1333813266065, 6200.1629779979685, 6217.968910155454, 5662.943370566303, 5665.886682266362, 5700.743466543995, 5712.828615141552, 5716.942830571704, 5684.37471878093, 5734.885302293973, 5744.856378590548, 5754.482096611304, 5756.683382413966, 5708.286464191976, 5713.885722285552, 5746.770129194827, 5757.107648314518, 5800.216970708963, 5659.660420374749, 5690.60165788395, 5724.284464441924, 5725.885482290351, 5761.855953601152, 5421.749764629147, 5506.614536982413, 5567.582431317667, 5573.386927437979, 5610.13042978339, 6049.062395328687, 6078.088286756978, 6082.6600126742815, 6176.81469555914, 6179.258488981351, 6447.3552644735155, 6619.106420633201, 6687.164104486939, 6694.0, 6718.395344419026, 6607.471402287805, 6646.368594983469, 6702.463802895757, 6733.966330168367, 6764.492663050241, 6225.28409232935, 6254.0, 6255.593386429913, 6260.311365749764, 6260.5304602154565, 6802.387785099365, 6871.622060786704, 6878.105846239957, 6879.415249703831, 6886.449084073262, 6447.580907241015, 6458.903116453257, 6461.8061458156335, 6524.804255872332, 6566.212054553484, 6492.188476440445, 6607.900881486781, 6611.173603299588, 6616.900746488806, 6650.902601070769, 6586.176727909012, 6609.702563384563, 6616.27221005689, 6616.437602803816, 6626.436752875963, 6622.801315960529, 6633.900491492632, 6652.401283884474, 6653.73385064592, 6663.300353462913, 5353.839384818461, 5368.570514358842, 5370.0065487884085, 5371.301730775052, 5371.892562148813, 4625.421822272216, 4642.419697537808, 4643.233866412004, 4648.48866624671, 4659.5573420525, 5664.773409063591, 5738.454846789548, 5739.0, 5743.282089738783, 5772.19189313488, 6591.077249185118, 6613.768518101843, 6618.139641846626, 6619.602823830585, 6630.270670226271, 5719.913216488937, 5742.13867919807, 5795.797147099831, 5797.971010145006, 5800.216970708984, 5658.773649054074, 5723.227364305849, 5749.108888122348, 5803.970980145156, 5804.767809287581, 6405.135306733625, 6410.038494225903, 6416.197975253093, 6431.260405053479, 6525.902111468236, 6719.529632925648, 6719.731210751515, 6732.899006514808, 6741.460683145435, 6752.527323087336, 6442.967785161044, 6522.445592124723, 6526.0, 6526.314736952551, 6538.673066346569, 6511.511636627367, 6516.902246466401, 6520.18497687789, 6627.4698024158915, 6660.733570657119, 6623.2383275923885, 6644.966775166094, 6677.966610167113, 6691.2639609643875, 6733.595984240961, 6533.346665333619, 6562.114114594468, 6566.179227596551, 6568.671566421755, 6596.670166491752, 6489.5781774184525, 6507.674616269073, 6566.24488183865, 6587.341265873374, 6597.538172327891, 6396.840078997876, 6445.065465507381, 6498.155239818982, 6522.412982831568, 6531.281559028598, 6290.937090629218, 6468.191476065492, 6470.579412338183, 6470.708818103102, 6477.77327793525, 6294.748210071546, 6316.62100273985, 6318.399752023551, 6320.399562041599, 6335.556511044183], "value": 6067.034652302986, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [823.8393513264916, 871.5947084605659, 903.9186473217413, 907.2832462354738, 1164.6331405607239, 29302.999999999975, 30845.38309233814, 41021.58978410219, 41902.37146442798, 42002.57997420025, 41026.79486602563, 41301.38047929276, 41367.379489307685, 41404.0, 41486.58513414865, 7726.613669316525, 7827.1390147083775, 7878.172791856859, 7884.724034658788, 7895.131535531115, 7696.615169241515, 7793.844123117534, 7802.375809935192, 7809.804754881145, 7811.648475818602, 7817.2964433201005, 7847.058352997648, 7853.410994175431, 7873.763787086396, 7880.566568838712, 7767.0, 7781.766547003599, 7791.415643826679, 7817.022872141011, 7822.452428330019, 7624.923750762477, 7670.693172273102, 7718.884216736754, 7721.459497835153, 7731.22687731228, 38402.80798596017, 38737.806310968546, 38822.0, 38826.80586597049, 38968.41547376791, 3539.8761043363615, 3583.605803361628, 3583.8208089595414, 3586.605473397924, 3590.8204589770407, 30380.392392152033, 30428.69571304281, 30623.693763062307, 30679.846600766854, 30746.0, 4633.559811817872, 4722.126406614787, 4788.784504697297, 4807.879803004917, 4838.806447742086, 181897.0, 182282.0, 182708.0, 182789.0, 183568.0, 46895.0, 46974.29538556925, 47191.764041180264, 47367.763161183975, 47457.28814067792, 48353.0, 48529.757351213724, 48724.26913596299, 48733.75633121883, 48979.26531102036, 15917.204139792964, 16158.0, 16160.434384796476, 16202.43291484816, 16237.188140592923, 13583.66040848967, 13585.388657510435, 13586.116902401312, 13601.727965440834, 13616.93191534036, 12765.170263933, 12851.678708032374, 12915.22508649484, 13022.0, 13126.540571079966, 5368.946310536923, 5426.375966763794, 5443.646162999393, 5512.6968016759465, 5598.944010559923, 953.7997020625608, 982.2240430060173, 990.7225976726528, 1019.607451131305, 1022.6880801355509, 22110.668339974913, 22195.556088878133, 22496.437589060406, 22536.323910282717, 22542.0, 1074.1728868771158, 1083.8482612434266, 1114.0419239454063, 1117.0895719988346, 1131.1911982932243, 11773.646790596296, 11991.160618756603, 12219.0, 15588.532344029698, 16238.675226495405, 216635.0, 217369.0, 218296.0, 218592.0, 221905.0, 56398.718006409705, 56497.43502565004, 56587.0, 56671.14993275104, 56766.0, 1462.8390877003521, 1481.0595272002356, 1481.7110663420744, 1496.8877334199979, 1504.383202886833, 25730.456172629703, 26063.218103456926, 26470.07354742575, 26666.7333326672, 26766.464670706086, 33995.66004340024, 34161.31677366488, 34235.65764342325, 34251.0, 34296.31407371889, 184706.0, 185189.0, 185414.0, 185539.0723046376, 186156.0, 32933.67066329306, 32945.835270823496, 32982.835085824416, 33065.338693226484, 33247.168820779676, 41656.00383861868, 42357.0, 43149.78425107854, 43355.132897342504, 43448.0, 29220.7077929218, 29952.0, 30899.845500773252, 31183.84408078036, 31213.53179702261, 32262.35475290528, 32361.190970225933, 32662.0, 32944.67055329512, 32997.83501082479, 35835.64164358428, 38193.4270985941, 38194.427083594324, 38308.80845595754, 38388.0, 33086.17284567905, 33986.490202646484, 34392.0, 34428.13929651779, 34687.306253875286, 23598.52802943897, 23989.76010239945, 25081.49837003213, 25233.0, 25813.870930645226], "value": 17107.305086559667, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.9568782341323, 581.5870731780436, 584.4039080138259, 591.0513625630863, 593.6853467662138, 22164.88917555412, 22341.888290558563, 23241.186558470457, 23324.06703731851, 23399.298021059352, 25645.61531577028, 26403.603945940777, 26470.0, 26472.470550588976, 26500.747436467875, 6390.360963903608, 6539.215294164707, 6555.180602424697, 6561.868762624768, 6570.572912760679, 6483.675816209195, 6551.344865513434, 6565.277819439858, 6932.480063995195, 6948.166220053576, 6939.0979172707475, 6943.0, 6953.89569156463, 6957.79126626202, 6971.895421568655, 6945.617991010492, 6979.720811167573, 6981.476389270774, 6981.546199497042, 6988.19635741886, 6807.65961701913, 6811.557248778863, 6831.2144103428245, 6839.2818754031105, 6840.384365407137, 31329.216769580715, 31703.0, 32058.679413205802, 33282.33435331304, 33313.66686333154, 1900.0, 1944.9611007779906, 1945.4552725236815, 1948.1136083082163, 1949.6588097082954, 20573.0, 21045.78954210469, 21078.894605527028, 21080.78919210819, 21084.156633734634, 2420.394901274681, 2449.228493024709, 2532.9493410131695, 2549.1842610364693, 2561.5645340292194, 137345.0, 144743.0, 147779.99999999892, 147940.0, 148085.0, 20752.0, 21998.89000554987, 22139.225127120797, 22628.0, 23055.19306824253, 22161.889190554266, 23166.53666926652, 23212.071517139462, 43612.127757445305, 43780.78109609432, 10490.527926243338, 10703.411312377739, 10753.892461075446, 10761.83857242142, 10890.945545272223, 9990.0, 10058.145057670034, 10106.090451859222, 10124.633174521345, 10134.493275336205, 9349.672761453316, 9409.576569054272, 9488.857667134998, 9605.327627066177, 9613.423194608346, 2631.25009372328, 2637.129747183425, 2706.3369474478877, 2759.296379423274, 2839.971600284012, 913.9405938613969, 949.5204921514729, 967.2503809547657, 974.4104816585864, 985.8028394321165, 19596.60806783885, 19759.802401976085, 20056.09747561363, 20210.191592336436, 20334.186632534827, 997.3866072365443, 1017.5726194998118, 1025.4052649463292, 1034.7826956339102, 1040.8542804007445, 10826.56693732242, 11192.608258710907, 11197.552097916156, 11313.943430282796, 11355.261907975955, 465638.0, 469399.0, 517053.0, 518607.0, 529622.0, 116493.41753291349, 124429.37785311016, 126337.0, 127598.36200818936, 128554.0, 1244.464880101547, 1252.3612957391795, 1303.9739205215842, 1306.6406738147086, 1314.493919840868, 19483.220671172996, 20605.38183854487, 20697.172113115306, 21820.563588728455, 21907.342779716633, 59703.402965969784, 60210.0, 60488.0, 60643.39356606554, 61669.38330616816, 69406.65296673484, 69513.3048669527, 69708.0, 69794.65102674454, 70206.64896675484, 14780.556583302518, 14983.850161498245, 14996.550103496913, 15086.019408738397, 15171.393144274105, 23445.648315274942, 23570.175043873383, 23859.642105368086, 23991.760082398952, 24194.75805241925, 18542.536436589195, 18563.535911602317, 19483.415497535098, 19679.31122410709, 19852.106655200263, 15616.297266622802, 15927.922363080801, 16085.276162572478, 16144.354225830835, 16769.916150419656, 18890.622187556448, 19388.321408750628, 19644.0177991097, 20348.38954831298, 20397.0, 14988.100713957198, 15032.549023529311, 15091.622709432351, 15100.697986039999, 15178.468753593572, 17862.55343616368, 18802.811971880652, 18829.811701882805, 19116.8088319115, 19440.0], "value": 12632.317257618897, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.3218390804598, 1394.6931675031497, 1430.3348942741623, 1432.4771458417674, 1439.352291468839, 13510.189388636676, 14003.0, 14182.645433864158, 14183.574492765221, 14221.360038798253, 14365.640858978531, 14523.201223932705, 14560.635984100376, 14570.562883113496, 14611.342489587967, 2642.114891511346, 2690.9192724218306, 2694.8248363856383, 2703.648525691657, 2711.024031348713, 2242.1703969531295, 2327.499587588668, 2329.46422322865, 2652.4429869727383, 2747.1346525844397, 2668.546347120994, 2676.8661566921674, 2680.664916885389, 2682.0, 2690.0315886281023, 2445.3153117127144, 2471.5798314286612, 2491.5888878335054, 2495.0768215760286, 2495.126705653016, 2454.521368333176, 2470.9135180268604, 2486.0, 2566.948661026769, 2611.8824652890476, 13893.305334733222, 13972.650683732989, 13994.510192143227, 14006.859931400759, 14080.084794488326, 7142.821429464305, 7422.740204092831, 7451.366633836131, 7461.962690186514, 7466.328030477229, 4872.244802055687, 4971.0, 5044.47033061527, 5130.050940576006, 5176.870578235536, 1656.1388078199388, 1676.3210899585688, 1681.915904204785, 1719.4583706132528, 1731.5065206416236, 23103.191388301497, 23385.76614233853, 23398.53202935949, 23428.531429371316, 23442.17952371659, 6260.843478913063, 6503.349665033459, 6530.2816690164045, 6577.309382514863, 6607.603543787388, 15676.294566744524, 15723.528294151194, 15753.606159846095, 15779.211039447982, 15782.526524204291, 7465.738699145504, 7623.847523049508, 7643.9617801911745, 7668.578228197391, 7681.731139410093, 6563.113979612786, 6924.446044316443, 7047.894281585678, 7051.153861536648, 7053.894191587027, 4441.267190913463, 4639.489656137822, 5188.584913206934, 5214.165733482625, 5238.292830467838, 1492.1718446262212, 1495.6410461489165, 1518.0, 1527.4501179575343, 1556.9532914012598, 791.8891355210275, 797.2266901105918, 816.9135050382977, 820.2207902492656, 838.7693384319361, 7013.579185248901, 7380.188179300273, 7391.039164908635, 7402.518836275625, 7541.208173141851, 904.1862323908535, 932.2728271947891, 937.3298091864214, 956.5073986896754, 976.5849513956682, 4406.845760398371, 4523.909521809611, 4554.4990051094355, 4652.185867473178, 4690.413698287714, 18549.165287562093, 18660.0, 19389.418317450218, 19426.611467770847, 19491.610167796563, 18707.90646046761, 19039.238430462905, 19532.511687207934, 19685.803141968398, 19722.802771972096, 10531.684049478528, 10769.461526923778, 10820.945895270474, 10855.999999999842, 10901.0, 7062.929370706227, 7652.426068044808, 7716.42126840478, 7783.143854175923, 7875.448718589644, 16156.788240881746, 16587.253573588976, 16865.747013794557, 17011.91494042522, 17063.146842658065, 16880.662386752443, 17126.486205413858, 17173.914130429268, 17397.652046959243, 17428.825711743226, 9981.60073597048, 11387.373694446886, 11570.652880413601, 11596.188266821242, 11602.477888495207, 11577.479013444583, 13222.867771322164, 13226.338683065616, 13230.206187628773, 13279.203247805162, 9209.631614735335, 9463.810723785347, 9534.09426104519, 9572.282078844253, 9608.615655373986, 10070.69787906364, 10465.0, 10656.840147397641, 10662.253642244968, 10908.61819836302, 8702.173293537104, 8818.823623527622, 8823.779405514657, 8824.0, 8829.38194326391, 6114.0, 6453.0, 6638.070670106093, 6680.866382672222, 6778.559393639595, 6673.299303573055, 6676.766313179015, 6822.931770682229, 6867.519273650796, 6940.41006514452], "value": 6200.899051406165, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.50610870803075, 480.4930798008102, 512.3595505617977, 514.539487158993, 522.2114606943514, 6161.322254551996, 6209.565330426861, 6226.813195604133, 6247.2815626203, 6278.121063051165, 5922.3485416604135, 6036.849078773022, 6071.60534565253, 6103.694815259241, 6124.295705993812, 4878.682885612424, 4888.266759985995, 4912.410510738708, 4943.009479265047, 4946.159152944008, 4821.54195351441, 4884.340614017097, 4930.334404855353, 4969.552740253376, 4982.87542811429, 4897.853064408074, 4933.605311575066, 4938.629602779806, 4942.752862356868, 4955.0, 4945.258211268302, 4978.56050535237, 4979.6265280104135, 4981.576565991895, 5029.874253143664, 4401.141777353385, 4402.675979635387, 4438.579654510558, 4449.443819522559, 4451.910961780746, 5949.6430214187285, 6014.0678194879865, 6094.847628809271, 6120.755169793203, 6131.325554189036, 5674.460926212003, 5683.318001839764, 5690.918725442194, 5705.6006079574445, 5707.4577915098, 6356.618602883796, 6406.263279722845, 6450.419462248373, 6542.4438922691625, 6567.310432404624, 4805.471398146201, 4826.420829500483, 4867.245576935581, 4868.683535570177, 4872.707637541759, 6777.762778302736, 6848.691808868563, 6922.0, 6935.54918930278, 6936.237013928464, 4885.7312847793, 4905.092557876804, 4917.631177661689, 4944.975275123673, 5012.548870601663, 5011.57401620859, 5019.698818070928, 5037.596992240612, 5043.9747801260755, 5051.621128415383, 5641.379448260688, 5677.489025987598, 5698.572607054488, 5726.398728133569, 5729.341125770589, 6417.679116044179, 6494.616134109458, 6525.771597994142, 6550.443212327005, 6669.533132680665, 1627.8290779468223, 1666.275170300923, 1696.5080126763348, 1750.0986991699285, 1752.1589636974325, 1190.5892467098838, 1204.1931905623078, 1214.4455097475245, 1219.189239155969, 1227.300438749904, 5684.403137670568, 5706.25818643582, 5707.6575405475805, 5734.741936612862, 5756.884862302731, 6673.366030227121, 6739.483616186417, 6753.49348798842, 6757.493188010821, 6774.796756097325, 5851.970740146272, 5858.355580886099, 5863.765449381977, 5871.588988770831, 5900.321463031806, 3616.439451884962, 3638.5451818522683, 3646.908827279286, 3651.1419816343137, 3659.524261845996, 6525.042487253805, 6541.247756507967, 6541.771038013647, 6549.115869357671, 6577.934220657828, 6823.283555226729, 6872.312768723088, 6901.930980690229, 6906.309369063053, 6971.5468494547695, 6451.741930322828, 6524.575902566318, 6565.770198043046, 6567.179102612174, 6573.619539896676, 6646.90029649546, 6650.20197576294, 6679.432248258952, 6705.530612857053, 6763.289854565397, 6508.186476690414, 6588.637624930675, 6627.271000190071, 6682.866342673216, 6740.898886516804, 6475.255345635123, 6475.255345635313, 6477.995910633859, 6491.0, 6529.183852018497, 4938.950610493993, 4982.676126051795, 4992.8751781205765, 5000.149974504416, 5007.24891266313, 6460.515461340325, 6604.14146160996, 6741.662916854236, 6748.898766518408, 6751.729930802713, 6306.013605714185, 6356.4279214870885, 6402.871942561216, 6424.169111803114, 6473.126127972663, 6413.390727880844, 6461.61230326182, 6534.085228068169, 6542.967285163544, 6547.639879806657, 6466.805995820133, 6495.382938620824, 6504.284528701744, 6517.340101827216, 6532.771353002622, 6410.903836442364, 6484.935150648622, 6490.610563366213, 6497.577657452251, 6555.80332590023, 6232.376762323732, 6296.842578935563, 6316.368363163647, 6353.52348573868, 6384.138141350858], "value": 5030.649237396094, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [896.0546623312405, 901.333013569958, 920.6731610278355, 928.2295694573505, 929.1080562659846, 17821.55446113846, 18487.075646217687, 18539.443816685503, 18550.0, 18552.165152568166, 16489.67020659586, 16535.83464165361, 16621.91689041556, 19083.90458047708, 21191.258305959294, 1012.6708819633611, 1028.0182425783396, 1036.455860673149, 1037.3827572594294, 1041.0786453988237, 1061.4480470155515, 1063.3726101600055, 1065.9680209593726, 1066.317556763672, 1066.317556763672, 1048.1248157788257, 1086.435053772042, 1092.017184533918, 1122.685648018552, 1130.1354463835173, 1071.9732006699855, 1074.08702602787, 1074.3661239868477, 1074.5325783284297, 1075.623531763884, 778.7313376884965, 779.419332597213, 779.4699604269094, 782.0732432068017, 783.1815752538611, 17787.0, 17890.91054544732, 18187.0, 18336.63326733458, 19280.4215873524, 20343.49141271465, 20465.0, 20523.58952820935, 20685.172593096257, 20825.8958705207, 15206.391744330214, 15666.746660267152, 15748.291326890318, 15780.132092734895, 15790.763138552931, 3470.1151206442446, 3487.163080860575, 3494.0391392367046, 3495.9825200873834, 3500.929981400383, 111685.44157279162, 112189.0, 112626.43686781514, 114452.0, 117281.0, 16416.671666566603, 17033.659326813395, 17034.31862725502, 17035.063071531185, 17039.574010649834, 28857.711422885503, 30303.93936212222, 30387.088387348416, 30540.694593054228, 30661.846690766404, 9041.0, 9208.355415121012, 9276.02601726809, 9279.257659387367, 9290.303227257982, 4266.616004559604, 4387.846425375096, 4390.034192477649, 4405.163019026375, 4410.80151393188, 5004.174311238605, 5140.665856719302, 5149.45930677281, 5149.819756308511, 5162.948370516322, 1007.5012868630075, 1099.1371773158032, 1101.3502033800096, 1106.0598491282506, 1107.595727559451, 668.6289109544152, 679.3444326225216, 684.1311534351346, 687.1376422589618, 687.4603436302478, 10704.19718521114, 11335.263207891461, 11838.704032399086, 11929.642110736691, 12326.50693972231, 632.5666918161074, 653.4641593892962, 670.7283550162192, 681.3697329969758, 685.8491131951063, 5168.3539557555305, 5193.195054766517, 5251.735028364137, 5295.311609490742, 5460.0, 95089.52455237818, 95908.0, 100244.0, 102270.0, 104743.0, 85360.0, 86456.56771716102, 86870.0, 87781.5610921954, 92004.0, 75266.0, 76946.61526692331, 77635.22364776392, 77651.0, 78957.60521197357, 10154.644587439405, 10304.845427318745, 10326.535305911102, 10399.740006499898, 10516.421596812248, 34380.48429273513, 36003.0999225011, 36383.090422739646, 37022.44466332953, 37420.43869341908, 45423.77288113538, 45474.0, 45771.31343029791, 46133.76933115313, 47464.0, 15374.461893833663, 15628.765568516708, 15659.530214093595, 15738.213089345716, 15771.44799931997, 17227.483175504756, 17275.82724172742, 17324.39364622232, 17438.564035899202, 17596.472105836845, 14822.25888705582, 14952.401903924156, 14981.325840336993, 14998.925005374904, 15046.62383440379, 16172.595685107966, 16242.593935151715, 17133.400330988854, 17608.73586896223, 17794.288228470716, 16832.242549085076, 17322.740158897373, 17366.47900562985, 17370.91314543419, 17392.30430782805, 12990.805137922749, 13069.738605228033, 13186.011049171158, 13218.669533261746, 13431.86568134306, 13004.869951300745, 13026.0, 13203.537876174289, 13464.93267533656, 13672.316384180951], "value": 8655.68819988925, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1534.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 108.0, 106.0, 108.0, 108.0, 113.0, 108.0, 99.0, 111.0, 111.0], "value": 107.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 117.0, 147.0, 167.0, 120.0, 143.0, 144.0, 117.0, 169.0, 118.0], "value": 139.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 164.0, 164.0, 162.0, 163.0, 163.0, 167.0, 166.0], "value": 163.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 77.0, 176.0, 75.0, 74.0, 74.0, 89.0, 103.0, 74.0], "value": 89.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 126.0, 83.0, 78.0, 78.0, 131.0, 79.0, 79.0, 125.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0, 115.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 151.0, 151.0, 151.0, 151.0, 150.0, 150.0, 151.0, 152.0], "value": 150.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 58.0, 61.0, 57.0, 56.0, 57.0, 56.0, 56.0, 56.0], "value": 57.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 53.0, 54.0, 54.0, 53.0, 54.0, 55.0, 53.0, 55.0], "value": 53.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 90.0, 85.0, 79.0, 83.0, 89.0, 80.0, 91.0, 92.0, 87.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 129.0, 131.0, 135.0, 126.0, 135.0, 131.0, 145.0, 128.0, 166.0], "value": 135.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 188.0, 182.0, 177.0, 183.0, 185.0, 180.0, 185.0, 184.0, 184.0], "value": 182.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 67.0, 67.0, 69.0, 67.0, 68.0, 67.0, 66.0, 68.0], "value": 67.6, "unit": "score"}]}]} 03:39:27 INFO - Return code: 0 03:39:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:39:27 INFO - # TBPL SUCCESS # 03:39:27 INFO - Running post-action listener: _package_coverage_data 03:39:27 INFO - Running post-action listener: _resource_record_post_action 03:39:27 INFO - [mozharness: 2017-08-01 10:39:27.330663Z] Finished run-tests step (success) 03:39:27 INFO - Running post-run listener: _resource_record_post_run 03:39:27 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 5214208; Write bytes: 436658176; Read time: 2028; Write time: 350780 03:39:27 INFO - TinderboxPrint: CPU usage
13.1% 03:39:27 INFO - TinderboxPrint: I/O read bytes / time
5,214,208 / 2,028 03:39:27 INFO - TinderboxPrint: I/O write bytes / time
436,658,176 / 350,780 03:39:27 INFO - TinderboxPrint: CPU idle
8,276.7 (86.9%) 03:39:27 INFO - TinderboxPrint: CPU user
1,200.8 (12.6%) 03:39:27 INFO - TinderboxPrint: Swap in / out
0 / 0 03:39:27 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 116576256; Read time: 0; Write time: 103012 03:39:27 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:39:27 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 2842624; Write bytes: 320081920; Read time: 1568; Write time: 247768 03:39:27 INFO - Running post-run listener: _upload_blobber_files 03:39:27 INFO - Blob upload gear active. 03:39:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:39:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:39:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:39:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:39:28 INFO - (blobuploader) - INFO - Open directory for files ... 03:39:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:39:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:39:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:39:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:39:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:39:29 INFO - (blobuploader) - INFO - Done attempting. 03:39:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:39:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:39:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:39:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:39:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:39:33 INFO - (blobuploader) - INFO - Done attempting. 03:39:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:39:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:39:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:39:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:39:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:39:34 INFO - (blobuploader) - INFO - Done attempting. 03:39:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:39:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:39:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:39:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:39:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:39:35 INFO - (blobuploader) - INFO - Done attempting. 03:39:35 INFO - (blobuploader) - INFO - Iteration through files over. 03:39:35 INFO - Return code: 0 03:39:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:39:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:39:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c9321ec06663044d2aa47384a32b274440abb89041c98abd4e0d51ee038d62a1163128e0389821f18be5305c8be2e68dcada5c396a2331c944c35de236a2e1c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a02fb88ae699f4628625f53ba41cf7f8f9896cf9b4e0b7d462b724504dd9a44298dbac10782a74e0b4399fd03fd4fd5b184bd4e02fd6580a572c941589da01f5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/76b93af819bc399b834b49fa7781ba25b870853ad12d28f512edd1c0510e9f62090f3df02c33a81022cb1dd3a917bdebd3b25f33c34d245fbc939a575c0ade6f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f8718a541ba3a9f4a7afdfeb57f44fb7c5ddcfb823f527565beb5ce90f3bb09841e6863b43b601189005900229a584b1a752d95707bdaf04bc99860c5a139d6e"} 03:39:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:39:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:39:35 INFO - Contents: 03:39:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c9321ec06663044d2aa47384a32b274440abb89041c98abd4e0d51ee038d62a1163128e0389821f18be5305c8be2e68dcada5c396a2331c944c35de236a2e1c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a02fb88ae699f4628625f53ba41cf7f8f9896cf9b4e0b7d462b724504dd9a44298dbac10782a74e0b4399fd03fd4fd5b184bd4e02fd6580a572c941589da01f5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/76b93af819bc399b834b49fa7781ba25b870853ad12d28f512edd1c0510e9f62090f3df02c33a81022cb1dd3a917bdebd3b25f33c34d245fbc939a575c0ade6f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f8718a541ba3a9f4a7afdfeb57f44fb7c5ddcfb823f527565beb5ce90f3bb09841e6863b43b601189005900229a584b1a752d95707bdaf04bc99860c5a139d6e"} 03:39:35 INFO - Running post-run listener: copy_logs_to_upload_dir 03:39:35 INFO - Copying logs to upload dir... 03:39:35 INFO - mkdir: /builds/slave/test/build/upload/logs 03:39:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.317293 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-08-01 03:39:35.386974) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-01 03:39:35.395941) ========= 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=ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c9321ec06663044d2aa47384a32b274440abb89041c98abd4e0d51ee038d62a1163128e0389821f18be5305c8be2e68dcada5c396a2331c944c35de236a2e1c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a02fb88ae699f4628625f53ba41cf7f8f9896cf9b4e0b7d462b724504dd9a44298dbac10782a74e0b4399fd03fd4fd5b184bd4e02fd6580a572c941589da01f5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/76b93af819bc399b834b49fa7781ba25b870853ad12d28f512edd1c0510e9f62090f3df02c33a81022cb1dd3a917bdebd3b25f33c34d245fbc939a575c0ade6f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f8718a541ba3a9f4a7afdfeb57f44fb7c5ddcfb823f527565beb5ce90f3bb09841e6863b43b601189005900229a584b1a752d95707bdaf04bc99860c5a139d6e"} build_url:https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011173 build_url: 'https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c9321ec06663044d2aa47384a32b274440abb89041c98abd4e0d51ee038d62a1163128e0389821f18be5305c8be2e68dcada5c396a2331c944c35de236a2e1c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a02fb88ae699f4628625f53ba41cf7f8f9896cf9b4e0b7d462b724504dd9a44298dbac10782a74e0b4399fd03fd4fd5b184bd4e02fd6580a572c941589da01f5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/76b93af819bc399b834b49fa7781ba25b870853ad12d28f512edd1c0510e9f62090f3df02c33a81022cb1dd3a917bdebd3b25f33c34d245fbc939a575c0ade6f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f8718a541ba3a9f4a7afdfeb57f44fb7c5ddcfb823f527565beb5ce90f3bb09841e6863b43b601189005900229a584b1a752d95707bdaf04bc99860c5a139d6e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-01 03:39:35.419536) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 03:39:35.419838) ========= 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=ed14279e38b05397cf4aa391000001a4-1501577446.769167-479014813 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004384 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 03:39:35.503145) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-01 03:39:35.503427) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-01 03:39:35.505722) ========= ========= Total master_lag: 0.20 =========