builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-088 starttime: 1505644150.59 results: success (0) revision: faa897d7948b7e2439573f39c34366c138913663 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:10.586998) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:10.587524) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:10.592065) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:10.745221) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:10.745557) ========= 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=9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-17 03:29:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.8M=0s 2017-09-17 03:29:11 (45.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.147784 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:10.915989) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:10.916399) ========= 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=9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020522 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:10.967751) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-17 03:29:10.968191) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev faa897d7948b7e2439573f39c34366c138913663 --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 faa897d7948b7e2439573f39c34366c138913663 --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=9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-17 03:29:11,205 truncating revision to first 12 chars 2017-09-17 03:29:11,205 Setting DEBUG logging. 2017-09-17 03:29:11,205 attempt 1/10 2017-09-17 03:29:11,205 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/faa897d7948b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-17 03:29:11,704 attempt 1/10 2017-09-17 03:29:12,406 current task status: no status available at this point. state: STARTED 2017-09-17 03:29:12,406 sleeping for 9.00s (attempt 1/10) 2017-09-17 03:29:21,415 attempt 2/10 2017-09-17 03:29:22,033 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-17 03:29:22,218 unpacking tar archive at: mozilla-central-faa897d7948b/testing/mozharness/ program finished with exit code 0 elapsedTime=11.283777 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-17 03:29:22.267571) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:22.270956) ========= script_repo_revision: faa897d7948b7e2439573f39c34366c138913663 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:22.271390) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:22.271647) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-17 03:29:22.284874) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-17 03:29:22.285229) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:29:22 INFO - MultiFileLogger online at 20170917 03:29:22 in /builds/slave/test 03:29:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:29:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:29:22 INFO - {'append_to_log': False, 03:29:22 INFO - 'base_work_dir': '/builds/slave/test', 03:29:22 INFO - 'blob_upload_branch': 'Firefox', 03:29:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:29:22 INFO - 'branch': 'Firefox', 03:29:22 INFO - 'buildbot_json_path': 'buildprops.json', 03:29:22 INFO - 'code_coverage': False, 03:29:22 INFO - 'config_files': ('talos/linux_config.py',), 03:29:22 INFO - 'default_actions': ('clobber', 03:29:22 INFO - 'read-buildbot-config', 03:29:22 INFO - 'download-and-extract', 03:29:22 INFO - 'populate-webroot', 03:29:22 INFO - 'create-virtualenv', 03:29:22 INFO - 'install', 03:29:22 INFO - 'setup-mitmproxy', 03:29:22 INFO - 'run-tests'), 03:29:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:29:22 INFO - 'disable_ccov_upload': False, 03:29:22 INFO - 'disable_stylo': False, 03:29:22 INFO - 'download_minidump_stackwalk': True, 03:29:22 INFO - 'download_symbols': 'ondemand', 03:29:22 INFO - 'enable_stylo': False, 03:29:22 INFO - 'enable_webrender': False, 03:29:22 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:29:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:29:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:29:22 INFO - 'gecko_profile': False, 03:29:22 INFO - 'gecko_profile_interval': 0, 03:29:22 INFO - 'installer_path': 'installer.exe', 03:29:22 INFO - 'log_level': 'info', 03:29:22 INFO - 'log_name': 'talos', 03:29:22 INFO - 'log_to_console': True, 03:29:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:29:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:29:22 INFO - 'opt_config_files': (), 03:29:22 INFO - 'pip_index': False, 03:29:22 INFO - 'suite': 'dromaeojs-e10s', 03:29:22 INFO - 'system_bits': '32', 03:29:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:29:22 INFO - 'title': 'talos-linux64-ix-088', 03:29:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:29:22 INFO - 'use_talos_json': True, 03:29:22 INFO - 'verify': 'False', 03:29:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:29:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:29:22 INFO - 'work_dir': 'build'} 03:29:22 INFO - [mozharness: 2017-09-17 10:29:22.666568Z] Running clobber step. 03:29:22 INFO - Running pre-action listener: _resource_record_pre_action 03:29:22 INFO - Running main action method: clobber 03:29:22 INFO - rmtree: /builds/slave/test/build 03:29:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:29:23 INFO - Running post-action listener: _resource_record_post_action 03:29:23 INFO - [mozharness: 2017-09-17 10:29:23.668836Z] Finished clobber step (success) 03:29:23 INFO - [mozharness: 2017-09-17 10:29:23.668934Z] Running read-buildbot-config step. 03:29:23 INFO - Running pre-action listener: _resource_record_pre_action 03:29:23 INFO - Running main action method: read_buildbot_config 03:29:23 INFO - Using buildbot properties: 03:29:23 INFO - { 03:29:23 INFO - "project": "", 03:29:23 INFO - "product": "firefox", 03:29:23 INFO - "who": "archaeopteryx@coole-files.de", 03:29:23 INFO - "installer_path": "public/build/target.tar.bz2", 03:29:23 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 03:29:23 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:29:23 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 03:29:23 INFO - "stage_platform": "linux64-qr", 03:29:23 INFO - "basedir": "/builds/slave/test", 03:29:23 INFO - "buildnumber": 68, 03:29:23 INFO - "platform": "ubuntu64_hw_qr", 03:29:23 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:29:23 INFO - "slavebuilddir": "test", 03:29:23 INFO - "taskId": "Z9z1V2Z3RNWDxz91_flh0w", 03:29:23 INFO - "branch": "mozilla-central", 03:29:23 INFO - "script_repo_revision": "production", 03:29:23 INFO - "revision": "faa897d7948b7e2439573f39c34366c138913663", 03:29:23 INFO - "slavename": "talos-linux64-ix-088", 03:29:23 INFO - "repo_path": "mozilla-central" 03:29:23 INFO - } 03:29:23 INFO - Finding installer, test and symbols from parent task. 03:29:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z9z1V2Z3RNWDxz91_flh0w'}, attempt #1 03:29:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z9z1V2Z3RNWDxz91_flh0w'}, attempt #1 03:29:24 INFO - Task dependencies: OSv4zTzPSF2AmqFHfdlHSQ 03:29:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ'}, attempt #1 03:29:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.tar.bz2 03:29:25 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.test_packages.json 03:29:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.crashreporter-symbols.zip 03:29:25 INFO - Running post-action listener: _resource_record_post_action 03:29:25 INFO - [mozharness: 2017-09-17 10:29:25.086990Z] Finished read-buildbot-config step (success) 03:29:25 INFO - [mozharness: 2017-09-17 10:29:25.087206Z] Running download-and-extract step. 03:29:25 INFO - Running pre-action listener: _resource_record_pre_action 03:29:25 INFO - Running main action method: download_and_extract 03:29:25 INFO - mkdir: /builds/slave/test/build/tests 03:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:25 INFO - trying https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.test_packages.json 03:29:25 INFO - Downloading https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:29:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:29:25 INFO - Downloaded 989 bytes. 03:29:25 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:29:25 INFO - Using the following test package requirements: 03:29:25 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:29:25 INFO - u'common': [u'target.common.tests.zip'], 03:29:25 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:29:25 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:29:25 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:29:25 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:29:25 INFO - u'mozbase': [u'target.common.tests.zip'], 03:29:25 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:29:25 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:29:25 INFO - u'web-platform': [u'target.common.tests.zip', 03:29:25 INFO - u'target.web-platform.tests.tar.gz'], 03:29:25 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:29:25 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:29:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.common.tests.zip 03:29:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:29:25 INFO - Fetch https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.common.tests.zip into memory 03:29:27 INFO - Content-Length response header: 54731186 03:29:27 INFO - Bytes received: 54731186 03:29:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.talos.tests.zip 03:29:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:29:38 INFO - Fetch https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.talos.tests.zip into memory 03:29:39 INFO - Content-Length response header: 14508748 03:29:39 INFO - Bytes received: 14508748 03:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:40 INFO - trying https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.tar.bz2 03:29:40 INFO - Downloading https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:29:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:29:43 INFO - Downloaded 61513324 bytes. 03:29:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.tar.bz2 03:29:43 INFO - mkdir: /builds/slave/test/properties 03:29:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:29:43 INFO - Writing to file /builds/slave/test/properties/build_url 03:29:43 INFO - Contents: 03:29:43 INFO - build_url:https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.tar.bz2 03:29:43 INFO - Running post-action listener: _resource_record_post_action 03:29:43 INFO - Running post-action listener: find_tests_for_verification 03:29:43 INFO - Running post-action listener: set_extra_try_arguments 03:29:43 INFO - [mozharness: 2017-09-17 10:29:43.399414Z] Finished download-and-extract step (success) 03:29:43 INFO - [mozharness: 2017-09-17 10:29:43.399512Z] Running populate-webroot step. 03:29:43 INFO - Running pre-action listener: _resource_record_pre_action 03:29:43 INFO - Running main action method: populate_webroot 03:29:43 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:29:43 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['tresize', 03:29:43 INFO - 'tcanvasmark']}, 03:29:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:29:43 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['dromaeo_css', 03:29:43 INFO - 'kraken']}, 03:29:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:29:43 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:29:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'tests': ['damp', 'tps']}, 03:29:43 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['damp', 'tps']}, 03:29:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:29:43 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['dromaeo_dom']}, 03:29:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:29:43 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['basic_compositor_video', 03:29:43 INFO - 'glvideo']}, 03:29:43 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:29:43 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['ts_paint_webext', 03:29:43 INFO - 'tp5o_webext']}, 03:29:43 INFO - 'other-e10s': {'tests': ['a11yr', 03:29:43 INFO - 'ts_paint', 03:29:43 INFO - 'tpaint', 03:29:43 INFO - 'sessionrestore', 03:29:43 INFO - 'sessionrestore_many_windows', 03:29:43 INFO - 'sessionrestore_no_auto_restore', 03:29:43 INFO - 'tabpaint']}, 03:29:43 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['a11yr', 03:29:43 INFO - 'ts_paint', 03:29:43 INFO - 'tpaint', 03:29:43 INFO - 'sessionrestore', 03:29:43 INFO - 'sessionrestore_many_windows', 03:29:43 INFO - 'sessionrestore_no_auto_restore', 03:29:43 INFO - 'tabpaint']}, 03:29:43 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:29:43 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 03:29:43 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['perf_reftest_singletons']}, 03:29:43 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['bloom_basic']}, 03:29:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:29:43 INFO - 'tsvgr_opacity', 03:29:43 INFO - 'tart', 03:29:43 INFO - 'tscrollx', 03:29:43 INFO - 'tsvg_static']}, 03:29:43 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['tsvgx', 03:29:43 INFO - 'tsvgr_opacity', 03:29:43 INFO - 'tart', 03:29:43 INFO - 'tscrollx', 03:29:43 INFO - 'tsvg_static']}, 03:29:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:29:43 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['tp5o']}, 03:29:43 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:29:43 INFO - 'talos_options': ['--mitmproxy', 03:29:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:29:43 INFO - '--firstNonBlankPaint'], 03:29:43 INFO - 'tests': ['tp6_google', 03:29:43 INFO - 'tp6_youtube', 03:29:43 INFO - 'tp6_amazon', 03:29:43 INFO - 'tp6_facebook']}, 03:29:43 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:29:43 INFO - 'talos_options': ['--disable-stylo', 03:29:43 INFO - '--mitmproxy', 03:29:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:29:43 INFO - '--firstNonBlankPaint'], 03:29:43 INFO - 'tests': ['tp6_google', 03:29:43 INFO - 'tp6_youtube', 03:29:43 INFO - 'tp6_amazon', 03:29:43 INFO - 'tp6_facebook']}, 03:29:43 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:29:43 INFO - 'talos_options': ['--stylo-threads=1', 03:29:43 INFO - '--mitmproxy', 03:29:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:29:43 INFO - '--firstNonBlankPaint'], 03:29:43 INFO - 'tests': ['tp6_google', 03:29:43 INFO - 'tp6_youtube', 03:29:43 INFO - 'tp6_amazon', 03:29:43 INFO - 'tp6_facebook']}, 03:29:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'talos_options': ['--xperf_path', 03:29:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:29:43 INFO - 'tests': ['tp5n']}, 03:29:43 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'talos_options': ['--disable-stylo', 03:29:43 INFO - '--xperf_path', 03:29:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:29:43 INFO - 'tests': ['tp5n']}}} 03:29:43 INFO - Running post-action listener: _resource_record_post_action 03:29:43 INFO - [mozharness: 2017-09-17 10:29:43.414566Z] Finished populate-webroot step (success) 03:29:43 INFO - [mozharness: 2017-09-17 10:29:43.414656Z] Running create-virtualenv step. 03:29:43 INFO - Running pre-action listener: _resource_record_pre_action 03:29:43 INFO - Running main action method: create_virtualenv 03:29:43 INFO - Creating virtualenv /builds/slave/test/build/venv 03:29:43 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:29:43 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:29:43 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:29:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:29:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:29:44 INFO - Installing distribute.............................................................................................................................................................................................done. 03:29:44 INFO - Installing pip...............done. 03:29:44 INFO - Return code: 0 03:29:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:29:44 INFO - Reading from file tmpfile_stdout 03:29:44 INFO - Output received: 03:29:44 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:29:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:44 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 0x28951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c01a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c05090>, '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 0x2c05d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfff90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:29:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:29:44 INFO - Using env: {'DISPLAY': ':0', 03:29:44 INFO - 'HOME': '/home/cltbld', 03:29:44 INFO - 'LANG': 'en_US.UTF-8', 03:29:44 INFO - 'LANGUAGE': 'en_US:en', 03:29:44 INFO - 'LOGNAME': 'cltbld', 03:29:44 INFO - 'MAIL': '/var/mail/cltbld', 03:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:44 INFO - 'MOZ_NO_REMOTE': '1', 03:29:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:44 INFO - 'NO_EM_RESTART': '1', 03:29:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:44 INFO - 'PWD': '/builds/slave/test', 03:29:44 INFO - 'SHELL': '/bin/bash', 03:29:44 INFO - 'SHLVL': '1', 03:29:44 INFO - 'TERM': 'linux', 03:29:44 INFO - 'TMOUT': '86400', 03:29:44 INFO - 'USER': 'cltbld', 03:29:44 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 03:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:44 INFO - '_': '/tools/buildbot/bin/python'} 03:29:45 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:29:45 INFO - Downloading/unpacking pip>=1.5 03:29:45 INFO - Running setup.py egg_info for package pip 03:29:45 INFO - warning: no files found matching 'pip/cacert.pem' 03:29:45 INFO - warning: no files found matching '*.html' under directory 'docs' 03:29:45 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:29:45 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:29:45 INFO - Installing collected packages: pip 03:29:45 INFO - Found existing installation: pip 1.1 03:29:45 INFO - Uninstalling pip: 03:29:45 INFO - Successfully uninstalled pip 03:29:45 INFO - Running setup.py install for pip 03:29:45 INFO - warning: no files found matching 'pip/cacert.pem' 03:29:45 INFO - warning: no files found matching '*.html' under directory 'docs' 03:29:45 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:29:45 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:29:45 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:29:45 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:29:45 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:29:45 INFO - Successfully installed pip 03:29:45 INFO - Cleaning up... 03:29:45 INFO - Return code: 0 03:29:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:45 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 0x28951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c01a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c05090>, '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 0x2c05d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfff90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:29:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:29:45 INFO - Using env: {'DISPLAY': ':0', 03:29:45 INFO - 'HOME': '/home/cltbld', 03:29:45 INFO - 'LANG': 'en_US.UTF-8', 03:29:45 INFO - 'LANGUAGE': 'en_US:en', 03:29:45 INFO - 'LOGNAME': 'cltbld', 03:29:45 INFO - 'MAIL': '/var/mail/cltbld', 03:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:45 INFO - 'MOZ_NO_REMOTE': '1', 03:29:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:45 INFO - 'NO_EM_RESTART': '1', 03:29:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:45 INFO - 'PWD': '/builds/slave/test', 03:29:45 INFO - 'SHELL': '/bin/bash', 03:29:45 INFO - 'SHLVL': '1', 03:29:45 INFO - 'TERM': 'linux', 03:29:45 INFO - 'TMOUT': '86400', 03:29:45 INFO - 'USER': 'cltbld', 03:29:45 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 03:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:45 INFO - '_': '/tools/buildbot/bin/python'} 03:29:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:45 INFO - Downloading/unpacking psutil>=3.1.1 03:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:29:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:29:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:29:47 INFO - Installing collected packages: psutil 03:29:47 INFO - Running setup.py install for psutil 03:29:47 INFO - building 'psutil._psutil_linux' extension 03:29:47 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:29:47 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:29:47 INFO - building 'psutil._psutil_posix' extension 03:29:47 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:29:47 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:29:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:29:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:29:47 INFO - Successfully installed psutil 03:29:47 INFO - Cleaning up... 03:29:47 INFO - Return code: 0 03:29:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:29: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')]} 03:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29: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')]} 03:29:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:47 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 0x28951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c01a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c05090>, '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 0x2c05d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfff90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:29:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:29:47 INFO - Using env: {'DISPLAY': ':0', 03:29:47 INFO - 'HOME': '/home/cltbld', 03:29:47 INFO - 'LANG': 'en_US.UTF-8', 03:29:47 INFO - 'LANGUAGE': 'en_US:en', 03:29:47 INFO - 'LOGNAME': 'cltbld', 03:29:47 INFO - 'MAIL': '/var/mail/cltbld', 03:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:47 INFO - 'MOZ_NO_REMOTE': '1', 03:29:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:47 INFO - 'NO_EM_RESTART': '1', 03:29:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:47 INFO - 'PWD': '/builds/slave/test', 03:29:47 INFO - 'SHELL': '/bin/bash', 03:29:47 INFO - 'SHLVL': '1', 03:29:47 INFO - 'TERM': 'linux', 03:29:47 INFO - 'TMOUT': '86400', 03:29:47 INFO - 'USER': 'cltbld', 03:29:47 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 03:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:47 INFO - '_': '/tools/buildbot/bin/python'} 03:29:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:29:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:29:49 INFO - Installing collected packages: mozsystemmonitor 03:29:49 INFO - Running setup.py install for mozsystemmonitor 03:29:49 INFO - Successfully installed mozsystemmonitor 03:29:49 INFO - Cleaning up... 03:29:50 INFO - Return code: 0 03:29:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:50 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 0x28951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c01a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c05090>, '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 0x2c05d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfff90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:29:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:29:50 INFO - Using env: {'DISPLAY': ':0', 03:29:50 INFO - 'HOME': '/home/cltbld', 03:29:50 INFO - 'LANG': 'en_US.UTF-8', 03:29:50 INFO - 'LANGUAGE': 'en_US:en', 03:29:50 INFO - 'LOGNAME': 'cltbld', 03:29:50 INFO - 'MAIL': '/var/mail/cltbld', 03:29:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:50 INFO - 'MOZ_NO_REMOTE': '1', 03:29:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:50 INFO - 'NO_EM_RESTART': '1', 03:29:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:50 INFO - 'PWD': '/builds/slave/test', 03:29:50 INFO - 'SHELL': '/bin/bash', 03:29:50 INFO - 'SHLVL': '1', 03:29:50 INFO - 'TERM': 'linux', 03:29:50 INFO - 'TMOUT': '86400', 03:29:50 INFO - 'USER': 'cltbld', 03:29:50 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 03:29:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:50 INFO - '_': '/tools/buildbot/bin/python'} 03:29:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:50 INFO - Downloading/unpacking jsonschema==2.5.1 03:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:29:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:51 INFO - Downloading functools32-3.2.3-2.tar.gz 03:29:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:29:51 INFO - warning: no files found matching '*.txt' 03:29:51 INFO - no previously-included directories found matching 'build' 03:29:51 INFO - no previously-included directories found matching 'dist' 03:29:51 INFO - no previously-included directories found matching '.git*' 03:29:51 INFO - Installing collected packages: jsonschema, functools32 03:29:51 INFO - Running setup.py install for functools32 03:29:51 INFO - warning: no files found matching '*.txt' 03:29:51 INFO - no previously-included directories found matching 'build' 03:29:51 INFO - no previously-included directories found matching 'dist' 03:29:51 INFO - no previously-included directories found matching '.git*' 03:29:51 INFO - Successfully installed jsonschema functools32 03:29:51 INFO - Cleaning up... 03:29:51 INFO - Return code: 0 03:29:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:51 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 0x28951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c01a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c05090>, '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 0x2c05d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfff90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:29:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:29:51 INFO - Using env: {'DISPLAY': ':0', 03:29:51 INFO - 'HOME': '/home/cltbld', 03:29:51 INFO - 'LANG': 'en_US.UTF-8', 03:29:51 INFO - 'LANGUAGE': 'en_US:en', 03:29:51 INFO - 'LOGNAME': 'cltbld', 03:29:51 INFO - 'MAIL': '/var/mail/cltbld', 03:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:51 INFO - 'MOZ_NO_REMOTE': '1', 03:29:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:51 INFO - 'NO_EM_RESTART': '1', 03:29:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:51 INFO - 'PWD': '/builds/slave/test', 03:29:51 INFO - 'SHELL': '/bin/bash', 03:29:51 INFO - 'SHLVL': '1', 03:29:51 INFO - 'TERM': 'linux', 03:29:51 INFO - 'TMOUT': '86400', 03:29:51 INFO - 'USER': 'cltbld', 03:29:51 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 03:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:51 INFO - '_': '/tools/buildbot/bin/python'} 03:29:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:29:52 INFO - Cleaning up... 03:29:52 INFO - Return code: 0 03:29:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:29: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')]} 03:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29: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')]} 03:29:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x28951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c01a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c05090>, '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 0x2c05d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfff90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:29:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:29:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:29:52 INFO - Using env: {'DISPLAY': ':0', 03:29:52 INFO - 'HOME': '/home/cltbld', 03:29:52 INFO - 'LANG': 'en_US.UTF-8', 03:29:52 INFO - 'LANGUAGE': 'en_US:en', 03:29:52 INFO - 'LOGNAME': 'cltbld', 03:29:52 INFO - 'MAIL': '/var/mail/cltbld', 03:29:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:52 INFO - 'MOZ_NO_REMOTE': '1', 03:29:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:52 INFO - 'NO_EM_RESTART': '1', 03:29:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:52 INFO - 'PWD': '/builds/slave/test', 03:29:52 INFO - 'SHELL': '/bin/bash', 03:29:52 INFO - 'SHLVL': '1', 03:29:52 INFO - 'TERM': 'linux', 03:29:52 INFO - 'TMOUT': '86400', 03:29:52 INFO - 'USER': 'cltbld', 03:29:52 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 03:29:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:52 INFO - '_': '/tools/buildbot/bin/python'} 03:29:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:52 INFO - Downloading/unpacking blobuploader==1.2.4 03:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:53 INFO - Downloading blobuploader-1.2.4.tar.gz 03:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:29:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:29:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:54 INFO - Downloading docopt-0.6.1.tar.gz 03:29:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:29:54 INFO - Installing collected packages: blobuploader, requests, docopt 03:29:54 INFO - Running setup.py install for blobuploader 03:29:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:29:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:29:54 INFO - Running setup.py install for requests 03:29:54 INFO - Running setup.py install for docopt 03:29:54 INFO - Successfully installed blobuploader requests docopt 03:29:54 INFO - Cleaning up... 03:29:54 INFO - Return code: 0 03:29:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:54 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 0x28951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c01a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c05090>, '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 0x2c05d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfff90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:29:54 INFO - Using env: {'DISPLAY': ':0', 03:29:54 INFO - 'HOME': '/home/cltbld', 03:29:54 INFO - 'LANG': 'en_US.UTF-8', 03:29:54 INFO - 'LANGUAGE': 'en_US:en', 03:29:54 INFO - 'LOGNAME': 'cltbld', 03:29:54 INFO - 'MAIL': '/var/mail/cltbld', 03:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:54 INFO - 'MOZ_NO_REMOTE': '1', 03:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:54 INFO - 'NO_EM_RESTART': '1', 03:29:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:54 INFO - 'PWD': '/builds/slave/test', 03:29:54 INFO - 'SHELL': '/bin/bash', 03:29:54 INFO - 'SHLVL': '1', 03:29:54 INFO - 'TERM': 'linux', 03:29:54 INFO - 'TMOUT': '86400', 03:29:54 INFO - 'USER': 'cltbld', 03:29:54 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 03:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:54 INFO - '_': '/tools/buildbot/bin/python'} 03:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:29:54 INFO - Running setup.py (path:/tmp/pip-hq_hQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:29:55 INFO - Running setup.py (path:/tmp/pip-FCyybb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:29:55 INFO - Running setup.py (path:/tmp/pip-HGzLZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:29:55 INFO - Running setup.py (path:/tmp/pip-sc2B0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:29:55 INFO - Running setup.py (path:/tmp/pip-okkYTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:29:55 INFO - Running setup.py (path:/tmp/pip-rG6kbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:29:55 INFO - Running setup.py (path:/tmp/pip-Vs2r6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:29:55 INFO - Running setup.py (path:/tmp/pip-IZoLbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:29:55 INFO - Running setup.py (path:/tmp/pip-C_p3fm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:29:55 INFO - Running setup.py (path:/tmp/pip-NQ6Xo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:29:55 INFO - Running setup.py (path:/tmp/pip-sV03Km-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:29:55 INFO - Running setup.py (path:/tmp/pip-gvN0JZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:29:55 INFO - Running setup.py (path:/tmp/pip-RySUAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:29:55 INFO - Running setup.py (path:/tmp/pip-Do99_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:29:55 INFO - Running setup.py (path:/tmp/pip-W7EM5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:29:56 INFO - Running setup.py (path:/tmp/pip-k_8K2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:29:56 INFO - Running setup.py (path:/tmp/pip-kOq1zv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:29:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:29:56 INFO - Running setup.py install for manifestparser 03:29:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:29:56 INFO - Running setup.py install for mozcrash 03:29:56 INFO - Running setup.py install for mozdebug 03:29:56 INFO - Running setup.py install for mozdevice 03:29:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:29:56 INFO - Running setup.py install for mozfile 03:29:56 INFO - Running setup.py install for mozhttpd 03:29:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:29:56 INFO - Running setup.py install for mozinfo 03:29:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:29:56 INFO - Running setup.py install for mozInstall 03:29:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:29:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:29:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:29:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:29:56 INFO - Running setup.py install for mozleak 03:29:57 INFO - Running setup.py install for mozlog 03:29:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:29:57 INFO - Running setup.py install for moznetwork 03:29:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:29:57 INFO - Running setup.py install for mozprocess 03:29:57 INFO - Running setup.py install for mozprofile 03:29:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:29:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:29:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:29:57 INFO - Running setup.py install for mozrunner 03:29:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:29:57 INFO - Running setup.py install for mozscreenshot 03:29:57 INFO - Running setup.py install for moztest 03:29:57 INFO - Running setup.py install for mozversion 03:29:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:29:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:29:57 INFO - Cleaning up... 03:29:57 INFO - Return code: 0 03:29:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:29:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:57 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 0x28951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c01a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c05090>, '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 0x2c05d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfff90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:29:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:29:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:29:57 INFO - Using env: {'DISPLAY': ':0', 03:29:57 INFO - 'HOME': '/home/cltbld', 03:29:57 INFO - 'LANG': 'en_US.UTF-8', 03:29:57 INFO - 'LANGUAGE': 'en_US:en', 03:29:57 INFO - 'LOGNAME': 'cltbld', 03:29:57 INFO - 'MAIL': '/var/mail/cltbld', 03:29:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:57 INFO - 'MOZ_NO_REMOTE': '1', 03:29:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:57 INFO - 'NO_EM_RESTART': '1', 03:29:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:57 INFO - 'PWD': '/builds/slave/test', 03:29:57 INFO - 'SHELL': '/bin/bash', 03:29:57 INFO - 'SHLVL': '1', 03:29:57 INFO - 'TERM': 'linux', 03:29:57 INFO - 'TMOUT': '86400', 03:29:57 INFO - 'USER': 'cltbld', 03:29:57 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 03:29:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:57 INFO - '_': '/tools/buildbot/bin/python'} 03:29:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:29:58 INFO - Running setup.py (path:/tmp/pip-THDUBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:29:58 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:29:58 INFO - Running setup.py (path:/tmp/pip-GFHQGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:29:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:29:58 INFO - Running setup.py (path:/tmp/pip-Qt3wpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:29:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:29:58 INFO - Running setup.py (path:/tmp/pip-JuIwHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:29:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:29:58 INFO - Running setup.py (path:/tmp/pip-bkE0fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:29:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:29:58 INFO - Running setup.py (path:/tmp/pip-uoL0Tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:29:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:29:58 INFO - Running setup.py (path:/tmp/pip-Ion248-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:29:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:29:58 INFO - Running setup.py (path:/tmp/pip-Nbzu4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:29:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:29:58 INFO - Running setup.py (path:/tmp/pip-9cpc9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:29:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:29:58 INFO - Running setup.py (path:/tmp/pip-YRFAxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:29:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:29:58 INFO - Running setup.py (path:/tmp/pip-04CD4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:29:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:29:58 INFO - Running setup.py (path:/tmp/pip-TiXdEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:29:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:29:59 INFO - Running setup.py (path:/tmp/pip-i_NzKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:29:59 INFO - Running setup.py (path:/tmp/pip-U4WfQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:29:59 INFO - Running setup.py (path:/tmp/pip-QXkl7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:29:59 INFO - Running setup.py (path:/tmp/pip-AqSind-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:29:59 INFO - Running setup.py (path:/tmp/pip-bHTCOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:29:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:00 INFO - Downloading blessings-1.6.tar.gz 03:30:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:30:00 INFO - Installing collected packages: blessings 03:30:00 INFO - Running setup.py install for blessings 03:30:00 INFO - Successfully installed blessings 03:30:00 INFO - Cleaning up... 03:30:00 INFO - Return code: 0 03:30:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:30:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:30:00 INFO - Reading from file tmpfile_stdout 03:30:00 INFO - Current package versions: 03:30:00 INFO - argparse == 1.2.1 03:30:00 INFO - blessings == 1.6 03:30:00 INFO - blobuploader == 1.2.4 03:30:00 INFO - docopt == 0.6.1 03:30:00 INFO - functools32 == 3.2.3-2 03:30:00 INFO - jsonschema == 2.5.1 03:30:00 INFO - manifestparser == 1.1 03:30:00 INFO - mozInstall == 1.13 03:30:00 INFO - mozcrash == 1.0 03:30:00 INFO - mozdebug == 0.1 03:30:00 INFO - mozdevice == 0.51 03:30:00 INFO - mozfile == 1.2 03:30:00 INFO - mozhttpd == 0.7 03:30:00 INFO - mozinfo == 0.10 03:30:00 INFO - mozleak == 0.1 03:30:00 INFO - mozlog == 3.5 03:30:00 INFO - moznetwork == 0.27 03:30:00 INFO - mozprocess == 0.25 03:30:00 INFO - mozprofile == 0.28 03:30:00 INFO - mozrunner == 6.13 03:30:00 INFO - mozscreenshot == 0.1 03:30:00 INFO - mozsystemmonitor == 0.3 03:30:00 INFO - moztest == 0.8 03:30:00 INFO - mozversion == 1.4 03:30:00 INFO - psutil == 3.1.1 03:30:00 INFO - requests == 1.2.3 03:30:00 INFO - wsgiref == 0.1.2 03:30:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:00 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 0x28951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c01a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c05090>, '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 0x2c05d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfff90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:30:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:30:00 INFO - Using env: {'DISPLAY': ':0', 03:30:00 INFO - 'HOME': '/home/cltbld', 03:30:00 INFO - 'LANG': 'en_US.UTF-8', 03:30:00 INFO - 'LANGUAGE': 'en_US:en', 03:30:00 INFO - 'LOGNAME': 'cltbld', 03:30:00 INFO - 'MAIL': '/var/mail/cltbld', 03:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:00 INFO - 'MOZ_NO_REMOTE': '1', 03:30:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:00 INFO - 'NO_EM_RESTART': '1', 03:30:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:00 INFO - 'PWD': '/builds/slave/test', 03:30:00 INFO - 'SHELL': '/bin/bash', 03:30:00 INFO - 'SHLVL': '1', 03:30:00 INFO - 'TERM': 'linux', 03:30:00 INFO - 'TMOUT': '86400', 03:30:00 INFO - 'USER': 'cltbld', 03:30:00 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 03:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:00 INFO - '_': '/tools/buildbot/bin/python'} 03:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:30:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:30:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:30:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:30:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:30:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:30:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:30:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:30:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:30:01 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:30:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:30:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:30:02 INFO - Installing collected packages: simplejson 03:30:02 INFO - Running setup.py install for simplejson 03:30:02 INFO - building 'simplejson._speedups' extension 03:30:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 03:30:03 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 03:30:03 INFO - Successfully installed simplejson 03:30:03 INFO - Cleaning up... 03:30:03 INFO - Return code: 0 03:30:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28951f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c01a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c05090>, '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 0x2c05d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfff90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:30:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:30:03 INFO - Using env: {'DISPLAY': ':0', 03:30:03 INFO - 'HOME': '/home/cltbld', 03:30:03 INFO - 'LANG': 'en_US.UTF-8', 03:30:03 INFO - 'LANGUAGE': 'en_US:en', 03:30:03 INFO - 'LOGNAME': 'cltbld', 03:30:03 INFO - 'MAIL': '/var/mail/cltbld', 03:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:03 INFO - 'MOZ_NO_REMOTE': '1', 03:30:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:03 INFO - 'NO_EM_RESTART': '1', 03:30:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:03 INFO - 'PWD': '/builds/slave/test', 03:30:03 INFO - 'SHELL': '/bin/bash', 03:30:03 INFO - 'SHLVL': '1', 03:30:03 INFO - 'TERM': 'linux', 03:30:03 INFO - 'TMOUT': '86400', 03:30:03 INFO - 'USER': 'cltbld', 03:30:03 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 03:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:03 INFO - '_': '/tools/buildbot/bin/python'} 03:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:30:03 INFO - Cleaning up... 03:30:03 INFO - Return code: 0 03:30:03 INFO - Running post-action listener: _resource_record_post_action 03:30:03 INFO - Running post-action listener: _start_resource_monitoring 03:30:03 INFO - Starting resource monitoring. 03:30:03 INFO - [mozharness: 2017-09-17 10:30:03.613520Z] Finished create-virtualenv step (success) 03:30:03 INFO - [mozharness: 2017-09-17 10:30:03.614031Z] Running install step. 03:30:03 INFO - Running pre-action listener: _resource_record_pre_action 03:30:03 INFO - Running main action method: install 03:30:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:30:03 INFO - Reading from file tmpfile_stdout 03:30:03 INFO - Detecting whether we're running mozinstall >=1.0... 03:30:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:30:03 INFO - Reading from file tmpfile_stdout 03:30:03 INFO - Output received: 03:30:03 INFO - Usage: mozinstall [options] installer 03:30:03 INFO - Options: 03:30:03 INFO - -h, --help show this help message and exit 03:30:03 INFO - -d DEST, --destination=DEST 03:30:03 INFO - Directory to install application into. [default: 03:30:03 INFO - "/builds/slave/test"] 03:30:03 INFO - --app=APP Application being installed. [default: firefox] 03:30:03 INFO - mkdir: /builds/slave/test/build/application 03:30:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:30:21 INFO - Reading from file tmpfile_stdout 03:30:21 INFO - Output received: 03:30:21 INFO - /builds/slave/test/build/application/firefox/firefox 03:30:21 INFO - Running post-action listener: _resource_record_post_action 03:30:21 INFO - [mozharness: 2017-09-17 10:30:21.714928Z] Finished install step (success) 03:30:21 INFO - [mozharness: 2017-09-17 10:30:21.715180Z] Running setup-mitmproxy step. 03:30:21 INFO - Running pre-action listener: _resource_record_pre_action 03:30:21 INFO - Running main action method: setup_mitmproxy 03:30:21 INFO - Skipping: mitmproxy is not required 03:30:21 INFO - Running post-action listener: _resource_record_post_action 03:30:21 INFO - [mozharness: 2017-09-17 10:30:21.716303Z] Finished setup-mitmproxy step (success) 03:30:21 INFO - [mozharness: 2017-09-17 10:30:21.716556Z] Running run-tests step. 03:30:21 INFO - Running pre-action listener: _resource_record_pre_action 03:30:21 INFO - Running pre-action listener: _set_gcov_prefix 03:30:21 INFO - Running main action method: run_tests 03:30:21 WARNING - Try message not found. 03:30:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:30:21 INFO - Python 2.7.3 03:30:21 INFO - Return code: 0 03:30:21 INFO - grabbing minidump binary from tooltool 03:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:21 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 0x2c05090>, '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 0x2c05d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bfff90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:30:21 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 03:30:21 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 03:30:21 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 03:30:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:30:21 INFO - Return code: 0 03:30:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:30:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:30:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:30:21 INFO - ENV: RUST_BACKTRACE is now full 03:30:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:30:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:30:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-088 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:30:21 INFO - Using env: {'DISPLAY': ':0', 03:30:21 INFO - 'HOME': '/home/cltbld', 03:30:21 INFO - 'LANG': 'en_US.UTF-8', 03:30:21 INFO - 'LANGUAGE': 'en_US:en', 03:30:21 INFO - 'LOGNAME': 'cltbld', 03:30:21 INFO - 'MAIL': '/var/mail/cltbld', 03:30:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:30:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:30:21 INFO - 'MOZ_ACCELERATED': '1', 03:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:21 INFO - 'MOZ_NO_REMOTE': '1', 03:30:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:30:21 INFO - 'MOZ_WEBRENDER': '1', 03:30:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:21 INFO - 'NO_EM_RESTART': '1', 03:30:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:21 INFO - 'PWD': '/builds/slave/test', 03:30:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:30:21 INFO - 'RUST_BACKTRACE': 'full', 03:30:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:30:21 INFO - 'SHELL': '/bin/bash', 03:30:21 INFO - 'SHLVL': '1', 03:30:21 INFO - 'TERM': 'linux', 03:30:21 INFO - 'TMOUT': '86400', 03:30:21 INFO - 'USER': 'cltbld', 03:30:21 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 03:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:21 INFO - '_': '/tools/buildbot/bin/python'} 03:30:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:30:22 INFO - mozversion application_buildid: 20170917094019 03:30:22 INFO - mozversion application_changeset: faa897d7948b7e2439573f39c34366c138913663 03:30:22 INFO - mozversion application_display_name: Nightly 03:30:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:30:22 INFO - mozversion application_name: Firefox 03:30:22 INFO - mozversion application_remotingname: firefox 03:30:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:30:22 INFO - mozversion application_vendor: Mozilla 03:30:22 INFO - mozversion application_version: 57.0a1 03:30:22 INFO - mozversion platform_buildid: 20170917094019 03:30:22 INFO - mozversion platform_changeset: faa897d7948b7e2439573f39c34366c138913663 03:30:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:30:22 INFO - mozversion platform_version: 57.0a1 03:30:22 INFO - using testdate: 1505644222 03:30:22 INFO - actual date: 1505644222 03:30:22 INFO - starting webserver on 'localhost:38816' 03:30:22 INFO - SUITE-START | Running 2 tests 03:30:22 INFO - TEST-START | dromaeo_css 03:30:22 INFO - Initialising browser for dromaeo_css test... 03:30:22 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38816/getInfo.html -profile /tmp/tmpxVs96C/profile 03:30:22 INFO - TEST-INFO | started process 13515 (/builds/slave/test/build/application/firefox/firefox http://localhost:38816/getInfo.html) 03:30:28 INFO - TEST-INFO | 13515: exit 0 03:30:28 INFO - Browser initialized. 03:30:28 INFO - Running cycle 1/1 for dromaeo_css test... 03:30:28 INFO - Using env: {'DISPLAY': ':0', 03:30:28 INFO - 'HOME': '/home/cltbld', 03:30:28 INFO - 'JSGC_DISABLE_POISONING': '1', 03:30:28 INFO - 'LANG': 'en_US.UTF-8', 03:30:28 INFO - 'LANGUAGE': 'en_US:en', 03:30:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:30:28 INFO - 'LOGNAME': 'cltbld', 03:30:28 INFO - 'MAIL': '/var/mail/cltbld', 03:30:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:30:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:30:28 INFO - 'MOZ_ACCELERATED': '1', 03:30:28 INFO - 'MOZ_CRASHREPORTER': '1', 03:30:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:30:28 INFO - 'MOZ_NO_REMOTE': '1', 03:30:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:30:28 INFO - 'MOZ_WEBRENDER': '1', 03:30:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:28 INFO - 'NO_EM_RESTART': '1', 03:30:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:28 INFO - 'PWD': '/builds/slave/test', 03:30:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:30:28 INFO - 'RUST_BACKTRACE': 'full', 03:30:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:30:28 INFO - 'SHELL': '/bin/bash', 03:30:28 INFO - 'SHLVL': '1', 03:30:28 INFO - 'TERM': 'linux', 03:30:28 INFO - 'TMOUT': '86400', 03:30:28 INFO - 'USER': 'cltbld', 03:30:28 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 03:30:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:28 INFO - '_': '/tools/buildbot/bin/python'} 03:30:28 INFO - TEST-INFO | started process 13759 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxVs96C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:30:29 INFO - PID 13759 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:30:29 INFO - PID 13759 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:30:29 INFO - PID 13759 | 03:30:29 INFO - PID 13759 | (/builds/slave/test/build/application/firefox/firefox:13818): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:30:29 INFO - PID 13759 | 03:30:39 INFO - PID 13759 | RSS: Main: 185339904 03:30:39 INFO - PID 13759 | 03:33:36 INFO - PID 13759 | Cycle 1(1): loaded http://localhost:38816/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38816/tests/dromaeo/cssquery-ext.html) 03:33:38 INFO - PID 13759 | RSS: Main: 187465728 03:33:38 INFO - PID 13759 | 03:36:40 INFO - PID 13759 | Cycle 1(1): loaded http://localhost:38816/tests/dromaeo/cssquery-ext.html (next: http://localhost:38816/tests/dromaeo/cssquery-jquery.html) 03:36:41 INFO - PID 13759 | RSS: Main: 187424768 03:36:41 INFO - PID 13759 | 03:39:43 INFO - PID 13759 | Cycle 1(1): loaded http://localhost:38816/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38816/tests/dromaeo/cssquery-mootools.html) 03:39:43 INFO - PID 13759 | RSS: Main: 193282048 03:39:43 INFO - PID 13759 | 03:42:45 INFO - PID 13759 | Cycle 1(1): loaded http://localhost:38816/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38816/tests/dromaeo/cssquery-prototype.html) 03:42:46 INFO - PID 13759 | RSS: Main: 187850752 03:42:46 INFO - PID 13759 | 03:45:50 INFO - PID 13759 | Cycle 1(1): loaded http://localhost:38816/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38816/tests/dromaeo/cssquery-yui.html) 03:45:50 INFO - PID 13759 | RSS: Main: 189509632 03:45:50 INFO - PID 13759 | 03:48:52 INFO - PID 13759 | Cycle 1(1): loaded http://localhost:38816/tests/dromaeo/cssquery-yui.html (next: http://localhost:38816/tests/dromaeo/cssquery-dojo.html) 03:48:52 INFO - PID 13759 | RSS: Main: 191885312 03:48:52 INFO - PID 13759 | 03:48:52 INFO - PID 13759 | __start_tp_report 03:48:52 INFO - PID 13759 | _x_x_mozilla_page_load 03:48:52 INFO - PID 13759 | _x_x_mozilla_page_load_details 03:48:52 INFO - PID 13759 | |i|pagename|runs| 03:48:52 INFO - PID 13759 | |0;dojo.html;3115.2523394385344;3124.734397576206;3131.3111115554584;3138.2781960149164;3172.4924012158053;6034.8793024139495;6212.844678883024;6236.875262494753;6239.4696450801675;6312.652804095773;6018.548608854342;6127.325994140652;6158.907616385746;6189.566730328879;6193.721282542285;5656.123300888348;5657.9434205658035;5682;5694.402087780785;5786.594938354317;5681.318241810988;5740.024195886709;5742.569307301948;5775.653460792366;5830.154627578999;160.6958972413419;5731.799387021455;5769.221155144065;5770.04794208955;5791;5544.22380866679;5558.583106267046;5592.049351610236;5697.287839020122;5761.654300741927;5606.159076138571;5659.292588426447;5695.743691533891;5716.685582292972;5731.799387021434;6130.877382452326;6140.84647883802;6144.41628045335;6191.721372538202;6209.254889413254;6554.1151944487365;6574.046763219354;6619.768308109193;6634.0712300278;6676.399124078807;6658.10115634388;6696.19645642526;6757.628330441824;6777.49168812341;6778.69495872682;6086.330503644595;6166.81499555014;6172.043333283348;6180.134781130645;6196.411340922605;6700.2294736105;6705.362990515942;6716.597004179765;6732.697028633724;6740.39336459721;6409.4231519163495;6433.485321174295;6441.871162576722;6498.805035848932;6553.705083271264;6632.900506492407;6672.9332706673285;6685.598864068172;6694.832629184212;6722;6458.6770661466735;6600.900986485207;6610.669466526654;6667.066610674509;6693.364130407604;6280.497560195265;6502.837429064217;6658.067870498134;6721.831954201184;6742.325767423316;5144;5169.586433085344;5177.275181474596;5179.093658609803;5213.973930130401;4576.290674945389;4591.058832939239;4591.47198072226;4593.333966574828;4633.374494443207;5758.452946970031;5851.590388672752;5857.062869940874;5870.618409803349;5874.73563689635;6623.238327592292;6645;6656.134702488743;6736.93263067363;6747.460203183831;5579.9163012554845;5612.859678507988;5754.367019627837;5877.206577112121;5882.441168089024;5553.08374118266;5630.915536266959;5652.547796176296;5673.234113394721;5675.3757086720425;6307.306196318401;6438.323975982548;6452.935470645328;6469.8706025880165;6478.4169424752;6614.503912206604;6665.166854143232;6665.966670166715;6706.865862682816;6729.0915726376315;6443.194600674916;6451.612903225821;6496.447801936888;6553.868922621617;6595.571287866273;6512.088307637029;6537.836554086186;6604.306547812603;6628.767993120217;6636.767713130017;6513.609183449008;6540.803775886731;6591.6045037296;6628.337166283333;6635.767748128792;6384.648844313608;6453.064305675557;6469.288378278292;6478.805635830932;6492.837679058061;6417.518686098468;6496.765614533224;6547.312532184052;6549.050387693853;6555.4427873629365;6236.002239641635;6394.296627370893;6397.840053998687;6491;6495.317991610813;6400.615963042233;6420.2937676856445;6436.935630643821;6468.967655161882;6471.06169605414;6182.783602573888;6203.751849925952;6230.034344676583;6251.78118765841;6280.434760871543 03:48:52 INFO - PID 13759 | |1;ext.html;855.9144085591441;870.3037569944047;882.1311008656477;887.1793590928394;888.8533391990318;37494.43758343627;41622.37566436502;41704.374434383506;41778.58221417785;41812.372814407805;37124.62875371245;37582;37596;37657.246855062884;37673;6973.965130174342;7021.543599666005;7058.964705176467;7095.184053833824;7137.036500072476;6966.16406031277;7017.333353331425;7057.717691292342;7070.717171313141;7078;6945.236024037352;6977.092977912865;6999.790006299819;7000.28493019211;7012.053372794684;7006.649667516629;7680.193579674137;7861.724839630642;7875.369970402354;7938.563379014152;7691.115521715018;7775.688972441096;7779.7666070017995;7782.143964163937;7795.064592248909;37285;37333.06667333311;37528.24943501142;37576.06059848498;37851.43222851659;3549.343371476285;3568.536090308269;3583.552055993001;3586.28274345132;3588.982055089708;32041;32366.676333236603;32388.35223295521;32433.675663243303;32433.83783081093;5161.122609156415;5198.610104242159;5218.478152184789;5248.921266181011;5295.152775555931;183917;185019;185271;185715.07142464202;185888;41319.79340103341;41739.37390939138;47669.76165119152;47671.523284767405;47842.5215747845;49931.25103123456;50060.74969625129;50082;50175.498245017814;50187.74906125446;15408.460703875311;15651.37394504207;15669.764953525706;15670.921645391929;15674.921625392028;12546.623601291976;12595.685107872376;12649.05132115095;12683.746325073447;12729.809052864215;14014.71970560583;14052.859471405154;14216.00487965853;14296.356663949939;14313.78529322061;5749.338826034976;5797.36229014808;5799.3620701722775;5853.88292234153;5910.231669882888;983.9065288797552;993.7764003099389;997.7505623594102;1001.0740065439439;1017.4099022566893;22908.312750617508;23036;23038.65442018371;23245.3026409208;23304.883475582854;1074.4573990134932;1089.2865173311493;1097.040089921319;1110.2394859521253;1111.9777604447866;12020.110146028383;12039.277643341427;12156.209846359958;12166.513339466323;12192.268463892195;216957;218105;218849;218968;219208;51537;51540.48459515357;51797.223041654404;51956.48043519516;52036;1435.0887186636562;1467.7871708602406;1474.027142086218;1474.6460849396283;1479.837217906008;21967.450813729785;22027;22214.333569992927;22699.886500568053;22770.65844012374;36302.273954520235;36402.817985909904;36541.45187822238;36548.45177322289;36651.26697466089;181013;181534;181620;181759;183451;29220.415591687623;29409.264768380963;30141.849290753406;32579;34071.65928340784;41692.37461438141;42273.788631057876;42454.36318455287;42586.78706606447;43146;30908;31001;31405.842970785914;31633;31728.682713172573;30933.535996960512;31223;31275.374492510477;31284.843575781975;31618;34738.65261347354;34744.13139671528;34797.652023480456;35060.474092888115;35108.12229694278;30071.548926766554;30389.088327350215;30883.301418421303;30912.845435773575;31123;21952.75496132882;23032.309030729106;23122.76877231206;23569.17507887216;23663.64504532468 03:48:52 INFO - PID 13759 | |2;jquery.html;566.0970751651115;568.1335962656948;571.6277448588361;578.8350320158754;590.4272855330329;22256.109755609777;22925.77074229257;22951.08195672175;22958;22992.425189370253;23652.526949461;24194.274171774832;24679.259622211317;24729.629055564183;24777.50444991099;6609.372109649587;6693.732250709966;6716.966415167917;6735.158105236846;6737;6648.16897887764;6687.6990535425775;6736.124303840494;6740.427063699592;6748.358905903931;6372.171617689693;6565.179352580927;6610.8677826443445;6637.701303441332;6685.966570167142;6570.901436478457;6602.042703807945;6602.1747281589805;6606.570572912769;6606.735730570771;5930.851728706817;6070.2715674118945;6073.696315184223;6091.969540152271;6163.7842675506145;27135.1859444217;27631.861840690668;27852.721472785215;27867.86066069677;28146.859265703744;2004.899755012258;2045.2739277556402;2048.897555122253;2052.558699879518;2058.742657167854;18674.34639787601;19039.809601903944;19375.12811923467;19383.224671013144;19412.708809367872;2403.495265994143;2451.521953219123;2470;2486.3286912533695;2488.129154795825;123800.38099809534;128989;129333;133721.3313933434;140430;14093.71812563743;14374.640633984025;14443.422263109358;14507.1295722257;14527.332684026676;22042.33872983813;22383.104675812785;22402.887985560294;22406.775932240467;22505;9559.856602150974;10041.146502547219;10062.39625622465;10194.082532572103;10281;9350.766230844147;9392.8591071134;9413.105754953269;9421.38760980534;9457.43255404678;8301.46040507365;8784.604692788713;8999.235065019415;9119.179273865251;9141.908580914105;2408;2478.1202673050807;2492.513959777862;2514.0823599386094;2519.533886231053;932.8927173375149;934.780326623243;941.9011003844635;957.4638202606424;969.6170012844887;18676;18816.435506934813;19125.90437047806;19183.52041198981;19209.03954802282;991.9355241909253;992.4243938515643;1007.8286691262502;1018.4245901066022;1021.3004092196869;10408.739781505523;10702.785944281071;10720.892791072145;10742.355458672348;10761.924262021681;431091;439071;445524;446937;457262;109864.4506777461;109954;112650;114016;119027;1146.7018575170516;1156.3004382348727;1181.9054475641929;1182.2847177457513;1191.988080119205;16806.57983550421;16822;17022.233999469805;17089.91455042717;17159.742603861203;51147.23279150741;52226.73886630542;52723.736381317845;53074.73462632662;54259.45740542544;68987;69661;69742.65128674325;69945.65027174831;71405.64297178481;13811.930940345233;13908.652283692989;14119.858801412265;14136.929315353358;14221.360038798486;21465.46336341604;21661.783382166606;21901.780982189975;22058.44853878666;22374;15268.465603703817;15407.383704652137;15462.768058478907;15559.76660350073;17477.737833932755;15244.466443674419;15861.365545378056;15944;16140.515784526482;16236.918815405847;18006.189721462826;18009;18335.816641833946;18358.81641183571;18369.54076148107;13736.65658358549;13858.65353366174;13892.93053534726;13921.303934803422;14044.508442204473;16445.91777041107;17378.82621173772;17449;17516.38692645751;17541.210645521238 03:48:52 INFO - PID 13759 | |3;mootools.html;1354.8374195096587;1409.9718005639888;1416.6600015996157;1417.5959851442342;1419.5457453614847;14787.260636968149;15514.30185641646;15676.764848527282;15678;15690.293936772858;15166;15352.846471535282;15389.307481163347;15426.84573154268;15428.922855385706;2735.343517555792;2760.9585856212225;2794.091920125957;2803.1590522843167;2822.2379957411486;2533.961990570134;2604.583266677332;2615.829971051885;2616.5944278636844;2616.7121616622253;2656.747608977144;2682.4903268378944;2731.3308239481257;2767.4465106978582;2776.2642899631614;2722.537168681329;2761.737634924679;2764.25365151409;2766.2669392610974;2771.113243761997;2692.7980401469968;2715.91852244433;2740.8355498670144;2747.3131717070733;2750.8074434789373;14312.499062532865;14667.92666036663;14692.926535367362;14740;14767.70464590713;7872.803179920491;8140.674373025072;8143.674253029931;8212.137725538792;8226.547539885303;5167.3282473278605;5336.546393556552;5393.514583687448;5415.566754659618;5466;1897.8671492995495;1926.2776458827939;1932.7777305609754;1955.3449594385897;1992.392320342295;27060.729392706016;27468.313292167655;27518.312042198904;27598.172054838386;27753.722462775317;7012.9298707013295;7024;7145.285471452917;7259.6007219603425;7274.854502909912;20188.09153588092;20209.090590923443;20230.89884550568;20297.797022029885;20367.59264814725;7931.801704957307;8214.301784348278;8240.958795205986;8285.171482851667;8291.295239904555;7082.787516374517;7215.278472152848;7275.854482910418;7303.488755787044;7397.926020739831;5510.8071217507995;5736.627119237236;5741.913871291934;5745;5781.4218578141845;1767.6729804986119;1793.9641207176044;1796.1827368547279;1810.5383127302716;1844.087176847469;717.7739012211136;870;875.6978842299396;886.8448021596195;898.3397203055749;8026.759197223976;8249.133840946733;8249.711260105867;8410;8495.617697203517;965.2037069417759;966.2221911361281;978.1636700621009;999.6201443451442;1004.1213937804421;4843.273508973612;5000.399952005782;5006.123928312531;5063.240513922941;5332.2801421808335;20519.38441846747;21153.89423052875;21650.6752398711;22000.229991950524;22044;20908.37274881756;21288;21577.892110539662;21750;21960.560788784136;10835.83746243807;10903.563857445613;11044.171687123498;11152.553897844158;11162.776744465065;7767.811391529858;7912.4856884302335;7961.960190199012;8025.03699556057;8092.595370231583;18213.362532311305;18719.71920421222;18936.526586834887;19029.14368853377;19389.612207756047;18956.620867582846;19135.52161195981;19168.61662766709;19328;19386.709199362303;11352.489137988645;11400.828987565028;11484.598039068593;11493.942530287295;11564.768704626029;13703.040787145203;13993.790093148407;14081.718365632834;14118.929405352908;14120.223387713775;11212.383318917538;11529.654110376701;11621.709457263636;11703.946644802008;11753.529858805552;11122.332660040423;11251.831222531504;11319.151063670093;11402.258853174517;11585.88414115848;8777.341699372444;10200.693979180636;10208.897911020795;10245;10249.282550221413;6500.349965003462;6652.301508341555;6674.299198584079;6766.729330826909;6789.388954994074;6283;6412.519061070345;6427.196600424947;6452.548321617442;6632 03:48:52 INFO - PID 13759 | |4;prototype.html;504.9043575441291;508.08291034682384;517.8420581722573;527.6860268140457;530.2126342381565;6250.531210159239;6258.9999999999945;6262.780802671908;6277.497800175975;6296.8980428425075;6123.724432400541;6158.59969102008;6208.192934918454;6219;6250.84372890678;4769.308450274725;4987.351644286256;5132.358455193101;5146.8713282168055;5167.199084141946;4912.7297998610065;4992.625553083533;5010.799568017275;5027.09512287788;5035.748212589374;4915.262710593422;4916.410030796309;4936.629752768539;4953.405591329028;5006.098902197621;4965.801367945278;4972.776225069844;4976.651634385595;4988.152014157602;4989.850304490835;4469.977650111761;4488.304312831517;4538.7276763393975;4542.613877820356;4543.954560454419;6120.44915957566;6147.938520614826;6150.600210986271;6217.409346112157;6239.719212635397;5612.15817627355;5622.775088996435;5646.419002679257;5669.886602267973;5685.403032681592;6390.329015453403;6428.742850285983;6467.288598254188;6498.220213574401;6514.469099761553;4546.363509108727;4804.639652026112;4812.133815913135;4813.518648135158;4849.102915960559;6566.901496477557;6617.8014659560295;6633.90049149268;6710.530262881649;6755.324467553205;4879.26810978349;4881.9511804881495;4897.5592196701755;4926.8768280793265;4932.26016097581;4971.4282857471135;5016.685650340704;5018.397792264952;5024.221245706921;5025.974870125626;5586.9607593228175;5590.440955904377;5602.915956260578;5634.126710359901;5654.886902261932;6465.4827613790785;6507.8698426031215;6561.606303621798;6591.604503729792;6619.106420633249;1706.436875830973;1732.5668582854287;1740.1125426032816;1755.5611097225694;1762.4007837335369;1109.1293334732359;1160.3733983648904;1168.5501082083458;1184.8874356936078;1188.60932708731;5609.298837645294;5618.831435056954;5642.435756424325;5675.148727690879;5687.914681279784;6592.692557604896;6609.653572981619;6630.9336906630315;6699.631520266432;6715.966420167868;5570.248016517799;5576.665400076008;5597.608167428322;5609.467100625516;5659.603827732101;3467.6358982306997;3502.6322236165347;3505.8071806050402;3527.2063785648024;3542.6811586956783;6504.674766261668;6527.54307198501;6527.67361631926;6544.803655890331;6591.868162636721;6564.457352522153;6655.966720166368;6664.566803157779;6691.76578819739;6705.463562914955;6481.254655714559;6507.381798729113;6518.641474718936;6564.80305590833;6577.914644083673;6560.868782624416;6575.868482630225;6579.144711187515;6591.781259993512;6670.633115178712;5931.347551769388;6451.70967306463;6473.320301368477;6490.578112422678;6530.379613936669;6302.590331628429;6323.241211054703;6449.677516124269;6450.612963222222;6532.150820393318;4825.469198388245;4845.370101886732;4879.609631229422;4888.826681596463;4916.532929371704;6384;6479.708413121515;6557.737580534708;6594.8351291218105;6613.206415230203;6281.748730050929;6400.967995160181;6490.707918143789;6504.512161587805;6540.411362977355;6334.4028948131045;6451.3548645135115;6480.25477070143;6486.740530378732;6515.54391192612;6310.873782524415;6339.714712837841;6344.873102538016;6387.968060159669;6445.452136568257;6487.967560162169;6501.414872661483;6530.706118224786;6549.7380104795275;6554.410103090745;6248.9062664059165;6309.49524038085;6312.305646378986;6331.820998141205;6335.809925702236 03:48:52 INFO - PID 13759 | |5;yui.html;909.531591230516;910.5219759626196;928.3594319919255;934.6168071090854;934.8924873639531;18921.905390473046;19713.704294435596;19719.1126399312;19736.309229176964;19810.10854511547;18048.548786280317;18189.545261368472;18230.81769182308;18235.72646410305;18274.086295685196;953.3850666320202;979.990200097997;983.2871168402886;983.3509883476906;983.650803964593;968.7578105473632;975.790205105902;977.8484334928097;978.5156347607945;982.4940155819759;985.9063388978036;1013.2147585621129;1023.4677967456918;1034.3070143004168;1037.2687255484902;1013.8175128476871;1017.2726500552141;1021.4637315409433;1021.4739409204266;1025.9281850270484;749.3967356278193;751.6617522115071;758.4349659503705;758.6851456645486;760.4144808497481;19409.126589303374;19466.70799938002;19555.315563955337;20192.49518762028;20245.8987705062;21281.68077478839;21304.786952130435;21612;21796.128154873946;22025.118995240173;16917.323307067825;16975.66048679032;17179.312827486887;17303.480895573153;17361.305547778073;3738.532683414573;3791.6777073948747;3792.317382871082;3792.9620703792884;3798.525184351956;97692.51153744257;113741;140733.29633351768;141233;142884;16694.332226711038;16717.91641041787;16782.160891955355;16987.150642467826;17040.744388834177;30144.54783178254;30259;30760.69239307623;31224.68775312263;31403;8984.550772461482;9002.729918102468;9014.23379012778;9023.729288121367;9060;4120.052387950788;4154.878182890603;4157.8336866525;4180.226658068267;4240.448741663626;5263.184206448007;5316.0962636351915;5371.221172929982;5390.649607775482;5397.163439666858;1065.9553637435356;1080.9729756755987;1108.9778204435866;1114.4483480676954;1114.6934592986993;672.7342699633715;683.5898460923406;687.7970998555438;690.3786592067079;701.8210356359095;11818.586349477899;11908.64274071762;11932;12605.49578016869;12640.620781376574;677.6577828196729;681.924988251292;689.9896501552481;704.9048378468841;706.2372637551432;5450.972745136249;5512.145617429305;5518.944810551843;5577.6932268724795;5638.464345887216;126763;127489;128116;130701.03948440781;131235;113548;114355;114974;116827;118593.407032966;98520.53698657734;100397.49801250947;100517;101854;102296;10442.112420444044;10514.947425262824;10662.84005739899;10702.89297107019;10729.463526823783;40968;42777.78611106925;43743.12513749771;44305.55694443014;44361.77819110884;59256.70371648114;59438.40561594329;59850.70074649599;60259.09611355921;60369.396306038136;15893.284802183698;16084.276207570922;16135.273912673723;16175.27211275472;16365.509034728977;16419.507414777574;16430.507084787474;16490.58773530671;16600.916995414944;16646.417375391804;15075.849241507884;15097.245137743288;15104.848951510783;15153.393864245747;15354;14759.704805904037;14930.701385972437;14958.626034349229;14996.2501874908;15127.319270633068;16213;16414;16581.419650312182;16588.66822663564;16761.580960476087;12360.44378002974;12478.625641230778;12535.498580056696;12661.113722039368;12808.039397045073;11939.880601193878;11948.402579870797;12076.396180190779;12182.878171218528;12256.816147757612 03:48:52 INFO - PID 13759 | __end_tp_report 03:48:52 INFO - PID 13759 | __start_cc_report 03:48:52 INFO - PID 13759 | _x_x_mozilla_cycle_collect,290 03:48:52 INFO - PID 13759 | __end_cc_report 03:48:52 INFO - PID 13759 | __startTimestamp1505645332965__endTimestamp 03:48:52 INFO - PID 13759 | 03:48:52 INFO - PID 13759 | ------- Summary: start ------- 03:48:52 INFO - PID 13759 | Number of tests: 6 03:48:52 INFO - PID 13759 | 03:48:52 INFO - PID 13759 | [#0] dojo.html Cycles:165 Average:6063.94 Median:6312.65 stddev:858.44 (13.6%) stddev-sans-first:829.32 03:48:52 INFO - PID 13759 | Values: 3115.3 3124.7 3131.3 3138.3 3172.5 6034.9 6212.8 6236.9 6239.5 6312.7 6018.5 6127.3 6158.9 6189.6 6193.7 5656.1 5657.9 5682.0 5694.4 5786.6 5681.3 5740.0 5742.6 5775.7 5830.2 160.7 5731.8 5769.2 5770.0 5791.0 5544.2 5558.6 5592.0 5697.3 5761.7 5606.2 5659.3 5695.7 5716.7 5731.8 6130.9 6140.8 6144.4 6191.7 6209.3 6554.1 6574.0 6619.8 6634.1 6676.4 6658.1 6696.2 6757.6 6777.5 6778.7 6086.3 6166.8 6172.0 6180.1 6196.4 6700.2 6705.4 6716.6 6732.7 6740.4 6409.4 6433.5 6441.9 6498.8 6553.7 6632.9 6672.9 6685.6 6694.8 6722.0 6458.7 6600.9 6610.7 6667.1 6693.4 6280.5 6502.8 6658.1 6721.8 6742.3 5144.0 5169.6 5177.3 5179.1 5214.0 4576.3 4591.1 4591.5 4593.3 4633.4 5758.5 5851.6 5857.1 5870.6 5874.7 6623.2 6645.0 6656.1 6736.9 6747.5 5579.9 5612.9 5754.4 5877.2 5882.4 5553.1 5630.9 5652.5 5673.2 5675.4 6307.3 6438.3 6452.9 6469.9 6478.4 6614.5 6665.2 6666.0 6706.9 6729.1 6443.2 6451.6 6496.4 6553.9 6595.6 6512.1 6537.8 6604.3 6628.8 6636.8 6513.6 6540.8 6591.6 6628.3 6635.8 6384.6 6453.1 6469.3 6478.8 6492.8 6417.5 6496.8 6547.3 6549.1 6555.4 6236.0 6394.3 6397.8 6491.0 6495.3 6400.6 6420.3 6436.9 6469.0 6471.1 6182.8 6203.8 6230.0 6251.8 6280.4 03:48:52 INFO - PID 13759 | 03:48:52 INFO - PID 13759 | [#1] ext.html Cycles:180 Average:36045.37 Median:35084.30 stddev:50683.29 (144.5%) stddev-sans-first:50756.59 03:48:52 INFO - PID 13759 | Values: 855.9 870.3 882.1 887.2 888.9 37494.4 41622.4 41704.4 41778.6 41812.4 37124.6 37582.0 37596.0 37657.2 37673.0 6974.0 7021.5 7059.0 7095.2 7137.0 6966.2 7017.3 7057.7 7070.7 7078.0 6945.2 6977.1 6999.8 7000.3 7012.1 7006.6 7680.2 7861.7 7875.4 7938.6 7691.1 7775.7 7779.8 7782.1 7795.1 37285.0 37333.1 37528.2 37576.1 37851.4 3549.3 3568.5 3583.6 3586.3 3589.0 32041.0 32366.7 32388.4 32433.7 32433.8 5161.1 5198.6 5218.5 5248.9 5295.2 183917.0 185019.0 185271.0 185715.1 185888.0 41319.8 41739.4 47669.8 47671.5 47842.5 49931.3 50060.7 50082.0 50175.5 50187.7 15408.5 15651.4 15669.8 15670.9 15674.9 12546.6 12595.7 12649.1 12683.7 12729.8 14014.7 14052.9 14216.0 14296.4 14313.8 5749.3 5797.4 5799.4 5853.9 5910.2 983.9 993.8 997.8 1001.1 1017.4 22908.3 23036.0 23038.7 23245.3 23304.9 1074.5 1089.3 1097.0 1110.2 1112.0 12020.1 12039.3 12156.2 12166.5 12192.3 216957.0 218105.0 218849.0 218968.0 219208.0 51537.0 51540.5 51797.2 51956.5 52036.0 1435.1 1467.8 1474.0 1474.6 1479.8 21967.5 22027.0 22214.3 22699.9 22770.7 36302.3 36402.8 36541.5 36548.5 36651.3 181013.0 181534.0 181620.0 181759.0 183451.0 29220.4 29409.3 30141.8 32579.0 34071.7 41692.4 42273.8 42454.4 42586.8 43146.0 30908.0 31001.0 31405.8 31633.0 31728.7 30933.5 31223.0 31275.4 31284.8 31618.0 34738.7 34744.1 34797.7 35060.5 35108.1 30071.5 30389.1 30883.3 30912.8 31123.0 21952.8 23032.3 23122.8 23569.2 23663.6 03:48:52 INFO - PID 13759 | 03:48:52 INFO - PID 13759 | [#2] jquery.html Cycles:180 Average:32698.51 Median:22404.83 stddev:75239.04 (335.8%) stddev-sans-first:75411.42 03:48:52 INFO - PID 13759 | Values: 566.1 568.1 571.6 578.8 590.4 22256.1 22925.8 22951.1 22958.0 22992.4 23652.5 24194.3 24679.3 24729.6 24777.5 6609.4 6693.7 6717.0 6735.2 6737.0 6648.2 6687.7 6736.1 6740.4 6748.4 6372.2 6565.2 6610.9 6637.7 6686.0 6570.9 6602.0 6602.2 6606.6 6606.7 5930.9 6070.3 6073.7 6092.0 6163.8 27135.2 27631.9 27852.7 27867.9 28146.9 2004.9 2045.3 2048.9 2052.6 2058.7 18674.3 19039.8 19375.1 19383.2 19412.7 2403.5 2451.5 2470.0 2486.3 2488.1 123800.4 128989.0 129333.0 133721.3 140430.0 14093.7 14374.6 14443.4 14507.1 14527.3 22042.3 22383.1 22402.9 22406.8 22505.0 9559.9 10041.1 10062.4 10194.1 10281.0 9350.8 9392.9 9413.1 9421.4 9457.4 8301.5 8784.6 8999.2 9119.2 9141.9 2408.0 2478.1 2492.5 2514.1 2519.5 932.9 934.8 941.9 957.5 969.6 18676.0 18816.4 19125.9 19183.5 19209.0 991.9 992.4 1007.8 1018.4 1021.3 10408.7 10702.8 10720.9 10742.4 10761.9 431091.0 439071.0 445524.0 446937.0 457262.0 109864.5 109954.0 112650.0 114016.0 119027.0 1146.7 1156.3 1181.9 1182.3 1192.0 16806.6 16822.0 17022.2 17089.9 17159.7 51147.2 52226.7 52723.7 53074.7 54259.5 68987.0 69661.0 69742.7 69945.7 71405.6 13811.9 13908.7 14119.9 14136.9 14221.4 21465.5 21661.8 21901.8 22058.4 22374.0 15268.5 15407.4 15462.8 15559.8 17477.7 15244.5 15861.4 15944.0 16140.5 16236.9 18006.2 18009.0 18335.8 18358.8 18369.5 13736.7 13858.7 13892.9 13921.3 14044.5 16445.9 17378.8 17449.0 17516.4 17541.2 03:48:52 INFO - PID 13759 | 03:48:52 INFO - PID 13759 | [#3] mootools.html Cycles:180 Average:9452.33 Median:2669.62 stddev:6862.39 (257.1%) stddev-sans-first:6854.67 03:48:52 INFO - PID 13759 | Values: 1354.8 1410.0 1416.7 1417.6 1419.5 14787.3 15514.3 15676.8 15678.0 15690.3 15166.0 15352.8 15389.3 15426.8 15428.9 2735.3 2761.0 2794.1 2803.2 2822.2 2534.0 2604.6 2615.8 2616.6 2616.7 2656.7 2682.5 2731.3 2767.4 2776.3 2722.5 2761.7 2764.3 2766.3 2771.1 2692.8 2715.9 2740.8 2747.3 2750.8 14312.5 14667.9 14692.9 14740.0 14767.7 7872.8 8140.7 8143.7 8212.1 8226.5 5167.3 5336.5 5393.5 5415.6 5466.0 1897.9 1926.3 1932.8 1955.3 1992.4 27060.7 27468.3 27518.3 27598.2 27753.7 7012.9 7024.0 7145.3 7259.6 7274.9 20188.1 20209.1 20230.9 20297.8 20367.6 7931.8 8214.3 8241.0 8285.2 8291.3 7082.8 7215.3 7275.9 7303.5 7397.9 5510.8 5736.6 5741.9 5745.0 5781.4 1767.7 1794.0 1796.2 1810.5 1844.1 717.8 870.0 875.7 886.8 898.3 8026.8 8249.1 8249.7 8410.0 8495.6 965.2 966.2 978.2 999.6 1004.1 4843.3 5000.4 5006.1 5063.2 5332.3 20519.4 21153.9 21650.7 22000.2 22044.0 20908.4 21288.0 21577.9 21750.0 21960.6 10835.8 10903.6 11044.2 11152.6 11162.8 7767.8 7912.5 7962.0 8025.0 8092.6 18213.4 18719.7 18936.5 19029.1 19389.6 18956.6 19135.5 19168.6 19328.0 19386.7 11352.5 11400.8 11484.6 11493.9 11564.8 13703.0 13993.8 14081.7 14118.9 14120.2 11212.4 11529.7 11621.7 11703.9 11753.5 11122.3 11251.8 11319.2 11402.3 11585.9 8777.3 10200.7 10208.9 10245.0 10249.3 6500.3 6652.3 6674.3 6766.7 6789.4 6283.0 6412.5 6427.2 6452.5 6632.0 03:48:52 INFO - PID 13759 | 03:48:52 INFO - PID 13759 | [#4] prototype.html Cycles:180 Average:5448.62 Median:6183.40 stddev:1524.44 (24.7%) stddev-sans-first:1482.87 03:48:52 INFO - PID 13759 | Values: 504.9 508.1 517.8 527.7 530.2 6250.5 6259.0 6262.8 6277.5 6296.9 6123.7 6158.6 6208.2 6219.0 6250.8 4769.3 4987.4 5132.4 5146.9 5167.2 4912.7 4992.6 5010.8 5027.1 5035.7 4915.3 4916.4 4936.6 4953.4 5006.1 4965.8 4972.8 4976.7 4988.2 4989.9 4470.0 4488.3 4538.7 4542.6 4544.0 6120.4 6147.9 6150.6 6217.4 6239.7 5612.2 5622.8 5646.4 5669.9 5685.4 6390.3 6428.7 6467.3 6498.2 6514.5 4546.4 4804.6 4812.1 4813.5 4849.1 6566.9 6617.8 6633.9 6710.5 6755.3 4879.3 4882.0 4897.6 4926.9 4932.3 4971.4 5016.7 5018.4 5024.2 5026.0 5587.0 5590.4 5602.9 5634.1 5654.9 6465.5 6507.9 6561.6 6591.6 6619.1 1706.4 1732.6 1740.1 1755.6 1762.4 1109.1 1160.4 1168.6 1184.9 1188.6 5609.3 5618.8 5642.4 5675.1 5687.9 6592.7 6609.7 6630.9 6699.6 6716.0 5570.2 5576.7 5597.6 5609.5 5659.6 3467.6 3502.6 3505.8 3527.2 3542.7 6504.7 6527.5 6527.7 6544.8 6591.9 6564.5 6656.0 6664.6 6691.8 6705.5 6481.3 6507.4 6518.6 6564.8 6577.9 6560.9 6575.9 6579.1 6591.8 6670.6 5931.3 6451.7 6473.3 6490.6 6530.4 6302.6 6323.2 6449.7 6450.6 6532.2 4825.5 4845.4 4879.6 4888.8 4916.5 6384.0 6479.7 6557.7 6594.8 6613.2 6281.7 6401.0 6490.7 6504.5 6540.4 6334.4 6451.4 6480.3 6486.7 6515.5 6310.9 6339.7 6344.9 6388.0 6445.5 6488.0 6501.4 6530.7 6549.7 6554.4 6248.9 6309.5 6312.3 6331.8 6335.8 03:48:52 INFO - PID 13759 | 03:48:52 INFO - PID 13759 | [#5] yui.html Cycles:180 Average:24505.08 Median:18254.91 stddev:35688.75 (195.5%) stddev-sans-first:35744.89 03:48:52 INFO - PID 13759 | Values: 909.5 910.5 928.4 934.6 934.9 18921.9 19713.7 19719.1 19736.3 19810.1 18048.5 18189.5 18230.8 18235.7 18274.1 953.4 980.0 983.3 983.4 983.7 968.8 975.8 977.8 978.5 982.5 985.9 1013.2 1023.5 1034.3 1037.3 1013.8 1017.3 1021.5 1021.5 1025.9 749.4 751.7 758.4 758.7 760.4 19409.1 19466.7 19555.3 20192.5 20245.9 21281.7 21304.8 21612.0 21796.1 22025.1 16917.3 16975.7 17179.3 17303.5 17361.3 3738.5 3791.7 3792.3 3793.0 3798.5 97692.5 113741.0 140733.3 141233.0 142884.0 16694.3 16717.9 16782.2 16987.2 17040.7 30144.5 30259.0 30760.7 31224.7 31403.0 8984.6 9002.7 9014.2 9023.7 9060.0 4120.1 4154.9 4157.8 4180.2 4240.4 5263.2 5316.1 5371.2 5390.6 5397.2 1066.0 1081.0 1109.0 1114.4 1114.7 672.7 683.6 687.8 690.4 701.8 11818.6 11908.6 11932.0 12605.5 12640.6 677.7 681.9 690.0 704.9 706.2 5451.0 5512.1 5518.9 5577.7 5638.5 126763.0 127489.0 128116.0 130701.0 131235.0 113548.0 114355.0 114974.0 116827.0 118593.4 98520.5 100397.5 100517.0 101854.0 102296.0 10442.1 10514.9 10662.8 10702.9 10729.5 40968.0 42777.8 43743.1 44305.6 44361.8 59256.7 59438.4 59850.7 60259.1 60369.4 15893.3 16084.3 16135.3 16175.3 16365.5 16419.5 16430.5 16490.6 16600.9 16646.4 15075.8 15097.2 15104.8 15153.4 15354.0 14759.7 14930.7 14958.6 14996.3 15127.3 16213.0 16414.0 16581.4 16588.7 16761.6 12360.4 12478.6 12535.5 12661.1 12808.0 11939.9 11948.4 12076.4 12182.9 12256.8 03:48:52 INFO - PID 13759 | -------- Summary: end -------- 03:48:52 INFO - PID 13759 | 03:48:53 INFO - TEST-INFO | 13759: exit 0 03:48:53 INFO - TEST-OK | dromaeo_css | took 1111706ms 03:48:53 INFO - TEST-START | kraken 03:48:53 INFO - Initialising browser for kraken test... 03:48:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38816/getInfo.html -profile /tmp/tmpYFrqTA/profile 03:48:53 INFO - TEST-INFO | started process 14145 (/builds/slave/test/build/application/firefox/firefox http://localhost:38816/getInfo.html) 03:48:58 INFO - TEST-INFO | 14145: exit 0 03:48:59 INFO - Browser initialized. 03:48:59 INFO - Running cycle 1/1 for kraken test... 03:48:59 INFO - Using env: {'DISPLAY': ':0', 03:48:59 INFO - 'HOME': '/home/cltbld', 03:48:59 INFO - 'JSGC_DISABLE_POISONING': '1', 03:48:59 INFO - 'LANG': 'en_US.UTF-8', 03:48:59 INFO - 'LANGUAGE': 'en_US:en', 03:48:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:48:59 INFO - 'LOGNAME': 'cltbld', 03:48:59 INFO - 'MAIL': '/var/mail/cltbld', 03:48:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:48:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:48:59 INFO - 'MOZ_ACCELERATED': '1', 03:48:59 INFO - 'MOZ_CRASHREPORTER': '1', 03:48:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:48:59 INFO - 'MOZ_NO_REMOTE': '1', 03:48:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:48:59 INFO - 'MOZ_WEBRENDER': '1', 03:48:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:59 INFO - 'NO_EM_RESTART': '1', 03:48:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:59 INFO - 'PWD': '/builds/slave/test', 03:48:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:48:59 INFO - 'RUST_BACKTRACE': 'full', 03:48:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:48:59 INFO - 'SHELL': '/bin/bash', 03:48:59 INFO - 'SHLVL': '1', 03:48:59 INFO - 'TERM': 'linux', 03:48:59 INFO - 'TMOUT': '86400', 03:48:59 INFO - 'USER': 'cltbld', 03:48:59 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051', 03:48:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:59 INFO - '_': '/tools/buildbot/bin/python'} 03:48:59 INFO - TEST-INFO | started process 14392 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYFrqTA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:48:59 INFO - PID 14392 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:49:00 INFO - PID 14392 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:49:00 INFO - PID 14392 | 03:49:00 INFO - PID 14392 | (/builds/slave/test/build/application/firefox/firefox:14452): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:49:00 INFO - PID 14392 | 03:49:10 INFO - PID 14392 | RSS: Main: 185827328 03:49:10 INFO - PID 14392 | 03:49:13 INFO - PID 14392 | Cycle 1(1): loaded http://localhost:38816/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38816/tests/kraken/driver.html?testName=audio-beat-detection) 03:49:14 INFO - PID 14392 | RSS: Main: 187297792 03:49:14 INFO - PID 14392 | 03:49:17 INFO - PID 14392 | Cycle 1(1): loaded http://localhost:38816/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38816/tests/kraken/driver.html?testName=audio-dft) 03:49:17 INFO - PID 14392 | RSS: Main: 187609088 03:49:17 INFO - PID 14392 | 03:49:20 INFO - PID 14392 | Cycle 1(1): loaded http://localhost:38816/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38816/tests/kraken/driver.html?testName=audio-fft) 03:49:21 INFO - PID 14392 | RSS: Main: 185024512 03:49:21 INFO - PID 14392 | 03:49:23 INFO - PID 14392 | Cycle 1(1): loaded http://localhost:38816/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38816/tests/kraken/driver.html?testName=audio-oscillator) 03:49:24 INFO - PID 14392 | RSS: Main: 184602624 03:49:24 INFO - PID 14392 | 03:49:26 INFO - PID 14392 | Cycle 1(1): loaded http://localhost:38816/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38816/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:49:26 INFO - PID 14392 | RSS: Main: 185204736 03:49:26 INFO - PID 14392 | 03:49:30 INFO - PID 14392 | Cycle 1(1): loaded http://localhost:38816/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38816/tests/kraken/driver.html?testName=imaging-darkroom) 03:49:30 INFO - PID 14392 | RSS: Main: 187617280 03:49:30 INFO - PID 14392 | 03:49:34 INFO - PID 14392 | Cycle 1(1): loaded http://localhost:38816/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38816/tests/kraken/driver.html?testName=imaging-desaturate) 03:49:35 INFO - PID 14392 | RSS: Main: 186089472 03:49:35 INFO - PID 14392 | 03:49:38 INFO - PID 14392 | Cycle 1(1): loaded http://localhost:38816/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38816/tests/kraken/driver.html?testName=json-parse-financial) 03:49:38 INFO - PID 14392 | RSS: Main: 187609088 03:49:38 INFO - PID 14392 | 03:49:40 INFO - PID 14392 | Cycle 1(1): loaded http://localhost:38816/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38816/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:49:40 INFO - PID 14392 | RSS: Main: 186908672 03:49:40 INFO - PID 14392 | 03:49:42 INFO - PID 14392 | Cycle 1(1): loaded http://localhost:38816/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38816/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:49:43 INFO - PID 14392 | RSS: Main: 184827904 03:49:43 INFO - PID 14392 | 03:49:45 INFO - PID 14392 | Cycle 1(1): loaded http://localhost:38816/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38816/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:49:46 INFO - PID 14392 | RSS: Main: 185196544 03:49:46 INFO - PID 14392 | 03:49:48 INFO - PID 14392 | Cycle 1(1): loaded http://localhost:38816/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38816/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:49:49 INFO - PID 14392 | RSS: Main: 185516032 03:49:49 INFO - PID 14392 | 03:49:52 INFO - PID 14392 | Cycle 1(1): loaded http://localhost:38816/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38816/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:49:52 INFO - PID 14392 | RSS: Main: 184774656 03:49:52 INFO - PID 14392 | 03:49:54 INFO - PID 14392 | Cycle 1(1): loaded http://localhost:38816/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38816/tests/kraken/driver.html?testName=ai-astar) 03:49:55 INFO - PID 14392 | RSS: Main: 186904576 03:49:55 INFO - PID 14392 | 03:49:55 INFO - PID 14392 | __start_tp_report 03:49:55 INFO - PID 14392 | _x_x_mozilla_page_load 03:49:55 INFO - PID 14392 | _x_x_mozilla_page_load_details 03:49:55 INFO - PID 14392 | |i|pagename|runs| 03:49:55 INFO - PID 14392 | |0;ai-astar;112;112;117;118;110;112;109;117;108;113 03:49:55 INFO - PID 14392 | |1;audio-beat-detection;123;127;118;117;121;126;117;123;118;125 03:49:55 INFO - PID 14392 | |2;audio-dft;171;167;166;165;163;172;170;165;165;166 03:49:55 INFO - PID 14392 | |3;audio-fft;82;74;74;74;75;84;75;74;74;75 03:49:55 INFO - PID 14392 | |4;audio-oscillator;83;83;84;86;83;81;85;81;85;88 03:49:55 INFO - PID 14392 | |5;imaging-gaussian-blur;115;121;114;114;116;119;111;117;112;116 03:49:55 INFO - PID 14392 | |6;imaging-darkroom;151;161;148;150;149;148;154;151;149;148 03:49:55 INFO - PID 14392 | |7;imaging-desaturate;103;95;95;96;95;95;98;94;98;98 03:49:55 INFO - PID 14392 | |8;json-parse-financial;60;57;57;56;55;56;54;57;55;55 03:49:55 INFO - PID 14392 | |9;json-stringify-tinderbox;43;43;41;37;44;45;37;44;37;38 03:49:55 INFO - PID 14392 | |10;stanford-crypto-aes;86;84;75;79;78;81;74;83;81;83 03:49:55 INFO - PID 14392 | |11;stanford-crypto-ccm;117;121;119;120;117;121;119;117;113;114 03:49:55 INFO - PID 14392 | |12;stanford-crypto-pbkdf2;172;173;164;166;169;169;172;170;170;171 03:49:55 INFO - PID 14392 | |13;stanford-crypto-sha256-iterative;70;70;78;73;66;63;66;66;66;64 03:49:55 INFO - PID 14392 | __end_tp_report 03:49:55 INFO - PID 14392 | __start_cc_report 03:49:55 INFO - PID 14392 | _x_x_mozilla_cycle_collect,767 03:49:55 INFO - PID 14392 | __end_cc_report 03:49:55 INFO - PID 14392 | __startTimestamp1505645395066__endTimestamp 03:49:55 INFO - PID 14392 | 03:49:55 INFO - PID 14392 | ------- Summary: start ------- 03:49:55 INFO - PID 14392 | Number of tests: 14 03:49:55 INFO - PID 14392 | 03:49:55 INFO - PID 14392 | [#0] ai-astar Cycles:10 Average:112.80 Median:112.50 stddev:3.49 (3.1%) stddev-sans-first:3.69 03:49:55 INFO - PID 14392 | Values: 112.0 112.0 117.0 118.0 110.0 112.0 109.0 117.0 108.0 113.0 03:49:55 INFO - PID 14392 | 03:49:55 INFO - PID 14392 | [#1] audio-beat-detection Cycles:10 Average:121.50 Median:123.00 stddev:3.84 (3.1%) stddev-sans-first:4.03 03:49:55 INFO - PID 14392 | Values: 123.0 127.0 118.0 117.0 121.0 126.0 117.0 123.0 118.0 125.0 03:49:55 INFO - PID 14392 | 03:49:55 INFO - PID 14392 | [#2] audio-dft Cycles:10 Average:167.00 Median:166.50 stddev:2.98 (1.8%) stddev-sans-first:2.79 03:49:55 INFO - PID 14392 | Values: 171.0 167.0 166.0 165.0 163.0 172.0 170.0 165.0 165.0 166.0 03:49:55 INFO - PID 14392 | 03:49:55 INFO - PID 14392 | [#3] audio-fft Cycles:10 Average:76.10 Median:75.00 stddev:3.70 (4.9%) stddev-sans-first:3.24 03:49:55 INFO - PID 14392 | Values: 82.0 74.0 74.0 74.0 75.0 84.0 75.0 74.0 74.0 75.0 03:49:55 INFO - PID 14392 | 03:49:55 INFO - PID 14392 | [#4] audio-oscillator Cycles:10 Average:83.90 Median:84.50 stddev:2.18 (2.6%) stddev-sans-first:2.29 03:49:55 INFO - PID 14392 | Values: 83.0 83.0 84.0 86.0 83.0 81.0 85.0 81.0 85.0 88.0 03:49:55 INFO - PID 14392 | 03:49:55 INFO - PID 14392 | [#5] imaging-gaussian-blur Cycles:10 Average:115.50 Median:116.00 stddev:3.03 (2.6%) stddev-sans-first:3.21 03:49:55 INFO - PID 14392 | Values: 115.0 121.0 114.0 114.0 116.0 119.0 111.0 117.0 112.0 116.0 03:49:55 INFO - PID 14392 | 03:49:55 INFO - PID 14392 | [#6] imaging-darkroom Cycles:10 Average:150.90 Median:150.50 stddev:4.01 (2.7%) stddev-sans-first:4.26 03:49:55 INFO - PID 14392 | Values: 151.0 161.0 148.0 150.0 149.0 148.0 154.0 151.0 149.0 148.0 03:49:55 INFO - PID 14392 | 03:49:55 INFO - PID 14392 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:95.50 stddev:2.67 (2.8%) stddev-sans-first:1.58 03:49:55 INFO - PID 14392 | Values: 103.0 95.0 95.0 96.0 95.0 95.0 98.0 94.0 98.0 98.0 03:49:55 INFO - PID 14392 | 03:49:55 INFO - PID 14392 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.50 stddev:1.69 (3.0%) stddev-sans-first:1.09 03:49:55 INFO - PID 14392 | Values: 60.0 57.0 57.0 56.0 55.0 56.0 54.0 57.0 55.0 55.0 03:49:55 INFO - PID 14392 | 03:49:55 INFO - PID 14392 | [#9] json-stringify-tinderbox Cycles:10 Average:40.90 Median:43.00 stddev:3.31 (7.7%) stddev-sans-first:3.43 03:49:55 INFO - PID 14392 | Values: 43.0 43.0 41.0 37.0 44.0 45.0 37.0 44.0 37.0 38.0 03:49:55 INFO - PID 14392 | 03:49:55 INFO - PID 14392 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:82.00 stddev:3.89 (4.7%) stddev-sans-first:3.56 03:49:55 INFO - PID 14392 | Values: 86.0 84.0 75.0 79.0 78.0 81.0 74.0 83.0 81.0 83.0 03:49:55 INFO - PID 14392 | 03:49:55 INFO - PID 14392 | [#11] stanford-crypto-ccm Cycles:10 Average:117.80 Median:119.00 stddev:2.74 (2.3%) stddev-sans-first:2.89 03:49:55 INFO - PID 14392 | Values: 117.0 121.0 119.0 120.0 117.0 121.0 119.0 117.0 113.0 114.0 03:49:55 INFO - PID 14392 | 03:49:55 INFO - PID 14392 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.60 Median:170.50 stddev:2.80 (1.6%) stddev-sans-first:2.83 03:49:55 INFO - PID 14392 | Values: 172.0 173.0 164.0 166.0 169.0 169.0 172.0 170.0 170.0 171.0 03:49:55 INFO - PID 14392 | 03:49:55 INFO - PID 14392 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.00 stddev:4.59 (6.7%) stddev-sans-first:4.82 03:49:55 INFO - PID 14392 | Values: 70.0 70.0 78.0 73.0 66.0 63.0 66.0 66.0 66.0 64.0 03:49:55 INFO - PID 14392 | -------- Summary: end -------- 03:49:55 INFO - PID 14392 | 03:49:55 INFO - PID 14392 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:49:55 INFO - TEST-INFO | 14392: exit 0 03:49:56 INFO - TEST-OK | kraken | took 62219ms 03:49:56 INFO - SUITE-END | took 1173s 03:49:56 INFO - Completed test suite (00:19:34) 03:49:56 INFO - javascript benchmark 03:49:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8402.224089480573, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3115.2523394385344, 3124.734397576206, 3131.3111115554584, 3138.2781960149164, 3172.4924012158053, 6034.8793024139495, 6212.844678883024, 6236.875262494753, 6239.4696450801675, 6312.652804095773, 6018.548608854342, 6127.325994140652, 6158.907616385746, 6189.566730328879, 6193.721282542285, 5656.123300888348, 5657.9434205658035, 5682.0, 5694.402087780785, 5786.594938354317, 5681.318241810988, 5740.024195886709, 5742.569307301948, 5775.653460792366, 5830.154627578999, 160.6958972413419, 5731.799387021455, 5769.221155144065, 5770.04794208955, 5791.0, 5544.22380866679, 5558.583106267046, 5592.049351610236, 5697.287839020122, 5761.654300741927, 5606.159076138571, 5659.292588426447, 5695.743691533891, 5716.685582292972, 5731.799387021434, 6130.877382452326, 6140.84647883802, 6144.41628045335, 6191.721372538202, 6209.254889413254, 6554.1151944487365, 6574.046763219354, 6619.768308109193, 6634.0712300278, 6676.399124078807, 6658.10115634388, 6696.19645642526, 6757.628330441824, 6777.49168812341, 6778.69495872682, 6086.330503644595, 6166.81499555014, 6172.043333283348, 6180.134781130645, 6196.411340922605, 6700.2294736105, 6705.362990515942, 6716.597004179765, 6732.697028633724, 6740.39336459721, 6409.4231519163495, 6433.485321174295, 6441.871162576722, 6498.805035848932, 6553.705083271264, 6632.900506492407, 6672.9332706673285, 6685.598864068172, 6694.832629184212, 6722.0, 6458.6770661466735, 6600.900986485207, 6610.669466526654, 6667.066610674509, 6693.364130407604, 6280.497560195265, 6502.837429064217, 6658.067870498134, 6721.831954201184, 6742.325767423316, 5144.0, 5169.586433085344, 5177.275181474596, 5179.093658609803, 5213.973930130401, 4576.290674945389, 4591.058832939239, 4591.47198072226, 4593.333966574828, 4633.374494443207, 5758.452946970031, 5851.590388672752, 5857.062869940874, 5870.618409803349, 5874.73563689635, 6623.238327592292, 6645.0, 6656.134702488743, 6736.93263067363, 6747.460203183831, 5579.9163012554845, 5612.859678507988, 5754.367019627837, 5877.206577112121, 5882.441168089024, 5553.08374118266, 5630.915536266959, 5652.547796176296, 5673.234113394721, 5675.3757086720425, 6307.306196318401, 6438.323975982548, 6452.935470645328, 6469.8706025880165, 6478.4169424752, 6614.503912206604, 6665.166854143232, 6665.966670166715, 6706.865862682816, 6729.0915726376315, 6443.194600674916, 6451.612903225821, 6496.447801936888, 6553.868922621617, 6595.571287866273, 6512.088307637029, 6537.836554086186, 6604.306547812603, 6628.767993120217, 6636.767713130017, 6513.609183449008, 6540.803775886731, 6591.6045037296, 6628.337166283333, 6635.767748128792, 6384.648844313608, 6453.064305675557, 6469.288378278292, 6478.805635830932, 6492.837679058061, 6417.518686098468, 6496.765614533224, 6547.312532184052, 6549.050387693853, 6555.4427873629365, 6236.002239641635, 6394.296627370893, 6397.840053998687, 6491.0, 6495.317991610813, 6400.615963042233, 6420.2937676856445, 6436.935630643821, 6468.967655161882, 6471.06169605414, 6182.783602573888, 6203.751849925952, 6230.034344676583, 6251.78118765841, 6280.434760871543], "value": 6004.015984789252, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [855.9144085591441, 870.3037569944047, 882.1311008656477, 887.1793590928394, 888.8533391990318, 37494.43758343627, 41622.37566436502, 41704.374434383506, 41778.58221417785, 41812.372814407805, 37124.62875371245, 37582.0, 37596.0, 37657.246855062884, 37673.0, 6973.965130174342, 7021.543599666005, 7058.964705176467, 7095.184053833824, 7137.036500072476, 6966.16406031277, 7017.333353331425, 7057.717691292342, 7070.717171313141, 7078.0, 6945.236024037352, 6977.092977912865, 6999.790006299819, 7000.28493019211, 7012.053372794684, 7006.649667516629, 7680.193579674137, 7861.724839630642, 7875.369970402354, 7938.563379014152, 7691.115521715018, 7775.688972441096, 7779.7666070017995, 7782.143964163937, 7795.064592248909, 37285.0, 37333.06667333311, 37528.24943501142, 37576.06059848498, 37851.43222851659, 3549.343371476285, 3568.536090308269, 3583.552055993001, 3586.28274345132, 3588.982055089708, 32041.0, 32366.676333236603, 32388.35223295521, 32433.675663243303, 32433.83783081093, 5161.122609156415, 5198.610104242159, 5218.478152184789, 5248.921266181011, 5295.152775555931, 183917.0, 185019.0, 185271.0, 185715.07142464202, 185888.0, 41319.79340103341, 41739.37390939138, 47669.76165119152, 47671.523284767405, 47842.5215747845, 49931.25103123456, 50060.74969625129, 50082.0, 50175.498245017814, 50187.74906125446, 15408.460703875311, 15651.37394504207, 15669.764953525706, 15670.921645391929, 15674.921625392028, 12546.623601291976, 12595.685107872376, 12649.05132115095, 12683.746325073447, 12729.809052864215, 14014.71970560583, 14052.859471405154, 14216.00487965853, 14296.356663949939, 14313.78529322061, 5749.338826034976, 5797.36229014808, 5799.3620701722775, 5853.88292234153, 5910.231669882888, 983.9065288797552, 993.7764003099389, 997.7505623594102, 1001.0740065439439, 1017.4099022566893, 22908.312750617508, 23036.0, 23038.65442018371, 23245.3026409208, 23304.883475582854, 1074.4573990134932, 1089.2865173311493, 1097.040089921319, 1110.2394859521253, 1111.9777604447866, 12020.110146028383, 12039.277643341427, 12156.209846359958, 12166.513339466323, 12192.268463892195, 216957.0, 218105.0, 218849.0, 218968.0, 219208.0, 51537.0, 51540.48459515357, 51797.223041654404, 51956.48043519516, 52036.0, 1435.0887186636562, 1467.7871708602406, 1474.027142086218, 1474.6460849396283, 1479.837217906008, 21967.450813729785, 22027.0, 22214.333569992927, 22699.886500568053, 22770.65844012374, 36302.273954520235, 36402.817985909904, 36541.45187822238, 36548.45177322289, 36651.26697466089, 181013.0, 181534.0, 181620.0, 181759.0, 183451.0, 29220.415591687623, 29409.264768380963, 30141.849290753406, 32579.0, 34071.65928340784, 41692.37461438141, 42273.788631057876, 42454.36318455287, 42586.78706606447, 43146.0, 30908.0, 31001.0, 31405.842970785914, 31633.0, 31728.682713172573, 30933.535996960512, 31223.0, 31275.374492510477, 31284.843575781975, 31618.0, 34738.65261347354, 34744.13139671528, 34797.652023480456, 35060.474092888115, 35108.12229694278, 30071.548926766554, 30389.088327350215, 30883.301418421303, 30912.845435773575, 31123.0, 21952.75496132882, 23032.309030729106, 23122.76877231206, 23569.17507887216, 23663.64504532468], "value": 16699.127951679104, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.0970751651115, 568.1335962656948, 571.6277448588361, 578.8350320158754, 590.4272855330329, 22256.109755609777, 22925.77074229257, 22951.08195672175, 22958.0, 22992.425189370253, 23652.526949461, 24194.274171774832, 24679.259622211317, 24729.629055564183, 24777.50444991099, 6609.372109649587, 6693.732250709966, 6716.966415167917, 6735.158105236846, 6737.0, 6648.16897887764, 6687.6990535425775, 6736.124303840494, 6740.427063699592, 6748.358905903931, 6372.171617689693, 6565.179352580927, 6610.8677826443445, 6637.701303441332, 6685.966570167142, 6570.901436478457, 6602.042703807945, 6602.1747281589805, 6606.570572912769, 6606.735730570771, 5930.851728706817, 6070.2715674118945, 6073.696315184223, 6091.969540152271, 6163.7842675506145, 27135.1859444217, 27631.861840690668, 27852.721472785215, 27867.86066069677, 28146.859265703744, 2004.899755012258, 2045.2739277556402, 2048.897555122253, 2052.558699879518, 2058.742657167854, 18674.34639787601, 19039.809601903944, 19375.12811923467, 19383.224671013144, 19412.708809367872, 2403.495265994143, 2451.521953219123, 2470.0, 2486.3286912533695, 2488.129154795825, 123800.38099809534, 128989.0, 129333.0, 133721.3313933434, 140430.0, 14093.71812563743, 14374.640633984025, 14443.422263109358, 14507.1295722257, 14527.332684026676, 22042.33872983813, 22383.104675812785, 22402.887985560294, 22406.775932240467, 22505.0, 9559.856602150974, 10041.146502547219, 10062.39625622465, 10194.082532572103, 10281.0, 9350.766230844147, 9392.8591071134, 9413.105754953269, 9421.38760980534, 9457.43255404678, 8301.46040507365, 8784.604692788713, 8999.235065019415, 9119.179273865251, 9141.908580914105, 2408.0, 2478.1202673050807, 2492.513959777862, 2514.0823599386094, 2519.533886231053, 932.8927173375149, 934.780326623243, 941.9011003844635, 957.4638202606424, 969.6170012844887, 18676.0, 18816.435506934813, 19125.90437047806, 19183.52041198981, 19209.03954802282, 991.9355241909253, 992.4243938515643, 1007.8286691262502, 1018.4245901066022, 1021.3004092196869, 10408.739781505523, 10702.785944281071, 10720.892791072145, 10742.355458672348, 10761.924262021681, 431091.0, 439071.0, 445524.0, 446937.0, 457262.0, 109864.4506777461, 109954.0, 112650.0, 114016.0, 119027.0, 1146.7018575170516, 1156.3004382348727, 1181.9054475641929, 1182.2847177457513, 1191.988080119205, 16806.57983550421, 16822.0, 17022.233999469805, 17089.91455042717, 17159.742603861203, 51147.23279150741, 52226.73886630542, 52723.736381317845, 53074.73462632662, 54259.45740542544, 68987.0, 69661.0, 69742.65128674325, 69945.65027174831, 71405.64297178481, 13811.930940345233, 13908.652283692989, 14119.858801412265, 14136.929315353358, 14221.360038798486, 21465.46336341604, 21661.783382166606, 21901.780982189975, 22058.44853878666, 22374.0, 15268.465603703817, 15407.383704652137, 15462.768058478907, 15559.76660350073, 17477.737833932755, 15244.466443674419, 15861.365545378056, 15944.0, 16140.515784526482, 16236.918815405847, 18006.189721462826, 18009.0, 18335.816641833946, 18358.81641183571, 18369.54076148107, 13736.65658358549, 13858.65353366174, 13892.93053534726, 13921.303934803422, 14044.508442204473, 16445.91777041107, 17378.82621173772, 17449.0, 17516.38692645751, 17541.210645521238], "value": 11607.760741467104, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1354.8374195096587, 1409.9718005639888, 1416.6600015996157, 1417.5959851442342, 1419.5457453614847, 14787.260636968149, 15514.30185641646, 15676.764848527282, 15678.0, 15690.293936772858, 15166.0, 15352.846471535282, 15389.307481163347, 15426.84573154268, 15428.922855385706, 2735.343517555792, 2760.9585856212225, 2794.091920125957, 2803.1590522843167, 2822.2379957411486, 2533.961990570134, 2604.583266677332, 2615.829971051885, 2616.5944278636844, 2616.7121616622253, 2656.747608977144, 2682.4903268378944, 2731.3308239481257, 2767.4465106978582, 2776.2642899631614, 2722.537168681329, 2761.737634924679, 2764.25365151409, 2766.2669392610974, 2771.113243761997, 2692.7980401469968, 2715.91852244433, 2740.8355498670144, 2747.3131717070733, 2750.8074434789373, 14312.499062532865, 14667.92666036663, 14692.926535367362, 14740.0, 14767.70464590713, 7872.803179920491, 8140.674373025072, 8143.674253029931, 8212.137725538792, 8226.547539885303, 5167.3282473278605, 5336.546393556552, 5393.514583687448, 5415.566754659618, 5466.0, 1897.8671492995495, 1926.2776458827939, 1932.7777305609754, 1955.3449594385897, 1992.392320342295, 27060.729392706016, 27468.313292167655, 27518.312042198904, 27598.172054838386, 27753.722462775317, 7012.9298707013295, 7024.0, 7145.285471452917, 7259.6007219603425, 7274.854502909912, 20188.09153588092, 20209.090590923443, 20230.89884550568, 20297.797022029885, 20367.59264814725, 7931.801704957307, 8214.301784348278, 8240.958795205986, 8285.171482851667, 8291.295239904555, 7082.787516374517, 7215.278472152848, 7275.854482910418, 7303.488755787044, 7397.926020739831, 5510.8071217507995, 5736.627119237236, 5741.913871291934, 5745.0, 5781.4218578141845, 1767.6729804986119, 1793.9641207176044, 1796.1827368547279, 1810.5383127302716, 1844.087176847469, 717.7739012211136, 870.0, 875.6978842299396, 886.8448021596195, 898.3397203055749, 8026.759197223976, 8249.133840946733, 8249.711260105867, 8410.0, 8495.617697203517, 965.2037069417759, 966.2221911361281, 978.1636700621009, 999.6201443451442, 1004.1213937804421, 4843.273508973612, 5000.399952005782, 5006.123928312531, 5063.240513922941, 5332.2801421808335, 20519.38441846747, 21153.89423052875, 21650.6752398711, 22000.229991950524, 22044.0, 20908.37274881756, 21288.0, 21577.892110539662, 21750.0, 21960.560788784136, 10835.83746243807, 10903.563857445613, 11044.171687123498, 11152.553897844158, 11162.776744465065, 7767.811391529858, 7912.4856884302335, 7961.960190199012, 8025.03699556057, 8092.595370231583, 18213.362532311305, 18719.71920421222, 18936.526586834887, 19029.14368853377, 19389.612207756047, 18956.620867582846, 19135.52161195981, 19168.61662766709, 19328.0, 19386.709199362303, 11352.489137988645, 11400.828987565028, 11484.598039068593, 11493.942530287295, 11564.768704626029, 13703.040787145203, 13993.790093148407, 14081.718365632834, 14118.929405352908, 14120.223387713775, 11212.383318917538, 11529.654110376701, 11621.709457263636, 11703.946644802008, 11753.529858805552, 11122.332660040423, 11251.831222531504, 11319.151063670093, 11402.258853174517, 11585.88414115848, 8777.341699372444, 10200.693979180636, 10208.897911020795, 10245.0, 10249.282550221413, 6500.349965003462, 6652.301508341555, 6674.299198584079, 6766.729330826909, 6789.388954994074, 6283.0, 6412.519061070345, 6427.196600424947, 6452.548321617442, 6632.0], "value": 6741.373403023384, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.9043575441291, 508.08291034682384, 517.8420581722573, 527.6860268140457, 530.2126342381565, 6250.531210159239, 6258.9999999999945, 6262.780802671908, 6277.497800175975, 6296.8980428425075, 6123.724432400541, 6158.59969102008, 6208.192934918454, 6219.0, 6250.84372890678, 4769.308450274725, 4987.351644286256, 5132.358455193101, 5146.8713282168055, 5167.199084141946, 4912.7297998610065, 4992.625553083533, 5010.799568017275, 5027.09512287788, 5035.748212589374, 4915.262710593422, 4916.410030796309, 4936.629752768539, 4953.405591329028, 5006.098902197621, 4965.801367945278, 4972.776225069844, 4976.651634385595, 4988.152014157602, 4989.850304490835, 4469.977650111761, 4488.304312831517, 4538.7276763393975, 4542.613877820356, 4543.954560454419, 6120.44915957566, 6147.938520614826, 6150.600210986271, 6217.409346112157, 6239.719212635397, 5612.15817627355, 5622.775088996435, 5646.419002679257, 5669.886602267973, 5685.403032681592, 6390.329015453403, 6428.742850285983, 6467.288598254188, 6498.220213574401, 6514.469099761553, 4546.363509108727, 4804.639652026112, 4812.133815913135, 4813.518648135158, 4849.102915960559, 6566.901496477557, 6617.8014659560295, 6633.90049149268, 6710.530262881649, 6755.324467553205, 4879.26810978349, 4881.9511804881495, 4897.5592196701755, 4926.8768280793265, 4932.26016097581, 4971.4282857471135, 5016.685650340704, 5018.397792264952, 5024.221245706921, 5025.974870125626, 5586.9607593228175, 5590.440955904377, 5602.915956260578, 5634.126710359901, 5654.886902261932, 6465.4827613790785, 6507.8698426031215, 6561.606303621798, 6591.604503729792, 6619.106420633249, 1706.436875830973, 1732.5668582854287, 1740.1125426032816, 1755.5611097225694, 1762.4007837335369, 1109.1293334732359, 1160.3733983648904, 1168.5501082083458, 1184.8874356936078, 1188.60932708731, 5609.298837645294, 5618.831435056954, 5642.435756424325, 5675.148727690879, 5687.914681279784, 6592.692557604896, 6609.653572981619, 6630.9336906630315, 6699.631520266432, 6715.966420167868, 5570.248016517799, 5576.665400076008, 5597.608167428322, 5609.467100625516, 5659.603827732101, 3467.6358982306997, 3502.6322236165347, 3505.8071806050402, 3527.2063785648024, 3542.6811586956783, 6504.674766261668, 6527.54307198501, 6527.67361631926, 6544.803655890331, 6591.868162636721, 6564.457352522153, 6655.966720166368, 6664.566803157779, 6691.76578819739, 6705.463562914955, 6481.254655714559, 6507.381798729113, 6518.641474718936, 6564.80305590833, 6577.914644083673, 6560.868782624416, 6575.868482630225, 6579.144711187515, 6591.781259993512, 6670.633115178712, 5931.347551769388, 6451.70967306463, 6473.320301368477, 6490.578112422678, 6530.379613936669, 6302.590331628429, 6323.241211054703, 6449.677516124269, 6450.612963222222, 6532.150820393318, 4825.469198388245, 4845.370101886732, 4879.609631229422, 4888.826681596463, 4916.532929371704, 6384.0, 6479.708413121515, 6557.737580534708, 6594.8351291218105, 6613.206415230203, 6281.748730050929, 6400.967995160181, 6490.707918143789, 6504.512161587805, 6540.411362977355, 6334.4028948131045, 6451.3548645135115, 6480.25477070143, 6486.740530378732, 6515.54391192612, 6310.873782524415, 6339.714712837841, 6344.873102538016, 6387.968060159669, 6445.452136568257, 6487.967560162169, 6501.414872661483, 6530.706118224786, 6549.7380104795275, 6554.410103090745, 6248.9062664059165, 6309.49524038085, 6312.305646378986, 6331.820998141205, 6335.809925702236], "value": 4998.778366209392, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [909.531591230516, 910.5219759626196, 928.3594319919255, 934.6168071090854, 934.8924873639531, 18921.905390473046, 19713.704294435596, 19719.1126399312, 19736.309229176964, 19810.10854511547, 18048.548786280317, 18189.545261368472, 18230.81769182308, 18235.72646410305, 18274.086295685196, 953.3850666320202, 979.990200097997, 983.2871168402886, 983.3509883476906, 983.650803964593, 968.7578105473632, 975.790205105902, 977.8484334928097, 978.5156347607945, 982.4940155819759, 985.9063388978036, 1013.2147585621129, 1023.4677967456918, 1034.3070143004168, 1037.2687255484902, 1013.8175128476871, 1017.2726500552141, 1021.4637315409433, 1021.4739409204266, 1025.9281850270484, 749.3967356278193, 751.6617522115071, 758.4349659503705, 758.6851456645486, 760.4144808497481, 19409.126589303374, 19466.70799938002, 19555.315563955337, 20192.49518762028, 20245.8987705062, 21281.68077478839, 21304.786952130435, 21612.0, 21796.128154873946, 22025.118995240173, 16917.323307067825, 16975.66048679032, 17179.312827486887, 17303.480895573153, 17361.305547778073, 3738.532683414573, 3791.6777073948747, 3792.317382871082, 3792.9620703792884, 3798.525184351956, 97692.51153744257, 113741.0, 140733.29633351768, 141233.0, 142884.0, 16694.332226711038, 16717.91641041787, 16782.160891955355, 16987.150642467826, 17040.744388834177, 30144.54783178254, 30259.0, 30760.69239307623, 31224.68775312263, 31403.0, 8984.550772461482, 9002.729918102468, 9014.23379012778, 9023.729288121367, 9060.0, 4120.052387950788, 4154.878182890603, 4157.8336866525, 4180.226658068267, 4240.448741663626, 5263.184206448007, 5316.0962636351915, 5371.221172929982, 5390.649607775482, 5397.163439666858, 1065.9553637435356, 1080.9729756755987, 1108.9778204435866, 1114.4483480676954, 1114.6934592986993, 672.7342699633715, 683.5898460923406, 687.7970998555438, 690.3786592067079, 701.8210356359095, 11818.586349477899, 11908.64274071762, 11932.0, 12605.49578016869, 12640.620781376574, 677.6577828196729, 681.924988251292, 689.9896501552481, 704.9048378468841, 706.2372637551432, 5450.972745136249, 5512.145617429305, 5518.944810551843, 5577.6932268724795, 5638.464345887216, 126763.0, 127489.0, 128116.0, 130701.03948440781, 131235.0, 113548.0, 114355.0, 114974.0, 116827.0, 118593.407032966, 98520.53698657734, 100397.49801250947, 100517.0, 101854.0, 102296.0, 10442.112420444044, 10514.947425262824, 10662.84005739899, 10702.89297107019, 10729.463526823783, 40968.0, 42777.78611106925, 43743.12513749771, 44305.55694443014, 44361.77819110884, 59256.70371648114, 59438.40561594329, 59850.70074649599, 60259.09611355921, 60369.396306038136, 15893.284802183698, 16084.276207570922, 16135.273912673723, 16175.27211275472, 16365.509034728977, 16419.507414777574, 16430.507084787474, 16490.58773530671, 16600.916995414944, 16646.417375391804, 15075.849241507884, 15097.245137743288, 15104.848951510783, 15153.393864245747, 15354.0, 14759.704805904037, 14930.701385972437, 14958.626034349229, 14996.2501874908, 15127.319270633068, 16213.0, 16414.0, 16581.419650312182, 16588.66822663564, 16761.580960476087, 12360.44378002974, 12478.625641230778, 12535.498580056696, 12661.113722039368, 12808.039397045073, 11939.880601193878, 11948.402579870797, 12076.396180190779, 12182.878171218528, 12256.816147757612], "value": 8971.06249077927, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1457.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 117.0, 118.0, 110.0, 112.0, 109.0, 117.0, 108.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 127.0, 118.0, 117.0, 121.0, 126.0, 117.0, 123.0, 118.0, 125.0], "value": 121.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 167.0, 166.0, 165.0, 163.0, 172.0, 170.0, 165.0, 165.0, 166.0], "value": 167.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 74.0, 74.0, 74.0, 75.0, 84.0, 75.0, 74.0, 74.0, 75.0], "value": 76.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 84.0, 86.0, 83.0, 81.0, 85.0, 81.0, 85.0, 88.0], "value": 83.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 121.0, 114.0, 114.0, 116.0, 119.0, 111.0, 117.0, 112.0, 116.0], "value": 115.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 161.0, 148.0, 150.0, 149.0, 148.0, 154.0, 151.0, 149.0, 148.0], "value": 150.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 95.0, 95.0, 96.0, 95.0, 95.0, 98.0, 94.0, 98.0, 98.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 57.0, 57.0, 56.0, 55.0, 56.0, 54.0, 57.0, 55.0, 55.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 43.0, 41.0, 37.0, 44.0, 45.0, 37.0, 44.0, 37.0, 38.0], "value": 40.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 75.0, 79.0, 78.0, 81.0, 74.0, 83.0, 81.0, 83.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 121.0, 119.0, 120.0, 117.0, 121.0, 119.0, 117.0, 113.0, 114.0], "value": 117.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 164.0, 166.0, 169.0, 169.0, 172.0, 170.0, 170.0, 171.0], "value": 169.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 78.0, 73.0, 66.0, 63.0, 66.0, 66.0, 66.0, 64.0], "value": 68.2, "unit": "score"}]}]} 03:49:56 INFO - Return code: 0 03:49:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:49:56 INFO - # TBPL SUCCESS # 03:49:56 INFO - Running post-action listener: _package_coverage_data 03:49:56 INFO - Running post-action listener: _resource_record_post_action 03:49:56 INFO - [mozharness: 2017-09-17 10:49:56.354830Z] Finished run-tests step (success) 03:49:56 INFO - Running post-run listener: _resource_record_post_run 03:49:56 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 6492160; Write bytes: 343883776; Read time: 692; Write time: 266220 03:49:56 INFO - TinderboxPrint: CPU usage
13.3% 03:49:56 INFO - TinderboxPrint: I/O read bytes / time
6,492,160 / 692 03:49:56 INFO - TinderboxPrint: I/O write bytes / time
343,883,776 / 266,220 03:49:56 INFO - TinderboxPrint: CPU idle
8,237.6 (86.7%) 03:49:56 INFO - TinderboxPrint: CPU user
1,202.6 (12.7%) 03:49:56 INFO - TinderboxPrint: Swap in / out
0 / 0 03:49:56 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10072064; Read time: 0; Write time: 1252 03:49:56 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:49:56 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 6488064; Write bytes: 333811712; Read time: 680; Write time: 264968 03:49:57 INFO - Running post-run listener: _upload_blobber_files 03:49:57 INFO - Blob upload gear active. 03:49:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:49:57 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:49:57 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:49:57 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:49:57 INFO - (blobuploader) - INFO - Open directory for files ... 03:49:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:49:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:49:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:58 INFO - (blobuploader) - INFO - Done attempting. 03:49:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:49:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:50:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:50:05 INFO - (blobuploader) - INFO - Done attempting. 03:50:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:50:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:50:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:05 INFO - (blobuploader) - CRITICAL - Something went wrong on blobber! 03:50:05 INFO - (blobuploader) - INFO - Upload failed. Trying again ... 03:50:05 INFO - (blobuploader) - INFO - Done attempting. 03:50:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:50:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:50:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:05 INFO - (blobuploader) - CRITICAL - Something went wrong on blobber! 03:50:05 INFO - (blobuploader) - INFO - Upload failed. Trying again ... 03:50:05 INFO - (blobuploader) - INFO - Done attempting. 03:50:05 INFO - (blobuploader) - INFO - Iteration through files over. 03:50:05 INFO - Return code: 0 03:50:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:50:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:50:05 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": null, "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c1d4f5bda7b6d86141317706a65c9c1b99adcfe53d37544eee3bc2524839858192f29073fdcab6959fd326039e39e2224b1ae158cb28a46e2caea1f3163cd49", "dromaeojs-e10s_errorsummary.log": null, "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a0d2e20023d52f981b029cf65707181e1962757a78baa1ca57600e01bfe166a12bd261ace5393781575205c98127144e7b4fda79d6b7e58b8b56e164094ad34"} 03:50:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:50:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:50:05 INFO - Contents: 03:50:06 INFO - blobber_files:{"perfherder-data.json": null, "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c1d4f5bda7b6d86141317706a65c9c1b99adcfe53d37544eee3bc2524839858192f29073fdcab6959fd326039e39e2224b1ae158cb28a46e2caea1f3163cd49", "dromaeojs-e10s_errorsummary.log": null, "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a0d2e20023d52f981b029cf65707181e1962757a78baa1ca57600e01bfe166a12bd261ace5393781575205c98127144e7b4fda79d6b7e58b8b56e164094ad34"} 03:50:06 INFO - Running post-run listener: copy_logs_to_upload_dir 03:50:06 INFO - Copying logs to upload dir... 03:50:06 INFO - mkdir: /builds/slave/test/build/upload/logs 03:50:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.557235 ========= master_lag: 0.65 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-17 03:50:06.491529) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-17 03:50:06.498370) ========= 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=9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": null, "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c1d4f5bda7b6d86141317706a65c9c1b99adcfe53d37544eee3bc2524839858192f29073fdcab6959fd326039e39e2224b1ae158cb28a46e2caea1f3163cd49", "dromaeojs-e10s_errorsummary.log": null, "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a0d2e20023d52f981b029cf65707181e1962757a78baa1ca57600e01bfe166a12bd261ace5393781575205c98127144e7b4fda79d6b7e58b8b56e164094ad34"} build_url:https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.009901 build_url: 'https://queue.taskcluster.net/v1/task/OSv4zTzPSF2AmqFHfdlHSQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": null, "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c1d4f5bda7b6d86141317706a65c9c1b99adcfe53d37544eee3bc2524839858192f29073fdcab6959fd326039e39e2224b1ae158cb28a46e2caea1f3163cd49", "dromaeojs-e10s_errorsummary.log": null, "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a0d2e20023d52f981b029cf65707181e1962757a78baa1ca57600e01bfe166a12bd261ace5393781575205c98127144e7b4fda79d6b7e58b8b56e164094ad34"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-17 03:50:06.530072) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 03:50:06.530404) ========= 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=9f7544b6a75ada7b6b576032000001a7-1505642388.305678-1950866051 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004620 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 03:50:06.586963) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-17 03:50:06.587339) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-17 03:50:06.587737) ========= ========= Total master_lag: 0.79 =========