builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-048 starttime: 1500396807.97 results: success (0) revision: 56c6c81993e6b0f0e094e0c88b42ce3ec566ead9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-18 09:53:27.971587) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-18 09:53:27.972006) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-18 09:53:27.972285) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-18 09:53:28.066098) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 09:53:28.066416) ========= 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=fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-18 09:53:28-- 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% 68.6M=0s 2017-07-18 09:53:28 (68.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.161953 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 09:53:28.261392) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 09:53:28.261685) ========= 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=fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016006 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 09:53:28.338034) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-18 09:53:28.338419) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 56c6c81993e6b0f0e094e0c88b42ce3ec566ead9 --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 56c6c81993e6b0f0e094e0c88b42ce3ec566ead9 --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=fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-18 09:53:29,097 truncating revision to first 12 chars 2017-07-18 09:53:29,098 Setting DEBUG logging. 2017-07-18 09:53:29,098 attempt 1/10 2017-07-18 09:53:29,098 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/56c6c81993e6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-18 09:53:29,951 unpacking tar archive at: mozilla-central-56c6c81993e6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.226086 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-18 09:53:29.583770) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-18 09:53:29.588200) ========= script_repo_revision: 56c6c81993e6b0f0e094e0c88b42ce3ec566ead9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-18 09:53:29.588621) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-18 09:53:29.588910) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-18 09:53:29.616766) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-07-18 09:53:29.617098) ========= /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=fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:53:30 INFO - MultiFileLogger online at 20170718 09:53:30 in /builds/slave/test 09:53:30 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 09:53:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:53:30 INFO - {'append_to_log': False, 09:53:30 INFO - 'base_work_dir': '/builds/slave/test', 09:53:30 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 09:53:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:53:30 INFO - 'branch': 'Firefox-Non-PGO', 09:53:30 INFO - 'buildbot_json_path': 'buildprops.json', 09:53:30 INFO - 'code_coverage': False, 09:53:30 INFO - 'config_files': ('talos/linux_config.py',), 09:53:30 INFO - 'default_actions': ('clobber', 09:53:30 INFO - 'read-buildbot-config', 09:53:30 INFO - 'download-and-extract', 09:53:30 INFO - 'populate-webroot', 09:53:30 INFO - 'create-virtualenv', 09:53:30 INFO - 'install', 09:53:30 INFO - 'setup-mitmproxy', 09:53:30 INFO - 'run-tests'), 09:53:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:53:30 INFO - 'disable_ccov_upload': False, 09:53:30 INFO - 'download_minidump_stackwalk': True, 09:53:30 INFO - 'download_symbols': 'ondemand', 09:53:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:53:30 INFO - 'tooltool.py': '/tools/tooltool.py', 09:53:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:53:30 INFO - '/tools/misc-python/virtualenv.py')}, 09:53:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:53:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:53:30 INFO - 'gecko_profile': False, 09:53:30 INFO - 'gecko_profile_interval': 0, 09:53:30 INFO - 'installer_path': 'installer.exe', 09:53:30 INFO - 'log_level': 'info', 09:53:30 INFO - 'log_name': 'talos', 09:53:30 INFO - 'log_to_console': True, 09:53:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:53:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:53:30 INFO - 'opt_config_files': (), 09:53:30 INFO - 'pip_index': False, 09:53:30 INFO - 'suite': 'dromaeojs-e10s', 09:53:30 INFO - 'system_bits': '32', 09:53:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:53:30 INFO - 'title': 'talos-linux64-ix-048', 09:53:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:53:30 INFO - 'use_talos_json': True, 09:53:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:53:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:53:30 INFO - 'work_dir': 'build'} 09:53:30 INFO - [mozharness: 2017-07-18 16:53:30.475351Z] Running clobber step. 09:53:30 INFO - Running pre-action listener: _resource_record_pre_action 09:53:30 INFO - Running main action method: clobber 09:53:30 INFO - rmtree: /builds/slave/test/build 09:53:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:53:31 INFO - Running post-action listener: _resource_record_post_action 09:53:31 INFO - [mozharness: 2017-07-18 16:53:31.501059Z] Finished clobber step (success) 09:53:31 INFO - [mozharness: 2017-07-18 16:53:31.501162Z] Running read-buildbot-config step. 09:53:31 INFO - Running pre-action listener: _resource_record_pre_action 09:53:31 INFO - Running main action method: read_buildbot_config 09:53:31 INFO - Using buildbot properties: 09:53:31 INFO - { 09:53:31 INFO - "project": "", 09:53:31 INFO - "product": "firefox", 09:53:31 INFO - "who": "ryanvm@gmail.com", 09:53:31 INFO - "installer_path": "public/build/target.tar.bz2", 09:53:31 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 09:53:31 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:53:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 09:53:31 INFO - "stage_platform": "linux64", 09:53:31 INFO - "basedir": "/builds/slave/test", 09:53:31 INFO - "buildnumber": 727, 09:53:31 INFO - "platform": "ubuntu64_hw", 09:53:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:53:31 INFO - "slavebuilddir": "test", 09:53:31 INFO - "taskId": "aNhklFTLSEGBKfRntt7LpQ", 09:53:31 INFO - "branch": "mozilla-central", 09:53:31 INFO - "script_repo_revision": "production", 09:53:31 INFO - "revision": "56c6c81993e6b0f0e094e0c88b42ce3ec566ead9", 09:53:31 INFO - "slavename": "talos-linux64-ix-048", 09:53:31 INFO - "repo_path": "mozilla-central" 09:53:31 INFO - } 09:53:31 INFO - Finding installer, test and symbols from parent task. 09:53:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aNhklFTLSEGBKfRntt7LpQ'}, attempt #1 09:53:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aNhklFTLSEGBKfRntt7LpQ'}, attempt #1 09:53:32 INFO - Task dependencies: IUEOraFoSpqpTYcx7zr34w 09:53:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w'}, attempt #1 09:53:32 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.tar.bz2 09:53:32 INFO - Set test_url: https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/test_packages.json 09:53:32 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.crashreporter-symbols.zip 09:53:32 INFO - Running post-action listener: _resource_record_post_action 09:53:32 INFO - [mozharness: 2017-07-18 16:53:32.847371Z] Finished read-buildbot-config step (success) 09:53:32 INFO - [mozharness: 2017-07-18 16:53:32.847594Z] Running download-and-extract step. 09:53:32 INFO - Running pre-action listener: _resource_record_pre_action 09:53:32 INFO - Running main action method: download_and_extract 09:53:32 INFO - mkdir: /builds/slave/test/build/tests 09:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:32 INFO - trying https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.test_packages.json 09:53:32 INFO - Downloading https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:53:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:53:33 INFO - Downloaded 989 bytes. 09:53:33 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:53:33 INFO - Using the following test package requirements: 09:53:33 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:53:33 INFO - u'common': [u'target.common.tests.zip'], 09:53:33 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:53:33 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:53:33 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:53:33 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:53:33 INFO - u'mozbase': [u'target.common.tests.zip'], 09:53:33 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:53:33 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:53:33 INFO - u'web-platform': [u'target.common.tests.zip', 09:53:33 INFO - u'target.web-platform.tests.tar.gz'], 09:53:33 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:53:33 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:53:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.common.tests.zip 09:53:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:53:33 INFO - Fetch https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.common.tests.zip into memory 09:53:35 INFO - Content-Length response header: 72762069 09:53:35 INFO - Bytes received: 72762069 09:53:46 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:53:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.common.tests.zip 09:53:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:53:46 INFO - Fetch https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.common.tests.zip into memory 09:53:48 INFO - Content-Length response header: 72762069 09:53:48 INFO - Bytes received: 72762069 09:54:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.talos.tests.zip 09:54:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:54:00 INFO - Fetch https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.talos.tests.zip into memory 09:54:01 INFO - Content-Length response header: 14495033 09:54:01 INFO - Bytes received: 14495033 09:54: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')]} 09:54:01 INFO - trying https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.tar.bz2 09:54:01 INFO - Downloading https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:54:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:54:04 INFO - Downloaded 61871104 bytes. 09:54:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.tar.bz2 09:54:04 INFO - mkdir: /builds/slave/test/properties 09:54:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:54:04 INFO - Writing to file /builds/slave/test/properties/build_url 09:54:04 INFO - Contents: 09:54:04 INFO - build_url:https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.tar.bz2 09:54:04 INFO - Running post-action listener: _resource_record_post_action 09:54:04 INFO - Running post-action listener: set_extra_try_arguments 09:54:04 INFO - [mozharness: 2017-07-18 16:54:04.485207Z] Finished download-and-extract step (success) 09:54:04 INFO - [mozharness: 2017-07-18 16:54:04.485437Z] Running populate-webroot step. 09:54:04 INFO - Running pre-action listener: _resource_record_pre_action 09:54:04 INFO - Running main action method: populate_webroot 09:54:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:54:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:54:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:54:04 INFO - '--tppagecycles', 09:54:04 INFO - '7'], 09:54:04 INFO - 'tests': ['tsvgm']}}, 09:54:04 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:54:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:54:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:54:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:54:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:54:04 INFO - 'talos_options': ['--disable-e10s'], 09:54:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:54:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:54:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:54:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:54:04 INFO - 'talos_options': ['--disable-e10s'], 09:54:04 INFO - 'tests': ['damp', 'tps']}, 09:54:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:54:04 INFO - 'tests': ['damp', 'tps']}, 09:54:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:54:04 INFO - 'tests': ['dromaeo_dom']}, 09:54:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:54:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:54:04 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:54:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:54:04 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:54:04 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:54:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:54:04 INFO - 'tests': ['a11yr', 09:54:04 INFO - 'ts_paint', 09:54:04 INFO - 'tpaint', 09:54:04 INFO - 'sessionrestore', 09:54:04 INFO - 'sessionrestore_no_auto_restore', 09:54:04 INFO - 'tabpaint']}, 09:54:04 INFO - 'other-e10s': {'tests': ['a11yr', 09:54:04 INFO - 'ts_paint', 09:54:04 INFO - 'tpaint', 09:54:04 INFO - 'sessionrestore', 09:54:04 INFO - 'sessionrestore_many_windows', 09:54:04 INFO - 'sessionrestore_no_auto_restore', 09:54:04 INFO - 'tabpaint']}, 09:54:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:54:04 INFO - 'ts_paint', 09:54:04 INFO - 'tpaint', 09:54:04 INFO - 'sessionrestore', 09:54:04 INFO - 'sessionrestore_many_windows', 09:54:04 INFO - 'sessionrestore_no_auto_restore', 09:54:04 INFO - 'tabpaint']}, 09:54:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:54:04 INFO - 'ts_paint', 09:54:04 INFO - 'tpaint', 09:54:04 INFO - 'sessionrestore', 09:54:04 INFO - 'sessionrestore_many_windows', 09:54:04 INFO - 'sessionrestore_no_auto_restore', 09:54:04 INFO - 'tabpaint']}, 09:54:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:54:04 INFO - 'tests': ['a11yr', 09:54:04 INFO - 'ts_paint', 09:54:04 INFO - 'tpaint', 09:54:04 INFO - 'sessionrestore', 09:54:04 INFO - 'sessionrestore_no_auto_restore', 09:54:04 INFO - 'tabpaint']}, 09:54:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:54:04 INFO - 'tests': ['a11yr', 09:54:04 INFO - 'ts_paint', 09:54:04 INFO - 'tpaint', 09:54:04 INFO - 'sessionrestore', 09:54:04 INFO - 'sessionrestore_no_auto_restore', 09:54:04 INFO - 'tabpaint']}, 09:54:04 INFO - 'perf-reftest': {'tests': ['bloom_basic']}, 09:54:04 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 09:54:04 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 09:54:04 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:54:04 INFO - 'talos_options': ['--mitmproxy', 09:54:04 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:54:04 INFO - '--firstNonBlankPaint'], 09:54:04 INFO - 'tests': ['quantum_pageload_google', 09:54:04 INFO - 'quantum_pageload_youtube', 09:54:04 INFO - 'quantum_pageload_amazon', 09:54:04 INFO - 'quantum_pageload_facebook']}, 09:54:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:54:04 INFO - 'tests': ['tsvgx', 09:54:04 INFO - 'tsvgr_opacity', 09:54:04 INFO - 'tart', 09:54:04 INFO - 'tscrollx', 09:54:04 INFO - 'cart', 09:54:04 INFO - 'tsvg_static']}, 09:54:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:54:04 INFO - 'tsvgr_opacity', 09:54:04 INFO - 'tart', 09:54:04 INFO - 'tscrollx', 09:54:04 INFO - 'cart', 09:54:04 INFO - 'tsvg_static']}, 09:54:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:54:04 INFO - 'talos_options': ['--disable-e10s'], 09:54:04 INFO - 'tests': ['tp5o']}, 09:54:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:54:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:54:04 INFO - 'talos_options': ['--disable-e10s', 09:54:04 INFO - '--xperf_path', 09:54:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:54:04 INFO - 'tests': ['tp5n']}, 09:54:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:54:04 INFO - 'talos_options': ['--xperf_path', 09:54:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:54:04 INFO - 'tests': ['tp5n']}}, 09:54:04 INFO - 'talos.zip': {'path': '', 09:54:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:54:04 INFO - Running post-action listener: _resource_record_post_action 09:54:04 INFO - [mozharness: 2017-07-18 16:54:04.506584Z] Finished populate-webroot step (success) 09:54:04 INFO - [mozharness: 2017-07-18 16:54:04.506674Z] Running create-virtualenv step. 09:54:04 INFO - Running pre-action listener: _resource_record_pre_action 09:54:04 INFO - Running main action method: create_virtualenv 09:54:04 INFO - Creating virtualenv /builds/slave/test/build/venv 09:54:04 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 09:54:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:54:04 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:54:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:54:04 INFO - Using real prefix '/usr' 09:54:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:54:05 INFO - Installing distribute.............................................................................................................................................................................................done. 09:54:07 INFO - Installing pip.................done. 09:54:07 INFO - Return code: 0 09:54:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:54:07 INFO - Reading from file tmpfile_stdout 09:54:07 INFO - Output received: 09:54:07 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) 09:54:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:54: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')]} 09:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54: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')]} 09:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:07 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 0x248b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2659a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fcb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f9d10>, '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 0x27fac10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb0c0>, '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': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', '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 09:54:07 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 09:54:07 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 09:54:07 INFO - Using env: {'DISPLAY': ':0', 09:54:07 INFO - 'HOME': '/home/cltbld', 09:54:07 INFO - 'LANG': 'en_US.UTF-8', 09:54:07 INFO - 'LANGUAGE': 'en_US:en', 09:54:07 INFO - 'LOGNAME': 'cltbld', 09:54:07 INFO - 'MAIL': '/var/mail/cltbld', 09:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:07 INFO - 'MOZ_NO_REMOTE': '1', 09:54:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:54:07 INFO - 'NO_EM_RESTART': '1', 09:54:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:07 INFO - 'PWD': '/builds/slave/test', 09:54:07 INFO - 'SHELL': '/bin/bash', 09:54:07 INFO - 'SHLVL': '1', 09:54:07 INFO - 'TERM': 'linux', 09:54:07 INFO - 'TMOUT': '86400', 09:54:07 INFO - 'USER': 'cltbld', 09:54:07 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', 09:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:07 INFO - '_': '/tools/buildbot/bin/python'} 09:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:07 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 09:54:07 INFO - Cleaning up... 09:54:07 INFO - Return code: 0 09:54:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:54: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')]} 09:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54: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')]} 09:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:07 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 0x248b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2659a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fcb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f9d10>, '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 0x27fac10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb0c0>, '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': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', '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 09:54:07 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 09:54:07 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 09:54:07 INFO - Using env: {'DISPLAY': ':0', 09:54:07 INFO - 'HOME': '/home/cltbld', 09:54:07 INFO - 'LANG': 'en_US.UTF-8', 09:54:07 INFO - 'LANGUAGE': 'en_US:en', 09:54:07 INFO - 'LOGNAME': 'cltbld', 09:54:07 INFO - 'MAIL': '/var/mail/cltbld', 09:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:07 INFO - 'MOZ_NO_REMOTE': '1', 09:54:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:54:07 INFO - 'NO_EM_RESTART': '1', 09:54:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:07 INFO - 'PWD': '/builds/slave/test', 09:54:07 INFO - 'SHELL': '/bin/bash', 09:54:07 INFO - 'SHLVL': '1', 09:54:07 INFO - 'TERM': 'linux', 09:54:07 INFO - 'TMOUT': '86400', 09:54:07 INFO - 'USER': 'cltbld', 09:54:07 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', 09:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:07 INFO - '_': '/tools/buildbot/bin/python'} 09:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:07 INFO - Downloading/unpacking psutil>=3.1.1 09:54:07 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 09:54:07 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 09:54:07 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 09:54:07 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 09:54:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:54:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:54:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:54:08 INFO - Installing collected packages: psutil 09:54:08 INFO - Running setup.py install for psutil 09:54:09 INFO - building 'psutil._psutil_linux' extension 09:54:09 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 09:54:09 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 09:54:09 INFO - building 'psutil._psutil_posix' extension 09:54:09 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 09:54:09 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 09:54:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:54:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:54:09 INFO - Successfully installed psutil 09:54:09 INFO - Cleaning up... 09:54:09 INFO - Return code: 0 09:54:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:54:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:09 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 0x248b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2659a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fcb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f9d10>, '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 0x27fac10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb0c0>, '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': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', '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 09:54:09 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 09:54:09 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 09:54:09 INFO - Using env: {'DISPLAY': ':0', 09:54:09 INFO - 'HOME': '/home/cltbld', 09:54:09 INFO - 'LANG': 'en_US.UTF-8', 09:54:09 INFO - 'LANGUAGE': 'en_US:en', 09:54:09 INFO - 'LOGNAME': 'cltbld', 09:54:09 INFO - 'MAIL': '/var/mail/cltbld', 09:54:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:09 INFO - 'MOZ_NO_REMOTE': '1', 09:54:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:54:09 INFO - 'NO_EM_RESTART': '1', 09:54:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:54:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:09 INFO - 'PWD': '/builds/slave/test', 09:54:09 INFO - 'SHELL': '/bin/bash', 09:54:09 INFO - 'SHLVL': '1', 09:54:09 INFO - 'TERM': 'linux', 09:54:09 INFO - 'TMOUT': '86400', 09:54:09 INFO - 'USER': 'cltbld', 09:54:09 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', 09:54:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:09 INFO - '_': '/tools/buildbot/bin/python'} 09:54:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:54:09 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 09:54:09 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 09:54:09 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 09:54:09 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 09:54:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:54:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:54:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:54:10 INFO - Installing collected packages: mozsystemmonitor 09:54:10 INFO - Running setup.py install for mozsystemmonitor 09:54:10 INFO - Successfully installed mozsystemmonitor 09:54:10 INFO - Cleaning up... 09:54:10 INFO - Return code: 0 09:54:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:54: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')]} 09:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54: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')]} 09:54:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:10 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 0x248b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2659a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fcb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f9d10>, '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 0x27fac10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb0c0>, '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': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', '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 09:54:10 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 09:54:10 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 09:54:10 INFO - Using env: {'DISPLAY': ':0', 09:54:10 INFO - 'HOME': '/home/cltbld', 09:54:10 INFO - 'LANG': 'en_US.UTF-8', 09:54:10 INFO - 'LANGUAGE': 'en_US:en', 09:54:10 INFO - 'LOGNAME': 'cltbld', 09:54:10 INFO - 'MAIL': '/var/mail/cltbld', 09:54:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:10 INFO - 'MOZ_NO_REMOTE': '1', 09:54:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:54:10 INFO - 'NO_EM_RESTART': '1', 09:54:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:54:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:10 INFO - 'PWD': '/builds/slave/test', 09:54:10 INFO - 'SHELL': '/bin/bash', 09:54:10 INFO - 'SHLVL': '1', 09:54:10 INFO - 'TERM': 'linux', 09:54:10 INFO - 'TMOUT': '86400', 09:54:10 INFO - 'USER': 'cltbld', 09:54:10 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', 09:54:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:10 INFO - '_': '/tools/buildbot/bin/python'} 09:54:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:11 INFO - Downloading/unpacking jsonschema==2.5.1 09:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:54:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:54: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 09:54: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 09:54: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 09:54: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 09:54:12 INFO - Downloading functools32-3.2.3-2.tar.gz 09:54:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:54:12 INFO - warning: no files found matching '*.txt' 09:54:12 INFO - no previously-included directories found matching 'build' 09:54:12 INFO - no previously-included directories found matching 'dist' 09:54:12 INFO - no previously-included directories found matching '.git*' 09:54:12 INFO - Installing collected packages: jsonschema, functools32 09:54:12 INFO - Running setup.py install for functools32 09:54:12 INFO - warning: no files found matching '*.txt' 09:54:12 INFO - no previously-included directories found matching 'build' 09:54:12 INFO - no previously-included directories found matching 'dist' 09:54:12 INFO - no previously-included directories found matching '.git*' 09:54:12 INFO - Successfully installed jsonschema functools32 09:54:12 INFO - Cleaning up... 09:54:12 INFO - Return code: 0 09:54:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:54:12 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')]} 09:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:12 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')]} 09:54:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:12 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 0x248b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2659a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fcb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f9d10>, '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 0x27fac10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb0c0>, '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': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', '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 09:54:12 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 09:54:12 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 09:54:12 INFO - Using env: {'DISPLAY': ':0', 09:54:12 INFO - 'HOME': '/home/cltbld', 09:54:12 INFO - 'LANG': 'en_US.UTF-8', 09:54:12 INFO - 'LANGUAGE': 'en_US:en', 09:54:12 INFO - 'LOGNAME': 'cltbld', 09:54:12 INFO - 'MAIL': '/var/mail/cltbld', 09:54:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:12 INFO - 'MOZ_NO_REMOTE': '1', 09:54:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:54:12 INFO - 'NO_EM_RESTART': '1', 09:54:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:54:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:12 INFO - 'PWD': '/builds/slave/test', 09:54:12 INFO - 'SHELL': '/bin/bash', 09:54:12 INFO - 'SHLVL': '1', 09:54:12 INFO - 'TERM': 'linux', 09:54:12 INFO - 'TMOUT': '86400', 09:54:12 INFO - 'USER': 'cltbld', 09:54:12 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', 09:54:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:12 INFO - '_': '/tools/buildbot/bin/python'} 09:54:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:54:12 INFO - Cleaning up... 09:54:12 INFO - Return code: 0 09:54:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:54:12 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')]} 09:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:12 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')]} 09:54:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:12 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 0x248b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2659a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fcb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f9d10>, '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 0x27fac10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb0c0>, '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': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', '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 09:54:12 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 09:54:12 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 09:54:12 INFO - Using env: {'DISPLAY': ':0', 09:54:12 INFO - 'HOME': '/home/cltbld', 09:54:12 INFO - 'LANG': 'en_US.UTF-8', 09:54:12 INFO - 'LANGUAGE': 'en_US:en', 09:54:12 INFO - 'LOGNAME': 'cltbld', 09:54:12 INFO - 'MAIL': '/var/mail/cltbld', 09:54:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:12 INFO - 'MOZ_NO_REMOTE': '1', 09:54:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:54:12 INFO - 'NO_EM_RESTART': '1', 09:54:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:54:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:12 INFO - 'PWD': '/builds/slave/test', 09:54:12 INFO - 'SHELL': '/bin/bash', 09:54:12 INFO - 'SHLVL': '1', 09:54:12 INFO - 'TERM': 'linux', 09:54:12 INFO - 'TMOUT': '86400', 09:54:12 INFO - 'USER': 'cltbld', 09:54:12 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', 09:54:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:12 INFO - '_': '/tools/buildbot/bin/python'} 09:54:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:13 INFO - Downloading/unpacking blobuploader==1.2.4 09:54:13 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 09:54:13 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 09:54:13 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 09:54:13 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 09:54:14 INFO - Downloading blobuploader-1.2.4.tar.gz 09:54:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:54:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:54: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 09:54: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 09:54: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 09:54: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 09:54:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:54:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:54: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 09:54: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 09:54: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 09:54: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 09:54:14 INFO - Downloading docopt-0.6.1.tar.gz 09:54:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:54:14 INFO - Installing collected packages: blobuploader, requests, docopt 09:54:14 INFO - Running setup.py install for blobuploader 09:54:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:54:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:54:14 INFO - Running setup.py install for requests 09:54:15 INFO - Running setup.py install for docopt 09:54:15 INFO - Successfully installed blobuploader requests docopt 09:54:15 INFO - Cleaning up... 09:54:15 INFO - Return code: 0 09:54:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:54:15 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')]} 09:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:15 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')]} 09:54:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:15 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 0x248b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2659a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fcb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f9d10>, '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 0x27fac10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb0c0>, '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': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', '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 09:54:15 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 09:54:15 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 09:54:15 INFO - Using env: {'DISPLAY': ':0', 09:54:15 INFO - 'HOME': '/home/cltbld', 09:54:15 INFO - 'LANG': 'en_US.UTF-8', 09:54:15 INFO - 'LANGUAGE': 'en_US:en', 09:54:15 INFO - 'LOGNAME': 'cltbld', 09:54:15 INFO - 'MAIL': '/var/mail/cltbld', 09:54:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:15 INFO - 'MOZ_NO_REMOTE': '1', 09:54:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:54:15 INFO - 'NO_EM_RESTART': '1', 09:54:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:54:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:15 INFO - 'PWD': '/builds/slave/test', 09:54:15 INFO - 'SHELL': '/bin/bash', 09:54:15 INFO - 'SHLVL': '1', 09:54:15 INFO - 'TERM': 'linux', 09:54:15 INFO - 'TMOUT': '86400', 09:54:15 INFO - 'USER': 'cltbld', 09:54:15 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', 09:54:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:15 INFO - '_': '/tools/buildbot/bin/python'} 09:54:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:54:15 INFO - Running setup.py (path:/tmp/pip-32rG4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:54:15 INFO - Running setup.py (path:/tmp/pip-q9E2Rh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:54:15 INFO - Running setup.py (path:/tmp/pip-xBrBk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:54:15 INFO - Running setup.py (path:/tmp/pip-dAX8ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:54:15 INFO - Running setup.py (path:/tmp/pip-A9gdGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:54:15 INFO - Running setup.py (path:/tmp/pip-vLcUqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:54:16 INFO - Running setup.py (path:/tmp/pip-u7Pih2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:54:16 INFO - Running setup.py (path:/tmp/pip-XqgmAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:54:16 INFO - Running setup.py (path:/tmp/pip-17acwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:54:16 INFO - Running setup.py (path:/tmp/pip-gG1rNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:54:16 INFO - Running setup.py (path:/tmp/pip-oO9MPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:54:16 INFO - Running setup.py (path:/tmp/pip-ZTIVCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:54:16 INFO - Running setup.py (path:/tmp/pip-ORXpdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:54:16 INFO - Running setup.py (path:/tmp/pip-onT08i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:54:16 INFO - Running setup.py (path:/tmp/pip-YkW1lF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:54:16 INFO - Running setup.py (path:/tmp/pip-TwuEAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:54:16 INFO - Running setup.py (path:/tmp/pip-eZxuzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:54:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:54:16 INFO - Running setup.py install for manifestparser 09:54:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:54:16 INFO - Running setup.py install for mozcrash 09:54:17 INFO - Running setup.py install for mozdebug 09:54:17 INFO - Running setup.py install for mozdevice 09:54:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:54:17 INFO - Running setup.py install for mozfile 09:54:17 INFO - Running setup.py install for mozhttpd 09:54:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:54:17 INFO - Running setup.py install for mozinfo 09:54:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:54:17 INFO - Running setup.py install for mozInstall 09:54:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:54:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:54:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:54:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:54:17 INFO - Running setup.py install for mozleak 09:54:17 INFO - Running setup.py install for mozlog 09:54:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:54:17 INFO - Running setup.py install for moznetwork 09:54:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:54:17 INFO - Running setup.py install for mozprocess 09:54:18 INFO - Running setup.py install for mozprofile 09:54:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:54:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:54:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:54:18 INFO - Running setup.py install for mozrunner 09:54:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:54:18 INFO - Running setup.py install for mozscreenshot 09:54:18 INFO - Running setup.py install for moztest 09:54:18 INFO - Running setup.py install for mozversion 09:54:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:54:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:54:18 INFO - Cleaning up... 09:54:18 INFO - Return code: 0 09:54:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:54:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:18 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 0x248b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2659a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fcb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f9d10>, '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 0x27fac10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb0c0>, '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': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', '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 09:54:18 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 09:54:18 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 09:54:18 INFO - Using env: {'DISPLAY': ':0', 09:54:18 INFO - 'HOME': '/home/cltbld', 09:54:18 INFO - 'LANG': 'en_US.UTF-8', 09:54:18 INFO - 'LANGUAGE': 'en_US:en', 09:54:18 INFO - 'LOGNAME': 'cltbld', 09:54:18 INFO - 'MAIL': '/var/mail/cltbld', 09:54:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:18 INFO - 'MOZ_NO_REMOTE': '1', 09:54:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:54:18 INFO - 'NO_EM_RESTART': '1', 09:54:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:54:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:18 INFO - 'PWD': '/builds/slave/test', 09:54:18 INFO - 'SHELL': '/bin/bash', 09:54:18 INFO - 'SHLVL': '1', 09:54:18 INFO - 'TERM': 'linux', 09:54:18 INFO - 'TMOUT': '86400', 09:54:18 INFO - 'USER': 'cltbld', 09:54:18 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', 09:54:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:18 INFO - '_': '/tools/buildbot/bin/python'} 09:54:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:54:18 INFO - Running setup.py (path:/tmp/pip-YO6HWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:54:18 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)) 09:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:54:18 INFO - Running setup.py (path:/tmp/pip-jmAZez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:54:18 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)) 09:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:54:18 INFO - Running setup.py (path:/tmp/pip-aBP5Jh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:54:19 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)) 09:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:54:19 INFO - Running setup.py (path:/tmp/pip-19IWzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:54:19 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)) 09:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:54:19 INFO - Running setup.py (path:/tmp/pip-c1RWnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:54:19 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)) 09:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:54:19 INFO - Running setup.py (path:/tmp/pip-Z4suyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:54:19 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)) 09:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:54:19 INFO - Running setup.py (path:/tmp/pip-jSNoRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:54:19 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)) 09:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:54:19 INFO - Running setup.py (path:/tmp/pip-_0fKX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:54:19 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)) 09:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:54:19 INFO - Running setup.py (path:/tmp/pip-YDNT_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:54:19 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)) 09:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:54:19 INFO - Running setup.py (path:/tmp/pip-Fnshhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:54:19 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)) 09:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:54:19 INFO - Running setup.py (path:/tmp/pip-xXY4R9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:54:19 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)) 09:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:54:19 INFO - Running setup.py (path:/tmp/pip-k6CQVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:54:19 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)) 09:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:54:19 INFO - Running setup.py (path:/tmp/pip-ftMuE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:54:19 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)) 09:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:54:19 INFO - Running setup.py (path:/tmp/pip-0hkule-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:54:19 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)) 09:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:54:19 INFO - Running setup.py (path:/tmp/pip-9Op1XJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:54:19 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)) 09:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:54:19 INFO - Running setup.py (path:/tmp/pip-MQxgRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:54:19 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)) 09:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:54:19 INFO - Running setup.py (path:/tmp/pip-8EfKBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:54:20 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)) 09:54:20 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)) 09:54:20 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)) 09:54:20 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)) 09:54:20 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)) 09:54:20 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)) 09:54:20 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)) 09:54:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:54:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:21 INFO - Downloading blessings-1.6.tar.gz 09:54:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:54:21 INFO - Installing collected packages: blessings 09:54:21 INFO - Running setup.py install for blessings 09:54:21 INFO - Successfully installed blessings 09:54:21 INFO - Cleaning up... 09:54:21 INFO - Return code: 0 09:54:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:54:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:54:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:54:21 INFO - Reading from file tmpfile_stdout 09:54:21 INFO - Current package versions: 09:54:21 INFO - argparse == 1.2.1 09:54:21 INFO - blessings == 1.6 09:54:21 INFO - blobuploader == 1.2.4 09:54:21 INFO - docopt == 0.6.1 09:54:21 INFO - functools32 == 3.2.3-2 09:54:21 INFO - jsonschema == 2.5.1 09:54:21 INFO - manifestparser == 1.1 09:54:21 INFO - mozInstall == 1.13 09:54:21 INFO - mozcrash == 1.0 09:54:21 INFO - mozdebug == 0.1 09:54:21 INFO - mozdevice == 0.51 09:54:21 INFO - mozfile == 1.2 09:54:21 INFO - mozhttpd == 0.7 09:54:21 INFO - mozinfo == 0.10 09:54:21 INFO - mozleak == 0.1 09:54:21 INFO - mozlog == 3.5 09:54:21 INFO - moznetwork == 0.27 09:54:21 INFO - mozprocess == 0.25 09:54:21 INFO - mozprofile == 0.28 09:54:21 INFO - mozrunner == 6.13 09:54:21 INFO - mozscreenshot == 0.1 09:54:21 INFO - mozsystemmonitor == 0.3 09:54:21 INFO - moztest == 0.8 09:54:21 INFO - mozversion == 1.4 09:54:21 INFO - psutil == 3.1.1 09:54:21 INFO - requests == 1.2.3 09:54:21 INFO - wsgiref == 0.1.2 09:54:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:54:21 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')]} 09:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:21 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')]} 09:54:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:21 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 0x248b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2659a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fcb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f9d10>, '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 0x27fac10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb0c0>, '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': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', '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 09:54:21 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 09:54:21 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 09:54:21 INFO - Using env: {'DISPLAY': ':0', 09:54:21 INFO - 'HOME': '/home/cltbld', 09:54:21 INFO - 'LANG': 'en_US.UTF-8', 09:54:21 INFO - 'LANGUAGE': 'en_US:en', 09:54:21 INFO - 'LOGNAME': 'cltbld', 09:54:21 INFO - 'MAIL': '/var/mail/cltbld', 09:54:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:21 INFO - 'MOZ_NO_REMOTE': '1', 09:54:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:54:21 INFO - 'NO_EM_RESTART': '1', 09:54:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:54:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:21 INFO - 'PWD': '/builds/slave/test', 09:54:21 INFO - 'SHELL': '/bin/bash', 09:54:21 INFO - 'SHLVL': '1', 09:54:21 INFO - 'TERM': 'linux', 09:54:21 INFO - 'TMOUT': '86400', 09:54:21 INFO - 'USER': 'cltbld', 09:54:21 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', 09:54:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:21 INFO - '_': '/tools/buildbot/bin/python'} 09:54:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:21 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)) 09:54:21 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)) 09:54:21 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)) 09:54:21 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)) 09:54:21 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)) 09:54:21 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)) 09:54:21 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)) 09:54:21 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)) 09:54:21 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)) 09:54:21 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:22 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:54:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:54:22 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 09:54:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 09:54:23 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)) 09:54:23 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)) 09:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:54:23 INFO - Installing collected packages: marionette-driver, simplejson 09:54:23 INFO - Running setup.py install for marionette-driver 09:54:23 INFO - Running setup.py install for simplejson 09:54:23 INFO - building 'simplejson._speedups' extension 09:54:23 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 09:54:24 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 09:54:24 INFO - Successfully installed marionette-driver simplejson 09:54:24 INFO - Cleaning up... 09:54:24 INFO - Return code: 0 09:54:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:24 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 0x248b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2659a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27fcb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f9d10>, '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 0x27fac10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb0c0>, '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': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', '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 09:54:24 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 09:54:24 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 09:54:24 INFO - Using env: {'DISPLAY': ':0', 09:54:24 INFO - 'HOME': '/home/cltbld', 09:54:24 INFO - 'LANG': 'en_US.UTF-8', 09:54:24 INFO - 'LANGUAGE': 'en_US:en', 09:54:24 INFO - 'LOGNAME': 'cltbld', 09:54:24 INFO - 'MAIL': '/var/mail/cltbld', 09:54:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:24 INFO - 'MOZ_NO_REMOTE': '1', 09:54:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:54:24 INFO - 'NO_EM_RESTART': '1', 09:54:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:54:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:24 INFO - 'PWD': '/builds/slave/test', 09:54:24 INFO - 'SHELL': '/bin/bash', 09:54:24 INFO - 'SHLVL': '1', 09:54:24 INFO - 'TERM': 'linux', 09:54:24 INFO - 'TMOUT': '86400', 09:54:24 INFO - 'USER': 'cltbld', 09:54:24 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', 09:54:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:24 INFO - '_': '/tools/buildbot/bin/python'} 09:54:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:54:24 INFO - Cleaning up... 09:54:24 INFO - Return code: 0 09:54:24 INFO - Running post-action listener: _resource_record_post_action 09:54:24 INFO - Running post-action listener: _start_resource_monitoring 09:54:24 INFO - Starting resource monitoring. 09:54:24 INFO - [mozharness: 2017-07-18 16:54:24.659700Z] Finished create-virtualenv step (success) 09:54:24 INFO - [mozharness: 2017-07-18 16:54:24.660216Z] Running install step. 09:54:24 INFO - Running pre-action listener: _resource_record_pre_action 09:54:24 INFO - Running main action method: install 09:54:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:54:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:54:24 INFO - Reading from file tmpfile_stdout 09:54:24 INFO - Detecting whether we're running mozinstall >=1.0... 09:54:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:54:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:54:25 INFO - Reading from file tmpfile_stdout 09:54:25 INFO - Output received: 09:54:25 INFO - Usage: mozinstall [options] installer 09:54:25 INFO - Options: 09:54:25 INFO - -h, --help show this help message and exit 09:54:25 INFO - -d DEST, --destination=DEST 09:54:25 INFO - Directory to install application into. [default: 09:54:25 INFO - "/builds/slave/test"] 09:54:25 INFO - --app=APP Application being installed. [default: firefox] 09:54:25 INFO - mkdir: /builds/slave/test/build/application 09:54:25 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'] 09:54:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:54:42 INFO - Reading from file tmpfile_stdout 09:54:42 INFO - Output received: 09:54:42 INFO - /builds/slave/test/build/application/firefox/firefox 09:54:42 INFO - Running post-action listener: _resource_record_post_action 09:54:42 INFO - [mozharness: 2017-07-18 16:54:42.933621Z] Finished install step (success) 09:54:42 INFO - [mozharness: 2017-07-18 16:54:42.933768Z] Running setup-mitmproxy step. 09:54:42 INFO - Running pre-action listener: _resource_record_pre_action 09:54:42 INFO - Running main action method: setup_mitmproxy 09:54:42 INFO - Skipping: mitmproxy is not required 09:54:42 INFO - Running post-action listener: _resource_record_post_action 09:54:42 INFO - [mozharness: 2017-07-18 16:54:42.934305Z] Finished setup-mitmproxy step (success) 09:54:42 INFO - [mozharness: 2017-07-18 16:54:42.934409Z] Running run-tests step. 09:54:42 INFO - Running pre-action listener: _resource_record_pre_action 09:54:42 INFO - Running pre-action listener: _set_gcov_prefix 09:54:42 INFO - Running main action method: run_tests 09:54:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:54:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:54:42 INFO - Python 2.7.3 09:54:42 INFO - Return code: 0 09:54:42 INFO - grabbing minidump binary from tooltool 09:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:42 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 0x27f9d10>, '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 0x27fac10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fb0c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:54:42 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 09:54:42 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 09:54:42 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 09:54:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:54:43 INFO - Return code: 0 09:54:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:54:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:54:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:54:43 INFO - ENV: RUST_BACKTRACE is now 1 09:54:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:54:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:54:43 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/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 09:54:43 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/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-048 --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 09:54:43 INFO - Using env: {'DISPLAY': ':0', 09:54:43 INFO - 'HOME': '/home/cltbld', 09:54:43 INFO - 'LANG': 'en_US.UTF-8', 09:54:43 INFO - 'LANGUAGE': 'en_US:en', 09:54:43 INFO - 'LOGNAME': 'cltbld', 09:54:43 INFO - 'MAIL': '/var/mail/cltbld', 09:54:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:54:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:54:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:43 INFO - 'MOZ_NO_REMOTE': '1', 09:54:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:54:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:54:43 INFO - 'NO_EM_RESTART': '1', 09:54:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:54:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:43 INFO - 'PWD': '/builds/slave/test', 09:54:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:54:43 INFO - 'RUST_BACKTRACE': '1', 09:54:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:54:43 INFO - 'SHELL': '/bin/bash', 09:54:43 INFO - 'SHLVL': '1', 09:54:43 INFO - 'TERM': 'linux', 09:54:43 INFO - 'TMOUT': '86400', 09:54:43 INFO - 'USER': 'cltbld', 09:54:43 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394', 09:54:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:43 INFO - '_': '/tools/buildbot/bin/python'} 09:54:43 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/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 09:54:43 INFO - mozversion application_buildid: 20170718161501 09:54:43 INFO - mozversion application_changeset: 56c6c81993e6b0f0e094e0c88b42ce3ec566ead9 09:54:43 INFO - mozversion application_display_name: Nightly 09:54:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:54:43 INFO - mozversion application_name: Firefox 09:54:43 INFO - mozversion application_remotingname: firefox 09:54:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:54:43 INFO - mozversion application_vendor: Mozilla 09:54:43 INFO - mozversion application_version: 56.0a1 09:54:43 INFO - mozversion platform_buildid: 20170718161501 09:54:43 INFO - mozversion platform_changeset: 56c6c81993e6b0f0e094e0c88b42ce3ec566ead9 09:54:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:54:43 INFO - mozversion platform_version: 56.0a1 09:54:43 INFO - using testdate: 1500396883 09:54:43 INFO - actual date: 1500396883 09:54:43 INFO - starting webserver on 'localhost:53767' 09:54:43 INFO - SUITE-START | Running 2 tests 09:54:43 INFO - TEST-START | dromaeo_css 09:54:43 INFO - Initialising browser for dromaeo_css test... 09:54:43 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53767/getInfo.html -profile /tmp/tmpFiKeK3/profile 09:54:43 INFO - TEST-INFO | started process 15772 (/builds/slave/test/build/application/firefox/firefox http://localhost:53767/getInfo.html) 09:54:49 INFO - TEST-INFO | 15772: exit 0 09:54:49 INFO - Browser initialized. 09:54:49 INFO - Running cycle 1/1 for dromaeo_css test... 09:54:49 INFO - TEST-INFO | started process 16020 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFiKeK3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:55:01 INFO - PID 16020 | RSS: Main: 173060096 09:55:01 INFO - PID 16020 | 09:57:59 INFO - PID 16020 | Cycle 1(1): loaded http://localhost:53767/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53767/tests/dromaeo/cssquery-ext.html) 09:58:00 INFO - PID 16020 | RSS: Main: 178360320 09:58:00 INFO - PID 16020 | 10:01:02 INFO - PID 16020 | Cycle 1(1): loaded http://localhost:53767/tests/dromaeo/cssquery-ext.html (next: http://localhost:53767/tests/dromaeo/cssquery-jquery.html) 10:01:03 INFO - PID 16020 | RSS: Main: 176164864 10:01:03 INFO - PID 16020 | 10:04:04 INFO - PID 16020 | Cycle 1(1): loaded http://localhost:53767/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53767/tests/dromaeo/cssquery-mootools.html) 10:04:05 INFO - PID 16020 | RSS: Main: 177549312 10:04:05 INFO - PID 16020 | 10:07:06 INFO - PID 16020 | Cycle 1(1): loaded http://localhost:53767/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53767/tests/dromaeo/cssquery-prototype.html) 10:07:07 INFO - PID 16020 | RSS: Main: 174923776 10:07:07 INFO - PID 16020 | 10:10:10 INFO - PID 16020 | Cycle 1(1): loaded http://localhost:53767/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53767/tests/dromaeo/cssquery-yui.html) 10:10:11 INFO - PID 16020 | RSS: Main: 177852416 10:10:11 INFO - PID 16020 | 10:13:12 INFO - PID 16020 | Cycle 1(1): loaded http://localhost:53767/tests/dromaeo/cssquery-yui.html (next: http://localhost:53767/tests/dromaeo/cssquery-dojo.html) 10:13:13 INFO - PID 16020 | RSS: Main: 180326400 10:13:13 INFO - PID 16020 | 10:13:13 INFO - PID 16020 | __start_tp_report 10:13:13 INFO - PID 16020 | _x_x_mozilla_page_load 10:13:13 INFO - PID 16020 | _x_x_mozilla_page_load_details 10:13:13 INFO - PID 16020 | |i|pagename|runs| 10:13:13 INFO - PID 16020 | |0;dojo.html;3089.2276930767307;3107.440660681077;3135.2318681922916;3156.0847354267257;3181.809091454513;6087.847803804907;6101.664408457533;6138.109974053759;6167.969160154198;6246.781362652308;6233.532485063627;6243.65659888706;6244.157038799761;6250.031245156996;6261.436470717635;5608.495235428811;5685.033544297469;5689.288838895138;5697.0599851024535;5704.914426283609;5665.405132461094;5679.4036626154275;5681.062624666925;5694.402087780785;5730.111832665943;5655.066913959192;5724.971375143118;5730.7707691692285;5734.885302293952;5737.885242295151;5710.600257981943;5719.14212868069;5732.22614946981;5733.082706766918;5735.655860648374;5621.831345059655;5632.662040277555;5645.802396916089;5655.745491452894;5732.971335143297;6151.569390142692;6186.474149697281;6219.533534984895;6250.468710159642;6281.7173227204885;6586.176727909012;6606.273309935903;6651.467882569383;6663.533552651316;6709.966450167718;6521.93478065218;6634.900476492857;6638.535302528825;6640.468762498988;6641.535092543524;6145.385461453863;6172.969135154295;6179.752809887644;6187.535934804908;6188.10272510488;6799.456043516556;6814.557053791586;6821.658917054127;6890.689918953659;6905.033295338657;6415.518836087312;6479.8056058318325;6488.448481878999;6488.708008139645;6579.605223686594;6487.610743355413;6619.139511863524;6658.6337751424135;6665.266820649725;6713.395794378529;6481.4166724994975;6509.967450162813;6512.088307636935;6539.378759017885;6551.148350714377;5608.369301747189;6543.738250470023;6549.377809108127;6635.734570617218;6654.134962454947;5252.080885844962;5282.6302158849285;5346.385165705916;5356.223347614574;5367.597430192751;4468.754218517947;4537.069900670354;4545.499995000548;4546.04533048057;4568.634509239253;5784.971075144597;5793.188953546507;5795.072788353843;5813.157092221604;5817.156512305692;6707.0610114584;6710.563813352116;6712.53012289135;6713.295104014008;6723.865522689519;5716.79991200306;5790.334111577223;5796.62321949072;5806.854828629318;5807.94192058074;5811.88376232473;5827.446392592697;5829.679367634821;5843.678597677169;5847;5497.39528651848;6406.423421892052;6436.581622194542;6463.482921366373;6504.15445992018;6650.301718319507;6687.197536295578;6722.899156512656;6752.797416077526;6758.324167583202;6424.13274207976;6432.324605916311;6504.707288172138;6533.803985880431;6540.476761858945;6017.879642407215;6417.743290268524;6492.5455218134275;6516.934830651633;6572.737090516517;6446.871062578628;6672.266050734513;6730.899036514554;6735.797926062226;6779.491538134561;6466.288708241996;6473.158489396348;6541.574797638138;6548.3124271950755;6557.704903279268;6447.258565264867;6465.967670161619;6531.9020214695865;6548.8362790930605;6558.114654521769;6292.182016337847;6371.267304260069;6407.327230640715;6410.134631824831;6489.1888513935755;6296.622202667671;6328.525360598066;6362.618242905441;6455.289918108911;6468.902966455413;5299.33758280215;6227.19046523949;6246.625202487865;6333.778317758856;6360.459360954185 10:13:13 INFO - PID 16020 | |1;ext.html;712.1560950273926;749.4154559443635;758.1054355860085;785.030487348125;794.4597673581962;39374;40178.59821401785;40263.79868100663;40356.79821600888;40359.79820100899;37031;37234.882953511435;37307.62692373076;37367.439488407625;37369.25261494768;6568.244651865049;6671.733130674767;6675.399214070732;6689.732410703566;6715.630640314781;6601.10885030522;6684.097646817666;6696.665166741644;6697.062411262427;6698.8995165072565;6647.302033286508;6652.434543063845;6652.467802575807;6669.699863506154;6673.566218195826;6606.240282367541;6638.369354911276;6638.502112341545;6654.534182607195;6663.2337281212795;6034.306054803666;6050.27396712393;6051.909221361639;6075.48358389533;6085.908711369333;33180.170495737555;33221.16947076318;33230;33392;33458.49812252818;3214.7267482264037;3251.7723759336855;3348.748843836722;3395.5416018837386;3428.5542879425766;30290.69709302923;30691;30698.693013069806;30709.692903070907;30821;4863.954249836267;4869.123557759602;4883.755812209375;4890.682105663121;4963.553280204799;186692;190494.09505904902;193452;194772;194863;41985;42029.15941681149;48158.5184148154;48255.75872120687;48389.27416088762;51475;51523.742381287855;51571;51574.226386604234;51705.7414712924;15613.609659758597;15660.921695391678;15788.447404340794;15839.920800395925;15861.444849430214;12866.228026318451;12912.935435322763;12953.805692914613;12957.870421295667;12965.09244352905;14021.859781402056;14107.576772696835;14233.928830355782;14240;14344.211068391338;5699.088145896637;5712.371639119693;5719.599628025998;5797.826065218051;5838.620489668158;993.8757655293089;994.6667866264737;994.9751256218508;1019.1490105761624;1019.9133073688671;21207;21961.23135690275;22169.44576385571;22201.44496387571;22369.88815055947;1072.5012869015945;1106.8450416941635;1106.9944650276698;1107.9889201108047;1120.439780109945;11674.591389301504;11675.88324116748;11731.76536469266;11846.052315814713;11853.46659400329;235814;235947;235993;236680;237376;49752.25371619429;50526;50571;50593;50603;1378.6622277541867;1378.8827949624394;1391.172252509755;1399.5101714400082;1444.3428240150706;21205.469863253544;22067.896605169997;22295.442613934134;22464.887675561517;22492;35391;35413.822930886214;35441.29117417586;35497;35651.46522802108;192062;192180;192414;193725;194187;29842.552361715025;29960.400791984473;30328;33157.50263746094;33359.83320083481;38495;42267.232375327345;42364.36453453139;42414.78792606017;42506.14987700291;27887;33023.834880825445;33626.159346015564;34041.31917361589;34139.82930085334;32218.516722248718;32675.673243268215;32770.83614581912;33075.50386744153;33352.49971250482;30150.84924575363;30793.538096928114;31179;31577.21056973594;31829.20426989344;30529.847350763102;31000.22499437532;31637.8418107908;32008.83995580007;32182;23582.882085589463;23626.29121126369;23658.171963981178;23944.401389964692;24387.878060609582 10:13:13 INFO - PID 16020 | |2;jquery.html;563.4450066684316;565.9586361095584;571.9885602287954;577.3418303134426;579.4437340153455;21231.57536849264;22201.333959981184;22246.777532224674;22289.44276393087;22334;18002.729959050626;18680.439586812416;18681.906590467064;18692.25230990762;21586.676199857015;6129.478994285491;6323.114763933053;6342;6361.745530178788;6383.808485745411;6155.107509411133;6224.066390041485;6245.219347581552;6259.937400625981;6267.62394256346;6182.752689892399;6228.096925945735;6230.968845155768;6234.937650623504;6259.37406259375;5805.071188609823;5973.492253158487;6176.166217560639;6215.844603884894;6225.937740622581;5703.230063941356;5773.769049238025;5774.451427114417;5776.480116789509;5778.451047150514;26509;26947.730522694914;26984;26989.32526686829;27009.459810803673;1905.3807512558474;1923.807619238079;1929.3343796390222;1929.6623090959026;1932.719755635439;16110.355585776671;17764.378246761302;18005.369812056517;18192.63614727698;18246.361377351863;2098.5383215692523;2175.3256490487843;2198.791114844087;2267.501149747053;2272.125231785758;132495.33752331274;138386;138760.30619846936;138942.3052884729;138980;13344.666383340495;14125.929370353082;14249.216293103771;14366.85633143676;14456.783148252785;21759.129634814748;21840;21911.452213694785;21920.123195072338;22003.449913752283;8996.460212387146;9067.68263110788;9150.771230719152;9211.40125891815;9279.67521136757;8215.507069575844;8272.420930534925;8389.244967952913;8390.75073038918;8396.664133434595;8425.452345597516;8564.785880353042;8608.48349099056;8753.518556479328;8767.561621919007;2394.485185685086;2429.477662302613;2453.926382208537;2548.299217715143;2552.2726023083333;940.8917974433028;945.1257586732245;958.0946006024379;964.6961207219704;994.1798016636304;18438;18647.160877760532;18729.531761706065;18865.056747162587;18873.622527549647;970.2044323654677;983.537737263485;987.9654212102686;1026.9229807764448;1047.3977462958828;9981.700548983543;10065.194784417228;10110.898891010997;10217.540210690537;10316.690499285034;493263;531994;548972;550030;572331;120323;125884.37057814653;126016.36991814982;127297;127457;1245.1346314311465;1249.256692268099;1287.7682017236987;1297.2864924291603;1297.7209899871573;18730.438086857415;19743.703844442633;19922.800771992675;20076.598468030264;20131.597368052266;53516.46483535271;54929.176062358296;56064.15903761528;57080.42919570751;58453.70773146107;68563;68882;68971;71197;75434.62282688552;13993.860061399255;14434.278286085864;14448.349824258145;14448.494302699355;14505.347259373142;21078.57842843104;22153.667694984266;22368.77631223667;22580.661290080334;22865.085396584615;15021.624459388602;17887.195076221342;17955.461336159937;17995.190216440555;18045.909770451064;15811;15913.443029493912;15971.121588312755;16700.916495417445;16745.665086698442;17869.821301786815;18442.26230950747;18507.074646267363;18526.166322515248;19318.8068119317;14194.716105677622;14383.640408989859;14493.492727754478;14552.85447145515;14578;17690.64618707593;17886.5528361792;18043;18086.638267234845;18283.908580457544 10:13:13 INFO - PID 16020 | |3;mootools.html;1327.9004074694396;1375.1817668487247;1382.1499777636752;1396.343718452327;1401.4464286606785;13292.734145317088;13776.104553204035;13834.446622135116;13901.652458688537;13927.234002129879;13645.863541364582;14003.579892603213;14326.785098223534;14442.927785361086;14468.131912085257;2504.7620476054744;2566.910158144465;2586.3275548357374;2588.7799537039373;2590.4042070323835;2484.7391023942496;2538.9492210155786;2541.9745802542016;2545.9490810183784;2549.260714392824;2371.1700904683394;2473.245660073677;2506.323292710967;2511.9120830770926;2519.407939134302;2510.460251046025;2532.6201069839485;2533.075427468979;2538.0862889359814;2538.1624064058815;2331.219041621042;2346.8122550196113;2348.330725743155;2348.6829278047417;2350.21267875262;12960.935195323964;12974.286414247212;13080.934595327057;13082.738345233138;13127.671808204872;6682.899756503656;6774.424173945171;7070.823229419306;7079.256678048834;7242.529235599669;4788.539495453885;4814.807407703687;4841.443234028062;4872.415310162768;4894.951050489521;1788.2757483219186;1830.4417152768401;1840.9079546022645;1880.4170707080848;1899.67705490067;22327.553448930932;22740.65889011666;22803.88598056999;22853.42866428336;23003.884980575156;6205.472534834499;6406.391392817675;6440.516961227927;6558.770443034471;6610.140681711443;17588.38440654571;17604.559886002953;17628.383006594708;17716.91141544284;17750.91124544369;7289.744858929912;7409.407247420193;7456.515326503868;7460.29127232912;7460.85078298431;6579.835504112435;6627.569208001464;6703.430208432241;6823.215330237086;6900;5026.773795179226;5251.238570407269;5276.287701160371;5328.547073498718;5407.540359069444;1565.4051460444957;1622.7160246956971;1628.788257526514;1637.4269005847857;1640.9097499637635;883.1918794303269;883.3066043156092;908.4912449028433;917.3532659475054;927.4342650982895;7286.7085316586745;7292.927070729331;7306.342429181399;7319.853602927911;7340.972263883061;633.3349982518338;699.3635791429774;704.3344039882364;704.3519961635205;710.6659869861261;4982.77577509013;5533.833984980457;5622.522085622686;5800.738966746507;5883.441073098049;18701.34545290908;18788.436346909613;18863.151158197914;18918.3378581749;19038.428847134608;18521.259149633865;18702.064896755106;18708.906455467637;18859.905700471685;18865.15106820169;10324.587016519405;10403.427811470441;10446.895531044744;10571.947140264403;10673.786524269472;7818.569978651229;8207;8321;8425.536595487061;8538.871916921127;15677.608059798597;16109.758353624939;16505.422310219084;16838.494845154666;16843;16035.518934431986;16526.75209871877;17115.144242787563;17223.7416438756;17349.21928513245;10403.21975851799;10629.89370106289;10666.46667666629;10697.304675196086;10702.411367374869;12257.938710306391;12386.81419778686;12415.193012454161;12489.250644961332;12500.124991250525;9780.021997800448;10078.244131690006;10278.640247591298;10540.472976351306;10620.309679871092;9654.937956824604;9973.401595904268;10096.646617368651;10113.747156321151;10300.381977081399;9364.110409511086;9478.668246611336;9496.14534691881;9507.714768556954;9510.09654082861;6071.423214794588;6808.659567021728;6917.13535808024;7046.577205367695;7091;6743.35938085881;6811;6901.585904845725;6973.267806880204;6988 10:13:13 INFO - PID 16020 | |4;prototype.html;488.8504852700374;495.6146192043529;510.0589412533874;510.93613298337704;514.290279414408;6025.548083893709;6040.607360521569;6138;6162.568620196588;6235.532335074865;5809.215755872956;5874.9412505874925;5903.645781253128;5944.4352786485215;6012.5791194616395;4836.879078023042;4873.829415970442;4898.265260210961;4908.460069392364;4909.386326709161;4869.563478773753;4879.219324908016;4885.096257192413;4895.632827537949;4911.729854857981;4703.882402939937;4794.993051459198;4833.661643684926;4844.442889067767;4856.39295088114;4565.644251475163;4739.933514959124;4798.744590042675;4813.181759100961;4820.276958456225;4248.4052232687445;4265.680073994432;4279;4307.418498502693;4327.285997810358;5830.970845145747;5853.970730146365;5882.735276912549;5940.227770389865;5940.732667029994;5474.671519708831;5490.368607610136;5493.313335833021;5509.630858730744;5536.640118392332;6315.778947736853;6345.968270158666;6346.746130154788;6400.871982560369;6406.839829004266;4711.293306004092;4732.100900828831;4732.455767586737;4741.2651039089;4769.713817170981;6685.13093297873;6712.22809376923;6716.6305853178055;6728.192616886005;6785.930291261114;4820.325154478376;4835.347228124158;4858.518151963614;4903.583195428358;4903.632227582945;4877.756112194447;4908.460069392364;4933.112039832865;4939.1109600271575;4962.330085438492;5458.181272809111;5506.669599824023;5515.696636685021;5521.503064724194;5578.24693666358;6355.968220158869;6427.714457108597;6431.935680643133;6455.225372955276;6466.764269403454;1692.483792443292;1708.13739061773;1727.455851406828;1734.4536471011843;1737.0793479455908;1113.504490501735;1180.7719971229997;1184.135581025856;1194.4684615346087;1204.6988252936767;5556.861078473071;5560.665440294381;5620.74706638194;5643.1535269709875;5740.225069615549;6424.486041116699;6520.804375868732;6539.449614650291;6550.246721626979;6626.503012274099;5633.633813802172;5636.746346414421;5668.149777533403;5706.600537962386;5712.485876271073;3460.602030766444;3483.494893240517;3494.5107684924133;3508.999805166597;3526.2242306692488;6296.464800491918;6366.10342294255;6398.5521013528605;6410.583312084699;6445.2587952386075;6655.234648015444;6665.70047826329;6713.12729345182;6779.322067793181;6784.9629470465;6350.523710721714;6427.22873255203;6438.130852334969;6441.227052753699;6481.865673507117;6491.610503369813;6498.285188629342;6583.8807402742605;6590.7693230736695;6634.512057978258;6534.509911756542;6542.8364290893105;6550.705218265285;6553.180852393451;6598.571092878948;6363.331850155852;6409.871802563829;6419.133416988647;6447.774327898501;6482.805515834532;4809.701380627141;4813.687110337817;4852.490488498657;4853.878653033702;4885.4870238624435;6606.669666516751;6609.636469994004;6620.3710647488415;6631.933680663132;6665.333466653296;6375.904361434489;6415.230172379344;6445.25879523842;6473.579217350857;6496.382843629847;6408.045546108482;6413.198350206224;6420.844248035399;6439.839004024937;6483.546151769519;6207.286162091417;6305.747770089145;6311.368863113652;6324.6521441321165;6344.4290013897125;6354.586951848115;6376.139221205078;6383.425491705769;6387.712552935222;6417.871642567029;5664.603477756517;6046.334903160737;6237.1891654084675;6268.216472940882;6361.77733779334 10:13:13 INFO - PID 16020 | |5;yui.html;893.9642414303428;903.2773780975218;911.1025639744852;913.9946059334733;920.406337912047;18710.15804288807;18972.525686857836;19411.708824367644;19425.90287048563;20942.26702065428;19916.601667966668;21095.894520527414;21140.365789026317;21147.894260528676;21331.68002479964;1006.3408467453829;1016.8576399304085;1026.0765311219925;1029.8043371759343;1029.8609687692178;998.4608311511811;1041.5052849896315;1051.1958351860794;1053.2837670384135;1055.171690223175;1029.7425643589104;1030.8814486334054;1033.9689809305733;1038.0346277961494;1094.534822700353;1000.1049061090283;1033.9172866170688;1036.2280101324525;1036.942318834788;1097.1716354152643;774.8760198368234;775.4610545670761;787.2403130978576;790.4032455496064;792.3938187286731;19019.239230430765;19020.5244868878;19187.80812191874;19247.326343577908;19261.9036904816;19425.51436214092;20588.073536690885;20647.5870482591;20722.792772072236;20971.16115355384;15509.922450387676;16231.51305460838;16254.024758514526;16320.428784992588;16350.345986160539;3549.4853246279404;3575.606683264865;3583.552055993001;3584.9283014339567;3588.318219538279;115914;118445.40777296145;120144;120710;124193.37903310516;15696.293666785023;15944.840551594336;16052.75920861188;16366.263518141712;16563.757718171186;28833;28911.56632650512;28990.565141522893;29883.252918677208;30075.398492030035;8211.01467823865;8253.050899146554;8286.41995060352;8332.416730828783;8347.874781878276;3549.201429678353;3623.9094022649647;3907.1404291055924;3968.305546529346;3981.0246489609654;4789.090072886141;4875.512448755097;4891.755412229374;4892.339534162914;4910.607151427877;976.8144052629978;1006.6275478073013;1025.8922813104666;1027.091024443358;1028.5782829040206;646.7219095788762;668.2949488289804;679.9796006119725;680.4454369688688;708.125465050662;10989.670309890716;13177.341132943506;13749.518766843112;13788;13819.309034548232;688.4114082459525;691.8339598496425;706.445440329339;720.6540860387043;720.92069872314;5340.851716880811;5526.806561770319;5538.307711536058;5600.915986260209;5708.686022268815;106101;108992.4550377243;113132;114698;115200;93776.51776265318;96235.51882240544;98743;99128;103513;81468.59265703634;86034.27931441336;86181.56909215414;88282.55858720666;88863.66704499438;10594.523246454082;10683.839742404025;10809.621663241749;10846.945765271123;10943.562257509611;35956.64043359533;36106.8194659025;38205.80897095497;38569;38934.41598376083;68204.6589767048;71124.64437677778;72062.27937720556;73498;75079.62460187664;17576.91211543934;17963.191656375235;18107.185176667346;18186.818131818512;18311.633767324314;18422.90788546049;19383.224671013002;19666.21335146578;19891.502712432306;19965;18523.88856668604;18594.163262652943;18915.716264256323;18918.243270269035;18977.146028428477;17827.286908523514;18853.24587016504;19023.714644280622;19065.33271335552;19156.71264931055;17294.308227670754;18005.63988720192;18154.546136346697;18309.54226144357;18629.90685046566;12469.189502682297;12742.171758835646;12847.421866015864;13286;13900.652483687989;13544.796828047783;13904.37430315618;14036.69542004499;14131.929340353232;14705.191214483306 10:13:13 INFO - PID 16020 | __end_tp_report 10:13:13 INFO - PID 16020 | __start_cc_report 10:13:13 INFO - PID 16020 | _x_x_mozilla_cycle_collect,355 10:13:13 INFO - PID 16020 | __end_cc_report 10:13:13 INFO - PID 16020 | __startTimestamp1500397993105__endTimestamp 10:13:13 INFO - PID 16020 | 10:13:13 INFO - PID 16020 | ------- Summary: start ------- 10:13:13 INFO - PID 16020 | Number of tests: 6 10:13:13 INFO - PID 16020 | 10:13:13 INFO - PID 16020 | [#0] dojo.html Cycles:165 Average:6089.46 Median:6296.62 stddev:724.76 (11.5%) stddev-sans-first:687.71 10:13:13 INFO - PID 16020 | Values: 3089.2 3107.4 3135.2 3156.1 3181.8 6087.8 6101.7 6138.1 6168.0 6246.8 6233.5 6243.7 6244.2 6250.0 6261.4 5608.5 5685.0 5689.3 5697.1 5704.9 5665.4 5679.4 5681.1 5694.4 5730.1 5655.1 5725.0 5730.8 5734.9 5737.9 5710.6 5719.1 5732.2 5733.1 5735.7 5621.8 5632.7 5645.8 5655.7 5733.0 6151.6 6186.5 6219.5 6250.5 6281.7 6586.2 6606.3 6651.5 6663.5 6710.0 6521.9 6634.9 6638.5 6640.5 6641.5 6145.4 6173.0 6179.8 6187.5 6188.1 6799.5 6814.6 6821.7 6890.7 6905.0 6415.5 6479.8 6488.4 6488.7 6579.6 6487.6 6619.1 6658.6 6665.3 6713.4 6481.4 6510.0 6512.1 6539.4 6551.1 5608.4 6543.7 6549.4 6635.7 6654.1 5252.1 5282.6 5346.4 5356.2 5367.6 4468.8 4537.1 4545.5 4546.0 4568.6 5785.0 5793.2 5795.1 5813.2 5817.2 6707.1 6710.6 6712.5 6713.3 6723.9 5716.8 5790.3 5796.6 5806.9 5807.9 5811.9 5827.4 5829.7 5843.7 5847.0 5497.4 6406.4 6436.6 6463.5 6504.2 6650.3 6687.2 6722.9 6752.8 6758.3 6424.1 6432.3 6504.7 6533.8 6540.5 6017.9 6417.7 6492.5 6516.9 6572.7 6446.9 6672.3 6730.9 6735.8 6779.5 6466.3 6473.2 6541.6 6548.3 6557.7 6447.3 6466.0 6531.9 6548.8 6558.1 6292.2 6371.3 6407.3 6410.1 6489.2 6296.6 6328.5 6362.6 6455.3 6468.9 5299.3 6227.2 6246.6 6333.8 6360.5 10:13:13 INFO - PID 16020 | 10:13:13 INFO - PID 16020 | [#1] ext.html Cycles:180 Average:36673.19 Median:33225.58 stddev:54045.88 (162.7%) stddev-sans-first:54130.04 10:13:13 INFO - PID 16020 | Values: 712.2 749.4 758.1 785.0 794.5 39374.0 40178.6 40263.8 40356.8 40359.8 37031.0 37234.9 37307.6 37367.4 37369.3 6568.2 6671.7 6675.4 6689.7 6715.6 6601.1 6684.1 6696.7 6697.1 6698.9 6647.3 6652.4 6652.5 6669.7 6673.6 6606.2 6638.4 6638.5 6654.5 6663.2 6034.3 6050.3 6051.9 6075.5 6085.9 33180.2 33221.2 33230.0 33392.0 33458.5 3214.7 3251.8 3348.7 3395.5 3428.6 30290.7 30691.0 30698.7 30709.7 30821.0 4864.0 4869.1 4883.8 4890.7 4963.6 186692.0 190494.1 193452.0 194772.0 194863.0 41985.0 42029.2 48158.5 48255.8 48389.3 51475.0 51523.7 51571.0 51574.2 51705.7 15613.6 15660.9 15788.4 15839.9 15861.4 12866.2 12912.9 12953.8 12957.9 12965.1 14021.9 14107.6 14233.9 14240.0 14344.2 5699.1 5712.4 5719.6 5797.8 5838.6 993.9 994.7 995.0 1019.1 1019.9 21207.0 21961.2 22169.4 22201.4 22369.9 1072.5 1106.8 1107.0 1108.0 1120.4 11674.6 11675.9 11731.8 11846.1 11853.5 235814.0 235947.0 235993.0 236680.0 237376.0 49752.3 50526.0 50571.0 50593.0 50603.0 1378.7 1378.9 1391.2 1399.5 1444.3 21205.5 22067.9 22295.4 22464.9 22492.0 35391.0 35413.8 35441.3 35497.0 35651.5 192062.0 192180.0 192414.0 193725.0 194187.0 29842.6 29960.4 30328.0 33157.5 33359.8 38495.0 42267.2 42364.4 42414.8 42506.1 27887.0 33023.8 33626.2 34041.3 34139.8 32218.5 32675.7 32770.8 33075.5 33352.5 30150.8 30793.5 31179.0 31577.2 31829.2 30529.8 31000.2 31637.8 32008.8 32182.0 23582.9 23626.3 23658.2 23944.4 24387.9 10:13:13 INFO - PID 16020 | 10:13:13 INFO - PID 16020 | [#2] jquery.html Cycles:180 Average:35761.82 Median:22078.56 stddev:90665.47 (410.6%) stddev-sans-first:90881.30 10:13:13 INFO - PID 16020 | Values: 563.4 566.0 572.0 577.3 579.4 21231.6 22201.3 22246.8 22289.4 22334.0 18002.7 18680.4 18681.9 18692.3 21586.7 6129.5 6323.1 6342.0 6361.7 6383.8 6155.1 6224.1 6245.2 6259.9 6267.6 6182.8 6228.1 6231.0 6234.9 6259.4 5805.1 5973.5 6176.2 6215.8 6225.9 5703.2 5773.8 5774.5 5776.5 5778.5 26509.0 26947.7 26984.0 26989.3 27009.5 1905.4 1923.8 1929.3 1929.7 1932.7 16110.4 17764.4 18005.4 18192.6 18246.4 2098.5 2175.3 2198.8 2267.5 2272.1 132495.3 138386.0 138760.3 138942.3 138980.0 13344.7 14125.9 14249.2 14366.9 14456.8 21759.1 21840.0 21911.5 21920.1 22003.4 8996.5 9067.7 9150.8 9211.4 9279.7 8215.5 8272.4 8389.2 8390.8 8396.7 8425.5 8564.8 8608.5 8753.5 8767.6 2394.5 2429.5 2453.9 2548.3 2552.3 940.9 945.1 958.1 964.7 994.2 18438.0 18647.2 18729.5 18865.1 18873.6 970.2 983.5 988.0 1026.9 1047.4 9981.7 10065.2 10110.9 10217.5 10316.7 493263.0 531994.0 548972.0 550030.0 572331.0 120323.0 125884.4 126016.4 127297.0 127457.0 1245.1 1249.3 1287.8 1297.3 1297.7 18730.4 19743.7 19922.8 20076.6 20131.6 53516.5 54929.2 56064.2 57080.4 58453.7 68563.0 68882.0 68971.0 71197.0 75434.6 13993.9 14434.3 14448.3 14448.5 14505.3 21078.6 22153.7 22368.8 22580.7 22865.1 15021.6 17887.2 17955.5 17995.2 18045.9 15811.0 15913.4 15971.1 16700.9 16745.7 17869.8 18442.3 18507.1 18526.2 19318.8 14194.7 14383.6 14493.5 14552.9 14578.0 17690.6 17886.6 18043.0 18086.6 18283.9 10:13:13 INFO - PID 16020 | 10:13:13 INFO - PID 16020 | [#3] mootools.html Cycles:180 Average:8523.25 Median:2505.54 stddev:5872.83 (234.4%) stddev-sans-first:5864.42 10:13:13 INFO - PID 16020 | Values: 1327.9 1375.2 1382.1 1396.3 1401.4 13292.7 13776.1 13834.4 13901.7 13927.2 13645.9 14003.6 14326.8 14442.9 14468.1 2504.8 2566.9 2586.3 2588.8 2590.4 2484.7 2538.9 2542.0 2545.9 2549.3 2371.2 2473.2 2506.3 2511.9 2519.4 2510.5 2532.6 2533.1 2538.1 2538.2 2331.2 2346.8 2348.3 2348.7 2350.2 12960.9 12974.3 13080.9 13082.7 13127.7 6682.9 6774.4 7070.8 7079.3 7242.5 4788.5 4814.8 4841.4 4872.4 4895.0 1788.3 1830.4 1840.9 1880.4 1899.7 22327.6 22740.7 22803.9 22853.4 23003.9 6205.5 6406.4 6440.5 6558.8 6610.1 17588.4 17604.6 17628.4 17716.9 17750.9 7289.7 7409.4 7456.5 7460.3 7460.9 6579.8 6627.6 6703.4 6823.2 6900.0 5026.8 5251.2 5276.3 5328.5 5407.5 1565.4 1622.7 1628.8 1637.4 1640.9 883.2 883.3 908.5 917.4 927.4 7286.7 7292.9 7306.3 7319.9 7341.0 633.3 699.4 704.3 704.4 710.7 4982.8 5533.8 5622.5 5800.7 5883.4 18701.3 18788.4 18863.2 18918.3 19038.4 18521.3 18702.1 18708.9 18859.9 18865.2 10324.6 10403.4 10446.9 10571.9 10673.8 7818.6 8207.0 8321.0 8425.5 8538.9 15677.6 16109.8 16505.4 16838.5 16843.0 16035.5 16526.8 17115.1 17223.7 17349.2 10403.2 10629.9 10666.5 10697.3 10702.4 12257.9 12386.8 12415.2 12489.3 12500.1 9780.0 10078.2 10278.6 10540.5 10620.3 9654.9 9973.4 10096.6 10113.7 10300.4 9364.1 9478.7 9496.1 9507.7 9510.1 6071.4 6808.7 6917.1 7046.6 7091.0 6743.4 6811.0 6901.6 6973.3 6988.0 10:13:13 INFO - PID 16020 | 10:13:13 INFO - PID 16020 | [#4] prototype.html Cycles:180 Average:5375.77 Median:5893.19 stddev:1518.37 (25.8%) stddev-sans-first:1477.66 10:13:13 INFO - PID 16020 | Values: 488.9 495.6 510.1 510.9 514.3 6025.5 6040.6 6138.0 6162.6 6235.5 5809.2 5874.9 5903.6 5944.4 6012.6 4836.9 4873.8 4898.3 4908.5 4909.4 4869.6 4879.2 4885.1 4895.6 4911.7 4703.9 4795.0 4833.7 4844.4 4856.4 4565.6 4739.9 4798.7 4813.2 4820.3 4248.4 4265.7 4279.0 4307.4 4327.3 5831.0 5854.0 5882.7 5940.2 5940.7 5474.7 5490.4 5493.3 5509.6 5536.6 6315.8 6346.0 6346.7 6400.9 6406.8 4711.3 4732.1 4732.5 4741.3 4769.7 6685.1 6712.2 6716.6 6728.2 6785.9 4820.3 4835.3 4858.5 4903.6 4903.6 4877.8 4908.5 4933.1 4939.1 4962.3 5458.2 5506.7 5515.7 5521.5 5578.2 6356.0 6427.7 6431.9 6455.2 6466.8 1692.5 1708.1 1727.5 1734.5 1737.1 1113.5 1180.8 1184.1 1194.5 1204.7 5556.9 5560.7 5620.7 5643.2 5740.2 6424.5 6520.8 6539.4 6550.2 6626.5 5633.6 5636.7 5668.1 5706.6 5712.5 3460.6 3483.5 3494.5 3509.0 3526.2 6296.5 6366.1 6398.6 6410.6 6445.3 6655.2 6665.7 6713.1 6779.3 6785.0 6350.5 6427.2 6438.1 6441.2 6481.9 6491.6 6498.3 6583.9 6590.8 6634.5 6534.5 6542.8 6550.7 6553.2 6598.6 6363.3 6409.9 6419.1 6447.8 6482.8 4809.7 4813.7 4852.5 4853.9 4885.5 6606.7 6609.6 6620.4 6631.9 6665.3 6375.9 6415.2 6445.3 6473.6 6496.4 6408.0 6413.2 6420.8 6439.8 6483.5 6207.3 6305.7 6311.4 6324.7 6344.4 6354.6 6376.1 6383.4 6387.7 6417.9 5664.6 6046.3 6237.2 6268.2 6361.8 10:13:13 INFO - PID 16020 | 10:13:13 INFO - PID 16020 | [#5] yui.html Cycles:180 Average:23488.68 Median:19254.62 stddev:31812.19 (165.2%) stddev-sans-first:31856.19 10:13:13 INFO - PID 16020 | Values: 894.0 903.3 911.1 914.0 920.4 18710.2 18972.5 19411.7 19425.9 20942.3 19916.6 21095.9 21140.4 21147.9 21331.7 1006.3 1016.9 1026.1 1029.8 1029.9 998.5 1041.5 1051.2 1053.3 1055.2 1029.7 1030.9 1034.0 1038.0 1094.5 1000.1 1033.9 1036.2 1036.9 1097.2 774.9 775.5 787.2 790.4 792.4 19019.2 19020.5 19187.8 19247.3 19261.9 19425.5 20588.1 20647.6 20722.8 20971.2 15509.9 16231.5 16254.0 16320.4 16350.3 3549.5 3575.6 3583.6 3584.9 3588.3 115914.0 118445.4 120144.0 120710.0 124193.4 15696.3 15944.8 16052.8 16366.3 16563.8 28833.0 28911.6 28990.6 29883.3 30075.4 8211.0 8253.1 8286.4 8332.4 8347.9 3549.2 3623.9 3907.1 3968.3 3981.0 4789.1 4875.5 4891.8 4892.3 4910.6 976.8 1006.6 1025.9 1027.1 1028.6 646.7 668.3 680.0 680.4 708.1 10989.7 13177.3 13749.5 13788.0 13819.3 688.4 691.8 706.4 720.7 720.9 5340.9 5526.8 5538.3 5600.9 5708.7 106101.0 108992.5 113132.0 114698.0 115200.0 93776.5 96235.5 98743.0 99128.0 103513.0 81468.6 86034.3 86181.6 88282.6 88863.7 10594.5 10683.8 10809.6 10846.9 10943.6 35956.6 36106.8 38205.8 38569.0 38934.4 68204.7 71124.6 72062.3 73498.0 75079.6 17576.9 17963.2 18107.2 18186.8 18311.6 18422.9 19383.2 19666.2 19891.5 19965.0 18523.9 18594.2 18915.7 18918.2 18977.1 17827.3 18853.2 19023.7 19065.3 19156.7 17294.3 18005.6 18154.5 18309.5 18629.9 12469.2 12742.2 12847.4 13286.0 13900.7 13544.8 13904.4 14036.7 14131.9 14705.2 10:13:13 INFO - PID 16020 | -------- Summary: end -------- 10:13:13 INFO - PID 16020 | 10:13:14 INFO - TEST-INFO | 16020: exit 0 10:13:14 INFO - TEST-OK | dromaeo_css | took 1110713ms 10:13:14 INFO - TEST-START | kraken 10:13:14 INFO - Initialising browser for kraken test... 10:13:14 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53767/getInfo.html -profile /tmp/tmpjmnW_H/profile 10:13:14 INFO - TEST-INFO | started process 16418 (/builds/slave/test/build/application/firefox/firefox http://localhost:53767/getInfo.html) 10:13:20 INFO - TEST-INFO | 16418: exit 0 10:13:20 INFO - Browser initialized. 10:13:20 INFO - Running cycle 1/1 for kraken test... 10:13:20 INFO - TEST-INFO | started process 16669 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjmnW_H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 10:13:31 INFO - PID 16669 | RSS: Main: 173948928 10:13:31 INFO - PID 16669 | 10:13:34 INFO - PID 16669 | Cycle 1(1): loaded http://localhost:53767/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53767/tests/kraken/driver.html?testName=audio-beat-detection) 10:13:35 INFO - PID 16669 | RSS: Main: 177451008 10:13:35 INFO - PID 16669 | 10:13:37 INFO - PID 16669 | Cycle 1(1): loaded http://localhost:53767/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53767/tests/kraken/driver.html?testName=audio-dft) 10:13:38 INFO - PID 16669 | RSS: Main: 178413568 10:13:38 INFO - PID 16669 | 10:13:41 INFO - PID 16669 | Cycle 1(1): loaded http://localhost:53767/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53767/tests/kraken/driver.html?testName=audio-fft) 10:13:41 INFO - PID 16669 | RSS: Main: 172236800 10:13:41 INFO - PID 16669 | 10:13:44 INFO - PID 16669 | Cycle 1(1): loaded http://localhost:53767/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53767/tests/kraken/driver.html?testName=audio-oscillator) 10:13:44 INFO - PID 16669 | RSS: Main: 173113344 10:13:44 INFO - PID 16669 | 10:13:46 INFO - PID 16669 | Cycle 1(1): loaded http://localhost:53767/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53767/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:13:47 INFO - PID 16669 | RSS: Main: 173154304 10:13:47 INFO - PID 16669 | 10:13:50 INFO - PID 16669 | Cycle 1(1): loaded http://localhost:53767/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53767/tests/kraken/driver.html?testName=imaging-darkroom) 10:13:51 INFO - PID 16669 | RSS: Main: 176611328 10:13:51 INFO - PID 16669 | 10:13:54 INFO - PID 16669 | Cycle 1(1): loaded http://localhost:53767/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53767/tests/kraken/driver.html?testName=imaging-desaturate) 10:13:55 INFO - PID 16669 | RSS: Main: 177950720 10:13:55 INFO - PID 16669 | 10:13:57 INFO - PID 16669 | Cycle 1(1): loaded http://localhost:53767/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53767/tests/kraken/driver.html?testName=json-parse-financial) 10:13:58 INFO - PID 16669 | RSS: Main: 176177152 10:13:58 INFO - PID 16669 | 10:14:00 INFO - PID 16669 | Cycle 1(1): loaded http://localhost:53767/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53767/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:14:00 INFO - PID 16669 | RSS: Main: 176197632 10:14:00 INFO - PID 16669 | 10:14:02 INFO - PID 16669 | Cycle 1(1): loaded http://localhost:53767/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53767/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:14:03 INFO - PID 16669 | RSS: Main: 176562176 10:14:03 INFO - PID 16669 | 10:14:05 INFO - PID 16669 | Cycle 1(1): loaded http://localhost:53767/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53767/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:14:05 INFO - PID 16669 | RSS: Main: 176312320 10:14:05 INFO - PID 16669 | 10:14:08 INFO - PID 16669 | Cycle 1(1): loaded http://localhost:53767/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53767/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:14:09 INFO - PID 16669 | RSS: Main: 176226304 10:14:09 INFO - PID 16669 | 10:14:11 INFO - PID 16669 | Cycle 1(1): loaded http://localhost:53767/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53767/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:14:12 INFO - PID 16669 | RSS: Main: 176492544 10:14:12 INFO - PID 16669 | 10:14:14 INFO - PID 16669 | Cycle 1(1): loaded http://localhost:53767/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53767/tests/kraken/driver.html?testName=ai-astar) 10:14:14 INFO - PID 16669 | RSS: Main: 178036736 10:14:14 INFO - PID 16669 | 10:14:14 INFO - PID 16669 | __start_tp_report 10:14:14 INFO - PID 16669 | _x_x_mozilla_page_load 10:14:14 INFO - PID 16669 | _x_x_mozilla_page_load_details 10:14:14 INFO - PID 16669 | |i|pagename|runs| 10:14:14 INFO - PID 16669 | |0;ai-astar;99;111;102;110;108;111;110;100;108;109 10:14:14 INFO - PID 16669 | |1;audio-beat-detection;146;120;146;175;117;150;145;118;170;119 10:14:14 INFO - PID 16669 | |2;audio-dft;163;163;161;162;163;172;164;162;164;165 10:14:14 INFO - PID 16669 | |3;audio-fft;77;76;75;186;79;76;74;76;101;77 10:14:14 INFO - PID 16669 | |4;audio-oscillator;82;81;132;80;80;81;131;82;80;131 10:14:14 INFO - PID 16669 | |5;imaging-gaussian-blur;114;113;114;114;114;113;114;114;114;115 10:14:14 INFO - PID 16669 | |6;imaging-darkroom;150;150;150;149;151;150;150;150;151;150 10:14:14 INFO - PID 16669 | |7;imaging-desaturate;94;93;94;94;94;94;95;95;94;94 10:14:14 INFO - PID 16669 | |8;json-parse-financial;56;57;56;59;57;57;58;56;58;56 10:14:14 INFO - PID 16669 | |9;json-stringify-tinderbox;54;55;55;53;54;53;54;54;54;54 10:14:14 INFO - PID 16669 | |10;stanford-crypto-aes;86;76;77;77;82;76;77;77;86;78 10:14:14 INFO - PID 16669 | |11;stanford-crypto-ccm;135;128;130;129;122;129;129;125;127;166 10:14:14 INFO - PID 16669 | |12;stanford-crypto-pbkdf2;181;183;176;181;182;177;177;175;184;179 10:14:14 INFO - PID 16669 | |13;stanford-crypto-sha256-iterative;71;68;71;71;69;68;76;68;67;67 10:14:14 INFO - PID 16669 | __end_tp_report 10:14:14 INFO - PID 16669 | __start_cc_report 10:14:14 INFO - PID 16669 | _x_x_mozilla_cycle_collect,978 10:14:14 INFO - PID 16669 | __end_cc_report 10:14:14 INFO - PID 16669 | __startTimestamp1500398054604__endTimestamp 10:14:14 INFO - PID 16669 | 10:14:14 INFO - PID 16669 | ------- Summary: start ------- 10:14:14 INFO - PID 16669 | Number of tests: 14 10:14:14 INFO - PID 16669 | 10:14:14 INFO - PID 16669 | [#0] ai-astar Cycles:10 Average:106.80 Median:110.00 stddev:4.64 (4.2%) stddev-sans-first:3.97 10:14:14 INFO - PID 16669 | Values: 99.0 111.0 102.0 110.0 108.0 111.0 110.0 100.0 108.0 109.0 10:14:14 INFO - PID 16669 | 10:14:14 INFO - PID 16669 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:146.00 stddev:21.53 (14.7%) stddev-sans-first:22.75 10:14:14 INFO - PID 16669 | Values: 146.0 120.0 146.0 175.0 117.0 150.0 145.0 118.0 170.0 119.0 10:14:14 INFO - PID 16669 | 10:14:14 INFO - PID 16669 | [#2] audio-dft Cycles:10 Average:163.90 Median:163.50 stddev:3.07 (1.9%) stddev-sans-first:3.24 10:14:14 INFO - PID 16669 | Values: 163.0 163.0 161.0 162.0 163.0 172.0 164.0 162.0 164.0 165.0 10:14:14 INFO - PID 16669 | 10:14:14 INFO - PID 16669 | [#3] audio-fft Cycles:10 Average:89.70 Median:76.00 stddev:34.74 (45.7%) stddev-sans-first:36.55 10:14:14 INFO - PID 16669 | Values: 77.0 76.0 75.0 186.0 79.0 76.0 74.0 76.0 101.0 77.0 10:14:14 INFO - PID 16669 | 10:14:14 INFO - PID 16669 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:80.50 stddev:24.39 (30.3%) stddev-sans-first:25.34 10:14:14 INFO - PID 16669 | Values: 82.0 81.0 132.0 80.0 80.0 81.0 131.0 82.0 80.0 131.0 10:14:14 INFO - PID 16669 | 10:14:14 INFO - PID 16669 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 10:14:14 INFO - PID 16669 | Values: 114.0 113.0 114.0 114.0 114.0 113.0 114.0 114.0 114.0 115.0 10:14:14 INFO - PID 16669 | 10:14:14 INFO - PID 16669 | [#6] imaging-darkroom Cycles:10 Average:150.10 Median:150.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 10:14:14 INFO - PID 16669 | Values: 150.0 150.0 150.0 149.0 151.0 150.0 150.0 150.0 151.0 150.0 10:14:14 INFO - PID 16669 | 10:14:14 INFO - PID 16669 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 10:14:14 INFO - PID 16669 | Values: 94.0 93.0 94.0 94.0 94.0 94.0 95.0 95.0 94.0 94.0 10:14:14 INFO - PID 16669 | 10:14:14 INFO - PID 16669 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.00 stddev:1.05 (1.8%) stddev-sans-first:1.05 10:14:14 INFO - PID 16669 | Values: 56.0 57.0 56.0 59.0 57.0 57.0 58.0 56.0 58.0 56.0 10:14:14 INFO - PID 16669 | 10:14:14 INFO - PID 16669 | [#9] json-stringify-tinderbox Cycles:10 Average:54.00 Median:54.00 stddev:0.67 (1.2%) stddev-sans-first:0.71 10:14:14 INFO - PID 16669 | Values: 54.0 55.0 55.0 53.0 54.0 53.0 54.0 54.0 54.0 54.0 10:14:14 INFO - PID 16669 | 10:14:14 INFO - PID 16669 | [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:77.50 stddev:3.97 (5.1%) stddev-sans-first:3.36 10:14:14 INFO - PID 16669 | Values: 86.0 76.0 77.0 77.0 82.0 76.0 77.0 77.0 86.0 78.0 10:14:14 INFO - PID 16669 | 10:14:14 INFO - PID 16669 | [#11] stanford-crypto-ccm Cycles:10 Average:132.00 Median:129.00 stddev:12.41 (9.6%) stddev-sans-first:13.11 10:14:14 INFO - PID 16669 | Values: 135.0 128.0 130.0 129.0 122.0 129.0 129.0 125.0 127.0 166.0 10:14:14 INFO - PID 16669 | 10:14:14 INFO - PID 16669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.50 Median:181.00 stddev:3.14 (1.7%) stddev-sans-first:3.28 10:14:14 INFO - PID 16669 | Values: 181.0 183.0 176.0 181.0 182.0 177.0 177.0 175.0 184.0 179.0 10:14:14 INFO - PID 16669 | 10:14:14 INFO - PID 16669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:70.00 stddev:2.76 (3.9%) stddev-sans-first:2.88 10:14:14 INFO - PID 16669 | Values: 71.0 68.0 71.0 71.0 69.0 68.0 76.0 68.0 67.0 67.0 10:14:14 INFO - PID 16669 | -------- Summary: end -------- 10:14:14 INFO - PID 16669 | 10:14:15 INFO - PID 16669 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 10:14:15 INFO - TEST-INFO | 16669: exit 0 10:14:15 INFO - TEST-OK | kraken | took 61374ms 10:14:15 INFO - SUITE-END | took 1172s 10:14:15 INFO - Completed test suite (00:19:32) 10:14:15 INFO - javascript benchmark 10:14:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8230.577789103832, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3089.2276930767307, 3107.440660681077, 3135.2318681922916, 3156.0847354267257, 3181.809091454513, 6087.847803804907, 6101.664408457533, 6138.109974053759, 6167.969160154198, 6246.781362652308, 6233.532485063627, 6243.65659888706, 6244.157038799761, 6250.031245156996, 6261.436470717635, 5608.495235428811, 5685.033544297469, 5689.288838895138, 5697.0599851024535, 5704.914426283609, 5665.405132461094, 5679.4036626154275, 5681.062624666925, 5694.402087780785, 5730.111832665943, 5655.066913959192, 5724.971375143118, 5730.7707691692285, 5734.885302293952, 5737.885242295151, 5710.600257981943, 5719.14212868069, 5732.22614946981, 5733.082706766918, 5735.655860648374, 5621.831345059655, 5632.662040277555, 5645.802396916089, 5655.745491452894, 5732.971335143297, 6151.569390142692, 6186.474149697281, 6219.533534984895, 6250.468710159642, 6281.7173227204885, 6586.176727909012, 6606.273309935903, 6651.467882569383, 6663.533552651316, 6709.966450167718, 6521.93478065218, 6634.900476492857, 6638.535302528825, 6640.468762498988, 6641.535092543524, 6145.385461453863, 6172.969135154295, 6179.752809887644, 6187.535934804908, 6188.10272510488, 6799.456043516556, 6814.557053791586, 6821.658917054127, 6890.689918953659, 6905.033295338657, 6415.518836087312, 6479.8056058318325, 6488.448481878999, 6488.708008139645, 6579.605223686594, 6487.610743355413, 6619.139511863524, 6658.6337751424135, 6665.266820649725, 6713.395794378529, 6481.4166724994975, 6509.967450162813, 6512.088307636935, 6539.378759017885, 6551.148350714377, 5608.369301747189, 6543.738250470023, 6549.377809108127, 6635.734570617218, 6654.134962454947, 5252.080885844962, 5282.6302158849285, 5346.385165705916, 5356.223347614574, 5367.597430192751, 4468.754218517947, 4537.069900670354, 4545.499995000548, 4546.04533048057, 4568.634509239253, 5784.971075144597, 5793.188953546507, 5795.072788353843, 5813.157092221604, 5817.156512305692, 6707.0610114584, 6710.563813352116, 6712.53012289135, 6713.295104014008, 6723.865522689519, 5716.79991200306, 5790.334111577223, 5796.62321949072, 5806.854828629318, 5807.94192058074, 5811.88376232473, 5827.446392592697, 5829.679367634821, 5843.678597677169, 5847.0, 5497.39528651848, 6406.423421892052, 6436.581622194542, 6463.482921366373, 6504.15445992018, 6650.301718319507, 6687.197536295578, 6722.899156512656, 6752.797416077526, 6758.324167583202, 6424.13274207976, 6432.324605916311, 6504.707288172138, 6533.803985880431, 6540.476761858945, 6017.879642407215, 6417.743290268524, 6492.5455218134275, 6516.934830651633, 6572.737090516517, 6446.871062578628, 6672.266050734513, 6730.899036514554, 6735.797926062226, 6779.491538134561, 6466.288708241996, 6473.158489396348, 6541.574797638138, 6548.3124271950755, 6557.704903279268, 6447.258565264867, 6465.967670161619, 6531.9020214695865, 6548.8362790930605, 6558.114654521769, 6292.182016337847, 6371.267304260069, 6407.327230640715, 6410.134631824831, 6489.1888513935755, 6296.622202667671, 6328.525360598066, 6362.618242905441, 6455.289918108911, 6468.902966455413, 5299.33758280215, 6227.19046523949, 6246.625202487865, 6333.778317758856, 6360.459360954185], "value": 6035.977627015068, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.1560950273926, 749.4154559443635, 758.1054355860085, 785.030487348125, 794.4597673581962, 39374.0, 40178.59821401785, 40263.79868100663, 40356.79821600888, 40359.79820100899, 37031.0, 37234.882953511435, 37307.62692373076, 37367.439488407625, 37369.25261494768, 6568.244651865049, 6671.733130674767, 6675.399214070732, 6689.732410703566, 6715.630640314781, 6601.10885030522, 6684.097646817666, 6696.665166741644, 6697.062411262427, 6698.8995165072565, 6647.302033286508, 6652.434543063845, 6652.467802575807, 6669.699863506154, 6673.566218195826, 6606.240282367541, 6638.369354911276, 6638.502112341545, 6654.534182607195, 6663.2337281212795, 6034.306054803666, 6050.27396712393, 6051.909221361639, 6075.48358389533, 6085.908711369333, 33180.170495737555, 33221.16947076318, 33230.0, 33392.0, 33458.49812252818, 3214.7267482264037, 3251.7723759336855, 3348.748843836722, 3395.5416018837386, 3428.5542879425766, 30290.69709302923, 30691.0, 30698.693013069806, 30709.692903070907, 30821.0, 4863.954249836267, 4869.123557759602, 4883.755812209375, 4890.682105663121, 4963.553280204799, 186692.0, 190494.09505904902, 193452.0, 194772.0, 194863.0, 41985.0, 42029.15941681149, 48158.5184148154, 48255.75872120687, 48389.27416088762, 51475.0, 51523.742381287855, 51571.0, 51574.226386604234, 51705.7414712924, 15613.609659758597, 15660.921695391678, 15788.447404340794, 15839.920800395925, 15861.444849430214, 12866.228026318451, 12912.935435322763, 12953.805692914613, 12957.870421295667, 12965.09244352905, 14021.859781402056, 14107.576772696835, 14233.928830355782, 14240.0, 14344.211068391338, 5699.088145896637, 5712.371639119693, 5719.599628025998, 5797.826065218051, 5838.620489668158, 993.8757655293089, 994.6667866264737, 994.9751256218508, 1019.1490105761624, 1019.9133073688671, 21207.0, 21961.23135690275, 22169.44576385571, 22201.44496387571, 22369.88815055947, 1072.5012869015945, 1106.8450416941635, 1106.9944650276698, 1107.9889201108047, 1120.439780109945, 11674.591389301504, 11675.88324116748, 11731.76536469266, 11846.052315814713, 11853.46659400329, 235814.0, 235947.0, 235993.0, 236680.0, 237376.0, 49752.25371619429, 50526.0, 50571.0, 50593.0, 50603.0, 1378.6622277541867, 1378.8827949624394, 1391.172252509755, 1399.5101714400082, 1444.3428240150706, 21205.469863253544, 22067.896605169997, 22295.442613934134, 22464.887675561517, 22492.0, 35391.0, 35413.822930886214, 35441.29117417586, 35497.0, 35651.46522802108, 192062.0, 192180.0, 192414.0, 193725.0, 194187.0, 29842.552361715025, 29960.400791984473, 30328.0, 33157.50263746094, 33359.83320083481, 38495.0, 42267.232375327345, 42364.36453453139, 42414.78792606017, 42506.14987700291, 27887.0, 33023.834880825445, 33626.159346015564, 34041.31917361589, 34139.82930085334, 32218.516722248718, 32675.673243268215, 32770.83614581912, 33075.50386744153, 33352.49971250482, 30150.84924575363, 30793.538096928114, 31179.0, 31577.21056973594, 31829.20426989344, 30529.847350763102, 31000.22499437532, 31637.8418107908, 32008.83995580007, 32182.0, 23582.882085589463, 23626.29121126369, 23658.171963981178, 23944.401389964692, 24387.878060609582], "value": 16260.938358667114, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.4450066684316, 565.9586361095584, 571.9885602287954, 577.3418303134426, 579.4437340153455, 21231.57536849264, 22201.333959981184, 22246.777532224674, 22289.44276393087, 22334.0, 18002.729959050626, 18680.439586812416, 18681.906590467064, 18692.25230990762, 21586.676199857015, 6129.478994285491, 6323.114763933053, 6342.0, 6361.745530178788, 6383.808485745411, 6155.107509411133, 6224.066390041485, 6245.219347581552, 6259.937400625981, 6267.62394256346, 6182.752689892399, 6228.096925945735, 6230.968845155768, 6234.937650623504, 6259.37406259375, 5805.071188609823, 5973.492253158487, 6176.166217560639, 6215.844603884894, 6225.937740622581, 5703.230063941356, 5773.769049238025, 5774.451427114417, 5776.480116789509, 5778.451047150514, 26509.0, 26947.730522694914, 26984.0, 26989.32526686829, 27009.459810803673, 1905.3807512558474, 1923.807619238079, 1929.3343796390222, 1929.6623090959026, 1932.719755635439, 16110.355585776671, 17764.378246761302, 18005.369812056517, 18192.63614727698, 18246.361377351863, 2098.5383215692523, 2175.3256490487843, 2198.791114844087, 2267.501149747053, 2272.125231785758, 132495.33752331274, 138386.0, 138760.30619846936, 138942.3052884729, 138980.0, 13344.666383340495, 14125.929370353082, 14249.216293103771, 14366.85633143676, 14456.783148252785, 21759.129634814748, 21840.0, 21911.452213694785, 21920.123195072338, 22003.449913752283, 8996.460212387146, 9067.68263110788, 9150.771230719152, 9211.40125891815, 9279.67521136757, 8215.507069575844, 8272.420930534925, 8389.244967952913, 8390.75073038918, 8396.664133434595, 8425.452345597516, 8564.785880353042, 8608.48349099056, 8753.518556479328, 8767.561621919007, 2394.485185685086, 2429.477662302613, 2453.926382208537, 2548.299217715143, 2552.2726023083333, 940.8917974433028, 945.1257586732245, 958.0946006024379, 964.6961207219704, 994.1798016636304, 18438.0, 18647.160877760532, 18729.531761706065, 18865.056747162587, 18873.622527549647, 970.2044323654677, 983.537737263485, 987.9654212102686, 1026.9229807764448, 1047.3977462958828, 9981.700548983543, 10065.194784417228, 10110.898891010997, 10217.540210690537, 10316.690499285034, 493263.0, 531994.0, 548972.0, 550030.0, 572331.0, 120323.0, 125884.37057814653, 126016.36991814982, 127297.0, 127457.0, 1245.1346314311465, 1249.256692268099, 1287.7682017236987, 1297.2864924291603, 1297.7209899871573, 18730.438086857415, 19743.703844442633, 19922.800771992675, 20076.598468030264, 20131.597368052266, 53516.46483535271, 54929.176062358296, 56064.15903761528, 57080.42919570751, 58453.70773146107, 68563.0, 68882.0, 68971.0, 71197.0, 75434.62282688552, 13993.860061399255, 14434.278286085864, 14448.349824258145, 14448.494302699355, 14505.347259373142, 21078.57842843104, 22153.667694984266, 22368.77631223667, 22580.661290080334, 22865.085396584615, 15021.624459388602, 17887.195076221342, 17955.461336159937, 17995.190216440555, 18045.909770451064, 15811.0, 15913.443029493912, 15971.121588312755, 16700.916495417445, 16745.665086698442, 17869.821301786815, 18442.26230950747, 18507.074646267363, 18526.166322515248, 19318.8068119317, 14194.716105677622, 14383.640408989859, 14493.492727754478, 14552.85447145515, 14578.0, 17690.64618707593, 17886.5528361792, 18043.0, 18086.638267234845, 18283.908580457544], "value": 11501.724466875736, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.9004074694396, 1375.1817668487247, 1382.1499777636752, 1396.343718452327, 1401.4464286606785, 13292.734145317088, 13776.104553204035, 13834.446622135116, 13901.652458688537, 13927.234002129879, 13645.863541364582, 14003.579892603213, 14326.785098223534, 14442.927785361086, 14468.131912085257, 2504.7620476054744, 2566.910158144465, 2586.3275548357374, 2588.7799537039373, 2590.4042070323835, 2484.7391023942496, 2538.9492210155786, 2541.9745802542016, 2545.9490810183784, 2549.260714392824, 2371.1700904683394, 2473.245660073677, 2506.323292710967, 2511.9120830770926, 2519.407939134302, 2510.460251046025, 2532.6201069839485, 2533.075427468979, 2538.0862889359814, 2538.1624064058815, 2331.219041621042, 2346.8122550196113, 2348.330725743155, 2348.6829278047417, 2350.21267875262, 12960.935195323964, 12974.286414247212, 13080.934595327057, 13082.738345233138, 13127.671808204872, 6682.899756503656, 6774.424173945171, 7070.823229419306, 7079.256678048834, 7242.529235599669, 4788.539495453885, 4814.807407703687, 4841.443234028062, 4872.415310162768, 4894.951050489521, 1788.2757483219186, 1830.4417152768401, 1840.9079546022645, 1880.4170707080848, 1899.67705490067, 22327.553448930932, 22740.65889011666, 22803.88598056999, 22853.42866428336, 23003.884980575156, 6205.472534834499, 6406.391392817675, 6440.516961227927, 6558.770443034471, 6610.140681711443, 17588.38440654571, 17604.559886002953, 17628.383006594708, 17716.91141544284, 17750.91124544369, 7289.744858929912, 7409.407247420193, 7456.515326503868, 7460.29127232912, 7460.85078298431, 6579.835504112435, 6627.569208001464, 6703.430208432241, 6823.215330237086, 6900.0, 5026.773795179226, 5251.238570407269, 5276.287701160371, 5328.547073498718, 5407.540359069444, 1565.4051460444957, 1622.7160246956971, 1628.788257526514, 1637.4269005847857, 1640.9097499637635, 883.1918794303269, 883.3066043156092, 908.4912449028433, 917.3532659475054, 927.4342650982895, 7286.7085316586745, 7292.927070729331, 7306.342429181399, 7319.853602927911, 7340.972263883061, 633.3349982518338, 699.3635791429774, 704.3344039882364, 704.3519961635205, 710.6659869861261, 4982.77577509013, 5533.833984980457, 5622.522085622686, 5800.738966746507, 5883.441073098049, 18701.34545290908, 18788.436346909613, 18863.151158197914, 18918.3378581749, 19038.428847134608, 18521.259149633865, 18702.064896755106, 18708.906455467637, 18859.905700471685, 18865.15106820169, 10324.587016519405, 10403.427811470441, 10446.895531044744, 10571.947140264403, 10673.786524269472, 7818.569978651229, 8207.0, 8321.0, 8425.536595487061, 8538.871916921127, 15677.608059798597, 16109.758353624939, 16505.422310219084, 16838.494845154666, 16843.0, 16035.518934431986, 16526.75209871877, 17115.144242787563, 17223.7416438756, 17349.21928513245, 10403.21975851799, 10629.89370106289, 10666.46667666629, 10697.304675196086, 10702.411367374869, 12257.938710306391, 12386.81419778686, 12415.193012454161, 12489.250644961332, 12500.124991250525, 9780.021997800448, 10078.244131690006, 10278.640247591298, 10540.472976351306, 10620.309679871092, 9654.937956824604, 9973.401595904268, 10096.646617368651, 10113.747156321151, 10300.381977081399, 9364.110409511086, 9478.668246611336, 9496.14534691881, 9507.714768556954, 9510.09654082861, 6071.423214794588, 6808.659567021728, 6917.13535808024, 7046.577205367695, 7091.0, 6743.35938085881, 6811.0, 6901.585904845725, 6973.267806880204, 6988.0], "value": 6178.396705771525, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.8504852700374, 495.6146192043529, 510.0589412533874, 510.93613298337704, 514.290279414408, 6025.548083893709, 6040.607360521569, 6138.0, 6162.568620196588, 6235.532335074865, 5809.215755872956, 5874.9412505874925, 5903.645781253128, 5944.4352786485215, 6012.5791194616395, 4836.879078023042, 4873.829415970442, 4898.265260210961, 4908.460069392364, 4909.386326709161, 4869.563478773753, 4879.219324908016, 4885.096257192413, 4895.632827537949, 4911.729854857981, 4703.882402939937, 4794.993051459198, 4833.661643684926, 4844.442889067767, 4856.39295088114, 4565.644251475163, 4739.933514959124, 4798.744590042675, 4813.181759100961, 4820.276958456225, 4248.4052232687445, 4265.680073994432, 4279.0, 4307.418498502693, 4327.285997810358, 5830.970845145747, 5853.970730146365, 5882.735276912549, 5940.227770389865, 5940.732667029994, 5474.671519708831, 5490.368607610136, 5493.313335833021, 5509.630858730744, 5536.640118392332, 6315.778947736853, 6345.968270158666, 6346.746130154788, 6400.871982560369, 6406.839829004266, 4711.293306004092, 4732.100900828831, 4732.455767586737, 4741.2651039089, 4769.713817170981, 6685.13093297873, 6712.22809376923, 6716.6305853178055, 6728.192616886005, 6785.930291261114, 4820.325154478376, 4835.347228124158, 4858.518151963614, 4903.583195428358, 4903.632227582945, 4877.756112194447, 4908.460069392364, 4933.112039832865, 4939.1109600271575, 4962.330085438492, 5458.181272809111, 5506.669599824023, 5515.696636685021, 5521.503064724194, 5578.24693666358, 6355.968220158869, 6427.714457108597, 6431.935680643133, 6455.225372955276, 6466.764269403454, 1692.483792443292, 1708.13739061773, 1727.455851406828, 1734.4536471011843, 1737.0793479455908, 1113.504490501735, 1180.7719971229997, 1184.135581025856, 1194.4684615346087, 1204.6988252936767, 5556.861078473071, 5560.665440294381, 5620.74706638194, 5643.1535269709875, 5740.225069615549, 6424.486041116699, 6520.804375868732, 6539.449614650291, 6550.246721626979, 6626.503012274099, 5633.633813802172, 5636.746346414421, 5668.149777533403, 5706.600537962386, 5712.485876271073, 3460.602030766444, 3483.494893240517, 3494.5107684924133, 3508.999805166597, 3526.2242306692488, 6296.464800491918, 6366.10342294255, 6398.5521013528605, 6410.583312084699, 6445.2587952386075, 6655.234648015444, 6665.70047826329, 6713.12729345182, 6779.322067793181, 6784.9629470465, 6350.523710721714, 6427.22873255203, 6438.130852334969, 6441.227052753699, 6481.865673507117, 6491.610503369813, 6498.285188629342, 6583.8807402742605, 6590.7693230736695, 6634.512057978258, 6534.509911756542, 6542.8364290893105, 6550.705218265285, 6553.180852393451, 6598.571092878948, 6363.331850155852, 6409.871802563829, 6419.133416988647, 6447.774327898501, 6482.805515834532, 4809.701380627141, 4813.687110337817, 4852.490488498657, 4853.878653033702, 4885.4870238624435, 6606.669666516751, 6609.636469994004, 6620.3710647488415, 6631.933680663132, 6665.333466653296, 6375.904361434489, 6415.230172379344, 6445.25879523842, 6473.579217350857, 6496.382843629847, 6408.045546108482, 6413.198350206224, 6420.844248035399, 6439.839004024937, 6483.546151769519, 6207.286162091417, 6305.747770089145, 6311.368863113652, 6324.6521441321165, 6344.4290013897125, 6354.586951848115, 6376.139221205078, 6383.425491705769, 6387.712552935222, 6417.871642567029, 5664.603477756517, 6046.334903160737, 6237.1891654084675, 6268.216472940882, 6361.77733779334], "value": 4929.479051808861, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [893.9642414303428, 903.2773780975218, 911.1025639744852, 913.9946059334733, 920.406337912047, 18710.15804288807, 18972.525686857836, 19411.708824367644, 19425.90287048563, 20942.26702065428, 19916.601667966668, 21095.894520527414, 21140.365789026317, 21147.894260528676, 21331.68002479964, 1006.3408467453829, 1016.8576399304085, 1026.0765311219925, 1029.8043371759343, 1029.8609687692178, 998.4608311511811, 1041.5052849896315, 1051.1958351860794, 1053.2837670384135, 1055.171690223175, 1029.7425643589104, 1030.8814486334054, 1033.9689809305733, 1038.0346277961494, 1094.534822700353, 1000.1049061090283, 1033.9172866170688, 1036.2280101324525, 1036.942318834788, 1097.1716354152643, 774.8760198368234, 775.4610545670761, 787.2403130978576, 790.4032455496064, 792.3938187286731, 19019.239230430765, 19020.5244868878, 19187.80812191874, 19247.326343577908, 19261.9036904816, 19425.51436214092, 20588.073536690885, 20647.5870482591, 20722.792772072236, 20971.16115355384, 15509.922450387676, 16231.51305460838, 16254.024758514526, 16320.428784992588, 16350.345986160539, 3549.4853246279404, 3575.606683264865, 3583.552055993001, 3584.9283014339567, 3588.318219538279, 115914.0, 118445.40777296145, 120144.0, 120710.0, 124193.37903310516, 15696.293666785023, 15944.840551594336, 16052.75920861188, 16366.263518141712, 16563.757718171186, 28833.0, 28911.56632650512, 28990.565141522893, 29883.252918677208, 30075.398492030035, 8211.01467823865, 8253.050899146554, 8286.41995060352, 8332.416730828783, 8347.874781878276, 3549.201429678353, 3623.9094022649647, 3907.1404291055924, 3968.305546529346, 3981.0246489609654, 4789.090072886141, 4875.512448755097, 4891.755412229374, 4892.339534162914, 4910.607151427877, 976.8144052629978, 1006.6275478073013, 1025.8922813104666, 1027.091024443358, 1028.5782829040206, 646.7219095788762, 668.2949488289804, 679.9796006119725, 680.4454369688688, 708.125465050662, 10989.670309890716, 13177.341132943506, 13749.518766843112, 13788.0, 13819.309034548232, 688.4114082459525, 691.8339598496425, 706.445440329339, 720.6540860387043, 720.92069872314, 5340.851716880811, 5526.806561770319, 5538.307711536058, 5600.915986260209, 5708.686022268815, 106101.0, 108992.4550377243, 113132.0, 114698.0, 115200.0, 93776.51776265318, 96235.51882240544, 98743.0, 99128.0, 103513.0, 81468.59265703634, 86034.27931441336, 86181.56909215414, 88282.55858720666, 88863.66704499438, 10594.523246454082, 10683.839742404025, 10809.621663241749, 10846.945765271123, 10943.562257509611, 35956.64043359533, 36106.8194659025, 38205.80897095497, 38569.0, 38934.41598376083, 68204.6589767048, 71124.64437677778, 72062.27937720556, 73498.0, 75079.62460187664, 17576.91211543934, 17963.191656375235, 18107.185176667346, 18186.818131818512, 18311.633767324314, 18422.90788546049, 19383.224671013002, 19666.21335146578, 19891.502712432306, 19965.0, 18523.88856668604, 18594.163262652943, 18915.716264256323, 18918.243270269035, 18977.146028428477, 17827.286908523514, 18853.24587016504, 19023.714644280622, 19065.33271335552, 19156.71264931055, 17294.308227670754, 18005.63988720192, 18154.546136346697, 18309.54226144357, 18629.90685046566, 12469.189502682297, 12742.171758835646, 12847.421866015864, 13286.0, 13900.652483687989, 13544.796828047783, 13904.37430315618, 14036.69542004499, 14131.929340353232, 14705.191214483306], "value": 9041.129677705012, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1526.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 111.0, 102.0, 110.0, 108.0, 111.0, 110.0, 100.0, 108.0, 109.0], "value": 106.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 120.0, 146.0, 175.0, 117.0, 150.0, 145.0, 118.0, 170.0, 119.0], "value": 140.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 161.0, 162.0, 163.0, 172.0, 164.0, 162.0, 164.0, 165.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 75.0, 186.0, 79.0, 76.0, 74.0, 76.0, 101.0, 77.0], "value": 89.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 132.0, 80.0, 80.0, 81.0, 131.0, 82.0, 80.0, 131.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 150.0, 149.0, 151.0, 150.0, 150.0, 150.0, 151.0, 150.0], "value": 150.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 56.0, 59.0, 57.0, 57.0, 58.0, 56.0, 58.0, 56.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 55.0, 53.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": [86.0, 76.0, 77.0, 77.0, 82.0, 76.0, 77.0, 77.0, 86.0, 78.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 128.0, 130.0, 129.0, 122.0, 129.0, 129.0, 125.0, 127.0, 166.0], "value": 132.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 183.0, 176.0, 181.0, 182.0, 177.0, 177.0, 175.0, 184.0, 179.0], "value": 179.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 71.0, 71.0, 69.0, 68.0, 76.0, 68.0, 67.0, 67.0], "value": 69.6, "unit": "score"}]}]} 10:14:15 INFO - Return code: 0 10:14:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:14:15 INFO - # TBPL SUCCESS # 10:14:15 INFO - Running post-action listener: _package_coverage_data 10:14:15 INFO - Running post-action listener: _resource_record_post_action 10:14:15 INFO - [mozharness: 2017-07-18 17:14:15.923351Z] Finished run-tests step (success) 10:14:15 INFO - Running post-run listener: _resource_record_post_run 10:14:16 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 436211712; Read time: 16; Write time: 441692 10:14:16 INFO - TinderboxPrint: CPU usage
13.1% 10:14:16 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 10:14:16 INFO - TinderboxPrint: I/O write bytes / time
436,211,712 / 441,692 10:14:16 INFO - TinderboxPrint: CPU idle
8,253.4 (86.9%) 10:14:16 INFO - TinderboxPrint: CPU user
1,195.7 (12.6%) 10:14:16 INFO - TinderboxPrint: Swap in / out
0 / 0 10:14:16 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 106602496; Read time: 0; Write time: 92352 10:14:16 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:14:16 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 329609216; Read time: 0; Write time: 349340 10:14:16 INFO - Running post-run listener: _upload_blobber_files 10:14:16 INFO - Blob upload gear active. 10:14:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:14:16 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 10:14:16 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'] 10:14:16 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 10:14:16 INFO - Traceback (most recent call last): 10:14:16 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 10:14:16 INFO - main() 10:14:16 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 10:14:16 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 10:14:16 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 10:14:16 INFO - return set(response.json().get('whitelist', [])) 10:14:16 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 10:14:16 INFO - return json.loads(self.text or self.content, **kwargs) 10:14:16 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/__init__.py", line 488, in loads 10:14:16 INFO - return _default_decoder.decode(s) 10:14:16 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 370, in decode 10:14:16 INFO - obj, end = self.raw_decode(s) 10:14:16 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 389, in raw_decode 10:14:16 INFO - return self.scan_once(s, idx=_w(s, idx).end()) 10:14:16 INFO - simplejson.scanner.JSONDecodeError: Expecting value: line 1 column 1 (char 0) 10:14:16 ERROR - Return code: 1 10:14:16 INFO - Setting buildbot property blobber_files to {} 10:14:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:14:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:14:16 INFO - Contents: 10:14:16 INFO - blobber_files:{} 10:14:16 INFO - Running post-run listener: copy_logs_to_upload_dir 10:14:16 INFO - Copying logs to upload dir... 10:14:16 INFO - mkdir: /builds/slave/test/build/upload/logs 10:14:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.701470 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-07-18 10:14:16.339931) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-18 10:14:16.347255) ========= 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=fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011266 build_url: 'https://queue.taskcluster.net/v1/task/IUEOraFoSpqpTYcx7zr34w/artifacts/public/build/target.tar.bz2' blobber_files: '{}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-18 10:14:16.376029) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 10:14:16.376446) ========= 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=fa44defc139c4ba8574fe040000001db-1500396040.540435-726418394 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004481 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 10:14:16.429828) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-18 10:14:16.430204) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-18 10:14:16.430584) ========= ========= Total master_lag: 0.20 =========