builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-038 starttime: 1502879653.28 results: success (0) revision: 6ebc251bd288c268b020815025b05854ccde5c08 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-16 03:34:13.285243) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-16 03:34:13.285650) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-16 03:34:13.285923) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-16 03:34:13.298645) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 03:34:13.298901) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-16 03:34:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.1M=0s 2017-08-16 03:34:13 (43.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.185686 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 03:34:13.499946) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 03:34:13.500245) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.307614 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 03:34:13.824205) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 03:34:13.824533) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6ebc251bd288c268b020815025b05854ccde5c08 --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 6ebc251bd288c268b020815025b05854ccde5c08 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-16 03:34:13,912 truncating revision to first 12 chars 2017-08-16 03:34:13,912 Setting DEBUG logging. 2017-08-16 03:34:13,912 attempt 1/10 2017-08-16 03:34:13,912 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6ebc251bd288?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-16 03:34:14,355 unpacking tar archive at: mozilla-central-6ebc251bd288/testing/mozharness/ program finished with exit code 0 elapsedTime=0.830273 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 03:34:14.672407) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-16 03:34:14.675742) ========= script_repo_revision: 6ebc251bd288c268b020815025b05854ccde5c08 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-16 03:34:14.676134) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-16 03:34:14.676399) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-16 03:34:14.688209) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-08-16 03:34:14.688511) ========= /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=8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:34:14 INFO - MultiFileLogger online at 20170816 03:34:14 in /builds/slave/test 03:34:14 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:34:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:34:14 INFO - {'append_to_log': False, 03:34:14 INFO - 'base_work_dir': '/builds/slave/test', 03:34:14 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:34:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:34:15 INFO - 'branch': 'Firefox-Non-PGO', 03:34:15 INFO - 'buildbot_json_path': 'buildprops.json', 03:34:15 INFO - 'code_coverage': False, 03:34:15 INFO - 'config_files': ('talos/linux_config.py',), 03:34:15 INFO - 'default_actions': ('clobber', 03:34:15 INFO - 'read-buildbot-config', 03:34:15 INFO - 'download-and-extract', 03:34:15 INFO - 'populate-webroot', 03:34:15 INFO - 'create-virtualenv', 03:34:15 INFO - 'install', 03:34:15 INFO - 'setup-mitmproxy', 03:34:15 INFO - 'run-tests'), 03:34:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:34:15 INFO - 'disable_ccov_upload': False, 03:34:15 INFO - 'download_minidump_stackwalk': True, 03:34:15 INFO - 'download_symbols': 'ondemand', 03:34:15 INFO - 'enable_stylo': False, 03:34:15 INFO - 'enable_webrender': False, 03:34:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:34:15 INFO - 'tooltool.py': '/tools/tooltool.py', 03:34:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:34:15 INFO - '/tools/misc-python/virtualenv.py')}, 03:34:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:34:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:34:15 INFO - 'gecko_profile': False, 03:34:15 INFO - 'gecko_profile_interval': 0, 03:34:15 INFO - 'installer_path': 'installer.exe', 03:34:15 INFO - 'log_level': 'info', 03:34:15 INFO - 'log_name': 'talos', 03:34:15 INFO - 'log_to_console': True, 03:34:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:34:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:34:15 INFO - 'opt_config_files': (), 03:34:15 INFO - 'pip_index': False, 03:34:15 INFO - 'suite': 'dromaeojs-e10s', 03:34:15 INFO - 'system_bits': '32', 03:34:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:34:15 INFO - 'title': 'talos-linux64-ix-038', 03:34:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:34:15 INFO - 'use_talos_json': True, 03:34:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:34:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:34:15 INFO - 'work_dir': 'build'} 03:34:15 INFO - [mozharness: 2017-08-16 10:34:15.009766Z] Running clobber step. 03:34:15 INFO - Running pre-action listener: _resource_record_pre_action 03:34:15 INFO - Running main action method: clobber 03:34:15 INFO - rmtree: /builds/slave/test/build 03:34:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:34:17 INFO - Running post-action listener: _resource_record_post_action 03:34:17 INFO - [mozharness: 2017-08-16 10:34:17.381749Z] Finished clobber step (success) 03:34:17 INFO - [mozharness: 2017-08-16 10:34:17.381872Z] Running read-buildbot-config step. 03:34:17 INFO - Running pre-action listener: _resource_record_pre_action 03:34:17 INFO - Running main action method: read_buildbot_config 03:34:17 INFO - Using buildbot properties: 03:34:17 INFO - { 03:34:17 INFO - "project": "", 03:34:17 INFO - "product": "firefox", 03:34:17 INFO - "who": "cbook@mozilla.com", 03:34:17 INFO - "installer_path": "public/build/target.tar.bz2", 03:34:17 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:34:17 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:34:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:34:17 INFO - "stage_platform": "linux64", 03:34:17 INFO - "basedir": "/builds/slave/test", 03:34:17 INFO - "buildnumber": 771, 03:34:17 INFO - "platform": "ubuntu64_hw", 03:34:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:34:17 INFO - "slavebuilddir": "test", 03:34:17 INFO - "taskId": "bWHiXLLuQL6io9NCLXlrgA", 03:34:17 INFO - "branch": "mozilla-central", 03:34:17 INFO - "script_repo_revision": "production", 03:34:17 INFO - "revision": "6ebc251bd288c268b020815025b05854ccde5c08", 03:34:17 INFO - "slavename": "talos-linux64-ix-038", 03:34:17 INFO - "repo_path": "mozilla-central" 03:34:17 INFO - } 03:34:17 INFO - Finding installer, test and symbols from parent task. 03:34:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bWHiXLLuQL6io9NCLXlrgA'}, attempt #1 03:34:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bWHiXLLuQL6io9NCLXlrgA'}, attempt #1 03:34:18 INFO - Task dependencies: Ik1VZCnkRAKLMf_kMSI2Ig 03:34:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig'}, attempt #1 03:34:18 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.tar.bz2 03:34:19 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.test_packages.json 03:34:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.crashreporter-symbols.zip 03:34:19 INFO - Running post-action listener: _resource_record_post_action 03:34:19 INFO - [mozharness: 2017-08-16 10:34:19.192504Z] Finished read-buildbot-config step (success) 03:34:19 INFO - [mozharness: 2017-08-16 10:34:19.192603Z] Running download-and-extract step. 03:34:19 INFO - Running pre-action listener: _resource_record_pre_action 03:34:19 INFO - Running main action method: download_and_extract 03:34:19 INFO - mkdir: /builds/slave/test/build/tests 03:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:34:19 INFO - trying https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.test_packages.json 03:34:19 INFO - Downloading https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:34:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:34:19 INFO - Downloaded 989 bytes. 03:34:19 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:34:19 INFO - Using the following test package requirements: 03:34:19 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:34:19 INFO - u'common': [u'target.common.tests.zip'], 03:34:19 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:34:19 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:34:19 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:34:19 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:34:19 INFO - u'mozbase': [u'target.common.tests.zip'], 03:34:19 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:34:19 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:34:19 INFO - u'web-platform': [u'target.common.tests.zip', 03:34:19 INFO - u'target.web-platform.tests.tar.gz'], 03:34:19 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:34:19 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:34:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.common.tests.zip 03:34:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:34:19 INFO - Fetch https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.common.tests.zip into memory 03:34:20 INFO - Content-Length response header: 54003804 03:34:20 INFO - Bytes received: 54003804 03:34:32 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:34:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.common.tests.zip 03:34:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:34:32 INFO - Fetch https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.common.tests.zip into memory 03:34:34 INFO - Content-Length response header: 54003804 03:34:34 INFO - Bytes received: 54003804 03:34:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.talos.tests.zip 03:34:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:34:53 INFO - Fetch https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.talos.tests.zip into memory 03:34:53 INFO - Content-Length response header: 14496050 03:34:53 INFO - Bytes received: 14496050 03:34:54 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:34:54 INFO - trying https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.tar.bz2 03:34:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:34:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:34:55 INFO - Downloaded 61804821 bytes. 03:34:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.tar.bz2 03:34:55 INFO - mkdir: /builds/slave/test/properties 03:34:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:34:55 INFO - Writing to file /builds/slave/test/properties/build_url 03:34:55 INFO - Contents: 03:34:55 INFO - build_url:https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.tar.bz2 03:34:55 INFO - Running post-action listener: _resource_record_post_action 03:34:55 INFO - Running post-action listener: set_extra_try_arguments 03:34:55 INFO - [mozharness: 2017-08-16 10:34:55.150073Z] Finished download-and-extract step (success) 03:34:55 INFO - [mozharness: 2017-08-16 10:34:55.150172Z] Running populate-webroot step. 03:34:55 INFO - Running pre-action listener: _resource_record_pre_action 03:34:55 INFO - Running main action method: populate_webroot 03:34:55 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:34:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:34:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:34:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:34:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:34:55 INFO - 'tests': ['damp', 'tps']}, 03:34:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:34:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:34:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:34:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:34:55 INFO - 'other-e10s': {'tests': ['a11yr', 03:34:55 INFO - 'ts_paint', 03:34:55 INFO - 'tpaint', 03:34:55 INFO - 'sessionrestore', 03:34:55 INFO - 'sessionrestore_many_windows', 03:34:55 INFO - 'sessionrestore_no_auto_restore', 03:34:55 INFO - 'tabpaint']}, 03:34:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:34:55 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:34:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:34:55 INFO - 'tsvgr_opacity', 03:34:55 INFO - 'tart', 03:34:55 INFO - 'tscrollx', 03:34:55 INFO - 'tsvg_static']}, 03:34:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:34:55 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:34:55 INFO - 'talos_options': ['--mitmproxy', 03:34:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:34:55 INFO - '--firstNonBlankPaint'], 03:34:55 INFO - 'tests': ['tp6_google', 03:34:55 INFO - 'tp6_youtube', 03:34:55 INFO - 'tp6_amazon', 03:34:55 INFO - 'tp6_facebook']}, 03:34:55 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:34:55 INFO - 'talos_options': ['--stylo', 03:34:55 INFO - '--mitmproxy', 03:34:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:34:55 INFO - '--firstNonBlankPaint'], 03:34:55 INFO - 'tests': ['tp6_google', 03:34:55 INFO - 'tp6_youtube', 03:34:55 INFO - 'tp6_amazon', 03:34:55 INFO - 'tp6_facebook']}, 03:34:55 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:34:55 INFO - 'talos_options': ['--stylo', 03:34:55 INFO - '--stylo-threads=1', 03:34:55 INFO - '--mitmproxy', 03:34:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:34:55 INFO - '--firstNonBlankPaint'], 03:34:55 INFO - 'tests': ['tp6_google', 03:34:55 INFO - 'tp6_youtube', 03:34:55 INFO - 'tp6_amazon', 03:34:55 INFO - 'tp6_facebook']}, 03:34:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:34:55 INFO - 'talos_options': ['--xperf_path', 03:34:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:34:55 INFO - 'tests': ['tp5n']}}} 03:34:55 INFO - Running post-action listener: _resource_record_post_action 03:34:55 INFO - [mozharness: 2017-08-16 10:34:55.158648Z] Finished populate-webroot step (success) 03:34:55 INFO - [mozharness: 2017-08-16 10:34:55.158739Z] Running create-virtualenv step. 03:34:55 INFO - Running pre-action listener: _resource_record_pre_action 03:34:55 INFO - Running main action method: create_virtualenv 03:34:55 INFO - Creating virtualenv /builds/slave/test/build/venv 03:34:55 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:34:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:34:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:34:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:34:55 INFO - Using real prefix '/usr' 03:34:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:34:55 INFO - Installing distribute.............................................................................................................................................................................................done. 03:34:57 INFO - Installing pip.................done. 03:34:57 INFO - Return code: 0 03:34:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:34:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:34:58 INFO - Reading from file tmpfile_stdout 03:34:58 INFO - Output received: 03:34:58 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:34:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:34:58 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:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:34:58 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:34:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:34:58 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 0x21301f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2236a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23d9a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d6bb0>, '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 0x23d7ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d7f40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', '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:34:58 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:34:58 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:34:58 INFO - Using env: {'DISPLAY': ':0', 03:34:58 INFO - 'HOME': '/home/cltbld', 03:34:58 INFO - 'LANG': 'en_US.UTF-8', 03:34:58 INFO - 'LANGUAGE': 'en_US:en', 03:34:58 INFO - 'LOGNAME': 'cltbld', 03:34:58 INFO - 'MAIL': '/var/mail/cltbld', 03:34:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:58 INFO - 'MOZ_NO_REMOTE': '1', 03:34:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:58 INFO - 'NO_EM_RESTART': '1', 03:34:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:58 INFO - 'PWD': '/builds/slave/test', 03:34:58 INFO - 'SHELL': '/bin/bash', 03:34:58 INFO - 'SHLVL': '1', 03:34:58 INFO - 'TERM': 'linux', 03:34:58 INFO - 'TMOUT': '86400', 03:34:58 INFO - 'USER': 'cltbld', 03:34:58 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', 03:34:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:58 INFO - '_': '/tools/buildbot/bin/python'} 03:34:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:34:58 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:34:58 INFO - Cleaning up... 03:34:58 INFO - Return code: 0 03:34:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:34:58 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:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:34:58 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:34:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:34:58 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 0x21301f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2236a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23d9a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d6bb0>, '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 0x23d7ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d7f40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', '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:34:58 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:34:58 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:34:58 INFO - Using env: {'DISPLAY': ':0', 03:34:58 INFO - 'HOME': '/home/cltbld', 03:34:58 INFO - 'LANG': 'en_US.UTF-8', 03:34:58 INFO - 'LANGUAGE': 'en_US:en', 03:34:58 INFO - 'LOGNAME': 'cltbld', 03:34:58 INFO - 'MAIL': '/var/mail/cltbld', 03:34:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:58 INFO - 'MOZ_NO_REMOTE': '1', 03:34:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:58 INFO - 'NO_EM_RESTART': '1', 03:34:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:58 INFO - 'PWD': '/builds/slave/test', 03:34:58 INFO - 'SHELL': '/bin/bash', 03:34:58 INFO - 'SHLVL': '1', 03:34:58 INFO - 'TERM': 'linux', 03:34:58 INFO - 'TMOUT': '86400', 03:34:58 INFO - 'USER': 'cltbld', 03:34:58 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', 03:34:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:58 INFO - '_': '/tools/buildbot/bin/python'} 03:34:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:34:58 INFO - Downloading/unpacking psutil>=3.1.1 03:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:34:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:34:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:34:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:34:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:34:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:34:59 INFO - Installing collected packages: psutil 03:34:59 INFO - Running setup.py install for psutil 03:34:59 INFO - building 'psutil._psutil_linux' extension 03:34:59 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:35:01 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:35:01 INFO - building 'psutil._psutil_posix' extension 03:35:01 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:35:01 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:35:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:35:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:35:01 INFO - Successfully installed psutil 03:35:01 INFO - Cleaning up... 03:35:01 INFO - Return code: 0 03:35:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:35:01 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:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:01 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:35:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:01 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 0x21301f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2236a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23d9a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d6bb0>, '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 0x23d7ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d7f40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', '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:35:01 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:35:01 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:35:01 INFO - Using env: {'DISPLAY': ':0', 03:35:01 INFO - 'HOME': '/home/cltbld', 03:35:01 INFO - 'LANG': 'en_US.UTF-8', 03:35:01 INFO - 'LANGUAGE': 'en_US:en', 03:35:01 INFO - 'LOGNAME': 'cltbld', 03:35:01 INFO - 'MAIL': '/var/mail/cltbld', 03:35:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:01 INFO - 'MOZ_NO_REMOTE': '1', 03:35:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:01 INFO - 'NO_EM_RESTART': '1', 03:35:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:01 INFO - 'PWD': '/builds/slave/test', 03:35:01 INFO - 'SHELL': '/bin/bash', 03:35:01 INFO - 'SHLVL': '1', 03:35:01 INFO - 'TERM': 'linux', 03:35:01 INFO - 'TMOUT': '86400', 03:35:01 INFO - 'USER': 'cltbld', 03:35:01 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', 03:35:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:01 INFO - '_': '/tools/buildbot/bin/python'} 03:35:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:35:01 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:35:01 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:35:01 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:35:01 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:35:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:35:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:35:03 INFO - Installing collected packages: mozsystemmonitor 03:35:03 INFO - Running setup.py install for mozsystemmonitor 03:35:03 INFO - Successfully installed mozsystemmonitor 03:35:03 INFO - Cleaning up... 03:35:03 INFO - Return code: 0 03:35:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:35:03 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:35:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:03 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:35:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:03 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 0x21301f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2236a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23d9a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d6bb0>, '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 0x23d7ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d7f40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', '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:35:03 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:35:03 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:35:03 INFO - Using env: {'DISPLAY': ':0', 03:35:03 INFO - 'HOME': '/home/cltbld', 03:35:03 INFO - 'LANG': 'en_US.UTF-8', 03:35:03 INFO - 'LANGUAGE': 'en_US:en', 03:35:03 INFO - 'LOGNAME': 'cltbld', 03:35:03 INFO - 'MAIL': '/var/mail/cltbld', 03:35:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:03 INFO - 'MOZ_NO_REMOTE': '1', 03:35:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:03 INFO - 'NO_EM_RESTART': '1', 03:35:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:03 INFO - 'PWD': '/builds/slave/test', 03:35:03 INFO - 'SHELL': '/bin/bash', 03:35:03 INFO - 'SHLVL': '1', 03:35:03 INFO - 'TERM': 'linux', 03:35:03 INFO - 'TMOUT': '86400', 03:35:03 INFO - 'USER': 'cltbld', 03:35:03 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', 03:35:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:03 INFO - '_': '/tools/buildbot/bin/python'} 03:35:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:03 INFO - Downloading/unpacking jsonschema==2.5.1 03:35:03 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:35:03 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:35:03 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:35:03 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:35:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:35:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:04 INFO - Downloading functools32-3.2.3-2.tar.gz 03:35:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:35:04 INFO - warning: no files found matching '*.txt' 03:35:04 INFO - no previously-included directories found matching 'build' 03:35:04 INFO - no previously-included directories found matching 'dist' 03:35:04 INFO - no previously-included directories found matching '.git*' 03:35:04 INFO - Installing collected packages: jsonschema, functools32 03:35:04 INFO - Running setup.py install for functools32 03:35:04 INFO - warning: no files found matching '*.txt' 03:35:04 INFO - no previously-included directories found matching 'build' 03:35:04 INFO - no previously-included directories found matching 'dist' 03:35:04 INFO - no previously-included directories found matching '.git*' 03:35:04 INFO - Successfully installed jsonschema functools32 03:35:04 INFO - Cleaning up... 03:35:04 INFO - Return code: 0 03:35:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:35:04 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:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:04 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:35:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:04 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 0x21301f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2236a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23d9a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d6bb0>, '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 0x23d7ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d7f40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', '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:35:04 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:35:04 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:35:04 INFO - Using env: {'DISPLAY': ':0', 03:35:04 INFO - 'HOME': '/home/cltbld', 03:35:04 INFO - 'LANG': 'en_US.UTF-8', 03:35:04 INFO - 'LANGUAGE': 'en_US:en', 03:35:04 INFO - 'LOGNAME': 'cltbld', 03:35:04 INFO - 'MAIL': '/var/mail/cltbld', 03:35:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:04 INFO - 'MOZ_NO_REMOTE': '1', 03:35:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:04 INFO - 'NO_EM_RESTART': '1', 03:35:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:04 INFO - 'PWD': '/builds/slave/test', 03:35:04 INFO - 'SHELL': '/bin/bash', 03:35:04 INFO - 'SHLVL': '1', 03:35:04 INFO - 'TERM': 'linux', 03:35:04 INFO - 'TMOUT': '86400', 03:35:04 INFO - 'USER': 'cltbld', 03:35:04 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', 03:35:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:04 INFO - '_': '/tools/buildbot/bin/python'} 03:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:35:05 INFO - Cleaning up... 03:35:05 INFO - Return code: 0 03:35:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:05 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 0x21301f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2236a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23d9a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d6bb0>, '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 0x23d7ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d7f40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', '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:35:05 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:35:05 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:35:05 INFO - Using env: {'DISPLAY': ':0', 03:35:05 INFO - 'HOME': '/home/cltbld', 03:35:05 INFO - 'LANG': 'en_US.UTF-8', 03:35:05 INFO - 'LANGUAGE': 'en_US:en', 03:35:05 INFO - 'LOGNAME': 'cltbld', 03:35:05 INFO - 'MAIL': '/var/mail/cltbld', 03:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:05 INFO - 'MOZ_NO_REMOTE': '1', 03:35:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:05 INFO - 'NO_EM_RESTART': '1', 03:35:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:05 INFO - 'PWD': '/builds/slave/test', 03:35:05 INFO - 'SHELL': '/bin/bash', 03:35:05 INFO - 'SHLVL': '1', 03:35:05 INFO - 'TERM': 'linux', 03:35:05 INFO - 'TMOUT': '86400', 03:35:05 INFO - 'USER': 'cltbld', 03:35:05 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', 03:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:05 INFO - '_': '/tools/buildbot/bin/python'} 03:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:05 INFO - Downloading/unpacking blobuploader==1.2.4 03:35:05 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:35:05 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:35:05 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:35:05 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:35:06 INFO - Downloading blobuploader-1.2.4.tar.gz 03:35:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:35:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:35:06 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:35:06 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:35:06 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:35:06 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:35:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:35:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:35:06 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:35:06 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:35:06 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:35:06 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:35:07 INFO - Downloading docopt-0.6.1.tar.gz 03:35:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:35:07 INFO - Installing collected packages: blobuploader, requests, docopt 03:35:07 INFO - Running setup.py install for blobuploader 03:35:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:35:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:35:07 INFO - Running setup.py install for requests 03:35:07 INFO - Running setup.py install for docopt 03:35:07 INFO - Successfully installed blobuploader requests docopt 03:35:07 INFO - Cleaning up... 03:35:07 INFO - Return code: 0 03:35:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:35:07 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:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:07 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:35:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:07 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 0x21301f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2236a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23d9a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d6bb0>, '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 0x23d7ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d7f40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', '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:35:07 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:35:07 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:35:07 INFO - Using env: {'DISPLAY': ':0', 03:35:07 INFO - 'HOME': '/home/cltbld', 03:35:07 INFO - 'LANG': 'en_US.UTF-8', 03:35:07 INFO - 'LANGUAGE': 'en_US:en', 03:35:07 INFO - 'LOGNAME': 'cltbld', 03:35:07 INFO - 'MAIL': '/var/mail/cltbld', 03:35:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:07 INFO - 'MOZ_NO_REMOTE': '1', 03:35:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:07 INFO - 'NO_EM_RESTART': '1', 03:35:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:07 INFO - 'PWD': '/builds/slave/test', 03:35:07 INFO - 'SHELL': '/bin/bash', 03:35:07 INFO - 'SHLVL': '1', 03:35:07 INFO - 'TERM': 'linux', 03:35:07 INFO - 'TMOUT': '86400', 03:35:07 INFO - 'USER': 'cltbld', 03:35:07 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', 03:35:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:07 INFO - '_': '/tools/buildbot/bin/python'} 03:35:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:35:07 INFO - Running setup.py (path:/tmp/pip-mGf4FV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:35:07 INFO - Running setup.py (path:/tmp/pip-MTnWnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:35:08 INFO - Running setup.py (path:/tmp/pip-e4UvUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:35:08 INFO - Running setup.py (path:/tmp/pip-vfysG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:35:08 INFO - Running setup.py (path:/tmp/pip-40q2t3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:35:08 INFO - Running setup.py (path:/tmp/pip-ylhzlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:35:08 INFO - Running setup.py (path:/tmp/pip-zzHkIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:35:08 INFO - Running setup.py (path:/tmp/pip-8emLRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:35:08 INFO - Running setup.py (path:/tmp/pip-QgJJfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:35:08 INFO - Running setup.py (path:/tmp/pip-JBgzE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:35:08 INFO - Running setup.py (path:/tmp/pip-0NcykH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:35:08 INFO - Running setup.py (path:/tmp/pip-mLgkQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:35:08 INFO - Running setup.py (path:/tmp/pip-G0YG_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:35:08 INFO - Running setup.py (path:/tmp/pip-GcVv6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:35:08 INFO - Running setup.py (path:/tmp/pip-lW2PDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:35:08 INFO - Running setup.py (path:/tmp/pip-ddu9Da-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:35:09 INFO - Running setup.py (path:/tmp/pip-N0KgdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:35:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:35:09 INFO - Running setup.py install for manifestparser 03:35:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:35:09 INFO - Running setup.py install for mozcrash 03:35:09 INFO - Running setup.py install for mozdebug 03:35:09 INFO - Running setup.py install for mozdevice 03:35:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:35:09 INFO - Running setup.py install for mozfile 03:35:09 INFO - Running setup.py install for mozhttpd 03:35:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:35:09 INFO - Running setup.py install for mozinfo 03:35:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:35:09 INFO - Running setup.py install for mozInstall 03:35:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:35:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:35:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:35:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:35:09 INFO - Running setup.py install for mozleak 03:35:09 INFO - Running setup.py install for mozlog 03:35:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:35:10 INFO - Running setup.py install for moznetwork 03:35:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:35:10 INFO - Running setup.py install for mozprocess 03:35:10 INFO - Running setup.py install for mozprofile 03:35:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:35:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:35:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:35:10 INFO - Running setup.py install for mozrunner 03:35:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:35:10 INFO - Running setup.py install for mozscreenshot 03:35:10 INFO - Running setup.py install for moztest 03:35:10 INFO - Running setup.py install for mozversion 03:35:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:35:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:35:10 INFO - Cleaning up... 03:35:10 INFO - Return code: 0 03:35:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:35:10 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:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:10 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:35:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:10 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 0x21301f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2236a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23d9a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d6bb0>, '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 0x23d7ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d7f40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', '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:35:10 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:35:10 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:35:10 INFO - Using env: {'DISPLAY': ':0', 03:35:10 INFO - 'HOME': '/home/cltbld', 03:35:10 INFO - 'LANG': 'en_US.UTF-8', 03:35:10 INFO - 'LANGUAGE': 'en_US:en', 03:35:10 INFO - 'LOGNAME': 'cltbld', 03:35:10 INFO - 'MAIL': '/var/mail/cltbld', 03:35:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:10 INFO - 'MOZ_NO_REMOTE': '1', 03:35:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:10 INFO - 'NO_EM_RESTART': '1', 03:35:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:10 INFO - 'PWD': '/builds/slave/test', 03:35:10 INFO - 'SHELL': '/bin/bash', 03:35:10 INFO - 'SHLVL': '1', 03:35:10 INFO - 'TERM': 'linux', 03:35:10 INFO - 'TMOUT': '86400', 03:35:10 INFO - 'USER': 'cltbld', 03:35:10 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', 03:35:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:10 INFO - '_': '/tools/buildbot/bin/python'} 03:35:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:35:11 INFO - Running setup.py (path:/tmp/pip-YxB7s_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:35:11 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:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:35:11 INFO - Running setup.py (path:/tmp/pip-BC1fKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:35:11 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:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:35:11 INFO - Running setup.py (path:/tmp/pip-D9rcyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:35:11 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:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:35:11 INFO - Running setup.py (path:/tmp/pip-oMAeff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:35:11 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:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:35:11 INFO - Running setup.py (path:/tmp/pip-5UYsX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:35:11 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:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:35:11 INFO - Running setup.py (path:/tmp/pip-kXMb05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:35:11 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:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:35:11 INFO - Running setup.py (path:/tmp/pip-G5kIbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:35:11 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:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:35:11 INFO - Running setup.py (path:/tmp/pip-nuJBC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:35:11 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:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:35:11 INFO - Running setup.py (path:/tmp/pip-JdVqxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:35:11 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:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:35:11 INFO - Running setup.py (path:/tmp/pip-_34Ykn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:35:11 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:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:35:11 INFO - Running setup.py (path:/tmp/pip-WQq5nY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:35:11 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:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:35:11 INFO - Running setup.py (path:/tmp/pip-3LaoMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:35:11 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:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:35:11 INFO - Running setup.py (path:/tmp/pip-M5rAvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:35:11 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:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:35:12 INFO - Running setup.py (path:/tmp/pip-GuiJlf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:35:12 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:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:35:12 INFO - Running setup.py (path:/tmp/pip-md9gOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:35:12 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:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:35:12 INFO - Running setup.py (path:/tmp/pip-jsHM9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:35:12 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:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:35:12 INFO - Running setup.py (path:/tmp/pip-ShRW84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:35:12 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:35:12 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:35:12 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:35:12 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:35:12 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:35:12 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:35:12 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:35:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:35:12 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:35:12 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:35:12 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:35:12 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:35:13 INFO - Downloading blessings-1.6.tar.gz 03:35:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:35:13 INFO - Installing collected packages: blessings 03:35:13 INFO - Running setup.py install for blessings 03:35:13 INFO - Successfully installed blessings 03:35:13 INFO - Cleaning up... 03:35:13 INFO - Return code: 0 03:35:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:35:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:35:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:35:13 INFO - Reading from file tmpfile_stdout 03:35:13 INFO - Current package versions: 03:35:13 INFO - argparse == 1.2.1 03:35:13 INFO - blessings == 1.6 03:35:13 INFO - blobuploader == 1.2.4 03:35:13 INFO - docopt == 0.6.1 03:35:13 INFO - functools32 == 3.2.3-2 03:35:13 INFO - jsonschema == 2.5.1 03:35:13 INFO - manifestparser == 1.1 03:35:13 INFO - mozInstall == 1.13 03:35:13 INFO - mozcrash == 1.0 03:35:13 INFO - mozdebug == 0.1 03:35:13 INFO - mozdevice == 0.51 03:35:13 INFO - mozfile == 1.2 03:35:13 INFO - mozhttpd == 0.7 03:35:13 INFO - mozinfo == 0.10 03:35:13 INFO - mozleak == 0.1 03:35:13 INFO - mozlog == 3.5 03:35:13 INFO - moznetwork == 0.27 03:35:13 INFO - mozprocess == 0.25 03:35:13 INFO - mozprofile == 0.28 03:35:13 INFO - mozrunner == 6.13 03:35:13 INFO - mozscreenshot == 0.1 03:35:13 INFO - mozsystemmonitor == 0.3 03:35:13 INFO - moztest == 0.8 03:35:13 INFO - mozversion == 1.4 03:35:13 INFO - psutil == 3.1.1 03:35:13 INFO - requests == 1.2.3 03:35:13 INFO - wsgiref == 0.1.2 03:35:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:35: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:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35: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:35:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:13 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 0x21301f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2236a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23d9a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d6bb0>, '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 0x23d7ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d7f40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', '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:35:13 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:35:13 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:35:13 INFO - Using env: {'DISPLAY': ':0', 03:35:13 INFO - 'HOME': '/home/cltbld', 03:35:13 INFO - 'LANG': 'en_US.UTF-8', 03:35:13 INFO - 'LANGUAGE': 'en_US:en', 03:35:13 INFO - 'LOGNAME': 'cltbld', 03:35:13 INFO - 'MAIL': '/var/mail/cltbld', 03:35:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:13 INFO - 'MOZ_NO_REMOTE': '1', 03:35:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:13 INFO - 'NO_EM_RESTART': '1', 03:35:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:13 INFO - 'PWD': '/builds/slave/test', 03:35:13 INFO - 'SHELL': '/bin/bash', 03:35:13 INFO - 'SHLVL': '1', 03:35:13 INFO - 'TERM': 'linux', 03:35:13 INFO - 'TMOUT': '86400', 03:35:13 INFO - 'USER': 'cltbld', 03:35:13 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', 03:35:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:13 INFO - '_': '/tools/buildbot/bin/python'} 03:35:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:14 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:35:14 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:35:14 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:35:14 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:35:14 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:35:14 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:35:14 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:35:14 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:35:14 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:35:14 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:35: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:35: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:35: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:35: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:35:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:35:15 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:35:15 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:35:15 INFO - Installing collected packages: simplejson 03:35:15 INFO - Running setup.py install for simplejson 03:35:15 INFO - building 'simplejson._speedups' extension 03:35:15 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:35:16 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:35:16 INFO - Successfully installed simplejson 03:35:16 INFO - Cleaning up... 03:35:16 INFO - Return code: 0 03:35:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:35: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:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35: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:35:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21301f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2236a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23d9a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d6bb0>, '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 0x23d7ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d7f40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', '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:35: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', 'jsonschema'] in /builds/slave/test/build 03:35: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 jsonschema 03:35:16 INFO - Using env: {'DISPLAY': ':0', 03:35:16 INFO - 'HOME': '/home/cltbld', 03:35:16 INFO - 'LANG': 'en_US.UTF-8', 03:35:16 INFO - 'LANGUAGE': 'en_US:en', 03:35:16 INFO - 'LOGNAME': 'cltbld', 03:35:16 INFO - 'MAIL': '/var/mail/cltbld', 03:35:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:16 INFO - 'MOZ_NO_REMOTE': '1', 03:35:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:16 INFO - 'NO_EM_RESTART': '1', 03:35:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:16 INFO - 'PWD': '/builds/slave/test', 03:35:16 INFO - 'SHELL': '/bin/bash', 03:35:16 INFO - 'SHLVL': '1', 03:35:16 INFO - 'TERM': 'linux', 03:35:16 INFO - 'TMOUT': '86400', 03:35:16 INFO - 'USER': 'cltbld', 03:35:16 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', 03:35:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:16 INFO - '_': '/tools/buildbot/bin/python'} 03:35:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:35:16 INFO - Cleaning up... 03:35:16 INFO - Return code: 0 03:35:16 INFO - Running post-action listener: _resource_record_post_action 03:35:16 INFO - Running post-action listener: _start_resource_monitoring 03:35:16 INFO - Starting resource monitoring. 03:35:16 INFO - [mozharness: 2017-08-16 10:35:16.565162Z] Finished create-virtualenv step (success) 03:35:16 INFO - [mozharness: 2017-08-16 10:35:16.566157Z] Running install step. 03:35:16 INFO - Running pre-action listener: _resource_record_pre_action 03:35:16 INFO - Running main action method: install 03:35:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:35:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:35:16 INFO - Reading from file tmpfile_stdout 03:35:16 INFO - Detecting whether we're running mozinstall >=1.0... 03:35:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:35:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:35:16 INFO - Reading from file tmpfile_stdout 03:35:16 INFO - Output received: 03:35:16 INFO - Usage: mozinstall [options] installer 03:35:16 INFO - Options: 03:35:16 INFO - -h, --help show this help message and exit 03:35:16 INFO - -d DEST, --destination=DEST 03:35:16 INFO - Directory to install application into. [default: 03:35:16 INFO - "/builds/slave/test"] 03:35:16 INFO - --app=APP Application being installed. [default: firefox] 03:35:16 INFO - mkdir: /builds/slave/test/build/application 03:35:16 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:35:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:35:34 INFO - Reading from file tmpfile_stdout 03:35:34 INFO - Output received: 03:35:34 INFO - /builds/slave/test/build/application/firefox/firefox 03:35:34 INFO - Running post-action listener: _resource_record_post_action 03:35:34 INFO - [mozharness: 2017-08-16 10:35:34.857761Z] Finished install step (success) 03:35:34 INFO - [mozharness: 2017-08-16 10:35:34.858043Z] Running setup-mitmproxy step. 03:35:34 INFO - Running pre-action listener: _resource_record_pre_action 03:35:34 INFO - Running main action method: setup_mitmproxy 03:35:34 INFO - Skipping: mitmproxy is not required 03:35:34 INFO - Running post-action listener: _resource_record_post_action 03:35:34 INFO - [mozharness: 2017-08-16 10:35:34.859207Z] Finished setup-mitmproxy step (success) 03:35:34 INFO - [mozharness: 2017-08-16 10:35:34.859428Z] Running run-tests step. 03:35:34 INFO - Running pre-action listener: _resource_record_pre_action 03:35:34 INFO - Running pre-action listener: _set_gcov_prefix 03:35:34 INFO - Running main action method: run_tests 03:35:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:35:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:35:34 INFO - Python 2.7.3 03:35:34 INFO - Return code: 0 03:35:34 INFO - grabbing minidump binary from tooltool 03:35:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:34 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 0x23d6bb0>, '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 0x23d7ab0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23d7f40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:35:34 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:35:34 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:35:34 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:35:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:35:35 INFO - Return code: 0 03:35:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:35:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:35:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:35:35 INFO - ENV: RUST_BACKTRACE is now full 03:35:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:35:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:35:35 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/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:35:35 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/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-038 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:35:35 INFO - Using env: {'DISPLAY': ':0', 03:35:35 INFO - 'HOME': '/home/cltbld', 03:35:35 INFO - 'LANG': 'en_US.UTF-8', 03:35:35 INFO - 'LANGUAGE': 'en_US:en', 03:35:35 INFO - 'LOGNAME': 'cltbld', 03:35:35 INFO - 'MAIL': '/var/mail/cltbld', 03:35:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:35:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:35:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:35 INFO - 'MOZ_NO_REMOTE': '1', 03:35:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:35:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:35 INFO - 'NO_EM_RESTART': '1', 03:35:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:35 INFO - 'PWD': '/builds/slave/test', 03:35:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:35:35 INFO - 'RUST_BACKTRACE': 'full', 03:35:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:35:35 INFO - 'SHELL': '/bin/bash', 03:35:35 INFO - 'SHLVL': '1', 03:35:35 INFO - 'TERM': 'linux', 03:35:35 INFO - 'TMOUT': '86400', 03:35:35 INFO - 'USER': 'cltbld', 03:35:35 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258', 03:35:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:35 INFO - '_': '/tools/buildbot/bin/python'} 03:35:35 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/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:35:36 INFO - mozversion application_buildid: 20170816092342 03:35:36 INFO - mozversion application_changeset: 6ebc251bd288c268b020815025b05854ccde5c08 03:35:36 INFO - mozversion application_display_name: Nightly 03:35:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:35:36 INFO - mozversion application_name: Firefox 03:35:36 INFO - mozversion application_remotingname: firefox 03:35:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:35:36 INFO - mozversion application_vendor: Mozilla 03:35:36 INFO - mozversion application_version: 57.0a1 03:35:36 INFO - mozversion platform_buildid: 20170816092342 03:35:36 INFO - mozversion platform_changeset: 6ebc251bd288c268b020815025b05854ccde5c08 03:35:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:35:36 INFO - mozversion platform_version: 57.0a1 03:35:36 INFO - using testdate: 1502879736 03:35:36 INFO - actual date: 1502879736 03:35:36 INFO - starting webserver on 'localhost:54396' 03:35:36 INFO - SUITE-START | Running 2 tests 03:35:36 INFO - TEST-START | dromaeo_css 03:35:36 INFO - Initialising browser for dromaeo_css test... 03:35:37 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54396/getInfo.html -profile /tmp/tmpLrJAH7/profile 03:35:37 INFO - TEST-INFO | started process 3321 (/builds/slave/test/build/application/firefox/firefox http://localhost:54396/getInfo.html) 03:35:46 INFO - TEST-INFO | 3321: exit 0 03:35:46 INFO - Browser initialized. 03:35:46 INFO - Running cycle 1/1 for dromaeo_css test... 03:35:46 INFO - TEST-INFO | started process 3531 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLrJAH7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:35:47 INFO - PID 3531 | 03:35:47 INFO - PID 3531 | (/builds/slave/test/build/application/firefox/firefox:3579): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:35:47 INFO - PID 3531 | 03:35:57 INFO - PID 3531 | RSS: Main: 168554496 03:35:57 INFO - PID 3531 | 03:38:55 INFO - PID 3531 | Cycle 1(1): loaded http://localhost:54396/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54396/tests/dromaeo/cssquery-ext.html) 03:38:57 INFO - PID 3531 | RSS: Main: 173133824 03:38:57 INFO - PID 3531 | 03:41:59 INFO - PID 3531 | Cycle 1(1): loaded http://localhost:54396/tests/dromaeo/cssquery-ext.html (next: http://localhost:54396/tests/dromaeo/cssquery-jquery.html) 03:41:59 INFO - PID 3531 | RSS: Main: 170598400 03:41:59 INFO - PID 3531 | 03:45:01 INFO - PID 3531 | Cycle 1(1): loaded http://localhost:54396/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54396/tests/dromaeo/cssquery-mootools.html) 03:45:02 INFO - PID 3531 | RSS: Main: 173576192 03:45:02 INFO - PID 3531 | 03:48:03 INFO - PID 3531 | Cycle 1(1): loaded http://localhost:54396/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54396/tests/dromaeo/cssquery-prototype.html) 03:48:04 INFO - PID 3531 | RSS: Main: 171089920 03:48:04 INFO - PID 3531 | 03:51:07 INFO - PID 3531 | Cycle 1(1): loaded http://localhost:54396/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54396/tests/dromaeo/cssquery-yui.html) 03:51:08 INFO - PID 3531 | RSS: Main: 172257280 03:51:08 INFO - PID 3531 | 03:54:10 INFO - PID 3531 | Cycle 1(1): loaded http://localhost:54396/tests/dromaeo/cssquery-yui.html (next: http://localhost:54396/tests/dromaeo/cssquery-dojo.html) 03:54:10 INFO - PID 3531 | RSS: Main: 175583232 03:54:10 INFO - PID 3531 | 03:54:10 INFO - PID 3531 | __start_tp_report 03:54:10 INFO - PID 3531 | _x_x_mozilla_page_load 03:54:10 INFO - PID 3531 | _x_x_mozilla_page_load_details 03:54:10 INFO - PID 3531 | |i|pagename|runs| 03:54:10 INFO - PID 3531 | |0;dojo.html;3138.2154461384653;3180.8250546219956;3181.4591519441706;3190.58522392089;3208.9358212835746;6098.939010609893;6151.231096112986;6271.968640156804;6287.842803929899;6309.305976342599;6037.516998640109;6140.017597184451;6162.168107305512;6176.876462470748;6194.132821405007;5639.830805075854;5662.773489060432;5681.11942648888;5704.8859022819515;5767.653940763546;5756.913646295309;5757.453041961007;5768.798092066779;5797.130430435447;5822.272215973004;138.28062143809927;5588;5644.548436125121;5763.682997435139;5789.2763404574425;5617.21359009739;5627.6904770237625;5643.322801263875;5645.23789288449;5718.656880587178;5596.524295434893;5605.2713147291;5657.688827114507;5665.405132461074;5704.486596206361;5985.940140598582;6100.176476175704;6173.9073913891325;6181.690915454209;6197.442230199304;6541.901871471884;6551.181102362205;6591.143151390336;6643.103181070542;6671.799846004627;6538.280789113193;6610.206775187008;6667.499937504658;6725.226598941086;6743.662816859138;6238.095476155977;6264.467520260738;6282.371762823726;6293.653849038347;6294.496440284766;6822.3859852613505;6924.169099708067;6931.0643063186835;6933.2026816915695;6937.791866244021;6492.318306577741;6503.187101612299;6580.605163690194;6586.80239592813;6599.835004124935;6600.73597056122;6616.073749675049;6656.800295991128;6731.427828634523;6747.05541224229;6577.210734711865;6596.538242322991;6596.670166491752;6600.5049621278595;6608.240052394036;6524.478041756648;6599.142111525567;6629.204495460575;6664.933610622277;6671.099401580725;5309.734513274321;5386.973065134727;5459.590530710213;5463.125899856004;5468.316460442445;4571.291449825282;4577.656675749332;4626.606738427204;4638.281066434708;4640.489546149921;5731.742071606787;5737.426257374229;5740.1102829061565;5747.023006088892;5752.194692743019;6577.704003319862;6633.701483433257;6657;6669.599824010478;6674.299198584175;5700.344460387026;5752.309722833287;5788.88422231553;5808.6224395414165;5813.12803079533;5845.986984815635;5856.267966504187;5910.763569457174;5941.316748573883;5947.851303717442;6304.211973503312;6400.7119679614525;6424.1327420798525;6507.381798729113;6511.609303441714;6596.967015164798;6609.339066093545;6647.202335719551;6700.497462690415;6725;6524.510661700297;6526.282108967915;6529.640869752209;6543.574667646588;6553.442957348679;6557.836054098686;6571.047198156336;6650.567713098633;6666.366695163952;6675.933240667726;6546.541742078009;6630.701618427086;6681.198256209286;6770.2891196425635;6771.153605799275;6449.226092868886;6466.676666166767;6502.772402965874;6513.250976137805;6533.509986751108;6454.096426500388;6478.28738838718;6502.349765023649;6532.085508028975;6550.050242714875;6379.74481020754;6384.329645387167;6468.094466774562;6477.190351206099;6477.578957367756;6479.125318081999;6511.576747511396;6542.313057129123;6612.272650008399;6627.304133065959;6193.597416167935;6206.72069756871;6210.5652604317265;6212.254529456494;6225.221847269091 03:54:10 INFO - PID 3531 | |1;ext.html;744.2780502912174;786.2609147401446;813.9837203255934;832.371559472598;842.4061036968938;39713;40803;40844.59155408449;40934.18131637365;40958.59041409585;37281.81359093208;37365.813170934176;37605.247895042085;37625;37794.62205377945;6583.4074933256115;6706.228783689865;6718.563293385939;6725.394714475696;6732.158480189976;6609.239937407187;6691.698873550702;6694.531382803206;6714.194296684404;6720.361565651256;6654.567453115556;7424.368928641045;7588.506747061451;7605.239476052406;7606.505577137468;7485.62571871407;7528.548287102792;7556.093268807724;7558.546487210758;7567.999999999973;7428.145763237243;7506.174320824705;7510.061242344707;7533.811654708621;7537.9623101884135;38690;39008;39113.21773564513;39157.608423915684;39274.8217553471;3525.8589656413715;3575.94636080459;3578.248567800765;3580.1765593913287;3582.158192824684;31182.532262016088;31247.531287030713;31280.53079203814;31297.374052518822;31303.53044704331;5095.363079615048;5100.285959965608;5107.616928730323;5111.463296353866;5121.359830021247;194772;196192;197334;197346;197514;48097.75951120222;48120;48149.51850481451;48165;48189.75905120522;49716.50283497119;49818.750906245965;50276;50285;50350.24474632883;15645.217739112999;15689.450869219523;15692.372305107896;15704.842951570337;15736.527904162664;12768.233905965672;12837;12848.100632955604;12856.807147892789;12864.22814631125;14337.64155896111;14508.274586270643;14514.709705805824;14614.561563153122;14769.187694676895;5874.9118763218585;5911.231539899785;5958.702064896738;5961.135635332853;5988.6706231157705;972.4602845420718;1008.4050410257983;1009.3641006166215;1011.3729487717662;1018.2719355660738;23618.763812362;23669.881650591982;23895.880520597286;23915.282541523782;23954.40113997129;1129.4296380328046;1130.7512347283584;1130.9208355415205;1136.891995260449;1139.3050239353988;12308.630741077603;16244.675106497803;16290.51128466148;16447;16607.08661023631;250449.9910001826;250746;250966;251104;251159;53042.46957530452;56554.43445565574;56580.71709641508;56975;57238.42761572414;1461.65651071998;1464.2898194375507;1485.7548504496638;1488.5013520470764;1504.9548513544612;27106.593401099395;27769.722302776714;27930.86034569814;28026.2993425166;28308.575371369036;37442.25115497729;37946.62053379431;37949.620503794606;37955.62044379521;38046.42930355992;220995;221393.89303053383;221538;221820;221998;34530.13674658053;34848.47727284143;34875.825620871736;35021.124471887386;35143;41812.3728144072;44124.11751765011;44176.77911610421;44185.55814441943;44402.333964989906;31888.36223275568;32408.67591324151;32441.51337729889;32501.512477313332;32859.17852053718;31542.526862096627;31923.042308730775;32662.83668581642;32910.83544582358;32977.34045319032;34069.148271292426;34245.315093697485;34246;34597.65402345944;34775.47836782501;31529.684703152674;32082.35835283328;33813.49279760756;34528.48207276943;35066.824665876506;24925.501489970462;25646.358841029123;25807.096751613604;25845.48309033771;26360 03:54:10 INFO - PID 3531 | |2;jquery.html;555.6055200807426;564.3143580549633;566.2440641743273;571.1090698510326;573.985650358741;21630.89184554077;22874.428139296528;22946.770532294715;22964.770352296473;22987.655185172236;25124.74875251247;26213.344666383302;27339.72660273397;27407;27544.449111017766;6603.108580341653;6768.8307792305095;6772.390484856362;6787.660616969157;6799.626020568867;6145.8156255312415;6405.903911441332;6568.146141001663;6615.569987950792;6624.834379140536;6421.068945002972;6421.165248517709;6421.711023003976;6421.7752378666755;6423.421892029716;6382.553221274513;6396.072569477446;6400.455961243277;6401.455876250524;6402.807915762512;5948.583599148062;5969.283685957669;5975.790847320366;5977.46202841742;5981.730822113015;29679.25801854949;29960;30572.8471357644;30876.53685194724;31046.534301985488;1871.6537440573538;1912.7991560886046;1913.7799153097294;1915.6934890417606;1916.252661462031;18309.45071647853;18936.71594926077;18986.145623446977;19074.23703051876;19077.2369105237;2342.426105604121;2355.9293221203393;2407.0973384980634;2414.468816860288;2431.8784060797066;129217.35391322983;133954.33022834823;133969;134102.3294883529;134115.32942335322;14324.283785810876;14469.276536173149;14603.634909127144;15053.172075535731;15176.165310908005;21230.46923826886;21251.46871328198;23698.526029479657;24407;24626.27616066894;10210.285280030326;10340.172786177087;10557.366558006544;10633.149348052137;10675.626353077605;9691.176250018687;9710.271729620306;9724.465154416575;9784.217262618973;9834.409935403755;9575.377600455948;9580.742547533871;9661.9033809661;9882.308238423242;9965.900340996643;2819.8991751145527;2822.3508593023307;2849.5725641153576;2850.372917958046;2928.048384275119;957.4207604399378;974.551706215149;974.6734843827255;987.6098940918441;997.4514017290461;20168.89915550413;20519.281825136346;20636.587268254552;20805.479863003242;21073.89463052675;1038.5793753529833;1048.9213309001855;1049.2497864027257;1062.4793851012948;1065.7229120428744;11471.942640286745;11514;11625.360605166628;11680.240784348991;11681;471034;484117;487856;491127;491171;118842;118883;120641.39679301548;120948;121586;1205.3185805800858;1226.5400474821943;1250.349818094604;1250.531050855929;1252.9185602935597;18494.167762450452;18576.349827755963;18878.433646990612;22054;23352.18267360634;48401;57027;59152.40847591641;62114.378856212665;62441.375586245376;71788.28211717815;71890;72147.27852721406;72246;74043.62978185074;14219.146851188962;14529.128252304896;14572.854271457574;14666.63333416673;14793.186374749492;23886;23900.402489937194;24367.14714984967;24502.754972450046;24507.754922451262;18248.270069197082;18627.90686046615;18980.810191897905;18988.335408260646;19124.521886952938;15993.760093598838;17030.829691702926;17147.31410743606;17397.130143492523;17704.380346687805;18975;19084.522886927938;19167.616647667248;19351.12919918635;19369.225230990603;14318.642033948818;14388.568342949728;14390.848732101616;14396.856031439971;14410.063345882483;18636.254549817855;18943;18965.71551426702;19032;19117.426477205707 03:54:10 INFO - PID 3531 | |3;mootools.html;1338.317458096371;1379.8827099696525;1387.4311532271768;1406.725688490744;1410.1750475971555;14184.57446276612;14592.781108283385;14834.10995340279;14882.330295136719;14910.62723431912;15170.772438413405;15352.309146088424;15364.846351536482;15466.381344746196;15488.225588720576;2626.6322714819894;2679.1560658392546;2680.6864636328255;2694.609281654159;2696.6763988321427;2573.3437973316777;2673.679158500973;2715.4433341165113;2715.891364345424;2716.8234064785725;2584.198898341511;2601.6617839680816;2605.114261151208;2643.6298918151474;2646.3251870772924;2515.710693270279;2525.848449093051;2579.896804127842;2629.8027647926483;2630.1714959787796;2564.7435256474205;2586.314626623946;2594.6237795519733;2612.111881960124;2619.724928882459;14051.929740351336;14282.714345713028;14284.785728214085;14332.211728354936;14361.138331700131;7461.253874612549;7731.381489480828;7763.301302882711;7775.300222979902;7783.299503044753;4763.14263432582;4792.257200133985;4849;4955.702657840541;4956.826511072131;1873.7095750158746;1890.962180756391;1908.5896532245495;1945.5000324582843;1946.1923301829725;23861.76138238613;24026.038958441815;24110.035598576036;24110.27669169945;24122.758772412228;6124.295705993779;6323.652199129092;6430.64631445275;6462.353764623594;6490.123833282447;15893;16459.58851028734;16463.506094817174;16467.423640172536;16488.67022659564;7230.855382892418;7389.408847292311;7397.852042959111;7403.51877127985;7416.147143078615;5605.411431799684;5795.826125216166;6243.282022567372;6829.590224586541;6858.931410685929;5014.373203349582;5111;5190.792368305226;5197.29836472079;5211.661242019257;1457.883369330451;1472.9410823566936;1477.409036385433;1486.1900264355952;1488.1740633948264;856.39624065034;867.4491697771961;873.2097451806126;882.5498995512206;883.9911600884037;7319.231480694557;7329.780106596811;8552.657893684307;9044.683436079837;9471.242300615933;972.8394814855469;980.6616717232544;981.5533932061038;991.6281394477071;997.3567049253191;4791.47293797682;5651.57613179005;5823.679697616587;5871.236739223874;6039.577229593886;18422.447326580223;19001.524961875788;19039.619207616048;19058.2376704933;19067.332643357415;18759.812401875806;18833.529161771065;18854.15156317969;19006.524836879187;19114.904425477784;11125.27685700443;11380.317180969168;11423.485943132579;11441.713957151138;11452.484638191301;8346;8355.080941096596;8375.45559538628;8393.454425462325;8862.734117976472;17585.120743962496;17661.558461038578;17746.822531774516;17870.285188592312;17896.91051544786;17914.372996945043;17955.730664039787;17963;18027.639447211244;18159.636807264043;11905.345206013752;12159.148859579744;12166.270023798601;12169.148159629096;12173.573924912585;13506.59480215595;13525.458981640624;13568.38942247621;13572.79640805369;13572.86427135716;11069.667909962714;11155.163362747664;11234.775304494027;11375.26060806045;11422.714432139262;10345.431001295;10504.894951050697;10555.366677999345;10725.195610329101;11203.159763017644;9287.907120928974;9299.76750581241;9308.906910930804;9350.3454758169;9373.812523749622;7113.964430178023;7129;7199.3520583147765;7201.13586369639;7245.166805817398;6899.689513971984;7067.116610423697;7111.395531379891;7159.319864612853;7271.2365201653065 03:54:10 INFO - PID 3531 | |4;prototype.html;498.9920360871043;500.3095727895505;512.8589637849593;524.9790008399664;530.5940955169297;6060.606060606057;6127.35662755411;6201.131841542182;6259.155014073099;6284.434400903918;6065.423784740443;6073.997790364585;6126.938730612704;6153.9384606154035;6184.134221209023;4911.214205727084;4918.868660208136;4958.355413796201;4971.950280497185;4996.375453068366;4721.787519561611;4907.705537667734;4921.335619691332;4922.286268491067;4942.728149951751;4813.37426134602;4835.879103022435;4853.733044682541;4875.390576177978;4895;4813.687110337817;4832.710037397732;4888.535589119028;4891.33694543855;4895.192293271606;4378.8905277368;4459.085887393109;4459.487158976727;4459.799309031101;4477.194105061088;5974.266083697689;6053.303870054956;6059.484943779784;6097.878042439126;6123.908141377883;5589.329280486327;5616.663000219959;5636.379998200195;5652.576056795716;5697.829065128053;6317.463015643676;6437.967810160966;6561.114249576294;6563.1796025496815;6593.274739778621;4885.755712214411;4898.485659005824;4898.848770538956;4909.361782968192;4921.901561968776;6647.734090636369;6758.324167583251;6763.7632682855865;6784.932150678479;6817;4924.4090709115135;4928.605711543068;4928.79244585079;4930.6795058321095;4951.628627852925;4941.65408421407;4988.176950803149;4998;5018.594793457838;5022.146235140035;5498.202760599771;5518.089515229942;5551.3893471718075;5574.749136288877;5583.692896890629;6381.351739028344;6407.7436902524305;6494.480441564663;6501.349865013461;6545.952647576365;1672.222416576298;1715.5282297368074;1724.2327164411715;1752.824717528237;1764.1443899708631;1199.7822210456393;1212.7210741529498;1215.9452824622913;1217.829503869459;1238.207547169812;5611.831645050655;5653.971730141323;5655.999999999918;5685.374608793029;5710.2576665033275;6633.18089811828;6696.196456425163;6711.966440167768;6720.8319792005595;6753.257141714407;5728.713564321767;5746.827595172152;5789.1605717171615;5820.970895145582;5862.355140934494;3538.752287339913;3608.657177568127;3608.8376023079027;3614.8915532534065;3618;6636.469082473391;6659.633720145342;6664;6680.365365290289;6680.732770689118;6839.384455398938;6918.861622767517;6935.757248496278;7000.354916594596;7022.754203602849;6474.870502589922;6546.639934803441;6593.208814942238;6608.67826434715;6654.70053847578;6628.7017084230365;6672.8999065013095;6674.866502669823;6688.464922806261;6701.497387695845;6544.4764418845425;6553.311902250195;6617.716887673745;6690.966545167243;6757.222919364336;6478.870422591616;6512.251091124392;6523.217213934168;6537.182852143482;6661.933380666325;4893.975530122327;4917.729524876023;4939.53074457926;4972.8756781080765;4973.452920178705;6416.358364163733;6644.60132392058;6731.831704207434;6775.186977562526;6859.794206173823;6303.684815759286;6418.422341989243;6436.195475565554;6450.741970321134;6517.185351831021;6401.743930242925;6410.0705397718;6423.871522569429;6443.613383197023;6556.2788093310655;6210.937890621036;6285.622862628257;6312.210973628296;6345.762576297578;6430.967845160744;6557.409833115043;6607.405333520006;6609.966950165411;6616.900746488903;6618.503612229199;6264.718087686157;6278.932581461071;6336.683165841781;6402.359764023747;6426.4216220540375 03:54:10 INFO - PID 3531 | |5;yui.html;897.6858099665118;904.8687940248664;911.3939230411777;919.8114386550758;924.9722508324749;18853.62292754144;19896.10467528963;19932;19954.501137471554;22555.774442255573;22127.44681382966;22810.885945570248;22880.885595572;22912.198073067448;22992.8850355748;972.9416235025885;988.7626969527298;990.2721499697716;990.5195979949755;994.3884511025703;1027.286036204838;1040.3757745352802;1060.777236780277;1073.7315671082229;1076.8061748885198;1122.1247427006906;1129.6441620889393;1129.8474705914678;1130.2145009218596;1130.3839407522878;1122.0350498571222;1123.0117496603018;1125;1125.2235957189514;1125.94370281486;819.8360327934378;820.741466438076;821.6918655504186;822.8724547695117;823.4194892600702;19740.40778776639;19942.401727948185;19959.700604490943;20359.065888740544;21391.358259252247;21495.24766633176;21570;21842.672359914613;21878.89060554703;22222.66666000011;15684.451044213512;15795.368185272575;15866.682666346724;17027.744583831252;17865.196066177054;3690.944635830465;3700.833462494194;3710.9628903710886;3711.350513660099;3712.610175931515;126147;126929;130478;130604.99999999905;130679;16558.089305088335;16935.830641693672;17296;17397.565060873578;17649.647007059786;31463.685363146076;31837.363252734816;31934.84032579822;32412.513812292833;32573.18567035843;7993.160718124514;8280.461769985051;8646.308295336357;8874.378793484395;8959.372843900996;4093.8362465501045;4167.562405947392;4171.0823618803815;4174.979125104416;4178.310578754532;5316.468353164652;5374.704391258519;5377.623566350318;5417.106177480671;5420.566354691615;1034.110664828262;1057.0116940660507;1057.846612241236;1066.157735389034;1068.3483075324045;669.9832504187434;672.37133280383;683.3303362704578;688.6797639097744;698.5703792167781;11834;12112.878871211351;12127.818082728767;12278.579711303924;13224.632261281477;665.3811954882007;668.7960172147542;669.3774789445862;685.6023506366296;685.7839780469136;6052.364501727343;6374.617522948545;6433.646149461824;6471.741130354827;6593.307702691244;108250.45874770575;109550;111889;112239;116000.41999789947;98071;102382.48808755909;103940.48029759954;106235.93764062415;106488;89604;90862;91645.54177229072;94548;94573.5271323639;13033.934830325788;13343.199408035549;13374.13068150606;13469.46122155142;13519;38703.419448707726;38907.805460972515;39181;39252;41185.79407103065;51091.23363149481;52298;52334.73832630965;52810.107426250055;54201.45798541964;18870.433886983414;18884.716729248797;19231.615367692848;19278.518037049187;19285.903570482056;21307.25424610131;21365.14539418406;21402.143914243257;21426.142954281655;21551.676724848825;18884.433466995462;19014.809851901304;19077.046147692836;19139.425817225507;19206.423807285802;17573.472795816146;17782.822171778633;17784.73322900132;17809.198586063398;17941.282348705907;18781.34265300708;19237.711434328776;19261.614767704847;19312.13095410682;19485.805141948968;14003.789943150658;14094.154350738989;14155.221462819232;14347.678685386438;14377.784333235219;14089.647758806112;14248.216348100954;14322.928385358007;14380.712385752435;14492.347844347241 03:54:10 INFO - PID 3531 | __end_tp_report 03:54:10 INFO - PID 3531 | __start_cc_report 03:54:10 INFO - PID 3531 | _x_x_mozilla_cycle_collect,389 03:54:10 INFO - PID 3531 | __end_cc_report 03:54:10 INFO - PID 3531 | __startTimestamp1502880850397__endTimestamp 03:54:10 INFO - PID 3531 | 03:54:10 INFO - PID 3531 | ------- Summary: start ------- 03:54:10 INFO - PID 3531 | Number of tests: 6 03:54:10 INFO - PID 3531 | 03:54:10 INFO - PID 3531 | [#0] dojo.html Cycles:165 Average:6094.34 Median:6384.33 stddev:854.56 (13.4%) stddev-sans-first:825.12 03:54:10 INFO - PID 3531 | Values: 3138.2 3180.8 3181.5 3190.6 3208.9 6098.9 6151.2 6272.0 6287.8 6309.3 6037.5 6140.0 6162.2 6176.9 6194.1 5639.8 5662.8 5681.1 5704.9 5767.7 5756.9 5757.5 5768.8 5797.1 5822.3 138.3 5588.0 5644.5 5763.7 5789.3 5617.2 5627.7 5643.3 5645.2 5718.7 5596.5 5605.3 5657.7 5665.4 5704.5 5985.9 6100.2 6173.9 6181.7 6197.4 6541.9 6551.2 6591.1 6643.1 6671.8 6538.3 6610.2 6667.5 6725.2 6743.7 6238.1 6264.5 6282.4 6293.7 6294.5 6822.4 6924.2 6931.1 6933.2 6937.8 6492.3 6503.2 6580.6 6586.8 6599.8 6600.7 6616.1 6656.8 6731.4 6747.1 6577.2 6596.5 6596.7 6600.5 6608.2 6524.5 6599.1 6629.2 6664.9 6671.1 5309.7 5387.0 5459.6 5463.1 5468.3 4571.3 4577.7 4626.6 4638.3 4640.5 5731.7 5737.4 5740.1 5747.0 5752.2 6577.7 6633.7 6657.0 6669.6 6674.3 5700.3 5752.3 5788.9 5808.6 5813.1 5846.0 5856.3 5910.8 5941.3 5947.9 6304.2 6400.7 6424.1 6507.4 6511.6 6597.0 6609.3 6647.2 6700.5 6725.0 6524.5 6526.3 6529.6 6543.6 6553.4 6557.8 6571.0 6650.6 6666.4 6675.9 6546.5 6630.7 6681.2 6770.3 6771.2 6449.2 6466.7 6502.8 6513.3 6533.5 6454.1 6478.3 6502.3 6532.1 6550.1 6379.7 6384.3 6468.1 6477.2 6477.6 6479.1 6511.6 6542.3 6612.3 6627.3 6193.6 6206.7 6210.6 6212.3 6225.2 03:54:10 INFO - PID 3531 | 03:54:10 INFO - PID 3531 | [#1] ext.html Cycles:180 Average:39217.43 Median:34948.48 stddev:58145.57 (166.4%) stddev-sans-first:58236.92 03:54:10 INFO - PID 3531 | Values: 744.3 786.3 814.0 832.4 842.4 39713.0 40803.0 40844.6 40934.2 40958.6 37281.8 37365.8 37605.2 37625.0 37794.6 6583.4 6706.2 6718.6 6725.4 6732.2 6609.2 6691.7 6694.5 6714.2 6720.4 6654.6 7424.4 7588.5 7605.2 7606.5 7485.6 7528.5 7556.1 7558.5 7568.0 7428.1 7506.2 7510.1 7533.8 7538.0 38690.0 39008.0 39113.2 39157.6 39274.8 3525.9 3575.9 3578.2 3580.2 3582.2 31182.5 31247.5 31280.5 31297.4 31303.5 5095.4 5100.3 5107.6 5111.5 5121.4 194772.0 196192.0 197334.0 197346.0 197514.0 48097.8 48120.0 48149.5 48165.0 48189.8 49716.5 49818.8 50276.0 50285.0 50350.2 15645.2 15689.5 15692.4 15704.8 15736.5 12768.2 12837.0 12848.1 12856.8 12864.2 14337.6 14508.3 14514.7 14614.6 14769.2 5874.9 5911.2 5958.7 5961.1 5988.7 972.5 1008.4 1009.4 1011.4 1018.3 23618.8 23669.9 23895.9 23915.3 23954.4 1129.4 1130.8 1130.9 1136.9 1139.3 12308.6 16244.7 16290.5 16447.0 16607.1 250450.0 250746.0 250966.0 251104.0 251159.0 53042.5 56554.4 56580.7 56975.0 57238.4 1461.7 1464.3 1485.8 1488.5 1505.0 27106.6 27769.7 27930.9 28026.3 28308.6 37442.3 37946.6 37949.6 37955.6 38046.4 220995.0 221393.9 221538.0 221820.0 221998.0 34530.1 34848.5 34875.8 35021.1 35143.0 41812.4 44124.1 44176.8 44185.6 44402.3 31888.4 32408.7 32441.5 32501.5 32859.2 31542.5 31923.0 32662.8 32910.8 32977.3 34069.1 34245.3 34246.0 34597.7 34775.5 31529.7 32082.4 33813.5 34528.5 35066.8 24925.5 25646.4 25807.1 25845.5 26360.0 03:54:10 INFO - PID 3531 | 03:54:10 INFO - PID 3531 | [#2] jquery.html Cycles:180 Average:34934.81 Median:23169.92 stddev:81761.08 (352.9%) stddev-sans-first:81949.70 03:54:10 INFO - PID 3531 | Values: 555.6 564.3 566.2 571.1 574.0 21630.9 22874.4 22946.8 22964.8 22987.7 25124.7 26213.3 27339.7 27407.0 27544.4 6603.1 6768.8 6772.4 6787.7 6799.6 6145.8 6405.9 6568.1 6615.6 6624.8 6421.1 6421.2 6421.7 6421.8 6423.4 6382.6 6396.1 6400.5 6401.5 6402.8 5948.6 5969.3 5975.8 5977.5 5981.7 29679.3 29960.0 30572.8 30876.5 31046.5 1871.7 1912.8 1913.8 1915.7 1916.3 18309.5 18936.7 18986.1 19074.2 19077.2 2342.4 2355.9 2407.1 2414.5 2431.9 129217.4 133954.3 133969.0 134102.3 134115.3 14324.3 14469.3 14603.6 15053.2 15176.2 21230.5 21251.5 23698.5 24407.0 24626.3 10210.3 10340.2 10557.4 10633.1 10675.6 9691.2 9710.3 9724.5 9784.2 9834.4 9575.4 9580.7 9661.9 9882.3 9965.9 2819.9 2822.4 2849.6 2850.4 2928.0 957.4 974.6 974.7 987.6 997.5 20168.9 20519.3 20636.6 20805.5 21073.9 1038.6 1048.9 1049.2 1062.5 1065.7 11471.9 11514.0 11625.4 11680.2 11681.0 471034.0 484117.0 487856.0 491127.0 491171.0 118842.0 118883.0 120641.4 120948.0 121586.0 1205.3 1226.5 1250.3 1250.5 1252.9 18494.2 18576.3 18878.4 22054.0 23352.2 48401.0 57027.0 59152.4 62114.4 62441.4 71788.3 71890.0 72147.3 72246.0 74043.6 14219.1 14529.1 14572.9 14666.6 14793.2 23886.0 23900.4 24367.1 24502.8 24507.8 18248.3 18627.9 18980.8 18988.3 19124.5 15993.8 17030.8 17147.3 17397.1 17704.4 18975.0 19084.5 19167.6 19351.1 19369.2 14318.6 14388.6 14390.8 14396.9 14410.1 18636.3 18943.0 18965.7 19032.0 19117.4 03:54:10 INFO - PID 3531 | 03:54:10 INFO - PID 3531 | [#3] mootools.html Cycles:180 Average:8910.62 Median:2585.26 stddev:6137.42 (237.4%) stddev-sans-first:6128.27 03:54:10 INFO - PID 3531 | Values: 1338.3 1379.9 1387.4 1406.7 1410.2 14184.6 14592.8 14834.1 14882.3 14910.6 15170.8 15352.3 15364.8 15466.4 15488.2 2626.6 2679.2 2680.7 2694.6 2696.7 2573.3 2673.7 2715.4 2715.9 2716.8 2584.2 2601.7 2605.1 2643.6 2646.3 2515.7 2525.8 2579.9 2629.8 2630.2 2564.7 2586.3 2594.6 2612.1 2619.7 14051.9 14282.7 14284.8 14332.2 14361.1 7461.3 7731.4 7763.3 7775.3 7783.3 4763.1 4792.3 4849.0 4955.7 4956.8 1873.7 1891.0 1908.6 1945.5 1946.2 23861.8 24026.0 24110.0 24110.3 24122.8 6124.3 6323.7 6430.6 6462.4 6490.1 15893.0 16459.6 16463.5 16467.4 16488.7 7230.9 7389.4 7397.9 7403.5 7416.1 5605.4 5795.8 6243.3 6829.6 6858.9 5014.4 5111.0 5190.8 5197.3 5211.7 1457.9 1472.9 1477.4 1486.2 1488.2 856.4 867.4 873.2 882.5 884.0 7319.2 7329.8 8552.7 9044.7 9471.2 972.8 980.7 981.6 991.6 997.4 4791.5 5651.6 5823.7 5871.2 6039.6 18422.4 19001.5 19039.6 19058.2 19067.3 18759.8 18833.5 18854.2 19006.5 19114.9 11125.3 11380.3 11423.5 11441.7 11452.5 8346.0 8355.1 8375.5 8393.5 8862.7 17585.1 17661.6 17746.8 17870.3 17896.9 17914.4 17955.7 17963.0 18027.6 18159.6 11905.3 12159.1 12166.3 12169.1 12173.6 13506.6 13525.5 13568.4 13572.8 13572.9 11069.7 11155.2 11234.8 11375.3 11422.7 10345.4 10504.9 10555.4 10725.2 11203.2 9287.9 9299.8 9308.9 9350.3 9373.8 7114.0 7129.0 7199.4 7201.1 7245.2 6899.7 7067.1 7111.4 7159.3 7271.2 03:54:10 INFO - PID 3531 | 03:54:10 INFO - PID 3531 | [#4] prototype.html Cycles:180 Average:5474.96 Median:6085.94 stddev:1544.15 (25.4%) stddev-sans-first:1502.64 03:54:10 INFO - PID 3531 | Values: 499.0 500.3 512.9 525.0 530.6 6060.6 6127.4 6201.1 6259.2 6284.4 6065.4 6074.0 6126.9 6153.9 6184.1 4911.2 4918.9 4958.4 4972.0 4996.4 4721.8 4907.7 4921.3 4922.3 4942.7 4813.4 4835.9 4853.7 4875.4 4895.0 4813.7 4832.7 4888.5 4891.3 4895.2 4378.9 4459.1 4459.5 4459.8 4477.2 5974.3 6053.3 6059.5 6097.9 6123.9 5589.3 5616.7 5636.4 5652.6 5697.8 6317.5 6438.0 6561.1 6563.2 6593.3 4885.8 4898.5 4898.8 4909.4 4921.9 6647.7 6758.3 6763.8 6784.9 6817.0 4924.4 4928.6 4928.8 4930.7 4951.6 4941.7 4988.2 4998.0 5018.6 5022.1 5498.2 5518.1 5551.4 5574.7 5583.7 6381.4 6407.7 6494.5 6501.3 6546.0 1672.2 1715.5 1724.2 1752.8 1764.1 1199.8 1212.7 1215.9 1217.8 1238.2 5611.8 5654.0 5656.0 5685.4 5710.3 6633.2 6696.2 6712.0 6720.8 6753.3 5728.7 5746.8 5789.2 5821.0 5862.4 3538.8 3608.7 3608.8 3614.9 3618.0 6636.5 6659.6 6664.0 6680.4 6680.7 6839.4 6918.9 6935.8 7000.4 7022.8 6474.9 6546.6 6593.2 6608.7 6654.7 6628.7 6672.9 6674.9 6688.5 6701.5 6544.5 6553.3 6617.7 6691.0 6757.2 6478.9 6512.3 6523.2 6537.2 6661.9 4894.0 4917.7 4939.5 4972.9 4973.5 6416.4 6644.6 6731.8 6775.2 6859.8 6303.7 6418.4 6436.2 6450.7 6517.2 6401.7 6410.1 6423.9 6443.6 6556.3 6210.9 6285.6 6312.2 6345.8 6431.0 6557.4 6607.4 6610.0 6616.9 6618.5 6264.7 6278.9 6336.7 6402.4 6426.4 03:54:10 INFO - PID 3531 | 03:54:10 INFO - PID 3531 | [#5] yui.html Cycles:180 Average:24064.12 Median:20159.38 stddev:32573.99 (161.6%) stddev-sans-first:32618.92 03:54:10 INFO - PID 3531 | Values: 897.7 904.9 911.4 919.8 925.0 18853.6 19896.1 19932.0 19954.5 22555.8 22127.4 22810.9 22880.9 22912.2 22992.9 972.9 988.8 990.3 990.5 994.4 1027.3 1040.4 1060.8 1073.7 1076.8 1122.1 1129.6 1129.8 1130.2 1130.4 1122.0 1123.0 1125.0 1125.2 1125.9 819.8 820.7 821.7 822.9 823.4 19740.4 19942.4 19959.7 20359.1 21391.4 21495.2 21570.0 21842.7 21878.9 22222.7 15684.5 15795.4 15866.7 17027.7 17865.2 3690.9 3700.8 3711.0 3711.4 3712.6 126147.0 126929.0 130478.0 130605.0 130679.0 16558.1 16935.8 17296.0 17397.6 17649.6 31463.7 31837.4 31934.8 32412.5 32573.2 7993.2 8280.5 8646.3 8874.4 8959.4 4093.8 4167.6 4171.1 4175.0 4178.3 5316.5 5374.7 5377.6 5417.1 5420.6 1034.1 1057.0 1057.8 1066.2 1068.3 670.0 672.4 683.3 688.7 698.6 11834.0 12112.9 12127.8 12278.6 13224.6 665.4 668.8 669.4 685.6 685.8 6052.4 6374.6 6433.6 6471.7 6593.3 108250.5 109550.0 111889.0 112239.0 116000.4 98071.0 102382.5 103940.5 106235.9 106488.0 89604.0 90862.0 91645.5 94548.0 94573.5 13033.9 13343.2 13374.1 13469.5 13519.0 38703.4 38907.8 39181.0 39252.0 41185.8 51091.2 52298.0 52334.7 52810.1 54201.5 18870.4 18884.7 19231.6 19278.5 19285.9 21307.3 21365.1 21402.1 21426.1 21551.7 18884.4 19014.8 19077.0 19139.4 19206.4 17573.5 17782.8 17784.7 17809.2 17941.3 18781.3 19237.7 19261.6 19312.1 19485.8 14003.8 14094.2 14155.2 14347.7 14377.8 14089.6 14248.2 14322.9 14380.7 14492.3 03:54:10 INFO - PID 3531 | -------- Summary: end -------- 03:54:10 INFO - PID 3531 | 03:54:11 INFO - TEST-INFO | 3531: exit 0 03:54:11 INFO - TEST-OK | dromaeo_css | took 1114382ms 03:54:11 INFO - TEST-START | kraken 03:54:11 INFO - Initialising browser for kraken test... 03:54:11 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54396/getInfo.html -profile /tmp/tmpV1wUNR/profile 03:54:11 INFO - TEST-INFO | started process 3927 (/builds/slave/test/build/application/firefox/firefox http://localhost:54396/getInfo.html) 03:54:18 INFO - TEST-INFO | 3927: exit 0 03:54:18 INFO - Browser initialized. 03:54:18 INFO - Running cycle 1/1 for kraken test... 03:54:18 INFO - TEST-INFO | started process 4136 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV1wUNR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:54:19 INFO - PID 4136 | 03:54:19 INFO - PID 4136 | (/builds/slave/test/build/application/firefox/firefox:4184): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:54:19 INFO - PID 4136 | 03:54:29 INFO - PID 4136 | RSS: Main: 165150720 03:54:29 INFO - PID 4136 | 03:54:32 INFO - PID 4136 | Cycle 1(1): loaded http://localhost:54396/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54396/tests/kraken/driver.html?testName=audio-beat-detection) 03:54:33 INFO - PID 4136 | RSS: Main: 171540480 03:54:33 INFO - PID 4136 | 03:54:35 INFO - PID 4136 | Cycle 1(1): loaded http://localhost:54396/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54396/tests/kraken/driver.html?testName=audio-dft) 03:54:36 INFO - PID 4136 | RSS: Main: 171483136 03:54:36 INFO - PID 4136 | 03:54:39 INFO - PID 4136 | Cycle 1(1): loaded http://localhost:54396/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54396/tests/kraken/driver.html?testName=audio-fft) 03:54:39 INFO - PID 4136 | RSS: Main: 166502400 03:54:39 INFO - PID 4136 | 03:54:42 INFO - PID 4136 | Cycle 1(1): loaded http://localhost:54396/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54396/tests/kraken/driver.html?testName=audio-oscillator) 03:54:42 INFO - PID 4136 | RSS: Main: 167403520 03:54:42 INFO - PID 4136 | 03:54:44 INFO - PID 4136 | Cycle 1(1): loaded http://localhost:54396/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54396/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:54:45 INFO - PID 4136 | RSS: Main: 167632896 03:54:45 INFO - PID 4136 | 03:54:48 INFO - PID 4136 | Cycle 1(1): loaded http://localhost:54396/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54396/tests/kraken/driver.html?testName=imaging-darkroom) 03:54:49 INFO - PID 4136 | RSS: Main: 170708992 03:54:49 INFO - PID 4136 | 03:54:52 INFO - PID 4136 | Cycle 1(1): loaded http://localhost:54396/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54396/tests/kraken/driver.html?testName=imaging-desaturate) 03:54:53 INFO - PID 4136 | RSS: Main: 170680320 03:54:53 INFO - PID 4136 | 03:54:55 INFO - PID 4136 | Cycle 1(1): loaded http://localhost:54396/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54396/tests/kraken/driver.html?testName=json-parse-financial) 03:54:56 INFO - PID 4136 | RSS: Main: 170782720 03:54:56 INFO - PID 4136 | 03:54:58 INFO - PID 4136 | Cycle 1(1): loaded http://localhost:54396/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54396/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:54:58 INFO - PID 4136 | RSS: Main: 170647552 03:54:58 INFO - PID 4136 | 03:55:00 INFO - PID 4136 | Cycle 1(1): loaded http://localhost:54396/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54396/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:55:01 INFO - PID 4136 | RSS: Main: 170827776 03:55:01 INFO - PID 4136 | 03:55:03 INFO - PID 4136 | Cycle 1(1): loaded http://localhost:54396/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54396/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:55:03 INFO - PID 4136 | RSS: Main: 171020288 03:55:03 INFO - PID 4136 | 03:55:06 INFO - PID 4136 | Cycle 1(1): loaded http://localhost:54396/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54396/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:55:07 INFO - PID 4136 | RSS: Main: 171073536 03:55:07 INFO - PID 4136 | 03:55:10 INFO - PID 4136 | Cycle 1(1): loaded http://localhost:54396/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54396/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:55:10 INFO - PID 4136 | RSS: Main: 171208704 03:55:10 INFO - PID 4136 | 03:55:12 INFO - PID 4136 | Cycle 1(1): loaded http://localhost:54396/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54396/tests/kraken/driver.html?testName=ai-astar) 03:55:13 INFO - PID 4136 | RSS: Main: 172552192 03:55:13 INFO - PID 4136 | 03:55:13 INFO - PID 4136 | __start_tp_report 03:55:13 INFO - PID 4136 | _x_x_mozilla_page_load 03:55:13 INFO - PID 4136 | _x_x_mozilla_page_load_details 03:55:13 INFO - PID 4136 | |i|pagename|runs| 03:55:13 INFO - PID 4136 | |0;ai-astar;98;109;100;108;109;109;111;99;106;110 03:55:13 INFO - PID 4136 | |1;audio-beat-detection;144;119;145;174;118;149;141;119;172;127 03:55:13 INFO - PID 4136 | |2;audio-dft;164;163;161;162;163;171;162;162;164;170 03:55:13 INFO - PID 4136 | |3;audio-fft;75;76;76;135;74;74;75;89;98;74 03:55:13 INFO - PID 4136 | |4;audio-oscillator;87;79;123;79;79;78;136;79;79;123 03:55:13 INFO - PID 4136 | |5;imaging-gaussian-blur;113;114;114;113;113;113;114;114;114;114 03:55:13 INFO - PID 4136 | |6;imaging-darkroom;150;150;149;150;149;150;149;150;150;149 03:55:13 INFO - PID 4136 | |7;imaging-desaturate;93;93;93;94;94;94;93;95;94;93 03:55:13 INFO - PID 4136 | |8;json-parse-financial;55;54;55;55;53;54;58;54;53;55 03:55:13 INFO - PID 4136 | |9;json-stringify-tinderbox;54;54;54;55;54;53;54;54;54;54 03:55:13 INFO - PID 4136 | |10;stanford-crypto-aes;77;77;74;78;75;75;77;75;76;76 03:55:13 INFO - PID 4136 | |11;stanford-crypto-ccm;129;121;126;126;123;120;125;128;139;162 03:55:13 INFO - PID 4136 | |12;stanford-crypto-pbkdf2;183;181;182;180;180;177;185;174;176;187 03:55:13 INFO - PID 4136 | |13;stanford-crypto-sha256-iterative;81;68;69;79;75;74;67;69;69;75 03:55:13 INFO - PID 4136 | __end_tp_report 03:55:13 INFO - PID 4136 | __start_cc_report 03:55:13 INFO - PID 4136 | _x_x_mozilla_cycle_collect,958 03:55:13 INFO - PID 4136 | __end_cc_report 03:55:13 INFO - PID 4136 | __startTimestamp1502880913051__endTimestamp 03:55:13 INFO - PID 4136 | 03:55:13 INFO - PID 4136 | ------- Summary: start ------- 03:55:13 INFO - PID 4136 | Number of tests: 14 03:55:13 INFO - PID 4136 | 03:55:13 INFO - PID 4136 | [#0] ai-astar Cycles:10 Average:105.90 Median:109.50 stddev:4.95 (4.5%) stddev-sans-first:4.35 03:55:13 INFO - PID 4136 | Values: 98.0 109.0 100.0 108.0 109.0 109.0 111.0 99.0 106.0 110.0 03:55:13 INFO - PID 4136 | 03:55:13 INFO - PID 4136 | [#1] audio-beat-detection Cycles:10 Average:140.80 Median:144.50 stddev:20.63 (14.3%) stddev-sans-first:21.85 03:55:13 INFO - PID 4136 | Values: 144.0 119.0 145.0 174.0 118.0 149.0 141.0 119.0 172.0 127.0 03:55:13 INFO - PID 4136 | 03:55:13 INFO - PID 4136 | [#2] audio-dft Cycles:10 Average:164.20 Median:163.50 stddev:3.46 (2.1%) stddev-sans-first:3.67 03:55:13 INFO - PID 4136 | Values: 164.0 163.0 161.0 162.0 163.0 171.0 162.0 162.0 164.0 170.0 03:55:13 INFO - PID 4136 | 03:55:13 INFO - PID 4136 | [#3] audio-fft Cycles:10 Average:84.60 Median:75.50 stddev:19.46 (25.8%) stddev-sans-first:20.33 03:55:13 INFO - PID 4136 | Values: 75.0 76.0 76.0 135.0 74.0 74.0 75.0 89.0 98.0 74.0 03:55:13 INFO - PID 4136 | 03:55:13 INFO - PID 4136 | [#4] audio-oscillator Cycles:10 Average:94.20 Median:79.00 stddev:23.28 (29.5%) stddev-sans-first:24.54 03:55:13 INFO - PID 4136 | Values: 87.0 79.0 123.0 79.0 79.0 78.0 136.0 79.0 79.0 123.0 03:55:13 INFO - PID 4136 | 03:55:13 INFO - PID 4136 | [#5] imaging-gaussian-blur Cycles:10 Average:113.60 Median:114.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 03:55:13 INFO - PID 4136 | Values: 113.0 114.0 114.0 113.0 113.0 113.0 114.0 114.0 114.0 114.0 03:55:13 INFO - PID 4136 | 03:55:13 INFO - PID 4136 | [#6] imaging-darkroom Cycles:10 Average:149.60 Median:150.00 stddev:0.52 (0.3%) stddev-sans-first:0.53 03:55:13 INFO - PID 4136 | Values: 150.0 150.0 149.0 150.0 149.0 150.0 149.0 150.0 150.0 149.0 03:55:13 INFO - PID 4136 | 03:55:13 INFO - PID 4136 | [#7] imaging-desaturate Cycles:10 Average:93.60 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 03:55:13 INFO - PID 4136 | Values: 93.0 93.0 93.0 94.0 94.0 94.0 93.0 95.0 94.0 93.0 03:55:13 INFO - PID 4136 | 03:55:13 INFO - PID 4136 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:1.43 (2.6%) stddev-sans-first:1.51 03:55:13 INFO - PID 4136 | Values: 55.0 54.0 55.0 55.0 53.0 54.0 58.0 54.0 53.0 55.0 03:55:13 INFO - PID 4136 | 03:55:13 INFO - PID 4136 | [#9] json-stringify-tinderbox Cycles:10 Average:54.00 Median:54.00 stddev:0.47 (0.9%) stddev-sans-first:0.50 03:55:13 INFO - PID 4136 | Values: 54.0 54.0 54.0 55.0 54.0 53.0 54.0 54.0 54.0 54.0 03:55:13 INFO - PID 4136 | 03:55:13 INFO - PID 4136 | [#10] stanford-crypto-aes Cycles:10 Average:76.00 Median:76.50 stddev:1.25 (1.6%) stddev-sans-first:1.27 03:55:13 INFO - PID 4136 | Values: 77.0 77.0 74.0 78.0 75.0 75.0 77.0 75.0 76.0 76.0 03:55:13 INFO - PID 4136 | 03:55:13 INFO - PID 4136 | [#11] stanford-crypto-ccm Cycles:10 Average:129.90 Median:127.00 stddev:12.46 (9.8%) stddev-sans-first:13.21 03:55:13 INFO - PID 4136 | Values: 129.0 121.0 126.0 126.0 123.0 120.0 125.0 128.0 139.0 162.0 03:55:13 INFO - PID 4136 | 03:55:13 INFO - PID 4136 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.50 Median:181.50 stddev:4.03 (2.2%) stddev-sans-first:4.18 03:55:13 INFO - PID 4136 | Values: 183.0 181.0 182.0 180.0 180.0 177.0 185.0 174.0 176.0 187.0 03:55:13 INFO - PID 4136 | 03:55:13 INFO - PID 4136 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.60 Median:74.50 stddev:4.90 (6.6%) stddev-sans-first:4.15 03:55:13 INFO - PID 4136 | Values: 81.0 68.0 69.0 79.0 75.0 74.0 67.0 69.0 69.0 75.0 03:55:13 INFO - PID 4136 | -------- Summary: end -------- 03:55:13 INFO - PID 4136 | 03:55:13 INFO - PID 4136 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:55:13 INFO - TEST-INFO | 4136: exit 0 03:55:13 INFO - TEST-OK | kraken | took 62627ms 03:55:13 INFO - SUITE-END | took 1177s 03:55:14 INFO - Completed test suite (00:19:37) 03:55:14 INFO - javascript benchmark 03:55:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8537.818953232052, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3138.2154461384653, 3180.8250546219956, 3181.4591519441706, 3190.58522392089, 3208.9358212835746, 6098.939010609893, 6151.231096112986, 6271.968640156804, 6287.842803929899, 6309.305976342599, 6037.516998640109, 6140.017597184451, 6162.168107305512, 6176.876462470748, 6194.132821405007, 5639.830805075854, 5662.773489060432, 5681.11942648888, 5704.8859022819515, 5767.653940763546, 5756.913646295309, 5757.453041961007, 5768.798092066779, 5797.130430435447, 5822.272215973004, 138.28062143809927, 5588.0, 5644.548436125121, 5763.682997435139, 5789.2763404574425, 5617.21359009739, 5627.6904770237625, 5643.322801263875, 5645.23789288449, 5718.656880587178, 5596.524295434893, 5605.2713147291, 5657.688827114507, 5665.405132461074, 5704.486596206361, 5985.940140598582, 6100.176476175704, 6173.9073913891325, 6181.690915454209, 6197.442230199304, 6541.901871471884, 6551.181102362205, 6591.143151390336, 6643.103181070542, 6671.799846004627, 6538.280789113193, 6610.206775187008, 6667.499937504658, 6725.226598941086, 6743.662816859138, 6238.095476155977, 6264.467520260738, 6282.371762823726, 6293.653849038347, 6294.496440284766, 6822.3859852613505, 6924.169099708067, 6931.0643063186835, 6933.2026816915695, 6937.791866244021, 6492.318306577741, 6503.187101612299, 6580.605163690194, 6586.80239592813, 6599.835004124935, 6600.73597056122, 6616.073749675049, 6656.800295991128, 6731.427828634523, 6747.05541224229, 6577.210734711865, 6596.538242322991, 6596.670166491752, 6600.5049621278595, 6608.240052394036, 6524.478041756648, 6599.142111525567, 6629.204495460575, 6664.933610622277, 6671.099401580725, 5309.734513274321, 5386.973065134727, 5459.590530710213, 5463.125899856004, 5468.316460442445, 4571.291449825282, 4577.656675749332, 4626.606738427204, 4638.281066434708, 4640.489546149921, 5731.742071606787, 5737.426257374229, 5740.1102829061565, 5747.023006088892, 5752.194692743019, 6577.704003319862, 6633.701483433257, 6657.0, 6669.599824010478, 6674.299198584175, 5700.344460387026, 5752.309722833287, 5788.88422231553, 5808.6224395414165, 5813.12803079533, 5845.986984815635, 5856.267966504187, 5910.763569457174, 5941.316748573883, 5947.851303717442, 6304.211973503312, 6400.7119679614525, 6424.1327420798525, 6507.381798729113, 6511.609303441714, 6596.967015164798, 6609.339066093545, 6647.202335719551, 6700.497462690415, 6725.0, 6524.510661700297, 6526.282108967915, 6529.640869752209, 6543.574667646588, 6553.442957348679, 6557.836054098686, 6571.047198156336, 6650.567713098633, 6666.366695163952, 6675.933240667726, 6546.541742078009, 6630.701618427086, 6681.198256209286, 6770.2891196425635, 6771.153605799275, 6449.226092868886, 6466.676666166767, 6502.772402965874, 6513.250976137805, 6533.509986751108, 6454.096426500388, 6478.28738838718, 6502.349765023649, 6532.085508028975, 6550.050242714875, 6379.74481020754, 6384.329645387167, 6468.094466774562, 6477.190351206099, 6477.578957367756, 6479.125318081999, 6511.576747511396, 6542.313057129123, 6612.272650008399, 6627.304133065959, 6193.597416167935, 6206.72069756871, 6210.5652604317265, 6212.254529456494, 6225.221847269091], "value": 6035.086154415516, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.2780502912174, 786.2609147401446, 813.9837203255934, 832.371559472598, 842.4061036968938, 39713.0, 40803.0, 40844.59155408449, 40934.18131637365, 40958.59041409585, 37281.81359093208, 37365.813170934176, 37605.247895042085, 37625.0, 37794.62205377945, 6583.4074933256115, 6706.228783689865, 6718.563293385939, 6725.394714475696, 6732.158480189976, 6609.239937407187, 6691.698873550702, 6694.531382803206, 6714.194296684404, 6720.361565651256, 6654.567453115556, 7424.368928641045, 7588.506747061451, 7605.239476052406, 7606.505577137468, 7485.62571871407, 7528.548287102792, 7556.093268807724, 7558.546487210758, 7567.999999999973, 7428.145763237243, 7506.174320824705, 7510.061242344707, 7533.811654708621, 7537.9623101884135, 38690.0, 39008.0, 39113.21773564513, 39157.608423915684, 39274.8217553471, 3525.8589656413715, 3575.94636080459, 3578.248567800765, 3580.1765593913287, 3582.158192824684, 31182.532262016088, 31247.531287030713, 31280.53079203814, 31297.374052518822, 31303.53044704331, 5095.363079615048, 5100.285959965608, 5107.616928730323, 5111.463296353866, 5121.359830021247, 194772.0, 196192.0, 197334.0, 197346.0, 197514.0, 48097.75951120222, 48120.0, 48149.51850481451, 48165.0, 48189.75905120522, 49716.50283497119, 49818.750906245965, 50276.0, 50285.0, 50350.24474632883, 15645.217739112999, 15689.450869219523, 15692.372305107896, 15704.842951570337, 15736.527904162664, 12768.233905965672, 12837.0, 12848.100632955604, 12856.807147892789, 12864.22814631125, 14337.64155896111, 14508.274586270643, 14514.709705805824, 14614.561563153122, 14769.187694676895, 5874.9118763218585, 5911.231539899785, 5958.702064896738, 5961.135635332853, 5988.6706231157705, 972.4602845420718, 1008.4050410257983, 1009.3641006166215, 1011.3729487717662, 1018.2719355660738, 23618.763812362, 23669.881650591982, 23895.880520597286, 23915.282541523782, 23954.40113997129, 1129.4296380328046, 1130.7512347283584, 1130.9208355415205, 1136.891995260449, 1139.3050239353988, 12308.630741077603, 16244.675106497803, 16290.51128466148, 16447.0, 16607.08661023631, 250449.9910001826, 250746.0, 250966.0, 251104.0, 251159.0, 53042.46957530452, 56554.43445565574, 56580.71709641508, 56975.0, 57238.42761572414, 1461.65651071998, 1464.2898194375507, 1485.7548504496638, 1488.5013520470764, 1504.9548513544612, 27106.593401099395, 27769.722302776714, 27930.86034569814, 28026.2993425166, 28308.575371369036, 37442.25115497729, 37946.62053379431, 37949.620503794606, 37955.62044379521, 38046.42930355992, 220995.0, 221393.89303053383, 221538.0, 221820.0, 221998.0, 34530.13674658053, 34848.47727284143, 34875.825620871736, 35021.124471887386, 35143.0, 41812.3728144072, 44124.11751765011, 44176.77911610421, 44185.55814441943, 44402.333964989906, 31888.36223275568, 32408.67591324151, 32441.51337729889, 32501.512477313332, 32859.17852053718, 31542.526862096627, 31923.042308730775, 32662.83668581642, 32910.83544582358, 32977.34045319032, 34069.148271292426, 34245.315093697485, 34246.0, 34597.65402345944, 34775.47836782501, 31529.684703152674, 32082.35835283328, 33813.49279760756, 34528.48207276943, 35066.824665876506, 24925.501489970462, 25646.358841029123, 25807.096751613604, 25845.48309033771, 26360.0], "value": 17348.321183258904, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.6055200807426, 564.3143580549633, 566.2440641743273, 571.1090698510326, 573.985650358741, 21630.89184554077, 22874.428139296528, 22946.770532294715, 22964.770352296473, 22987.655185172236, 25124.74875251247, 26213.344666383302, 27339.72660273397, 27407.0, 27544.449111017766, 6603.108580341653, 6768.8307792305095, 6772.390484856362, 6787.660616969157, 6799.626020568867, 6145.8156255312415, 6405.903911441332, 6568.146141001663, 6615.569987950792, 6624.834379140536, 6421.068945002972, 6421.165248517709, 6421.711023003976, 6421.7752378666755, 6423.421892029716, 6382.553221274513, 6396.072569477446, 6400.455961243277, 6401.455876250524, 6402.807915762512, 5948.583599148062, 5969.283685957669, 5975.790847320366, 5977.46202841742, 5981.730822113015, 29679.25801854949, 29960.0, 30572.8471357644, 30876.53685194724, 31046.534301985488, 1871.6537440573538, 1912.7991560886046, 1913.7799153097294, 1915.6934890417606, 1916.252661462031, 18309.45071647853, 18936.71594926077, 18986.145623446977, 19074.23703051876, 19077.2369105237, 2342.426105604121, 2355.9293221203393, 2407.0973384980634, 2414.468816860288, 2431.8784060797066, 129217.35391322983, 133954.33022834823, 133969.0, 134102.3294883529, 134115.32942335322, 14324.283785810876, 14469.276536173149, 14603.634909127144, 15053.172075535731, 15176.165310908005, 21230.46923826886, 21251.46871328198, 23698.526029479657, 24407.0, 24626.27616066894, 10210.285280030326, 10340.172786177087, 10557.366558006544, 10633.149348052137, 10675.626353077605, 9691.176250018687, 9710.271729620306, 9724.465154416575, 9784.217262618973, 9834.409935403755, 9575.377600455948, 9580.742547533871, 9661.9033809661, 9882.308238423242, 9965.900340996643, 2819.8991751145527, 2822.3508593023307, 2849.5725641153576, 2850.372917958046, 2928.048384275119, 957.4207604399378, 974.551706215149, 974.6734843827255, 987.6098940918441, 997.4514017290461, 20168.89915550413, 20519.281825136346, 20636.587268254552, 20805.479863003242, 21073.89463052675, 1038.5793753529833, 1048.9213309001855, 1049.2497864027257, 1062.4793851012948, 1065.7229120428744, 11471.942640286745, 11514.0, 11625.360605166628, 11680.240784348991, 11681.0, 471034.0, 484117.0, 487856.0, 491127.0, 491171.0, 118842.0, 118883.0, 120641.39679301548, 120948.0, 121586.0, 1205.3185805800858, 1226.5400474821943, 1250.349818094604, 1250.531050855929, 1252.9185602935597, 18494.167762450452, 18576.349827755963, 18878.433646990612, 22054.0, 23352.18267360634, 48401.0, 57027.0, 59152.40847591641, 62114.378856212665, 62441.375586245376, 71788.28211717815, 71890.0, 72147.27852721406, 72246.0, 74043.62978185074, 14219.146851188962, 14529.128252304896, 14572.854271457574, 14666.63333416673, 14793.186374749492, 23886.0, 23900.402489937194, 24367.14714984967, 24502.754972450046, 24507.754922451262, 18248.270069197082, 18627.90686046615, 18980.810191897905, 18988.335408260646, 19124.521886952938, 15993.760093598838, 17030.829691702926, 17147.31410743606, 17397.130143492523, 17704.380346687805, 18975.0, 19084.522886927938, 19167.616647667248, 19351.12919918635, 19369.225230990603, 14318.642033948818, 14388.568342949728, 14390.848732101616, 14396.856031439971, 14410.063345882483, 18636.254549817855, 18943.0, 18965.71551426702, 19032.0, 19117.426477205707], "value": 12148.981807239617, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1338.317458096371, 1379.8827099696525, 1387.4311532271768, 1406.725688490744, 1410.1750475971555, 14184.57446276612, 14592.781108283385, 14834.10995340279, 14882.330295136719, 14910.62723431912, 15170.772438413405, 15352.309146088424, 15364.846351536482, 15466.381344746196, 15488.225588720576, 2626.6322714819894, 2679.1560658392546, 2680.6864636328255, 2694.609281654159, 2696.6763988321427, 2573.3437973316777, 2673.679158500973, 2715.4433341165113, 2715.891364345424, 2716.8234064785725, 2584.198898341511, 2601.6617839680816, 2605.114261151208, 2643.6298918151474, 2646.3251870772924, 2515.710693270279, 2525.848449093051, 2579.896804127842, 2629.8027647926483, 2630.1714959787796, 2564.7435256474205, 2586.314626623946, 2594.6237795519733, 2612.111881960124, 2619.724928882459, 14051.929740351336, 14282.714345713028, 14284.785728214085, 14332.211728354936, 14361.138331700131, 7461.253874612549, 7731.381489480828, 7763.301302882711, 7775.300222979902, 7783.299503044753, 4763.14263432582, 4792.257200133985, 4849.0, 4955.702657840541, 4956.826511072131, 1873.7095750158746, 1890.962180756391, 1908.5896532245495, 1945.5000324582843, 1946.1923301829725, 23861.76138238613, 24026.038958441815, 24110.035598576036, 24110.27669169945, 24122.758772412228, 6124.295705993779, 6323.652199129092, 6430.64631445275, 6462.353764623594, 6490.123833282447, 15893.0, 16459.58851028734, 16463.506094817174, 16467.423640172536, 16488.67022659564, 7230.855382892418, 7389.408847292311, 7397.852042959111, 7403.51877127985, 7416.147143078615, 5605.411431799684, 5795.826125216166, 6243.282022567372, 6829.590224586541, 6858.931410685929, 5014.373203349582, 5111.0, 5190.792368305226, 5197.29836472079, 5211.661242019257, 1457.883369330451, 1472.9410823566936, 1477.409036385433, 1486.1900264355952, 1488.1740633948264, 856.39624065034, 867.4491697771961, 873.2097451806126, 882.5498995512206, 883.9911600884037, 7319.231480694557, 7329.780106596811, 8552.657893684307, 9044.683436079837, 9471.242300615933, 972.8394814855469, 980.6616717232544, 981.5533932061038, 991.6281394477071, 997.3567049253191, 4791.47293797682, 5651.57613179005, 5823.679697616587, 5871.236739223874, 6039.577229593886, 18422.447326580223, 19001.524961875788, 19039.619207616048, 19058.2376704933, 19067.332643357415, 18759.812401875806, 18833.529161771065, 18854.15156317969, 19006.524836879187, 19114.904425477784, 11125.27685700443, 11380.317180969168, 11423.485943132579, 11441.713957151138, 11452.484638191301, 8346.0, 8355.080941096596, 8375.45559538628, 8393.454425462325, 8862.734117976472, 17585.120743962496, 17661.558461038578, 17746.822531774516, 17870.285188592312, 17896.91051544786, 17914.372996945043, 17955.730664039787, 17963.0, 18027.639447211244, 18159.636807264043, 11905.345206013752, 12159.148859579744, 12166.270023798601, 12169.148159629096, 12173.573924912585, 13506.59480215595, 13525.458981640624, 13568.38942247621, 13572.79640805369, 13572.86427135716, 11069.667909962714, 11155.163362747664, 11234.775304494027, 11375.26060806045, 11422.714432139262, 10345.431001295, 10504.894951050697, 10555.366677999345, 10725.195610329101, 11203.159763017644, 9287.907120928974, 9299.76750581241, 9308.906910930804, 9350.3454758169, 9373.812523749622, 7113.964430178023, 7129.0, 7199.3520583147765, 7201.13586369639, 7245.166805817398, 6899.689513971984, 7067.116610423697, 7111.395531379891, 7159.319864612853, 7271.2365201653065], "value": 6459.370538718782, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.9920360871043, 500.3095727895505, 512.8589637849593, 524.9790008399664, 530.5940955169297, 6060.606060606057, 6127.35662755411, 6201.131841542182, 6259.155014073099, 6284.434400903918, 6065.423784740443, 6073.997790364585, 6126.938730612704, 6153.9384606154035, 6184.134221209023, 4911.214205727084, 4918.868660208136, 4958.355413796201, 4971.950280497185, 4996.375453068366, 4721.787519561611, 4907.705537667734, 4921.335619691332, 4922.286268491067, 4942.728149951751, 4813.37426134602, 4835.879103022435, 4853.733044682541, 4875.390576177978, 4895.0, 4813.687110337817, 4832.710037397732, 4888.535589119028, 4891.33694543855, 4895.192293271606, 4378.8905277368, 4459.085887393109, 4459.487158976727, 4459.799309031101, 4477.194105061088, 5974.266083697689, 6053.303870054956, 6059.484943779784, 6097.878042439126, 6123.908141377883, 5589.329280486327, 5616.663000219959, 5636.379998200195, 5652.576056795716, 5697.829065128053, 6317.463015643676, 6437.967810160966, 6561.114249576294, 6563.1796025496815, 6593.274739778621, 4885.755712214411, 4898.485659005824, 4898.848770538956, 4909.361782968192, 4921.901561968776, 6647.734090636369, 6758.324167583251, 6763.7632682855865, 6784.932150678479, 6817.0, 4924.4090709115135, 4928.605711543068, 4928.79244585079, 4930.6795058321095, 4951.628627852925, 4941.65408421407, 4988.176950803149, 4998.0, 5018.594793457838, 5022.146235140035, 5498.202760599771, 5518.089515229942, 5551.3893471718075, 5574.749136288877, 5583.692896890629, 6381.351739028344, 6407.7436902524305, 6494.480441564663, 6501.349865013461, 6545.952647576365, 1672.222416576298, 1715.5282297368074, 1724.2327164411715, 1752.824717528237, 1764.1443899708631, 1199.7822210456393, 1212.7210741529498, 1215.9452824622913, 1217.829503869459, 1238.207547169812, 5611.831645050655, 5653.971730141323, 5655.999999999918, 5685.374608793029, 5710.2576665033275, 6633.18089811828, 6696.196456425163, 6711.966440167768, 6720.8319792005595, 6753.257141714407, 5728.713564321767, 5746.827595172152, 5789.1605717171615, 5820.970895145582, 5862.355140934494, 3538.752287339913, 3608.657177568127, 3608.8376023079027, 3614.8915532534065, 3618.0, 6636.469082473391, 6659.633720145342, 6664.0, 6680.365365290289, 6680.732770689118, 6839.384455398938, 6918.861622767517, 6935.757248496278, 7000.354916594596, 7022.754203602849, 6474.870502589922, 6546.639934803441, 6593.208814942238, 6608.67826434715, 6654.70053847578, 6628.7017084230365, 6672.8999065013095, 6674.866502669823, 6688.464922806261, 6701.497387695845, 6544.4764418845425, 6553.311902250195, 6617.716887673745, 6690.966545167243, 6757.222919364336, 6478.870422591616, 6512.251091124392, 6523.217213934168, 6537.182852143482, 6661.933380666325, 4893.975530122327, 4917.729524876023, 4939.53074457926, 4972.8756781080765, 4973.452920178705, 6416.358364163733, 6644.60132392058, 6731.831704207434, 6775.186977562526, 6859.794206173823, 6303.684815759286, 6418.422341989243, 6436.195475565554, 6450.741970321134, 6517.185351831021, 6401.743930242925, 6410.0705397718, 6423.871522569429, 6443.613383197023, 6556.2788093310655, 6210.937890621036, 6285.622862628257, 6312.210973628296, 6345.762576297578, 6430.967845160744, 6557.409833115043, 6607.405333520006, 6609.966950165411, 6616.900746488903, 6618.503612229199, 6264.718087686157, 6278.932581461071, 6336.683165841781, 6402.359764023747, 6426.4216220540375], "value": 5021.69494368092, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [897.6858099665118, 904.8687940248664, 911.3939230411777, 919.8114386550758, 924.9722508324749, 18853.62292754144, 19896.10467528963, 19932.0, 19954.501137471554, 22555.774442255573, 22127.44681382966, 22810.885945570248, 22880.885595572, 22912.198073067448, 22992.8850355748, 972.9416235025885, 988.7626969527298, 990.2721499697716, 990.5195979949755, 994.3884511025703, 1027.286036204838, 1040.3757745352802, 1060.777236780277, 1073.7315671082229, 1076.8061748885198, 1122.1247427006906, 1129.6441620889393, 1129.8474705914678, 1130.2145009218596, 1130.3839407522878, 1122.0350498571222, 1123.0117496603018, 1125.0, 1125.2235957189514, 1125.94370281486, 819.8360327934378, 820.741466438076, 821.6918655504186, 822.8724547695117, 823.4194892600702, 19740.40778776639, 19942.401727948185, 19959.700604490943, 20359.065888740544, 21391.358259252247, 21495.24766633176, 21570.0, 21842.672359914613, 21878.89060554703, 22222.66666000011, 15684.451044213512, 15795.368185272575, 15866.682666346724, 17027.744583831252, 17865.196066177054, 3690.944635830465, 3700.833462494194, 3710.9628903710886, 3711.350513660099, 3712.610175931515, 126147.0, 126929.0, 130478.0, 130604.99999999905, 130679.0, 16558.089305088335, 16935.830641693672, 17296.0, 17397.565060873578, 17649.647007059786, 31463.685363146076, 31837.363252734816, 31934.84032579822, 32412.513812292833, 32573.18567035843, 7993.160718124514, 8280.461769985051, 8646.308295336357, 8874.378793484395, 8959.372843900996, 4093.8362465501045, 4167.562405947392, 4171.0823618803815, 4174.979125104416, 4178.310578754532, 5316.468353164652, 5374.704391258519, 5377.623566350318, 5417.106177480671, 5420.566354691615, 1034.110664828262, 1057.0116940660507, 1057.846612241236, 1066.157735389034, 1068.3483075324045, 669.9832504187434, 672.37133280383, 683.3303362704578, 688.6797639097744, 698.5703792167781, 11834.0, 12112.878871211351, 12127.818082728767, 12278.579711303924, 13224.632261281477, 665.3811954882007, 668.7960172147542, 669.3774789445862, 685.6023506366296, 685.7839780469136, 6052.364501727343, 6374.617522948545, 6433.646149461824, 6471.741130354827, 6593.307702691244, 108250.45874770575, 109550.0, 111889.0, 112239.0, 116000.41999789947, 98071.0, 102382.48808755909, 103940.48029759954, 106235.93764062415, 106488.0, 89604.0, 90862.0, 91645.54177229072, 94548.0, 94573.5271323639, 13033.934830325788, 13343.199408035549, 13374.13068150606, 13469.46122155142, 13519.0, 38703.419448707726, 38907.805460972515, 39181.0, 39252.0, 41185.79407103065, 51091.23363149481, 52298.0, 52334.73832630965, 52810.107426250055, 54201.45798541964, 18870.433886983414, 18884.716729248797, 19231.615367692848, 19278.518037049187, 19285.903570482056, 21307.25424610131, 21365.14539418406, 21402.143914243257, 21426.142954281655, 21551.676724848825, 18884.433466995462, 19014.809851901304, 19077.046147692836, 19139.425817225507, 19206.423807285802, 17573.472795816146, 17782.822171778633, 17784.73322900132, 17809.198586063398, 17941.282348705907, 18781.34265300708, 19237.711434328776, 19261.614767704847, 19312.13095410682, 19485.805141948968, 14003.789943150658, 14094.154350738989, 14155.221462819232, 14347.678685386438, 14377.784333235219, 14089.647758806112, 14248.216348100954, 14322.928385358007, 14380.712385752435, 14492.347844347241], "value": 9387.129204734329, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1514.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 109.0, 100.0, 108.0, 109.0, 109.0, 111.0, 99.0, 106.0, 110.0], "value": 105.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 119.0, 145.0, 174.0, 118.0, 149.0, 141.0, 119.0, 172.0, 127.0], "value": 140.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 161.0, 162.0, 163.0, 171.0, 162.0, 162.0, 164.0, 170.0], "value": 164.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 135.0, 74.0, 74.0, 75.0, 89.0, 98.0, 74.0], "value": 84.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 79.0, 123.0, 79.0, 79.0, 78.0, 136.0, 79.0, 79.0, 123.0], "value": 94.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 113.0, 113.0, 113.0, 114.0, 114.0, 114.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 149.0, 150.0, 149.0, 150.0, 149.0, 150.0, 150.0, 149.0], "value": 149.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 94.0, 94.0, 93.0, 95.0, 94.0, 93.0], "value": 93.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 55.0, 53.0, 54.0, 58.0, 54.0, 53.0, 55.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 55.0, 54.0, 53.0, 54.0, 54.0, 54.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 74.0, 78.0, 75.0, 75.0, 77.0, 75.0, 76.0, 76.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 121.0, 126.0, 126.0, 123.0, 120.0, 125.0, 128.0, 139.0, 162.0], "value": 129.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 181.0, 182.0, 180.0, 180.0, 177.0, 185.0, 174.0, 176.0, 187.0], "value": 180.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 68.0, 69.0, 79.0, 75.0, 74.0, 67.0, 69.0, 69.0, 75.0], "value": 72.6, "unit": "score"}]}]} 03:55:14 INFO - Return code: 0 03:55:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:55:14 INFO - # TBPL SUCCESS # 03:55:14 INFO - Running post-action listener: _package_coverage_data 03:55:14 INFO - Running post-action listener: _resource_record_post_action 03:55:14 INFO - [mozharness: 2017-08-16 10:55:14.326842Z] Finished run-tests step (success) 03:55:14 INFO - Running post-run listener: _resource_record_post_run 03:55:14 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 7802880; Write bytes: 438849536; Read time: 5268; Write time: 715264 03:55:14 INFO - TinderboxPrint: CPU usage
13.1% 03:55:14 INFO - TinderboxPrint: I/O read bytes / time
7,802,880 / 5,268 03:55:14 INFO - TinderboxPrint: I/O write bytes / time
438,849,536 / 715,264 03:55:14 INFO - TinderboxPrint: CPU idle
8,287.5 (86.8%) 03:55:14 INFO - TinderboxPrint: CPU user
1,196.7 (12.5%) 03:55:14 INFO - TinderboxPrint: Swap in / out
0 / 0 03:55:14 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 4096; Write bytes: 164839424; Read time: 20; Write time: 284064 03:55:14 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:55:14 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 6045696; Write bytes: 229015552; Read time: 4684; Write time: 326572 03:55:14 INFO - Running post-run listener: _upload_blobber_files 03:55:14 INFO - Blob upload gear active. 03:55:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:55:14 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:55:14 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:55:14 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:55:15 INFO - (blobuploader) - INFO - Open directory for files ... 03:55:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:55:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:55:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:55:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:55:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:55:16 INFO - (blobuploader) - INFO - Done attempting. 03:55:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:55:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:55:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:55:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:55:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:55:22 INFO - (blobuploader) - INFO - Done attempting. 03:55:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:55:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:55:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:55:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:55:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:55:23 INFO - (blobuploader) - INFO - Done attempting. 03:55:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:55:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:55:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:55:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:55:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:55:24 INFO - (blobuploader) - INFO - Done attempting. 03:55:24 INFO - (blobuploader) - INFO - Iteration through files over. 03:55:24 INFO - Return code: 0 03:55:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:55:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:55:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/98c53f3b637859463544d06c378681b5702c4f6fd2223df968fa704ef6a032c9c87ac1d19057a1f2535026826314782b8c2d4897dd96f4a397309e5df5e72eb3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/14435db9dca5942e5bb0d653658ae04b5f00bcbb4b370c07934daf4bf8d532ce638522c10b28164e52107de23e5ff92b364dc9bb7c15de06ad152dba79e0b904", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/daa19a9620cefe438e10a3725e2951dbd631aa7de8996559221e075fb226fb1f086ee86eb62efee96623328805ce3d1d19d83bb9472cf0d1deef31183a990f5c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4413cc8fd5d9555cb6e8c23faa34b1493beeb3a0836c9c7466cef0af33ef7470e55028ae8ce0b8c0b975058cdea9cb39078fb0603470d851c8f95d0f5fde622b"} 03:55:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:55:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:55:24 INFO - Contents: 03:55:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/98c53f3b637859463544d06c378681b5702c4f6fd2223df968fa704ef6a032c9c87ac1d19057a1f2535026826314782b8c2d4897dd96f4a397309e5df5e72eb3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/14435db9dca5942e5bb0d653658ae04b5f00bcbb4b370c07934daf4bf8d532ce638522c10b28164e52107de23e5ff92b364dc9bb7c15de06ad152dba79e0b904", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/daa19a9620cefe438e10a3725e2951dbd631aa7de8996559221e075fb226fb1f086ee86eb62efee96623328805ce3d1d19d83bb9472cf0d1deef31183a990f5c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4413cc8fd5d9555cb6e8c23faa34b1493beeb3a0836c9c7466cef0af33ef7470e55028ae8ce0b8c0b975058cdea9cb39078fb0603470d851c8f95d0f5fde622b"} 03:55:24 INFO - Running post-run listener: copy_logs_to_upload_dir 03:55:24 INFO - Copying logs to upload dir... 03:55:24 INFO - mkdir: /builds/slave/test/build/upload/logs 03:55:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.752833 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-08-16 03:55:24.503515) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-16 03:55:24.507960) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258 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/98c53f3b637859463544d06c378681b5702c4f6fd2223df968fa704ef6a032c9c87ac1d19057a1f2535026826314782b8c2d4897dd96f4a397309e5df5e72eb3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/14435db9dca5942e5bb0d653658ae04b5f00bcbb4b370c07934daf4bf8d532ce638522c10b28164e52107de23e5ff92b364dc9bb7c15de06ad152dba79e0b904", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/daa19a9620cefe438e10a3725e2951dbd631aa7de8996559221e075fb226fb1f086ee86eb62efee96623328805ce3d1d19d83bb9472cf0d1deef31183a990f5c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4413cc8fd5d9555cb6e8c23faa34b1493beeb3a0836c9c7466cef0af33ef7470e55028ae8ce0b8c0b975058cdea9cb39078fb0603470d851c8f95d0f5fde622b"} build_url:https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011138 build_url: 'https://queue.taskcluster.net/v1/task/Ik1VZCnkRAKLMf_kMSI2Ig/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/98c53f3b637859463544d06c378681b5702c4f6fd2223df968fa704ef6a032c9c87ac1d19057a1f2535026826314782b8c2d4897dd96f4a397309e5df5e72eb3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/14435db9dca5942e5bb0d653658ae04b5f00bcbb4b370c07934daf4bf8d532ce638522c10b28164e52107de23e5ff92b364dc9bb7c15de06ad152dba79e0b904", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/daa19a9620cefe438e10a3725e2951dbd631aa7de8996559221e075fb226fb1f086ee86eb62efee96623328805ce3d1d19d83bb9472cf0d1deef31183a990f5c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4413cc8fd5d9555cb6e8c23faa34b1493beeb3a0836c9c7466cef0af33ef7470e55028ae8ce0b8c0b975058cdea9cb39078fb0603470d851c8f95d0f5fde622b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-16 03:55:24.534117) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 03:55:24.534424) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1502879651.979309-968417258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004421 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 03:55:24.616426) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-16 03:55:24.616752) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-16 03:55:24.617049) ========= ========= Total master_lag: 0.20 =========