builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-005 starttime: 1506018823.68 results: success (0) revision: ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:43.677435) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:43.677887) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:43.679898) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:43.717056) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:43.717322) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-21 11:33:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 32.0M=0s 2017-09-21 11:33:43 (32.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.189159 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:43.930684) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:43.931381) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018564 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:43.992807) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2017-09-21 11:33:43.993131) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2 --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 ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2 --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=7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-21 11:33:44,046 truncating revision to first 12 chars 2017-09-21 11:33:44,046 Setting DEBUG logging. 2017-09-21 11:33:44,046 attempt 1/10 2017-09-21 11:33:44,046 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ca7d18dbacbf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-21 11:33:44,846 attempt 1/10 2017-09-21 11:33:45,546 current task status: no status available at this point. state: STARTED 2017-09-21 11:33:45,546 sleeping for 11.00s (attempt 1/10) 2017-09-21 11:33:56,558 attempt 2/10 2017-09-21 11:33:57,066 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-21 11:33:57,247 unpacking tar archive at: mozilla-central-ca7d18dbacbf/testing/mozharness/ program finished with exit code 0 elapsedTime=13.485465 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2017-09-21 11:33:57.495868) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:57.496196) ========= script_repo_revision: ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:57.496613) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:57.496889) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:57.511289) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-21 11:33:57.511679) ========= /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=7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:33:57 INFO - MultiFileLogger online at 20170921 11:33:57 in /builds/slave/test 11:33:57 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 11:33:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:33:57 INFO - {'append_to_log': False, 11:33:57 INFO - 'base_work_dir': '/builds/slave/test', 11:33:57 INFO - 'blob_upload_branch': 'Firefox', 11:33:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:33:57 INFO - 'branch': 'Firefox', 11:33:57 INFO - 'buildbot_json_path': 'buildprops.json', 11:33:57 INFO - 'code_coverage': False, 11:33:57 INFO - 'config_files': ('talos/linux_config.py',), 11:33:57 INFO - 'default_actions': ('clobber', 11:33:57 INFO - 'read-buildbot-config', 11:33:57 INFO - 'download-and-extract', 11:33:57 INFO - 'populate-webroot', 11:33:57 INFO - 'create-virtualenv', 11:33:57 INFO - 'install', 11:33:57 INFO - 'setup-mitmproxy', 11:33:57 INFO - 'run-tests'), 11:33:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:33:57 INFO - 'disable_ccov_upload': False, 11:33:57 INFO - 'disable_stylo': False, 11:33:57 INFO - 'download_minidump_stackwalk': True, 11:33:57 INFO - 'download_symbols': 'ondemand', 11:33:57 INFO - 'enable_stylo': False, 11:33:57 INFO - 'enable_webrender': False, 11:33:57 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:33:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:33:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:33:57 INFO - 'gecko_profile': False, 11:33:57 INFO - 'gecko_profile_interval': 0, 11:33:57 INFO - 'installer_path': 'installer.exe', 11:33:57 INFO - 'log_level': 'info', 11:33:57 INFO - 'log_name': 'talos', 11:33:57 INFO - 'log_to_console': True, 11:33:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:33:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:33:57 INFO - 'opt_config_files': (), 11:33:57 INFO - 'pip_index': False, 11:33:57 INFO - 'suite': 'dromaeojs-e10s', 11:33:57 INFO - 'system_bits': '32', 11:33:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:33:57 INFO - 'title': 'talos-linux64-ix-005', 11:33:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:33:57 INFO - 'use_talos_json': True, 11:33:57 INFO - 'verify': 'False', 11:33:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:33:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:33:57 INFO - 'work_dir': 'build'} 11:33:57 INFO - [mozharness: 2017-09-21 18:33:57.705284Z] Running clobber step. 11:33:57 INFO - Running pre-action listener: _resource_record_pre_action 11:33:57 INFO - Running main action method: clobber 11:33:57 INFO - rmtree: /builds/slave/test/build 11:33:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:33:58 INFO - Running post-action listener: _resource_record_post_action 11:33:58 INFO - [mozharness: 2017-09-21 18:33:58.703990Z] Finished clobber step (success) 11:33:58 INFO - [mozharness: 2017-09-21 18:33:58.704089Z] Running read-buildbot-config step. 11:33:58 INFO - Running pre-action listener: _resource_record_pre_action 11:33:58 INFO - Running main action method: read_buildbot_config 11:33:58 INFO - Using buildbot properties: 11:33:58 INFO - { 11:33:58 INFO - "project": "", 11:33:58 INFO - "product": "firefox", 11:33:58 INFO - "who": "ffxbld@noreply.mozilla.org", 11:33:58 INFO - "installer_path": "public/build/target.tar.bz2", 11:33:58 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 11:33:58 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:33:58 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 11:33:58 INFO - "stage_platform": "linux64-qr", 11:33:58 INFO - "basedir": "/builds/slave/test", 11:33:58 INFO - "buildnumber": 70, 11:33:58 INFO - "platform": "ubuntu64_hw_qr", 11:33:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:33:58 INFO - "slavebuilddir": "test", 11:33:58 INFO - "taskId": "NOsS3QVnSqGVbIDOLErfag", 11:33:58 INFO - "branch": "mozilla-central", 11:33:58 INFO - "script_repo_revision": "production", 11:33:58 INFO - "revision": "ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2", 11:33:58 INFO - "slavename": "talos-linux64-ix-005", 11:33:58 INFO - "repo_path": "mozilla-central" 11:33:58 INFO - } 11:33:58 INFO - Finding installer, test and symbols from parent task. 11:33:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NOsS3QVnSqGVbIDOLErfag'}, attempt #1 11:33:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NOsS3QVnSqGVbIDOLErfag'}, attempt #1 11:33:59 INFO - Task dependencies: TWoC3Z28QNeZ_g1kUharhQ 11:33:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ'}, attempt #1 11:34:00 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.tar.bz2 11:34:00 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.test_packages.json 11:34:00 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.crashreporter-symbols.zip 11:34:00 INFO - Running post-action listener: _resource_record_post_action 11:34:00 INFO - [mozharness: 2017-09-21 18:34:00.136899Z] Finished read-buildbot-config step (success) 11:34:00 INFO - [mozharness: 2017-09-21 18:34:00.137118Z] Running download-and-extract step. 11:34:00 INFO - Running pre-action listener: _resource_record_pre_action 11:34:00 INFO - Running main action method: download_and_extract 11:34:00 INFO - mkdir: /builds/slave/test/build/tests 11:34: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')]} 11:34:00 INFO - trying https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.test_packages.json 11:34:00 INFO - Downloading https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:34:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:34:00 INFO - Downloaded 989 bytes. 11:34:00 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:34:00 INFO - Using the following test package requirements: 11:34:00 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:34:00 INFO - u'common': [u'target.common.tests.zip'], 11:34:00 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:34:00 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:34:00 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:34:00 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:34:00 INFO - u'mozbase': [u'target.common.tests.zip'], 11:34:00 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:34:00 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:34:00 INFO - u'web-platform': [u'target.common.tests.zip', 11:34:00 INFO - u'target.web-platform.tests.tar.gz'], 11:34:00 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:34:00 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 11:34:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.common.tests.zip 11:34:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:34:00 INFO - Fetch https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.common.tests.zip into memory 11:34:01 INFO - Content-Length response header: 54741039 11:34:01 INFO - Bytes received: 54741039 11:34:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.talos.tests.zip 11:34:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:34:12 INFO - Fetch https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.talos.tests.zip into memory 11:34:13 INFO - Content-Length response header: 14509184 11:34:13 INFO - Bytes received: 14509184 11:34:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:13 INFO - trying https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.tar.bz2 11:34:13 INFO - Downloading https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:34:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:34:14 INFO - Downloaded 61492348 bytes. 11:34:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.tar.bz2 11:34:14 INFO - mkdir: /builds/slave/test/properties 11:34:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:34:14 INFO - Writing to file /builds/slave/test/properties/build_url 11:34:14 INFO - Contents: 11:34:14 INFO - build_url:https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.tar.bz2 11:34:14 INFO - Running post-action listener: _resource_record_post_action 11:34:14 INFO - Running post-action listener: find_tests_for_verification 11:34:14 INFO - Running post-action listener: set_extra_try_arguments 11:34:14 INFO - [mozharness: 2017-09-21 18:34:14.676065Z] Finished download-and-extract step (success) 11:34:14 INFO - [mozharness: 2017-09-21 18:34:14.676163Z] Running populate-webroot step. 11:34:14 INFO - Running pre-action listener: _resource_record_pre_action 11:34:14 INFO - Running main action method: populate_webroot 11:34:14 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:34:14 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:34:14 INFO - 'tests': ['tresize', 11:34:14 INFO - 'tcanvasmark']}, 11:34:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:34:14 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:34:14 INFO - 'tests': ['dromaeo_css', 11:34:14 INFO - 'kraken']}, 11:34:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:34:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:34:14 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:34:14 INFO - 'talos_options': ['--disable-stylo'], 11:34:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:34:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:34:14 INFO - 'tests': ['damp', 'tps']}, 11:34:14 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:34:14 INFO - 'talos_options': ['--disable-stylo'], 11:34:14 INFO - 'tests': ['damp', 'tps']}, 11:34:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:34:14 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:34:14 INFO - 'tests': ['dromaeo_dom']}, 11:34:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:34:14 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:34:14 INFO - 'tests': ['basic_compositor_video', 11:34:14 INFO - 'glvideo']}, 11:34:14 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:34:14 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:34:14 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:34:14 INFO - 'talos_options': ['--disable-stylo'], 11:34:14 INFO - 'tests': ['ts_paint_webext', 11:34:14 INFO - 'tp5o_webext']}, 11:34:14 INFO - 'other-e10s': {'tests': ['a11yr', 11:34:14 INFO - 'ts_paint', 11:34:14 INFO - 'tpaint', 11:34:14 INFO - 'sessionrestore', 11:34:14 INFO - 'sessionrestore_many_windows', 11:34:14 INFO - 'sessionrestore_no_auto_restore', 11:34:14 INFO - 'tabpaint']}, 11:34:14 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:34:14 INFO - 'tests': ['a11yr', 11:34:14 INFO - 'ts_paint', 11:34:14 INFO - 'tpaint', 11:34:14 INFO - 'sessionrestore', 11:34:14 INFO - 'sessionrestore_many_windows', 11:34:14 INFO - 'sessionrestore_no_auto_restore', 11:34:14 INFO - 'tabpaint']}, 11:34:14 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:34:14 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 11:34:14 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:34:14 INFO - 'tests': ['perf_reftest_singletons']}, 11:34:14 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:34:14 INFO - 'tests': ['bloom_basic']}, 11:34:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:34:14 INFO - 'tsvgr_opacity', 11:34:14 INFO - 'tart', 11:34:14 INFO - 'tscrollx', 11:34:14 INFO - 'tsvg_static']}, 11:34:14 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:34:14 INFO - 'tests': ['tsvgx', 11:34:14 INFO - 'tsvgr_opacity', 11:34:14 INFO - 'tart', 11:34:14 INFO - 'tscrollx', 11:34:14 INFO - 'tsvg_static']}, 11:34:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:34:14 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:34:14 INFO - 'talos_options': ['--disable-stylo'], 11:34:14 INFO - 'tests': ['tp5o']}, 11:34:14 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:34:14 INFO - 'talos_options': ['--mitmproxy', 11:34:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:34:14 INFO - '--firstNonBlankPaint'], 11:34:14 INFO - 'tests': ['tp6_google', 11:34:14 INFO - 'tp6_youtube', 11:34:14 INFO - 'tp6_amazon', 11:34:14 INFO - 'tp6_facebook']}, 11:34:14 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:34:14 INFO - 'talos_options': ['--disable-stylo', 11:34:14 INFO - '--mitmproxy', 11:34:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:34:14 INFO - '--firstNonBlankPaint'], 11:34:14 INFO - 'tests': ['tp6_google', 11:34:14 INFO - 'tp6_youtube', 11:34:14 INFO - 'tp6_amazon', 11:34:14 INFO - 'tp6_facebook']}, 11:34:14 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:34:14 INFO - 'talos_options': ['--stylo-threads=1', 11:34:14 INFO - '--mitmproxy', 11:34:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:34:14 INFO - '--firstNonBlankPaint'], 11:34:14 INFO - 'tests': ['tp6_google', 11:34:14 INFO - 'tp6_youtube', 11:34:14 INFO - 'tp6_amazon', 11:34:14 INFO - 'tp6_facebook']}, 11:34:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:34:14 INFO - 'talos_options': ['--xperf_path', 11:34:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:34:14 INFO - 'tests': ['tp5n']}, 11:34:14 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:34:14 INFO - 'talos_options': ['--disable-stylo', 11:34:14 INFO - '--xperf_path', 11:34:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:34:14 INFO - 'tests': ['tp5n']}}} 11:34:14 INFO - Running post-action listener: _resource_record_post_action 11:34:14 INFO - [mozharness: 2017-09-21 18:34:14.691140Z] Finished populate-webroot step (success) 11:34:14 INFO - [mozharness: 2017-09-21 18:34:14.691229Z] Running create-virtualenv step. 11:34:14 INFO - Running pre-action listener: _resource_record_pre_action 11:34:14 INFO - Running main action method: create_virtualenv 11:34:14 INFO - Creating virtualenv /builds/slave/test/build/venv 11:34:14 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:34:14 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:34:14 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:34:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:34:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:34:15 INFO - Installing distribute.............................................................................................................................................................................................done. 11:34:15 INFO - Installing pip...............done. 11:34:15 INFO - Return code: 0 11:34:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:34:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:34:15 INFO - Reading from file tmpfile_stdout 11:34:15 INFO - Output received: 11:34:15 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:34:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:34:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:15 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 0x29bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d27a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d2b090>, '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 0x2d2bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d25f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', '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 11:34:15 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 11:34:15 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 11:34:15 INFO - Using env: {'DISPLAY': ':0', 11:34:15 INFO - 'HOME': '/home/cltbld', 11:34:15 INFO - 'LANG': 'en_US.UTF-8', 11:34:15 INFO - 'LANGUAGE': 'en_US:en', 11:34:15 INFO - 'LOGNAME': 'cltbld', 11:34:15 INFO - 'MAIL': '/var/mail/cltbld', 11:34:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:15 INFO - 'MOZ_NO_REMOTE': '1', 11:34:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:15 INFO - 'NO_EM_RESTART': '1', 11:34:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:15 INFO - 'PWD': '/builds/slave/test', 11:34:15 INFO - 'SHELL': '/bin/bash', 11:34:15 INFO - 'SHLVL': '1', 11:34:15 INFO - 'TERM': 'linux', 11:34:15 INFO - 'TMOUT': '86400', 11:34:15 INFO - 'USER': 'cltbld', 11:34:15 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', 11:34:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:15 INFO - '_': '/tools/buildbot/bin/python'} 11:34:16 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:34:16 INFO - Downloading/unpacking pip>=1.5 11:34:16 INFO - Running setup.py egg_info for package pip 11:34:16 INFO - warning: no files found matching 'pip/cacert.pem' 11:34:16 INFO - warning: no files found matching '*.html' under directory 'docs' 11:34:16 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:34:16 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:34:16 INFO - Installing collected packages: pip 11:34:16 INFO - Found existing installation: pip 1.1 11:34:16 INFO - Uninstalling pip: 11:34:16 INFO - Successfully uninstalled pip 11:34:16 INFO - Running setup.py install for pip 11:34:16 INFO - warning: no files found matching 'pip/cacert.pem' 11:34:16 INFO - warning: no files found matching '*.html' under directory 'docs' 11:34:16 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:34:16 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:34:16 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:34:16 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:34:16 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:34:16 INFO - Successfully installed pip 11:34:16 INFO - Cleaning up... 11:34:16 INFO - Return code: 0 11:34:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x29bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d27a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d2b090>, '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 0x2d2bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d25f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', '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 11:34:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:34:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:34:16 INFO - Using env: {'DISPLAY': ':0', 11:34:16 INFO - 'HOME': '/home/cltbld', 11:34:16 INFO - 'LANG': 'en_US.UTF-8', 11:34:16 INFO - 'LANGUAGE': 'en_US:en', 11:34:16 INFO - 'LOGNAME': 'cltbld', 11:34:16 INFO - 'MAIL': '/var/mail/cltbld', 11:34:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:16 INFO - 'MOZ_NO_REMOTE': '1', 11:34:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:16 INFO - 'NO_EM_RESTART': '1', 11:34:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:16 INFO - 'PWD': '/builds/slave/test', 11:34:16 INFO - 'SHELL': '/bin/bash', 11:34:16 INFO - 'SHLVL': '1', 11:34:16 INFO - 'TERM': 'linux', 11:34:16 INFO - 'TMOUT': '86400', 11:34:16 INFO - 'USER': 'cltbld', 11:34:16 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', 11:34:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:16 INFO - '_': '/tools/buildbot/bin/python'} 11:34:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:34:17 INFO - Downloading/unpacking psutil>=3.1.1 11:34:17 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 11:34:17 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 11:34:17 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 11:34:17 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 11:34:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:34:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:34:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:34:18 INFO - Installing collected packages: psutil 11:34:18 INFO - Running setup.py install for psutil 11:34:18 INFO - building 'psutil._psutil_linux' extension 11:34:18 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 11:34:18 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 11:34:18 INFO - building 'psutil._psutil_posix' extension 11:34:18 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 11:34:18 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 11:34:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:34:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:34:18 INFO - Successfully installed psutil 11:34:18 INFO - Cleaning up... 11:34:18 INFO - Return code: 0 11:34:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:34:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d27a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d2b090>, '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 0x2d2bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d25f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', '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 11:34:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:34:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:34:18 INFO - Using env: {'DISPLAY': ':0', 11:34:18 INFO - 'HOME': '/home/cltbld', 11:34:18 INFO - 'LANG': 'en_US.UTF-8', 11:34:18 INFO - 'LANGUAGE': 'en_US:en', 11:34:18 INFO - 'LOGNAME': 'cltbld', 11:34:18 INFO - 'MAIL': '/var/mail/cltbld', 11:34:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:18 INFO - 'MOZ_NO_REMOTE': '1', 11:34:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:18 INFO - 'NO_EM_RESTART': '1', 11:34:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:18 INFO - 'PWD': '/builds/slave/test', 11:34:18 INFO - 'SHELL': '/bin/bash', 11:34:18 INFO - 'SHLVL': '1', 11:34:18 INFO - 'TERM': 'linux', 11:34:18 INFO - 'TMOUT': '86400', 11:34:18 INFO - 'USER': 'cltbld', 11:34:18 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', 11:34:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:18 INFO - '_': '/tools/buildbot/bin/python'} 11:34:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:34:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:34:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:34:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:34:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:34:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:34:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:34:20 INFO - Installing collected packages: mozsystemmonitor 11:34:20 INFO - Running setup.py install for mozsystemmonitor 11:34:20 INFO - Successfully installed mozsystemmonitor 11:34:20 INFO - Cleaning up... 11:34:20 INFO - Return code: 0 11:34:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:34:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:20 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 0x29bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d27a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d2b090>, '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 0x2d2bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d25f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', '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 11:34:20 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 11:34:20 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 11:34:20 INFO - Using env: {'DISPLAY': ':0', 11:34:20 INFO - 'HOME': '/home/cltbld', 11:34:20 INFO - 'LANG': 'en_US.UTF-8', 11:34:20 INFO - 'LANGUAGE': 'en_US:en', 11:34:20 INFO - 'LOGNAME': 'cltbld', 11:34:20 INFO - 'MAIL': '/var/mail/cltbld', 11:34:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:20 INFO - 'MOZ_NO_REMOTE': '1', 11:34:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:20 INFO - 'NO_EM_RESTART': '1', 11:34:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:20 INFO - 'PWD': '/builds/slave/test', 11:34:20 INFO - 'SHELL': '/bin/bash', 11:34:20 INFO - 'SHLVL': '1', 11:34:20 INFO - 'TERM': 'linux', 11:34:20 INFO - 'TMOUT': '86400', 11:34:20 INFO - 'USER': 'cltbld', 11:34:20 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', 11:34:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:20 INFO - '_': '/tools/buildbot/bin/python'} 11:34:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:34:21 INFO - Downloading/unpacking jsonschema==2.5.1 11:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:34:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:34:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:34:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:34:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:34:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:34:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:34:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:34:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:34:22 INFO - Downloading functools32-3.2.3-2.tar.gz 11:34:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:34:22 INFO - warning: no files found matching '*.txt' 11:34:22 INFO - no previously-included directories found matching 'build' 11:34:22 INFO - no previously-included directories found matching 'dist' 11:34:22 INFO - no previously-included directories found matching '.git*' 11:34:22 INFO - Installing collected packages: jsonschema, functools32 11:34:23 INFO - Running setup.py install for functools32 11:34:23 INFO - warning: no files found matching '*.txt' 11:34:23 INFO - no previously-included directories found matching 'build' 11:34:23 INFO - no previously-included directories found matching 'dist' 11:34:23 INFO - no previously-included directories found matching '.git*' 11:34:23 INFO - Successfully installed jsonschema functools32 11:34:23 INFO - Cleaning up... 11:34:23 INFO - Return code: 0 11:34:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:34:23 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')]} 11:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:23 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')]} 11:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:23 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 0x29bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d27a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d2b090>, '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 0x2d2bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d25f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', '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 11:34:23 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 11:34:23 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 11:34:23 INFO - Using env: {'DISPLAY': ':0', 11:34:23 INFO - 'HOME': '/home/cltbld', 11:34:23 INFO - 'LANG': 'en_US.UTF-8', 11:34:23 INFO - 'LANGUAGE': 'en_US:en', 11:34:23 INFO - 'LOGNAME': 'cltbld', 11:34:23 INFO - 'MAIL': '/var/mail/cltbld', 11:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:23 INFO - 'MOZ_NO_REMOTE': '1', 11:34:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:23 INFO - 'NO_EM_RESTART': '1', 11:34:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:23 INFO - 'PWD': '/builds/slave/test', 11:34:23 INFO - 'SHELL': '/bin/bash', 11:34:23 INFO - 'SHLVL': '1', 11:34:23 INFO - 'TERM': 'linux', 11:34:23 INFO - 'TMOUT': '86400', 11:34:23 INFO - 'USER': 'cltbld', 11:34:23 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', 11:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:23 INFO - '_': '/tools/buildbot/bin/python'} 11:34:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:34:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:34:23 INFO - Cleaning up... 11:34:23 INFO - Return code: 0 11:34:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:34:23 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')]} 11:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:23 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')]} 11:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:23 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 0x29bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d27a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d2b090>, '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 0x2d2bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d25f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', '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 11:34:23 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 11:34:23 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 11:34:23 INFO - Using env: {'DISPLAY': ':0', 11:34:23 INFO - 'HOME': '/home/cltbld', 11:34:23 INFO - 'LANG': 'en_US.UTF-8', 11:34:23 INFO - 'LANGUAGE': 'en_US:en', 11:34:23 INFO - 'LOGNAME': 'cltbld', 11:34:23 INFO - 'MAIL': '/var/mail/cltbld', 11:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:23 INFO - 'MOZ_NO_REMOTE': '1', 11:34:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:23 INFO - 'NO_EM_RESTART': '1', 11:34:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:23 INFO - 'PWD': '/builds/slave/test', 11:34:23 INFO - 'SHELL': '/bin/bash', 11:34:23 INFO - 'SHLVL': '1', 11:34:23 INFO - 'TERM': 'linux', 11:34:23 INFO - 'TMOUT': '86400', 11:34:23 INFO - 'USER': 'cltbld', 11:34:23 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', 11:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:23 INFO - '_': '/tools/buildbot/bin/python'} 11:34:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:34:23 INFO - Downloading/unpacking blobuploader==1.2.4 11:34:23 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 11:34:23 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 11:34:23 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 11:34:23 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 11:34:24 INFO - Downloading blobuploader-1.2.4.tar.gz 11:34:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:34:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:34:24 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 11:34:24 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 11:34:24 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 11:34:24 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 11:34:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:34:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:34:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:34:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:34:25 INFO - Downloading docopt-0.6.1.tar.gz 11:34:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:34:25 INFO - Installing collected packages: blobuploader, requests, docopt 11:34:25 INFO - Running setup.py install for blobuploader 11:34:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:34:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:34:25 INFO - Running setup.py install for requests 11:34:25 INFO - Running setup.py install for docopt 11:34:25 INFO - Successfully installed blobuploader requests docopt 11:34:25 INFO - Cleaning up... 11:34:25 INFO - Return code: 0 11:34:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:34: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')]} 11:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34: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')]} 11:34:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:25 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 0x29bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d27a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d2b090>, '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 0x2d2bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d25f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', '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 11:34:25 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 11:34:25 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 11:34:25 INFO - Using env: {'DISPLAY': ':0', 11:34:25 INFO - 'HOME': '/home/cltbld', 11:34:25 INFO - 'LANG': 'en_US.UTF-8', 11:34:25 INFO - 'LANGUAGE': 'en_US:en', 11:34:25 INFO - 'LOGNAME': 'cltbld', 11:34:25 INFO - 'MAIL': '/var/mail/cltbld', 11:34:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:25 INFO - 'MOZ_NO_REMOTE': '1', 11:34:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:25 INFO - 'NO_EM_RESTART': '1', 11:34:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:25 INFO - 'PWD': '/builds/slave/test', 11:34:25 INFO - 'SHELL': '/bin/bash', 11:34:25 INFO - 'SHLVL': '1', 11:34:25 INFO - 'TERM': 'linux', 11:34:25 INFO - 'TMOUT': '86400', 11:34:25 INFO - 'USER': 'cltbld', 11:34:25 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', 11:34:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:25 INFO - '_': '/tools/buildbot/bin/python'} 11:34:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:34:26 INFO - Running setup.py (path:/tmp/pip-U9CY0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:34:26 INFO - Running setup.py (path:/tmp/pip-jhz5o6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:34:26 INFO - Running setup.py (path:/tmp/pip-EcweZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:34:26 INFO - Running setup.py (path:/tmp/pip-ilqpMw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:34:26 INFO - Running setup.py (path:/tmp/pip-RHBxHD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:34:26 INFO - Running setup.py (path:/tmp/pip-SzExwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:34:26 INFO - Running setup.py (path:/tmp/pip-_VehO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:34:26 INFO - Running setup.py (path:/tmp/pip-KEDeIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:34:26 INFO - Running setup.py (path:/tmp/pip-usKXfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:34:26 INFO - Running setup.py (path:/tmp/pip-RiPArA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:34:26 INFO - Running setup.py (path:/tmp/pip-_ri6Az-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:34:26 INFO - Running setup.py (path:/tmp/pip-AR2Ov_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:34:26 INFO - Running setup.py (path:/tmp/pip-u2ReJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:34:26 INFO - Running setup.py (path:/tmp/pip-Wdcqoo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:34:27 INFO - Running setup.py (path:/tmp/pip-YNn6YV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:34:27 INFO - Running setup.py (path:/tmp/pip-acSdTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:34:27 INFO - Running setup.py (path:/tmp/pip-zGEOUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:34:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:34:27 INFO - Running setup.py install for manifestparser 11:34:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:34:27 INFO - Running setup.py install for mozcrash 11:34:27 INFO - Running setup.py install for mozdebug 11:34:27 INFO - Running setup.py install for mozdevice 11:34:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:34:27 INFO - Running setup.py install for mozfile 11:34:27 INFO - Running setup.py install for mozhttpd 11:34:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:34:27 INFO - Running setup.py install for mozinfo 11:34:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:34:27 INFO - Running setup.py install for mozInstall 11:34:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:34:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:34:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:34:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:34:28 INFO - Running setup.py install for mozleak 11:34:28 INFO - Running setup.py install for mozlog 11:34:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:34:28 INFO - Running setup.py install for moznetwork 11:34:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:34:28 INFO - Running setup.py install for mozprocess 11:34:28 INFO - Running setup.py install for mozprofile 11:34:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:34:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:34:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:34:28 INFO - Running setup.py install for mozrunner 11:34:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:34:28 INFO - Running setup.py install for mozscreenshot 11:34:28 INFO - Running setup.py install for moztest 11:34:28 INFO - Running setup.py install for mozversion 11:34:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:34:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:34:28 INFO - Cleaning up... 11:34:29 INFO - Return code: 0 11:34:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:34:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:29 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 0x29bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d27a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d2b090>, '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 0x2d2bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d25f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', '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 11:34:29 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 11:34:29 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 11:34:29 INFO - Using env: {'DISPLAY': ':0', 11:34:29 INFO - 'HOME': '/home/cltbld', 11:34:29 INFO - 'LANG': 'en_US.UTF-8', 11:34:29 INFO - 'LANGUAGE': 'en_US:en', 11:34:29 INFO - 'LOGNAME': 'cltbld', 11:34:29 INFO - 'MAIL': '/var/mail/cltbld', 11:34:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:29 INFO - 'MOZ_NO_REMOTE': '1', 11:34:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:29 INFO - 'NO_EM_RESTART': '1', 11:34:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:29 INFO - 'PWD': '/builds/slave/test', 11:34:29 INFO - 'SHELL': '/bin/bash', 11:34:29 INFO - 'SHLVL': '1', 11:34:29 INFO - 'TERM': 'linux', 11:34:29 INFO - 'TMOUT': '86400', 11:34:29 INFO - 'USER': 'cltbld', 11:34:29 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', 11:34:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:29 INFO - '_': '/tools/buildbot/bin/python'} 11:34:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:34:29 INFO - Running setup.py (path:/tmp/pip-GPv2ct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:34:29 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)) 11:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:34:29 INFO - Running setup.py (path:/tmp/pip-JqP_bR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:34:29 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)) 11:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:34:29 INFO - Running setup.py (path:/tmp/pip-u4qWxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:34:29 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)) 11:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:34:29 INFO - Running setup.py (path:/tmp/pip-tWL6CA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:34:29 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)) 11:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:34:29 INFO - Running setup.py (path:/tmp/pip-FKIQmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:34:29 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)) 11:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:34:29 INFO - Running setup.py (path:/tmp/pip-hosK9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:34:29 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)) 11:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:34:29 INFO - Running setup.py (path:/tmp/pip-h7ACin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:34:29 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)) 11:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:34:29 INFO - Running setup.py (path:/tmp/pip-SQ4lw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:34:29 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)) 11:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:34:29 INFO - Running setup.py (path:/tmp/pip-bzGo7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:34:29 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)) 11:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:34:29 INFO - Running setup.py (path:/tmp/pip-IgBq1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:34:29 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)) 11:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:34:29 INFO - Running setup.py (path:/tmp/pip-mylmRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:34:30 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)) 11:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:34:30 INFO - Running setup.py (path:/tmp/pip-zDSin2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:34:30 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)) 11:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:34:30 INFO - Running setup.py (path:/tmp/pip-wXW8UI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:34:30 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)) 11:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:34:30 INFO - Running setup.py (path:/tmp/pip-2XXDao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:34:30 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)) 11:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:34:30 INFO - Running setup.py (path:/tmp/pip-81Zgce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:34:30 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)) 11:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:34:30 INFO - Running setup.py (path:/tmp/pip-jccouO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:34:30 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)) 11:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:34:30 INFO - Running setup.py (path:/tmp/pip-n6x43x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:34:30 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)) 11:34:30 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)) 11:34:30 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)) 11:34:30 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)) 11:34:30 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)) 11:34:30 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)) 11:34:30 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)) 11:34:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:34:30 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 11:34:30 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 11:34:30 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 11:34:30 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 11:34:31 INFO - Downloading blessings-1.6.tar.gz 11:34:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:34:31 INFO - Installing collected packages: blessings 11:34:31 INFO - Running setup.py install for blessings 11:34:31 INFO - Successfully installed blessings 11:34:31 INFO - Cleaning up... 11:34:31 INFO - Return code: 0 11:34:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:34:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:34:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:34:32 INFO - Reading from file tmpfile_stdout 11:34:32 INFO - Current package versions: 11:34:32 INFO - argparse == 1.2.1 11:34:32 INFO - blessings == 1.6 11:34:32 INFO - blobuploader == 1.2.4 11:34:32 INFO - docopt == 0.6.1 11:34:32 INFO - functools32 == 3.2.3-2 11:34:32 INFO - jsonschema == 2.5.1 11:34:32 INFO - manifestparser == 1.1 11:34:32 INFO - mozInstall == 1.13 11:34:32 INFO - mozcrash == 1.0 11:34:32 INFO - mozdebug == 0.1 11:34:32 INFO - mozdevice == 0.51 11:34:32 INFO - mozfile == 1.2 11:34:32 INFO - mozhttpd == 0.7 11:34:32 INFO - mozinfo == 0.10 11:34:32 INFO - mozleak == 0.1 11:34:32 INFO - mozlog == 3.5 11:34:32 INFO - moznetwork == 0.27 11:34:32 INFO - mozprocess == 0.25 11:34:32 INFO - mozprofile == 0.28 11:34:32 INFO - mozrunner == 6.13 11:34:32 INFO - mozscreenshot == 0.1 11:34:32 INFO - mozsystemmonitor == 0.3 11:34:32 INFO - moztest == 0.8 11:34:32 INFO - mozversion == 1.4 11:34:32 INFO - psutil == 3.1.1 11:34:32 INFO - requests == 1.2.3 11:34:32 INFO - wsgiref == 0.1.2 11:34:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:34:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:32 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 0x29bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d27a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d2b090>, '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 0x2d2bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d25f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', '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 11:34:32 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 11:34:32 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 11:34:32 INFO - Using env: {'DISPLAY': ':0', 11:34:32 INFO - 'HOME': '/home/cltbld', 11:34:32 INFO - 'LANG': 'en_US.UTF-8', 11:34:32 INFO - 'LANGUAGE': 'en_US:en', 11:34:32 INFO - 'LOGNAME': 'cltbld', 11:34:32 INFO - 'MAIL': '/var/mail/cltbld', 11:34:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:32 INFO - 'MOZ_NO_REMOTE': '1', 11:34:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:32 INFO - 'NO_EM_RESTART': '1', 11:34:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:32 INFO - 'PWD': '/builds/slave/test', 11:34:32 INFO - 'SHELL': '/bin/bash', 11:34:32 INFO - 'SHLVL': '1', 11:34:32 INFO - 'TERM': 'linux', 11:34:32 INFO - 'TMOUT': '86400', 11:34:32 INFO - 'USER': 'cltbld', 11:34:32 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', 11:34:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:32 INFO - '_': '/tools/buildbot/bin/python'} 11:34:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:34:32 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)) 11:34:32 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)) 11:34:32 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)) 11:34:32 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)) 11:34:32 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)) 11:34:32 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)) 11:34:32 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)) 11:34:32 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)) 11:34:32 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)) 11:34:32 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:34:32 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 11:34:32 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 11:34:32 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 11:34:32 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 11:34:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:34:33 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)) 11:34:33 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)) 11:34:33 INFO - Installing collected packages: simplejson 11:34:33 INFO - Running setup.py install for simplejson 11:34:33 INFO - building 'simplejson._speedups' extension 11:34:33 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 11:34:34 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 11:34:34 INFO - Successfully installed simplejson 11:34:34 INFO - Cleaning up... 11:34:34 INFO - Return code: 0 11:34:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:34:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:34 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 0x29bb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d27a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d2b090>, '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 0x2d2bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d25f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', '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 11:34:34 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 11:34:34 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 11:34:34 INFO - Using env: {'DISPLAY': ':0', 11:34:34 INFO - 'HOME': '/home/cltbld', 11:34:34 INFO - 'LANG': 'en_US.UTF-8', 11:34:34 INFO - 'LANGUAGE': 'en_US:en', 11:34:34 INFO - 'LOGNAME': 'cltbld', 11:34:34 INFO - 'MAIL': '/var/mail/cltbld', 11:34:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:34 INFO - 'MOZ_NO_REMOTE': '1', 11:34:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:34 INFO - 'NO_EM_RESTART': '1', 11:34:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:34 INFO - 'PWD': '/builds/slave/test', 11:34:34 INFO - 'SHELL': '/bin/bash', 11:34:34 INFO - 'SHLVL': '1', 11:34:34 INFO - 'TERM': 'linux', 11:34:34 INFO - 'TMOUT': '86400', 11:34:34 INFO - 'USER': 'cltbld', 11:34:34 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', 11:34:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:34 INFO - '_': '/tools/buildbot/bin/python'} 11:34:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:34:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:34:34 INFO - Cleaning up... 11:34:34 INFO - Return code: 0 11:34:34 INFO - Running post-action listener: _resource_record_post_action 11:34:34 INFO - Running post-action listener: _start_resource_monitoring 11:34:34 INFO - Starting resource monitoring. 11:34:34 INFO - [mozharness: 2017-09-21 18:34:34.713257Z] Finished create-virtualenv step (success) 11:34:34 INFO - [mozharness: 2017-09-21 18:34:34.713773Z] Running install step. 11:34:34 INFO - Running pre-action listener: _resource_record_pre_action 11:34:34 INFO - Running main action method: install 11:34:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:34:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:34:34 INFO - Reading from file tmpfile_stdout 11:34:34 INFO - Detecting whether we're running mozinstall >=1.0... 11:34:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:34:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:34:35 INFO - Reading from file tmpfile_stdout 11:34:35 INFO - Output received: 11:34:35 INFO - Usage: mozinstall [options] installer 11:34:35 INFO - Options: 11:34:35 INFO - -h, --help show this help message and exit 11:34:35 INFO - -d DEST, --destination=DEST 11:34:35 INFO - Directory to install application into. [default: 11:34:35 INFO - "/builds/slave/test"] 11:34:35 INFO - --app=APP Application being installed. [default: firefox] 11:34:35 INFO - mkdir: /builds/slave/test/build/application 11:34:35 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'] 11:34:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:34:52 INFO - Reading from file tmpfile_stdout 11:34:52 INFO - Output received: 11:34:52 INFO - /builds/slave/test/build/application/firefox/firefox 11:34:52 INFO - Running post-action listener: _resource_record_post_action 11:34:52 INFO - [mozharness: 2017-09-21 18:34:52.830145Z] Finished install step (success) 11:34:52 INFO - [mozharness: 2017-09-21 18:34:52.830292Z] Running setup-mitmproxy step. 11:34:52 INFO - Running pre-action listener: _resource_record_pre_action 11:34:52 INFO - Running main action method: setup_mitmproxy 11:34:52 INFO - Skipping: mitmproxy is not required 11:34:52 INFO - Running post-action listener: _resource_record_post_action 11:34:52 INFO - [mozharness: 2017-09-21 18:34:52.831038Z] Finished setup-mitmproxy step (success) 11:34:52 INFO - [mozharness: 2017-09-21 18:34:52.831259Z] Running run-tests step. 11:34:52 INFO - Running pre-action listener: _resource_record_pre_action 11:34:52 INFO - Running pre-action listener: _set_gcov_prefix 11:34:52 INFO - Running main action method: run_tests 11:34:52 WARNING - Try message not found. 11:34:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:34:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:34:52 INFO - Python 2.7.3 11:34:52 INFO - Return code: 0 11:34:52 INFO - grabbing minidump binary from tooltool 11:34: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')]} 11:34:52 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 0x2d2b090>, '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 0x2d2bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d25f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:34:52 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 11:34:52 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 11:34:52 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 11:34:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:34:52 INFO - Return code: 0 11:34:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:34:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:34:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:34:52 INFO - ENV: RUST_BACKTRACE is now full 11:34:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:34:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:34:52 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/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 11:34:52 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/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-005 --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 11:34:52 INFO - Using env: {'DISPLAY': ':0', 11:34:52 INFO - 'HOME': '/home/cltbld', 11:34:52 INFO - 'LANG': 'en_US.UTF-8', 11:34:52 INFO - 'LANGUAGE': 'en_US:en', 11:34:52 INFO - 'LOGNAME': 'cltbld', 11:34:52 INFO - 'MAIL': '/var/mail/cltbld', 11:34:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:34:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:34:52 INFO - 'MOZ_ACCELERATED': '1', 11:34:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:52 INFO - 'MOZ_NO_REMOTE': '1', 11:34:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:34:52 INFO - 'MOZ_WEBRENDER': '1', 11:34:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:52 INFO - 'NO_EM_RESTART': '1', 11:34:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:52 INFO - 'PWD': '/builds/slave/test', 11:34:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:34:52 INFO - 'RUST_BACKTRACE': 'full', 11:34:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:34:52 INFO - 'SHELL': '/bin/bash', 11:34:52 INFO - 'SHLVL': '1', 11:34:52 INFO - 'TERM': 'linux', 11:34:52 INFO - 'TMOUT': '86400', 11:34:52 INFO - 'USER': 'cltbld', 11:34:52 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', 11:34:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:52 INFO - '_': '/tools/buildbot/bin/python'} 11:34:52 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/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 11:34:53 INFO - mozversion application_buildid: 20170921174618 11:34:53 INFO - mozversion application_changeset: ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2 11:34:53 INFO - mozversion application_display_name: Nightly 11:34:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:34:53 INFO - mozversion application_name: Firefox 11:34:53 INFO - mozversion application_remotingname: firefox 11:34:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:34:53 INFO - mozversion application_vendor: Mozilla 11:34:53 INFO - mozversion application_version: 58.0a1 11:34:53 INFO - mozversion platform_buildid: 20170921174618 11:34:53 INFO - mozversion platform_changeset: ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2 11:34:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:34:53 INFO - mozversion platform_version: 58.0a1 11:34:53 INFO - using testdate: 1506018893 11:34:53 INFO - actual date: 1506018893 11:34:53 INFO - starting webserver on 'localhost:60012' 11:34:53 INFO - SUITE-START | Running 2 tests 11:34:53 INFO - TEST-START | dromaeo_css 11:34:53 INFO - Initialising browser for dromaeo_css test... 11:34:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60012/getInfo.html -profile /tmp/tmp9Jq193/profile 11:34:53 INFO - TEST-INFO | started process 6140 (/builds/slave/test/build/application/firefox/firefox http://localhost:60012/getInfo.html) 11:34:59 INFO - TEST-INFO | 6140: exit 0 11:34:59 INFO - Browser initialized. 11:34:59 INFO - Running cycle 1/1 for dromaeo_css test... 11:34:59 INFO - Using env: {'DISPLAY': ':0', 11:34:59 INFO - 'HOME': '/home/cltbld', 11:34:59 INFO - 'JSGC_DISABLE_POISONING': '1', 11:34:59 INFO - 'LANG': 'en_US.UTF-8', 11:34:59 INFO - 'LANGUAGE': 'en_US:en', 11:34:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:34:59 INFO - 'LOGNAME': 'cltbld', 11:34:59 INFO - 'MAIL': '/var/mail/cltbld', 11:34:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:34:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:34:59 INFO - 'MOZ_ACCELERATED': '1', 11:34:59 INFO - 'MOZ_CRASHREPORTER': '1', 11:34:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:34:59 INFO - 'MOZ_NO_REMOTE': '1', 11:34:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:34:59 INFO - 'MOZ_WEBRENDER': '1', 11:34:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:59 INFO - 'NO_EM_RESTART': '1', 11:34:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:59 INFO - 'PWD': '/builds/slave/test', 11:34:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:34:59 INFO - 'RUST_BACKTRACE': 'full', 11:34:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:34:59 INFO - 'SHELL': '/bin/bash', 11:34:59 INFO - 'SHLVL': '1', 11:34:59 INFO - 'TERM': 'linux', 11:34:59 INFO - 'TMOUT': '86400', 11:34:59 INFO - 'USER': 'cltbld', 11:34:59 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', 11:34:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:59 INFO - '_': '/tools/buildbot/bin/python'} 11:34:59 INFO - TEST-INFO | started process 6384 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9Jq193/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:35:00 INFO - PID 6384 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:35:00 INFO - PID 6384 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:35:00 INFO - PID 6384 | 11:35:00 INFO - PID 6384 | (/builds/slave/test/build/application/firefox/firefox:6444): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:35:00 INFO - PID 6384 | 11:35:10 INFO - PID 6384 | RSS: Main: 206155776 11:35:10 INFO - PID 6384 | 11:38:08 INFO - PID 6384 | Cycle 1(1): loaded http://localhost:60012/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60012/tests/dromaeo/cssquery-ext.html) 11:38:10 INFO - PID 6384 | RSS: Main: 204517376 11:38:10 INFO - PID 6384 | 11:41:12 INFO - PID 6384 | Cycle 1(1): loaded http://localhost:60012/tests/dromaeo/cssquery-ext.html (next: http://localhost:60012/tests/dromaeo/cssquery-jquery.html) 11:41:13 INFO - PID 6384 | RSS: Main: 202117120 11:41:13 INFO - PID 6384 | 11:44:15 INFO - PID 6384 | Cycle 1(1): loaded http://localhost:60012/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60012/tests/dromaeo/cssquery-mootools.html) 11:44:16 INFO - PID 6384 | RSS: Main: 207126528 11:44:16 INFO - PID 6384 | 11:47:18 INFO - PID 6384 | Cycle 1(1): loaded http://localhost:60012/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60012/tests/dromaeo/cssquery-prototype.html) 11:47:18 INFO - PID 6384 | RSS: Main: 205578240 11:47:18 INFO - PID 6384 | 11:50:22 INFO - PID 6384 | Cycle 1(1): loaded http://localhost:60012/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60012/tests/dromaeo/cssquery-yui.html) 11:50:23 INFO - PID 6384 | RSS: Main: 207290368 11:50:23 INFO - PID 6384 | 11:53:25 INFO - PID 6384 | Cycle 1(1): loaded http://localhost:60012/tests/dromaeo/cssquery-yui.html (next: http://localhost:60012/tests/dromaeo/cssquery-dojo.html) 11:53:25 INFO - PID 6384 | RSS: Main: 209248256 11:53:25 INFO - PID 6384 | 11:53:25 INFO - PID 6384 | __start_tp_report 11:53:25 INFO - PID 6384 | _x_x_mozilla_page_load 11:53:25 INFO - PID 6384 | _x_x_mozilla_page_load_details 11:53:25 INFO - PID 6384 | |i|pagename|runs| 11:53:25 INFO - PID 6384 | |0;dojo.html;3120.3135310231746;3135.2788858562544;3154.936901261975;3162.541431492435;3169.5404166395874;6162.753489860412;6297.401746834049;6320.778772742955;6408.935910640892;6431.935680643192;6245.125682404456;6271.749130034793;6292.370762923717;6304.243490781101;6335.429811316981;5807.1579620954935;5815.651060936336;5829.446202610745;5837.50381217597;5867.442592953684;5880.617759845618;5893.292804863401;5921.407859214066;5952.166696662471;5990.640561566298;5857.736401861926;5915.260592425947;5946.345901950782;5966.67183304918;5997.340292567814;5298.788048478056;5681.687507187103;5766.481016708516;5867.6772777497645;5870.794522191745;5805.36141024487;5812.88374232513;5821.27234095738;5856.677882716449;5865.090910908816;6150.600210986317;6215.595986260923;6328.208973878265;6357.523185761041;6389.968050159766;6591.209054913441;6646.10277612521;6671.933280667228;6706.027625994204;6801.897971530431;6740.191177058784;6753.358430949052;6817.659117044128;6820.249772525068;6886.931130688729;6262.780802671885;6301.1493448384335;6328.905066424008;6329.019002054689;6365.809025729235;6807.285235050248;6893.655317234118;6900.758473453405;6973.651317434108;6980.965095174492;6596.769113081019;6614.0740296358545;6648.700808463631;6726.024726414643;6760.222574403908;6813.386795188358;6830.282820303896;6881.071055407556;6882.346177113166;6890.827729306707;6738;6754.3921047106;6813.727450902007;6833.555818871758;6850.828729281808;6578.605283682994;6658.1011563438315;6728.7308507659145;6732.023856540774;6785.694643741043;5322.4411436799355;5332.33345831771;5339.092354299778;5345.545628621533;5350.438203988603;4659.091477161988;4672.742999135012;4709.529047095263;4738.336632871401;4755.191617425046;5718.628289161191;5811.215485909433;5845.590808643354;5858.882822343529;5865.736041878125;6516.934830651728;6802.727890884408;6804.76183333581;6877.518573699893;6887.862242755117;5711.057675483582;5724.742386592613;5839.124131380327;5848.766049357978;5850.502707269845;5831.154482599999;5872.442117998783;5888.646681199141;5895.764169433261;5899.911501327483;6565.704543295563;6573.835654108686;6654;6658.067870498134;6679.499037572202;6789.219239787488;6795.7961261162245;6879.793606191823;6893.20728116253;6917.515773895779;6331.366863313632;6359.90460143089;6430.099786030053;6452.645104519296;6476.676166191766;6500.057491663777;6576.375244351779;6610.8016759497295;6611.702473388613;6632.2372927114;6752.831179220559;6790.185177778698;6796.932030679828;6842.349976752201;6858;6599.439047681001;6602.042703808017;6616.933830661632;6684.665766711548;6685.264620891795;6473.514486413632;6540.313267107075;6589.4069533742195;6691.230508491586;6691.866162676816;6311.147995020613;6398.168238129199;6480.384363485461;6547.738090476328;6625.569337993015;6453.064305675557;6510.414062734377;6510.73957041732;6549.214094308713;6588.242352129567;6354.364563543609;6444.9033264502;6488.805335839932;6497.675116244074;6503.02454631809 11:53:25 INFO - PID 6384 | |1;ext.html;821.1460081515224;841.8905542279505;862.6764963138822;866.159824969779;866.8613021916494;39187;39833.601663983354;39865.20269594606;42906.78546607262;43149;39101.21797564047;39303;39463.80268098663;39500;39540;7090.503664743457;7215.711371545131;7269.20038795732;7269.709211631528;7270.05489286392;7081.61051142187;7871.606419678993;7999.600019999006;8061.395395345343;8072.313853322475;7995.880061799077;8043.758687239392;8050;8065.516069035847;8066.193380661945;7784.766457006299;7910.80222994424;8015.198480152026;8021.5187088774865;8030.156833532512;7878.369730421553;7890.408219383569;7900.762977110696;7953.602319883983;7962.6416811243635;39053;39100.80449597734;39180.60819391827;39183.804080979695;39189.804050979845;3443.6728510791436;3449.9655003449893;3483.965160348415;3512.771669841452;3517.26137511123;31429.528557071662;31798.205044873834;31900;31952.20119497008;31977.52033719496;5227.529522343008;5259.421463638997;5310.256564081032;5371.838844834661;5427.45725427458;186663;186938;188137;188398;189015;42484.36273455901;48666.27000594994;48811;49438.75280623574;49714.25428618574;51970;53377;53442.465575344526;53565.1965220522;53689.46310536923;13217;13273.336333183302;13294.069415140848;13363.732725345439;13431.261280629464;10457.738556536147;10496.160307175407;10514.474276286155;10539.578416863393;10596.523156457977;14200.715985680436;14353.712925741427;14376.856231437552;14457.638559035899;14476.420943162155;5466.9179962300595;5528.55771538276;5624.128260119688;5624.268845050117;5648.068068768689;993.9552720127467;997.3018886779283;1006.2453160129902;1006.7667107792895;1010.838265877456;22863;22892.542149156925;23114;23160.18939337115;23409.531809363718;1086.875009373932;1095.5617752898904;1096.8080585897596;1116.4250411038322;1129.8700649425257;12315.384230788424;12381;12426.440810163564;12609.810852837214;12630.368481576068;229527;232170;232284;232384;232488;54356.18465723017;54542;54577.72711136419;54588.72705636446;54835.72582137144;1372.9519466818613;1434.6700258940502;1450.6155868694805;1454.163855782929;1459.7956286119952;22151.889240553694;22539.43651408728;22877;23150.768492315536;23157;37830.24339513249;37974.62025379711;38035.61964380431;38081.80959095187;38123.42814857835;194570;195827;196343;196388;196933;31379;31493.055208343787;34410.13974650654;35053.29893402169;35259.82370088133;44974.55025449704;45248.54751452575;45391.773041134584;45724.54275457203;45821.77089114533;32470.512942305413;32798.34403311968;32941.17647058747;32983.34033319368;33247.16882077871;31924.36151277008;32328.515072273465;32426.675733242366;32495.350092997534;32498;35564.644353556134;35898.64101358953;36208.81895590505;36388.27223455569;36716.81641591775;31266.21834454066;31390.86091390934;31456.685433145376;31658.208544786565;31708.07298175456;22993.310200694006;23221.187258445872;23454.648180276967;23599.52800944006;23955.28134155978 11:53:25 INFO - PID 6384 | |2;jquery.html;577.2121054760254;581.7324030945765;583.1864548954209;591.3495155329141;592.3484167415843;21991.23030693924;22701.20545780898;22912.656310155362;22955.655665165035;22982.08071677133;20972.58054838902;22690.54618907621;22709.545809083807;22870.085196592117;23165.884170579124;6383.872322553546;6568.737250509974;6578.769743058994;6579.572327798678;6587.11074005008;6432.324605916381;6529.51028672849;6531.608103513804;6566.737330506774;6589.04458853466;6351.904721429182;6537.77117800877;6628.966855165741;6633.668316584176;6651.634160121192;6679.9980002999455;6726.596404215763;6728.9663551682415;6730.764423245188;6738.393544580987;6221.844453888643;6238.563300568962;6242;6253.999360102407;6268.968655156741;26928.46143077147;27446.313842153908;27969.86015069932;27970.580441293398;28056.298592535146;1967.5376286572643;2007.688808234726;2021.787712290203;2032.1058734156923;2039.6634555298358;19109.904450477658;19711.112999914894;19760.604787904304;19833.80166198334;19837.702434463496;2416.5287768885073;2480.3179125740385;2506.323292710976;2540.657011303469;2560.705518865317;116379;121043;122265;128866.355668222;129070;14567.520537011264;14617.488387906373;14701.117932924059;14714.896382771334;14890.851091489163;23102;23201.187958421713;23224.187153449548;23252.53494930092;23311.300660980196;9277.90722092784;9308.022657620988;9493.288003399774;9497;9516.24029031573;8285.54429506383;8380.16198380157;8448.197421244971;8469.830603388022;8514.616842242114;9000.684976025808;9067;9083.863742043875;9295.302852286106;9298.256139508823;2566.5123626510904;2690.179495253927;2694.205209463213;2707.1878436468983;2710.7966902482394;945.8912225094202;957.3011701457974;967.743538026661;977.5014742481244;981.1113606307829;18550.53623659392;18569.628607427774;19082.809171908382;19146.234150634096;19425.805741942684;997.9251556133319;1006.7483129217695;1022.3456987527989;1023.8054769593754;1031.2677998621064;10287;10633.680989570325;10722.624708135178;10760.085392741548;10855.51150198243;483953;485176;497027;503225;504243;103105.48447257715;104813;105859;108007.45996270125;108966.99999999841;1015.8120747661707;1096.950637221327;1108.1633366808007;1163.4764356039816;1166.0380186346129;18983;19088.633649911953;19370.2251909922;19439;19500.512487187934;52195.47804522058;53422.46577534175;53847.73076134594;53985.19022214749;55052.449475506335;64632;65525;65960;66754.33245667676;67303;14037;14340.784888226894;14453.638659033608;14462.27688615544;14528.636784080483;22004.66992995074;22100.2264920727;22168.445788854762;22451.32646020622;22795.88602056979;16821.654267658803;17696.29214831392;18074.909625452314;18108.275668973092;18148.274069037092;15495.767563486783;15780.368785248462;16107;16620.3351865924;16832.495025149266;18118;18368.540786480444;18539.2584296632;18590;18715.625687485903;14189.858101418853;14238.857611423753;14266.42934282617;14312.642183945069;14403.927980360031;17752.55618609545;17763.11184440747;17809.910950445166;17848.286068557114;17937.103144842968 11:53:25 INFO - PID 6384 | |3;mootools.html;1326.1645163546966;1391.2000599655194;1395.9790603140948;1401.446428660679;1402.8036074949516;12750.872491275084;13373.130746501483;13396.397162127703;15515.689686206297;15735.370585176608;15497.92251038746;15506.84493155068;15572.844271557282;15661.921690391531;15759.133247671374;2788.4283721836973;2839.843808590527;2841.247069526577;2841.7584505317072;2846.2172902451784;2691.286808995618;2762.406082692221;2773.1126039667315;2778.4443111377705;2786.303424143964;2698.892044318225;2721.142840005402;2729.331313828115;2735.302497863052;2739.657542807149;2600.5319042572423;2627.18557247253;2637.3538483071675;2638.3140383500427;2648.8278261913106;2575.562154433751;2586.974130258692;2591.598302263152;2595.8442493450452;2597.194869590433;14843.10941343512;14965.625859353495;15088.622784430367;15140.318685659171;15216.923915380463;7795.493292935941;8007.399445041645;8086.716964906259;8101.635426405767;8111.351091912633;5337.823994723172;5386.111291636876;5630.127330263816;5680.0627896397045;5701.230333904953;1867.1131212674006;1928.6142771445627;1933.497290704413;1949.4249196486928;1953.6190442863647;26923.1923042311;27007.594886076724;27031;27104.1868743938;27170.86414567934;7332.23011583776;7449.88825167623;7509.09890813095;7539.585322807298;7873.409494287951;19615.901920490305;19759.012049397472;19777.901110494644;19815.900920495595;20004.19983200685;8084.676612935535;8296.087430382653;8303.6678532858;8310.667573297;8314.958425207835;5951.226340575698;6001.489873360726;6136.417040381157;6498.285188629247;6520.217573891164;5333.7066461345;5471.06991811393;5541.944580554224;5566.6381685191145;5723.885522289531;1625.179284461363;1633.6242664187066;1652.8099268584026;1656.5196093132856;1657.3701993242491;897.4704924094815;924.8936372317135;936.4942930817418;940.8165407745561;942.6794889737386;7520.323170914535;7645.426593005435;7771.611419429119;7775.805604859924;7885;701.1446035836271;704.8484575816224;705.0517054561709;711.7081996381562;718.3893690363254;4479.977600111979;4676.929846052312;4789.592884604847;5247.868803279872;5297.894401020931;21541.784582153978;22024;22650.320490385315;22696.886515567316;22755.544889102126;21345.14619415206;21596.676049859263;22144.446388840406;22456;22459.887700561394;11620.6513804586;11648.184627076023;11659.358735269472;11702.063834893188;11721.062315014779;8102.594870256581;8159.347252219927;8162.83674326522;8209.671613135408;8266.297364724065;17027.318907244065;17817.73273400926;17858.46424607264;18770;18981.050947452848;15339;18215.726764098814;19352.903235483733;19375.612487750448;20017.699734504284;10417;10469;10494.317869338774;10542.683719488428;10551.94724026375;13251.80122298147;13451.596452106452;13494.865051349361;13528.391222395212;13575;11208.88791112111;11586.246893951866;11589.188756786943;11723.003544698797;11754.353510557;10690.73273168177;10803.513841877291;10809.405482698527;10862.402567858844;11072.667819965414;10199.643012494527;10439.164866810788;10444.791104178026;10487.318324308895;10491.160707143257;7454.739084132029;7485.66314515859;7523.247675232433;7536.472446928662;7570.242975702386;7497.925020749941;7555.962220188864;7611.314981651456;7632.69469221225;7665.616719164131 11:53:25 INFO - PID 6384 | |4;prototype.html;501.46096848308053;526.2159382520047;534.6794910090209;534.9248011496891;536.3617295418452;6304.810855674331;6382.425581697652;6395.744170233191;6404.167458230435;6409.327020662833;6045.455908968203;6196.566240363177;6196.72114754836;6204;6250;5090.720010399426;5144.971005798837;5171.301874246966;5190.8702282443055;5248.212768084789;5147.433782283946;5217.47825217479;5238.79044838206;5246.0589581854765;5251.816186433475;4848.466668666444;5032.169692000815;5037.294778730962;5050.116229659813;5055.51972562606;5038.39539255288;5053.79784808609;5066.771995260222;5081.720505372203;5084.237364395333;4464.575865292792;4512.142692888341;4531.637469002472;4534.9319760203625;4557.453105627313;6100;6127.5404344674325;6159.5072394208355;6161;6183.690815459209;5759.568032397587;5768.653880767168;5784.595078344475;5786.247787787603;5819.592628516006;6520.934790652128;6603.570767900119;6614.305497922697;6619.404253617197;6696.866062678768;4852.8544143675745;4859.465458799529;4912.631552633567;4923.4091908970795;4962.230854217602;6478.7042591481895;6684.660368870954;6756.324367563254;6778.2205046420295;6822.829429264259;4985.90028199434;5027.74861256943;5061.291419201315;5071.239314102914;5091.618128640367;5063.215201643751;5111.948880511221;5115.74421278942;5143.5113664201845;5169.508896654812;5633.183188437653;5650.915236271459;5708.143778433268;5722.513586345124;5756.597038207369;6612.867742645217;6632.535722499475;6643.40209381158;6647.169103862017;6656;1770.2653398839595;1770.486558897931;1777.2002598830577;1778.1287169287216;1780.6171673090348;992.6476100984047;1204.8674645788956;1222.1872454817446;1238.4798384678456;1245.2590708528412;5556.944430555643;5584.621304110954;5591.6365436246515;5612.242347283064;5614.287642269065;6642.535022548472;6658.3341665834;6660.267370589231;6676.464413184995;6678.098456708282;5593.300837395325;5639.154126880919;5648.915266271009;5669.574781891375;5693.4021927696995;3519.348920449725;3545;3564.7345192456823;3593.191531905353;3604.4593311003036;6385.138006369174;6433.742650293936;6441.903371449432;6524.314946930599;6544.564103845424;6779.220389655155;6814.931850681529;6826.044353790473;6858.794236172923;6902.6203558803745;6272.498200143978;6299.527535434769;6346.238451385955;6348.523860710465;6428;6561.311062338576;6591.439727623206;6613.2394774601535;6671.032700258533;6677.3656502632175;5921.549220441019;6356.586821856565;6558.639274839929;6590.8352291193105;6599.175103112111;6224.751009959551;6304.71628776697;6327.36726327382;6401.167848179707;6425.485961123192;4904.901901962012;5001.599872010303;5012.699238045729;5030.773615187252;5041.773120209526;6543.149390579194;6583.80248592543;6630.204375474974;6631.237407698177;6689.933100668931;6311.211098612674;6408.00675895237;6412.198475190601;6503.317151699003;6605.060747967301;6386.116095751671;6388.552801303864;6476.093346931528;6562.343765623399;6627.271000189879;6402;6432.195975503062;6445.452136568444;6465.030245463218;6488.772892948724;6460.806175814733;6594.406503414716;6650.9667451662435;6666.899996500153;6710.470432458169;6367.172267605181;6374.58565193261;6402.10370548133;6428.614283143026;6435.806925792233 11:53:25 INFO - PID 6384 | |5;yui.html;898.7708134425721;917.3074328881697;923.2060428031887;930.8696782450454;932.7248461703797;19207.903960480195;19308.227670893146;19399.321023764172;19406.51483712906;19470.90264548677;18772.812271877276;18909.149088291026;18912.62174756504;19092.904535477337;19119.808801911975;948.0850978805452;956.2636769687372;961.9134277914986;968.2592816495387;970.7281961050917;984.916282116021;987.8221920054388;988.7972965542046;989.7723523589578;990.8662330585387;995.4077323992213;1010.8281592129356;1013.8073765984476;1034.0331789776546;1037.0459177556666;1024.3341827811944;1027.917766578672;1028.0850043461326;1029.5675816157195;1030.4590090202628;737.71229220604;748.1060133140899;751.7631945937012;752.0561695072704;752.7252552818237;19755.308564200328;20780;21282.255121070844;21292.787072129235;21425.250116245857;22272.220472283392;22516.88741556282;22690.092396304128;22801.201957931393;23064.76935230643;17705.114744262737;17777.20002599886;17990.640187196313;18083;18109.63780724378;3802.3345914465135;3818.8472461101524;3852.5569559500736;3865.2655995361074;3869.303525365433;106036;138321;143442.28278858538;143773;146063;16859.57851053709;17830.5542361442;17835.10824458772;17839.46481605554;17936.641267174582;31136.22159446032;31815.68184318127;31956.840215798773;31971.52042719361;32083.19792005172;9108.271338292921;9221;9245.537723113817;9364.531773411301;9438.197753191056;4390.670699697535;4395.142947125344;4429.402030725811;4457.286834106528;4465.754383508938;5648.096304591328;5691;5755.453231942959;5757.251557297608;5802.158686990447;1070.817960946641;1088.4122573810212;1092.6503518873883;1098.7967226063204;1101.3557069114497;660.0758937487556;684.387473211473;684.5858255755196;695.0581961442274;697.5570512724456;11074.612388566362;11296.096312294996;11506.137039722054;11697.23967942081;11952.64142075739;658.6937074260492;664.4617859533793;673.8551211489553;680.3094858718405;681.7886455198905;5019.14674505335;5056.6713163644245;5127.692338459704;5209.270702101709;5279.44565820591;124125;124241.37879310545;128389.35805320913;129824;130001.34999324943;110395;111402.44298778454;115009;116167.41916290534;116816.41591791986;95030.52484737532;95121.52439237898;96404.51797740966;99879;100051;9600.66397676078;9689.27330450205;9800.166985806287;9934.850977235203;9936.801263974825;44087.33868991904;44612.776936115115;45271;45490.54509454863;46028.539714603765;56843.431565683815;57526.7123664379;58043;58478.12282815676;59244;15551.377944882079;15699.686006279582;15867.444639438027;15936.681266374839;16012.759808602646;17724.55688607795;17769.933803971802;17871;18098.185581648595;18381.264749409875;15024.24878756036;15125.470608528649;15457.922710386376;15490.612734681723;15672.294746736196;15676.843231567538;15840;16030.118343490754;16129.274182662042;16191;15049;15072.924635376752;15083.698326033198;15100;15156.848431515984;11537.596184133516;11969.521219151136;11974;12079.335636540074;12079.698007549881;11056.226064175431;11243.831342529706;11257.71855703614;11320.886791132312;11552.537898483966 11:53:25 INFO - PID 6384 | __end_tp_report 11:53:25 INFO - PID 6384 | __start_cc_report 11:53:25 INFO - PID 6384 | _x_x_mozilla_cycle_collect,311 11:53:25 INFO - PID 6384 | __end_cc_report 11:53:25 INFO - PID 6384 | __startTimestamp1506020005363__endTimestamp 11:53:25 INFO - PID 6384 | 11:53:25 INFO - PID 6384 | ------- Summary: start ------- 11:53:25 INFO - PID 6384 | Number of tests: 6 11:53:25 INFO - PID 6384 | 11:53:25 INFO - PID 6384 | [#0] dojo.html Cycles:165 Average:6221.34 Median:6452.65 stddev:741.16 (11.5%) stddev-sans-first:702.37 11:53:25 INFO - PID 6384 | Values: 3120.3 3135.3 3154.9 3162.5 3169.5 6162.8 6297.4 6320.8 6408.9 6431.9 6245.1 6271.7 6292.4 6304.2 6335.4 5807.2 5815.7 5829.4 5837.5 5867.4 5880.6 5893.3 5921.4 5952.2 5990.6 5857.7 5915.3 5946.3 5966.7 5997.3 5298.8 5681.7 5766.5 5867.7 5870.8 5805.4 5812.9 5821.3 5856.7 5865.1 6150.6 6215.6 6328.2 6357.5 6390.0 6591.2 6646.1 6671.9 6706.0 6801.9 6740.2 6753.4 6817.7 6820.2 6886.9 6262.8 6301.1 6328.9 6329.0 6365.8 6807.3 6893.7 6900.8 6973.7 6981.0 6596.8 6614.1 6648.7 6726.0 6760.2 6813.4 6830.3 6881.1 6882.3 6890.8 6738.0 6754.4 6813.7 6833.6 6850.8 6578.6 6658.1 6728.7 6732.0 6785.7 5322.4 5332.3 5339.1 5345.5 5350.4 4659.1 4672.7 4709.5 4738.3 4755.2 5718.6 5811.2 5845.6 5858.9 5865.7 6516.9 6802.7 6804.8 6877.5 6887.9 5711.1 5724.7 5839.1 5848.8 5850.5 5831.2 5872.4 5888.6 5895.8 5899.9 6565.7 6573.8 6654.0 6658.1 6679.5 6789.2 6795.8 6879.8 6893.2 6917.5 6331.4 6359.9 6430.1 6452.6 6476.7 6500.1 6576.4 6610.8 6611.7 6632.2 6752.8 6790.2 6796.9 6842.3 6858.0 6599.4 6602.0 6616.9 6684.7 6685.3 6473.5 6540.3 6589.4 6691.2 6691.9 6311.1 6398.2 6480.4 6547.7 6625.6 6453.1 6510.4 6510.7 6549.2 6588.2 6354.4 6444.9 6488.8 6497.7 6503.0 11:53:25 INFO - PID 6384 | 11:53:25 INFO - PID 6384 | [#1] ext.html Cycles:180 Average:37502.79 Median:3515.02 stddev:53473.25 (1521.3%) stddev-sans-first:53552.32 11:53:25 INFO - PID 6384 | Values: 821.1 841.9 862.7 866.2 866.9 39187.0 39833.6 39865.2 42906.8 43149.0 39101.2 39303.0 39463.8 39500.0 39540.0 7090.5 7215.7 7269.2 7269.7 7270.1 7081.6 7871.6 7999.6 8061.4 8072.3 7995.9 8043.8 8050.0 8065.5 8066.2 7784.8 7910.8 8015.2 8021.5 8030.2 7878.4 7890.4 7900.8 7953.6 7962.6 39053.0 39100.8 39180.6 39183.8 39189.8 3443.7 3450.0 3484.0 3512.8 3517.3 31429.5 31798.2 31900.0 31952.2 31977.5 5227.5 5259.4 5310.3 5371.8 5427.5 186663.0 186938.0 188137.0 188398.0 189015.0 42484.4 48666.3 48811.0 49438.8 49714.3 51970.0 53377.0 53442.5 53565.2 53689.5 13217.0 13273.3 13294.1 13363.7 13431.3 10457.7 10496.2 10514.5 10539.6 10596.5 14200.7 14353.7 14376.9 14457.6 14476.4 5466.9 5528.6 5624.1 5624.3 5648.1 994.0 997.3 1006.2 1006.8 1010.8 22863.0 22892.5 23114.0 23160.2 23409.5 1086.9 1095.6 1096.8 1116.4 1129.9 12315.4 12381.0 12426.4 12609.8 12630.4 229527.0 232170.0 232284.0 232384.0 232488.0 54356.2 54542.0 54577.7 54588.7 54835.7 1373.0 1434.7 1450.6 1454.2 1459.8 22151.9 22539.4 22877.0 23150.8 23157.0 37830.2 37974.6 38035.6 38081.8 38123.4 194570.0 195827.0 196343.0 196388.0 196933.0 31379.0 31493.1 34410.1 35053.3 35259.8 44974.6 45248.5 45391.8 45724.5 45821.8 32470.5 32798.3 32941.2 32983.3 33247.2 31924.4 32328.5 32426.7 32495.4 32498.0 35564.6 35898.6 36208.8 36388.3 36716.8 31266.2 31390.9 31456.7 31658.2 31708.1 22993.3 23221.2 23454.6 23599.5 23955.3 11:53:25 INFO - PID 6384 | 11:53:25 INFO - PID 6384 | [#2] jquery.html Cycles:180 Average:33752.12 Median:22934.16 stddev:82558.90 (360.0%) stddev-sans-first:82752.92 11:53:25 INFO - PID 6384 | Values: 577.2 581.7 583.2 591.3 592.3 21991.2 22701.2 22912.7 22955.7 22982.1 20972.6 22690.5 22709.5 22870.1 23165.9 6383.9 6568.7 6578.8 6579.6 6587.1 6432.3 6529.5 6531.6 6566.7 6589.0 6351.9 6537.8 6629.0 6633.7 6651.6 6680.0 6726.6 6729.0 6730.8 6738.4 6221.8 6238.6 6242.0 6254.0 6269.0 26928.5 27446.3 27969.9 27970.6 28056.3 1967.5 2007.7 2021.8 2032.1 2039.7 19109.9 19711.1 19760.6 19833.8 19837.7 2416.5 2480.3 2506.3 2540.7 2560.7 116379.0 121043.0 122265.0 128866.4 129070.0 14567.5 14617.5 14701.1 14714.9 14890.9 23102.0 23201.2 23224.2 23252.5 23311.3 9277.9 9308.0 9493.3 9497.0 9516.2 8285.5 8380.2 8448.2 8469.8 8514.6 9000.7 9067.0 9083.9 9295.3 9298.3 2566.5 2690.2 2694.2 2707.2 2710.8 945.9 957.3 967.7 977.5 981.1 18550.5 18569.6 19082.8 19146.2 19425.8 997.9 1006.7 1022.3 1023.8 1031.3 10287.0 10633.7 10722.6 10760.1 10855.5 483953.0 485176.0 497027.0 503225.0 504243.0 103105.5 104813.0 105859.0 108007.5 108967.0 1015.8 1097.0 1108.2 1163.5 1166.0 18983.0 19088.6 19370.2 19439.0 19500.5 52195.5 53422.5 53847.7 53985.2 55052.4 64632.0 65525.0 65960.0 66754.3 67303.0 14037.0 14340.8 14453.6 14462.3 14528.6 22004.7 22100.2 22168.4 22451.3 22795.9 16821.7 17696.3 18074.9 18108.3 18148.3 15495.8 15780.4 16107.0 16620.3 16832.5 18118.0 18368.5 18539.3 18590.0 18715.6 14189.9 14238.9 14266.4 14312.6 14403.9 17752.6 17763.1 17809.9 17848.3 17937.1 11:53:25 INFO - PID 6384 | 11:53:25 INFO - PID 6384 | [#3] mootools.html Cycles:180 Average:9392.80 Median:2613.86 stddev:6813.59 (260.7%) stddev-sans-first:6805.75 11:53:25 INFO - PID 6384 | Values: 1326.2 1391.2 1396.0 1401.4 1402.8 12750.9 13373.1 13396.4 15515.7 15735.4 15497.9 15506.8 15572.8 15661.9 15759.1 2788.4 2839.8 2841.2 2841.8 2846.2 2691.3 2762.4 2773.1 2778.4 2786.3 2698.9 2721.1 2729.3 2735.3 2739.7 2600.5 2627.2 2637.4 2638.3 2648.8 2575.6 2587.0 2591.6 2595.8 2597.2 14843.1 14965.6 15088.6 15140.3 15216.9 7795.5 8007.4 8086.7 8101.6 8111.4 5337.8 5386.1 5630.1 5680.1 5701.2 1867.1 1928.6 1933.5 1949.4 1953.6 26923.2 27007.6 27031.0 27104.2 27170.9 7332.2 7449.9 7509.1 7539.6 7873.4 19615.9 19759.0 19777.9 19815.9 20004.2 8084.7 8296.1 8303.7 8310.7 8315.0 5951.2 6001.5 6136.4 6498.3 6520.2 5333.7 5471.1 5541.9 5566.6 5723.9 1625.2 1633.6 1652.8 1656.5 1657.4 897.5 924.9 936.5 940.8 942.7 7520.3 7645.4 7771.6 7775.8 7885.0 701.1 704.8 705.1 711.7 718.4 4480.0 4676.9 4789.6 5247.9 5297.9 21541.8 22024.0 22650.3 22696.9 22755.5 21345.1 21596.7 22144.4 22456.0 22459.9 11620.7 11648.2 11659.4 11702.1 11721.1 8102.6 8159.3 8162.8 8209.7 8266.3 17027.3 17817.7 17858.5 18770.0 18981.1 15339.0 18215.7 19352.9 19375.6 20017.7 10417.0 10469.0 10494.3 10542.7 10551.9 13251.8 13451.6 13494.9 13528.4 13575.0 11208.9 11586.2 11589.2 11723.0 11754.4 10690.7 10803.5 10809.4 10862.4 11072.7 10199.6 10439.2 10444.8 10487.3 10491.2 7454.7 7485.7 7523.2 7536.5 7570.2 7497.9 7556.0 7611.3 7632.7 7665.6 11:53:25 INFO - PID 6384 | 11:53:25 INFO - PID 6384 | [#4] prototype.html Cycles:180 Average:5504.41 Median:6190.13 stddev:1523.81 (24.6%) stddev-sans-first:1481.10 11:53:25 INFO - PID 6384 | Values: 501.5 526.2 534.7 534.9 536.4 6304.8 6382.4 6395.7 6404.2 6409.3 6045.5 6196.6 6196.7 6204.0 6250.0 5090.7 5145.0 5171.3 5190.9 5248.2 5147.4 5217.5 5238.8 5246.1 5251.8 4848.5 5032.2 5037.3 5050.1 5055.5 5038.4 5053.8 5066.8 5081.7 5084.2 4464.6 4512.1 4531.6 4534.9 4557.5 6100.0 6127.5 6159.5 6161.0 6183.7 5759.6 5768.7 5784.6 5786.2 5819.6 6520.9 6603.6 6614.3 6619.4 6696.9 4852.9 4859.5 4912.6 4923.4 4962.2 6478.7 6684.7 6756.3 6778.2 6822.8 4985.9 5027.7 5061.3 5071.2 5091.6 5063.2 5111.9 5115.7 5143.5 5169.5 5633.2 5650.9 5708.1 5722.5 5756.6 6612.9 6632.5 6643.4 6647.2 6656.0 1770.3 1770.5 1777.2 1778.1 1780.6 992.6 1204.9 1222.2 1238.5 1245.3 5556.9 5584.6 5591.6 5612.2 5614.3 6642.5 6658.3 6660.3 6676.5 6678.1 5593.3 5639.2 5648.9 5669.6 5693.4 3519.3 3545.0 3564.7 3593.2 3604.5 6385.1 6433.7 6441.9 6524.3 6544.6 6779.2 6814.9 6826.0 6858.8 6902.6 6272.5 6299.5 6346.2 6348.5 6428.0 6561.3 6591.4 6613.2 6671.0 6677.4 5921.5 6356.6 6558.6 6590.8 6599.2 6224.8 6304.7 6327.4 6401.2 6425.5 4904.9 5001.6 5012.7 5030.8 5041.8 6543.1 6583.8 6630.2 6631.2 6689.9 6311.2 6408.0 6412.2 6503.3 6605.1 6386.1 6388.6 6476.1 6562.3 6627.3 6402.0 6432.2 6445.5 6465.0 6488.8 6460.8 6594.4 6651.0 6666.9 6710.5 6367.2 6374.6 6402.1 6428.6 6435.8 11:53:25 INFO - PID 6384 | 11:53:25 INFO - PID 6384 | [#5] yui.html Cycles:180 Average:24659.61 Median:20267.65 stddev:35881.01 (177.0%) stddev-sans-first:35937.31 11:53:25 INFO - PID 6384 | Values: 898.8 917.3 923.2 930.9 932.7 19207.9 19308.2 19399.3 19406.5 19470.9 18772.8 18909.1 18912.6 19092.9 19119.8 948.1 956.3 961.9 968.3 970.7 984.9 987.8 988.8 989.8 990.9 995.4 1010.8 1013.8 1034.0 1037.0 1024.3 1027.9 1028.1 1029.6 1030.5 737.7 748.1 751.8 752.1 752.7 19755.3 20780.0 21282.3 21292.8 21425.3 22272.2 22516.9 22690.1 22801.2 23064.8 17705.1 17777.2 17990.6 18083.0 18109.6 3802.3 3818.8 3852.6 3865.3 3869.3 106036.0 138321.0 143442.3 143773.0 146063.0 16859.6 17830.6 17835.1 17839.5 17936.6 31136.2 31815.7 31956.8 31971.5 32083.2 9108.3 9221.0 9245.5 9364.5 9438.2 4390.7 4395.1 4429.4 4457.3 4465.8 5648.1 5691.0 5755.5 5757.3 5802.2 1070.8 1088.4 1092.7 1098.8 1101.4 660.1 684.4 684.6 695.1 697.6 11074.6 11296.1 11506.1 11697.2 11952.6 658.7 664.5 673.9 680.3 681.8 5019.1 5056.7 5127.7 5209.3 5279.4 124125.0 124241.4 128389.4 129824.0 130001.3 110395.0 111402.4 115009.0 116167.4 116816.4 95030.5 95121.5 96404.5 99879.0 100051.0 9600.7 9689.3 9800.2 9934.9 9936.8 44087.3 44612.8 45271.0 45490.5 46028.5 56843.4 57526.7 58043.0 58478.1 59244.0 15551.4 15699.7 15867.4 15936.7 16012.8 17724.6 17769.9 17871.0 18098.2 18381.3 15024.2 15125.5 15457.9 15490.6 15672.3 15676.8 15840.0 16030.1 16129.3 16191.0 15049.0 15072.9 15083.7 15100.0 15156.8 11537.6 11969.5 11974.0 12079.3 12079.7 11056.2 11243.8 11257.7 11320.9 11552.5 11:53:25 INFO - PID 6384 | -------- Summary: end -------- 11:53:25 INFO - PID 6384 | 11:53:26 INFO - TEST-INFO | 6384: exit 0 11:53:26 INFO - TEST-OK | dromaeo_css | took 1113058ms 11:53:26 INFO - TEST-START | kraken 11:53:26 INFO - Initialising browser for kraken test... 11:53:26 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60012/getInfo.html -profile /tmp/tmpximgta/profile 11:53:26 INFO - TEST-INFO | started process 6762 (/builds/slave/test/build/application/firefox/firefox http://localhost:60012/getInfo.html) 11:53:31 INFO - TEST-INFO | 6762: exit 0 11:53:32 INFO - Browser initialized. 11:53:32 INFO - Running cycle 1/1 for kraken test... 11:53:32 INFO - Using env: {'DISPLAY': ':0', 11:53:32 INFO - 'HOME': '/home/cltbld', 11:53:32 INFO - 'JSGC_DISABLE_POISONING': '1', 11:53:32 INFO - 'LANG': 'en_US.UTF-8', 11:53:32 INFO - 'LANGUAGE': 'en_US:en', 11:53:32 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:53:32 INFO - 'LOGNAME': 'cltbld', 11:53:32 INFO - 'MAIL': '/var/mail/cltbld', 11:53:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:53:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:53:32 INFO - 'MOZ_ACCELERATED': '1', 11:53:32 INFO - 'MOZ_CRASHREPORTER': '1', 11:53:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:32 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:53:32 INFO - 'MOZ_NO_REMOTE': '1', 11:53:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:53:32 INFO - 'MOZ_WEBRENDER': '1', 11:53:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:32 INFO - 'NO_EM_RESTART': '1', 11:53:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:32 INFO - 'PWD': '/builds/slave/test', 11:53:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:53:32 INFO - 'RUST_BACKTRACE': 'full', 11:53:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:53:32 INFO - 'SHELL': '/bin/bash', 11:53:32 INFO - 'SHLVL': '1', 11:53:32 INFO - 'TERM': 'linux', 11:53:32 INFO - 'TMOUT': '86400', 11:53:32 INFO - 'USER': 'cltbld', 11:53:32 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248', 11:53:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:32 INFO - '_': '/tools/buildbot/bin/python'} 11:53:32 INFO - TEST-INFO | started process 7007 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpximgta/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:53:32 INFO - PID 7007 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:53:33 INFO - PID 7007 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:53:33 INFO - PID 7007 | 11:53:33 INFO - PID 7007 | (/builds/slave/test/build/application/firefox/firefox:7067): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:53:33 INFO - PID 7007 | 11:53:43 INFO - PID 7007 | RSS: Main: 205344768 11:53:43 INFO - PID 7007 | 11:53:46 INFO - PID 7007 | Cycle 1(1): loaded http://localhost:60012/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60012/tests/kraken/driver.html?testName=audio-beat-detection) 11:53:47 INFO - PID 7007 | RSS: Main: 203255808 11:53:47 INFO - PID 7007 | 11:53:49 INFO - PID 7007 | Cycle 1(1): loaded http://localhost:60012/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60012/tests/kraken/driver.html?testName=audio-dft) 11:53:50 INFO - PID 7007 | RSS: Main: 203902976 11:53:50 INFO - PID 7007 | 11:53:53 INFO - PID 7007 | Cycle 1(1): loaded http://localhost:60012/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60012/tests/kraken/driver.html?testName=audio-fft) 11:53:54 INFO - PID 7007 | RSS: Main: 201089024 11:53:54 INFO - PID 7007 | 11:53:56 INFO - PID 7007 | Cycle 1(1): loaded http://localhost:60012/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60012/tests/kraken/driver.html?testName=audio-oscillator) 11:53:57 INFO - PID 7007 | RSS: Main: 201224192 11:53:57 INFO - PID 7007 | 11:53:59 INFO - PID 7007 | Cycle 1(1): loaded http://localhost:60012/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60012/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:53:59 INFO - PID 7007 | RSS: Main: 202559488 11:53:59 INFO - PID 7007 | 11:54:03 INFO - PID 7007 | Cycle 1(1): loaded http://localhost:60012/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60012/tests/kraken/driver.html?testName=imaging-darkroom) 11:54:03 INFO - PID 7007 | RSS: Main: 205369344 11:54:03 INFO - PID 7007 | 11:54:07 INFO - PID 7007 | Cycle 1(1): loaded http://localhost:60012/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60012/tests/kraken/driver.html?testName=imaging-desaturate) 11:54:08 INFO - PID 7007 | RSS: Main: 203923456 11:54:08 INFO - PID 7007 | 11:54:11 INFO - PID 7007 | Cycle 1(1): loaded http://localhost:60012/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60012/tests/kraken/driver.html?testName=json-parse-financial) 11:54:11 INFO - PID 7007 | RSS: Main: 205262848 11:54:11 INFO - PID 7007 | 11:54:13 INFO - PID 7007 | Cycle 1(1): loaded http://localhost:60012/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60012/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:54:13 INFO - PID 7007 | RSS: Main: 205275136 11:54:13 INFO - PID 7007 | 11:54:15 INFO - PID 7007 | Cycle 1(1): loaded http://localhost:60012/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60012/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:54:16 INFO - PID 7007 | RSS: Main: 201338880 11:54:16 INFO - PID 7007 | 11:54:18 INFO - PID 7007 | Cycle 1(1): loaded http://localhost:60012/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60012/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:54:19 INFO - PID 7007 | RSS: Main: 202162176 11:54:19 INFO - PID 7007 | 11:54:21 INFO - PID 7007 | Cycle 1(1): loaded http://localhost:60012/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60012/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:54:22 INFO - PID 7007 | RSS: Main: 201572352 11:54:22 INFO - PID 7007 | 11:54:25 INFO - PID 7007 | Cycle 1(1): loaded http://localhost:60012/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60012/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:54:25 INFO - PID 7007 | RSS: Main: 202465280 11:54:25 INFO - PID 7007 | 11:54:27 INFO - PID 7007 | Cycle 1(1): loaded http://localhost:60012/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60012/tests/kraken/driver.html?testName=ai-astar) 11:54:28 INFO - PID 7007 | RSS: Main: 204828672 11:54:28 INFO - PID 7007 | 11:54:28 INFO - PID 7007 | __start_tp_report 11:54:28 INFO - PID 7007 | _x_x_mozilla_page_load 11:54:28 INFO - PID 7007 | _x_x_mozilla_page_load_details 11:54:28 INFO - PID 7007 | |i|pagename|runs| 11:54:28 INFO - PID 7007 | |0;ai-astar;114;108;118;107;108;109;110;107;106;117 11:54:28 INFO - PID 7007 | |1;audio-beat-detection;124;128;129;121;119;120;116;125;118;147 11:54:28 INFO - PID 7007 | |2;audio-dft;164;165;166;161;162;177;169;165;163;166 11:54:28 INFO - PID 7007 | |3;audio-fft;80;79;79;78;80;77;81;84;76;79 11:54:28 INFO - PID 7007 | |4;audio-oscillator;86;78;84;78;86;81;90;85;85;82 11:54:28 INFO - PID 7007 | |5;imaging-gaussian-blur;115;114;120;114;116;114;114;124;120;126 11:54:28 INFO - PID 7007 | |6;imaging-darkroom;155;150;151;152;147;148;155;150;149;154 11:54:28 INFO - PID 7007 | |7;imaging-desaturate;101;96;96;95;104;95;97;95;95;94 11:54:28 INFO - PID 7007 | |8;json-parse-financial;53;53;52;54;51;50;52;51;52;52 11:54:28 INFO - PID 7007 | |9;json-stringify-tinderbox;40;44;39;49;39;39;49;39;38;38 11:54:28 INFO - PID 7007 | |10;stanford-crypto-aes;81;77;79;76;75;77;73;82;86;78 11:54:28 INFO - PID 7007 | |11;stanford-crypto-ccm;123;117;114;122;119;114;116;114;118;111 11:54:28 INFO - PID 7007 | |12;stanford-crypto-pbkdf2;174;177;169;178;184;173;183;178;168;168 11:54:28 INFO - PID 7007 | |13;stanford-crypto-sha256-iterative;67;71;67;66;68;68;64;66;67;72 11:54:28 INFO - PID 7007 | __end_tp_report 11:54:28 INFO - PID 7007 | __start_cc_report 11:54:28 INFO - PID 7007 | _x_x_mozilla_cycle_collect,734 11:54:28 INFO - PID 7007 | __end_cc_report 11:54:28 INFO - PID 7007 | __startTimestamp1506020068019__endTimestamp 11:54:28 INFO - PID 7007 | 11:54:28 INFO - PID 7007 | ------- Summary: start ------- 11:54:28 INFO - PID 7007 | Number of tests: 14 11:54:28 INFO - PID 7007 | 11:54:28 INFO - PID 7007 | [#0] ai-astar Cycles:10 Average:110.40 Median:109.50 stddev:4.35 (4.0%) stddev-sans-first:4.42 11:54:28 INFO - PID 7007 | Values: 114.0 108.0 118.0 107.0 108.0 109.0 110.0 107.0 106.0 117.0 11:54:28 INFO - PID 7007 | 11:54:28 INFO - PID 7007 | [#1] audio-beat-detection Cycles:10 Average:124.70 Median:124.50 stddev:8.92 (7.2%) stddev-sans-first:9.46 11:54:28 INFO - PID 7007 | Values: 124.0 128.0 129.0 121.0 119.0 120.0 116.0 125.0 118.0 147.0 11:54:28 INFO - PID 7007 | 11:54:28 INFO - PID 7007 | [#2] audio-dft Cycles:10 Average:165.80 Median:165.50 stddev:4.54 (2.7%) stddev-sans-first:4.77 11:54:28 INFO - PID 7007 | Values: 164.0 165.0 166.0 161.0 162.0 177.0 169.0 165.0 163.0 166.0 11:54:28 INFO - PID 7007 | 11:54:28 INFO - PID 7007 | [#3] audio-fft Cycles:10 Average:79.30 Median:79.50 stddev:2.21 (2.8%) stddev-sans-first:2.33 11:54:28 INFO - PID 7007 | Values: 80.0 79.0 79.0 78.0 80.0 77.0 81.0 84.0 76.0 79.0 11:54:28 INFO - PID 7007 | 11:54:28 INFO - PID 7007 | [#4] audio-oscillator Cycles:10 Average:83.50 Median:85.00 stddev:3.78 (4.4%) stddev-sans-first:3.90 11:54:28 INFO - PID 7007 | Values: 86.0 78.0 84.0 78.0 86.0 81.0 90.0 85.0 85.0 82.0 11:54:28 INFO - PID 7007 | 11:54:28 INFO - PID 7007 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:4.52 (3.8%) stddev-sans-first:4.69 11:54:28 INFO - PID 7007 | Values: 115.0 114.0 120.0 114.0 116.0 114.0 114.0 124.0 120.0 126.0 11:54:28 INFO - PID 7007 | 11:54:28 INFO - PID 7007 | [#6] imaging-darkroom Cycles:10 Average:151.10 Median:151.50 stddev:2.85 (1.9%) stddev-sans-first:2.65 11:54:28 INFO - PID 7007 | Values: 155.0 150.0 151.0 152.0 147.0 148.0 155.0 150.0 149.0 154.0 11:54:28 INFO - PID 7007 | 11:54:28 INFO - PID 7007 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:95.00 stddev:3.19 (3.4%) stddev-sans-first:3.00 11:54:28 INFO - PID 7007 | Values: 101.0 96.0 96.0 95.0 104.0 95.0 97.0 95.0 95.0 94.0 11:54:28 INFO - PID 7007 | 11:54:28 INFO - PID 7007 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:52.00 stddev:1.15 (2.2%) stddev-sans-first:1.17 11:54:28 INFO - PID 7007 | Values: 53.0 53.0 52.0 54.0 51.0 50.0 52.0 51.0 52.0 52.0 11:54:28 INFO - PID 7007 | 11:54:28 INFO - PID 7007 | [#9] json-stringify-tinderbox Cycles:10 Average:41.40 Median:39.50 stddev:4.35 (11.0%) stddev-sans-first:4.59 11:54:28 INFO - PID 7007 | Values: 40.0 44.0 39.0 49.0 39.0 39.0 49.0 39.0 38.0 38.0 11:54:28 INFO - PID 7007 | 11:54:28 INFO - PID 7007 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:78.50 stddev:3.78 (4.8%) stddev-sans-first:3.89 11:54:28 INFO - PID 7007 | Values: 81.0 77.0 79.0 76.0 75.0 77.0 73.0 82.0 86.0 78.0 11:54:28 INFO - PID 7007 | 11:54:28 INFO - PID 7007 | [#11] stanford-crypto-ccm Cycles:10 Average:116.80 Median:117.50 stddev:3.79 (3.2%) stddev-sans-first:3.30 11:54:28 INFO - PID 7007 | Values: 123.0 117.0 114.0 122.0 119.0 114.0 116.0 114.0 118.0 111.0 11:54:28 INFO - PID 7007 | 11:54:28 INFO - PID 7007 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:177.50 stddev:5.83 (3.3%) stddev-sans-first:6.16 11:54:28 INFO - PID 7007 | Values: 174.0 177.0 169.0 178.0 184.0 173.0 183.0 178.0 168.0 168.0 11:54:28 INFO - PID 7007 | 11:54:28 INFO - PID 7007 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:67.50 stddev:2.37 (3.5%) stddev-sans-first:2.50 11:54:28 INFO - PID 7007 | Values: 67.0 71.0 67.0 66.0 68.0 68.0 64.0 66.0 67.0 72.0 11:54:28 INFO - PID 7007 | -------- Summary: end -------- 11:54:28 INFO - PID 7007 | 11:54:28 INFO - PID 7007 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:54:28 INFO - TEST-INFO | 7007: exit 0 11:54:28 INFO - TEST-OK | kraken | took 62673ms 11:54:28 INFO - SUITE-END | took 1175s 11:54:29 INFO - Completed test suite (00:19:35) 11:54:29 INFO - javascript benchmark 11:54:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8479.125286304072, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3120.3135310231746, 3135.2788858562544, 3154.936901261975, 3162.541431492435, 3169.5404166395874, 6162.753489860412, 6297.401746834049, 6320.778772742955, 6408.935910640892, 6431.935680643192, 6245.125682404456, 6271.749130034793, 6292.370762923717, 6304.243490781101, 6335.429811316981, 5807.1579620954935, 5815.651060936336, 5829.446202610745, 5837.50381217597, 5867.442592953684, 5880.617759845618, 5893.292804863401, 5921.407859214066, 5952.166696662471, 5990.640561566298, 5857.736401861926, 5915.260592425947, 5946.345901950782, 5966.67183304918, 5997.340292567814, 5298.788048478056, 5681.687507187103, 5766.481016708516, 5867.6772777497645, 5870.794522191745, 5805.36141024487, 5812.88374232513, 5821.27234095738, 5856.677882716449, 5865.090910908816, 6150.600210986317, 6215.595986260923, 6328.208973878265, 6357.523185761041, 6389.968050159766, 6591.209054913441, 6646.10277612521, 6671.933280667228, 6706.027625994204, 6801.897971530431, 6740.191177058784, 6753.358430949052, 6817.659117044128, 6820.249772525068, 6886.931130688729, 6262.780802671885, 6301.1493448384335, 6328.905066424008, 6329.019002054689, 6365.809025729235, 6807.285235050248, 6893.655317234118, 6900.758473453405, 6973.651317434108, 6980.965095174492, 6596.769113081019, 6614.0740296358545, 6648.700808463631, 6726.024726414643, 6760.222574403908, 6813.386795188358, 6830.282820303896, 6881.071055407556, 6882.346177113166, 6890.827729306707, 6738.0, 6754.3921047106, 6813.727450902007, 6833.555818871758, 6850.828729281808, 6578.605283682994, 6658.1011563438315, 6728.7308507659145, 6732.023856540774, 6785.694643741043, 5322.4411436799355, 5332.33345831771, 5339.092354299778, 5345.545628621533, 5350.438203988603, 4659.091477161988, 4672.742999135012, 4709.529047095263, 4738.336632871401, 4755.191617425046, 5718.628289161191, 5811.215485909433, 5845.590808643354, 5858.882822343529, 5865.736041878125, 6516.934830651728, 6802.727890884408, 6804.76183333581, 6877.518573699893, 6887.862242755117, 5711.057675483582, 5724.742386592613, 5839.124131380327, 5848.766049357978, 5850.502707269845, 5831.154482599999, 5872.442117998783, 5888.646681199141, 5895.764169433261, 5899.911501327483, 6565.704543295563, 6573.835654108686, 6654.0, 6658.067870498134, 6679.499037572202, 6789.219239787488, 6795.7961261162245, 6879.793606191823, 6893.20728116253, 6917.515773895779, 6331.366863313632, 6359.90460143089, 6430.099786030053, 6452.645104519296, 6476.676166191766, 6500.057491663777, 6576.375244351779, 6610.8016759497295, 6611.702473388613, 6632.2372927114, 6752.831179220559, 6790.185177778698, 6796.932030679828, 6842.349976752201, 6858.0, 6599.439047681001, 6602.042703808017, 6616.933830661632, 6684.665766711548, 6685.264620891795, 6473.514486413632, 6540.313267107075, 6589.4069533742195, 6691.230508491586, 6691.866162676816, 6311.147995020613, 6398.168238129199, 6480.384363485461, 6547.738090476328, 6625.569337993015, 6453.064305675557, 6510.414062734377, 6510.73957041732, 6549.214094308713, 6588.242352129567, 6354.364563543609, 6444.9033264502, 6488.805335839932, 6497.675116244074, 6503.02454631809], "value": 6164.921583363637, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [821.1460081515224, 841.8905542279505, 862.6764963138822, 866.159824969779, 866.8613021916494, 39187.0, 39833.601663983354, 39865.20269594606, 42906.78546607262, 43149.0, 39101.21797564047, 39303.0, 39463.80268098663, 39500.0, 39540.0, 7090.503664743457, 7215.711371545131, 7269.20038795732, 7269.709211631528, 7270.05489286392, 7081.61051142187, 7871.606419678993, 7999.600019999006, 8061.395395345343, 8072.313853322475, 7995.880061799077, 8043.758687239392, 8050.0, 8065.516069035847, 8066.193380661945, 7784.766457006299, 7910.80222994424, 8015.198480152026, 8021.5187088774865, 8030.156833532512, 7878.369730421553, 7890.408219383569, 7900.762977110696, 7953.602319883983, 7962.6416811243635, 39053.0, 39100.80449597734, 39180.60819391827, 39183.804080979695, 39189.804050979845, 3443.6728510791436, 3449.9655003449893, 3483.965160348415, 3512.771669841452, 3517.26137511123, 31429.528557071662, 31798.205044873834, 31900.0, 31952.20119497008, 31977.52033719496, 5227.529522343008, 5259.421463638997, 5310.256564081032, 5371.838844834661, 5427.45725427458, 186663.0, 186938.0, 188137.0, 188398.0, 189015.0, 42484.36273455901, 48666.27000594994, 48811.0, 49438.75280623574, 49714.25428618574, 51970.0, 53377.0, 53442.465575344526, 53565.1965220522, 53689.46310536923, 13217.0, 13273.336333183302, 13294.069415140848, 13363.732725345439, 13431.261280629464, 10457.738556536147, 10496.160307175407, 10514.474276286155, 10539.578416863393, 10596.523156457977, 14200.715985680436, 14353.712925741427, 14376.856231437552, 14457.638559035899, 14476.420943162155, 5466.9179962300595, 5528.55771538276, 5624.128260119688, 5624.268845050117, 5648.068068768689, 993.9552720127467, 997.3018886779283, 1006.2453160129902, 1006.7667107792895, 1010.838265877456, 22863.0, 22892.542149156925, 23114.0, 23160.18939337115, 23409.531809363718, 1086.875009373932, 1095.5617752898904, 1096.8080585897596, 1116.4250411038322, 1129.8700649425257, 12315.384230788424, 12381.0, 12426.440810163564, 12609.810852837214, 12630.368481576068, 229527.0, 232170.0, 232284.0, 232384.0, 232488.0, 54356.18465723017, 54542.0, 54577.72711136419, 54588.72705636446, 54835.72582137144, 1372.9519466818613, 1434.6700258940502, 1450.6155868694805, 1454.163855782929, 1459.7956286119952, 22151.889240553694, 22539.43651408728, 22877.0, 23150.768492315536, 23157.0, 37830.24339513249, 37974.62025379711, 38035.61964380431, 38081.80959095187, 38123.42814857835, 194570.0, 195827.0, 196343.0, 196388.0, 196933.0, 31379.0, 31493.055208343787, 34410.13974650654, 35053.29893402169, 35259.82370088133, 44974.55025449704, 45248.54751452575, 45391.773041134584, 45724.54275457203, 45821.77089114533, 32470.512942305413, 32798.34403311968, 32941.17647058747, 32983.34033319368, 33247.16882077871, 31924.36151277008, 32328.515072273465, 32426.675733242366, 32495.350092997534, 32498.0, 35564.644353556134, 35898.64101358953, 36208.81895590505, 36388.27223455569, 36716.81641591775, 31266.21834454066, 31390.86091390934, 31456.685433145376, 31658.208544786565, 31708.07298175456, 22993.310200694006, 23221.187258445872, 23454.648180276967, 23599.52800944006, 23955.28134155978], "value": 17022.953490075673, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.2121054760254, 581.7324030945765, 583.1864548954209, 591.3495155329141, 592.3484167415843, 21991.23030693924, 22701.20545780898, 22912.656310155362, 22955.655665165035, 22982.08071677133, 20972.58054838902, 22690.54618907621, 22709.545809083807, 22870.085196592117, 23165.884170579124, 6383.872322553546, 6568.737250509974, 6578.769743058994, 6579.572327798678, 6587.11074005008, 6432.324605916381, 6529.51028672849, 6531.608103513804, 6566.737330506774, 6589.04458853466, 6351.904721429182, 6537.77117800877, 6628.966855165741, 6633.668316584176, 6651.634160121192, 6679.9980002999455, 6726.596404215763, 6728.9663551682415, 6730.764423245188, 6738.393544580987, 6221.844453888643, 6238.563300568962, 6242.0, 6253.999360102407, 6268.968655156741, 26928.46143077147, 27446.313842153908, 27969.86015069932, 27970.580441293398, 28056.298592535146, 1967.5376286572643, 2007.688808234726, 2021.787712290203, 2032.1058734156923, 2039.6634555298358, 19109.904450477658, 19711.112999914894, 19760.604787904304, 19833.80166198334, 19837.702434463496, 2416.5287768885073, 2480.3179125740385, 2506.323292710976, 2540.657011303469, 2560.705518865317, 116379.0, 121043.0, 122265.0, 128866.355668222, 129070.0, 14567.520537011264, 14617.488387906373, 14701.117932924059, 14714.896382771334, 14890.851091489163, 23102.0, 23201.187958421713, 23224.187153449548, 23252.53494930092, 23311.300660980196, 9277.90722092784, 9308.022657620988, 9493.288003399774, 9497.0, 9516.24029031573, 8285.54429506383, 8380.16198380157, 8448.197421244971, 8469.830603388022, 8514.616842242114, 9000.684976025808, 9067.0, 9083.863742043875, 9295.302852286106, 9298.256139508823, 2566.5123626510904, 2690.179495253927, 2694.205209463213, 2707.1878436468983, 2710.7966902482394, 945.8912225094202, 957.3011701457974, 967.743538026661, 977.5014742481244, 981.1113606307829, 18550.53623659392, 18569.628607427774, 19082.809171908382, 19146.234150634096, 19425.805741942684, 997.9251556133319, 1006.7483129217695, 1022.3456987527989, 1023.8054769593754, 1031.2677998621064, 10287.0, 10633.680989570325, 10722.624708135178, 10760.085392741548, 10855.51150198243, 483953.0, 485176.0, 497027.0, 503225.0, 504243.0, 103105.48447257715, 104813.0, 105859.0, 108007.45996270125, 108966.99999999841, 1015.8120747661707, 1096.950637221327, 1108.1633366808007, 1163.4764356039816, 1166.0380186346129, 18983.0, 19088.633649911953, 19370.2251909922, 19439.0, 19500.512487187934, 52195.47804522058, 53422.46577534175, 53847.73076134594, 53985.19022214749, 55052.449475506335, 64632.0, 65525.0, 65960.0, 66754.33245667676, 67303.0, 14037.0, 14340.784888226894, 14453.638659033608, 14462.27688615544, 14528.636784080483, 22004.66992995074, 22100.2264920727, 22168.445788854762, 22451.32646020622, 22795.88602056979, 16821.654267658803, 17696.29214831392, 18074.909625452314, 18108.275668973092, 18148.274069037092, 15495.767563486783, 15780.368785248462, 16107.0, 16620.3351865924, 16832.495025149266, 18118.0, 18368.540786480444, 18539.2584296632, 18590.0, 18715.625687485903, 14189.858101418853, 14238.857611423753, 14266.42934282617, 14312.642183945069, 14403.927980360031, 17752.55618609545, 17763.11184440747, 17809.910950445166, 17848.286068557114, 17937.103144842968], "value": 11682.796295534588, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1326.1645163546966, 1391.2000599655194, 1395.9790603140948, 1401.446428660679, 1402.8036074949516, 12750.872491275084, 13373.130746501483, 13396.397162127703, 15515.689686206297, 15735.370585176608, 15497.92251038746, 15506.84493155068, 15572.844271557282, 15661.921690391531, 15759.133247671374, 2788.4283721836973, 2839.843808590527, 2841.247069526577, 2841.7584505317072, 2846.2172902451784, 2691.286808995618, 2762.406082692221, 2773.1126039667315, 2778.4443111377705, 2786.303424143964, 2698.892044318225, 2721.142840005402, 2729.331313828115, 2735.302497863052, 2739.657542807149, 2600.5319042572423, 2627.18557247253, 2637.3538483071675, 2638.3140383500427, 2648.8278261913106, 2575.562154433751, 2586.974130258692, 2591.598302263152, 2595.8442493450452, 2597.194869590433, 14843.10941343512, 14965.625859353495, 15088.622784430367, 15140.318685659171, 15216.923915380463, 7795.493292935941, 8007.399445041645, 8086.716964906259, 8101.635426405767, 8111.351091912633, 5337.823994723172, 5386.111291636876, 5630.127330263816, 5680.0627896397045, 5701.230333904953, 1867.1131212674006, 1928.6142771445627, 1933.497290704413, 1949.4249196486928, 1953.6190442863647, 26923.1923042311, 27007.594886076724, 27031.0, 27104.1868743938, 27170.86414567934, 7332.23011583776, 7449.88825167623, 7509.09890813095, 7539.585322807298, 7873.409494287951, 19615.901920490305, 19759.012049397472, 19777.901110494644, 19815.900920495595, 20004.19983200685, 8084.676612935535, 8296.087430382653, 8303.6678532858, 8310.667573297, 8314.958425207835, 5951.226340575698, 6001.489873360726, 6136.417040381157, 6498.285188629247, 6520.217573891164, 5333.7066461345, 5471.06991811393, 5541.944580554224, 5566.6381685191145, 5723.885522289531, 1625.179284461363, 1633.6242664187066, 1652.8099268584026, 1656.5196093132856, 1657.3701993242491, 897.4704924094815, 924.8936372317135, 936.4942930817418, 940.8165407745561, 942.6794889737386, 7520.323170914535, 7645.426593005435, 7771.611419429119, 7775.805604859924, 7885.0, 701.1446035836271, 704.8484575816224, 705.0517054561709, 711.7081996381562, 718.3893690363254, 4479.977600111979, 4676.929846052312, 4789.592884604847, 5247.868803279872, 5297.894401020931, 21541.784582153978, 22024.0, 22650.320490385315, 22696.886515567316, 22755.544889102126, 21345.14619415206, 21596.676049859263, 22144.446388840406, 22456.0, 22459.887700561394, 11620.6513804586, 11648.184627076023, 11659.358735269472, 11702.063834893188, 11721.062315014779, 8102.594870256581, 8159.347252219927, 8162.83674326522, 8209.671613135408, 8266.297364724065, 17027.318907244065, 17817.73273400926, 17858.46424607264, 18770.0, 18981.050947452848, 15339.0, 18215.726764098814, 19352.903235483733, 19375.612487750448, 20017.699734504284, 10417.0, 10469.0, 10494.317869338774, 10542.683719488428, 10551.94724026375, 13251.80122298147, 13451.596452106452, 13494.865051349361, 13528.391222395212, 13575.0, 11208.88791112111, 11586.246893951866, 11589.188756786943, 11723.003544698797, 11754.353510557, 10690.73273168177, 10803.513841877291, 10809.405482698527, 10862.402567858844, 11072.667819965414, 10199.643012494527, 10439.164866810788, 10444.791104178026, 10487.318324308895, 10491.160707143257, 7454.739084132029, 7485.66314515859, 7523.247675232433, 7536.472446928662, 7570.242975702386, 7497.925020749941, 7555.962220188864, 7611.314981651456, 7632.69469221225, 7665.616719164131], "value": 6660.924598863301, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.46096848308053, 526.2159382520047, 534.6794910090209, 534.9248011496891, 536.3617295418452, 6304.810855674331, 6382.425581697652, 6395.744170233191, 6404.167458230435, 6409.327020662833, 6045.455908968203, 6196.566240363177, 6196.72114754836, 6204.0, 6250.0, 5090.720010399426, 5144.971005798837, 5171.301874246966, 5190.8702282443055, 5248.212768084789, 5147.433782283946, 5217.47825217479, 5238.79044838206, 5246.0589581854765, 5251.816186433475, 4848.466668666444, 5032.169692000815, 5037.294778730962, 5050.116229659813, 5055.51972562606, 5038.39539255288, 5053.79784808609, 5066.771995260222, 5081.720505372203, 5084.237364395333, 4464.575865292792, 4512.142692888341, 4531.637469002472, 4534.9319760203625, 4557.453105627313, 6100.0, 6127.5404344674325, 6159.5072394208355, 6161.0, 6183.690815459209, 5759.568032397587, 5768.653880767168, 5784.595078344475, 5786.247787787603, 5819.592628516006, 6520.934790652128, 6603.570767900119, 6614.305497922697, 6619.404253617197, 6696.866062678768, 4852.8544143675745, 4859.465458799529, 4912.631552633567, 4923.4091908970795, 4962.230854217602, 6478.7042591481895, 6684.660368870954, 6756.324367563254, 6778.2205046420295, 6822.829429264259, 4985.90028199434, 5027.74861256943, 5061.291419201315, 5071.239314102914, 5091.618128640367, 5063.215201643751, 5111.948880511221, 5115.74421278942, 5143.5113664201845, 5169.508896654812, 5633.183188437653, 5650.915236271459, 5708.143778433268, 5722.513586345124, 5756.597038207369, 6612.867742645217, 6632.535722499475, 6643.40209381158, 6647.169103862017, 6656.0, 1770.2653398839595, 1770.486558897931, 1777.2002598830577, 1778.1287169287216, 1780.6171673090348, 992.6476100984047, 1204.8674645788956, 1222.1872454817446, 1238.4798384678456, 1245.2590708528412, 5556.944430555643, 5584.621304110954, 5591.6365436246515, 5612.242347283064, 5614.287642269065, 6642.535022548472, 6658.3341665834, 6660.267370589231, 6676.464413184995, 6678.098456708282, 5593.300837395325, 5639.154126880919, 5648.915266271009, 5669.574781891375, 5693.4021927696995, 3519.348920449725, 3545.0, 3564.7345192456823, 3593.191531905353, 3604.4593311003036, 6385.138006369174, 6433.742650293936, 6441.903371449432, 6524.314946930599, 6544.564103845424, 6779.220389655155, 6814.931850681529, 6826.044353790473, 6858.794236172923, 6902.6203558803745, 6272.498200143978, 6299.527535434769, 6346.238451385955, 6348.523860710465, 6428.0, 6561.311062338576, 6591.439727623206, 6613.2394774601535, 6671.032700258533, 6677.3656502632175, 5921.549220441019, 6356.586821856565, 6558.639274839929, 6590.8352291193105, 6599.175103112111, 6224.751009959551, 6304.71628776697, 6327.36726327382, 6401.167848179707, 6425.485961123192, 4904.901901962012, 5001.599872010303, 5012.699238045729, 5030.773615187252, 5041.773120209526, 6543.149390579194, 6583.80248592543, 6630.204375474974, 6631.237407698177, 6689.933100668931, 6311.211098612674, 6408.00675895237, 6412.198475190601, 6503.317151699003, 6605.060747967301, 6386.116095751671, 6388.552801303864, 6476.093346931528, 6562.343765623399, 6627.271000189879, 6402.0, 6432.195975503062, 6445.452136568444, 6465.030245463218, 6488.772892948724, 6460.806175814733, 6594.406503414716, 6650.9667451662435, 6666.899996500153, 6710.470432458169, 6367.172267605181, 6374.58565193261, 6402.10370548133, 6428.614283143026, 6435.806925792233], "value": 5055.949837859995, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [898.7708134425721, 917.3074328881697, 923.2060428031887, 930.8696782450454, 932.7248461703797, 19207.903960480195, 19308.227670893146, 19399.321023764172, 19406.51483712906, 19470.90264548677, 18772.812271877276, 18909.149088291026, 18912.62174756504, 19092.904535477337, 19119.808801911975, 948.0850978805452, 956.2636769687372, 961.9134277914986, 968.2592816495387, 970.7281961050917, 984.916282116021, 987.8221920054388, 988.7972965542046, 989.7723523589578, 990.8662330585387, 995.4077323992213, 1010.8281592129356, 1013.8073765984476, 1034.0331789776546, 1037.0459177556666, 1024.3341827811944, 1027.917766578672, 1028.0850043461326, 1029.5675816157195, 1030.4590090202628, 737.71229220604, 748.1060133140899, 751.7631945937012, 752.0561695072704, 752.7252552818237, 19755.308564200328, 20780.0, 21282.255121070844, 21292.787072129235, 21425.250116245857, 22272.220472283392, 22516.88741556282, 22690.092396304128, 22801.201957931393, 23064.76935230643, 17705.114744262737, 17777.20002599886, 17990.640187196313, 18083.0, 18109.63780724378, 3802.3345914465135, 3818.8472461101524, 3852.5569559500736, 3865.2655995361074, 3869.303525365433, 106036.0, 138321.0, 143442.28278858538, 143773.0, 146063.0, 16859.57851053709, 17830.5542361442, 17835.10824458772, 17839.46481605554, 17936.641267174582, 31136.22159446032, 31815.68184318127, 31956.840215798773, 31971.52042719361, 32083.19792005172, 9108.271338292921, 9221.0, 9245.537723113817, 9364.531773411301, 9438.197753191056, 4390.670699697535, 4395.142947125344, 4429.402030725811, 4457.286834106528, 4465.754383508938, 5648.096304591328, 5691.0, 5755.453231942959, 5757.251557297608, 5802.158686990447, 1070.817960946641, 1088.4122573810212, 1092.6503518873883, 1098.7967226063204, 1101.3557069114497, 660.0758937487556, 684.387473211473, 684.5858255755196, 695.0581961442274, 697.5570512724456, 11074.612388566362, 11296.096312294996, 11506.137039722054, 11697.23967942081, 11952.64142075739, 658.6937074260492, 664.4617859533793, 673.8551211489553, 680.3094858718405, 681.7886455198905, 5019.14674505335, 5056.6713163644245, 5127.692338459704, 5209.270702101709, 5279.44565820591, 124125.0, 124241.37879310545, 128389.35805320913, 129824.0, 130001.34999324943, 110395.0, 111402.44298778454, 115009.0, 116167.41916290534, 116816.41591791986, 95030.52484737532, 95121.52439237898, 96404.51797740966, 99879.0, 100051.0, 9600.66397676078, 9689.27330450205, 9800.166985806287, 9934.850977235203, 9936.801263974825, 44087.33868991904, 44612.776936115115, 45271.0, 45490.54509454863, 46028.539714603765, 56843.431565683815, 57526.7123664379, 58043.0, 58478.12282815676, 59244.0, 15551.377944882079, 15699.686006279582, 15867.444639438027, 15936.681266374839, 16012.759808602646, 17724.55688607795, 17769.933803971802, 17871.0, 18098.185581648595, 18381.264749409875, 15024.24878756036, 15125.470608528649, 15457.922710386376, 15490.612734681723, 15672.294746736196, 15676.843231567538, 15840.0, 16030.118343490754, 16129.274182662042, 16191.0, 15049.0, 15072.924635376752, 15083.698326033198, 15100.0, 15156.848431515984, 11537.596184133516, 11969.521219151136, 11974.0, 12079.335636540074, 12079.698007549881, 11056.226064175431, 11243.831342529706, 11257.71855703614, 11320.886791132312, 11552.537898483966], "value": 8999.836135789457, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1460.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 108.0, 118.0, 107.0, 108.0, 109.0, 110.0, 107.0, 106.0, 117.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 128.0, 129.0, 121.0, 119.0, 120.0, 116.0, 125.0, 118.0, 147.0], "value": 124.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 166.0, 161.0, 162.0, 177.0, 169.0, 165.0, 163.0, 166.0], "value": 165.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 78.0, 80.0, 77.0, 81.0, 84.0, 76.0, 79.0], "value": 79.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 78.0, 84.0, 78.0, 86.0, 81.0, 90.0, 85.0, 85.0, 82.0], "value": 83.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 120.0, 114.0, 116.0, 114.0, 114.0, 124.0, 120.0, 126.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 150.0, 151.0, 152.0, 147.0, 148.0, 155.0, 150.0, 149.0, 154.0], "value": 151.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 96.0, 96.0, 95.0, 104.0, 95.0, 97.0, 95.0, 95.0, 94.0], "value": 96.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 52.0, 54.0, 51.0, 50.0, 52.0, 51.0, 52.0, 52.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [40.0, 44.0, 39.0, 49.0, 39.0, 39.0, 49.0, 39.0, 38.0, 38.0], "value": 41.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 77.0, 79.0, 76.0, 75.0, 77.0, 73.0, 82.0, 86.0, 78.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 117.0, 114.0, 122.0, 119.0, 114.0, 116.0, 114.0, 118.0, 111.0], "value": 116.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 177.0, 169.0, 178.0, 184.0, 173.0, 183.0, 178.0, 168.0, 168.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 67.0, 66.0, 68.0, 68.0, 64.0, 66.0, 67.0, 72.0], "value": 67.6, "unit": "score"}]}]} 11:54:29 INFO - Return code: 0 11:54:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:54:29 INFO - # TBPL SUCCESS # 11:54:29 INFO - Running post-action listener: _package_coverage_data 11:54:29 INFO - Running post-action listener: _resource_record_post_action 11:54:29 INFO - [mozharness: 2017-09-21 18:54:29.318148Z] Finished run-tests step (success) 11:54:29 INFO - Running post-run listener: _resource_record_post_run 11:54:29 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 343502848; Read time: 8; Write time: 190508 11:54:29 INFO - TinderboxPrint: CPU usage
13.3% 11:54:29 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 11:54:29 INFO - TinderboxPrint: I/O write bytes / time
343,502,848 / 190,508 11:54:29 INFO - TinderboxPrint: CPU idle
8,251.6 (86.7%) 11:54:29 INFO - TinderboxPrint: CPU user
1,202.3 (12.6%) 11:54:29 INFO - TinderboxPrint: Swap in / out
0 / 0 11:54:29 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10121216; Read time: 0; Write time: 956 11:54:29 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:54:29 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 333381632; Read time: 0; Write time: 189552 11:54:29 INFO - Running post-run listener: _upload_blobber_files 11:54:29 INFO - Blob upload gear active. 11:54:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:54:29 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 11:54:29 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'] 11:54:29 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 11:54:30 INFO - (blobuploader) - INFO - Open directory for files ... 11:54:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:54:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:54:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:54:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:54:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:54:31 INFO - (blobuploader) - INFO - Done attempting. 11:54:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:54:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:54:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:54:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:54:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:54:32 INFO - (blobuploader) - INFO - Done attempting. 11:54:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:54:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:54:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:54:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:54:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:54:39 INFO - (blobuploader) - INFO - Done attempting. 11:54:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:54:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:54:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:54:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:54:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:54:40 INFO - (blobuploader) - INFO - Done attempting. 11:54:40 INFO - (blobuploader) - INFO - Iteration through files over. 11:54:40 INFO - Return code: 0 11:54:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:54:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:54:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14d714bdd35f9ad2756f974062cfb3df0077888ab92863708ec0e74e1fd32f75a4d47e596982a226b1b6c829c1ccbe6bae0526c974b1fc2d2b4053d706f04fbf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a77ddc7001f68e98319feb1f5e6926ab1bff5d96733ede44f026385d3f35378248ea69740b25f10de923d6852c7c435b64d3f1ee6965fa9a1d7bdde578d018a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ee6d5503ec504c0c0e215e6c53fcd0b7f95b57ad4e1d5a64e8fcb555d37322d07596ffd4bc49d6b692bcbd63c7e4e2ac0f4e74f93bab6ef24b99b96dab35439", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b691d4184311e3727713e7d0e4eceb3064808faf5564b99f0753aa142dff25a7be09f9612f2542b6510f0a73427f4f81d39b65ac95a1aba4b0cb3ce5adb6de1"} 11:54:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:54:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:54:40 INFO - Contents: 11:54:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14d714bdd35f9ad2756f974062cfb3df0077888ab92863708ec0e74e1fd32f75a4d47e596982a226b1b6c829c1ccbe6bae0526c974b1fc2d2b4053d706f04fbf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a77ddc7001f68e98319feb1f5e6926ab1bff5d96733ede44f026385d3f35378248ea69740b25f10de923d6852c7c435b64d3f1ee6965fa9a1d7bdde578d018a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ee6d5503ec504c0c0e215e6c53fcd0b7f95b57ad4e1d5a64e8fcb555d37322d07596ffd4bc49d6b692bcbd63c7e4e2ac0f4e74f93bab6ef24b99b96dab35439", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b691d4184311e3727713e7d0e4eceb3064808faf5564b99f0753aa142dff25a7be09f9612f2542b6510f0a73427f4f81d39b65ac95a1aba4b0cb3ce5adb6de1"} 11:54:40 INFO - Running post-run listener: copy_logs_to_upload_dir 11:54:40 INFO - Copying logs to upload dir... 11:54:40 INFO - mkdir: /builds/slave/test/build/upload/logs 11:54:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.641718 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-21 11:54:40.188199) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 11:54:40.191995) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14d714bdd35f9ad2756f974062cfb3df0077888ab92863708ec0e74e1fd32f75a4d47e596982a226b1b6c829c1ccbe6bae0526c974b1fc2d2b4053d706f04fbf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a77ddc7001f68e98319feb1f5e6926ab1bff5d96733ede44f026385d3f35378248ea69740b25f10de923d6852c7c435b64d3f1ee6965fa9a1d7bdde578d018a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ee6d5503ec504c0c0e215e6c53fcd0b7f95b57ad4e1d5a64e8fcb555d37322d07596ffd4bc49d6b692bcbd63c7e4e2ac0f4e74f93bab6ef24b99b96dab35439", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b691d4184311e3727713e7d0e4eceb3064808faf5564b99f0753aa142dff25a7be09f9612f2542b6510f0a73427f4f81d39b65ac95a1aba4b0cb3ce5adb6de1"} build_url:https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011463 build_url: 'https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14d714bdd35f9ad2756f974062cfb3df0077888ab92863708ec0e74e1fd32f75a4d47e596982a226b1b6c829c1ccbe6bae0526c974b1fc2d2b4053d706f04fbf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a77ddc7001f68e98319feb1f5e6926ab1bff5d96733ede44f026385d3f35378248ea69740b25f10de923d6852c7c435b64d3f1ee6965fa9a1d7bdde578d018a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ee6d5503ec504c0c0e215e6c53fcd0b7f95b57ad4e1d5a64e8fcb555d37322d07596ffd4bc49d6b692bcbd63c7e4e2ac0f4e74f93bab6ef24b99b96dab35439", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b691d4184311e3727713e7d0e4eceb3064808faf5564b99f0753aa142dff25a7be09f9612f2542b6510f0a73427f4f81d39b65ac95a1aba4b0cb3ce5adb6de1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 11:54:40.221137) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 11:54:40.221544) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1506012044.314249-1509047248 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004551 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 11:54:40.305910) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 11:54:40.306282) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 11:54:40.306709) ========= ========= Total master_lag: 0.22 =========