builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-080 starttime: 1505343768.82 results: success (0) revision: 8645a74bbbd06b67699317df1abf3897db0e43d5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:48.824919) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:48.825374) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:48.825650) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:48.845418) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:48.845774) ========= 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=781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-13 16:02:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 110M=0s 2017-09-13 16:02:48 (110 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111238 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:48.973829) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:48.974142) ========= 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=781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016902 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:49.028586) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-13 16:02:49.028941) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8645a74bbbd06b67699317df1abf3897db0e43d5 --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 8645a74bbbd06b67699317df1abf3897db0e43d5 --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=781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-13 16:02:49,116 truncating revision to first 12 chars 2017-09-13 16:02:49,116 Setting DEBUG logging. 2017-09-13 16:02:49,116 attempt 1/10 2017-09-13 16:02:49,116 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8645a74bbbd0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-13 16:02:49,951 unpacking tar archive at: mozilla-central-8645a74bbbd0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.149549 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-13 16:02:50.201774) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:50.204678) ========= script_repo_revision: 8645a74bbbd06b67699317df1abf3897db0e43d5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:50.205143) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:50.205437) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:50.224036) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-13 16:02:50.224448) ========= /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=781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:02:50 INFO - MultiFileLogger online at 20170913 16:02:50 in /builds/slave/test 16:02:50 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 16:02:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:02:50 INFO - {'append_to_log': False, 16:02:50 INFO - 'base_work_dir': '/builds/slave/test', 16:02:50 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:02:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:02:50 INFO - 'branch': 'Firefox-Non-PGO', 16:02:50 INFO - 'buildbot_json_path': 'buildprops.json', 16:02:50 INFO - 'code_coverage': False, 16:02:50 INFO - 'config_files': ('talos/linux_config.py',), 16:02:50 INFO - 'default_actions': ('clobber', 16:02:50 INFO - 'read-buildbot-config', 16:02:50 INFO - 'download-and-extract', 16:02:50 INFO - 'populate-webroot', 16:02:50 INFO - 'create-virtualenv', 16:02:50 INFO - 'install', 16:02:50 INFO - 'setup-mitmproxy', 16:02:50 INFO - 'run-tests'), 16:02:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:02:50 INFO - 'disable_ccov_upload': False, 16:02:50 INFO - 'disable_stylo': False, 16:02:50 INFO - 'download_minidump_stackwalk': True, 16:02:50 INFO - 'download_symbols': 'ondemand', 16:02:50 INFO - 'enable_stylo': False, 16:02:50 INFO - 'enable_webrender': False, 16:02:50 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:02:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:02:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:02:50 INFO - 'gecko_profile': False, 16:02:50 INFO - 'gecko_profile_interval': 0, 16:02:50 INFO - 'installer_path': 'installer.exe', 16:02:50 INFO - 'log_level': 'info', 16:02:50 INFO - 'log_name': 'talos', 16:02:50 INFO - 'log_to_console': True, 16:02:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:02:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:02:50 INFO - 'opt_config_files': (), 16:02:50 INFO - 'pip_index': False, 16:02:50 INFO - 'suite': 'dromaeojs-e10s', 16:02:50 INFO - 'system_bits': '32', 16:02:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:02:50 INFO - 'title': 'talos-linux64-ix-080', 16:02:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:02:50 INFO - 'use_talos_json': True, 16:02:50 INFO - 'verify': 'False', 16:02:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:02:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:02:50 INFO - 'work_dir': 'build'} 16:02:50 INFO - [mozharness: 2017-09-13 23:02:50.448335Z] Running clobber step. 16:02:50 INFO - Running pre-action listener: _resource_record_pre_action 16:02:50 INFO - Running main action method: clobber 16:02:50 INFO - rmtree: /builds/slave/test/build 16:02:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:02:51 INFO - Running post-action listener: _resource_record_post_action 16:02:51 INFO - [mozharness: 2017-09-13 23:02:51.449760Z] Finished clobber step (success) 16:02:51 INFO - [mozharness: 2017-09-13 23:02:51.449870Z] Running read-buildbot-config step. 16:02:51 INFO - Running pre-action listener: _resource_record_pre_action 16:02:51 INFO - Running main action method: read_buildbot_config 16:02:51 INFO - Using buildbot properties: 16:02:51 INFO - { 16:02:51 INFO - "project": "", 16:02:51 INFO - "product": "firefox", 16:02:51 INFO - "who": "archaeopteryx@coole-files.de", 16:02:51 INFO - "installer_path": "public/build/target.tar.bz2", 16:02:51 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:02:51 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:02:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 16:02:51 INFO - "stage_platform": "linux64", 16:02:51 INFO - "basedir": "/builds/slave/test", 16:02:51 INFO - "buildnumber": 820, 16:02:51 INFO - "platform": "ubuntu64_hw", 16:02:51 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:02:51 INFO - "slavebuilddir": "test", 16:02:51 INFO - "taskId": "eLTqrYfgRZWaWqW4o6Fb6Q", 16:02:51 INFO - "branch": "mozilla-central", 16:02:51 INFO - "script_repo_revision": "production", 16:02:51 INFO - "revision": "8645a74bbbd06b67699317df1abf3897db0e43d5", 16:02:51 INFO - "slavename": "talos-linux64-ix-080", 16:02:51 INFO - "repo_path": "mozilla-central" 16:02:51 INFO - } 16:02:51 INFO - Finding installer, test and symbols from parent task. 16:02:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eLTqrYfgRZWaWqW4o6Fb6Q'}, attempt #1 16:02:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eLTqrYfgRZWaWqW4o6Fb6Q'}, attempt #1 16:02:52 INFO - Task dependencies: A1V4MAzpSR2fT4tPLmTCBw 16:02:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw'}, attempt #1 16:02:52 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.tar.bz2 16:02:52 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.test_packages.json 16:02:52 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.crashreporter-symbols.zip 16:02:52 INFO - Running post-action listener: _resource_record_post_action 16:02:52 INFO - [mozharness: 2017-09-13 23:02:52.819299Z] Finished read-buildbot-config step (success) 16:02:52 INFO - [mozharness: 2017-09-13 23:02:52.819516Z] Running download-and-extract step. 16:02:52 INFO - Running pre-action listener: _resource_record_pre_action 16:02:52 INFO - Running main action method: download_and_extract 16:02:52 INFO - mkdir: /builds/slave/test/build/tests 16:02: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')]} 16:02:52 INFO - trying https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.test_packages.json 16:02:52 INFO - Downloading https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:02:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:02:53 INFO - Downloaded 989 bytes. 16:02:53 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:02:53 INFO - Using the following test package requirements: 16:02:53 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:02:53 INFO - u'common': [u'target.common.tests.zip'], 16:02:53 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:02:53 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:02:53 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:02:53 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:02:53 INFO - u'mozbase': [u'target.common.tests.zip'], 16:02:53 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:02:53 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:02:53 INFO - u'web-platform': [u'target.common.tests.zip', 16:02:53 INFO - u'target.web-platform.tests.tar.gz'], 16:02:53 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:02:53 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 16:02:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.common.tests.zip 16:02:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:02:53 INFO - Fetch https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.common.tests.zip into memory 16:02:54 INFO - Content-Length response header: 54572657 16:02:54 INFO - Bytes received: 54572657 16:03:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.talos.tests.zip 16:03:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:03:05 INFO - Fetch https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.talos.tests.zip into memory 16:03:06 INFO - Content-Length response header: 14500351 16:03:06 INFO - Bytes received: 14500351 16:03:06 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')]} 16:03:06 INFO - trying https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.tar.bz2 16:03:06 INFO - Downloading https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:03:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:03:07 INFO - Downloaded 61287064 bytes. 16:03:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.tar.bz2 16:03:07 INFO - mkdir: /builds/slave/test/properties 16:03:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:03:07 INFO - Writing to file /builds/slave/test/properties/build_url 16:03:07 INFO - Contents: 16:03:07 INFO - build_url:https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.tar.bz2 16:03:07 INFO - Running post-action listener: _resource_record_post_action 16:03:07 INFO - Running post-action listener: find_tests_for_verification 16:03:07 INFO - Running post-action listener: set_extra_try_arguments 16:03:07 INFO - [mozharness: 2017-09-13 23:03:07.604711Z] Finished download-and-extract step (success) 16:03:07 INFO - [mozharness: 2017-09-13 23:03:07.604809Z] Running populate-webroot step. 16:03:07 INFO - Running pre-action listener: _resource_record_pre_action 16:03:07 INFO - Running main action method: populate_webroot 16:03:07 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:03:07 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:03:07 INFO - 'tests': ['tresize', 16:03:07 INFO - 'tcanvasmark']}, 16:03:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:03:07 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:03:07 INFO - 'tests': ['dromaeo_css', 16:03:07 INFO - 'kraken']}, 16:03:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:03:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:03:07 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:03:07 INFO - 'talos_options': ['--disable-stylo'], 16:03:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:03:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:03:07 INFO - 'tests': ['damp', 'tps']}, 16:03:07 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:03:07 INFO - 'talos_options': ['--disable-stylo'], 16:03:07 INFO - 'tests': ['damp', 'tps']}, 16:03:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:03:07 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:03:07 INFO - 'tests': ['dromaeo_dom']}, 16:03:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:03:07 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:03:07 INFO - 'tests': ['basic_compositor_video', 16:03:07 INFO - 'glvideo']}, 16:03:07 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:03:07 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:03:07 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:03:07 INFO - 'talos_options': ['--disable-stylo'], 16:03:07 INFO - 'tests': ['ts_paint_webext', 16:03:07 INFO - 'tp5o_webext']}, 16:03:07 INFO - 'other-e10s': {'tests': ['a11yr', 16:03:07 INFO - 'ts_paint', 16:03:07 INFO - 'tpaint', 16:03:07 INFO - 'sessionrestore', 16:03:07 INFO - 'sessionrestore_many_windows', 16:03:07 INFO - 'sessionrestore_no_auto_restore', 16:03:07 INFO - 'tabpaint']}, 16:03:07 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:03:07 INFO - 'tests': ['a11yr', 16:03:07 INFO - 'ts_paint', 16:03:07 INFO - 'tpaint', 16:03:07 INFO - 'sessionrestore', 16:03:07 INFO - 'sessionrestore_many_windows', 16:03:07 INFO - 'sessionrestore_no_auto_restore', 16:03:07 INFO - 'tabpaint']}, 16:03:07 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:03:07 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:03:07 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:03:07 INFO - 'tests': ['bloom_basic_singleton']}, 16:03:07 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:03:07 INFO - 'tests': ['bloom_basic']}, 16:03:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:03:07 INFO - 'tsvgr_opacity', 16:03:07 INFO - 'tart', 16:03:07 INFO - 'tscrollx', 16:03:07 INFO - 'tsvg_static']}, 16:03:07 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:03:07 INFO - 'tests': ['tsvgx', 16:03:07 INFO - 'tsvgr_opacity', 16:03:07 INFO - 'tart', 16:03:07 INFO - 'tscrollx', 16:03:07 INFO - 'tsvg_static']}, 16:03:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:03:07 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:03:07 INFO - 'talos_options': ['--disable-stylo'], 16:03:07 INFO - 'tests': ['tp5o']}, 16:03:07 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:03:07 INFO - 'talos_options': ['--mitmproxy', 16:03:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:03:07 INFO - '--firstNonBlankPaint'], 16:03:07 INFO - 'tests': ['tp6_google', 16:03:07 INFO - 'tp6_youtube', 16:03:07 INFO - 'tp6_amazon', 16:03:07 INFO - 'tp6_facebook']}, 16:03:07 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:03:07 INFO - 'talos_options': ['--disable-stylo', 16:03:07 INFO - '--mitmproxy', 16:03:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:03:07 INFO - '--firstNonBlankPaint'], 16:03:07 INFO - 'tests': ['tp6_google', 16:03:07 INFO - 'tp6_youtube', 16:03:07 INFO - 'tp6_amazon', 16:03:07 INFO - 'tp6_facebook']}, 16:03:07 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:03:07 INFO - 'talos_options': ['--stylo-threads=1', 16:03:07 INFO - '--mitmproxy', 16:03:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:03:07 INFO - '--firstNonBlankPaint'], 16:03:07 INFO - 'tests': ['tp6_google', 16:03:07 INFO - 'tp6_youtube', 16:03:07 INFO - 'tp6_amazon', 16:03:07 INFO - 'tp6_facebook']}, 16:03:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:03:07 INFO - 'talos_options': ['--xperf_path', 16:03:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:03:07 INFO - 'tests': ['tp5n']}, 16:03:07 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:03:07 INFO - 'talos_options': ['--disable-stylo', 16:03:07 INFO - '--xperf_path', 16:03:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:03:07 INFO - 'tests': ['tp5n']}}} 16:03:07 INFO - Running post-action listener: _resource_record_post_action 16:03:07 INFO - [mozharness: 2017-09-13 23:03:07.619644Z] Finished populate-webroot step (success) 16:03:07 INFO - [mozharness: 2017-09-13 23:03:07.619733Z] Running create-virtualenv step. 16:03:07 INFO - Running pre-action listener: _resource_record_pre_action 16:03:07 INFO - Running main action method: create_virtualenv 16:03:07 INFO - Creating virtualenv /builds/slave/test/build/venv 16:03:07 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:03:07 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:03:07 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:03:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:03:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:03:08 INFO - Installing distribute.............................................................................................................................................................................................done. 16:03:08 INFO - Installing pip...............done. 16:03:08 INFO - Return code: 0 16:03:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:03:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:03:08 INFO - Reading from file tmpfile_stdout 16:03:08 INFO - Output received: 16:03:08 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:03:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:03:08 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')]} 16:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:08 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')]} 16:03:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:08 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 0x9cc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb95a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3c090>, '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 0xd3cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd36f90>, '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': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', '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 16:03:08 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 16:03:08 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 16:03:08 INFO - Using env: {'DISPLAY': ':0', 16:03:08 INFO - 'HOME': '/home/cltbld', 16:03:08 INFO - 'LANG': 'en_US.UTF-8', 16:03:08 INFO - 'LANGUAGE': 'en_US:en', 16:03:08 INFO - 'LOGNAME': 'cltbld', 16:03:08 INFO - 'MAIL': '/var/mail/cltbld', 16:03:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:08 INFO - 'MOZ_NO_REMOTE': '1', 16:03:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:08 INFO - 'NO_EM_RESTART': '1', 16:03:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:08 INFO - 'PWD': '/builds/slave/test', 16:03:08 INFO - 'SHELL': '/bin/bash', 16:03:08 INFO - 'SHLVL': '1', 16:03:08 INFO - 'TERM': 'linux', 16:03:08 INFO - 'TMOUT': '86400', 16:03:08 INFO - 'USER': 'cltbld', 16:03:08 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', 16:03:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:08 INFO - '_': '/tools/buildbot/bin/python'} 16:03:09 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:03:09 INFO - Downloading/unpacking pip>=1.5 16:03:09 INFO - Running setup.py egg_info for package pip 16:03:09 INFO - warning: no files found matching 'pip/cacert.pem' 16:03:09 INFO - warning: no files found matching '*.html' under directory 'docs' 16:03:09 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:03:09 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:03:09 INFO - Installing collected packages: pip 16:03:09 INFO - Found existing installation: pip 1.1 16:03:09 INFO - Uninstalling pip: 16:03:09 INFO - Successfully uninstalled pip 16:03:09 INFO - Running setup.py install for pip 16:03:09 INFO - warning: no files found matching 'pip/cacert.pem' 16:03:09 INFO - warning: no files found matching '*.html' under directory 'docs' 16:03:09 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:03:09 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:03:09 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:03:09 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:03:09 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:03:09 INFO - Successfully installed pip 16:03:09 INFO - Cleaning up... 16:03:09 INFO - Return code: 0 16:03:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:03:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x9cc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb95a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3c090>, '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 0xd3cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd36f90>, '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': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', '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 16:03:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:03:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:03:09 INFO - Using env: {'DISPLAY': ':0', 16:03:09 INFO - 'HOME': '/home/cltbld', 16:03:09 INFO - 'LANG': 'en_US.UTF-8', 16:03:09 INFO - 'LANGUAGE': 'en_US:en', 16:03:09 INFO - 'LOGNAME': 'cltbld', 16:03:09 INFO - 'MAIL': '/var/mail/cltbld', 16:03:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:09 INFO - 'MOZ_NO_REMOTE': '1', 16:03:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:09 INFO - 'NO_EM_RESTART': '1', 16:03:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:09 INFO - 'PWD': '/builds/slave/test', 16:03:09 INFO - 'SHELL': '/bin/bash', 16:03:09 INFO - 'SHLVL': '1', 16:03:09 INFO - 'TERM': 'linux', 16:03:09 INFO - 'TMOUT': '86400', 16:03:09 INFO - 'USER': 'cltbld', 16:03:09 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', 16:03:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:09 INFO - '_': '/tools/buildbot/bin/python'} 16:03:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:10 INFO - Downloading/unpacking psutil>=3.1.1 16:03:10 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 16:03:10 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 16:03:10 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 16:03:10 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 16:03:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:03:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:03:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:03:11 INFO - Installing collected packages: psutil 16:03:11 INFO - Running setup.py install for psutil 16:03:11 INFO - building 'psutil._psutil_linux' extension 16:03:11 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 16:03:11 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 16:03:11 INFO - building 'psutil._psutil_posix' extension 16:03:11 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 16:03:11 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 16:03:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:03:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:03:11 INFO - Successfully installed psutil 16:03:11 INFO - Cleaning up... 16:03:11 INFO - Return code: 0 16:03:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:03: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')]} 16:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03: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')]} 16:03:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:11 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 0x9cc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb95a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3c090>, '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 0xd3cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd36f90>, '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': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', '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 16:03:11 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 16:03:11 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 16:03:11 INFO - Using env: {'DISPLAY': ':0', 16:03:11 INFO - 'HOME': '/home/cltbld', 16:03:11 INFO - 'LANG': 'en_US.UTF-8', 16:03:11 INFO - 'LANGUAGE': 'en_US:en', 16:03:11 INFO - 'LOGNAME': 'cltbld', 16:03:11 INFO - 'MAIL': '/var/mail/cltbld', 16:03:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:11 INFO - 'MOZ_NO_REMOTE': '1', 16:03:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:11 INFO - 'NO_EM_RESTART': '1', 16:03:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:11 INFO - 'PWD': '/builds/slave/test', 16:03:11 INFO - 'SHELL': '/bin/bash', 16:03:11 INFO - 'SHLVL': '1', 16:03:11 INFO - 'TERM': 'linux', 16:03:11 INFO - 'TMOUT': '86400', 16:03:11 INFO - 'USER': 'cltbld', 16:03:11 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', 16:03:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:11 INFO - '_': '/tools/buildbot/bin/python'} 16:03:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:03:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:03:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:03:13 INFO - Installing collected packages: mozsystemmonitor 16:03:13 INFO - Running setup.py install for mozsystemmonitor 16:03:13 INFO - Successfully installed mozsystemmonitor 16:03:13 INFO - Cleaning up... 16:03:14 INFO - Return code: 0 16:03:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:03:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x9cc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb95a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3c090>, '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 0xd3cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd36f90>, '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': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', '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 16:03:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:03:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:03:14 INFO - Using env: {'DISPLAY': ':0', 16:03:14 INFO - 'HOME': '/home/cltbld', 16:03:14 INFO - 'LANG': 'en_US.UTF-8', 16:03:14 INFO - 'LANGUAGE': 'en_US:en', 16:03:14 INFO - 'LOGNAME': 'cltbld', 16:03:14 INFO - 'MAIL': '/var/mail/cltbld', 16:03:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:14 INFO - 'MOZ_NO_REMOTE': '1', 16:03:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:14 INFO - 'NO_EM_RESTART': '1', 16:03:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:14 INFO - 'PWD': '/builds/slave/test', 16:03:14 INFO - 'SHELL': '/bin/bash', 16:03:14 INFO - 'SHLVL': '1', 16:03:14 INFO - 'TERM': 'linux', 16:03:14 INFO - 'TMOUT': '86400', 16:03:14 INFO - 'USER': 'cltbld', 16:03:14 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', 16:03:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:14 INFO - '_': '/tools/buildbot/bin/python'} 16:03:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:14 INFO - Downloading/unpacking jsonschema==2.5.1 16:03:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:03:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:03:16 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 16:03:16 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 16:03:16 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 16:03:16 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 16:03:16 INFO - Downloading functools32-3.2.3-2.tar.gz 16:03:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:03:16 INFO - warning: no files found matching '*.txt' 16:03:16 INFO - no previously-included directories found matching 'build' 16:03:16 INFO - no previously-included directories found matching 'dist' 16:03:16 INFO - no previously-included directories found matching '.git*' 16:03:16 INFO - Installing collected packages: jsonschema, functools32 16:03:17 INFO - Running setup.py install for functools32 16:03:17 INFO - warning: no files found matching '*.txt' 16:03:17 INFO - no previously-included directories found matching 'build' 16:03:17 INFO - no previously-included directories found matching 'dist' 16:03:17 INFO - no previously-included directories found matching '.git*' 16:03:17 INFO - Successfully installed jsonschema functools32 16:03:17 INFO - Cleaning up... 16:03:17 INFO - Return code: 0 16:03:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:03:17 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')]} 16:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:17 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')]} 16:03:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:17 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 0x9cc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb95a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3c090>, '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 0xd3cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd36f90>, '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': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', '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 16:03:17 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 16:03:17 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 16:03:17 INFO - Using env: {'DISPLAY': ':0', 16:03:17 INFO - 'HOME': '/home/cltbld', 16:03:17 INFO - 'LANG': 'en_US.UTF-8', 16:03:17 INFO - 'LANGUAGE': 'en_US:en', 16:03:17 INFO - 'LOGNAME': 'cltbld', 16:03:17 INFO - 'MAIL': '/var/mail/cltbld', 16:03:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:17 INFO - 'MOZ_NO_REMOTE': '1', 16:03:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:17 INFO - 'NO_EM_RESTART': '1', 16:03:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:17 INFO - 'PWD': '/builds/slave/test', 16:03:17 INFO - 'SHELL': '/bin/bash', 16:03:17 INFO - 'SHLVL': '1', 16:03:17 INFO - 'TERM': 'linux', 16:03:17 INFO - 'TMOUT': '86400', 16:03:17 INFO - 'USER': 'cltbld', 16:03:17 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', 16:03:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:17 INFO - '_': '/tools/buildbot/bin/python'} 16:03:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:03:18 INFO - Cleaning up... 16:03:18 INFO - Return code: 0 16:03:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:03:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x9cc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb95a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3c090>, '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 0xd3cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd36f90>, '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': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', '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 16:03:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:03:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:03:18 INFO - Using env: {'DISPLAY': ':0', 16:03:18 INFO - 'HOME': '/home/cltbld', 16:03:18 INFO - 'LANG': 'en_US.UTF-8', 16:03:18 INFO - 'LANGUAGE': 'en_US:en', 16:03:18 INFO - 'LOGNAME': 'cltbld', 16:03:18 INFO - 'MAIL': '/var/mail/cltbld', 16:03:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:18 INFO - 'MOZ_NO_REMOTE': '1', 16:03:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:18 INFO - 'NO_EM_RESTART': '1', 16:03:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:18 INFO - 'PWD': '/builds/slave/test', 16:03:18 INFO - 'SHELL': '/bin/bash', 16:03:18 INFO - 'SHLVL': '1', 16:03:18 INFO - 'TERM': 'linux', 16:03:18 INFO - 'TMOUT': '86400', 16:03:18 INFO - 'USER': 'cltbld', 16:03:18 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', 16:03:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:18 INFO - '_': '/tools/buildbot/bin/python'} 16:03:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:18 INFO - Downloading/unpacking blobuploader==1.2.4 16:03:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:19 INFO - Downloading blobuploader-1.2.4.tar.gz 16:03:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:03:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:03:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:20 INFO - Downloading docopt-0.6.1.tar.gz 16:03:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:03:20 INFO - Installing collected packages: blobuploader, requests, docopt 16:03:20 INFO - Running setup.py install for blobuploader 16:03:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:03:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:03:20 INFO - Running setup.py install for requests 16:03:20 INFO - Running setup.py install for docopt 16:03:20 INFO - Successfully installed blobuploader requests docopt 16:03:20 INFO - Cleaning up... 16:03:20 INFO - Return code: 0 16:03:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:03:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:20 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 0x9cc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb95a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3c090>, '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 0xd3cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd36f90>, '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': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', '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 16:03:20 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 16:03:20 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 16:03:20 INFO - Using env: {'DISPLAY': ':0', 16:03:20 INFO - 'HOME': '/home/cltbld', 16:03:20 INFO - 'LANG': 'en_US.UTF-8', 16:03:20 INFO - 'LANGUAGE': 'en_US:en', 16:03:20 INFO - 'LOGNAME': 'cltbld', 16:03:20 INFO - 'MAIL': '/var/mail/cltbld', 16:03:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:20 INFO - 'MOZ_NO_REMOTE': '1', 16:03:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:20 INFO - 'NO_EM_RESTART': '1', 16:03:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:20 INFO - 'PWD': '/builds/slave/test', 16:03:20 INFO - 'SHELL': '/bin/bash', 16:03:20 INFO - 'SHLVL': '1', 16:03:20 INFO - 'TERM': 'linux', 16:03:20 INFO - 'TMOUT': '86400', 16:03:20 INFO - 'USER': 'cltbld', 16:03:20 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', 16:03:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:20 INFO - '_': '/tools/buildbot/bin/python'} 16:03:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:03:21 INFO - Running setup.py (path:/tmp/pip-XmVM4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:03:21 INFO - Running setup.py (path:/tmp/pip-Vzxc4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:03:21 INFO - Running setup.py (path:/tmp/pip-4ZD0D7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:03:21 INFO - Running setup.py (path:/tmp/pip-L2ucV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:03:21 INFO - Running setup.py (path:/tmp/pip-ueeC3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:03:21 INFO - Running setup.py (path:/tmp/pip-LCWUIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:03:21 INFO - Running setup.py (path:/tmp/pip-5zeO_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:03:21 INFO - Running setup.py (path:/tmp/pip-Q1Vf3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:03:21 INFO - Running setup.py (path:/tmp/pip-M03Qyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:03:21 INFO - Running setup.py (path:/tmp/pip-SB_txw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:03:21 INFO - Running setup.py (path:/tmp/pip-dRPe0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:03:21 INFO - Running setup.py (path:/tmp/pip-JyRjhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:03:21 INFO - Running setup.py (path:/tmp/pip-fKHK7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:03:22 INFO - Running setup.py (path:/tmp/pip-BGP6hE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:03:22 INFO - Running setup.py (path:/tmp/pip-SGwynI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:03:22 INFO - Running setup.py (path:/tmp/pip-ZWOGNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:03:22 INFO - Running setup.py (path:/tmp/pip-LIGD8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:03:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:03:22 INFO - Running setup.py install for manifestparser 16:03:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:03:22 INFO - Running setup.py install for mozcrash 16:03:22 INFO - Running setup.py install for mozdebug 16:03:22 INFO - Running setup.py install for mozdevice 16:03:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:03:22 INFO - Running setup.py install for mozfile 16:03:22 INFO - Running setup.py install for mozhttpd 16:03:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:03:22 INFO - Running setup.py install for mozinfo 16:03:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:03:22 INFO - Running setup.py install for mozInstall 16:03:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:03:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:03:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:03:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:03:23 INFO - Running setup.py install for mozleak 16:03:23 INFO - Running setup.py install for mozlog 16:03:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:03:23 INFO - Running setup.py install for moznetwork 16:03:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:03:23 INFO - Running setup.py install for mozprocess 16:03:23 INFO - Running setup.py install for mozprofile 16:03:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:03:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:03:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:03:23 INFO - Running setup.py install for mozrunner 16:03:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:03:23 INFO - Running setup.py install for mozscreenshot 16:03:23 INFO - Running setup.py install for moztest 16:03:23 INFO - Running setup.py install for mozversion 16:03:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:03:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:03:24 INFO - Cleaning up... 16:03:24 INFO - Return code: 0 16:03:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:24 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 0x9cc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb95a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3c090>, '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 0xd3cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd36f90>, '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': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', '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 16:03:24 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 16:03:24 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 16:03:24 INFO - Using env: {'DISPLAY': ':0', 16:03:24 INFO - 'HOME': '/home/cltbld', 16:03:24 INFO - 'LANG': 'en_US.UTF-8', 16:03:24 INFO - 'LANGUAGE': 'en_US:en', 16:03:24 INFO - 'LOGNAME': 'cltbld', 16:03:24 INFO - 'MAIL': '/var/mail/cltbld', 16:03:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:24 INFO - 'MOZ_NO_REMOTE': '1', 16:03:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:24 INFO - 'NO_EM_RESTART': '1', 16:03:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:24 INFO - 'PWD': '/builds/slave/test', 16:03:24 INFO - 'SHELL': '/bin/bash', 16:03:24 INFO - 'SHLVL': '1', 16:03:24 INFO - 'TERM': 'linux', 16:03:24 INFO - 'TMOUT': '86400', 16:03:24 INFO - 'USER': 'cltbld', 16:03:24 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', 16:03:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:24 INFO - '_': '/tools/buildbot/bin/python'} 16:03:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:03:24 INFO - Running setup.py (path:/tmp/pip-4VgLat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:03:24 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)) 16:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:03:24 INFO - Running setup.py (path:/tmp/pip-RKfPa3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:03:24 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)) 16:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:03:24 INFO - Running setup.py (path:/tmp/pip-lqjT7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:03:24 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)) 16:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:03:24 INFO - Running setup.py (path:/tmp/pip-ThJO0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:03:24 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)) 16:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:03:24 INFO - Running setup.py (path:/tmp/pip-Mo9U8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:03:24 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)) 16:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:03:24 INFO - Running setup.py (path:/tmp/pip-fGfdyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:03:24 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)) 16:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:03:24 INFO - Running setup.py (path:/tmp/pip-34M1Q2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:03:24 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)) 16:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:03:24 INFO - Running setup.py (path:/tmp/pip-h2ibnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:03:24 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)) 16:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:03:24 INFO - Running setup.py (path:/tmp/pip-UXMeP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:03:24 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)) 16:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:03:24 INFO - Running setup.py (path:/tmp/pip-aINhS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:03:25 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)) 16:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:03:25 INFO - Running setup.py (path:/tmp/pip-YSx5mB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:03:25 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)) 16:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:03:25 INFO - Running setup.py (path:/tmp/pip-2PdK6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:03:25 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)) 16:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:03:25 INFO - Running setup.py (path:/tmp/pip-Gb1ZqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:03:25 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)) 16:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:03:25 INFO - Running setup.py (path:/tmp/pip-b8yAzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:03:25 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)) 16:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:03:25 INFO - Running setup.py (path:/tmp/pip-ays76A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:03:25 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)) 16:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:03:25 INFO - Running setup.py (path:/tmp/pip-NrjYEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:03:25 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)) 16:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:03:25 INFO - Running setup.py (path:/tmp/pip-NyNqrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:03:25 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)) 16:03:25 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)) 16:03:25 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)) 16:03:25 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)) 16:03:25 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)) 16:03:25 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)) 16:03:25 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)) 16:03:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:03:25 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 16:03:25 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 16:03:25 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 16:03:25 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 16:03:26 INFO - Downloading blessings-1.6.tar.gz 16:03:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:03:26 INFO - Installing collected packages: blessings 16:03:26 INFO - Running setup.py install for blessings 16:03:26 INFO - Successfully installed blessings 16:03:26 INFO - Cleaning up... 16:03:26 INFO - Return code: 0 16:03:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:03:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:03:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:03:27 INFO - Reading from file tmpfile_stdout 16:03:27 INFO - Current package versions: 16:03:27 INFO - argparse == 1.2.1 16:03:27 INFO - blessings == 1.6 16:03:27 INFO - blobuploader == 1.2.4 16:03:27 INFO - docopt == 0.6.1 16:03:27 INFO - functools32 == 3.2.3-2 16:03:27 INFO - jsonschema == 2.5.1 16:03:27 INFO - manifestparser == 1.1 16:03:27 INFO - mozInstall == 1.13 16:03:27 INFO - mozcrash == 1.0 16:03:27 INFO - mozdebug == 0.1 16:03:27 INFO - mozdevice == 0.51 16:03:27 INFO - mozfile == 1.2 16:03:27 INFO - mozhttpd == 0.7 16:03:27 INFO - mozinfo == 0.10 16:03:27 INFO - mozleak == 0.1 16:03:27 INFO - mozlog == 3.5 16:03:27 INFO - moznetwork == 0.27 16:03:27 INFO - mozprocess == 0.25 16:03:27 INFO - mozprofile == 0.28 16:03:27 INFO - mozrunner == 6.13 16:03:27 INFO - mozscreenshot == 0.1 16:03:27 INFO - mozsystemmonitor == 0.3 16:03:27 INFO - moztest == 0.8 16:03:27 INFO - mozversion == 1.4 16:03:27 INFO - psutil == 3.1.1 16:03:27 INFO - requests == 1.2.3 16:03:27 INFO - wsgiref == 0.1.2 16:03:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:03:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:27 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 0x9cc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb95a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3c090>, '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 0xd3cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd36f90>, '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': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', '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 16:03:27 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 16:03:27 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 16:03:27 INFO - Using env: {'DISPLAY': ':0', 16:03:27 INFO - 'HOME': '/home/cltbld', 16:03:27 INFO - 'LANG': 'en_US.UTF-8', 16:03:27 INFO - 'LANGUAGE': 'en_US:en', 16:03:27 INFO - 'LOGNAME': 'cltbld', 16:03:27 INFO - 'MAIL': '/var/mail/cltbld', 16:03:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:27 INFO - 'MOZ_NO_REMOTE': '1', 16:03:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:27 INFO - 'NO_EM_RESTART': '1', 16:03:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:27 INFO - 'PWD': '/builds/slave/test', 16:03:27 INFO - 'SHELL': '/bin/bash', 16:03:27 INFO - 'SHLVL': '1', 16:03:27 INFO - 'TERM': 'linux', 16:03:27 INFO - 'TMOUT': '86400', 16:03:27 INFO - 'USER': 'cltbld', 16:03:27 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', 16:03:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:27 INFO - '_': '/tools/buildbot/bin/python'} 16:03:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:27 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)) 16:03:27 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)) 16:03:27 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)) 16:03:27 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)) 16:03:27 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)) 16:03:27 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)) 16:03:27 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)) 16:03:27 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)) 16:03:27 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)) 16:03:27 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:03:28 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)) 16:03:28 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)) 16:03:28 INFO - Installing collected packages: simplejson 16:03:28 INFO - Running setup.py install for simplejson 16:03:28 INFO - building 'simplejson._speedups' extension 16:03:28 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 16:03:29 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 16:03:29 INFO - Successfully installed simplejson 16:03:29 INFO - Cleaning up... 16:03:29 INFO - Return code: 0 16:03:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:03:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:29 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 0x9cc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb95a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd3c090>, '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 0xd3cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd36f90>, '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': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', '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 16:03:29 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 16:03:29 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 16:03:29 INFO - Using env: {'DISPLAY': ':0', 16:03:29 INFO - 'HOME': '/home/cltbld', 16:03:29 INFO - 'LANG': 'en_US.UTF-8', 16:03:29 INFO - 'LANGUAGE': 'en_US:en', 16:03:29 INFO - 'LOGNAME': 'cltbld', 16:03:29 INFO - 'MAIL': '/var/mail/cltbld', 16:03:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:29 INFO - 'MOZ_NO_REMOTE': '1', 16:03:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:29 INFO - 'NO_EM_RESTART': '1', 16:03:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:29 INFO - 'PWD': '/builds/slave/test', 16:03:29 INFO - 'SHELL': '/bin/bash', 16:03:29 INFO - 'SHLVL': '1', 16:03:29 INFO - 'TERM': 'linux', 16:03:29 INFO - 'TMOUT': '86400', 16:03:29 INFO - 'USER': 'cltbld', 16:03:29 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', 16:03:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:29 INFO - '_': '/tools/buildbot/bin/python'} 16:03:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:03:29 INFO - Cleaning up... 16:03:29 INFO - Return code: 0 16:03:29 INFO - Running post-action listener: _resource_record_post_action 16:03:29 INFO - Running post-action listener: _start_resource_monitoring 16:03:29 INFO - Starting resource monitoring. 16:03:29 INFO - [mozharness: 2017-09-13 23:03:29.779088Z] Finished create-virtualenv step (success) 16:03:29 INFO - [mozharness: 2017-09-13 23:03:29.779601Z] Running install step. 16:03:29 INFO - Running pre-action listener: _resource_record_pre_action 16:03:29 INFO - Running main action method: install 16:03:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:03:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:03:30 INFO - Reading from file tmpfile_stdout 16:03:30 INFO - Detecting whether we're running mozinstall >=1.0... 16:03:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:03:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:03:30 INFO - Reading from file tmpfile_stdout 16:03:30 INFO - Output received: 16:03:30 INFO - Usage: mozinstall [options] installer 16:03:30 INFO - Options: 16:03:30 INFO - -h, --help show this help message and exit 16:03:30 INFO - -d DEST, --destination=DEST 16:03:30 INFO - Directory to install application into. [default: 16:03:30 INFO - "/builds/slave/test"] 16:03:30 INFO - --app=APP Application being installed. [default: firefox] 16:03:30 INFO - mkdir: /builds/slave/test/build/application 16:03:30 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'] 16:03:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:03:47 INFO - Reading from file tmpfile_stdout 16:03:47 INFO - Output received: 16:03:47 INFO - /builds/slave/test/build/application/firefox/firefox 16:03:47 INFO - Running post-action listener: _resource_record_post_action 16:03:47 INFO - [mozharness: 2017-09-13 23:03:47.876597Z] Finished install step (success) 16:03:47 INFO - [mozharness: 2017-09-13 23:03:47.876848Z] Running setup-mitmproxy step. 16:03:47 INFO - Running pre-action listener: _resource_record_pre_action 16:03:47 INFO - Running main action method: setup_mitmproxy 16:03:47 INFO - Skipping: mitmproxy is not required 16:03:47 INFO - Running post-action listener: _resource_record_post_action 16:03:47 INFO - [mozharness: 2017-09-13 23:03:47.878108Z] Finished setup-mitmproxy step (success) 16:03:47 INFO - [mozharness: 2017-09-13 23:03:47.878330Z] Running run-tests step. 16:03:47 INFO - Running pre-action listener: _resource_record_pre_action 16:03:47 INFO - Running pre-action listener: _set_gcov_prefix 16:03:47 INFO - Running main action method: run_tests 16:03:47 WARNING - Try message not found. 16:03:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:03:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:03:47 INFO - Python 2.7.3 16:03:47 INFO - Return code: 0 16:03:47 INFO - grabbing minidump binary from tooltool 16:03: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')]} 16:03:47 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 0xd3c090>, '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 0xd3cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd36f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:03:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 16:03:47 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:03:47 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 16:03:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:03:48 INFO - Return code: 0 16:03:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:03:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:03:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:03:48 INFO - ENV: RUST_BACKTRACE is now full 16:03:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:03:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:03:48 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/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-080', '--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 16:03:48 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/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-080 --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 16:03:48 INFO - Using env: {'DISPLAY': ':0', 16:03:48 INFO - 'HOME': '/home/cltbld', 16:03:48 INFO - 'LANG': 'en_US.UTF-8', 16:03:48 INFO - 'LANGUAGE': 'en_US:en', 16:03:48 INFO - 'LOGNAME': 'cltbld', 16:03:48 INFO - 'MAIL': '/var/mail/cltbld', 16:03:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:03:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:03:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:48 INFO - 'MOZ_NO_REMOTE': '1', 16:03:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:03:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:48 INFO - 'NO_EM_RESTART': '1', 16:03:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:48 INFO - 'PWD': '/builds/slave/test', 16:03:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:03:48 INFO - 'RUST_BACKTRACE': 'full', 16:03:48 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:03:48 INFO - 'SHELL': '/bin/bash', 16:03:48 INFO - 'SHLVL': '1', 16:03:48 INFO - 'TERM': 'linux', 16:03:48 INFO - 'TMOUT': '86400', 16:03:48 INFO - 'USER': 'cltbld', 16:03:48 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', 16:03:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:48 INFO - '_': '/tools/buildbot/bin/python'} 16:03:48 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/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-080', '--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 16:03:48 INFO - mozversion application_buildid: 20170913215920 16:03:48 INFO - mozversion application_changeset: 8645a74bbbd06b67699317df1abf3897db0e43d5 16:03:48 INFO - mozversion application_display_name: Nightly 16:03:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:03:48 INFO - mozversion application_name: Firefox 16:03:48 INFO - mozversion application_remotingname: firefox 16:03:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:03:48 INFO - mozversion application_vendor: Mozilla 16:03:48 INFO - mozversion application_version: 57.0a1 16:03:48 INFO - mozversion platform_buildid: 20170913215920 16:03:48 INFO - mozversion platform_changeset: 8645a74bbbd06b67699317df1abf3897db0e43d5 16:03:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:03:48 INFO - mozversion platform_version: 57.0a1 16:03:48 INFO - using testdate: 1505343828 16:03:48 INFO - actual date: 1505343828 16:03:48 INFO - starting webserver on 'localhost:37141' 16:03:48 INFO - SUITE-START | Running 2 tests 16:03:48 INFO - TEST-START | dromaeo_css 16:03:48 INFO - Initialising browser for dromaeo_css test... 16:03:48 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37141/getInfo.html -profile /tmp/tmpy2Ki5O/profile 16:03:48 INFO - TEST-INFO | started process 11959 (/builds/slave/test/build/application/firefox/firefox http://localhost:37141/getInfo.html) 16:03:54 INFO - TEST-INFO | 11959: exit 0 16:03:54 INFO - Browser initialized. 16:03:54 INFO - Running cycle 1/1 for dromaeo_css test... 16:03:54 INFO - Using env: {'DISPLAY': ':0', 16:03:54 INFO - 'HOME': '/home/cltbld', 16:03:54 INFO - 'JSGC_DISABLE_POISONING': '1', 16:03:54 INFO - 'LANG': 'en_US.UTF-8', 16:03:54 INFO - 'LANGUAGE': 'en_US:en', 16:03:54 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:03:54 INFO - 'LOGNAME': 'cltbld', 16:03:54 INFO - 'MAIL': '/var/mail/cltbld', 16:03:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:03:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:03:54 INFO - 'MOZ_CRASHREPORTER': '1', 16:03:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:54 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:03:54 INFO - 'MOZ_NO_REMOTE': '1', 16:03:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:03:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:54 INFO - 'NO_EM_RESTART': '1', 16:03:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:54 INFO - 'PWD': '/builds/slave/test', 16:03:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:03:54 INFO - 'RUST_BACKTRACE': 'full', 16:03:54 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:03:54 INFO - 'SHELL': '/bin/bash', 16:03:54 INFO - 'SHLVL': '1', 16:03:54 INFO - 'TERM': 'linux', 16:03:54 INFO - 'TMOUT': '86400', 16:03:54 INFO - 'USER': 'cltbld', 16:03:54 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', 16:03:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:54 INFO - '_': '/tools/buildbot/bin/python'} 16:03:54 INFO - TEST-INFO | started process 12196 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpy2Ki5O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:03:55 INFO - PID 12196 | 16:03:55 INFO - PID 12196 | (/builds/slave/test/build/application/firefox/firefox:12245): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:03:55 INFO - PID 12196 | 16:04:06 INFO - PID 12196 | RSS: Main: 174538752 16:04:06 INFO - PID 12196 | 16:07:03 INFO - PID 12196 | Cycle 1(1): loaded http://localhost:37141/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37141/tests/dromaeo/cssquery-ext.html) 16:07:04 INFO - PID 12196 | RSS: Main: 176943104 16:07:04 INFO - PID 12196 | 16:10:05 INFO - PID 12196 | Cycle 1(1): loaded http://localhost:37141/tests/dromaeo/cssquery-ext.html (next: http://localhost:37141/tests/dromaeo/cssquery-jquery.html) 16:10:06 INFO - PID 12196 | RSS: Main: 173969408 16:10:06 INFO - PID 12196 | 16:13:08 INFO - PID 12196 | Cycle 1(1): loaded http://localhost:37141/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37141/tests/dromaeo/cssquery-mootools.html) 16:13:08 INFO - PID 12196 | RSS: Main: 176091136 16:13:08 INFO - PID 12196 | 16:16:10 INFO - PID 12196 | Cycle 1(1): loaded http://localhost:37141/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37141/tests/dromaeo/cssquery-prototype.html) 16:16:11 INFO - PID 12196 | RSS: Main: 175144960 16:16:11 INFO - PID 12196 | 16:19:14 INFO - PID 12196 | Cycle 1(1): loaded http://localhost:37141/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37141/tests/dromaeo/cssquery-yui.html) 16:19:14 INFO - PID 12196 | RSS: Main: 176349184 16:19:14 INFO - PID 12196 | 16:22:16 INFO - PID 12196 | Cycle 1(1): loaded http://localhost:37141/tests/dromaeo/cssquery-yui.html (next: http://localhost:37141/tests/dromaeo/cssquery-dojo.html) 16:22:16 INFO - PID 12196 | RSS: Main: 179265536 16:22:16 INFO - PID 12196 | 16:22:16 INFO - PID 12196 | __start_tp_report 16:22:16 INFO - PID 12196 | _x_x_mozilla_page_load 16:22:16 INFO - PID 12196 | _x_x_mozilla_page_load_details 16:22:16 INFO - PID 12196 | |i|pagename|runs| 16:22:16 INFO - PID 12196 | |0;dojo.html;3022.727954484096;3078.5536097265895;3095.8916437924663;3123.063081075678;3140.748740100792;6323;6338.271098823631;6356.904646430307;6398.808035758923;6403.135576697145;6049.848753781147;6144.631322120676;6161.16824228729;6190.350013248601;6220.6267623942485;5691.890081434122;5798.855028624276;5806.912896306538;5810.1865738796605;5852.561057920673;5812.244408226946;5878.44154805295;5880.941430542501;5890.823275301726;5892.234009578749;5564.944350556483;5697.202391665171;5770.624909380919;5771.653700777967;5802.506786923117;5709.514691251248;5710.514606258473;5778.913316300259;5786.652800831921;5804.303483581955;5692.601517893749;5699.715014249313;5788.016037273674;5793.797217097382;5838.357780644125;6196.2874269459135;6247.125402443661;6260.624362538217;6263.060540918853;6270.905936410957;6710.563813352165;6734.191896972395;6758.763443279511;6810.3189681032;6826.692798824065;6613.173353330833;6715.6977935993;6724.193096828412;6743.561668491532;6767.830804229884;6228.283747369065;6231.252249730016;6242.96878515609;6342.302346741854;6343.9365606343345;6938.5836849789175;6972.686229119702;6978.5812851228075;7019.157701075801;7032.156141263081;6601.405873471314;6613.768518101843;6630.9668451657435;6654.301298363602;6730;6722;6776.15298087739;6784.219814721271;6826.863462730717;6833.077534532775;6712.630805305657;6724.462043036546;6728.360805723449;6790.354916282945;6819.113515242987;6501.284858665543;6715.865682686319;6774.966125169342;6846.520743548003;6868.484863635247;5253.579713622901;5264.684118952875;5280.577553795687;5281.471852814765;5285.101532739443;4581.312803079498;4583.495815460296;4606.493285738633;4668.323093151474;4723.385959825269;5819.73811178498;5883.794067207713;5885.411458854081;5889.911651325234;5892.234009578728;6678.09845670838;6799.966000170067;6804.455643548505;6885.51801373899;6889.758858439931;5700.629459085146;5749.88500229993;5775.6823374714795;5826.970865145647;5882.088276317178;5845.269341332333;5858.297004359419;5866.354700982888;5867.413258674099;5868.94131058684;6367.840803979938;6447.2907980122145;6532.052852336385;6536.477081833442;6546.247181574085;6588.275289718032;6715.832104197434;6740.494462915203;6843.965780171067;6845.178578570504;6482.870342593216;6502.804915852532;6504.902426463702;6506.25178104524;6527.15147030883;6647.867042659216;6746.831329216809;6765.9661701691175;6783.050372947692;6789.830254243486;6785.89821152693;6799.796006119825;6800.421964133104;6884.724611015704;6887.7244910203035;6385.233771947396;6489.707963141575;6534.640594767334;6540.738370465318;6639.601623902581;6573.539852210491;6625.436837868642;6626.370494802986;6630.370114839083;6631.303713110054;6331.303556608677;6447.065175549613;6471.288158302677;6490.643014634241;6545.2800191979795;6672.4328432081875;6712.765053223114;6712.765053223114;6717.429018533479;6727.058211850443;6258.874822503433;6406.615603063831;6411.775587854403;6493.220813502221;6500.512461565496 16:22:16 INFO - PID 12196 | |1;ext.html;834.9874751878726;858.2747578296342;863.1627321498147;867.3581549653254;875.3741075131284;40709.3893591596;41490.585094149086;41495.79252103739;41533.37699934503;41574.16851662965;37275;37618.247635047286;37642.43536346957;37659.43510847333;37693;7056.470764692643;7156.463265255101;7169.498135130543;7169.677364518583;7172.892406613905;7088.468364872655;7158.35574798268;7166.104236970379;7176.067111275527;7193.028941092938;7705.460617756759;7883.0934442539265;7886.6845326186885;7888.368930485547;7891.881621775678;7740.109887362941;7817.140114587391;7817.530948143101;7820.139784623687;7823.608819558999;7585.12771031333;7725.304722574995;7725.884111738328;7743.264389882952;7743.690252389898;37086;37343.81328093342;37438.25123497542;37457.43813842795;37533.81233093817;3519.0498565387456;3535.5934067582302;3541.203229273419;3570.393033184365;3576.409892367756;30202;30404.239894002607;30432;30470.238244043856;30484.8475757622;5221.321228240343;5233.842984710464;5251.711155886424;5266.183741520071;5290.074237008508;183093;184094;184162.07918960453;184164;184220;46311;46432;46535.76732116318;46742.76628616835;47847.17222622182;49350.0129997398;49408.25887611689;49422;49472;49506.75246623744;15975.600609984844;16053.919730401507;16323.510294691177;16327;16340.591485212964;13308.46766129344;13425.731485370237;13433.462661493431;13440.260785656686;13445.05884588089;13666.248356340497;13804.723905521832;13919.791203131961;13949.860501394856;14075.648108797363;5525.972370138204;5558.583106267046;5598.300212473441;5611.9438805612235;5667.603267771216;1007.2294694558668;1009.2481101579224;1015.2690063154664;1029.9227557933184;1034.4827586206898;22585.548289034126;23045.769542304697;23123.421914451937;23237.954292056897;23408.18071367528;1124.0614087237248;1133.3369978562632;1134.0927258193412;1139.9829002564968;1143.1540659911605;11928.986036186847;12136.356594183748;12249.877501224873;12253.816192757116;12344.567940122053;212916;213882;221697;222135;222605;43771.781141094725;44098.7795061029;44218;51203.743981279855;51659;1449.253634378275;1476.8227812662549;1484.287541979834;1484.8812095032372;1486.49459183878;26634.733652663224;26806.329841754112;26897.462050759266;26917.596236056084;27042.45915081648;36297;36657.26685466329;36689.26621467609;36782.63217367899;36890.63109368979;165685;165972;166441;166577;166802;32607.510887337183;33147.17132071718;33867.15332116717;33952.83023584866;33998.32003359968;40946.38580421237;42396.78801605972;42565.787171063945;42901.570984289756;43371.56628433801;31337.68662313439;31412.685873140977;31813;32087.518687219243;32132.67867321297;30586.541201881544;31027.689723103384;31282.21794455157;31450.52824207593;31542.684573153976;28225.717742822308;29291.121266362043;29388.265293367836;29401.411971760874;29412.264693382836;30531.694683052887;30810.69189308168;30924.536131958488;31109.68890311068;31640.367192655558;22427.990740416393;22851.542969140857;22947.541049178588;22992.655110173695;23367.41581460477 16:22:16 INFO - PID 12196 | |2;jquery.html;564.4750382144604;567.5487987050294;568.6502800777523;571.8598943258904;579.5798046416348;22646.433839154008;23412.41468963277;23453.296401107953;23493.5301293974;23494.530109397823;23761.881190594024;24850.75149248507;25035.499290014188;25114.748852511468;25115.37211569707;6449.419552240286;6639.4688424926135;6641.070250164981;6641.8007459776045;6647.501437392215;6512.5766825156215;6624.900626490607;6625.834354141137;6627.5029372797235;6629.73481060757;6200.34896335885;6558.442532384753;6565.704543295539;6570.211574611053;6580.670966451682;6320;6477.578957367756;6505.512086593524;6516.93483065168;6523.217213934311;6003.969980150115;6069.57512974092;6074.878502429927;6075.726592303357;6083;27814;28230.43539129206;28331.433371332663;28348;28420.715792842013;2060.1656329186703;2075.5537559424797;2097.4861159015986;2097.863638863469;2098.6222479953603;18920.905395472935;19211.80788192114;19579.825210487415;19736.30922917705;19955.80044199554;2417;2418.91365739941;2434.3670645632087;2465.4206261528525;2473.6413220082986;127241;131098.0670579884;131255;135450.32274838662;135898.32050839782;14113.647158820906;14165.362558684677;14240.287985600678;14333.283335833166;14728.189949552878;22246;22331.441713956956;22363.440913976956;22376.440588985406;22391.328260152222;9968.700938971697;10087.798244035079;10181;10270.897291026995;10279.280450368551;9400.341976061747;9516.714498565054;9534.540869763738;9582.904170958202;9632.807343853083;8733.292679269447;8884.422512536665;8918.866217006751;9128.908710912805;9265.953670231605;2634.113719051171;2648.47030593882;2667.8132530722664;2725.5639097744265;2734.425770588159;951.2817822543935;964.4888209249109;981.2052237687496;985.4973963278779;985.9605615775432;18561.44315670532;18770.624587508173;18981;19052.428427147206;19130.617387652168;1028.6245520385155;1031.6492392586406;1038.5378506564507;1047.0942634621;1048.051513380392;10420.32267902584;10572.894271057345;10628.521716522775;10729;10776.946115269373;458574;477469;483486;494979;496946;111207.44396277967;112981;114139.42930285295;114363.42818285855;114882;1035.9119474844572;1088.3089238333557;1123.4831977290376;1144.5993902134187;1180.7579446213676;19364.515887102934;19653.213871444983;19701.901490492455;19928.402147935583;20256.493587660425;52108.47891521036;52890.47109528856;53386.1992070127;54231.72884135554;55314.170287446526;64754;66278.33721662722;68443;68804.3119568818;68856;14059.929700351433;14221.64445888861;14247.786283205553;14328.498502552779;14351.067180633225;22041.66937495971;22279.220227292615;22374.440638984157;22470.438239044153;22567.435814104778;17712.114394280492;17734.645307094044;18178.363757268962;18232;18263.54341141482;15954.202289885692;16084.35662573484;16383.098929558511;16512.587185319982;16762.16189190511;17453.86549874254;18388.724169137742;18545.16546755372;18701.25194992185;18704.06479676038;13883.861161388257;14065.296735163405;14082.084664497184;14092.224927629079;14092.436302547783;17461.214245358737;17675.204615792063;17724.11379431049;17769.200385982404;17795.911020444815 16:22:16 INFO - PID 12196 | |3;mootools.html;1334.1794796200336;1397.3572156807868;1409.4010045730563;1412.9081609695374;1420.7797791342339;14909.32908019139;15032.624184395396;15523.379064837407;15543.999999999985;15551.922240388782;15720.528384148494;15844.28700708468;15853.445129420472;15875.444359447421;15880.52358429249;2732.4261904999926;2793.2877116335408;2794.552871540554;2794.5947837563644;2795.1474800185933;2590.2876708905014;2675.291047872315;2800.215939536933;2802.6636803583597;2805.382815780535;2715.945681086377;2725.9454810903867;2739.58906164075;2740.5341092014405;2747.972520274792;2771.7921155913386;2779.652543432071;2798.6501687289087;2799.14626039058;2801.887924483008;2694.9461010779673;2695.7978151638704;2696.2315740014;2701.230149407429;2707.6750789905336;14913.179775112365;15255.847441525553;15273.236338183046;15331.540053798293;15336.15651139187;8010.799730006738;8073.717419890276;8443.860078889333;8445.36659750515;8446.155384461566;5077.64456488046;5246.947530524722;5310.60170487215;5328.6802791832615;5373.731313434352;1803.2696757813108;1830.9267629294811;1836.2012524551862;1877.8591605629633;1879.915403806832;25909.22272331833;26180.73819261802;26184.34539136518;26259.73740262592;26261;6733.124693789776;6791;6797.694103765243;6815.318468153145;6953.061336719579;17245.137743112795;17301.913490432467;17352.65294694124;17379.304827807;17414.47756567305;7887.960560197277;7888.092869320101;7891.842163156704;7900.683972641145;7957.204279572113;6331.936680633226;7255.963720181366;7258.709651613876;7372.447066470037;7407.740729074564;4967.751612419437;5300.833816560427;5302.946970530245;5444.91832622511;5464.180372944011;1527.8930474866875;1566.6161790361207;1581.6283173454228;1582.3037863340094;1587.8888477806443;866.6100254885326;874.5714599846029;889.6174644902625;892.2772554231094;896.5069211933411;7161.39128174111;7205.315495027964;7223.205447400782;7236.131664200329;7256.020437240905;982.7346616413529;987.5555999800118;1005.0803514784037;1006.0342071611188;1008.3546530220665;4313.41768861206;4364.60718535327;4382.211201983673;4402.097569998143;4442.977785111054;21012.684809727867;22247;22450.55098898013;22531.211407600655;22564.435889102577;21106;21380.930953452265;21801.345959621238;21860.344189674335;21893.014814333394;11358.602448914413;11503.424828758529;11567.190296679151;11573.247738896946;11681.065514758799;7887.3295769857905;7933.325667318342;7973.20267973198;7985.281324680806;8059.717909873127;18199.272029118685;18484.81515184831;18674.626507469504;18828;18972.525686857938;18917.621647566695;18960.146793394055;19068.52328691738;19108.42674719705;19114.139863706445;11078.612248571262;11246.493907774333;11253.71865703364;11257.21199516026;11259.09927205838;12452.626421207378;12841.743165136832;12854.871451285368;14071.085379450713;14442.133471991714;11291.604793832177;11386.487608057492;11388.373639449579;11473.139514536277;11626.360550169822;11048.723781905517;11229.21395502307;11242.887571124511;11422.086233101165;11549.538018479167;9657.372270802376;9854.55654495535;9858;9861.901380986099;9862.704118876445;6700.095487109178;6771.458283337222;6839.2818754032105;6848.691808868513;6881.518293719592;7035.437165026683;7118.85762284741;7163.534370265916;7170.641467926478;7210.134783825974 16:22:16 INFO - PID 12196 | |4;prototype.html;498.69106039039264;507.65986788851467;525.926370308157;529.8251576979598;530.2708775433779;6135.969320153398;6223.844403889905;6239.095331176983;6245.500359971203;6276.215473065867;6127.3872612738705;6129.406354347868;6183.196184496021;6184.876302473959;6194.473469755065;4976.104301225778;4988.401391832976;4989.351384320052;4992.126377883856;5003.499650034985;4842.685225460334;5000.174971129759;5009.799608015675;5022.698638081727;5028.421731500869;4943.530364615356;4975.353204083464;4989.376327959005;4998.150314446553;5002.1746411842005;4930.383702037246;4956.529129732669;4964.826231081913;4969.776360063788;4976.651634385595;4484.730916145042;4496.168208881335;4497.820087196508;4499.932501012455;4504.211762941472;6092.329843717188;6149.907751383733;6168.228971378578;6181.165542651729;6207.162033125515;5629.915551266734;5631.943680563183;5665.830025099253;5677.858053548653;5701.914471282934;6400.9039864402075;6494.740210391578;6526.389444222302;6537.346265373472;6545.803625891231;4782.187028205213;4857.441394239672;4861.6353773467135;4863.2461968394955;4870.269459581091;6710.865782684368;6863.656817159172;6904.689288982008;6918.654067296615;6953.478489113337;4897.975510122498;4973.179425394769;4982.825601103944;4991.575716064102;5004.474530174352;5022.321986531844;5047.318611987408;5068.797248110108;5068.974655126701;5103.693778373309;5618.775248990077;5631.8043057921295;5637.746301416446;5650;5675.772969081273;6289.7484100635465;6307.590006649645;6514.967425162844;6627.3372662733345;6632.706622208623;1725.5505375484402;1730.195459111519;1735.5053809664437;1743.8256174382461;1756.5081777102432;1041.9322744021615;1202.2365797718337;1202.3447221264432;1208.6132437619876;1218.7318789866215;5593.188987596775;5647.604667673223;5671.7522145128005;5697.515711164598;5757.309122905278;6618.86762264752;6623.4701223901975;6716.817034463721;6741.561798483083;6761.560498567577;5674.347450043216;5726.541876649858;5729.169270455761;5743.62666426681;5744.885102298014;3498.2478767065204;3535.487354333607;3546.5813674529877;3548.8048157351595;3570.1788588624763;6349.936500635027;6400.095941475696;6403.903941440882;6421.422072013448;6428.325025872309;6821.249662537117;6879.174499060045;6908.378245957888;6909.170899491993;6953.443724502028;6403.551751377358;6418.967905160444;6430.678466076677;6455.4835613150835;6476.5751534662295;6609.777191219448;6647.7122256145485;6657.470161338661;6667.599944003376;6691.431228345645;6657.7669781557415;6669.566478178903;6675.198976122896;6751.189857217166;6752.662366881538;6388.169537960035;6453.096566480603;6455;6472.15861937945;6508.772192973223;4946.332245146859;4959.82640607577;4959.876003099951;4985.277134815359;4997.400311962588;6612.933870661424;6740.224874139537;6770.32296770319;6783.660816959231;6810.761623343357;6301.306856245901;6373.936260637334;6398.648074355955;6434.67826608677;6452.35476452351;6462.967685161544;6485.189351331083;6505.837354066186;6538.346165383424;6547.467390055395;6418.326075761977;6439.100465362823;6498.122753428227;6514.348565143448;6525;6152.938470615237;6516.631507383313;6568.770093046912;6576.967115164393;6581.243157037002;6219.543280404306;6254.8123556293385;6273.811785646438;6297.779577714758;6336.841578960563 16:22:16 INFO - PID 12196 | |5;yui.html;889.8042430665259;909.0500427053729;925.3152667026399;936.1574582875411;938.9389689670169;18195.909020454896;19094.522636934085;19116.713249301272;19141.521461963457;19184.136713847874;18039.098045097726;18504.444866653987;18612.255509779625;18754.624907501842;18796.436106916815;966.6616684160523;982.8918818929913;986.0681655835239;986.0780170540531;986.753311672082;933.780561568031;972.3485264872517;987.9654212102578;991.3952488981715;991.4497453913075;1057.4395570347729;1057.5769692123135;1058.4919238765397;1059.0256963593513;1069.2942657845824;1060.9204309676766;1062.219268837404;1062.8671416072991;1063.2929102147064;1064.159314141831;719.3022767915114;735.7645553422907;742.1947187301784;742.7845924681836;774.2837874965634;18132;18136.183871725803;18352.357667481574;18358.08209589515;18434.90782546092;20723;21098.05058772359;21403.785962140337;21505.354839354844;21629.783702162935;17650.205740741698;17892.10539473021;18052.819471805244;18203.54491137719;18276.90861545697;3683.3001729671278;3712.3874560697723;3722.664960153599;3723.0320116769562;3723.3298006358846;140855;141627.29186354;142566;143316;143662;16963.660726785394;17187.484375468506;17241.56896077608;17321.480355589352;17445.825541744674;30905.381892362027;31323.216919577193;31547.264900430237;31646;31880.521792173135;8451.281641060456;8521.318294536422;8542.572871356531;8560.272376847914;8563.529005904611;4217.97891010543;4248.065425606424;4370.847020354272;4430.313301438282;4459.46486421625;5496.917546236809;5576.33084029919;5593.188987596857;5594.748236329375;5628.183913332544;1012.4785735346303;1041.9479026048666;1051.4690081508788;1057.5663977769077;1057.7461409261875;652.7943697735199;663.091564556551;671.1275342055309;682.3960794696729;684.5174152222671;12103.757924841453;12106.757864842653;12315.69210769738;12769.744605108031;13267.07130500875;696.3106524540668;701.4809041309394;706.9116360454943;718.989215161773;722.3246264742482;5483.341998960151;5567.498925096761;5583.302087239095;5585.301837270341;5634.915476267859;125494;125604;126757.36621317019;128202.35898820446;129704.35147824389;110040;111025;112542.43728781467;114830.42584787022;116812.41593792147;89140.5542972281;92306;92915.53542232381;94646.52676736572;96229.5188524053;10513.264071515227;10543.841842372523;10611.469426528796;10621.203409744454;10775.461226938465;43186.35220471633;44269;45322.54677453183;45713.77143114263;46092.769536152104;56892;57039.714801425725;57056.714716426155;57884;59706;15757.921210393875;16026.67946641084;16069.19654017271;16186.757198641793;16244.350225990827;17274.740878886576;17486.038267895387;17644.470665880042;17743.911280443514;17857.46427607174;16048.518544443685;16495.505134845975;16644.750328745322;16714.832851671326;16773.245203965605;16301;16424;16491.34034638649;16573.83426165723;16632.251548680095;15657.295421705823;15800.683986320439;15933.203339833193;15960.201989900226;16035.919820400823;12082.39588020613;12147.878521215029;12209.206401583868;12365.38173091324;12412;11659.18385712992;11783.293002419883;11872;11912.642620721392;12003.519859205884 16:22:16 INFO - PID 12196 | __end_tp_report 16:22:16 INFO - PID 12196 | __start_cc_report 16:22:16 INFO - PID 12196 | _x_x_mozilla_cycle_collect,362 16:22:16 INFO - PID 12196 | __end_cc_report 16:22:16 INFO - PID 12196 | __startTimestamp1505344936772__endTimestamp 16:22:16 INFO - PID 12196 | 16:22:16 INFO - PID 12196 | ------- Summary: start ------- 16:22:16 INFO - PID 12196 | Number of tests: 6 16:22:16 INFO - PID 12196 | 16:22:16 INFO - PID 12196 | [#0] dojo.html Cycles:165 Average:6203.36 Median:6447.29 stddev:760.00 (11.8%) stddev-sans-first:720.21 16:22:16 INFO - PID 12196 | Values: 3022.7 3078.6 3095.9 3123.1 3140.7 6323.0 6338.3 6356.9 6398.8 6403.1 6049.8 6144.6 6161.2 6190.4 6220.6 5691.9 5798.9 5806.9 5810.2 5852.6 5812.2 5878.4 5880.9 5890.8 5892.2 5564.9 5697.2 5770.6 5771.7 5802.5 5709.5 5710.5 5778.9 5786.7 5804.3 5692.6 5699.7 5788.0 5793.8 5838.4 6196.3 6247.1 6260.6 6263.1 6270.9 6710.6 6734.2 6758.8 6810.3 6826.7 6613.2 6715.7 6724.2 6743.6 6767.8 6228.3 6231.3 6243.0 6342.3 6343.9 6938.6 6972.7 6978.6 7019.2 7032.2 6601.4 6613.8 6631.0 6654.3 6730.0 6722.0 6776.2 6784.2 6826.9 6833.1 6712.6 6724.5 6728.4 6790.4 6819.1 6501.3 6715.9 6775.0 6846.5 6868.5 5253.6 5264.7 5280.6 5281.5 5285.1 4581.3 4583.5 4606.5 4668.3 4723.4 5819.7 5883.8 5885.4 5889.9 5892.2 6678.1 6800.0 6804.5 6885.5 6889.8 5700.6 5749.9 5775.7 5827.0 5882.1 5845.3 5858.3 5866.4 5867.4 5868.9 6367.8 6447.3 6532.1 6536.5 6546.2 6588.3 6715.8 6740.5 6844.0 6845.2 6482.9 6502.8 6504.9 6506.3 6527.2 6647.9 6746.8 6766.0 6783.1 6789.8 6785.9 6799.8 6800.4 6884.7 6887.7 6385.2 6489.7 6534.6 6540.7 6639.6 6573.5 6625.4 6626.4 6630.4 6631.3 6331.3 6447.1 6471.3 6490.6 6545.3 6672.4 6712.8 6712.8 6717.4 6727.1 6258.9 6406.6 6411.8 6493.2 6500.5 16:22:16 INFO - PID 12196 | 16:22:16 INFO - PID 12196 | [#1] ext.html Cycles:180 Average:35543.69 Median:32877.34 stddev:49329.81 (150.0%) stddev-sans-first:49399.34 16:22:16 INFO - PID 12196 | Values: 835.0 858.3 863.2 867.4 875.4 40709.4 41490.6 41495.8 41533.4 41574.2 37275.0 37618.2 37642.4 37659.4 37693.0 7056.5 7156.5 7169.5 7169.7 7172.9 7088.5 7158.4 7166.1 7176.1 7193.0 7705.5 7883.1 7886.7 7888.4 7891.9 7740.1 7817.1 7817.5 7820.1 7823.6 7585.1 7725.3 7725.9 7743.3 7743.7 37086.0 37343.8 37438.3 37457.4 37533.8 3519.0 3535.6 3541.2 3570.4 3576.4 30202.0 30404.2 30432.0 30470.2 30484.8 5221.3 5233.8 5251.7 5266.2 5290.1 183093.0 184094.0 184162.1 184164.0 184220.0 46311.0 46432.0 46535.8 46742.8 47847.2 49350.0 49408.3 49422.0 49472.0 49506.8 15975.6 16053.9 16323.5 16327.0 16340.6 13308.5 13425.7 13433.5 13440.3 13445.1 13666.2 13804.7 13919.8 13949.9 14075.6 5526.0 5558.6 5598.3 5611.9 5667.6 1007.2 1009.2 1015.3 1029.9 1034.5 22585.5 23045.8 23123.4 23238.0 23408.2 1124.1 1133.3 1134.1 1140.0 1143.2 11929.0 12136.4 12249.9 12253.8 12344.6 212916.0 213882.0 221697.0 222135.0 222605.0 43771.8 44098.8 44218.0 51203.7 51659.0 1449.3 1476.8 1484.3 1484.9 1486.5 26634.7 26806.3 26897.5 26917.6 27042.5 36297.0 36657.3 36689.3 36782.6 36890.6 165685.0 165972.0 166441.0 166577.0 166802.0 32607.5 33147.2 33867.2 33952.8 33998.3 40946.4 42396.8 42565.8 42901.6 43371.6 31337.7 31412.7 31813.0 32087.5 32132.7 30586.5 31027.7 31282.2 31450.5 31542.7 28225.7 29291.1 29388.3 29401.4 29412.3 30531.7 30810.7 30924.5 31109.7 31640.4 22428.0 22851.5 22947.5 22992.7 23367.4 16:22:16 INFO - PID 12196 | 16:22:16 INFO - PID 12196 | [#2] jquery.html Cycles:180 Average:33989.25 Median:22383.88 stddev:81113.97 (362.4%) stddev-sans-first:81302.69 16:22:16 INFO - PID 12196 | Values: 564.5 567.5 568.7 571.9 579.6 22646.4 23412.4 23453.3 23493.5 23494.5 23761.9 24850.8 25035.5 25114.7 25115.4 6449.4 6639.5 6641.1 6641.8 6647.5 6512.6 6624.9 6625.8 6627.5 6629.7 6200.3 6558.4 6565.7 6570.2 6580.7 6320.0 6477.6 6505.5 6516.9 6523.2 6004.0 6069.6 6074.9 6075.7 6083.0 27814.0 28230.4 28331.4 28348.0 28420.7 2060.2 2075.6 2097.5 2097.9 2098.6 18920.9 19211.8 19579.8 19736.3 19955.8 2417.0 2418.9 2434.4 2465.4 2473.6 127241.0 131098.1 131255.0 135450.3 135898.3 14113.6 14165.4 14240.3 14333.3 14728.2 22246.0 22331.4 22363.4 22376.4 22391.3 9968.7 10087.8 10181.0 10270.9 10279.3 9400.3 9516.7 9534.5 9582.9 9632.8 8733.3 8884.4 8918.9 9128.9 9266.0 2634.1 2648.5 2667.8 2725.6 2734.4 951.3 964.5 981.2 985.5 986.0 18561.4 18770.6 18981.0 19052.4 19130.6 1028.6 1031.6 1038.5 1047.1 1048.1 10420.3 10572.9 10628.5 10729.0 10776.9 458574.0 477469.0 483486.0 494979.0 496946.0 111207.4 112981.0 114139.4 114363.4 114882.0 1035.9 1088.3 1123.5 1144.6 1180.8 19364.5 19653.2 19701.9 19928.4 20256.5 52108.5 52890.5 53386.2 54231.7 55314.2 64754.0 66278.3 68443.0 68804.3 68856.0 14059.9 14221.6 14247.8 14328.5 14351.1 22041.7 22279.2 22374.4 22470.4 22567.4 17712.1 17734.6 18178.4 18232.0 18263.5 15954.2 16084.4 16383.1 16512.6 16762.2 17453.9 18388.7 18545.2 18701.3 18704.1 13883.9 14065.3 14082.1 14092.2 14092.4 17461.2 17675.2 17724.1 17769.2 17795.9 16:22:16 INFO - PID 12196 | 16:22:16 INFO - PID 12196 | [#3] mootools.html Cycles:180 Average:9293.94 Median:2695.37 stddev:6719.48 (249.3%) stddev-sans-first:6711.72 16:22:16 INFO - PID 12196 | Values: 1334.2 1397.4 1409.4 1412.9 1420.8 14909.3 15032.6 15523.4 15544.0 15551.9 15720.5 15844.3 15853.4 15875.4 15880.5 2732.4 2793.3 2794.6 2794.6 2795.1 2590.3 2675.3 2800.2 2802.7 2805.4 2715.9 2725.9 2739.6 2740.5 2748.0 2771.8 2779.7 2798.7 2799.1 2801.9 2694.9 2695.8 2696.2 2701.2 2707.7 14913.2 15255.8 15273.2 15331.5 15336.2 8010.8 8073.7 8443.9 8445.4 8446.2 5077.6 5246.9 5310.6 5328.7 5373.7 1803.3 1830.9 1836.2 1877.9 1879.9 25909.2 26180.7 26184.3 26259.7 26261.0 6733.1 6791.0 6797.7 6815.3 6953.1 17245.1 17301.9 17352.7 17379.3 17414.5 7888.0 7888.1 7891.8 7900.7 7957.2 6331.9 7256.0 7258.7 7372.4 7407.7 4967.8 5300.8 5302.9 5444.9 5464.2 1527.9 1566.6 1581.6 1582.3 1587.9 866.6 874.6 889.6 892.3 896.5 7161.4 7205.3 7223.2 7236.1 7256.0 982.7 987.6 1005.1 1006.0 1008.4 4313.4 4364.6 4382.2 4402.1 4443.0 21012.7 22247.0 22450.6 22531.2 22564.4 21106.0 21380.9 21801.3 21860.3 21893.0 11358.6 11503.4 11567.2 11573.2 11681.1 7887.3 7933.3 7973.2 7985.3 8059.7 18199.3 18484.8 18674.6 18828.0 18972.5 18917.6 18960.1 19068.5 19108.4 19114.1 11078.6 11246.5 11253.7 11257.2 11259.1 12452.6 12841.7 12854.9 14071.1 14442.1 11291.6 11386.5 11388.4 11473.1 11626.4 11048.7 11229.2 11242.9 11422.1 11549.5 9657.4 9854.6 9858.0 9861.9 9862.7 6700.1 6771.5 6839.3 6848.7 6881.5 7035.4 7118.9 7163.5 7170.6 7210.1 16:22:16 INFO - PID 12196 | 16:22:16 INFO - PID 12196 | [#4] prototype.html Cycles:180 Average:5484.40 Median:6160.58 stddev:1539.70 (25.0%) stddev-sans-first:1497.86 16:22:16 INFO - PID 12196 | Values: 498.7 507.7 525.9 529.8 530.3 6136.0 6223.8 6239.1 6245.5 6276.2 6127.4 6129.4 6183.2 6184.9 6194.5 4976.1 4988.4 4989.4 4992.1 5003.5 4842.7 5000.2 5009.8 5022.7 5028.4 4943.5 4975.4 4989.4 4998.2 5002.2 4930.4 4956.5 4964.8 4969.8 4976.7 4484.7 4496.2 4497.8 4499.9 4504.2 6092.3 6149.9 6168.2 6181.2 6207.2 5629.9 5631.9 5665.8 5677.9 5701.9 6400.9 6494.7 6526.4 6537.3 6545.8 4782.2 4857.4 4861.6 4863.2 4870.3 6710.9 6863.7 6904.7 6918.7 6953.5 4898.0 4973.2 4982.8 4991.6 5004.5 5022.3 5047.3 5068.8 5069.0 5103.7 5618.8 5631.8 5637.7 5650.0 5675.8 6289.7 6307.6 6515.0 6627.3 6632.7 1725.6 1730.2 1735.5 1743.8 1756.5 1041.9 1202.2 1202.3 1208.6 1218.7 5593.2 5647.6 5671.8 5697.5 5757.3 6618.9 6623.5 6716.8 6741.6 6761.6 5674.3 5726.5 5729.2 5743.6 5744.9 3498.2 3535.5 3546.6 3548.8 3570.2 6349.9 6400.1 6403.9 6421.4 6428.3 6821.2 6879.2 6908.4 6909.2 6953.4 6403.6 6419.0 6430.7 6455.5 6476.6 6609.8 6647.7 6657.5 6667.6 6691.4 6657.8 6669.6 6675.2 6751.2 6752.7 6388.2 6453.1 6455.0 6472.2 6508.8 4946.3 4959.8 4959.9 4985.3 4997.4 6612.9 6740.2 6770.3 6783.7 6810.8 6301.3 6373.9 6398.6 6434.7 6452.4 6463.0 6485.2 6505.8 6538.3 6547.5 6418.3 6439.1 6498.1 6514.3 6525.0 6152.9 6516.6 6568.8 6577.0 6581.2 6219.5 6254.8 6273.8 6297.8 6336.8 16:22:16 INFO - PID 12196 | 16:22:16 INFO - PID 12196 | [#5] yui.html Cycles:180 Average:24658.63 Median:18396.49 stddev:36064.42 (196.0%) stddev-sans-first:36121.43 16:22:16 INFO - PID 12196 | Values: 889.8 909.1 925.3 936.2 938.9 18195.9 19094.5 19116.7 19141.5 19184.1 18039.1 18504.4 18612.3 18754.6 18796.4 966.7 982.9 986.1 986.1 986.8 933.8 972.3 988.0 991.4 991.4 1057.4 1057.6 1058.5 1059.0 1069.3 1060.9 1062.2 1062.9 1063.3 1064.2 719.3 735.8 742.2 742.8 774.3 18132.0 18136.2 18352.4 18358.1 18434.9 20723.0 21098.1 21403.8 21505.4 21629.8 17650.2 17892.1 18052.8 18203.5 18276.9 3683.3 3712.4 3722.7 3723.0 3723.3 140855.0 141627.3 142566.0 143316.0 143662.0 16963.7 17187.5 17241.6 17321.5 17445.8 30905.4 31323.2 31547.3 31646.0 31880.5 8451.3 8521.3 8542.6 8560.3 8563.5 4218.0 4248.1 4370.8 4430.3 4459.5 5496.9 5576.3 5593.2 5594.7 5628.2 1012.5 1041.9 1051.5 1057.6 1057.7 652.8 663.1 671.1 682.4 684.5 12103.8 12106.8 12315.7 12769.7 13267.1 696.3 701.5 706.9 719.0 722.3 5483.3 5567.5 5583.3 5585.3 5634.9 125494.0 125604.0 126757.4 128202.4 129704.4 110040.0 111025.0 112542.4 114830.4 116812.4 89140.6 92306.0 92915.5 94646.5 96229.5 10513.3 10543.8 10611.5 10621.2 10775.5 43186.4 44269.0 45322.5 45713.8 46092.8 56892.0 57039.7 57056.7 57884.0 59706.0 15757.9 16026.7 16069.2 16186.8 16244.4 17274.7 17486.0 17644.5 17743.9 17857.5 16048.5 16495.5 16644.8 16714.8 16773.2 16301.0 16424.0 16491.3 16573.8 16632.3 15657.3 15800.7 15933.2 15960.2 16035.9 12082.4 12147.9 12209.2 12365.4 12412.0 11659.2 11783.3 11872.0 11912.6 12003.5 16:22:16 INFO - PID 12196 | -------- Summary: end -------- 16:22:16 INFO - PID 12196 | 16:22:17 INFO - TEST-INFO | 12196: exit 0 16:22:17 INFO - TEST-OK | dromaeo_css | took 1109227ms 16:22:17 INFO - TEST-START | kraken 16:22:17 INFO - Initialising browser for kraken test... 16:22:17 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37141/getInfo.html -profile /tmp/tmpnM32om/profile 16:22:17 INFO - TEST-INFO | started process 12571 (/builds/slave/test/build/application/firefox/firefox http://localhost:37141/getInfo.html) 16:22:23 INFO - TEST-INFO | 12571: exit 0 16:22:23 INFO - Browser initialized. 16:22:23 INFO - Running cycle 1/1 for kraken test... 16:22:23 INFO - Using env: {'DISPLAY': ':0', 16:22:23 INFO - 'HOME': '/home/cltbld', 16:22:23 INFO - 'JSGC_DISABLE_POISONING': '1', 16:22:23 INFO - 'LANG': 'en_US.UTF-8', 16:22:23 INFO - 'LANGUAGE': 'en_US:en', 16:22:23 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:22:23 INFO - 'LOGNAME': 'cltbld', 16:22:23 INFO - 'MAIL': '/var/mail/cltbld', 16:22:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:22:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:22:23 INFO - 'MOZ_CRASHREPORTER': '1', 16:22:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:23 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:22:23 INFO - 'MOZ_NO_REMOTE': '1', 16:22:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:22:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:23 INFO - 'NO_EM_RESTART': '1', 16:22:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:23 INFO - 'PWD': '/builds/slave/test', 16:22:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:22:23 INFO - 'RUST_BACKTRACE': 'full', 16:22:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:22:23 INFO - 'SHELL': '/bin/bash', 16:22:23 INFO - 'SHLVL': '1', 16:22:23 INFO - 'TERM': 'linux', 16:22:23 INFO - 'TMOUT': '86400', 16:22:23 INFO - 'USER': 'cltbld', 16:22:23 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174', 16:22:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:23 INFO - '_': '/tools/buildbot/bin/python'} 16:22:23 INFO - TEST-INFO | started process 12803 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnM32om/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:22:24 INFO - PID 12803 | 16:22:24 INFO - PID 12803 | (/builds/slave/test/build/application/firefox/firefox:12854): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:22:24 INFO - PID 12803 | 16:22:34 INFO - PID 12803 | RSS: Main: 176128000 16:22:34 INFO - PID 12803 | 16:22:37 INFO - PID 12803 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37141/tests/kraken/driver.html?testName=audio-beat-detection) 16:22:38 INFO - PID 12803 | RSS: Main: 180994048 16:22:38 INFO - PID 12803 | 16:22:40 INFO - PID 12803 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37141/tests/kraken/driver.html?testName=audio-dft) 16:22:41 INFO - PID 12803 | RSS: Main: 181637120 16:22:41 INFO - PID 12803 | 16:22:44 INFO - PID 12803 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37141/tests/kraken/driver.html?testName=audio-fft) 16:22:44 INFO - PID 12803 | RSS: Main: 176984064 16:22:44 INFO - PID 12803 | 16:22:47 INFO - PID 12803 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37141/tests/kraken/driver.html?testName=audio-oscillator) 16:22:47 INFO - PID 12803 | RSS: Main: 177426432 16:22:47 INFO - PID 12803 | 16:22:49 INFO - PID 12803 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37141/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:22:50 INFO - PID 12803 | RSS: Main: 177725440 16:22:50 INFO - PID 12803 | 16:22:53 INFO - PID 12803 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37141/tests/kraken/driver.html?testName=imaging-darkroom) 16:22:54 INFO - PID 12803 | RSS: Main: 180101120 16:22:54 INFO - PID 12803 | 16:22:57 INFO - PID 12803 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37141/tests/kraken/driver.html?testName=imaging-desaturate) 16:22:58 INFO - PID 12803 | RSS: Main: 180166656 16:22:58 INFO - PID 12803 | 16:23:01 INFO - PID 12803 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37141/tests/kraken/driver.html?testName=json-parse-financial) 16:23:02 INFO - PID 12803 | RSS: Main: 180379648 16:23:02 INFO - PID 12803 | 16:23:03 INFO - PID 12803 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37141/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:23:04 INFO - PID 12803 | RSS: Main: 180580352 16:23:04 INFO - PID 12803 | 16:23:05 INFO - PID 12803 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37141/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:23:06 INFO - PID 12803 | RSS: Main: 180072448 16:23:06 INFO - PID 12803 | 16:23:08 INFO - PID 12803 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37141/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:23:09 INFO - PID 12803 | RSS: Main: 176873472 16:23:09 INFO - PID 12803 | 16:23:11 INFO - PID 12803 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37141/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:23:12 INFO - PID 12803 | RSS: Main: 177659904 16:23:12 INFO - PID 12803 | 16:23:15 INFO - PID 12803 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37141/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:23:16 INFO - PID 12803 | RSS: Main: 177975296 16:23:16 INFO - PID 12803 | 16:23:18 INFO - PID 12803 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37141/tests/kraken/driver.html?testName=ai-astar) 16:23:18 INFO - PID 12803 | RSS: Main: 178802688 16:23:18 INFO - PID 12803 | 16:23:18 INFO - PID 12803 | __start_tp_report 16:23:18 INFO - PID 12803 | _x_x_mozilla_page_load 16:23:18 INFO - PID 12803 | _x_x_mozilla_page_load_details 16:23:18 INFO - PID 12803 | |i|pagename|runs| 16:23:18 INFO - PID 12803 | |0;ai-astar;110;108;108;108;109;111;108;111;108;108 16:23:18 INFO - PID 12803 | |1;audio-beat-detection;116;117;116;117;120;119;120;117;121;119 16:23:18 INFO - PID 12803 | |2;audio-dft;164;160;161;162;161;159;163;164;165;165 16:23:18 INFO - PID 12803 | |3;audio-fft;73;77;73;74;83;74;74;73;73;74 16:23:18 INFO - PID 12803 | |4;audio-oscillator;78;79;79;79;78;80;80;78;92;77 16:23:18 INFO - PID 12803 | |5;imaging-gaussian-blur;115;114;114;114;114;114;114;114;114;113 16:23:18 INFO - PID 12803 | |6;imaging-darkroom;149;148;148;147;147;147;147;149;149;147 16:23:18 INFO - PID 12803 | |7;imaging-desaturate;93;94;94;94;94;94;94;94;95;97 16:23:18 INFO - PID 12803 | |8;json-parse-financial;54;56;54;50;52;51;51;51;51;50 16:23:18 INFO - PID 12803 | |9;json-stringify-tinderbox;38;38;37;38;37;38;37;37;37;38 16:23:18 INFO - PID 12803 | |10;stanford-crypto-aes;74;77;75;73;79;76;75;74;79;72 16:23:18 INFO - PID 12803 | |11;stanford-crypto-ccm;116;112;114;116;121;115;114;114;112;110 16:23:18 INFO - PID 12803 | |12;stanford-crypto-pbkdf2;175;171;174;173;174;173;179;171;174;171 16:23:18 INFO - PID 12803 | |13;stanford-crypto-sha256-iterative;68;66;70;67;64;66;66;65;68;67 16:23:18 INFO - PID 12803 | __end_tp_report 16:23:18 INFO - PID 12803 | __start_cc_report 16:23:18 INFO - PID 12803 | _x_x_mozilla_cycle_collect,941 16:23:18 INFO - PID 12803 | __end_cc_report 16:23:18 INFO - PID 12803 | __startTimestamp1505344998273__endTimestamp 16:23:18 INFO - PID 12803 | 16:23:18 INFO - PID 12803 | ------- Summary: start ------- 16:23:18 INFO - PID 12803 | Number of tests: 14 16:23:18 INFO - PID 12803 | 16:23:18 INFO - PID 12803 | [#0] ai-astar Cycles:10 Average:108.90 Median:108.50 stddev:1.29 (1.2%) stddev-sans-first:1.30 16:23:18 INFO - PID 12803 | Values: 110.0 108.0 108.0 108.0 109.0 111.0 108.0 111.0 108.0 108.0 16:23:18 INFO - PID 12803 | 16:23:18 INFO - PID 12803 | [#1] audio-beat-detection Cycles:10 Average:118.20 Median:119.00 stddev:1.81 (1.5%) stddev-sans-first:1.74 16:23:18 INFO - PID 12803 | Values: 116.0 117.0 116.0 117.0 120.0 119.0 120.0 117.0 121.0 119.0 16:23:18 INFO - PID 12803 | 16:23:18 INFO - PID 12803 | [#2] audio-dft Cycles:10 Average:162.40 Median:163.50 stddev:2.12 (1.3%) stddev-sans-first:2.17 16:23:18 INFO - PID 12803 | Values: 164.0 160.0 161.0 162.0 161.0 159.0 163.0 164.0 165.0 165.0 16:23:18 INFO - PID 12803 | 16:23:18 INFO - PID 12803 | [#3] audio-fft Cycles:10 Average:74.80 Median:74.00 stddev:3.12 (4.2%) stddev-sans-first:3.24 16:23:18 INFO - PID 12803 | Values: 73.0 77.0 73.0 74.0 83.0 74.0 74.0 73.0 73.0 74.0 16:23:18 INFO - PID 12803 | 16:23:18 INFO - PID 12803 | [#4] audio-oscillator Cycles:10 Average:80.00 Median:79.00 stddev:4.32 (5.5%) stddev-sans-first:4.52 16:23:18 INFO - PID 12803 | Values: 78.0 79.0 79.0 79.0 78.0 80.0 80.0 78.0 92.0 77.0 16:23:18 INFO - PID 12803 | 16:23:18 INFO - PID 12803 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.47 (0.4%) stddev-sans-first:0.33 16:23:18 INFO - PID 12803 | Values: 115.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 113.0 16:23:18 INFO - PID 12803 | 16:23:18 INFO - PID 12803 | [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:0.92 (0.6%) stddev-sans-first:0.87 16:23:18 INFO - PID 12803 | Values: 149.0 148.0 148.0 147.0 147.0 147.0 147.0 149.0 149.0 147.0 16:23:18 INFO - PID 12803 | 16:23:18 INFO - PID 12803 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:1.06 (1.1%) stddev-sans-first:1.01 16:23:18 INFO - PID 12803 | Values: 93.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 95.0 97.0 16:23:18 INFO - PID 12803 | 16:23:18 INFO - PID 12803 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:51.50 stddev:2.00 (3.9%) stddev-sans-first:1.99 16:23:18 INFO - PID 12803 | Values: 54.0 56.0 54.0 50.0 52.0 51.0 51.0 51.0 51.0 50.0 16:23:18 INFO - PID 12803 | 16:23:18 INFO - PID 12803 | [#9] json-stringify-tinderbox Cycles:10 Average:37.50 Median:38.00 stddev:0.53 (1.4%) stddev-sans-first:0.53 16:23:18 INFO - PID 12803 | Values: 38.0 38.0 37.0 38.0 37.0 38.0 37.0 37.0 37.0 38.0 16:23:18 INFO - PID 12803 | 16:23:18 INFO - PID 12803 | [#10] stanford-crypto-aes Cycles:10 Average:75.40 Median:75.50 stddev:2.37 (3.1%) stddev-sans-first:2.46 16:23:18 INFO - PID 12803 | Values: 74.0 77.0 75.0 73.0 79.0 76.0 75.0 74.0 79.0 72.0 16:23:18 INFO - PID 12803 | 16:23:18 INFO - PID 12803 | [#11] stanford-crypto-ccm Cycles:10 Average:114.40 Median:114.50 stddev:2.99 (2.6%) stddev-sans-first:3.11 16:23:18 INFO - PID 12803 | Values: 116.0 112.0 114.0 116.0 121.0 115.0 114.0 114.0 112.0 110.0 16:23:18 INFO - PID 12803 | 16:23:18 INFO - PID 12803 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:174.00 stddev:2.42 (1.4%) stddev-sans-first:2.50 16:23:18 INFO - PID 12803 | Values: 175.0 171.0 174.0 173.0 174.0 173.0 179.0 171.0 174.0 171.0 16:23:18 INFO - PID 12803 | 16:23:18 INFO - PID 12803 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.70 (2.5%) stddev-sans-first:1.74 16:23:18 INFO - PID 12803 | Values: 68.0 66.0 70.0 67.0 64.0 66.0 66.0 65.0 68.0 67.0 16:23:18 INFO - PID 12803 | -------- Summary: end -------- 16:23:18 INFO - PID 12803 | 16:23:18 INFO - PID 12803 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:23:19 INFO - TEST-INFO | 12803: exit 0 16:23:19 INFO - TEST-OK | kraken | took 61628ms 16:23:19 INFO - SUITE-END | took 1170s 16:23:19 INFO - Completed test suite (00:19:31) 16:23:19 INFO - javascript benchmark 16:23:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8455.017487997304, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3022.727954484096, 3078.5536097265895, 3095.8916437924663, 3123.063081075678, 3140.748740100792, 6323.0, 6338.271098823631, 6356.904646430307, 6398.808035758923, 6403.135576697145, 6049.848753781147, 6144.631322120676, 6161.16824228729, 6190.350013248601, 6220.6267623942485, 5691.890081434122, 5798.855028624276, 5806.912896306538, 5810.1865738796605, 5852.561057920673, 5812.244408226946, 5878.44154805295, 5880.941430542501, 5890.823275301726, 5892.234009578749, 5564.944350556483, 5697.202391665171, 5770.624909380919, 5771.653700777967, 5802.506786923117, 5709.514691251248, 5710.514606258473, 5778.913316300259, 5786.652800831921, 5804.303483581955, 5692.601517893749, 5699.715014249313, 5788.016037273674, 5793.797217097382, 5838.357780644125, 6196.2874269459135, 6247.125402443661, 6260.624362538217, 6263.060540918853, 6270.905936410957, 6710.563813352165, 6734.191896972395, 6758.763443279511, 6810.3189681032, 6826.692798824065, 6613.173353330833, 6715.6977935993, 6724.193096828412, 6743.561668491532, 6767.830804229884, 6228.283747369065, 6231.252249730016, 6242.96878515609, 6342.302346741854, 6343.9365606343345, 6938.5836849789175, 6972.686229119702, 6978.5812851228075, 7019.157701075801, 7032.156141263081, 6601.405873471314, 6613.768518101843, 6630.9668451657435, 6654.301298363602, 6730.0, 6722.0, 6776.15298087739, 6784.219814721271, 6826.863462730717, 6833.077534532775, 6712.630805305657, 6724.462043036546, 6728.360805723449, 6790.354916282945, 6819.113515242987, 6501.284858665543, 6715.865682686319, 6774.966125169342, 6846.520743548003, 6868.484863635247, 5253.579713622901, 5264.684118952875, 5280.577553795687, 5281.471852814765, 5285.101532739443, 4581.312803079498, 4583.495815460296, 4606.493285738633, 4668.323093151474, 4723.385959825269, 5819.73811178498, 5883.794067207713, 5885.411458854081, 5889.911651325234, 5892.234009578728, 6678.09845670838, 6799.966000170067, 6804.455643548505, 6885.51801373899, 6889.758858439931, 5700.629459085146, 5749.88500229993, 5775.6823374714795, 5826.970865145647, 5882.088276317178, 5845.269341332333, 5858.297004359419, 5866.354700982888, 5867.413258674099, 5868.94131058684, 6367.840803979938, 6447.2907980122145, 6532.052852336385, 6536.477081833442, 6546.247181574085, 6588.275289718032, 6715.832104197434, 6740.494462915203, 6843.965780171067, 6845.178578570504, 6482.870342593216, 6502.804915852532, 6504.902426463702, 6506.25178104524, 6527.15147030883, 6647.867042659216, 6746.831329216809, 6765.9661701691175, 6783.050372947692, 6789.830254243486, 6785.89821152693, 6799.796006119825, 6800.421964133104, 6884.724611015704, 6887.7244910203035, 6385.233771947396, 6489.707963141575, 6534.640594767334, 6540.738370465318, 6639.601623902581, 6573.539852210491, 6625.436837868642, 6626.370494802986, 6630.370114839083, 6631.303713110054, 6331.303556608677, 6447.065175549613, 6471.288158302677, 6490.643014634241, 6545.2800191979795, 6672.4328432081875, 6712.765053223114, 6712.765053223114, 6717.429018533479, 6727.058211850443, 6258.874822503433, 6406.615603063831, 6411.775587854403, 6493.220813502221, 6500.512461565496], "value": 6143.6637121829235, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [834.9874751878726, 858.2747578296342, 863.1627321498147, 867.3581549653254, 875.3741075131284, 40709.3893591596, 41490.585094149086, 41495.79252103739, 41533.37699934503, 41574.16851662965, 37275.0, 37618.247635047286, 37642.43536346957, 37659.43510847333, 37693.0, 7056.470764692643, 7156.463265255101, 7169.498135130543, 7169.677364518583, 7172.892406613905, 7088.468364872655, 7158.35574798268, 7166.104236970379, 7176.067111275527, 7193.028941092938, 7705.460617756759, 7883.0934442539265, 7886.6845326186885, 7888.368930485547, 7891.881621775678, 7740.109887362941, 7817.140114587391, 7817.530948143101, 7820.139784623687, 7823.608819558999, 7585.12771031333, 7725.304722574995, 7725.884111738328, 7743.264389882952, 7743.690252389898, 37086.0, 37343.81328093342, 37438.25123497542, 37457.43813842795, 37533.81233093817, 3519.0498565387456, 3535.5934067582302, 3541.203229273419, 3570.393033184365, 3576.409892367756, 30202.0, 30404.239894002607, 30432.0, 30470.238244043856, 30484.8475757622, 5221.321228240343, 5233.842984710464, 5251.711155886424, 5266.183741520071, 5290.074237008508, 183093.0, 184094.0, 184162.07918960453, 184164.0, 184220.0, 46311.0, 46432.0, 46535.76732116318, 46742.76628616835, 47847.17222622182, 49350.0129997398, 49408.25887611689, 49422.0, 49472.0, 49506.75246623744, 15975.600609984844, 16053.919730401507, 16323.510294691177, 16327.0, 16340.591485212964, 13308.46766129344, 13425.731485370237, 13433.462661493431, 13440.260785656686, 13445.05884588089, 13666.248356340497, 13804.723905521832, 13919.791203131961, 13949.860501394856, 14075.648108797363, 5525.972370138204, 5558.583106267046, 5598.300212473441, 5611.9438805612235, 5667.603267771216, 1007.2294694558668, 1009.2481101579224, 1015.2690063154664, 1029.9227557933184, 1034.4827586206898, 22585.548289034126, 23045.769542304697, 23123.421914451937, 23237.954292056897, 23408.18071367528, 1124.0614087237248, 1133.3369978562632, 1134.0927258193412, 1139.9829002564968, 1143.1540659911605, 11928.986036186847, 12136.356594183748, 12249.877501224873, 12253.816192757116, 12344.567940122053, 212916.0, 213882.0, 221697.0, 222135.0, 222605.0, 43771.781141094725, 44098.7795061029, 44218.0, 51203.743981279855, 51659.0, 1449.253634378275, 1476.8227812662549, 1484.287541979834, 1484.8812095032372, 1486.49459183878, 26634.733652663224, 26806.329841754112, 26897.462050759266, 26917.596236056084, 27042.45915081648, 36297.0, 36657.26685466329, 36689.26621467609, 36782.63217367899, 36890.63109368979, 165685.0, 165972.0, 166441.0, 166577.0, 166802.0, 32607.510887337183, 33147.17132071718, 33867.15332116717, 33952.83023584866, 33998.32003359968, 40946.38580421237, 42396.78801605972, 42565.787171063945, 42901.570984289756, 43371.56628433801, 31337.68662313439, 31412.685873140977, 31813.0, 32087.518687219243, 32132.67867321297, 30586.541201881544, 31027.689723103384, 31282.21794455157, 31450.52824207593, 31542.684573153976, 28225.717742822308, 29291.121266362043, 29388.265293367836, 29401.411971760874, 29412.264693382836, 30531.694683052887, 30810.69189308168, 30924.536131958488, 31109.68890311068, 31640.367192655558, 22427.990740416393, 22851.542969140857, 22947.541049178588, 22992.655110173695, 23367.41581460477], "value": 16749.491343558355, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.4750382144604, 567.5487987050294, 568.6502800777523, 571.8598943258904, 579.5798046416348, 22646.433839154008, 23412.41468963277, 23453.296401107953, 23493.5301293974, 23494.530109397823, 23761.881190594024, 24850.75149248507, 25035.499290014188, 25114.748852511468, 25115.37211569707, 6449.419552240286, 6639.4688424926135, 6641.070250164981, 6641.8007459776045, 6647.501437392215, 6512.5766825156215, 6624.900626490607, 6625.834354141137, 6627.5029372797235, 6629.73481060757, 6200.34896335885, 6558.442532384753, 6565.704543295539, 6570.211574611053, 6580.670966451682, 6320.0, 6477.578957367756, 6505.512086593524, 6516.93483065168, 6523.217213934311, 6003.969980150115, 6069.57512974092, 6074.878502429927, 6075.726592303357, 6083.0, 27814.0, 28230.43539129206, 28331.433371332663, 28348.0, 28420.715792842013, 2060.1656329186703, 2075.5537559424797, 2097.4861159015986, 2097.863638863469, 2098.6222479953603, 18920.905395472935, 19211.80788192114, 19579.825210487415, 19736.30922917705, 19955.80044199554, 2417.0, 2418.91365739941, 2434.3670645632087, 2465.4206261528525, 2473.6413220082986, 127241.0, 131098.0670579884, 131255.0, 135450.32274838662, 135898.32050839782, 14113.647158820906, 14165.362558684677, 14240.287985600678, 14333.283335833166, 14728.189949552878, 22246.0, 22331.441713956956, 22363.440913976956, 22376.440588985406, 22391.328260152222, 9968.700938971697, 10087.798244035079, 10181.0, 10270.897291026995, 10279.280450368551, 9400.341976061747, 9516.714498565054, 9534.540869763738, 9582.904170958202, 9632.807343853083, 8733.292679269447, 8884.422512536665, 8918.866217006751, 9128.908710912805, 9265.953670231605, 2634.113719051171, 2648.47030593882, 2667.8132530722664, 2725.5639097744265, 2734.425770588159, 951.2817822543935, 964.4888209249109, 981.2052237687496, 985.4973963278779, 985.9605615775432, 18561.44315670532, 18770.624587508173, 18981.0, 19052.428427147206, 19130.617387652168, 1028.6245520385155, 1031.6492392586406, 1038.5378506564507, 1047.0942634621, 1048.051513380392, 10420.32267902584, 10572.894271057345, 10628.521716522775, 10729.0, 10776.946115269373, 458574.0, 477469.0, 483486.0, 494979.0, 496946.0, 111207.44396277967, 112981.0, 114139.42930285295, 114363.42818285855, 114882.0, 1035.9119474844572, 1088.3089238333557, 1123.4831977290376, 1144.5993902134187, 1180.7579446213676, 19364.515887102934, 19653.213871444983, 19701.901490492455, 19928.402147935583, 20256.493587660425, 52108.47891521036, 52890.47109528856, 53386.1992070127, 54231.72884135554, 55314.170287446526, 64754.0, 66278.33721662722, 68443.0, 68804.3119568818, 68856.0, 14059.929700351433, 14221.64445888861, 14247.786283205553, 14328.498502552779, 14351.067180633225, 22041.66937495971, 22279.220227292615, 22374.440638984157, 22470.438239044153, 22567.435814104778, 17712.114394280492, 17734.645307094044, 18178.363757268962, 18232.0, 18263.54341141482, 15954.202289885692, 16084.35662573484, 16383.098929558511, 16512.587185319982, 16762.16189190511, 17453.86549874254, 18388.724169137742, 18545.16546755372, 18701.25194992185, 18704.06479676038, 13883.861161388257, 14065.296735163405, 14082.084664497184, 14092.224927629079, 14092.436302547783, 17461.214245358737, 17675.204615792063, 17724.11379431049, 17769.200385982404, 17795.911020444815], "value": 11797.907224910674, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1334.1794796200336, 1397.3572156807868, 1409.4010045730563, 1412.9081609695374, 1420.7797791342339, 14909.32908019139, 15032.624184395396, 15523.379064837407, 15543.999999999985, 15551.922240388782, 15720.528384148494, 15844.28700708468, 15853.445129420472, 15875.444359447421, 15880.52358429249, 2732.4261904999926, 2793.2877116335408, 2794.552871540554, 2794.5947837563644, 2795.1474800185933, 2590.2876708905014, 2675.291047872315, 2800.215939536933, 2802.6636803583597, 2805.382815780535, 2715.945681086377, 2725.9454810903867, 2739.58906164075, 2740.5341092014405, 2747.972520274792, 2771.7921155913386, 2779.652543432071, 2798.6501687289087, 2799.14626039058, 2801.887924483008, 2694.9461010779673, 2695.7978151638704, 2696.2315740014, 2701.230149407429, 2707.6750789905336, 14913.179775112365, 15255.847441525553, 15273.236338183046, 15331.540053798293, 15336.15651139187, 8010.799730006738, 8073.717419890276, 8443.860078889333, 8445.36659750515, 8446.155384461566, 5077.64456488046, 5246.947530524722, 5310.60170487215, 5328.6802791832615, 5373.731313434352, 1803.2696757813108, 1830.9267629294811, 1836.2012524551862, 1877.8591605629633, 1879.915403806832, 25909.22272331833, 26180.73819261802, 26184.34539136518, 26259.73740262592, 26261.0, 6733.124693789776, 6791.0, 6797.694103765243, 6815.318468153145, 6953.061336719579, 17245.137743112795, 17301.913490432467, 17352.65294694124, 17379.304827807, 17414.47756567305, 7887.960560197277, 7888.092869320101, 7891.842163156704, 7900.683972641145, 7957.204279572113, 6331.936680633226, 7255.963720181366, 7258.709651613876, 7372.447066470037, 7407.740729074564, 4967.751612419437, 5300.833816560427, 5302.946970530245, 5444.91832622511, 5464.180372944011, 1527.8930474866875, 1566.6161790361207, 1581.6283173454228, 1582.3037863340094, 1587.8888477806443, 866.6100254885326, 874.5714599846029, 889.6174644902625, 892.2772554231094, 896.5069211933411, 7161.39128174111, 7205.315495027964, 7223.205447400782, 7236.131664200329, 7256.020437240905, 982.7346616413529, 987.5555999800118, 1005.0803514784037, 1006.0342071611188, 1008.3546530220665, 4313.41768861206, 4364.60718535327, 4382.211201983673, 4402.097569998143, 4442.977785111054, 21012.684809727867, 22247.0, 22450.55098898013, 22531.211407600655, 22564.435889102577, 21106.0, 21380.930953452265, 21801.345959621238, 21860.344189674335, 21893.014814333394, 11358.602448914413, 11503.424828758529, 11567.190296679151, 11573.247738896946, 11681.065514758799, 7887.3295769857905, 7933.325667318342, 7973.20267973198, 7985.281324680806, 8059.717909873127, 18199.272029118685, 18484.81515184831, 18674.626507469504, 18828.0, 18972.525686857938, 18917.621647566695, 18960.146793394055, 19068.52328691738, 19108.42674719705, 19114.139863706445, 11078.612248571262, 11246.493907774333, 11253.71865703364, 11257.21199516026, 11259.09927205838, 12452.626421207378, 12841.743165136832, 12854.871451285368, 14071.085379450713, 14442.133471991714, 11291.604793832177, 11386.487608057492, 11388.373639449579, 11473.139514536277, 11626.360550169822, 11048.723781905517, 11229.21395502307, 11242.887571124511, 11422.086233101165, 11549.538018479167, 9657.372270802376, 9854.55654495535, 9858.0, 9861.901380986099, 9862.704118876445, 6700.095487109178, 6771.458283337222, 6839.2818754032105, 6848.691808868513, 6881.518293719592, 7035.437165026683, 7118.85762284741, 7163.534370265916, 7170.641467926478, 7210.134783825974], "value": 6631.620238797199, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.69106039039264, 507.65986788851467, 525.926370308157, 529.8251576979598, 530.2708775433779, 6135.969320153398, 6223.844403889905, 6239.095331176983, 6245.500359971203, 6276.215473065867, 6127.3872612738705, 6129.406354347868, 6183.196184496021, 6184.876302473959, 6194.473469755065, 4976.104301225778, 4988.401391832976, 4989.351384320052, 4992.126377883856, 5003.499650034985, 4842.685225460334, 5000.174971129759, 5009.799608015675, 5022.698638081727, 5028.421731500869, 4943.530364615356, 4975.353204083464, 4989.376327959005, 4998.150314446553, 5002.1746411842005, 4930.383702037246, 4956.529129732669, 4964.826231081913, 4969.776360063788, 4976.651634385595, 4484.730916145042, 4496.168208881335, 4497.820087196508, 4499.932501012455, 4504.211762941472, 6092.329843717188, 6149.907751383733, 6168.228971378578, 6181.165542651729, 6207.162033125515, 5629.915551266734, 5631.943680563183, 5665.830025099253, 5677.858053548653, 5701.914471282934, 6400.9039864402075, 6494.740210391578, 6526.389444222302, 6537.346265373472, 6545.803625891231, 4782.187028205213, 4857.441394239672, 4861.6353773467135, 4863.2461968394955, 4870.269459581091, 6710.865782684368, 6863.656817159172, 6904.689288982008, 6918.654067296615, 6953.478489113337, 4897.975510122498, 4973.179425394769, 4982.825601103944, 4991.575716064102, 5004.474530174352, 5022.321986531844, 5047.318611987408, 5068.797248110108, 5068.974655126701, 5103.693778373309, 5618.775248990077, 5631.8043057921295, 5637.746301416446, 5650.0, 5675.772969081273, 6289.7484100635465, 6307.590006649645, 6514.967425162844, 6627.3372662733345, 6632.706622208623, 1725.5505375484402, 1730.195459111519, 1735.5053809664437, 1743.8256174382461, 1756.5081777102432, 1041.9322744021615, 1202.2365797718337, 1202.3447221264432, 1208.6132437619876, 1218.7318789866215, 5593.188987596775, 5647.604667673223, 5671.7522145128005, 5697.515711164598, 5757.309122905278, 6618.86762264752, 6623.4701223901975, 6716.817034463721, 6741.561798483083, 6761.560498567577, 5674.347450043216, 5726.541876649858, 5729.169270455761, 5743.62666426681, 5744.885102298014, 3498.2478767065204, 3535.487354333607, 3546.5813674529877, 3548.8048157351595, 3570.1788588624763, 6349.936500635027, 6400.095941475696, 6403.903941440882, 6421.422072013448, 6428.325025872309, 6821.249662537117, 6879.174499060045, 6908.378245957888, 6909.170899491993, 6953.443724502028, 6403.551751377358, 6418.967905160444, 6430.678466076677, 6455.4835613150835, 6476.5751534662295, 6609.777191219448, 6647.7122256145485, 6657.470161338661, 6667.599944003376, 6691.431228345645, 6657.7669781557415, 6669.566478178903, 6675.198976122896, 6751.189857217166, 6752.662366881538, 6388.169537960035, 6453.096566480603, 6455.0, 6472.15861937945, 6508.772192973223, 4946.332245146859, 4959.82640607577, 4959.876003099951, 4985.277134815359, 4997.400311962588, 6612.933870661424, 6740.224874139537, 6770.32296770319, 6783.660816959231, 6810.761623343357, 6301.306856245901, 6373.936260637334, 6398.648074355955, 6434.67826608677, 6452.35476452351, 6462.967685161544, 6485.189351331083, 6505.837354066186, 6538.346165383424, 6547.467390055395, 6418.326075761977, 6439.100465362823, 6498.122753428227, 6514.348565143448, 6525.0, 6152.938470615237, 6516.631507383313, 6568.770093046912, 6576.967115164393, 6581.243157037002, 6219.543280404306, 6254.8123556293385, 6273.811785646438, 6297.779577714758, 6336.841578960563], "value": 5029.791319352902, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [889.8042430665259, 909.0500427053729, 925.3152667026399, 936.1574582875411, 938.9389689670169, 18195.909020454896, 19094.522636934085, 19116.713249301272, 19141.521461963457, 19184.136713847874, 18039.098045097726, 18504.444866653987, 18612.255509779625, 18754.624907501842, 18796.436106916815, 966.6616684160523, 982.8918818929913, 986.0681655835239, 986.0780170540531, 986.753311672082, 933.780561568031, 972.3485264872517, 987.9654212102578, 991.3952488981715, 991.4497453913075, 1057.4395570347729, 1057.5769692123135, 1058.4919238765397, 1059.0256963593513, 1069.2942657845824, 1060.9204309676766, 1062.219268837404, 1062.8671416072991, 1063.2929102147064, 1064.159314141831, 719.3022767915114, 735.7645553422907, 742.1947187301784, 742.7845924681836, 774.2837874965634, 18132.0, 18136.183871725803, 18352.357667481574, 18358.08209589515, 18434.90782546092, 20723.0, 21098.05058772359, 21403.785962140337, 21505.354839354844, 21629.783702162935, 17650.205740741698, 17892.10539473021, 18052.819471805244, 18203.54491137719, 18276.90861545697, 3683.3001729671278, 3712.3874560697723, 3722.664960153599, 3723.0320116769562, 3723.3298006358846, 140855.0, 141627.29186354, 142566.0, 143316.0, 143662.0, 16963.660726785394, 17187.484375468506, 17241.56896077608, 17321.480355589352, 17445.825541744674, 30905.381892362027, 31323.216919577193, 31547.264900430237, 31646.0, 31880.521792173135, 8451.281641060456, 8521.318294536422, 8542.572871356531, 8560.272376847914, 8563.529005904611, 4217.97891010543, 4248.065425606424, 4370.847020354272, 4430.313301438282, 4459.46486421625, 5496.917546236809, 5576.33084029919, 5593.188987596857, 5594.748236329375, 5628.183913332544, 1012.4785735346303, 1041.9479026048666, 1051.4690081508788, 1057.5663977769077, 1057.7461409261875, 652.7943697735199, 663.091564556551, 671.1275342055309, 682.3960794696729, 684.5174152222671, 12103.757924841453, 12106.757864842653, 12315.69210769738, 12769.744605108031, 13267.07130500875, 696.3106524540668, 701.4809041309394, 706.9116360454943, 718.989215161773, 722.3246264742482, 5483.341998960151, 5567.498925096761, 5583.302087239095, 5585.301837270341, 5634.915476267859, 125494.0, 125604.0, 126757.36621317019, 128202.35898820446, 129704.35147824389, 110040.0, 111025.0, 112542.43728781467, 114830.42584787022, 116812.41593792147, 89140.5542972281, 92306.0, 92915.53542232381, 94646.52676736572, 96229.5188524053, 10513.264071515227, 10543.841842372523, 10611.469426528796, 10621.203409744454, 10775.461226938465, 43186.35220471633, 44269.0, 45322.54677453183, 45713.77143114263, 46092.769536152104, 56892.0, 57039.714801425725, 57056.714716426155, 57884.0, 59706.0, 15757.921210393875, 16026.67946641084, 16069.19654017271, 16186.757198641793, 16244.350225990827, 17274.740878886576, 17486.038267895387, 17644.470665880042, 17743.911280443514, 17857.46427607174, 16048.518544443685, 16495.505134845975, 16644.750328745322, 16714.832851671326, 16773.245203965605, 16301.0, 16424.0, 16491.34034638649, 16573.83426165723, 16632.251548680095, 15657.295421705823, 15800.683986320439, 15933.203339833193, 15960.201989900226, 16035.919820400823, 12082.39588020613, 12147.878521215029, 12209.206401583868, 12365.38173091324, 12412.0, 11659.18385712992, 11783.293002419883, 11872.0, 11912.642620721392, 12003.519859205884], "value": 9021.073140161096, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1419.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 108.0, 108.0, 108.0, 109.0, 111.0, 108.0, 111.0, 108.0, 108.0], "value": 108.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 120.0, 119.0, 120.0, 117.0, 121.0, 119.0], "value": 118.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 160.0, 161.0, 162.0, 161.0, 159.0, 163.0, 164.0, 165.0, 165.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 77.0, 73.0, 74.0, 83.0, 74.0, 74.0, 73.0, 73.0, 74.0], "value": 74.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 79.0, 79.0, 78.0, 80.0, 80.0, 78.0, 92.0, 77.0], "value": 80.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 113.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 148.0, 147.0, 147.0, 147.0, 147.0, 149.0, 149.0, 147.0], "value": 147.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 97.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 54.0, 50.0, 52.0, 51.0, 51.0, 51.0, 51.0, 50.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 37.0, 38.0, 37.0, 38.0, 37.0, 37.0, 37.0, 38.0], "value": 37.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 75.0, 73.0, 79.0, 76.0, 75.0, 74.0, 79.0, 72.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 112.0, 114.0, 116.0, 121.0, 115.0, 114.0, 114.0, 112.0, 110.0], "value": 114.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 171.0, 174.0, 173.0, 174.0, 173.0, 179.0, 171.0, 174.0, 171.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 70.0, 67.0, 64.0, 66.0, 66.0, 65.0, 68.0, 67.0], "value": 66.7, "unit": "score"}]}]} 16:23:19 INFO - Return code: 0 16:23:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:23:19 INFO - # TBPL SUCCESS # 16:23:19 INFO - Running post-action listener: _package_coverage_data 16:23:19 INFO - Running post-action listener: _resource_record_post_action 16:23:19 INFO - [mozharness: 2017-09-13 23:23:19.555328Z] Finished run-tests step (success) 16:23:19 INFO - Running post-run listener: _resource_record_post_run 16:23:19 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 335597568; Read time: 16; Write time: 270800 16:23:19 INFO - TinderboxPrint: CPU usage
13.1% 16:23:19 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 16:23:19 INFO - TinderboxPrint: I/O write bytes / time
335,597,568 / 270,800 16:23:19 INFO - TinderboxPrint: CPU idle
8,235.1 (86.9%) 16:23:19 INFO - TinderboxPrint: CPU user
1,193.7 (12.6%) 16:23:19 INFO - TinderboxPrint: Swap in / out
0 / 0 16:23:19 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 9564160; Read time: 0; Write time: 860 16:23:19 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:23:19 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 325050368; Read time: 0; Write time: 269872 16:23:20 INFO - Running post-run listener: _upload_blobber_files 16:23:20 INFO - Blob upload gear active. 16:23:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:23:20 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 16:23:20 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'] 16:23:20 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 16:23:20 INFO - (blobuploader) - INFO - Open directory for files ... 16:23:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:23:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:23:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:23:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:23:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:23:21 INFO - (blobuploader) - INFO - Done attempting. 16:23:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:23:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:23:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:23:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:23:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:23:23 INFO - (blobuploader) - INFO - Done attempting. 16:23:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:23:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:23:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:23:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:23:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:23:24 INFO - (blobuploader) - INFO - Done attempting. 16:23:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:23:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:23:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:23:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:23:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:23:27 INFO - (blobuploader) - INFO - Done attempting. 16:23:27 INFO - (blobuploader) - INFO - Iteration through files over. 16:23:27 INFO - Return code: 0 16:23:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:23:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:23:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7636b8c571e8d8ceaf58d353bd8522d1fe938416f12773f5cc4959a3da64e3184967fc1d0deded069e1c1a5e4312597a42fac552b846435ef3f3f9c003cbf14", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ae20a560cd7bcd760f6e4384f7d464fb2f97795faf0ea7801ad2d73cbb7475ca6bf11b78f538394f773891f5bf30db4dca95a08ecc091935c708306d862b057d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1c8a51ebaa54a76aeeb7334612798585c0c675f2c7b3e152cfa4310d4d323fd89b6fb97139b902e4c5a29bed7e025a569fdd3c3eb462bf4d9da355aec300f7d7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9929df6c869bf3daadb72b6f87352750c7548dac8cc4f17fadc9c2df431644ee154f26c55ebd2d739191bdcd032d0b8f64312c17acf6a047da34849af9140774"} 16:23:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:23:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:23:27 INFO - Contents: 16:23:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7636b8c571e8d8ceaf58d353bd8522d1fe938416f12773f5cc4959a3da64e3184967fc1d0deded069e1c1a5e4312597a42fac552b846435ef3f3f9c003cbf14", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ae20a560cd7bcd760f6e4384f7d464fb2f97795faf0ea7801ad2d73cbb7475ca6bf11b78f538394f773891f5bf30db4dca95a08ecc091935c708306d862b057d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1c8a51ebaa54a76aeeb7334612798585c0c675f2c7b3e152cfa4310d4d323fd89b6fb97139b902e4c5a29bed7e025a569fdd3c3eb462bf4d9da355aec300f7d7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9929df6c869bf3daadb72b6f87352750c7548dac8cc4f17fadc9c2df431644ee154f26c55ebd2d739191bdcd032d0b8f64312c17acf6a047da34849af9140774"} 16:23:27 INFO - Running post-run listener: copy_logs_to_upload_dir 16:23:27 INFO - Copying logs to upload dir... 16:23:27 INFO - mkdir: /builds/slave/test/build/upload/logs 16:23:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.726138 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-13 16:23:27.994702) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 16:23:27.997700) ========= 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=781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7636b8c571e8d8ceaf58d353bd8522d1fe938416f12773f5cc4959a3da64e3184967fc1d0deded069e1c1a5e4312597a42fac552b846435ef3f3f9c003cbf14", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ae20a560cd7bcd760f6e4384f7d464fb2f97795faf0ea7801ad2d73cbb7475ca6bf11b78f538394f773891f5bf30db4dca95a08ecc091935c708306d862b057d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1c8a51ebaa54a76aeeb7334612798585c0c675f2c7b3e152cfa4310d4d323fd89b6fb97139b902e4c5a29bed7e025a569fdd3c3eb462bf4d9da355aec300f7d7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9929df6c869bf3daadb72b6f87352750c7548dac8cc4f17fadc9c2df431644ee154f26c55ebd2d739191bdcd032d0b8f64312c17acf6a047da34849af9140774"} build_url:https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011538 build_url: 'https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7636b8c571e8d8ceaf58d353bd8522d1fe938416f12773f5cc4959a3da64e3184967fc1d0deded069e1c1a5e4312597a42fac552b846435ef3f3f9c003cbf14", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ae20a560cd7bcd760f6e4384f7d464fb2f97795faf0ea7801ad2d73cbb7475ca6bf11b78f538394f773891f5bf30db4dca95a08ecc091935c708306d862b057d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1c8a51ebaa54a76aeeb7334612798585c0c675f2c7b3e152cfa4310d4d323fd89b6fb97139b902e4c5a29bed7e025a569fdd3c3eb462bf4d9da355aec300f7d7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9929df6c869bf3daadb72b6f87352750c7548dac8cc4f17fadc9c2df431644ee154f26c55ebd2d739191bdcd032d0b8f64312c17acf6a047da34849af9140774"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 16:23:28.028681) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:23:28.028980) ========= 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=781b77c4c33858b1f599d705000001c1-1505343766.454713-1859379174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004534 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:23:28.111039) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 16:23:28.114346) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 16:23:28.114653) ========= ========= Total master_lag: 0.22 =========