builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-058 starttime: 1502485740.76 results: success (0) revision: 80ff3f300e05f38f96c385b03d1973a966a2bd35 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-11 14:09:00.760642) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-11 14:09:00.761125) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-11 14:09:00.761412) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-11 14:09:00.777679) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 14:09:00.778057) ========= 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=0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-11 14:09:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.4M=0s 2017-08-11 14:09:01 (50.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122379 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 14:09:00.916910) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 14:09:00.917276) ========= 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=0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016958 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 14:09:00.969618) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-11 14:09:00.970015) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 80ff3f300e05f38f96c385b03d1973a966a2bd35 --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 80ff3f300e05f38f96c385b03d1973a966a2bd35 --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=0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-11 14:09:01,753 truncating revision to first 12 chars 2017-08-11 14:09:01,753 Setting DEBUG logging. 2017-08-11 14:09:01,753 attempt 1/10 2017-08-11 14:09:01,753 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/80ff3f300e05?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-11 14:09:02,868 unpacking tar archive at: mozilla-central-80ff3f300e05/testing/mozharness/ program finished with exit code 0 elapsedTime=1.453097 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-11 14:09:02.439004) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-11 14:09:02.439374) ========= script_repo_revision: 80ff3f300e05f38f96c385b03d1973a966a2bd35 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-11 14:09:02.439791) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-11 14:09:02.440065) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-11 14:09:02.449263) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-08-11 14:09:02.449655) ========= /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=0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:09:03 INFO - MultiFileLogger online at 20170811 14:09:03 in /builds/slave/test 14:09:03 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 14:09:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:09:03 INFO - {'append_to_log': False, 14:09:03 INFO - 'base_work_dir': '/builds/slave/test', 14:09:03 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 14:09:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:09:03 INFO - 'branch': 'Firefox-Non-PGO', 14:09:03 INFO - 'buildbot_json_path': 'buildprops.json', 14:09:03 INFO - 'code_coverage': False, 14:09:03 INFO - 'config_files': ('talos/linux_config.py',), 14:09:03 INFO - 'default_actions': ('clobber', 14:09:03 INFO - 'read-buildbot-config', 14:09:03 INFO - 'download-and-extract', 14:09:03 INFO - 'populate-webroot', 14:09:03 INFO - 'create-virtualenv', 14:09:03 INFO - 'install', 14:09:03 INFO - 'setup-mitmproxy', 14:09:03 INFO - 'run-tests'), 14:09:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:09:03 INFO - 'disable_ccov_upload': False, 14:09:03 INFO - 'download_minidump_stackwalk': True, 14:09:03 INFO - 'download_symbols': 'ondemand', 14:09:03 INFO - 'enable_stylo': False, 14:09:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:09:03 INFO - 'tooltool.py': '/tools/tooltool.py', 14:09:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:09:03 INFO - '/tools/misc-python/virtualenv.py')}, 14:09:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:09:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:09:03 INFO - 'gecko_profile': False, 14:09:03 INFO - 'gecko_profile_interval': 0, 14:09:03 INFO - 'installer_path': 'installer.exe', 14:09:03 INFO - 'log_level': 'info', 14:09:03 INFO - 'log_name': 'talos', 14:09:03 INFO - 'log_to_console': True, 14:09:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:09:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:09:03 INFO - 'opt_config_files': (), 14:09:03 INFO - 'pip_index': False, 14:09:03 INFO - 'suite': 'dromaeojs-e10s', 14:09:03 INFO - 'system_bits': '32', 14:09:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:09:03 INFO - 'title': 'talos-linux64-ix-058', 14:09:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:09:03 INFO - 'use_talos_json': True, 14:09:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:09:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:09:03 INFO - 'work_dir': 'build'} 14:09:03 INFO - [mozharness: 2017-08-11 21:09:03.340354Z] Running clobber step. 14:09:03 INFO - Running pre-action listener: _resource_record_pre_action 14:09:03 INFO - Running main action method: clobber 14:09:03 INFO - rmtree: /builds/slave/test/build 14:09:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:09:04 INFO - Running post-action listener: _resource_record_post_action 14:09:04 INFO - [mozharness: 2017-08-11 21:09:04.385558Z] Finished clobber step (success) 14:09:04 INFO - [mozharness: 2017-08-11 21:09:04.385663Z] Running read-buildbot-config step. 14:09:04 INFO - Running pre-action listener: _resource_record_pre_action 14:09:04 INFO - Running main action method: read_buildbot_config 14:09:04 INFO - Using buildbot properties: 14:09:04 INFO - { 14:09:04 INFO - "project": "", 14:09:04 INFO - "product": "firefox", 14:09:04 INFO - "who": "kwierso@gmail.com", 14:09:04 INFO - "installer_path": "public/build/target.tar.bz2", 14:09:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 14:09:04 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 14:09:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 14:09:04 INFO - "stage_platform": "linux64", 14:09:04 INFO - "basedir": "/builds/slave/test", 14:09:04 INFO - "buildnumber": 768, 14:09:04 INFO - "platform": "ubuntu64_hw", 14:09:04 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:09:04 INFO - "slavebuilddir": "test", 14:09:04 INFO - "taskId": "Zv_Gd139Q9iwpYUXbgoIYg", 14:09:04 INFO - "branch": "mozilla-central", 14:09:04 INFO - "script_repo_revision": "production", 14:09:04 INFO - "revision": "80ff3f300e05f38f96c385b03d1973a966a2bd35", 14:09:04 INFO - "slavename": "talos-linux64-ix-058", 14:09:04 INFO - "repo_path": "mozilla-central" 14:09:04 INFO - } 14:09:04 INFO - Finding installer, test and symbols from parent task. 14:09:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zv_Gd139Q9iwpYUXbgoIYg'}, attempt #1 14:09:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zv_Gd139Q9iwpYUXbgoIYg'}, attempt #1 14:09:05 INFO - Task dependencies: ciIwbBSeQxOcIhzBZHfbuA 14:09:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA'}, attempt #1 14:09:05 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.tar.bz2 14:09:05 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.test_packages.json 14:09:05 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.crashreporter-symbols.zip 14:09:05 INFO - Running post-action listener: _resource_record_post_action 14:09:05 INFO - [mozharness: 2017-08-11 21:09:05.725530Z] Finished read-buildbot-config step (success) 14:09:05 INFO - [mozharness: 2017-08-11 21:09:05.725755Z] Running download-and-extract step. 14:09:05 INFO - Running pre-action listener: _resource_record_pre_action 14:09:05 INFO - Running main action method: download_and_extract 14:09:05 INFO - mkdir: /builds/slave/test/build/tests 14:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:05 INFO - trying https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.test_packages.json 14:09:05 INFO - Downloading https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:09:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:09:06 INFO - Downloaded 989 bytes. 14:09:06 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:09:06 INFO - Using the following test package requirements: 14:09:06 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 14:09:06 INFO - u'common': [u'target.common.tests.zip'], 14:09:06 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:09:06 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:09:06 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:09:06 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:09:06 INFO - u'mozbase': [u'target.common.tests.zip'], 14:09:06 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:09:06 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:09:06 INFO - u'web-platform': [u'target.common.tests.zip', 14:09:06 INFO - u'target.web-platform.tests.tar.gz'], 14:09:06 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:09:06 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 14:09:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.common.tests.zip 14:09:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:09:06 INFO - Fetch https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.common.tests.zip into memory 14:09:07 INFO - Content-Length response header: 54036779 14:09:07 INFO - Bytes received: 54036779 14:09:18 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:09:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.common.tests.zip 14:09:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:09:18 INFO - Fetch https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.common.tests.zip into memory 14:09:19 INFO - Content-Length response header: 54036779 14:09:19 INFO - Bytes received: 54036779 14:09:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.talos.tests.zip 14:09:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:09:30 INFO - Fetch https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.talos.tests.zip into memory 14:09:31 INFO - Content-Length response header: 14496050 14:09:31 INFO - Bytes received: 14496050 14:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:31 INFO - trying https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.tar.bz2 14:09:31 INFO - Downloading https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:09:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:09:33 INFO - Downloaded 61811921 bytes. 14:09:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.tar.bz2 14:09:33 INFO - mkdir: /builds/slave/test/properties 14:09:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:09:33 INFO - Writing to file /builds/slave/test/properties/build_url 14:09:33 INFO - Contents: 14:09:33 INFO - build_url:https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.tar.bz2 14:09:33 INFO - Running post-action listener: _resource_record_post_action 14:09:33 INFO - Running post-action listener: set_extra_try_arguments 14:09:33 INFO - [mozharness: 2017-08-11 21:09:33.017673Z] Finished download-and-extract step (success) 14:09:33 INFO - [mozharness: 2017-08-11 21:09:33.017772Z] Running populate-webroot step. 14:09:33 INFO - Running pre-action listener: _resource_record_pre_action 14:09:33 INFO - Running main action method: populate_webroot 14:09:33 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:09:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:09:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:09:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:09:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:09:33 INFO - 'tests': ['damp', 'tps']}, 14:09:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:09:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:09:33 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:09:33 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:09:33 INFO - 'other-e10s': {'tests': ['a11yr', 14:09:33 INFO - 'ts_paint', 14:09:33 INFO - 'tpaint', 14:09:33 INFO - 'sessionrestore', 14:09:33 INFO - 'sessionrestore_many_windows', 14:09:33 INFO - 'sessionrestore_no_auto_restore', 14:09:33 INFO - 'tabpaint']}, 14:09:33 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 14:09:33 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 14:09:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:09:33 INFO - 'tsvgr_opacity', 14:09:33 INFO - 'tart', 14:09:33 INFO - 'tscrollx', 14:09:33 INFO - 'tsvg_static']}, 14:09:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:09:33 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:09:33 INFO - 'talos_options': ['--mitmproxy', 14:09:33 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:09:33 INFO - '--firstNonBlankPaint'], 14:09:33 INFO - 'tests': ['tp6_google', 14:09:33 INFO - 'tp6_youtube', 14:09:33 INFO - 'tp6_amazon', 14:09:33 INFO - 'tp6_facebook']}, 14:09:33 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:09:33 INFO - 'talos_options': ['--stylo', 14:09:33 INFO - '--mitmproxy', 14:09:33 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:09:33 INFO - '--firstNonBlankPaint'], 14:09:33 INFO - 'tests': ['tp6_google', 14:09:33 INFO - 'tp6_youtube', 14:09:33 INFO - 'tp6_amazon', 14:09:33 INFO - 'tp6_facebook']}, 14:09:33 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:09:33 INFO - 'talos_options': ['--stylo', 14:09:33 INFO - '--stylo-threads=1', 14:09:33 INFO - '--mitmproxy', 14:09:33 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:09:33 INFO - '--firstNonBlankPaint'], 14:09:33 INFO - 'tests': ['tp6_google', 14:09:33 INFO - 'tp6_youtube', 14:09:33 INFO - 'tp6_amazon', 14:09:33 INFO - 'tp6_facebook']}, 14:09:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:09:33 INFO - 'talos_options': ['--xperf_path', 14:09:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:09:33 INFO - 'tests': ['tp5n']}}} 14:09:33 INFO - Running post-action listener: _resource_record_post_action 14:09:33 INFO - [mozharness: 2017-08-11 21:09:33.026236Z] Finished populate-webroot step (success) 14:09:33 INFO - [mozharness: 2017-08-11 21:09:33.026328Z] Running create-virtualenv step. 14:09:33 INFO - Running pre-action listener: _resource_record_pre_action 14:09:33 INFO - Running main action method: create_virtualenv 14:09:33 INFO - Creating virtualenv /builds/slave/test/build/venv 14:09:33 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 14:09:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:09:33 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 14:09:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:09:33 INFO - Using real prefix '/usr' 14:09:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:09:33 INFO - Installing distribute.............................................................................................................................................................................................done. 14:09:35 INFO - Installing pip.................done. 14:09:35 INFO - Return code: 0 14:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:09:35 INFO - Reading from file tmpfile_stdout 14:09:35 INFO - Output received: 14:09:35 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) 14:09:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:09:35 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')]} 14:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:35 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')]} 14:09:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:35 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 0x182e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b9cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9ff00>, '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 0x1b9aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b050>, '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': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', '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 14:09:35 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 14:09:35 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 14:09:35 INFO - Using env: {'DISPLAY': ':0', 14:09:35 INFO - 'HOME': '/home/cltbld', 14:09:35 INFO - 'LANG': 'en_US.UTF-8', 14:09:35 INFO - 'LANGUAGE': 'en_US:en', 14:09:35 INFO - 'LOGNAME': 'cltbld', 14:09:35 INFO - 'MAIL': '/var/mail/cltbld', 14:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:35 INFO - 'MOZ_NO_REMOTE': '1', 14:09:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:35 INFO - 'NO_EM_RESTART': '1', 14:09:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:35 INFO - 'PWD': '/builds/slave/test', 14:09:35 INFO - 'SHELL': '/bin/bash', 14:09:35 INFO - 'SHLVL': '1', 14:09:35 INFO - 'TERM': 'linux', 14:09:35 INFO - 'TMOUT': '86400', 14:09:35 INFO - 'USER': 'cltbld', 14:09:35 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', 14:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:35 INFO - '_': '/tools/buildbot/bin/python'} 14:09:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:36 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 14:09:36 INFO - Cleaning up... 14:09:36 INFO - Return code: 0 14:09:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:09:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x182e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b9cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9ff00>, '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 0x1b9aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b050>, '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': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', '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 14:09:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:09:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:09:36 INFO - Using env: {'DISPLAY': ':0', 14:09:36 INFO - 'HOME': '/home/cltbld', 14:09:36 INFO - 'LANG': 'en_US.UTF-8', 14:09:36 INFO - 'LANGUAGE': 'en_US:en', 14:09:36 INFO - 'LOGNAME': 'cltbld', 14:09:36 INFO - 'MAIL': '/var/mail/cltbld', 14:09:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:36 INFO - 'MOZ_NO_REMOTE': '1', 14:09:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:36 INFO - 'NO_EM_RESTART': '1', 14:09:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:36 INFO - 'PWD': '/builds/slave/test', 14:09:36 INFO - 'SHELL': '/bin/bash', 14:09:36 INFO - 'SHLVL': '1', 14:09:36 INFO - 'TERM': 'linux', 14:09:36 INFO - 'TMOUT': '86400', 14:09:36 INFO - 'USER': 'cltbld', 14:09:36 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', 14:09:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:36 INFO - '_': '/tools/buildbot/bin/python'} 14:09:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:36 INFO - Downloading/unpacking psutil>=3.1.1 14:09:36 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 14:09:36 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 14:09:36 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 14:09:36 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 14:09:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:09:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:09:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:09:37 INFO - Installing collected packages: psutil 14:09:37 INFO - Running setup.py install for psutil 14:09:37 INFO - building 'psutil._psutil_linux' extension 14:09:37 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 14:09:37 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 14:09:37 INFO - building 'psutil._psutil_posix' extension 14:09:37 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 14:09:37 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 14:09:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:09:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:09:37 INFO - Successfully installed psutil 14:09:37 INFO - Cleaning up... 14:09:38 INFO - Return code: 0 14:09:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x182e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b9cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9ff00>, '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 0x1b9aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b050>, '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': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', '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 14:09:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:09:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:09:38 INFO - Using env: {'DISPLAY': ':0', 14:09:38 INFO - 'HOME': '/home/cltbld', 14:09:38 INFO - 'LANG': 'en_US.UTF-8', 14:09:38 INFO - 'LANGUAGE': 'en_US:en', 14:09:38 INFO - 'LOGNAME': 'cltbld', 14:09:38 INFO - 'MAIL': '/var/mail/cltbld', 14:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:38 INFO - 'MOZ_NO_REMOTE': '1', 14:09:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:38 INFO - 'NO_EM_RESTART': '1', 14:09:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:38 INFO - 'PWD': '/builds/slave/test', 14:09:38 INFO - 'SHELL': '/bin/bash', 14:09:38 INFO - 'SHLVL': '1', 14:09:38 INFO - 'TERM': 'linux', 14:09:38 INFO - 'TMOUT': '86400', 14:09:38 INFO - 'USER': 'cltbld', 14:09:38 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', 14:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:38 INFO - '_': '/tools/buildbot/bin/python'} 14:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:09:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:09:39 INFO - Installing collected packages: mozsystemmonitor 14:09:39 INFO - Running setup.py install for mozsystemmonitor 14:09:39 INFO - Successfully installed mozsystemmonitor 14:09:39 INFO - Cleaning up... 14:09:39 INFO - Return code: 0 14:09:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:09:39 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')]} 14:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:39 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')]} 14:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:39 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 0x182e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b9cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9ff00>, '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 0x1b9aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b050>, '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': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', '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 14:09:39 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 14:09:39 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 14:09:39 INFO - Using env: {'DISPLAY': ':0', 14:09:39 INFO - 'HOME': '/home/cltbld', 14:09:39 INFO - 'LANG': 'en_US.UTF-8', 14:09:39 INFO - 'LANGUAGE': 'en_US:en', 14:09:39 INFO - 'LOGNAME': 'cltbld', 14:09:39 INFO - 'MAIL': '/var/mail/cltbld', 14:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:39 INFO - 'MOZ_NO_REMOTE': '1', 14:09:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:39 INFO - 'NO_EM_RESTART': '1', 14:09:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:39 INFO - 'PWD': '/builds/slave/test', 14:09:39 INFO - 'SHELL': '/bin/bash', 14:09:39 INFO - 'SHLVL': '1', 14:09:39 INFO - 'TERM': 'linux', 14:09:39 INFO - 'TMOUT': '86400', 14:09:39 INFO - 'USER': 'cltbld', 14:09:39 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', 14:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:39 INFO - '_': '/tools/buildbot/bin/python'} 14:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:39 INFO - Downloading/unpacking jsonschema==2.5.1 14:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:09:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:40 INFO - Downloading functools32-3.2.3-2.tar.gz 14:09:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:09:41 INFO - warning: no files found matching '*.txt' 14:09:41 INFO - no previously-included directories found matching 'build' 14:09:41 INFO - no previously-included directories found matching 'dist' 14:09:41 INFO - no previously-included directories found matching '.git*' 14:09:41 INFO - Installing collected packages: jsonschema, functools32 14:09:41 INFO - Running setup.py install for functools32 14:09:41 INFO - warning: no files found matching '*.txt' 14:09:41 INFO - no previously-included directories found matching 'build' 14:09:41 INFO - no previously-included directories found matching 'dist' 14:09:41 INFO - no previously-included directories found matching '.git*' 14:09:41 INFO - Successfully installed jsonschema functools32 14:09:41 INFO - Cleaning up... 14:09:41 INFO - Return code: 0 14:09:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:09:41 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')]} 14:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:41 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')]} 14:09:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:41 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 0x182e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b9cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9ff00>, '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 0x1b9aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b050>, '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': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', '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 14:09:41 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 14:09:41 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 14:09:41 INFO - Using env: {'DISPLAY': ':0', 14:09:41 INFO - 'HOME': '/home/cltbld', 14:09:41 INFO - 'LANG': 'en_US.UTF-8', 14:09:41 INFO - 'LANGUAGE': 'en_US:en', 14:09:41 INFO - 'LOGNAME': 'cltbld', 14:09:41 INFO - 'MAIL': '/var/mail/cltbld', 14:09:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:41 INFO - 'MOZ_NO_REMOTE': '1', 14:09:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:41 INFO - 'NO_EM_RESTART': '1', 14:09:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:41 INFO - 'PWD': '/builds/slave/test', 14:09:41 INFO - 'SHELL': '/bin/bash', 14:09:41 INFO - 'SHLVL': '1', 14:09:41 INFO - 'TERM': 'linux', 14:09:41 INFO - 'TMOUT': '86400', 14:09:41 INFO - 'USER': 'cltbld', 14:09:41 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', 14:09:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:41 INFO - '_': '/tools/buildbot/bin/python'} 14:09:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:09:41 INFO - Cleaning up... 14:09:41 INFO - Return code: 0 14:09:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:09:41 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')]} 14:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:41 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')]} 14:09:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:41 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 0x182e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b9cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9ff00>, '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 0x1b9aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b050>, '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': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', '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 14:09:41 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 14:09:41 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 14:09:41 INFO - Using env: {'DISPLAY': ':0', 14:09:41 INFO - 'HOME': '/home/cltbld', 14:09:41 INFO - 'LANG': 'en_US.UTF-8', 14:09:41 INFO - 'LANGUAGE': 'en_US:en', 14:09:41 INFO - 'LOGNAME': 'cltbld', 14:09:41 INFO - 'MAIL': '/var/mail/cltbld', 14:09:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:41 INFO - 'MOZ_NO_REMOTE': '1', 14:09:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:41 INFO - 'NO_EM_RESTART': '1', 14:09:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:41 INFO - 'PWD': '/builds/slave/test', 14:09:41 INFO - 'SHELL': '/bin/bash', 14:09:41 INFO - 'SHLVL': '1', 14:09:41 INFO - 'TERM': 'linux', 14:09:41 INFO - 'TMOUT': '86400', 14:09:41 INFO - 'USER': 'cltbld', 14:09:41 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', 14:09:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:41 INFO - '_': '/tools/buildbot/bin/python'} 14:09:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:41 INFO - Downloading/unpacking blobuploader==1.2.4 14:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:42 INFO - Downloading blobuploader-1.2.4.tar.gz 14:09:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:09:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:09:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:09:43 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 14:09:43 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 14:09:43 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 14:09:43 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 14:09:43 INFO - Downloading docopt-0.6.1.tar.gz 14:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:09:43 INFO - Installing collected packages: blobuploader, requests, docopt 14:09:43 INFO - Running setup.py install for blobuploader 14:09:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:09:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:09:43 INFO - Running setup.py install for requests 14:09:43 INFO - Running setup.py install for docopt 14:09:43 INFO - Successfully installed blobuploader requests docopt 14:09:43 INFO - Cleaning up... 14:09:43 INFO - Return code: 0 14:09:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:09:43 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')]} 14:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:43 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')]} 14:09:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:43 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 0x182e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b9cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9ff00>, '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 0x1b9aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b050>, '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': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', '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 14:09:43 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 14:09:43 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 14:09:43 INFO - Using env: {'DISPLAY': ':0', 14:09:43 INFO - 'HOME': '/home/cltbld', 14:09:43 INFO - 'LANG': 'en_US.UTF-8', 14:09:43 INFO - 'LANGUAGE': 'en_US:en', 14:09:43 INFO - 'LOGNAME': 'cltbld', 14:09:43 INFO - 'MAIL': '/var/mail/cltbld', 14:09:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:43 INFO - 'MOZ_NO_REMOTE': '1', 14:09:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:43 INFO - 'NO_EM_RESTART': '1', 14:09:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:43 INFO - 'PWD': '/builds/slave/test', 14:09:43 INFO - 'SHELL': '/bin/bash', 14:09:43 INFO - 'SHLVL': '1', 14:09:43 INFO - 'TERM': 'linux', 14:09:43 INFO - 'TMOUT': '86400', 14:09:43 INFO - 'USER': 'cltbld', 14:09:43 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', 14:09:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:43 INFO - '_': '/tools/buildbot/bin/python'} 14:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:09:44 INFO - Running setup.py (path:/tmp/pip-ri8VGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:09:44 INFO - Running setup.py (path:/tmp/pip-sSM3qZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:09:44 INFO - Running setup.py (path:/tmp/pip-VxTErq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:09:44 INFO - Running setup.py (path:/tmp/pip-KuyIYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:09:44 INFO - Running setup.py (path:/tmp/pip-Tq2fiJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:09:44 INFO - Running setup.py (path:/tmp/pip-NImgng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:09:44 INFO - Running setup.py (path:/tmp/pip-3qllB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:09:44 INFO - Running setup.py (path:/tmp/pip-5P1roc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:09:44 INFO - Running setup.py (path:/tmp/pip-u3z390-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:09:44 INFO - Running setup.py (path:/tmp/pip-9tdtrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:09:44 INFO - Running setup.py (path:/tmp/pip-HP2K44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:09:44 INFO - Running setup.py (path:/tmp/pip-M2sonM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:09:45 INFO - Running setup.py (path:/tmp/pip-USwlN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:09:45 INFO - Running setup.py (path:/tmp/pip-U_ZpMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:45 INFO - Running setup.py (path:/tmp/pip-AarKOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:09:45 INFO - Running setup.py (path:/tmp/pip-PW9dB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:09:45 INFO - Running setup.py (path:/tmp/pip-CYTKXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:09:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:09:45 INFO - Running setup.py install for manifestparser 14:09:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:09:45 INFO - Running setup.py install for mozcrash 14:09:45 INFO - Running setup.py install for mozdebug 14:09:45 INFO - Running setup.py install for mozdevice 14:09:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:09:45 INFO - Running setup.py install for mozfile 14:09:45 INFO - Running setup.py install for mozhttpd 14:09:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:09:46 INFO - Running setup.py install for mozinfo 14:09:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:09:46 INFO - Running setup.py install for mozInstall 14:09:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:09:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:09:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:09:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:09:46 INFO - Running setup.py install for mozleak 14:09:46 INFO - Running setup.py install for mozlog 14:09:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:09:46 INFO - Running setup.py install for moznetwork 14:09:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:09:46 INFO - Running setup.py install for mozprocess 14:09:46 INFO - Running setup.py install for mozprofile 14:09:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:09:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:09:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:09:46 INFO - Running setup.py install for mozrunner 14:09:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:09:46 INFO - Running setup.py install for mozscreenshot 14:09:47 INFO - Running setup.py install for moztest 14:09:47 INFO - Running setup.py install for mozversion 14:09:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:09:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:09:47 INFO - Cleaning up... 14:09:47 INFO - Return code: 0 14:09:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:09:47 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')]} 14:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:47 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')]} 14:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:47 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 0x182e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b9cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9ff00>, '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 0x1b9aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b050>, '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': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', '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 14:09:47 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 14:09:47 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 14:09:47 INFO - Using env: {'DISPLAY': ':0', 14:09:47 INFO - 'HOME': '/home/cltbld', 14:09:47 INFO - 'LANG': 'en_US.UTF-8', 14:09:47 INFO - 'LANGUAGE': 'en_US:en', 14:09:47 INFO - 'LOGNAME': 'cltbld', 14:09:47 INFO - 'MAIL': '/var/mail/cltbld', 14:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:47 INFO - 'MOZ_NO_REMOTE': '1', 14:09:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:47 INFO - 'NO_EM_RESTART': '1', 14:09:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:47 INFO - 'PWD': '/builds/slave/test', 14:09:47 INFO - 'SHELL': '/bin/bash', 14:09:47 INFO - 'SHLVL': '1', 14:09:47 INFO - 'TERM': 'linux', 14:09:47 INFO - 'TMOUT': '86400', 14:09:47 INFO - 'USER': 'cltbld', 14:09:47 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', 14:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:47 INFO - '_': '/tools/buildbot/bin/python'} 14:09:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:09:47 INFO - Running setup.py (path:/tmp/pip-k5Apf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:09:47 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)) 14:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:09:47 INFO - Running setup.py (path:/tmp/pip-Vlb_Hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:09:47 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)) 14:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:09:47 INFO - Running setup.py (path:/tmp/pip-AaBau9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:09:47 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)) 14:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:09:47 INFO - Running setup.py (path:/tmp/pip-Oe1R8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:09:47 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)) 14:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:09:47 INFO - Running setup.py (path:/tmp/pip-_7yRxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:09:47 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)) 14:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:09:47 INFO - Running setup.py (path:/tmp/pip-TCnFNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:09:47 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)) 14:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:09:47 INFO - Running setup.py (path:/tmp/pip-cVlXBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:09:47 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)) 14:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:09:47 INFO - Running setup.py (path:/tmp/pip-G2lrD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:09:48 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)) 14:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:09:48 INFO - Running setup.py (path:/tmp/pip-DebxAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:09:48 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)) 14:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:09:48 INFO - Running setup.py (path:/tmp/pip-BHUCFb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:09:48 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)) 14:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:09:48 INFO - Running setup.py (path:/tmp/pip-QcqcWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:09:48 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)) 14:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:09:48 INFO - Running setup.py (path:/tmp/pip-1tR7n2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:09:48 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)) 14:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:09:48 INFO - Running setup.py (path:/tmp/pip-vb0kyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:09:48 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)) 14:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:09:48 INFO - Running setup.py (path:/tmp/pip-HtMvxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:09:48 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)) 14:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:48 INFO - Running setup.py (path:/tmp/pip-EIUFfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:48 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)) 14:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:09:48 INFO - Running setup.py (path:/tmp/pip-6zlf04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:09:48 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)) 14:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:09:48 INFO - Running setup.py (path:/tmp/pip-QN4fpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:09:48 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)) 14:09:48 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)) 14:09:48 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)) 14:09:48 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)) 14:09:48 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)) 14:09:48 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)) 14:09:48 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)) 14:09:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:09:48 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 14:09:48 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 14:09:48 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 14:09:48 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 14:09:49 INFO - Downloading blessings-1.6.tar.gz 14:09:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:09:49 INFO - Installing collected packages: blessings 14:09:49 INFO - Running setup.py install for blessings 14:09:49 INFO - Successfully installed blessings 14:09:49 INFO - Cleaning up... 14:09:50 INFO - Return code: 0 14:09:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:09:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:09:50 INFO - Reading from file tmpfile_stdout 14:09:50 INFO - Current package versions: 14:09:50 INFO - argparse == 1.2.1 14:09:50 INFO - blessings == 1.6 14:09:50 INFO - blobuploader == 1.2.4 14:09:50 INFO - docopt == 0.6.1 14:09:50 INFO - functools32 == 3.2.3-2 14:09:50 INFO - jsonschema == 2.5.1 14:09:50 INFO - manifestparser == 1.1 14:09:50 INFO - mozInstall == 1.13 14:09:50 INFO - mozcrash == 1.0 14:09:50 INFO - mozdebug == 0.1 14:09:50 INFO - mozdevice == 0.51 14:09:50 INFO - mozfile == 1.2 14:09:50 INFO - mozhttpd == 0.7 14:09:50 INFO - mozinfo == 0.10 14:09:50 INFO - mozleak == 0.1 14:09:50 INFO - mozlog == 3.5 14:09:50 INFO - moznetwork == 0.27 14:09:50 INFO - mozprocess == 0.25 14:09:50 INFO - mozprofile == 0.28 14:09:50 INFO - mozrunner == 6.13 14:09:50 INFO - mozscreenshot == 0.1 14:09:50 INFO - mozsystemmonitor == 0.3 14:09:50 INFO - moztest == 0.8 14:09:50 INFO - mozversion == 1.4 14:09:50 INFO - psutil == 3.1.1 14:09:50 INFO - requests == 1.2.3 14:09:50 INFO - wsgiref == 0.1.2 14:09:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:50 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 0x182e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b9cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9ff00>, '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 0x1b9aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b050>, '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': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', '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 14:09:50 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 14:09:50 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 14:09:50 INFO - Using env: {'DISPLAY': ':0', 14:09:50 INFO - 'HOME': '/home/cltbld', 14:09:50 INFO - 'LANG': 'en_US.UTF-8', 14:09:50 INFO - 'LANGUAGE': 'en_US:en', 14:09:50 INFO - 'LOGNAME': 'cltbld', 14:09:50 INFO - 'MAIL': '/var/mail/cltbld', 14:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:50 INFO - 'MOZ_NO_REMOTE': '1', 14:09:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:50 INFO - 'NO_EM_RESTART': '1', 14:09:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:50 INFO - 'PWD': '/builds/slave/test', 14:09:50 INFO - 'SHELL': '/bin/bash', 14:09:50 INFO - 'SHLVL': '1', 14:09:50 INFO - 'TERM': 'linux', 14:09:50 INFO - 'TMOUT': '86400', 14:09:50 INFO - 'USER': 'cltbld', 14:09:50 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', 14:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:50 INFO - '_': '/tools/buildbot/bin/python'} 14:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:50 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)) 14:09:50 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)) 14:09:50 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)) 14:09:50 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)) 14:09:50 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)) 14:09:50 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)) 14:09:50 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)) 14:09:50 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)) 14:09:50 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)) 14:09:50 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:09:50 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 14:09:50 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 14:09:50 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 14:09:50 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 14:09:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 14:09:51 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)) 14:09:51 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)) 14:09:51 INFO - Installing collected packages: simplejson 14:09:51 INFO - Running setup.py install for simplejson 14:09:51 INFO - building 'simplejson._speedups' extension 14:09:51 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 14:09:52 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 14:09:52 INFO - Successfully installed simplejson 14:09:52 INFO - Cleaning up... 14:09:52 INFO - Return code: 0 14:09:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:52 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 0x182e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19fba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b9cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9ff00>, '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 0x1b9aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b050>, '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': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', '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 14:09:52 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 14:09:52 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 14:09:52 INFO - Using env: {'DISPLAY': ':0', 14:09:52 INFO - 'HOME': '/home/cltbld', 14:09:52 INFO - 'LANG': 'en_US.UTF-8', 14:09:52 INFO - 'LANGUAGE': 'en_US:en', 14:09:52 INFO - 'LOGNAME': 'cltbld', 14:09:52 INFO - 'MAIL': '/var/mail/cltbld', 14:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:52 INFO - 'MOZ_NO_REMOTE': '1', 14:09:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:52 INFO - 'NO_EM_RESTART': '1', 14:09:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:52 INFO - 'PWD': '/builds/slave/test', 14:09:52 INFO - 'SHELL': '/bin/bash', 14:09:52 INFO - 'SHLVL': '1', 14:09:52 INFO - 'TERM': 'linux', 14:09:52 INFO - 'TMOUT': '86400', 14:09:52 INFO - 'USER': 'cltbld', 14:09:52 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', 14:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:52 INFO - '_': '/tools/buildbot/bin/python'} 14:09:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:09:52 INFO - Cleaning up... 14:09:52 INFO - Return code: 0 14:09:52 INFO - Running post-action listener: _resource_record_post_action 14:09:52 INFO - Running post-action listener: _start_resource_monitoring 14:09:52 INFO - Starting resource monitoring. 14:09:52 INFO - [mozharness: 2017-08-11 21:09:52.992226Z] Finished create-virtualenv step (success) 14:09:52 INFO - [mozharness: 2017-08-11 21:09:52.992831Z] Running install step. 14:09:52 INFO - Running pre-action listener: _resource_record_pre_action 14:09:52 INFO - Running main action method: install 14:09:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:09:53 INFO - Reading from file tmpfile_stdout 14:09:53 INFO - Detecting whether we're running mozinstall >=1.0... 14:09:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:09:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:09:53 INFO - Reading from file tmpfile_stdout 14:09:53 INFO - Output received: 14:09:53 INFO - Usage: mozinstall [options] installer 14:09:53 INFO - Options: 14:09:53 INFO - -h, --help show this help message and exit 14:09:53 INFO - -d DEST, --destination=DEST 14:09:53 INFO - Directory to install application into. [default: 14:09:53 INFO - "/builds/slave/test"] 14:09:53 INFO - --app=APP Application being installed. [default: firefox] 14:09:53 INFO - mkdir: /builds/slave/test/build/application 14:09:53 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'] 14:09:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:10:11 INFO - Reading from file tmpfile_stdout 14:10:11 INFO - Output received: 14:10:11 INFO - /builds/slave/test/build/application/firefox/firefox 14:10:11 INFO - Running post-action listener: _resource_record_post_action 14:10:11 INFO - [mozharness: 2017-08-11 21:10:11.357707Z] Finished install step (success) 14:10:11 INFO - [mozharness: 2017-08-11 21:10:11.357869Z] Running setup-mitmproxy step. 14:10:11 INFO - Running pre-action listener: _resource_record_pre_action 14:10:11 INFO - Running main action method: setup_mitmproxy 14:10:11 INFO - Skipping: mitmproxy is not required 14:10:11 INFO - Running post-action listener: _resource_record_post_action 14:10:11 INFO - [mozharness: 2017-08-11 21:10:11.358417Z] Finished setup-mitmproxy step (success) 14:10:11 INFO - [mozharness: 2017-08-11 21:10:11.358522Z] Running run-tests step. 14:10:11 INFO - Running pre-action listener: _resource_record_pre_action 14:10:11 INFO - Running pre-action listener: _set_gcov_prefix 14:10:11 INFO - Running main action method: run_tests 14:10:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:10:11 INFO - Python 2.7.3 14:10:11 INFO - Return code: 0 14:10:11 INFO - grabbing minidump binary from tooltool 14:10:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:10:11 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 0x1b9ff00>, '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 0x1b9aba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:10:11 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 14:10:11 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 14:10:11 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 14:10:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:10:11 INFO - Return code: 0 14:10:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:10:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:10:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:10:11 INFO - ENV: RUST_BACKTRACE is now full 14:10:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:10:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:10:11 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/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 14:10:11 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/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-058 --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 14:10:11 INFO - Using env: {'DISPLAY': ':0', 14:10:11 INFO - 'HOME': '/home/cltbld', 14:10:11 INFO - 'LANG': 'en_US.UTF-8', 14:10:11 INFO - 'LANGUAGE': 'en_US:en', 14:10:11 INFO - 'LOGNAME': 'cltbld', 14:10:11 INFO - 'MAIL': '/var/mail/cltbld', 14:10:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:10:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:10:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:11 INFO - 'MOZ_NO_REMOTE': '1', 14:10:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:10:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:10:11 INFO - 'NO_EM_RESTART': '1', 14:10:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:10:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:11 INFO - 'PWD': '/builds/slave/test', 14:10:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:10:11 INFO - 'RUST_BACKTRACE': 'full', 14:10:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:10:11 INFO - 'SHELL': '/bin/bash', 14:10:11 INFO - 'SHLVL': '1', 14:10:11 INFO - 'TERM': 'linux', 14:10:11 INFO - 'TMOUT': '86400', 14:10:11 INFO - 'USER': 'cltbld', 14:10:11 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875', 14:10:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:11 INFO - '_': '/tools/buildbot/bin/python'} 14:10:11 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/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 14:10:11 INFO - mozversion application_buildid: 20170811201601 14:10:11 INFO - mozversion application_changeset: 80ff3f300e05f38f96c385b03d1973a966a2bd35 14:10:11 INFO - mozversion application_display_name: Nightly 14:10:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:10:11 INFO - mozversion application_name: Firefox 14:10:11 INFO - mozversion application_remotingname: firefox 14:10:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:10:11 INFO - mozversion application_vendor: Mozilla 14:10:11 INFO - mozversion application_version: 57.0a1 14:10:11 INFO - mozversion platform_buildid: 20170811201601 14:10:11 INFO - mozversion platform_changeset: 80ff3f300e05f38f96c385b03d1973a966a2bd35 14:10:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:10:11 INFO - mozversion platform_version: 57.0a1 14:10:11 INFO - using testdate: 1502485811 14:10:11 INFO - actual date: 1502485811 14:10:11 INFO - starting webserver on 'localhost:43126' 14:10:11 INFO - SUITE-START | Running 2 tests 14:10:11 INFO - TEST-START | dromaeo_css 14:10:11 INFO - Initialising browser for dromaeo_css test... 14:10:11 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43126/getInfo.html -profile /tmp/tmpMVLqF5/profile 14:10:11 INFO - TEST-INFO | started process 26504 (/builds/slave/test/build/application/firefox/firefox http://localhost:43126/getInfo.html) 14:10:17 INFO - TEST-INFO | 26504: exit 0 14:10:17 INFO - Browser initialized. 14:10:17 INFO - Running cycle 1/1 for dromaeo_css test... 14:10:17 INFO - TEST-INFO | started process 26718 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMVLqF5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 14:10:18 INFO - PID 26718 | 14:10:18 INFO - PID 26718 | (/builds/slave/test/build/application/firefox/firefox:26767): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 14:10:18 INFO - PID 26718 | 14:10:29 INFO - PID 26718 | RSS: Main: 165089280 14:10:29 INFO - PID 26718 | 14:13:28 INFO - PID 26718 | Cycle 1(1): loaded http://localhost:43126/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43126/tests/dromaeo/cssquery-ext.html) 14:13:29 INFO - PID 26718 | RSS: Main: 172752896 14:13:29 INFO - PID 26718 | 14:16:31 INFO - PID 26718 | Cycle 1(1): loaded http://localhost:43126/tests/dromaeo/cssquery-ext.html (next: http://localhost:43126/tests/dromaeo/cssquery-jquery.html) 14:16:32 INFO - PID 26718 | RSS: Main: 170573824 14:16:32 INFO - PID 26718 | 14:19:33 INFO - PID 26718 | Cycle 1(1): loaded http://localhost:43126/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43126/tests/dromaeo/cssquery-mootools.html) 14:19:34 INFO - PID 26718 | RSS: Main: 171991040 14:19:34 INFO - PID 26718 | 14:22:35 INFO - PID 26718 | Cycle 1(1): loaded http://localhost:43126/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43126/tests/dromaeo/cssquery-prototype.html) 14:22:36 INFO - PID 26718 | RSS: Main: 170766336 14:22:36 INFO - PID 26718 | 14:25:40 INFO - PID 26718 | Cycle 1(1): loaded http://localhost:43126/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43126/tests/dromaeo/cssquery-yui.html) 14:25:40 INFO - PID 26718 | RSS: Main: 173981696 14:25:40 INFO - PID 26718 | 14:28:42 INFO - PID 26718 | Cycle 1(1): loaded http://localhost:43126/tests/dromaeo/cssquery-yui.html (next: http://localhost:43126/tests/dromaeo/cssquery-dojo.html) 14:28:42 INFO - PID 26718 | RSS: Main: 174739456 14:28:42 INFO - PID 26718 | 14:28:42 INFO - PID 26718 | __start_tp_report 14:28:42 INFO - PID 26718 | _x_x_mozilla_page_load 14:28:42 INFO - PID 26718 | _x_x_mozilla_page_load_details 14:28:42 INFO - PID 26718 | |i|pagename|runs| 14:28:42 INFO - PID 26718 | |0;dojo.html;3177.491601343784;3183.554302397664;3188.6014248218967;3213.983930080349;3219.661935496773;6174.567780255384;6238.500919926406;6280.057991301302;6283.968580157099;6319.620822750639;6062.242219722535;6143.846403839906;6182.103594978726;6185.938140618592;6189.226346706661;5653.462921022507;5666.093425051992;5680.176374425727;5692.715364231772;5716.114002329625;5708.600397972144;5708.771649134051;5714.3999880012625;5730.398308177644;5754.82735517933;5658.74535645897;5769.076947688371;5787.855303617423;5812.186293918855;5856.180134781134;5608.887822243573;5690.174924635925;5705.515031222351;5740.913886291709;5761.942380576183;5258.106121959276;5466.480684334982;5639.323281206241;5675.3757086720425;5688.232088668018;6066.878662426727;6100.115483254947;6104.084387341934;6160.168377269101;6221.408966148254;6646.169228846395;6674.06563081169;6765.8646827063185;6775.525713200029;6785.1518560179975;6609.20689517261;6677.298883617247;6711.899321510181;6712;6734.124563806723;6168.44483996438;6190.690465476753;6227.595206311576;6314.936850631435;6318.399752023596;6810.659467026629;6874.381305682513;6899.0341352210735;6899.17209934811;6905.136857892763;6477.125588045554;6487.902681459782;6501.31736167705;6572.8356791080605;6601.6699165041555;6520.902186467207;6587.637679927554;6627.569208001464;6653.733850646016;6743.325667433315;6673.132492775908;6698.866022679519;6703.530752847351;6707.295733947865;6716.697748601325;6663.36698013688;6665.833354166185;6752.189737231563;6763.053172555846;6765.492588055916;5299.205119232146;5306.151015837448;5385.488378604026;5389.083855744533;5389.730513474311;4542.409486766765;4581.587657110876;4611.031683346535;4618.1918164321105;4651.813927442932;5882.529397648178;5884.9705751470965;5890.734916928748;5900.822975310747;5907.822765317047;6725.6973436195485;6770.661466926733;6804.149481314836;6805.897911531331;6816.352446517573;5773.566982476331;5818.127280907897;5890.558208134407;5901.114832775118;5908.940910590925;5884;5894.056950887923;5974.85062873423;5975.611585246945;5987.6108052976415;6323.241211054611;6406.743730250738;6409.166808314889;6410.1987251593555;6561.606303621798;6567.737290508326;6636.800895973129;6645.568038077509;6656.2345330287635;6689.2307384651385;6413.358664133736;6455.870882582416;6465.77369792055;6511.9348806511325;6514.3811337922825;6546.0508226307875;6549.443297319781;6553.901691474727;6556;6568;6548.8035358939305;6561;6581.835454113685;6675.766348177791;6694.096296999843;6331.873362532631;6381.202349706286;6434.2922278548385;6441;6457.160569125984;6425.325340839145;6503.674816259074;6590.769323073861;6595.241547222131;6601.10885030534;6463.192100987377;6477.676116194266;6496.252930912817;6516.869662606816;6523.804285871432;6525.738970441129;6535.379138981789;6559.14731084975;6606.537542371988;6630.602163870183;6203.65879876593;6288.496920246461;6295.96852015737;6313;6423.325550817097 14:28:42 INFO - PID 26718 | |1;ext.html;787.6101329841729;844.8183640517288;870.2820173356982;874.4665753890126;1117.854678891745;29278;29836.552451713214;38955.41566876499;39796.80101599488;39904.40143397851;40822.387664184986;41274;41280.38079428811;41342.37986430198;41361;6979.546329488567;7389.963050184755;7521;7521.360684341837;7530.8117297067465;7378.889316660255;7476.1776204617445;7479;7479.513831600929;7481.5885126318035;7464.552126872378;7485.73799917003;7500.324970752631;7512.887306690377;7516.624168791539;7402.29678180572;7464.992226049468;7475.401967842559;7482.363999060087;7499;7376.4467664924805;7433.8513229735645;7435.739749108755;7440.144383395925;7446.143693475211;37269.627303726884;37455.81272093649;37460;37510.62489375126;37625.81187094047;3542.4509201073874;3578.982105089484;3587.7309201809708;3589.174489867345;3596.8381422836037;30430;30534.54198187029;30535.08394748161;30765.384692306252;30805.845970770228;5058.595312375038;5061.848144555669;5065.493450654942;5081.187010078406;5101.948980510185;190466;191587.9999999986;191678;191823;192123;47804.28293575599;47829;47829.28256076162;47854.5214547857;47887;49690.254646180336;49736.50263497391;49903.50096499061;49930.251046234334;49954.250686239735;16390.754138687927;16759.91620041892;16796;16851.74722379165;16876.493705188863;12147;12383.62849114528;13614.591562253148;13911.65220869466;13951.720965580835;13750.518731844337;13877.583672489842;13894.374753136133;13992;14046.508372206923;5679.375268720438;5722.885542289131;5742.28221472316;5770.605198842517;5790.247267855236;997.6009596161593;1015.7663737340453;1020.4693559349246;1030.283952652899;1038.5741845843168;22888.31335059951;23664.468857155927;23784.881075594512;28909.42181156365;29709.40581188364;1103.194667892443;1103.9227254092136;1105.1048650593045;1107.7119948813206;1114.7436089699252;12231.633051008484;12745.745085098246;12855.871441285655;12868.935655321664;13006;227133;229319;229364;229388;229505;50378.24432633514;50655.24017139746;50740.23889641658;50783.23825142626;50842.237366439535;1452.201289290907;1460.2479722942694;1501.1368463133742;1506.8342482326934;1512.8940974131706;22836.657450138595;22891.656625150303;22941.197058102887;23231.53536929218;23275.650865237374;36782;36821.079473013386;36862.63137368592;36961;36978;206969;207240;207571;208163;208174;32803.507947380334;33038.66961330356;33121.50317745284;35174.13084560748;37234;44037.33943990907;46139;46442.53557464382;46578.767106164254;46594.76702616465;33497.330053398306;33924.83037584796;33981.83009084939;34771.13072173216;34901;33169.668303316656;33651;34148.487772682936;34846.303073938885;34901;36148;37119.44320835135;37315.81342093272;37417;37535.06162345963;32094.51858222082;32805.507917381736;32981.17547061342;33151;33790.66209337974;24160.637590435806;24713.505729885663;24795.380115497257;24970.500589987736;25236.74763252344 14:28:42 INFO - PID 26718 | |2;jquery.html;554.7379711157115;568.7383803450413;573.8737477754895;577.5062321714933;581.6161333519876;21875.562488750234;22303.219387321446;22707.659385109237;22735;22767;25336.2399128026;25894;26016.47967040658;26043.86978065112;26045;6289.999999999988;6430.5177111716575;6432.742690292383;6436.388543088399;6442.130312407812;5905.4980326672285;6244.438000579947;6249.593776404542;6253.7185826637915;6263.467605253559;6192.969035154818;6193.659348735818;6198.194234749499;6226.968865155668;6237.968810155943;6180.814575562717;6198.783042593533;6200.10098535712;6204.720787564571;6208.658523781213;5768.538516918678;5774.624649397818;5782.508486778629;5799.5650326225705;5801.158831969383;28230.858845705847;29146.125616231548;29222.70777292221;29537.556936645968;29702.554461682877;1944.3097700316325;1991.0542492316179;2009.136071489259;2011.8893460859647;2018.1725492548132;19174.328898488486;19796.208151673916;19819.80180198194;19821.30625428103;19839.206431742714;2312.2947501012354;2339.6256598944087;2352.929412117639;2356.752540983189;2382.0828980842325;124078.37960810229;126758.09862851973;126769.36615316957;126909.36545317307;126910;14591.197484138262;14979.625509362135;15098.54704358871;15155.545333640008;15418.229088545751;22780.772192277866;22788;22792;22817.88591057034;23920.52158956811;9751.21990240779;9839.311248212556;9910.702678919644;9923.255755818342;10048.497575121215;9096.363254572248;9133.45199288045;9144.131307525775;9207.585658645377;9227.815443691088;9476.526173691425;9531.809363812685;9594.616215351447;9611.759706007406;9638.180754635794;2748.725127487235;2752;2782.4713304472084;2794.8043636945627;2872.6265585473757;949.487276870496;978.7846673731767;981.7300242433245;983.4836710726926;989.1641562879355;20235.79764202339;20320.18719251243;20559.074841632162;24432.511349772903;26186.73813261881;1036.2694300518117;1045.4824861693357;1052.1740433759614;1052.5474046160225;1054.625607909181;11445.370504622326;11754.88245117538;11768.58809941648;11909.702257443632;11976.221545599508;512302;512908;519260;521243;534218;122078;124773.37613311876;125719;127757.3612131952;128554.35722821513;1259.1500737002486;1261.3441010674399;1293.1852932652557;1294.7345794112184;1296.8443786745463;18387.356442524448;18464.63070738551;18594.628107438046;20390.388288351372;21274.680879786505;57656.42343576678;58078;59026.40973590209;60306.09540856803;60570;63797.362026379145;65414.34585654082;67579.66210169114;67977;68396.316036839;14864.925675371554;15197.544073677807;15225.771613425586;15253.542393728207;15267.160306182823;23411.648825267293;23449.41376465602;23695.881520592975;23806.880965595756;24112.276631701076;18361.26554937841;18634.627307453506;18868.43394698161;19110.52223694363;19121.808781912558;15601.29794159243;16011.759823602422;17028.744568831724;17129.914350428167;17135.314587416364;19220.519487012938;19351.225950961805;19520;19559.60880782405;19667;14540.563783086523;14760.409583616534;14823.555293341216;14898.925505372838;14911.10533367958;18329.633407332047;18787.812121878607;18788.436346909613;18907.243710251434;18936.337228196946 14:28:42 INFO - PID 26718 | |3;mootools.html;1363.7408892310461;1395.4139261510165;1408.9151353457837;1419.198153043531;1422.8363738170108;14134.363953622085;14204;14746.926265368658;14813.481528146516;14825.62935926601;14817.333220005099;15122.621934451645;15139.924300378483;15150.772738408932;15154.621134471643;2740.876660550275;2798.5802129680505;2800.313923088846;2800.7619352355073;2801.775857931371;2736.097087960968;2842.474142283684;2850.3871667591466;2850.3871667591466;2855.771538276933;2672.812903096784;2711.3899372641094;2741.9588706169425;2744.0395861448533;2766.1424958263;2724.4687285979244;2728.945421091577;2739.0960982875704;2787.5957986091903;2790.162951114678;2638.9076382326525;2678.3571942733797;2679.316774222561;2701.0546308791963;2702.594610808375;14204.644883877882;14309.928450357786;14315.928420357935;14322.355494002795;14363.856361436461;7582.962085189539;7859.724909628136;7895.881561776578;7897.486663366863;7898.131205567383;5062.2406639004075;5147.562457191143;5152.046871328816;5279.70961597112;5301.920471192935;1801.6847051765887;1830.560665440298;1854.9165287562091;1855.9072046397625;1888.1503323504478;24104.276871693877;24119.517609647883;24161;24205.152819651405;24239.394015149763;6423.518236132215;6627.370399812106;6678.3989440950545;6806.217285012287;6852.554583952027;16352.427665031666;16367.59081022984;16367.67264654724;16373.508794736177;16395.42616008434;7809;8163.183681631908;8178.918210817935;8192.877106843402;8198.262156405834;5944.940550594439;6075.75696972125;6402.935970640234;7260.2739726026975;7315.012473316035;5103.489651034941;5179.404368497596;5203.297554830049;5208.5312321891215;5288.36539615241;1509.8490150984815;1512.4026009726097;1526.213999790109;1536.8386319436522;1544.4440001599414;780.9609519524001;795.7374066558021;805.5299079180519;807.3541167066323;814.7881550796835;6897.586144831326;6898.7240510380025;6968.512204145763;6986.685599148051;6999.685014174374;713.5968177979425;730.0217708270836;731.6195578299255;736.7679181058057;743.7359737293183;4613.215753321943;4870.488598697157;4909.459959404463;4924.926126108111;4927.1131196384995;19558.511037224183;20558.69161962572;20566.58866822685;20569.382918512467;20570.58858822815;19274.614507709768;20103.49741256451;20257.797422025887;20265.797342026686;20319;10961.671149865677;11065.72335691598;11233.382163980896;11265.887341126649;11307.49116289769;7353.338199562065;7588;7602.087749470099;7608.8478230433975;7611.086669599684;17348.13259337003;17458.21438035317;17612.767106302435;17690.734638980168;17831.910840445715;17624.73562896583;17864.106794660474;17962.371317003843;18046.780949348555;18086.909565452614;11564.248323858923;11763.176577639482;11898.405079745806;11912;11912.583059592873;12932.418041188357;13103.737925241632;13232.470701171846;13290.269035203157;13300.135491193041;10852.457377130953;10856.402897840379;11093.778124437305;11276.210665253682;11551.191416601094;10099.14157296638;10326.741831454274;10920.836187457353;11000.339979601249;11214.775704486028;9448.244140468883;9584.568694408908;9588;9608;9615.855762163434;6904.620245886525;6921.446284297345;6952.687129079102;6967.477439191979;6991.580505169502;6923.757668481579;6971.442284617117;6974.407175390149;7023.894641580483;7073.398761105158 14:28:42 INFO - PID 26718 | |4;prototype.html;477.28883962895253;495.64561338642864;501.4559203264458;503.3355970119444;528.3949877390388;6147.81556553303;6215.192025036745;6221.502279817621;6234.566049808541;6247.375262473762;5928.7035648217525;6027.608205466641;6042.413768991798;6050.939490605093;6090.17782599349;4856.805727770885;4858.878528036809;4863.100326439611;4879.268109783526;4931.926021109686;4818.662693611431;4920.581750551208;4928.33467481889;4947.480514545975;4956.727379994099;4901.240307752286;4917.139500587401;4923.926141107886;4934.5312195341385;4938.950335610735;4914.606831453475;4931.013797240548;4945.134601444751;4946.505349465061;4948.505149485059;4418.05011922435;4427.6015158635555;4429.379886815848;4467.82128714851;4468.218061839165;6065.150878876917;6077.969610151965;6090.208272924491;6094.268687757453;6124.1732366130445;5574.916376254359;5606.495415412592;5717.771289148429;5769.682667453289;5772.711364431803;6400.39196276353;6442.291347951722;6568.8357791055605;6574.76988305407;6582.6505566359265;4882.0699337033675;4894.363732714724;4895.804167833282;4900.681455705368;4901.534354236342;6811.693473793691;6852.486063545254;6878.27778083299;6882.724691012353;6945.201301850251;5031.547160755549;5032.874178145503;5038.556300194403;5040.899182016339;5044.167712327425;5088.821891233789;5090.345637667699;5099.6685215460875;5100.617453690914;5105.438401775802;5671.1493276008105;5693.516051135617;5732.656040637492;5741.62679425836;5757.424257574293;6579.17760279965;6613.371729685672;6614.437772789367;6616.900746488806;6651.334866513407;1674.5646132005766;1706.223668230952;1724.4050802473128;1775.0326072290632;1778.1731494854955;1216.403962058585;1236.258245052961;1242.8881400673984;1245.2715161630367;1246.3954981833804;5783;5783.392743761929;5792.275965504312;5793.102069179284;5816.127580862904;6623.20521537409;6748.527603067738;6756.837811900243;6761.18865736105;6777.559458635272;5812.7674893003805;5828.271466066742;5855.736491857877;5866.911996320058;5873.236479257669;3525.101099219734;3556.6621170988715;3563.645814590439;3595.3708101082207;3597.982010089933;6559.409653131241;6580.243272023683;6599.967000164968;6600.273969863311;6608.933910660832;6895.207051189077;6930.44556435494;6993.825154371181;7010.789676309617;7015.106615828162;6476.319986401454;6496.837579060561;6568.704408301638;6587.637679927554;6609.537332386687;6658.600483970978;6675.33246675348;6697.866042679217;6702.162229721285;6756.527043106936;6487.772927947499;6567.146270984741;6589.571677840925;6644.966775166094;6740.157480314961;6447.38946190135;6456.838579035561;6523.510736694672;6525.836854078686;6532.608043517404;4965.553100220997;4968.652194346361;4984.825531106394;4996.825111121093;5020.146575082318;6357.332480089524;6530.412262896172;6716.429103526059;6755.695993680198;6789.660516974231;6208.255009398901;6513.706883190173;6517.022446633043;6521.641309727821;6530.281669016309;6495.798277318522;6520.445762110084;6539.771108011197;6545.181852268466;6550.246721627074;6440.647464032419;6456.580322279036;6478.481721462366;6490.610563366213;6499.1876015498065;6574.8356291093105;6586.703598338182;6590.47276217911;6627.768028118992;6655.23464801554;6405.583637063576;6410.519211059096;6411.134496842867;6431.967840160769;6432.517561183024 14:28:42 INFO - PID 26718 | |5;yui.html;883.0551310098193;889.2308153447262;895.3061377432489;900.3607438718509;912.6988093929004;16628.66742665146;17539.123043847805;17713.291468341267;21888.781112188874;22858.77141228587;19698.507537311576;20174.697379539317;21166;21204.681929771064;21397.251096211636;1031.3760175094073;1048.035807057505;1048.2819268800858;1048.36573872807;1049.4542837724416;969.1035791892507;1048.2557384257186;1050.2543194332034;1059.5655781129738;1065.8614380130573;1113.7104352868273;1114.2701530497536;1116.40272454237;1116.4585176189541;1116.8771435142128;1106.0985296982933;1108.578740078773;1110.5224753356094;1111.4053981120103;1112.6217086190652;812.4515951732557;814.8899898513744;815.6655771133807;818.292177266666;818.7420962396826;19782.70325945112;20071.1971521139;20534;20692.48268793277;20799.480012999644;21201.89399052995;21505.892470537703;21551.245706400357;21660.783392166035;21703.782962170335;16458.588535286595;16494.75257871133;16579.585510362216;16808.663826723518;16986.915065424593;3498.982505087484;3518.032541051206;3519.665631764978;3520.0495866116007;3522.964770352315;117532;119288;120349.39825300817;121682;122922;15610.3755849767;15936.601584960468;16076.839231607768;16158.838411615969;16163.676726465404;27896;31773.841130794197;32395;32415;32446.675533244837;8802;9037.367384283169;9043.864342034874;9051.185393314634;9057.547122643842;4086.203190377907;4138.358554424069;4186.602272784081;4192.3711443283755;4195.202911446815;5189.377274727057;5302.973485132549;5369.4362091980565;5376.731163441812;5378.9462105379225;1051.2903789941759;1075.2903083964702;1087.0814162033068;1094.7810437912449;1095.189559725813;586.1266712598198;600.6786369292407;607;613.5398451161628;631.8325643704422;11247.437628118561;11266.94366528162;11927.22473039267;12002.759944801055;13362.198268103944;678.223434167875;679.3342524326188;682.743971010871;684.4079870911725;687.4740823270203;5493.901219756065;5848.210491583582;5894.823155305347;5989.251343582052;6074.058520929263;110351;113315;113410.43294783474;114577.42711286391;117541;96707;97735;97752;99903.50048249858;100282.49858750659;83124;88343;89835.55082224547;90618.54690726635;92427;12085.274883507018;12706.936465317614;12722.173058751152;12882.162659427107;13057.608271751862;39346.21307573775;40228;40281.597184027785;41294.58705412907;41416.1716765657;50518.74740626274;50717.49282507128;50997.235041473665;52574;52938.205926910356;17974.730379044064;18039;18259;18307.633847323246;18833.90583047076;17934.91032544829;18082.186301616723;18425.355112570996;18792.812071879107;18945.90527047356;18004.279828807226;18034.549136271697;18195.363162289257;18244;18426.72359914629;18561.44315670532;18794.812051879853;18823.71764423562;18844.152013159714;18921.243150273836;16871.156442178086;17051;17224.913875430542;17348.913255433643;17393.304267829146;13330.533431329857;13775;13937;13997.720045599235;14104.78842817338;13953.906913962986;14080.366383512972;14140.72733453994;14992.175430350999;15355.925085243925 14:28:42 INFO - PID 26718 | __end_tp_report 14:28:42 INFO - PID 26718 | __start_cc_report 14:28:42 INFO - PID 26718 | _x_x_mozilla_cycle_collect,367 14:28:42 INFO - PID 26718 | __end_cc_report 14:28:42 INFO - PID 26718 | __startTimestamp1502486922866__endTimestamp 14:28:42 INFO - PID 26718 | 14:28:42 INFO - PID 26718 | ------- Summary: start ------- 14:28:42 INFO - PID 26718 | Number of tests: 6 14:28:42 INFO - PID 26718 | 14:28:42 INFO - PID 26718 | [#0] dojo.html Cycles:165 Average:6143.76 Median:6406.74 stddev:721.42 (11.3%) stddev-sans-first:685.08 14:28:42 INFO - PID 26718 | Values: 3177.5 3183.6 3188.6 3214.0 3219.7 6174.6 6238.5 6280.1 6284.0 6319.6 6062.2 6143.8 6182.1 6185.9 6189.2 5653.5 5666.1 5680.2 5692.7 5716.1 5708.6 5708.8 5714.4 5730.4 5754.8 5658.7 5769.1 5787.9 5812.2 5856.2 5608.9 5690.2 5705.5 5740.9 5761.9 5258.1 5466.5 5639.3 5675.4 5688.2 6066.9 6100.1 6104.1 6160.2 6221.4 6646.2 6674.1 6765.9 6775.5 6785.2 6609.2 6677.3 6711.9 6712.0 6734.1 6168.4 6190.7 6227.6 6314.9 6318.4 6810.7 6874.4 6899.0 6899.2 6905.1 6477.1 6487.9 6501.3 6572.8 6601.7 6520.9 6587.6 6627.6 6653.7 6743.3 6673.1 6698.9 6703.5 6707.3 6716.7 6663.4 6665.8 6752.2 6763.1 6765.5 5299.2 5306.2 5385.5 5389.1 5389.7 4542.4 4581.6 4611.0 4618.2 4651.8 5882.5 5885.0 5890.7 5900.8 5907.8 6725.7 6770.7 6804.1 6805.9 6816.4 5773.6 5818.1 5890.6 5901.1 5908.9 5884.0 5894.1 5974.9 5975.6 5987.6 6323.2 6406.7 6409.2 6410.2 6561.6 6567.7 6636.8 6645.6 6656.2 6689.2 6413.4 6455.9 6465.8 6511.9 6514.4 6546.1 6549.4 6553.9 6556.0 6568.0 6548.8 6561.0 6581.8 6675.8 6694.1 6331.9 6381.2 6434.3 6441.0 6457.2 6425.3 6503.7 6590.8 6595.2 6601.1 6463.2 6477.7 6496.3 6516.9 6523.8 6525.7 6535.4 6559.1 6606.5 6630.6 6203.7 6288.5 6296.0 6313.0 6423.3 14:28:42 INFO - PID 26718 | 14:28:42 INFO - PID 26718 | [#1] ext.html Cycles:180 Average:37866.43 Median:19358.29 stddev:54335.74 (280.7%) stddev-sans-first:54416.83 14:28:42 INFO - PID 26718 | Values: 787.6 844.8 870.3 874.5 1117.9 29278.0 29836.6 38955.4 39796.8 39904.4 40822.4 41274.0 41280.4 41342.4 41361.0 6979.5 7390.0 7521.0 7521.4 7530.8 7378.9 7476.2 7479.0 7479.5 7481.6 7464.6 7485.7 7500.3 7512.9 7516.6 7402.3 7465.0 7475.4 7482.4 7499.0 7376.4 7433.9 7435.7 7440.1 7446.1 37269.6 37455.8 37460.0 37510.6 37625.8 3542.5 3579.0 3587.7 3589.2 3596.8 30430.0 30534.5 30535.1 30765.4 30805.8 5058.6 5061.8 5065.5 5081.2 5101.9 190466.0 191588.0 191678.0 191823.0 192123.0 47804.3 47829.0 47829.3 47854.5 47887.0 49690.3 49736.5 49903.5 49930.3 49954.3 16390.8 16759.9 16796.0 16851.7 16876.5 12147.0 12383.6 13614.6 13911.7 13951.7 13750.5 13877.6 13894.4 13992.0 14046.5 5679.4 5722.9 5742.3 5770.6 5790.2 997.6 1015.8 1020.5 1030.3 1038.6 22888.3 23664.5 23784.9 28909.4 29709.4 1103.2 1103.9 1105.1 1107.7 1114.7 12231.6 12745.7 12855.9 12868.9 13006.0 227133.0 229319.0 229364.0 229388.0 229505.0 50378.2 50655.2 50740.2 50783.2 50842.2 1452.2 1460.2 1501.1 1506.8 1512.9 22836.7 22891.7 22941.2 23231.5 23275.7 36782.0 36821.1 36862.6 36961.0 36978.0 206969.0 207240.0 207571.0 208163.0 208174.0 32803.5 33038.7 33121.5 35174.1 37234.0 44037.3 46139.0 46442.5 46578.8 46594.8 33497.3 33924.8 33981.8 34771.1 34901.0 33169.7 33651.0 34148.5 34846.3 34901.0 36148.0 37119.4 37315.8 37417.0 37535.1 32094.5 32805.5 32981.2 33151.0 33790.7 24160.6 24713.5 24795.4 24970.5 25236.7 14:28:42 INFO - PID 26718 | 14:28:42 INFO - PID 26718 | [#2] jquery.html Cycles:180 Average:35674.98 Median:23430.53 stddev:87067.86 (371.6%) stddev-sans-first:87272.17 14:28:42 INFO - PID 26718 | Values: 554.7 568.7 573.9 577.5 581.6 21875.6 22303.2 22707.7 22735.0 22767.0 25336.2 25894.0 26016.5 26043.9 26045.0 6290.0 6430.5 6432.7 6436.4 6442.1 5905.5 6244.4 6249.6 6253.7 6263.5 6193.0 6193.7 6198.2 6227.0 6238.0 6180.8 6198.8 6200.1 6204.7 6208.7 5768.5 5774.6 5782.5 5799.6 5801.2 28230.9 29146.1 29222.7 29537.6 29702.6 1944.3 1991.1 2009.1 2011.9 2018.2 19174.3 19796.2 19819.8 19821.3 19839.2 2312.3 2339.6 2352.9 2356.8 2382.1 124078.4 126758.1 126769.4 126909.4 126910.0 14591.2 14979.6 15098.5 15155.5 15418.2 22780.8 22788.0 22792.0 22817.9 23920.5 9751.2 9839.3 9910.7 9923.3 10048.5 9096.4 9133.5 9144.1 9207.6 9227.8 9476.5 9531.8 9594.6 9611.8 9638.2 2748.7 2752.0 2782.5 2794.8 2872.6 949.5 978.8 981.7 983.5 989.2 20235.8 20320.2 20559.1 24432.5 26186.7 1036.3 1045.5 1052.2 1052.5 1054.6 11445.4 11754.9 11768.6 11909.7 11976.2 512302.0 512908.0 519260.0 521243.0 534218.0 122078.0 124773.4 125719.0 127757.4 128554.4 1259.2 1261.3 1293.2 1294.7 1296.8 18387.4 18464.6 18594.6 20390.4 21274.7 57656.4 58078.0 59026.4 60306.1 60570.0 63797.4 65414.3 67579.7 67977.0 68396.3 14864.9 15197.5 15225.8 15253.5 15267.2 23411.6 23449.4 23695.9 23806.9 24112.3 18361.3 18634.6 18868.4 19110.5 19121.8 15601.3 16011.8 17028.7 17129.9 17135.3 19220.5 19351.2 19520.0 19559.6 19667.0 14540.6 14760.4 14823.6 14898.9 14911.1 18329.6 18787.8 18788.4 18907.2 18936.3 14:28:42 INFO - PID 26718 | 14:28:42 INFO - PID 26718 | [#3] mootools.html Cycles:180 Average:8914.08 Median:2706.99 stddev:6237.25 (230.4%) stddev-sans-first:6228.94 14:28:42 INFO - PID 26718 | Values: 1363.7 1395.4 1408.9 1419.2 1422.8 14134.4 14204.0 14746.9 14813.5 14825.6 14817.3 15122.6 15139.9 15150.8 15154.6 2740.9 2798.6 2800.3 2800.8 2801.8 2736.1 2842.5 2850.4 2850.4 2855.8 2672.8 2711.4 2742.0 2744.0 2766.1 2724.5 2728.9 2739.1 2787.6 2790.2 2638.9 2678.4 2679.3 2701.1 2702.6 14204.6 14309.9 14315.9 14322.4 14363.9 7583.0 7859.7 7895.9 7897.5 7898.1 5062.2 5147.6 5152.0 5279.7 5301.9 1801.7 1830.6 1854.9 1855.9 1888.2 24104.3 24119.5 24161.0 24205.2 24239.4 6423.5 6627.4 6678.4 6806.2 6852.6 16352.4 16367.6 16367.7 16373.5 16395.4 7809.0 8163.2 8178.9 8192.9 8198.3 5944.9 6075.8 6402.9 7260.3 7315.0 5103.5 5179.4 5203.3 5208.5 5288.4 1509.8 1512.4 1526.2 1536.8 1544.4 781.0 795.7 805.5 807.4 814.8 6897.6 6898.7 6968.5 6986.7 6999.7 713.6 730.0 731.6 736.8 743.7 4613.2 4870.5 4909.5 4924.9 4927.1 19558.5 20558.7 20566.6 20569.4 20570.6 19274.6 20103.5 20257.8 20265.8 20319.0 10961.7 11065.7 11233.4 11265.9 11307.5 7353.3 7588.0 7602.1 7608.8 7611.1 17348.1 17458.2 17612.8 17690.7 17831.9 17624.7 17864.1 17962.4 18046.8 18086.9 11564.2 11763.2 11898.4 11912.0 11912.6 12932.4 13103.7 13232.5 13290.3 13300.1 10852.5 10856.4 11093.8 11276.2 11551.2 10099.1 10326.7 10920.8 11000.3 11214.8 9448.2 9584.6 9588.0 9608.0 9615.9 6904.6 6921.4 6952.7 6967.5 6991.6 6923.8 6971.4 6974.4 7023.9 7073.4 14:28:42 INFO - PID 26718 | 14:28:42 INFO - PID 26718 | [#4] prototype.html Cycles:180 Average:5508.08 Median:6084.07 stddev:1551.34 (25.5%) stddev-sans-first:1509.04 14:28:42 INFO - PID 26718 | Values: 477.3 495.6 501.5 503.3 528.4 6147.8 6215.2 6221.5 6234.6 6247.4 5928.7 6027.6 6042.4 6050.9 6090.2 4856.8 4858.9 4863.1 4879.3 4931.9 4818.7 4920.6 4928.3 4947.5 4956.7 4901.2 4917.1 4923.9 4934.5 4939.0 4914.6 4931.0 4945.1 4946.5 4948.5 4418.1 4427.6 4429.4 4467.8 4468.2 6065.2 6078.0 6090.2 6094.3 6124.2 5574.9 5606.5 5717.8 5769.7 5772.7 6400.4 6442.3 6568.8 6574.8 6582.7 4882.1 4894.4 4895.8 4900.7 4901.5 6811.7 6852.5 6878.3 6882.7 6945.2 5031.5 5032.9 5038.6 5040.9 5044.2 5088.8 5090.3 5099.7 5100.6 5105.4 5671.1 5693.5 5732.7 5741.6 5757.4 6579.2 6613.4 6614.4 6616.9 6651.3 1674.6 1706.2 1724.4 1775.0 1778.2 1216.4 1236.3 1242.9 1245.3 1246.4 5783.0 5783.4 5792.3 5793.1 5816.1 6623.2 6748.5 6756.8 6761.2 6777.6 5812.8 5828.3 5855.7 5866.9 5873.2 3525.1 3556.7 3563.6 3595.4 3598.0 6559.4 6580.2 6600.0 6600.3 6608.9 6895.2 6930.4 6993.8 7010.8 7015.1 6476.3 6496.8 6568.7 6587.6 6609.5 6658.6 6675.3 6697.9 6702.2 6756.5 6487.8 6567.1 6589.6 6645.0 6740.2 6447.4 6456.8 6523.5 6525.8 6532.6 4965.6 4968.7 4984.8 4996.8 5020.1 6357.3 6530.4 6716.4 6755.7 6789.7 6208.3 6513.7 6517.0 6521.6 6530.3 6495.8 6520.4 6539.8 6545.2 6550.2 6440.6 6456.6 6478.5 6490.6 6499.2 6574.8 6586.7 6590.5 6627.8 6655.2 6405.6 6410.5 6411.1 6432.0 6432.5 14:28:42 INFO - PID 26718 | 14:28:42 INFO - PID 26718 | [#5] yui.html Cycles:180 Average:23324.98 Median:18882.70 stddev:31501.84 (166.8%) stddev-sans-first:31545.14 14:28:42 INFO - PID 26718 | Values: 883.1 889.2 895.3 900.4 912.7 16628.7 17539.1 17713.3 21888.8 22858.8 19698.5 20174.7 21166.0 21204.7 21397.3 1031.4 1048.0 1048.3 1048.4 1049.5 969.1 1048.3 1050.3 1059.6 1065.9 1113.7 1114.3 1116.4 1116.5 1116.9 1106.1 1108.6 1110.5 1111.4 1112.6 812.5 814.9 815.7 818.3 818.7 19782.7 20071.2 20534.0 20692.5 20799.5 21201.9 21505.9 21551.2 21660.8 21703.8 16458.6 16494.8 16579.6 16808.7 16986.9 3499.0 3518.0 3519.7 3520.0 3523.0 117532.0 119288.0 120349.4 121682.0 122922.0 15610.4 15936.6 16076.8 16158.8 16163.7 27896.0 31773.8 32395.0 32415.0 32446.7 8802.0 9037.4 9043.9 9051.2 9057.5 4086.2 4138.4 4186.6 4192.4 4195.2 5189.4 5303.0 5369.4 5376.7 5378.9 1051.3 1075.3 1087.1 1094.8 1095.2 586.1 600.7 607.0 613.5 631.8 11247.4 11266.9 11927.2 12002.8 13362.2 678.2 679.3 682.7 684.4 687.5 5493.9 5848.2 5894.8 5989.3 6074.1 110351.0 113315.0 113410.4 114577.4 117541.0 96707.0 97735.0 97752.0 99903.5 100282.5 83124.0 88343.0 89835.6 90618.5 92427.0 12085.3 12706.9 12722.2 12882.2 13057.6 39346.2 40228.0 40281.6 41294.6 41416.2 50518.7 50717.5 50997.2 52574.0 52938.2 17974.7 18039.0 18259.0 18307.6 18833.9 17934.9 18082.2 18425.4 18792.8 18945.9 18004.3 18034.5 18195.4 18244.0 18426.7 18561.4 18794.8 18823.7 18844.2 18921.2 16871.2 17051.0 17224.9 17348.9 17393.3 13330.5 13775.0 13937.0 13997.7 14104.8 13953.9 14080.4 14140.7 14992.2 15355.9 14:28:42 INFO - PID 26718 | -------- Summary: end -------- 14:28:42 INFO - PID 26718 | 14:28:43 INFO - TEST-INFO | 26718: exit 0 14:28:43 INFO - TEST-OK | dromaeo_css | took 1111818ms 14:28:43 INFO - TEST-START | kraken 14:28:43 INFO - Initialising browser for kraken test... 14:28:43 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43126/getInfo.html -profile /tmp/tmpGzrxP4/profile 14:28:43 INFO - TEST-INFO | started process 27088 (/builds/slave/test/build/application/firefox/firefox http://localhost:43126/getInfo.html) 14:28:49 INFO - TEST-INFO | 27088: exit 0 14:28:49 INFO - Browser initialized. 14:28:49 INFO - Running cycle 1/1 for kraken test... 14:28:49 INFO - TEST-INFO | started process 27307 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGzrxP4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 14:28:50 INFO - PID 27307 | 14:28:50 INFO - PID 27307 | (/builds/slave/test/build/application/firefox/firefox:27356): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 14:28:50 INFO - PID 27307 | 14:29:00 INFO - PID 27307 | RSS: Main: 165216256 14:29:00 INFO - PID 27307 | 14:29:03 INFO - PID 27307 | Cycle 1(1): loaded http://localhost:43126/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43126/tests/kraken/driver.html?testName=audio-beat-detection) 14:29:04 INFO - PID 27307 | RSS: Main: 172646400 14:29:04 INFO - PID 27307 | 14:29:07 INFO - PID 27307 | Cycle 1(1): loaded http://localhost:43126/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43126/tests/kraken/driver.html?testName=audio-dft) 14:29:07 INFO - PID 27307 | RSS: Main: 175796224 14:29:07 INFO - PID 27307 | 14:29:10 INFO - PID 27307 | Cycle 1(1): loaded http://localhost:43126/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43126/tests/kraken/driver.html?testName=audio-fft) 14:29:11 INFO - PID 27307 | RSS: Main: 169074688 14:29:11 INFO - PID 27307 | 14:29:13 INFO - PID 27307 | Cycle 1(1): loaded http://localhost:43126/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43126/tests/kraken/driver.html?testName=audio-oscillator) 14:29:14 INFO - PID 27307 | RSS: Main: 169676800 14:29:14 INFO - PID 27307 | 14:29:16 INFO - PID 27307 | Cycle 1(1): loaded http://localhost:43126/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43126/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:29:16 INFO - PID 27307 | RSS: Main: 170082304 14:29:16 INFO - PID 27307 | 14:29:19 INFO - PID 27307 | Cycle 1(1): loaded http://localhost:43126/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43126/tests/kraken/driver.html?testName=imaging-darkroom) 14:29:20 INFO - PID 27307 | RSS: Main: 171339776 14:29:20 INFO - PID 27307 | 14:29:24 INFO - PID 27307 | Cycle 1(1): loaded http://localhost:43126/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43126/tests/kraken/driver.html?testName=imaging-desaturate) 14:29:24 INFO - PID 27307 | RSS: Main: 171986944 14:29:24 INFO - PID 27307 | 14:29:27 INFO - PID 27307 | Cycle 1(1): loaded http://localhost:43126/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43126/tests/kraken/driver.html?testName=json-parse-financial) 14:29:28 INFO - PID 27307 | RSS: Main: 171921408 14:29:28 INFO - PID 27307 | 14:29:29 INFO - PID 27307 | Cycle 1(1): loaded http://localhost:43126/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43126/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:29:30 INFO - PID 27307 | RSS: Main: 171806720 14:29:30 INFO - PID 27307 | 14:29:32 INFO - PID 27307 | Cycle 1(1): loaded http://localhost:43126/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43126/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:29:32 INFO - PID 27307 | RSS: Main: 171802624 14:29:32 INFO - PID 27307 | 14:29:34 INFO - PID 27307 | Cycle 1(1): loaded http://localhost:43126/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43126/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:29:35 INFO - PID 27307 | RSS: Main: 171687936 14:29:35 INFO - PID 27307 | 14:29:38 INFO - PID 27307 | Cycle 1(1): loaded http://localhost:43126/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43126/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:29:38 INFO - PID 27307 | RSS: Main: 172023808 14:29:38 INFO - PID 27307 | 14:29:42 INFO - PID 27307 | Cycle 1(1): loaded http://localhost:43126/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43126/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:29:42 INFO - PID 27307 | RSS: Main: 172236800 14:29:42 INFO - PID 27307 | 14:29:44 INFO - PID 27307 | Cycle 1(1): loaded http://localhost:43126/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43126/tests/kraken/driver.html?testName=ai-astar) 14:29:44 INFO - PID 27307 | RSS: Main: 173826048 14:29:44 INFO - PID 27307 | 14:29:44 INFO - PID 27307 | __start_tp_report 14:29:44 INFO - PID 27307 | _x_x_mozilla_page_load 14:29:44 INFO - PID 27307 | _x_x_mozilla_page_load_details 14:29:44 INFO - PID 27307 | |i|pagename|runs| 14:29:44 INFO - PID 27307 | |0;ai-astar;98;108;101;107;110;109;108;100;110;109 14:29:44 INFO - PID 27307 | |1;audio-beat-detection;147;117;152;185;119;143;140;117;176;120 14:29:44 INFO - PID 27307 | |2;audio-dft;165;163;162;164;162;164;162;168;163;164 14:29:44 INFO - PID 27307 | |3;audio-fft;76;76;75;177;76;76;74;76;159;77 14:29:44 INFO - PID 27307 | |4;audio-oscillator;87;79;130;80;79;79;135;79;80;130 14:29:44 INFO - PID 27307 | |5;imaging-gaussian-blur;116;117;114;114;113;114;115;114;115;115 14:29:44 INFO - PID 27307 | |6;imaging-darkroom;151;150;150;152;151;150;150;150;152;149 14:29:44 INFO - PID 27307 | |7;imaging-desaturate;94;95;94;94;94;94;94;95;99;93 14:29:44 INFO - PID 27307 | |8;json-parse-financial;56;56;56;57;57;56;56;56;56;56 14:29:44 INFO - PID 27307 | |9;json-stringify-tinderbox;55;55;57;56;55;55;55;55;55;57 14:29:44 INFO - PID 27307 | |10;stanford-crypto-aes;78;77;78;79;80;81;77;76;77;76 14:29:44 INFO - PID 27307 | |11;stanford-crypto-ccm;131;126;122;123;131;138;132;127;122;167 14:29:44 INFO - PID 27307 | |12;stanford-crypto-pbkdf2;177;176;181;186;189;182;179;185;183;181 14:29:44 INFO - PID 27307 | |13;stanford-crypto-sha256-iterative;68;68;70;67;71;69;69;68;67;67 14:29:44 INFO - PID 27307 | __end_tp_report 14:29:44 INFO - PID 27307 | __start_cc_report 14:29:44 INFO - PID 27307 | _x_x_mozilla_cycle_collect,949 14:29:44 INFO - PID 27307 | __end_cc_report 14:29:44 INFO - PID 27307 | __startTimestamp1502486984702__endTimestamp 14:29:44 INFO - PID 27307 | 14:29:44 INFO - PID 27307 | ------- Summary: start ------- 14:29:44 INFO - PID 27307 | Number of tests: 14 14:29:44 INFO - PID 27307 | 14:29:44 INFO - PID 27307 | [#0] ai-astar Cycles:10 Average:106.00 Median:109.00 stddev:4.52 (4.1%) stddev-sans-first:3.76 14:29:44 INFO - PID 27307 | Values: 98.0 108.0 101.0 107.0 110.0 109.0 108.0 100.0 110.0 109.0 14:29:44 INFO - PID 27307 | 14:29:44 INFO - PID 27307 | [#1] audio-beat-detection Cycles:10 Average:141.60 Median:145.00 stddev:24.49 (16.9%) stddev-sans-first:25.89 14:29:44 INFO - PID 27307 | Values: 147.0 117.0 152.0 185.0 119.0 143.0 140.0 117.0 176.0 120.0 14:29:44 INFO - PID 27307 | 14:29:44 INFO - PID 27307 | [#2] audio-dft Cycles:10 Average:163.70 Median:164.00 stddev:1.83 (1.1%) stddev-sans-first:1.88 14:29:44 INFO - PID 27307 | Values: 165.0 163.0 162.0 164.0 162.0 164.0 162.0 168.0 163.0 164.0 14:29:44 INFO - PID 27307 | 14:29:44 INFO - PID 27307 | [#3] audio-fft Cycles:10 Average:94.20 Median:76.00 stddev:39.13 (51.5%) stddev-sans-first:40.95 14:29:44 INFO - PID 27307 | Values: 76.0 76.0 75.0 177.0 76.0 76.0 74.0 76.0 159.0 77.0 14:29:44 INFO - PID 27307 | 14:29:44 INFO - PID 27307 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:79.00 stddev:24.90 (31.5%) stddev-sans-first:26.21 14:29:44 INFO - PID 27307 | Values: 87.0 79.0 130.0 80.0 79.0 79.0 135.0 79.0 80.0 130.0 14:29:44 INFO - PID 27307 | 14:29:44 INFO - PID 27307 | [#5] imaging-gaussian-blur Cycles:10 Average:114.70 Median:115.00 stddev:1.16 (1.0%) stddev-sans-first:1.13 14:29:44 INFO - PID 27307 | Values: 116.0 117.0 114.0 114.0 113.0 114.0 115.0 114.0 115.0 115.0 14:29:44 INFO - PID 27307 | 14:29:44 INFO - PID 27307 | [#6] imaging-darkroom Cycles:10 Average:150.50 Median:150.50 stddev:0.97 (0.6%) stddev-sans-first:1.01 14:29:44 INFO - PID 27307 | Values: 151.0 150.0 150.0 152.0 151.0 150.0 150.0 150.0 152.0 149.0 14:29:44 INFO - PID 27307 | 14:29:44 INFO - PID 27307 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:94.00 stddev:1.65 (1.8%) stddev-sans-first:1.73 14:29:44 INFO - PID 27307 | Values: 94.0 95.0 94.0 94.0 94.0 94.0 94.0 95.0 99.0 93.0 14:29:44 INFO - PID 27307 | 14:29:44 INFO - PID 27307 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.00 stddev:0.42 (0.8%) stddev-sans-first:0.44 14:29:44 INFO - PID 27307 | Values: 56.0 56.0 56.0 57.0 57.0 56.0 56.0 56.0 56.0 56.0 14:29:44 INFO - PID 27307 | 14:29:44 INFO - PID 27307 | [#9] json-stringify-tinderbox Cycles:10 Average:55.50 Median:55.00 stddev:0.85 (1.5%) stddev-sans-first:0.88 14:29:44 INFO - PID 27307 | Values: 55.0 55.0 57.0 56.0 55.0 55.0 55.0 55.0 55.0 57.0 14:29:44 INFO - PID 27307 | 14:29:44 INFO - PID 27307 | [#10] stanford-crypto-aes Cycles:10 Average:77.90 Median:78.00 stddev:1.66 (2.1%) stddev-sans-first:1.76 14:29:44 INFO - PID 27307 | Values: 78.0 77.0 78.0 79.0 80.0 81.0 77.0 76.0 77.0 76.0 14:29:44 INFO - PID 27307 | 14:29:44 INFO - PID 27307 | [#11] stanford-crypto-ccm Cycles:10 Average:131.90 Median:131.00 stddev:13.35 (10.2%) stddev-sans-first:14.16 14:29:44 INFO - PID 27307 | Values: 131.0 126.0 122.0 123.0 131.0 138.0 132.0 127.0 122.0 167.0 14:29:44 INFO - PID 27307 | 14:29:44 INFO - PID 27307 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.90 Median:182.50 stddev:4.04 (2.2%) stddev-sans-first:3.88 14:29:44 INFO - PID 27307 | Values: 177.0 176.0 181.0 186.0 189.0 182.0 179.0 185.0 183.0 181.0 14:29:44 INFO - PID 27307 | 14:29:44 INFO - PID 27307 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.50 stddev:1.35 (2.0%) stddev-sans-first:1.42 14:29:44 INFO - PID 27307 | Values: 68.0 68.0 70.0 67.0 71.0 69.0 69.0 68.0 67.0 67.0 14:29:44 INFO - PID 27307 | -------- Summary: end -------- 14:29:44 INFO - PID 27307 | 14:29:45 INFO - PID 27307 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 14:29:45 INFO - TEST-INFO | 27307: exit 0 14:29:45 INFO - TEST-OK | kraken | took 61825ms 14:29:45 INFO - SUITE-END | took 1173s 14:29:45 INFO - Completed test suite (00:19:34) 14:29:45 INFO - javascript benchmark 14:29:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8490.9725034513, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3177.491601343784, 3183.554302397664, 3188.6014248218967, 3213.983930080349, 3219.661935496773, 6174.567780255384, 6238.500919926406, 6280.057991301302, 6283.968580157099, 6319.620822750639, 6062.242219722535, 6143.846403839906, 6182.103594978726, 6185.938140618592, 6189.226346706661, 5653.462921022507, 5666.093425051992, 5680.176374425727, 5692.715364231772, 5716.114002329625, 5708.600397972144, 5708.771649134051, 5714.3999880012625, 5730.398308177644, 5754.82735517933, 5658.74535645897, 5769.076947688371, 5787.855303617423, 5812.186293918855, 5856.180134781134, 5608.887822243573, 5690.174924635925, 5705.515031222351, 5740.913886291709, 5761.942380576183, 5258.106121959276, 5466.480684334982, 5639.323281206241, 5675.3757086720425, 5688.232088668018, 6066.878662426727, 6100.115483254947, 6104.084387341934, 6160.168377269101, 6221.408966148254, 6646.169228846395, 6674.06563081169, 6765.8646827063185, 6775.525713200029, 6785.1518560179975, 6609.20689517261, 6677.298883617247, 6711.899321510181, 6712.0, 6734.124563806723, 6168.44483996438, 6190.690465476753, 6227.595206311576, 6314.936850631435, 6318.399752023596, 6810.659467026629, 6874.381305682513, 6899.0341352210735, 6899.17209934811, 6905.136857892763, 6477.125588045554, 6487.902681459782, 6501.31736167705, 6572.8356791080605, 6601.6699165041555, 6520.902186467207, 6587.637679927554, 6627.569208001464, 6653.733850646016, 6743.325667433315, 6673.132492775908, 6698.866022679519, 6703.530752847351, 6707.295733947865, 6716.697748601325, 6663.36698013688, 6665.833354166185, 6752.189737231563, 6763.053172555846, 6765.492588055916, 5299.205119232146, 5306.151015837448, 5385.488378604026, 5389.083855744533, 5389.730513474311, 4542.409486766765, 4581.587657110876, 4611.031683346535, 4618.1918164321105, 4651.813927442932, 5882.529397648178, 5884.9705751470965, 5890.734916928748, 5900.822975310747, 5907.822765317047, 6725.6973436195485, 6770.661466926733, 6804.149481314836, 6805.897911531331, 6816.352446517573, 5773.566982476331, 5818.127280907897, 5890.558208134407, 5901.114832775118, 5908.940910590925, 5884.0, 5894.056950887923, 5974.85062873423, 5975.611585246945, 5987.6108052976415, 6323.241211054611, 6406.743730250738, 6409.166808314889, 6410.1987251593555, 6561.606303621798, 6567.737290508326, 6636.800895973129, 6645.568038077509, 6656.2345330287635, 6689.2307384651385, 6413.358664133736, 6455.870882582416, 6465.77369792055, 6511.9348806511325, 6514.3811337922825, 6546.0508226307875, 6549.443297319781, 6553.901691474727, 6556.0, 6568.0, 6548.8035358939305, 6561.0, 6581.835454113685, 6675.766348177791, 6694.096296999843, 6331.873362532631, 6381.202349706286, 6434.2922278548385, 6441.0, 6457.160569125984, 6425.325340839145, 6503.674816259074, 6590.769323073861, 6595.241547222131, 6601.10885030534, 6463.192100987377, 6477.676116194266, 6496.252930912817, 6516.869662606816, 6523.804285871432, 6525.738970441129, 6535.379138981789, 6559.14731084975, 6606.537542371988, 6630.602163870183, 6203.65879876593, 6288.496920246461, 6295.96852015737, 6313.0, 6423.325550817097], "value": 6090.253278312385, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [787.6101329841729, 844.8183640517288, 870.2820173356982, 874.4665753890126, 1117.854678891745, 29278.0, 29836.552451713214, 38955.41566876499, 39796.80101599488, 39904.40143397851, 40822.387664184986, 41274.0, 41280.38079428811, 41342.37986430198, 41361.0, 6979.546329488567, 7389.963050184755, 7521.0, 7521.360684341837, 7530.8117297067465, 7378.889316660255, 7476.1776204617445, 7479.0, 7479.513831600929, 7481.5885126318035, 7464.552126872378, 7485.73799917003, 7500.324970752631, 7512.887306690377, 7516.624168791539, 7402.29678180572, 7464.992226049468, 7475.401967842559, 7482.363999060087, 7499.0, 7376.4467664924805, 7433.8513229735645, 7435.739749108755, 7440.144383395925, 7446.143693475211, 37269.627303726884, 37455.81272093649, 37460.0, 37510.62489375126, 37625.81187094047, 3542.4509201073874, 3578.982105089484, 3587.7309201809708, 3589.174489867345, 3596.8381422836037, 30430.0, 30534.54198187029, 30535.08394748161, 30765.384692306252, 30805.845970770228, 5058.595312375038, 5061.848144555669, 5065.493450654942, 5081.187010078406, 5101.948980510185, 190466.0, 191587.9999999986, 191678.0, 191823.0, 192123.0, 47804.28293575599, 47829.0, 47829.28256076162, 47854.5214547857, 47887.0, 49690.254646180336, 49736.50263497391, 49903.50096499061, 49930.251046234334, 49954.250686239735, 16390.754138687927, 16759.91620041892, 16796.0, 16851.74722379165, 16876.493705188863, 12147.0, 12383.62849114528, 13614.591562253148, 13911.65220869466, 13951.720965580835, 13750.518731844337, 13877.583672489842, 13894.374753136133, 13992.0, 14046.508372206923, 5679.375268720438, 5722.885542289131, 5742.28221472316, 5770.605198842517, 5790.247267855236, 997.6009596161593, 1015.7663737340453, 1020.4693559349246, 1030.283952652899, 1038.5741845843168, 22888.31335059951, 23664.468857155927, 23784.881075594512, 28909.42181156365, 29709.40581188364, 1103.194667892443, 1103.9227254092136, 1105.1048650593045, 1107.7119948813206, 1114.7436089699252, 12231.633051008484, 12745.745085098246, 12855.871441285655, 12868.935655321664, 13006.0, 227133.0, 229319.0, 229364.0, 229388.0, 229505.0, 50378.24432633514, 50655.24017139746, 50740.23889641658, 50783.23825142626, 50842.237366439535, 1452.201289290907, 1460.2479722942694, 1501.1368463133742, 1506.8342482326934, 1512.8940974131706, 22836.657450138595, 22891.656625150303, 22941.197058102887, 23231.53536929218, 23275.650865237374, 36782.0, 36821.079473013386, 36862.63137368592, 36961.0, 36978.0, 206969.0, 207240.0, 207571.0, 208163.0, 208174.0, 32803.507947380334, 33038.66961330356, 33121.50317745284, 35174.13084560748, 37234.0, 44037.33943990907, 46139.0, 46442.53557464382, 46578.767106164254, 46594.76702616465, 33497.330053398306, 33924.83037584796, 33981.83009084939, 34771.13072173216, 34901.0, 33169.668303316656, 33651.0, 34148.487772682936, 34846.303073938885, 34901.0, 36148.0, 37119.44320835135, 37315.81342093272, 37417.0, 37535.06162345963, 32094.51858222082, 32805.507917381736, 32981.17547061342, 33151.0, 33790.66209337974, 24160.637590435806, 24713.505729885663, 24795.380115497257, 24970.500589987736, 25236.74763252344], "value": 17229.055902889402, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [554.7379711157115, 568.7383803450413, 573.8737477754895, 577.5062321714933, 581.6161333519876, 21875.562488750234, 22303.219387321446, 22707.659385109237, 22735.0, 22767.0, 25336.2399128026, 25894.0, 26016.47967040658, 26043.86978065112, 26045.0, 6289.999999999988, 6430.5177111716575, 6432.742690292383, 6436.388543088399, 6442.130312407812, 5905.4980326672285, 6244.438000579947, 6249.593776404542, 6253.7185826637915, 6263.467605253559, 6192.969035154818, 6193.659348735818, 6198.194234749499, 6226.968865155668, 6237.968810155943, 6180.814575562717, 6198.783042593533, 6200.10098535712, 6204.720787564571, 6208.658523781213, 5768.538516918678, 5774.624649397818, 5782.508486778629, 5799.5650326225705, 5801.158831969383, 28230.858845705847, 29146.125616231548, 29222.70777292221, 29537.556936645968, 29702.554461682877, 1944.3097700316325, 1991.0542492316179, 2009.136071489259, 2011.8893460859647, 2018.1725492548132, 19174.328898488486, 19796.208151673916, 19819.80180198194, 19821.30625428103, 19839.206431742714, 2312.2947501012354, 2339.6256598944087, 2352.929412117639, 2356.752540983189, 2382.0828980842325, 124078.37960810229, 126758.09862851973, 126769.36615316957, 126909.36545317307, 126910.0, 14591.197484138262, 14979.625509362135, 15098.54704358871, 15155.545333640008, 15418.229088545751, 22780.772192277866, 22788.0, 22792.0, 22817.88591057034, 23920.52158956811, 9751.21990240779, 9839.311248212556, 9910.702678919644, 9923.255755818342, 10048.497575121215, 9096.363254572248, 9133.45199288045, 9144.131307525775, 9207.585658645377, 9227.815443691088, 9476.526173691425, 9531.809363812685, 9594.616215351447, 9611.759706007406, 9638.180754635794, 2748.725127487235, 2752.0, 2782.4713304472084, 2794.8043636945627, 2872.6265585473757, 949.487276870496, 978.7846673731767, 981.7300242433245, 983.4836710726926, 989.1641562879355, 20235.79764202339, 20320.18719251243, 20559.074841632162, 24432.511349772903, 26186.73813261881, 1036.2694300518117, 1045.4824861693357, 1052.1740433759614, 1052.5474046160225, 1054.625607909181, 11445.370504622326, 11754.88245117538, 11768.58809941648, 11909.702257443632, 11976.221545599508, 512302.0, 512908.0, 519260.0, 521243.0, 534218.0, 122078.0, 124773.37613311876, 125719.0, 127757.3612131952, 128554.35722821513, 1259.1500737002486, 1261.3441010674399, 1293.1852932652557, 1294.7345794112184, 1296.8443786745463, 18387.356442524448, 18464.63070738551, 18594.628107438046, 20390.388288351372, 21274.680879786505, 57656.42343576678, 58078.0, 59026.40973590209, 60306.09540856803, 60570.0, 63797.362026379145, 65414.34585654082, 67579.66210169114, 67977.0, 68396.316036839, 14864.925675371554, 15197.544073677807, 15225.771613425586, 15253.542393728207, 15267.160306182823, 23411.648825267293, 23449.41376465602, 23695.881520592975, 23806.880965595756, 24112.276631701076, 18361.26554937841, 18634.627307453506, 18868.43394698161, 19110.52223694363, 19121.808781912558, 15601.29794159243, 16011.759823602422, 17028.744568831724, 17129.914350428167, 17135.314587416364, 19220.519487012938, 19351.225950961805, 19520.0, 19559.60880782405, 19667.0, 14540.563783086523, 14760.409583616534, 14823.555293341216, 14898.925505372838, 14911.10533367958, 18329.633407332047, 18787.812121878607, 18788.436346909613, 18907.243710251434, 18936.337228196946], "value": 12070.919659728677, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1363.7408892310461, 1395.4139261510165, 1408.9151353457837, 1419.198153043531, 1422.8363738170108, 14134.363953622085, 14204.0, 14746.926265368658, 14813.481528146516, 14825.62935926601, 14817.333220005099, 15122.621934451645, 15139.924300378483, 15150.772738408932, 15154.621134471643, 2740.876660550275, 2798.5802129680505, 2800.313923088846, 2800.7619352355073, 2801.775857931371, 2736.097087960968, 2842.474142283684, 2850.3871667591466, 2850.3871667591466, 2855.771538276933, 2672.812903096784, 2711.3899372641094, 2741.9588706169425, 2744.0395861448533, 2766.1424958263, 2724.4687285979244, 2728.945421091577, 2739.0960982875704, 2787.5957986091903, 2790.162951114678, 2638.9076382326525, 2678.3571942733797, 2679.316774222561, 2701.0546308791963, 2702.594610808375, 14204.644883877882, 14309.928450357786, 14315.928420357935, 14322.355494002795, 14363.856361436461, 7582.962085189539, 7859.724909628136, 7895.881561776578, 7897.486663366863, 7898.131205567383, 5062.2406639004075, 5147.562457191143, 5152.046871328816, 5279.70961597112, 5301.920471192935, 1801.6847051765887, 1830.560665440298, 1854.9165287562091, 1855.9072046397625, 1888.1503323504478, 24104.276871693877, 24119.517609647883, 24161.0, 24205.152819651405, 24239.394015149763, 6423.518236132215, 6627.370399812106, 6678.3989440950545, 6806.217285012287, 6852.554583952027, 16352.427665031666, 16367.59081022984, 16367.67264654724, 16373.508794736177, 16395.42616008434, 7809.0, 8163.183681631908, 8178.918210817935, 8192.877106843402, 8198.262156405834, 5944.940550594439, 6075.75696972125, 6402.935970640234, 7260.2739726026975, 7315.012473316035, 5103.489651034941, 5179.404368497596, 5203.297554830049, 5208.5312321891215, 5288.36539615241, 1509.8490150984815, 1512.4026009726097, 1526.213999790109, 1536.8386319436522, 1544.4440001599414, 780.9609519524001, 795.7374066558021, 805.5299079180519, 807.3541167066323, 814.7881550796835, 6897.586144831326, 6898.7240510380025, 6968.512204145763, 6986.685599148051, 6999.685014174374, 713.5968177979425, 730.0217708270836, 731.6195578299255, 736.7679181058057, 743.7359737293183, 4613.215753321943, 4870.488598697157, 4909.459959404463, 4924.926126108111, 4927.1131196384995, 19558.511037224183, 20558.69161962572, 20566.58866822685, 20569.382918512467, 20570.58858822815, 19274.614507709768, 20103.49741256451, 20257.797422025887, 20265.797342026686, 20319.0, 10961.671149865677, 11065.72335691598, 11233.382163980896, 11265.887341126649, 11307.49116289769, 7353.338199562065, 7588.0, 7602.087749470099, 7608.8478230433975, 7611.086669599684, 17348.13259337003, 17458.21438035317, 17612.767106302435, 17690.734638980168, 17831.910840445715, 17624.73562896583, 17864.106794660474, 17962.371317003843, 18046.780949348555, 18086.909565452614, 11564.248323858923, 11763.176577639482, 11898.405079745806, 11912.0, 11912.583059592873, 12932.418041188357, 13103.737925241632, 13232.470701171846, 13290.269035203157, 13300.135491193041, 10852.457377130953, 10856.402897840379, 11093.778124437305, 11276.210665253682, 11551.191416601094, 10099.14157296638, 10326.741831454274, 10920.836187457353, 11000.339979601249, 11214.775704486028, 9448.244140468883, 9584.568694408908, 9588.0, 9608.0, 9615.855762163434, 6904.620245886525, 6921.446284297345, 6952.687129079102, 6967.477439191979, 6991.580505169502, 6923.757668481579, 6971.442284617117, 6974.407175390149, 7023.894641580483, 7073.398761105158], "value": 6405.519270888603, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.28883962895253, 495.64561338642864, 501.4559203264458, 503.3355970119444, 528.3949877390388, 6147.81556553303, 6215.192025036745, 6221.502279817621, 6234.566049808541, 6247.375262473762, 5928.7035648217525, 6027.608205466641, 6042.413768991798, 6050.939490605093, 6090.17782599349, 4856.805727770885, 4858.878528036809, 4863.100326439611, 4879.268109783526, 4931.926021109686, 4818.662693611431, 4920.581750551208, 4928.33467481889, 4947.480514545975, 4956.727379994099, 4901.240307752286, 4917.139500587401, 4923.926141107886, 4934.5312195341385, 4938.950335610735, 4914.606831453475, 4931.013797240548, 4945.134601444751, 4946.505349465061, 4948.505149485059, 4418.05011922435, 4427.6015158635555, 4429.379886815848, 4467.82128714851, 4468.218061839165, 6065.150878876917, 6077.969610151965, 6090.208272924491, 6094.268687757453, 6124.1732366130445, 5574.916376254359, 5606.495415412592, 5717.771289148429, 5769.682667453289, 5772.711364431803, 6400.39196276353, 6442.291347951722, 6568.8357791055605, 6574.76988305407, 6582.6505566359265, 4882.0699337033675, 4894.363732714724, 4895.804167833282, 4900.681455705368, 4901.534354236342, 6811.693473793691, 6852.486063545254, 6878.27778083299, 6882.724691012353, 6945.201301850251, 5031.547160755549, 5032.874178145503, 5038.556300194403, 5040.899182016339, 5044.167712327425, 5088.821891233789, 5090.345637667699, 5099.6685215460875, 5100.617453690914, 5105.438401775802, 5671.1493276008105, 5693.516051135617, 5732.656040637492, 5741.62679425836, 5757.424257574293, 6579.17760279965, 6613.371729685672, 6614.437772789367, 6616.900746488806, 6651.334866513407, 1674.5646132005766, 1706.223668230952, 1724.4050802473128, 1775.0326072290632, 1778.1731494854955, 1216.403962058585, 1236.258245052961, 1242.8881400673984, 1245.2715161630367, 1246.3954981833804, 5783.0, 5783.392743761929, 5792.275965504312, 5793.102069179284, 5816.127580862904, 6623.20521537409, 6748.527603067738, 6756.837811900243, 6761.18865736105, 6777.559458635272, 5812.7674893003805, 5828.271466066742, 5855.736491857877, 5866.911996320058, 5873.236479257669, 3525.101099219734, 3556.6621170988715, 3563.645814590439, 3595.3708101082207, 3597.982010089933, 6559.409653131241, 6580.243272023683, 6599.967000164968, 6600.273969863311, 6608.933910660832, 6895.207051189077, 6930.44556435494, 6993.825154371181, 7010.789676309617, 7015.106615828162, 6476.319986401454, 6496.837579060561, 6568.704408301638, 6587.637679927554, 6609.537332386687, 6658.600483970978, 6675.33246675348, 6697.866042679217, 6702.162229721285, 6756.527043106936, 6487.772927947499, 6567.146270984741, 6589.571677840925, 6644.966775166094, 6740.157480314961, 6447.38946190135, 6456.838579035561, 6523.510736694672, 6525.836854078686, 6532.608043517404, 4965.553100220997, 4968.652194346361, 4984.825531106394, 4996.825111121093, 5020.146575082318, 6357.332480089524, 6530.412262896172, 6716.429103526059, 6755.695993680198, 6789.660516974231, 6208.255009398901, 6513.706883190173, 6517.022446633043, 6521.641309727821, 6530.281669016309, 6495.798277318522, 6520.445762110084, 6539.771108011197, 6545.181852268466, 6550.246721627074, 6440.647464032419, 6456.580322279036, 6478.481721462366, 6490.610563366213, 6499.1876015498065, 6574.8356291093105, 6586.703598338182, 6590.47276217911, 6627.768028118992, 6655.23464801554, 6405.583637063576, 6410.519211059096, 6411.134496842867, 6431.967840160769, 6432.517561183024], "value": 5049.349232176289, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [883.0551310098193, 889.2308153447262, 895.3061377432489, 900.3607438718509, 912.6988093929004, 16628.66742665146, 17539.123043847805, 17713.291468341267, 21888.781112188874, 22858.77141228587, 19698.507537311576, 20174.697379539317, 21166.0, 21204.681929771064, 21397.251096211636, 1031.3760175094073, 1048.035807057505, 1048.2819268800858, 1048.36573872807, 1049.4542837724416, 969.1035791892507, 1048.2557384257186, 1050.2543194332034, 1059.5655781129738, 1065.8614380130573, 1113.7104352868273, 1114.2701530497536, 1116.40272454237, 1116.4585176189541, 1116.8771435142128, 1106.0985296982933, 1108.578740078773, 1110.5224753356094, 1111.4053981120103, 1112.6217086190652, 812.4515951732557, 814.8899898513744, 815.6655771133807, 818.292177266666, 818.7420962396826, 19782.70325945112, 20071.1971521139, 20534.0, 20692.48268793277, 20799.480012999644, 21201.89399052995, 21505.892470537703, 21551.245706400357, 21660.783392166035, 21703.782962170335, 16458.588535286595, 16494.75257871133, 16579.585510362216, 16808.663826723518, 16986.915065424593, 3498.982505087484, 3518.032541051206, 3519.665631764978, 3520.0495866116007, 3522.964770352315, 117532.0, 119288.0, 120349.39825300817, 121682.0, 122922.0, 15610.3755849767, 15936.601584960468, 16076.839231607768, 16158.838411615969, 16163.676726465404, 27896.0, 31773.841130794197, 32395.0, 32415.0, 32446.675533244837, 8802.0, 9037.367384283169, 9043.864342034874, 9051.185393314634, 9057.547122643842, 4086.203190377907, 4138.358554424069, 4186.602272784081, 4192.3711443283755, 4195.202911446815, 5189.377274727057, 5302.973485132549, 5369.4362091980565, 5376.731163441812, 5378.9462105379225, 1051.2903789941759, 1075.2903083964702, 1087.0814162033068, 1094.7810437912449, 1095.189559725813, 586.1266712598198, 600.6786369292407, 607.0, 613.5398451161628, 631.8325643704422, 11247.437628118561, 11266.94366528162, 11927.22473039267, 12002.759944801055, 13362.198268103944, 678.223434167875, 679.3342524326188, 682.743971010871, 684.4079870911725, 687.4740823270203, 5493.901219756065, 5848.210491583582, 5894.823155305347, 5989.251343582052, 6074.058520929263, 110351.0, 113315.0, 113410.43294783474, 114577.42711286391, 117541.0, 96707.0, 97735.0, 97752.0, 99903.50048249858, 100282.49858750659, 83124.0, 88343.0, 89835.55082224547, 90618.54690726635, 92427.0, 12085.274883507018, 12706.936465317614, 12722.173058751152, 12882.162659427107, 13057.608271751862, 39346.21307573775, 40228.0, 40281.597184027785, 41294.58705412907, 41416.1716765657, 50518.74740626274, 50717.49282507128, 50997.235041473665, 52574.0, 52938.205926910356, 17974.730379044064, 18039.0, 18259.0, 18307.633847323246, 18833.90583047076, 17934.91032544829, 18082.186301616723, 18425.355112570996, 18792.812071879107, 18945.90527047356, 18004.279828807226, 18034.549136271697, 18195.363162289257, 18244.0, 18426.72359914629, 18561.44315670532, 18794.812051879853, 18823.71764423562, 18844.152013159714, 18921.243150273836, 16871.156442178086, 17051.0, 17224.913875430542, 17348.913255433643, 17393.304267829146, 13330.533431329857, 13775.0, 13937.0, 13997.720045599235, 14104.78842817338, 13953.906913962986, 14080.366383512972, 14140.72733453994, 14992.175430350999, 15355.925085243925], "value": 9147.254690299149, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1532.9000000000005, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 108.0, 101.0, 107.0, 110.0, 109.0, 108.0, 100.0, 110.0, 109.0], "value": 106.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 117.0, 152.0, 185.0, 119.0, 143.0, 140.0, 117.0, 176.0, 120.0], "value": 141.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 162.0, 164.0, 162.0, 164.0, 162.0, 168.0, 163.0, 164.0], "value": 163.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 177.0, 76.0, 76.0, 74.0, 76.0, 159.0, 77.0], "value": 94.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 79.0, 130.0, 80.0, 79.0, 79.0, 135.0, 79.0, 80.0, 130.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 114.0, 114.0, 113.0, 114.0, 115.0, 114.0, 115.0, 115.0], "value": 114.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 150.0, 152.0, 151.0, 150.0, 150.0, 150.0, 152.0, 149.0], "value": 150.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 99.0, 93.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 57.0, 57.0, 56.0, 56.0, 56.0, 56.0, 56.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 57.0, 56.0, 55.0, 55.0, 55.0, 55.0, 55.0, 57.0], "value": 55.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 78.0, 79.0, 80.0, 81.0, 77.0, 76.0, 77.0, 76.0], "value": 77.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 126.0, 122.0, 123.0, 131.0, 138.0, 132.0, 127.0, 122.0, 167.0], "value": 131.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 176.0, 181.0, 186.0, 189.0, 182.0, 179.0, 185.0, 183.0, 181.0], "value": 181.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 70.0, 67.0, 71.0, 69.0, 69.0, 68.0, 67.0, 67.0], "value": 68.4, "unit": "score"}]}]} 14:29:45 INFO - Return code: 0 14:29:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:29:46 INFO - # TBPL SUCCESS # 14:29:46 INFO - Running post-action listener: _package_coverage_data 14:29:46 INFO - Running post-action listener: _resource_record_post_action 14:29:46 INFO - [mozharness: 2017-08-11 21:29:46.003456Z] Finished run-tests step (success) 14:29:46 INFO - Running post-run listener: _resource_record_post_run 14:29:46 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 444956672; Read time: 12; Write time: 265744 14:29:46 INFO - TinderboxPrint: CPU usage
13.1% 14:29:46 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 14:29:46 INFO - TinderboxPrint: I/O write bytes / time
444,956,672 / 265,744 14:29:46 INFO - TinderboxPrint: CPU idle
8,257.2 (86.9%) 14:29:46 INFO - TinderboxPrint: CPU user
1,200.0 (12.6%) 14:29:46 INFO - TinderboxPrint: Swap in / out
0 / 0 14:29:46 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 120340480; Read time: 0; Write time: 97212 14:29:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 14:29:46 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 324616192; Read time: 0; Write time: 168532 14:29:46 INFO - Running post-run listener: _upload_blobber_files 14:29:46 INFO - Blob upload gear active. 14:29:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:29:46 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 14:29:46 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'] 14:29:46 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 14:29:47 INFO - (blobuploader) - INFO - Open directory for files ... 14:29:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:29:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:29:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:29:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:29:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:29:47 INFO - (blobuploader) - INFO - Done attempting. 14:29:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:29:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:29:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:29:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:29:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:29:51 INFO - (blobuploader) - INFO - Done attempting. 14:29:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:29:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:29:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:29:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:29:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:29:51 INFO - (blobuploader) - INFO - Done attempting. 14:29:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:29:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:29:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:29:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:29:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:29:52 INFO - (blobuploader) - INFO - Done attempting. 14:29:52 INFO - (blobuploader) - INFO - Iteration through files over. 14:29:52 INFO - Return code: 0 14:29:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:29:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:29:52 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c894c7653092e28f9311ee269d1be97fc5323f356bb41047f95fecf3404d4341edcd3880cde74da828bc8fc7c86fdf093517a0c71a718d2ae7de27b4e376f3bd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b57de9f577cd8676019fd09422972b036ae6d5b6239ad9a4eb512b65f0c2c48ef3f520819193d119a0d403f2deef763a3b092dc97f27bbff7ff31584d5293efb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/83b2d4b95fc1b1bde9a704a6109563473fcf118e7de83e79b4c0a3047b3b14d138df228ee46e6f13075a77f27cddca6cfdcd7d2807bea908513323eb39577cc1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d2fed9ad8ad52ac0158e227d3b5da1de3ce6cc93259e6ba8e1058df341129ea4351a2c63743ccb54710fa50e2cb3d49aa6d54ce432148014b710f8d08bb8b76"} 14:29:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:29:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:29:52 INFO - Contents: 14:29:52 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c894c7653092e28f9311ee269d1be97fc5323f356bb41047f95fecf3404d4341edcd3880cde74da828bc8fc7c86fdf093517a0c71a718d2ae7de27b4e376f3bd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b57de9f577cd8676019fd09422972b036ae6d5b6239ad9a4eb512b65f0c2c48ef3f520819193d119a0d403f2deef763a3b092dc97f27bbff7ff31584d5293efb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/83b2d4b95fc1b1bde9a704a6109563473fcf118e7de83e79b4c0a3047b3b14d138df228ee46e6f13075a77f27cddca6cfdcd7d2807bea908513323eb39577cc1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d2fed9ad8ad52ac0158e227d3b5da1de3ce6cc93259e6ba8e1058df341129ea4351a2c63743ccb54710fa50e2cb3d49aa6d54ce432148014b710f8d08bb8b76"} 14:29:52 INFO - Running post-run listener: copy_logs_to_upload_dir 14:29:52 INFO - Copying logs to upload dir... 14:29:52 INFO - mkdir: /builds/slave/test/build/upload/logs 14:29:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.501381 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-08-11 14:29:51.978018) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-11 14:29:51.986062) ========= 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=0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c894c7653092e28f9311ee269d1be97fc5323f356bb41047f95fecf3404d4341edcd3880cde74da828bc8fc7c86fdf093517a0c71a718d2ae7de27b4e376f3bd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b57de9f577cd8676019fd09422972b036ae6d5b6239ad9a4eb512b65f0c2c48ef3f520819193d119a0d403f2deef763a3b092dc97f27bbff7ff31584d5293efb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/83b2d4b95fc1b1bde9a704a6109563473fcf118e7de83e79b4c0a3047b3b14d138df228ee46e6f13075a77f27cddca6cfdcd7d2807bea908513323eb39577cc1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d2fed9ad8ad52ac0158e227d3b5da1de3ce6cc93259e6ba8e1058df341129ea4351a2c63743ccb54710fa50e2cb3d49aa6d54ce432148014b710f8d08bb8b76"} build_url:https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011340 build_url: 'https://queue.taskcluster.net/v1/task/ciIwbBSeQxOcIhzBZHfbuA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c894c7653092e28f9311ee269d1be97fc5323f356bb41047f95fecf3404d4341edcd3880cde74da828bc8fc7c86fdf093517a0c71a718d2ae7de27b4e376f3bd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b57de9f577cd8676019fd09422972b036ae6d5b6239ad9a4eb512b65f0c2c48ef3f520819193d119a0d403f2deef763a3b092dc97f27bbff7ff31584d5293efb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/83b2d4b95fc1b1bde9a704a6109563473fcf118e7de83e79b4c0a3047b3b14d138df228ee46e6f13075a77f27cddca6cfdcd7d2807bea908513323eb39577cc1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d2fed9ad8ad52ac0158e227d3b5da1de3ce6cc93259e6ba8e1058df341129ea4351a2c63743ccb54710fa50e2cb3d49aa6d54ce432148014b710f8d08bb8b76"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-11 14:29:52.013753) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 14:29:52.014106) ========= 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=0e2a256762fed810e2f0f810000001b3-1502485740.865096-890003875 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004432 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 14:29:52.063542) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-11 14:29:52.066914) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-11 14:29:52.067250) ========= ========= Total master_lag: 0.16 =========