builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-086 starttime: 1503319194.34 results: success (0) revision: c7c96eebbcb91e5e0c8ef0dbbb5324812fa1e476 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-21 05:39:54.336753) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-21 05:39:54.337165) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-21 05:39:54.337431) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-21 05:39:54.363165) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-21 05:39:54.363441) ========= 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=438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-21 05:39:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 167M=0s 2017-08-21 05:39:54 (167 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.256958 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-21 05:39:54.633605) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-21 05:39:54.633908) ========= 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=438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013301 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-21 05:39:54.685917) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-21 05:39:54.689293) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c7c96eebbcb91e5e0c8ef0dbbb5324812fa1e476 --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 c7c96eebbcb91e5e0c8ef0dbbb5324812fa1e476 --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=438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-21 05:39:54,678 truncating revision to first 12 chars 2017-08-21 05:39:54,678 Setting DEBUG logging. 2017-08-21 05:39:54,678 attempt 1/10 2017-08-21 05:39:54,678 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c7c96eebbcb9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-21 05:39:55,123 unpacking tar archive at: mozilla-central-c7c96eebbcb9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.787903 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-21 05:39:55.491864) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-21 05:39:55.495191) ========= script_repo_revision: c7c96eebbcb91e5e0c8ef0dbbb5324812fa1e476 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-21 05:39:55.495546) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-21 05:39:55.495801) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-21 05:39:55.507509) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-21 05:39:55.507774) ========= /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=438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:39:55 INFO - MultiFileLogger online at 20170821 05:39:55 in /builds/slave/test 05:39:55 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 05:39:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:39:55 INFO - {'append_to_log': False, 05:39:55 INFO - 'base_work_dir': '/builds/slave/test', 05:39:55 INFO - 'blob_upload_branch': 'Firefox', 05:39:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:39:55 INFO - 'branch': 'Firefox', 05:39:55 INFO - 'buildbot_json_path': 'buildprops.json', 05:39:55 INFO - 'code_coverage': False, 05:39:55 INFO - 'config_files': ('talos/linux_config.py',), 05:39:55 INFO - 'default_actions': ('clobber', 05:39:55 INFO - 'read-buildbot-config', 05:39:55 INFO - 'download-and-extract', 05:39:55 INFO - 'populate-webroot', 05:39:55 INFO - 'create-virtualenv', 05:39:55 INFO - 'install', 05:39:55 INFO - 'setup-mitmproxy', 05:39:55 INFO - 'run-tests'), 05:39:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:39:55 INFO - 'disable_ccov_upload': False, 05:39:55 INFO - 'download_minidump_stackwalk': True, 05:39:55 INFO - 'download_symbols': 'ondemand', 05:39:55 INFO - 'enable_stylo': False, 05:39:55 INFO - 'enable_webrender': False, 05:39:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:39:55 INFO - 'tooltool.py': '/tools/tooltool.py', 05:39:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:39:55 INFO - '/tools/misc-python/virtualenv.py')}, 05:39:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:39:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:39:55 INFO - 'gecko_profile': False, 05:39:55 INFO - 'gecko_profile_interval': 0, 05:39:55 INFO - 'installer_path': 'installer.exe', 05:39:55 INFO - 'log_level': 'info', 05:39:55 INFO - 'log_name': 'talos', 05:39:55 INFO - 'log_to_console': True, 05:39:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:39:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:39:55 INFO - 'opt_config_files': (), 05:39:55 INFO - 'pip_index': False, 05:39:55 INFO - 'suite': 'dromaeojs-e10s', 05:39:55 INFO - 'system_bits': '32', 05:39:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:39:55 INFO - 'title': 'talos-linux64-ix-086', 05:39:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:39:55 INFO - 'use_talos_json': True, 05:39:55 INFO - 'verify': 'False', 05:39:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:39:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:39:55 INFO - 'work_dir': 'build'} 05:39:55 INFO - [mozharness: 2017-08-21 12:39:55.745704Z] Running clobber step. 05:39:55 INFO - Running pre-action listener: _resource_record_pre_action 05:39:55 INFO - Running main action method: clobber 05:39:55 INFO - rmtree: /builds/slave/test/build 05:39:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:39:56 INFO - Running post-action listener: _resource_record_post_action 05:39:56 INFO - [mozharness: 2017-08-21 12:39:56.717306Z] Finished clobber step (success) 05:39:56 INFO - [mozharness: 2017-08-21 12:39:56.717403Z] Running read-buildbot-config step. 05:39:56 INFO - Running pre-action listener: _resource_record_pre_action 05:39:56 INFO - Running main action method: read_buildbot_config 05:39:56 INFO - Using buildbot properties: 05:39:56 INFO - { 05:39:56 INFO - "project": "", 05:39:56 INFO - "product": "firefox", 05:39:56 INFO - "who": "archaeopteryx@coole-files.de", 05:39:56 INFO - "installer_path": "public/build/target.tar.bz2", 05:39:56 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 05:39:56 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:39:56 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 05:39:56 INFO - "stage_platform": "linux64-stylosequential", 05:39:56 INFO - "basedir": "/builds/slave/test", 05:39:56 INFO - "buildnumber": 159, 05:39:56 INFO - "platform": "ubuntu64_hw_styloseq", 05:39:56 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:39:56 INFO - "slavebuilddir": "test", 05:39:56 INFO - "taskId": "IIfZphF1TTakhWFlJZ6rUQ", 05:39:56 INFO - "branch": "mozilla-central", 05:39:56 INFO - "script_repo_revision": "production", 05:39:56 INFO - "revision": "c7c96eebbcb91e5e0c8ef0dbbb5324812fa1e476", 05:39:56 INFO - "slavename": "talos-linux64-ix-086", 05:39:56 INFO - "repo_path": "mozilla-central" 05:39:56 INFO - } 05:39:56 INFO - Finding installer, test and symbols from parent task. 05:39:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IIfZphF1TTakhWFlJZ6rUQ'}, attempt #1 05:39:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IIfZphF1TTakhWFlJZ6rUQ'}, attempt #1 05:39:57 INFO - Task dependencies: G7SN3CghTUOgq49ARGJ-8A 05:39:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A'}, attempt #1 05:39:58 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.tar.bz2 05:39:58 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.test_packages.json 05:39:58 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.crashreporter-symbols.zip 05:39:58 INFO - Running post-action listener: _resource_record_post_action 05:39:58 INFO - [mozharness: 2017-08-21 12:39:58.074892Z] Finished read-buildbot-config step (success) 05:39:58 INFO - [mozharness: 2017-08-21 12:39:58.075148Z] Running download-and-extract step. 05:39:58 INFO - Running pre-action listener: _resource_record_pre_action 05:39:58 INFO - Running main action method: download_and_extract 05:39:58 INFO - mkdir: /builds/slave/test/build/tests 05:39:58 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')]} 05:39:58 INFO - trying https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.test_packages.json 05:39:58 INFO - Downloading https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:39:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:39:58 INFO - Downloaded 989 bytes. 05:39:58 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:39:58 INFO - Using the following test package requirements: 05:39:58 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:39:58 INFO - u'common': [u'target.common.tests.zip'], 05:39:58 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:39:58 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:39:58 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:39:58 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:39:58 INFO - u'mozbase': [u'target.common.tests.zip'], 05:39:58 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:39:58 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:39:58 INFO - u'web-platform': [u'target.common.tests.zip', 05:39:58 INFO - u'target.web-platform.tests.tar.gz'], 05:39:58 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:39:58 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:39:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.common.tests.zip 05:39:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:39:58 INFO - Fetch https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.common.tests.zip into memory 05:39:59 INFO - Content-Length response header: 54005814 05:40:00 INFO - Bytes received: 54005814 05:40:11 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:40:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.common.tests.zip 05:40:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:40:11 INFO - Fetch https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.common.tests.zip into memory 05:40:12 INFO - Content-Length response header: 54005814 05:40:12 INFO - Bytes received: 54005814 05:40:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.talos.tests.zip 05:40:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:40:24 INFO - Fetch https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.talos.tests.zip into memory 05:40:24 INFO - Content-Length response header: 14496256 05:40:24 INFO - Bytes received: 14496256 05:40: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')]} 05:40:25 INFO - trying https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.tar.bz2 05:40:25 INFO - Downloading https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:40:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:40:26 INFO - Downloaded 62090232 bytes. 05:40:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.tar.bz2 05:40:26 INFO - mkdir: /builds/slave/test/properties 05:40:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:40:26 INFO - Writing to file /builds/slave/test/properties/build_url 05:40:26 INFO - Contents: 05:40:26 INFO - build_url:https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.tar.bz2 05:40:26 INFO - Running post-action listener: _resource_record_post_action 05:40:26 INFO - Running post-action listener: find_tests_for_verification 05:40:26 INFO - Running post-action listener: set_extra_try_arguments 05:40:26 INFO - [mozharness: 2017-08-21 12:40:26.183728Z] Finished download-and-extract step (success) 05:40:26 INFO - [mozharness: 2017-08-21 12:40:26.183826Z] Running populate-webroot step. 05:40:26 INFO - Running pre-action listener: _resource_record_pre_action 05:40:26 INFO - Running main action method: populate_webroot 05:40:26 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:40:26 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 05:40:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:40:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:40:26 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 05:40:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:40:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:40:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:40:26 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:40:26 INFO - 'talos_options': ['--stylo'], 05:40:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:40:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:40:26 INFO - 'tests': ['damp', 'tps']}, 05:40:26 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:40:26 INFO - 'talos_options': ['--stylo'], 05:40:26 INFO - 'tests': ['damp', 'tps']}, 05:40:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:40:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:40:26 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 05:40:26 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 05:40:26 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:40:26 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:40:26 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:40:26 INFO - 'talos_options': ['--stylo'], 05:40:26 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:40:26 INFO - 'other-e10s': {'tests': ['a11yr', 05:40:26 INFO - 'ts_paint', 05:40:26 INFO - 'tpaint', 05:40:26 INFO - 'sessionrestore', 05:40:26 INFO - 'sessionrestore_many_windows', 05:40:26 INFO - 'sessionrestore_no_auto_restore', 05:40:26 INFO - 'tabpaint']}, 05:40:26 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 05:40:26 INFO - 'tests': ['a11yr', 05:40:26 INFO - 'ts_paint', 05:40:26 INFO - 'tpaint', 05:40:26 INFO - 'sessionrestore', 05:40:26 INFO - 'sessionrestore_many_windows', 05:40:26 INFO - 'sessionrestore_no_auto_restore', 05:40:26 INFO - 'tabpaint']}, 05:40:26 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:40:26 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:40:26 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 05:40:26 INFO - 'tests': ['bloom_basic_singleton']}, 05:40:26 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 05:40:26 INFO - 'tests': ['bloom_basic']}, 05:40:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:40:26 INFO - 'tsvgr_opacity', 05:40:26 INFO - 'tart', 05:40:26 INFO - 'tscrollx', 05:40:26 INFO - 'tsvg_static']}, 05:40:26 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 05:40:26 INFO - 'tests': ['tsvgx', 05:40:26 INFO - 'tsvgr_opacity', 05:40:26 INFO - 'tart', 05:40:26 INFO - 'tscrollx', 05:40:26 INFO - 'tsvg_static']}, 05:40:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:40:26 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:40:26 INFO - 'talos_options': ['--stylo'], 05:40:26 INFO - 'tests': ['tp5o']}, 05:40:26 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:40:26 INFO - 'talos_options': ['--mitmproxy', 05:40:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:40:26 INFO - '--firstNonBlankPaint'], 05:40:26 INFO - 'tests': ['tp6_google', 05:40:26 INFO - 'tp6_youtube', 05:40:26 INFO - 'tp6_amazon', 05:40:26 INFO - 'tp6_facebook']}, 05:40:26 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:40:26 INFO - 'talos_options': ['--stylo', 05:40:26 INFO - '--mitmproxy', 05:40:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:40:26 INFO - '--firstNonBlankPaint'], 05:40:26 INFO - 'tests': ['tp6_google', 05:40:26 INFO - 'tp6_youtube', 05:40:26 INFO - 'tp6_amazon', 05:40:26 INFO - 'tp6_facebook']}, 05:40:26 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:40:26 INFO - 'talos_options': ['--stylo', 05:40:26 INFO - '--stylo-threads=1', 05:40:26 INFO - '--mitmproxy', 05:40:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:40:26 INFO - '--firstNonBlankPaint'], 05:40:26 INFO - 'tests': ['tp6_google', 05:40:26 INFO - 'tp6_youtube', 05:40:26 INFO - 'tp6_amazon', 05:40:26 INFO - 'tp6_facebook']}, 05:40:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:40:26 INFO - 'talos_options': ['--xperf_path', 05:40:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:40:26 INFO - 'tests': ['tp5n']}, 05:40:26 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:40:26 INFO - 'talos_options': ['--stylo', 05:40:26 INFO - '--xperf_path', 05:40:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:40:26 INFO - 'tests': ['tp5n']}}} 05:40:26 INFO - Running post-action listener: _resource_record_post_action 05:40:26 INFO - [mozharness: 2017-08-21 12:40:26.198161Z] Finished populate-webroot step (success) 05:40:26 INFO - [mozharness: 2017-08-21 12:40:26.198250Z] Running create-virtualenv step. 05:40:26 INFO - Running pre-action listener: _resource_record_pre_action 05:40:26 INFO - Running main action method: create_virtualenv 05:40:26 INFO - Creating virtualenv /builds/slave/test/build/venv 05:40:26 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:40:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:40:26 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:40:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:40:26 INFO - Using real prefix '/usr' 05:40:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:40:27 INFO - Installing distribute.............................................................................................................................................................................................done. 05:40:29 INFO - Installing pip.................done. 05:40:29 INFO - Return code: 0 05:40:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:40:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:40:30 INFO - Reading from file tmpfile_stdout 05:40:30 INFO - Output received: 05:40:30 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:40:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:40:30 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')]} 05:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:30 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')]} 05:40:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:30 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 0x11db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547130>, '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 0x1548030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15484c0>, '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': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', '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 05:40:30 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 05:40:30 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 05:40:30 INFO - Using env: {'DISPLAY': ':0', 05:40:30 INFO - 'HOME': '/home/cltbld', 05:40:30 INFO - 'LANG': 'en_US.UTF-8', 05:40:30 INFO - 'LANGUAGE': 'en_US:en', 05:40:30 INFO - 'LOGNAME': 'cltbld', 05:40:30 INFO - 'MAIL': '/var/mail/cltbld', 05:40:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:30 INFO - 'MOZ_NO_REMOTE': '1', 05:40:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:30 INFO - 'NO_EM_RESTART': '1', 05:40:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:30 INFO - 'PWD': '/builds/slave/test', 05:40:30 INFO - 'SHELL': '/bin/bash', 05:40:30 INFO - 'SHLVL': '1', 05:40:30 INFO - 'TERM': 'linux', 05:40:30 INFO - 'TMOUT': '86400', 05:40:30 INFO - 'USER': 'cltbld', 05:40:30 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', 05:40:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:30 INFO - '_': '/tools/buildbot/bin/python'} 05:40:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:40:30 INFO - Cleaning up... 05:40:30 INFO - Return code: 0 05:40:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:40:30 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')]} 05:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:30 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')]} 05:40:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:30 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 0x11db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547130>, '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 0x1548030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15484c0>, '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': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', '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 05:40:30 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 05:40:30 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 05:40:30 INFO - Using env: {'DISPLAY': ':0', 05:40:30 INFO - 'HOME': '/home/cltbld', 05:40:30 INFO - 'LANG': 'en_US.UTF-8', 05:40:30 INFO - 'LANGUAGE': 'en_US:en', 05:40:30 INFO - 'LOGNAME': 'cltbld', 05:40:30 INFO - 'MAIL': '/var/mail/cltbld', 05:40:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:30 INFO - 'MOZ_NO_REMOTE': '1', 05:40:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:30 INFO - 'NO_EM_RESTART': '1', 05:40:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:30 INFO - 'PWD': '/builds/slave/test', 05:40:30 INFO - 'SHELL': '/bin/bash', 05:40:30 INFO - 'SHLVL': '1', 05:40:30 INFO - 'TERM': 'linux', 05:40:30 INFO - 'TMOUT': '86400', 05:40:30 INFO - 'USER': 'cltbld', 05:40:30 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', 05:40:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:30 INFO - '_': '/tools/buildbot/bin/python'} 05:40:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:30 INFO - Downloading/unpacking psutil>=3.1.1 05:40: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 05:40: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 05:40: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 05:40: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 05:40:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:40:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:40:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:40:31 INFO - Installing collected packages: psutil 05:40:31 INFO - Running setup.py install for psutil 05:40:31 INFO - building 'psutil._psutil_linux' extension 05:40:31 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 05:40:32 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 05:40:32 INFO - building 'psutil._psutil_posix' extension 05:40:32 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 05:40:32 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 05:40:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:40:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:40:32 INFO - Successfully installed psutil 05:40:32 INFO - Cleaning up... 05:40:33 INFO - Return code: 0 05:40:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:40:33 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')]} 05:40:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:33 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')]} 05:40:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:33 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 0x11db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547130>, '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 0x1548030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15484c0>, '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': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', '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 05:40:33 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 05:40:33 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 05:40:33 INFO - Using env: {'DISPLAY': ':0', 05:40:33 INFO - 'HOME': '/home/cltbld', 05:40:33 INFO - 'LANG': 'en_US.UTF-8', 05:40:33 INFO - 'LANGUAGE': 'en_US:en', 05:40:33 INFO - 'LOGNAME': 'cltbld', 05:40:33 INFO - 'MAIL': '/var/mail/cltbld', 05:40:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:33 INFO - 'MOZ_NO_REMOTE': '1', 05:40:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:33 INFO - 'NO_EM_RESTART': '1', 05:40:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:33 INFO - 'PWD': '/builds/slave/test', 05:40:33 INFO - 'SHELL': '/bin/bash', 05:40:33 INFO - 'SHLVL': '1', 05:40:33 INFO - 'TERM': 'linux', 05:40:33 INFO - 'TMOUT': '86400', 05:40:33 INFO - 'USER': 'cltbld', 05:40:33 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', 05:40:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:33 INFO - '_': '/tools/buildbot/bin/python'} 05:40:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:40:33 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 05:40:33 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 05:40:33 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 05:40:33 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 05:40:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:40:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:40:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:40:34 INFO - Installing collected packages: mozsystemmonitor 05:40:34 INFO - Running setup.py install for mozsystemmonitor 05:40:34 INFO - Successfully installed mozsystemmonitor 05:40:34 INFO - Cleaning up... 05:40:34 INFO - Return code: 0 05:40:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:40: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')]} 05:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40: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')]} 05:40:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547130>, '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 0x1548030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15484c0>, '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': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', '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 05:40: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==2.5.1'] in /builds/slave/test/build 05:40: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==2.5.1 05:40:34 INFO - Using env: {'DISPLAY': ':0', 05:40:34 INFO - 'HOME': '/home/cltbld', 05:40:34 INFO - 'LANG': 'en_US.UTF-8', 05:40:34 INFO - 'LANGUAGE': 'en_US:en', 05:40:34 INFO - 'LOGNAME': 'cltbld', 05:40:34 INFO - 'MAIL': '/var/mail/cltbld', 05:40:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:34 INFO - 'MOZ_NO_REMOTE': '1', 05:40:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:34 INFO - 'NO_EM_RESTART': '1', 05:40:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:34 INFO - 'PWD': '/builds/slave/test', 05:40:34 INFO - 'SHELL': '/bin/bash', 05:40:34 INFO - 'SHLVL': '1', 05:40:34 INFO - 'TERM': 'linux', 05:40:34 INFO - 'TMOUT': '86400', 05:40:34 INFO - 'USER': 'cltbld', 05:40:34 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', 05:40:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:34 INFO - '_': '/tools/buildbot/bin/python'} 05:40:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:34 INFO - Downloading/unpacking jsonschema==2.5.1 05:40:34 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 05:40:34 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 05:40:34 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 05:40:34 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 05:40:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:40:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:40:35 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 05:40:35 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 05:40:35 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 05:40:35 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 05:40:35 INFO - Downloading functools32-3.2.3-2.tar.gz 05:40:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:40:36 INFO - warning: no files found matching '*.txt' 05:40:36 INFO - no previously-included directories found matching 'build' 05:40:36 INFO - no previously-included directories found matching 'dist' 05:40:36 INFO - no previously-included directories found matching '.git*' 05:40:36 INFO - Installing collected packages: jsonschema, functools32 05:40:36 INFO - Running setup.py install for functools32 05:40:36 INFO - warning: no files found matching '*.txt' 05:40:36 INFO - no previously-included directories found matching 'build' 05:40:36 INFO - no previously-included directories found matching 'dist' 05:40:36 INFO - no previously-included directories found matching '.git*' 05:40:36 INFO - Successfully installed jsonschema functools32 05:40:36 INFO - Cleaning up... 05:40:36 INFO - Return code: 0 05:40:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547130>, '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 0x1548030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15484c0>, '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': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', '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 05:40:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:40:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:40:36 INFO - Using env: {'DISPLAY': ':0', 05:40:36 INFO - 'HOME': '/home/cltbld', 05:40:36 INFO - 'LANG': 'en_US.UTF-8', 05:40:36 INFO - 'LANGUAGE': 'en_US:en', 05:40:36 INFO - 'LOGNAME': 'cltbld', 05:40:36 INFO - 'MAIL': '/var/mail/cltbld', 05:40:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:36 INFO - 'MOZ_NO_REMOTE': '1', 05:40:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:36 INFO - 'NO_EM_RESTART': '1', 05:40:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:36 INFO - 'PWD': '/builds/slave/test', 05:40:36 INFO - 'SHELL': '/bin/bash', 05:40:36 INFO - 'SHLVL': '1', 05:40:36 INFO - 'TERM': 'linux', 05:40:36 INFO - 'TMOUT': '86400', 05:40:36 INFO - 'USER': 'cltbld', 05:40:36 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', 05:40:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:36 INFO - '_': '/tools/buildbot/bin/python'} 05:40:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:40:36 INFO - Cleaning up... 05:40:36 INFO - Return code: 0 05:40:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547130>, '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 0x1548030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15484c0>, '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': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', '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 05:40:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:40:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:40:36 INFO - Using env: {'DISPLAY': ':0', 05:40:36 INFO - 'HOME': '/home/cltbld', 05:40:36 INFO - 'LANG': 'en_US.UTF-8', 05:40:36 INFO - 'LANGUAGE': 'en_US:en', 05:40:36 INFO - 'LOGNAME': 'cltbld', 05:40:36 INFO - 'MAIL': '/var/mail/cltbld', 05:40:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:36 INFO - 'MOZ_NO_REMOTE': '1', 05:40:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:36 INFO - 'NO_EM_RESTART': '1', 05:40:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:36 INFO - 'PWD': '/builds/slave/test', 05:40:36 INFO - 'SHELL': '/bin/bash', 05:40:36 INFO - 'SHLVL': '1', 05:40:36 INFO - 'TERM': 'linux', 05:40:36 INFO - 'TMOUT': '86400', 05:40:36 INFO - 'USER': 'cltbld', 05:40:36 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', 05:40:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:36 INFO - '_': '/tools/buildbot/bin/python'} 05:40:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:36 INFO - Downloading/unpacking blobuploader==1.2.4 05:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:37 INFO - Downloading blobuploader-1.2.4.tar.gz 05:40:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:40:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:40:37 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 05:40:37 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 05:40:37 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 05:40:37 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 05:40:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:40:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:38 INFO - Downloading docopt-0.6.1.tar.gz 05:40:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:40:38 INFO - Installing collected packages: blobuploader, requests, docopt 05:40:38 INFO - Running setup.py install for blobuploader 05:40:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:40:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:40:38 INFO - Running setup.py install for requests 05:40:38 INFO - Running setup.py install for docopt 05:40:38 INFO - Successfully installed blobuploader requests docopt 05:40:38 INFO - Cleaning up... 05:40:39 INFO - Return code: 0 05:40:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:39 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 0x11db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547130>, '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 0x1548030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15484c0>, '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': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', '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 05:40:39 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 05:40:39 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 05:40:39 INFO - Using env: {'DISPLAY': ':0', 05:40:39 INFO - 'HOME': '/home/cltbld', 05:40:39 INFO - 'LANG': 'en_US.UTF-8', 05:40:39 INFO - 'LANGUAGE': 'en_US:en', 05:40:39 INFO - 'LOGNAME': 'cltbld', 05:40:39 INFO - 'MAIL': '/var/mail/cltbld', 05:40:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:39 INFO - 'MOZ_NO_REMOTE': '1', 05:40:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:39 INFO - 'NO_EM_RESTART': '1', 05:40:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:39 INFO - 'PWD': '/builds/slave/test', 05:40:39 INFO - 'SHELL': '/bin/bash', 05:40:39 INFO - 'SHLVL': '1', 05:40:39 INFO - 'TERM': 'linux', 05:40:39 INFO - 'TMOUT': '86400', 05:40:39 INFO - 'USER': 'cltbld', 05:40:39 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', 05:40:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:39 INFO - '_': '/tools/buildbot/bin/python'} 05:40:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:40:39 INFO - Running setup.py (path:/tmp/pip-kONeoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:40:39 INFO - Running setup.py (path:/tmp/pip-MKuZNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:40:39 INFO - Running setup.py (path:/tmp/pip-MeZ3mq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:40:39 INFO - Running setup.py (path:/tmp/pip-S7AkeL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:40:39 INFO - Running setup.py (path:/tmp/pip-BDBXUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:40:39 INFO - Running setup.py (path:/tmp/pip-wjL9Nf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:40:39 INFO - Running setup.py (path:/tmp/pip-jgVtR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:40:39 INFO - Running setup.py (path:/tmp/pip-qJe8lK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:40:39 INFO - Running setup.py (path:/tmp/pip-kF9IEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:40:39 INFO - Running setup.py (path:/tmp/pip-XAuAMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:40:39 INFO - Running setup.py (path:/tmp/pip-FB7W1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:40:40 INFO - Running setup.py (path:/tmp/pip-zAiuYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:40:40 INFO - Running setup.py (path:/tmp/pip-M_Lxws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:40:40 INFO - Running setup.py (path:/tmp/pip-ioMYXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:40 INFO - Running setup.py (path:/tmp/pip-tQcJNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:40:40 INFO - Running setup.py (path:/tmp/pip-1Wkkcl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:40:40 INFO - Running setup.py (path:/tmp/pip-cvxQZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:40:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:40:40 INFO - Running setup.py install for manifestparser 05:40:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:40:40 INFO - Running setup.py install for mozcrash 05:40:40 INFO - Running setup.py install for mozdebug 05:40:40 INFO - Running setup.py install for mozdevice 05:40:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:40:40 INFO - Running setup.py install for mozfile 05:40:40 INFO - Running setup.py install for mozhttpd 05:40:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:40:41 INFO - Running setup.py install for mozinfo 05:40:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:40:41 INFO - Running setup.py install for mozInstall 05:40:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:40:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:40:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:40:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:40:41 INFO - Running setup.py install for mozleak 05:40:41 INFO - Running setup.py install for mozlog 05:40:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:40:41 INFO - Running setup.py install for moznetwork 05:40:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:40:41 INFO - Running setup.py install for mozprocess 05:40:41 INFO - Running setup.py install for mozprofile 05:40:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:40:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:40:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:40:41 INFO - Running setup.py install for mozrunner 05:40:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:40:41 INFO - Running setup.py install for mozscreenshot 05:40:42 INFO - Running setup.py install for moztest 05:40:42 INFO - Running setup.py install for mozversion 05:40:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:40:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:40:42 INFO - Cleaning up... 05:40:42 INFO - Return code: 0 05:40:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:42 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 0x11db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547130>, '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 0x1548030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15484c0>, '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': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', '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 05:40:42 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 05:40:42 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 05:40:42 INFO - Using env: {'DISPLAY': ':0', 05:40:42 INFO - 'HOME': '/home/cltbld', 05:40:42 INFO - 'LANG': 'en_US.UTF-8', 05:40:42 INFO - 'LANGUAGE': 'en_US:en', 05:40:42 INFO - 'LOGNAME': 'cltbld', 05:40:42 INFO - 'MAIL': '/var/mail/cltbld', 05:40:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:42 INFO - 'MOZ_NO_REMOTE': '1', 05:40:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:42 INFO - 'NO_EM_RESTART': '1', 05:40:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:42 INFO - 'PWD': '/builds/slave/test', 05:40:42 INFO - 'SHELL': '/bin/bash', 05:40:42 INFO - 'SHLVL': '1', 05:40:42 INFO - 'TERM': 'linux', 05:40:42 INFO - 'TMOUT': '86400', 05:40:42 INFO - 'USER': 'cltbld', 05:40:42 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', 05:40:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:42 INFO - '_': '/tools/buildbot/bin/python'} 05:40:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:40:42 INFO - Running setup.py (path:/tmp/pip-mpts4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:40:42 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)) 05:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:40:42 INFO - Running setup.py (path:/tmp/pip-iXplM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:40:42 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)) 05:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:40:42 INFO - Running setup.py (path:/tmp/pip-3wjVtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:40:42 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)) 05:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:40:42 INFO - Running setup.py (path:/tmp/pip-7VQckd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:40:42 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)) 05:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:40:42 INFO - Running setup.py (path:/tmp/pip-XR4EQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:40:42 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)) 05:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:40:42 INFO - Running setup.py (path:/tmp/pip-KCfwzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:40:42 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)) 05:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:40:42 INFO - Running setup.py (path:/tmp/pip-mbIvrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:40:42 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)) 05:40:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:40:42 INFO - Running setup.py (path:/tmp/pip-dDH15z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:40:43 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)) 05:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:40:43 INFO - Running setup.py (path:/tmp/pip-MAacs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:40:43 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)) 05:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:40:43 INFO - Running setup.py (path:/tmp/pip-VbXPS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:40:43 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)) 05:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:40:43 INFO - Running setup.py (path:/tmp/pip-ygr60H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:40:43 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)) 05:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:40:43 INFO - Running setup.py (path:/tmp/pip-lOJB7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:40:43 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)) 05:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:40:43 INFO - Running setup.py (path:/tmp/pip-A6IQry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:40:43 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)) 05:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:40:43 INFO - Running setup.py (path:/tmp/pip-u7AMDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:40:43 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)) 05:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:43 INFO - Running setup.py (path:/tmp/pip-It6KHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:43 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)) 05:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:40:43 INFO - Running setup.py (path:/tmp/pip-hUwnhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:40:43 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)) 05:40:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:40:43 INFO - Running setup.py (path:/tmp/pip-A_huxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:40:43 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)) 05:40:43 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)) 05:40:43 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)) 05:40:43 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)) 05:40:43 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)) 05:40:43 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)) 05:40:43 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)) 05:40:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:44 INFO - Downloading blessings-1.6.tar.gz 05:40:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:40:44 INFO - Installing collected packages: blessings 05:40:44 INFO - Running setup.py install for blessings 05:40:45 INFO - Successfully installed blessings 05:40:45 INFO - Cleaning up... 05:40:45 INFO - Return code: 0 05:40:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:40:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:40:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:40:45 INFO - Reading from file tmpfile_stdout 05:40:45 INFO - Current package versions: 05:40:45 INFO - argparse == 1.2.1 05:40:45 INFO - blessings == 1.6 05:40:45 INFO - blobuploader == 1.2.4 05:40:45 INFO - docopt == 0.6.1 05:40:45 INFO - functools32 == 3.2.3-2 05:40:45 INFO - jsonschema == 2.5.1 05:40:45 INFO - manifestparser == 1.1 05:40:45 INFO - mozInstall == 1.13 05:40:45 INFO - mozcrash == 1.0 05:40:45 INFO - mozdebug == 0.1 05:40:45 INFO - mozdevice == 0.51 05:40:45 INFO - mozfile == 1.2 05:40:45 INFO - mozhttpd == 0.7 05:40:45 INFO - mozinfo == 0.10 05:40:45 INFO - mozleak == 0.1 05:40:45 INFO - mozlog == 3.5 05:40:45 INFO - moznetwork == 0.27 05:40:45 INFO - mozprocess == 0.25 05:40:45 INFO - mozprofile == 0.28 05:40:45 INFO - mozrunner == 6.13 05:40:45 INFO - mozscreenshot == 0.1 05:40:45 INFO - mozsystemmonitor == 0.3 05:40:45 INFO - moztest == 0.8 05:40:45 INFO - mozversion == 1.4 05:40:45 INFO - psutil == 3.1.1 05:40:45 INFO - requests == 1.2.3 05:40:45 INFO - wsgiref == 0.1.2 05:40:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:45 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 0x11db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547130>, '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 0x1548030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15484c0>, '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': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', '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 05:40:45 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 05:40:45 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 05:40:45 INFO - Using env: {'DISPLAY': ':0', 05:40:45 INFO - 'HOME': '/home/cltbld', 05:40:45 INFO - 'LANG': 'en_US.UTF-8', 05:40:45 INFO - 'LANGUAGE': 'en_US:en', 05:40:45 INFO - 'LOGNAME': 'cltbld', 05:40:45 INFO - 'MAIL': '/var/mail/cltbld', 05:40:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:45 INFO - 'MOZ_NO_REMOTE': '1', 05:40:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:45 INFO - 'NO_EM_RESTART': '1', 05:40:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:45 INFO - 'PWD': '/builds/slave/test', 05:40:45 INFO - 'SHELL': '/bin/bash', 05:40:45 INFO - 'SHLVL': '1', 05:40:45 INFO - 'TERM': 'linux', 05:40:45 INFO - 'TMOUT': '86400', 05:40:45 INFO - 'USER': 'cltbld', 05:40:45 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', 05:40:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:45 INFO - '_': '/tools/buildbot/bin/python'} 05:40:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:45 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)) 05:40:45 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)) 05:40:45 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)) 05:40:45 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)) 05:40:45 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)) 05:40:45 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)) 05:40:45 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)) 05:40:45 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)) 05:40:45 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)) 05:40:45 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:40:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:40:46 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)) 05:40:46 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)) 05:40:46 INFO - Installing collected packages: simplejson 05:40:46 INFO - Running setup.py install for simplejson 05:40:46 INFO - building 'simplejson._speedups' extension 05:40:46 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 05:40:47 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 05:40:47 INFO - Successfully installed simplejson 05:40:47 INFO - Cleaning up... 05:40:47 INFO - Return code: 0 05:40:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:40:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1549f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547130>, '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 0x1548030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15484c0>, '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': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', '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 05:40:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:40:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:40:47 INFO - Using env: {'DISPLAY': ':0', 05:40:47 INFO - 'HOME': '/home/cltbld', 05:40:47 INFO - 'LANG': 'en_US.UTF-8', 05:40:47 INFO - 'LANGUAGE': 'en_US:en', 05:40:47 INFO - 'LOGNAME': 'cltbld', 05:40:47 INFO - 'MAIL': '/var/mail/cltbld', 05:40:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:47 INFO - 'MOZ_NO_REMOTE': '1', 05:40:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:47 INFO - 'NO_EM_RESTART': '1', 05:40:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:47 INFO - 'PWD': '/builds/slave/test', 05:40:47 INFO - 'SHELL': '/bin/bash', 05:40:47 INFO - 'SHLVL': '1', 05:40:47 INFO - 'TERM': 'linux', 05:40:47 INFO - 'TMOUT': '86400', 05:40:47 INFO - 'USER': 'cltbld', 05:40:47 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', 05:40:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:47 INFO - '_': '/tools/buildbot/bin/python'} 05:40:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:40:48 INFO - Cleaning up... 05:40:48 INFO - Return code: 0 05:40:48 INFO - Running post-action listener: _resource_record_post_action 05:40:48 INFO - Running post-action listener: _start_resource_monitoring 05:40:48 INFO - Starting resource monitoring. 05:40:48 INFO - [mozharness: 2017-08-21 12:40:48.057290Z] Finished create-virtualenv step (success) 05:40:48 INFO - [mozharness: 2017-08-21 12:40:48.058289Z] Running install step. 05:40:48 INFO - Running pre-action listener: _resource_record_pre_action 05:40:48 INFO - Running main action method: install 05:40:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:40:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:40:48 INFO - Reading from file tmpfile_stdout 05:40:48 INFO - Detecting whether we're running mozinstall >=1.0... 05:40:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:40:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:40:48 INFO - Reading from file tmpfile_stdout 05:40:48 INFO - Output received: 05:40:48 INFO - Usage: mozinstall [options] installer 05:40:48 INFO - Options: 05:40:48 INFO - -h, --help show this help message and exit 05:40:48 INFO - -d DEST, --destination=DEST 05:40:48 INFO - Directory to install application into. [default: 05:40:48 INFO - "/builds/slave/test"] 05:40:48 INFO - --app=APP Application being installed. [default: firefox] 05:40:48 INFO - mkdir: /builds/slave/test/build/application 05:40:48 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'] 05:40:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:41:06 INFO - Reading from file tmpfile_stdout 05:41:06 INFO - Output received: 05:41:06 INFO - /builds/slave/test/build/application/firefox/firefox 05:41:06 INFO - Running post-action listener: _resource_record_post_action 05:41:06 INFO - [mozharness: 2017-08-21 12:41:06.418495Z] Finished install step (success) 05:41:06 INFO - [mozharness: 2017-08-21 12:41:06.418642Z] Running setup-mitmproxy step. 05:41:06 INFO - Running pre-action listener: _resource_record_pre_action 05:41:06 INFO - Running main action method: setup_mitmproxy 05:41:06 INFO - Skipping: mitmproxy is not required 05:41:06 INFO - Running post-action listener: _resource_record_post_action 05:41:06 INFO - [mozharness: 2017-08-21 12:41:06.419454Z] Finished setup-mitmproxy step (success) 05:41:06 INFO - [mozharness: 2017-08-21 12:41:06.419674Z] Running run-tests step. 05:41:06 INFO - Running pre-action listener: _resource_record_pre_action 05:41:06 INFO - Running pre-action listener: _set_gcov_prefix 05:41:06 INFO - Running main action method: run_tests 05:41:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:41:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:41:06 INFO - Python 2.7.3 05:41:06 INFO - Return code: 0 05:41:06 INFO - grabbing minidump binary from tooltool 05:41:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:06 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1547130>, '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 0x1548030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15484c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:41:06 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:41:06 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:41:06 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:41:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:41:06 INFO - Return code: 0 05:41:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:41:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:41:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:41:06 INFO - ENV: RUST_BACKTRACE is now full 05:41:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:41:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:41:06 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/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-086', '--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 05:41:06 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/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-086 --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 05:41:06 INFO - Using env: {'DISPLAY': ':0', 05:41:06 INFO - 'HOME': '/home/cltbld', 05:41:06 INFO - 'LANG': 'en_US.UTF-8', 05:41:06 INFO - 'LANGUAGE': 'en_US:en', 05:41:06 INFO - 'LOGNAME': 'cltbld', 05:41:06 INFO - 'MAIL': '/var/mail/cltbld', 05:41:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:41:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:41:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:06 INFO - 'MOZ_NO_REMOTE': '1', 05:41:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:41:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:06 INFO - 'NO_EM_RESTART': '1', 05:41:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:06 INFO - 'PWD': '/builds/slave/test', 05:41:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:41:06 INFO - 'RUST_BACKTRACE': 'full', 05:41:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:41:06 INFO - 'SHELL': '/bin/bash', 05:41:06 INFO - 'SHLVL': '1', 05:41:06 INFO - 'STYLO_FORCE_ENABLED': '1', 05:41:06 INFO - 'TERM': 'linux', 05:41:06 INFO - 'TMOUT': '86400', 05:41:06 INFO - 'USER': 'cltbld', 05:41:06 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484', 05:41:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:06 INFO - '_': '/tools/buildbot/bin/python'} 05:41:06 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/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-086', '--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 05:41:06 INFO - mozversion application_buildid: 20170821114200 05:41:06 INFO - mozversion application_changeset: c7c96eebbcb91e5e0c8ef0dbbb5324812fa1e476 05:41:06 INFO - mozversion application_display_name: Nightly 05:41:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:41:06 INFO - mozversion application_name: Firefox 05:41:06 INFO - mozversion application_remotingname: firefox 05:41:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:41:06 INFO - mozversion application_vendor: Mozilla 05:41:06 INFO - mozversion application_version: 57.0a1 05:41:06 INFO - mozversion platform_buildid: 20170821114200 05:41:06 INFO - mozversion platform_changeset: c7c96eebbcb91e5e0c8ef0dbbb5324812fa1e476 05:41:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:41:06 INFO - mozversion platform_version: 57.0a1 05:41:06 INFO - using testdate: 1503319266 05:41:06 INFO - actual date: 1503319266 05:41:06 INFO - starting webserver on 'localhost:45914' 05:41:06 INFO - SUITE-START | Running 2 tests 05:41:06 INFO - TEST-START | dromaeo_css 05:41:06 INFO - Initialising browser for dromaeo_css test... 05:41:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45914/getInfo.html -profile /tmp/tmpN6SVtk/profile 05:41:06 INFO - TEST-INFO | started process 3348 (/builds/slave/test/build/application/firefox/firefox http://localhost:45914/getInfo.html) 05:41:16 INFO - TEST-INFO | 3348: exit 0 05:41:16 INFO - Browser initialized. 05:41:16 INFO - Running cycle 1/1 for dromaeo_css test... 05:41:16 INFO - TEST-INFO | started process 3580 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpN6SVtk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:41:17 INFO - PID 3580 | 05:41:17 INFO - PID 3580 | (/builds/slave/test/build/application/firefox/firefox:3630): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:41:17 INFO - PID 3580 | 05:41:17 INFO - PID 3580 | 05:41:17 INFO - PID 3580 | (/builds/slave/test/build/application/firefox/firefox:3691): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:41:17 INFO - PID 3580 | 05:41:18 INFO - PID 3580 | __metrics Screen width/height:1600/1200 05:41:18 INFO - PID 3580 | colorDepth:24 05:41:18 INFO - PID 3580 | Browser inner width/height: 1024/768 05:41:18 INFO - PID 3580 | __metrics 05:41:27 INFO - PID 3580 | RSS: Main: 174456832 05:41:27 INFO - PID 3580 | 05:44:26 INFO - PID 3580 | Cycle 1(1): loaded http://localhost:45914/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45914/tests/dromaeo/cssquery-ext.html) 05:44:27 INFO - PID 3580 | RSS: Main: 178245632 05:44:27 INFO - PID 3580 | 05:47:29 INFO - PID 3580 | Cycle 1(1): loaded http://localhost:45914/tests/dromaeo/cssquery-ext.html (next: http://localhost:45914/tests/dromaeo/cssquery-jquery.html) 05:47:30 INFO - PID 3580 | RSS: Main: 174862336 05:47:30 INFO - PID 3580 | 05:50:32 INFO - PID 3580 | Cycle 1(1): loaded http://localhost:45914/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45914/tests/dromaeo/cssquery-mootools.html) 05:50:32 INFO - PID 3580 | RSS: Main: 176779264 05:50:32 INFO - PID 3580 | 05:53:34 INFO - PID 3580 | Cycle 1(1): loaded http://localhost:45914/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45914/tests/dromaeo/cssquery-prototype.html) 05:53:35 INFO - PID 3580 | RSS: Main: 175112192 05:53:35 INFO - PID 3580 | 05:56:39 INFO - PID 3580 | Cycle 1(1): loaded http://localhost:45914/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45914/tests/dromaeo/cssquery-yui.html) 05:56:39 INFO - PID 3580 | RSS: Main: 176566272 05:56:39 INFO - PID 3580 | 05:59:41 INFO - PID 3580 | Cycle 1(1): loaded http://localhost:45914/tests/dromaeo/cssquery-yui.html (next: http://localhost:45914/tests/dromaeo/cssquery-dojo.html) 05:59:41 INFO - PID 3580 | RSS: Main: 179302400 05:59:41 INFO - PID 3580 | 05:59:41 INFO - PID 3580 | __start_tp_report 05:59:41 INFO - PID 3580 | _x_x_mozilla_page_load 05:59:41 INFO - PID 3580 | _x_x_mozilla_page_load_details 05:59:41 INFO - PID 3580 | |i|pagename|runs| 05:59:41 INFO - PID 3580 | |0;dojo.html;3182.522621606757;3194.680531946806;3210;3220.2432428379334;3220.404225218335;6208;6292;6317.241930968285;6318.905216421757;6340.3025667176635;6150.4157105075055;6190.0405437157315;6196.318404975449;6202.596831205969;6219.440250377465;5652.434756524335;5663.490285874271;5677.659340439566;5695.743691533891;5709.714514274269;5747.827565173052;5748.540116790647;5819.388964158766;5837.299524057098;5890.499307558863;5699.914501282484;5778.711064446761;5819.854503637401;5836.15375770513;5847.122931560258;5541.473560011793;5670.631408958446;5754.4533269339345;5809.128630705386;5821.214136091616;5623.550115990711;5624.91562626561;5626.9437305626825;5646.830595082154;5690.2887139107615;6119.479844213247;6138.969305153446;6225.221847269091;6258.779537990094;6288.9056664150075;6521.184851893513;6601.6699165041555;6659.4006539411685;6667.233268174174;6775.796726098225;6644.4020038196795;6734.158230221223;6772.69522871472;6776.085228494139;6786;6243.032329988859;6244.344343843922;6259.342769009234;6290.182276304051;6290.465310448617;6854.246032936473;6859.348361905611;6871.415929645936;6964.955256711533;6971.95420686901;6402.103705481236;6553.672316384162;6580;6662.7334906604165;6664.466842652576;6575.309592492719;6649.135612370361;6735.797926062226;6751.662416879137;6756.662166891636;6654.3345665434035;6689.632070236184;6729.394354508117;6732.629705366252;6762.932370676231;6709.7651582194385;6712.093867327848;6786.59280443175;6797.52417330792;6808.148981377328;5282.207668849626;5333.813316533902;5375.677459352373;5385.246065550826;5464.644798088111;4683.250679891201;4691.483936766953;4729.858104256878;4742.099001189763;4781.6891902026255;5679.744411501492;5729;5753.280839895013;5763.711814409263;5767.5674324425;6565.212174539086;6668.133142691419;6676.46588272937;6682.0311054896765;6685.930251159791;5739.569532285095;5745.1382292656435;5773.1051686988585;5872.823815285548;5875.471207591338;5937;5973.761049557969;5995.850103747354;5999.220101386792;6038.396160384014;6476.125723027425;6532.150820393318;6586.934130658728;6600.372964568358;6606.273309935807;6545.14913061299;6683.732650693918;6752.324767523209;6836.213835408793;6836.760713375008;6526.641034742945;6534.379233972765;6536.084948107364;6538.378854008862;6540.117084193763;6553.705083271359;6654.866902661823;6675.566088204251;6676.399124078856;6682.933170668425;6762.256151823394;6779.118714567075;6854.554453960676;6870.20992585859;6871.862562748617;6385.201849768779;6516.250631177286;6523.11937888398;6602.438792702674;6624.436922861418;6458.225012998282;6481.773137940149;6496.220453545605;6497.870042599027;6499.805005849832;6327.588706734047;6426.550141490051;6431.871362572816;6434.260060092962;6434.806955791334;6460.67696615158;6539.673016349068;6550.639714815731;6673.599584024974;6708.329167083252;6405.231372235362;6431.83920401975;6497.31778163305;6504.902426463512;6523.575967562283 05:59:41 INFO - PID 3580 | |1;ext.html;846.5386364431384;872.7076429396152;1147.4262868565718;1168.9006434453072;1250.18113135896;38515;41350.793246033725;41542;41615.79192104039;41971.79014104925;38623.613763862355;38904.80547597265;38946.02634934128;38948.610513894855;38973.22053558927;7237.5657460552275;7317.890231646503;7327.8534429311385;7329.377002954755;7357.963210183968;7143.142822861238;7196;7218.602976836272;7234.457415693818;7243.203247642781;7251.782446526613;7270.345668889799;7665.731699390522;7810.843783124335;7817.374610031212;7709.26761957616;7764.883526747103;7767.883481747778;7771.067471903351;7779.8833017504785;6874.243833178346;6978.825529361756;7489.925100749032;7680.731174408925;7683.654235559413;39103.804480977415;39237.60762392396;39304.80347598272;39427;39525.60474395277;3031.7119873611973;3071.063325685665;3074.2621770774854;3078.076577026883;3080.199148221456;30625.540616890765;30752.53871191934;30843.228919276975;30848.84575577108;30937;5019.749012549358;5032.597392208614;5034.949650503485;5040.697558146523;5056.342675452204;230396;231291;237617;239365.99999999825;239810;44006.11987760227;44533.77733111314;51377.229341559905;51523.93904243863;51624.741876290376;51638.483615163364;52088.47911520912;52298.73850630722;52451.73774131105;52495.47504524982;16187.271572779253;16357.918210408872;16376.83623163777;16391.91804040972;16392;13366.198028118344;13459.730805383837;13483.932580337036;13503.864961350457;13512.189268643717;14313.92843035799;14335.283235838166;14347.92826035863;14440.783388249185;14553.92723036378;5764.942350576441;5897.410258974069;5898.970505147533;5911.822645320647;5984.910226346608;967.579103090158;995.8456439251928;996.1731762636994;1002.6390499420203;1012.8278192707257;22640.207592734176;23385.649215261783;23386.298411047697;23555.646665300035;23811;1062.4581463453694;1073.7745073534645;1075.596651255779;1080.8919108089285;1099.9010089092021;11913.22564033335;12142.878571214174;12165.69585760363;12228.266304021608;12259;246264;247042;247924;248252;249492;51968;51968.740156298976;52141;52141.217881731805;52332.47667523276;1443.1124858212333;1463.5682473670292;1492.1942151238209;1493.1414436698724;1505.0668585477074;22331.44171395728;22454.32637020892;22486.66270005984;22511.212107576157;22563.887180563994;37155.6284437163;37297.440538391405;37408.625913740514;37449.43825842669;37574.06064848401;215414;215542;215685;215697;216218;33374.49938250977;37048.81475592605;39065.60934390734;39125;39165.216695666495;46211.76894115508;48656.51343486662;48735.02529949452;49129.263061053396;49610.50389496059;35279.823600881835;35356.46965295574;35385.82307088448;35436;36003.639963601076;34792.652073478945;35423.64576354204;35495.46756798702;35634.574617016064;36674;39594;39624.207515848946;39689.40465893072;40093;40233.39649905195;34339.4849077269;34589.308213836084;34751.65248347585;34774.652253478154;35707.46438803472;24806.131785387424;25511.87244063768;25656.230313090637;25733.485330293664;25997.480050398506 05:59:41 INFO - PID 3580 | |2;jquery.html;576.3919065386019;588.2117961931011;595.9046552551592;598.2402349016747;609.5123900879296;21843;22676.77323226767;22689.886550567244;22722.88638556809;22746.88626556869;27312.726872731266;27997.86001069997;28220.717792822066;28299.292517687016;28310.575341369844;6766.864662706731;6964.965175174117;7020.578765274075;7020.824479388005;7021.438284937193;6647.601143931355;6960.651967401635;6966.825829354256;6984.580925144508;7027.051348068022;7000.229974702804;7003.649817509104;7004.194517630487;7012.544184628008;7029.7891063267925;6960.303969602999;7000.824979375506;7007.299270072977;7018.789436316919;7031.402330801888;6889.552179108342;6901.551399159039;6904.930950690479;6907.930920690829;6913.066735990677;24622.384440389134;24708.876455617607;24791.008359665593;31052.37895242105;31449.21376965571;1999.4501512084148;2012.6377252094617;2019.6162729081427;2032.2683833819813;2033.9593208135902;20019.899900500404;20589.176432942666;20589.897050514803;20630.793692063038;20689.68965465519;2515.1699939020077;2528.115159694092;2561.0908127614616;2608.8565128917903;2630.7763840073426;144213;147202;147351.2632436831;147451.2627436856;148302;20711.58576828485;21456.678149827763;21527.46181345479;21990.340289791333;22774;44966.55033449689;45101.548984510395;45276.54723452789;45323.320150197775;45362.54637453649;11616.419179041015;11841.88158118425;11977.341246231541;11988.280703157838;12016.879831201752;8521.786955326166;9996.350237234556;10038.698839034842;10068.496575171212;10109.29234953561;9515;11556;11739.423773707591;11815.291082535075;11962.760744785055;4069.918601627951;4154.4183814266025;4304.634106101016;4306.332518459643;4426.336049592587;936.5738588942015;943.7782121201512;949.7055912667096;963.5423173992298;970.825251454745;19673.409797706092;19991;20191.596168076558;20717.482062948246;24386.51226975486;1030.407515678488;1033.7415646088477;1038.6209033702796;1041.3803786746873;1055.630529314749;11366.545338186545;11695.356755378536;11731.35477548726;11747.412629368497;11786.58746943853;539060;552266;553399;554220;572851;123033;124947;126666.36666816792;127298;127325;1280.2524553983744;1306.392527474729;1311.3181145804128;1323.0474058677737;1325.3836965810751;18530.351437699617;18708.719369209746;18710.438686839418;18905.527361816065;19416.90291548533;59767.70116149391;61076.69461652841;61559;62277.68861155665;62930;77991.61004194943;78082;78178.21821781709;78764.21235787569;78812.21187788049;11804.645860624196;12363.62909112728;13949.721005580035;14596.686298232786;14864.62838429048;23201.419964501023;23304.184353547545;23317.88341058284;23342.883285584143;23357;18302.63394732071;18394.908025459787;18657.440276791716;18671.71992420142;18834.717479238097;16115.194240288172;16806.495805125865;16898.66202675915;17080.82919170842;17134.657306854042;17792.733109003635;18956.052197390352;18975.90512047431;19008.239670413583;19175.712364314266;14783.408663653763;14858.108513488793;14864;14931.626709332353;14937.477188298357;18220.45338639843;18224.90887545607;18409.263629454672;18475.630487390445;18495.90752046285 05:59:41 INFO - PID 3580 | |3;mootools.html;1395.4418232706919;1444.6605047813769;1455.2505459688261;1460.8027916231306;1461.0430168239807;14097.083689560184;14309.855211583075;14798.77801832972;14820.6294842629;14823.555293341191;14920.179390133539;15230.619234519143;15298.464553740621;15313.617159571017;15338.69322613547;2470.814688898335;2525.6590360301307;2528.3679080229945;2528.683914510686;2532.4175439648893;2570.498752743216;2662.587298968653;2682.248970288322;2697.743714347134;2744.0807329544627;2742.3829638331317;2774.1954833098375;2783.1233161554146;2785.5821626756046;2787.749102580767;2558.0663057983793;2582.612608108789;2596.156249219002;2637.129747183425;2682.342826007621;2582;2599.4411201591747;2599.7790187834057;2605.5831067029376;2648.708642049373;14215.786763198457;14307.570772876727;14313.928430357782;14341.067830590979;14346.497872574515;7621.275978782007;7892.289693927517;7896.881546776803;7896.921030789676;7910.64402101901;4980.402351717781;5012.699238045729;5087.440381558025;5160.40655324635;5185.55922746567;1791.3730194431976;1794.9820501794945;1808.493621785902;1834.3213011185946;1837.595728939631;24013.639795403084;24543.018279268807;24598.385040373956;24636.630450543078;24659.876700616383;6868.416184624363;7076;7090.113735783028;7258.0927384076995;7269.8546029079125;19101.140448679842;19114.33099841499;19120.426387208405;19174.61650766977;19211.807881921282;7725;7742.341900938371;7953.681852725942;7973.521588704581;7988.960055199686;7061.717531298793;7112.715491380391;7145.178304494945;7150.141982962078;7177;5138.717370544581;5273.2617433559335;5289;5289.814856480081;5334.039872822929;1418.3121186224673;1427.728731541004;1539.6458814472735;1546.7370547007035;1568.1688704986375;821.7781199076215;834.3533761335013;843.5402705525503;853.8078932240196;858.9656413743505;7537.886931696029;7706.422018348646;7719.575423351769;7833.2166783322355;7942.324902383362;987.481572174614;1002.4386343647581;1004.4475538453821;1006.7533454303742;1033.0186322993036;4934.742880475667;4947.678400904002;4954.975225123923;4998.525140111684;5104.591632669451;18492.260309587735;19124.235030598622;19124.33064842724;19137.2345106197;19219.80780192208;19528.21887124499;19607.90196049039;20035.198592056153;20041.799582003994;20058.39824805258;11353.886461135447;11368.488418021043;11405.201635885409;11414.315141091562;11432;7510.549367037996;7812.335951444191;7828.13890472059;7843.921560784319;7990.720324788605;16894.155292235093;17638.559036024202;17646.647067058842;17676.469705908843;17752.644947101246;17428.390006349717;17571.472855814347;17682.381116660854;17744;17857;11846.407679615812;12139.39303034827;12155.87844121583;12180.208286461351;12195.329256890956;13242.735145296847;13494.460221591027;13593.32033398346;13604.115732477358;13614;10894.891051089387;10949.945250274017;11001.779964400506;11070.39112848801;11118.944405277922;10640.255182137176;10815;10931.781364372828;10982.395968221825;10989.395583242998;9214.723558293263;9235.722928311892;9261.72214833556;9311.255099592152;9374.156325930699;6725.663716814041;6843.3840954314355;6914.170299564084;6980.7905762827195;7023.33278338557;7208.243134471015;7345.889811652936;7390.371818395497;7391.667374968247;7441.92558074434 05:59:41 INFO - PID 3580 | |4;prototype.html;504.5711145526301;507.53814029233394;519.9480051994801;526.7919171927089;529.4414392815578;6089.512838972888;6122.693745377502;6167.7841275555365;6244.937550624492;6307.243130824302;6064.817360614682;6070.18052562905;6197.969010154954;6199.156522955763;6218.191635087433;5049.318342023826;5066.594672426216;5129.179331306973;5146.305248791421;5149.227615857614;4816.927746083811;5056.696598204101;5100.214924776317;5106.361704786902;5111.8019251721935;4859.757012149396;4892.535209155142;4953.455119936804;4961.330220420251;4987.251912213179;4906.754662266872;4947.2331788572465;4957.950420495821;4965.478624744404;4983.551480366785;4423.734575925455;4432.401625780542;4459.464864216315;4476.2837945928495;4483.708558943658;6036.728347224342;6071.939280607182;6077.270727512682;6095.146679464879;6121.418465245794;5646.322441307028;5697.344805347397;5721.39925307845;5729.169270455803;5742.913856292159;6524.510661700391;6525.510586706016;6558.2458017328145;6560.475161987029;6603.570767900071;4846.345743324641;4880.389951256093;4885.487023862479;4889.315495830587;4892.19278818998;6586.637734924625;6625.900611490832;6796.150481189802;6839.965800171017;6983.2318444971015;4906.1414252505665;4927.260910863328;4956.231784073474;4961.231009193581;4989.451160372356;5040.747962601855;5043.193089105725;5096.286519887219;5096.413912400048;5097.235414687826;5667.121596152603;5706.457886500776;5721.5708821838525;5734.111212761945;5736.885262294731;6468.805935821839;6475.7733479328;6490.610563366213;6497.675116244169;6564.343565643398;1743.5118166913285;1757.8769486135848;1772.9522401626143;1776.209586733891;1791.408835084419;1155.0932517973517;1215.3558613934629;1229.6434034130182;1242.7203879127123;1243.2229856339789;5662.68855212959;5669.716514174274;5733.856653583611;5783.450572195635;5806.390329015477;6696.229933557605;6707.463402927851;6770.52606317553;6782.830429239309;6826.590404575742;5689.516391106719;5725.8568535786935;5744.770209191585;5783.971080144573;5821.650700957956;3471.7222622190166;3550.4318160248786;3574.160072382988;3610.693091087287;3625.619309972468;6388.744450222032;6408.783480024369;6435.549511534241;6448.226212854487;6506.917786308431;6863.485238607124;6896.689648965808;6898.379145876795;6937.791866244021;6982.371586557234;6507.251666058464;6525.184351956005;6595.4393876521035;6599.472042236705;6599.637019963948;6711.798646040626;6751.864962700817;6779.661016949231;6823.249442561413;6850.760223392157;6693.698783554654;6697.995300704738;6709.496787741036;6772.458203343819;6798.8640227196165;6585.407313341822;6595.318193860727;6610.8347291318105;6640.535162538576;6644.767433139817;5475.87744512382;5513.711809598972;5514.558835293086;5554.722263886709;5573.526250268773;6594.406503414524;6612.371824676648;6720.32796720324;6791.898121528082;6844.760433384808;6442.423030787722;6482.708278127401;6490.123833282447;6521.065520515882;6605.768798092044;6475.222973243241;6475.7733479328;6478.287388387368;6549.770758023446;6596.142501474777;6209.751609935552;6405.967970160119;6406.5515413920575;6413.775517856852;6494.155759751202;6449.1616089909885;6466.838329041811;6528.21661400635;6553.4101930826455;6619.933800662125;6273.592216505913;6275.937240627535;6319.146915166578;6360.554761166674;6398.328175541501 05:59:41 INFO - PID 3580 | |5;yui.html;893.4639216470115;912.2246090822802;924.6902287733609;927.9536023198842;941.1952780372781;18330.72503912441;19467.707984380228;19501.902490487544;19523;19544.31594894179;15534.22328883557;15623.296951637176;17737.91131044343;22462.438439039033;22583.435414114614;1035.2494441529907;1060.6923992042298;1061.0556604621893;1061.1192710050677;1061.1669839176247;1051.4217180550704;1053.4416759117642;1053.4522048534757;1053.7629033467485;1054.6045233037612;1050.086424810416;1096.2490693874681;1102.8125218712837;1103.1230172013268;1103.2332528892423;1094.6332978452228;1094.7974624694377;1094.895984881435;1095.386583513235;1097.0729733375285;796.1441450440799;796.6295672512309;796.6295672512309;797.8723404255292;801.6112185589984;18653.06734663275;18765.812341876543;19075.332363367215;19240.518987025298;20000.79999200004;21696.132154713792;21707.02318395676;22025.559488810293;22042;22256.443588910246;16831.41090061842;16898.239579218964;17080.572985675335;17097.57256068596;17152.48542543726;3423.640517745626;3449.0515108345407;3449.482577613353;3451.758376913617;3452.3267962747273;132214;132335;134867.32566337203;135415;137428;16575.585610359598;16901.408450704414;16966.745498817527;17064.57338566546;17170.22733976974;31816.840915795736;31900.84049579784;32384.676153238637;32442.351152976807;32730;8587.313014958789;8625.353098517637;8674.56627168639;8723.869141962876;8757.2994160467;4015.31739604268;4045.1505183911117;4128.793560321972;4136.379543068504;4137.731047481964;5269.55208807248;5356.384015838151;5376.489233522887;5385.246065550826;5387.407385187705;1045.1482042135635;1104.5195340027115;1114.3926560024806;1119.462657924201;1123.0229700160912;668.669008840627;681.4105798484276;681.8738533371342;683.1324218242804;690.5683947532792;11317.603883864025;11843.28940263587;11984.520619175135;12215.144939854124;12359.505619775287;626.8088233088952;634.8349429148454;661.0645936000601;671.6473851228046;687.8246047257917;5679.119736440858;5744.655320680772;5905.261842269716;5940;6195.628262304276;114941.425292873;116176;116637;118517;122570;90423.54788226017;94922;103565;106643.4667826656;108337;87969;88982;89013;90111;90420.09579904248;11930.4034798258;12257.632271031884;12354.691132721395;12654.609422241709;13146.868531314565;39686.60313396829;41140.97147571335;41942.790286048374;42097.78951105225;42364.576354236066;72429;74276;74720.62639686766;76543.61728191323;77734;19294.421167365002;19581.804181958567;19808.90095549513;19841.900790495954;19995.700064498753;19943.900280498503;20183.39449816508;20414;20436.18255269832;20523.17907283692;17995.2801887923;18157.818421815613;18685.71971420457;18886.150123244213;19021.048947552845;18011.909940450212;18190.45428637143;18322.725159122358;18335.908320458846;18628.81371186325;15910.840891590937;16287.755683664518;17491.12544372802;17940.91029544844;18466.81533184651;14519.49181778675;15069.849301506845;15161.09033457996;15233.466828660943;15366.615834604225;13719.588412347646;13846.446142154202;13906.096103753223;14021.859781402056;14046.016778825384 05:59:41 INFO - PID 3580 | __end_tp_report 05:59:41 INFO - PID 3580 | __start_cc_report 05:59:41 INFO - PID 3580 | _x_x_mozilla_cycle_collect,360 05:59:41 INFO - PID 3580 | __end_cc_report 05:59:41 INFO - PID 3580 | __startTimestamp1503320381780__endTimestamp 05:59:41 INFO - PID 3580 | 05:59:41 INFO - PID 3580 | ------- Summary: start ------- 05:59:41 INFO - PID 3580 | Number of tests: 6 05:59:41 INFO - PID 3580 | 05:59:41 INFO - PID 3580 | [#0] dojo.html Cycles:165 Average:6179.22 Median:6434.26 stddev:724.59 (11.3%) stddev-sans-first:687.62 05:59:41 INFO - PID 3580 | Values: 3182.5 3194.7 3210.0 3220.2 3220.4 6208.0 6292.0 6317.2 6318.9 6340.3 6150.4 6190.0 6196.3 6202.6 6219.4 5652.4 5663.5 5677.7 5695.7 5709.7 5747.8 5748.5 5819.4 5837.3 5890.5 5699.9 5778.7 5819.9 5836.2 5847.1 5541.5 5670.6 5754.5 5809.1 5821.2 5623.6 5624.9 5626.9 5646.8 5690.3 6119.5 6139.0 6225.2 6258.8 6288.9 6521.2 6601.7 6659.4 6667.2 6775.8 6644.4 6734.2 6772.7 6776.1 6786.0 6243.0 6244.3 6259.3 6290.2 6290.5 6854.2 6859.3 6871.4 6965.0 6972.0 6402.1 6553.7 6580.0 6662.7 6664.5 6575.3 6649.1 6735.8 6751.7 6756.7 6654.3 6689.6 6729.4 6732.6 6762.9 6709.8 6712.1 6786.6 6797.5 6808.1 5282.2 5333.8 5375.7 5385.2 5464.6 4683.3 4691.5 4729.9 4742.1 4781.7 5679.7 5729.0 5753.3 5763.7 5767.6 6565.2 6668.1 6676.5 6682.0 6685.9 5739.6 5745.1 5773.1 5872.8 5875.5 5937.0 5973.8 5995.9 5999.2 6038.4 6476.1 6532.2 6586.9 6600.4 6606.3 6545.1 6683.7 6752.3 6836.2 6836.8 6526.6 6534.4 6536.1 6538.4 6540.1 6553.7 6654.9 6675.6 6676.4 6682.9 6762.3 6779.1 6854.6 6870.2 6871.9 6385.2 6516.3 6523.1 6602.4 6624.4 6458.2 6481.8 6496.2 6497.9 6499.8 6327.6 6426.6 6431.9 6434.3 6434.8 6460.7 6539.7 6550.6 6673.6 6708.3 6405.2 6431.8 6497.3 6504.9 6523.6 05:59:41 INFO - PID 3580 | 05:59:41 INFO - PID 3580 | [#1] ext.html Cycles:180 Average:40411.10 Median:36861.41 stddev:60622.63 (164.5%) stddev-sans-first:60719.90 05:59:41 INFO - PID 3580 | Values: 846.5 872.7 1147.4 1168.9 1250.2 38515.0 41350.8 41542.0 41615.8 41971.8 38623.6 38904.8 38946.0 38948.6 38973.2 7237.6 7317.9 7327.9 7329.4 7358.0 7143.1 7196.0 7218.6 7234.5 7243.2 7251.8 7270.3 7665.7 7810.8 7817.4 7709.3 7764.9 7767.9 7771.1 7779.9 6874.2 6978.8 7489.9 7680.7 7683.7 39103.8 39237.6 39304.8 39427.0 39525.6 3031.7 3071.1 3074.3 3078.1 3080.2 30625.5 30752.5 30843.2 30848.8 30937.0 5019.7 5032.6 5034.9 5040.7 5056.3 230396.0 231291.0 237617.0 239366.0 239810.0 44006.1 44533.8 51377.2 51523.9 51624.7 51638.5 52088.5 52298.7 52451.7 52495.5 16187.3 16357.9 16376.8 16391.9 16392.0 13366.2 13459.7 13483.9 13503.9 13512.2 14313.9 14335.3 14347.9 14440.8 14553.9 5764.9 5897.4 5899.0 5911.8 5984.9 967.6 995.8 996.2 1002.6 1012.8 22640.2 23385.6 23386.3 23555.6 23811.0 1062.5 1073.8 1075.6 1080.9 1099.9 11913.2 12142.9 12165.7 12228.3 12259.0 246264.0 247042.0 247924.0 248252.0 249492.0 51968.0 51968.7 52141.0 52141.2 52332.5 1443.1 1463.6 1492.2 1493.1 1505.1 22331.4 22454.3 22486.7 22511.2 22563.9 37155.6 37297.4 37408.6 37449.4 37574.1 215414.0 215542.0 215685.0 215697.0 216218.0 33374.5 37048.8 39065.6 39125.0 39165.2 46211.8 48656.5 48735.0 49129.3 49610.5 35279.8 35356.5 35385.8 35436.0 36003.6 34792.7 35423.6 35495.5 35634.6 36674.0 39594.0 39624.2 39689.4 40093.0 40233.4 34339.5 34589.3 34751.7 34774.7 35707.5 24806.1 25511.9 25656.2 25733.5 25997.5 05:59:41 INFO - PID 3580 | 05:59:41 INFO - PID 3580 | [#2] jquery.html Cycles:180 Average:38569.78 Median:22333.56 stddev:93063.33 (416.7%) stddev-sans-first:93280.67 05:59:41 INFO - PID 3580 | Values: 576.4 588.2 595.9 598.2 609.5 21843.0 22676.8 22689.9 22722.9 22746.9 27312.7 27997.9 28220.7 28299.3 28310.6 6766.9 6965.0 7020.6 7020.8 7021.4 6647.6 6960.7 6966.8 6984.6 7027.1 7000.2 7003.6 7004.2 7012.5 7029.8 6960.3 7000.8 7007.3 7018.8 7031.4 6889.6 6901.6 6904.9 6907.9 6913.1 24622.4 24708.9 24791.0 31052.4 31449.2 1999.5 2012.6 2019.6 2032.3 2034.0 20019.9 20589.2 20589.9 20630.8 20689.7 2515.2 2528.1 2561.1 2608.9 2630.8 144213.0 147202.0 147351.3 147451.3 148302.0 20711.6 21456.7 21527.5 21990.3 22774.0 44966.6 45101.5 45276.5 45323.3 45362.5 11616.4 11841.9 11977.3 11988.3 12016.9 8521.8 9996.4 10038.7 10068.5 10109.3 9515.0 11556.0 11739.4 11815.3 11962.8 4069.9 4154.4 4304.6 4306.3 4426.3 936.6 943.8 949.7 963.5 970.8 19673.4 19991.0 20191.6 20717.5 24386.5 1030.4 1033.7 1038.6 1041.4 1055.6 11366.5 11695.4 11731.4 11747.4 11786.6 539060.0 552266.0 553399.0 554220.0 572851.0 123033.0 124947.0 126666.4 127298.0 127325.0 1280.3 1306.4 1311.3 1323.0 1325.4 18530.4 18708.7 18710.4 18905.5 19416.9 59767.7 61076.7 61559.0 62277.7 62930.0 77991.6 78082.0 78178.2 78764.2 78812.2 11804.6 12363.6 13949.7 14596.7 14864.6 23201.4 23304.2 23317.9 23342.9 23357.0 18302.6 18394.9 18657.4 18671.7 18834.7 16115.2 16806.5 16898.7 17080.8 17134.7 17792.7 18956.1 18975.9 19008.2 19175.7 14783.4 14858.1 14864.0 14931.6 14937.5 18220.5 18224.9 18409.3 18475.6 18495.9 05:59:41 INFO - PID 3580 | 05:59:41 INFO - PID 3580 | [#3] mootools.html Cycles:180 Average:9023.20 Median:2527.01 stddev:6283.64 (248.7%) stddev-sans-first:6275.13 05:59:41 INFO - PID 3580 | Values: 1395.4 1444.7 1455.3 1460.8 1461.0 14097.1 14309.9 14798.8 14820.6 14823.6 14920.2 15230.6 15298.5 15313.6 15338.7 2470.8 2525.7 2528.4 2528.7 2532.4 2570.5 2662.6 2682.2 2697.7 2744.1 2742.4 2774.2 2783.1 2785.6 2787.7 2558.1 2582.6 2596.2 2637.1 2682.3 2582.0 2599.4 2599.8 2605.6 2648.7 14215.8 14307.6 14313.9 14341.1 14346.5 7621.3 7892.3 7896.9 7896.9 7910.6 4980.4 5012.7 5087.4 5160.4 5185.6 1791.4 1795.0 1808.5 1834.3 1837.6 24013.6 24543.0 24598.4 24636.6 24659.9 6868.4 7076.0 7090.1 7258.1 7269.9 19101.1 19114.3 19120.4 19174.6 19211.8 7725.0 7742.3 7953.7 7973.5 7989.0 7061.7 7112.7 7145.2 7150.1 7177.0 5138.7 5273.3 5289.0 5289.8 5334.0 1418.3 1427.7 1539.6 1546.7 1568.2 821.8 834.4 843.5 853.8 859.0 7537.9 7706.4 7719.6 7833.2 7942.3 987.5 1002.4 1004.4 1006.8 1033.0 4934.7 4947.7 4955.0 4998.5 5104.6 18492.3 19124.2 19124.3 19137.2 19219.8 19528.2 19607.9 20035.2 20041.8 20058.4 11353.9 11368.5 11405.2 11414.3 11432.0 7510.5 7812.3 7828.1 7843.9 7990.7 16894.2 17638.6 17646.6 17676.5 17752.6 17428.4 17571.5 17682.4 17744.0 17857.0 11846.4 12139.4 12155.9 12180.2 12195.3 13242.7 13494.5 13593.3 13604.1 13614.0 10894.9 10949.9 11001.8 11070.4 11118.9 10640.3 10815.0 10931.8 10982.4 10989.4 9214.7 9235.7 9261.7 9311.3 9374.2 6725.7 6843.4 6914.2 6980.8 7023.3 7208.2 7345.9 7390.4 7391.7 7441.9 05:59:41 INFO - PID 3580 | 05:59:41 INFO - PID 3580 | [#4] prototype.html Cycles:180 Average:5525.14 Median:6092.33 stddev:1541.49 (25.3%) stddev-sans-first:1499.04 05:59:41 INFO - PID 3580 | Values: 504.6 507.5 519.9 526.8 529.4 6089.5 6122.7 6167.8 6244.9 6307.2 6064.8 6070.2 6198.0 6199.2 6218.2 5049.3 5066.6 5129.2 5146.3 5149.2 4816.9 5056.7 5100.2 5106.4 5111.8 4859.8 4892.5 4953.5 4961.3 4987.3 4906.8 4947.2 4958.0 4965.5 4983.6 4423.7 4432.4 4459.5 4476.3 4483.7 6036.7 6071.9 6077.3 6095.1 6121.4 5646.3 5697.3 5721.4 5729.2 5742.9 6524.5 6525.5 6558.2 6560.5 6603.6 4846.3 4880.4 4885.5 4889.3 4892.2 6586.6 6625.9 6796.2 6840.0 6983.2 4906.1 4927.3 4956.2 4961.2 4989.5 5040.7 5043.2 5096.3 5096.4 5097.2 5667.1 5706.5 5721.6 5734.1 5736.9 6468.8 6475.8 6490.6 6497.7 6564.3 1743.5 1757.9 1773.0 1776.2 1791.4 1155.1 1215.4 1229.6 1242.7 1243.2 5662.7 5669.7 5733.9 5783.5 5806.4 6696.2 6707.5 6770.5 6782.8 6826.6 5689.5 5725.9 5744.8 5784.0 5821.7 3471.7 3550.4 3574.2 3610.7 3625.6 6388.7 6408.8 6435.5 6448.2 6506.9 6863.5 6896.7 6898.4 6937.8 6982.4 6507.3 6525.2 6595.4 6599.5 6599.6 6711.8 6751.9 6779.7 6823.2 6850.8 6693.7 6698.0 6709.5 6772.5 6798.9 6585.4 6595.3 6610.8 6640.5 6644.8 5475.9 5513.7 5514.6 5554.7 5573.5 6594.4 6612.4 6720.3 6791.9 6844.8 6442.4 6482.7 6490.1 6521.1 6605.8 6475.2 6475.8 6478.3 6549.8 6596.1 6209.8 6406.0 6406.6 6413.8 6494.2 6449.2 6466.8 6528.2 6553.4 6619.9 6273.6 6275.9 6319.1 6360.6 6398.3 05:59:41 INFO - PID 3580 | 05:59:41 INFO - PID 3580 | [#5] yui.html Cycles:180 Average:24655.99 Median:19267.47 stddev:34043.94 (176.7%) stddev-sans-first:34092.69 05:59:41 INFO - PID 3580 | Values: 893.5 912.2 924.7 928.0 941.2 18330.7 19467.7 19501.9 19523.0 19544.3 15534.2 15623.3 17737.9 22462.4 22583.4 1035.2 1060.7 1061.1 1061.1 1061.2 1051.4 1053.4 1053.5 1053.8 1054.6 1050.1 1096.2 1102.8 1103.1 1103.2 1094.6 1094.8 1094.9 1095.4 1097.1 796.1 796.6 796.6 797.9 801.6 18653.1 18765.8 19075.3 19240.5 20000.8 21696.1 21707.0 22025.6 22042.0 22256.4 16831.4 16898.2 17080.6 17097.6 17152.5 3423.6 3449.1 3449.5 3451.8 3452.3 132214.0 132335.0 134867.3 135415.0 137428.0 16575.6 16901.4 16966.7 17064.6 17170.2 31816.8 31900.8 32384.7 32442.4 32730.0 8587.3 8625.4 8674.6 8723.9 8757.3 4015.3 4045.2 4128.8 4136.4 4137.7 5269.6 5356.4 5376.5 5385.2 5387.4 1045.1 1104.5 1114.4 1119.5 1123.0 668.7 681.4 681.9 683.1 690.6 11317.6 11843.3 11984.5 12215.1 12359.5 626.8 634.8 661.1 671.6 687.8 5679.1 5744.7 5905.3 5940.0 6195.6 114941.4 116176.0 116637.0 118517.0 122570.0 90423.5 94922.0 103565.0 106643.5 108337.0 87969.0 88982.0 89013.0 90111.0 90420.1 11930.4 12257.6 12354.7 12654.6 13146.9 39686.6 41141.0 41942.8 42097.8 42364.6 72429.0 74276.0 74720.6 76543.6 77734.0 19294.4 19581.8 19808.9 19841.9 19995.7 19943.9 20183.4 20414.0 20436.2 20523.2 17995.3 18157.8 18685.7 18886.2 19021.0 18011.9 18190.5 18322.7 18335.9 18628.8 15910.8 16287.8 17491.1 17940.9 18466.8 14519.5 15069.8 15161.1 15233.5 15366.6 13719.6 13846.4 13906.1 14021.9 14046.0 05:59:41 INFO - PID 3580 | -------- Summary: end -------- 05:59:41 INFO - PID 3580 | 05:59:42 INFO - TEST-INFO | 3580: exit 0 05:59:42 INFO - TEST-OK | dromaeo_css | took 1115591ms 05:59:42 INFO - TEST-START | kraken 05:59:42 INFO - Initialising browser for kraken test... 05:59:42 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45914/getInfo.html -profile /tmp/tmpzojiEc/profile 05:59:42 INFO - TEST-INFO | started process 4036 (/builds/slave/test/build/application/firefox/firefox http://localhost:45914/getInfo.html) 05:59:48 INFO - TEST-INFO | 4036: exit 0 05:59:48 INFO - Browser initialized. 05:59:48 INFO - Running cycle 1/1 for kraken test... 05:59:48 INFO - TEST-INFO | started process 4225 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzojiEc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:59:49 INFO - PID 4225 | 05:59:49 INFO - PID 4225 | (/builds/slave/test/build/application/firefox/firefox:4275): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:59:49 INFO - PID 4225 | 05:59:50 INFO - PID 4225 | 05:59:50 INFO - PID 4225 | (/builds/slave/test/build/application/firefox/firefox:4337): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:59:50 INFO - PID 4225 | 05:59:50 INFO - PID 4225 | __metrics Screen width/height:1600/1200 05:59:50 INFO - PID 4225 | colorDepth:24 05:59:50 INFO - PID 4225 | Browser inner width/height: 1024/768 05:59:50 INFO - PID 4225 | __metrics 05:59:59 INFO - PID 4225 | RSS: Main: 174206976 05:59:59 INFO - PID 4225 | 06:00:02 INFO - PID 4225 | Cycle 1(1): loaded http://localhost:45914/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45914/tests/kraken/driver.html?testName=audio-beat-detection) 06:00:03 INFO - PID 4225 | RSS: Main: 178106368 06:00:03 INFO - PID 4225 | 06:00:06 INFO - PID 4225 | Cycle 1(1): loaded http://localhost:45914/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45914/tests/kraken/driver.html?testName=audio-dft) 06:00:06 INFO - PID 4225 | RSS: Main: 180658176 06:00:06 INFO - PID 4225 | 06:00:09 INFO - PID 4225 | Cycle 1(1): loaded http://localhost:45914/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45914/tests/kraken/driver.html?testName=audio-fft) 06:00:10 INFO - PID 4225 | RSS: Main: 174096384 06:00:10 INFO - PID 4225 | 06:00:12 INFO - PID 4225 | Cycle 1(1): loaded http://localhost:45914/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45914/tests/kraken/driver.html?testName=audio-oscillator) 06:00:13 INFO - PID 4225 | RSS: Main: 174690304 06:00:13 INFO - PID 4225 | 06:00:15 INFO - PID 4225 | Cycle 1(1): loaded http://localhost:45914/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45914/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:00:16 INFO - PID 4225 | RSS: Main: 175460352 06:00:16 INFO - PID 4225 | 06:00:19 INFO - PID 4225 | Cycle 1(1): loaded http://localhost:45914/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45914/tests/kraken/driver.html?testName=imaging-darkroom) 06:00:19 INFO - PID 4225 | RSS: Main: 176640000 06:00:19 INFO - PID 4225 | 06:00:23 INFO - PID 4225 | Cycle 1(1): loaded http://localhost:45914/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45914/tests/kraken/driver.html?testName=imaging-desaturate) 06:00:24 INFO - PID 4225 | RSS: Main: 176328704 06:00:24 INFO - PID 4225 | 06:00:27 INFO - PID 4225 | Cycle 1(1): loaded http://localhost:45914/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45914/tests/kraken/driver.html?testName=json-parse-financial) 06:00:27 INFO - PID 4225 | RSS: Main: 176812032 06:00:27 INFO - PID 4225 | 06:00:29 INFO - PID 4225 | Cycle 1(1): loaded http://localhost:45914/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45914/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:00:30 INFO - PID 4225 | RSS: Main: 176734208 06:00:30 INFO - PID 4225 | 06:00:31 INFO - PID 4225 | Cycle 1(1): loaded http://localhost:45914/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45914/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:00:32 INFO - PID 4225 | RSS: Main: 176799744 06:00:32 INFO - PID 4225 | 06:00:34 INFO - PID 4225 | Cycle 1(1): loaded http://localhost:45914/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45914/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:00:35 INFO - PID 4225 | RSS: Main: 176820224 06:00:35 INFO - PID 4225 | 06:00:38 INFO - PID 4225 | Cycle 1(1): loaded http://localhost:45914/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45914/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:00:38 INFO - PID 4225 | RSS: Main: 177127424 06:00:38 INFO - PID 4225 | 06:00:41 INFO - PID 4225 | Cycle 1(1): loaded http://localhost:45914/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45914/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:00:42 INFO - PID 4225 | RSS: Main: 177532928 06:00:42 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | Cycle 1(1): loaded http://localhost:45914/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45914/tests/kraken/driver.html?testName=ai-astar) 06:00:44 INFO - PID 4225 | RSS: Main: 178479104 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | __start_tp_report 06:00:44 INFO - PID 4225 | _x_x_mozilla_page_load 06:00:44 INFO - PID 4225 | _x_x_mozilla_page_load_details 06:00:44 INFO - PID 4225 | |i|pagename|runs| 06:00:44 INFO - PID 4225 | |0;ai-astar;98;108;100;110;109;116;108;100;108;111 06:00:44 INFO - PID 4225 | |1;audio-beat-detection;143;123;146;191;121;153;143;121;179;121 06:00:44 INFO - PID 4225 | |2;audio-dft;164;165;163;168;163;161;167;166;163;176 06:00:44 INFO - PID 4225 | |3;audio-fft;74;75;76;173;75;77;73;75;172;79 06:00:44 INFO - PID 4225 | |4;audio-oscillator;87;79;142;80;88;83;128;82;79;129 06:00:44 INFO - PID 4225 | |5;imaging-gaussian-blur;114;114;116;120;114;114;120;114;118;114 06:00:44 INFO - PID 4225 | |6;imaging-darkroom;153;152;152;153;150;150;151;152;151;150 06:00:44 INFO - PID 4225 | |7;imaging-desaturate;96;98;99;94;94;94;94;94;94;95 06:00:44 INFO - PID 4225 | |8;json-parse-financial;58;59;60;57;62;57;55;57;60;58 06:00:44 INFO - PID 4225 | |9;json-stringify-tinderbox;55;55;59;57;58;59;59;55;55;56 06:00:44 INFO - PID 4225 | |10;stanford-crypto-aes;83;76;75;83;75;78;77;75;76;79 06:00:44 INFO - PID 4225 | |11;stanford-crypto-ccm;126;130;127;134;122;121;121;120;126;158 06:00:44 INFO - PID 4225 | |12;stanford-crypto-pbkdf2;174;175;176;170;183;172;181;168;176;167 06:00:44 INFO - PID 4225 | |13;stanford-crypto-sha256-iterative;71;70;70;67;69;70;69;67;67;70 06:00:44 INFO - PID 4225 | __end_tp_report 06:00:44 INFO - PID 4225 | __start_cc_report 06:00:44 INFO - PID 4225 | _x_x_mozilla_cycle_collect,963 06:00:44 INFO - PID 4225 | __end_cc_report 06:00:44 INFO - PID 4225 | __startTimestamp1503320444581__endTimestamp 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | ------- Summary: start ------- 06:00:44 INFO - PID 4225 | Number of tests: 14 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | [#0] ai-astar Cycles:10 Average:106.80 Median:109.50 stddev:5.69 (5.2%) stddev-sans-first:5.07 06:00:44 INFO - PID 4225 | Values: 98.0 108.0 100.0 110.0 109.0 116.0 108.0 100.0 108.0 111.0 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | [#1] audio-beat-detection Cycles:10 Average:144.10 Median:144.50 stddev:24.83 (17.2%) stddev-sans-first:26.33 06:00:44 INFO - PID 4225 | Values: 143.0 123.0 146.0 191.0 121.0 153.0 143.0 121.0 179.0 121.0 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | [#2] audio-dft Cycles:10 Average:165.60 Median:165.50 stddev:4.22 (2.6%) stddev-sans-first:4.44 06:00:44 INFO - PID 4225 | Values: 164.0 165.0 163.0 168.0 163.0 161.0 167.0 166.0 163.0 176.0 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | [#3] audio-fft Cycles:10 Average:94.90 Median:75.00 stddev:40.93 (54.6%) stddev-sans-first:42.71 06:00:44 INFO - PID 4225 | Values: 74.0 75.0 76.0 173.0 75.0 77.0 73.0 75.0 172.0 79.0 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:81.00 stddev:24.82 (30.6%) stddev-sans-first:26.02 06:00:44 INFO - PID 4225 | Values: 87.0 79.0 142.0 80.0 88.0 83.0 128.0 82.0 79.0 129.0 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | [#5] imaging-gaussian-blur Cycles:10 Average:115.80 Median:115.00 stddev:2.57 (2.2%) stddev-sans-first:2.65 06:00:44 INFO - PID 4225 | Values: 114.0 114.0 116.0 120.0 114.0 114.0 120.0 114.0 118.0 114.0 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | [#6] imaging-darkroom Cycles:10 Average:151.40 Median:152.00 stddev:1.17 (0.8%) stddev-sans-first:1.09 06:00:44 INFO - PID 4225 | Values: 153.0 152.0 152.0 153.0 150.0 150.0 151.0 152.0 151.0 150.0 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:94.50 stddev:1.87 (2.0%) stddev-sans-first:1.96 06:00:44 INFO - PID 4225 | Values: 96.0 98.0 99.0 94.0 94.0 94.0 94.0 94.0 94.0 95.0 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:58.50 stddev:2.00 (3.4%) stddev-sans-first:2.12 06:00:44 INFO - PID 4225 | Values: 58.0 59.0 60.0 57.0 62.0 57.0 55.0 57.0 60.0 58.0 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | [#9] json-stringify-tinderbox Cycles:10 Average:56.80 Median:57.50 stddev:1.81 (3.2%) stddev-sans-first:1.80 06:00:44 INFO - PID 4225 | Values: 55.0 55.0 59.0 57.0 58.0 59.0 59.0 55.0 55.0 56.0 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | [#10] stanford-crypto-aes Cycles:10 Average:77.70 Median:77.50 stddev:3.09 (4.0%) stddev-sans-first:2.62 06:00:44 INFO - PID 4225 | Values: 83.0 76.0 75.0 83.0 75.0 78.0 77.0 75.0 76.0 79.0 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | [#11] stanford-crypto-ccm Cycles:10 Average:128.50 Median:126.50 stddev:11.28 (8.9%) stddev-sans-first:11.92 06:00:44 INFO - PID 4225 | Values: 126.0 130.0 127.0 134.0 122.0 121.0 121.0 120.0 126.0 158.0 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.20 Median:175.50 stddev:5.20 (3.0%) stddev-sans-first:5.52 06:00:44 INFO - PID 4225 | Values: 174.0 175.0 176.0 170.0 183.0 172.0 181.0 168.0 176.0 167.0 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:70.00 stddev:1.49 (2.1%) stddev-sans-first:1.39 06:00:44 INFO - PID 4225 | Values: 71.0 70.0 70.0 67.0 69.0 70.0 69.0 67.0 67.0 70.0 06:00:44 INFO - PID 4225 | -------- Summary: end -------- 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150079,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | 06:00:44 INFO - PID 4225 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 06:00:44 INFO - PID 4225 | 06:00:45 INFO - PID 4225 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 06:00:45 INFO - TEST-INFO | 4225: exit 0 06:00:45 INFO - TEST-OK | kraken | took 62902ms 06:00:45 INFO - SUITE-END | took 1178s 06:00:45 INFO - Completed test suite (00:19:38) 06:00:45 INFO - javascript benchmark 06:00:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8701.166547250077, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3182.522621606757, 3194.680531946806, 3210.0, 3220.2432428379334, 3220.404225218335, 6208.0, 6292.0, 6317.241930968285, 6318.905216421757, 6340.3025667176635, 6150.4157105075055, 6190.0405437157315, 6196.318404975449, 6202.596831205969, 6219.440250377465, 5652.434756524335, 5663.490285874271, 5677.659340439566, 5695.743691533891, 5709.714514274269, 5747.827565173052, 5748.540116790647, 5819.388964158766, 5837.299524057098, 5890.499307558863, 5699.914501282484, 5778.711064446761, 5819.854503637401, 5836.15375770513, 5847.122931560258, 5541.473560011793, 5670.631408958446, 5754.4533269339345, 5809.128630705386, 5821.214136091616, 5623.550115990711, 5624.91562626561, 5626.9437305626825, 5646.830595082154, 5690.2887139107615, 6119.479844213247, 6138.969305153446, 6225.221847269091, 6258.779537990094, 6288.9056664150075, 6521.184851893513, 6601.6699165041555, 6659.4006539411685, 6667.233268174174, 6775.796726098225, 6644.4020038196795, 6734.158230221223, 6772.69522871472, 6776.085228494139, 6786.0, 6243.032329988859, 6244.344343843922, 6259.342769009234, 6290.182276304051, 6290.465310448617, 6854.246032936473, 6859.348361905611, 6871.415929645936, 6964.955256711533, 6971.95420686901, 6402.103705481236, 6553.672316384162, 6580.0, 6662.7334906604165, 6664.466842652576, 6575.309592492719, 6649.135612370361, 6735.797926062226, 6751.662416879137, 6756.662166891636, 6654.3345665434035, 6689.632070236184, 6729.394354508117, 6732.629705366252, 6762.932370676231, 6709.7651582194385, 6712.093867327848, 6786.59280443175, 6797.52417330792, 6808.148981377328, 5282.207668849626, 5333.813316533902, 5375.677459352373, 5385.246065550826, 5464.644798088111, 4683.250679891201, 4691.483936766953, 4729.858104256878, 4742.099001189763, 4781.6891902026255, 5679.744411501492, 5729.0, 5753.280839895013, 5763.711814409263, 5767.5674324425, 6565.212174539086, 6668.133142691419, 6676.46588272937, 6682.0311054896765, 6685.930251159791, 5739.569532285095, 5745.1382292656435, 5773.1051686988585, 5872.823815285548, 5875.471207591338, 5937.0, 5973.761049557969, 5995.850103747354, 5999.220101386792, 6038.396160384014, 6476.125723027425, 6532.150820393318, 6586.934130658728, 6600.372964568358, 6606.273309935807, 6545.14913061299, 6683.732650693918, 6752.324767523209, 6836.213835408793, 6836.760713375008, 6526.641034742945, 6534.379233972765, 6536.084948107364, 6538.378854008862, 6540.117084193763, 6553.705083271359, 6654.866902661823, 6675.566088204251, 6676.399124078856, 6682.933170668425, 6762.256151823394, 6779.118714567075, 6854.554453960676, 6870.20992585859, 6871.862562748617, 6385.201849768779, 6516.250631177286, 6523.11937888398, 6602.438792702674, 6624.436922861418, 6458.225012998282, 6481.773137940149, 6496.220453545605, 6497.870042599027, 6499.805005849832, 6327.588706734047, 6426.550141490051, 6431.871362572816, 6434.260060092962, 6434.806955791334, 6460.67696615158, 6539.673016349068, 6550.639714815731, 6673.599584024974, 6708.329167083252, 6405.231372235362, 6431.83920401975, 6497.31778163305, 6504.902426463512, 6523.575967562283], "value": 6125.6115509615465, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [846.5386364431384, 872.7076429396152, 1147.4262868565718, 1168.9006434453072, 1250.18113135896, 38515.0, 41350.793246033725, 41542.0, 41615.79192104039, 41971.79014104925, 38623.613763862355, 38904.80547597265, 38946.02634934128, 38948.610513894855, 38973.22053558927, 7237.5657460552275, 7317.890231646503, 7327.8534429311385, 7329.377002954755, 7357.963210183968, 7143.142822861238, 7196.0, 7218.602976836272, 7234.457415693818, 7243.203247642781, 7251.782446526613, 7270.345668889799, 7665.731699390522, 7810.843783124335, 7817.374610031212, 7709.26761957616, 7764.883526747103, 7767.883481747778, 7771.067471903351, 7779.8833017504785, 6874.243833178346, 6978.825529361756, 7489.925100749032, 7680.731174408925, 7683.654235559413, 39103.804480977415, 39237.60762392396, 39304.80347598272, 39427.0, 39525.60474395277, 3031.7119873611973, 3071.063325685665, 3074.2621770774854, 3078.076577026883, 3080.199148221456, 30625.540616890765, 30752.53871191934, 30843.228919276975, 30848.84575577108, 30937.0, 5019.749012549358, 5032.597392208614, 5034.949650503485, 5040.697558146523, 5056.342675452204, 230396.0, 231291.0, 237617.0, 239365.99999999825, 239810.0, 44006.11987760227, 44533.77733111314, 51377.229341559905, 51523.93904243863, 51624.741876290376, 51638.483615163364, 52088.47911520912, 52298.73850630722, 52451.73774131105, 52495.47504524982, 16187.271572779253, 16357.918210408872, 16376.83623163777, 16391.91804040972, 16392.0, 13366.198028118344, 13459.730805383837, 13483.932580337036, 13503.864961350457, 13512.189268643717, 14313.92843035799, 14335.283235838166, 14347.92826035863, 14440.783388249185, 14553.92723036378, 5764.942350576441, 5897.410258974069, 5898.970505147533, 5911.822645320647, 5984.910226346608, 967.579103090158, 995.8456439251928, 996.1731762636994, 1002.6390499420203, 1012.8278192707257, 22640.207592734176, 23385.649215261783, 23386.298411047697, 23555.646665300035, 23811.0, 1062.4581463453694, 1073.7745073534645, 1075.596651255779, 1080.8919108089285, 1099.9010089092021, 11913.22564033335, 12142.878571214174, 12165.69585760363, 12228.266304021608, 12259.0, 246264.0, 247042.0, 247924.0, 248252.0, 249492.0, 51968.0, 51968.740156298976, 52141.0, 52141.217881731805, 52332.47667523276, 1443.1124858212333, 1463.5682473670292, 1492.1942151238209, 1493.1414436698724, 1505.0668585477074, 22331.44171395728, 22454.32637020892, 22486.66270005984, 22511.212107576157, 22563.887180563994, 37155.6284437163, 37297.440538391405, 37408.625913740514, 37449.43825842669, 37574.06064848401, 215414.0, 215542.0, 215685.0, 215697.0, 216218.0, 33374.49938250977, 37048.81475592605, 39065.60934390734, 39125.0, 39165.216695666495, 46211.76894115508, 48656.51343486662, 48735.02529949452, 49129.263061053396, 49610.50389496059, 35279.823600881835, 35356.46965295574, 35385.82307088448, 35436.0, 36003.639963601076, 34792.652073478945, 35423.64576354204, 35495.46756798702, 35634.574617016064, 36674.0, 39594.0, 39624.207515848946, 39689.40465893072, 40093.0, 40233.39649905195, 34339.4849077269, 34589.308213836084, 34751.65248347585, 34774.652253478154, 35707.46438803472, 24806.131785387424, 25511.87244063768, 25656.230313090637, 25733.485330293664, 25997.480050398506], "value": 17599.658358203043, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.3919065386019, 588.2117961931011, 595.9046552551592, 598.2402349016747, 609.5123900879296, 21843.0, 22676.77323226767, 22689.886550567244, 22722.88638556809, 22746.88626556869, 27312.726872731266, 27997.86001069997, 28220.717792822066, 28299.292517687016, 28310.575341369844, 6766.864662706731, 6964.965175174117, 7020.578765274075, 7020.824479388005, 7021.438284937193, 6647.601143931355, 6960.651967401635, 6966.825829354256, 6984.580925144508, 7027.051348068022, 7000.229974702804, 7003.649817509104, 7004.194517630487, 7012.544184628008, 7029.7891063267925, 6960.303969602999, 7000.824979375506, 7007.299270072977, 7018.789436316919, 7031.402330801888, 6889.552179108342, 6901.551399159039, 6904.930950690479, 6907.930920690829, 6913.066735990677, 24622.384440389134, 24708.876455617607, 24791.008359665593, 31052.37895242105, 31449.21376965571, 1999.4501512084148, 2012.6377252094617, 2019.6162729081427, 2032.2683833819813, 2033.9593208135902, 20019.899900500404, 20589.176432942666, 20589.897050514803, 20630.793692063038, 20689.68965465519, 2515.1699939020077, 2528.115159694092, 2561.0908127614616, 2608.8565128917903, 2630.7763840073426, 144213.0, 147202.0, 147351.2632436831, 147451.2627436856, 148302.0, 20711.58576828485, 21456.678149827763, 21527.46181345479, 21990.340289791333, 22774.0, 44966.55033449689, 45101.548984510395, 45276.54723452789, 45323.320150197775, 45362.54637453649, 11616.419179041015, 11841.88158118425, 11977.341246231541, 11988.280703157838, 12016.879831201752, 8521.786955326166, 9996.350237234556, 10038.698839034842, 10068.496575171212, 10109.29234953561, 9515.0, 11556.0, 11739.423773707591, 11815.291082535075, 11962.760744785055, 4069.918601627951, 4154.4183814266025, 4304.634106101016, 4306.332518459643, 4426.336049592587, 936.5738588942015, 943.7782121201512, 949.7055912667096, 963.5423173992298, 970.825251454745, 19673.409797706092, 19991.0, 20191.596168076558, 20717.482062948246, 24386.51226975486, 1030.407515678488, 1033.7415646088477, 1038.6209033702796, 1041.3803786746873, 1055.630529314749, 11366.545338186545, 11695.356755378536, 11731.35477548726, 11747.412629368497, 11786.58746943853, 539060.0, 552266.0, 553399.0, 554220.0, 572851.0, 123033.0, 124947.0, 126666.36666816792, 127298.0, 127325.0, 1280.2524553983744, 1306.392527474729, 1311.3181145804128, 1323.0474058677737, 1325.3836965810751, 18530.351437699617, 18708.719369209746, 18710.438686839418, 18905.527361816065, 19416.90291548533, 59767.70116149391, 61076.69461652841, 61559.0, 62277.68861155665, 62930.0, 77991.61004194943, 78082.0, 78178.21821781709, 78764.21235787569, 78812.21187788049, 11804.645860624196, 12363.62909112728, 13949.721005580035, 14596.686298232786, 14864.62838429048, 23201.419964501023, 23304.184353547545, 23317.88341058284, 23342.883285584143, 23357.0, 18302.63394732071, 18394.908025459787, 18657.440276791716, 18671.71992420142, 18834.717479238097, 16115.194240288172, 16806.495805125865, 16898.66202675915, 17080.82919170842, 17134.657306854042, 17792.733109003635, 18956.052197390352, 18975.90512047431, 19008.239670413583, 19175.712364314266, 14783.408663653763, 14858.108513488793, 14864.0, 14931.626709332353, 14937.477188298357, 18220.45338639843, 18224.90887545607, 18409.263629454672, 18475.630487390445, 18495.90752046285], "value": 13051.705056446252, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1395.4418232706919, 1444.6605047813769, 1455.2505459688261, 1460.8027916231306, 1461.0430168239807, 14097.083689560184, 14309.855211583075, 14798.77801832972, 14820.6294842629, 14823.555293341191, 14920.179390133539, 15230.619234519143, 15298.464553740621, 15313.617159571017, 15338.69322613547, 2470.814688898335, 2525.6590360301307, 2528.3679080229945, 2528.683914510686, 2532.4175439648893, 2570.498752743216, 2662.587298968653, 2682.248970288322, 2697.743714347134, 2744.0807329544627, 2742.3829638331317, 2774.1954833098375, 2783.1233161554146, 2785.5821626756046, 2787.749102580767, 2558.0663057983793, 2582.612608108789, 2596.156249219002, 2637.129747183425, 2682.342826007621, 2582.0, 2599.4411201591747, 2599.7790187834057, 2605.5831067029376, 2648.708642049373, 14215.786763198457, 14307.570772876727, 14313.928430357782, 14341.067830590979, 14346.497872574515, 7621.275978782007, 7892.289693927517, 7896.881546776803, 7896.921030789676, 7910.64402101901, 4980.402351717781, 5012.699238045729, 5087.440381558025, 5160.40655324635, 5185.55922746567, 1791.3730194431976, 1794.9820501794945, 1808.493621785902, 1834.3213011185946, 1837.595728939631, 24013.639795403084, 24543.018279268807, 24598.385040373956, 24636.630450543078, 24659.876700616383, 6868.416184624363, 7076.0, 7090.113735783028, 7258.0927384076995, 7269.8546029079125, 19101.140448679842, 19114.33099841499, 19120.426387208405, 19174.61650766977, 19211.807881921282, 7725.0, 7742.341900938371, 7953.681852725942, 7973.521588704581, 7988.960055199686, 7061.717531298793, 7112.715491380391, 7145.178304494945, 7150.141982962078, 7177.0, 5138.717370544581, 5273.2617433559335, 5289.0, 5289.814856480081, 5334.039872822929, 1418.3121186224673, 1427.728731541004, 1539.6458814472735, 1546.7370547007035, 1568.1688704986375, 821.7781199076215, 834.3533761335013, 843.5402705525503, 853.8078932240196, 858.9656413743505, 7537.886931696029, 7706.422018348646, 7719.575423351769, 7833.2166783322355, 7942.324902383362, 987.481572174614, 1002.4386343647581, 1004.4475538453821, 1006.7533454303742, 1033.0186322993036, 4934.742880475667, 4947.678400904002, 4954.975225123923, 4998.525140111684, 5104.591632669451, 18492.260309587735, 19124.235030598622, 19124.33064842724, 19137.2345106197, 19219.80780192208, 19528.21887124499, 19607.90196049039, 20035.198592056153, 20041.799582003994, 20058.39824805258, 11353.886461135447, 11368.488418021043, 11405.201635885409, 11414.315141091562, 11432.0, 7510.549367037996, 7812.335951444191, 7828.13890472059, 7843.921560784319, 7990.720324788605, 16894.155292235093, 17638.559036024202, 17646.647067058842, 17676.469705908843, 17752.644947101246, 17428.390006349717, 17571.472855814347, 17682.381116660854, 17744.0, 17857.0, 11846.407679615812, 12139.39303034827, 12155.87844121583, 12180.208286461351, 12195.329256890956, 13242.735145296847, 13494.460221591027, 13593.32033398346, 13604.115732477358, 13614.0, 10894.891051089387, 10949.945250274017, 11001.779964400506, 11070.39112848801, 11118.944405277922, 10640.255182137176, 10815.0, 10931.781364372828, 10982.395968221825, 10989.395583242998, 9214.723558293263, 9235.722928311892, 9261.72214833556, 9311.255099592152, 9374.156325930699, 6725.663716814041, 6843.3840954314355, 6914.170299564084, 6980.7905762827195, 7023.33278338557, 7208.243134471015, 7345.889811652936, 7390.371818395497, 7391.667374968247, 7441.92558074434], "value": 6511.071502693563, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.5711145526301, 507.53814029233394, 519.9480051994801, 526.7919171927089, 529.4414392815578, 6089.512838972888, 6122.693745377502, 6167.7841275555365, 6244.937550624492, 6307.243130824302, 6064.817360614682, 6070.18052562905, 6197.969010154954, 6199.156522955763, 6218.191635087433, 5049.318342023826, 5066.594672426216, 5129.179331306973, 5146.305248791421, 5149.227615857614, 4816.927746083811, 5056.696598204101, 5100.214924776317, 5106.361704786902, 5111.8019251721935, 4859.757012149396, 4892.535209155142, 4953.455119936804, 4961.330220420251, 4987.251912213179, 4906.754662266872, 4947.2331788572465, 4957.950420495821, 4965.478624744404, 4983.551480366785, 4423.734575925455, 4432.401625780542, 4459.464864216315, 4476.2837945928495, 4483.708558943658, 6036.728347224342, 6071.939280607182, 6077.270727512682, 6095.146679464879, 6121.418465245794, 5646.322441307028, 5697.344805347397, 5721.39925307845, 5729.169270455803, 5742.913856292159, 6524.510661700391, 6525.510586706016, 6558.2458017328145, 6560.475161987029, 6603.570767900071, 4846.345743324641, 4880.389951256093, 4885.487023862479, 4889.315495830587, 4892.19278818998, 6586.637734924625, 6625.900611490832, 6796.150481189802, 6839.965800171017, 6983.2318444971015, 4906.1414252505665, 4927.260910863328, 4956.231784073474, 4961.231009193581, 4989.451160372356, 5040.747962601855, 5043.193089105725, 5096.286519887219, 5096.413912400048, 5097.235414687826, 5667.121596152603, 5706.457886500776, 5721.5708821838525, 5734.111212761945, 5736.885262294731, 6468.805935821839, 6475.7733479328, 6490.610563366213, 6497.675116244169, 6564.343565643398, 1743.5118166913285, 1757.8769486135848, 1772.9522401626143, 1776.209586733891, 1791.408835084419, 1155.0932517973517, 1215.3558613934629, 1229.6434034130182, 1242.7203879127123, 1243.2229856339789, 5662.68855212959, 5669.716514174274, 5733.856653583611, 5783.450572195635, 5806.390329015477, 6696.229933557605, 6707.463402927851, 6770.52606317553, 6782.830429239309, 6826.590404575742, 5689.516391106719, 5725.8568535786935, 5744.770209191585, 5783.971080144573, 5821.650700957956, 3471.7222622190166, 3550.4318160248786, 3574.160072382988, 3610.693091087287, 3625.619309972468, 6388.744450222032, 6408.783480024369, 6435.549511534241, 6448.226212854487, 6506.917786308431, 6863.485238607124, 6896.689648965808, 6898.379145876795, 6937.791866244021, 6982.371586557234, 6507.251666058464, 6525.184351956005, 6595.4393876521035, 6599.472042236705, 6599.637019963948, 6711.798646040626, 6751.864962700817, 6779.661016949231, 6823.249442561413, 6850.760223392157, 6693.698783554654, 6697.995300704738, 6709.496787741036, 6772.458203343819, 6798.8640227196165, 6585.407313341822, 6595.318193860727, 6610.8347291318105, 6640.535162538576, 6644.767433139817, 5475.87744512382, 5513.711809598972, 5514.558835293086, 5554.722263886709, 5573.526250268773, 6594.406503414524, 6612.371824676648, 6720.32796720324, 6791.898121528082, 6844.760433384808, 6442.423030787722, 6482.708278127401, 6490.123833282447, 6521.065520515882, 6605.768798092044, 6475.222973243241, 6475.7733479328, 6478.287388387368, 6549.770758023446, 6596.142501474777, 6209.751609935552, 6405.967970160119, 6406.5515413920575, 6413.775517856852, 6494.155759751202, 6449.1616089909885, 6466.838329041811, 6528.21661400635, 6553.4101930826455, 6619.933800662125, 6273.592216505913, 6275.937240627535, 6319.146915166578, 6360.554761166674, 6398.328175541501], "value": 5071.298064762986, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [893.4639216470115, 912.2246090822802, 924.6902287733609, 927.9536023198842, 941.1952780372781, 18330.72503912441, 19467.707984380228, 19501.902490487544, 19523.0, 19544.31594894179, 15534.22328883557, 15623.296951637176, 17737.91131044343, 22462.438439039033, 22583.435414114614, 1035.2494441529907, 1060.6923992042298, 1061.0556604621893, 1061.1192710050677, 1061.1669839176247, 1051.4217180550704, 1053.4416759117642, 1053.4522048534757, 1053.7629033467485, 1054.6045233037612, 1050.086424810416, 1096.2490693874681, 1102.8125218712837, 1103.1230172013268, 1103.2332528892423, 1094.6332978452228, 1094.7974624694377, 1094.895984881435, 1095.386583513235, 1097.0729733375285, 796.1441450440799, 796.6295672512309, 796.6295672512309, 797.8723404255292, 801.6112185589984, 18653.06734663275, 18765.812341876543, 19075.332363367215, 19240.518987025298, 20000.79999200004, 21696.132154713792, 21707.02318395676, 22025.559488810293, 22042.0, 22256.443588910246, 16831.41090061842, 16898.239579218964, 17080.572985675335, 17097.57256068596, 17152.48542543726, 3423.640517745626, 3449.0515108345407, 3449.482577613353, 3451.758376913617, 3452.3267962747273, 132214.0, 132335.0, 134867.32566337203, 135415.0, 137428.0, 16575.585610359598, 16901.408450704414, 16966.745498817527, 17064.57338566546, 17170.22733976974, 31816.840915795736, 31900.84049579784, 32384.676153238637, 32442.351152976807, 32730.0, 8587.313014958789, 8625.353098517637, 8674.56627168639, 8723.869141962876, 8757.2994160467, 4015.31739604268, 4045.1505183911117, 4128.793560321972, 4136.379543068504, 4137.731047481964, 5269.55208807248, 5356.384015838151, 5376.489233522887, 5385.246065550826, 5387.407385187705, 1045.1482042135635, 1104.5195340027115, 1114.3926560024806, 1119.462657924201, 1123.0229700160912, 668.669008840627, 681.4105798484276, 681.8738533371342, 683.1324218242804, 690.5683947532792, 11317.603883864025, 11843.28940263587, 11984.520619175135, 12215.144939854124, 12359.505619775287, 626.8088233088952, 634.8349429148454, 661.0645936000601, 671.6473851228046, 687.8246047257917, 5679.119736440858, 5744.655320680772, 5905.261842269716, 5940.0, 6195.628262304276, 114941.425292873, 116176.0, 116637.0, 118517.0, 122570.0, 90423.54788226017, 94922.0, 103565.0, 106643.4667826656, 108337.0, 87969.0, 88982.0, 89013.0, 90111.0, 90420.09579904248, 11930.4034798258, 12257.632271031884, 12354.691132721395, 12654.609422241709, 13146.868531314565, 39686.60313396829, 41140.97147571335, 41942.790286048374, 42097.78951105225, 42364.576354236066, 72429.0, 74276.0, 74720.62639686766, 76543.61728191323, 77734.0, 19294.421167365002, 19581.804181958567, 19808.90095549513, 19841.900790495954, 19995.700064498753, 19943.900280498503, 20183.39449816508, 20414.0, 20436.18255269832, 20523.17907283692, 17995.2801887923, 18157.818421815613, 18685.71971420457, 18886.150123244213, 19021.048947552845, 18011.909940450212, 18190.45428637143, 18322.725159122358, 18335.908320458846, 18628.81371186325, 15910.840891590937, 16287.755683664518, 17491.12544372802, 17940.91029544844, 18466.81533184651, 14519.49181778675, 15069.849301506845, 15161.09033457996, 15233.466828660943, 15366.615834604225, 13719.588412347646, 13846.446142154202, 13906.096103753223, 14021.859781402056, 14046.016778825384], "value": 9339.941134320283, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1536.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 108.0, 100.0, 110.0, 109.0, 116.0, 108.0, 100.0, 108.0, 111.0], "value": 106.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 123.0, 146.0, 191.0, 121.0, 153.0, 143.0, 121.0, 179.0, 121.0], "value": 144.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 163.0, 168.0, 163.0, 161.0, 167.0, 166.0, 163.0, 176.0], "value": 165.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 173.0, 75.0, 77.0, 73.0, 75.0, 172.0, 79.0], "value": 94.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 79.0, 142.0, 80.0, 88.0, 83.0, 128.0, 82.0, 79.0, 129.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 116.0, 120.0, 114.0, 114.0, 120.0, 114.0, 118.0, 114.0], "value": 115.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 152.0, 152.0, 153.0, 150.0, 150.0, 151.0, 152.0, 151.0, 150.0], "value": 151.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 99.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 60.0, 57.0, 62.0, 57.0, 55.0, 57.0, 60.0, 58.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 59.0, 57.0, 58.0, 59.0, 59.0, 55.0, 55.0, 56.0], "value": 56.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 76.0, 75.0, 83.0, 75.0, 78.0, 77.0, 75.0, 76.0, 79.0], "value": 77.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 130.0, 127.0, 134.0, 122.0, 121.0, 121.0, 120.0, 126.0, 158.0], "value": 128.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 175.0, 176.0, 170.0, 183.0, 172.0, 181.0, 168.0, 176.0, 167.0], "value": 174.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 70.0, 67.0, 69.0, 70.0, 69.0, 67.0, 67.0, 70.0], "value": 69.0, "unit": "score"}]}]} 06:00:45 INFO - Return code: 0 06:00:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:00:45 INFO - # TBPL SUCCESS # 06:00:45 INFO - Running post-action listener: _package_coverage_data 06:00:45 INFO - Running post-action listener: _resource_record_post_action 06:00:45 INFO - [mozharness: 2017-08-21 13:00:45.851571Z] Finished run-tests step (success) 06:00:45 INFO - Running post-run listener: _resource_record_post_run 06:00:45 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 9502720; Write bytes: 446758912; Read time: 3924; Write time: 344156 06:00:45 INFO - TinderboxPrint: CPU usage
13.2% 06:00:45 INFO - TinderboxPrint: I/O read bytes / time
9,502,720 / 3,924 06:00:45 INFO - TinderboxPrint: I/O write bytes / time
446,758,912 / 344,156 06:00:45 INFO - TinderboxPrint: CPU idle
8,282.9 (86.8%) 06:00:45 INFO - TinderboxPrint: CPU user
1,202.9 (12.6%) 06:00:45 INFO - TinderboxPrint: Swap in / out
0 / 0 06:00:45 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 118951936; Read time: 0; Write time: 104144 06:00:45 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 06:00:45 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 7135232; Write bytes: 327806976; Read time: 3596; Write time: 240012 06:00:46 INFO - Running post-run listener: _upload_blobber_files 06:00:46 INFO - Blob upload gear active. 06:00:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:00:46 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:00:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:00:46 INFO - (blobuploader) - INFO - Open directory for files ... 06:00:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:00:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:00:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:49 INFO - (blobuploader) - INFO - Done attempting. 06:00:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:00:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:00:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:50 INFO - (blobuploader) - INFO - Done attempting. 06:00:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:00:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:00:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:51 INFO - (blobuploader) - INFO - Done attempting. 06:00:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:00:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:00:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:52 INFO - (blobuploader) - INFO - Done attempting. 06:00:52 INFO - (blobuploader) - INFO - Iteration through files over. 06:00:52 INFO - Return code: 0 06:00:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:00:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:00:52 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bba33365837516b9879321164b77cea97067ff42024654fd6cfe7cfd5a00597cc7a45489371ae6b5a5c18db7475a39c17c7cc4708ecd9c3a9a99dba67635c8f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c91451e92a9ffecd560cf0d9b4dfb223f4cc848970a6e6bb8e38140ef7e6180da131b453e55bea11b4e077c831467f26010471369b487ff1cdc9bbb294b0bfc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b212404d53093ffc00a694c3dfdf871b2880294b31dbadb6777d714bdd5b463b9de9f3cd10db6830e9439dae614c3ff42524bc333e4f56d0cc75790852b2ed79", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9985d6bedcd7863aa37649603b637d529f7ee00418a55b1d6b6a8682fa999b9f50eb23e01ea5c83ad790fb955f1d24e8932b647d90bb764bd3485ab3232e263"} 06:00:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:00:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:00:52 INFO - Contents: 06:00:52 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bba33365837516b9879321164b77cea97067ff42024654fd6cfe7cfd5a00597cc7a45489371ae6b5a5c18db7475a39c17c7cc4708ecd9c3a9a99dba67635c8f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c91451e92a9ffecd560cf0d9b4dfb223f4cc848970a6e6bb8e38140ef7e6180da131b453e55bea11b4e077c831467f26010471369b487ff1cdc9bbb294b0bfc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b212404d53093ffc00a694c3dfdf871b2880294b31dbadb6777d714bdd5b463b9de9f3cd10db6830e9439dae614c3ff42524bc333e4f56d0cc75790852b2ed79", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9985d6bedcd7863aa37649603b637d529f7ee00418a55b1d6b6a8682fa999b9f50eb23e01ea5c83ad790fb955f1d24e8932b647d90bb764bd3485ab3232e263"} 06:00:52 INFO - Running post-run listener: copy_logs_to_upload_dir 06:00:52 INFO - Copying logs to upload dir... 06:00:52 INFO - mkdir: /builds/slave/test/build/upload/logs 06:00:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.007883 ========= master_lag: -0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-21 06:00:52.480713) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-21 06:00:52.485616) ========= 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=438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bba33365837516b9879321164b77cea97067ff42024654fd6cfe7cfd5a00597cc7a45489371ae6b5a5c18db7475a39c17c7cc4708ecd9c3a9a99dba67635c8f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c91451e92a9ffecd560cf0d9b4dfb223f4cc848970a6e6bb8e38140ef7e6180da131b453e55bea11b4e077c831467f26010471369b487ff1cdc9bbb294b0bfc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b212404d53093ffc00a694c3dfdf871b2880294b31dbadb6777d714bdd5b463b9de9f3cd10db6830e9439dae614c3ff42524bc333e4f56d0cc75790852b2ed79", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9985d6bedcd7863aa37649603b637d529f7ee00418a55b1d6b6a8682fa999b9f50eb23e01ea5c83ad790fb955f1d24e8932b647d90bb764bd3485ab3232e263"} build_url:https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011251 build_url: 'https://queue.taskcluster.net/v1/task/G7SN3CghTUOgq49ARGJ-8A/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bba33365837516b9879321164b77cea97067ff42024654fd6cfe7cfd5a00597cc7a45489371ae6b5a5c18db7475a39c17c7cc4708ecd9c3a9a99dba67635c8f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c91451e92a9ffecd560cf0d9b4dfb223f4cc848970a6e6bb8e38140ef7e6180da131b453e55bea11b4e077c831467f26010471369b487ff1cdc9bbb294b0bfc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b212404d53093ffc00a694c3dfdf871b2880294b31dbadb6777d714bdd5b463b9de9f3cd10db6830e9439dae614c3ff42524bc333e4f56d0cc75790852b2ed79", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9985d6bedcd7863aa37649603b637d529f7ee00418a55b1d6b6a8682fa999b9f50eb23e01ea5c83ad790fb955f1d24e8932b647d90bb764bd3485ab3232e263"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-21 06:00:52.517254) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-21 06:00:52.517645) ========= 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=438111f3d31ce799eff2119d000001bc-1503319192.975398-855079484 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004446 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-21 06:00:52.600517) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-21 06:00:52.600861) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-21 06:00:52.601210) ========= ========= Total master_lag: 0.13 =========